Test Date: 2015-07-09 11:22
Analysis date: 2016-05-25 21:28
Logfile
LogfileView
[12:55:49.273] <TB1> INFO: *** Welcome to pxar ***
[12:55:49.273] <TB1> INFO: *** Today: 2015/07/09
[12:55:49.273] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C15.dat
[12:55:49.274] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:55:49.274] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//defaultMaskFile.dat
[12:55:49.274] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters_C15.dat
[12:55:49.346] <TB1> INFO: clk: 4
[12:55:49.346] <TB1> INFO: ctr: 4
[12:55:49.346] <TB1> INFO: sda: 19
[12:55:49.346] <TB1> INFO: tin: 9
[12:55:49.346] <TB1> INFO: level: 15
[12:55:49.346] <TB1> INFO: triggerdelay: 0
[12:55:49.346] <TB1> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[12:55:49.346] <TB1> INFO: Log level: INFO
[12:55:49.354] <TB1> INFO: Found DTB DTB_WXBYFL
[12:55:49.372] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[12:55:49.376] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[12:55:49.379] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[12:55:50.965] <TB1> INFO: DUT info:
[12:55:50.965] <TB1> INFO: The DUT currently contains the following objects:
[12:55:50.965] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:55:50.965] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:55:50.965] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:55:50.965] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:55:50.965] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.965] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.966] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.966] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.966] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:50.966] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:51.367] <TB1> INFO: enter 'restricted' command line mode
[12:55:51.367] <TB1> INFO: enter test to run
[12:55:51.367] <TB1> INFO: test: pretest no parameter change
[12:55:51.367] <TB1> INFO: running: pretest
[12:55:51.372] <TB1> INFO: ######################################################################
[12:55:51.372] <TB1> INFO: PixTestPretest::doTest()
[12:55:51.372] <TB1> INFO: ######################################################################
[12:55:51.374] <TB1> INFO: ----------------------------------------------------------------------
[12:55:51.374] <TB1> INFO: PixTestPretest::programROC()
[12:55:51.374] <TB1> INFO: ----------------------------------------------------------------------
[12:56:09.395] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:56:09.395] <TB1> INFO: IA differences per ROC: 20.1 17.7 17.7 16.1 17.7 17.7 18.5 16.9 18.5 18.5 20.9 17.7 16.1 18.5 16.1 19.3
[12:56:09.488] <TB1> INFO: ----------------------------------------------------------------------
[12:56:09.488] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:56:09.488] <TB1> INFO: ----------------------------------------------------------------------
[12:56:29.086] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[12:56:29.089] <TB1> INFO: ----------------------------------------------------------------------
[12:56:29.089] <TB1> INFO: PixTestPretest::findWorkingPixel()
[12:56:29.089] <TB1> INFO: ----------------------------------------------------------------------
[12:56:37.406] <TB1> INFO: Test took 8311ms.
[12:56:37.718] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:56:37.769] <TB1> INFO: ----------------------------------------------------------------------
[12:56:37.769] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[12:56:37.769] <TB1> INFO: ----------------------------------------------------------------------
[12:56:46.067] <TB1> INFO: Test took 8289ms.
[12:56:46.395] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[12:56:46.395] <TB1> INFO: CalDel: 130 133 157 140 127 163 143 127 144 148 154 140 133 158 124 140
[12:56:46.395] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:56:46.399] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C0.dat
[12:56:46.400] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C1.dat
[12:56:46.400] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C2.dat
[12:56:46.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C3.dat
[12:56:46.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C4.dat
[12:56:46.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C5.dat
[12:56:46.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C6.dat
[12:56:46.401] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C7.dat
[12:56:46.402] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C8.dat
[12:56:46.402] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C9.dat
[12:56:46.402] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C10.dat
[12:56:46.402] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C11.dat
[12:56:46.403] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C12.dat
[12:56:46.403] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C13.dat
[12:56:46.403] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C14.dat
[12:56:46.403] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters_C15.dat
[12:56:46.403] <TB1> INFO: PixTestPretest::doTest() done, duration: 55 seconds
[12:56:46.502] <TB1> INFO: enter test to run
[12:56:46.502] <TB1> INFO: test: fulltest no parameter change
[12:56:46.502] <TB1> INFO: running: fulltest
[12:56:46.502] <TB1> INFO: ######################################################################
[12:56:46.502] <TB1> INFO: PixTestFullTest::doTest()
[12:56:46.502] <TB1> INFO: ######################################################################
[12:56:46.504] <TB1> INFO: ######################################################################
[12:56:46.504] <TB1> INFO: PixTestAlive::doTest()
[12:56:46.504] <TB1> INFO: ######################################################################
[12:56:46.505] <TB1> INFO: ----------------------------------------------------------------------
[12:56:46.505] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:56:46.505] <TB1> INFO: ----------------------------------------------------------------------
[12:56:49.991] <TB1> INFO: Test took 3484ms.
[12:56:50.013] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:50.256] <TB1> INFO: PixTestAlive::aliveTest() done
[12:56:50.256] <TB1> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:56:50.258] <TB1> INFO: ----------------------------------------------------------------------
[12:56:50.258] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:56:50.258] <TB1> INFO: ----------------------------------------------------------------------
[12:56:53.033] <TB1> INFO: Test took 2773ms.
[12:56:53.037] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:53.037] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:56:53.277] <TB1> INFO: PixTestAlive::maskTest() done
[12:56:53.277] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:56:53.279] <TB1> INFO: ----------------------------------------------------------------------
[12:56:53.279] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:56:53.279] <TB1> INFO: ----------------------------------------------------------------------
[12:56:56.723] <TB1> INFO: Test took 3442ms.
[12:56:56.744] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:56.987] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:56:56.987] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:56:56.987] <TB1> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[12:56:56.996] <TB1> INFO: ######################################################################
[12:56:56.996] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:56:56.996] <TB1> INFO: ######################################################################
[12:56:57.000] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[12:56:57.011] <TB1> INFO: dacScan step from 0 .. 29
[12:57:18.403] <TB1> INFO: Test took 21391ms.
[12:57:18.438] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:18.438] <TB1> INFO: dacScan step from 30 .. 59
[12:57:42.507] <TB1> INFO: Test took 24069ms.
[12:57:42.649] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:42.671] <TB1> INFO: dacScan step from 60 .. 89
[12:58:11.858] <TB1> INFO: Test took 29186ms.
[12:58:12.125] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:12.206] <TB1> INFO: dacScan step from 90 .. 119
[12:58:41.396] <TB1> INFO: Test took 29190ms.
[12:58:41.679] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:41.764] <TB1> INFO: dacScan step from 120 .. 149
[12:59:05.906] <TB1> INFO: Test took 24142ms.
[12:59:06.106] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:28.484] <TB1> INFO: PixTestBBMap::doTest() done, duration: 151 seconds
[12:59:28.484] <TB1> INFO: number of dead bumps (per ROC): 49 31 10 26 16 4 37 63 40 30 62 36 18 26 93 52
[12:59:28.484] <TB1> INFO: separation cut (per ROC): 81 74 84 65 79 81 72 80 64 64 71 75 72 73 71 78
[12:59:28.528] <TB1> INFO: ######################################################################
[12:59:28.528] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50
[12:59:28.528] <TB1> INFO: ######################################################################
[12:59:28.528] <TB1> INFO: ----------------------------------------------------------------------
[12:59:28.528] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[12:59:28.528] <TB1> INFO: ----------------------------------------------------------------------
[12:59:28.529] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[12:59:28.536] <TB1> INFO: dacScan step from 0 .. 3
[12:59:46.347] <TB1> INFO: Test took 17810ms.
[12:59:46.373] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:46.373] <TB1> INFO: dacScan step from 4 .. 7
[13:00:04.750] <TB1> INFO: Test took 18377ms.
[13:00:04.779] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:04.779] <TB1> INFO: dacScan step from 8 .. 11
[13:00:23.628] <TB1> INFO: Test took 18849ms.
[13:00:23.655] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:23.655] <TB1> INFO: dacScan step from 12 .. 15
[13:00:42.608] <TB1> INFO: Test took 18953ms.
[13:00:42.638] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:42.638] <TB1> INFO: dacScan step from 16 .. 19
[13:01:01.609] <TB1> INFO: Test took 18971ms.
[13:01:01.641] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:01.641] <TB1> INFO: dacScan step from 20 .. 23
[13:01:20.582] <TB1> INFO: Test took 18941ms.
[13:01:20.617] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:20.617] <TB1> INFO: dacScan step from 24 .. 27
[13:01:39.613] <TB1> INFO: Test took 18996ms.
[13:01:39.642] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:39.642] <TB1> INFO: dacScan step from 28 .. 31
[13:01:58.638] <TB1> INFO: Test took 18996ms.
[13:01:58.675] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:58.675] <TB1> INFO: dacScan step from 32 .. 35
[13:02:17.613] <TB1> INFO: Test took 18938ms.
[13:02:17.642] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:17.642] <TB1> INFO: dacScan step from 36 .. 39
[13:02:36.663] <TB1> INFO: Test took 19020ms.
[13:02:36.692] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:36.692] <TB1> INFO: dacScan step from 40 .. 43
[13:02:55.720] <TB1> INFO: Test took 19028ms.
[13:02:55.752] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:55.752] <TB1> INFO: dacScan step from 44 .. 47
[13:03:14.766] <TB1> INFO: Test took 19014ms.
[13:03:14.798] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:14.798] <TB1> INFO: dacScan step from 48 .. 51
[13:03:33.768] <TB1> INFO: Test took 18970ms.
[13:03:33.793] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:33.794] <TB1> INFO: dacScan step from 52 .. 55
[13:03:52.794] <TB1> INFO: Test took 19000ms.
[13:03:52.823] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:52.823] <TB1> INFO: dacScan step from 56 .. 59
[13:04:11.749] <TB1> INFO: Test took 18926ms.
[13:04:11.784] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:11.784] <TB1> INFO: dacScan step from 60 .. 63
[13:04:30.726] <TB1> INFO: Test took 18942ms.
[13:04:30.755] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:30.755] <TB1> INFO: dacScan step from 64 .. 67
[13:04:49.896] <TB1> INFO: Test took 19141ms.
[13:04:49.940] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:49.940] <TB1> INFO: dacScan step from 68 .. 71
[13:05:09.461] <TB1> INFO: Test took 19521ms.
[13:05:09.513] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:09.515] <TB1> INFO: dacScan step from 72 .. 75
[13:05:29.861] <TB1> INFO: Test took 20346ms.
[13:05:29.929] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:29.932] <TB1> INFO: dacScan step from 76 .. 79
[13:05:51.941] <TB1> INFO: Test took 22009ms.
[13:05:52.052] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:52.057] <TB1> INFO: dacScan step from 80 .. 83
[13:06:17.479] <TB1> INFO: Test took 25422ms.
[13:06:17.619] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:17.626] <TB1> INFO: dacScan step from 84 .. 87
[13:06:44.626] <TB1> INFO: Test took 27000ms.
[13:06:44.807] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:44.815] <TB1> INFO: dacScan step from 88 .. 91
[13:07:12.815] <TB1> INFO: Test took 28001ms.
[13:07:13.020] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:13.031] <TB1> INFO: dacScan step from 92 .. 95
[13:07:41.937] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (0) != Token Chain Length (4)

