[15:49:57.572] <TB3> INFO: *** Welcome to pxar ***
[15:49:57.572] <TB3> INFO: *** Today: 2016/04/10
[15:49:57.675] <TB3> INFO: *** Version: 9751-dirty
[15:49:57.675] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C15.dat
[15:49:57.676] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:49:57.676] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//defaultMaskFile.dat
[15:49:57.676] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters_C15.dat
[15:49:57.766] <TB3> INFO: clk: 4
[15:49:57.766] <TB3> INFO: ctr: 4
[15:49:57.766] <TB3> INFO: sda: 19
[15:49:57.766] <TB3> INFO: tin: 9
[15:49:57.766] <TB3> INFO: level: 15
[15:49:57.766] <TB3> INFO: triggerdelay: 0
[15:49:57.766] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[15:49:57.766] <TB3> INFO: Log level: INFO
[15:49:57.774] <TB3> INFO: Found DTB DTB_WZ4I6J
[15:49:57.783] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[15:49:57.785] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[15:49:57.786] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:49:59.356] <TB3> INFO: DUT info:
[15:49:59.356] <TB3> INFO: The DUT currently contains the following objects:
[15:49:59.356] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:49:59.356] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:49:59.356] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:49:59.356] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:49:59.356] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.356] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.356] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.357] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:59.758] <TB3> INFO: enter 'restricted' command line mode
[15:49:59.758] <TB3> INFO: enter test to run
[15:49:59.758] <TB3> INFO: test: pretest no parameter change
[15:49:59.758] <TB3> INFO: running: pretest
[15:49:59.766] <TB3> INFO: ----------------------------------------------------------------------
[15:49:59.766] <TB3> INFO: PixTestPretest::programROC()
[15:49:59.766] <TB3> INFO: ----------------------------------------------------------------------
[15:50:17.781] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:50:17.781] <TB3> INFO: IA differences per ROC: 17.7 17.7 17.7 16.9 16.1 18.5 17.7 17.7 18.5 18.5 17.7 18.5 17.7 17.7 17.7 17.7
[15:50:17.881] <TB3> INFO: enter test to run
[15:50:17.881] <TB3> INFO: test: pretest no parameter change
[15:50:17.881] <TB3> INFO: running: pretest
[15:50:17.882] <TB3> INFO: ----------------------------------------------------------------------
[15:50:17.882] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:50:17.882] <TB3> INFO: ----------------------------------------------------------------------
[15:50:39.155] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[15:50:39.155] <TB3> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 20.1 20.1 19.3 19.3 18.5 19.3 20.1 19.3 17.7 19.3 19.3
[15:50:39.186] <TB3> INFO: enter test to run
[15:50:39.186] <TB3> INFO: test: pretest no parameter change
[15:50:39.186] <TB3> INFO: running: pretest
[15:50:39.186] <TB3> INFO: ----------------------------------------------------------------------
[15:50:39.186] <TB3> INFO: PixTestPretest::findTiming()
[15:50:39.186] <TB3> INFO: ----------------------------------------------------------------------
[15:50:39.187] <TB3> INFO: PixTestCmd::init()

[15:52:08.937] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[15:52:08.937] <TB3> INFO: (success/tries = 100/100), width = 4
[15:52:08.938] <TB3> INFO: enter test to run
[15:52:08.938] <TB3> INFO: test: pretest no parameter change
[15:52:08.938] <TB3> INFO: running: pretest
[15:52:08.940] <TB3> INFO: ----------------------------------------------------------------------
[15:52:08.940] <TB3> INFO: PixTestPretest::findWorkingPixel()
[15:52:08.940] <TB3> INFO: ----------------------------------------------------------------------
[15:52:09.034] <TB3> INFO: Expecting 231680 events.


