[06:06:35.679] <TB1> INFO: *** Welcome to pxar ***
[06:06:35.679] <TB1> INFO: *** Today: 2016/08/19
[06:06:35.787] <TB1> INFO: *** Version: fe94
[06:06:35.787] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C15.dat
[06:06:35.788] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//tbmParameters_C0b.dat
[06:06:35.788] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//defaultMaskFile.dat
[06:06:35.788] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters_C15.dat
[06:06:35.854] <TB1> INFO: clk: 4
[06:06:35.854] <TB1> INFO: ctr: 4
[06:06:35.854] <TB1> INFO: sda: 19
[06:06:35.855] <TB1> INFO: tin: 9
[06:06:35.855] <TB1> INFO: level: 15
[06:06:35.855] <TB1> INFO: triggerdelay: 0
[06:06:35.855] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[06:06:35.855] <TB1> INFO: Log level: INFO
[06:06:35.861] <TB1> INFO: Found DTB DTB_WXBYFL
[06:06:35.872] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[06:06:35.874] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[06:06:35.876] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[06:06:37.409] <TB1> INFO: DUT info:
[06:06:37.409] <TB1> INFO: The DUT currently contains the following objects:
[06:06:37.409] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[06:06:37.409] <TB1> INFO: TBM Core alpha (0): 7 registers set
[06:06:37.409] <TB1> INFO: TBM Core beta (1): 7 registers set
[06:06:37.409] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:06:37.409] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.409] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:37.810] <TB1> INFO: enter 'restricted' command line mode
[06:06:37.810] <TB1> INFO: enter test to run
[06:06:37.810] <TB1> INFO: test: pretest no parameter change
[06:06:37.811] <TB1> INFO: running: pretest
[06:06:37.817] <TB1> INFO: ----------------------------------------------------------------------
[06:06:37.817] <TB1> INFO: PixTestPretest::programROC()
[06:06:37.817] <TB1> INFO: ----------------------------------------------------------------------
[06:06:55.835] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[06:06:55.835] <TB1> INFO: IA differences per ROC: 19.3 19.3 18.5 17.7 20.1 20.1 20.9 19.3 19.3 17.7 21.7 18.5 20.9 20.1 20.9 17.7
[06:06:55.931] <TB1> INFO: enter test to run
[06:06:55.931] <TB1> INFO: test: pretest no parameter change
[06:06:55.931] <TB1> INFO: running: pretest
[06:06:55.932] <TB1> INFO: ----------------------------------------------------------------------
[06:06:55.932] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[06:06:55.932] <TB1> INFO: ----------------------------------------------------------------------
[06:07:02.631] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[06:07:02.631] <TB1> INFO: i(loss) [mA/ROC]: 19.3 18.5 19.3 18.5 18.5 19.3 19.3 19.3 18.5 18.5 18.5 19.3 19.3 18.5 19.3 19.3
[06:07:02.667] <TB1> INFO: enter test to run
[06:07:02.667] <TB1> INFO: test: pretest no parameter change
[06:07:02.667] <TB1> INFO: running: pretest
[06:07:02.667] <TB1> INFO: ----------------------------------------------------------------------
[06:07:02.667] <TB1> INFO: PixTestPretest::findTiming()
[06:07:02.667] <TB1> INFO: ----------------------------------------------------------------------
[06:07:02.667] <TB1> INFO: PixTestCmd::init()

[06:08:32.118] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 4, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[06:08:32.118] <TB1> INFO: (success/tries = 100/100), width = 5
[06:08:32.119] <TB1> INFO: enter test to run
[06:08:32.119] <TB1> INFO: test: pretest no parameter change
[06:08:32.119] <TB1> INFO: running: pretest
[06:08:32.120] <TB1> INFO: ----------------------------------------------------------------------
[06:08:32.120] <TB1> INFO: PixTestPretest::findWorkingPixel()
[06:08:32.120] <TB1> INFO: ----------------------------------------------------------------------
[06:08:32.210] <TB1> INFO: Expecting 231680 events.