[13:07:41.937] <TB1> WARNING: ROC 0: Readback start marker after 31 readouts!

[13:07:41.937] <TB1> WARNING: ROC 1: Readback start marker after 31 readouts!

[13:07:41.937] <TB1> WARNING: ROC 2: Readback start marker after 31 readouts!

[13:07:41.937] <TB1> WARNING: ROC 3: Readback start marker after 31 readouts!

[13:07:42.041] <TB1> INFO: Test took 29010ms.
[13:07:42.281] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:42.291] <TB1> INFO: dacScan step from 96 .. 99
[13:08:10.797] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[13:08:10.797] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:08:10.797] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:08:11.680] <TB1> INFO: Test took 29389ms.
[13:08:11.903] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:11.916] <TB1> INFO: dacScan step from 100 .. 103
[13:08:40.108] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[13:08:40.108] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:08:40.108] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:08:41.295] <TB1> INFO: Test took 29379ms.
[13:08:41.517] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:41.528] <TB1> INFO: dacScan step from 104 .. 107
[13:09:09.926] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[13:09:09.926] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:09:09.926] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:09:11.206] <TB1> INFO: Test took 29678ms.
[13:09:11.446] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:11.457] <TB1> INFO: dacScan step from 108 .. 111
[13:09:39.722] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[13:09:39.723] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:09:39.723] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:09:40.973] <TB1> INFO: Test took 29516ms.
[13:09:41.266] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:41.279] <TB1> INFO: dacScan step from 112 .. 115
[13:10:09.456] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:10:09.456] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:10:10.747] <TB1> INFO: Test took 29468ms.
[13:10:10.991] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:11.005] <TB1> INFO: dacScan step from 116 .. 119
[13:10:39.241] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[13:10:39.241] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:10:39.241] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:10:40.499] <TB1> INFO: Test took 29494ms.
[13:10:40.749] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:40.772] <TB1> INFO: dacScan step from 120 .. 123
[13:11:09.013] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:11:09.013] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:11:10.314] <TB1> INFO: Test took 29542ms.
[13:11:10.543] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:10.554] <TB1> INFO: dacScan step from 124 .. 127
[13:11:39.901] <TB1> INFO: Test took 29347ms.
[13:11:40.147] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:40.160] <TB1> INFO: dacScan step from 128 .. 131
[13:12:09.481] <TB1> INFO: Test took 29321ms.
[13:12:09.703] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:09.714] <TB1> INFO: dacScan step from 132 .. 135
[13:12:39.134] <TB1> INFO: Test took 29420ms.
[13:12:39.377] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:39.389] <TB1> INFO: dacScan step from 136 .. 139
[13:13:08.791] <TB1> INFO: Test took 29402ms.
[13:13:09.042] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:09.055] <TB1> INFO: dacScan step from 140 .. 143
[13:13:38.288] <TB1> INFO: Test took 29233ms.
[13:13:38.511] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:38.522] <TB1> INFO: dacScan step from 144 .. 147
[13:14:06.493] <TB1> INFO: Test took 27971ms.
[13:14:06.729] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:06.742] <TB1> INFO: dacScan step from 148 .. 149
[13:14:21.555] <TB1> INFO: Test took 14813ms.
[13:14:21.665] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:21.671] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:23.052] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:24.437] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:25.823] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:27.289] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:28.727] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:30.238] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:31.739] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:33.313] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:34.812] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:36.322] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:37.879] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:39.289] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:40.937] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:42.433] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:43.883] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:14:45.405] <TB1> INFO: PixTestScurves::scurves() done
[13:14:45.405] <TB1> INFO: Vcal mean: 84.39 78.75 77.74 75.27 90.30 82.29 86.20 92.49 81.27 74.93 89.95 86.18 92.24 94.17 88.60 87.77
[13:14:45.405] <TB1> INFO: Vcal RMS: 5.32 4.52 4.05 4.54 5.09 4.71 4.94 5.53 5.04 4.84 6.84 5.93 5.12 5.55 4.90 5.31
[13:14:45.405] <TB1> INFO: PixTestScurves::fullTest() done, duration: 916 seconds
[13:14:45.475] <TB1> INFO: ######################################################################
[13:14:45.475] <TB1> INFO: PixTestTrim::doTest()
[13:14:45.475] <TB1> INFO: ######################################################################
[13:14:45.477] <TB1> INFO: ----------------------------------------------------------------------
[13:14:45.477] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:14:45.477] <TB1> INFO: ----------------------------------------------------------------------
[13:14:45.561] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:14:45.561] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[13:14:45.569] <TB1> INFO: dacScan step from 0 .. 19
[13:14:59.919] <TB1> INFO: Test took 14350ms.
[13:14:59.941] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:59.941] <TB1> INFO: dacScan step from 20 .. 39
[13:15:15.027] <TB1> INFO: Test took 15086ms.
[13:15:15.056] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:15.056] <TB1> INFO: dacScan step from 40 .. 59
[13:15:30.200] <TB1> INFO: Test took 15144ms.
[13:15:30.224] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:30.224] <TB1> INFO: dacScan step from 60 .. 79
[13:15:45.401] <TB1> INFO: Test took 15177ms.
[13:15:45.429] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:45.429] <TB1> INFO: dacScan step from 80 .. 99
[13:16:01.560] <TB1> INFO: Test took 16131ms.
[13:16:01.629] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:01.640] <TB1> INFO: dacScan step from 100 .. 119
[13:16:21.022] <TB1> INFO: Test took 19382ms.
[13:16:21.202] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:21.237] <TB1> INFO: dacScan step from 120 .. 139
[13:16:38.817] <TB1> INFO: Test took 17580ms.
[13:16:38.990] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:39.018] <TB1> INFO: dacScan step from 140 .. 159
[13:16:54.190] <TB1> INFO: Test took 15171ms.
[13:16:54.271] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:15.359] <TB1> INFO: ROC 0 VthrComp = 92
[13:17:15.359] <TB1> INFO: ROC 1 VthrComp = 84
[13:17:15.359] <TB1> INFO: ROC 2 VthrComp = 85
[13:17:15.359] <TB1> INFO: ROC 3 VthrComp = 79
[13:17:15.359] <TB1> INFO: ROC 4 VthrComp = 95
[13:17:15.359] <TB1> INFO: ROC 5 VthrComp = 86
[13:17:15.360] <TB1> INFO: ROC 6 VthrComp = 89
[13:17:15.360] <TB1> INFO: ROC 7 VthrComp = 95
[13:17:15.360] <TB1> INFO: ROC 8 VthrComp = 86
[13:17:15.360] <TB1> INFO: ROC 9 VthrComp = 78
[13:17:15.360] <TB1> INFO: ROC 10 VthrComp = 93
[13:17:15.360] <TB1> INFO: ROC 11 VthrComp = 92
[13:17:15.360] <TB1> INFO: ROC 12 VthrComp = 92
[13:17:15.361] <TB1> INFO: ROC 13 VthrComp = 97
[13:17:15.361] <TB1> INFO: ROC 14 VthrComp = 92
[13:17:15.361] <TB1> INFO: ROC 15 VthrComp = 96
[13:17:15.361] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:17:15.361] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[13:17:15.369] <TB1> INFO: dacScan step from 0 .. 19
[13:17:29.826] <TB1> INFO: Test took 14457ms.
[13:17:29.847] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:29.847] <TB1> INFO: dacScan step from 20 .. 39
[13:17:45.122] <TB1> INFO: Test took 15275ms.
[13:17:45.156] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:45.159] <TB1> INFO: dacScan step from 40 .. 59
[13:18:04.089] <TB1> INFO: Test took 18930ms.
[13:18:04.247] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:04.281] <TB1> INFO: dacScan step from 60 .. 79
[13:18:24.465] <TB1> INFO: Test took 20184ms.
[13:18:24.629] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:24.678] <TB1> INFO: dacScan step from 80 .. 99
[13:18:44.867] <TB1> INFO: Test took 20189ms.
[13:18:45.056] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:45.108] <TB1> INFO: dacScan step from 100 .. 119
[13:19:05.330] <TB1> INFO: Test took 20222ms.
[13:19:05.553] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:05.605] <TB1> INFO: dacScan step from 120 .. 139
[13:19:25.180] <TB1> INFO: Test took 19575ms.
[13:19:25.347] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:25.400] <TB1> INFO: dacScan step from 140 .. 159
[13:19:44.474] <TB1> INFO: Test took 19074ms.
[13:19:44.629] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:09.611] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 58.7816 for pixel 0/77 mean/min/max = 45.5707/32.3312/58.8101
[13:20:09.611] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.3875 for pixel 0/7 mean/min/max = 45.9253/32.4248/59.4259
[13:20:09.612] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 56.3576 for pixel 13/76 mean/min/max = 44.6078/32.7782/56.4374
[13:20:09.612] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.3531 for pixel 0/13 mean/min/max = 47.193/34.9917/59.3942
[13:20:09.612] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.0349 for pixel 15/4 mean/min/max = 45.1126/32.1575/58.0677
[13:20:09.612] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 58.025 for pixel 13/47 mean/min/max = 45.3518/32.5046/58.1989
[13:20:09.613] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 59.1811 for pixel 30/3 mean/min/max = 46.2215/33.11/59.333
[13:20:09.613] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.4428 for pixel 0/69 mean/min/max = 45.5747/32.5876/58.5617
[13:20:09.613] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.8034 for pixel 24/74 mean/min/max = 45.1635/31.3782/58.9489
[13:20:09.613] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 60.6317 for pixel 2/68 mean/min/max = 47.3992/34.106/60.6925
[13:20:09.614] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 63.1093 for pixel 14/2 mean/min/max = 47.2169/31.1907/63.243
[13:20:09.614] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.8862 for pixel 2/69 mean/min/max = 45.9754/30.9897/60.9612
[13:20:09.614] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 58.1746 for pixel 10/73 mean/min/max = 45.6244/33.0267/58.2221
[13:20:09.614] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.5832 for pixel 51/56 mean/min/max = 44.7512/30.9064/58.5959
[13:20:09.615] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.7567 for pixel 11/16 mean/min/max = 45.6495/33.2054/58.0936
[13:20:09.615] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.944 for pixel 32/61 mean/min/max = 44.9753/31.9954/57.9552
[13:20:09.615] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:21:44.031] <TB1> INFO: Test took 94416ms.
[13:21:45.691] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[13:21:45.699] <TB1> INFO: dacScan step from 0 .. 19
[13:22:08.285] <TB1> INFO: Test took 22586ms.
[13:22:08.334] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:08.336] <TB1> INFO: dacScan step from 20 .. 39
[13:22:36.985] <TB1> INFO: Test took 28649ms.
[13:22:37.235] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:37.265] <TB1> INFO: dacScan step from 40 .. 59
[13:23:06.554] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (232) != TBM ID (0)