[15:52:19.206] <TB3> INFO: 231680 events read in total (9615ms).
[15:52:19.210] <TB3> INFO: Test took 10264ms.
[15:52:19.434] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:52:19.494] <TB3> INFO: enter test to run
[15:52:19.494] <TB3> INFO: test: pretest no parameter change
[15:52:19.494] <TB3> INFO: running: pretest
[15:52:19.496] <TB3> INFO: ----------------------------------------------------------------------
[15:52:19.496] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[15:52:19.496] <TB3> INFO: ----------------------------------------------------------------------
[15:52:19.587] <TB3> INFO: Expecting 231680 events.
[15:52:29.837] <TB3> INFO: 231680 events read in total (9694ms).
[15:52:29.841] <TB3> INFO: Test took 10342ms.
[15:52:30.096] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[15:52:30.096] <TB3> INFO: CalDel: 139 123 126 131 158 140 145 128 131 139 127 116 144 151 127 149
[15:52:30.096] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 53 51 51 51 51 51 51 51 51
[15:52:30.132] <TB3> INFO: enter test to run
[15:52:30.132] <TB3> INFO: test: pretest no parameter change
[15:52:30.132] <TB3> INFO: running: pretest
[15:52:30.132] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C0.dat
[15:52:30.132] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C1.dat
[15:52:30.132] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C2.dat
[15:52:30.132] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C3.dat
[15:52:30.132] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C4.dat
[15:52:30.133] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C5.dat
[15:52:30.133] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C6.dat
[15:52:30.133] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C7.dat
[15:52:30.133] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C8.dat
[15:52:30.133] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C9.dat
[15:52:30.133] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C10.dat
[15:52:30.133] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C11.dat
[15:52:30.134] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C12.dat
[15:52:30.134] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C13.dat
[15:52:30.134] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C14.dat
[15:52:30.134] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C15.dat
[15:52:30.134] <TB3> INFO: enter test to run
[15:52:30.134] <TB3> INFO: test: fulltest no parameter change
[15:52:30.134] <TB3> INFO: running: fulltest
[15:52:30.134] <TB3> INFO: ######################################################################
[15:52:30.134] <TB3> INFO: PixTestFullTest::doTest()
[15:52:30.134] <TB3> INFO: ######################################################################
[15:52:30.136] <TB3> INFO: ######################################################################
[15:52:30.136] <TB3> INFO: PixTestAlive::doTest()
[15:52:30.136] <TB3> INFO: ######################################################################
[15:52:30.137] <TB3> INFO: ----------------------------------------------------------------------
[15:52:30.137] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:52:30.137] <TB3> INFO: ----------------------------------------------------------------------
[15:52:30.398] <TB3> INFO: Expecting 41600 events.
[15:52:34.255] <TB3> INFO: 41600 events read in total (3300ms).
[15:52:34.255] <TB3> INFO: Test took 4117ms.
[15:52:34.483] <TB3> INFO: PixTestAlive::aliveTest() done
[15:52:34.483] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 1 0 0 1 0 0 0
[15:52:34.484] <TB3> INFO: ----------------------------------------------------------------------
[15:52:34.484] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:52:34.484] <TB3> INFO: ----------------------------------------------------------------------
[15:52:34.778] <TB3> INFO: Expecting 41600 events.
[15:52:37.790] <TB3> INFO: 41600 events read in total (2456ms).
[15:52:37.790] <TB3> INFO: Test took 3304ms.
[15:52:37.791] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:52:38.039] <TB3> INFO: PixTestAlive::maskTest() done
[15:52:38.039] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:52:38.041] <TB3> INFO: ----------------------------------------------------------------------
[15:52:38.041] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:52:38.041] <TB3> INFO: ----------------------------------------------------------------------
[15:52:38.307] <TB3> INFO: Expecting 41600 events.
[15:52:42.028] <TB3> INFO: 41600 events read in total (3165ms).
[15:52:42.029] <TB3> INFO: Test took 3987ms.
[15:52:42.259] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[15:52:42.259] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:52:42.259] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:52:42.259] <TB3> INFO: Decoding statistics:
[15:52:42.259] <TB3> INFO: General information:
[15:52:42.259] <TB3> INFO: 16bit words read: 0
[15:52:42.259] <TB3> INFO: valid events total: 0
[15:52:42.259] <TB3> INFO: empty events: 0
[15:52:42.259] <TB3> INFO: valid events with pixels: 0
[15:52:42.259] <TB3> INFO: valid pixel hits: 0
[15:52:42.259] <TB3> INFO: Event errors: 0
[15:52:42.259] <TB3> INFO: start marker: 0
[15:52:42.259] <TB3> INFO: stop marker: 0
[15:52:42.259] <TB3> INFO: overflow: 0
[15:52:42.259] <TB3> INFO: invalid 5bit words: 0
[15:52:42.259] <TB3> INFO: invalid XOR eye diagram: 0
[15:52:42.259] <TB3> INFO: frame (failed synchr.): 0
[15:52:42.259] <TB3> INFO: idle data (no TBM trl): 0
[15:52:42.259] <TB3> INFO: no data (only TBM hdr): 0
[15:52:42.259] <TB3> INFO: TBM errors: 0
[15:52:42.259] <TB3> INFO: flawed TBM headers: 0
[15:52:42.259] <TB3> INFO: flawed TBM trailers: 0
[15:52:42.259] <TB3> INFO: event ID mismatches: 0
[15:52:42.259] <TB3> INFO: ROC errors: 0
[15:52:42.259] <TB3> INFO: missing ROC header(s): 0
[15:52:42.259] <TB3> INFO: misplaced readback start: 0
[15:52:42.259] <TB3> INFO: Pixel decoding errors: 0
[15:52:42.259] <TB3> INFO: pixel data incomplete: 0
[15:52:42.259] <TB3> INFO: pixel address: 0
[15:52:42.259] <TB3> INFO: pulse height fill bit: 0
[15:52:42.259] <TB3> INFO: buffer corruption: 0
[15:52:42.266] <TB3> INFO: ######################################################################
[15:52:42.266] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:52:42.266] <TB3> INFO: ######################################################################
[15:52:42.269] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:52:42.282] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:52:42.282] <TB3> INFO: run 1 of 1
[15:52:42.565] <TB3> INFO: Expecting 3120000 events.
[15:53:24.396] <TB3> INFO: 874220 events read in total (41275ms).
[15:54:05.134] <TB3> INFO: 1736985 events read in total (82013ms).
[15:54:46.130] <TB3> INFO: 2606295 events read in total (123009ms).
[15:55:11.160] <TB3> INFO: 3120000 events read in total (148039ms).
[15:55:11.212] <TB3> INFO: Test took 148930ms.
[15:55:36.044] <TB3> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[15:55:36.044] <TB3> INFO: number of dead bumps (per ROC): 5 1 0 0 1 0 1 0 7 2 2 3 8 10 9 12
[15:55:36.044] <TB3> INFO: separation cut (per ROC): 96 97 106 102 89 91 96 103 92 81 94 104 92 85 100 81
[15:55:36.044] <TB3> INFO: Decoding statistics:
[15:55:36.044] <TB3> INFO: General information:
[15:55:36.044] <TB3> INFO: 16bit words read: 0
[15:55:36.044] <TB3> INFO: valid events total: 0
[15:55:36.044] <TB3> INFO: empty events: 0
[15:55:36.044] <TB3> INFO: valid events with pixels: 0
[15:55:36.044] <TB3> INFO: valid pixel hits: 0
[15:55:36.044] <TB3> INFO: Event errors: 0
[15:55:36.044] <TB3> INFO: start marker: 0
[15:55:36.044] <TB3> INFO: stop marker: 0
[15:55:36.044] <TB3> INFO: overflow: 0
[15:55:36.044] <TB3> INFO: invalid 5bit words: 0
[15:55:36.044] <TB3> INFO: invalid XOR eye diagram: 0
[15:55:36.044] <TB3> INFO: frame (failed synchr.): 0
[15:55:36.044] <TB3> INFO: idle data (no TBM trl): 0
[15:55:36.044] <TB3> INFO: no data (only TBM hdr): 0
[15:55:36.044] <TB3> INFO: TBM errors: 0
[15:55:36.044] <TB3> INFO: flawed TBM headers: 0
[15:55:36.044] <TB3> INFO: flawed TBM trailers: 0
[15:55:36.044] <TB3> INFO: event ID mismatches: 0
[15:55:36.044] <TB3> INFO: ROC errors: 0
[15:55:36.044] <TB3> INFO: missing ROC header(s): 0
[15:55:36.044] <TB3> INFO: misplaced readback start: 0
[15:55:36.044] <TB3> INFO: Pixel decoding errors: 0
[15:55:36.044] <TB3> INFO: pixel data incomplete: 0
[15:55:36.044] <TB3> INFO: pixel address: 0
[15:55:36.044] <TB3> INFO: pulse height fill bit: 0
[15:55:36.044] <TB3> INFO: buffer corruption: 0
[15:55:36.120] <TB3> INFO: ######################################################################
[15:55:36.120] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:55:36.120] <TB3> INFO: ######################################################################
[15:55:36.120] <TB3> INFO: ----------------------------------------------------------------------
[15:55:36.120] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:55:36.120] <TB3> INFO: ----------------------------------------------------------------------
[15:55:36.120] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:55:36.128] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:55:36.128] <TB3> INFO: run 1 of 1
[15:55:36.404] <TB3> INFO: Expecting 26208000 events.
[15:56:10.641] <TB3> INFO: 954750 events read in total (33680ms).
[15:56:44.101] <TB3> INFO: 1890550 events read in total (67140ms).
[15:57:17.537] <TB3> INFO: 2827300 events read in total (100576ms).
[15:57:50.887] <TB3> INFO: 3764200 events read in total (133926ms).
[15:58:24.352] <TB3> INFO: 4695850 events read in total (167391ms).
[15:58:57.988] <TB3> INFO: 5627450 events read in total (201027ms).
[15:59:31.444] <TB3> INFO: 6557700 events read in total (234483ms).
[16:00:04.634] <TB3> INFO: 7482900 events read in total (267673ms).
[16:00:38.020] <TB3> INFO: 8409500 events read in total (301059ms).
[16:01:11.593] <TB3> INFO: 9338350 events read in total (334632ms).
[16:01:45.114] <TB3> INFO: 10264200 events read in total (368153ms).
[16:02:18.037] <TB3> INFO: 11187750 events read in total (401076ms).
[16:02:51.328] <TB3> INFO: 12110050 events read in total (434367ms).
[16:03:24.842] <TB3> INFO: 13027300 events read in total (467881ms).
[16:03:58.170] <TB3> INFO: 13938500 events read in total (501209ms).
[16:04:31.424] <TB3> INFO: 14847000 events read in total (534463ms).
[16:05:04.812] <TB3> INFO: 15754950 events read in total (567851ms).
[16:05:37.711] <TB3> INFO: 16660750 events read in total (600750ms).
[16:06:10.728] <TB3> INFO: 17563450 events read in total (633767ms).
[16:06:43.673] <TB3> INFO: 18469100 events read in total (666712ms).
[16:07:16.816] <TB3> INFO: 19371900 events read in total (699855ms).
[16:07:50.093] <TB3> INFO: 20275050 events read in total (733132ms).
[16:08:23.279] <TB3> INFO: 21176450 events read in total (766318ms).
[16:08:55.833] <TB3> INFO: 22076500 events read in total (798872ms).
[16:09:28.926] <TB3> INFO: 22976800 events read in total (831965ms).
[16:10:01.989] <TB3> INFO: 23879850 events read in total (865028ms).
[16:10:35.375] <TB3> INFO: 24783850 events read in total (898414ms).
[16:11:08.714] <TB3> INFO: 25687150 events read in total (931753ms).
[16:11:28.143] <TB3> INFO: 26208000 events read in total (951182ms).
[16:11:28.167] <TB3> INFO: Test took 952039ms.
[16:11:28.377] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:29.934] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:31.563] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:33.020] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:34.538] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:35.990] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:37.436] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:38.796] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:40.122] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:41.492] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:42.876] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:44.274] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:45.645] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:47.067] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:48.539] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:50.197] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:11:51.810] <TB3> INFO: PixTestScurves::scurves() done
[16:11:51.810] <TB3> INFO: Vcal mean: 100.68 98.94 109.39 108.25 95.01 91.65 107.15 110.96 106.14 86.73 100.43 104.81 90.54 90.19 104.92 92.80
[16:11:51.810] <TB3> INFO: Vcal RMS: 5.75 5.52 5.30 5.33 5.22 5.24 4.88 5.31 5.38 5.12 5.36 4.81 6.82 5.32 5.48 5.45
[16:11:51.811] <TB3> INFO: PixTestScurves::fullTest() done, duration: 975 seconds
[16:11:51.811] <TB3> INFO: Decoding statistics:
[16:11:51.811] <TB3> INFO: General information:
[16:11:51.811] <TB3> INFO: 16bit words read: 0
[16:11:51.811] <TB3> INFO: valid events total: 0
[16:11:51.811] <TB3> INFO: empty events: 0
[16:11:51.811] <TB3> INFO: valid events with pixels: 0
[16:11:51.811] <TB3> INFO: valid pixel hits: 0
[16:11:51.811] <TB3> INFO: Event errors: 0
[16:11:51.811] <TB3> INFO: start marker: 0
[16:11:51.811] <TB3> INFO: stop marker: 0
[16:11:51.811] <TB3> INFO: overflow: 0
[16:11:51.811] <TB3> INFO: invalid 5bit words: 0
[16:11:51.811] <TB3> INFO: invalid XOR eye diagram: 0
[16:11:51.811] <TB3> INFO: frame (failed synchr.): 0
[16:11:51.811] <TB3> INFO: idle data (no TBM trl): 0
[16:11:51.811] <TB3> INFO: no data (only TBM hdr): 0
[16:11:51.811] <TB3> INFO: TBM errors: 0
[16:11:51.811] <TB3> INFO: flawed TBM headers: 0
[16:11:51.811] <TB3> INFO: flawed TBM trailers: 0
[16:11:51.811] <TB3> INFO: event ID mismatches: 0
[16:11:51.811] <TB3> INFO: ROC errors: 0
[16:11:51.811] <TB3> INFO: missing ROC header(s): 0
[16:11:51.811] <TB3> INFO: misplaced readback start: 0
[16:11:51.811] <TB3> INFO: Pixel decoding errors: 0
[16:11:51.811] <TB3> INFO: pixel data incomplete: 0
[16:11:51.811] <TB3> INFO: pixel address: 0
[16:11:51.811] <TB3> INFO: pulse height fill bit: 0
[16:11:51.811] <TB3> INFO: buffer corruption: 0
[16:11:51.886] <TB3> INFO: ######################################################################
[16:11:51.886] <TB3> INFO: PixTestTrim::doTest()
[16:11:51.886] <TB3> INFO: ######################################################################
[16:11:51.887] <TB3> INFO: ----------------------------------------------------------------------
[16:11:51.887] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[16:11:51.887] <TB3> INFO: ----------------------------------------------------------------------
[16:11:51.975] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:11:51.975] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:11:51.983] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:11:51.983] <TB3> INFO: run 1 of 1
[16:11:52.256] <TB3> INFO: Expecting 6281600 events.
[16:12:32.543] <TB3> INFO: 1088840 events read in total (39727ms).
[16:13:12.106] <TB3> INFO: 2172420 events read in total (79290ms).
[16:13:51.675] <TB3> INFO: 3250590 events read in total (118860ms).
[16:14:31.067] <TB3> INFO: 4330850 events read in total (158251ms).
[16:15:10.650] <TB3> INFO: 5413050 events read in total (197834ms).
[16:15:42.911] <TB3> INFO: 6281600 events read in total (230095ms).
[16:15:42.939] <TB3> INFO: Test took 230956ms.
[16:16:03.634] <TB3> INFO: ROC 0 VthrComp = 100
[16:16:03.635] <TB3> INFO: ROC 1 VthrComp = 101
[16:16:03.635] <TB3> INFO: ROC 2 VthrComp = 105
[16:16:03.635] <TB3> INFO: ROC 3 VthrComp = 105
[16:16:03.635] <TB3> INFO: ROC 4 VthrComp = 93
[16:16:03.635] <TB3> INFO: ROC 5 VthrComp = 95
[16:16:03.635] <TB3> INFO: ROC 6 VthrComp = 102
[16:16:03.635] <TB3> INFO: ROC 7 VthrComp = 105
[16:16:03.635] <TB3> INFO: ROC 8 VthrComp = 102
[16:16:03.635] <TB3> INFO: ROC 9 VthrComp = 88
[16:16:03.636] <TB3> INFO: ROC 10 VthrComp = 100
[16:16:03.636] <TB3> INFO: ROC 11 VthrComp = 107
[16:16:03.636] <TB3> INFO: ROC 12 VthrComp = 88
[16:16:03.636] <TB3> INFO: ROC 13 VthrComp = 87
[16:16:03.636] <TB3> INFO: ROC 14 VthrComp = 102
[16:16:03.636] <TB3> INFO: ROC 15 VthrComp = 95
[16:16:03.636] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:16:03.636] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:16:03.644] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:16:03.644] <TB3> INFO: run 1 of 1
[16:16:03.909] <TB3> INFO: Expecting 6281600 events.
[16:16:39.669] <TB3> INFO: 759990 events read in total (35201ms).
[16:17:14.613] <TB3> INFO: 1517920 events read in total (70145ms).
[16:17:50.019] <TB3> INFO: 2275570 events read in total (105551ms).
[16:18:24.892] <TB3> INFO: 3033860 events read in total (140424ms).
[16:19:00.304] <TB3> INFO: 3785850 events read in total (175836ms).
[16:19:35.445] <TB3> INFO: 4533890 events read in total (210977ms).
[16:20:09.983] <TB3> INFO: 5279740 events read in total (245515ms).
[16:20:46.206] <TB3> INFO: 6025770 events read in total (281738ms).
[16:20:58.874] <TB3> INFO: 6281600 events read in total (294406ms).
[16:20:58.927] <TB3> INFO: Test took 295284ms.
[16:21:23.972] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 59.1965 for pixel 6/74 mean/min/max = 45.5498/31.7938/59.3059
[16:21:23.972] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 59.168 for pixel 16/10 mean/min/max = 45.4527/31.6703/59.2352
[16:21:23.972] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 61.8503 for pixel 0/7 mean/min/max = 47.9428/34.027/61.8586
[16:21:23.973] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 61.8862 for pixel 1/17 mean/min/max = 47.8297/33.4161/62.2433
[16:21:23.973] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.4886 for pixel 13/55 mean/min/max = 46.5256/33.4984/59.5528
[16:21:23.973] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 55.9376 for pixel 29/79 mean/min/max = 44.9549/33.0807/56.8292
[16:21:23.973] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.8698 for pixel 0/12 mean/min/max = 45.1875/32.3344/58.0407
[16:21:23.974] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 63.6581 for pixel 25/2 mean/min/max = 49.0331/34.2817/63.7844
[16:21:23.974] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 61.6099 for pixel 24/78 mean/min/max = 46.925/32.1737/61.6764
[16:21:23.974] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 58.6426 for pixel 22/5 mean/min/max = 46.2112/33.6318/58.7907
[16:21:23.974] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 57.8967 for pixel 0/74 mean/min/max = 44.9447/31.9829/57.9065
[16:21:23.975] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.9886 for pixel 17/2 mean/min/max = 46.3494/34.6598/58.039
[16:21:23.975] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 65.7698 for pixel 8/2 mean/min/max = 48.986/32.1527/65.8193
[16:21:23.975] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.4517 for pixel 27/79 mean/min/max = 45.6254/32.7867/58.4641
[16:21:23.975] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 61.8534 for pixel 0/55 mean/min/max = 47.3913/32.92/61.8626
[16:21:23.976] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.1324 for pixel 5/20 mean/min/max = 45.22/32.2659/58.1741
[16:21:23.976] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:21:24.064] <TB3> INFO: Expecting 514560 events.
[16:21:37.094] <TB3> INFO: 514560 events read in total (12474ms).
[16:21:37.100] <TB3> INFO: Expecting 514560 events.
[16:21:50.059] <TB3> INFO: 514560 events read in total (12544ms).
[16:21:50.067] <TB3> INFO: Expecting 514560 events.
[16:22:02.967] <TB3> INFO: 514560 events read in total (12488ms).
[16:22:02.977] <TB3> INFO: Expecting 514560 events.
[16:22:15.787] <TB3> INFO: 514560 events read in total (12400ms).
[16:22:15.798] <TB3> INFO: Expecting 514560 events.
[16:22:28.690] <TB3> INFO: 514560 events read in total (12478ms).
[16:22:28.703] <TB3> INFO: Expecting 514560 events.
[16:22:41.549] <TB3> INFO: 514560 events read in total (12441ms).
[16:22:41.565] <TB3> INFO: Expecting 514560 events.
[16:22:54.419] <TB3> INFO: 514560 events read in total (12452ms).
[16:22:54.438] <TB3> INFO: Expecting 514560 events.
[16:23:07.237] <TB3> INFO: 514560 events read in total (12390ms).
[16:23:07.258] <TB3> INFO: Expecting 514560 events.
[16:23:20.076] <TB3> INFO: 514560 events read in total (12414ms).
[16:23:20.097] <TB3> INFO: Expecting 514560 events.
[16:23:32.920] <TB3> INFO: 514560 events read in total (12421ms).
[16:23:32.942] <TB3> INFO: Expecting 514560 events.
[16:23:45.618] <TB3> INFO: 514560 events read in total (12272ms).
[16:23:45.641] <TB3> INFO: Expecting 514560 events.
[16:23:58.581] <TB3> INFO: 514560 events read in total (12533ms).
[16:23:58.607] <TB3> INFO: Expecting 514560 events.
[16:24:11.675] <TB3> INFO: 514560 events read in total (12666ms).
[16:24:11.706] <TB3> INFO: Expecting 514560 events.
[16:24:24.395] <TB3> INFO: 514560 events read in total (12287ms).
[16:24:24.425] <TB3> INFO: Expecting 514560 events.
[16:24:37.160] <TB3> INFO: 514560 events read in total (12333ms).
[16:24:37.190] <TB3> INFO: Expecting 514560 events.
[16:24:49.852] <TB3> INFO: 514560 events read in total (12260ms).
[16:24:49.887] <TB3> INFO: Test took 205911ms.
[16:24:50.840] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:24:50.849] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:24:50.849] <TB3> INFO: run 1 of 1
[16:24:51.125] <TB3> INFO: Expecting 6281600 events.
[16:25:26.517] <TB3> INFO: 745080 events read in total (34836ms).
[16:26:01.425] <TB3> INFO: 1488560 events read in total (69744ms).
[16:26:36.464] <TB3> INFO: 2231660 events read in total (104783ms).
[16:27:11.086] <TB3> INFO: 2974970 events read in total (139405ms).
[16:27:45.634] <TB3> INFO: 3712180 events read in total (173953ms).
[16:28:20.391] <TB3> INFO: 4444750 events read in total (208710ms).
[16:28:54.923] <TB3> INFO: 5175110 events read in total (243242ms).
[16:29:29.717] <TB3> INFO: 5904630 events read in total (278036ms).
[16:29:47.471] <TB3> INFO: 6281600 events read in total (295790ms).
[16:29:47.518] <TB3> INFO: Test took 296669ms.
[16:30:11.234] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.110685 .. 255.000000
[16:30:11.309] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[16:30:11.317] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:30:11.317] <TB3> INFO: run 1 of 1
[16:30:11.609] <TB3> INFO: Expecting 10649600 events.
[16:30:46.414] <TB3> INFO: 712450 events read in total (34245ms).
[16:31:20.850] <TB3> INFO: 1424930 events read in total (68681ms).
[16:31:55.395] <TB3> INFO: 2137500 events read in total (103226ms).
[16:32:29.672] <TB3> INFO: 2850130 events read in total (137503ms).
[16:33:04.242] <TB3> INFO: 3562700 events read in total (172073ms).
[16:33:38.528] <TB3> INFO: 4275710 events read in total (206359ms).
[16:34:13.294] <TB3> INFO: 4988530 events read in total (241125ms).
[16:34:47.323] <TB3> INFO: 5701500 events read in total (275154ms).
[16:35:21.727] <TB3> INFO: 6414210 events read in total (309558ms).
[16:35:56.127] <TB3> INFO: 7126160 events read in total (343958ms).
[16:36:30.425] <TB3> INFO: 7838080 events read in total (378256ms).
[16:37:04.723] <TB3> INFO: 8549270 events read in total (412554ms).
[16:37:39.001] <TB3> INFO: 9260610 events read in total (446832ms).
[16:38:13.581] <TB3> INFO: 9971760 events read in total (481412ms).
[16:38:47.535] <TB3> INFO: 10649600 events read in total (515366ms).
[16:38:47.643] <TB3> INFO: Test took 516326ms.
[16:39:16.806] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 16.697822 .. 69.050995
[16:39:16.881] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 79 (-1/-1) hits flags = 528 (plus default)
[16:39:16.889] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:39:16.889] <TB3> INFO: run 1 of 1
[16:39:17.175] <TB3> INFO: Expecting 3078400 events.
[16:39:54.148] <TB3> INFO: 824260 events read in total (36417ms).
[16:40:30.784] <TB3> INFO: 1648570 events read in total (73053ms).
[16:41:06.899] <TB3> INFO: 2471890 events read in total (109168ms).
[16:41:33.965] <TB3> INFO: 3078400 events read in total (136234ms).
[16:41:33.991] <TB3> INFO: Test took 137103ms.
[16:41:52.661] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 1.028067 .. 44.723423
[16:41:52.736] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 54 (-1/-1) hits flags = 528 (plus default)
[16:41:52.744] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:41:52.744] <TB3> INFO: run 1 of 1
[16:41:53.004] <TB3> INFO: Expecting 2246400 events.
[16:42:31.483] <TB3> INFO: 968720 events read in total (37923ms).
[16:43:09.847] <TB3> INFO: 1936450 events read in total (76287ms).
[16:43:22.573] <TB3> INFO: 2246400 events read in total (89013ms).
[16:43:22.584] <TB3> INFO: Test took 89840ms.
[16:43:37.385] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 0.191088 .. 43.182412
[16:43:37.471] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 0 .. 53 (-1/-1) hits flags = 528 (plus default)
[16:43:37.480] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:43:37.480] <TB3> INFO: run 1 of 1
[16:43:37.758] <TB3> INFO: Expecting 2246400 events.
[16:44:17.102] <TB3> INFO: 982090 events read in total (38788ms).
[16:44:55.502] <TB3> INFO: 1963860 events read in total (77189ms).
[16:45:06.869] <TB3> INFO: 2246400 events read in total (88556ms).
[16:45:06.886] <TB3> INFO: Test took 89407ms.
[16:45:21.260] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:45:21.260] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:45:21.268] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:45:21.268] <TB3> INFO: run 1 of 1
[16:45:21.535] <TB3> INFO: Expecting 1705600 events.
[16:45:59.475] <TB3> INFO: 878290 events read in total (37384ms).
[16:46:34.713] <TB3> INFO: 1705600 events read in total (72622ms).
[16:46:34.725] <TB3> INFO: Test took 73456ms.
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:46:49.087] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:46:49.088] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:46:49.088] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:46:49.088] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:46:49.088] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:46:49.088] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:46:49.088] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:46:49.088] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:46:49.088] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:46:49.095] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:46:49.104] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:46:49.114] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:46:49.124] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:46:49.133] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:46:49.139] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:46:49.146] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:46:49.153] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:46:49.161] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:46:49.167] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:46:49.176] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:46:49.186] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:46:49.195] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:46:49.204] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:46:49.212] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:46:49.218] <TB3> INFO: PixTestTrim::trimTest() done
[16:46:49.219] <TB3> INFO: vtrim: 107 108 112 110 93 94 92 104 110 97 89 107 135 79 99 98
[16:46:49.219] <TB3> INFO: vthrcomp: 100 101 105 105 93 95 102 105 102 88 100 107 88 87 102 95
[16:46:49.219] <TB3> INFO: vcal mean: 34.95 34.99 34.98 35.01 34.98 34.95 34.93 34.99 35.01 34.75 34.98 35.01 34.98 34.93 35.00 34.99
[16:46:49.219] <TB3> INFO: vcal RMS: 0.91 0.87 0.84 0.96 0.87 0.84 0.89 0.94 0.94 1.01 0.84 0.79 1.26 0.87 0.88 0.85
[16:46:49.219] <TB3> INFO: bits mean: 9.52 9.55 8.47 8.86 8.85 9.29 9.52 8.54 9.17 9.23 9.39 8.76 9.51 9.17 8.45 9.67
[16:46:49.219] <TB3> INFO: bits RMS: 2.71 2.73 2.68 2.52 2.74 2.76 2.71 2.54 2.73 2.54 2.80 2.56 2.43 2.74 2.86 2.65
[16:46:49.225] <TB3> INFO: ----------------------------------------------------------------------
[16:46:49.225] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:46:49.225] <TB3> INFO: ----------------------------------------------------------------------
[16:46:49.228] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:46:49.236] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:46:49.236] <TB3> INFO: run 1 of 1
[16:46:49.497] <TB3> INFO: Expecting 4160000 events.
[16:47:31.949] <TB3> INFO: 973145 events read in total (41896ms).
[16:48:14.238] <TB3> INFO: 1935485 events read in total (84185ms).
[16:48:57.143] <TB3> INFO: 2884915 events read in total (127090ms).
[16:49:38.928] <TB3> INFO: 3830685 events read in total (168875ms).
[16:49:54.043] <TB3> INFO: 4160000 events read in total (183990ms).
[16:49:54.080] <TB3> INFO: Test took 184844ms.
[16:50:23.510] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 192 (-1/-1) hits flags = 528 (plus default)
[16:50:23.518] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:50:23.522] <TB3> INFO: run 1 of 1
[16:50:23.809] <TB3> INFO: Expecting 4014400 events.
[16:51:06.679] <TB3> INFO: 950410 events read in total (42314ms).
[16:51:48.498] <TB3> INFO: 1891240 events read in total (84133ms).
[16:52:31.297] <TB3> INFO: 2820450 events read in total (126932ms).
[16:53:12.667] <TB3> INFO: 3746205 events read in total (168302ms).
[16:53:25.173] <TB3> INFO: 4014400 events read in total (180808ms).
[16:53:25.206] <TB3> INFO: Test took 181684ms.
[16:53:53.235] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[16:53:53.243] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:53:53.243] <TB3> INFO: run 1 of 1
[16:53:53.506] <TB3> INFO: Expecting 3723200 events.
[16:54:37.667] <TB3> INFO: 982595 events read in total (43604ms).
[16:55:20.169] <TB3> INFO: 1952770 events read in total (86106ms).
[16:56:02.156] <TB3> INFO: 2909775 events read in total (128093ms).
[16:56:37.675] <TB3> INFO: 3723200 events read in total (163612ms).
[16:56:37.708] <TB3> INFO: Test took 164465ms.
[16:57:07.135] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 180 (-1/-1) hits flags = 528 (plus default)
[16:57:07.143] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:57:07.143] <TB3> INFO: run 1 of 1
[16:57:07.402] <TB3> INFO: Expecting 3764800 events.
[16:57:50.120] <TB3> INFO: 977380 events read in total (42162ms).
[16:58:31.993] <TB3> INFO: 1942600 events read in total (84035ms).
[16:59:13.840] <TB3> INFO: 2894745 events read in total (125882ms).
[16:59:52.475] <TB3> INFO: 3764800 events read in total (164517ms).
[16:59:52.516] <TB3> INFO: Test took 165373ms.
[17:00:19.049] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 177 (-1/-1) hits flags = 528 (plus default)
[17:00:19.058] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:00:19.058] <TB3> INFO: run 1 of 1
[17:00:19.319] <TB3> INFO: Expecting 3702400 events.
[17:01:02.153] <TB3> INFO: 984495 events read in total (42278ms).
[17:01:44.216] <TB3> INFO: 1956035 events read in total (84341ms).
[17:02:25.823] <TB3> INFO: 2914515 events read in total (125948ms).
[17:02:59.977] <TB3> INFO: 3702400 events read in total (160102ms).
[17:03:00.012] <TB3> INFO: Test took 160954ms.
[17:03:26.006] <TB3> INFO: PixTestTrim::trimBitTest() done
[17:03:26.007] <TB3> INFO: PixTestTrim::doTest() done, duration: 3094 seconds
[17:03:26.007] <TB3> INFO: Decoding statistics:
[17:03:26.007] <TB3> INFO: General information:
[17:03:26.007] <TB3> INFO: 16bit words read: 0
[17:03:26.007] <TB3> INFO: valid events total: 0
[17:03:26.007] <TB3> INFO: empty events: 0
[17:03:26.007] <TB3> INFO: valid events with pixels: 0
[17:03:26.007] <TB3> INFO: valid pixel hits: 0
[17:03:26.007] <TB3> INFO: Event errors: 0
[17:03:26.007] <TB3> INFO: start marker: 0
[17:03:26.007] <TB3> INFO: stop marker: 0
[17:03:26.007] <TB3> INFO: overflow: 0
[17:03:26.007] <TB3> INFO: invalid 5bit words: 0
[17:03:26.007] <TB3> INFO: invalid XOR eye diagram: 0
[17:03:26.007] <TB3> INFO: frame (failed synchr.): 0
[17:03:26.007] <TB3> INFO: idle data (no TBM trl): 0
[17:03:26.007] <TB3> INFO: no data (only TBM hdr): 0
[17:03:26.007] <TB3> INFO: TBM errors: 0
[17:03:26.007] <TB3> INFO: flawed TBM headers: 0
[17:03:26.007] <TB3> INFO: flawed TBM trailers: 0
[17:03:26.007] <TB3> INFO: event ID mismatches: 0
[17:03:26.007] <TB3> INFO: ROC errors: 0
[17:03:26.007] <TB3> INFO: missing ROC header(s): 0
[17:03:26.007] <TB3> INFO: misplaced readback start: 0
[17:03:26.007] <TB3> INFO: Pixel decoding errors: 0
[17:03:26.007] <TB3> INFO: pixel data incomplete: 0
[17:03:26.007] <TB3> INFO: pixel address: 0
[17:03:26.007] <TB3> INFO: pulse height fill bit: 0
[17:03:26.007] <TB3> INFO: buffer corruption: 0
[17:03:26.685] <TB3> INFO: ######################################################################
[17:03:26.685] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:03:26.685] <TB3> INFO: ######################################################################
[17:03:26.946] <TB3> INFO: Expecting 41600 events.
[17:03:30.691] <TB3> INFO: 41600 events read in total (3189ms).
[17:03:30.692] <TB3> INFO: Test took 4006ms.
[17:03:31.169] <TB3> INFO: Expecting 41600 events.
[17:03:35.015] <TB3> INFO: 41600 events read in total (3289ms).
[17:03:35.016] <TB3> INFO: Test took 4123ms.
[17:03:35.325] <TB3> INFO: Expecting 41600 events.
[17:03:39.134] <TB3> INFO: 41600 events read in total (3253ms).
[17:03:39.134] <TB3> INFO: Test took 4095ms.
[17:03:39.390] <TB3> INFO: Expecting 2560 events.
[17:03:40.248] <TB3> INFO: 2560 events read in total (302ms).
[17:03:40.248] <TB3> INFO: Test took 1101ms.
[17:03:40.554] <TB3> INFO: Expecting 2560 events.
[17:03:41.407] <TB3> INFO: 2560 events read in total (297ms).
[17:03:41.407] <TB3> INFO: Test took 1158ms.
[17:03:41.713] <TB3> INFO: Expecting 2560 events.
[17:03:42.573] <TB3> INFO: 2560 events read in total (303ms).
[17:03:42.573] <TB3> INFO: Test took 1165ms.
[17:03:42.878] <TB3> INFO: Expecting 2560 events.
[17:03:43.732] <TB3> INFO: 2560 events read in total (297ms).
[17:03:43.732] <TB3> INFO: Test took 1158ms.
[17:03:44.038] <TB3> INFO: Expecting 2560 events.
[17:03:44.891] <TB3> INFO: 2560 events read in total (297ms).
[17:03:44.891] <TB3> INFO: Test took 1159ms.
[17:03:45.197] <TB3> INFO: Expecting 2560 events.
[17:03:46.051] <TB3> INFO: 2560 events read in total (297ms).
[17:03:46.051] <TB3> INFO: Test took 1159ms.
[17:03:46.357] <TB3> INFO: Expecting 2560 events.
[17:03:47.209] <TB3> INFO: 2560 events read in total (296ms).
[17:03:47.210] <TB3> INFO: Test took 1158ms.
[17:03:47.515] <TB3> INFO: Expecting 2560 events.
[17:03:48.374] <TB3> INFO: 2560 events read in total (303ms).
[17:03:48.374] <TB3> INFO: Test took 1164ms.
[17:03:48.679] <TB3> INFO: Expecting 2560 events.
[17:03:49.532] <TB3> INFO: 2560 events read in total (297ms).
[17:03:49.532] <TB3> INFO: Test took 1158ms.
[17:03:49.838] <TB3> INFO: Expecting 2560 events.
[17:03:50.695] <TB3> INFO: 2560 events read in total (301ms).
[17:03:50.695] <TB3> INFO: Test took 1162ms.
[17:03:51.002] <TB3> INFO: Expecting 2560 events.
[17:03:51.858] <TB3> INFO: 2560 events read in total (300ms).
[17:03:51.859] <TB3> INFO: Test took 1163ms.
[17:03:52.163] <TB3> INFO: Expecting 2560 events.
[17:03:53.020] <TB3> INFO: 2560 events read in total (301ms).
[17:03:53.020] <TB3> INFO: Test took 1161ms.
[17:03:53.330] <TB3> INFO: Expecting 2560 events.
[17:03:54.185] <TB3> INFO: 2560 events read in total (299ms).
[17:03:54.185] <TB3> INFO: Test took 1164ms.
[17:03:54.491] <TB3> INFO: Expecting 2560 events.
[17:03:55.347] <TB3> INFO: 2560 events read in total (300ms).
[17:03:55.348] <TB3> INFO: Test took 1162ms.
[17:03:55.653] <TB3> INFO: Expecting 2560 events.
[17:03:56.512] <TB3> INFO: 2560 events read in total (303ms).
[17:03:56.512] <TB3> INFO: Test took 1164ms.
[17:03:56.817] <TB3> INFO: Expecting 2560 events.
[17:03:57.672] <TB3> INFO: 2560 events read in total (298ms).
[17:03:57.672] <TB3> INFO: Test took 1159ms.
[17:03:57.675] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:03:57.978] <TB3> INFO: Expecting 655360 events.
[17:04:14.912] <TB3> INFO: 655360 events read in total (16378ms).
[17:04:14.923] <TB3> INFO: Expecting 655360 events.
[17:04:31.729] <TB3> INFO: 655360 events read in total (16405ms).
[17:04:31.743] <TB3> INFO: Expecting 655360 events.
[17:04:48.621] <TB3> INFO: 655360 events read in total (16476ms).
[17:04:48.637] <TB3> INFO: Expecting 655360 events.
[17:05:05.326] <TB3> INFO: 655360 events read in total (16287ms).
[17:05:05.348] <TB3> INFO: Expecting 655360 events.
[17:05:22.181] <TB3> INFO: 655360 events read in total (16431ms).
[17:05:22.204] <TB3> INFO: Expecting 655360 events.
[17:05:39.046] <TB3> INFO: 655360 events read in total (16440ms).
[17:05:39.072] <TB3> INFO: Expecting 655360 events.
[17:05:55.890] <TB3> INFO: 655360 events read in total (16416ms).
[17:05:55.920] <TB3> INFO: Expecting 655360 events.
[17:06:12.671] <TB3> INFO: 655360 events read in total (16349ms).
[17:06:12.704] <TB3> INFO: Expecting 655360 events.
[17:06:29.568] <TB3> INFO: 655360 events read in total (16462ms).
[17:06:29.605] <TB3> INFO: Expecting 655360 events.
[17:06:46.377] <TB3> INFO: 655360 events read in total (16370ms).
[17:06:46.417] <TB3> INFO: Expecting 655360 events.
[17:07:03.249] <TB3> INFO: 655360 events read in total (16430ms).
[17:07:03.293] <TB3> INFO: Expecting 655360 events.
[17:07:19.991] <TB3> INFO: 655360 events read in total (16296ms).
[17:07:20.046] <TB3> INFO: Expecting 655360 events.
[17:07:36.904] <TB3> INFO: 655360 events read in total (16456ms).
[17:07:36.962] <TB3> INFO: Expecting 655360 events.
[17:07:53.629] <TB3> INFO: 655360 events read in total (16265ms).
[17:07:53.687] <TB3> INFO: Expecting 655360 events.
[17:08:10.392] <TB3> INFO: 655360 events read in total (16304ms).
[17:08:10.457] <TB3> INFO: Expecting 655360 events.
[17:08:27.228] <TB3> INFO: 655360 events read in total (16369ms).
[17:08:27.288] <TB3> INFO: Test took 269613ms.
[17:08:27.369] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:08:27.600] <TB3> INFO: Expecting 655360 events.
[17:08:44.441] <TB3> INFO: 655360 events read in total (16285ms).
[17:08:44.451] <TB3> INFO: Expecting 655360 events.
[17:09:01.251] <TB3> INFO: 655360 events read in total (16398ms).
[17:09:01.264] <TB3> INFO: Expecting 655360 events.
[17:09:17.987] <TB3> INFO: 655360 events read in total (16321ms).
[17:09:18.003] <TB3> INFO: Expecting 655360 events.
[17:09:34.733] <TB3> INFO: 655360 events read in total (16328ms).
[17:09:34.752] <TB3> INFO: Expecting 655360 events.
[17:09:51.461] <TB3> INFO: 655360 events read in total (16307ms).
[17:09:51.488] <TB3> INFO: Expecting 655360 events.
[17:10:08.175] <TB3> INFO: 655360 events read in total (16286ms).
[17:10:08.201] <TB3> INFO: Expecting 655360 events.
[17:10:25.081] <TB3> INFO: 655360 events read in total (16479ms).
[17:10:25.113] <TB3> INFO: Expecting 655360 events.
[17:10:41.696] <TB3> INFO: 655360 events read in total (16182ms).
[17:10:41.728] <TB3> INFO: Expecting 655360 events.
[17:10:58.640] <TB3> INFO: 655360 events read in total (16510ms).
[17:10:58.682] <TB3> INFO: Expecting 655360 events.
[17:11:15.311] <TB3> INFO: 655360 events read in total (16227ms).
[17:11:15.350] <TB3> INFO: Expecting 655360 events.
[17:11:31.995] <TB3> INFO: 655360 events read in total (16243ms).
[17:11:32.041] <TB3> INFO: Expecting 655360 events.
[17:11:48.768] <TB3> INFO: 655360 events read in total (16325ms).
[17:11:48.822] <TB3> INFO: Expecting 655360 events.
[17:12:05.692] <TB3> INFO: 655360 events read in total (16468ms).
[17:12:05.744] <TB3> INFO: Expecting 655360 events.
[17:12:22.556] <TB3> INFO: 655360 events read in total (16410ms).
[17:12:22.609] <TB3> INFO: Expecting 655360 events.
[17:12:39.539] <TB3> INFO: 655360 events read in total (16528ms).
[17:12:39.604] <TB3> INFO: Expecting 655360 events.
[17:12:56.490] <TB3> INFO: 655360 events read in total (16484ms).
[17:12:56.550] <TB3> INFO: Test took 269181ms.
[17:12:56.743] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.750] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.756] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.763] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.770] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.777] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.783] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.790] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.797] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.804] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.811] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.818] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:12:56.825] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:12:56.832] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:12:56.838] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:12:56.845] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:12:56.852] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.859] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.866] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.873] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.880] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:56.915] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C0.dat
[17:12:56.915] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C1.dat
[17:12:56.915] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C2.dat
[17:12:56.915] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C3.dat
[17:12:56.915] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C4.dat
[17:12:56.915] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C5.dat
[17:12:56.915] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C6.dat
[17:12:56.915] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C7.dat
[17:12:56.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C8.dat
[17:12:56.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C9.dat
[17:12:56.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C10.dat
[17:12:56.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C11.dat
[17:12:56.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C12.dat
[17:12:56.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C13.dat
[17:12:56.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C14.dat
[17:12:56.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C15.dat
[17:12:57.189] <TB3> INFO: Expecting 41600 events.
[17:13:00.681] <TB3> INFO: 41600 events read in total (2936ms).
[17:13:00.682] <TB3> INFO: Test took 3763ms.
[17:13:01.147] <TB3> INFO: Expecting 41600 events.
[17:13:04.668] <TB3> INFO: 41600 events read in total (2965ms).
[17:13:04.669] <TB3> INFO: Test took 3804ms.
[17:13:05.120] <TB3> INFO: Expecting 41600 events.
[17:13:08.614] <TB3> INFO: 41600 events read in total (2937ms).
[17:13:08.615] <TB3> INFO: Test took 3771ms.
[17:13:08.798] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:08.888] <TB3> INFO: Expecting 2560 events.
[17:13:09.743] <TB3> INFO: 2560 events read in total (299ms).
[17:13:09.743] <TB3> INFO: Test took 945ms.
[17:13:09.745] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:10.048] <TB3> INFO: Expecting 2560 events.
[17:13:10.902] <TB3> INFO: 2560 events read in total (297ms).
[17:13:10.902] <TB3> INFO: Test took 1157ms.
[17:13:10.905] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:11.208] <TB3> INFO: Expecting 2560 events.
[17:13:12.062] <TB3> INFO: 2560 events read in total (298ms).
[17:13:12.062] <TB3> INFO: Test took 1157ms.
[17:13:12.065] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:12.368] <TB3> INFO: Expecting 2560 events.
[17:13:13.222] <TB3> INFO: 2560 events read in total (298ms).
[17:13:13.223] <TB3> INFO: Test took 1158ms.
[17:13:13.225] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:13.528] <TB3> INFO: Expecting 2560 events.
[17:13:14.381] <TB3> INFO: 2560 events read in total (297ms).
[17:13:14.381] <TB3> INFO: Test took 1156ms.
[17:13:14.383] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:14.688] <TB3> INFO: Expecting 2560 events.
[17:13:15.547] <TB3> INFO: 2560 events read in total (303ms).
[17:13:15.547] <TB3> INFO: Test took 1164ms.
[17:13:15.549] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:15.852] <TB3> INFO: Expecting 2560 events.
[17:13:16.711] <TB3> INFO: 2560 events read in total (302ms).
[17:13:16.711] <TB3> INFO: Test took 1162ms.
[17:13:16.726] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:17.017] <TB3> INFO: Expecting 2560 events.
[17:13:17.877] <TB3> INFO: 2560 events read in total (304ms).
[17:13:17.877] <TB3> INFO: Test took 1151ms.
[17:13:17.879] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:18.189] <TB3> INFO: Expecting 2560 events.
[17:13:19.041] <TB3> INFO: 2560 events read in total (296ms).
[17:13:19.042] <TB3> INFO: Test took 1163ms.
[17:13:19.043] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:19.348] <TB3> INFO: Expecting 2560 events.
[17:13:20.207] <TB3> INFO: 2560 events read in total (303ms).
[17:13:20.208] <TB3> INFO: Test took 1165ms.
[17:13:20.210] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:20.514] <TB3> INFO: Expecting 2560 events.
[17:13:21.372] <TB3> INFO: 2560 events read in total (302ms).
[17:13:21.373] <TB3> INFO: Test took 1164ms.
[17:13:21.376] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:21.679] <TB3> INFO: Expecting 2560 events.
[17:13:22.537] <TB3> INFO: 2560 events read in total (302ms).
[17:13:22.538] <TB3> INFO: Test took 1163ms.
[17:13:22.540] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:22.850] <TB3> INFO: Expecting 2560 events.
[17:13:23.708] <TB3> INFO: 2560 events read in total (302ms).
[17:13:23.708] <TB3> INFO: Test took 1168ms.
[17:13:23.711] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:24.013] <TB3> INFO: Expecting 2560 events.
[17:13:24.866] <TB3> INFO: 2560 events read in total (297ms).
[17:13:24.866] <TB3> INFO: Test took 1155ms.
[17:13:24.868] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:25.172] <TB3> INFO: Expecting 2560 events.
[17:13:26.025] <TB3> INFO: 2560 events read in total (297ms).
[17:13:26.025] <TB3> INFO: Test took 1157ms.
[17:13:26.027] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:26.331] <TB3> INFO: Expecting 2560 events.
[17:13:27.184] <TB3> INFO: 2560 events read in total (297ms).
[17:13:27.185] <TB3> INFO: Test took 1158ms.
[17:13:27.186] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:27.490] <TB3> INFO: Expecting 2560 events.
[17:13:28.345] <TB3> INFO: 2560 events read in total (299ms).
[17:13:28.345] <TB3> INFO: Test took 1159ms.
[17:13:28.347] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:28.650] <TB3> INFO: Expecting 2560 events.
[17:13:29.504] <TB3> INFO: 2560 events read in total (297ms).
[17:13:29.505] <TB3> INFO: Test took 1158ms.
[17:13:29.507] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:29.810] <TB3> INFO: Expecting 2560 events.
[17:13:30.664] <TB3> INFO: 2560 events read in total (298ms).
[17:13:30.664] <TB3> INFO: Test took 1157ms.
[17:13:30.666] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:30.969] <TB3> INFO: Expecting 2560 events.
[17:13:31.823] <TB3> INFO: 2560 events read in total (297ms).
[17:13:31.823] <TB3> INFO: Test took 1157ms.
[17:13:31.825] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:32.128] <TB3> INFO: Expecting 2560 events.
[17:13:32.982] <TB3> INFO: 2560 events read in total (297ms).
[17:13:32.983] <TB3> INFO: Test took 1159ms.
[17:13:32.985] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:33.288] <TB3> INFO: Expecting 2560 events.
[17:13:34.142] <TB3> INFO: 2560 events read in total (298ms).
[17:13:34.142] <TB3> INFO: Test took 1158ms.
[17:13:34.144] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:34.448] <TB3> INFO: Expecting 2560 events.
[17:13:35.301] <TB3> INFO: 2560 events read in total (297ms).
[17:13:35.301] <TB3> INFO: Test took 1157ms.
[17:13:35.303] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:35.606] <TB3> INFO: Expecting 2560 events.
[17:13:36.460] <TB3> INFO: 2560 events read in total (298ms).
[17:13:36.460] <TB3> INFO: Test took 1157ms.
[17:13:36.462] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:36.766] <TB3> INFO: Expecting 2560 events.
[17:13:37.619] <TB3> INFO: 2560 events read in total (297ms).
[17:13:37.619] <TB3> INFO: Test took 1157ms.
[17:13:37.621] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:37.925] <TB3> INFO: Expecting 2560 events.
[17:13:38.777] <TB3> INFO: 2560 events read in total (296ms).
[17:13:38.777] <TB3> INFO: Test took 1156ms.
[17:13:38.779] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:39.083] <TB3> INFO: Expecting 2560 events.
[17:13:39.936] <TB3> INFO: 2560 events read in total (297ms).
[17:13:39.936] <TB3> INFO: Test took 1157ms.
[17:13:39.938] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:40.242] <TB3> INFO: Expecting 2560 events.
[17:13:41.095] <TB3> INFO: 2560 events read in total (297ms).
[17:13:41.096] <TB3> INFO: Test took 1158ms.
[17:13:41.097] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:41.401] <TB3> INFO: Expecting 2560 events.
[17:13:42.254] <TB3> INFO: 2560 events read in total (297ms).
[17:13:42.254] <TB3> INFO: Test took 1157ms.
[17:13:42.256] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:42.560] <TB3> INFO: Expecting 2560 events.
[17:13:43.414] <TB3> INFO: 2560 events read in total (298ms).
[17:13:43.414] <TB3> INFO: Test took 1158ms.
[17:13:43.416] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:43.720] <TB3> INFO: Expecting 2560 events.
[17:13:44.574] <TB3> INFO: 2560 events read in total (298ms).
[17:13:44.574] <TB3> INFO: Test took 1158ms.
[17:13:44.576] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:13:44.879] <TB3> INFO: Expecting 2560 events.
[17:13:45.733] <TB3> INFO: 2560 events read in total (297ms).
[17:13:45.733] <TB3> INFO: Test took 1158ms.
[17:13:46.195] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 619 seconds
[17:13:46.195] <TB3> INFO: PH scale (per ROC): 74 79 73 77 69 69 72 66 69 75 79 72 70 70 74 66
[17:13:46.195] <TB3> INFO: PH offset (per ROC): 179 178 174 179 177 179 196 192 193 164 176 182 178 181 170 179
[17:13:46.199] <TB3> INFO: Decoding statistics:
[17:13:46.199] <TB3> INFO: General information:
[17:13:46.199] <TB3> INFO: 16bit words read: 91932
[17:13:46.199] <TB3> INFO: valid events total: 10240
[17:13:46.199] <TB3> INFO: empty events: 7737
[17:13:46.199] <TB3> INFO: valid events with pixels: 2503
[17:13:46.199] <TB3> INFO: valid pixel hits: 2503
[17:13:46.199] <TB3> INFO: Event errors: 0
[17:13:46.199] <TB3> INFO: start marker: 0
[17:13:46.199] <TB3> INFO: stop marker: 0
[17:13:46.199] <TB3> INFO: overflow: 0
[17:13:46.199] <TB3> INFO: invalid 5bit words: 0
[17:13:46.199] <TB3> INFO: invalid XOR eye diagram: 0
[17:13:46.199] <TB3> INFO: frame (failed synchr.): 0
[17:13:46.199] <TB3> INFO: idle data (no TBM trl): 0
[17:13:46.199] <TB3> INFO: no data (only TBM hdr): 0
[17:13:46.199] <TB3> INFO: TBM errors: 0
[17:13:46.199] <TB3> INFO: flawed TBM headers: 0
[17:13:46.199] <TB3> INFO: flawed TBM trailers: 0
[17:13:46.199] <TB3> INFO: event ID mismatches: 0
[17:13:46.199] <TB3> INFO: ROC errors: 0
[17:13:46.199] <TB3> INFO: missing ROC header(s): 0
[17:13:46.199] <TB3> INFO: misplaced readback start: 0
[17:13:46.199] <TB3> INFO: Pixel decoding errors: 0
[17:13:46.199] <TB3> INFO: pixel data incomplete: 0
[17:13:46.199] <TB3> INFO: pixel address: 0
[17:13:46.199] <TB3> INFO: pulse height fill bit: 0
[17:13:46.199] <TB3> INFO: buffer corruption: 0
[17:13:46.372] <TB3> INFO: ######################################################################
[17:13:46.372] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:13:46.372] <TB3> INFO: ######################################################################
[17:13:46.381] <TB3> INFO: scanning low vcal = 10
[17:13:46.652] <TB3> INFO: Expecting 41600 events.
[17:13:50.203] <TB3> INFO: 41600 events read in total (2995ms).
[17:13:50.203] <TB3> INFO: Test took 3822ms.
[17:13:50.205] <TB3> INFO: scanning low vcal = 20
[17:13:50.508] <TB3> INFO: Expecting 41600 events.
[17:13:54.046] <TB3> INFO: 41600 events read in total (2982ms).
[17:13:54.046] <TB3> INFO: Test took 3841ms.
[17:13:54.048] <TB3> INFO: scanning low vcal = 30
[17:13:54.352] <TB3> INFO: Expecting 41600 events.
[17:13:57.930] <TB3> INFO: 41600 events read in total (3022ms).
[17:13:57.931] <TB3> INFO: Test took 3883ms.
[17:13:57.933] <TB3> INFO: scanning low vcal = 40
[17:13:58.224] <TB3> INFO: Expecting 41600 events.
[17:14:02.386] <TB3> INFO: 41600 events read in total (3606ms).
[17:14:02.387] <TB3> INFO: Test took 4454ms.
[17:14:02.389] <TB3> INFO: scanning low vcal = 50
[17:14:02.650] <TB3> INFO: Expecting 41600 events.
[17:14:06.834] <TB3> INFO: 41600 events read in total (3628ms).
[17:14:06.835] <TB3> INFO: Test took 4446ms.
[17:14:06.838] <TB3> INFO: scanning low vcal = 60
[17:14:07.105] <TB3> INFO: Expecting 41600 events.
[17:14:11.334] <TB3> INFO: 41600 events read in total (3673ms).
[17:14:11.334] <TB3> INFO: Test took 4496ms.
[17:14:11.337] <TB3> INFO: scanning low vcal = 70
[17:14:11.631] <TB3> INFO: Expecting 41600 events.
[17:14:15.849] <TB3> INFO: 41600 events read in total (3662ms).
[17:14:15.849] <TB3> INFO: Test took 4512ms.
[17:14:15.852] <TB3> INFO: scanning low vcal = 80
[17:14:16.129] <TB3> INFO: Expecting 41600 events.
[17:14:20.370] <TB3> INFO: 41600 events read in total (3684ms).
[17:14:20.371] <TB3> INFO: Test took 4519ms.
[17:14:20.373] <TB3> INFO: scanning low vcal = 90
[17:14:20.658] <TB3> INFO: Expecting 41600 events.
[17:14:24.879] <TB3> INFO: 41600 events read in total (3665ms).
[17:14:24.880] <TB3> INFO: Test took 4507ms.
[17:14:24.883] <TB3> INFO: scanning low vcal = 100
[17:14:25.163] <TB3> INFO: Expecting 41600 events.
[17:14:29.361] <TB3> INFO: 41600 events read in total (3642ms).
[17:14:29.362] <TB3> INFO: Test took 4479ms.
[17:14:29.364] <TB3> INFO: scanning low vcal = 110
[17:14:29.652] <TB3> INFO: Expecting 41600 events.
[17:14:33.877] <TB3> INFO: 41600 events read in total (3668ms).
[17:14:33.878] <TB3> INFO: Test took 4514ms.
[17:14:33.881] <TB3> INFO: scanning low vcal = 120
[17:14:34.156] <TB3> INFO: Expecting 41600 events.
[17:14:38.397] <TB3> INFO: 41600 events read in total (3684ms).
[17:14:38.398] <TB3> INFO: Test took 4517ms.
[17:14:38.400] <TB3> INFO: scanning low vcal = 130
[17:14:38.674] <TB3> INFO: Expecting 41600 events.
[17:14:42.906] <TB3> INFO: 41600 events read in total (3676ms).
[17:14:42.907] <TB3> INFO: Test took 4507ms.
[17:14:42.910] <TB3> INFO: scanning low vcal = 140
[17:14:43.179] <TB3> INFO: Expecting 41600 events.
[17:14:47.425] <TB3> INFO: 41600 events read in total (3689ms).
[17:14:47.426] <TB3> INFO: Test took 4516ms.
[17:14:47.428] <TB3> INFO: scanning low vcal = 150
[17:14:47.707] <TB3> INFO: Expecting 41600 events.
[17:14:51.948] <TB3> INFO: 41600 events read in total (3685ms).
[17:14:51.948] <TB3> INFO: Test took 4520ms.
[17:14:51.951] <TB3> INFO: scanning low vcal = 160
[17:14:52.243] <TB3> INFO: Expecting 41600 events.
[17:14:56.455] <TB3> INFO: 41600 events read in total (3656ms).
[17:14:56.456] <TB3> INFO: Test took 4505ms.
[17:14:56.462] <TB3> INFO: scanning low vcal = 170
[17:14:56.744] <TB3> INFO: Expecting 41600 events.
[17:15:00.974] <TB3> INFO: 41600 events read in total (3674ms).
[17:15:00.974] <TB3> INFO: Test took 4512ms.
[17:15:00.978] <TB3> INFO: scanning low vcal = 180
[17:15:01.243] <TB3> INFO: Expecting 41600 events.
[17:15:05.435] <TB3> INFO: 41600 events read in total (3636ms).
[17:15:05.435] <TB3> INFO: Test took 4457ms.
[17:15:05.438] <TB3> INFO: scanning low vcal = 190
[17:15:05.697] <TB3> INFO: Expecting 41600 events.
[17:15:09.902] <TB3> INFO: 41600 events read in total (3649ms).
[17:15:09.903] <TB3> INFO: Test took 4465ms.
[17:15:09.906] <TB3> INFO: scanning low vcal = 200
[17:15:10.169] <TB3> INFO: Expecting 41600 events.
[17:15:14.328] <TB3> INFO: 41600 events read in total (3603ms).
[17:15:14.329] <TB3> INFO: Test took 4423ms.
[17:15:14.331] <TB3> INFO: scanning low vcal = 210
[17:15:14.590] <TB3> INFO: Expecting 41600 events.
[17:15:18.765] <TB3> INFO: 41600 events read in total (3619ms).
[17:15:18.765] <TB3> INFO: Test took 4433ms.
[17:15:18.768] <TB3> INFO: scanning low vcal = 220
[17:15:19.030] <TB3> INFO: Expecting 41600 events.
[17:15:23.214] <TB3> INFO: 41600 events read in total (3628ms).
[17:15:23.215] <TB3> INFO: Test took 4447ms.
[17:15:23.217] <TB3> INFO: scanning low vcal = 230
[17:15:23.508] <TB3> INFO: Expecting 41600 events.
[17:15:27.716] <TB3> INFO: 41600 events read in total (3652ms).
[17:15:27.724] <TB3> INFO: Test took 4507ms.
[17:15:27.726] <TB3> INFO: scanning low vcal = 240
[17:15:28.009] <TB3> INFO: Expecting 41600 events.
[17:15:32.173] <TB3> INFO: 41600 events read in total (3608ms).
[17:15:32.174] <TB3> INFO: Test took 4447ms.
[17:15:32.176] <TB3> INFO: scanning low vcal = 250
[17:15:32.440] <TB3> INFO: Expecting 41600 events.
[17:15:36.631] <TB3> INFO: 41600 events read in total (3635ms).
[17:15:36.632] <TB3> INFO: Test took 4456ms.
[17:15:36.636] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[17:15:36.924] <TB3> INFO: Expecting 41600 events.
[17:15:41.140] <TB3> INFO: 41600 events read in total (3659ms).
[17:15:41.140] <TB3> INFO: Test took 4504ms.
[17:15:41.143] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[17:15:41.409] <TB3> INFO: Expecting 41600 events.
[17:15:45.575] <TB3> INFO: 41600 events read in total (3610ms).
[17:15:45.576] <TB3> INFO: Test took 4432ms.
[17:15:45.579] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[17:15:45.842] <TB3> INFO: Expecting 41600 events.
[17:15:50.037] <TB3> INFO: 41600 events read in total (3638ms).
[17:15:50.038] <TB3> INFO: Test took 4459ms.
[17:15:50.040] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[17:15:50.300] <TB3> INFO: Expecting 41600 events.
[17:15:54.472] <TB3> INFO: 41600 events read in total (3615ms).
[17:15:54.473] <TB3> INFO: Test took 4432ms.
[17:15:54.476] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:15:54.741] <TB3> INFO: Expecting 41600 events.
[17:15:58.928] <TB3> INFO: 41600 events read in total (3631ms).
[17:15:58.929] <TB3> INFO: Test took 4453ms.
[17:15:59.401] <TB3> INFO: PixTestGainPedestal::measure() done
[17:16:36.865] <TB3> INFO: PixTestGainPedestal::fit() done
[17:16:36.865] <TB3> INFO: non-linearity mean: 0.955 0.962 0.955 0.965 0.958 0.951 0.953 0.964 0.953 0.955 0.962 0.957 0.961 0.963 0.960 0.956
[17:16:36.865] <TB3> INFO: non-linearity RMS: 0.006 0.005 0.006 0.006 0.007 0.008 0.008 0.006 0.008 0.006 0.005 0.006 0.005 0.006 0.005 0.005
[17:16:36.865] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[17:16:36.890] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[17:16:36.909] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[17:16:36.928] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[17:16:36.947] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[17:16:36.965] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[17:16:36.984] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[17:16:37.003] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[17:16:37.022] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[17:16:37.041] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[17:16:37.059] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[17:16:37.081] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[17:16:37.100] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[17:16:37.119] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[17:16:37.137] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[17:16:37.159] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[17:16:37.187] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 170 seconds
[17:16:37.187] <TB3> INFO: Decoding statistics:
[17:16:37.187] <TB3> INFO: General information:
[17:16:37.187] <TB3> INFO: 16bit words read: 2662400
[17:16:37.187] <TB3> INFO: valid events total: 166400
[17:16:37.187] <TB3> INFO: empty events: 0
[17:16:37.187] <TB3> INFO: valid events with pixels: 166400
[17:16:37.187] <TB3> INFO: valid pixel hits: 663963
[17:16:37.187] <TB3> INFO: Event errors: 0
[17:16:37.187] <TB3> INFO: start marker: 0
[17:16:37.187] <TB3> INFO: stop marker: 0
[17:16:37.187] <TB3> INFO: overflow: 0
[17:16:37.187] <TB3> INFO: invalid 5bit words: 0
[17:16:37.187] <TB3> INFO: invalid XOR eye diagram: 0
[17:16:37.187] <TB3> INFO: frame (failed synchr.): 0
[17:16:37.187] <TB3> INFO: idle data (no TBM trl): 0
[17:16:37.187] <TB3> INFO: no data (only TBM hdr): 0
[17:16:37.187] <TB3> INFO: TBM errors: 0
[17:16:37.187] <TB3> INFO: flawed TBM headers: 0
[17:16:37.187] <TB3> INFO: flawed TBM trailers: 0
[17:16:37.187] <TB3> INFO: event ID mismatches: 0
[17:16:37.187] <TB3> INFO: ROC errors: 0
[17:16:37.187] <TB3> INFO: missing ROC header(s): 0
[17:16:37.187] <TB3> INFO: misplaced readback start: 0
[17:16:37.187] <TB3> INFO: Pixel decoding errors: 0
[17:16:37.187] <TB3> INFO: pixel data incomplete: 0
[17:16:37.187] <TB3> INFO: pixel address: 0
[17:16:37.187] <TB3> INFO: pulse height fill bit: 0
[17:16:37.187] <TB3> INFO: buffer corruption: 0
[17:16:37.197] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C15.dat
[17:16:37.198] <TB3> INFO: ######################################################################
[17:16:37.198] <TB3> INFO: PixTestReadback::doTest()
[17:16:37.198] <TB3> INFO: ######################################################################
[17:16:37.198] <TB3> INFO: ----------------------------------------------------------------------
[17:16:37.198] <TB3> INFO: PixTestReadback::CalibrateVd()
[17:16:37.198] <TB3> INFO: ----------------------------------------------------------------------
[17:16:46.668] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C0.dat
[17:16:46.668] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C1.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C2.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C3.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C4.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C5.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C6.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C7.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C8.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C9.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C10.dat
[17:16:46.669] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C11.dat
[17:16:46.670] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C12.dat
[17:16:46.670] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C13.dat
[17:16:46.670] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C14.dat
[17:16:46.670] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C15.dat
[17:16:46.708] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:16:46.708] <TB3> INFO: ----------------------------------------------------------------------
[17:16:46.708] <TB3> INFO: PixTestReadback::CalibrateVa()
[17:16:46.708] <TB3> INFO: ----------------------------------------------------------------------
[17:16:56.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C0.dat
[17:16:56.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C1.dat
[17:16:56.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C2.dat
[17:16:56.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C3.dat
[17:16:56.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C4.dat
[17:16:56.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C5.dat
[17:16:56.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C6.dat
[17:16:56.218] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C7.dat
[17:16:56.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C8.dat
[17:16:56.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C9.dat
[17:16:56.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C10.dat
[17:16:56.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C11.dat
[17:16:56.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C12.dat
[17:16:56.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C13.dat
[17:16:56.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C14.dat
[17:16:56.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C15.dat
[17:16:56.251] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:16:56.251] <TB3> INFO: ----------------------------------------------------------------------
[17:16:56.251] <TB3> INFO: PixTestReadback::readbackVbg()
[17:16:56.251] <TB3> INFO: ----------------------------------------------------------------------
[17:17:03.540] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:17:03.540] <TB3> INFO: ----------------------------------------------------------------------
[17:17:03.540] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[17:17:03.540] <TB3> INFO: ----------------------------------------------------------------------
[17:17:03.540] <TB3> INFO: Vbg will be calibrated using Vd calibration
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.5calibrated Vbg = 1.21748 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.1calibrated Vbg = 1.21125 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.1calibrated Vbg = 1.22103 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 150.4calibrated Vbg = 1.22518 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.9calibrated Vbg = 1.23393 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 151calibrated Vbg = 1.23639 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 162.3calibrated Vbg = 1.22702 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 163.5calibrated Vbg = 1.23512 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 162.1calibrated Vbg = 1.23126 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.3calibrated Vbg = 1.2366 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 170calibrated Vbg = 1.22902 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 144.1calibrated Vbg = 1.22359 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 161.9calibrated Vbg = 1.2197 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 150.6calibrated Vbg = 1.22444 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.5calibrated Vbg = 1.22114 :::*/*/*/*/
[17:17:03.540] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 161calibrated Vbg = 1.2183 :::*/*/*/*/
[17:17:03.542] <TB3> INFO: ----------------------------------------------------------------------
[17:17:03.542] <TB3> INFO: PixTestReadback::CalibrateIa()
[17:17:03.542] <TB3> INFO: ----------------------------------------------------------------------
[17:19:39.057] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C0.dat
[17:19:39.057] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C1.dat
[17:19:39.057] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C2.dat
[17:19:39.057] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C3.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C4.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C5.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C6.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C7.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C8.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C9.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C10.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C11.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C12.dat
[17:19:39.058] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C13.dat
[17:19:39.059] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C14.dat
[17:19:39.059] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2195_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C15.dat
[17:19:39.089] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:19:39.090] <TB3> INFO: PixTestReadback::doTest() done
[17:19:39.090] <TB3> INFO: Decoding statistics:
[17:19:39.090] <TB3> INFO: General information:
[17:19:39.090] <TB3> INFO: 16bit words read: 1024
[17:19:39.090] <TB3> INFO: valid events total: 128
[17:19:39.090] <TB3> INFO: empty events: 128
[17:19:39.090] <TB3> INFO: valid events with pixels: 0
[17:19:39.090] <TB3> INFO: valid pixel hits: 0
[17:19:39.090] <TB3> INFO: Event errors: 0
[17:19:39.090] <TB3> INFO: start marker: 0
[17:19:39.090] <TB3> INFO: stop marker: 0
[17:19:39.090] <TB3> INFO: overflow: 0
[17:19:39.090] <TB3> INFO: invalid 5bit words: 0
[17:19:39.090] <TB3> INFO: invalid XOR eye diagram: 0
[17:19:39.090] <TB3> INFO: frame (failed synchr.): 0
[17:19:39.090] <TB3> INFO: idle data (no TBM trl): 0
[17:19:39.090] <TB3> INFO: no data (only TBM hdr): 0
[17:19:39.090] <TB3> INFO: TBM errors: 0
[17:19:39.090] <TB3> INFO: flawed TBM headers: 0
[17:19:39.090] <TB3> INFO: flawed TBM trailers: 0
[17:19:39.090] <TB3> INFO: event ID mismatches: 0
[17:19:39.090] <TB3> INFO: ROC errors: 0
[17:19:39.090] <TB3> INFO: missing ROC header(s): 0
[17:19:39.090] <TB3> INFO: misplaced readback start: 0
[17:19:39.090] <TB3> INFO: Pixel decoding errors: 0
[17:19:39.090] <TB3> INFO: pixel data incomplete: 0
[17:19:39.090] <TB3> INFO: pixel address: 0
[17:19:39.090] <TB3> INFO: pulse height fill bit: 0
[17:19:39.090] <TB3> INFO: buffer corruption: 0
[17:19:39.109] <TB3> INFO: Decoding statistics:
[17:19:39.109] <TB3> INFO: General information:
[17:19:39.109] <TB3> INFO: 16bit words read: 2755356
[17:19:39.109] <TB3> INFO: valid events total: 176768
[17:19:39.109] <TB3> INFO: empty events: 7865
[17:19:39.109] <TB3> INFO: valid events with pixels: 168903
[17:19:39.109] <TB3> INFO: valid pixel hits: 666466
[17:19:39.109] <TB3> INFO: Event errors: 0
[17:19:39.109] <TB3> INFO: start marker: 0
[17:19:39.109] <TB3> INFO: stop marker: 0
[17:19:39.109] <TB3> INFO: overflow: 0
[17:19:39.109] <TB3> INFO: invalid 5bit words: 0
[17:19:39.109] <TB3> INFO: invalid XOR eye diagram: 0
[17:19:39.109] <TB3> INFO: frame (failed synchr.): 0
[17:19:39.109] <TB3> INFO: idle data (no TBM trl): 0
[17:19:39.109] <TB3> INFO: no data (only TBM hdr): 0
[17:19:39.109] <TB3> INFO: TBM errors: 0
[17:19:39.109] <TB3> INFO: flawed TBM headers: 0
[17:19:39.109] <TB3> INFO: flawed TBM trailers: 0
[17:19:39.109] <TB3> INFO: event ID mismatches: 0
[17:19:39.109] <TB3> INFO: ROC errors: 0
[17:19:39.109] <TB3> INFO: missing ROC header(s): 0
[17:19:39.109] <TB3> INFO: misplaced readback start: 0
[17:19:39.109] <TB3> INFO: Pixel decoding errors: 0
[17:19:39.109] <TB3> INFO: pixel data incomplete: 0
[17:19:39.109] <TB3> INFO: pixel address: 0
[17:19:39.109] <TB3> INFO: pulse height fill bit: 0
[17:19:39.109] <TB3> INFO: buffer corruption: 0
[17:19:39.110] <TB3> INFO: enter test to run
[17:19:39.110] <TB3> INFO: test: exit no parameter change
[17:19:39.248] <TB3> QUIET: Connection to board 170 closed.
[17:19:39.328] <TB3> INFO: pXar: this is the end, my friend