[10:28:14.633] <TB1> INFO: *** Welcome to pxar ***
[10:28:14.633] <TB1> INFO: *** Today: 2016/04/19
[10:28:14.740] <TB1> INFO: *** Version: 9751-dirty
[10:28:14.740] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C15.dat
[10:28:14.742] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:28:14.743] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//defaultMaskFile.dat
[10:28:14.743] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters_C15.dat
[10:28:14.827] <TB1> INFO: clk: 4
[10:28:14.827] <TB1> INFO: ctr: 4
[10:28:14.827] <TB1> INFO: sda: 19
[10:28:14.827] <TB1> INFO: tin: 9
[10:28:14.827] <TB1> INFO: level: 15
[10:28:14.827] <TB1> INFO: triggerdelay: 0
[10:28:14.827] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:28:14.827] <TB1> INFO: Log level: INFO
[10:28:14.832] <TB1> INFO: Found DTB DTB_WXBYFL
[10:28:14.841] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[10:28:14.843] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[10:28:14.845] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[10:28:16.380] <TB1> INFO: DUT info:
[10:28:16.380] <TB1> INFO: The DUT currently contains the following objects:
[10:28:16.380] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:28:16.380] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:28:16.380] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:28:16.380] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:28:16.380] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.381] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:16.782] <TB1> INFO: enter 'restricted' command line mode
[10:28:16.782] <TB1> INFO: enter test to run
[10:28:16.782] <TB1> INFO: test: pretest no parameter change
[10:28:16.782] <TB1> INFO: running: pretest
[10:28:16.788] <TB1> INFO: ----------------------------------------------------------------------
[10:28:16.788] <TB1> INFO: PixTestPretest::programROC()
[10:28:16.788] <TB1> INFO: ----------------------------------------------------------------------
[10:28:34.804] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:28:34.804] <TB1> INFO: IA differences per ROC: 19.3 19.3 21.7 19.3 18.5 20.1 18.5 20.1 18.5 18.5 20.9 19.3 22.5 20.1 20.9 21.7
[10:28:34.904] <TB1> INFO: enter test to run
[10:28:34.904] <TB1> INFO: test: pretest no parameter change
[10:28:34.904] <TB1> INFO: running: pretest
[10:28:34.905] <TB1> INFO: ----------------------------------------------------------------------
[10:28:34.905] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:28:34.905] <TB1> INFO: ----------------------------------------------------------------------
[10:28:41.696] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 391.5 mA = 24.4688 mA/ROC
[10:28:41.696] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 19.3 20.1 19.3 19.3 20.1 19.3 19.3 20.1 20.1 19.3 19.3 19.3 20.1
[10:28:41.729] <TB1> INFO: enter test to run
[10:28:41.730] <TB1> INFO: test: pretest no parameter change
[10:28:41.730] <TB1> INFO: running: pretest
[10:28:41.730] <TB1> INFO: ----------------------------------------------------------------------
[10:28:41.730] <TB1> INFO: PixTestPretest::findTiming()
[10:28:41.730] <TB1> INFO: ----------------------------------------------------------------------
[10:28:41.730] <TB1> INFO: PixTestCmd::init()

[10:30:12.084] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:30:12.095] <TB1> INFO: (success/tries = 100/100), width = 5
[10:30:12.096] <TB1> INFO: enter test to run
[10:30:12.096] <TB1> INFO: test: pretest no parameter change
[10:30:12.096] <TB1> INFO: running: pretest
[10:30:12.098] <TB1> INFO: ----------------------------------------------------------------------
[10:30:12.098] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:30:12.098] <TB1> INFO: ----------------------------------------------------------------------
[10:30:12.193] <TB1> INFO: Expecting 231680 events.