[13:23:06.554] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:23:06.554] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (233)

[13:23:06.554] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:23:07.677] <TB1> INFO: Test took 30412ms.
[13:23:08.094] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:08.147] <TB1> INFO: dacScan step from 60 .. 79
[13:23:40.265] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (201) != TBM ID (0)

[13:23:40.265] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:23:40.265] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (202)

[13:23:40.265] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:23:41.553] <TB1> INFO: Test took 33406ms.
[13:23:41.819] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:41.870] <TB1> INFO: dacScan step from 80 .. 99
[13:24:13.883] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[13:24:13.883] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:24:13.883] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:24:15.198] <TB1> INFO: Test took 33328ms.
[13:24:15.481] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:15.529] <TB1> INFO: dacScan step from 100 .. 119
[13:24:48.832] <TB1> INFO: Test took 33303ms.
[13:24:49.109] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:49.157] <TB1> INFO: dacScan step from 120 .. 139
[13:25:20.866] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:25:20.867] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:25:22.109] <TB1> INFO: Test took 32952ms.
[13:25:22.399] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:22.451] <TB1> INFO: dacScan step from 140 .. 159
[13:25:54.339] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[13:25:54.339] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (204) != TBM ID (205)

[13:25:54.339] <TB1> WARNING: ROC 0: Readback start marker after 15 readouts!

