[07:16:46.412] <TB2> INFO: *** Welcome to pxar ***
[07:16:46.412] <TB2> INFO: *** Today: 2016/04/11
[07:16:46.524] <TB2> INFO: *** Version: 9751-dirty
[07:16:46.525] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C15.dat
[07:16:46.526] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:16:46.526] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//defaultMaskFile.dat
[07:16:46.526] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters_C15.dat
[07:16:46.616] <TB2> INFO: clk: 4
[07:16:46.616] <TB2> INFO: ctr: 4
[07:16:46.616] <TB2> INFO: sda: 19
[07:16:46.616] <TB2> INFO: tin: 9
[07:16:46.616] <TB2> INFO: level: 15
[07:16:46.616] <TB2> INFO: triggerdelay: 0
[07:16:46.616] <TB2> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[07:16:46.616] <TB2> INFO: Log level: INFO
[07:16:46.623] <TB2> INFO: Found DTB DTB_WXC55Z
[07:16:46.633] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[07:16:46.635] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[07:16:46.637] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[07:16:48.171] <TB2> INFO: DUT info:
[07:16:48.171] <TB2> INFO: The DUT currently contains the following objects:
[07:16:48.171] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[07:16:48.171] <TB2> INFO: TBM Core alpha (0): 7 registers set
[07:16:48.171] <TB2> INFO: TBM Core beta (1): 7 registers set
[07:16:48.171] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:16:48.171] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.171] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.171] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.171] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.172] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:16:48.573] <TB2> INFO: enter 'restricted' command line mode
[07:16:48.573] <TB2> INFO: enter test to run
[07:16:48.573] <TB2> INFO: test: pretest no parameter change
[07:16:48.573] <TB2> INFO: running: pretest
[07:16:48.580] <TB2> INFO: ----------------------------------------------------------------------
[07:16:48.580] <TB2> INFO: PixTestPretest::programROC()
[07:16:48.580] <TB2> INFO: ----------------------------------------------------------------------
[07:17:06.594] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:17:06.594] <TB2> INFO: IA differences per ROC: 20.9 16.9 18.5 17.7 18.5 16.1 19.3 20.9 16.9 17.7 19.3 19.3 17.7 18.5 16.9 20.1
[07:17:06.686] <TB2> INFO: enter test to run
[07:17:06.686] <TB2> INFO: test: pretest no parameter change
[07:17:06.686] <TB2> INFO: running: pretest
[07:17:06.687] <TB2> INFO: ----------------------------------------------------------------------
[07:17:06.687] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:17:06.687] <TB2> INFO: ----------------------------------------------------------------------
[07:17:27.987] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[07:17:27.987] <TB2> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 20.1 19.3 19.3 20.1 19.3 20.1 19.3 20.1 20.1 19.3 18.5 19.3 20.1
[07:17:28.024] <TB2> INFO: enter test to run
[07:17:28.024] <TB2> INFO: test: pretest no parameter change
[07:17:28.025] <TB2> INFO: running: pretest
[07:17:28.025] <TB2> INFO: ----------------------------------------------------------------------
[07:17:28.025] <TB2> INFO: PixTestPretest::findTiming()
[07:17:28.025] <TB2> INFO: ----------------------------------------------------------------------
[07:17:28.025] <TB2> INFO: PixTestCmd::init()

[07:18:58.157] <TB2> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[07:18:58.157] <TB2> INFO: (success/tries = 100/100), width = 3
[07:18:58.158] <TB2> INFO: enter test to run
[07:18:58.158] <TB2> INFO: test: pretest no parameter change
[07:18:58.158] <TB2> INFO: running: pretest
[07:18:58.159] <TB2> INFO: ----------------------------------------------------------------------
[07:18:58.159] <TB2> INFO: PixTestPretest::findWorkingPixel()
[07:18:58.159] <TB2> INFO: ----------------------------------------------------------------------
[07:18:58.254] <TB2> INFO: Expecting 231680 events.


