[10:17:34.604] <TB0> INFO: *** Welcome to pxar ***
[10:17:34.604] <TB0> INFO: *** Today: 2016/06/30
[10:17:35.586] <TB0> INFO: *** Version: 9751-dirty
[10:17:35.586] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C15.dat
[10:17:35.586] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//tbmParameters_C0b.dat
[10:17:35.586] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//defaultMaskFile.dat
[10:17:35.587] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters_C15.dat
[10:17:35.651] <TB0> INFO: clk: 4
[10:17:35.651] <TB0> INFO: ctr: 4
[10:17:35.651] <TB0> INFO: sda: 19
[10:17:35.651] <TB0> INFO: tin: 9
[10:17:35.651] <TB0> INFO: level: 15
[10:17:35.651] <TB0> INFO: triggerdelay: 0
[10:17:35.651] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:17:35.651] <TB0> INFO: Log level: INFO
[10:17:35.660] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[10:17:35.662] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[10:17:35.664] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[10:17:37.198] <TB0> INFO: DUT info:
[10:17:37.198] <TB0> INFO: The DUT currently contains the following objects:
[10:17:37.198] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:17:37.198] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:17:37.198] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:17:37.198] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:17:37.198] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.198] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:37.599] <TB0> INFO: enter 'restricted' command line mode
[10:17:37.599] <TB0> INFO: enter test to run
[10:17:37.599] <TB0> INFO: test: pretest no parameter change
[10:17:37.599] <TB0> INFO: running: pretest
[10:17:37.604] <TB0> INFO: ----------------------------------------------------------------------
[10:17:37.604] <TB0> INFO: PixTestPretest::programROC()
[10:17:37.604] <TB0> INFO: ----------------------------------------------------------------------
[10:17:55.618] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:17:55.618] <TB0> INFO: IA differences per ROC: 17.7 19.3 20.1 18.5 16.9 19.3 19.3 16.9 19.3 20.9 19.3 20.1 20.9 20.9 17.7 18.5
[10:17:55.703] <TB0> INFO: enter test to run
[10:17:55.703] <TB0> INFO: test: pretest no parameter change
[10:17:55.703] <TB0> INFO: running: pretest
[10:17:55.704] <TB0> INFO: ----------------------------------------------------------------------
[10:17:55.704] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:17:55.704] <TB0> INFO: ----------------------------------------------------------------------
[10:18:00.680] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[10:18:00.680] <TB0> INFO: i(loss) [mA/ROC]: 18.5 19.3 18.5 19.3 19.3 18.5 19.3 18.5 18.5 18.5 17.7 18.5 18.5 18.5 19.3 18.5
[10:18:00.707] <TB0> INFO: enter test to run
[10:18:00.707] <TB0> INFO: test: pretest no parameter change
[10:18:00.707] <TB0> INFO: running: pretest
[10:18:00.707] <TB0> INFO: ----------------------------------------------------------------------
[10:18:00.707] <TB0> INFO: PixTestPretest::findTiming()
[10:18:00.707] <TB0> INFO: ----------------------------------------------------------------------
[10:18:00.708] <TB0> INFO: PixTestCmd::init()

[10:19:29.627] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[10:19:29.627] <TB0> INFO: (success/tries = 100/100), width = 4
[10:19:29.628] <TB0> INFO: enter test to run
[10:19:29.628] <TB0> INFO: test: pretest no parameter change
[10:19:29.628] <TB0> INFO: running: pretest
[10:19:29.629] <TB0> INFO: ----------------------------------------------------------------------
[10:19:29.629] <TB0> INFO: PixTestPretest::findWorkingPixel()
[10:19:29.629] <TB0> INFO: ----------------------------------------------------------------------
[10:19:29.719] <TB0> INFO: Expecting 231680 events.