[13:25:54.339] <TB1> WARNING: ROC 1: Readback start marker after 15 readouts!

[13:25:54.339] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:25:54.339] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:25:55.586] <TB1> INFO: Test took 33135ms.
[13:25:55.874] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:55.923] <TB1> INFO: dacScan step from 160 .. 179
[13:26:27.928] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (207) != TBM ID (0)

[13:26:27.928] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:26:27.929] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (208)

[13:26:27.929] <TB1> WARNING: ROC 3: Readback start marker after 31 readouts!

[13:26:29.196] <TB1> INFO: Test took 33273ms.
[13:26:29.496] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:29.547] <TB1> INFO: dacScan step from 180 .. 199
[13:26:59.721] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:26:59.721] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:27:00.832] <TB1> INFO: Test took 31285ms.
[13:27:01.100] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:26.625] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.198917 .. 255.000000
[13:27:26.704] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)
[13:27:26.712] <TB1> INFO: dacScan step from 0 .. 19
[13:27:39.772] <TB1> INFO: Test took 13060ms.
[13:27:39.792] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:39.793] <TB1> INFO: dacScan step from 20 .. 39
[13:27:54.429] <TB1> INFO: Test took 14636ms.
[13:27:54.513] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:54.530] <TB1> INFO: dacScan step from 40 .. 59
[13:28:11.271] <TB1> INFO: Test took 16740ms.
[13:28:11.414] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:11.459] <TB1> INFO: dacScan step from 60 .. 79
[13:28:29.117] <TB1> INFO: Test took 17658ms.
[13:28:29.259] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:29.315] <TB1> INFO: dacScan step from 80 .. 99
[13:28:46.988] <TB1> INFO: Test took 17673ms.
[13:28:47.161] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:47.224] <TB1> INFO: dacScan step from 100 .. 119
[13:29:03.752] <TB1> INFO: Test took 16528ms.
[13:29:03.896] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:03.976] <TB1> INFO: dacScan step from 120 .. 139
[13:29:21.709] <TB1> INFO: Test took 17733ms.
[13:29:21.886] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:21.946] <TB1> INFO: dacScan step from 140 .. 159
[13:29:39.611] <TB1> INFO: Test took 17664ms.
[13:29:39.754] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:39.809] <TB1> INFO: dacScan step from 160 .. 179
[13:29:56.882] <TB1> INFO: Test took 17072ms.
[13:29:57.057] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:57.110] <TB1> INFO: dacScan step from 180 .. 199
[13:30:15.716] <TB1> INFO: Test took 18605ms.
[13:30:15.919] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:15.968] <TB1> INFO: dacScan step from 200 .. 219
[13:30:32.705] <TB1> INFO: Test took 16737ms.
[13:30:32.931] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:32.991] <TB1> INFO: dacScan step from 220 .. 239
[13:30:50.701] <TB1> INFO: Test took 17710ms.
[13:30:50.898] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:50.953] <TB1> INFO: dacScan step from 240 .. 255
[13:31:05.745] <TB1> INFO: Test took 14792ms.
[13:31:05.861] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:40.140] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.650395 .. 44.936673
[13:31:40.234] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 4 .. 54 (20) hits flags = 16 (plus default)
[13:31:40.243] <TB1> INFO: dacScan step from 4 .. 23
[13:31:53.321] <TB1> INFO: Test took 13078ms.
[13:31:53.347] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:53.347] <TB1> INFO: dacScan step from 24 .. 43
[13:32:09.001] <TB1> INFO: Test took 15654ms.
[13:32:09.135] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:09.165] <TB1> INFO: dacScan step from 44 .. 54
[13:32:20.192] <TB1> INFO: Test took 11027ms.
[13:32:20.279] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:36.202] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 18.912137 .. 42.648716
[13:32:36.285] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 8 .. 52 (20) hits flags = 16 (plus default)
[13:32:36.294] <TB1> INFO: dacScan step from 8 .. 27
[13:32:49.264] <TB1> INFO: Test took 12970ms.
[13:32:49.284] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:49.285] <TB1> INFO: dacScan step from 28 .. 47
[13:33:05.516] <TB1> INFO: Test took 16231ms.
[13:33:05.672] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:05.721] <TB1> INFO: dacScan step from 48 .. 52
[13:33:12.392] <TB1> INFO: Test took 6671ms.
[13:33:12.447] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:28.279] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 1.716216 .. 41.778759
[13:33:28.358] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 1 .. 51 (20) hits flags = 16 (plus default)
[13:33:28.366] <TB1> INFO: dacScan step from 1 .. 20
[13:33:41.427] <TB1> INFO: Test took 13061ms.
[13:33:41.453] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:41.453] <TB1> INFO: dacScan step from 21 .. 40
[13:33:56.441] <TB1> INFO: Test took 14988ms.
[13:33:56.526] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:56.544] <TB1> INFO: dacScan step from 41 .. 51
[13:34:07.575] <TB1> INFO: Test took 11031ms.
[13:34:07.657] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:24.301] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:34:24.301] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[13:34:24.309] <TB1> INFO: dacScan step from 15 .. 34
[13:34:46.675] <TB1> INFO: Test took 22366ms.
[13:34:46.741] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:46.749] <TB1> INFO: dacScan step from 35 .. 54
[13:35:18.668] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:35:18.668] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:35:19.352] <TB1> INFO: Test took 32603ms.
[13:35:19.665] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:19.711] <TB1> INFO: dacScan step from 55 .. 55
[13:35:24.071] <TB1> INFO: Test took 4359ms.
[13:35:24.093] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:39.039] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:35:39.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:35:39.041] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:35:39.041] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:35:39.041] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:35:39.041] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:35:39.041] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:35:39.048] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:35:39.054] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:35:39.059] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:35:39.065] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:35:39.071] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:35:39.077] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:35:39.083] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:35:39.089] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:35:39.095] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:35:39.101] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:35:39.107] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:35:39.113] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:35:39.119] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:35:39.125] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:35:39.131] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:35:39.137] <TB1> INFO: PixTestTrim::trimTest() done
[13:35:39.137] <TB1> INFO: vtrim: 114 111 98 109 109 107 102 120 99 108 136 116 93 95 94 116
[13:35:39.137] <TB1> INFO: vthrcomp: 92 84 85 79 95 86 89 95 86 78 93 92 92 97 92 96
[13:35:39.137] <TB1> INFO: vcal mean: 35.02 35.06 35.04 35.06 35.02 35.03 35.03 35.02 35.09 35.03 35.05 35.04 35.04 35.02 35.07 35.04
[13:35:39.137] <TB1> INFO: vcal RMS: 1.11 0.97 0.95 1.08 1.04 1.01 0.99 1.22 1.04 1.01 1.09 1.09 1.07 1.06 1.01 1.02
[13:35:39.137] <TB1> INFO: bits mean: 9.75 9.69 9.83 9.25 9.94 9.77 9.37 9.59 10.07 9.22 9.79 10.28 9.82 9.47 9.71 10.17
[13:35:39.137] <TB1> INFO: bits RMS: 2.56 2.57 2.53 2.33 2.54 2.57 2.56 2.61 2.55 2.39 2.57 2.45 2.41 2.91 2.45 2.44
[13:35:39.142] <TB1> INFO: ----------------------------------------------------------------------
[13:35:39.142] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 240 150 100
[13:35:39.142] <TB1> INFO: ----------------------------------------------------------------------
[13:35:39.146] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[13:35:39.154] <TB1> INFO: dacScan step from 0 .. 19
[13:36:00.615] <TB1> INFO: Test took 21461ms.
[13:36:00.661] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:00.661] <TB1> INFO: dacScan step from 20 .. 39
[13:36:23.776] <TB1> INFO: Test took 23115ms.
[13:36:23.828] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:23.832] <TB1> INFO: dacScan step from 40 .. 59
[13:36:53.562] <TB1> INFO: Test took 29730ms.
[13:36:53.810] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:53.849] <TB1> INFO: dacScan step from 60 .. 79
[13:37:25.878] <TB1> INFO: Test took 32029ms.
[13:37:26.164] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:26.213] <TB1> INFO: dacScan step from 80 .. 99
[13:37:58.092] <TB1> INFO: Test took 31879ms.
[13:37:58.355] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:58.412] <TB1> INFO: dacScan step from 100 .. 119
[13:38:31.483] <TB1> INFO: Test took 33071ms.
[13:38:31.792] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:31.845] <TB1> INFO: dacScan step from 120 .. 139
[13:39:02.824] <TB1> INFO: Test took 30979ms.
[13:39:03.098] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:03.149] <TB1> INFO: dacScan step from 140 .. 159
[13:39:35.919] <TB1> INFO: Test took 32770ms.
[13:39:36.224] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:36.273] <TB1> INFO: dacScan step from 160 .. 179
[13:40:09.486] <TB1> INFO: Test took 33213ms.
[13:40:09.747] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:09.794] <TB1> INFO: dacScan step from 180 .. 199
[13:40:42.921] <TB1> INFO: Test took 33127ms.
[13:40:43.196] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:09.959] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 85 (20) hits flags = 16 (plus default)
[13:41:09.967] <TB1> INFO: dacScan step from 0 .. 19
[13:41:32.834] <TB1> INFO: Test took 22867ms.
[13:41:32.873] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:32.873] <TB1> INFO: dacScan step from 20 .. 39
[13:41:56.839] <TB1> INFO: Test took 23966ms.
[13:41:56.949] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:56.960] <TB1> INFO: dacScan step from 40 .. 59
[13:42:29.259] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[13:42:29.259] <TB1> WARNING: ROC 2: Readback start marker after 31 readouts!