[07:19:08.484] <TB2> INFO: 231680 events read in total (9673ms).
[07:19:08.488] <TB2> INFO: Test took 10323ms.
[07:19:08.717] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:19:08.782] <TB2> INFO: enter test to run
[07:19:08.782] <TB2> INFO: test: pretest no parameter change
[07:19:08.782] <TB2> INFO: running: pretest
[07:19:08.783] <TB2> INFO: ----------------------------------------------------------------------
[07:19:08.783] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[07:19:08.783] <TB2> INFO: ----------------------------------------------------------------------
[07:19:08.877] <TB2> INFO: Expecting 231680 events.
[07:19:19.157] <TB2> INFO: 231680 events read in total (9724ms).
[07:19:19.161] <TB2> INFO: Test took 10373ms.
[07:19:19.394] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[07:19:19.394] <TB2> INFO: CalDel: 142 143 144 144 147 148 173 149 143 143 139 143 140 150 126 126
[07:19:19.394] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:19:19.428] <TB2> INFO: enter test to run
[07:19:19.428] <TB2> INFO: test: pretest no parameter change
[07:19:19.428] <TB2> INFO: running: pretest
[07:19:19.428] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C0.dat
[07:19:19.428] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C1.dat
[07:19:19.429] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C2.dat
[07:19:19.429] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C3.dat
[07:19:19.429] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C4.dat
[07:19:19.429] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C5.dat
[07:19:19.429] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C6.dat
[07:19:19.429] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C7.dat
[07:19:19.429] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C8.dat
[07:19:19.430] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C9.dat
[07:19:19.430] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C10.dat
[07:19:19.430] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C11.dat
[07:19:19.430] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C12.dat
[07:19:19.430] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C13.dat
[07:19:19.430] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C14.dat
[07:19:19.430] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters_C15.dat
[07:19:19.430] <TB2> INFO: enter test to run
[07:19:19.430] <TB2> INFO: test: fulltest no parameter change
[07:19:19.430] <TB2> INFO: running: fulltest
[07:19:19.430] <TB2> INFO: ######################################################################
[07:19:19.430] <TB2> INFO: PixTestFullTest::doTest()
[07:19:19.430] <TB2> INFO: ######################################################################
[07:19:19.432] <TB2> INFO: ######################################################################
[07:19:19.432] <TB2> INFO: PixTestAlive::doTest()
[07:19:19.432] <TB2> INFO: ######################################################################
[07:19:19.433] <TB2> INFO: ----------------------------------------------------------------------
[07:19:19.433] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:19:19.433] <TB2> INFO: ----------------------------------------------------------------------
[07:19:19.701] <TB2> INFO: Expecting 41600 events.
[07:19:23.553] <TB2> INFO: 41600 events read in total (3296ms).
[07:19:23.553] <TB2> INFO: Test took 4119ms.
[07:19:23.785] <TB2> INFO: PixTestAlive::aliveTest() done
[07:19:23.785] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[07:19:23.786] <TB2> INFO: ----------------------------------------------------------------------
[07:19:23.786] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:19:23.786] <TB2> INFO: ----------------------------------------------------------------------
[07:19:24.058] <TB2> INFO: Expecting 41600 events.
[07:19:27.048] <TB2> INFO: 41600 events read in total (2434ms).
[07:19:27.048] <TB2> INFO: Test took 3260ms.
[07:19:27.049] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:19:27.301] <TB2> INFO: PixTestAlive::maskTest() done
[07:19:27.301] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:19:27.302] <TB2> INFO: ----------------------------------------------------------------------
[07:19:27.302] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:19:27.302] <TB2> INFO: ----------------------------------------------------------------------
[07:19:27.600] <TB2> INFO: Expecting 41600 events.
[07:19:31.429] <TB2> INFO: 41600 events read in total (3273ms).
[07:19:31.430] <TB2> INFO: Test took 4125ms.
[07:19:31.665] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[07:19:31.665] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:19:31.665] <TB2> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[07:19:31.665] <TB2> INFO: Decoding statistics:
[07:19:31.665] <TB2> INFO: General information:
[07:19:31.665] <TB2> INFO: 16bit words read: 0
[07:19:31.665] <TB2> INFO: valid events total: 0
[07:19:31.665] <TB2> INFO: empty events: 0
[07:19:31.665] <TB2> INFO: valid events with pixels: 0
[07:19:31.665] <TB2> INFO: valid pixel hits: 0
[07:19:31.665] <TB2> INFO: Event errors: 0
[07:19:31.665] <TB2> INFO: start marker: 0
[07:19:31.666] <TB2> INFO: stop marker: 0
[07:19:31.666] <TB2> INFO: overflow: 0
[07:19:31.666] <TB2> INFO: invalid 5bit words: 0
[07:19:31.666] <TB2> INFO: invalid XOR eye diagram: 0
[07:19:31.666] <TB2> INFO: frame (failed synchr.): 0
[07:19:31.666] <TB2> INFO: idle data (no TBM trl): 0
[07:19:31.666] <TB2> INFO: no data (only TBM hdr): 0
[07:19:31.666] <TB2> INFO: TBM errors: 0
[07:19:31.666] <TB2> INFO: flawed TBM headers: 0
[07:19:31.666] <TB2> INFO: flawed TBM trailers: 0
[07:19:31.666] <TB2> INFO: event ID mismatches: 0
[07:19:31.666] <TB2> INFO: ROC errors: 0
[07:19:31.666] <TB2> INFO: missing ROC header(s): 0
[07:19:31.666] <TB2> INFO: misplaced readback start: 0
[07:19:31.666] <TB2> INFO: Pixel decoding errors: 0
[07:19:31.666] <TB2> INFO: pixel data incomplete: 0
[07:19:31.666] <TB2> INFO: pixel address: 0
[07:19:31.666] <TB2> INFO: pulse height fill bit: 0
[07:19:31.666] <TB2> INFO: buffer corruption: 0
[07:19:31.680] <TB2> INFO: ######################################################################
[07:19:31.680] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:19:31.680] <TB2> INFO: ######################################################################
[07:19:31.682] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:19:31.694] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:19:31.694] <TB2> INFO: run 1 of 1
[07:19:31.956] <TB2> INFO: Expecting 3120000 events.
[07:20:13.732] <TB2> INFO: 874095 events read in total (41220ms).
[07:20:55.193] <TB2> INFO: 1738500 events read in total (82681ms).
[07:21:36.231] <TB2> INFO: 2612625 events read in total (123720ms).
[07:21:59.985] <TB2> INFO: 3120000 events read in total (147473ms).
[07:22:00.033] <TB2> INFO: Test took 148338ms.
[07:22:22.485] <TB2> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[07:22:22.486] <TB2> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 0 0 3 0 2 4 2 4 4 13
[07:22:22.486] <TB2> INFO: separation cut (per ROC): 103 90 104 91 82 89 91 92 77 86 100 107 107 71 89 94
[07:22:22.486] <TB2> INFO: Decoding statistics:
[07:22:22.486] <TB2> INFO: General information:
[07:22:22.486] <TB2> INFO: 16bit words read: 0
[07:22:22.486] <TB2> INFO: valid events total: 0
[07:22:22.486] <TB2> INFO: empty events: 0
[07:22:22.486] <TB2> INFO: valid events with pixels: 0
[07:22:22.486] <TB2> INFO: valid pixel hits: 0
[07:22:22.486] <TB2> INFO: Event errors: 0
[07:22:22.486] <TB2> INFO: start marker: 0
[07:22:22.486] <TB2> INFO: stop marker: 0
[07:22:22.486] <TB2> INFO: overflow: 0
[07:22:22.486] <TB2> INFO: invalid 5bit words: 0
[07:22:22.486] <TB2> INFO: invalid XOR eye diagram: 0
[07:22:22.486] <TB2> INFO: frame (failed synchr.): 0
[07:22:22.486] <TB2> INFO: idle data (no TBM trl): 0
[07:22:22.486] <TB2> INFO: no data (only TBM hdr): 0
[07:22:22.486] <TB2> INFO: TBM errors: 0
[07:22:22.486] <TB2> INFO: flawed TBM headers: 0
[07:22:22.486] <TB2> INFO: flawed TBM trailers: 0
[07:22:22.486] <TB2> INFO: event ID mismatches: 0
[07:22:22.486] <TB2> INFO: ROC errors: 0
[07:22:22.486] <TB2> INFO: missing ROC header(s): 0
[07:22:22.486] <TB2> INFO: misplaced readback start: 0
[07:22:22.486] <TB2> INFO: Pixel decoding errors: 0
[07:22:22.486] <TB2> INFO: pixel data incomplete: 0
[07:22:22.486] <TB2> INFO: pixel address: 0
[07:22:22.486] <TB2> INFO: pulse height fill bit: 0
[07:22:22.486] <TB2> INFO: buffer corruption: 0
[07:22:22.557] <TB2> INFO: ######################################################################
[07:22:22.557] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:22:22.557] <TB2> INFO: ######################################################################
[07:22:22.557] <TB2> INFO: ----------------------------------------------------------------------
[07:22:22.557] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:22:22.557] <TB2> INFO: ----------------------------------------------------------------------
[07:22:22.557] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:22:22.565] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:22:22.565] <TB2> INFO: run 1 of 1
[07:22:22.826] <TB2> INFO: Expecting 26208000 events.
[07:22:57.096] <TB2> INFO: 893200 events read in total (33714ms).
[07:23:30.073] <TB2> INFO: 1770750 events read in total (66691ms).
[07:24:02.867] <TB2> INFO: 2648900 events read in total (99485ms).
[07:24:35.757] <TB2> INFO: 3525250 events read in total (132375ms).
[07:25:08.755] <TB2> INFO: 4396900 events read in total (165373ms).
[07:25:41.854] <TB2> INFO: 5272400 events read in total (198472ms).
[07:26:14.720] <TB2> INFO: 6148250 events read in total (231338ms).
[07:26:47.825] <TB2> INFO: 7021550 events read in total (264443ms).
[07:27:20.588] <TB2> INFO: 7894350 events read in total (297206ms).
[07:27:54.205] <TB2> INFO: 8768300 events read in total (330823ms).
[07:28:26.895] <TB2> INFO: 9644250 events read in total (363513ms).
[07:28:59.900] <TB2> INFO: 10517600 events read in total (396518ms).
[07:29:32.506] <TB2> INFO: 11388750 events read in total (429124ms).
[07:30:05.526] <TB2> INFO: 12261600 events read in total (462144ms).
[07:30:38.918] <TB2> INFO: 13132300 events read in total (495536ms).
[07:31:11.589] <TB2> INFO: 13995550 events read in total (528207ms).
[07:31:44.071] <TB2> INFO: 14857900 events read in total (560689ms).
[07:32:17.323] <TB2> INFO: 15721950 events read in total (593941ms).
[07:32:50.237] <TB2> INFO: 16582150 events read in total (626855ms).
[07:33:23.148] <TB2> INFO: 17444450 events read in total (659766ms).
[07:33:56.185] <TB2> INFO: 18308650 events read in total (692803ms).
[07:34:29.369] <TB2> INFO: 19169850 events read in total (725987ms).
[07:35:01.933] <TB2> INFO: 20030250 events read in total (758551ms).
[07:35:34.838] <TB2> INFO: 20892250 events read in total (791456ms).
[07:36:08.519] <TB2> INFO: 21756850 events read in total (825137ms).
[07:36:41.388] <TB2> INFO: 22616300 events read in total (858006ms).
[07:37:14.258] <TB2> INFO: 23480700 events read in total (890876ms).
[07:37:47.222] <TB2> INFO: 24344300 events read in total (923840ms).
[07:38:20.408] <TB2> INFO: 25209800 events read in total (957026ms).
[07:38:53.057] <TB2> INFO: 26086700 events read in total (989675ms).
[07:38:57.960] <TB2> INFO: 26208000 events read in total (994578ms).
[07:38:57.990] <TB2> INFO: Test took 995425ms.
[07:38:58.220] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:38:59.642] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:01.105] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:02.500] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:03.914] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:05.396] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:06.870] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:08.314] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:09.716] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:11.138] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:12.686] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:14.127] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:15.558] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:16.987] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:18.517] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:19.974] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:39:21.434] <TB2> INFO: PixTestScurves::scurves() done
[07:39:21.434] <TB2> INFO: Vcal mean: 104.16 86.29 98.49 81.36 84.82 81.12 86.66 83.41 80.59 92.24 95.36 102.98 103.15 73.90 83.16 108.01
[07:39:21.434] <TB2> INFO: Vcal RMS: 5.62 5.76 5.46 4.56 4.98 4.19 5.10 4.63 4.42 5.51 5.86 6.28 6.51 4.55 4.82 6.20
[07:39:21.434] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1018 seconds
[07:39:21.434] <TB2> INFO: Decoding statistics:
[07:39:21.434] <TB2> INFO: General information:
[07:39:21.434] <TB2> INFO: 16bit words read: 0
[07:39:21.434] <TB2> INFO: valid events total: 0
[07:39:21.434] <TB2> INFO: empty events: 0
[07:39:21.434] <TB2> INFO: valid events with pixels: 0
[07:39:21.434] <TB2> INFO: valid pixel hits: 0
[07:39:21.434] <TB2> INFO: Event errors: 0
[07:39:21.434] <TB2> INFO: start marker: 0
[07:39:21.434] <TB2> INFO: stop marker: 0
[07:39:21.434] <TB2> INFO: overflow: 0
[07:39:21.434] <TB2> INFO: invalid 5bit words: 0
[07:39:21.434] <TB2> INFO: invalid XOR eye diagram: 0
[07:39:21.434] <TB2> INFO: frame (failed synchr.): 0
[07:39:21.434] <TB2> INFO: idle data (no TBM trl): 0
[07:39:21.434] <TB2> INFO: no data (only TBM hdr): 0
[07:39:21.434] <TB2> INFO: TBM errors: 0
[07:39:21.434] <TB2> INFO: flawed TBM headers: 0
[07:39:21.434] <TB2> INFO: flawed TBM trailers: 0
[07:39:21.434] <TB2> INFO: event ID mismatches: 0
[07:39:21.434] <TB2> INFO: ROC errors: 0
[07:39:21.434] <TB2> INFO: missing ROC header(s): 0
[07:39:21.434] <TB2> INFO: misplaced readback start: 0
[07:39:21.434] <TB2> INFO: Pixel decoding errors: 0
[07:39:21.434] <TB2> INFO: pixel data incomplete: 0
[07:39:21.434] <TB2> INFO: pixel address: 0
[07:39:21.434] <TB2> INFO: pulse height fill bit: 0
[07:39:21.434] <TB2> INFO: buffer corruption: 0
[07:39:21.504] <TB2> INFO: ######################################################################
[07:39:21.504] <TB2> INFO: PixTestTrim::doTest()
[07:39:21.504] <TB2> INFO: ######################################################################
[07:39:21.505] <TB2> INFO: ----------------------------------------------------------------------
[07:39:21.505] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:39:21.505] <TB2> INFO: ----------------------------------------------------------------------
[07:39:21.583] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:39:21.583] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:39:21.591] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[07:39:21.591] <TB2> INFO: run 1 of 1
[07:39:21.851] <TB2> INFO: Expecting 6281600 events.
[07:40:01.422] <TB2> INFO: 1070160 events read in total (39010ms).
[07:40:40.418] <TB2> INFO: 2132870 events read in total (78006ms).
[07:41:19.529] <TB2> INFO: 3190430 events read in total (117117ms).
[07:41:59.050] <TB2> INFO: 4251900 events read in total (156639ms).
[07:42:38.254] <TB2> INFO: 5318470 events read in total (195842ms).
[07:43:13.345] <TB2> INFO: 6281600 events read in total (230933ms).
[07:43:13.376] <TB2> INFO: Test took 231785ms.
[07:43:32.641] <TB2> INFO: ROC 0 VthrComp = 106
[07:43:32.641] <TB2> INFO: ROC 1 VthrComp = 88
[07:43:32.641] <TB2> INFO: ROC 2 VthrComp = 101
[07:43:32.641] <TB2> INFO: ROC 3 VthrComp = 86
[07:43:32.642] <TB2> INFO: ROC 4 VthrComp = 88
[07:43:32.642] <TB2> INFO: ROC 5 VthrComp = 86
[07:43:32.642] <TB2> INFO: ROC 6 VthrComp = 88
[07:43:32.642] <TB2> INFO: ROC 7 VthrComp = 87
[07:43:32.642] <TB2> INFO: ROC 8 VthrComp = 85
[07:43:32.642] <TB2> INFO: ROC 9 VthrComp = 92
[07:43:32.642] <TB2> INFO: ROC 10 VthrComp = 98
[07:43:32.642] <TB2> INFO: ROC 11 VthrComp = 100
[07:43:32.642] <TB2> INFO: ROC 12 VthrComp = 99
[07:43:32.643] <TB2> INFO: ROC 13 VthrComp = 77
[07:43:32.643] <TB2> INFO: ROC 14 VthrComp = 87
[07:43:32.643] <TB2> INFO: ROC 15 VthrComp = 102
[07:43:32.643] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:43:32.643] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:43:32.651] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[07:43:32.651] <TB2> INFO: run 1 of 1
[07:43:32.910] <TB2> INFO: Expecting 6281600 events.
[07:44:08.833] <TB2> INFO: 756050 events read in total (35366ms).
[07:44:43.480] <TB2> INFO: 1510400 events read in total (70013ms).
[07:45:18.215] <TB2> INFO: 2264740 events read in total (104748ms).
[07:45:52.901] <TB2> INFO: 3019690 events read in total (139434ms).
[07:46:27.944] <TB2> INFO: 3769830 events read in total (174477ms).
[07:47:02.451] <TB2> INFO: 4517160 events read in total (208984ms).
[07:47:37.228] <TB2> INFO: 5263060 events read in total (243761ms).
[07:48:11.799] <TB2> INFO: 6009060 events read in total (278332ms).
[07:48:24.818] <TB2> INFO: 6281600 events read in total (291351ms).
[07:48:24.870] <TB2> INFO: Test took 292218ms.
[07:48:49.843] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 62.3842 for pixel 5/64 mean/min/max = 48.1274/33.7031/62.5518
[07:48:49.844] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 61.1528 for pixel 23/79 mean/min/max = 46.5477/31.9304/61.165
[07:48:49.844] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 59.0242 for pixel 33/0 mean/min/max = 45.6936/32.1271/59.2602
[07:48:49.844] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 57.1264 for pixel 0/0 mean/min/max = 44.7165/32.1399/57.2931
[07:48:49.845] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 58.4661 for pixel 9/0 mean/min/max = 45.5402/32.4746/58.6057
[07:48:49.845] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 56.1208 for pixel 23/0 mean/min/max = 44.5377/32.7085/56.3669
[07:48:49.845] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 58.927 for pixel 0/49 mean/min/max = 46.0724/33.1186/59.0262
[07:48:49.845] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 56.7905 for pixel 21/41 mean/min/max = 44.7433/32.4927/56.9938
[07:48:49.846] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 57.2077 for pixel 2/2 mean/min/max = 45.0305/32.6129/57.448
[07:48:49.846] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 60.4132 for pixel 7/29 mean/min/max = 46.3895/32.2475/60.5314
[07:48:49.846] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 60.2898 for pixel 2/35 mean/min/max = 45.9947/31.6902/60.2992
[07:48:49.846] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 64.0961 for pixel 1/5 mean/min/max = 48.3183/32.2458/64.3908
[07:48:49.847] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 65.5242 for pixel 3/16 mean/min/max = 48.3744/31.1095/65.6392
[07:48:49.847] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 57.9146 for pixel 4/78 mean/min/max = 46.2993/34.4938/58.1048
[07:48:49.847] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 57.062 for pixel 25/68 mean/min/max = 44.9859/32.7303/57.2415
[07:48:49.848] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 64.4971 for pixel 25/1 mean/min/max = 48.396/31.9492/64.8428
[07:48:49.848] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:48:49.936] <TB2> INFO: Expecting 514560 events.
[07:49:02.897] <TB2> INFO: 514560 events read in total (12404ms).
[07:49:02.902] <TB2> INFO: Expecting 514560 events.
[07:49:15.743] <TB2> INFO: 514560 events read in total (12429ms).
[07:49:15.751] <TB2> INFO: Expecting 514560 events.
[07:49:28.519] <TB2> INFO: 514560 events read in total (12361ms).
[07:49:28.527] <TB2> INFO: Expecting 514560 events.
[07:49:41.235] <TB2> INFO: 514560 events read in total (12290ms).
[07:49:41.244] <TB2> INFO: Expecting 514560 events.
[07:49:53.984] <TB2> INFO: 514560 events read in total (12321ms).
[07:49:53.995] <TB2> INFO: Expecting 514560 events.
[07:50:06.778] <TB2> INFO: 514560 events read in total (12366ms).
[07:50:06.794] <TB2> INFO: Expecting 514560 events.
[07:50:19.584] <TB2> INFO: 514560 events read in total (12388ms).
[07:50:19.600] <TB2> INFO: Expecting 514560 events.
[07:50:32.444] <TB2> INFO: 514560 events read in total (12435ms).
[07:50:32.464] <TB2> INFO: Expecting 514560 events.
[07:50:45.245] <TB2> INFO: 514560 events read in total (12380ms).
[07:50:45.265] <TB2> INFO: Expecting 514560 events.
[07:50:58.024] <TB2> INFO: 514560 events read in total (12356ms).
[07:50:58.044] <TB2> INFO: Expecting 514560 events.
[07:51:10.972] <TB2> INFO: 514560 events read in total (12522ms).
[07:51:10.999] <TB2> INFO: Expecting 514560 events.
[07:51:23.751] <TB2> INFO: 514560 events read in total (12351ms).
[07:51:23.775] <TB2> INFO: Expecting 514560 events.
[07:51:36.481] <TB2> INFO: 514560 events read in total (12304ms).
[07:51:36.507] <TB2> INFO: Expecting 514560 events.
[07:51:49.439] <TB2> INFO: 514560 events read in total (12531ms).
[07:51:49.474] <TB2> INFO: Expecting 514560 events.
[07:52:02.239] <TB2> INFO: 514560 events read in total (12364ms).
[07:52:02.274] <TB2> INFO: Expecting 514560 events.
[07:52:15.053] <TB2> INFO: 514560 events read in total (12377ms).
[07:52:15.090] <TB2> INFO: Test took 205242ms.
[07:52:16.006] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:52:16.014] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[07:52:16.014] <TB2> INFO: run 1 of 1
[07:52:16.279] <TB2> INFO: Expecting 6281600 events.
[07:52:51.526] <TB2> INFO: 742240 events read in total (34691ms).
[07:53:26.524] <TB2> INFO: 1483140 events read in total (69689ms).
[07:54:01.082] <TB2> INFO: 2224160 events read in total (104247ms).
[07:54:36.193] <TB2> INFO: 2965560 events read in total (139358ms).
[07:55:10.873] <TB2> INFO: 3701870 events read in total (174038ms).
[07:55:46.085] <TB2> INFO: 4434030 events read in total (209250ms).
[07:56:20.801] <TB2> INFO: 5164450 events read in total (243966ms).
[07:56:55.420] <TB2> INFO: 5893930 events read in total (278585ms).
[07:57:13.723] <TB2> INFO: 6281600 events read in total (296888ms).
[07:57:13.765] <TB2> INFO: Test took 297752ms.
[07:57:38.063] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.039569 .. 255.000000
[07:57:38.138] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[07:57:38.146] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[07:57:38.146] <TB2> INFO: run 1 of 1
[07:57:38.406] <TB2> INFO: Expecting 10649600 events.
[07:58:13.152] <TB2> INFO: 712100 events read in total (34190ms).
[07:58:47.205] <TB2> INFO: 1424560 events read in total (68243ms).
[07:59:21.778] <TB2> INFO: 2136700 events read in total (102816ms).
[07:59:56.462] <TB2> INFO: 2849100 events read in total (137500ms).
[08:00:30.816] <TB2> INFO: 3561730 events read in total (171854ms).
[08:01:05.552] <TB2> INFO: 4274330 events read in total (206590ms).
[08:01:39.608] <TB2> INFO: 4986790 events read in total (240646ms).
[08:02:14.310] <TB2> INFO: 5699620 events read in total (275348ms).
[08:02:48.854] <TB2> INFO: 6411780 events read in total (309892ms).
[08:03:23.229] <TB2> INFO: 7123520 events read in total (344267ms).
[08:03:57.326] <TB2> INFO: 7835320 events read in total (378364ms).
[08:04:31.637] <TB2> INFO: 8546660 events read in total (412675ms).
[08:05:06.774] <TB2> INFO: 9257820 events read in total (447812ms).
[08:05:41.767] <TB2> INFO: 9969000 events read in total (482805ms).
[08:06:14.764] <TB2> INFO: 10649600 events read in total (515802ms).
[08:06:14.883] <TB2> INFO: Test took 516737ms.
[08:06:42.414] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 1.998430 .. 47.454824
[08:06:42.489] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 57 (-1/-1) hits flags = 528 (plus default)
[08:06:42.498] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:06:42.498] <TB2> INFO: run 1 of 1
[08:06:42.757] <TB2> INFO: Expecting 2371200 events.
[08:07:21.219] <TB2> INFO: 943160 events read in total (37902ms).
[08:07:59.338] <TB2> INFO: 1885440 events read in total (76021ms).
[08:08:19.395] <TB2> INFO: 2371200 events read in total (96079ms).
[08:08:19.411] <TB2> INFO: Test took 96913ms.
[08:08:33.065] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 18.571686 .. 44.576851
[08:08:33.145] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 8 .. 54 (-1/-1) hits flags = 528 (plus default)
[08:08:33.153] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:08:33.153] <TB2> INFO: run 1 of 1
[08:08:33.426] <TB2> INFO: Expecting 1955200 events.
[08:09:12.597] <TB2> INFO: 930920 events read in total (38615ms).
[08:09:50.374] <TB2> INFO: 1860160 events read in total (76392ms).
[08:09:54.611] <TB2> INFO: 1955200 events read in total (80629ms).
[08:09:54.626] <TB2> INFO: Test took 81474ms.
[08:10:08.748] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 9.500000 .. 44.576851
[08:10:08.823] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 9 .. 54 (-1/-1) hits flags = 528 (plus default)
[08:10:08.831] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:10:08.831] <TB2> INFO: run 1 of 1
[08:10:09.090] <TB2> INFO: Expecting 1913600 events.
[08:10:48.011] <TB2> INFO: 925190 events read in total (38365ms).
[08:11:26.399] <TB2> INFO: 1849480 events read in total (76753ms).
[08:11:29.368] <TB2> INFO: 1913600 events read in total (79722ms).
[08:11:29.382] <TB2> INFO: Test took 80552ms.
[08:11:43.653] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:11:43.653] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:11:43.662] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:11:43.662] <TB2> INFO: run 1 of 1
[08:11:43.953] <TB2> INFO: Expecting 1705600 events.
[08:12:21.511] <TB2> INFO: 877290 events read in total (37002ms).
[08:12:56.259] <TB2> INFO: 1705600 events read in total (71750ms).
[08:12:56.279] <TB2> INFO: Test took 72618ms.
[08:13:09.663] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:13:09.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:13:09.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:13:09.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:13:09.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:13:09.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:13:09.665] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:13:09.665] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:13:09.665] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:13:09.665] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:13:09.666] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:13:09.666] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:13:09.666] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:13:09.666] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:13:09.666] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:13:09.667] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:13:09.667] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:13:09.673] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:13:09.679] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:13:09.685] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:13:09.690] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:13:09.696] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:13:09.702] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:13:09.708] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:13:09.714] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:13:09.720] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:13:09.725] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:13:09.731] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:13:09.737] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:13:09.743] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:13:09.749] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:13:09.754] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:13:09.760] <TB2> INFO: PixTestTrim::trimTest() done
[08:13:09.760] <TB2> INFO: vtrim: 110 95 99 78 94 81 93 99 98 104 110 105 111 94 99 117
[08:13:09.760] <TB2> INFO: vthrcomp: 106 88 101 86 88 86 88 87 85 92 98 100 99 77 87 102
[08:13:09.760] <TB2> INFO: vcal mean: 35.00 34.97 34.98 34.99 34.96 34.97 34.99 34.99 34.72 34.95 34.97 34.94 34.95 34.97 34.95 34.90
[08:13:09.760] <TB2> INFO: vcal RMS: 0.88 0.88 0.87 0.79 0.87 0.83 0.82 0.84 0.98 0.92 0.88 1.20 0.96 0.81 0.83 1.07
[08:13:09.760] <TB2> INFO: bits mean: 8.56 9.16 9.56 9.24 9.35 9.80 9.11 9.86 9.44 9.16 9.13 9.00 9.22 8.55 9.51 8.95
[08:13:09.760] <TB2> INFO: bits RMS: 2.63 2.74 2.65 2.85 2.69 2.51 2.69 2.54 2.66 2.75 2.88 2.63 2.70 2.70 2.61 2.73
[08:13:09.766] <TB2> INFO: ----------------------------------------------------------------------
[08:13:09.767] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[08:13:09.767] <TB2> INFO: ----------------------------------------------------------------------
[08:13:09.769] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:13:09.778] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:13:09.778] <TB2> INFO: run 1 of 1
[08:13:10.037] <TB2> INFO: Expecting 4160000 events.
[08:13:52.408] <TB2> INFO: 923040 events read in total (41814ms).
[08:14:34.589] <TB2> INFO: 1841570 events read in total (83995ms).
[08:15:16.029] <TB2> INFO: 2753260 events read in total (125435ms).
[08:15:57.590] <TB2> INFO: 3663930 events read in total (166996ms).
[08:16:20.120] <TB2> INFO: 4160000 events read in total (189526ms).
[08:16:20.161] <TB2> INFO: Test took 190383ms.
[08:16:48.385] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[08:16:48.393] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:16:48.393] <TB2> INFO: run 1 of 1
[08:16:48.652] <TB2> INFO: Expecting 3806400 events.
[08:17:30.711] <TB2> INFO: 923085 events read in total (41503ms).
[08:18:11.808] <TB2> INFO: 1841090 events read in total (82600ms).
[08:18:54.080] <TB2> INFO: 2750470 events read in total (124873ms).
[08:19:35.215] <TB2> INFO: 3660880 events read in total (166007ms).
[08:19:42.131] <TB2> INFO: 3806400 events read in total (172923ms).
[08:19:42.166] <TB2> INFO: Test took 173773ms.
[08:20:10.698] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[08:20:10.706] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:20:10.707] <TB2> INFO: run 1 of 1
[08:20:10.965] <TB2> INFO: Expecting 3536000 events.
[08:20:54.267] <TB2> INFO: 950540 events read in total (42745ms).
[08:21:36.002] <TB2> INFO: 1894105 events read in total (84480ms).
[08:22:17.530] <TB2> INFO: 2828910 events read in total (126008ms).
[08:22:49.192] <TB2> INFO: 3536000 events read in total (157670ms).
[08:22:49.237] <TB2> INFO: Test took 158530ms.
[08:23:15.073] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[08:23:15.081] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:23:15.081] <TB2> INFO: run 1 of 1
[08:23:15.358] <TB2> INFO: Expecting 3515200 events.
[08:23:58.406] <TB2> INFO: 952170 events read in total (42492ms).
[08:24:40.778] <TB2> INFO: 1897175 events read in total (84864ms).
[08:25:22.448] <TB2> INFO: 2833605 events read in total (126534ms).
[08:25:52.986] <TB2> INFO: 3515200 events read in total (157072ms).
[08:25:53.026] <TB2> INFO: Test took 157945ms.
[08:26:17.598] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[08:26:17.606] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:26:17.606] <TB2> INFO: run 1 of 1
[08:26:17.874] <TB2> INFO: Expecting 3536000 events.
[08:27:00.629] <TB2> INFO: 949230 events read in total (42199ms).
[08:27:42.256] <TB2> INFO: 1891185 events read in total (83826ms).
[08:28:24.735] <TB2> INFO: 2824495 events read in total (126305ms).
[08:28:57.153] <TB2> INFO: 3536000 events read in total (158723ms).
[08:28:57.193] <TB2> INFO: Test took 159587ms.
[08:29:26.722] <TB2> INFO: PixTestTrim::trimBitTest() done
[08:29:26.723] <TB2> INFO: PixTestTrim::doTest() done, duration: 3005 seconds
[08:29:26.724] <TB2> INFO: Decoding statistics:
[08:29:26.724] <TB2> INFO: General information:
[08:29:26.724] <TB2> INFO: 16bit words read: 0
[08:29:26.724] <TB2> INFO: valid events total: 0
[08:29:26.724] <TB2> INFO: empty events: 0
[08:29:26.724] <TB2> INFO: valid events with pixels: 0
[08:29:26.724] <TB2> INFO: valid pixel hits: 0
[08:29:26.724] <TB2> INFO: Event errors: 0
[08:29:26.724] <TB2> INFO: start marker: 0
[08:29:26.724] <TB2> INFO: stop marker: 0
[08:29:26.724] <TB2> INFO: overflow: 0
[08:29:26.724] <TB2> INFO: invalid 5bit words: 0
[08:29:26.724] <TB2> INFO: invalid XOR eye diagram: 0
[08:29:26.724] <TB2> INFO: frame (failed synchr.): 0
[08:29:26.724] <TB2> INFO: idle data (no TBM trl): 0
[08:29:26.724] <TB2> INFO: no data (only TBM hdr): 0
[08:29:26.724] <TB2> INFO: TBM errors: 0
[08:29:26.724] <TB2> INFO: flawed TBM headers: 0
[08:29:26.724] <TB2> INFO: flawed TBM trailers: 0
[08:29:26.724] <TB2> INFO: event ID mismatches: 0
[08:29:26.724] <TB2> INFO: ROC errors: 0
[08:29:26.724] <TB2> INFO: missing ROC header(s): 0
[08:29:26.724] <TB2> INFO: misplaced readback start: 0
[08:29:26.724] <TB2> INFO: Pixel decoding errors: 0
[08:29:26.724] <TB2> INFO: pixel data incomplete: 0
[08:29:26.724] <TB2> INFO: pixel address: 0
[08:29:26.724] <TB2> INFO: pulse height fill bit: 0
[08:29:26.724] <TB2> INFO: buffer corruption: 0
[08:29:27.568] <TB2> INFO: ######################################################################
[08:29:27.568] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:29:27.568] <TB2> INFO: ######################################################################
[08:29:27.870] <TB2> INFO: Expecting 41600 events.
[08:29:31.617] <TB2> INFO: 41600 events read in total (3191ms).
[08:29:31.618] <TB2> INFO: Test took 4049ms.
[08:29:32.078] <TB2> INFO: Expecting 41600 events.
[08:29:35.938] <TB2> INFO: 41600 events read in total (3304ms).
[08:29:35.939] <TB2> INFO: Test took 4121ms.
[08:29:36.223] <TB2> INFO: Expecting 41600 events.
[08:29:40.064] <TB2> INFO: 41600 events read in total (3285ms).
[08:29:40.065] <TB2> INFO: Test took 4103ms.
[08:29:40.320] <TB2> INFO: Expecting 2560 events.
[08:29:41.179] <TB2> INFO: 2560 events read in total (303ms).
[08:29:41.179] <TB2> INFO: Test took 1101ms.
[08:29:41.485] <TB2> INFO: Expecting 2560 events.
[08:29:42.344] <TB2> INFO: 2560 events read in total (303ms).
[08:29:42.345] <TB2> INFO: Test took 1163ms.
[08:29:42.650] <TB2> INFO: Expecting 2560 events.
[08:29:43.508] <TB2> INFO: 2560 events read in total (302ms).
[08:29:43.508] <TB2> INFO: Test took 1163ms.
[08:29:43.814] <TB2> INFO: Expecting 2560 events.
[08:29:44.667] <TB2> INFO: 2560 events read in total (296ms).
[08:29:44.667] <TB2> INFO: Test took 1158ms.
[08:29:44.973] <TB2> INFO: Expecting 2560 events.
[08:29:45.827] <TB2> INFO: 2560 events read in total (298ms).
[08:29:45.828] <TB2> INFO: Test took 1160ms.
[08:29:46.133] <TB2> INFO: Expecting 2560 events.
[08:29:46.988] <TB2> INFO: 2560 events read in total (299ms).
[08:29:46.988] <TB2> INFO: Test took 1160ms.
[08:29:47.294] <TB2> INFO: Expecting 2560 events.
[08:29:48.148] <TB2> INFO: 2560 events read in total (298ms).
[08:29:48.148] <TB2> INFO: Test took 1159ms.
[08:29:48.454] <TB2> INFO: Expecting 2560 events.
[08:29:49.309] <TB2> INFO: 2560 events read in total (299ms).
[08:29:49.309] <TB2> INFO: Test took 1160ms.
[08:29:49.614] <TB2> INFO: Expecting 2560 events.
[08:29:50.472] <TB2> INFO: 2560 events read in total (301ms).
[08:29:50.472] <TB2> INFO: Test took 1162ms.
[08:29:50.777] <TB2> INFO: Expecting 2560 events.
[08:29:51.630] <TB2> INFO: 2560 events read in total (296ms).
[08:29:51.630] <TB2> INFO: Test took 1157ms.
[08:29:51.936] <TB2> INFO: Expecting 2560 events.
[08:29:52.795] <TB2> INFO: 2560 events read in total (303ms).
[08:29:52.795] <TB2> INFO: Test took 1164ms.
[08:29:53.101] <TB2> INFO: Expecting 2560 events.
[08:29:53.954] <TB2> INFO: 2560 events read in total (297ms).
[08:29:53.954] <TB2> INFO: Test took 1158ms.
[08:29:54.259] <TB2> INFO: Expecting 2560 events.
[08:29:55.113] <TB2> INFO: 2560 events read in total (298ms).
[08:29:55.113] <TB2> INFO: Test took 1158ms.
[08:29:55.417] <TB2> INFO: Expecting 2560 events.
[08:29:56.277] <TB2> INFO: 2560 events read in total (304ms).
[08:29:56.277] <TB2> INFO: Test took 1163ms.
[08:29:56.583] <TB2> INFO: Expecting 2560 events.
[08:29:57.437] <TB2> INFO: 2560 events read in total (298ms).
[08:29:57.437] <TB2> INFO: Test took 1159ms.
[08:29:57.743] <TB2> INFO: Expecting 2560 events.
[08:29:58.598] <TB2> INFO: 2560 events read in total (299ms).
[08:29:58.599] <TB2> INFO: Test took 1162ms.
[08:29:58.603] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:29:58.905] <TB2> INFO: Expecting 655360 events.
[08:30:15.853] <TB2> INFO: 655360 events read in total (16391ms).
[08:30:15.865] <TB2> INFO: Expecting 655360 events.
[08:30:32.711] <TB2> INFO: 655360 events read in total (16444ms).
[08:30:32.724] <TB2> INFO: Expecting 655360 events.
[08:30:49.488] <TB2> INFO: 655360 events read in total (16362ms).
[08:30:49.507] <TB2> INFO: Expecting 655360 events.
[08:31:06.303] <TB2> INFO: 655360 events read in total (16394ms).
[08:31:06.327] <TB2> INFO: Expecting 655360 events.
[08:31:23.219] <TB2> INFO: 655360 events read in total (16491ms).
[08:31:23.248] <TB2> INFO: Expecting 655360 events.
[08:31:40.057] <TB2> INFO: 655360 events read in total (16404ms).
[08:31:40.085] <TB2> INFO: Expecting 655360 events.
[08:31:56.923] <TB2> INFO: 655360 events read in total (16436ms).
[08:31:56.953] <TB2> INFO: Expecting 655360 events.
[08:32:13.757] <TB2> INFO: 655360 events read in total (16402ms).
[08:32:13.788] <TB2> INFO: Expecting 655360 events.
[08:32:30.574] <TB2> INFO: 655360 events read in total (16384ms).
[08:32:30.618] <TB2> INFO: Expecting 655360 events.
[08:32:47.398] <TB2> INFO: 655360 events read in total (16378ms).
[08:32:47.446] <TB2> INFO: Expecting 655360 events.
[08:33:04.351] <TB2> INFO: 655360 events read in total (16503ms).
[08:33:04.410] <TB2> INFO: Expecting 655360 events.
[08:33:21.223] <TB2> INFO: 655360 events read in total (16412ms).
[08:33:21.282] <TB2> INFO: Expecting 655360 events.
[08:33:38.018] <TB2> INFO: 655360 events read in total (16335ms).
[08:33:38.086] <TB2> INFO: Expecting 655360 events.
[08:33:55.096] <TB2> INFO: 655360 events read in total (16608ms).
[08:33:55.158] <TB2> INFO: Expecting 655360 events.
[08:34:11.818] <TB2> INFO: 655360 events read in total (16258ms).
[08:34:11.879] <TB2> INFO: Expecting 655360 events.
[08:34:28.364] <TB2> INFO: 655360 events read in total (16083ms).
[08:34:28.427] <TB2> INFO: Test took 269824ms.
[08:34:28.511] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:34:28.738] <TB2> INFO: Expecting 655360 events.
[08:34:45.475] <TB2> INFO: 655360 events read in total (16180ms).
[08:34:45.485] <TB2> INFO: Expecting 655360 events.
[08:35:02.276] <TB2> INFO: 655360 events read in total (16389ms).
[08:35:02.290] <TB2> INFO: Expecting 655360 events.
[08:35:18.978] <TB2> INFO: 655360 events read in total (16286ms).
[08:35:18.994] <TB2> INFO: Expecting 655360 events.
[08:35:35.959] <TB2> INFO: 655360 events read in total (16563ms).
[08:35:35.981] <TB2> INFO: Expecting 655360 events.
[08:35:52.842] <TB2> INFO: 655360 events read in total (16459ms).
[08:35:52.870] <TB2> INFO: Expecting 655360 events.
[08:36:09.533] <TB2> INFO: 655360 events read in total (16261ms).
[08:36:09.560] <TB2> INFO: Expecting 655360 events.
[08:36:26.506] <TB2> INFO: 655360 events read in total (16544ms).
[08:36:26.537] <TB2> INFO: Expecting 655360 events.
[08:36:43.330] <TB2> INFO: 655360 events read in total (16392ms).
[08:36:43.364] <TB2> INFO: Expecting 655360 events.
[08:37:00.241] <TB2> INFO: 655360 events read in total (16475ms).
[08:37:00.282] <TB2> INFO: Expecting 655360 events.
[08:37:17.267] <TB2> INFO: 655360 events read in total (16583ms).
[08:37:17.309] <TB2> INFO: Expecting 655360 events.
[08:37:33.963] <TB2> INFO: 655360 events read in total (16252ms).
[08:37:34.012] <TB2> INFO: Expecting 655360 events.
[08:37:50.800] <TB2> INFO: 655360 events read in total (16387ms).
[08:37:50.847] <TB2> INFO: Expecting 655360 events.
[08:38:07.715] <TB2> INFO: 655360 events read in total (16466ms).
[08:38:07.798] <TB2> INFO: Expecting 655360 events.
[08:38:24.686] <TB2> INFO: 655360 events read in total (16486ms).
[08:38:24.748] <TB2> INFO: Expecting 655360 events.
[08:38:41.424] <TB2> INFO: 655360 events read in total (16274ms).
[08:38:41.486] <TB2> INFO: Expecting 655360 events.
[08:38:58.208] <TB2> INFO: 655360 events read in total (16320ms).
[08:38:58.270] <TB2> INFO: Test took 269759ms.
[08:38:58.454] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.461] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.468] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.474] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.481] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[08:38:58.488] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[08:38:58.495] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[08:38:58.502] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[08:38:58.509] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.516] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.522] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.529] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.536] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.543] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.550] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.556] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[08:38:58.563] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[08:38:58.570] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[08:38:58.577] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[08:38:58.584] <TB2> INFO: safety margin for low PH: adding 5, margin is now 25
[08:38:58.591] <TB2> INFO: safety margin for low PH: adding 6, margin is now 26
[08:38:58.598] <TB2> INFO: safety margin for low PH: adding 7, margin is now 27
[08:38:58.605] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.613] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.621] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.628] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.636] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:38:58.675] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:38:58.675] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:38:58.675] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:38:58.675] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:38:58.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:38:58.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:38:58.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:38:58.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:38:58.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:38:58.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:38:58.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:38:58.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:38:58.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:38:58.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:38:58.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:38:58.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:38:58.941] <TB2> INFO: Expecting 41600 events.
[08:39:02.411] <TB2> INFO: 41600 events read in total (2914ms).
[08:39:02.412] <TB2> INFO: Test took 3732ms.
[08:39:02.887] <TB2> INFO: Expecting 41600 events.
[08:39:06.415] <TB2> INFO: 41600 events read in total (2972ms).
[08:39:06.415] <TB2> INFO: Test took 3828ms.
[08:39:06.862] <TB2> INFO: Expecting 41600 events.
[08:39:10.399] <TB2> INFO: 41600 events read in total (2981ms).
[08:39:10.399] <TB2> INFO: Test took 3801ms.
[08:39:10.589] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:10.678] <TB2> INFO: Expecting 2560 events.
[08:39:11.537] <TB2> INFO: 2560 events read in total (303ms).
[08:39:11.538] <TB2> INFO: Test took 949ms.
[08:39:11.540] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:11.844] <TB2> INFO: Expecting 2560 events.
[08:39:12.699] <TB2> INFO: 2560 events read in total (299ms).
[08:39:12.699] <TB2> INFO: Test took 1159ms.
[08:39:12.701] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:13.004] <TB2> INFO: Expecting 2560 events.
[08:39:13.861] <TB2> INFO: 2560 events read in total (300ms).
[08:39:13.861] <TB2> INFO: Test took 1160ms.
[08:39:13.863] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:14.167] <TB2> INFO: Expecting 2560 events.
[08:39:15.024] <TB2> INFO: 2560 events read in total (300ms).
[08:39:15.024] <TB2> INFO: Test took 1161ms.
[08:39:15.026] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:15.330] <TB2> INFO: Expecting 2560 events.
[08:39:16.185] <TB2> INFO: 2560 events read in total (299ms).
[08:39:16.185] <TB2> INFO: Test took 1159ms.
[08:39:16.187] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:16.490] <TB2> INFO: Expecting 2560 events.
[08:39:17.347] <TB2> INFO: 2560 events read in total (300ms).
[08:39:17.348] <TB2> INFO: Test took 1161ms.
[08:39:17.350] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:17.654] <TB2> INFO: Expecting 2560 events.
[08:39:18.517] <TB2> INFO: 2560 events read in total (307ms).
[08:39:18.517] <TB2> INFO: Test took 1167ms.
[08:39:18.519] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:18.823] <TB2> INFO: Expecting 2560 events.
[08:39:19.678] <TB2> INFO: 2560 events read in total (299ms).
[08:39:19.678] <TB2> INFO: Test took 1159ms.
[08:39:19.680] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:19.985] <TB2> INFO: Expecting 2560 events.
[08:39:20.847] <TB2> INFO: 2560 events read in total (305ms).
[08:39:20.847] <TB2> INFO: Test took 1167ms.
[08:39:20.849] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:21.154] <TB2> INFO: Expecting 2560 events.
[08:39:22.009] <TB2> INFO: 2560 events read in total (298ms).
[08:39:22.009] <TB2> INFO: Test took 1160ms.
[08:39:22.012] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:22.315] <TB2> INFO: Expecting 2560 events.
[08:39:23.168] <TB2> INFO: 2560 events read in total (297ms).
[08:39:23.168] <TB2> INFO: Test took 1156ms.
[08:39:23.172] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:23.475] <TB2> INFO: Expecting 2560 events.
[08:39:24.331] <TB2> INFO: 2560 events read in total (300ms).
[08:39:24.331] <TB2> INFO: Test took 1159ms.
[08:39:24.335] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:24.637] <TB2> INFO: Expecting 2560 events.
[08:39:25.495] <TB2> INFO: 2560 events read in total (302ms).
[08:39:25.495] <TB2> INFO: Test took 1161ms.
[08:39:25.498] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:25.801] <TB2> INFO: Expecting 2560 events.
[08:39:26.654] <TB2> INFO: 2560 events read in total (297ms).
[08:39:26.654] <TB2> INFO: Test took 1157ms.
[08:39:26.657] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:26.960] <TB2> INFO: Expecting 2560 events.
[08:39:27.813] <TB2> INFO: 2560 events read in total (297ms).
[08:39:27.813] <TB2> INFO: Test took 1156ms.
[08:39:27.816] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:28.119] <TB2> INFO: Expecting 2560 events.
[08:39:28.973] <TB2> INFO: 2560 events read in total (298ms).
[08:39:28.973] <TB2> INFO: Test took 1157ms.
[08:39:28.976] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:29.278] <TB2> INFO: Expecting 2560 events.
[08:39:30.133] <TB2> INFO: 2560 events read in total (298ms).
[08:39:30.134] <TB2> INFO: Test took 1158ms.
[08:39:30.137] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:30.439] <TB2> INFO: Expecting 2560 events.
[08:39:31.291] <TB2> INFO: 2560 events read in total (296ms).
[08:39:31.292] <TB2> INFO: Test took 1155ms.
[08:39:31.294] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:31.597] <TB2> INFO: Expecting 2560 events.
[08:39:32.451] <TB2> INFO: 2560 events read in total (298ms).
[08:39:32.451] <TB2> INFO: Test took 1157ms.
[08:39:32.454] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:32.756] <TB2> INFO: Expecting 2560 events.
[08:39:33.612] <TB2> INFO: 2560 events read in total (300ms).
[08:39:33.613] <TB2> INFO: Test took 1159ms.
[08:39:33.616] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:33.917] <TB2> INFO: Expecting 2560 events.
[08:39:34.773] <TB2> INFO: 2560 events read in total (300ms).
[08:39:34.773] <TB2> INFO: Test took 1157ms.
[08:39:34.776] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:35.079] <TB2> INFO: Expecting 2560 events.
[08:39:35.937] <TB2> INFO: 2560 events read in total (301ms).
[08:39:35.937] <TB2> INFO: Test took 1161ms.
[08:39:35.939] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:36.244] <TB2> INFO: Expecting 2560 events.
[08:39:37.102] <TB2> INFO: 2560 events read in total (302ms).
[08:39:37.102] <TB2> INFO: Test took 1163ms.
[08:39:37.106] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:37.408] <TB2> INFO: Expecting 2560 events.
[08:39:38.266] <TB2> INFO: 2560 events read in total (301ms).
[08:39:38.267] <TB2> INFO: Test took 1161ms.
[08:39:38.269] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:38.572] <TB2> INFO: Expecting 2560 events.
[08:39:39.435] <TB2> INFO: 2560 events read in total (306ms).
[08:39:39.435] <TB2> INFO: Test took 1166ms.
[08:39:39.439] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:39.742] <TB2> INFO: Expecting 2560 events.
[08:39:40.598] <TB2> INFO: 2560 events read in total (299ms).
[08:39:40.598] <TB2> INFO: Test took 1160ms.
[08:39:40.600] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:40.904] <TB2> INFO: Expecting 2560 events.
[08:39:41.758] <TB2> INFO: 2560 events read in total (298ms).
[08:39:41.758] <TB2> INFO: Test took 1158ms.
[08:39:41.761] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:42.064] <TB2> INFO: Expecting 2560 events.
[08:39:42.917] <TB2> INFO: 2560 events read in total (297ms).
[08:39:42.917] <TB2> INFO: Test took 1157ms.
[08:39:42.919] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:43.223] <TB2> INFO: Expecting 2560 events.
[08:39:44.076] <TB2> INFO: 2560 events read in total (297ms).
[08:39:44.076] <TB2> INFO: Test took 1157ms.
[08:39:44.079] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:44.382] <TB2> INFO: Expecting 2560 events.
[08:39:45.234] <TB2> INFO: 2560 events read in total (296ms).
[08:39:45.235] <TB2> INFO: Test took 1156ms.
[08:39:45.239] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:45.540] <TB2> INFO: Expecting 2560 events.
[08:39:46.394] <TB2> INFO: 2560 events read in total (298ms).
[08:39:46.394] <TB2> INFO: Test took 1155ms.
[08:39:46.397] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:46.700] <TB2> INFO: Expecting 2560 events.
[08:39:47.555] <TB2> INFO: 2560 events read in total (299ms).
[08:39:47.555] <TB2> INFO: Test took 1159ms.
[08:39:48.028] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 620 seconds
[08:39:48.028] <TB2> INFO: PH scale (per ROC): 79 76 80 80 84 82 81 71 82 74 90 76 69 84 83 78
[08:39:48.028] <TB2> INFO: PH offset (per ROC): 159 170 165 156 148 151 145 159 147 157 176 174 179 145 157 185
[08:39:48.032] <TB2> INFO: Decoding statistics:
[08:39:48.032] <TB2> INFO: General information:
[08:39:48.032] <TB2> INFO: 16bit words read: 91932
[08:39:48.032] <TB2> INFO: valid events total: 10240
[08:39:48.032] <TB2> INFO: empty events: 7737
[08:39:48.032] <TB2> INFO: valid events with pixels: 2503
[08:39:48.032] <TB2> INFO: valid pixel hits: 2503
[08:39:48.032] <TB2> INFO: Event errors: 0
[08:39:48.032] <TB2> INFO: start marker: 0
[08:39:48.032] <TB2> INFO: stop marker: 0
[08:39:48.032] <TB2> INFO: overflow: 0
[08:39:48.032] <TB2> INFO: invalid 5bit words: 0
[08:39:48.032] <TB2> INFO: invalid XOR eye diagram: 0
[08:39:48.032] <TB2> INFO: frame (failed synchr.): 0
[08:39:48.032] <TB2> INFO: idle data (no TBM trl): 0
[08:39:48.032] <TB2> INFO: no data (only TBM hdr): 0
[08:39:48.032] <TB2> INFO: TBM errors: 0
[08:39:48.032] <TB2> INFO: flawed TBM headers: 0
[08:39:48.032] <TB2> INFO: flawed TBM trailers: 0
[08:39:48.032] <TB2> INFO: event ID mismatches: 0
[08:39:48.032] <TB2> INFO: ROC errors: 0
[08:39:48.032] <TB2> INFO: missing ROC header(s): 0
[08:39:48.032] <TB2> INFO: misplaced readback start: 0
[08:39:48.032] <TB2> INFO: Pixel decoding errors: 0
[08:39:48.032] <TB2> INFO: pixel data incomplete: 0
[08:39:48.032] <TB2> INFO: pixel address: 0
[08:39:48.032] <TB2> INFO: pulse height fill bit: 0
[08:39:48.032] <TB2> INFO: buffer corruption: 0
[08:39:48.198] <TB2> INFO: ######################################################################
[08:39:48.198] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:39:48.198] <TB2> INFO: ######################################################################
[08:39:48.208] <TB2> INFO: scanning low vcal = 10
[08:39:48.465] <TB2> INFO: Expecting 41600 events.
[08:39:51.989] <TB2> INFO: 41600 events read in total (2968ms).
[08:39:51.989] <TB2> INFO: Test took 3781ms.
[08:39:51.992] <TB2> INFO: scanning low vcal = 20
[08:39:52.295] <TB2> INFO: Expecting 41600 events.
[08:39:55.852] <TB2> INFO: 41600 events read in total (3001ms).
[08:39:55.852] <TB2> INFO: Test took 3860ms.
[08:39:55.855] <TB2> INFO: scanning low vcal = 30
[08:39:56.157] <TB2> INFO: Expecting 41600 events.
[08:39:59.720] <TB2> INFO: 41600 events read in total (3007ms).
[08:39:59.721] <TB2> INFO: Test took 3866ms.
[08:39:59.724] <TB2> INFO: scanning low vcal = 40
[08:40:00.020] <TB2> INFO: Expecting 41600 events.
[08:40:04.183] <TB2> INFO: 41600 events read in total (3607ms).
[08:40:04.183] <TB2> INFO: Test took 4459ms.
[08:40:04.186] <TB2> INFO: scanning low vcal = 50
[08:40:04.497] <TB2> INFO: Expecting 41600 events.
[08:40:08.713] <TB2> INFO: 41600 events read in total (3660ms).
[08:40:08.714] <TB2> INFO: Test took 4528ms.
[08:40:08.716] <TB2> INFO: scanning low vcal = 60
[08:40:08.978] <TB2> INFO: Expecting 41600 events.
[08:40:13.178] <TB2> INFO: 41600 events read in total (3644ms).
[08:40:13.178] <TB2> INFO: Test took 4461ms.
[08:40:13.181] <TB2> INFO: scanning low vcal = 70
[08:40:13.443] <TB2> INFO: Expecting 41600 events.
[08:40:17.694] <TB2> INFO: 41600 events read in total (3694ms).
[08:40:17.695] <TB2> INFO: Test took 4513ms.
[08:40:17.698] <TB2> INFO: scanning low vcal = 80
[08:40:17.985] <TB2> INFO: Expecting 41600 events.
[08:40:22.204] <TB2> INFO: 41600 events read in total (3663ms).
[08:40:22.204] <TB2> INFO: Test took 4506ms.
[08:40:22.207] <TB2> INFO: scanning low vcal = 90
[08:40:22.467] <TB2> INFO: Expecting 41600 events.
[08:40:26.687] <TB2> INFO: 41600 events read in total (3664ms).
[08:40:26.688] <TB2> INFO: Test took 4481ms.
[08:40:26.691] <TB2> INFO: scanning low vcal = 100
[08:40:26.992] <TB2> INFO: Expecting 41600 events.
[08:40:31.232] <TB2> INFO: 41600 events read in total (3684ms).
[08:40:31.232] <TB2> INFO: Test took 4541ms.
[08:40:31.236] <TB2> INFO: scanning low vcal = 110
[08:40:31.530] <TB2> INFO: Expecting 41600 events.
[08:40:35.795] <TB2> INFO: 41600 events read in total (3709ms).
[08:40:35.796] <TB2> INFO: Test took 4560ms.
[08:40:35.799] <TB2> INFO: scanning low vcal = 120
[08:40:36.076] <TB2> INFO: Expecting 41600 events.
[08:40:40.253] <TB2> INFO: 41600 events read in total (3621ms).
[08:40:40.253] <TB2> INFO: Test took 4454ms.
[08:40:40.256] <TB2> INFO: scanning low vcal = 130
[08:40:40.530] <TB2> INFO: Expecting 41600 events.
[08:40:44.752] <TB2> INFO: 41600 events read in total (3665ms).
[08:40:44.752] <TB2> INFO: Test took 4496ms.
[08:40:44.762] <TB2> INFO: scanning low vcal = 140
[08:40:45.021] <TB2> INFO: Expecting 41600 events.
[08:40:49.214] <TB2> INFO: 41600 events read in total (3637ms).
[08:40:49.214] <TB2> INFO: Test took 4452ms.
[08:40:49.217] <TB2> INFO: scanning low vcal = 150
[08:40:49.488] <TB2> INFO: Expecting 41600 events.
[08:40:53.662] <TB2> INFO: 41600 events read in total (3618ms).
[08:40:53.662] <TB2> INFO: Test took 4445ms.
[08:40:53.665] <TB2> INFO: scanning low vcal = 160
[08:40:53.924] <TB2> INFO: Expecting 41600 events.
[08:40:58.101] <TB2> INFO: 41600 events read in total (3621ms).
[08:40:58.102] <TB2> INFO: Test took 4437ms.
[08:40:58.105] <TB2> INFO: scanning low vcal = 170
[08:40:58.366] <TB2> INFO: Expecting 41600 events.
[08:41:02.600] <TB2> INFO: 41600 events read in total (3677ms).
[08:41:02.600] <TB2> INFO: Test took 4495ms.
[08:41:02.604] <TB2> INFO: scanning low vcal = 180
[08:41:02.900] <TB2> INFO: Expecting 41600 events.
[08:41:07.130] <TB2> INFO: 41600 events read in total (3673ms).
[08:41:07.131] <TB2> INFO: Test took 4527ms.
[08:41:07.134] <TB2> INFO: scanning low vcal = 190
[08:41:07.416] <TB2> INFO: Expecting 41600 events.
[08:41:11.654] <TB2> INFO: 41600 events read in total (3681ms).
[08:41:11.655] <TB2> INFO: Test took 4521ms.
[08:41:11.658] <TB2> INFO: scanning low vcal = 200
[08:41:11.916] <TB2> INFO: Expecting 41600 events.
[08:41:16.141] <TB2> INFO: 41600 events read in total (3668ms).
[08:41:16.142] <TB2> INFO: Test took 4484ms.
[08:41:16.145] <TB2> INFO: scanning low vcal = 210
[08:41:16.402] <TB2> INFO: Expecting 41600 events.
[08:41:20.652] <TB2> INFO: 41600 events read in total (3693ms).
[08:41:20.652] <TB2> INFO: Test took 4507ms.
[08:41:20.655] <TB2> INFO: scanning low vcal = 220
[08:41:20.944] <TB2> INFO: Expecting 41600 events.
[08:41:25.127] <TB2> INFO: 41600 events read in total (3627ms).
[08:41:25.128] <TB2> INFO: Test took 4473ms.
[08:41:25.131] <TB2> INFO: scanning low vcal = 230
[08:41:25.401] <TB2> INFO: Expecting 41600 events.
[08:41:29.605] <TB2> INFO: 41600 events read in total (3648ms).
[08:41:29.606] <TB2> INFO: Test took 4475ms.
[08:41:29.608] <TB2> INFO: scanning low vcal = 240
[08:41:29.875] <TB2> INFO: Expecting 41600 events.
[08:41:34.061] <TB2> INFO: 41600 events read in total (3630ms).
[08:41:34.061] <TB2> INFO: Test took 4453ms.
[08:41:34.064] <TB2> INFO: scanning low vcal = 250
[08:41:34.328] <TB2> INFO: Expecting 41600 events.
[08:41:38.576] <TB2> INFO: 41600 events read in total (3692ms).
[08:41:38.576] <TB2> INFO: Test took 4512ms.
[08:41:38.580] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[08:41:38.883] <TB2> INFO: Expecting 41600 events.
[08:41:43.073] <TB2> INFO: 41600 events read in total (3634ms).
[08:41:43.073] <TB2> INFO: Test took 4493ms.
[08:41:43.076] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[08:41:43.348] <TB2> INFO: Expecting 41600 events.
[08:41:47.544] <TB2> INFO: 41600 events read in total (3640ms).
[08:41:47.544] <TB2> INFO: Test took 4468ms.
[08:41:47.547] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[08:41:47.826] <TB2> INFO: Expecting 41600 events.
[08:41:52.046] <TB2> INFO: 41600 events read in total (3664ms).
[08:41:52.046] <TB2> INFO: Test took 4499ms.
[08:41:52.049] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[08:41:52.317] <TB2> INFO: Expecting 41600 events.
[08:41:56.563] <TB2> INFO: 41600 events read in total (3689ms).
[08:41:56.564] <TB2> INFO: Test took 4515ms.
[08:41:56.566] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:41:56.833] <TB2> INFO: Expecting 41600 events.
[08:42:00.998] <TB2> INFO: 41600 events read in total (3608ms).
[08:42:00.998] <TB2> INFO: Test took 4432ms.
[08:42:01.438] <TB2> INFO: PixTestGainPedestal::measure() done
[08:42:32.783] <TB2> INFO: PixTestGainPedestal::fit() done
[08:42:32.783] <TB2> INFO: non-linearity mean: 0.958 0.956 0.962 0.957 0.960 0.957 0.958 0.955 0.951 0.960 0.960 0.957 0.951 0.956 0.955 0.958
[08:42:32.783] <TB2> INFO: non-linearity RMS: 0.005 0.006 0.005 0.004 0.005 0.005 0.005 0.006 0.006 0.005 0.004 0.006 0.007 0.006 0.005 0.007
[08:42:32.783] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:42:32.801] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:42:32.819] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:42:32.837] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:42:32.855] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:42:32.873] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:42:32.891] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:42:32.909] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:42:32.927] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:42:32.945] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:42:32.963] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:42:32.981] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:42:32.999] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:42:33.017] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:42:33.035] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:42:33.053] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:42:33.071] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 164 seconds
[08:42:33.071] <TB2> INFO: Decoding statistics:
[08:42:33.071] <TB2> INFO: General information:
[08:42:33.071] <TB2> INFO: 16bit words read: 2662400
[08:42:33.071] <TB2> INFO: valid events total: 166400
[08:42:33.071] <TB2> INFO: empty events: 0
[08:42:33.071] <TB2> INFO: valid events with pixels: 166400
[08:42:33.071] <TB2> INFO: valid pixel hits: 665566
[08:42:33.071] <TB2> INFO: Event errors: 0
[08:42:33.071] <TB2> INFO: start marker: 0
[08:42:33.071] <TB2> INFO: stop marker: 0
[08:42:33.071] <TB2> INFO: overflow: 0
[08:42:33.071] <TB2> INFO: invalid 5bit words: 0
[08:42:33.071] <TB2> INFO: invalid XOR eye diagram: 0
[08:42:33.071] <TB2> INFO: frame (failed synchr.): 0
[08:42:33.071] <TB2> INFO: idle data (no TBM trl): 0
[08:42:33.071] <TB2> INFO: no data (only TBM hdr): 0
[08:42:33.071] <TB2> INFO: TBM errors: 0
[08:42:33.071] <TB2> INFO: flawed TBM headers: 0
[08:42:33.071] <TB2> INFO: flawed TBM trailers: 0
[08:42:33.071] <TB2> INFO: event ID mismatches: 0
[08:42:33.071] <TB2> INFO: ROC errors: 0
[08:42:33.071] <TB2> INFO: missing ROC header(s): 0
[08:42:33.071] <TB2> INFO: misplaced readback start: 0
[08:42:33.071] <TB2> INFO: Pixel decoding errors: 0
[08:42:33.071] <TB2> INFO: pixel data incomplete: 0
[08:42:33.071] <TB2> INFO: pixel address: 0
[08:42:33.071] <TB2> INFO: pulse height fill bit: 0
[08:42:33.071] <TB2> INFO: buffer corruption: 0
[08:42:33.078] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C15.dat
[08:42:33.079] <TB2> INFO: ######################################################################
[08:42:33.079] <TB2> INFO: PixTestReadback::doTest()
[08:42:33.079] <TB2> INFO: ######################################################################
[08:42:33.079] <TB2> INFO: ----------------------------------------------------------------------
[08:42:33.079] <TB2> INFO: PixTestReadback::CalibrateVd()
[08:42:33.079] <TB2> INFO: ----------------------------------------------------------------------
[08:42:42.566] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C0.dat
[08:42:42.566] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C1.dat
[08:42:42.566] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C2.dat
[08:42:42.567] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C3.dat
[08:42:42.567] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C4.dat
[08:42:42.567] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C5.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C6.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C7.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C8.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C9.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C10.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C11.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C12.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C13.dat
[08:42:42.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C14.dat
[08:42:42.569] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C15.dat
[08:42:42.600] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:42:42.601] <TB2> INFO: ----------------------------------------------------------------------
[08:42:42.601] <TB2> INFO: PixTestReadback::CalibrateVa()
[08:42:42.601] <TB2> INFO: ----------------------------------------------------------------------
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C0.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C1.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C2.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C3.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C4.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C5.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C6.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C7.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C8.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C9.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C10.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C11.dat
[08:42:52.088] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C12.dat
[08:42:52.089] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C13.dat
[08:42:52.089] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C14.dat
[08:42:52.089] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C15.dat
[08:42:52.118] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:42:52.118] <TB2> INFO: ----------------------------------------------------------------------
[08:42:52.118] <TB2> INFO: PixTestReadback::readbackVbg()
[08:42:52.118] <TB2> INFO: ----------------------------------------------------------------------
[08:42:59.438] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:42:59.438] <TB2> INFO: ----------------------------------------------------------------------
[08:42:59.438] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[08:42:59.438] <TB2> INFO: ----------------------------------------------------------------------
[08:42:59.438] <TB2> INFO: Vbg will be calibrated using Vd calibration
[08:42:59.438] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 147calibrated Vbg = 1.20645 :::*/*/*/*/
[08:42:59.438] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.7calibrated Vbg = 1.20859 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 156.2calibrated Vbg = 1.21152 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152calibrated Vbg = 1.21647 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152calibrated Vbg = 1.22029 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.8calibrated Vbg = 1.21753 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151.1calibrated Vbg = 1.22021 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.9calibrated Vbg = 1.22037 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.5calibrated Vbg = 1.22931 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153calibrated Vbg = 1.21669 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 150.7calibrated Vbg = 1.21896 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154calibrated Vbg = 1.21746 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.5calibrated Vbg = 1.20788 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151.5calibrated Vbg = 1.21678 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 158.3calibrated Vbg = 1.2208 :::*/*/*/*/
[08:42:59.439] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 159.2calibrated Vbg = 1.21938 :::*/*/*/*/
[08:42:59.441] <TB2> INFO: ----------------------------------------------------------------------
[08:42:59.441] <TB2> INFO: PixTestReadback::CalibrateIa()
[08:42:59.441] <TB2> INFO: ----------------------------------------------------------------------
[08:45:34.729] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C0.dat
[08:45:34.729] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C1.dat
[08:45:34.729] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C2.dat
[08:45:34.729] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C3.dat
[08:45:34.729] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C4.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C5.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C6.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C7.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C8.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C9.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C10.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C11.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C12.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C13.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C14.dat
[08:45:34.730] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2198_FullQualification_2016-04-11_09h06m_1460358374//000_FulltestPxar_m20//readbackCal_C15.dat
[08:45:34.751] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:45:34.751] <TB2> INFO: PixTestReadback::doTest() done
[08:45:34.751] <TB2> INFO: Decoding statistics:
[08:45:34.751] <TB2> INFO: General information:
[08:45:34.751] <TB2> INFO: 16bit words read: 1024
[08:45:34.751] <TB2> INFO: valid events total: 128
[08:45:34.751] <TB2> INFO: empty events: 128
[08:45:34.751] <TB2> INFO: valid events with pixels: 0
[08:45:34.751] <TB2> INFO: valid pixel hits: 0
[08:45:34.751] <TB2> INFO: Event errors: 0
[08:45:34.752] <TB2> INFO: start marker: 0
[08:45:34.752] <TB2> INFO: stop marker: 0
[08:45:34.752] <TB2> INFO: overflow: 0
[08:45:34.752] <TB2> INFO: invalid 5bit words: 0
[08:45:34.752] <TB2> INFO: invalid XOR eye diagram: 0
[08:45:34.752] <TB2> INFO: frame (failed synchr.): 0
[08:45:34.752] <TB2> INFO: idle data (no TBM trl): 0
[08:45:34.752] <TB2> INFO: no data (only TBM hdr): 0
[08:45:34.752] <TB2> INFO: TBM errors: 0
[08:45:34.752] <TB2> INFO: flawed TBM headers: 0
[08:45:34.752] <TB2> INFO: flawed TBM trailers: 0
[08:45:34.752] <TB2> INFO: event ID mismatches: 0
[08:45:34.752] <TB2> INFO: ROC errors: 0
[08:45:34.752] <TB2> INFO: missing ROC header(s): 0
[08:45:34.752] <TB2> INFO: misplaced readback start: 0
[08:45:34.752] <TB2> INFO: Pixel decoding errors: 0
[08:45:34.752] <TB2> INFO: pixel data incomplete: 0
[08:45:34.752] <TB2> INFO: pixel address: 0
[08:45:34.752] <TB2> INFO: pulse height fill bit: 0
[08:45:34.752] <TB2> INFO: buffer corruption: 0
[08:45:34.774] <TB2> INFO: Decoding statistics:
[08:45:34.774] <TB2> INFO: General information:
[08:45:34.774] <TB2> INFO: 16bit words read: 2755356
[08:45:34.774] <TB2> INFO: valid events total: 176768
[08:45:34.774] <TB2> INFO: empty events: 7865
[08:45:34.774] <TB2> INFO: valid events with pixels: 168903
[08:45:34.774] <TB2> INFO: valid pixel hits: 668069
[08:45:34.774] <TB2> INFO: Event errors: 0
[08:45:34.774] <TB2> INFO: start marker: 0
[08:45:34.774] <TB2> INFO: stop marker: 0
[08:45:34.774] <TB2> INFO: overflow: 0
[08:45:34.774] <TB2> INFO: invalid 5bit words: 0
[08:45:34.774] <TB2> INFO: invalid XOR eye diagram: 0
[08:45:34.774] <TB2> INFO: frame (failed synchr.): 0
[08:45:34.774] <TB2> INFO: idle data (no TBM trl): 0
[08:45:34.774] <TB2> INFO: no data (only TBM hdr): 0
[08:45:34.774] <TB2> INFO: TBM errors: 0
[08:45:34.774] <TB2> INFO: flawed TBM headers: 0
[08:45:34.774] <TB2> INFO: flawed TBM trailers: 0
[08:45:34.774] <TB2> INFO: event ID mismatches: 0
[08:45:34.774] <TB2> INFO: ROC errors: 0
[08:45:34.774] <TB2> INFO: missing ROC header(s): 0
[08:45:34.774] <TB2> INFO: misplaced readback start: 0
[08:45:34.774] <TB2> INFO: Pixel decoding errors: 0
[08:45:34.774] <TB2> INFO: pixel data incomplete: 0
[08:45:34.774] <TB2> INFO: pixel address: 0
[08:45:34.774] <TB2> INFO: pulse height fill bit: 0
[08:45:34.774] <TB2> INFO: buffer corruption: 0
[08:45:34.775] <TB2> INFO: enter test to run
[08:45:34.775] <TB2> INFO: test: exit no parameter change
[08:45:34.921] <TB2> QUIET: Connection to board 156 closed.
[08:45:35.001] <TB2> INFO: pXar: this is the end, my friend