[14:25:55.532] <TB3> INFO: *** Welcome to pxar ***
[14:25:55.532] <TB3> INFO: *** Today: 2015/12/15
[14:25:55.606] <TB3> INFO: *** Version: 3197
[14:25:55.606] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C15.dat
[14:25:55.607] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:25:55.608] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//defaultMaskFile.dat
[14:25:55.608] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters_C15.dat
[14:25:55.682] <TB3> INFO: clk: 4
[14:25:55.682] <TB3> INFO: ctr: 4
[14:25:55.682] <TB3> INFO: sda: 19
[14:25:55.682] <TB3> INFO: tin: 9
[14:25:55.682] <TB3> INFO: level: 15
[14:25:55.682] <TB3> INFO: triggerdelay: 0
[14:25:55.682] <TB3> QUIET: Instanciating API for pxar prod-12
[14:25:55.682] <TB3> INFO: Log level: INFO
[14:25:55.689] <TB3> INFO: Found DTB DTB_WZ4I6J
[14:25:55.698] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[14:25:55.701] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[14:25:55.703] <TB3> INFO: RPC call hashes of host and DTB match: 398089610
[14:25:57.224] <TB3> INFO: DUT info:
[14:25:57.224] <TB3> INFO: The DUT currently contains the following objects:
[14:25:57.224] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:25:57.224] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:25:57.224] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:25:57.224] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:25:57.224] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.224] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:57.625] <TB3> INFO: enter 'restricted' command line mode
[14:25:57.625] <TB3> INFO: enter test to run
[14:25:57.625] <TB3> INFO: test: pretest no parameter change
[14:25:57.625] <TB3> INFO: running: pretest
[14:25:57.630] <TB3> INFO: ######################################################################
[14:25:57.630] <TB3> INFO: PixTestPretest::doTest()
[14:25:57.630] <TB3> INFO: ######################################################################
[14:25:57.632] <TB3> INFO: ----------------------------------------------------------------------
[14:25:57.632] <TB3> INFO: PixTestPretest::programROC()
[14:25:57.632] <TB3> INFO: ----------------------------------------------------------------------
[14:26:15.648] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:26:15.648] <TB3> INFO: IA differences per ROC: 17.7 16.9 19.3 18.5 17.7 19.3 19.3 17.7 19.3 19.3 20.9 19.3 21.7 19.3 20.9 17.7
[14:26:15.724] <TB3> INFO: ----------------------------------------------------------------------
[14:26:15.724] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:26:15.724] <TB3> INFO: ----------------------------------------------------------------------
[14:26:24.141] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 395.5 mA = 24.7188 mA/ROC
[14:26:24.141] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3
[14:26:24.180] <TB3> INFO: ----------------------------------------------------------------------
[14:26:24.180] <TB3> INFO: PixTestPretest::findTiming()
[14:26:24.180] <TB3> INFO: ----------------------------------------------------------------------
[14:26:24.180] <TB3> INFO: PixTestCmd::init()