[13:42:29.259] <TB1> WARNING: ROC 3: Readback start marker after 31 readouts!

[13:42:29.322] <TB1> INFO: Test took 32362ms.
[13:42:29.595] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:29.642] <TB1> INFO: dacScan step from 60 .. 79
[13:43:00.550] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (71) != TBM ID (0)

[13:43:00.550] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[13:43:00.550] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (1) != TBM ID (72)

[13:43:00.550] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:43:01.617] <TB1> INFO: Test took 31975ms.
[13:43:01.889] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:01.942] <TB1> INFO: dacScan step from 80 .. 85
[13:43:12.983] <TB1> INFO: Test took 11041ms.
[13:43:13.065] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:30.483] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 77 (20) hits flags = 16 (plus default)
[13:43:30.492] <TB1> INFO: dacScan step from 0 .. 19
[13:43:53.213] <TB1> INFO: Test took 22721ms.
[13:43:53.251] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:53.251] <TB1> INFO: dacScan step from 20 .. 39
[13:44:18.789] <TB1> INFO: Test took 25538ms.
[13:44:18.935] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:18.951] <TB1> INFO: dacScan step from 40 .. 59
[13:44:49.526] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[13:44:49.526] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:44:49.526] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:44:50.263] <TB1> INFO: Test took 31312ms.
[13:44:50.579] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:50.636] <TB1> INFO: dacScan step from 60 .. 77
[13:45:18.943] <TB1> INFO: Test took 28307ms.
[13:45:19.181] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:37.471] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 72 (20) hits flags = 16 (plus default)
[13:45:37.479] <TB1> INFO: dacScan step from 0 .. 19
[13:46:00.239] <TB1> INFO: Test took 22760ms.
[13:46:00.275] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:00.276] <TB1> INFO: dacScan step from 20 .. 39
[13:46:25.482] <TB1> INFO: Test took 25206ms.
[13:46:25.664] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:25.688] <TB1> INFO: dacScan step from 40 .. 59
[13:46:56.353] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (8) != Token Chain Length (4)