[10:19:39.833] <TB0> INFO: 231680 events read in total (9557ms).
[10:19:39.837] <TB0> INFO: Test took 10206ms.
[10:19:40.065] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:19:40.124] <TB0> INFO: enter test to run
[10:19:40.124] <TB0> INFO: test: pretest no parameter change
[10:19:40.124] <TB0> INFO: running: pretest
[10:19:40.125] <TB0> INFO: ----------------------------------------------------------------------
[10:19:40.125] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[10:19:40.125] <TB0> INFO: ----------------------------------------------------------------------
[10:19:40.218] <TB0> INFO: Expecting 231680 events.
[10:19:50.358] <TB0> INFO: 231680 events read in total (9584ms).
[10:19:50.362] <TB0> INFO: Test took 10233ms.
[10:19:50.601] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[10:19:50.602] <TB0> INFO: CalDel: 146 149 139 165 157 120 137 125 122 133 137 139 162 171 149 139
[10:19:50.602] <TB0> INFO: VthrComp: 51 51 51 51 56 51 51 51 51 51 51 51 51 51 51 51
[10:19:50.634] <TB0> INFO: enter test to run
[10:19:50.634] <TB0> INFO: test: pretest no parameter change
[10:19:50.634] <TB0> INFO: running: pretest
[10:19:50.634] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C0.dat
[10:19:50.634] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C1.dat
[10:19:50.634] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C2.dat
[10:19:50.634] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C3.dat
[10:19:50.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C4.dat
[10:19:50.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C5.dat
[10:19:50.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C6.dat
[10:19:50.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C7.dat
[10:19:50.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C8.dat
[10:19:50.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C9.dat
[10:19:50.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C10.dat
[10:19:50.635] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C11.dat
[10:19:50.636] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C12.dat
[10:19:50.636] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C13.dat
[10:19:50.636] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C14.dat
[10:19:50.636] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters_C15.dat
[10:19:50.636] <TB0> INFO: enter test to run
[10:19:50.636] <TB0> INFO: test: fulltest no parameter change
[10:19:50.636] <TB0> INFO: running: fulltest
[10:19:50.636] <TB0> INFO: ######################################################################
[10:19:50.636] <TB0> INFO: PixTestFullTest::doTest()
[10:19:50.636] <TB0> INFO: ######################################################################
[10:19:50.637] <TB0> INFO: ######################################################################
[10:19:50.637] <TB0> INFO: PixTestAlive::doTest()
[10:19:50.637] <TB0> INFO: ######################################################################
[10:19:50.638] <TB0> INFO: ----------------------------------------------------------------------
[10:19:50.638] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:19:50.638] <TB0> INFO: ----------------------------------------------------------------------
[10:19:50.896] <TB0> INFO: Expecting 41600 events.
[10:19:54.562] <TB0> INFO: 41600 events read in total (3110ms).
[10:19:54.562] <TB0> INFO: Test took 3922ms.
[10:19:54.794] <TB0> INFO: PixTestAlive::aliveTest() done
[10:19:54.794] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[10:19:54.795] <TB0> INFO: ----------------------------------------------------------------------
[10:19:54.795] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:19:54.795] <TB0> INFO: ----------------------------------------------------------------------
[10:19:55.049] <TB0> INFO: Expecting 41600 events.
[10:19:57.957] <TB0> INFO: 41600 events read in total (2351ms).
[10:19:57.957] <TB0> INFO: Test took 3161ms.
[10:19:57.957] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:19:58.202] <TB0> INFO: PixTestAlive::maskTest() done
[10:19:58.202] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:19:58.203] <TB0> INFO: ----------------------------------------------------------------------
[10:19:58.203] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:19:58.203] <TB0> INFO: ----------------------------------------------------------------------
[10:19:58.456] <TB0> INFO: Expecting 41600 events.
[10:20:02.124] <TB0> INFO: 41600 events read in total (3112ms).
[10:20:02.124] <TB0> INFO: Test took 3920ms.
[10:20:02.355] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[10:20:02.355] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:20:02.355] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[10:20:02.355] <TB0> INFO: Decoding statistics:
[10:20:02.355] <TB0> INFO: General information:
[10:20:02.355] <TB0> INFO: 16bit words read: 0
[10:20:02.355] <TB0> INFO: valid events total: 0
[10:20:02.355] <TB0> INFO: empty events: 0
[10:20:02.355] <TB0> INFO: valid events with pixels: 0
[10:20:02.355] <TB0> INFO: valid pixel hits: 0
[10:20:02.355] <TB0> INFO: Event errors: 0
[10:20:02.355] <TB0> INFO: start marker: 0
[10:20:02.355] <TB0> INFO: stop marker: 0
[10:20:02.355] <TB0> INFO: overflow: 0
[10:20:02.355] <TB0> INFO: invalid 5bit words: 0
[10:20:02.355] <TB0> INFO: invalid XOR eye diagram: 0
[10:20:02.355] <TB0> INFO: frame (failed synchr.): 0
[10:20:02.355] <TB0> INFO: idle data (no TBM trl): 0
[10:20:02.355] <TB0> INFO: no data (only TBM hdr): 0
[10:20:02.355] <TB0> INFO: TBM errors: 0
[10:20:02.356] <TB0> INFO: flawed TBM headers: 0
[10:20:02.356] <TB0> INFO: flawed TBM trailers: 0
[10:20:02.356] <TB0> INFO: event ID mismatches: 0
[10:20:02.356] <TB0> INFO: ROC errors: 0
[10:20:02.356] <TB0> INFO: missing ROC header(s): 0
[10:20:02.356] <TB0> INFO: misplaced readback start: 0
[10:20:02.356] <TB0> INFO: Pixel decoding errors: 0
[10:20:02.356] <TB0> INFO: pixel data incomplete: 0
[10:20:02.356] <TB0> INFO: pixel address: 0
[10:20:02.356] <TB0> INFO: pulse height fill bit: 0
[10:20:02.356] <TB0> INFO: buffer corruption: 0
[10:20:02.362] <TB0> INFO: ######################################################################
[10:20:02.362] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:20:02.362] <TB0> INFO: ######################################################################
[10:20:02.365] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:20:02.376] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:20:02.376] <TB0> INFO: run 1 of 1
[10:20:02.629] <TB0> INFO: Expecting 3120000 events.
[10:20:42.682] <TB0> INFO: 891280 events read in total (39497ms).
[10:21:21.511] <TB0> INFO: 1755750 events read in total (78327ms).
[10:22:00.573] <TB0> INFO: 2628025 events read in total (117388ms).
[10:22:22.428] <TB0> INFO: 3120000 events read in total (139243ms).
[10:22:22.474] <TB0> INFO: Test took 140099ms.
[10:22:44.143] <TB0> INFO: PixTestBBMap::doTest() done, duration: 161 seconds
[10:22:44.143] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:22:44.143] <TB0> INFO: separation cut (per ROC): 103 100 102 98 110 111 113 111 110 108 103 104 107 109 112 102
[10:22:44.143] <TB0> INFO: Decoding statistics:
[10:22:44.143] <TB0> INFO: General information:
[10:22:44.143] <TB0> INFO: 16bit words read: 0
[10:22:44.143] <TB0> INFO: valid events total: 0
[10:22:44.143] <TB0> INFO: empty events: 0
[10:22:44.143] <TB0> INFO: valid events with pixels: 0
[10:22:44.143] <TB0> INFO: valid pixel hits: 0
[10:22:44.143] <TB0> INFO: Event errors: 0
[10:22:44.143] <TB0> INFO: start marker: 0
[10:22:44.143] <TB0> INFO: stop marker: 0
[10:22:44.143] <TB0> INFO: overflow: 0
[10:22:44.143] <TB0> INFO: invalid 5bit words: 0
[10:22:44.143] <TB0> INFO: invalid XOR eye diagram: 0
[10:22:44.143] <TB0> INFO: frame (failed synchr.): 0
[10:22:44.143] <TB0> INFO: idle data (no TBM trl): 0
[10:22:44.143] <TB0> INFO: no data (only TBM hdr): 0
[10:22:44.143] <TB0> INFO: TBM errors: 0
[10:22:44.143] <TB0> INFO: flawed TBM headers: 0
[10:22:44.143] <TB0> INFO: flawed TBM trailers: 0
[10:22:44.143] <TB0> INFO: event ID mismatches: 0
[10:22:44.143] <TB0> INFO: ROC errors: 0
[10:22:44.143] <TB0> INFO: missing ROC header(s): 0
[10:22:44.143] <TB0> INFO: misplaced readback start: 0
[10:22:44.143] <TB0> INFO: Pixel decoding errors: 0
[10:22:44.143] <TB0> INFO: pixel data incomplete: 0
[10:22:44.143] <TB0> INFO: pixel address: 0
[10:22:44.143] <TB0> INFO: pulse height fill bit: 0
[10:22:44.143] <TB0> INFO: buffer corruption: 0
[10:22:44.211] <TB0> INFO: ######################################################################
[10:22:44.211] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:22:44.211] <TB0> INFO: ######################################################################
[10:22:44.211] <TB0> INFO: ----------------------------------------------------------------------
[10:22:44.211] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:22:44.211] <TB0> INFO: ----------------------------------------------------------------------
[10:22:44.211] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:22:44.219] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[10:22:44.219] <TB0> INFO: run 1 of 1
[10:22:44.475] <TB0> INFO: Expecting 26208000 events.
[10:23:15.779] <TB0> INFO: 879000 events read in total (30748ms).
[10:23:46.528] <TB0> INFO: 1747600 events read in total (61497ms).
[10:24:17.029] <TB0> INFO: 2613700 events read in total (91998ms).
[10:24:47.684] <TB0> INFO: 3480300 events read in total (122653ms).
[10:25:18.267] <TB0> INFO: 4349150 events read in total (153236ms).
[10:25:48.695] <TB0> INFO: 5214900 events read in total (183664ms).
[10:26:19.230] <TB0> INFO: 6086050 events read in total (214199ms).
[10:26:49.792] <TB0> INFO: 6954700 events read in total (244761ms).
[10:27:20.601] <TB0> INFO: 7824400 events read in total (275570ms).
[10:27:51.262] <TB0> INFO: 8694600 events read in total (306231ms).
[10:28:21.970] <TB0> INFO: 9567200 events read in total (336939ms).
[10:28:52.702] <TB0> INFO: 10436900 events read in total (367671ms).
[10:29:23.330] <TB0> INFO: 11307100 events read in total (398299ms).
[10:29:54.246] <TB0> INFO: 12181550 events read in total (429215ms).
[10:30:24.938] <TB0> INFO: 13051650 events read in total (459907ms).
[10:30:55.771] <TB0> INFO: 13915900 events read in total (490740ms).
[10:31:26.429] <TB0> INFO: 14783050 events read in total (521398ms).
[10:31:57.109] <TB0> INFO: 15648150 events read in total (552078ms).
[10:32:27.799] <TB0> INFO: 16511500 events read in total (582768ms).
[10:32:58.834] <TB0> INFO: 17378400 events read in total (613803ms).
[10:33:29.628] <TB0> INFO: 18244150 events read in total (644597ms).
[10:34:00.470] <TB0> INFO: 19110950 events read in total (675439ms).
[10:34:31.406] <TB0> INFO: 19976900 events read in total (706375ms).
[10:35:02.271] <TB0> INFO: 20844800 events read in total (737240ms).
[10:35:33.105] <TB0> INFO: 21713600 events read in total (768074ms).
[10:36:04.094] <TB0> INFO: 22583750 events read in total (799063ms).
[10:36:34.948] <TB0> INFO: 23455400 events read in total (829917ms).
[10:37:05.803] <TB0> INFO: 24329900 events read in total (860772ms).
[10:37:36.799] <TB0> INFO: 25205700 events read in total (891768ms).
[10:38:08.011] <TB0> INFO: 26096550 events read in total (922980ms).
[10:38:12.186] <TB0> INFO: 26208000 events read in total (927155ms).
[10:38:12.214] <TB0> INFO: Test took 927994ms.
[10:38:12.434] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:13.824] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:15.210] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:16.585] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:17.984] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:19.325] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:20.695] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:22.041] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:23.376] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:24.738] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:26.102] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:27.477] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:28.834] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:30.176] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:31.519] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:32.857] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:34.245] <TB0> INFO: PixTestScurves::scurves() done
[10:38:34.245] <TB0> INFO: Vcal mean: 82.74 83.73 85.73 82.41 100.95 92.15 99.34 102.23 94.73 90.02 88.59 88.87 96.96 102.46 105.18 94.33
[10:38:34.245] <TB0> INFO: Vcal RMS: 4.39 4.60 4.89 4.51 5.90 4.81 5.17 5.85 6.59 5.78 5.06 4.95 5.49 6.49 5.42 5.85
[10:38:34.245] <TB0> INFO: PixTestScurves::fullTest() done, duration: 950 seconds
[10:38:34.245] <TB0> INFO: Decoding statistics:
[10:38:34.245] <TB0> INFO: General information:
[10:38:34.245] <TB0> INFO: 16bit words read: 0
[10:38:34.245] <TB0> INFO: valid events total: 0
[10:38:34.245] <TB0> INFO: empty events: 0
[10:38:34.245] <TB0> INFO: valid events with pixels: 0
[10:38:34.245] <TB0> INFO: valid pixel hits: 0
[10:38:34.245] <TB0> INFO: Event errors: 0
[10:38:34.245] <TB0> INFO: start marker: 0
[10:38:34.245] <TB0> INFO: stop marker: 0
[10:38:34.245] <TB0> INFO: overflow: 0
[10:38:34.245] <TB0> INFO: invalid 5bit words: 0
[10:38:34.245] <TB0> INFO: invalid XOR eye diagram: 0
[10:38:34.246] <TB0> INFO: frame (failed synchr.): 0
[10:38:34.246] <TB0> INFO: idle data (no TBM trl): 0
[10:38:34.246] <TB0> INFO: no data (only TBM hdr): 0
[10:38:34.246] <TB0> INFO: TBM errors: 0
[10:38:34.246] <TB0> INFO: flawed TBM headers: 0
[10:38:34.246] <TB0> INFO: flawed TBM trailers: 0
[10:38:34.246] <TB0> INFO: event ID mismatches: 0
[10:38:34.246] <TB0> INFO: ROC errors: 0
[10:38:34.246] <TB0> INFO: missing ROC header(s): 0
[10:38:34.246] <TB0> INFO: misplaced readback start: 0
[10:38:34.246] <TB0> INFO: Pixel decoding errors: 0
[10:38:34.246] <TB0> INFO: pixel data incomplete: 0
[10:38:34.246] <TB0> INFO: pixel address: 0
[10:38:34.246] <TB0> INFO: pulse height fill bit: 0
[10:38:34.246] <TB0> INFO: buffer corruption: 0
[10:38:34.315] <TB0> INFO: ######################################################################
[10:38:34.315] <TB0> INFO: PixTestTrim::doTest()
[10:38:34.315] <TB0> INFO: ######################################################################
[10:38:34.316] <TB0> INFO: ----------------------------------------------------------------------
[10:38:34.316] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:38:34.316] <TB0> INFO: ----------------------------------------------------------------------
[10:38:34.391] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:38:34.391] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:38:34.399] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:38:34.399] <TB0> INFO: run 1 of 1
[10:38:34.653] <TB0> INFO: Expecting 6281600 events.
[10:39:12.228] <TB0> INFO: 1056440 events read in total (37014ms).
[10:39:48.825] <TB0> INFO: 2107570 events read in total (73612ms).
[10:40:25.418] <TB0> INFO: 3153640 events read in total (110205ms).
[10:41:01.994] <TB0> INFO: 4205060 events read in total (146780ms).
[10:41:38.610] <TB0> INFO: 5259830 events read in total (183396ms).
[10:42:13.902] <TB0> INFO: 6281600 events read in total (218688ms).
[10:42:13.932] <TB0> INFO: Test took 219533ms.
[10:42:31.236] <TB0> INFO: ROC 0 VthrComp = 86
[10:42:31.236] <TB0> INFO: ROC 1 VthrComp = 85
[10:42:31.236] <TB0> INFO: ROC 2 VthrComp = 89
[10:42:31.236] <TB0> INFO: ROC 3 VthrComp = 85
[10:42:31.236] <TB0> INFO: ROC 4 VthrComp = 96
[10:42:31.236] <TB0> INFO: ROC 5 VthrComp = 97
[10:42:31.236] <TB0> INFO: ROC 6 VthrComp = 103
[10:42:31.237] <TB0> INFO: ROC 7 VthrComp = 101
[10:42:31.237] <TB0> INFO: ROC 8 VthrComp = 91
[10:42:31.237] <TB0> INFO: ROC 9 VthrComp = 93
[10:42:31.237] <TB0> INFO: ROC 10 VthrComp = 87
[10:42:31.237] <TB0> INFO: ROC 11 VthrComp = 89
[10:42:31.237] <TB0> INFO: ROC 12 VthrComp = 93
[10:42:31.237] <TB0> INFO: ROC 13 VthrComp = 91
[10:42:31.237] <TB0> INFO: ROC 14 VthrComp = 102
[10:42:31.237] <TB0> INFO: ROC 15 VthrComp = 93
[10:42:31.237] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:42:31.237] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:42:31.245] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:42:31.246] <TB0> INFO: run 1 of 1
[10:42:31.499] <TB0> INFO: Expecting 6281600 events.
[10:43:05.026] <TB0> INFO: 750360 events read in total (32972ms).
[10:43:37.897] <TB0> INFO: 1500410 events read in total (65842ms).
[10:44:10.830] <TB0> INFO: 2252140 events read in total (98775ms).
[10:44:43.798] <TB0> INFO: 3005730 events read in total (131743ms).
[10:45:16.746] <TB0> INFO: 3755760 events read in total (164691ms).
[10:45:49.780] <TB0> INFO: 4503750 events read in total (197725ms).
[10:46:22.752] <TB0> INFO: 5250750 events read in total (230697ms).
[10:46:55.631] <TB0> INFO: 5997540 events read in total (263576ms).
[10:47:08.304] <TB0> INFO: 6281600 events read in total (276249ms).
[10:47:08.345] <TB0> INFO: Test took 277099ms.
[10:47:30.898] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 57.6084 for pixel 47/30 mean/min/max = 44.9781/32.3475/57.6088
[10:47:30.898] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 57.1821 for pixel 18/1 mean/min/max = 44.839/32.4593/57.2187
[10:47:30.898] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 57.7876 for pixel 32/0 mean/min/max = 45.5845/33.2602/57.9087
[10:47:30.898] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 57.4691 for pixel 18/19 mean/min/max = 45.1759/32.7242/57.6275
[10:47:30.899] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 65.7327 for pixel 16/12 mean/min/max = 49.6107/33.3652/65.8562
[10:47:30.899] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 56.3408 for pixel 8/13 mean/min/max = 44.111/31.8093/56.4127
[10:47:30.899] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 56.822 for pixel 51/79 mean/min/max = 44.6442/32.1968/57.0915
[10:47:30.899] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 59.6614 for pixel 12/12 mean/min/max = 45.6288/31.593/59.6646
[10:47:30.900] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 65.2717 for pixel 3/79 mean/min/max = 48.4375/31.057/65.8179
[10:47:30.900] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 59.4207 for pixel 16/13 mean/min/max = 45.8308/32.1257/59.5359
[10:47:30.900] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 57.3086 for pixel 51/51 mean/min/max = 44.9083/32.4791/57.3375
[10:47:30.900] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 58.1089 for pixel 2/21 mean/min/max = 45.4628/32.7879/58.1376
[10:47:30.901] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 60.553 for pixel 25/20 mean/min/max = 46.7049/32.7896/60.6202
[10:47:30.901] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 67.2995 for pixel 51/6 mean/min/max = 49.2196/31.0806/67.3587
[10:47:30.901] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 62.2463 for pixel 7/12 mean/min/max = 47.1584/31.4692/62.8477
[10:47:30.901] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 61.6913 for pixel 8/8 mean/min/max = 46.4194/31.1255/61.7133
[10:47:30.902] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:47:30.990] <TB0> INFO: Expecting 514560 events.
[10:47:43.700] <TB0> INFO: 514560 events read in total (12153ms).
[10:47:43.706] <TB0> INFO: Expecting 514560 events.
[10:47:56.220] <TB0> INFO: 514560 events read in total (12091ms).
[10:47:56.227] <TB0> INFO: Expecting 514560 events.
[10:48:08.727] <TB0> INFO: 514560 events read in total (12077ms).
[10:48:08.736] <TB0> INFO: Expecting 514560 events.
[10:48:21.286] <TB0> INFO: 514560 events read in total (12129ms).
[10:48:21.297] <TB0> INFO: Expecting 514560 events.
[10:48:33.779] <TB0> INFO: 514560 events read in total (12066ms).
[10:48:33.792] <TB0> INFO: Expecting 514560 events.
[10:48:46.348] <TB0> INFO: 514560 events read in total (12139ms).
[10:48:46.362] <TB0> INFO: Expecting 514560 events.
[10:48:58.775] <TB0> INFO: 514560 events read in total (12001ms).
[10:48:58.792] <TB0> INFO: Expecting 514560 events.
[10:49:11.285] <TB0> INFO: 514560 events read in total (12078ms).
[10:49:11.303] <TB0> INFO: Expecting 514560 events.
[10:49:23.894] <TB0> INFO: 514560 events read in total (12181ms).
[10:49:23.916] <TB0> INFO: Expecting 514560 events.
[10:49:36.403] <TB0> INFO: 514560 events read in total (12083ms).
[10:49:36.425] <TB0> INFO: Expecting 514560 events.
[10:49:48.794] <TB0> INFO: 514560 events read in total (11963ms).
[10:49:48.817] <TB0> INFO: Expecting 514560 events.
[10:50:01.306] <TB0> INFO: 514560 events read in total (12078ms).
[10:50:01.331] <TB0> INFO: Expecting 514560 events.
[10:50:13.877] <TB0> INFO: 514560 events read in total (12139ms).
[10:50:13.903] <TB0> INFO: Expecting 514560 events.
[10:50:26.293] <TB0> INFO: 514560 events read in total (11986ms).
[10:50:26.321] <TB0> INFO: Expecting 514560 events.
[10:50:38.893] <TB0> INFO: 514560 events read in total (12165ms).
[10:50:38.922] <TB0> INFO: Expecting 514560 events.
[10:50:51.718] <TB0> INFO: 514560 events read in total (12394ms).
[10:50:51.750] <TB0> INFO: Test took 200848ms.
[10:50:52.618] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:50:52.626] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:50:52.626] <TB0> INFO: run 1 of 1
[10:50:52.879] <TB0> INFO: Expecting 6281600 events.
[10:51:26.580] <TB0> INFO: 737330 events read in total (33145ms).
[10:51:59.879] <TB0> INFO: 1474150 events read in total (66444ms).
[10:52:33.365] <TB0> INFO: 2212750 events read in total (99930ms).
[10:53:06.463] <TB0> INFO: 2953050 events read in total (133028ms).
[10:53:39.189] <TB0> INFO: 3688020 events read in total (165754ms).
[10:54:11.890] <TB0> INFO: 4418230 events read in total (198455ms).
[10:54:44.506] <TB0> INFO: 5146520 events read in total (231071ms).
[10:55:17.513] <TB0> INFO: 5874280 events read in total (264078ms).
[10:55:35.912] <TB0> INFO: 6281600 events read in total (282477ms).
[10:55:35.966] <TB0> INFO: Test took 283340ms.
[10:55:58.076] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.048294 .. 255.000000
[10:55:58.149] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:55:58.157] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:55:58.157] <TB0> INFO: run 1 of 1
[10:55:58.411] <TB0> INFO: Expecting 10649600 events.
[10:56:31.431] <TB0> INFO: 711820 events read in total (32464ms).
[10:57:04.062] <TB0> INFO: 1423370 events read in total (65095ms).
[10:57:36.451] <TB0> INFO: 2135600 events read in total (97484ms).
[10:58:09.013] <TB0> INFO: 2847700 events read in total (130047ms).
[10:58:41.537] <TB0> INFO: 3559990 events read in total (162570ms).
[10:59:14.086] <TB0> INFO: 4272520 events read in total (195119ms).
[10:59:46.377] <TB0> INFO: 4985110 events read in total (227410ms).
[11:00:18.835] <TB0> INFO: 5697210 events read in total (259868ms).
[11:00:51.297] <TB0> INFO: 6409410 events read in total (292330ms).
[11:01:23.715] <TB0> INFO: 7120900 events read in total (324748ms).
[11:01:56.221] <TB0> INFO: 7832050 events read in total (357254ms).
[11:02:28.612] <TB0> INFO: 8542670 events read in total (389645ms).
[11:03:00.972] <TB0> INFO: 9253150 events read in total (422005ms).
[11:03:33.372] <TB0> INFO: 9963150 events read in total (454405ms).
[11:04:04.654] <TB0> INFO: 10649600 events read in total (485687ms).
[11:04:04.759] <TB0> INFO: Test took 486602ms.
[11:04:32.183] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 0.205645 .. 46.889403
[11:04:32.256] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 0 .. 56 (-1/-1) hits flags = 528 (plus default)
[11:04:32.264] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[11:04:32.264] <TB0> INFO: run 1 of 1
[11:04:32.516] <TB0> INFO: Expecting 2371200 events.
[11:05:09.385] <TB0> INFO: 953470 events read in total (36313ms).
[11:05:45.472] <TB0> INFO: 1906160 events read in total (72400ms).
[11:06:03.266] <TB0> INFO: 2371200 events read in total (90194ms).
[11:06:03.280] <TB0> INFO: Test took 91016ms.
[11:06:15.757] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 2.053981 .. 47.305062
[11:06:15.829] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 2 .. 57 (-1/-1) hits flags = 528 (plus default)
[11:06:15.837] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[11:06:15.837] <TB0> INFO: run 1 of 1
[11:06:16.091] <TB0> INFO: Expecting 2329600 events.
[11:06:52.501] <TB0> INFO: 939630 events read in total (35854ms).
[11:07:28.584] <TB0> INFO: 1878760 events read in total (71937ms).
[11:07:46.010] <TB0> INFO: 2329600 events read in total (89363ms).
[11:07:46.021] <TB0> INFO: Test took 90183ms.
[11:07:58.722] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 2.053981 .. 47.305062
[11:07:58.794] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 57 (-1/-1) hits flags = 528 (plus default)
[11:07:58.802] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[11:07:58.802] <TB0> INFO: run 1 of 1
[11:07:59.056] <TB0> INFO: Expecting 2329600 events.
[11:08:35.577] <TB0> INFO: 939660 events read in total (35965ms).
[11:09:11.484] <TB0> INFO: 1879300 events read in total (71872ms).
[11:09:28.886] <TB0> INFO: 2329600 events read in total (89274ms).
[11:09:28.898] <TB0> INFO: Test took 90096ms.
[11:09:41.587] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:09:41.587] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:09:41.595] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[11:09:41.595] <TB0> INFO: run 1 of 1
[11:09:41.851] <TB0> INFO: Expecting 1705600 events.
[11:10:17.848] <TB0> INFO: 877170 events read in total (35441ms).
[11:10:51.429] <TB0> INFO: 1705600 events read in total (69022ms).
[11:10:51.448] <TB0> INFO: Test took 69853ms.
[11:11:03.856] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:11:03.856] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:11:03.856] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:11:03.856] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:11:03.856] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:11:03.856] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:11:03.856] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:11:03.857] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:11:03.857] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:11:03.857] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:11:03.857] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:11:03.857] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:11:03.857] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:11:03.857] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:11:03.857] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:11:03.858] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:11:03.858] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C0.dat
[11:11:03.868] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C1.dat
[11:11:03.879] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C2.dat
[11:11:03.889] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C3.dat
[11:11:03.900] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C4.dat
[11:11:03.910] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C5.dat
[11:11:03.921] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C6.dat
[11:11:03.932] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C7.dat
[11:11:03.942] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C8.dat
[11:11:03.953] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C9.dat
[11:11:03.963] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C10.dat
[11:11:03.974] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C11.dat
[11:11:03.984] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C12.dat
[11:11:03.995] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C13.dat
[11:11:04.005] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C14.dat
[11:11:04.016] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//trimParameters35_C15.dat
[11:11:04.026] <TB0> INFO: PixTestTrim::trimTest() done
[11:11:04.026] <TB0> INFO: vtrim: 98 97 91 101 108 94 85 108 123 97 82 86 92 97 111 115
[11:11:04.026] <TB0> INFO: vthrcomp: 86 85 89 85 96 97 103 101 91 93 87 89 93 91 102 93
[11:11:04.026] <TB0> INFO: vcal mean: 34.97 34.97 35.01 35.05 35.01 34.95 34.95 35.00 34.95 34.98 34.97 35.02 35.01 34.98 34.95 34.94
[11:11:04.026] <TB0> INFO: vcal RMS: 0.83 0.85 0.82 0.88 0.96 0.86 0.86 1.10 1.21 0.90 1.05 0.86 0.89 1.14 1.10 1.17
[11:11:04.026] <TB0> INFO: bits mean: 9.51 9.76 9.10 9.72 8.64 9.86 8.89 10.03 9.28 9.45 9.36 9.53 9.25 8.40 9.19 9.49
[11:11:04.026] <TB0> INFO: bits RMS: 2.63 2.53 2.66 2.50 2.56 2.68 2.99 2.46 2.80 2.72 2.72 2.56 2.59 2.92 2.80 2.76
[11:11:04.032] <TB0> INFO: ----------------------------------------------------------------------
[11:11:04.032] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:11:04.032] <TB0> INFO: ----------------------------------------------------------------------
[11:11:04.035] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:11:04.043] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:11:04.043] <TB0> INFO: run 1 of 1
[11:11:04.299] <TB0> INFO: Expecting 4160000 events.
[11:11:43.911] <TB0> INFO: 917145 events read in total (39056ms).
[11:12:23.029] <TB0> INFO: 1833635 events read in total (78174ms).
[11:13:02.227] <TB0> INFO: 2746940 events read in total (117373ms).
[11:13:41.453] <TB0> INFO: 3662015 events read in total (156598ms).
[11:14:03.024] <TB0> INFO: 4160000 events read in total (178169ms).
[11:14:03.061] <TB0> INFO: Test took 179019ms.
[11:14:29.716] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[11:14:29.724] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:14:29.724] <TB0> INFO: run 1 of 1
[11:14:29.978] <TB0> INFO: Expecting 3806400 events.
[11:15:09.694] <TB0> INFO: 918105 events read in total (39159ms).
[11:15:48.871] <TB0> INFO: 1836155 events read in total (78336ms).
[11:16:28.040] <TB0> INFO: 2749415 events read in total (117506ms).
[11:17:07.336] <TB0> INFO: 3668165 events read in total (156801ms).
[11:17:13.537] <TB0> INFO: 3806400 events read in total (163002ms).
[11:17:13.572] <TB0> INFO: Test took 163848ms.
[11:17:39.274] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[11:17:39.282] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:17:39.282] <TB0> INFO: run 1 of 1
[11:17:39.539] <TB0> INFO: Expecting 3515200 events.
[11:18:19.791] <TB0> INFO: 948515 events read in total (39696ms).
[11:18:59.790] <TB0> INFO: 1895255 events read in total (79695ms).
[11:19:39.536] <TB0> INFO: 2838910 events read in total (119441ms).
[11:20:08.015] <TB0> INFO: 3515200 events read in total (147920ms).
[11:20:08.056] <TB0> INFO: Test took 148774ms.
[11:20:32.316] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[11:20:32.324] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:20:32.324] <TB0> INFO: run 1 of 1
[11:20:32.580] <TB0> INFO: Expecting 3515200 events.
[11:21:12.914] <TB0> INFO: 948665 events read in total (39778ms).
[11:21:53.204] <TB0> INFO: 1895250 events read in total (80068ms).
[11:22:33.358] <TB0> INFO: 2838530 events read in total (120222ms).
[11:23:01.956] <TB0> INFO: 3515200 events read in total (148820ms).
[11:23:01.992] <TB0> INFO: Test took 149669ms.
[11:23:26.016] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[11:23:26.025] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:23:26.025] <TB0> INFO: run 1 of 1
[11:23:26.282] <TB0> INFO: Expecting 3536000 events.
[11:24:06.503] <TB0> INFO: 946045 events read in total (39664ms).
[11:24:46.221] <TB0> INFO: 1890340 events read in total (79382ms).
[11:25:26.096] <TB0> INFO: 2831055 events read in total (119257ms).
[11:25:55.943] <TB0> INFO: 3536000 events read in total (149104ms).
[11:25:55.978] <TB0> INFO: Test took 149954ms.
[11:26:20.039] <TB0> INFO: PixTestTrim::trimBitTest() done
[11:26:20.040] <TB0> INFO: PixTestTrim::doTest() done, duration: 2865 seconds
[11:26:20.040] <TB0> INFO: Decoding statistics:
[11:26:20.040] <TB0> INFO: General information:
[11:26:20.040] <TB0> INFO: 16bit words read: 0
[11:26:20.040] <TB0> INFO: valid events total: 0
[11:26:20.040] <TB0> INFO: empty events: 0
[11:26:20.040] <TB0> INFO: valid events with pixels: 0
[11:26:20.040] <TB0> INFO: valid pixel hits: 0
[11:26:20.040] <TB0> INFO: Event errors: 0
[11:26:20.040] <TB0> INFO: start marker: 0
[11:26:20.040] <TB0> INFO: stop marker: 0
[11:26:20.040] <TB0> INFO: overflow: 0
[11:26:20.040] <TB0> INFO: invalid 5bit words: 0
[11:26:20.040] <TB0> INFO: invalid XOR eye diagram: 0
[11:26:20.040] <TB0> INFO: frame (failed synchr.): 0
[11:26:20.040] <TB0> INFO: idle data (no TBM trl): 0
[11:26:20.040] <TB0> INFO: no data (only TBM hdr): 0
[11:26:20.040] <TB0> INFO: TBM errors: 0
[11:26:20.040] <TB0> INFO: flawed TBM headers: 0
[11:26:20.040] <TB0> INFO: flawed TBM trailers: 0
[11:26:20.040] <TB0> INFO: event ID mismatches: 0
[11:26:20.040] <TB0> INFO: ROC errors: 0
[11:26:20.040] <TB0> INFO: missing ROC header(s): 0
[11:26:20.040] <TB0> INFO: misplaced readback start: 0
[11:26:20.040] <TB0> INFO: Pixel decoding errors: 0
[11:26:20.040] <TB0> INFO: pixel data incomplete: 0
[11:26:20.040] <TB0> INFO: pixel address: 0
[11:26:20.040] <TB0> INFO: pulse height fill bit: 0
[11:26:20.040] <TB0> INFO: buffer corruption: 0
[11:26:20.695] <TB0> INFO: ######################################################################
[11:26:20.695] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:26:20.695] <TB0> INFO: ######################################################################
[11:26:20.949] <TB0> INFO: Expecting 41600 events.
[11:26:24.623] <TB0> INFO: 41600 events read in total (3117ms).
[11:26:24.623] <TB0> INFO: Test took 3927ms.
[11:26:25.083] <TB0> INFO: Expecting 41600 events.
[11:26:28.790] <TB0> INFO: 41600 events read in total (3151ms).
[11:26:28.791] <TB0> INFO: Test took 3961ms.
[11:26:29.063] <TB0> INFO: Expecting 41600 events.
[11:26:32.767] <TB0> INFO: 41600 events read in total (3147ms).
[11:26:32.768] <TB0> INFO: Test took 3957ms.
[11:26:33.022] <TB0> INFO: Expecting 2560 events.
[11:26:33.874] <TB0> INFO: 2560 events read in total (296ms).
[11:26:33.874] <TB0> INFO: Test took 1094ms.
[11:26:34.179] <TB0> INFO: Expecting 2560 events.
[11:26:35.031] <TB0> INFO: 2560 events read in total (297ms).
[11:26:35.032] <TB0> INFO: Test took 1158ms.
[11:26:35.337] <TB0> INFO: Expecting 2560 events.
[11:26:36.189] <TB0> INFO: 2560 events read in total (296ms).
[11:26:36.190] <TB0> INFO: Test took 1158ms.
[11:26:36.495] <TB0> INFO: Expecting 2560 events.
[11:26:37.346] <TB0> INFO: 2560 events read in total (295ms).
[11:26:37.347] <TB0> INFO: Test took 1157ms.
[11:26:37.652] <TB0> INFO: Expecting 2560 events.
[11:26:38.504] <TB0> INFO: 2560 events read in total (296ms).
[11:26:38.505] <TB0> INFO: Test took 1158ms.
[11:26:38.810] <TB0> INFO: Expecting 2560 events.
[11:26:39.661] <TB0> INFO: 2560 events read in total (295ms).
[11:26:39.661] <TB0> INFO: Test took 1156ms.
[11:26:39.967] <TB0> INFO: Expecting 2560 events.
[11:26:40.818] <TB0> INFO: 2560 events read in total (295ms).
[11:26:40.818] <TB0> INFO: Test took 1156ms.
[11:26:41.124] <TB0> INFO: Expecting 2560 events.
[11:26:41.975] <TB0> INFO: 2560 events read in total (295ms).
[11:26:41.975] <TB0> INFO: Test took 1156ms.
[11:26:42.281] <TB0> INFO: Expecting 2560 events.
[11:26:43.132] <TB0> INFO: 2560 events read in total (295ms).
[11:26:43.132] <TB0> INFO: Test took 1156ms.
[11:26:43.437] <TB0> INFO: Expecting 2560 events.
[11:26:44.289] <TB0> INFO: 2560 events read in total (295ms).
[11:26:44.289] <TB0> INFO: Test took 1156ms.
[11:26:44.594] <TB0> INFO: Expecting 2560 events.
[11:26:45.447] <TB0> INFO: 2560 events read in total (297ms).
[11:26:45.447] <TB0> INFO: Test took 1157ms.
[11:26:45.752] <TB0> INFO: Expecting 2560 events.
[11:26:46.604] <TB0> INFO: 2560 events read in total (295ms).
[11:26:46.604] <TB0> INFO: Test took 1156ms.
[11:26:46.909] <TB0> INFO: Expecting 2560 events.
[11:26:47.762] <TB0> INFO: 2560 events read in total (296ms).
[11:26:47.762] <TB0> INFO: Test took 1157ms.
[11:26:48.067] <TB0> INFO: Expecting 2560 events.
[11:26:48.920] <TB0> INFO: 2560 events read in total (296ms).
[11:26:48.920] <TB0> INFO: Test took 1158ms.
[11:26:49.225] <TB0> INFO: Expecting 2560 events.
[11:26:50.077] <TB0> INFO: 2560 events read in total (295ms).
[11:26:50.077] <TB0> INFO: Test took 1157ms.
[11:26:50.382] <TB0> INFO: Expecting 2560 events.
[11:26:51.235] <TB0> INFO: 2560 events read in total (297ms).
[11:26:51.235] <TB0> INFO: Test took 1158ms.
[11:26:51.238] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:26:51.541] <TB0> INFO: Expecting 655360 events.
[11:27:08.052] <TB0> INFO: 655360 events read in total (15955ms).
[11:27:08.061] <TB0> INFO: Expecting 655360 events.
[11:27:24.422] <TB0> INFO: 655360 events read in total (15959ms).
[11:27:24.433] <TB0> INFO: Expecting 655360 events.
[11:27:40.738] <TB0> INFO: 655360 events read in total (15903ms).
[11:27:40.753] <TB0> INFO: Expecting 655360 events.
[11:27:57.085] <TB0> INFO: 655360 events read in total (15930ms).
[11:27:57.104] <TB0> INFO: Expecting 655360 events.
[11:28:13.410] <TB0> INFO: 655360 events read in total (15905ms).
[11:28:13.432] <TB0> INFO: Expecting 655360 events.
[11:28:29.742] <TB0> INFO: 655360 events read in total (15908ms).
[11:28:29.767] <TB0> INFO: Expecting 655360 events.
[11:28:46.171] <TB0> INFO: 655360 events read in total (16002ms).
[11:28:46.200] <TB0> INFO: Expecting 655360 events.
[11:29:02.519] <TB0> INFO: 655360 events read in total (15917ms).
[11:29:02.550] <TB0> INFO: Expecting 655360 events.
[11:29:18.885] <TB0> INFO: 655360 events read in total (15933ms).
[11:29:18.921] <TB0> INFO: Expecting 655360 events.
[11:29:35.294] <TB0> INFO: 655360 events read in total (15969ms).
[11:29:35.333] <TB0> INFO: Expecting 655360 events.
[11:29:51.709] <TB0> INFO: 655360 events read in total (15974ms).
[11:29:51.751] <TB0> INFO: Expecting 655360 events.
[11:30:08.070] <TB0> INFO: 655360 events read in total (15917ms).
[11:30:08.116] <TB0> INFO: Expecting 655360 events.
[11:30:24.437] <TB0> INFO: 655360 events read in total (15919ms).
[11:30:24.485] <TB0> INFO: Expecting 655360 events.
[11:30:40.806] <TB0> INFO: 655360 events read in total (15919ms).
[11:30:40.856] <TB0> INFO: Expecting 655360 events.
[11:30:57.224] <TB0> INFO: 655360 events read in total (15966ms).
[11:30:57.278] <TB0> INFO: Expecting 655360 events.
[11:31:13.675] <TB0> INFO: 655360 events read in total (15995ms).
[11:31:13.733] <TB0> INFO: Test took 262495ms.
[11:31:13.809] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:31:14.036] <TB0> INFO: Expecting 655360 events.
[11:31:30.503] <TB0> INFO: 655360 events read in total (15911ms).
[11:31:30.511] <TB0> INFO: Expecting 655360 events.
[11:31:46.804] <TB0> INFO: 655360 events read in total (15891ms).
[11:31:46.815] <TB0> INFO: Expecting 655360 events.
[11:32:03.056] <TB0> INFO: 655360 events read in total (15839ms).
[11:32:03.071] <TB0> INFO: Expecting 655360 events.
[11:32:19.317] <TB0> INFO: 655360 events read in total (15844ms).
[11:32:19.336] <TB0> INFO: Expecting 655360 events.
[11:32:35.512] <TB0> INFO: 655360 events read in total (15774ms).
[11:32:35.534] <TB0> INFO: Expecting 655360 events.
[11:32:51.852] <TB0> INFO: 655360 events read in total (15916ms).
[11:32:51.877] <TB0> INFO: Expecting 655360 events.
[11:33:08.218] <TB0> INFO: 655360 events read in total (15939ms).
[11:33:08.249] <TB0> INFO: Expecting 655360 events.
[11:33:24.539] <TB0> INFO: 655360 events read in total (15888ms).
[11:33:24.571] <TB0> INFO: Expecting 655360 events.
[11:33:40.670] <TB0> INFO: 655360 events read in total (15697ms).
[11:33:40.705] <TB0> INFO: Expecting 655360 events.
[11:33:56.867] <TB0> INFO: 655360 events read in total (15761ms).
[11:33:56.904] <TB0> INFO: Expecting 655360 events.
[11:34:13.024] <TB0> INFO: 655360 events read in total (15718ms).
[11:34:13.065] <TB0> INFO: Expecting 655360 events.
[11:34:29.305] <TB0> INFO: 655360 events read in total (15839ms).
[11:34:29.350] <TB0> INFO: Expecting 655360 events.
[11:34:45.623] <TB0> INFO: 655360 events read in total (15871ms).
[11:34:45.670] <TB0> INFO: Expecting 655360 events.
[11:35:01.891] <TB0> INFO: 655360 events read in total (15819ms).
[11:35:01.942] <TB0> INFO: Expecting 655360 events.
[11:35:18.289] <TB0> INFO: 655360 events read in total (15945ms).
[11:35:18.344] <TB0> INFO: Expecting 655360 events.
[11:35:34.503] <TB0> INFO: 655360 events read in total (15757ms).
[11:35:34.562] <TB0> INFO: Test took 260753ms.
[11:35:34.745] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.752] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:35:34.758] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:35:34.765] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:35:34.771] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:35:34.778] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[11:35:34.784] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[11:35:34.791] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.797] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:35:34.804] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:35:34.810] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.817] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.823] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.830] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.836] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.843] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.849] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.856] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.862] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.868] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:35:34.875] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:35:34.881] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.888] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.894] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.901] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.907] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:35:34.943] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:35:34.943] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:35:34.943] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:35:34.944] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:35:34.944] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:35:34.944] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:35:34.944] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:35:34.944] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:35:34.945] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:35:34.945] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:35:34.945] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:35:34.945] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:35:34.945] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:35:34.945] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:35:34.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:35:34.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:35:35.208] <TB0> INFO: Expecting 41600 events.
[11:35:38.642] <TB0> INFO: 41600 events read in total (2877ms).
[11:35:38.643] <TB0> INFO: Test took 3694ms.
[11:35:39.074] <TB0> INFO: Expecting 41600 events.
[11:35:42.474] <TB0> INFO: 41600 events read in total (2843ms).
[11:35:42.475] <TB0> INFO: Test took 3654ms.
[11:35:42.906] <TB0> INFO: Expecting 41600 events.
[11:35:46.337] <TB0> INFO: 41600 events read in total (2875ms).
[11:35:46.337] <TB0> INFO: Test took 3684ms.
[11:35:46.519] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:46.607] <TB0> INFO: Expecting 2560 events.
[11:35:47.460] <TB0> INFO: 2560 events read in total (296ms).
[11:35:47.460] <TB0> INFO: Test took 941ms.
[11:35:47.462] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:47.766] <TB0> INFO: Expecting 2560 events.
[11:35:48.618] <TB0> INFO: 2560 events read in total (296ms).
[11:35:48.618] <TB0> INFO: Test took 1156ms.
[11:35:48.620] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:48.924] <TB0> INFO: Expecting 2560 events.
[11:35:49.776] <TB0> INFO: 2560 events read in total (296ms).
[11:35:49.776] <TB0> INFO: Test took 1156ms.
[11:35:49.778] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:50.082] <TB0> INFO: Expecting 2560 events.
[11:35:50.934] <TB0> INFO: 2560 events read in total (296ms).
[11:35:50.934] <TB0> INFO: Test took 1156ms.
[11:35:50.936] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:51.240] <TB0> INFO: Expecting 2560 events.
[11:35:52.092] <TB0> INFO: 2560 events read in total (296ms).
[11:35:52.092] <TB0> INFO: Test took 1156ms.
[11:35:52.094] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:52.397] <TB0> INFO: Expecting 2560 events.
[11:35:53.250] <TB0> INFO: 2560 events read in total (296ms).
[11:35:53.250] <TB0> INFO: Test took 1156ms.
[11:35:53.252] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:53.555] <TB0> INFO: Expecting 2560 events.
[11:35:54.408] <TB0> INFO: 2560 events read in total (296ms).
[11:35:54.408] <TB0> INFO: Test took 1156ms.
[11:35:54.410] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:54.713] <TB0> INFO: Expecting 2560 events.
[11:35:55.566] <TB0> INFO: 2560 events read in total (296ms).
[11:35:55.566] <TB0> INFO: Test took 1156ms.
[11:35:55.568] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:55.872] <TB0> INFO: Expecting 2560 events.
[11:35:56.724] <TB0> INFO: 2560 events read in total (296ms).
[11:35:56.724] <TB0> INFO: Test took 1156ms.
[11:35:56.725] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:57.029] <TB0> INFO: Expecting 2560 events.
[11:35:57.882] <TB0> INFO: 2560 events read in total (296ms).
[11:35:57.882] <TB0> INFO: Test took 1157ms.
[11:35:57.883] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:58.187] <TB0> INFO: Expecting 2560 events.
[11:35:59.040] <TB0> INFO: 2560 events read in total (297ms).
[11:35:59.040] <TB0> INFO: Test took 1157ms.
[11:35:59.041] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:59.345] <TB0> INFO: Expecting 2560 events.
[11:36:00.198] <TB0> INFO: 2560 events read in total (296ms).
[11:36:00.198] <TB0> INFO: Test took 1157ms.
[11:36:00.199] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:00.503] <TB0> INFO: Expecting 2560 events.
[11:36:01.355] <TB0> INFO: 2560 events read in total (295ms).
[11:36:01.356] <TB0> INFO: Test took 1157ms.
[11:36:01.357] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:01.661] <TB0> INFO: Expecting 2560 events.
[11:36:02.513] <TB0> INFO: 2560 events read in total (296ms).
[11:36:02.514] <TB0> INFO: Test took 1157ms.
[11:36:02.515] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:02.819] <TB0> INFO: Expecting 2560 events.
[11:36:03.671] <TB0> INFO: 2560 events read in total (296ms).
[11:36:03.671] <TB0> INFO: Test took 1156ms.
[11:36:03.673] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:03.977] <TB0> INFO: Expecting 2560 events.
[11:36:04.828] <TB0> INFO: 2560 events read in total (295ms).
[11:36:04.828] <TB0> INFO: Test took 1155ms.
[11:36:04.830] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:05.134] <TB0> INFO: Expecting 2560 events.
[11:36:05.985] <TB0> INFO: 2560 events read in total (295ms).
[11:36:05.985] <TB0> INFO: Test took 1155ms.
[11:36:05.987] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:06.291] <TB0> INFO: Expecting 2560 events.
[11:36:07.144] <TB0> INFO: 2560 events read in total (297ms).
[11:36:07.144] <TB0> INFO: Test took 1157ms.
[11:36:07.146] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:07.450] <TB0> INFO: Expecting 2560 events.
[11:36:08.303] <TB0> INFO: 2560 events read in total (297ms).
[11:36:08.303] <TB0> INFO: Test took 1157ms.
[11:36:08.305] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:08.609] <TB0> INFO: Expecting 2560 events.
[11:36:09.460] <TB0> INFO: 2560 events read in total (295ms).
[11:36:09.460] <TB0> INFO: Test took 1155ms.
[11:36:09.462] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:09.766] <TB0> INFO: Expecting 2560 events.
[11:36:10.619] <TB0> INFO: 2560 events read in total (297ms).
[11:36:10.619] <TB0> INFO: Test took 1157ms.
[11:36:10.621] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:10.925] <TB0> INFO: Expecting 2560 events.
[11:36:11.778] <TB0> INFO: 2560 events read in total (297ms).
[11:36:11.778] <TB0> INFO: Test took 1157ms.
[11:36:11.780] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:12.084] <TB0> INFO: Expecting 2560 events.
[11:36:12.937] <TB0> INFO: 2560 events read in total (297ms).
[11:36:12.937] <TB0> INFO: Test took 1157ms.
[11:36:12.939] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:13.243] <TB0> INFO: Expecting 2560 events.
[11:36:14.095] <TB0> INFO: 2560 events read in total (296ms).
[11:36:14.095] <TB0> INFO: Test took 1156ms.
[11:36:14.097] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:14.400] <TB0> INFO: Expecting 2560 events.
[11:36:15.253] <TB0> INFO: 2560 events read in total (296ms).
[11:36:15.253] <TB0> INFO: Test took 1157ms.
[11:36:15.255] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:15.558] <TB0> INFO: Expecting 2560 events.
[11:36:16.411] <TB0> INFO: 2560 events read in total (296ms).
[11:36:16.411] <TB0> INFO: Test took 1157ms.
[11:36:16.412] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:16.716] <TB0> INFO: Expecting 2560 events.
[11:36:17.569] <TB0> INFO: 2560 events read in total (296ms).
[11:36:17.569] <TB0> INFO: Test took 1157ms.
[11:36:17.570] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:17.874] <TB0> INFO: Expecting 2560 events.
[11:36:18.726] <TB0> INFO: 2560 events read in total (295ms).
[11:36:18.726] <TB0> INFO: Test took 1156ms.
[11:36:18.727] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:19.031] <TB0> INFO: Expecting 2560 events.
[11:36:19.884] <TB0> INFO: 2560 events read in total (296ms).
[11:36:19.884] <TB0> INFO: Test took 1157ms.
[11:36:19.885] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:20.189] <TB0> INFO: Expecting 2560 events.
[11:36:21.040] <TB0> INFO: 2560 events read in total (294ms).
[11:36:21.041] <TB0> INFO: Test took 1156ms.
[11:36:21.042] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:21.346] <TB0> INFO: Expecting 2560 events.
[11:36:22.199] <TB0> INFO: 2560 events read in total (296ms).
[11:36:22.200] <TB0> INFO: Test took 1158ms.
[11:36:22.201] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:36:22.506] <TB0> INFO: Expecting 2560 events.
[11:36:23.358] <TB0> INFO: 2560 events read in total (296ms).
[11:36:23.359] <TB0> INFO: Test took 1158ms.
[11:36:23.838] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 603 seconds
[11:36:23.838] <TB0> INFO: PH scale (per ROC): 85 80 85 79 69 83 81 82 78 79 80 78 69 66 77 71
[11:36:23.838] <TB0> INFO: PH offset (per ROC): 144 170 151 158 189 146 156 162 166 162 159 146 145 160 170 153
[11:36:23.841] <TB0> INFO: Decoding statistics:
[11:36:23.841] <TB0> INFO: General information:
[11:36:23.841] <TB0> INFO: 16bit words read: 91944
[11:36:23.841] <TB0> INFO: valid events total: 10240
[11:36:23.841] <TB0> INFO: empty events: 7734
[11:36:23.842] <TB0> INFO: valid events with pixels: 2506
[11:36:23.842] <TB0> INFO: valid pixel hits: 2506
[11:36:23.842] <TB0> INFO: Event errors: 0
[11:36:23.842] <TB0> INFO: start marker: 0
[11:36:23.842] <TB0> INFO: stop marker: 0
[11:36:23.842] <TB0> INFO: overflow: 0
[11:36:23.842] <TB0> INFO: invalid 5bit words: 0
[11:36:23.842] <TB0> INFO: invalid XOR eye diagram: 0
[11:36:23.842] <TB0> INFO: frame (failed synchr.): 0
[11:36:23.842] <TB0> INFO: idle data (no TBM trl): 0
[11:36:23.842] <TB0> INFO: no data (only TBM hdr): 0
[11:36:23.842] <TB0> INFO: TBM errors: 0
[11:36:23.842] <TB0> INFO: flawed TBM headers: 0
[11:36:23.842] <TB0> INFO: flawed TBM trailers: 0
[11:36:23.842] <TB0> INFO: event ID mismatches: 0
[11:36:23.842] <TB0> INFO: ROC errors: 0
[11:36:23.842] <TB0> INFO: missing ROC header(s): 0
[11:36:23.842] <TB0> INFO: misplaced readback start: 0
[11:36:23.842] <TB0> INFO: Pixel decoding errors: 0
[11:36:23.842] <TB0> INFO: pixel data incomplete: 0
[11:36:23.842] <TB0> INFO: pixel address: 0
[11:36:23.842] <TB0> INFO: pulse height fill bit: 0
[11:36:23.842] <TB0> INFO: buffer corruption: 0
[11:36:24.008] <TB0> INFO: ######################################################################
[11:36:24.008] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:36:24.008] <TB0> INFO: ######################################################################
[11:36:24.017] <TB0> INFO: scanning low vcal = 10
[11:36:24.271] <TB0> INFO: Expecting 41600 events.
[11:36:27.791] <TB0> INFO: 41600 events read in total (2964ms).
[11:36:27.791] <TB0> INFO: Test took 3774ms.
[11:36:27.793] <TB0> INFO: scanning low vcal = 20
[11:36:28.097] <TB0> INFO: Expecting 41600 events.
[11:36:31.629] <TB0> INFO: 41600 events read in total (2976ms).
[11:36:31.629] <TB0> INFO: Test took 3836ms.
[11:36:31.630] <TB0> INFO: scanning low vcal = 30
[11:36:31.935] <TB0> INFO: Expecting 41600 events.
[11:36:35.480] <TB0> INFO: 41600 events read in total (2989ms).
[11:36:35.481] <TB0> INFO: Test took 3851ms.
[11:36:35.483] <TB0> INFO: scanning low vcal = 40
[11:36:35.779] <TB0> INFO: Expecting 41600 events.
[11:36:39.864] <TB0> INFO: 41600 events read in total (3529ms).
[11:36:39.864] <TB0> INFO: Test took 4381ms.
[11:36:39.867] <TB0> INFO: scanning low vcal = 50
[11:36:40.120] <TB0> INFO: Expecting 41600 events.
[11:36:44.273] <TB0> INFO: 41600 events read in total (3596ms).
[11:36:44.274] <TB0> INFO: Test took 4407ms.
[11:36:44.276] <TB0> INFO: scanning low vcal = 60
[11:36:44.529] <TB0> INFO: Expecting 41600 events.
[11:36:48.683] <TB0> INFO: 41600 events read in total (3598ms).
[11:36:48.684] <TB0> INFO: Test took 4408ms.
[11:36:48.686] <TB0> INFO: scanning low vcal = 70
[11:36:48.939] <TB0> INFO: Expecting 41600 events.
[11:36:53.092] <TB0> INFO: 41600 events read in total (3597ms).
[11:36:53.092] <TB0> INFO: Test took 4406ms.
[11:36:53.094] <TB0> INFO: scanning low vcal = 80
[11:36:53.351] <TB0> INFO: Expecting 41600 events.
[11:36:57.501] <TB0> INFO: 41600 events read in total (3594ms).
[11:36:57.502] <TB0> INFO: Test took 4407ms.
[11:36:57.504] <TB0> INFO: scanning low vcal = 90
[11:36:57.756] <TB0> INFO: Expecting 41600 events.
[11:37:01.907] <TB0> INFO: 41600 events read in total (3595ms).
[11:37:01.907] <TB0> INFO: Test took 4403ms.
[11:37:01.910] <TB0> INFO: scanning low vcal = 100
[11:37:02.162] <TB0> INFO: Expecting 41600 events.
[11:37:06.313] <TB0> INFO: 41600 events read in total (3595ms).
[11:37:06.313] <TB0> INFO: Test took 4402ms.
[11:37:06.316] <TB0> INFO: scanning low vcal = 110
[11:37:06.569] <TB0> INFO: Expecting 41600 events.
[11:37:10.720] <TB0> INFO: 41600 events read in total (3596ms).
[11:37:10.721] <TB0> INFO: Test took 4405ms.
[11:37:10.723] <TB0> INFO: scanning low vcal = 120
[11:37:10.976] <TB0> INFO: Expecting 41600 events.
[11:37:15.130] <TB0> INFO: 41600 events read in total (3597ms).
[11:37:15.130] <TB0> INFO: Test took 4406ms.
[11:37:15.132] <TB0> INFO: scanning low vcal = 130
[11:37:15.388] <TB0> INFO: Expecting 41600 events.
[11:37:19.561] <TB0> INFO: 41600 events read in total (3616ms).
[11:37:19.561] <TB0> INFO: Test took 4428ms.
[11:37:19.563] <TB0> INFO: scanning low vcal = 140
[11:37:19.833] <TB0> INFO: Expecting 41600 events.
[11:37:24.003] <TB0> INFO: 41600 events read in total (3613ms).
[11:37:24.004] <TB0> INFO: Test took 4440ms.
[11:37:24.006] <TB0> INFO: scanning low vcal = 150
[11:37:24.267] <TB0> INFO: Expecting 41600 events.
[11:37:28.427] <TB0> INFO: 41600 events read in total (3603ms).
[11:37:28.427] <TB0> INFO: Test took 4421ms.
[11:37:28.430] <TB0> INFO: scanning low vcal = 160
[11:37:28.687] <TB0> INFO: Expecting 41600 events.
[11:37:32.844] <TB0> INFO: 41600 events read in total (3600ms).
[11:37:32.845] <TB0> INFO: Test took 4415ms.
[11:37:32.847] <TB0> INFO: scanning low vcal = 170
[11:37:33.104] <TB0> INFO: Expecting 41600 events.
[11:37:37.258] <TB0> INFO: 41600 events read in total (3598ms).
[11:37:37.259] <TB0> INFO: Test took 4412ms.
[11:37:37.263] <TB0> INFO: scanning low vcal = 180
[11:37:37.520] <TB0> INFO: Expecting 41600 events.
[11:37:41.672] <TB0> INFO: 41600 events read in total (3596ms).
[11:37:41.672] <TB0> INFO: Test took 4409ms.
[11:37:41.674] <TB0> INFO: scanning low vcal = 190
[11:37:41.927] <TB0> INFO: Expecting 41600 events.
[11:37:46.082] <TB0> INFO: 41600 events read in total (3599ms).
[11:37:46.082] <TB0> INFO: Test took 4407ms.
[11:37:46.084] <TB0> INFO: scanning low vcal = 200
[11:37:46.337] <TB0> INFO: Expecting 41600 events.
[11:37:50.527] <TB0> INFO: 41600 events read in total (3634ms).
[11:37:50.527] <TB0> INFO: Test took 4442ms.
[11:37:50.530] <TB0> INFO: scanning low vcal = 210
[11:37:50.783] <TB0> INFO: Expecting 41600 events.
[11:37:54.943] <TB0> INFO: 41600 events read in total (3604ms).
[11:37:54.944] <TB0> INFO: Test took 4414ms.
[11:37:54.946] <TB0> INFO: scanning low vcal = 220
[11:37:55.204] <TB0> INFO: Expecting 41600 events.
[11:37:59.357] <TB0> INFO: 41600 events read in total (3597ms).
[11:37:59.357] <TB0> INFO: Test took 4411ms.
[11:37:59.360] <TB0> INFO: scanning low vcal = 230
[11:37:59.614] <TB0> INFO: Expecting 41600 events.
[11:38:03.766] <TB0> INFO: 41600 events read in total (3595ms).
[11:38:03.767] <TB0> INFO: Test took 4407ms.
[11:38:03.769] <TB0> INFO: scanning low vcal = 240
[11:38:04.020] <TB0> INFO: Expecting 41600 events.
[11:38:08.170] <TB0> INFO: 41600 events read in total (3593ms).
[11:38:08.171] <TB0> INFO: Test took 4402ms.
[11:38:08.173] <TB0> INFO: scanning low vcal = 250
[11:38:08.424] <TB0> INFO: Expecting 41600 events.
[11:38:12.575] <TB0> INFO: 41600 events read in total (3594ms).
[11:38:12.576] <TB0> INFO: Test took 4403ms.
[11:38:12.580] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[11:38:12.832] <TB0> INFO: Expecting 41600 events.
[11:38:16.985] <TB0> INFO: 41600 events read in total (3596ms).
[11:38:16.986] <TB0> INFO: Test took 4406ms.
[11:38:16.988] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[11:38:17.240] <TB0> INFO: Expecting 41600 events.
[11:38:21.394] <TB0> INFO: 41600 events read in total (3597ms).
[11:38:21.394] <TB0> INFO: Test took 4406ms.
[11:38:21.397] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[11:38:21.649] <TB0> INFO: Expecting 41600 events.
[11:38:25.801] <TB0> INFO: 41600 events read in total (3596ms).
[11:38:25.802] <TB0> INFO: Test took 4405ms.
[11:38:25.804] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[11:38:26.059] <TB0> INFO: Expecting 41600 events.
[11:38:30.209] <TB0> INFO: 41600 events read in total (3593ms).
[11:38:30.210] <TB0> INFO: Test took 4406ms.
[11:38:30.212] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:38:30.466] <TB0> INFO: Expecting 41600 events.
[11:38:34.617] <TB0> INFO: 41600 events read in total (3595ms).
[11:38:34.617] <TB0> INFO: Test took 4405ms.
[11:38:35.153] <TB0> INFO: PixTestGainPedestal::measure() done
[11:39:05.818] <TB0> INFO: PixTestGainPedestal::fit() done
[11:39:05.818] <TB0> INFO: non-linearity mean: 0.962 0.961 0.962 0.961 0.959 0.962 0.957 0.956 0.953 0.950 0.958 0.953 0.955 0.961 0.951 0.953
[11:39:05.818] <TB0> INFO: non-linearity RMS: 0.005 0.005 0.005 0.006 0.009 0.005 0.006 0.006 0.008 0.007 0.006 0.007 0.006 0.005 0.008 0.008
[11:39:05.818] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:39:05.843] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:39:05.868] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:39:05.893] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:39:05.919] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:39:05.944] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:39:05.970] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:39:05.995] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:39:06.021] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:39:06.046] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:39:06.072] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:39:06.097] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:39:06.122] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:39:06.148] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:39:06.174] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:39:06.199] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:39:06.225] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 162 seconds
[11:39:06.225] <TB0> INFO: Decoding statistics:
[11:39:06.225] <TB0> INFO: General information:
[11:39:06.225] <TB0> INFO: 16bit words read: 2662400
[11:39:06.225] <TB0> INFO: valid events total: 166400
[11:39:06.225] <TB0> INFO: empty events: 0
[11:39:06.225] <TB0> INFO: valid events with pixels: 166400
[11:39:06.225] <TB0> INFO: valid pixel hits: 665508
[11:39:06.225] <TB0> INFO: Event errors: 0
[11:39:06.225] <TB0> INFO: start marker: 0
[11:39:06.225] <TB0> INFO: stop marker: 0
[11:39:06.225] <TB0> INFO: overflow: 0
[11:39:06.225] <TB0> INFO: invalid 5bit words: 0
[11:39:06.225] <TB0> INFO: invalid XOR eye diagram: 0
[11:39:06.225] <TB0> INFO: frame (failed synchr.): 0
[11:39:06.225] <TB0> INFO: idle data (no TBM trl): 0
[11:39:06.225] <TB0> INFO: no data (only TBM hdr): 0
[11:39:06.225] <TB0> INFO: TBM errors: 0
[11:39:06.225] <TB0> INFO: flawed TBM headers: 0
[11:39:06.225] <TB0> INFO: flawed TBM trailers: 0
[11:39:06.225] <TB0> INFO: event ID mismatches: 0
[11:39:06.225] <TB0> INFO: ROC errors: 0
[11:39:06.225] <TB0> INFO: missing ROC header(s): 0
[11:39:06.225] <TB0> INFO: misplaced readback start: 0
[11:39:06.225] <TB0> INFO: Pixel decoding errors: 0
[11:39:06.225] <TB0> INFO: pixel data incomplete: 0
[11:39:06.225] <TB0> INFO: pixel address: 0
[11:39:06.225] <TB0> INFO: pulse height fill bit: 0
[11:39:06.225] <TB0> INFO: buffer corruption: 0
[11:39:06.233] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C15.dat
[11:39:06.234] <TB0> INFO: ######################################################################
[11:39:06.234] <TB0> INFO: PixTestReadback::doTest()
[11:39:06.234] <TB0> INFO: ######################################################################
[11:39:06.234] <TB0> INFO: ----------------------------------------------------------------------
[11:39:06.234] <TB0> INFO: PixTestReadback::CalibrateVd()
[11:39:06.234] <TB0> INFO: ----------------------------------------------------------------------
[11:39:15.725] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C0.dat
[11:39:15.725] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C1.dat
[11:39:15.725] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C2.dat
[11:39:15.725] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C3.dat
[11:39:15.725] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C4.dat
[11:39:15.725] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C5.dat
[11:39:15.725] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C6.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C7.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C8.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C9.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C10.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C11.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C12.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C13.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C14.dat
[11:39:15.726] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C15.dat
[11:39:15.754] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:39:15.754] <TB0> INFO: ----------------------------------------------------------------------
[11:39:15.754] <TB0> INFO: PixTestReadback::CalibrateVa()
[11:39:15.754] <TB0> INFO: ----------------------------------------------------------------------
[11:39:25.210] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C0.dat
[11:39:25.210] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C1.dat
[11:39:25.211] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C2.dat
[11:39:25.211] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C3.dat
[11:39:25.211] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C4.dat
[11:39:25.211] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C5.dat
[11:39:25.211] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C6.dat
[11:39:25.211] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C7.dat
[11:39:25.211] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C8.dat
[11:39:25.211] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C9.dat
[11:39:25.212] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C10.dat
[11:39:25.212] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C11.dat
[11:39:25.212] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C12.dat
[11:39:25.212] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C13.dat
[11:39:25.212] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C14.dat
[11:39:25.212] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C15.dat
[11:39:25.240] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:39:25.240] <TB0> INFO: ----------------------------------------------------------------------
[11:39:25.240] <TB0> INFO: PixTestReadback::readbackVbg()
[11:39:25.240] <TB0> INFO: ----------------------------------------------------------------------
[11:39:32.551] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:39:32.551] <TB0> INFO: ----------------------------------------------------------------------
[11:39:32.551] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[11:39:32.551] <TB0> INFO: ----------------------------------------------------------------------
[11:39:32.551] <TB0> INFO: Vbg will be calibrated using Vd calibration
[11:39:32.551] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 145calibrated Vbg = 1.20694 :::*/*/*/*/
[11:39:32.551] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 140.1calibrated Vbg = 1.21329 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 141.7calibrated Vbg = 1.20899 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 145.8calibrated Vbg = 1.22244 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 147.9calibrated Vbg = 1.22273 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.9calibrated Vbg = 1.22497 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144.9calibrated Vbg = 1.22383 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.9calibrated Vbg = 1.22236 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.9calibrated Vbg = 1.22251 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 158.8calibrated Vbg = 1.22441 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 145.6calibrated Vbg = 1.22804 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 141.7calibrated Vbg = 1.21464 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 142.4calibrated Vbg = 1.21658 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148calibrated Vbg = 1.21583 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 150calibrated Vbg = 1.217 :::*/*/*/*/
[11:39:32.552] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.4calibrated Vbg = 1.21082 :::*/*/*/*/
[11:39:32.553] <TB0> INFO: ----------------------------------------------------------------------
[11:39:32.553] <TB0> INFO: PixTestReadback::CalibrateIa()
[11:39:32.553] <TB0> INFO: ----------------------------------------------------------------------
[11:42:07.796] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C0.dat
[11:42:07.796] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C1.dat
[11:42:07.796] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C2.dat
[11:42:07.796] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C3.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C4.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C5.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C6.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C7.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C8.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C9.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C10.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C11.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C12.dat
[11:42:07.797] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C13.dat
[11:42:07.798] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C14.dat
[11:42:07.798] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2270_FullQualification_2016-06-30_12h11m_1467281482//000_FulltestPxar_m20//readbackCal_C15.dat
[11:42:07.825] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:42:07.826] <TB0> INFO: PixTestReadback::doTest() done
[11:42:07.826] <TB0> INFO: Decoding statistics:
[11:42:07.826] <TB0> INFO: General information:
[11:42:07.826] <TB0> INFO: 16bit words read: 1024
[11:42:07.826] <TB0> INFO: valid events total: 128
[11:42:07.826] <TB0> INFO: empty events: 128
[11:42:07.826] <TB0> INFO: valid events with pixels: 0
[11:42:07.826] <TB0> INFO: valid pixel hits: 0
[11:42:07.826] <TB0> INFO: Event errors: 0
[11:42:07.826] <TB0> INFO: start marker: 0
[11:42:07.826] <TB0> INFO: stop marker: 0
[11:42:07.826] <TB0> INFO: overflow: 0
[11:42:07.826] <TB0> INFO: invalid 5bit words: 0
[11:42:07.826] <TB0> INFO: invalid XOR eye diagram: 0
[11:42:07.826] <TB0> INFO: frame (failed synchr.): 0
[11:42:07.826] <TB0> INFO: idle data (no TBM trl): 0
[11:42:07.826] <TB0> INFO: no data (only TBM hdr): 0
[11:42:07.826] <TB0> INFO: TBM errors: 0
[11:42:07.826] <TB0> INFO: flawed TBM headers: 0
[11:42:07.826] <TB0> INFO: flawed TBM trailers: 0
[11:42:07.826] <TB0> INFO: event ID mismatches: 0
[11:42:07.826] <TB0> INFO: ROC errors: 0
[11:42:07.826] <TB0> INFO: missing ROC header(s): 0
[11:42:07.826] <TB0> INFO: misplaced readback start: 0
[11:42:07.826] <TB0> INFO: Pixel decoding errors: 0
[11:42:07.826] <TB0> INFO: pixel data incomplete: 0
[11:42:07.826] <TB0> INFO: pixel address: 0
[11:42:07.826] <TB0> INFO: pulse height fill bit: 0
[11:42:07.826] <TB0> INFO: buffer corruption: 0
[11:42:07.846] <TB0> INFO: Decoding statistics:
[11:42:07.846] <TB0> INFO: General information:
[11:42:07.846] <TB0> INFO: 16bit words read: 2755368
[11:42:07.846] <TB0> INFO: valid events total: 176768
[11:42:07.846] <TB0> INFO: empty events: 7862
[11:42:07.846] <TB0> INFO: valid events with pixels: 168906
[11:42:07.846] <TB0> INFO: valid pixel hits: 668014
[11:42:07.846] <TB0> INFO: Event errors: 0
[11:42:07.846] <TB0> INFO: start marker: 0
[11:42:07.846] <TB0> INFO: stop marker: 0
[11:42:07.846] <TB0> INFO: overflow: 0
[11:42:07.846] <TB0> INFO: invalid 5bit words: 0
[11:42:07.846] <TB0> INFO: invalid XOR eye diagram: 0
[11:42:07.846] <TB0> INFO: frame (failed synchr.): 0
[11:42:07.846] <TB0> INFO: idle data (no TBM trl): 0
[11:42:07.846] <TB0> INFO: no data (only TBM hdr): 0
[11:42:07.846] <TB0> INFO: TBM errors: 0
[11:42:07.846] <TB0> INFO: flawed TBM headers: 0
[11:42:07.847] <TB0> INFO: flawed TBM trailers: 0
[11:42:07.847] <TB0> INFO: event ID mismatches: 0
[11:42:07.847] <TB0> INFO: ROC errors: 0
[11:42:07.847] <TB0> INFO: missing ROC header(s): 0
[11:42:07.847] <TB0> INFO: misplaced readback start: 0
[11:42:07.847] <TB0> INFO: Pixel decoding errors: 0
[11:42:07.847] <TB0> INFO: pixel data incomplete: 0
[11:42:07.847] <TB0> INFO: pixel address: 0
[11:42:07.847] <TB0> INFO: pulse height fill bit: 0
[11:42:07.847] <TB0> INFO: buffer corruption: 0
[11:42:07.847] <TB0> INFO: enter test to run
[11:42:07.847] <TB0> INFO: test: exit no parameter change
[11:42:08.008] <TB0> QUIET: Connection to board 73 closed.
[11:42:08.088] <TB0> INFO: pXar: this is the end, my friend