[10:30:22.281] <TB1> INFO: 231680 events read in total (9532ms).
[10:30:22.285] <TB1> INFO: Test took 10181ms.
[10:30:22.512] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:30:22.583] <TB1> INFO: enter test to run
[10:30:22.583] <TB1> INFO: test: pretest no parameter change
[10:30:22.583] <TB1> INFO: running: pretest
[10:30:22.584] <TB1> INFO: ----------------------------------------------------------------------
[10:30:22.584] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:30:22.584] <TB1> INFO: ----------------------------------------------------------------------
[10:30:22.676] <TB1> INFO: Expecting 231680 events.
[10:30:32.872] <TB1> INFO: 231680 events read in total (9640ms).
[10:30:32.875] <TB1> INFO: Test took 10287ms.
[10:30:33.113] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:30:33.113] <TB1> INFO: CalDel: 136 132 126 142 127 128 124 143 143 140 143 133 142 157 147 119
[10:30:33.113] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:30:33.146] <TB1> INFO: enter test to run
[10:30:33.146] <TB1> INFO: test: pretest no parameter change
[10:30:33.146] <TB1> INFO: running: pretest
[10:30:33.146] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C0.dat
[10:30:33.147] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C1.dat
[10:30:33.147] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C2.dat
[10:30:33.147] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C3.dat
[10:30:33.147] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C4.dat
[10:30:33.147] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C5.dat
[10:30:33.147] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C6.dat
[10:30:33.147] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C7.dat
[10:30:33.148] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C8.dat
[10:30:33.148] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C9.dat
[10:30:33.148] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C10.dat
[10:30:33.148] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C11.dat
[10:30:33.148] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C12.dat
[10:30:33.148] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C13.dat
[10:30:33.148] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C14.dat
[10:30:33.148] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters_C15.dat
[10:30:33.148] <TB1> INFO: enter test to run
[10:30:33.148] <TB1> INFO: test: fulltest no parameter change
[10:30:33.148] <TB1> INFO: running: fulltest
[10:30:33.148] <TB1> INFO: ######################################################################
[10:30:33.148] <TB1> INFO: PixTestFullTest::doTest()
[10:30:33.148] <TB1> INFO: ######################################################################
[10:30:33.149] <TB1> INFO: ######################################################################
[10:30:33.149] <TB1> INFO: PixTestAlive::doTest()
[10:30:33.149] <TB1> INFO: ######################################################################
[10:30:33.150] <TB1> INFO: ----------------------------------------------------------------------
[10:30:33.150] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:30:33.150] <TB1> INFO: ----------------------------------------------------------------------
[10:30:33.412] <TB1> INFO: Expecting 41600 events.
[10:30:37.275] <TB1> INFO: 41600 events read in total (3306ms).
[10:30:37.276] <TB1> INFO: Test took 4124ms.
[10:30:37.513] <TB1> INFO: PixTestAlive::aliveTest() done
[10:30:37.513] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:30:37.515] <TB1> INFO: ----------------------------------------------------------------------
[10:30:37.515] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:30:37.515] <TB1> INFO: ----------------------------------------------------------------------
[10:30:37.818] <TB1> INFO: Expecting 41600 events.
[10:30:40.793] <TB1> INFO: 41600 events read in total (2419ms).
[10:30:40.793] <TB1> INFO: Test took 3276ms.
[10:30:40.794] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:30:41.041] <TB1> INFO: PixTestAlive::maskTest() done
[10:30:41.042] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:30:41.043] <TB1> INFO: ----------------------------------------------------------------------
[10:30:41.043] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:30:41.043] <TB1> INFO: ----------------------------------------------------------------------
[10:30:41.337] <TB1> INFO: Expecting 41600 events.
[10:30:45.088] <TB1> INFO: 41600 events read in total (3195ms).
[10:30:45.089] <TB1> INFO: Test took 4044ms.
[10:30:45.318] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:30:45.318] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:30:45.318] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:30:45.318] <TB1> INFO: Decoding statistics:
[10:30:45.318] <TB1> INFO: General information:
[10:30:45.318] <TB1> INFO: 16bit words read: 0
[10:30:45.318] <TB1> INFO: valid events total: 0
[10:30:45.318] <TB1> INFO: empty events: 0
[10:30:45.318] <TB1> INFO: valid events with pixels: 0
[10:30:45.318] <TB1> INFO: valid pixel hits: 0
[10:30:45.318] <TB1> INFO: Event errors: 0
[10:30:45.318] <TB1> INFO: start marker: 0
[10:30:45.318] <TB1> INFO: stop marker: 0
[10:30:45.318] <TB1> INFO: overflow: 0
[10:30:45.318] <TB1> INFO: invalid 5bit words: 0
[10:30:45.318] <TB1> INFO: invalid XOR eye diagram: 0
[10:30:45.318] <TB1> INFO: frame (failed synchr.): 0
[10:30:45.318] <TB1> INFO: idle data (no TBM trl): 0
[10:30:45.318] <TB1> INFO: no data (only TBM hdr): 0
[10:30:45.318] <TB1> INFO: TBM errors: 0
[10:30:45.318] <TB1> INFO: flawed TBM headers: 0
[10:30:45.318] <TB1> INFO: flawed TBM trailers: 0
[10:30:45.318] <TB1> INFO: event ID mismatches: 0
[10:30:45.318] <TB1> INFO: ROC errors: 0
[10:30:45.318] <TB1> INFO: missing ROC header(s): 0
[10:30:45.318] <TB1> INFO: misplaced readback start: 0
[10:30:45.318] <TB1> INFO: Pixel decoding errors: 0
[10:30:45.318] <TB1> INFO: pixel data incomplete: 0
[10:30:45.318] <TB1> INFO: pixel address: 0
[10:30:45.318] <TB1> INFO: pulse height fill bit: 0
[10:30:45.318] <TB1> INFO: buffer corruption: 0
[10:30:45.325] <TB1> INFO: ######################################################################
[10:30:45.325] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:30:45.325] <TB1> INFO: ######################################################################
[10:30:45.327] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:30:45.339] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:30:45.339] <TB1> INFO: run 1 of 1
[10:30:45.602] <TB1> INFO: Expecting 3120000 events.
[10:31:27.545] <TB1> INFO: 867760 events read in total (41387ms).
[10:32:08.132] <TB1> INFO: 1724235 events read in total (81974ms).
[10:32:49.040] <TB1> INFO: 2589735 events read in total (122883ms).
[10:33:13.794] <TB1> INFO: 3120000 events read in total (147636ms).
[10:33:13.848] <TB1> INFO: Test took 148509ms.
[10:33:36.894] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[10:33:36.894] <TB1> INFO: number of dead bumps (per ROC): 3 3 3 2 1 1 0 1 4 0 0 0 2 0 3 3
[10:33:36.894] <TB1> INFO: separation cut (per ROC): 100 90 96 81 93 89 92 101 91 70 93 100 100 95 81 111
[10:33:36.894] <TB1> INFO: Decoding statistics:
[10:33:36.894] <TB1> INFO: General information:
[10:33:36.894] <TB1> INFO: 16bit words read: 0
[10:33:36.894] <TB1> INFO: valid events total: 0
[10:33:36.894] <TB1> INFO: empty events: 0
[10:33:36.894] <TB1> INFO: valid events with pixels: 0
[10:33:36.894] <TB1> INFO: valid pixel hits: 0
[10:33:36.894] <TB1> INFO: Event errors: 0
[10:33:36.894] <TB1> INFO: start marker: 0
[10:33:36.894] <TB1> INFO: stop marker: 0
[10:33:36.894] <TB1> INFO: overflow: 0
[10:33:36.894] <TB1> INFO: invalid 5bit words: 0
[10:33:36.894] <TB1> INFO: invalid XOR eye diagram: 0
[10:33:36.894] <TB1> INFO: frame (failed synchr.): 0
[10:33:36.894] <TB1> INFO: idle data (no TBM trl): 0
[10:33:36.894] <TB1> INFO: no data (only TBM hdr): 0
[10:33:36.894] <TB1> INFO: TBM errors: 0
[10:33:36.894] <TB1> INFO: flawed TBM headers: 0
[10:33:36.894] <TB1> INFO: flawed TBM trailers: 0
[10:33:36.894] <TB1> INFO: event ID mismatches: 0
[10:33:36.894] <TB1> INFO: ROC errors: 0
[10:33:36.894] <TB1> INFO: missing ROC header(s): 0
[10:33:36.894] <TB1> INFO: misplaced readback start: 0
[10:33:36.894] <TB1> INFO: Pixel decoding errors: 0
[10:33:36.894] <TB1> INFO: pixel data incomplete: 0
[10:33:36.894] <TB1> INFO: pixel address: 0
[10:33:36.894] <TB1> INFO: pulse height fill bit: 0
[10:33:36.894] <TB1> INFO: buffer corruption: 0
[10:33:36.965] <TB1> INFO: ######################################################################
[10:33:36.965] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:33:36.965] <TB1> INFO: ######################################################################
[10:33:36.965] <TB1> INFO: ----------------------------------------------------------------------
[10:33:36.965] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:33:36.965] <TB1> INFO: ----------------------------------------------------------------------
[10:33:36.965] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:33:36.973] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:33:36.973] <TB1> INFO: run 1 of 1
[10:33:37.232] <TB1> INFO: Expecting 26208000 events.
[10:34:11.015] <TB1> INFO: 910650 events read in total (33223ms).
[10:34:43.913] <TB1> INFO: 1805400 events read in total (66121ms).
[10:35:16.859] <TB1> INFO: 2698900 events read in total (99067ms).
[10:35:50.015] <TB1> INFO: 3590000 events read in total (132223ms).
[10:36:23.001] <TB1> INFO: 4479350 events read in total (165209ms).
[10:36:55.959] <TB1> INFO: 5368500 events read in total (198167ms).
[10:37:29.603] <TB1> INFO: 6259400 events read in total (231811ms).
[10:38:02.347] <TB1> INFO: 7148650 events read in total (264555ms).
[10:38:34.970] <TB1> INFO: 8036600 events read in total (297178ms).
[10:39:07.761] <TB1> INFO: 8924150 events read in total (329969ms).
[10:39:41.361] <TB1> INFO: 9812450 events read in total (363569ms).
[10:40:14.397] <TB1> INFO: 10701150 events read in total (396605ms).
[10:40:47.526] <TB1> INFO: 11589000 events read in total (429734ms).
[10:41:19.968] <TB1> INFO: 12476200 events read in total (462176ms).
[10:41:52.825] <TB1> INFO: 13360000 events read in total (495033ms).
[10:42:25.703] <TB1> INFO: 14239450 events read in total (527911ms).
[10:42:58.632] <TB1> INFO: 15116400 events read in total (560840ms).
[10:43:30.849] <TB1> INFO: 15991650 events read in total (593057ms).
[10:44:03.830] <TB1> INFO: 16867300 events read in total (626038ms).
[10:44:36.163] <TB1> INFO: 17743000 events read in total (658371ms).
[10:45:08.869] <TB1> INFO: 18616000 events read in total (691077ms).
[10:45:41.194] <TB1> INFO: 19489600 events read in total (723402ms).
[10:46:13.716] <TB1> INFO: 20363850 events read in total (755924ms).
[10:46:46.210] <TB1> INFO: 21236900 events read in total (788418ms).
[10:47:18.555] <TB1> INFO: 22110550 events read in total (820763ms).
[10:47:51.379] <TB1> INFO: 22985750 events read in total (853587ms).
[10:48:24.074] <TB1> INFO: 23862650 events read in total (886282ms).
[10:48:56.518] <TB1> INFO: 24739400 events read in total (918726ms).
[10:49:29.237] <TB1> INFO: 25614850 events read in total (951445ms).
[10:49:50.328] <TB1> INFO: 26208000 events read in total (972536ms).
[10:49:50.354] <TB1> INFO: Test took 973381ms.
[10:49:50.632] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:49:52.180] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:49:53.709] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:49:55.099] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:49:56.602] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:49:58.039] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:49:59.689] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:01.211] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:02.905] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:04.511] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:05.933] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:07.313] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:08.668] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:10.032] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:11.392] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:12.872] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:50:14.246] <TB1> INFO: PixTestScurves::scurves() done
[10:50:14.246] <TB1> INFO: Vcal mean: 100.04 89.17 85.51 76.23 94.13 90.90 102.07 96.60 93.93 77.03 86.54 95.63 88.85 92.85 79.85 98.39
[10:50:14.246] <TB1> INFO: Vcal RMS: 5.81 5.87 5.14 4.65 5.94 5.35 5.46 5.96 5.66 4.11 5.29 6.08 5.48 5.16 4.13 6.06
[10:50:14.247] <TB1> INFO: PixTestScurves::fullTest() done, duration: 997 seconds
[10:50:14.247] <TB1> INFO: Decoding statistics:
[10:50:14.247] <TB1> INFO: General information:
[10:50:14.247] <TB1> INFO: 16bit words read: 0
[10:50:14.247] <TB1> INFO: valid events total: 0
[10:50:14.247] <TB1> INFO: empty events: 0
[10:50:14.247] <TB1> INFO: valid events with pixels: 0
[10:50:14.247] <TB1> INFO: valid pixel hits: 0
[10:50:14.247] <TB1> INFO: Event errors: 0
[10:50:14.247] <TB1> INFO: start marker: 0
[10:50:14.247] <TB1> INFO: stop marker: 0
[10:50:14.247] <TB1> INFO: overflow: 0
[10:50:14.247] <TB1> INFO: invalid 5bit words: 0
[10:50:14.247] <TB1> INFO: invalid XOR eye diagram: 0
[10:50:14.247] <TB1> INFO: frame (failed synchr.): 0
[10:50:14.247] <TB1> INFO: idle data (no TBM trl): 0
[10:50:14.247] <TB1> INFO: no data (only TBM hdr): 0
[10:50:14.247] <TB1> INFO: TBM errors: 0
[10:50:14.247] <TB1> INFO: flawed TBM headers: 0
[10:50:14.247] <TB1> INFO: flawed TBM trailers: 0
[10:50:14.247] <TB1> INFO: event ID mismatches: 0
[10:50:14.247] <TB1> INFO: ROC errors: 0
[10:50:14.247] <TB1> INFO: missing ROC header(s): 0
[10:50:14.247] <TB1> INFO: misplaced readback start: 0
[10:50:14.247] <TB1> INFO: Pixel decoding errors: 0
[10:50:14.247] <TB1> INFO: pixel data incomplete: 0
[10:50:14.247] <TB1> INFO: pixel address: 0
[10:50:14.247] <TB1> INFO: pulse height fill bit: 0
[10:50:14.247] <TB1> INFO: buffer corruption: 0
[10:50:14.321] <TB1> INFO: ######################################################################
[10:50:14.321] <TB1> INFO: PixTestTrim::doTest()
[10:50:14.321] <TB1> INFO: ######################################################################
[10:50:14.322] <TB1> INFO: ----------------------------------------------------------------------
[10:50:14.322] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:50:14.322] <TB1> INFO: ----------------------------------------------------------------------
[10:50:14.400] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:50:14.400] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:50:14.407] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:50:14.407] <TB1> INFO: run 1 of 1
[10:50:14.666] <TB1> INFO: Expecting 6281600 events.
[10:50:53.885] <TB1> INFO: 1074000 events read in total (38661ms).
[10:51:33.495] <TB1> INFO: 2142150 events read in total (78271ms).
[10:52:13.407] <TB1> INFO: 3203780 events read in total (118183ms).
[10:52:51.919] <TB1> INFO: 4272330 events read in total (156695ms).
[10:53:31.015] <TB1> INFO: 5342830 events read in total (195791ms).
[10:54:04.685] <TB1> INFO: 6281600 events read in total (229461ms).
[10:54:04.721] <TB1> INFO: Test took 230313ms.
[10:54:23.469] <TB1> INFO: ROC 0 VthrComp = 101
[10:54:23.469] <TB1> INFO: ROC 1 VthrComp = 91
[10:54:23.469] <TB1> INFO: ROC 2 VthrComp = 92
[10:54:23.469] <TB1> INFO: ROC 3 VthrComp = 82
[10:54:23.469] <TB1> INFO: ROC 4 VthrComp = 97
[10:54:23.469] <TB1> INFO: ROC 5 VthrComp = 91
[10:54:23.469] <TB1> INFO: ROC 6 VthrComp = 101
[10:54:23.470] <TB1> INFO: ROC 7 VthrComp = 101
[10:54:23.470] <TB1> INFO: ROC 8 VthrComp = 95
[10:54:23.470] <TB1> INFO: ROC 9 VthrComp = 82
[10:54:23.470] <TB1> INFO: ROC 10 VthrComp = 93
[10:54:23.470] <TB1> INFO: ROC 11 VthrComp = 98
[10:54:23.470] <TB1> INFO: ROC 12 VthrComp = 95
[10:54:23.470] <TB1> INFO: ROC 13 VthrComp = 98
[10:54:23.470] <TB1> INFO: ROC 14 VthrComp = 84
[10:54:23.470] <TB1> INFO: ROC 15 VthrComp = 102
[10:54:23.470] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:54:23.470] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:54:23.478] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:54:23.478] <TB1> INFO: run 1 of 1
[10:54:23.737] <TB1> INFO: Expecting 6281600 events.
[10:54:59.142] <TB1> INFO: 754780 events read in total (34848ms).
[10:55:33.494] <TB1> INFO: 1507420 events read in total (69200ms).
[10:56:08.337] <TB1> INFO: 2260060 events read in total (104043ms).
[10:56:43.141] <TB1> INFO: 3013840 events read in total (138847ms).
[10:57:17.993] <TB1> INFO: 3762900 events read in total (173699ms).
[10:57:53.224] <TB1> INFO: 4508660 events read in total (208930ms).
[10:58:28.019] <TB1> INFO: 5252660 events read in total (243725ms).
[10:59:02.480] <TB1> INFO: 5997210 events read in total (278186ms).
[10:59:15.604] <TB1> INFO: 6281600 events read in total (291310ms).
[10:59:15.642] <TB1> INFO: Test took 292164ms.
[10:59:38.832] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.5595 for pixel 17/53 mean/min/max = 46.2211/31.7593/60.6828
[10:59:38.832] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 60.0416 for pixel 2/15 mean/min/max = 46.2926/32.4438/60.1415
[10:59:38.833] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.392 for pixel 8/79 mean/min/max = 44.5869/31.6599/57.514
[10:59:38.833] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.266 for pixel 0/60 mean/min/max = 44.7627/32.0788/57.4467
[10:59:38.833] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.7975 for pixel 12/70 mean/min/max = 44.9888/31.0838/58.8938
[10:59:38.833] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 58.4714 for pixel 14/48 mean/min/max = 45.5308/32.3399/58.7218
[10:59:38.834] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 59.4865 for pixel 2/0 mean/min/max = 46.1127/32.4675/59.7578
[10:59:38.834] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.2392 for pixel 1/7 mean/min/max = 45.1545/31.7044/58.6046
[10:59:38.834] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.7851 for pixel 4/73 mean/min/max = 45.523/32.2304/58.8155
[10:59:38.834] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 56.4875 for pixel 13/7 mean/min/max = 44.7545/32.781/56.728
[10:59:38.834] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.3497 for pixel 0/16 mean/min/max = 44.8482/31.9527/57.7438
[10:59:38.835] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.7116 for pixel 9/3 mean/min/max = 46.0481/31.3663/60.7299
[10:59:38.835] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.4127 for pixel 17/74 mean/min/max = 44.3255/31.2057/57.4453
[10:59:38.835] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.4187 for pixel 18/1 mean/min/max = 44.3541/31.9215/56.7867
[10:59:38.835] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.6054 for pixel 51/6 mean/min/max = 44.9027/33.0541/56.7512
[10:59:38.836] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 59.7705 for pixel 10/78 mean/min/max = 46.1832/32.5457/59.8207
[10:59:38.836] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:59:38.924] <TB1> INFO: Expecting 514560 events.
[10:59:51.786] <TB1> INFO: 514560 events read in total (12306ms).
[10:59:51.792] <TB1> INFO: Expecting 514560 events.
[11:00:04.476] <TB1> INFO: 514560 events read in total (12267ms).
[11:00:04.483] <TB1> INFO: Expecting 514560 events.
[11:00:17.065] <TB1> INFO: 514560 events read in total (12161ms).
[11:00:17.075] <TB1> INFO: Expecting 514560 events.
[11:00:29.680] <TB1> INFO: 514560 events read in total (12195ms).
[11:00:29.692] <TB1> INFO: Expecting 514560 events.
[11:00:42.337] <TB1> INFO: 514560 events read in total (12228ms).
[11:00:42.349] <TB1> INFO: Expecting 514560 events.
[11:00:55.083] <TB1> INFO: 514560 events read in total (12318ms).
[11:00:55.097] <TB1> INFO: Expecting 514560 events.
[11:01:07.892] <TB1> INFO: 514560 events read in total (12382ms).
[11:01:07.908] <TB1> INFO: Expecting 514560 events.
[11:01:20.775] <TB1> INFO: 514560 events read in total (12457ms).
[11:01:20.795] <TB1> INFO: Expecting 514560 events.
[11:01:33.514] <TB1> INFO: 514560 events read in total (12317ms).
[11:01:33.534] <TB1> INFO: Expecting 514560 events.
[11:01:46.416] <TB1> INFO: 514560 events read in total (12470ms).
[11:01:46.441] <TB1> INFO: Expecting 514560 events.
[11:01:59.228] <TB1> INFO: 514560 events read in total (12385ms).
[11:01:59.261] <TB1> INFO: Expecting 514560 events.
[11:02:11.957] <TB1> INFO: 514560 events read in total (12295ms).
[11:02:11.985] <TB1> INFO: Expecting 514560 events.
[11:02:24.732] <TB1> INFO: 514560 events read in total (12346ms).
[11:02:24.762] <TB1> INFO: Expecting 514560 events.
[11:02:37.572] <TB1> INFO: 514560 events read in total (12408ms).
[11:02:37.601] <TB1> INFO: Expecting 514560 events.
[11:02:50.334] <TB1> INFO: 514560 events read in total (12331ms).
[11:02:50.366] <TB1> INFO: Expecting 514560 events.
[11:03:03.274] <TB1> INFO: 514560 events read in total (12506ms).
[11:03:03.312] <TB1> INFO: Test took 204476ms.
[11:03:04.265] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:03:04.273] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:03:04.273] <TB1> INFO: run 1 of 1
[11:03:04.546] <TB1> INFO: Expecting 6281600 events.
[11:03:40.398] <TB1> INFO: 741830 events read in total (35296ms).
[11:04:15.038] <TB1> INFO: 1482380 events read in total (69936ms).
[11:04:49.689] <TB1> INFO: 2223010 events read in total (104588ms).
[11:05:24.267] <TB1> INFO: 2963650 events read in total (139165ms).
[11:05:58.824] <TB1> INFO: 3698710 events read in total (173722ms).
[11:06:33.022] <TB1> INFO: 4430060 events read in total (207920ms).
[11:07:07.127] <TB1> INFO: 5159360 events read in total (242025ms).
[11:07:41.319] <TB1> INFO: 5888820 events read in total (276217ms).
[11:07:59.927] <TB1> INFO: 6281600 events read in total (294825ms).
[11:07:59.971] <TB1> INFO: Test took 295698ms.
[11:08:23.531] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.056727 .. 255.000000
[11:08:23.607] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[11:08:23.615] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:08:23.615] <TB1> INFO: run 1 of 1
[11:08:23.876] <TB1> INFO: Expecting 10649600 events.
[11:08:58.188] <TB1> INFO: 712260 events read in total (33754ms).
[11:09:32.129] <TB1> INFO: 1424580 events read in total (67695ms).
[11:10:06.252] <TB1> INFO: 2137000 events read in total (101818ms).
[11:10:40.004] <TB1> INFO: 2849230 events read in total (135570ms).
[11:11:14.234] <TB1> INFO: 3561740 events read in total (169800ms).
[11:11:48.624] <TB1> INFO: 4274090 events read in total (204190ms).
[11:12:22.901] <TB1> INFO: 4986610 events read in total (238467ms).
[11:12:57.155] <TB1> INFO: 5699070 events read in total (272721ms).
[11:13:31.516] <TB1> INFO: 6411060 events read in total (307082ms).
[11:14:05.677] <TB1> INFO: 7122030 events read in total (341243ms).
[11:14:40.270] <TB1> INFO: 7833170 events read in total (375837ms).
[11:15:14.216] <TB1> INFO: 8543680 events read in total (409782ms).
[11:15:48.386] <TB1> INFO: 9254040 events read in total (443952ms).
[11:16:22.502] <TB1> INFO: 9964990 events read in total (478068ms).
[11:16:54.851] <TB1> INFO: 10649600 events read in total (510417ms).
[11:16:54.965] <TB1> INFO: Test took 511350ms.
[11:17:22.886] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.642298 .. 46.960009
[11:17:22.961] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 56 (-1/-1) hits flags = 528 (plus default)
[11:17:22.969] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:17:22.969] <TB1> INFO: run 1 of 1
[11:17:23.260] <TB1> INFO: Expecting 2204800 events.
[11:18:00.727] <TB1> INFO: 935990 events read in total (36910ms).
[11:18:38.561] <TB1> INFO: 1868700 events read in total (74744ms).
[11:18:52.454] <TB1> INFO: 2204800 events read in total (88637ms).
[11:18:52.472] <TB1> INFO: Test took 89503ms.
[11:19:06.772] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 12.022137 .. 44.790370
[11:19:06.849] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 2 .. 54 (-1/-1) hits flags = 528 (plus default)
[11:19:06.857] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:19:06.857] <TB1> INFO: run 1 of 1
[11:19:07.119] <TB1> INFO: Expecting 2204800 events.
[11:19:45.150] <TB1> INFO: 964100 events read in total (37475ms).
[11:20:23.176] <TB1> INFO: 1927460 events read in total (75501ms).
[11:20:34.280] <TB1> INFO: 2204800 events read in total (86606ms).
[11:20:34.300] <TB1> INFO: Test took 87444ms.
[11:20:48.672] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 12.022137 .. 44.790370
[11:20:48.749] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 54 (-1/-1) hits flags = 528 (plus default)
[11:20:48.757] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:20:48.757] <TB1> INFO: run 1 of 1
[11:20:49.023] <TB1> INFO: Expecting 2204800 events.
[11:21:27.811] <TB1> INFO: 962870 events read in total (38232ms).
[11:22:06.029] <TB1> INFO: 1925990 events read in total (76450ms).
[11:22:17.079] <TB1> INFO: 2204800 events read in total (87500ms).
[11:22:17.094] <TB1> INFO: Test took 88338ms.
[11:22:31.364] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:22:31.364] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:22:31.372] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:22:31.372] <TB1> INFO: run 1 of 1
[11:22:31.656] <TB1> INFO: Expecting 1705600 events.
[11:23:09.815] <TB1> INFO: 878880 events read in total (37602ms).
[11:23:44.093] <TB1> INFO: 1705600 events read in total (71880ms).
[11:23:44.115] <TB1> INFO: Test took 72744ms.
[11:23:57.283] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:23:57.283] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:23:57.283] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:23:57.283] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:23:57.283] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:23:57.283] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:23:57.283] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:23:57.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:23:57.285] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C0.dat
[11:23:57.290] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C1.dat
[11:23:57.296] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C2.dat
[11:23:57.302] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C3.dat
[11:23:57.308] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C4.dat
[11:23:57.314] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C5.dat
[11:23:57.320] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C6.dat
[11:23:57.326] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C7.dat
[11:23:57.332] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C8.dat
[11:23:57.338] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C9.dat
[11:23:57.344] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C10.dat
[11:23:57.349] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C11.dat
[11:23:57.355] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C12.dat
[11:23:57.361] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C13.dat
[11:23:57.367] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C14.dat
[11:23:57.373] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//trimParameters35_C15.dat
[11:23:57.379] <TB1> INFO: PixTestTrim::trimTest() done
[11:23:57.379] <TB1> INFO: vtrim: 109 100 84 86 94 97 101 109 89 101 90 96 86 91 84 95
[11:23:57.379] <TB1> INFO: vthrcomp: 101 91 92 82 97 91 101 101 95 82 93 98 95 98 84 102
[11:23:57.379] <TB1> INFO: vcal mean: 34.99 34.89 34.87 34.98 34.94 34.98 34.98 34.96 34.95 34.99 35.00 34.90 34.94 34.91 34.96 34.98
[11:23:57.379] <TB1> INFO: vcal RMS: 0.99 0.98 0.86 0.79 0.94 0.87 1.04 0.90 0.87 0.81 0.83 0.91 0.87 0.86 0.77 0.84
[11:23:57.379] <TB1> INFO: bits mean: 9.44 9.35 9.12 9.25 9.87 9.53 9.22 9.83 9.21 9.68 9.27 9.39 9.79 9.66 9.21 8.78
[11:23:57.379] <TB1> INFO: bits RMS: 2.65 2.69 2.97 2.87 2.72 2.66 2.73 2.61 2.81 2.58 2.90 2.81 2.78 2.71 2.76 2.89
[11:23:57.385] <TB1> INFO: ----------------------------------------------------------------------
[11:23:57.385] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:23:57.385] <TB1> INFO: ----------------------------------------------------------------------
[11:23:57.388] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:23:57.397] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:23:57.397] <TB1> INFO: run 1 of 1
[11:23:57.656] <TB1> INFO: Expecting 4160000 events.
[11:24:39.771] <TB1> INFO: 933085 events read in total (41558ms).
[11:25:21.247] <TB1> INFO: 1860695 events read in total (83034ms).
[11:26:02.195] <TB1> INFO: 2780905 events read in total (123983ms).
[11:26:44.093] <TB1> INFO: 3698900 events read in total (165880ms).
[11:27:04.769] <TB1> INFO: 4160000 events read in total (186556ms).
[11:27:04.808] <TB1> INFO: Test took 187411ms.
[11:27:33.875] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[11:27:33.883] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:27:33.883] <TB1> INFO: run 1 of 1
[11:27:34.143] <TB1> INFO: Expecting 3744000 events.
[11:28:16.728] <TB1> INFO: 940295 events read in total (42029ms).
[11:28:58.729] <TB1> INFO: 1874580 events read in total (84030ms).
[11:29:40.111] <TB1> INFO: 2799880 events read in total (125413ms).
[11:30:22.299] <TB1> INFO: 3727935 events read in total (167600ms).
[11:30:23.372] <TB1> INFO: 3744000 events read in total (168673ms).
[11:30:23.408] <TB1> INFO: Test took 169525ms.
[11:30:50.712] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[11:30:50.720] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:30:50.720] <TB1> INFO: run 1 of 1
[11:30:50.981] <TB1> INFO: Expecting 3390400 events.
[11:31:33.870] <TB1> INFO: 981590 events read in total (42333ms).
[11:32:16.238] <TB1> INFO: 1954140 events read in total (84701ms).
[11:32:58.466] <TB1> INFO: 2917640 events read in total (126930ms).
[11:33:19.089] <TB1> INFO: 3390400 events read in total (147552ms).
[11:33:19.121] <TB1> INFO: Test took 148401ms.
[11:33:45.702] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[11:33:45.713] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:33:45.713] <TB1> INFO: run 1 of 1
[11:33:46.045] <TB1> INFO: Expecting 3369600 events.
[11:34:29.313] <TB1> INFO: 983415 events read in total (42712ms).
[11:35:11.882] <TB1> INFO: 1957750 events read in total (85281ms).
[11:35:53.918] <TB1> INFO: 2923120 events read in total (127317ms).
[11:36:13.558] <TB1> INFO: 3369600 events read in total (146957ms).
[11:36:13.596] <TB1> INFO: Test took 147882ms.
[11:36:39.767] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[11:36:39.775] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:36:39.775] <TB1> INFO: run 1 of 1
[11:36:40.034] <TB1> INFO: Expecting 3390400 events.
[11:37:23.862] <TB1> INFO: 980045 events read in total (43272ms).
[11:38:06.318] <TB1> INFO: 1950860 events read in total (85728ms).
[11:38:48.442] <TB1> INFO: 2912805 events read in total (127852ms).
[11:39:09.781] <TB1> INFO: 3390400 events read in total (149191ms).
[11:39:09.815] <TB1> INFO: Test took 150040ms.
[11:39:34.877] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:39:34.878] <TB1> INFO: PixTestTrim::doTest() done, duration: 2960 seconds
[11:39:34.878] <TB1> INFO: Decoding statistics:
[11:39:34.878] <TB1> INFO: General information:
[11:39:34.878] <TB1> INFO: 16bit words read: 0
[11:39:34.878] <TB1> INFO: valid events total: 0
[11:39:34.878] <TB1> INFO: empty events: 0
[11:39:34.878] <TB1> INFO: valid events with pixels: 0
[11:39:34.878] <TB1> INFO: valid pixel hits: 0
[11:39:34.878] <TB1> INFO: Event errors: 0
[11:39:34.878] <TB1> INFO: start marker: 0
[11:39:34.878] <TB1> INFO: stop marker: 0
[11:39:34.878] <TB1> INFO: overflow: 0
[11:39:34.878] <TB1> INFO: invalid 5bit words: 0
[11:39:34.878] <TB1> INFO: invalid XOR eye diagram: 0
[11:39:34.878] <TB1> INFO: frame (failed synchr.): 0
[11:39:34.878] <TB1> INFO: idle data (no TBM trl): 0
[11:39:34.878] <TB1> INFO: no data (only TBM hdr): 0
[11:39:34.878] <TB1> INFO: TBM errors: 0
[11:39:34.878] <TB1> INFO: flawed TBM headers: 0
[11:39:34.878] <TB1> INFO: flawed TBM trailers: 0
[11:39:34.878] <TB1> INFO: event ID mismatches: 0
[11:39:34.878] <TB1> INFO: ROC errors: 0
[11:39:34.878] <TB1> INFO: missing ROC header(s): 0
[11:39:34.878] <TB1> INFO: misplaced readback start: 0
[11:39:34.878] <TB1> INFO: Pixel decoding errors: 0
[11:39:34.878] <TB1> INFO: pixel data incomplete: 0
[11:39:34.878] <TB1> INFO: pixel address: 0
[11:39:34.878] <TB1> INFO: pulse height fill bit: 0
[11:39:34.878] <TB1> INFO: buffer corruption: 0
[11:39:35.538] <TB1> INFO: ######################################################################
[11:39:35.538] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:39:35.538] <TB1> INFO: ######################################################################
[11:39:35.795] <TB1> INFO: Expecting 41600 events.
[11:39:39.608] <TB1> INFO: 41600 events read in total (3257ms).
[11:39:39.608] <TB1> INFO: Test took 4069ms.
[11:39:40.083] <TB1> INFO: Expecting 41600 events.
[11:39:43.935] <TB1> INFO: 41600 events read in total (3295ms).
[11:39:43.935] <TB1> INFO: Test took 4121ms.
[11:39:44.287] <TB1> INFO: Expecting 41600 events.
[11:39:48.054] <TB1> INFO: 41600 events read in total (3211ms).
[11:39:48.054] <TB1> INFO: Test took 4097ms.
[11:39:48.309] <TB1> INFO: Expecting 2560 events.
[11:39:49.161] <TB1> INFO: 2560 events read in total (295ms).
[11:39:49.161] <TB1> INFO: Test took 1094ms.
[11:39:49.466] <TB1> INFO: Expecting 2560 events.
[11:39:50.321] <TB1> INFO: 2560 events read in total (299ms).
[11:39:50.321] <TB1> INFO: Test took 1160ms.
[11:39:50.626] <TB1> INFO: Expecting 2560 events.
[11:39:51.479] <TB1> INFO: 2560 events read in total (297ms).
[11:39:51.479] <TB1> INFO: Test took 1157ms.
[11:39:51.784] <TB1> INFO: Expecting 2560 events.
[11:39:52.639] <TB1> INFO: 2560 events read in total (299ms).
[11:39:52.639] <TB1> INFO: Test took 1159ms.
[11:39:52.944] <TB1> INFO: Expecting 2560 events.
[11:39:53.798] <TB1> INFO: 2560 events read in total (298ms).
[11:39:53.798] <TB1> INFO: Test took 1159ms.
[11:39:54.103] <TB1> INFO: Expecting 2560 events.
[11:39:54.958] <TB1> INFO: 2560 events read in total (299ms).
[11:39:54.958] <TB1> INFO: Test took 1160ms.
[11:39:55.264] <TB1> INFO: Expecting 2560 events.
[11:39:56.117] <TB1> INFO: 2560 events read in total (297ms).
[11:39:56.117] <TB1> INFO: Test took 1159ms.
[11:39:56.422] <TB1> INFO: Expecting 2560 events.
[11:39:57.275] <TB1> INFO: 2560 events read in total (297ms).
[11:39:57.275] <TB1> INFO: Test took 1158ms.
[11:39:57.579] <TB1> INFO: Expecting 2560 events.
[11:39:58.432] <TB1> INFO: 2560 events read in total (296ms).
[11:39:58.433] <TB1> INFO: Test took 1158ms.
[11:39:58.737] <TB1> INFO: Expecting 2560 events.
[11:39:59.595] <TB1> INFO: 2560 events read in total (301ms).
[11:39:59.595] <TB1> INFO: Test took 1162ms.
[11:39:59.900] <TB1> INFO: Expecting 2560 events.
[11:40:00.751] <TB1> INFO: 2560 events read in total (295ms).
[11:40:00.751] <TB1> INFO: Test took 1155ms.
[11:40:01.057] <TB1> INFO: Expecting 2560 events.
[11:40:01.909] <TB1> INFO: 2560 events read in total (296ms).
[11:40:01.910] <TB1> INFO: Test took 1158ms.
[11:40:02.215] <TB1> INFO: Expecting 2560 events.
[11:40:03.068] <TB1> INFO: 2560 events read in total (297ms).
[11:40:03.069] <TB1> INFO: Test took 1159ms.
[11:40:03.374] <TB1> INFO: Expecting 2560 events.
[11:40:04.228] <TB1> INFO: 2560 events read in total (297ms).
[11:40:04.229] <TB1> INFO: Test took 1160ms.
[11:40:04.533] <TB1> INFO: Expecting 2560 events.
[11:40:05.386] <TB1> INFO: 2560 events read in total (296ms).
[11:40:05.386] <TB1> INFO: Test took 1158ms.
[11:40:05.691] <TB1> INFO: Expecting 2560 events.
[11:40:06.547] <TB1> INFO: 2560 events read in total (299ms).
[11:40:06.548] <TB1> INFO: Test took 1161ms.
[11:40:06.550] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:40:06.855] <TB1> INFO: Expecting 655360 events.
[11:40:23.810] <TB1> INFO: 655360 events read in total (16398ms).
[11:40:23.818] <TB1> INFO: Expecting 655360 events.
[11:40:40.571] <TB1> INFO: 655360 events read in total (16351ms).
[11:40:40.584] <TB1> INFO: Expecting 655360 events.
[11:40:57.171] <TB1> INFO: 655360 events read in total (16185ms).
[11:40:57.191] <TB1> INFO: Expecting 655360 events.
[11:41:13.884] <TB1> INFO: 655360 events read in total (16291ms).
[11:41:13.903] <TB1> INFO: Expecting 655360 events.
[11:41:30.554] <TB1> INFO: 655360 events read in total (16249ms).
[11:41:30.582] <TB1> INFO: Expecting 655360 events.
[11:41:47.182] <TB1> INFO: 655360 events read in total (16198ms).
[11:41:47.207] <TB1> INFO: Expecting 655360 events.
[11:42:03.839] <TB1> INFO: 655360 events read in total (16230ms).
[11:42:03.875] <TB1> INFO: Expecting 655360 events.
[11:42:20.489] <TB1> INFO: 655360 events read in total (16212ms).
[11:42:20.526] <TB1> INFO: Expecting 655360 events.
[11:42:37.239] <TB1> INFO: 655360 events read in total (16311ms).
[11:42:37.285] <TB1> INFO: Expecting 655360 events.
[11:42:53.818] <TB1> INFO: 655360 events read in total (16131ms).
[11:42:53.870] <TB1> INFO: Expecting 655360 events.
[11:43:10.569] <TB1> INFO: 655360 events read in total (16297ms).
[11:43:10.612] <TB1> INFO: Expecting 655360 events.
[11:43:27.329] <TB1> INFO: 655360 events read in total (16315ms).
[11:43:27.388] <TB1> INFO: Expecting 655360 events.
[11:43:44.084] <TB1> INFO: 655360 events read in total (16294ms).
[11:43:44.137] <TB1> INFO: Expecting 655360 events.
[11:44:00.736] <TB1> INFO: 655360 events read in total (16197ms).
[11:44:00.791] <TB1> INFO: Expecting 655360 events.
[11:44:17.546] <TB1> INFO: 655360 events read in total (16354ms).
[11:44:17.606] <TB1> INFO: Expecting 655360 events.
[11:44:34.168] <TB1> INFO: 655360 events read in total (16160ms).
[11:44:34.244] <TB1> INFO: Test took 267694ms.
[11:44:34.338] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:44:34.566] <TB1> INFO: Expecting 655360 events.
[11:44:51.189] <TB1> INFO: 655360 events read in total (16067ms).
[11:44:51.197] <TB1> INFO: Expecting 655360 events.
[11:45:07.881] <TB1> INFO: 655360 events read in total (16282ms).
[11:45:07.894] <TB1> INFO: Expecting 655360 events.
[11:45:24.574] <TB1> INFO: 655360 events read in total (16278ms).
[11:45:24.589] <TB1> INFO: Expecting 655360 events.
[11:45:41.215] <TB1> INFO: 655360 events read in total (16224ms).
[11:45:41.234] <TB1> INFO: Expecting 655360 events.
[11:45:57.804] <TB1> INFO: 655360 events read in total (16168ms).
[11:45:57.826] <TB1> INFO: Expecting 655360 events.
[11:46:14.332] <TB1> INFO: 655360 events read in total (16105ms).
[11:46:14.358] <TB1> INFO: Expecting 655360 events.
[11:46:30.907] <TB1> INFO: 655360 events read in total (16148ms).
[11:46:30.940] <TB1> INFO: Expecting 655360 events.
[11:46:47.499] <TB1> INFO: 655360 events read in total (16157ms).
[11:46:47.537] <TB1> INFO: Expecting 655360 events.
[11:47:04.162] <TB1> INFO: 655360 events read in total (16223ms).
[11:47:04.205] <TB1> INFO: Expecting 655360 events.
[11:47:20.862] <TB1> INFO: 655360 events read in total (16257ms).
[11:47:20.902] <TB1> INFO: Expecting 655360 events.
[11:47:37.456] <TB1> INFO: 655360 events read in total (16152ms).
[11:47:37.510] <TB1> INFO: Expecting 655360 events.
[11:47:54.070] <TB1> INFO: 655360 events read in total (16158ms).
[11:47:54.117] <TB1> INFO: Expecting 655360 events.
[11:48:10.747] <TB1> INFO: 655360 events read in total (16228ms).
[11:48:10.801] <TB1> INFO: Expecting 655360 events.
[11:48:27.544] <TB1> INFO: 655360 events read in total (16341ms).
[11:48:27.599] <TB1> INFO: Expecting 655360 events.
[11:48:44.243] <TB1> INFO: 655360 events read in total (16242ms).
[11:48:44.304] <TB1> INFO: Expecting 655360 events.
[11:49:00.960] <TB1> INFO: 655360 events read in total (16254ms).
[11:49:01.028] <TB1> INFO: Test took 266690ms.
[11:49:01.239] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.246] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.256] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.265] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.275] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.285] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.294] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.301] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.308] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.317] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.327] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.335] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:49:01.343] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:49:01.353] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:49:01.361] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[11:49:01.367] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.374] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.380] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.387] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.396] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:49:01.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:49:01.472] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:49:01.472] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:49:01.472] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:49:01.472] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:49:01.472] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:49:01.472] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:49:01.472] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:49:01.472] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:49:01.473] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:49:01.473] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:49:01.473] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:49:01.473] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:49:01.473] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:49:01.473] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:49:01.473] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:49:01.735] <TB1> INFO: Expecting 41600 events.
[11:49:05.277] <TB1> INFO: 41600 events read in total (2986ms).
[11:49:05.277] <TB1> INFO: Test took 3801ms.
[11:49:05.755] <TB1> INFO: Expecting 41600 events.
[11:49:09.242] <TB1> INFO: 41600 events read in total (2931ms).
[11:49:09.243] <TB1> INFO: Test took 3786ms.
[11:49:09.682] <TB1> INFO: Expecting 41600 events.
[11:49:13.150] <TB1> INFO: 41600 events read in total (2912ms).
[11:49:13.150] <TB1> INFO: Test took 3727ms.
[11:49:13.336] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:13.424] <TB1> INFO: Expecting 2560 events.
[11:49:14.276] <TB1> INFO: 2560 events read in total (296ms).
[11:49:14.277] <TB1> INFO: Test took 941ms.
[11:49:14.278] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:14.582] <TB1> INFO: Expecting 2560 events.
[11:49:15.437] <TB1> INFO: 2560 events read in total (299ms).
[11:49:15.437] <TB1> INFO: Test took 1159ms.
[11:49:15.439] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:15.742] <TB1> INFO: Expecting 2560 events.
[11:49:16.595] <TB1> INFO: 2560 events read in total (296ms).
[11:49:16.596] <TB1> INFO: Test took 1157ms.
[11:49:16.597] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:16.901] <TB1> INFO: Expecting 2560 events.
[11:49:17.753] <TB1> INFO: 2560 events read in total (296ms).
[11:49:17.754] <TB1> INFO: Test took 1157ms.
[11:49:17.762] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:18.059] <TB1> INFO: Expecting 2560 events.
[11:49:18.919] <TB1> INFO: 2560 events read in total (304ms).
[11:49:18.919] <TB1> INFO: Test took 1157ms.
[11:49:18.922] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:19.224] <TB1> INFO: Expecting 2560 events.
[11:49:20.077] <TB1> INFO: 2560 events read in total (297ms).
[11:49:20.077] <TB1> INFO: Test took 1155ms.
[11:49:20.079] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:20.383] <TB1> INFO: Expecting 2560 events.
[11:49:21.237] <TB1> INFO: 2560 events read in total (298ms).
[11:49:21.238] <TB1> INFO: Test took 1159ms.
[11:49:21.239] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:21.543] <TB1> INFO: Expecting 2560 events.
[11:49:22.398] <TB1> INFO: 2560 events read in total (299ms).
[11:49:22.398] <TB1> INFO: Test took 1160ms.
[11:49:22.401] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:22.704] <TB1> INFO: Expecting 2560 events.
[11:49:23.562] <TB1> INFO: 2560 events read in total (301ms).
[11:49:23.562] <TB1> INFO: Test took 1161ms.
[11:49:23.564] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:23.868] <TB1> INFO: Expecting 2560 events.
[11:49:24.721] <TB1> INFO: 2560 events read in total (297ms).
[11:49:24.722] <TB1> INFO: Test took 1158ms.
[11:49:24.723] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:25.027] <TB1> INFO: Expecting 2560 events.
[11:49:25.880] <TB1> INFO: 2560 events read in total (296ms).
[11:49:25.880] <TB1> INFO: Test took 1157ms.
[11:49:25.882] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:26.187] <TB1> INFO: Expecting 2560 events.
[11:49:27.040] <TB1> INFO: 2560 events read in total (297ms).
[11:49:27.040] <TB1> INFO: Test took 1158ms.
[11:49:27.042] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:27.346] <TB1> INFO: Expecting 2560 events.
[11:49:28.199] <TB1> INFO: 2560 events read in total (297ms).
[11:49:28.199] <TB1> INFO: Test took 1157ms.
[11:49:28.201] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:28.505] <TB1> INFO: Expecting 2560 events.
[11:49:29.360] <TB1> INFO: 2560 events read in total (299ms).
[11:49:29.361] <TB1> INFO: Test took 1160ms.
[11:49:29.362] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:29.666] <TB1> INFO: Expecting 2560 events.
[11:49:30.524] <TB1> INFO: 2560 events read in total (302ms).
[11:49:30.524] <TB1> INFO: Test took 1162ms.
[11:49:30.526] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:30.830] <TB1> INFO: Expecting 2560 events.
[11:49:31.687] <TB1> INFO: 2560 events read in total (300ms).
[11:49:31.687] <TB1> INFO: Test took 1161ms.
[11:49:31.690] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:31.994] <TB1> INFO: Expecting 2560 events.
[11:49:32.850] <TB1> INFO: 2560 events read in total (300ms).
[11:49:32.851] <TB1> INFO: Test took 1161ms.
[11:49:32.853] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:33.156] <TB1> INFO: Expecting 2560 events.
[11:49:34.016] <TB1> INFO: 2560 events read in total (303ms).
[11:49:34.017] <TB1> INFO: Test took 1164ms.
[11:49:34.019] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:34.323] <TB1> INFO: Expecting 2560 events.
[11:49:35.180] <TB1> INFO: 2560 events read in total (301ms).
[11:49:35.181] <TB1> INFO: Test took 1163ms.
[11:49:35.183] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:35.485] <TB1> INFO: Expecting 2560 events.
[11:49:36.343] <TB1> INFO: 2560 events read in total (302ms).
[11:49:36.343] <TB1> INFO: Test took 1160ms.
[11:49:36.346] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:36.649] <TB1> INFO: Expecting 2560 events.
[11:49:37.510] <TB1> INFO: 2560 events read in total (305ms).
[11:49:37.510] <TB1> INFO: Test took 1164ms.
[11:49:37.512] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:37.816] <TB1> INFO: Expecting 2560 events.
[11:49:38.673] <TB1> INFO: 2560 events read in total (301ms).
[11:49:38.674] <TB1> INFO: Test took 1162ms.
[11:49:38.677] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:38.979] <TB1> INFO: Expecting 2560 events.
[11:49:39.838] <TB1> INFO: 2560 events read in total (303ms).
[11:49:39.838] <TB1> INFO: Test took 1162ms.
[11:49:39.840] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:40.145] <TB1> INFO: Expecting 2560 events.
[11:49:41.001] <TB1> INFO: 2560 events read in total (300ms).
[11:49:41.001] <TB1> INFO: Test took 1161ms.
[11:49:41.004] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:41.307] <TB1> INFO: Expecting 2560 events.
[11:49:42.168] <TB1> INFO: 2560 events read in total (304ms).
[11:49:42.168] <TB1> INFO: Test took 1164ms.
[11:49:42.170] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:42.474] <TB1> INFO: Expecting 2560 events.
[11:49:43.337] <TB1> INFO: 2560 events read in total (306ms).
[11:49:43.337] <TB1> INFO: Test took 1167ms.
[11:49:43.342] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:43.642] <TB1> INFO: Expecting 2560 events.
[11:49:44.498] <TB1> INFO: 2560 events read in total (300ms).
[11:49:44.499] <TB1> INFO: Test took 1158ms.
[11:49:44.501] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:44.803] <TB1> INFO: Expecting 2560 events.
[11:49:45.661] <TB1> INFO: 2560 events read in total (301ms).
[11:49:45.662] <TB1> INFO: Test took 1161ms.
[11:49:45.663] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:45.968] <TB1> INFO: Expecting 2560 events.
[11:49:46.826] <TB1> INFO: 2560 events read in total (302ms).
[11:49:46.826] <TB1> INFO: Test took 1163ms.
[11:49:46.829] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:47.132] <TB1> INFO: Expecting 2560 events.
[11:49:47.988] <TB1> INFO: 2560 events read in total (300ms).
[11:49:47.988] <TB1> INFO: Test took 1159ms.
[11:49:47.991] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:48.294] <TB1> INFO: Expecting 2560 events.
[11:49:49.152] <TB1> INFO: 2560 events read in total (301ms).
[11:49:49.152] <TB1> INFO: Test took 1161ms.
[11:49:49.154] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:49:49.458] <TB1> INFO: Expecting 2560 events.
[11:49:50.316] <TB1> INFO: 2560 events read in total (302ms).
[11:49:50.317] <TB1> INFO: Test took 1163ms.
[11:49:50.786] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 615 seconds
[11:49:50.786] <TB1> INFO: PH scale (per ROC): 79 88 93 92 80 82 82 84 82 89 87 83 86 82 87 82
[11:49:50.786] <TB1> INFO: PH offset (per ROC): 178 149 145 150 155 153 158 166 160 147 144 159 155 147 156 169
[11:49:50.789] <TB1> INFO: Decoding statistics:
[11:49:50.789] <TB1> INFO: General information:
[11:49:50.789] <TB1> INFO: 16bit words read: 91944
[11:49:50.789] <TB1> INFO: valid events total: 10240
[11:49:50.789] <TB1> INFO: empty events: 7734
[11:49:50.789] <TB1> INFO: valid events with pixels: 2506
[11:49:50.789] <TB1> INFO: valid pixel hits: 2506
[11:49:50.789] <TB1> INFO: Event errors: 0
[11:49:50.789] <TB1> INFO: start marker: 0
[11:49:50.789] <TB1> INFO: stop marker: 0
[11:49:50.789] <TB1> INFO: overflow: 0
[11:49:50.789] <TB1> INFO: invalid 5bit words: 0
[11:49:50.789] <TB1> INFO: invalid XOR eye diagram: 0
[11:49:50.789] <TB1> INFO: frame (failed synchr.): 0
[11:49:50.789] <TB1> INFO: idle data (no TBM trl): 0
[11:49:50.789] <TB1> INFO: no data (only TBM hdr): 0
[11:49:50.789] <TB1> INFO: TBM errors: 0
[11:49:50.789] <TB1> INFO: flawed TBM headers: 0
[11:49:50.789] <TB1> INFO: flawed TBM trailers: 0
[11:49:50.789] <TB1> INFO: event ID mismatches: 0
[11:49:50.789] <TB1> INFO: ROC errors: 0
[11:49:50.789] <TB1> INFO: missing ROC header(s): 0
[11:49:50.789] <TB1> INFO: misplaced readback start: 0
[11:49:50.789] <TB1> INFO: Pixel decoding errors: 0
[11:49:50.789] <TB1> INFO: pixel data incomplete: 0
[11:49:50.789] <TB1> INFO: pixel address: 0
[11:49:50.789] <TB1> INFO: pulse height fill bit: 0
[11:49:50.789] <TB1> INFO: buffer corruption: 0
[11:49:50.971] <TB1> INFO: ######################################################################
[11:49:50.971] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:49:50.971] <TB1> INFO: ######################################################################
[11:49:50.980] <TB1> INFO: scanning low vcal = 10
[11:49:51.246] <TB1> INFO: Expecting 41600 events.
[11:49:54.796] <TB1> INFO: 41600 events read in total (2994ms).
[11:49:54.797] <TB1> INFO: Test took 3817ms.
[11:49:54.799] <TB1> INFO: scanning low vcal = 20
[11:49:55.101] <TB1> INFO: Expecting 41600 events.
[11:49:58.659] <TB1> INFO: 41600 events read in total (3002ms).
[11:49:58.659] <TB1> INFO: Test took 3860ms.
[11:49:58.660] <TB1> INFO: scanning low vcal = 30
[11:49:58.964] <TB1> INFO: Expecting 41600 events.
[11:50:02.513] <TB1> INFO: 41600 events read in total (2992ms).
[11:50:02.513] <TB1> INFO: Test took 3852ms.
[11:50:02.515] <TB1> INFO: scanning low vcal = 40
[11:50:02.812] <TB1> INFO: Expecting 41600 events.
[11:50:06.969] <TB1> INFO: 41600 events read in total (3601ms).
[11:50:06.970] <TB1> INFO: Test took 4455ms.
[11:50:06.973] <TB1> INFO: scanning low vcal = 50
[11:50:07.238] <TB1> INFO: Expecting 41600 events.
[11:50:11.466] <TB1> INFO: 41600 events read in total (3672ms).
[11:50:11.467] <TB1> INFO: Test took 4494ms.
[11:50:11.470] <TB1> INFO: scanning low vcal = 60
[11:50:11.737] <TB1> INFO: Expecting 41600 events.
[11:50:15.917] <TB1> INFO: 41600 events read in total (3624ms).
[11:50:15.918] <TB1> INFO: Test took 4448ms.
[11:50:15.920] <TB1> INFO: scanning low vcal = 70
[11:50:16.196] <TB1> INFO: Expecting 41600 events.
[11:50:20.401] <TB1> INFO: 41600 events read in total (3648ms).
[11:50:20.402] <TB1> INFO: Test took 4482ms.
[11:50:20.404] <TB1> INFO: scanning low vcal = 80
[11:50:20.692] <TB1> INFO: Expecting 41600 events.
[11:50:24.895] <TB1> INFO: 41600 events read in total (3646ms).
[11:50:24.895] <TB1> INFO: Test took 4491ms.
[11:50:24.897] <TB1> INFO: scanning low vcal = 90
[11:50:25.159] <TB1> INFO: Expecting 41600 events.
[11:50:29.364] <TB1> INFO: 41600 events read in total (3649ms).
[11:50:29.365] <TB1> INFO: Test took 4468ms.
[11:50:29.368] <TB1> INFO: scanning low vcal = 100
[11:50:29.653] <TB1> INFO: Expecting 41600 events.
[11:50:33.851] <TB1> INFO: 41600 events read in total (3642ms).
[11:50:33.851] <TB1> INFO: Test took 4483ms.
[11:50:33.854] <TB1> INFO: scanning low vcal = 110
[11:50:34.113] <TB1> INFO: Expecting 41600 events.
[11:50:38.322] <TB1> INFO: 41600 events read in total (3653ms).
[11:50:38.323] <TB1> INFO: Test took 4469ms.
[11:50:38.337] <TB1> INFO: scanning low vcal = 120
[11:50:38.602] <TB1> INFO: Expecting 41600 events.
[11:50:42.850] <TB1> INFO: 41600 events read in total (3691ms).
[11:50:42.851] <TB1> INFO: Test took 4514ms.
[11:50:42.853] <TB1> INFO: scanning low vcal = 130
[11:50:43.155] <TB1> INFO: Expecting 41600 events.
[11:50:47.350] <TB1> INFO: 41600 events read in total (3638ms).
[11:50:47.351] <TB1> INFO: Test took 4498ms.
[11:50:47.354] <TB1> INFO: scanning low vcal = 140
[11:50:47.631] <TB1> INFO: Expecting 41600 events.
[11:50:51.833] <TB1> INFO: 41600 events read in total (3645ms).
[11:50:51.834] <TB1> INFO: Test took 4480ms.
[11:50:51.836] <TB1> INFO: scanning low vcal = 150
[11:50:52.104] <TB1> INFO: Expecting 41600 events.
[11:50:56.318] <TB1> INFO: 41600 events read in total (3658ms).
[11:50:56.319] <TB1> INFO: Test took 4482ms.
[11:50:56.321] <TB1> INFO: scanning low vcal = 160
[11:50:56.606] <TB1> INFO: Expecting 41600 events.
[11:51:00.826] <TB1> INFO: 41600 events read in total (3664ms).
[11:51:00.827] <TB1> INFO: Test took 4506ms.
[11:51:00.830] <TB1> INFO: scanning low vcal = 170
[11:51:01.122] <TB1> INFO: Expecting 41600 events.
[11:51:05.312] <TB1> INFO: 41600 events read in total (3634ms).
[11:51:05.313] <TB1> INFO: Test took 4483ms.
[11:51:05.317] <TB1> INFO: scanning low vcal = 180
[11:51:05.576] <TB1> INFO: Expecting 41600 events.
[11:51:09.792] <TB1> INFO: 41600 events read in total (3660ms).
[11:51:09.793] <TB1> INFO: Test took 4475ms.
[11:51:09.795] <TB1> INFO: scanning low vcal = 190
[11:51:10.058] <TB1> INFO: Expecting 41600 events.
[11:51:14.253] <TB1> INFO: 41600 events read in total (3639ms).
[11:51:14.254] <TB1> INFO: Test took 4458ms.
[11:51:14.256] <TB1> INFO: scanning low vcal = 200
[11:51:14.518] <TB1> INFO: Expecting 41600 events.
[11:51:18.731] <TB1> INFO: 41600 events read in total (3656ms).
[11:51:18.732] <TB1> INFO: Test took 4476ms.
[11:51:18.735] <TB1> INFO: scanning low vcal = 210
[11:51:18.997] <TB1> INFO: Expecting 41600 events.
[11:51:23.209] <TB1> INFO: 41600 events read in total (3656ms).
[11:51:23.210] <TB1> INFO: Test took 4475ms.
[11:51:23.212] <TB1> INFO: scanning low vcal = 220
[11:51:23.471] <TB1> INFO: Expecting 41600 events.
[11:51:27.666] <TB1> INFO: 41600 events read in total (3638ms).
[11:51:27.666] <TB1> INFO: Test took 4454ms.
[11:51:27.669] <TB1> INFO: scanning low vcal = 230
[11:51:27.954] <TB1> INFO: Expecting 41600 events.
[11:51:32.154] <TB1> INFO: 41600 events read in total (3644ms).
[11:51:32.154] <TB1> INFO: Test took 4485ms.
[11:51:32.157] <TB1> INFO: scanning low vcal = 240
[11:51:32.420] <TB1> INFO: Expecting 41600 events.
[11:51:36.630] <TB1> INFO: 41600 events read in total (3654ms).
[11:51:36.631] <TB1> INFO: Test took 4474ms.
[11:51:36.633] <TB1> INFO: scanning low vcal = 250
[11:51:36.897] <TB1> INFO: Expecting 41600 events.
[11:51:41.138] <TB1> INFO: 41600 events read in total (3685ms).
[11:51:41.139] <TB1> INFO: Test took 4506ms.
[11:51:41.143] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[11:51:41.406] <TB1> INFO: Expecting 41600 events.
[11:51:45.633] <TB1> INFO: 41600 events read in total (3671ms).
[11:51:45.634] <TB1> INFO: Test took 4491ms.
[11:51:45.637] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[11:51:45.899] <TB1> INFO: Expecting 41600 events.
[11:51:50.095] <TB1> INFO: 41600 events read in total (3640ms).
[11:51:50.096] <TB1> INFO: Test took 4459ms.
[11:51:50.099] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[11:51:50.370] <TB1> INFO: Expecting 41600 events.
[11:51:54.552] <TB1> INFO: 41600 events read in total (3626ms).
[11:51:54.553] <TB1> INFO: Test took 4454ms.
[11:51:54.556] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[11:51:54.814] <TB1> INFO: Expecting 41600 events.
[11:51:59.016] <TB1> INFO: 41600 events read in total (3646ms).
[11:51:59.017] <TB1> INFO: Test took 4461ms.
[11:51:59.019] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:51:59.283] <TB1> INFO: Expecting 41600 events.
[11:52:03.520] <TB1> INFO: 41600 events read in total (3681ms).
[11:52:03.521] <TB1> INFO: Test took 4502ms.
[11:52:04.107] <TB1> INFO: PixTestGainPedestal::measure() done
[11:52:35.450] <TB1> INFO: PixTestGainPedestal::fit() done
[11:52:35.450] <TB1> INFO: non-linearity mean: 0.955 0.960 0.956 0.956 0.952 0.957 0.954 0.949 0.960 0.958 0.960 0.951 0.955 0.955 0.957 0.957
[11:52:35.450] <TB1> INFO: non-linearity RMS: 0.005 0.005 0.005 0.005 0.005 0.005 0.007 0.005 0.005 0.005 0.004 0.006 0.006 0.005 0.005 0.005
[11:52:35.451] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:52:35.469] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:52:35.487] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:52:35.505] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:52:35.522] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:52:35.540] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:52:35.558] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:52:35.576] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:52:35.594] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:52:35.612] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:52:35.630] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:52:35.648] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:52:35.666] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:52:35.683] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:52:35.701] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:52:35.719] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:52:35.737] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 164 seconds
[11:52:35.737] <TB1> INFO: Decoding statistics:
[11:52:35.737] <TB1> INFO: General information:
[11:52:35.737] <TB1> INFO: 16bit words read: 2662400
[11:52:35.737] <TB1> INFO: valid events total: 166400
[11:52:35.737] <TB1> INFO: empty events: 0
[11:52:35.737] <TB1> INFO: valid events with pixels: 166400
[11:52:35.737] <TB1> INFO: valid pixel hits: 665570
[11:52:35.737] <TB1> INFO: Event errors: 0
[11:52:35.737] <TB1> INFO: start marker: 0
[11:52:35.737] <TB1> INFO: stop marker: 0
[11:52:35.737] <TB1> INFO: overflow: 0
[11:52:35.737] <TB1> INFO: invalid 5bit words: 0
[11:52:35.737] <TB1> INFO: invalid XOR eye diagram: 0
[11:52:35.737] <TB1> INFO: frame (failed synchr.): 0
[11:52:35.737] <TB1> INFO: idle data (no TBM trl): 0
[11:52:35.737] <TB1> INFO: no data (only TBM hdr): 0
[11:52:35.737] <TB1> INFO: TBM errors: 0
[11:52:35.737] <TB1> INFO: flawed TBM headers: 0
[11:52:35.737] <TB1> INFO: flawed TBM trailers: 0
[11:52:35.737] <TB1> INFO: event ID mismatches: 0
[11:52:35.737] <TB1> INFO: ROC errors: 0
[11:52:35.737] <TB1> INFO: missing ROC header(s): 0
[11:52:35.737] <TB1> INFO: misplaced readback start: 0
[11:52:35.737] <TB1> INFO: Pixel decoding errors: 0
[11:52:35.737] <TB1> INFO: pixel data incomplete: 0
[11:52:35.737] <TB1> INFO: pixel address: 0
[11:52:35.737] <TB1> INFO: pulse height fill bit: 0
[11:52:35.737] <TB1> INFO: buffer corruption: 0
[11:52:35.744] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C15.dat
[11:52:35.745] <TB1> INFO: ######################################################################
[11:52:35.745] <TB1> INFO: PixTestReadback::doTest()
[11:52:35.745] <TB1> INFO: ######################################################################
[11:52:35.745] <TB1> INFO: ----------------------------------------------------------------------
[11:52:35.745] <TB1> INFO: PixTestReadback::CalibrateVd()
[11:52:35.745] <TB1> INFO: ----------------------------------------------------------------------
[11:52:45.207] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C0.dat
[11:52:45.207] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C1.dat
[11:52:45.207] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C2.dat
[11:52:45.207] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C3.dat
[11:52:45.207] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C4.dat
[11:52:45.207] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C5.dat
[11:52:45.207] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C6.dat
[11:52:45.207] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C7.dat
[11:52:45.208] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C8.dat
[11:52:45.208] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C9.dat
[11:52:45.208] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C10.dat
[11:52:45.208] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C11.dat
[11:52:45.208] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C12.dat
[11:52:45.208] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C13.dat
[11:52:45.208] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C14.dat
[11:52:45.208] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C15.dat
[11:52:45.238] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:52:45.238] <TB1> INFO: ----------------------------------------------------------------------
[11:52:45.238] <TB1> INFO: PixTestReadback::CalibrateVa()
[11:52:45.238] <TB1> INFO: ----------------------------------------------------------------------
[11:52:55.028] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C0.dat
[11:52:55.028] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C1.dat
[11:52:55.028] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C2.dat
[11:52:55.028] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C3.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C4.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C5.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C6.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C7.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C8.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C9.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C10.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C11.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C12.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C13.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C14.dat
[11:52:55.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C15.dat
[11:52:55.053] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:52:55.053] <TB1> INFO: ----------------------------------------------------------------------
[11:52:55.053] <TB1> INFO: PixTestReadback::readbackVbg()
[11:52:55.053] <TB1> INFO: ----------------------------------------------------------------------
[11:53:02.369] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:53:02.369] <TB1> INFO: ----------------------------------------------------------------------
[11:53:02.369] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[11:53:02.369] <TB1> INFO: ----------------------------------------------------------------------
[11:53:02.369] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157calibrated Vbg = 1.1742 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.9calibrated Vbg = 1.17266 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.5calibrated Vbg = 1.17133 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.3calibrated Vbg = 1.18262 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154calibrated Vbg = 1.1845 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 151.2calibrated Vbg = 1.18473 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.5calibrated Vbg = 1.18232 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.1calibrated Vbg = 1.18988 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.9calibrated Vbg = 1.181 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 145.3calibrated Vbg = 1.19145 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.9calibrated Vbg = 1.17899 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 162.3calibrated Vbg = 1.18335 :::*/*/*/*/
[11:53:02.369] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 147.5calibrated Vbg = 1.17926 :::*/*/*/*/
[11:53:02.370] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152calibrated Vbg = 1.18128 :::*/*/*/*/
[11:53:02.370] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.2calibrated Vbg = 1.18456 :::*/*/*/*/
[11:53:02.370] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153calibrated Vbg = 1.18321 :::*/*/*/*/
[11:53:02.373] <TB1> INFO: ----------------------------------------------------------------------
[11:53:02.373] <TB1> INFO: PixTestReadback::CalibrateIa()
[11:53:02.373] <TB1> INFO: ----------------------------------------------------------------------
[11:55:37.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C0.dat
[11:55:37.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C1.dat
[11:55:37.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C2.dat
[11:55:37.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C3.dat
[11:55:37.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C4.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C5.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C6.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C7.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C8.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C9.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C10.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C11.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C12.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C13.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C14.dat
[11:55:37.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//002_FulltestPxar_m20//readbackCal_C15.dat
[11:55:37.856] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:55:37.857] <TB1> INFO: PixTestReadback::doTest() done
[11:55:37.857] <TB1> INFO: Decoding statistics:
[11:55:37.857] <TB1> INFO: General information:
[11:55:37.857] <TB1> INFO: 16bit words read: 1024
[11:55:37.857] <TB1> INFO: valid events total: 128
[11:55:37.857] <TB1> INFO: empty events: 128
[11:55:37.857] <TB1> INFO: valid events with pixels: 0
[11:55:37.857] <TB1> INFO: valid pixel hits: 0
[11:55:37.857] <TB1> INFO: Event errors: 0
[11:55:37.857] <TB1> INFO: start marker: 0
[11:55:37.857] <TB1> INFO: stop marker: 0
[11:55:37.857] <TB1> INFO: overflow: 0
[11:55:37.857] <TB1> INFO: invalid 5bit words: 0
[11:55:37.857] <TB1> INFO: invalid XOR eye diagram: 0
[11:55:37.857] <TB1> INFO: frame (failed synchr.): 0
[11:55:37.857] <TB1> INFO: idle data (no TBM trl): 0
[11:55:37.857] <TB1> INFO: no data (only TBM hdr): 0
[11:55:37.857] <TB1> INFO: TBM errors: 0
[11:55:37.857] <TB1> INFO: flawed TBM headers: 0
[11:55:37.857] <TB1> INFO: flawed TBM trailers: 0
[11:55:37.857] <TB1> INFO: event ID mismatches: 0
[11:55:37.857] <TB1> INFO: ROC errors: 0
[11:55:37.857] <TB1> INFO: missing ROC header(s): 0
[11:55:37.857] <TB1> INFO: misplaced readback start: 0
[11:55:37.857] <TB1> INFO: Pixel decoding errors: 0
[11:55:37.857] <TB1> INFO: pixel data incomplete: 0
[11:55:37.857] <TB1> INFO: pixel address: 0
[11:55:37.857] <TB1> INFO: pulse height fill bit: 0
[11:55:37.857] <TB1> INFO: buffer corruption: 0
[11:55:37.878] <TB1> INFO: Decoding statistics:
[11:55:37.878] <TB1> INFO: General information:
[11:55:37.878] <TB1> INFO: 16bit words read: 2755368
[11:55:37.878] <TB1> INFO: valid events total: 176768
[11:55:37.878] <TB1> INFO: empty events: 7862
[11:55:37.878] <TB1> INFO: valid events with pixels: 168906
[11:55:37.878] <TB1> INFO: valid pixel hits: 668076
[11:55:37.878] <TB1> INFO: Event errors: 0
[11:55:37.878] <TB1> INFO: start marker: 0
[11:55:37.878] <TB1> INFO: stop marker: 0
[11:55:37.878] <TB1> INFO: overflow: 0
[11:55:37.878] <TB1> INFO: invalid 5bit words: 0
[11:55:37.878] <TB1> INFO: invalid XOR eye diagram: 0
[11:55:37.878] <TB1> INFO: frame (failed synchr.): 0
[11:55:37.878] <TB1> INFO: idle data (no TBM trl): 0
[11:55:37.878] <TB1> INFO: no data (only TBM hdr): 0
[11:55:37.878] <TB1> INFO: TBM errors: 0
[11:55:37.878] <TB1> INFO: flawed TBM headers: 0
[11:55:37.878] <TB1> INFO: flawed TBM trailers: 0
[11:55:37.878] <TB1> INFO: event ID mismatches: 0
[11:55:37.878] <TB1> INFO: ROC errors: 0
[11:55:37.878] <TB1> INFO: missing ROC header(s): 0
[11:55:37.878] <TB1> INFO: misplaced readback start: 0
[11:55:37.878] <TB1> INFO: Pixel decoding errors: 0
[11:55:37.878] <TB1> INFO: pixel data incomplete: 0
[11:55:37.878] <TB1> INFO: pixel address: 0
[11:55:37.878] <TB1> INFO: pulse height fill bit: 0
[11:55:37.878] <TB1> INFO: buffer corruption: 0
[11:55:37.878] <TB1> INFO: enter test to run
[11:55:37.878] <TB1> INFO: test: exit no parameter change
[11:55:38.026] <TB1> QUIET: Connection to board 153 closed.
[11:55:38.107] <TB1> INFO: pXar: this is the end, my friend