[13:46:56.353] <TB1> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (81) != TBM ID (82)

[13:46:56.353] <TB1> WARNING: ROC 0: Readback start marker after 15 readouts!

[13:46:56.353] <TB1> WARNING: ROC 1: Readback start marker after 15 readouts!

[13:46:56.353] <TB1> WARNING: ROC 2: Readback start marker after 15 readouts!

[13:46:56.353] <TB1> WARNING: ROC 3: Readback start marker after 15 readouts!

[13:46:57.485] <TB1> INFO: Test took 31797ms.
[13:46:57.783] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:57.831] <TB1> INFO: dacScan step from 60 .. 72
[13:47:19.105] <TB1> INFO: Test took 21274ms.
[13:47:19.278] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:36.526] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 70 (20) hits flags = 16 (plus default)
[13:47:36.535] <TB1> INFO: dacScan step from 0 .. 19
[13:47:59.441] <TB1> INFO: Test took 22906ms.
[13:47:59.482] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:59.483] <TB1> INFO: dacScan step from 20 .. 39
[13:48:27.729] <TB1> INFO: Test took 28246ms.
[13:48:27.945] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:27.974] <TB1> INFO: dacScan step from 40 .. 59
[13:49:00.136] <TB1> INFO: Test took 32162ms.
[13:49:00.452] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:00.505] <TB1> INFO: dacScan step from 60 .. 70
[13:49:18.374] <TB1> INFO: Test took 17869ms.
[13:49:18.540] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:34.829] <TB1> INFO: PixTestTrim::trimBitTest() done
[13:49:34.831] <TB1> INFO: PixTestTrim::doTest() done, duration: 2089 seconds
[13:49:35.531] <TB1> INFO: ######################################################################
[13:49:35.531] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:49:35.531] <TB1> INFO: ######################################################################
[13:49:38.979] <TB1> INFO: Test took 3446ms.
[13:49:39.004] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:42.691] <TB1> INFO: Test took 3488ms.
[13:49:42.763] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:46.248] <TB1> INFO: Test took 3472ms.
[13:49:46.314] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:46.321] <TB1> INFO: The DUT currently contains the following objects:
[13:49:46.321] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:46.321] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:46.321] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:46.321] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:46.321] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:46.322] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.433] <TB1> INFO: Test took 1111ms.
[13:49:47.434] <TB1> INFO: The DUT currently contains the following objects:
[13:49:47.434] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:47.434] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:47.434] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:47.434] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:47.434] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:47.435] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.541] <TB1> INFO: Test took 1106ms.
[13:49:48.542] <TB1> INFO: The DUT currently contains the following objects:
[13:49:48.542] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:48.542] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:48.542] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:48.542] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:48.542] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:48.542] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.656] <TB1> INFO: Test took 1114ms.
[13:49:49.657] <TB1> INFO: The DUT currently contains the following objects:
[13:49:49.657] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:49.657] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:49.657] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:49.657] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:49.657] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.657] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.657] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.657] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.657] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:49.658] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.767] <TB1> INFO: Test took 1109ms.
[13:49:50.769] <TB1> INFO: The DUT currently contains the following objects:
[13:49:50.769] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:50.769] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:50.769] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:50.769] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:50.769] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.769] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.769] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.769] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.769] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.769] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.769] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.769] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.771] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.771] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.771] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.771] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.771] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.771] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.771] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:50.772] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.881] <TB1> INFO: Test took 1109ms.
[13:49:51.882] <TB1> INFO: The DUT currently contains the following objects:
[13:49:51.882] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:51.882] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:51.882] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:51.882] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:51.882] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.882] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.882] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.882] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.882] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:51.883] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.994] <TB1> INFO: Test took 1111ms.
[13:49:52.996] <TB1> INFO: The DUT currently contains the following objects:
[13:49:52.996] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:52.996] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:52.996] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:52.996] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:52.996] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.996] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:52.997] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.107] <TB1> INFO: Test took 1110ms.
[13:49:54.108] <TB1> INFO: The DUT currently contains the following objects:
[13:49:54.108] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:54.108] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:54.109] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:54.109] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:54.109] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.109] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.110] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:54.110] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.221] <TB1> INFO: Test took 1111ms.
[13:49:55.223] <TB1> INFO: The DUT currently contains the following objects:
[13:49:55.223] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:55.223] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:55.223] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:55.223] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:55.223] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.223] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.224] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:55.224] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.331] <TB1> INFO: Test took 1107ms.
[13:49:56.332] <TB1> INFO: The DUT currently contains the following objects:
[13:49:56.332] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:56.332] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:56.332] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:56.332] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:56.332] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:56.332] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.443] <TB1> INFO: Test took 1111ms.
[13:49:57.445] <TB1> INFO: The DUT currently contains the following objects:
[13:49:57.455] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:57.455] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:57.455] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:57.455] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:57.455] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:57.455] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.555] <TB1> INFO: Test took 1100ms.
[13:49:58.557] <TB1> INFO: The DUT currently contains the following objects:
[13:49:58.557] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:58.557] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:58.557] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:58.557] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:58.557] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.557] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.558] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.558] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:58.558] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.667] <TB1> INFO: Test took 1109ms.
[13:49:59.668] <TB1> INFO: The DUT currently contains the following objects:
[13:49:59.668] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:49:59.668] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:49:59.668] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:49:59.668] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:49:59.668] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.668] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.668] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:49:59.669] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.780] <TB1> INFO: Test took 1111ms.
[13:50:00.781] <TB1> INFO: The DUT currently contains the following objects:
[13:50:00.781] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:50:00.781] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:50:00.781] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:50:00.781] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:50:00.781] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.781] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.782] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.782] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.782] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.782] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.782] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.782] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.782] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.782] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.783] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.783] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.783] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.783] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.783] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:00.783] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.894] <TB1> INFO: Test took 1111ms.
[13:50:01.896] <TB1> INFO: The DUT currently contains the following objects:
[13:50:01.896] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:50:01.896] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:50:01.896] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:50:01.896] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:50:01.896] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.896] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.896] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.896] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.896] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.896] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.896] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.896] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.897] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.897] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.897] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.897] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.897] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.897] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.897] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:01.897] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.008] <TB1> INFO: Test took 1111ms.
[13:50:03.009] <TB1> INFO: The DUT currently contains the following objects:
[13:50:03.009] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:50:03.009] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:50:03.009] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:50:03.009] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:50:03.009] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.009] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:03.010] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:50:04.120] <TB1> INFO: Test took 1110ms.
[13:50:04.126] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:49.465] <TB1> INFO: Test took 225339ms.
[13:53:51.476] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:33.133] <TB1> INFO: Test took 221657ms.
[13:57:34.963] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:34.970] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:34.977] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:57:34.984] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:57:34.990] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[13:57:34.997] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[13:57:35.004] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.011] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.020] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.026] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.033] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.040] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.047] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.054] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:57:35.060] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:57:35.067] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.074] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.080] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.087] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.094] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.100] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.107] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:57:35.162] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:57:35.162] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:57:35.163] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:57:35.163] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:57:35.163] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:57:35.163] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:57:35.163] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:57:35.164] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:57:35.164] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:57:35.164] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:57:35.164] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:57:35.164] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:57:35.165] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:57:35.165] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:57:35.165] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:57:35.165] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:57:38.633] <TB1> INFO: Test took 3463ms.
[13:57:42.430] <TB1> INFO: Test took 3521ms.
[13:57:46.264] <TB1> INFO: Test took 3514ms.
[13:57:46.545] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:47.468] <TB1> INFO: Test took 923ms.
[13:57:47.472] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:48.582] <TB1> INFO: Test took 1110ms.
[13:57:48.586] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:49.690] <TB1> INFO: Test took 1104ms.
[13:57:49.693] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:50.805] <TB1> INFO: Test took 1112ms.
[13:57:50.811] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:51.924] <TB1> INFO: Test took 1113ms.
[13:57:51.928] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:53.040] <TB1> INFO: Test took 1112ms.
[13:57:53.044] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:54.153] <TB1> INFO: Test took 1109ms.
[13:57:54.156] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:55.268] <TB1> INFO: Test took 1112ms.
[13:57:55.272] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:56.383] <TB1> INFO: Test took 1111ms.
[13:57:56.387] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:57.497] <TB1> INFO: Test took 1110ms.
[13:57:57.501] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:58.611] <TB1> INFO: Test took 1110ms.
[13:57:58.615] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:57:59.727] <TB1> INFO: Test took 1112ms.
[13:57:59.731] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:00.840] <TB1> INFO: Test took 1110ms.
[13:58:00.844] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:01.956] <TB1> INFO: Test took 1112ms.
[13:58:01.961] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:03.073] <TB1> INFO: Test took 1113ms.
[13:58:03.077] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:04.191] <TB1> INFO: Test took 1114ms.
[13:58:04.195] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:05.305] <TB1> INFO: Test took 1110ms.
[13:58:05.309] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:06.422] <TB1> INFO: Test took 1113ms.
[13:58:06.426] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:07.536] <TB1> INFO: Test took 1110ms.
[13:58:07.541] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:08.649] <TB1> INFO: Test took 1108ms.
[13:58:08.654] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:09.762] <TB1> INFO: Test took 1109ms.
[13:58:09.765] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:10.876] <TB1> INFO: Test took 1111ms.
[13:58:10.881] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:11.993] <TB1> INFO: Test took 1113ms.
[13:58:11.998] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:13.106] <TB1> INFO: Test took 1109ms.
[13:58:13.111] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:14.223] <TB1> INFO: Test took 1113ms.
[13:58:14.227] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:15.339] <TB1> INFO: Test took 1112ms.
[13:58:15.343] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:16.456] <TB1> INFO: Test took 1113ms.
[13:58:16.460] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:17.572] <TB1> INFO: Test took 1112ms.
[13:58:17.576] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:18.686] <TB1> INFO: Test took 1111ms.
[13:58:18.691] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:19.801] <TB1> INFO: Test took 1110ms.
[13:58:19.805] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:20.917] <TB1> INFO: Test took 1112ms.
[13:58:20.922] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:58:22.033] <TB1> INFO: Test took 1111ms.
[13:58:22.621] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 527 seconds
[13:58:22.621] <TB1> INFO: PH scale (per ROC): 90 88 94 87 79 89 81 80 91 78 87 88 80 77 80 80
[13:58:22.621] <TB1> INFO: PH offset (per ROC): 159 144 139 169 153 166 166 154 144 144 160 174 175 149 147 166
[13:58:22.796] <TB1> INFO: ######################################################################
[13:58:22.796] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:58:22.796] <TB1> INFO: ######################################################################
[13:58:22.806] <TB1> INFO: scanning low vcal = 50
[13:58:27.188] <TB1> INFO: Test took 4382ms.
[13:58:27.252] <TB1> INFO: scanning low vcal = 100
[13:58:31.646] <TB1> INFO: Test took 4394ms.
[13:58:31.709] <TB1> INFO: scanning low vcal = 150
[13:58:35.930] <TB1> INFO: Test took 4221ms.
[13:58:36.010] <TB1> INFO: scanning low vcal = 200
[13:58:40.265] <TB1> INFO: Test took 4255ms.
[13:58:40.331] <TB1> INFO: scanning low vcal = 250
[13:58:44.589] <TB1> INFO: Test took 4258ms.
[13:58:44.676] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[13:58:48.907] <TB1> INFO: Test took 4231ms.
[13:58:48.995] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[13:58:53.245] <TB1> INFO: Test took 4250ms.
[13:58:53.300] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[13:58:57.550] <TB1> INFO: Test took 4250ms.
[13:58:57.629] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[13:59:01.889] <TB1> INFO: Test took 4260ms.
[13:59:01.985] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:59:06.432] <TB1> INFO: Test took 4447ms.
[13:59:06.836] <TB1> INFO: PixTestGainPedestal::measure() done
[13:59:31.620] <TB1> INFO: PixTestGainPedestal::fit() done
[13:59:31.620] <TB1> INFO: non-linearity mean: 0.952 0.964 0.955 0.957 0.957 0.951 0.951 0.960 0.957 0.945 0.947 0.950 0.959 0.956 0.960 0.951
[13:59:31.621] <TB1> INFO: non-linearity RMS: 0.006 0.004 0.005 0.006 0.006 0.005 0.006 0.006 0.005 0.007 0.006 0.007 0.005 0.006 0.004 0.005
[13:59:31.621] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:59:31.639] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:59:31.657] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:59:31.675] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:59:31.693] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:59:31.712] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:59:31.730] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:59:31.748] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:59:31.765] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:59:31.783] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:59:31.801] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:59:31.818] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:59:31.836] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:59:31.854] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:59:31.871] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:59:31.889] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2030_FullQualification_2015-07-09_11h22m_1436433757//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:59:31.907] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 69 seconds
[13:59:31.912] <TB1> INFO: enter test to run
[13:59:31.912] <TB1> INFO: test: exit no parameter change
[13:59:32.331] <TB1> QUIET: Connection to board 153 closed.
[13:59:32.410] <TB1> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master