[14:28:15.853] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[14:28:15.853] <TB3> INFO: (success/tries = 100/100), width = 5
[14:28:15.855] <TB3> INFO: ----------------------------------------------------------------------
[14:28:15.855] <TB3> INFO: PixTestPretest::findWorkingPixel()
[14:28:15.855] <TB3> INFO: ----------------------------------------------------------------------
[14:28:15.993] <TB3> INFO: Expecting 231680 events.
[14:28:24.099] <TB3> INFO: 231680 events read in total (7390ms).
[14:28:24.104] <TB3> INFO: Test took 8246ms.
[14:28:24.402] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:28:24.437] <TB3> INFO: ----------------------------------------------------------------------
[14:28:24.437] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[14:28:24.437] <TB3> INFO: ----------------------------------------------------------------------
[14:28:24.574] <TB3> INFO: Expecting 231680 events.
[14:28:33.137] <TB3> INFO: 231680 events read in total (7848ms).
[14:28:33.141] <TB3> INFO: Test took 8699ms.
[14:28:33.475] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[14:28:33.476] <TB3> INFO: CalDel: 132 126 128 118 129 129 139 131 158 141 139 148 138 128 149 145
[14:28:33.476] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:28:33.479] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C0.dat
[14:28:33.479] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C1.dat
[14:28:33.480] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C2.dat
[14:28:33.480] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C3.dat
[14:28:33.480] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C4.dat
[14:28:33.481] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C5.dat
[14:28:33.481] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C6.dat
[14:28:33.481] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C7.dat
[14:28:33.482] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C8.dat
[14:28:33.482] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C9.dat
[14:28:33.482] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C10.dat
[14:28:33.483] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C11.dat
[14:28:33.483] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C12.dat
[14:28:33.483] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C13.dat
[14:28:33.483] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C14.dat
[14:28:33.484] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters_C15.dat
[14:28:33.484] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//tbmParameters_C0a.dat
[14:28:33.484] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:28:33.485] <TB3> INFO: PixTestPretest::doTest() done, duration: 155 seconds
[14:28:33.564] <TB3> INFO: enter test to run
[14:28:33.565] <TB3> INFO: test: fulltest no parameter change
[14:28:33.565] <TB3> INFO: running: fulltest
[14:28:33.565] <TB3> INFO: ######################################################################
[14:28:33.565] <TB3> INFO: PixTestFullTest::doTest()
[14:28:33.565] <TB3> INFO: ######################################################################
[14:28:33.566] <TB3> INFO: ######################################################################
[14:28:33.566] <TB3> INFO: PixTestAlive::doTest()
[14:28:33.566] <TB3> INFO: ######################################################################
[14:28:33.568] <TB3> INFO: ----------------------------------------------------------------------
[14:28:33.568] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:28:33.568] <TB3> INFO: ----------------------------------------------------------------------
[14:28:33.887] <TB3> INFO: Expecting 41600 events.
[14:28:38.087] <TB3> INFO: 41600 events read in total (3484ms).
[14:28:38.089] <TB3> INFO: Test took 4519ms.
[14:28:38.095] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:38.381] <TB3> INFO: PixTestAlive::aliveTest() done
[14:28:38.381] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:28:38.383] <TB3> INFO: ----------------------------------------------------------------------
[14:28:38.383] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:28:38.383] <TB3> INFO: ----------------------------------------------------------------------
[14:28:38.691] <TB3> INFO: Expecting 41600 events.
[14:28:41.841] <TB3> INFO: 41600 events read in total (2435ms).
[14:28:41.842] <TB3> INFO: Test took 3457ms.
[14:28:41.842] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:41.842] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:28:42.152] <TB3> INFO: PixTestAlive::maskTest() done
[14:28:42.152] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:28:42.153] <TB3> INFO: ----------------------------------------------------------------------
[14:28:42.153] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:28:42.153] <TB3> INFO: ----------------------------------------------------------------------
[14:28:42.462] <TB3> INFO: Expecting 41600 events.
[14:28:46.721] <TB3> INFO: 41600 events read in total (3543ms).
[14:28:46.722] <TB3> INFO: Test took 4568ms.
[14:28:46.729] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:47.012] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[14:28:47.012] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:28:47.012] <TB3> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[14:28:47.012] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:47.012] <TB3> INFO: Decoding statistics:
[14:28:47.012] <TB3> INFO: General information:
[14:28:47.012] <TB3> INFO: 16bit words read: 0
[14:28:47.012] <TB3> INFO: valid events total: 0
[14:28:47.012] <TB3> INFO: empty events: 0
[14:28:47.012] <TB3> INFO: valid events with pixels: 0
[14:28:47.012] <TB3> INFO: valid pixel hits: 0
[14:28:47.012] <TB3> INFO: Event errors: 0
[14:28:47.012] <TB3> INFO: start marker: 0
[14:28:47.012] <TB3> INFO: stop marker: 0
[14:28:47.012] <TB3> INFO: overflow: 0
[14:28:47.012] <TB3> INFO: invalid 5bit words: 0
[14:28:47.012] <TB3> INFO: invalid XOR eye diagram: 0
[14:28:47.012] <TB3> INFO: TBM errors: 0
[14:28:47.012] <TB3> INFO: flawed TBM headers: 0
[14:28:47.012] <TB3> INFO: flawed TBM trailers: 0
[14:28:47.012] <TB3> INFO: event ID mismatches: 0
[14:28:47.012] <TB3> INFO: ROC errors: 0
[14:28:47.012] <TB3> INFO: missing ROC header(s): 0
[14:28:47.012] <TB3> INFO: misplaced readback start: 0
[14:28:47.012] <TB3> INFO: Pixel decoding errors: 0
[14:28:47.012] <TB3> INFO: pixel data incomplete: 0
[14:28:47.012] <TB3> INFO: pixel address: 0
[14:28:47.012] <TB3> INFO: pulse height fill bit: 0
[14:28:47.012] <TB3> INFO: buffer corruption: 0
[14:28:47.019] <TB3> INFO: ######################################################################
[14:28:47.019] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:28:47.019] <TB3> INFO: ######################################################################
[14:28:47.022] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:28:47.033] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:28:47.033] <TB3> INFO: run 1 of 1
[14:28:47.341] <TB3> INFO: Expecting 3120000 events.
[14:29:26.995] <TB3> INFO: 860850 events read in total (38938ms).
[14:30:05.419] <TB3> INFO: 1711185 events read in total (77362ms).
[14:30:43.573] <TB3> INFO: 2571630 events read in total (115516ms).
[14:31:07.870] <TB3> INFO: 3120000 events read in total (139813ms).
[14:31:07.928] <TB3> INFO: Test took 140895ms.
[14:31:08.034] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:34.249] <TB3> INFO: PixTestBBMap::doTest() done, duration: 167 seconds
[14:31:34.249] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 1 0 0 8 1 0 0 0 0 0 0 0
[14:31:34.249] <TB3> INFO: separation cut (per ROC): 84 90 91 88 90 100 93 102 83 85 100 94 99 107 97 88
[14:31:34.249] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:34.249] <TB3> INFO: Decoding statistics:
[14:31:34.249] <TB3> INFO: General information:
[14:31:34.249] <TB3> INFO: 16bit words read: 0
[14:31:34.249] <TB3> INFO: valid events total: 0
[14:31:34.249] <TB3> INFO: empty events: 0
[14:31:34.249] <TB3> INFO: valid events with pixels: 0
[14:31:34.250] <TB3> INFO: valid pixel hits: 0
[14:31:34.250] <TB3> INFO: Event errors: 0
[14:31:34.250] <TB3> INFO: start marker: 0
[14:31:34.250] <TB3> INFO: stop marker: 0
[14:31:34.250] <TB3> INFO: overflow: 0
[14:31:34.250] <TB3> INFO: invalid 5bit words: 0
[14:31:34.250] <TB3> INFO: invalid XOR eye diagram: 0
[14:31:34.250] <TB3> INFO: TBM errors: 0
[14:31:34.250] <TB3> INFO: flawed TBM headers: 0
[14:31:34.250] <TB3> INFO: flawed TBM trailers: 0
[14:31:34.250] <TB3> INFO: event ID mismatches: 0
[14:31:34.250] <TB3> INFO: ROC errors: 0
[14:31:34.250] <TB3> INFO: missing ROC header(s): 0
[14:31:34.250] <TB3> INFO: misplaced readback start: 0
[14:31:34.250] <TB3> INFO: Pixel decoding errors: 0
[14:31:34.250] <TB3> INFO: pixel data incomplete: 0
[14:31:34.250] <TB3> INFO: pixel address: 0
[14:31:34.250] <TB3> INFO: pulse height fill bit: 0
[14:31:34.250] <TB3> INFO: buffer corruption: 0
[14:31:34.334] <TB3> INFO: ######################################################################
[14:31:34.334] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:31:34.334] <TB3> INFO: ######################################################################
[14:31:34.334] <TB3> INFO: ----------------------------------------------------------------------
[14:31:34.334] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:31:34.334] <TB3> INFO: ----------------------------------------------------------------------
[14:31:34.334] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:31:34.344] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[14:31:34.344] <TB3> INFO: run 1 of 1
[14:31:34.672] <TB3> INFO: Expecting 26208000 events.
[14:32:03.673] <TB3> INFO: 931950 events read in total (28285ms).
[14:32:31.800] <TB3> INFO: 1850900 events read in total (56412ms).
[14:32:59.809] <TB3> INFO: 2767450 events read in total (84421ms).
[14:33:28.056] <TB3> INFO: 3684550 events read in total (112668ms).
[14:33:56.339] <TB3> INFO: 4600450 events read in total (140951ms).
[14:34:25.037] <TB3> INFO: 5514000 events read in total (169649ms).
[14:34:53.644] <TB3> INFO: 6429750 events read in total (198256ms).
[14:35:21.738] <TB3> INFO: 7342700 events read in total (226350ms).
[14:35:49.946] <TB3> INFO: 8254750 events read in total (254558ms).
[14:36:18.048] <TB3> INFO: 9170050 events read in total (282660ms).
[14:36:46.071] <TB3> INFO: 10077750 events read in total (310683ms).
[14:37:14.333] <TB3> INFO: 10986750 events read in total (338945ms).
[14:37:42.466] <TB3> INFO: 11896950 events read in total (367078ms).
[14:38:10.703] <TB3> INFO: 12806250 events read in total (395315ms).
[14:38:38.907] <TB3> INFO: 13706400 events read in total (423519ms).
[14:39:07.239] <TB3> INFO: 14600950 events read in total (451851ms).
[14:39:35.331] <TB3> INFO: 15497000 events read in total (479943ms).
[14:40:03.584] <TB3> INFO: 16392700 events read in total (508196ms).
[14:40:31.397] <TB3> INFO: 17289300 events read in total (536009ms).
[14:40:59.354] <TB3> INFO: 18186750 events read in total (563966ms).
[14:41:27.315] <TB3> INFO: 19081000 events read in total (591927ms).
[14:41:54.905] <TB3> INFO: 19975600 events read in total (619517ms).
[14:42:22.575] <TB3> INFO: 20870250 events read in total (647187ms).
[14:42:50.455] <TB3> INFO: 21765700 events read in total (675067ms).
[14:43:18.192] <TB3> INFO: 22658550 events read in total (702804ms).
[14:43:45.927] <TB3> INFO: 23553250 events read in total (730539ms).
[14:44:14.396] <TB3> INFO: 24453500 events read in total (759008ms).
[14:44:42.529] <TB3> INFO: 25356250 events read in total (787141ms).
[14:45:09.196] <TB3> INFO: 26208000 events read in total (813808ms).
[14:45:09.226] <TB3> INFO: Test took 814882ms.
[14:45:09.298] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:09.474] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:11.017] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:12.436] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:13.976] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:15.654] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:17.336] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:18.814] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:20.243] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:21.668] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:23.219] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:24.841] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:26.527] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:28.085] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:29.733] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:31.343] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:32.862] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[14:45:34.423] <TB3> INFO: PixTestScurves::scurves() done
[14:45:34.423] <TB3> INFO: Vcal mean: 88.09 95.76 92.53 94.59 90.75 103.29 91.91 99.67 91.19 82.24 102.18 91.05 90.89 111.70 98.58 93.48
[14:45:34.423] <TB3> INFO: Vcal RMS: 4.95 5.79 5.29 4.81 5.34 6.14 5.28 5.27 5.30 4.61 4.96 5.49 5.76 5.38 6.18 5.90
[14:45:34.423] <TB3> INFO: PixTestScurves::fullTest() done, duration: 840 seconds
[14:45:34.423] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:34.423] <TB3> INFO: Decoding statistics:
[14:45:34.423] <TB3> INFO: General information:
[14:45:34.423] <TB3> INFO: 16bit words read: 0
[14:45:34.423] <TB3> INFO: valid events total: 0
[14:45:34.423] <TB3> INFO: empty events: 0
[14:45:34.423] <TB3> INFO: valid events with pixels: 0
[14:45:34.423] <TB3> INFO: valid pixel hits: 0
[14:45:34.423] <TB3> INFO: Event errors: 0
[14:45:34.423] <TB3> INFO: start marker: 0
[14:45:34.423] <TB3> INFO: stop marker: 0
[14:45:34.423] <TB3> INFO: overflow: 0
[14:45:34.423] <TB3> INFO: invalid 5bit words: 0
[14:45:34.423] <TB3> INFO: invalid XOR eye diagram: 0
[14:45:34.423] <TB3> INFO: TBM errors: 0
[14:45:34.423] <TB3> INFO: flawed TBM headers: 0
[14:45:34.423] <TB3> INFO: flawed TBM trailers: 0
[14:45:34.423] <TB3> INFO: event ID mismatches: 0
[14:45:34.423] <TB3> INFO: ROC errors: 0
[14:45:34.423] <TB3> INFO: missing ROC header(s): 0
[14:45:34.423] <TB3> INFO: misplaced readback start: 0
[14:45:34.423] <TB3> INFO: Pixel decoding errors: 0
[14:45:34.423] <TB3> INFO: pixel data incomplete: 0
[14:45:34.423] <TB3> INFO: pixel address: 0
[14:45:34.423] <TB3> INFO: pulse height fill bit: 0
[14:45:34.423] <TB3> INFO: buffer corruption: 0
[14:45:34.504] <TB3> INFO: ######################################################################
[14:45:34.504] <TB3> INFO: PixTestTrim::doTest()
[14:45:34.504] <TB3> INFO: ######################################################################
[14:45:34.506] <TB3> INFO: ----------------------------------------------------------------------
[14:45:34.506] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[14:45:34.506] <TB3> INFO: ----------------------------------------------------------------------
[14:45:34.599] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[14:45:34.599] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:45:34.607] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[14:45:34.608] <TB3> INFO: run 1 of 1
[14:45:34.936] <TB3> INFO: Expecting 6281600 events.
[14:46:11.485] <TB3> INFO: 1079120 events read in total (35832ms).
[14:46:46.649] <TB3> INFO: 2151840 events read in total (70996ms).
[14:47:22.007] <TB3> INFO: 3217800 events read in total (106354ms).
[14:47:57.397] <TB3> INFO: 4289000 events read in total (141744ms).
[14:48:33.906] <TB3> INFO: 5365050 events read in total (178253ms).
[14:49:04.037] <TB3> INFO: 6281600 events read in total (208384ms).
[14:49:04.069] <TB3> INFO: Test took 209461ms.
[14:49:04.121] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:25.337] <TB3> INFO: ROC 0 VthrComp = 93
[14:49:25.337] <TB3> INFO: ROC 1 VthrComp = 95
[14:49:25.337] <TB3> INFO: ROC 2 VthrComp = 98
[14:49:25.337] <TB3> INFO: ROC 3 VthrComp = 98
[14:49:25.337] <TB3> INFO: ROC 4 VthrComp = 94
[14:49:25.337] <TB3> INFO: ROC 5 VthrComp = 102
[14:49:25.337] <TB3> INFO: ROC 6 VthrComp = 97
[14:49:25.337] <TB3> INFO: ROC 7 VthrComp = 100
[14:49:25.337] <TB3> INFO: ROC 8 VthrComp = 88
[14:49:25.337] <TB3> INFO: ROC 9 VthrComp = 85
[14:49:25.338] <TB3> INFO: ROC 10 VthrComp = 103
[14:49:25.338] <TB3> INFO: ROC 11 VthrComp = 93
[14:49:25.338] <TB3> INFO: ROC 12 VthrComp = 97
[14:49:25.338] <TB3> INFO: ROC 13 VthrComp = 109
[14:49:25.338] <TB3> INFO: ROC 14 VthrComp = 97
[14:49:25.338] <TB3> INFO: ROC 15 VthrComp = 92
[14:49:25.338] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[14:49:25.338] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:49:25.347] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[14:49:25.347] <TB3> INFO: run 1 of 1
[14:49:25.649] <TB3> INFO: Expecting 6281600 events.
[14:49:57.212] <TB3> INFO: 755120 events read in total (30847ms).
[14:50:28.319] <TB3> INFO: 1508210 events read in total (61954ms).
[14:50:59.708] <TB3> INFO: 2262360 events read in total (93343ms).
[14:51:30.597] <TB3> INFO: 3016800 events read in total (124232ms).
[14:52:01.605] <TB3> INFO: 3766440 events read in total (155240ms).
[14:52:33.122] <TB3> INFO: 4511750 events read in total (186757ms).
[14:53:04.298] <TB3> INFO: 5255320 events read in total (217933ms).
[14:53:34.602] <TB3> INFO: 5999670 events read in total (248237ms).
[14:53:46.725] <TB3> INFO: 6281600 events read in total (260360ms).
[14:53:46.770] <TB3> INFO: Test took 261423ms.
[14:53:46.908] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:10.640] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 56.7832 for pixel 18/12 mean/min/max = 45.0233/33.1175/56.9291
[14:54:10.640] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 60.1945 for pixel 0/54 mean/min/max = 46.2203/32.2319/60.2088
[14:54:10.640] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 55.5169 for pixel 4/0 mean/min/max = 43.9374/31.9467/55.9281
[14:54:10.641] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 55.0441 for pixel 45/74 mean/min/max = 43.7932/31.8548/55.7317
[14:54:10.641] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 58.3793 for pixel 21/72 mean/min/max = 45.681/32.9104/58.4516
[14:54:10.641] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 61.8764 for pixel 7/0 mean/min/max = 47.1551/32.3121/61.998
[14:54:10.642] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.7374 for pixel 14/2 mean/min/max = 44.7569/31.6874/57.8263
[14:54:10.642] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.7775 for pixel 20/75 mean/min/max = 45.4672/32.1092/58.8251
[14:54:10.643] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 59.6985 for pixel 17/9 mean/min/max = 46.9398/34.1467/59.7329
[14:54:10.643] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 57.1922 for pixel 51/4 mean/min/max = 44.7273/32.004/57.4506
[14:54:10.643] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 56.8309 for pixel 24/79 mean/min/max = 44.825/32.7734/56.8767
[14:54:10.644] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 59.8356 for pixel 12/68 mean/min/max = 46.3364/32.8081/59.8647
[14:54:10.644] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 58.3413 for pixel 0/5 mean/min/max = 44.9437/31.5041/58.3832
[14:54:10.645] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 63.89 for pixel 11/6 mean/min/max = 49.4927/34.9524/64.033
[14:54:10.645] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 61.6752 for pixel 0/23 mean/min/max = 46.5995/31.4803/61.7187
[14:54:10.645] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 60.929 for pixel 0/55 mean/min/max = 46.8804/32.7881/60.9727
[14:54:10.646] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:54:10.776] <TB3> INFO: Expecting 514560 events.
[14:54:21.602] <TB3> INFO: 514560 events read in total (10110ms).
[14:54:21.609] <TB3> INFO: Expecting 514560 events.
[14:54:32.502] <TB3> INFO: 514560 events read in total (10323ms).
[14:54:32.509] <TB3> INFO: Expecting 514560 events.
[14:54:43.482] <TB3> INFO: 514560 events read in total (10402ms).
[14:54:43.490] <TB3> INFO: Expecting 514560 events.
[14:54:54.205] <TB3> INFO: 514560 events read in total (10137ms).
[14:54:54.218] <TB3> INFO: Expecting 514560 events.
[14:55:04.912] <TB3> INFO: 514560 events read in total (10127ms).
[14:55:04.925] <TB3> INFO: Expecting 514560 events.
[14:55:15.673] <TB3> INFO: 514560 events read in total (10177ms).
[14:55:15.689] <TB3> INFO: Expecting 514560 events.
[14:55:26.501] <TB3> INFO: 514560 events read in total (10248ms).
[14:55:26.520] <TB3> INFO: Expecting 514560 events.
[14:55:37.128] <TB3> INFO: 514560 events read in total (10052ms).
[14:55:37.148] <TB3> INFO: Expecting 514560 events.
[14:55:47.802] <TB3> INFO: 514560 events read in total (10089ms).
[14:55:47.824] <TB3> INFO: Expecting 514560 events.
[14:55:58.507] <TB3> INFO: 514560 events read in total (10130ms).
[14:55:58.530] <TB3> INFO: Expecting 514560 events.
[14:56:09.199] <TB3> INFO: 514560 events read in total (10106ms).
[14:56:09.224] <TB3> INFO: Expecting 514560 events.
[14:56:20.604] <TB3> INFO: 514560 events read in total (10829ms).
[14:56:20.631] <TB3> INFO: Expecting 514560 events.
[14:56:31.252] <TB3> INFO: 514560 events read in total (10077ms).
[14:56:31.280] <TB3> INFO: Expecting 514560 events.
[14:56:41.913] <TB3> INFO: 514560 events read in total (10074ms).
[14:56:41.946] <TB3> INFO: Expecting 514560 events.
[14:56:52.818] <TB3> INFO: 514560 events read in total (10329ms).
[14:56:52.854] <TB3> INFO: Expecting 514560 events.
[14:57:03.541] <TB3> INFO: 514560 events read in total (10143ms).
[14:57:03.578] <TB3> INFO: Test took 172932ms.
[14:57:04.736] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:57:04.745] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[14:57:04.745] <TB3> INFO: run 1 of 1
[14:57:05.061] <TB3> INFO: Expecting 6281600 events.
[14:57:36.085] <TB3> INFO: 742260 events read in total (30308ms).
[14:58:07.134] <TB3> INFO: 1483200 events read in total (61357ms).
[14:58:38.024] <TB3> INFO: 2224710 events read in total (92248ms).
[14:59:08.553] <TB3> INFO: 2966800 events read in total (122776ms).
[14:59:39.568] <TB3> INFO: 3703440 events read in total (153791ms).
[15:00:10.464] <TB3> INFO: 4435800 events read in total (184687ms).
[15:00:41.184] <TB3> INFO: 5166310 events read in total (215407ms).
[15:01:11.645] <TB3> INFO: 5896510 events read in total (245868ms).
[15:01:27.889] <TB3> INFO: 6281600 events read in total (262112ms).
[15:01:27.941] <TB3> INFO: Test took 263196ms.
[15:01:28.099] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:54.955] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.060626 .. 255.000000
[15:01:55.045] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:01:55.054] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:01:55.054] <TB3> INFO: run 1 of 1
[15:01:55.379] <TB3> INFO: Expecting 10649600 events.
[15:02:26.307] <TB3> INFO: 712380 events read in total (30212ms).
[15:02:56.347] <TB3> INFO: 1424890 events read in total (60252ms).
[15:03:26.622] <TB3> INFO: 2137480 events read in total (90527ms).
[15:03:56.849] <TB3> INFO: 2850470 events read in total (120754ms).
[15:04:27.136] <TB3> INFO: 3563220 events read in total (151041ms).
[15:04:57.344] <TB3> INFO: 4276300 events read in total (181249ms).
[15:05:27.461] <TB3> INFO: 4989080 events read in total (211366ms).
[15:05:57.586] <TB3> INFO: 5702230 events read in total (241491ms).
[15:06:27.456] <TB3> INFO: 6414670 events read in total (271361ms).
[15:06:57.059] <TB3> INFO: 7126270 events read in total (300964ms).
[15:07:26.586] <TB3> INFO: 7837790 events read in total (330491ms).
[15:07:55.927] <TB3> INFO: 8548730 events read in total (359832ms).
[15:08:25.764] <TB3> INFO: 9259860 events read in total (389669ms).
[15:08:55.377] <TB3> INFO: 9970780 events read in total (419282ms).
[15:09:24.960] <TB3> INFO: 10649600 events read in total (448865ms).
[15:09:25.067] <TB3> INFO: Test took 450014ms.
[15:09:25.345] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:55.324] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 18.820838 .. 44.039549
[15:09:55.419] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 8 .. 54 (-1/-1) hits flags = 528 (plus default)
[15:09:55.429] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:09:55.429] <TB3> INFO: run 1 of 1
[15:09:55.767] <TB3> INFO: Expecting 1955200 events.
[15:10:29.909] <TB3> INFO: 930110 events read in total (33424ms).
[15:11:03.973] <TB3> INFO: 1857400 events read in total (67488ms).
[15:11:08.075] <TB3> INFO: 1955200 events read in total (71590ms).
[15:11:08.092] <TB3> INFO: Test took 72663ms.
[15:11:08.124] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:22.215] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 2.800871 .. 40.267099
[15:11:22.291] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 2 .. 50 (-1/-1) hits flags = 528 (plus default)
[15:11:22.300] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:11:22.300] <TB3> INFO: run 1 of 1
[15:11:22.604] <TB3> INFO: Expecting 2038400 events.
[15:11:58.096] <TB3> INFO: 1002050 events read in total (34777ms).
[15:12:33.216] <TB3> INFO: 2002590 events read in total (69897ms).
[15:12:34.946] <TB3> INFO: 2038400 events read in total (71628ms).
[15:12:34.960] <TB3> INFO: Test took 72660ms.
[15:12:34.984] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:48.497] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 2.800871 .. 40.267099
[15:12:48.575] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 50 (-1/-1) hits flags = 528 (plus default)
[15:12:48.583] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:12:48.583] <TB3> INFO: run 1 of 1
[15:12:48.889] <TB3> INFO: Expecting 2038400 events.
[15:13:24.389] <TB3> INFO: 1002330 events read in total (34784ms).
[15:13:59.416] <TB3> INFO: 2003830 events read in total (69811ms).
[15:14:01.190] <TB3> INFO: 2038400 events read in total (71586ms).
[15:14:01.206] <TB3> INFO: Test took 72624ms.
[15:14:01.237] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:14.623] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:14:14.623] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:14:14.633] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:14:14.633] <TB3> INFO: run 1 of 1
[15:14:14.940] <TB3> INFO: Expecting 1705600 events.
[15:14:49.171] <TB3> INFO: 879710 events read in total (33516ms).
[15:15:20.555] <TB3> INFO: 1705600 events read in total (64901ms).
[15:15:20.579] <TB3> INFO: Test took 65947ms.
[15:15:20.613] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:35.035] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:15:35.035] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:15:35.036] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:15:35.037] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:15:35.037] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:15:35.037] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:15:35.037] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:15:35.037] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:15:35.037] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:15:35.045] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:15:35.052] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:15:35.060] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:15:35.067] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:15:35.075] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:15:35.082] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:15:35.089] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:15:35.096] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:15:35.105] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:15:35.114] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:15:35.122] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:15:35.131] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:15:35.138] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:15:35.144] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:15:35.150] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:15:35.156] <TB3> INFO: PixTestTrim::trimTest() done
[15:15:35.156] <TB3> INFO: vtrim: 96 98 95 92 95 121 109 106 102 91 98 118 98 127 104 92
[15:15:35.156] <TB3> INFO: vthrcomp: 93 95 98 98 94 102 97 100 88 85 103 93 97 109 97 92
[15:15:35.156] <TB3> INFO: vcal mean: 34.99 34.98 34.99 34.97 35.02 34.96 34.90 34.93 34.98 34.97 34.98 35.00 34.99 35.01 35.00 35.02
[15:15:35.156] <TB3> INFO: vcal RMS: 0.80 0.84 0.81 0.82 0.81 0.87 0.81 0.86 0.89 0.83 0.84 0.84 0.80 0.87 0.86 0.95
[15:15:35.156] <TB3> INFO: bits mean: 9.45 9.20 9.84 10.07 9.16 9.27 9.80 9.47 9.07 9.34 9.64 9.54 9.43 8.62 9.16 8.85
[15:15:35.156] <TB3> INFO: bits RMS: 2.63 2.77 2.68 2.57 2.69 2.67 2.69 2.69 2.52 2.88 2.61 2.56 2.88 2.38 2.84 2.79
[15:15:35.164] <TB3> INFO: ----------------------------------------------------------------------
[15:15:35.164] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:15:35.164] <TB3> INFO: ----------------------------------------------------------------------
[15:15:35.168] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:15:35.176] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:15:35.176] <TB3> INFO: run 1 of 1
[15:15:35.479] <TB3> INFO: Expecting 4160000 events.
[15:16:15.981] <TB3> INFO: 957655 events read in total (39787ms).
[15:16:55.528] <TB3> INFO: 1907640 events read in total (79334ms).
[15:17:35.126] <TB3> INFO: 2847270 events read in total (118933ms).
[15:18:14.476] <TB3> INFO: 3784995 events read in total (158282ms).
[15:18:30.443] <TB3> INFO: 4160000 events read in total (174249ms).
[15:18:30.486] <TB3> INFO: Test took 175310ms.
[15:18:30.601] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:01.844] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[15:19:01.852] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:19:01.853] <TB3> INFO: run 1 of 1
[15:19:02.158] <TB3> INFO: Expecting 3723200 events.
[15:19:44.337] <TB3> INFO: 968885 events read in total (41463ms).
[15:20:24.233] <TB3> INFO: 1928625 events read in total (81359ms).
[15:21:03.853] <TB3> INFO: 2878225 events read in total (120979ms).
[15:21:39.995] <TB3> INFO: 3723200 events read in total (157121ms).
[15:21:40.033] <TB3> INFO: Test took 158180ms.
[15:21:40.115] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:08.599] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[15:22:08.607] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:22:08.607] <TB3> INFO: run 1 of 1
[15:22:08.910] <TB3> INFO: Expecting 3473600 events.
[15:22:49.863] <TB3> INFO: 1001380 events read in total (40237ms).
[15:23:31.648] <TB3> INFO: 1990120 events read in total (82022ms).
[15:24:11.864] <TB3> INFO: 2969575 events read in total (122238ms).
[15:24:33.543] <TB3> INFO: 3473600 events read in total (143917ms).
[15:24:33.577] <TB3> INFO: Test took 144969ms.
[15:24:33.653] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:01.479] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[15:25:01.489] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:25:01.489] <TB3> INFO: run 1 of 1
[15:25:01.812] <TB3> INFO: Expecting 3452800 events.
[15:25:43.956] <TB3> INFO: 1003995 events read in total (41428ms).
[15:26:26.158] <TB3> INFO: 1995105 events read in total (83630ms).
[15:27:06.870] <TB3> INFO: 2976375 events read in total (124342ms).
[15:27:26.568] <TB3> INFO: 3452800 events read in total (144040ms).
[15:27:26.608] <TB3> INFO: Test took 145118ms.
[15:27:26.676] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:53.930] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[15:27:53.938] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:27:53.939] <TB3> INFO: run 1 of 1
[15:27:54.252] <TB3> INFO: Expecting 3473600 events.
[15:28:35.826] <TB3> INFO: 1000360 events read in total (40859ms).
[15:29:16.875] <TB3> INFO: 1987900 events read in total (81908ms).
[15:29:57.818] <TB3> INFO: 2966060 events read in total (122851ms).
[15:30:19.643] <TB3> INFO: 3473600 events read in total (144676ms).
[15:30:19.675] <TB3> INFO: Test took 145736ms.
[15:30:19.751] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:45.666] <TB3> INFO: PixTestTrim::trimBitTest() done
[15:30:45.667] <TB3> INFO: PixTestTrim::doTest() done, duration: 2711 seconds
[15:30:45.667] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:45.667] <TB3> INFO: Decoding statistics:
[15:30:45.667] <TB3> INFO: General information:
[15:30:45.667] <TB3> INFO: 16bit words read: 0
[15:30:45.667] <TB3> INFO: valid events total: 0
[15:30:45.667] <TB3> INFO: empty events: 0
[15:30:45.667] <TB3> INFO: valid events with pixels: 0
[15:30:45.667] <TB3> INFO: valid pixel hits: 0
[15:30:45.667] <TB3> INFO: Event errors: 0
[15:30:45.668] <TB3> INFO: start marker: 0
[15:30:45.668] <TB3> INFO: stop marker: 0
[15:30:45.668] <TB3> INFO: overflow: 0
[15:30:45.668] <TB3> INFO: invalid 5bit words: 0
[15:30:45.668] <TB3> INFO: invalid XOR eye diagram: 0
[15:30:45.668] <TB3> INFO: TBM errors: 0
[15:30:45.668] <TB3> INFO: flawed TBM headers: 0
[15:30:45.668] <TB3> INFO: flawed TBM trailers: 0
[15:30:45.668] <TB3> INFO: event ID mismatches: 0
[15:30:45.668] <TB3> INFO: ROC errors: 0
[15:30:45.668] <TB3> INFO: missing ROC header(s): 0
[15:30:45.668] <TB3> INFO: misplaced readback start: 0
[15:30:45.668] <TB3> INFO: Pixel decoding errors: 0
[15:30:45.668] <TB3> INFO: pixel data incomplete: 0
[15:30:45.668] <TB3> INFO: pixel address: 0
[15:30:45.668] <TB3> INFO: pulse height fill bit: 0
[15:30:45.668] <TB3> INFO: buffer corruption: 0
[15:30:46.359] <TB3> INFO: ######################################################################
[15:30:46.359] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:30:46.359] <TB3> INFO: ######################################################################
[15:30:46.695] <TB3> INFO: Expecting 41600 events.
[15:30:51.035] <TB3> INFO: 41600 events read in total (3625ms).
[15:30:51.036] <TB3> INFO: Test took 4675ms.
[15:30:51.043] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:51.639] <TB3> INFO: Expecting 41600 events.
[15:30:56.108] <TB3> INFO: 41600 events read in total (3753ms).
[15:30:56.108] <TB3> INFO: Test took 4809ms.
[15:30:56.115] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:56.466] <TB3> INFO: Expecting 41600 events.
[15:31:00.780] <TB3> INFO: 41600 events read in total (3598ms).
[15:31:00.781] <TB3> INFO: Test took 4654ms.
[15:31:00.787] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:01.132] <TB3> INFO: Expecting 2560 events.
[15:31:02.195] <TB3> INFO: 2560 events read in total (347ms).
[15:31:02.195] <TB3> INFO: Test took 1401ms.
[15:31:02.609] <TB3> INFO: Expecting 2560 events.
[15:31:03.670] <TB3> INFO: 2560 events read in total (345ms).
[15:31:03.671] <TB3> INFO: Test took 1476ms.
[15:31:04.085] <TB3> INFO: Expecting 2560 events.
[15:31:05.146] <TB3> INFO: 2560 events read in total (346ms).
[15:31:05.146] <TB3> INFO: Test took 1475ms.
[15:31:05.567] <TB3> INFO: Expecting 2560 events.
[15:31:06.628] <TB3> INFO: 2560 events read in total (345ms).
[15:31:06.628] <TB3> INFO: Test took 1481ms.
[15:31:07.042] <TB3> INFO: Expecting 2560 events.
[15:31:08.104] <TB3> INFO: 2560 events read in total (346ms).
[15:31:08.104] <TB3> INFO: Test took 1475ms.
[15:31:08.519] <TB3> INFO: Expecting 2560 events.
[15:31:09.582] <TB3> INFO: 2560 events read in total (347ms).
[15:31:09.582] <TB3> INFO: Test took 1478ms.
[15:31:09.996] <TB3> INFO: Expecting 2560 events.
[15:31:11.057] <TB3> INFO: 2560 events read in total (346ms).
[15:31:11.057] <TB3> INFO: Test took 1475ms.
[15:31:11.471] <TB3> INFO: Expecting 2560 events.
[15:31:12.532] <TB3> INFO: 2560 events read in total (346ms).
[15:31:12.532] <TB3> INFO: Test took 1475ms.
[15:31:12.946] <TB3> INFO: Expecting 2560 events.
[15:31:14.007] <TB3> INFO: 2560 events read in total (346ms).
[15:31:14.007] <TB3> INFO: Test took 1475ms.
[15:31:14.421] <TB3> INFO: Expecting 2560 events.
[15:31:15.482] <TB3> INFO: 2560 events read in total (346ms).
[15:31:15.482] <TB3> INFO: Test took 1475ms.
[15:31:15.896] <TB3> INFO: Expecting 2560 events.
[15:31:16.960] <TB3> INFO: 2560 events read in total (348ms).
[15:31:16.961] <TB3> INFO: Test took 1478ms.
[15:31:17.374] <TB3> INFO: Expecting 2560 events.
[15:31:18.437] <TB3> INFO: 2560 events read in total (347ms).
[15:31:18.437] <TB3> INFO: Test took 1476ms.
[15:31:18.850] <TB3> INFO: Expecting 2560 events.
[15:31:19.916] <TB3> INFO: 2560 events read in total (350ms).
[15:31:19.916] <TB3> INFO: Test took 1479ms.
[15:31:20.330] <TB3> INFO: Expecting 2560 events.
[15:31:21.393] <TB3> INFO: 2560 events read in total (348ms).
[15:31:21.393] <TB3> INFO: Test took 1477ms.
[15:31:21.807] <TB3> INFO: Expecting 2560 events.
[15:31:22.870] <TB3> INFO: 2560 events read in total (347ms).
[15:31:22.870] <TB3> INFO: Test took 1477ms.
[15:31:23.284] <TB3> INFO: Expecting 2560 events.
[15:31:24.346] <TB3> INFO: 2560 events read in total (346ms).
[15:31:24.346] <TB3> INFO: Test took 1476ms.
[15:31:24.350] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:31:24.762] <TB3> INFO: Expecting 655360 events.
[15:31:39.288] <TB3> INFO: 655360 events read in total (13811ms).
[15:31:39.298] <TB3> INFO: Expecting 655360 events.
[15:31:53.058] <TB3> INFO: 655360 events read in total (13233ms).
[15:31:53.070] <TB3> INFO: Expecting 655360 events.
[15:32:06.878] <TB3> INFO: 655360 events read in total (13281ms).
[15:32:06.893] <TB3> INFO: Expecting 655360 events.
[15:32:20.649] <TB3> INFO: 655360 events read in total (13228ms).
[15:32:20.669] <TB3> INFO: Expecting 655360 events.
[15:32:34.313] <TB3> INFO: 655360 events read in total (13116ms).
[15:32:34.341] <TB3> INFO: Expecting 655360 events.
[15:32:48.016] <TB3> INFO: 655360 events read in total (13147ms).
[15:32:48.049] <TB3> INFO: Expecting 655360 events.
[15:33:02.348] <TB3> INFO: 655360 events read in total (13771ms).
[15:33:02.381] <TB3> INFO: Expecting 655360 events.
[15:33:16.027] <TB3> INFO: 655360 events read in total (13119ms).
[15:33:16.068] <TB3> INFO: Expecting 655360 events.
[15:33:29.698] <TB3> INFO: 655360 events read in total (13103ms).
[15:33:29.751] <TB3> INFO: Expecting 655360 events.
[15:33:44.051] <TB3> INFO: 655360 events read in total (13772ms).
[15:33:44.091] <TB3> INFO: Expecting 655360 events.
[15:33:58.691] <TB3> INFO: 655360 events read in total (14073ms).
[15:33:58.740] <TB3> INFO: Expecting 655360 events.
[15:34:12.690] <TB3> INFO: 655360 events read in total (13423ms).
[15:34:12.736] <TB3> INFO: Expecting 655360 events.
[15:34:27.366] <TB3> INFO: 655360 events read in total (14103ms).
[15:34:27.429] <TB3> INFO: Expecting 655360 events.
[15:34:41.719] <TB3> INFO: 655360 events read in total (13763ms).
[15:34:41.783] <TB3> INFO: Expecting 655360 events.
[15:34:55.644] <TB3> INFO: 655360 events read in total (13333ms).
[15:34:55.710] <TB3> INFO: Expecting 655360 events.
[15:35:09.991] <TB3> INFO: 655360 events read in total (13753ms).
[15:35:10.052] <TB3> INFO: Test took 225702ms.
[15:35:10.132] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:35:10.441] <TB3> INFO: Expecting 655360 events.
[15:35:24.499] <TB3> INFO: 655360 events read in total (13342ms).
[15:35:24.509] <TB3> INFO: Expecting 655360 events.
[15:35:38.137] <TB3> INFO: 655360 events read in total (13098ms).
[15:35:38.155] <TB3> INFO: Expecting 655360 events.
[15:35:51.939] <TB3> INFO: 655360 events read in total (13257ms).
[15:35:51.955] <TB3> INFO: Expecting 655360 events.
[15:36:06.207] <TB3> INFO: 655360 events read in total (13724ms).
[15:36:06.240] <TB3> INFO: Expecting 655360 events.
[15:36:20.526] <TB3> INFO: 655360 events read in total (13758ms).
[15:36:20.554] <TB3> INFO: Expecting 655360 events.
[15:36:34.799] <TB3> INFO: 655360 events read in total (13718ms).
[15:36:34.827] <TB3> INFO: Expecting 655360 events.
[15:36:49.260] <TB3> INFO: 655360 events read in total (13905ms).
[15:36:49.290] <TB3> INFO: Expecting 655360 events.
[15:37:03.485] <TB3> INFO: 655360 events read in total (13667ms).
[15:37:03.524] <TB3> INFO: Expecting 655360 events.
[15:37:17.824] <TB3> INFO: 655360 events read in total (13773ms).
[15:37:17.864] <TB3> INFO: Expecting 655360 events.
[15:37:32.172] <TB3> INFO: 655360 events read in total (13781ms).
[15:37:32.215] <TB3> INFO: Expecting 655360 events.
[15:37:46.390] <TB3> INFO: 655360 events read in total (13647ms).
[15:37:46.444] <TB3> INFO: Expecting 655360 events.
[15:38:00.505] <TB3> INFO: 655360 events read in total (13533ms).
[15:38:00.551] <TB3> INFO: Expecting 655360 events.
[15:38:14.863] <TB3> INFO: 655360 events read in total (13785ms).
[15:38:14.917] <TB3> INFO: Expecting 655360 events.
[15:38:29.298] <TB3> INFO: 655360 events read in total (13853ms).
[15:38:29.357] <TB3> INFO: Expecting 655360 events.
[15:38:44.005] <TB3> INFO: 655360 events read in total (14120ms).
[15:38:44.062] <TB3> INFO: Expecting 655360 events.
[15:38:58.579] <TB3> INFO: 655360 events read in total (13990ms).
[15:38:58.638] <TB3> INFO: Test took 228506ms.
[15:38:58.822] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.828] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.835] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.842] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.848] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.855] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.862] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.868] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.875] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[15:38:58.882] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[15:38:58.888] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[15:38:58.895] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[15:38:58.902] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[15:38:58.909] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[15:38:58.915] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[15:38:58.922] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[15:38:58.929] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[15:38:58.935] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.942] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.949] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.955] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.962] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.969] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.975] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:58.982] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[15:38:59.017] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:38:59.018] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:38:59.018] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:38:59.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:38:59.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:38:59.020] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:38:59.020] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:38:59.020] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:38:59.020] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:38:59.021] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:38:59.021] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:38:59.021] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:38:59.021] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:38:59.021] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:38:59.021] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:38:59.022] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:38:59.334] <TB3> INFO: Expecting 41600 events.
[15:39:03.348] <TB3> INFO: 41600 events read in total (3298ms).
[15:39:03.348] <TB3> INFO: Test took 4324ms.
[15:39:03.900] <TB3> INFO: Expecting 41600 events.
[15:39:07.951] <TB3> INFO: 41600 events read in total (3335ms).
[15:39:07.951] <TB3> INFO: Test took 4373ms.
[15:39:08.508] <TB3> INFO: Expecting 41600 events.
[15:39:12.427] <TB3> INFO: 41600 events read in total (3204ms).
[15:39:12.427] <TB3> INFO: Test took 4250ms.
[15:39:12.662] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:12.797] <TB3> INFO: Expecting 2560 events.
[15:39:13.865] <TB3> INFO: 2560 events read in total (352ms).
[15:39:13.866] <TB3> INFO: Test took 1204ms.
[15:39:13.868] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:14.281] <TB3> INFO: Expecting 2560 events.
[15:39:15.344] <TB3> INFO: 2560 events read in total (348ms).
[15:39:15.345] <TB3> INFO: Test took 1477ms.
[15:39:15.348] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:15.760] <TB3> INFO: Expecting 2560 events.
[15:39:16.828] <TB3> INFO: 2560 events read in total (352ms).
[15:39:16.828] <TB3> INFO: Test took 1480ms.
[15:39:16.833] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:17.243] <TB3> INFO: Expecting 2560 events.
[15:39:18.306] <TB3> INFO: 2560 events read in total (347ms).
[15:39:18.306] <TB3> INFO: Test took 1473ms.
[15:39:18.308] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:18.721] <TB3> INFO: Expecting 2560 events.
[15:39:19.784] <TB3> INFO: 2560 events read in total (347ms).
[15:39:19.784] <TB3> INFO: Test took 1476ms.
[15:39:19.786] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:20.200] <TB3> INFO: Expecting 2560 events.
[15:39:21.264] <TB3> INFO: 2560 events read in total (348ms).
[15:39:21.265] <TB3> INFO: Test took 1479ms.
[15:39:21.268] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:21.679] <TB3> INFO: Expecting 2560 events.
[15:39:22.749] <TB3> INFO: 2560 events read in total (354ms).
[15:39:22.749] <TB3> INFO: Test took 1481ms.
[15:39:22.754] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:23.164] <TB3> INFO: Expecting 2560 events.
[15:39:24.234] <TB3> INFO: 2560 events read in total (354ms).
[15:39:24.234] <TB3> INFO: Test took 1480ms.
[15:39:24.238] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:24.649] <TB3> INFO: Expecting 2560 events.
[15:39:25.717] <TB3> INFO: 2560 events read in total (352ms).
[15:39:25.717] <TB3> INFO: Test took 1479ms.
[15:39:25.719] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:26.133] <TB3> INFO: Expecting 2560 events.
[15:39:27.204] <TB3> INFO: 2560 events read in total (355ms).
[15:39:27.205] <TB3> INFO: Test took 1486ms.
[15:39:27.210] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:27.620] <TB3> INFO: Expecting 2560 events.
[15:39:28.690] <TB3> INFO: 2560 events read in total (354ms).
[15:39:28.690] <TB3> INFO: Test took 1481ms.
[15:39:28.695] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:29.105] <TB3> INFO: Expecting 2560 events.
[15:39:30.170] <TB3> INFO: 2560 events read in total (349ms).
[15:39:30.171] <TB3> INFO: Test took 1476ms.
[15:39:30.173] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:30.585] <TB3> INFO: Expecting 2560 events.
[15:39:31.656] <TB3> INFO: 2560 events read in total (355ms).
[15:39:31.656] <TB3> INFO: Test took 1483ms.
[15:39:31.659] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:32.072] <TB3> INFO: Expecting 2560 events.
[15:39:33.137] <TB3> INFO: 2560 events read in total (350ms).
[15:39:33.138] <TB3> INFO: Test took 1479ms.
[15:39:33.140] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:33.553] <TB3> INFO: Expecting 2560 events.
[15:39:34.625] <TB3> INFO: 2560 events read in total (356ms).
[15:39:34.625] <TB3> INFO: Test took 1485ms.
[15:39:34.628] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:35.040] <TB3> INFO: Expecting 2560 events.
[15:39:36.105] <TB3> INFO: 2560 events read in total (350ms).
[15:39:36.105] <TB3> INFO: Test took 1477ms.
[15:39:36.106] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:36.520] <TB3> INFO: Expecting 2560 events.
[15:39:37.585] <TB3> INFO: 2560 events read in total (350ms).
[15:39:37.585] <TB3> INFO: Test took 1479ms.
[15:39:37.593] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:38.001] <TB3> INFO: Expecting 2560 events.
[15:39:39.070] <TB3> INFO: 2560 events read in total (354ms).
[15:39:39.070] <TB3> INFO: Test took 1477ms.
[15:39:39.077] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:39.485] <TB3> INFO: Expecting 2560 events.
[15:39:40.555] <TB3> INFO: 2560 events read in total (354ms).
[15:39:40.555] <TB3> INFO: Test took 1479ms.
[15:39:40.560] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:40.969] <TB3> INFO: Expecting 2560 events.
[15:39:42.040] <TB3> INFO: 2560 events read in total (355ms).
[15:39:42.040] <TB3> INFO: Test took 1480ms.
[15:39:42.043] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:42.455] <TB3> INFO: Expecting 2560 events.
[15:39:43.525] <TB3> INFO: 2560 events read in total (354ms).
[15:39:43.526] <TB3> INFO: Test took 1483ms.
[15:39:43.529] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:43.941] <TB3> INFO: Expecting 2560 events.
[15:39:45.007] <TB3> INFO: 2560 events read in total (352ms).
[15:39:45.008] <TB3> INFO: Test took 1480ms.
[15:39:45.013] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:45.423] <TB3> INFO: Expecting 2560 events.
[15:39:46.494] <TB3> INFO: 2560 events read in total (355ms).
[15:39:46.494] <TB3> INFO: Test took 1482ms.
[15:39:46.498] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:46.909] <TB3> INFO: Expecting 2560 events.
[15:39:47.979] <TB3> INFO: 2560 events read in total (354ms).
[15:39:47.979] <TB3> INFO: Test took 1481ms.
[15:39:47.982] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:48.394] <TB3> INFO: Expecting 2560 events.
[15:39:49.464] <TB3> INFO: 2560 events read in total (354ms).
[15:39:49.464] <TB3> INFO: Test took 1482ms.
[15:39:49.467] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:49.879] <TB3> INFO: Expecting 2560 events.
[15:39:50.943] <TB3> INFO: 2560 events read in total (348ms).
[15:39:50.943] <TB3> INFO: Test took 1476ms.
[15:39:50.948] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:51.359] <TB3> INFO: Expecting 2560 events.
[15:39:52.426] <TB3> INFO: 2560 events read in total (351ms).
[15:39:52.426] <TB3> INFO: Test took 1479ms.
[15:39:52.430] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:52.841] <TB3> INFO: Expecting 2560 events.
[15:39:53.909] <TB3> INFO: 2560 events read in total (352ms).
[15:39:53.910] <TB3> INFO: Test took 1481ms.
[15:39:53.912] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:54.325] <TB3> INFO: Expecting 2560 events.
[15:39:55.388] <TB3> INFO: 2560 events read in total (347ms).
[15:39:55.388] <TB3> INFO: Test took 1476ms.
[15:39:55.392] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:55.802] <TB3> INFO: Expecting 2560 events.
[15:39:56.864] <TB3> INFO: 2560 events read in total (346ms).
[15:39:56.864] <TB3> INFO: Test took 1472ms.
[15:39:56.868] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:57.280] <TB3> INFO: Expecting 2560 events.
[15:39:58.349] <TB3> INFO: 2560 events read in total (353ms).
[15:39:58.349] <TB3> INFO: Test took 1481ms.
[15:39:58.351] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:58.763] <TB3> INFO: Expecting 2560 events.
[15:39:59.832] <TB3> INFO: 2560 events read in total (353ms).
[15:39:59.833] <TB3> INFO: Test took 1482ms.
[15:40:00.472] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 554 seconds
[15:40:00.472] <TB3> INFO: PH scale (per ROC): 71 67 77 70 75 70 70 72 69 69 71 68 71 68 67 66
[15:40:00.472] <TB3> INFO: PH offset (per ROC): 175 193 171 175 190 175 189 176 174 155 182 161 176 194 176 190
[15:40:00.481] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:00.481] <TB3> INFO: Decoding statistics:
[15:40:00.481] <TB3> INFO: General information:
[15:40:00.481] <TB3> INFO: 16bit words read: 91932
[15:40:00.481] <TB3> INFO: valid events total: 10240
[15:40:00.481] <TB3> INFO: empty events: 7737
[15:40:00.481] <TB3> INFO: valid events with pixels: 2503
[15:40:00.481] <TB3> INFO: valid pixel hits: 2503
[15:40:00.481] <TB3> INFO: Event errors: 0
[15:40:00.481] <TB3> INFO: start marker: 0
[15:40:00.481] <TB3> INFO: stop marker: 0
[15:40:00.481] <TB3> INFO: overflow: 0
[15:40:00.481] <TB3> INFO: invalid 5bit words: 0
[15:40:00.481] <TB3> INFO: invalid XOR eye diagram: 0
[15:40:00.481] <TB3> INFO: TBM errors: 0
[15:40:00.481] <TB3> INFO: flawed TBM headers: 0
[15:40:00.481] <TB3> INFO: flawed TBM trailers: 0
[15:40:00.481] <TB3> INFO: event ID mismatches: 0
[15:40:00.481] <TB3> INFO: ROC errors: 0
[15:40:00.481] <TB3> INFO: missing ROC header(s): 0
[15:40:00.481] <TB3> INFO: misplaced readback start: 0
[15:40:00.481] <TB3> INFO: Pixel decoding errors: 0
[15:40:00.481] <TB3> INFO: pixel data incomplete: 0
[15:40:00.481] <TB3> INFO: pixel address: 0
[15:40:00.481] <TB3> INFO: pulse height fill bit: 0
[15:40:00.481] <TB3> INFO: buffer corruption: 0
[15:40:00.664] <TB3> INFO: ######################################################################
[15:40:00.665] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[15:40:00.665] <TB3> INFO: ######################################################################
[15:40:00.675] <TB3> INFO: scanning low vcal = 10
[15:40:00.988] <TB3> INFO: Expecting 41600 events.
[15:40:04.809] <TB3> INFO: 41600 events read in total (3102ms).
[15:40:04.809] <TB3> INFO: Test took 4134ms.
[15:40:04.811] <TB3> INFO: scanning low vcal = 20
[15:40:05.224] <TB3> INFO: Expecting 41600 events.
[15:40:08.998] <TB3> INFO: 41600 events read in total (3058ms).
[15:40:08.998] <TB3> INFO: Test took 4187ms.
[15:40:09.002] <TB3> INFO: scanning low vcal = 30
[15:40:09.412] <TB3> INFO: Expecting 41600 events.
[15:40:13.240] <TB3> INFO: 41600 events read in total (3112ms).
[15:40:13.241] <TB3> INFO: Test took 4239ms.
[15:40:13.243] <TB3> INFO: scanning low vcal = 40
[15:40:13.649] <TB3> INFO: Expecting 41600 events.
[15:40:17.920] <TB3> INFO: 41600 events read in total (3555ms).
[15:40:17.920] <TB3> INFO: Test took 4677ms.
[15:40:17.924] <TB3> INFO: scanning low vcal = 50
[15:40:18.259] <TB3> INFO: Expecting 41600 events.
[15:40:22.633] <TB3> INFO: 41600 events read in total (3658ms).
[15:40:22.633] <TB3> INFO: Test took 4709ms.
[15:40:22.636] <TB3> INFO: scanning low vcal = 60
[15:40:22.985] <TB3> INFO: Expecting 41600 events.
[15:40:27.238] <TB3> INFO: 41600 events read in total (3538ms).
[15:40:27.239] <TB3> INFO: Test took 4603ms.
[15:40:27.241] <TB3> INFO: scanning low vcal = 70
[15:40:27.598] <TB3> INFO: Expecting 41600 events.
[15:40:31.910] <TB3> INFO: 41600 events read in total (3597ms).
[15:40:31.910] <TB3> INFO: Test took 4669ms.
[15:40:31.913] <TB3> INFO: scanning low vcal = 80
[15:40:32.267] <TB3> INFO: Expecting 41600 events.
[15:40:36.665] <TB3> INFO: 41600 events read in total (3682ms).
[15:40:36.666] <TB3> INFO: Test took 4753ms.
[15:40:36.668] <TB3> INFO: scanning low vcal = 90
[15:40:37.003] <TB3> INFO: Expecting 41600 events.
[15:40:41.341] <TB3> INFO: 41600 events read in total (3623ms).
[15:40:41.342] <TB3> INFO: Test took 4674ms.
[15:40:41.345] <TB3> INFO: scanning low vcal = 100
[15:40:41.688] <TB3> INFO: Expecting 41600 events.
[15:40:46.082] <TB3> INFO: 41600 events read in total (3678ms).
[15:40:46.083] <TB3> INFO: Test took 4738ms.
[15:40:46.086] <TB3> INFO: scanning low vcal = 110
[15:40:46.440] <TB3> INFO: Expecting 41600 events.
[15:40:50.709] <TB3> INFO: 41600 events read in total (3553ms).
[15:40:50.710] <TB3> INFO: Test took 4624ms.
[15:40:50.727] <TB3> INFO: scanning low vcal = 120
[15:40:51.060] <TB3> INFO: Expecting 41600 events.
[15:40:55.344] <TB3> INFO: 41600 events read in total (3569ms).
[15:40:55.345] <TB3> INFO: Test took 4618ms.
[15:40:55.347] <TB3> INFO: scanning low vcal = 130
[15:40:55.699] <TB3> INFO: Expecting 41600 events.
[15:40:59.997] <TB3> INFO: 41600 events read in total (3582ms).
[15:40:59.998] <TB3> INFO: Test took 4651ms.
[15:41:00.000] <TB3> INFO: scanning low vcal = 140
[15:41:00.351] <TB3> INFO: Expecting 41600 events.
[15:41:04.657] <TB3> INFO: 41600 events read in total (3590ms).
[15:41:04.658] <TB3> INFO: Test took 4658ms.
[15:41:04.661] <TB3> INFO: scanning low vcal = 150
[15:41:05.009] <TB3> INFO: Expecting 41600 events.
[15:41:09.316] <TB3> INFO: 41600 events read in total (3591ms).
[15:41:09.316] <TB3> INFO: Test took 4655ms.
[15:41:09.318] <TB3> INFO: scanning low vcal = 160
[15:41:09.667] <TB3> INFO: Expecting 41600 events.
[15:41:13.977] <TB3> INFO: 41600 events read in total (3594ms).
[15:41:13.978] <TB3> INFO: Test took 4659ms.
[15:41:13.981] <TB3> INFO: scanning low vcal = 170
[15:41:14.331] <TB3> INFO: Expecting 41600 events.
[15:41:18.591] <TB3> INFO: 41600 events read in total (3544ms).
[15:41:18.592] <TB3> INFO: Test took 4611ms.
[15:41:18.595] <TB3> INFO: scanning low vcal = 180
[15:41:18.948] <TB3> INFO: Expecting 41600 events.
[15:41:23.188] <TB3> INFO: 41600 events read in total (3525ms).
[15:41:23.189] <TB3> INFO: Test took 4594ms.
[15:41:23.191] <TB3> INFO: scanning low vcal = 190
[15:41:23.541] <TB3> INFO: Expecting 41600 events.
[15:41:27.953] <TB3> INFO: 41600 events read in total (3696ms).
[15:41:27.953] <TB3> INFO: Test took 4762ms.
[15:41:27.956] <TB3> INFO: scanning low vcal = 200
[15:41:28.304] <TB3> INFO: Expecting 41600 events.
[15:41:32.632] <TB3> INFO: 41600 events read in total (3612ms).
[15:41:32.633] <TB3> INFO: Test took 4677ms.
[15:41:32.636] <TB3> INFO: scanning low vcal = 210
[15:41:32.987] <TB3> INFO: Expecting 41600 events.
[15:41:37.326] <TB3> INFO: 41600 events read in total (3624ms).
[15:41:37.327] <TB3> INFO: Test took 4691ms.
[15:41:37.330] <TB3> INFO: scanning low vcal = 220
[15:41:37.670] <TB3> INFO: Expecting 41600 events.
[15:41:42.054] <TB3> INFO: 41600 events read in total (3668ms).
[15:41:42.054] <TB3> INFO: Test took 4724ms.
[15:41:42.056] <TB3> INFO: scanning low vcal = 230
[15:41:42.406] <TB3> INFO: Expecting 41600 events.
[15:41:46.776] <TB3> INFO: 41600 events read in total (3654ms).
[15:41:46.776] <TB3> INFO: Test took 4720ms.
[15:41:46.779] <TB3> INFO: scanning low vcal = 240
[15:41:47.133] <TB3> INFO: Expecting 41600 events.
[15:41:51.451] <TB3> INFO: 41600 events read in total (3602ms).
[15:41:51.451] <TB3> INFO: Test took 4672ms.
[15:41:51.454] <TB3> INFO: scanning low vcal = 250
[15:41:51.774] <TB3> INFO: Expecting 41600 events.
[15:41:56.122] <TB3> INFO: 41600 events read in total (3632ms).
[15:41:56.123] <TB3> INFO: Test took 4669ms.
[15:41:56.127] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[15:41:56.472] <TB3> INFO: Expecting 41600 events.
[15:42:00.818] <TB3> INFO: 41600 events read in total (3630ms).
[15:42:00.819] <TB3> INFO: Test took 4692ms.
[15:42:00.822] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[15:42:01.172] <TB3> INFO: Expecting 41600 events.
[15:42:05.504] <TB3> INFO: 41600 events read in total (3616ms).
[15:42:05.505] <TB3> INFO: Test took 4683ms.
[15:42:05.508] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[15:42:05.842] <TB3> INFO: Expecting 41600 events.
[15:42:10.189] <TB3> INFO: 41600 events read in total (3631ms).
[15:42:10.190] <TB3> INFO: Test took 4682ms.
[15:42:10.193] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[15:42:10.542] <TB3> INFO: Expecting 41600 events.
[15:42:14.903] <TB3> INFO: 41600 events read in total (3645ms).
[15:42:14.903] <TB3> INFO: Test took 4710ms.
[15:42:14.906] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[15:42:15.251] <TB3> INFO: Expecting 41600 events.
[15:42:19.586] <TB3> INFO: 41600 events read in total (3619ms).
[15:42:19.587] <TB3> INFO: Test took 4681ms.
[15:42:20.047] <TB3> INFO: PixTestGainPedestal::measure() done
[15:42:53.967] <TB3> INFO: PixTestGainPedestal::fit() done
[15:42:53.967] <TB3> INFO: non-linearity mean: 0.957 0.957 0.959 0.958 0.955 0.965 0.957 0.960 0.960 0.961 0.958 0.953 0.965 0.960 0.960 0.959
[15:42:53.967] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.008 0.005 0.007 0.004 0.007 0.006 0.007 0.006 0.006 0.007 0.006 0.009
[15:42:53.967] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[15:42:53.988] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[15:42:54.008] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[15:42:54.028] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[15:42:54.048] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[15:42:54.069] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[15:42:54.089] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[15:42:54.109] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[15:42:54.130] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[15:42:54.150] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[15:42:54.170] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[15:42:54.190] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[15:42:54.211] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[15:42:54.231] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[15:42:54.251] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[15:42:54.272] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[15:42:54.292] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 173 seconds
[15:42:54.292] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:54.292] <TB3> INFO: Decoding statistics:
[15:42:54.292] <TB3> INFO: General information:
[15:42:54.292] <TB3> INFO: 16bit words read: 2662400
[15:42:54.292] <TB3> INFO: valid events total: 166400
[15:42:54.292] <TB3> INFO: empty events: 0
[15:42:54.292] <TB3> INFO: valid events with pixels: 166400
[15:42:54.292] <TB3> INFO: valid pixel hits: 665590
[15:42:54.292] <TB3> INFO: Event errors: 0
[15:42:54.292] <TB3> INFO: start marker: 0
[15:42:54.292] <TB3> INFO: stop marker: 0
[15:42:54.292] <TB3> INFO: overflow: 0
[15:42:54.292] <TB3> INFO: invalid 5bit words: 0
[15:42:54.292] <TB3> INFO: invalid XOR eye diagram: 0
[15:42:54.292] <TB3> INFO: TBM errors: 0
[15:42:54.292] <TB3> INFO: flawed TBM headers: 0
[15:42:54.292] <TB3> INFO: flawed TBM trailers: 0
[15:42:54.292] <TB3> INFO: event ID mismatches: 0
[15:42:54.292] <TB3> INFO: ROC errors: 0
[15:42:54.292] <TB3> INFO: missing ROC header(s): 0
[15:42:54.292] <TB3> INFO: misplaced readback start: 0
[15:42:54.292] <TB3> INFO: Pixel decoding errors: 0
[15:42:54.292] <TB3> INFO: pixel data incomplete: 0
[15:42:54.292] <TB3> INFO: pixel address: 0
[15:42:54.292] <TB3> INFO: pulse height fill bit: 0
[15:42:54.292] <TB3> INFO: buffer corruption: 0
[15:42:54.299] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C15.dat
[15:42:54.300] <TB3> INFO: ######################################################################
[15:42:54.300] <TB3> INFO: PixTestReadback::doTest()
[15:42:54.300] <TB3> INFO: ######################################################################
[15:42:54.301] <TB3> INFO: PixTestReadback::RES sent once
[15:43:05.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C0.dat
[15:43:05.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C1.dat
[15:43:05.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C2.dat
[15:43:05.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C3.dat
[15:43:05.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C4.dat
[15:43:05.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C5.dat
[15:43:05.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C6.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C7.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C8.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C9.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C10.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C11.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C12.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C13.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C14.dat
[15:43:05.565] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C15.dat
[15:43:05.598] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:43:05.600] <TB3> INFO: PixTestReadback::RES sent once
[15:43:16.798] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C0.dat
[15:43:16.798] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C1.dat
[15:43:16.798] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C2.dat
[15:43:16.799] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C3.dat
[15:43:16.799] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C4.dat
[15:43:16.799] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C5.dat
[15:43:16.799] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C6.dat
[15:43:16.799] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C7.dat
[15:43:16.800] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C8.dat
[15:43:16.800] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C9.dat
[15:43:16.801] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C10.dat
[15:43:16.801] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C11.dat
[15:43:16.801] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C12.dat
[15:43:16.801] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C13.dat
[15:43:16.801] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C14.dat
[15:43:16.801] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C15.dat
[15:43:16.837] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:43:16.838] <TB3> INFO: PixTestReadback::RES sent once
[15:43:25.449] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:43:25.449] <TB3> INFO: Vbg will be calibrated using Vd calibration
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150calibrated Vbg = 1.21018 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.1calibrated Vbg = 1.20638 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 158.2calibrated Vbg = 1.21288 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 144.8calibrated Vbg = 1.21794 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.3calibrated Vbg = 1.21698 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.8calibrated Vbg = 1.22911 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151.7calibrated Vbg = 1.2226 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151.8calibrated Vbg = 1.22447 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 144calibrated Vbg = 1.23078 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 143calibrated Vbg = 1.22312 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 148.2calibrated Vbg = 1.21984 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 162.5calibrated Vbg = 1.21813 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.8calibrated Vbg = 1.20538 :::*/*/*/*/
[15:43:25.449] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 159calibrated Vbg = 1.2038 :::*/*/*/*/
[15:43:25.450] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.9calibrated Vbg = 1.21586 :::*/*/*/*/
[15:43:25.450] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 152.4calibrated Vbg = 1.21054 :::*/*/*/*/
[15:43:25.453] <TB3> INFO: PixTestReadback::RES sent once
[15:46:19.783] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C0.dat
[15:46:19.783] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C1.dat
[15:46:19.783] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C2.dat
[15:46:19.783] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C3.dat
[15:46:19.783] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C4.dat
[15:46:19.783] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C5.dat
[15:46:19.783] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C6.dat
[15:46:19.784] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C7.dat
[15:46:19.784] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C8.dat
[15:46:19.784] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C9.dat
[15:46:19.784] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C10.dat
[15:46:19.784] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C11.dat
[15:46:19.784] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C12.dat
[15:46:19.784] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C13.dat
[15:46:19.784] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C14.dat
[15:46:19.785] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2128_FullQualification_2015-12-15_09h42m_1450168934//004_FulltestPxar_p17//readbackCal_C15.dat
[15:46:19.807] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:46:19.808] <TB3> INFO: PixTestReadback::doTest() done
[15:46:19.808] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:19.808] <TB3> INFO: Decoding statistics:
[15:46:19.808] <TB3> INFO: General information:
[15:46:19.808] <TB3> INFO: 16bit words read: 1024
[15:46:19.808] <TB3> INFO: valid events total: 128
[15:46:19.808] <TB3> INFO: empty events: 128
[15:46:19.808] <TB3> INFO: valid events with pixels: 0
[15:46:19.808] <TB3> INFO: valid pixel hits: 0
[15:46:19.808] <TB3> INFO: Event errors: 0
[15:46:19.808] <TB3> INFO: start marker: 0
[15:46:19.808] <TB3> INFO: stop marker: 0
[15:46:19.808] <TB3> INFO: overflow: 0
[15:46:19.808] <TB3> INFO: invalid 5bit words: 0
[15:46:19.808] <TB3> INFO: invalid XOR eye diagram: 0
[15:46:19.808] <TB3> INFO: TBM errors: 0
[15:46:19.808] <TB3> INFO: flawed TBM headers: 0
[15:46:19.808] <TB3> INFO: flawed TBM trailers: 0
[15:46:19.808] <TB3> INFO: event ID mismatches: 0
[15:46:19.808] <TB3> INFO: ROC errors: 0
[15:46:19.808] <TB3> INFO: missing ROC header(s): 0
[15:46:19.808] <TB3> INFO: misplaced readback start: 0
[15:46:19.808] <TB3> INFO: Pixel decoding errors: 0
[15:46:19.808] <TB3> INFO: pixel data incomplete: 0
[15:46:19.808] <TB3> INFO: pixel address: 0
[15:46:19.808] <TB3> INFO: pulse height fill bit: 0
[15:46:19.808] <TB3> INFO: buffer corruption: 0
[15:46:19.823] <TB3> INFO: Decoding statistics:
[15:46:19.823] <TB3> INFO: General information:
[15:46:19.823] <TB3> INFO: 16bit words read: 2755356
[15:46:19.823] <TB3> INFO: valid events total: 176768
[15:46:19.823] <TB3> INFO: empty events: 7865
[15:46:19.823] <TB3> INFO: valid events with pixels: 168903
[15:46:19.823] <TB3> INFO: valid pixel hits: 668093
[15:46:19.823] <TB3> INFO: Event errors: 0
[15:46:19.823] <TB3> INFO: start marker: 0
[15:46:19.823] <TB3> INFO: stop marker: 0
[15:46:19.823] <TB3> INFO: overflow: 0
[15:46:19.823] <TB3> INFO: invalid 5bit words: 0
[15:46:19.823] <TB3> INFO: invalid XOR eye diagram: 0
[15:46:19.823] <TB3> INFO: TBM errors: 0
[15:46:19.823] <TB3> INFO: flawed TBM headers: 0
[15:46:19.823] <TB3> INFO: flawed TBM trailers: 0
[15:46:19.823] <TB3> INFO: event ID mismatches: 0
[15:46:19.823] <TB3> INFO: ROC errors: 0
[15:46:19.823] <TB3> INFO: missing ROC header(s): 0
[15:46:19.823] <TB3> INFO: misplaced readback start: 0
[15:46:19.823] <TB3> INFO: Pixel decoding errors: 0
[15:46:19.823] <TB3> INFO: pixel data incomplete: 0
[15:46:19.823] <TB3> INFO: pixel address: 0
[15:46:19.823] <TB3> INFO: pulse height fill bit: 0
[15:46:19.823] <TB3> INFO: buffer corruption: 0
[15:46:19.823] <TB3> INFO: enter test to run
[15:46:19.824] <TB3> INFO: test: exit no parameter change
[15:46:20.050] <TB3> QUIET: Connection to board 170 closed.
[15:46:20.129] <TB3> INFO: pXar: this is the end, my friend