[06:08:42.438] <TB1> INFO: 231680 events read in total (9671ms).
[06:08:42.443] <TB1> INFO: Test took 10320ms.
[06:08:42.667] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[06:08:42.729] <TB1> INFO: enter test to run
[06:08:42.729] <TB1> INFO: test: pretest no parameter change
[06:08:42.729] <TB1> INFO: running: pretest
[06:08:42.730] <TB1> INFO: ----------------------------------------------------------------------
[06:08:42.730] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[06:08:42.730] <TB1> INFO: ----------------------------------------------------------------------
[06:08:42.822] <TB1> INFO: Expecting 231680 events.
[06:08:52.993] <TB1> INFO: 231680 events read in total (9614ms).
[06:08:52.998] <TB1> INFO: Test took 10264ms.
[06:08:53.250] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[06:08:53.250] <TB1> INFO: CalDel: 160 159 166 144 136 135 160 142 168 173 170 162 148 149 149 166
[06:08:53.250] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[06:08:53.290] <TB1> INFO: enter test to run
[06:08:53.290] <TB1> INFO: test: pretest no parameter change
[06:08:53.290] <TB1> INFO: running: pretest
[06:08:53.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C0.dat
[06:08:53.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C1.dat
[06:08:53.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C2.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C3.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C4.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C5.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C6.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C7.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C8.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C9.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C10.dat
[06:08:53.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C11.dat
[06:08:53.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C12.dat
[06:08:53.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C13.dat
[06:08:53.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C14.dat
[06:08:53.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters_C15.dat
[06:08:53.292] <TB1> INFO: enter test to run
[06:08:53.292] <TB1> INFO: test: fulltest no parameter change
[06:08:53.292] <TB1> INFO: running: fulltest
[06:08:53.292] <TB1> INFO: ######################################################################
[06:08:53.292] <TB1> INFO: PixTestFullTest::doTest()
[06:08:53.292] <TB1> INFO: ######################################################################
[06:08:53.293] <TB1> INFO: ######################################################################
[06:08:53.293] <TB1> INFO: PixTestAlive::doTest()
[06:08:53.294] <TB1> INFO: ######################################################################
[06:08:53.295] <TB1> INFO: ----------------------------------------------------------------------
[06:08:53.295] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:08:53.295] <TB1> INFO: ----------------------------------------------------------------------
[06:08:53.567] <TB1> INFO: Expecting 41600 events.
[06:08:57.341] <TB1> INFO: 41600 events read in total (3218ms).
[06:08:57.342] <TB1> INFO: Test took 4046ms.
[06:08:57.567] <TB1> INFO: PixTestAlive::aliveTest() done
[06:08:57.567] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[06:08:57.568] <TB1> INFO: ----------------------------------------------------------------------
[06:08:57.568] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:08:57.568] <TB1> INFO: ----------------------------------------------------------------------
[06:08:57.832] <TB1> INFO: Expecting 41600 events.
[06:09:00.784] <TB1> INFO: 41600 events read in total (2395ms).
[06:09:00.784] <TB1> INFO: Test took 3214ms.
[06:09:00.784] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[06:09:01.030] <TB1> INFO: PixTestAlive::maskTest() done
[06:09:01.030] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:09:01.032] <TB1> INFO: ----------------------------------------------------------------------
[06:09:01.032] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:09:01.032] <TB1> INFO: ----------------------------------------------------------------------
[06:09:01.331] <TB1> INFO: Expecting 41600 events.
[06:09:05.073] <TB1> INFO: 41600 events read in total (3185ms).
[06:09:05.074] <TB1> INFO: Test took 4040ms.
[06:09:05.307] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[06:09:05.307] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:09:05.307] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[06:09:05.307] <TB1> INFO: Decoding statistics:
[06:09:05.307] <TB1> INFO: General information:
[06:09:05.307] <TB1> INFO: 16bit words read: 0
[06:09:05.307] <TB1> INFO: valid events total: 0
[06:09:05.307] <TB1> INFO: empty events: 0
[06:09:05.307] <TB1> INFO: valid events with pixels: 0
[06:09:05.307] <TB1> INFO: valid pixel hits: 0
[06:09:05.307] <TB1> INFO: Event errors: 0
[06:09:05.307] <TB1> INFO: start marker: 0
[06:09:05.307] <TB1> INFO: stop marker: 0
[06:09:05.307] <TB1> INFO: overflow: 0
[06:09:05.307] <TB1> INFO: invalid 5bit words: 0
[06:09:05.307] <TB1> INFO: invalid XOR eye diagram: 0
[06:09:05.307] <TB1> INFO: frame (failed synchr.): 0
[06:09:05.307] <TB1> INFO: idle data (no TBM trl): 0
[06:09:05.307] <TB1> INFO: no data (only TBM hdr): 0
[06:09:05.307] <TB1> INFO: TBM errors: 0
[06:09:05.307] <TB1> INFO: flawed TBM headers: 0
[06:09:05.307] <TB1> INFO: flawed TBM trailers: 0
[06:09:05.307] <TB1> INFO: event ID mismatches: 0
[06:09:05.307] <TB1> INFO: ROC errors: 0
[06:09:05.307] <TB1> INFO: missing ROC header(s): 0
[06:09:05.307] <TB1> INFO: misplaced readback start: 0
[06:09:05.307] <TB1> INFO: Pixel decoding errors: 0
[06:09:05.307] <TB1> INFO: pixel data incomplete: 0
[06:09:05.307] <TB1> INFO: pixel address: 0
[06:09:05.307] <TB1> INFO: pulse height fill bit: 0
[06:09:05.307] <TB1> INFO: buffer corruption: 0
[06:09:05.313] <TB1> INFO: ######################################################################
[06:09:05.313] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[06:09:05.313] <TB1> INFO: ######################################################################
[06:09:05.316] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[06:09:05.327] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[06:09:05.327] <TB1> INFO: run 1 of 1
[06:09:05.591] <TB1> INFO: Expecting 3120000 events.
[06:09:46.119] <TB1> INFO: 857960 events read in total (39972ms).
[06:10:25.867] <TB1> INFO: 1707455 events read in total (79721ms).
[06:11:05.968] <TB1> INFO: 2562460 events read in total (119822ms).
[06:11:32.983] <TB1> INFO: 3120000 events read in total (146836ms).
[06:11:33.043] <TB1> INFO: Test took 147716ms.
[06:11:58.906] <TB1> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[06:11:58.906] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 2 3 9 1 0 1 0 18 0 0 0 0 0
[06:11:58.906] <TB1> INFO: separation cut (per ROC): 88 66 91 69 93 106 70 75 92 97 104 77 100 83 86 84
[06:11:58.906] <TB1> INFO: Decoding statistics:
[06:11:58.906] <TB1> INFO: General information:
[06:11:58.906] <TB1> INFO: 16bit words read: 0
[06:11:58.906] <TB1> INFO: valid events total: 0
[06:11:58.906] <TB1> INFO: empty events: 0
[06:11:58.906] <TB1> INFO: valid events with pixels: 0
[06:11:58.906] <TB1> INFO: valid pixel hits: 0
[06:11:58.906] <TB1> INFO: Event errors: 0
[06:11:58.906] <TB1> INFO: start marker: 0
[06:11:58.906] <TB1> INFO: stop marker: 0
[06:11:58.906] <TB1> INFO: overflow: 0
[06:11:58.906] <TB1> INFO: invalid 5bit words: 0
[06:11:58.906] <TB1> INFO: invalid XOR eye diagram: 0
[06:11:58.906] <TB1> INFO: frame (failed synchr.): 0
[06:11:58.907] <TB1> INFO: idle data (no TBM trl): 0
[06:11:58.907] <TB1> INFO: no data (only TBM hdr): 0
[06:11:58.907] <TB1> INFO: TBM errors: 0
[06:11:58.907] <TB1> INFO: flawed TBM headers: 0
[06:11:58.907] <TB1> INFO: flawed TBM trailers: 0
[06:11:58.907] <TB1> INFO: event ID mismatches: 0
[06:11:58.907] <TB1> INFO: ROC errors: 0
[06:11:58.907] <TB1> INFO: missing ROC header(s): 0
[06:11:58.907] <TB1> INFO: misplaced readback start: 0
[06:11:58.907] <TB1> INFO: Pixel decoding errors: 0
[06:11:58.907] <TB1> INFO: pixel data incomplete: 0
[06:11:58.907] <TB1> INFO: pixel address: 0
[06:11:58.907] <TB1> INFO: pulse height fill bit: 0
[06:11:58.907] <TB1> INFO: buffer corruption: 0
[06:11:58.978] <TB1> INFO: ######################################################################
[06:11:58.978] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[06:11:58.978] <TB1> INFO: ######################################################################
[06:11:58.979] <TB1> INFO: ----------------------------------------------------------------------
[06:11:58.979] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[06:11:58.979] <TB1> INFO: ----------------------------------------------------------------------
[06:11:58.979] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[06:11:58.987] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[06:11:58.987] <TB1> INFO: run 1 of 1
[06:11:59.248] <TB1> INFO: Expecting 26208000 events.
[06:12:32.779] <TB1> INFO: 880200 events read in total (32974ms).
[06:13:05.581] <TB1> INFO: 1749400 events read in total (65776ms).
[06:13:38.564] <TB1> INFO: 2617900 events read in total (98759ms).
[06:14:11.370] <TB1> INFO: 3487550 events read in total (131565ms).
[06:14:44.165] <TB1> INFO: 4356100 events read in total (164360ms).
[06:15:16.726] <TB1> INFO: 5223700 events read in total (196921ms).
[06:15:49.827] <TB1> INFO: 6092550 events read in total (230022ms).
[06:16:22.499] <TB1> INFO: 6959800 events read in total (262694ms).
[06:16:55.361] <TB1> INFO: 7828650 events read in total (295556ms).
[06:17:27.805] <TB1> INFO: 8697400 events read in total (328000ms).
[06:18:00.717] <TB1> INFO: 9566350 events read in total (360912ms).
[06:18:33.375] <TB1> INFO: 10431550 events read in total (393570ms).
[06:19:05.886] <TB1> INFO: 11299000 events read in total (426081ms).
[06:19:38.255] <TB1> INFO: 12165700 events read in total (458450ms).
[06:20:10.775] <TB1> INFO: 13029300 events read in total (490970ms).
[06:20:43.361] <TB1> INFO: 13888000 events read in total (523556ms).
[06:21:15.899] <TB1> INFO: 14743450 events read in total (556094ms).
[06:21:48.682] <TB1> INFO: 15598600 events read in total (588877ms).
[06:22:21.486] <TB1> INFO: 16453000 events read in total (621681ms).
[06:22:53.921] <TB1> INFO: 17305900 events read in total (654116ms).
[06:23:26.248] <TB1> INFO: 18160450 events read in total (686443ms).
[06:23:58.573] <TB1> INFO: 19011700 events read in total (718768ms).
[06:24:30.907] <TB1> INFO: 19863050 events read in total (751102ms).
[06:25:03.907] <TB1> INFO: 20716100 events read in total (784102ms).
[06:25:36.502] <TB1> INFO: 21566950 events read in total (816697ms).
[06:26:09.167] <TB1> INFO: 22417300 events read in total (849362ms).
[06:26:42.200] <TB1> INFO: 23270700 events read in total (882395ms).
[06:27:14.793] <TB1> INFO: 24122700 events read in total (914988ms).
[06:27:47.601] <TB1> INFO: 24978950 events read in total (947796ms).
[06:28:20.684] <TB1> INFO: 25839700 events read in total (980879ms).
[06:28:34.454] <TB1> INFO: 26208000 events read in total (994649ms).
[06:28:34.487] <TB1> INFO: Test took 995500ms.
[06:28:34.717] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:36.105] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:37.716] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:39.367] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:40.820] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:42.210] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:43.546] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:44.980] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:46.430] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:47.846] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:49.494] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:50.915] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:52.854] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:54.268] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:55.836] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:57.355] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[06:28:58.830] <TB1> INFO: PixTestScurves::scurves() done
[06:28:58.830] <TB1> INFO: Vcal mean: 92.44 73.49 98.75 73.36 98.17 111.15 77.54 74.44 88.86 79.07 82.49 78.74 91.58 82.14 86.05 86.63
[06:28:58.830] <TB1> INFO: Vcal RMS: 5.46 4.26 6.10 4.30 5.47 5.74 3.98 4.34 4.90 4.61 4.41 3.72 5.50 4.79 4.68 4.83
[06:28:58.830] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1019 seconds
[06:28:58.830] <TB1> INFO: Decoding statistics:
[06:28:58.830] <TB1> INFO: General information:
[06:28:58.830] <TB1> INFO: 16bit words read: 0
[06:28:58.830] <TB1> INFO: valid events total: 0
[06:28:58.830] <TB1> INFO: empty events: 0
[06:28:58.830] <TB1> INFO: valid events with pixels: 0
[06:28:58.830] <TB1> INFO: valid pixel hits: 0
[06:28:58.830] <TB1> INFO: Event errors: 0
[06:28:58.830] <TB1> INFO: start marker: 0
[06:28:58.830] <TB1> INFO: stop marker: 0
[06:28:58.830] <TB1> INFO: overflow: 0
[06:28:58.830] <TB1> INFO: invalid 5bit words: 0
[06:28:58.830] <TB1> INFO: invalid XOR eye diagram: 0
[06:28:58.830] <TB1> INFO: frame (failed synchr.): 0
[06:28:58.830] <TB1> INFO: idle data (no TBM trl): 0
[06:28:58.830] <TB1> INFO: no data (only TBM hdr): 0
[06:28:58.830] <TB1> INFO: TBM errors: 0
[06:28:58.830] <TB1> INFO: flawed TBM headers: 0
[06:28:58.830] <TB1> INFO: flawed TBM trailers: 0
[06:28:58.830] <TB1> INFO: event ID mismatches: 0
[06:28:58.830] <TB1> INFO: ROC errors: 0
[06:28:58.830] <TB1> INFO: missing ROC header(s): 0
[06:28:58.830] <TB1> INFO: misplaced readback start: 0
[06:28:58.830] <TB1> INFO: Pixel decoding errors: 0
[06:28:58.830] <TB1> INFO: pixel data incomplete: 0
[06:28:58.830] <TB1> INFO: pixel address: 0
[06:28:58.830] <TB1> INFO: pulse height fill bit: 0
[06:28:58.830] <TB1> INFO: buffer corruption: 0
[06:28:58.901] <TB1> INFO: ######################################################################
[06:28:58.901] <TB1> INFO: PixTestTrim::doTest()
[06:28:58.901] <TB1> INFO: ######################################################################
[06:28:58.902] <TB1> INFO: ----------------------------------------------------------------------
[06:28:58.902] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[06:28:58.902] <TB1> INFO: ----------------------------------------------------------------------
[06:28:58.981] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[06:28:58.981] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[06:28:58.990] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[06:28:58.990] <TB1> INFO: run 1 of 1
[06:28:59.271] <TB1> INFO: Expecting 6281600 events.
[06:29:38.998] <TB1> INFO: 1062120 events read in total (39168ms).
[06:30:17.646] <TB1> INFO: 2122280 events read in total (77816ms).
[06:30:56.280] <TB1> INFO: 3177630 events read in total (116450ms).
[06:31:35.381] <TB1> INFO: 4239800 events read in total (155551ms).
[06:32:14.107] <TB1> INFO: 5302730 events read in total (194277ms).
[06:32:50.023] <TB1> INFO: 6281600 events read in total (230193ms).
[06:32:50.056] <TB1> INFO: Test took 231066ms.
[06:33:09.506] <TB1> INFO: ROC 0 VthrComp = 97
[06:33:09.506] <TB1> INFO: ROC 1 VthrComp = 79
[06:33:09.506] <TB1> INFO: ROC 2 VthrComp = 97
[06:33:09.507] <TB1> INFO: ROC 3 VthrComp = 79
[06:33:09.507] <TB1> INFO: ROC 4 VthrComp = 102
[06:33:09.507] <TB1> INFO: ROC 5 VthrComp = 107
[06:33:09.507] <TB1> INFO: ROC 6 VthrComp = 84
[06:33:09.507] <TB1> INFO: ROC 7 VthrComp = 82
[06:33:09.507] <TB1> INFO: ROC 8 VthrComp = 92
[06:33:09.507] <TB1> INFO: ROC 9 VthrComp = 81
[06:33:09.507] <TB1> INFO: ROC 10 VthrComp = 88
[06:33:09.507] <TB1> INFO: ROC 11 VthrComp = 84
[06:33:09.507] <TB1> INFO: ROC 12 VthrComp = 96
[06:33:09.508] <TB1> INFO: ROC 13 VthrComp = 87
[06:33:09.508] <TB1> INFO: ROC 14 VthrComp = 93
[06:33:09.508] <TB1> INFO: ROC 15 VthrComp = 92
[06:33:09.508] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[06:33:09.508] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[06:33:09.516] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[06:33:09.516] <TB1> INFO: run 1 of 1
[06:33:09.779] <TB1> INFO: Expecting 6281600 events.
[06:33:45.819] <TB1> INFO: 754980 events read in total (35484ms).
[06:34:20.139] <TB1> INFO: 1508470 events read in total (69804ms).
[06:34:54.973] <TB1> INFO: 2262910 events read in total (104638ms).
[06:35:29.839] <TB1> INFO: 3017340 events read in total (139504ms).
[06:36:03.906] <TB1> INFO: 3765900 events read in total (173571ms).
[06:36:38.483] <TB1> INFO: 4511210 events read in total (208148ms).
[06:37:13.026] <TB1> INFO: 5254350 events read in total (242691ms).
[06:37:47.708] <TB1> INFO: 5997470 events read in total (277373ms).
[06:38:00.932] <TB1> INFO: 6281600 events read in total (290598ms).
[06:38:00.976] <TB1> INFO: Test took 291460ms.
[06:38:26.392] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 57.4179 for pixel 5/3 mean/min/max = 44.8438/32.0778/57.6099
[06:38:26.392] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 56.3547 for pixel 18/13 mean/min/max = 45.7269/35.0937/56.3601
[06:38:26.393] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 62.7818 for pixel 21/76 mean/min/max = 47.1618/31.2599/63.0638
[06:38:26.393] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.0935 for pixel 10/6 mean/min/max = 45.9587/34.7756/57.1419
[06:38:26.393] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 59.3921 for pixel 0/20 mean/min/max = 45.7392/32.0299/59.4485
[06:38:26.393] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 67.4616 for pixel 0/54 mean/min/max = 50.8308/33.8731/67.7885
[06:38:26.393] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 55.5815 for pixel 0/2 mean/min/max = 43.9061/32.1724/55.6397
[06:38:26.394] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 56.348 for pixel 6/75 mean/min/max = 44.6071/32.7186/56.4956
[06:38:26.394] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.2688 for pixel 10/5 mean/min/max = 44.7859/32.2902/57.2816
[06:38:26.394] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 59.2992 for pixel 16/3 mean/min/max = 45.5635/31.6338/59.4931
[06:38:26.395] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.9425 for pixel 50/74 mean/min/max = 44.8931/32.793/56.9933
[06:38:26.395] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 55.3813 for pixel 23/68 mean/min/max = 44.3099/32.9456/55.6742
[06:38:26.395] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 59.6157 for pixel 21/6 mean/min/max = 45.4437/31.2603/59.627
[06:38:26.395] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.392 for pixel 13/76 mean/min/max = 45.139/31.8728/58.4052
[06:38:26.396] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.3065 for pixel 21/2 mean/min/max = 45.1919/32.9825/57.4012
[06:38:26.396] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.3316 for pixel 13/34 mean/min/max = 44.9803/32.5685/57.3921
[06:38:26.396] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[06:38:26.484] <TB1> INFO: Expecting 514560 events.
[06:38:39.363] <TB1> INFO: 514560 events read in total (12322ms).
[06:38:39.368] <TB1> INFO: Expecting 514560 events.
[06:38:52.106] <TB1> INFO: 514560 events read in total (12317ms).
[06:38:52.113] <TB1> INFO: Expecting 514560 events.
[06:39:04.803] <TB1> INFO: 514560 events read in total (12274ms).
[06:39:04.813] <TB1> INFO: Expecting 514560 events.
[06:39:17.479] <TB1> INFO: 514560 events read in total (12259ms).
[06:39:17.490] <TB1> INFO: Expecting 514560 events.
[06:39:30.225] <TB1> INFO: 514560 events read in total (12329ms).
[06:39:30.237] <TB1> INFO: Expecting 514560 events.
[06:39:43.082] <TB1> INFO: 514560 events read in total (12435ms).
[06:39:43.096] <TB1> INFO: Expecting 514560 events.
[06:39:55.893] <TB1> INFO: 514560 events read in total (12393ms).
[06:39:55.909] <TB1> INFO: Expecting 514560 events.
[06:40:08.730] <TB1> INFO: 514560 events read in total (12417ms).
[06:40:08.754] <TB1> INFO: Expecting 514560 events.
[06:40:21.491] <TB1> INFO: 514560 events read in total (12335ms).
[06:40:21.512] <TB1> INFO: Expecting 514560 events.
[06:40:34.359] <TB1> INFO: 514560 events read in total (12444ms).
[06:40:34.381] <TB1> INFO: Expecting 514560 events.
[06:40:47.041] <TB1> INFO: 514560 events read in total (12258ms).
[06:40:47.070] <TB1> INFO: Expecting 514560 events.
[06:40:59.835] <TB1> INFO: 514560 events read in total (12363ms).
[06:40:59.862] <TB1> INFO: Expecting 514560 events.
[06:41:12.560] <TB1> INFO: 514560 events read in total (12296ms).
[06:41:12.589] <TB1> INFO: Expecting 514560 events.
[06:41:25.282] <TB1> INFO: 514560 events read in total (12291ms).
[06:41:25.313] <TB1> INFO: Expecting 514560 events.
[06:41:38.096] <TB1> INFO: 514560 events read in total (12381ms).
[06:41:38.128] <TB1> INFO: Expecting 514560 events.
[06:41:50.884] <TB1> INFO: 514560 events read in total (12354ms).
[06:41:50.919] <TB1> INFO: Test took 204523ms.
[06:41:51.888] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[06:41:51.896] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[06:41:51.896] <TB1> INFO: run 1 of 1
[06:41:52.156] <TB1> INFO: Expecting 6281600 events.
[06:42:26.647] <TB1> INFO: 742350 events read in total (33935ms).
[06:43:01.277] <TB1> INFO: 1483560 events read in total (68565ms).
[06:43:35.659] <TB1> INFO: 2225610 events read in total (102947ms).
[06:44:10.621] <TB1> INFO: 2968000 events read in total (137909ms).
[06:44:45.085] <TB1> INFO: 3704350 events read in total (172373ms).
[06:45:19.678] <TB1> INFO: 4436700 events read in total (206966ms).
[06:45:53.994] <TB1> INFO: 5166730 events read in total (241282ms).
[06:46:28.376] <TB1> INFO: 5896140 events read in total (275664ms).
[06:46:46.655] <TB1> INFO: 6281600 events read in total (293943ms).
[06:46:46.706] <TB1> INFO: Test took 294810ms.
[06:47:10.921] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.182382 .. 255.000000
[06:47:11.004] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[06:47:11.012] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[06:47:11.012] <TB1> INFO: run 1 of 1
[06:47:11.282] <TB1> INFO: Expecting 10649600 events.
[06:47:46.211] <TB1> INFO: 712190 events read in total (34373ms).
[06:48:20.249] <TB1> INFO: 1424770 events read in total (68411ms).
[06:48:54.686] <TB1> INFO: 2137500 events read in total (102848ms).
[06:49:28.506] <TB1> INFO: 2850310 events read in total (136668ms).
[06:50:02.521] <TB1> INFO: 3562660 events read in total (170683ms).
[06:50:36.783] <TB1> INFO: 4275620 events read in total (204945ms).
[06:51:10.838] <TB1> INFO: 4988160 events read in total (239000ms).
[06:51:44.195] <TB1> INFO: 5700700 events read in total (272357ms).
[06:52:17.959] <TB1> INFO: 6412710 events read in total (306121ms).
[06:52:52.098] <TB1> INFO: 7124070 events read in total (340260ms).
[06:53:25.991] <TB1> INFO: 7835700 events read in total (374153ms).
[06:54:00.042] <TB1> INFO: 8546480 events read in total (408204ms).
[06:54:34.267] <TB1> INFO: 9256970 events read in total (442429ms).
[06:55:08.237] <TB1> INFO: 9967670 events read in total (476399ms).
[06:55:40.534] <TB1> INFO: 10649600 events read in total (508696ms).
[06:55:40.626] <TB1> INFO: Test took 509613ms.
[06:56:09.244] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 16.237041 .. 46.582149
[06:56:09.341] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 56 (-1/-1) hits flags = 528 (plus default)
[06:56:09.352] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[06:56:09.352] <TB1> INFO: run 1 of 1
[06:56:09.638] <TB1> INFO: Expecting 2121600 events.
[06:56:47.758] <TB1> INFO: 926130 events read in total (37563ms).
[06:57:24.966] <TB1> INFO: 1850180 events read in total (74771ms).
[06:57:36.289] <TB1> INFO: 2121600 events read in total (86095ms).
[06:57:36.308] <TB1> INFO: Test took 86955ms.
[06:57:49.894] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 19.503287 .. 41.920836
[06:57:49.970] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 51 (-1/-1) hits flags = 528 (plus default)
[06:57:49.978] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[06:57:49.978] <TB1> INFO: run 1 of 1
[06:57:50.241] <TB1> INFO: Expecting 1788800 events.
[06:58:28.651] <TB1> INFO: 952400 events read in total (37854ms).
[06:59:01.809] <TB1> INFO: 1788800 events read in total (71012ms).
[06:59:01.821] <TB1> INFO: Test took 71842ms.
[06:59:14.978] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 22.485012 .. 41.409701
[06:59:15.056] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 12 .. 51 (-1/-1) hits flags = 528 (plus default)
[06:59:15.065] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[06:59:15.065] <TB1> INFO: run 1 of 1
[06:59:15.329] <TB1> INFO: Expecting 1664000 events.
[06:59:53.577] <TB1> INFO: 933100 events read in total (37692ms).
[07:00:23.396] <TB1> INFO: 1664000 events read in total (67511ms).
[07:00:23.407] <TB1> INFO: Test took 68342ms.
[07:00:36.814] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[07:00:36.814] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[07:00:36.824] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:00:36.824] <TB1> INFO: run 1 of 1
[07:00:37.115] <TB1> INFO: Expecting 1705600 events.
[07:01:14.585] <TB1> INFO: 879410 events read in total (36914ms).
[07:01:49.773] <TB1> INFO: 1705600 events read in total (72102ms).
[07:01:49.788] <TB1> INFO: Test took 72964ms.
[07:02:03.381] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C0.dat
[07:02:03.381] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C1.dat
[07:02:03.381] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C2.dat
[07:02:03.381] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C3.dat
[07:02:03.381] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C4.dat
[07:02:03.381] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C5.dat
[07:02:03.381] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C6.dat
[07:02:03.382] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C7.dat
[07:02:03.382] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C8.dat
[07:02:03.382] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C9.dat
[07:02:03.382] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C10.dat
[07:02:03.382] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C11.dat
[07:02:03.382] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C12.dat
[07:02:03.382] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C13.dat
[07:02:03.382] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C14.dat
[07:02:03.383] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C15.dat
[07:02:03.383] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C0.dat
[07:02:03.389] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C1.dat
[07:02:03.396] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C2.dat
[07:02:03.402] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C3.dat
[07:02:03.409] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C4.dat
[07:02:03.415] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C5.dat
[07:02:03.423] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C6.dat
[07:02:03.429] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C7.dat
[07:02:03.435] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C8.dat
[07:02:03.441] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C9.dat
[07:02:03.447] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C10.dat
[07:02:03.453] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C11.dat
[07:02:03.459] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C12.dat
[07:02:03.465] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C13.dat
[07:02:03.471] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C14.dat
[07:02:03.477] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//trimParameters35_C15.dat
[07:02:03.483] <TB1> INFO: PixTestTrim::trimTest() done
[07:02:03.483] <TB1> INFO: vtrim: 100 97 111 87 104 138 94 104 106 99 88 96 105 103 99 102
[07:02:03.483] <TB1> INFO: vthrcomp: 97 79 97 79 102 107 84 82 92 81 88 84 96 87 93 92
[07:02:03.483] <TB1> INFO: vcal mean: 34.97 34.97 34.88 35.03 35.05 35.03 34.91 34.97 34.99 34.96 34.98 34.94 34.96 34.95 34.99 34.96
[07:02:03.483] <TB1> INFO: vcal RMS: 0.85 0.78 1.03 0.76 0.85 0.93 0.99 0.76 0.80 0.86 0.81 0.80 0.86 0.81 0.81 0.85
[07:02:03.483] <TB1> INFO: bits mean: 9.56 8.81 9.48 8.83 8.97 7.97 9.58 9.63 9.76 9.49 9.29 9.48 9.55 9.63 9.45 9.72
[07:02:03.483] <TB1> INFO: bits RMS: 2.75 2.51 2.65 2.51 2.95 2.69 2.75 2.63 2.57 2.83 2.72 2.68 2.80 2.67 2.59 2.54
[07:02:03.489] <TB1> INFO: ----------------------------------------------------------------------
[07:02:03.489] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[07:02:03.489] <TB1> INFO: ----------------------------------------------------------------------
[07:02:03.492] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[07:02:03.500] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:02:03.500] <TB1> INFO: run 1 of 1
[07:02:03.763] <TB1> INFO: Expecting 4160000 events.
[07:02:45.550] <TB1> INFO: 916165 events read in total (41230ms).
[07:03:26.794] <TB1> INFO: 1828715 events read in total (82474ms).
[07:04:07.565] <TB1> INFO: 2733320 events read in total (123245ms).
[07:04:48.567] <TB1> INFO: 3634230 events read in total (164247ms).
[07:05:12.473] <TB1> INFO: 4160000 events read in total (188153ms).
[07:05:12.513] <TB1> INFO: Test took 189013ms.
[07:05:41.111] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[07:05:41.119] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:05:41.119] <TB1> INFO: run 1 of 1
[07:05:41.379] <TB1> INFO: Expecting 3848000 events.
[07:06:22.987] <TB1> INFO: 912725 events read in total (41052ms).
[07:07:04.598] <TB1> INFO: 1822060 events read in total (82663ms).
[07:07:45.273] <TB1> INFO: 2721285 events read in total (123339ms).
[07:08:25.646] <TB1> INFO: 3618580 events read in total (163711ms).
[07:08:36.225] <TB1> INFO: 3848000 events read in total (174290ms).
[07:08:36.260] <TB1> INFO: Test took 175141ms.
[07:09:02.959] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[07:09:02.967] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:09:02.967] <TB1> INFO: run 1 of 1
[07:09:03.227] <TB1> INFO: Expecting 3598400 events.
[07:09:45.314] <TB1> INFO: 936910 events read in total (41531ms).
[07:10:26.890] <TB1> INFO: 1868850 events read in total (83108ms).
[07:11:08.369] <TB1> INFO: 2788725 events read in total (124586ms).
[07:11:45.158] <TB1> INFO: 3598400 events read in total (161375ms).
[07:11:45.206] <TB1> INFO: Test took 162239ms.
[07:12:13.552] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[07:12:13.560] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:12:13.560] <TB1> INFO: run 1 of 1
[07:12:13.824] <TB1> INFO: Expecting 3619200 events.
[07:12:56.445] <TB1> INFO: 934160 events read in total (42065ms).
[07:13:37.817] <TB1> INFO: 1863180 events read in total (83437ms).
[07:14:18.957] <TB1> INFO: 2780590 events read in total (124577ms).
[07:14:56.302] <TB1> INFO: 3619200 events read in total (161922ms).
[07:14:56.340] <TB1> INFO: Test took 162780ms.
[07:15:22.334] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[07:15:22.343] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:15:22.343] <TB1> INFO: run 1 of 1
[07:15:22.628] <TB1> INFO: Expecting 3598400 events.
[07:16:04.727] <TB1> INFO: 935815 events read in total (41542ms).
[07:16:46.315] <TB1> INFO: 1866575 events read in total (83130ms).
[07:17:27.963] <TB1> INFO: 2785730 events read in total (124779ms).
[07:18:04.042] <TB1> INFO: 3598400 events read in total (160857ms).
[07:18:04.080] <TB1> INFO: Test took 161737ms.
[07:18:30.895] <TB1> INFO: PixTestTrim::trimBitTest() done
[07:18:30.897] <TB1> INFO: PixTestTrim::doTest() done, duration: 2971 seconds
[07:18:30.897] <TB1> INFO: Decoding statistics:
[07:18:30.897] <TB1> INFO: General information:
[07:18:30.897] <TB1> INFO: 16bit words read: 0
[07:18:30.897] <TB1> INFO: valid events total: 0
[07:18:30.897] <TB1> INFO: empty events: 0
[07:18:30.897] <TB1> INFO: valid events with pixels: 0
[07:18:30.897] <TB1> INFO: valid pixel hits: 0
[07:18:30.897] <TB1> INFO: Event errors: 0
[07:18:30.897] <TB1> INFO: start marker: 0
[07:18:30.897] <TB1> INFO: stop marker: 0
[07:18:30.897] <TB1> INFO: overflow: 0
[07:18:30.897] <TB1> INFO: invalid 5bit words: 0
[07:18:30.897] <TB1> INFO: invalid XOR eye diagram: 0
[07:18:30.897] <TB1> INFO: frame (failed synchr.): 0
[07:18:30.897] <TB1> INFO: idle data (no TBM trl): 0
[07:18:30.897] <TB1> INFO: no data (only TBM hdr): 0
[07:18:30.897] <TB1> INFO: TBM errors: 0
[07:18:30.897] <TB1> INFO: flawed TBM headers: 0
[07:18:30.897] <TB1> INFO: flawed TBM trailers: 0
[07:18:30.897] <TB1> INFO: event ID mismatches: 0
[07:18:30.897] <TB1> INFO: ROC errors: 0
[07:18:30.897] <TB1> INFO: missing ROC header(s): 0
[07:18:30.897] <TB1> INFO: misplaced readback start: 0
[07:18:30.897] <TB1> INFO: Pixel decoding errors: 0
[07:18:30.897] <TB1> INFO: pixel data incomplete: 0
[07:18:30.897] <TB1> INFO: pixel address: 0
[07:18:30.897] <TB1> INFO: pulse height fill bit: 0
[07:18:30.897] <TB1> INFO: buffer corruption: 0
[07:18:31.656] <TB1> INFO: ######################################################################
[07:18:31.656] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[07:18:31.656] <TB1> INFO: ######################################################################
[07:18:31.948] <TB1> INFO: Expecting 41600 events.
[07:18:35.770] <TB1> INFO: 41600 events read in total (3266ms).
[07:18:35.771] <TB1> INFO: Test took 4113ms.
[07:18:36.266] <TB1> INFO: Expecting 41600 events.
[07:18:40.051] <TB1> INFO: 41600 events read in total (3229ms).
[07:18:40.052] <TB1> INFO: Test took 4077ms.
[07:18:40.336] <TB1> INFO: Expecting 41600 events.
[07:18:44.149] <TB1> INFO: 41600 events read in total (3257ms).
[07:18:44.150] <TB1> INFO: Test took 4076ms.
[07:18:44.405] <TB1> INFO: Expecting 2560 events.
[07:18:45.258] <TB1> INFO: 2560 events read in total (297ms).
[07:18:45.258] <TB1> INFO: Test took 1095ms.
[07:18:45.563] <TB1> INFO: Expecting 2560 events.
[07:18:46.416] <TB1> INFO: 2560 events read in total (296ms).
[07:18:46.416] <TB1> INFO: Test took 1157ms.
[07:18:46.721] <TB1> INFO: Expecting 2560 events.
[07:18:47.576] <TB1> INFO: 2560 events read in total (299ms).
[07:18:47.576] <TB1> INFO: Test took 1160ms.
[07:18:47.881] <TB1> INFO: Expecting 2560 events.
[07:18:48.735] <TB1> INFO: 2560 events read in total (298ms).
[07:18:48.735] <TB1> INFO: Test took 1159ms.
[07:18:49.041] <TB1> INFO: Expecting 2560 events.
[07:18:49.893] <TB1> INFO: 2560 events read in total (296ms).
[07:18:49.894] <TB1> INFO: Test took 1158ms.
[07:18:50.199] <TB1> INFO: Expecting 2560 events.
[07:18:51.053] <TB1> INFO: 2560 events read in total (298ms).
[07:18:51.053] <TB1> INFO: Test took 1159ms.
[07:18:51.358] <TB1> INFO: Expecting 2560 events.
[07:18:52.210] <TB1> INFO: 2560 events read in total (295ms).
[07:18:52.210] <TB1> INFO: Test took 1156ms.
[07:18:52.515] <TB1> INFO: Expecting 2560 events.
[07:18:53.368] <TB1> INFO: 2560 events read in total (297ms).
[07:18:53.368] <TB1> INFO: Test took 1158ms.
[07:18:53.673] <TB1> INFO: Expecting 2560 events.
[07:18:54.526] <TB1> INFO: 2560 events read in total (297ms).
[07:18:54.526] <TB1> INFO: Test took 1158ms.
[07:18:54.831] <TB1> INFO: Expecting 2560 events.
[07:18:55.685] <TB1> INFO: 2560 events read in total (297ms).
[07:18:55.685] <TB1> INFO: Test took 1158ms.
[07:18:55.990] <TB1> INFO: Expecting 2560 events.
[07:18:56.842] <TB1> INFO: 2560 events read in total (295ms).
[07:18:56.843] <TB1> INFO: Test took 1157ms.
[07:18:57.148] <TB1> INFO: Expecting 2560 events.
[07:18:58.001] <TB1> INFO: 2560 events read in total (297ms).
[07:18:58.001] <TB1> INFO: Test took 1158ms.
[07:18:58.306] <TB1> INFO: Expecting 2560 events.
[07:18:59.160] <TB1> INFO: 2560 events read in total (298ms).
[07:18:59.160] <TB1> INFO: Test took 1147ms.
[07:18:59.465] <TB1> INFO: Expecting 2560 events.
[07:19:00.319] <TB1> INFO: 2560 events read in total (298ms).
[07:19:00.319] <TB1> INFO: Test took 1158ms.
[07:19:00.624] <TB1> INFO: Expecting 2560 events.
[07:19:01.480] <TB1> INFO: 2560 events read in total (300ms).
[07:19:01.480] <TB1> INFO: Test took 1161ms.
[07:19:01.785] <TB1> INFO: Expecting 2560 events.
[07:19:02.640] <TB1> INFO: 2560 events read in total (298ms).
[07:19:02.640] <TB1> INFO: Test took 1159ms.
[07:19:02.643] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:19:02.947] <TB1> INFO: Expecting 655360 events.
[07:19:19.633] <TB1> INFO: 655360 events read in total (16130ms).
[07:19:19.643] <TB1> INFO: Expecting 655360 events.
[07:19:36.116] <TB1> INFO: 655360 events read in total (16064ms).
[07:19:36.131] <TB1> INFO: Expecting 655360 events.
[07:19:52.753] <TB1> INFO: 655360 events read in total (16220ms).
[07:19:52.774] <TB1> INFO: Expecting 655360 events.
[07:20:09.457] <TB1> INFO: 655360 events read in total (16281ms).
[07:20:09.478] <TB1> INFO: Expecting 655360 events.
[07:20:26.166] <TB1> INFO: 655360 events read in total (16286ms).
[07:20:26.194] <TB1> INFO: Expecting 655360 events.
[07:20:42.931] <TB1> INFO: 655360 events read in total (16335ms).
[07:20:42.962] <TB1> INFO: Expecting 655360 events.
[07:20:59.640] <TB1> INFO: 655360 events read in total (16277ms).
[07:20:59.675] <TB1> INFO: Expecting 655360 events.
[07:21:16.345] <TB1> INFO: 655360 events read in total (16268ms).
[07:21:16.388] <TB1> INFO: Expecting 655360 events.
[07:21:33.084] <TB1> INFO: 655360 events read in total (16294ms).
[07:21:33.126] <TB1> INFO: Expecting 655360 events.
[07:21:49.796] <TB1> INFO: 655360 events read in total (16268ms).
[07:21:49.846] <TB1> INFO: Expecting 655360 events.
[07:22:06.482] <TB1> INFO: 655360 events read in total (16234ms).
[07:22:06.541] <TB1> INFO: Expecting 655360 events.
[07:22:23.249] <TB1> INFO: 655360 events read in total (16307ms).
[07:22:23.310] <TB1> INFO: Expecting 655360 events.
[07:22:40.020] <TB1> INFO: 655360 events read in total (16308ms).
[07:22:40.084] <TB1> INFO: Expecting 655360 events.
[07:22:56.573] <TB1> INFO: 655360 events read in total (16087ms).
[07:22:56.639] <TB1> INFO: Expecting 655360 events.
[07:23:13.188] <TB1> INFO: 655360 events read in total (16147ms).
[07:23:13.257] <TB1> INFO: Expecting 655360 events.
[07:23:30.009] <TB1> INFO: 655360 events read in total (16350ms).
[07:23:30.082] <TB1> INFO: Test took 267439ms.
[07:23:30.161] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:23:30.391] <TB1> INFO: Expecting 655360 events.
[07:23:46.923] <TB1> INFO: 655360 events read in total (15976ms).
[07:23:46.933] <TB1> INFO: Expecting 655360 events.
[07:24:03.579] <TB1> INFO: 655360 events read in total (16244ms).
[07:24:03.592] <TB1> INFO: Expecting 655360 events.
[07:24:20.205] <TB1> INFO: 655360 events read in total (16211ms).
[07:24:20.222] <TB1> INFO: Expecting 655360 events.
[07:24:36.812] <TB1> INFO: 655360 events read in total (16188ms).
[07:24:36.833] <TB1> INFO: Expecting 655360 events.
[07:24:53.330] <TB1> INFO: 655360 events read in total (16095ms).
[07:24:53.354] <TB1> INFO: Expecting 655360 events.
[07:25:10.011] <TB1> INFO: 655360 events read in total (16255ms).
[07:25:10.038] <TB1> INFO: Expecting 655360 events.
[07:25:26.562] <TB1> INFO: 655360 events read in total (16122ms).
[07:25:26.597] <TB1> INFO: Expecting 655360 events.
[07:25:43.270] <TB1> INFO: 655360 events read in total (16271ms).
[07:25:43.309] <TB1> INFO: Expecting 655360 events.
[07:25:59.946] <TB1> INFO: 655360 events read in total (16235ms).
[07:25:59.985] <TB1> INFO: Expecting 655360 events.
[07:26:16.634] <TB1> INFO: 655360 events read in total (16247ms).
[07:26:16.679] <TB1> INFO: Expecting 655360 events.
[07:26:33.303] <TB1> INFO: 655360 events read in total (16222ms).
[07:26:33.348] <TB1> INFO: Expecting 655360 events.
[07:26:49.809] <TB1> INFO: 655360 events read in total (16059ms).
[07:26:49.861] <TB1> INFO: Expecting 655360 events.
[07:27:06.438] <TB1> INFO: 655360 events read in total (16175ms).
[07:27:06.507] <TB1> INFO: Expecting 655360 events.
[07:27:23.047] <TB1> INFO: 655360 events read in total (16139ms).
[07:27:23.119] <TB1> INFO: Expecting 655360 events.
[07:27:39.902] <TB1> INFO: 655360 events read in total (16381ms).
[07:27:39.974] <TB1> INFO: Expecting 655360 events.
[07:27:56.806] <TB1> INFO: 655360 events read in total (16430ms).
[07:27:56.893] <TB1> INFO: Test took 266732ms.
[07:27:57.146] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.156] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.162] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[07:27:57.169] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[07:27:57.175] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[07:27:57.182] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.189] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.195] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.202] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[07:27:57.209] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.219] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.226] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.236] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.246] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.255] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[07:27:57.262] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[07:27:57.272] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[07:27:57.282] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.292] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.300] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.307] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.313] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.321] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[07:27:57.363] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C0.dat
[07:27:57.363] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C1.dat
[07:27:57.363] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C2.dat
[07:27:57.363] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C3.dat
[07:27:57.363] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C4.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C5.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C6.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C7.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C8.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C9.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C10.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C11.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C12.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C13.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C14.dat
[07:27:57.364] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//dacParameters35_C15.dat
[07:27:57.647] <TB1> INFO: Expecting 41600 events.
[07:28:01.112] <TB1> INFO: 41600 events read in total (2909ms).
[07:28:01.113] <TB1> INFO: Test took 3746ms.
[07:28:01.557] <TB1> INFO: Expecting 41600 events.
[07:28:05.036] <TB1> INFO: 41600 events read in total (2922ms).
[07:28:05.036] <TB1> INFO: Test took 3744ms.
[07:28:05.484] <TB1> INFO: Expecting 41600 events.
[07:28:08.999] <TB1> INFO: 41600 events read in total (2958ms).
[07:28:09.000] <TB1> INFO: Test took 3784ms.
[07:28:09.191] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:09.279] <TB1> INFO: Expecting 2560 events.
[07:28:10.137] <TB1> INFO: 2560 events read in total (301ms).
[07:28:10.137] <TB1> INFO: Test took 946ms.
[07:28:10.139] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:10.443] <TB1> INFO: Expecting 2560 events.
[07:28:11.301] <TB1> INFO: 2560 events read in total (302ms).
[07:28:11.301] <TB1> INFO: Test took 1162ms.
[07:28:11.308] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:11.606] <TB1> INFO: Expecting 2560 events.
[07:28:12.460] <TB1> INFO: 2560 events read in total (297ms).
[07:28:12.461] <TB1> INFO: Test took 1153ms.
[07:28:12.463] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:12.766] <TB1> INFO: Expecting 2560 events.
[07:28:13.621] <TB1> INFO: 2560 events read in total (299ms).
[07:28:13.621] <TB1> INFO: Test took 1158ms.
[07:28:13.623] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:13.928] <TB1> INFO: Expecting 2560 events.
[07:28:14.781] <TB1> INFO: 2560 events read in total (297ms).
[07:28:14.781] <TB1> INFO: Test took 1158ms.
[07:28:14.784] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:15.087] <TB1> INFO: Expecting 2560 events.
[07:28:15.941] <TB1> INFO: 2560 events read in total (298ms).
[07:28:15.941] <TB1> INFO: Test took 1157ms.
[07:28:15.943] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:16.247] <TB1> INFO: Expecting 2560 events.
[07:28:17.100] <TB1> INFO: 2560 events read in total (297ms).
[07:28:17.100] <TB1> INFO: Test took 1157ms.
[07:28:17.102] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:17.406] <TB1> INFO: Expecting 2560 events.
[07:28:18.261] <TB1> INFO: 2560 events read in total (299ms).
[07:28:18.261] <TB1> INFO: Test took 1159ms.
[07:28:18.264] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:18.567] <TB1> INFO: Expecting 2560 events.
[07:28:19.422] <TB1> INFO: 2560 events read in total (299ms).
[07:28:19.422] <TB1> INFO: Test took 1158ms.
[07:28:19.424] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:19.729] <TB1> INFO: Expecting 2560 events.
[07:28:20.585] <TB1> INFO: 2560 events read in total (300ms).
[07:28:20.585] <TB1> INFO: Test took 1161ms.
[07:28:20.587] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:20.892] <TB1> INFO: Expecting 2560 events.
[07:28:21.752] <TB1> INFO: 2560 events read in total (304ms).
[07:28:21.752] <TB1> INFO: Test took 1165ms.
[07:28:21.755] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:22.057] <TB1> INFO: Expecting 2560 events.
[07:28:22.919] <TB1> INFO: 2560 events read in total (305ms).
[07:28:22.919] <TB1> INFO: Test took 1164ms.
[07:28:22.921] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:23.226] <TB1> INFO: Expecting 2560 events.
[07:28:24.081] <TB1> INFO: 2560 events read in total (299ms).
[07:28:24.082] <TB1> INFO: Test took 1161ms.
[07:28:24.084] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:24.387] <TB1> INFO: Expecting 2560 events.
[07:28:25.243] <TB1> INFO: 2560 events read in total (299ms).
[07:28:25.243] <TB1> INFO: Test took 1159ms.
[07:28:25.245] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:25.549] <TB1> INFO: Expecting 2560 events.
[07:28:26.405] <TB1> INFO: 2560 events read in total (300ms).
[07:28:26.405] <TB1> INFO: Test took 1160ms.
[07:28:26.408] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:26.711] <TB1> INFO: Expecting 2560 events.
[07:28:27.564] <TB1> INFO: 2560 events read in total (297ms).
[07:28:27.564] <TB1> INFO: Test took 1156ms.
[07:28:27.566] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:27.869] <TB1> INFO: Expecting 2560 events.
[07:28:28.725] <TB1> INFO: 2560 events read in total (299ms).
[07:28:28.726] <TB1> INFO: Test took 1160ms.
[07:28:28.728] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:29.031] <TB1> INFO: Expecting 2560 events.
[07:28:29.890] <TB1> INFO: 2560 events read in total (303ms).
[07:28:29.890] <TB1> INFO: Test took 1162ms.
[07:28:29.894] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:30.196] <TB1> INFO: Expecting 2560 events.
[07:28:31.049] <TB1> INFO: 2560 events read in total (297ms).
[07:28:31.050] <TB1> INFO: Test took 1156ms.
[07:28:31.051] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:31.355] <TB1> INFO: Expecting 2560 events.
[07:28:32.209] <TB1> INFO: 2560 events read in total (298ms).
[07:28:32.209] <TB1> INFO: Test took 1158ms.
[07:28:32.211] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:32.515] <TB1> INFO: Expecting 2560 events.
[07:28:33.368] <TB1> INFO: 2560 events read in total (297ms).
[07:28:33.369] <TB1> INFO: Test took 1158ms.
[07:28:33.371] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:33.674] <TB1> INFO: Expecting 2560 events.
[07:28:34.527] <TB1> INFO: 2560 events read in total (297ms).
[07:28:34.527] <TB1> INFO: Test took 1156ms.
[07:28:34.529] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:34.833] <TB1> INFO: Expecting 2560 events.
[07:28:35.685] <TB1> INFO: 2560 events read in total (296ms).
[07:28:35.685] <TB1> INFO: Test took 1156ms.
[07:28:35.688] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:35.990] <TB1> INFO: Expecting 2560 events.
[07:28:36.848] <TB1> INFO: 2560 events read in total (301ms).
[07:28:36.848] <TB1> INFO: Test took 1160ms.
[07:28:36.850] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:37.154] <TB1> INFO: Expecting 2560 events.
[07:28:38.010] <TB1> INFO: 2560 events read in total (300ms).
[07:28:38.010] <TB1> INFO: Test took 1160ms.
[07:28:38.012] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:38.317] <TB1> INFO: Expecting 2560 events.
[07:28:39.176] <TB1> INFO: 2560 events read in total (303ms).
[07:28:39.176] <TB1> INFO: Test took 1164ms.
[07:28:39.180] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:39.482] <TB1> INFO: Expecting 2560 events.
[07:28:40.338] <TB1> INFO: 2560 events read in total (299ms).
[07:28:40.339] <TB1> INFO: Test took 1159ms.
[07:28:40.342] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:40.644] <TB1> INFO: Expecting 2560 events.
[07:28:41.502] <TB1> INFO: 2560 events read in total (302ms).
[07:28:41.502] <TB1> INFO: Test took 1161ms.
[07:28:41.504] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:41.808] <TB1> INFO: Expecting 2560 events.
[07:28:42.661] <TB1> INFO: 2560 events read in total (297ms).
[07:28:42.662] <TB1> INFO: Test took 1158ms.
[07:28:42.664] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:42.967] <TB1> INFO: Expecting 2560 events.
[07:28:43.821] <TB1> INFO: 2560 events read in total (298ms).
[07:28:43.821] <TB1> INFO: Test took 1157ms.
[07:28:43.824] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:44.127] <TB1> INFO: Expecting 2560 events.
[07:28:44.979] <TB1> INFO: 2560 events read in total (296ms).
[07:28:44.979] <TB1> INFO: Test took 1155ms.
[07:28:44.982] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:28:45.285] <TB1> INFO: Expecting 2560 events.
[07:28:46.138] <TB1> INFO: 2560 events read in total (297ms).
[07:28:46.138] <TB1> INFO: Test took 1156ms.
[07:28:46.620] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 614 seconds
[07:28:46.620] <TB1> INFO: PH scale (per ROC): 79 94 66 82 80 74 79 99 77 80 83 79 75 80 96 84
[07:28:46.620] <TB1> INFO: PH offset (per ROC): 156 144 146 133 165 172 149 149 147 164 148 154 166 155 150 147
[07:28:46.624] <TB1> INFO: Decoding statistics:
[07:28:46.624] <TB1> INFO: General information:
[07:28:46.624] <TB1> INFO: 16bit words read: 91928
[07:28:46.624] <TB1> INFO: valid events total: 10240
[07:28:46.624] <TB1> INFO: empty events: 7738
[07:28:46.624] <TB1> INFO: valid events with pixels: 2502
[07:28:46.624] <TB1> INFO: valid pixel hits: 2502
[07:28:46.625] <TB1> INFO: Event errors: 0
[07:28:46.625] <TB1> INFO: start marker: 0
[07:28:46.625] <TB1> INFO: stop marker: 0
[07:28:46.625] <TB1> INFO: overflow: 0
[07:28:46.625] <TB1> INFO: invalid 5bit words: 0
[07:28:46.625] <TB1> INFO: invalid XOR eye diagram: 0
[07:28:46.625] <TB1> INFO: frame (failed synchr.): 0
[07:28:46.625] <TB1> INFO: idle data (no TBM trl): 0
[07:28:46.625] <TB1> INFO: no data (only TBM hdr): 0
[07:28:46.625] <TB1> INFO: TBM errors: 0
[07:28:46.625] <TB1> INFO: flawed TBM headers: 0
[07:28:46.625] <TB1> INFO: flawed TBM trailers: 0
[07:28:46.625] <TB1> INFO: event ID mismatches: 0
[07:28:46.625] <TB1> INFO: ROC errors: 0
[07:28:46.625] <TB1> INFO: missing ROC header(s): 0
[07:28:46.625] <TB1> INFO: misplaced readback start: 0
[07:28:46.625] <TB1> INFO: Pixel decoding errors: 0
[07:28:46.625] <TB1> INFO: pixel data incomplete: 0
[07:28:46.625] <TB1> INFO: pixel address: 0
[07:28:46.625] <TB1> INFO: pulse height fill bit: 0
[07:28:46.625] <TB1> INFO: buffer corruption: 0
[07:28:46.821] <TB1> INFO: ######################################################################
[07:28:46.821] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[07:28:46.821] <TB1> INFO: ######################################################################
[07:28:46.831] <TB1> INFO: scanning low vcal = 10
[07:28:47.095] <TB1> INFO: Expecting 41600 events.
[07:28:50.648] <TB1> INFO: 41600 events read in total (2996ms).
[07:28:50.649] <TB1> INFO: Test took 3818ms.
[07:28:50.650] <TB1> INFO: scanning low vcal = 20
[07:28:50.954] <TB1> INFO: Expecting 41600 events.
[07:28:54.498] <TB1> INFO: 41600 events read in total (2988ms).
[07:28:54.498] <TB1> INFO: Test took 3848ms.
[07:28:54.501] <TB1> INFO: scanning low vcal = 30
[07:28:54.803] <TB1> INFO: Expecting 41600 events.
[07:28:58.355] <TB1> INFO: 41600 events read in total (2996ms).
[07:28:58.356] <TB1> INFO: Test took 3855ms.
[07:28:58.358] <TB1> INFO: scanning low vcal = 40
[07:28:58.652] <TB1> INFO: Expecting 41600 events.
[07:29:02.796] <TB1> INFO: 41600 events read in total (3588ms).
[07:29:02.797] <TB1> INFO: Test took 4439ms.
[07:29:02.800] <TB1> INFO: scanning low vcal = 50
[07:29:03.099] <TB1> INFO: Expecting 41600 events.
[07:29:07.295] <TB1> INFO: 41600 events read in total (3639ms).
[07:29:07.295] <TB1> INFO: Test took 4495ms.
[07:29:07.379] <TB1> INFO: scanning low vcal = 60
[07:29:07.653] <TB1> INFO: Expecting 41600 events.
[07:29:11.868] <TB1> INFO: 41600 events read in total (3658ms).
[07:29:11.868] <TB1> INFO: Test took 4489ms.
[07:29:11.871] <TB1> INFO: scanning low vcal = 70
[07:29:12.142] <TB1> INFO: Expecting 41600 events.
[07:29:16.363] <TB1> INFO: 41600 events read in total (3665ms).
[07:29:16.364] <TB1> INFO: Test took 4493ms.
[07:29:16.366] <TB1> INFO: scanning low vcal = 80
[07:29:16.641] <TB1> INFO: Expecting 41600 events.
[07:29:20.833] <TB1> INFO: 41600 events read in total (3636ms).
[07:29:20.833] <TB1> INFO: Test took 4466ms.
[07:29:20.836] <TB1> INFO: scanning low vcal = 90
[07:29:21.109] <TB1> INFO: Expecting 41600 events.
[07:29:25.371] <TB1> INFO: 41600 events read in total (3706ms).
[07:29:25.371] <TB1> INFO: Test took 4535ms.
[07:29:25.375] <TB1> INFO: scanning low vcal = 100
[07:29:25.638] <TB1> INFO: Expecting 41600 events.
[07:29:29.868] <TB1> INFO: 41600 events read in total (3674ms).
[07:29:29.869] <TB1> INFO: Test took 4494ms.
[07:29:29.872] <TB1> INFO: scanning low vcal = 110
[07:29:30.150] <TB1> INFO: Expecting 41600 events.
[07:29:34.333] <TB1> INFO: 41600 events read in total (3627ms).
[07:29:34.334] <TB1> INFO: Test took 4462ms.
[07:29:34.336] <TB1> INFO: scanning low vcal = 120
[07:29:34.596] <TB1> INFO: Expecting 41600 events.
[07:29:38.768] <TB1> INFO: 41600 events read in total (3615ms).
[07:29:38.769] <TB1> INFO: Test took 4433ms.
[07:29:38.772] <TB1> INFO: scanning low vcal = 130
[07:29:39.044] <TB1> INFO: Expecting 41600 events.
[07:29:43.223] <TB1> INFO: 41600 events read in total (3623ms).
[07:29:43.223] <TB1> INFO: Test took 4451ms.
[07:29:43.226] <TB1> INFO: scanning low vcal = 140
[07:29:43.522] <TB1> INFO: Expecting 41600 events.
[07:29:47.727] <TB1> INFO: 41600 events read in total (3648ms).
[07:29:47.727] <TB1> INFO: Test took 4501ms.
[07:29:47.730] <TB1> INFO: scanning low vcal = 150
[07:29:47.993] <TB1> INFO: Expecting 41600 events.
[07:29:52.173] <TB1> INFO: 41600 events read in total (3623ms).
[07:29:52.174] <TB1> INFO: Test took 4444ms.
[07:29:52.176] <TB1> INFO: scanning low vcal = 160
[07:29:52.450] <TB1> INFO: Expecting 41600 events.
[07:29:56.682] <TB1> INFO: 41600 events read in total (3675ms).
[07:29:56.683] <TB1> INFO: Test took 4507ms.
[07:29:56.685] <TB1> INFO: scanning low vcal = 170
[07:29:56.977] <TB1> INFO: Expecting 41600 events.
[07:30:01.227] <TB1> INFO: 41600 events read in total (3693ms).
[07:30:01.227] <TB1> INFO: Test took 4541ms.
[07:30:01.231] <TB1> INFO: scanning low vcal = 180
[07:30:01.501] <TB1> INFO: Expecting 41600 events.
[07:30:05.751] <TB1> INFO: 41600 events read in total (3694ms).
[07:30:05.752] <TB1> INFO: Test took 4520ms.
[07:30:05.754] <TB1> INFO: scanning low vcal = 190
[07:30:06.055] <TB1> INFO: Expecting 41600 events.
[07:30:10.306] <TB1> INFO: 41600 events read in total (3694ms).
[07:30:10.307] <TB1> INFO: Test took 4552ms.
[07:30:10.310] <TB1> INFO: scanning low vcal = 200
[07:30:10.602] <TB1> INFO: Expecting 41600 events.
[07:30:14.788] <TB1> INFO: 41600 events read in total (3630ms).
[07:30:14.789] <TB1> INFO: Test took 4479ms.
[07:30:14.792] <TB1> INFO: scanning low vcal = 210
[07:30:15.095] <TB1> INFO: Expecting 41600 events.
[07:30:19.289] <TB1> INFO: 41600 events read in total (3638ms).
[07:30:19.290] <TB1> INFO: Test took 4498ms.
[07:30:19.294] <TB1> INFO: scanning low vcal = 220
[07:30:19.557] <TB1> INFO: Expecting 41600 events.
[07:30:23.759] <TB1> INFO: 41600 events read in total (3645ms).
[07:30:23.759] <TB1> INFO: Test took 4465ms.
[07:30:23.762] <TB1> INFO: scanning low vcal = 230
[07:30:24.043] <TB1> INFO: Expecting 41600 events.
[07:30:28.244] <TB1> INFO: 41600 events read in total (3645ms).
[07:30:28.245] <TB1> INFO: Test took 4482ms.
[07:30:28.248] <TB1> INFO: scanning low vcal = 240
[07:30:28.512] <TB1> INFO: Expecting 41600 events.
[07:30:32.754] <TB1> INFO: 41600 events read in total (3686ms).
[07:30:32.756] <TB1> INFO: Test took 4508ms.
[07:30:32.759] <TB1> INFO: scanning low vcal = 250
[07:30:33.061] <TB1> INFO: Expecting 41600 events.
[07:30:37.264] <TB1> INFO: 41600 events read in total (3646ms).
[07:30:37.274] <TB1> INFO: Test took 4515ms.
[07:30:37.278] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[07:30:37.540] <TB1> INFO: Expecting 41600 events.
[07:30:41.781] <TB1> INFO: 41600 events read in total (3684ms).
[07:30:41.782] <TB1> INFO: Test took 4504ms.
[07:30:41.784] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[07:30:42.059] <TB1> INFO: Expecting 41600 events.
[07:30:46.262] <TB1> INFO: 41600 events read in total (3647ms).
[07:30:46.263] <TB1> INFO: Test took 4478ms.
[07:30:46.265] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[07:30:46.573] <TB1> INFO: Expecting 41600 events.
[07:30:50.792] <TB1> INFO: 41600 events read in total (3662ms).
[07:30:50.793] <TB1> INFO: Test took 4527ms.
[07:30:50.796] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[07:30:51.055] <TB1> INFO: Expecting 41600 events.
[07:30:55.292] <TB1> INFO: 41600 events read in total (3681ms).
[07:30:55.293] <TB1> INFO: Test took 4497ms.
[07:30:55.295] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[07:30:55.599] <TB1> INFO: Expecting 41600 events.
[07:30:59.819] <TB1> INFO: 41600 events read in total (3664ms).
[07:30:59.820] <TB1> INFO: Test took 4524ms.
[07:31:00.283] <TB1> INFO: PixTestGainPedestal::measure() done
[07:31:32.353] <TB1> INFO: PixTestGainPedestal::fit() done
[07:31:32.353] <TB1> INFO: non-linearity mean: 0.941 0.955 0.957 0.950 0.961 0.957 0.953 0.950 0.952 0.958 0.956 0.953 0.961 0.959 0.962 0.957
[07:31:32.353] <TB1> INFO: non-linearity RMS: 0.008 0.004 0.006 0.006 0.005 0.007 0.006 0.006 0.006 0.004 0.005 0.006 0.005 0.005 0.005 0.005
[07:31:32.353] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[07:31:32.371] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[07:31:32.388] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[07:31:32.406] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[07:31:32.423] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[07:31:32.440] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[07:31:32.458] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[07:31:32.475] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[07:31:32.493] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[07:31:32.510] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[07:31:32.528] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[07:31:32.545] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[07:31:32.563] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[07:31:32.580] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[07:31:32.598] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[07:31:32.615] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[07:31:32.633] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[07:31:32.633] <TB1> INFO: Decoding statistics:
[07:31:32.633] <TB1> INFO: General information:
[07:31:32.633] <TB1> INFO: 16bit words read: 2662400
[07:31:32.633] <TB1> INFO: valid events total: 166400
[07:31:32.633] <TB1> INFO: empty events: 0
[07:31:32.633] <TB1> INFO: valid events with pixels: 166400
[07:31:32.633] <TB1> INFO: valid pixel hits: 665581
[07:31:32.633] <TB1> INFO: Event errors: 0
[07:31:32.633] <TB1> INFO: start marker: 0
[07:31:32.633] <TB1> INFO: stop marker: 0
[07:31:32.633] <TB1> INFO: overflow: 0
[07:31:32.633] <TB1> INFO: invalid 5bit words: 0
[07:31:32.633] <TB1> INFO: invalid XOR eye diagram: 0
[07:31:32.633] <TB1> INFO: frame (failed synchr.): 0
[07:31:32.633] <TB1> INFO: idle data (no TBM trl): 0
[07:31:32.633] <TB1> INFO: no data (only TBM hdr): 0
[07:31:32.633] <TB1> INFO: TBM errors: 0
[07:31:32.633] <TB1> INFO: flawed TBM headers: 0
[07:31:32.633] <TB1> INFO: flawed TBM trailers: 0
[07:31:32.633] <TB1> INFO: event ID mismatches: 0
[07:31:32.633] <TB1> INFO: ROC errors: 0
[07:31:32.633] <TB1> INFO: missing ROC header(s): 0
[07:31:32.633] <TB1> INFO: misplaced readback start: 0
[07:31:32.633] <TB1> INFO: Pixel decoding errors: 0
[07:31:32.633] <TB1> INFO: pixel data incomplete: 0
[07:31:32.633] <TB1> INFO: pixel address: 0
[07:31:32.633] <TB1> INFO: pulse height fill bit: 0
[07:31:32.633] <TB1> INFO: buffer corruption: 0
[07:31:32.640] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C15.dat
[07:31:32.641] <TB1> INFO: ######################################################################
[07:31:32.641] <TB1> INFO: PixTestReadback::doTest()
[07:31:32.641] <TB1> INFO: ######################################################################
[07:31:32.641] <TB1> INFO: ----------------------------------------------------------------------
[07:31:32.641] <TB1> INFO: PixTestReadback::CalibrateVd()
[07:31:32.641] <TB1> INFO: ----------------------------------------------------------------------
[07:31:42.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C0.dat
[07:31:42.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C1.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C2.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C3.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C4.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C5.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C6.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C7.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C8.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C9.dat
[07:31:42.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C10.dat
[07:31:42.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C11.dat
[07:31:42.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C12.dat
[07:31:42.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C13.dat
[07:31:42.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C14.dat
[07:31:42.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C15.dat
[07:31:42.175] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[07:31:42.175] <TB1> INFO: ----------------------------------------------------------------------
[07:31:42.175] <TB1> INFO: PixTestReadback::CalibrateVa()
[07:31:42.175] <TB1> INFO: ----------------------------------------------------------------------
[07:31:51.673] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C0.dat
[07:31:51.674] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C1.dat
[07:31:51.674] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C2.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C3.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C4.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C5.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C6.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C7.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C8.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C9.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C10.dat
[07:31:51.675] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C11.dat
[07:31:51.676] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C12.dat
[07:31:51.676] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C13.dat
[07:31:51.676] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C14.dat
[07:31:51.676] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C15.dat
[07:31:51.707] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[07:31:51.707] <TB1> INFO: ----------------------------------------------------------------------
[07:31:51.707] <TB1> INFO: PixTestReadback::readbackVbg()
[07:31:51.707] <TB1> INFO: ----------------------------------------------------------------------
[07:31:59.032] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[07:31:59.032] <TB1> INFO: ----------------------------------------------------------------------
[07:31:59.032] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[07:31:59.032] <TB1> INFO: ----------------------------------------------------------------------
[07:31:59.032] <TB1> INFO: Vbg will be calibrated using Vd calibration
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 161.9calibrated Vbg = 1.18796 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.9calibrated Vbg = 1.18906 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152calibrated Vbg = 1.19229 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 144.6calibrated Vbg = 1.19867 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.3calibrated Vbg = 1.20147 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 153.8calibrated Vbg = 1.2111 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.4calibrated Vbg = 1.2008 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.5calibrated Vbg = 1.20126 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.8calibrated Vbg = 1.20697 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.2calibrated Vbg = 1.20093 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 157.9calibrated Vbg = 1.20438 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 143.9calibrated Vbg = 1.2034 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.8calibrated Vbg = 1.19851 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 147.7calibrated Vbg = 1.19545 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.2calibrated Vbg = 1.19213 :::*/*/*/*/
[07:31:59.032] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147.1calibrated Vbg = 1.18793 :::*/*/*/*/
[07:31:59.034] <TB1> INFO: ----------------------------------------------------------------------
[07:31:59.034] <TB1> INFO: PixTestReadback::CalibrateIa()
[07:31:59.034] <TB1> INFO: ----------------------------------------------------------------------
[07:34:34.343] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C0.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C1.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C2.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C3.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C4.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C5.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C6.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C7.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C8.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C9.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C10.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C11.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C12.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C13.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C14.dat
[07:34:34.344] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2292_FullQualification_2016-08-19_07h55m_1471586112//000_FulltestPxar_m20//readbackCal_C15.dat
[07:34:34.374] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[07:34:34.375] <TB1> INFO: PixTestReadback::doTest() done
[07:34:34.375] <TB1> INFO: Decoding statistics:
[07:34:34.375] <TB1> INFO: General information:
[07:34:34.375] <TB1> INFO: 16bit words read: 1024
[07:34:34.375] <TB1> INFO: valid events total: 128
[07:34:34.375] <TB1> INFO: empty events: 128
[07:34:34.375] <TB1> INFO: valid events with pixels: 0
[07:34:34.375] <TB1> INFO: valid pixel hits: 0
[07:34:34.375] <TB1> INFO: Event errors: 0
[07:34:34.375] <TB1> INFO: start marker: 0
[07:34:34.375] <TB1> INFO: stop marker: 0
[07:34:34.375] <TB1> INFO: overflow: 0
[07:34:34.375] <TB1> INFO: invalid 5bit words: 0
[07:34:34.375] <TB1> INFO: invalid XOR eye diagram: 0
[07:34:34.375] <TB1> INFO: frame (failed synchr.): 0
[07:34:34.375] <TB1> INFO: idle data (no TBM trl): 0
[07:34:34.375] <TB1> INFO: no data (only TBM hdr): 0
[07:34:34.375] <TB1> INFO: TBM errors: 0
[07:34:34.375] <TB1> INFO: flawed TBM headers: 0
[07:34:34.375] <TB1> INFO: flawed TBM trailers: 0
[07:34:34.375] <TB1> INFO: event ID mismatches: 0
[07:34:34.375] <TB1> INFO: ROC errors: 0
[07:34:34.375] <TB1> INFO: missing ROC header(s): 0
[07:34:34.375] <TB1> INFO: misplaced readback start: 0
[07:34:34.375] <TB1> INFO: Pixel decoding errors: 0
[07:34:34.375] <TB1> INFO: pixel data incomplete: 0
[07:34:34.375] <TB1> INFO: pixel address: 0
[07:34:34.375] <TB1> INFO: pulse height fill bit: 0
[07:34:34.375] <TB1> INFO: buffer corruption: 0
[07:34:34.392] <TB1> INFO: Decoding statistics:
[07:34:34.392] <TB1> INFO: General information:
[07:34:34.392] <TB1> INFO: 16bit words read: 2755352
[07:34:34.392] <TB1> INFO: valid events total: 176768
[07:34:34.392] <TB1> INFO: empty events: 7866
[07:34:34.392] <TB1> INFO: valid events with pixels: 168902
[07:34:34.392] <TB1> INFO: valid pixel hits: 668083
[07:34:34.392] <TB1> INFO: Event errors: 0
[07:34:34.392] <TB1> INFO: start marker: 0
[07:34:34.392] <TB1> INFO: stop marker: 0
[07:34:34.392] <TB1> INFO: overflow: 0
[07:34:34.392] <TB1> INFO: invalid 5bit words: 0
[07:34:34.392] <TB1> INFO: invalid XOR eye diagram: 0
[07:34:34.392] <TB1> INFO: frame (failed synchr.): 0
[07:34:34.392] <TB1> INFO: idle data (no TBM trl): 0
[07:34:34.392] <TB1> INFO: no data (only TBM hdr): 0
[07:34:34.392] <TB1> INFO: TBM errors: 0
[07:34:34.392] <TB1> INFO: flawed TBM headers: 0
[07:34:34.392] <TB1> INFO: flawed TBM trailers: 0
[07:34:34.392] <TB1> INFO: event ID mismatches: 0
[07:34:34.392] <TB1> INFO: ROC errors: 0
[07:34:34.392] <TB1> INFO: missing ROC header(s): 0
[07:34:34.392] <TB1> INFO: misplaced readback start: 0
[07:34:34.392] <TB1> INFO: Pixel decoding errors: 0
[07:34:34.392] <TB1> INFO: pixel data incomplete: 0
[07:34:34.392] <TB1> INFO: pixel address: 0
[07:34:34.392] <TB1> INFO: pulse height fill bit: 0
[07:34:34.392] <TB1> INFO: buffer corruption: 0
[07:34:34.392] <TB1> INFO: enter test to run
[07:34:34.392] <TB1> INFO: test: exit no parameter change
[07:34:34.566] <TB1> QUIET: Connection to board 153 closed.
[07:34:34.646] <TB1> INFO: pXar: this is the end, my friend