[08:43:15.865] <TB0> INFO: *** Welcome to pxar ***
[08:43:15.865] <TB0> INFO: *** Today: 2016/09/26
[08:43:16.378] <TB0> INFO: *** Version: fe94
[08:43:16.378] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C15.dat
[08:43:16.378] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:43:16.378] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//defaultMaskFile.dat
[08:43:16.378] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters_C15.dat
[08:43:16.443] <TB0> INFO: clk: 4
[08:43:16.443] <TB0> INFO: ctr: 4
[08:43:16.443] <TB0> INFO: sda: 19
[08:43:16.443] <TB0> INFO: tin: 9
[08:43:16.443] <TB0> INFO: level: 15
[08:43:16.443] <TB0> INFO: triggerdelay: 0
[08:43:16.444] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:43:16.444] <TB0> INFO: Log level: INFO
[08:43:16.450] <TB0> INFO: Found DTB DTB_WS6AYH
[08:43:16.461] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[08:43:16.463] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[08:43:16.465] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[08:43:17.963] <TB0> INFO: DUT info:
[08:43:17.963] <TB0> INFO: The DUT currently contains the following objects:
[08:43:17.963] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[08:43:17.963] <TB0> INFO: TBM Core alpha (0): 7 registers set
[08:43:17.963] <TB0> INFO: TBM Core beta (1): 7 registers set
[08:43:17.963] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:43:17.963] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:17.963] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:18.364] <TB0> INFO: enter 'restricted' command line mode
[08:43:18.364] <TB0> INFO: enter test to run
[08:43:18.364] <TB0> INFO: test: pretest no parameter change
[08:43:18.364] <TB0> INFO: running: pretest
[08:43:18.368] <TB0> INFO: ----------------------------------------------------------------------
[08:43:18.368] <TB0> INFO: PixTestPretest::programROC()
[08:43:18.368] <TB0> INFO: ----------------------------------------------------------------------
[08:43:36.383] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:43:36.383] <TB0> INFO: IA differences per ROC: 16.9 17.7 18.5 19.3 16.9 20.1 20.1 20.9 19.3 19.3 20.1 21.7 19.3 21.7 20.9 20.1
[08:43:36.489] <TB0> INFO: enter test to run
[08:43:36.489] <TB0> INFO: test: pretest no parameter change
[08:43:36.489] <TB0> INFO: running: pretest
[08:43:36.490] <TB0> INFO: ----------------------------------------------------------------------
[08:43:36.490] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:43:36.490] <TB0> INFO: ----------------------------------------------------------------------
[08:43:42.868] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[08:43:42.868] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 18.5 18.5 19.3 18.5 18.5 20.1 19.3 19.3 18.5 18.5 19.3 18.5
[08:43:42.901] <TB0> INFO: enter test to run
[08:43:42.901] <TB0> INFO: test: pretest no parameter change
[08:43:42.901] <TB0> INFO: running: pretest
[08:43:42.901] <TB0> INFO: ----------------------------------------------------------------------
[08:43:42.901] <TB0> INFO: PixTestPretest::findTiming()
[08:43:42.901] <TB0> INFO: ----------------------------------------------------------------------
[08:43:42.901] <TB0> INFO: PixTestCmd::init()

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


[08:45:23.157] <TB0> INFO: 231680 events read in total (8722ms).
[08:45:23.162] <TB0> INFO: Test took 9372ms.
[08:45:23.365] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:45:23.438] <TB0> INFO: enter test to run
[08:45:23.438] <TB0> INFO: test: pretest no parameter change
[08:45:23.438] <TB0> INFO: running: pretest
[08:45:23.440] <TB0> INFO: ----------------------------------------------------------------------
[08:45:23.440] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[08:45:23.440] <TB0> INFO: ----------------------------------------------------------------------
[08:45:23.532] <TB0> INFO: Expecting 231680 events.
[08:45:32.751] <TB0> INFO: 231680 events read in total (8663ms).
[08:45:32.757] <TB0> INFO: Test took 9314ms.
[08:45:32.974] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[08:45:32.974] <TB0> INFO: CalDel: 165 145 145 161 145 158 153 170 146 164 152 170 144 148 139 154
[08:45:32.974] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:45:33.025] <TB0> INFO: enter test to run
[08:45:33.025] <TB0> INFO: test: pretest no parameter change
[08:45:33.025] <TB0> INFO: running: pretest
[08:45:33.025] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C0.dat
[08:45:33.025] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C1.dat
[08:45:33.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C2.dat
[08:45:33.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C3.dat
[08:45:33.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C4.dat
[08:45:33.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C5.dat
[08:45:33.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C6.dat
[08:45:33.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C7.dat
[08:45:33.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C8.dat
[08:45:33.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C9.dat
[08:45:33.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C10.dat
[08:45:33.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C11.dat
[08:45:33.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C12.dat
[08:45:33.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C13.dat
[08:45:33.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C14.dat
[08:45:33.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C15.dat
[08:45:33.028] <TB0> INFO: enter test to run
[08:45:33.028] <TB0> INFO: test: fulltest no parameter change
[08:45:33.028] <TB0> INFO: running: fulltest
[08:45:33.028] <TB0> INFO: ######################################################################
[08:45:33.028] <TB0> INFO: PixTestFullTest::doTest()
[08:45:33.028] <TB0> INFO: ######################################################################
[08:45:33.029] <TB0> INFO: ######################################################################
[08:45:33.029] <TB0> INFO: PixTestAlive::doTest()
[08:45:33.029] <TB0> INFO: ######################################################################
[08:45:33.030] <TB0> INFO: ----------------------------------------------------------------------
[08:45:33.030] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:45:33.030] <TB0> INFO: ----------------------------------------------------------------------
[08:45:33.350] <TB0> INFO: Expecting 41600 events.
[08:45:36.792] <TB0> INFO: 41600 events read in total (2886ms).
[08:45:36.792] <TB0> INFO: Test took 3760ms.
[08:45:37.003] <TB0> INFO: PixTestAlive::aliveTest() done
[08:45:37.003] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:45:37.004] <TB0> INFO: ----------------------------------------------------------------------
[08:45:37.004] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:45:37.004] <TB0> INFO: ----------------------------------------------------------------------
[08:45:37.270] <TB0> INFO: Expecting 41600 events.
[08:45:40.018] <TB0> INFO: 41600 events read in total (2192ms).
[08:45:40.018] <TB0> INFO: Test took 3013ms.
[08:45:40.018] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:45:40.255] <TB0> INFO: PixTestAlive::maskTest() done
[08:45:40.255] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:45:40.256] <TB0> INFO: ----------------------------------------------------------------------
[08:45:40.256] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:45:40.256] <TB0> INFO: ----------------------------------------------------------------------
[08:45:40.530] <TB0> INFO: Expecting 41600 events.
[08:45:43.968] <TB0> INFO: 41600 events read in total (2882ms).
[08:45:43.968] <TB0> INFO: Test took 3711ms.
[08:45:44.189] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[08:45:44.189] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:45:44.189] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:45:44.189] <TB0> INFO: Decoding statistics:
[08:45:44.189] <TB0> INFO: General information:
[08:45:44.189] <TB0> INFO: 16bit words read: 0
[08:45:44.189] <TB0> INFO: valid events total: 0
[08:45:44.189] <TB0> INFO: empty events: 0
[08:45:44.189] <TB0> INFO: valid events with pixels: 0
[08:45:44.189] <TB0> INFO: valid pixel hits: 0
[08:45:44.189] <TB0> INFO: Event errors: 0
[08:45:44.189] <TB0> INFO: start marker: 0
[08:45:44.189] <TB0> INFO: stop marker: 0
[08:45:44.189] <TB0> INFO: overflow: 0
[08:45:44.189] <TB0> INFO: invalid 5bit words: 0
[08:45:44.189] <TB0> INFO: invalid XOR eye diagram: 0
[08:45:44.190] <TB0> INFO: frame (failed synchr.): 0
[08:45:44.190] <TB0> INFO: idle data (no TBM trl): 0
[08:45:44.190] <TB0> INFO: no data (only TBM hdr): 0
[08:45:44.190] <TB0> INFO: TBM errors: 0
[08:45:44.190] <TB0> INFO: flawed TBM headers: 0
[08:45:44.190] <TB0> INFO: flawed TBM trailers: 0
[08:45:44.190] <TB0> INFO: event ID mismatches: 0
[08:45:44.190] <TB0> INFO: ROC errors: 0
[08:45:44.190] <TB0> INFO: missing ROC header(s): 0
[08:45:44.190] <TB0> INFO: misplaced readback start: 0
[08:45:44.190] <TB0> INFO: Pixel decoding errors: 0
[08:45:44.190] <TB0> INFO: pixel data incomplete: 0
[08:45:44.190] <TB0> INFO: pixel address: 0
[08:45:44.190] <TB0> INFO: pulse height fill bit: 0
[08:45:44.190] <TB0> INFO: buffer corruption: 0
[08:45:44.203] <TB0> INFO: ######################################################################
[08:45:44.203] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:45:44.203] <TB0> INFO: ######################################################################
[08:45:44.206] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:45:44.216] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:45:44.216] <TB0> INFO: run 1 of 1
[08:45:44.481] <TB0> INFO: Expecting 3120000 events.
[08:46:21.875] <TB0> INFO: 871125 events read in total (36838ms).
[08:46:57.859] <TB0> INFO: 1733125 events read in total (72823ms).
[08:47:34.899] <TB0> INFO: 2608445 events read in total (109862ms).
[08:47:55.861] <TB0> INFO: 3120000 events read in total (130824ms).
[08:47:55.933] <TB0> INFO: Test took 131717ms.
[08:48:20.406] <TB0> INFO: PixTestBBMap::doTest() done, duration: 156 seconds
[08:48:20.406] <TB0> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:48:20.406] <TB0> INFO: separation cut (per ROC): 72 78 86 66 89 69 80 72 74 85 74 81 71 85 79 89
[08:48:20.406] <TB0> INFO: Decoding statistics:
[08:48:20.406] <TB0> INFO: General information:
[08:48:20.406] <TB0> INFO: 16bit words read: 0
[08:48:20.406] <TB0> INFO: valid events total: 0
[08:48:20.406] <TB0> INFO: empty events: 0
[08:48:20.406] <TB0> INFO: valid events with pixels: 0
[08:48:20.406] <TB0> INFO: valid pixel hits: 0
[08:48:20.406] <TB0> INFO: Event errors: 0
[08:48:20.406] <TB0> INFO: start marker: 0
[08:48:20.406] <TB0> INFO: stop marker: 0
[08:48:20.406] <TB0> INFO: overflow: 0
[08:48:20.406] <TB0> INFO: invalid 5bit words: 0
[08:48:20.406] <TB0> INFO: invalid XOR eye diagram: 0
[08:48:20.406] <TB0> INFO: frame (failed synchr.): 0
[08:48:20.406] <TB0> INFO: idle data (no TBM trl): 0
[08:48:20.406] <TB0> INFO: no data (only TBM hdr): 0
[08:48:20.406] <TB0> INFO: TBM errors: 0
[08:48:20.406] <TB0> INFO: flawed TBM headers: 0
[08:48:20.406] <TB0> INFO: flawed TBM trailers: 0
[08:48:20.406] <TB0> INFO: event ID mismatches: 0
[08:48:20.406] <TB0> INFO: ROC errors: 0
[08:48:20.406] <TB0> INFO: missing ROC header(s): 0
[08:48:20.406] <TB0> INFO: misplaced readback start: 0
[08:48:20.406] <TB0> INFO: Pixel decoding errors: 0
[08:48:20.406] <TB0> INFO: pixel data incomplete: 0
[08:48:20.406] <TB0> INFO: pixel address: 0
[08:48:20.406] <TB0> INFO: pulse height fill bit: 0
[08:48:20.406] <TB0> INFO: buffer corruption: 0
[08:48:20.482] <TB0> INFO: ######################################################################
[08:48:20.482] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:48:20.482] <TB0> INFO: ######################################################################
[08:48:20.482] <TB0> INFO: ----------------------------------------------------------------------
[08:48:20.482] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:48:20.482] <TB0> INFO: ----------------------------------------------------------------------
[08:48:20.483] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[08:48:20.491] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[08:48:20.491] <TB0> INFO: run 1 of 1
[08:48:20.751] <TB0> INFO: Expecting 36608000 events.
[08:48:50.973] <TB0> INFO: 805900 events read in total (29663ms).
[08:49:20.554] <TB0> INFO: 1598650 events read in total (59244ms).
[08:49:50.350] <TB0> INFO: 2390900 events read in total (89040ms).
[08:50:19.503] <TB0> INFO: 3182150 events read in total (118193ms).
[08:50:48.631] <TB0> INFO: 3973550 events read in total (147321ms).
[08:51:16.602] <TB0> INFO: 4765150 events read in total (175292ms).
[08:51:44.790] <TB0> INFO: 5555950 events read in total (203480ms).
[08:52:12.715] <TB0> INFO: 6348750 events read in total (231405ms).
[08:52:40.998] <TB0> INFO: 7137800 events read in total (259688ms).
[08:53:08.738] <TB0> INFO: 7929350 events read in total (287428ms).
[08:53:36.895] <TB0> INFO: 8721350 events read in total (315585ms).
[08:54:04.417] <TB0> INFO: 9512300 events read in total (343108ms).
[08:54:32.653] <TB0> INFO: 10304700 events read in total (371343ms).
[08:55:00.744] <TB0> INFO: 11096500 events read in total (399434ms).
[08:55:29.046] <TB0> INFO: 11887600 events read in total (427736ms).
[08:55:57.229] <TB0> INFO: 12679850 events read in total (455919ms).
[08:56:25.118] <TB0> INFO: 13472250 events read in total (483808ms).
[08:56:53.212] <TB0> INFO: 14262900 events read in total (511902ms).
[08:57:21.226] <TB0> INFO: 15054000 events read in total (539916ms).
[08:57:49.302] <TB0> INFO: 15843850 events read in total (567993ms).
[08:58:17.189] <TB0> INFO: 16632150 events read in total (595879ms).
[08:58:44.989] <TB0> INFO: 17422800 events read in total (623679ms).
[08:59:13.090] <TB0> INFO: 18212050 events read in total (651780ms).
[08:59:40.662] <TB0> INFO: 18998050 events read in total (679352ms).
[09:00:08.790] <TB0> INFO: 19784200 events read in total (707480ms).
[09:00:36.712] <TB0> INFO: 20568450 events read in total (735402ms).
[09:01:04.764] <TB0> INFO: 21353250 events read in total (763454ms).
[09:01:33.157] <TB0> INFO: 22137200 events read in total (791847ms).
[09:02:00.971] <TB0> INFO: 22919850 events read in total (819661ms).
[09:02:29.216] <TB0> INFO: 23702700 events read in total (847906ms).
[09:02:57.443] <TB0> INFO: 24486000 events read in total (876133ms).
[09:03:25.404] <TB0> INFO: 25268800 events read in total (904094ms).
[09:03:53.563] <TB0> INFO: 26053050 events read in total (932254ms).
[09:04:21.633] <TB0> INFO: 26834600 events read in total (960323ms).
[09:04:49.803] <TB0> INFO: 27616550 events read in total (988493ms).
[09:05:18.191] <TB0> INFO: 28397350 events read in total (1016881ms).
[09:05:46.147] <TB0> INFO: 29178950 events read in total (1044837ms).
[09:06:14.386] <TB0> INFO: 29960200 events read in total (1073076ms).
[09:06:41.928] <TB0> INFO: 30741950 events read in total (1100618ms).
[09:07:10.496] <TB0> INFO: 31523200 events read in total (1129186ms).
[09:07:38.445] <TB0> INFO: 32304500 events read in total (1157135ms).
[09:08:06.600] <TB0> INFO: 33086750 events read in total (1185290ms).
[09:08:34.652] <TB0> INFO: 33867600 events read in total (1213342ms).
[09:09:02.465] <TB0> INFO: 34649950 events read in total (1241155ms).
[09:09:30.293] <TB0> INFO: 35431300 events read in total (1268983ms).
[09:09:58.435] <TB0> INFO: 36218550 events read in total (1297125ms).
[09:10:12.276] <TB0> INFO: 36608000 events read in total (1310966ms).
[09:10:12.340] <TB0> INFO: Test took 1311849ms.
[09:10:12.926] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:14.792] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:16.751] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:18.469] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:20.486] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:22.435] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:24.218] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:26.430] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:28.419] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:30.274] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:31.984] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:33.735] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:35.471] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:37.220] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:39.213] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:41.159] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:10:42.889] <TB0> INFO: PixTestScurves::scurves() done
[09:10:42.889] <TB0> INFO: Vcal mean: 79.22 84.84 80.02 73.76 93.75 76.32 86.62 80.56 75.05 88.08 74.94 81.31 77.15 81.08 76.85 90.45
[09:10:42.889] <TB0> INFO: Vcal RMS: 4.32 4.66 4.26 4.19 5.54 4.27 5.12 4.36 4.65 5.40 4.58 4.54 3.99 4.97 3.74 5.43
[09:10:42.890] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1342 seconds
[09:10:42.890] <TB0> INFO: Decoding statistics:
[09:10:42.890] <TB0> INFO: General information:
[09:10:42.890] <TB0> INFO: 16bit words read: 0
[09:10:42.890] <TB0> INFO: valid events total: 0
[09:10:42.890] <TB0> INFO: empty events: 0
[09:10:42.890] <TB0> INFO: valid events with pixels: 0
[09:10:42.890] <TB0> INFO: valid pixel hits: 0
[09:10:42.890] <TB0> INFO: Event errors: 0
[09:10:42.890] <TB0> INFO: start marker: 0
[09:10:42.890] <TB0> INFO: stop marker: 0
[09:10:42.890] <TB0> INFO: overflow: 0
[09:10:42.890] <TB0> INFO: invalid 5bit words: 0
[09:10:42.890] <TB0> INFO: invalid XOR eye diagram: 0
[09:10:42.890] <TB0> INFO: frame (failed synchr.): 0
[09:10:42.890] <TB0> INFO: idle data (no TBM trl): 0
[09:10:42.890] <TB0> INFO: no data (only TBM hdr): 0
[09:10:42.890] <TB0> INFO: TBM errors: 0
[09:10:42.890] <TB0> INFO: flawed TBM headers: 0
[09:10:42.890] <TB0> INFO: flawed TBM trailers: 0
[09:10:42.890] <TB0> INFO: event ID mismatches: 0
[09:10:42.890] <TB0> INFO: ROC errors: 0
[09:10:42.890] <TB0> INFO: missing ROC header(s): 0
[09:10:42.890] <TB0> INFO: misplaced readback start: 0
[09:10:42.890] <TB0> INFO: Pixel decoding errors: 0
[09:10:42.890] <TB0> INFO: pixel data incomplete: 0
[09:10:42.890] <TB0> INFO: pixel address: 0
[09:10:42.890] <TB0> INFO: pulse height fill bit: 0
[09:10:42.890] <TB0> INFO: buffer corruption: 0
[09:10:42.987] <TB0> INFO: ######################################################################
[09:10:42.987] <TB0> INFO: PixTestTrim::doTest()
[09:10:42.987] <TB0> INFO: ######################################################################
[09:10:42.988] <TB0> INFO: ----------------------------------------------------------------------
[09:10:42.988] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:10:42.988] <TB0> INFO: ----------------------------------------------------------------------
[09:10:43.076] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:10:43.076] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:10:43.084] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:10:43.084] <TB0> INFO: run 1 of 1
[09:10:43.345] <TB0> INFO: Expecting 6281600 events.
[09:11:20.412] <TB0> INFO: 1104530 events read in total (36502ms).
[09:11:56.072] <TB0> INFO: 2199960 events read in total (72162ms).
[09:12:31.869] <TB0> INFO: 3288230 events read in total (107959ms).
[09:13:07.709] <TB0> INFO: 4384320 events read in total (143799ms).
[09:13:43.973] <TB0> INFO: 5484010 events read in total (180063ms).
[09:14:09.933] <TB0> INFO: 6281600 events read in total (206023ms).
[09:14:09.988] <TB0> INFO: Test took 206904ms.
[09:14:28.751] <TB0> INFO: ROC 0 VthrComp = 82
[09:14:28.751] <TB0> INFO: ROC 1 VthrComp = 89
[09:14:28.751] <TB0> INFO: ROC 2 VthrComp = 86
[09:14:28.751] <TB0> INFO: ROC 3 VthrComp = 79
[09:14:28.752] <TB0> INFO: ROC 4 VthrComp = 96
[09:14:28.752] <TB0> INFO: ROC 5 VthrComp = 83
[09:14:28.752] <TB0> INFO: ROC 6 VthrComp = 93
[09:14:28.752] <TB0> INFO: ROC 7 VthrComp = 85
[09:14:28.752] <TB0> INFO: ROC 8 VthrComp = 81
[09:14:28.752] <TB0> INFO: ROC 9 VthrComp = 89
[09:14:28.752] <TB0> INFO: ROC 10 VthrComp = 81
[09:14:28.752] <TB0> INFO: ROC 11 VthrComp = 87
[09:14:28.752] <TB0> INFO: ROC 12 VthrComp = 82
[09:14:28.752] <TB0> INFO: ROC 13 VthrComp = 85
[09:14:28.752] <TB0> INFO: ROC 14 VthrComp = 85
[09:14:28.753] <TB0> INFO: ROC 15 VthrComp = 96
[09:14:28.753] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:14:28.753] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:14:28.761] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:14:28.761] <TB0> INFO: run 1 of 1
[09:14:29.023] <TB0> INFO: Expecting 6281600 events.
[09:15:01.098] <TB0> INFO: 758630 events read in total (31519ms).
[09:15:32.193] <TB0> INFO: 1515500 events read in total (62614ms).
[09:16:03.207] <TB0> INFO: 2273350 events read in total (93628ms).
[09:16:34.335] <TB0> INFO: 3030920 events read in total (124756ms).
[09:17:05.127] <TB0> INFO: 3783080 events read in total (155548ms).
[09:17:35.523] <TB0> INFO: 4531880 events read in total (185944ms).
[09:18:06.234] <TB0> INFO: 5278350 events read in total (216655ms).
[09:18:36.674] <TB0> INFO: 6024290 events read in total (247095ms).
[09:18:47.410] <TB0> INFO: 6281600 events read in total (257831ms).
[09:18:47.458] <TB0> INFO: Test took 258697ms.
[09:19:13.177] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 58.1581 for pixel 0/25 mean/min/max = 45.265/32.3281/58.2018
[09:19:13.177] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 57.7777 for pixel 5/26 mean/min/max = 45.4202/33.0582/57.7821
[09:19:13.177] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 56.5781 for pixel 51/17 mean/min/max = 44.0734/31.5337/56.6131
[09:19:13.177] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 56.1106 for pixel 20/78 mean/min/max = 45.3273/34.284/56.3706
[09:19:13.178] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 60.7313 for pixel 4/0 mean/min/max = 46.1686/31.6033/60.7338
[09:19:13.178] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 57.1485 for pixel 0/72 mean/min/max = 44.8111/32.406/57.2161
[09:19:13.178] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 57.6119 for pixel 3/67 mean/min/max = 45.1763/32.0255/58.327
[09:19:13.179] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.0355 for pixel 28/8 mean/min/max = 44.633/32.2049/57.0611
[09:19:13.179] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 57.243 for pixel 22/4 mean/min/max = 44.7371/32.2182/57.2561
[09:19:13.179] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 60.804 for pixel 0/68 mean/min/max = 46.528/31.9834/61.0726
[09:19:13.180] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 56.825 for pixel 19/10 mean/min/max = 44.603/32.0516/57.1544
[09:19:13.180] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 56.0149 for pixel 17/74 mean/min/max = 43.8118/31.518/56.1055
[09:19:13.181] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 55.7463 for pixel 3/68 mean/min/max = 44.3551/32.7128/55.9975
[09:19:13.181] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 58.7917 for pixel 2/78 mean/min/max = 45.364/31.8306/58.8974
[09:19:13.181] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 55.6012 for pixel 8/5 mean/min/max = 44.2099/32.7187/55.7011
[09:19:13.182] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 58.4518 for pixel 19/35 mean/min/max = 45.1544/31.8501/58.4588
[09:19:13.182] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:13.272] <TB0> INFO: Expecting 514560 events.
[09:19:24.650] <TB0> INFO: 514560 events read in total (10813ms).
[09:19:24.656] <TB0> INFO: Expecting 514560 events.
[09:19:35.770] <TB0> INFO: 514560 events read in total (10712ms).
[09:19:35.785] <TB0> INFO: Expecting 514560 events.
[09:19:46.759] <TB0> INFO: 514560 events read in total (10572ms).
[09:19:46.771] <TB0> INFO: Expecting 514560 events.
[09:19:57.592] <TB0> INFO: 514560 events read in total (10420ms).
[09:19:57.606] <TB0> INFO: Expecting 514560 events.
[09:20:08.603] <TB0> INFO: 514560 events read in total (10595ms).
[09:20:08.629] <TB0> INFO: Expecting 514560 events.
[09:20:19.502] <TB0> INFO: 514560 events read in total (10471ms).
[09:20:19.523] <TB0> INFO: Expecting 514560 events.
[09:20:30.161] <TB0> INFO: 514560 events read in total (10237ms).
[09:20:30.183] <TB0> INFO: Expecting 514560 events.
[09:20:40.791] <TB0> INFO: 514560 events read in total (10206ms).
[09:20:40.816] <TB0> INFO: Expecting 514560 events.
[09:20:51.669] <TB0> INFO: 514560 events read in total (10451ms).
[09:20:51.695] <TB0> INFO: Expecting 514560 events.
[09:21:02.512] <TB0> INFO: 514560 events read in total (10415ms).
[09:21:02.541] <TB0> INFO: Expecting 514560 events.
[09:21:13.261] <TB0> INFO: 514560 events read in total (10318ms).
[09:21:13.294] <TB0> INFO: Expecting 514560 events.
[09:21:24.096] <TB0> INFO: 514560 events read in total (10400ms).
[09:21:24.134] <TB0> INFO: Expecting 514560 events.
[09:21:34.886] <TB0> INFO: 514560 events read in total (10350ms).
[09:21:34.927] <TB0> INFO: Expecting 514560 events.
[09:21:45.738] <TB0> INFO: 514560 events read in total (10409ms).
[09:21:45.779] <TB0> INFO: Expecting 514560 events.
[09:21:56.674] <TB0> INFO: 514560 events read in total (10494ms).
[09:21:56.721] <TB0> INFO: Expecting 514560 events.
[09:22:07.615] <TB0> INFO: 514560 events read in total (10492ms).
[09:22:07.665] <TB0> INFO: Test took 174483ms.
[09:22:08.794] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:22:08.803] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:22:08.803] <TB0> INFO: run 1 of 1
[09:22:09.079] <TB0> INFO: Expecting 6281600 events.
[09:22:40.208] <TB0> INFO: 747070 events read in total (30573ms).
[09:23:11.054] <TB0> INFO: 1494520 events read in total (61419ms).
[09:23:41.838] <TB0> INFO: 2243850 events read in total (92203ms).
[09:24:12.838] <TB0> INFO: 2994050 events read in total (123203ms).
[09:24:43.586] <TB0> INFO: 3739040 events read in total (153951ms).
[09:25:14.225] <TB0> INFO: 4480540 events read in total (184590ms).
[09:25:45.079] <TB0> INFO: 5220060 events read in total (215444ms).
[09:26:16.263] <TB0> INFO: 5959470 events read in total (246628ms).
[09:26:29.716] <TB0> INFO: 6281600 events read in total (260081ms).
[09:26:29.770] <TB0> INFO: Test took 260967ms.
[09:26:55.194] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 11.900856 .. 51.469408
[09:26:55.467] <TB0> INFO: Expecting 208000 events.
[09:27:05.729] <TB0> INFO: 208000 events read in total (9706ms).
[09:27:05.729] <TB0> INFO: Test took 10534ms.
[09:27:05.819] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 61 (-1/-1) hits flags = 528 (plus default)
[09:27:05.829] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:27:05.829] <TB0> INFO: run 1 of 1
[09:27:06.137] <TB0> INFO: Expecting 2537600 events.
[09:27:40.718] <TB0> INFO: 972950 events read in total (34024ms).
[09:28:15.025] <TB0> INFO: 1932130 events read in total (68331ms).
[09:28:36.981] <TB0> INFO: 2537600 events read in total (90287ms).
[09:28:37.006] <TB0> INFO: Test took 91177ms.
[09:28:51.442] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 24.029307 .. 47.832704
[09:28:51.713] <TB0> INFO: Expecting 208000 events.
[09:29:01.586] <TB0> INFO: 208000 events read in total (9317ms).
[09:29:01.587] <TB0> INFO: Test took 10143ms.
[09:29:01.671] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 14 .. 57 (-1/-1) hits flags = 528 (plus default)
[09:29:01.679] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:29:01.679] <TB0> INFO: run 1 of 1
[09:29:01.940] <TB0> INFO: Expecting 1830400 events.
[09:29:35.733] <TB0> INFO: 919890 events read in total (33236ms).
[09:30:09.472] <TB0> INFO: 1820640 events read in total (66975ms).
[09:30:10.182] <TB0> INFO: 1830400 events read in total (67685ms).
[09:30:10.200] <TB0> INFO: Test took 68520ms.
[09:30:23.760] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 26.579421 .. 45.263116
[09:30:24.064] <TB0> INFO: Expecting 208000 events.
[09:30:34.152] <TB0> INFO: 208000 events read in total (9532ms).
[09:30:34.153] <TB0> INFO: Test took 10392ms.
[09:30:34.239] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 16 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:30:34.247] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:30:34.247] <TB0> INFO: run 1 of 1
[09:30:34.511] <TB0> INFO: Expecting 1664000 events.
[09:31:08.407] <TB0> INFO: 913760 events read in total (33340ms).
[09:31:36.683] <TB0> INFO: 1664000 events read in total (61616ms).
[09:31:36.704] <TB0> INFO: Test took 62457ms.
[09:31:49.906] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 29.501730 .. 45.259862
[09:31:50.168] <TB0> INFO: Expecting 208000 events.
[09:32:00.311] <TB0> INFO: 208000 events read in total (9587ms).
[09:32:00.312] <TB0> INFO: Test took 10404ms.
[09:32:00.405] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 19 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:32:00.416] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:32:00.416] <TB0> INFO: run 1 of 1
[09:32:00.727] <TB0> INFO: Expecting 1539200 events.
[09:32:34.011] <TB0> INFO: 890370 events read in total (32728ms).
[09:32:58.587] <TB0> INFO: 1539200 events read in total (57305ms).
[09:32:58.613] <TB0> INFO: Test took 58197ms.
[09:33:11.957] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:33:11.957] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:33:11.965] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:33:11.965] <TB0> INFO: run 1 of 1
[09:33:12.225] <TB0> INFO: Expecting 1705600 events.
[09:33:46.191] <TB0> INFO: 916640 events read in total (33410ms).
[09:34:16.030] <TB0> INFO: 1705600 events read in total (63249ms).
[09:34:16.055] <TB0> INFO: Test took 64091ms.
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:34:29.893] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:34:29.894] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:34:29.894] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:34:29.894] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:34:29.894] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:34:29.894] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:34:29.894] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:34:29.894] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:34:29.895] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:34:29.901] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:34:29.908] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:34:29.915] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:34:29.921] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:34:29.928] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:34:29.935] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:34:29.941] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:34:29.948] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:34:29.954] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:34:29.961] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:34:29.967] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:34:29.974] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:34:29.981] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:34:29.987] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:34:29.993] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:34:29.999] <TB0> INFO: PixTestTrim::trimTest() done
[09:34:29.999] <TB0> INFO: vtrim: 93 106 96 92 106 104 95 89 102 113 106 105 89 97 106 101
[09:34:29.999] <TB0> INFO: vthrcomp: 82 89 86 79 96 83 93 85 81 89 81 87 82 85 85 96
[09:34:29.999] <TB0> INFO: vcal mean: 37.73 36.71 36.23 37.68 36.38 36.38 36.00 37.12 37.09 36.69 36.40 36.50 36.93 35.88 35.68 35.68
[09:34:29.999] <TB0> INFO: vcal RMS: 2.59 1.99 1.62 2.52 1.84 1.73 1.49 2.27 2.24 1.97 1.79 1.85 2.14 1.35 1.12 1.22
[09:34:29.999] <TB0> INFO: bits mean: 6.15 6.71 7.38 6.39 7.68 7.33 7.86 7.11 7.11 6.47 7.52 7.70 6.87 7.69 8.07 8.16
[09:34:29.999] <TB0> INFO: bits RMS: 2.59 2.42 2.53 2.27 2.41 2.51 2.59 2.44 2.36 2.59 2.37 2.31 2.39 2.55 2.36 2.52
[09:34:30.006] <TB0> INFO: ----------------------------------------------------------------------
[09:34:30.006] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:34:30.006] <TB0> INFO: ----------------------------------------------------------------------
[09:34:30.008] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:34:30.016] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:34:30.016] <TB0> INFO: run 1 of 1
[09:34:30.277] <TB0> INFO: Expecting 4160000 events.
[09:35:09.100] <TB0> INFO: 976610 events read in total (38267ms).
[09:35:47.115] <TB0> INFO: 1943305 events read in total (76282ms).
[09:36:25.192] <TB0> INFO: 2895990 events read in total (114359ms).
[09:37:02.966] <TB0> INFO: 3846085 events read in total (152133ms).
[09:37:15.850] <TB0> INFO: 4160000 events read in total (165017ms).
[09:37:16.037] <TB0> INFO: Test took 166022ms.
[09:37:45.458] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 189 (-1/-1) hits flags = 528 (plus default)
[09:37:45.466] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:37:45.466] <TB0> INFO: run 1 of 1
[09:37:45.763] <TB0> INFO: Expecting 3952000 events.
[09:38:24.094] <TB0> INFO: 961365 events read in total (37775ms).
[09:39:01.601] <TB0> INFO: 1913835 events read in total (75282ms).
[09:39:39.215] <TB0> INFO: 2852105 events read in total (112896ms).
[09:40:16.805] <TB0> INFO: 3787750 events read in total (150486ms).
[09:40:23.812] <TB0> INFO: 3952000 events read in total (157493ms).
[09:40:23.874] <TB0> INFO: Test took 158409ms.
[09:40:56.011] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[09:40:56.022] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:40:56.022] <TB0> INFO: run 1 of 1
[09:40:56.376] <TB0> INFO: Expecting 3681600 events.
[09:41:35.325] <TB0> INFO: 996515 events read in total (38393ms).
[09:42:13.609] <TB0> INFO: 1978550 events read in total (76677ms).
[09:42:51.313] <TB0> INFO: 2946780 events read in total (114381ms).
[09:43:20.926] <TB0> INFO: 3681600 events read in total (143994ms).
[09:43:20.985] <TB0> INFO: Test took 144962ms.
[09:43:49.193] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 177 (-1/-1) hits flags = 528 (plus default)
[09:43:49.201] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:43:49.201] <TB0> INFO: run 1 of 1
[09:43:49.464] <TB0> INFO: Expecting 3702400 events.
[09:44:29.091] <TB0> INFO: 994825 events read in total (39071ms).
[09:45:07.257] <TB0> INFO: 1974850 events read in total (77237ms).
[09:45:45.341] <TB0> INFO: 2941135 events read in total (115321ms).
[09:46:15.954] <TB0> INFO: 3702400 events read in total (145934ms).
[09:46:16.014] <TB0> INFO: Test took 146813ms.
[09:46:42.973] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[09:46:42.981] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:46:42.981] <TB0> INFO: run 1 of 1
[09:46:43.272] <TB0> INFO: Expecting 3723200 events.
[09:47:22.533] <TB0> INFO: 992395 events read in total (38705ms).
[09:48:00.500] <TB0> INFO: 1970235 events read in total (76672ms).
[09:48:39.092] <TB0> INFO: 2934095 events read in total (115264ms).
[09:49:11.454] <TB0> INFO: 3723200 events read in total (147626ms).
[09:49:11.519] <TB0> INFO: Test took 148538ms.
[09:49:42.277] <TB0> INFO: PixTestTrim::trimBitTest() done
[09:49:42.278] <TB0> INFO: PixTestTrim::doTest() done, duration: 2339 seconds
[09:49:42.278] <TB0> INFO: Decoding statistics:
[09:49:42.278] <TB0> INFO: General information:
[09:49:42.279] <TB0> INFO: 16bit words read: 0
[09:49:42.279] <TB0> INFO: valid events total: 0
[09:49:42.279] <TB0> INFO: empty events: 0
[09:49:42.279] <TB0> INFO: valid events with pixels: 0
[09:49:42.279] <TB0> INFO: valid pixel hits: 0
[09:49:42.279] <TB0> INFO: Event errors: 0
[09:49:42.279] <TB0> INFO: start marker: 0
[09:49:42.279] <TB0> INFO: stop marker: 0
[09:49:42.279] <TB0> INFO: overflow: 0
[09:49:42.279] <TB0> INFO: invalid 5bit words: 0
[09:49:42.279] <TB0> INFO: invalid XOR eye diagram: 0
[09:49:42.279] <TB0> INFO: frame (failed synchr.): 0
[09:49:42.279] <TB0> INFO: idle data (no TBM trl): 0
[09:49:42.279] <TB0> INFO: no data (only TBM hdr): 0
[09:49:42.279] <TB0> INFO: TBM errors: 0
[09:49:42.279] <TB0> INFO: flawed TBM headers: 0
[09:49:42.279] <TB0> INFO: flawed TBM trailers: 0
[09:49:42.279] <TB0> INFO: event ID mismatches: 0
[09:49:42.279] <TB0> INFO: ROC errors: 0
[09:49:42.279] <TB0> INFO: missing ROC header(s): 0
[09:49:42.279] <TB0> INFO: misplaced readback start: 0
[09:49:42.279] <TB0> INFO: Pixel decoding errors: 0
[09:49:42.279] <TB0> INFO: pixel data incomplete: 0
[09:49:42.279] <TB0> INFO: pixel address: 0
[09:49:42.279] <TB0> INFO: pulse height fill bit: 0
[09:49:42.279] <TB0> INFO: buffer corruption: 0
[09:49:42.958] <TB0> INFO: ######################################################################
[09:49:42.958] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:49:42.958] <TB0> INFO: ######################################################################
[09:49:43.220] <TB0> INFO: Expecting 41600 events.
[09:49:46.656] <TB0> INFO: 41600 events read in total (2880ms).
[09:49:46.657] <TB0> INFO: Test took 3697ms.
[09:49:47.117] <TB0> INFO: Expecting 41600 events.
[09:49:50.645] <TB0> INFO: 41600 events read in total (2972ms).
[09:49:50.647] <TB0> INFO: Test took 3795ms.
[09:49:51.019] <TB0> INFO: Expecting 41600 events.
[09:49:54.492] <TB0> INFO: 41600 events read in total (2917ms).
[09:49:54.492] <TB0> INFO: Test took 3817ms.
[09:49:54.748] <TB0> INFO: Expecting 2560 events.
[09:49:55.600] <TB0> INFO: 2560 events read in total (296ms).
[09:49:55.600] <TB0> INFO: Test took 1095ms.
[09:49:55.906] <TB0> INFO: Expecting 2560 events.
[09:49:56.760] <TB0> INFO: 2560 events read in total (298ms).
[09:49:56.760] <TB0> INFO: Test took 1159ms.
[09:49:57.065] <TB0> INFO: Expecting 2560 events.
[09:49:57.918] <TB0> INFO: 2560 events read in total (297ms).
[09:49:57.918] <TB0> INFO: Test took 1157ms.
[09:49:58.223] <TB0> INFO: Expecting 2560 events.
[09:49:59.076] <TB0> INFO: 2560 events read in total (297ms).
[09:49:59.076] <TB0> INFO: Test took 1158ms.
[09:49:59.381] <TB0> INFO: Expecting 2560 events.
[09:50:00.238] <TB0> INFO: 2560 events read in total (301ms).
[09:50:00.238] <TB0> INFO: Test took 1161ms.
[09:50:00.542] <TB0> INFO: Expecting 2560 events.
[09:50:01.394] <TB0> INFO: 2560 events read in total (296ms).
[09:50:01.394] <TB0> INFO: Test took 1156ms.
[09:50:01.699] <TB0> INFO: Expecting 2560 events.
[09:50:02.554] <TB0> INFO: 2560 events read in total (299ms).
[09:50:02.555] <TB0> INFO: Test took 1160ms.
[09:50:02.860] <TB0> INFO: Expecting 2560 events.
[09:50:03.713] <TB0> INFO: 2560 events read in total (297ms).
[09:50:03.713] <TB0> INFO: Test took 1146ms.
[09:50:04.018] <TB0> INFO: Expecting 2560 events.
[09:50:04.875] <TB0> INFO: 2560 events read in total (301ms).
[09:50:04.875] <TB0> INFO: Test took 1161ms.
[09:50:05.179] <TB0> INFO: Expecting 2560 events.
[09:50:06.036] <TB0> INFO: 2560 events read in total (300ms).
[09:50:06.036] <TB0> INFO: Test took 1161ms.
[09:50:06.341] <TB0> INFO: Expecting 2560 events.
[09:50:07.194] <TB0> INFO: 2560 events read in total (297ms).
[09:50:07.194] <TB0> INFO: Test took 1156ms.
[09:50:07.499] <TB0> INFO: Expecting 2560 events.
[09:50:08.353] <TB0> INFO: 2560 events read in total (298ms).
[09:50:08.353] <TB0> INFO: Test took 1158ms.
[09:50:08.658] <TB0> INFO: Expecting 2560 events.
[09:50:09.509] <TB0> INFO: 2560 events read in total (295ms).
[09:50:09.509] <TB0> INFO: Test took 1156ms.
[09:50:09.815] <TB0> INFO: Expecting 2560 events.
[09:50:10.670] <TB0> INFO: 2560 events read in total (297ms).
[09:50:10.670] <TB0> INFO: Test took 1160ms.
[09:50:10.976] <TB0> INFO: Expecting 2560 events.
[09:50:11.828] <TB0> INFO: 2560 events read in total (296ms).
[09:50:11.828] <TB0> INFO: Test took 1157ms.
[09:50:12.133] <TB0> INFO: Expecting 2560 events.
[09:50:12.984] <TB0> INFO: 2560 events read in total (295ms).
[09:50:12.984] <TB0> INFO: Test took 1155ms.
[09:50:12.987] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:50:13.290] <TB0> INFO: Expecting 655360 events.
[09:50:27.520] <TB0> INFO: 655360 events read in total (13673ms).
[09:50:27.530] <TB0> INFO: Expecting 655360 events.
[09:50:41.288] <TB0> INFO: 655360 events read in total (13356ms).
[09:50:41.302] <TB0> INFO: Expecting 655360 events.
[09:50:55.080] <TB0> INFO: 655360 events read in total (13376ms).
[09:50:55.099] <TB0> INFO: Expecting 655360 events.
[09:51:08.831] <TB0> INFO: 655360 events read in total (13330ms).
[09:51:08.854] <TB0> INFO: Expecting 655360 events.
[09:51:22.562] <TB0> INFO: 655360 events read in total (13306ms).
[09:51:22.589] <TB0> INFO: Expecting 655360 events.
[09:51:36.421] <TB0> INFO: 655360 events read in total (13431ms).
[09:51:36.452] <TB0> INFO: Expecting 655360 events.
[09:51:50.147] <TB0> INFO: 655360 events read in total (13293ms).
[09:51:50.185] <TB0> INFO: Expecting 655360 events.
[09:52:03.977] <TB0> INFO: 655360 events read in total (13390ms).
[09:52:04.015] <TB0> INFO: Expecting 655360 events.
[09:52:17.786] <TB0> INFO: 655360 events read in total (13369ms).
[09:52:17.829] <TB0> INFO: Expecting 655360 events.
[09:52:31.611] <TB0> INFO: 655360 events read in total (13380ms).
[09:52:31.660] <TB0> INFO: Expecting 655360 events.
[09:52:45.443] <TB0> INFO: 655360 events read in total (13381ms).
[09:52:45.496] <TB0> INFO: Expecting 655360 events.
[09:52:59.203] <TB0> INFO: 655360 events read in total (13306ms).
[09:52:59.259] <TB0> INFO: Expecting 655360 events.
[09:53:13.087] <TB0> INFO: 655360 events read in total (13426ms).
[09:53:13.158] <TB0> INFO: Expecting 655360 events.
[09:53:26.978] <TB0> INFO: 655360 events read in total (13420ms).
[09:53:27.049] <TB0> INFO: Expecting 655360 events.
[09:53:40.941] <TB0> INFO: 655360 events read in total (13490ms).
[09:53:41.009] <TB0> INFO: Expecting 655360 events.
[09:53:54.768] <TB0> INFO: 655360 events read in total (13357ms).
[09:53:54.845] <TB0> INFO: Test took 221858ms.
[09:53:54.926] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:53:55.157] <TB0> INFO: Expecting 655360 events.
[09:54:08.976] <TB0> INFO: 655360 events read in total (13263ms).
[09:54:08.988] <TB0> INFO: Expecting 655360 events.
[09:54:22.681] <TB0> INFO: 655360 events read in total (13291ms).
[09:54:22.695] <TB0> INFO: Expecting 655360 events.
[09:54:36.597] <TB0> INFO: 655360 events read in total (13500ms).
[09:54:36.618] <TB0> INFO: Expecting 655360 events.
[09:54:50.225] <TB0> INFO: 655360 events read in total (13206ms).
[09:54:50.255] <TB0> INFO: Expecting 655360 events.
[09:55:03.890] <TB0> INFO: 655360 events read in total (13234ms).
[09:55:03.917] <TB0> INFO: Expecting 655360 events.
[09:55:17.598] <TB0> INFO: 655360 events read in total (13279ms).
[09:55:17.631] <TB0> INFO: Expecting 655360 events.
[09:55:31.493] <TB0> INFO: 655360 events read in total (13461ms).
[09:55:31.529] <TB0> INFO: Expecting 655360 events.
[09:55:45.274] <TB0> INFO: 655360 events read in total (13344ms).
[09:55:45.314] <TB0> INFO: Expecting 655360 events.
[09:55:59.085] <TB0> INFO: 655360 events read in total (13369ms).
[09:55:59.138] <TB0> INFO: Expecting 655360 events.
[09:56:12.860] <TB0> INFO: 655360 events read in total (13320ms).
[09:56:12.909] <TB0> INFO: Expecting 655360 events.
[09:56:26.697] <TB0> INFO: 655360 events read in total (13386ms).
[09:56:26.748] <TB0> INFO: Expecting 655360 events.
[09:56:40.509] <TB0> INFO: 655360 events read in total (13359ms).
[09:56:40.566] <TB0> INFO: Expecting 655360 events.
[09:56:54.281] <TB0> INFO: 655360 events read in total (13313ms).
[09:56:54.343] <TB0> INFO: Expecting 655360 events.
[09:57:08.012] <TB0> INFO: 655360 events read in total (13268ms).
[09:57:08.081] <TB0> INFO: Expecting 655360 events.
[09:57:22.028] <TB0> INFO: 655360 events read in total (13545ms).
[09:57:22.098] <TB0> INFO: Expecting 655360 events.
[09:57:35.865] <TB0> INFO: 655360 events read in total (13366ms).
[09:57:35.940] <TB0> INFO: Test took 221015ms.
[09:57:36.126] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.132] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.139] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.147] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.154] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.161] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.168] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.174] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.182] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.190] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.198] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.206] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.213] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.221] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.228] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.236] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:36.288] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:57:36.288] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:57:36.288] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:57:36.289] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:57:36.290] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:57:36.290] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:57:36.300] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:57:36.300] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:57:36.563] <TB0> INFO: Expecting 41600 events.
[09:57:39.699] <TB0> INFO: 41600 events read in total (2579ms).
[09:57:39.700] <TB0> INFO: Test took 3397ms.
[09:57:40.141] <TB0> INFO: Expecting 41600 events.
[09:57:43.182] <TB0> INFO: 41600 events read in total (2485ms).
[09:57:43.183] <TB0> INFO: Test took 3306ms.
[09:57:43.630] <TB0> INFO: Expecting 41600 events.
[09:57:46.728] <TB0> INFO: 41600 events read in total (2542ms).
[09:57:46.729] <TB0> INFO: Test took 3370ms.
[09:57:46.910] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:46.999] <TB0> INFO: Expecting 2560 events.
[09:57:47.851] <TB0> INFO: 2560 events read in total (296ms).
[09:57:47.851] <TB0> INFO: Test took 941ms.
[09:57:47.852] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:48.157] <TB0> INFO: Expecting 2560 events.
[09:57:49.009] <TB0> INFO: 2560 events read in total (296ms).
[09:57:49.009] <TB0> INFO: Test took 1157ms.
[09:57:49.010] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:49.314] <TB0> INFO: Expecting 2560 events.
[09:57:50.168] <TB0> INFO: 2560 events read in total (298ms).
[09:57:50.168] <TB0> INFO: Test took 1158ms.
[09:57:50.170] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:50.473] <TB0> INFO: Expecting 2560 events.
[09:57:51.324] <TB0> INFO: 2560 events read in total (294ms).
[09:57:51.325] <TB0> INFO: Test took 1155ms.
[09:57:51.326] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:51.631] <TB0> INFO: Expecting 2560 events.
[09:57:52.485] <TB0> INFO: 2560 events read in total (298ms).
[09:57:52.485] <TB0> INFO: Test took 1159ms.
[09:57:52.487] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:52.791] <TB0> INFO: Expecting 2560 events.
[09:57:53.643] <TB0> INFO: 2560 events read in total (296ms).
[09:57:53.644] <TB0> INFO: Test took 1157ms.
[09:57:53.645] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:53.949] <TB0> INFO: Expecting 2560 events.
[09:57:54.804] <TB0> INFO: 2560 events read in total (299ms).
[09:57:54.804] <TB0> INFO: Test took 1159ms.
[09:57:54.806] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:55.111] <TB0> INFO: Expecting 2560 events.
[09:57:55.965] <TB0> INFO: 2560 events read in total (298ms).
[09:57:55.965] <TB0> INFO: Test took 1159ms.
[09:57:55.967] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:56.271] <TB0> INFO: Expecting 2560 events.
[09:57:57.123] <TB0> INFO: 2560 events read in total (295ms).
[09:57:57.124] <TB0> INFO: Test took 1157ms.
[09:57:57.125] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:57.430] <TB0> INFO: Expecting 2560 events.
[09:57:58.284] <TB0> INFO: 2560 events read in total (298ms).
[09:57:58.285] <TB0> INFO: Test took 1160ms.
[09:57:58.286] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:58.591] <TB0> INFO: Expecting 2560 events.
[09:57:59.452] <TB0> INFO: 2560 events read in total (305ms).
[09:57:59.452] <TB0> INFO: Test took 1166ms.
[09:57:59.454] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:57:59.757] <TB0> INFO: Expecting 2560 events.
[09:58:00.609] <TB0> INFO: 2560 events read in total (296ms).
[09:58:00.609] <TB0> INFO: Test took 1155ms.
[09:58:00.611] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:00.915] <TB0> INFO: Expecting 2560 events.
[09:58:01.769] <TB0> INFO: 2560 events read in total (298ms).
[09:58:01.769] <TB0> INFO: Test took 1158ms.
[09:58:01.772] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:02.074] <TB0> INFO: Expecting 2560 events.
[09:58:02.934] <TB0> INFO: 2560 events read in total (303ms).
[09:58:02.934] <TB0> INFO: Test took 1162ms.
[09:58:02.935] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:03.240] <TB0> INFO: Expecting 2560 events.
[09:58:04.102] <TB0> INFO: 2560 events read in total (305ms).
[09:58:04.102] <TB0> INFO: Test took 1167ms.
[09:58:04.105] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:04.406] <TB0> INFO: Expecting 2560 events.
[09:58:05.260] <TB0> INFO: 2560 events read in total (297ms).
[09:58:05.261] <TB0> INFO: Test took 1156ms.
[09:58:05.264] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:05.565] <TB0> INFO: Expecting 2560 events.
[09:58:06.425] <TB0> INFO: 2560 events read in total (304ms).
[09:58:06.426] <TB0> INFO: Test took 1162ms.
[09:58:06.428] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:06.730] <TB0> INFO: Expecting 2560 events.
[09:58:07.588] <TB0> INFO: 2560 events read in total (301ms).
[09:58:07.588] <TB0> INFO: Test took 1160ms.
[09:58:07.591] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:07.893] <TB0> INFO: Expecting 2560 events.
[09:58:08.746] <TB0> INFO: 2560 events read in total (297ms).
[09:58:08.747] <TB0> INFO: Test took 1156ms.
[09:58:08.749] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:09.051] <TB0> INFO: Expecting 2560 events.
[09:58:09.905] <TB0> INFO: 2560 events read in total (298ms).
[09:58:09.905] <TB0> INFO: Test took 1156ms.
[09:58:09.908] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:10.210] <TB0> INFO: Expecting 2560 events.
[09:58:11.068] <TB0> INFO: 2560 events read in total (301ms).
[09:58:11.068] <TB0> INFO: Test took 1161ms.
[09:58:11.070] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:11.373] <TB0> INFO: Expecting 2560 events.
[09:58:12.233] <TB0> INFO: 2560 events read in total (304ms).
[09:58:12.233] <TB0> INFO: Test took 1163ms.
[09:58:12.235] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:12.539] <TB0> INFO: Expecting 2560 events.
[09:58:13.390] <TB0> INFO: 2560 events read in total (295ms).
[09:58:13.390] <TB0> INFO: Test took 1155ms.
[09:58:13.392] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:13.696] <TB0> INFO: Expecting 2560 events.
[09:58:14.551] <TB0> INFO: 2560 events read in total (299ms).
[09:58:14.552] <TB0> INFO: Test took 1160ms.
[09:58:14.553] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:14.857] <TB0> INFO: Expecting 2560 events.
[09:58:15.713] <TB0> INFO: 2560 events read in total (300ms).
[09:58:15.713] <TB0> INFO: Test took 1160ms.
[09:58:15.715] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:16.019] <TB0> INFO: Expecting 2560 events.
[09:58:16.874] <TB0> INFO: 2560 events read in total (299ms).
[09:58:16.874] <TB0> INFO: Test took 1159ms.
[09:58:16.877] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:17.179] <TB0> INFO: Expecting 2560 events.
[09:58:18.035] <TB0> INFO: 2560 events read in total (300ms).
[09:58:18.035] <TB0> INFO: Test took 1158ms.
[09:58:18.038] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:18.339] <TB0> INFO: Expecting 2560 events.
[09:58:19.202] <TB0> INFO: 2560 events read in total (306ms).
[09:58:19.203] <TB0> INFO: Test took 1165ms.
[09:58:19.206] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:19.508] <TB0> INFO: Expecting 2560 events.
[09:58:20.370] <TB0> INFO: 2560 events read in total (306ms).
[09:58:20.370] <TB0> INFO: Test took 1165ms.
[09:58:20.373] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:20.675] <TB0> INFO: Expecting 2560 events.
[09:58:21.533] <TB0> INFO: 2560 events read in total (302ms).
[09:58:21.533] <TB0> INFO: Test took 1161ms.
[09:58:21.535] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:21.838] <TB0> INFO: Expecting 2560 events.
[09:58:22.695] <TB0> INFO: 2560 events read in total (301ms).
[09:58:22.695] <TB0> INFO: Test took 1160ms.
[09:58:22.697] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:23.000] <TB0> INFO: Expecting 2560 events.
[09:58:23.857] <TB0> INFO: 2560 events read in total (301ms).
[09:58:23.858] <TB0> INFO: Test took 1161ms.
[09:58:24.330] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 521 seconds
[09:58:24.330] <TB0> INFO: PH scale (per ROC): 69 68 73 75 70 75 70 72 80 75 77 70 77 78 82 72
[09:58:24.330] <TB0> INFO: PH offset (per ROC): 177 164 175 181 184 165 173 176 159 167 187 173 179 175 164 175
[09:58:24.335] <TB0> INFO: Decoding statistics:
[09:58:24.335] <TB0> INFO: General information:
[09:58:24.335] <TB0> INFO: 16bit words read: 86920
[09:58:24.335] <TB0> INFO: valid events total: 10240
[09:58:24.335] <TB0> INFO: empty events: 7740
[09:58:24.335] <TB0> INFO: valid events with pixels: 2500
[09:58:24.335] <TB0> INFO: valid pixel hits: 2500
[09:58:24.335] <TB0> INFO: Event errors: 0
[09:58:24.335] <TB0> INFO: start marker: 0
[09:58:24.335] <TB0> INFO: stop marker: 0
[09:58:24.335] <TB0> INFO: overflow: 0
[09:58:24.335] <TB0> INFO: invalid 5bit words: 0
[09:58:24.335] <TB0> INFO: invalid XOR eye diagram: 0
[09:58:24.335] <TB0> INFO: frame (failed synchr.): 0
[09:58:24.335] <TB0> INFO: idle data (no TBM trl): 0
[09:58:24.335] <TB0> INFO: no data (only TBM hdr): 0
[09:58:24.335] <TB0> INFO: TBM errors: 0
[09:58:24.335] <TB0> INFO: flawed TBM headers: 0
[09:58:24.336] <TB0> INFO: flawed TBM trailers: 0
[09:58:24.336] <TB0> INFO: event ID mismatches: 0
[09:58:24.336] <TB0> INFO: ROC errors: 0
[09:58:24.336] <TB0> INFO: missing ROC header(s): 0
[09:58:24.336] <TB0> INFO: misplaced readback start: 0
[09:58:24.336] <TB0> INFO: Pixel decoding errors: 0
[09:58:24.336] <TB0> INFO: pixel data incomplete: 0
[09:58:24.336] <TB0> INFO: pixel address: 0
[09:58:24.336] <TB0> INFO: pulse height fill bit: 0
[09:58:24.336] <TB0> INFO: buffer corruption: 0
[09:58:24.511] <TB0> INFO: ######################################################################
[09:58:24.511] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:58:24.511] <TB0> INFO: ######################################################################
[09:58:24.521] <TB0> INFO: scanning low vcal = 10
[09:58:24.792] <TB0> INFO: Expecting 41600 events.
[09:58:28.187] <TB0> INFO: 41600 events read in total (2839ms).
[09:58:28.187] <TB0> INFO: Test took 3666ms.
[09:58:28.188] <TB0> INFO: scanning low vcal = 20
[09:58:28.485] <TB0> INFO: Expecting 41600 events.
[09:58:31.898] <TB0> INFO: 41600 events read in total (2856ms).
[09:58:31.898] <TB0> INFO: Test took 3710ms.
[09:58:31.900] <TB0> INFO: scanning low vcal = 30
[09:58:32.193] <TB0> INFO: Expecting 41600 events.
[09:58:35.610] <TB0> INFO: 41600 events read in total (2861ms).
[09:58:35.610] <TB0> INFO: Test took 3710ms.
[09:58:35.612] <TB0> INFO: scanning low vcal = 40
[09:58:35.902] <TB0> INFO: Expecting 41600 events.
[09:58:39.709] <TB0> INFO: 41600 events read in total (3251ms).
[09:58:39.709] <TB0> INFO: Test took 4097ms.
[09:58:39.712] <TB0> INFO: scanning low vcal = 50
[09:58:39.977] <TB0> INFO: Expecting 41600 events.
[09:58:43.912] <TB0> INFO: 41600 events read in total (3379ms).
[09:58:43.913] <TB0> INFO: Test took 4201ms.
[09:58:43.916] <TB0> INFO: scanning low vcal = 60
[09:58:44.180] <TB0> INFO: Expecting 41600 events.
[09:58:48.133] <TB0> INFO: 41600 events read in total (3397ms).
[09:58:48.133] <TB0> INFO: Test took 4217ms.
[09:58:48.135] <TB0> INFO: scanning low vcal = 70
[09:58:48.399] <TB0> INFO: Expecting 41600 events.
[09:58:52.337] <TB0> INFO: 41600 events read in total (3382ms).
[09:58:52.338] <TB0> INFO: Test took 4202ms.
[09:58:52.340] <TB0> INFO: scanning low vcal = 80
[09:58:52.601] <TB0> INFO: Expecting 41600 events.
[09:58:56.550] <TB0> INFO: 41600 events read in total (3393ms).
[09:58:56.550] <TB0> INFO: Test took 4209ms.
[09:58:56.554] <TB0> INFO: scanning low vcal = 90
[09:58:56.819] <TB0> INFO: Expecting 41600 events.
[09:59:00.793] <TB0> INFO: 41600 events read in total (3418ms).
[09:59:00.793] <TB0> INFO: Test took 4239ms.
[09:59:00.796] <TB0> INFO: scanning low vcal = 100
[09:59:01.065] <TB0> INFO: Expecting 41600 events.
[09:59:05.034] <TB0> INFO: 41600 events read in total (3413ms).
[09:59:05.035] <TB0> INFO: Test took 4239ms.
[09:59:05.037] <TB0> INFO: scanning low vcal = 110
[09:59:05.302] <TB0> INFO: Expecting 41600 events.
[09:59:09.242] <TB0> INFO: 41600 events read in total (3384ms).
[09:59:09.242] <TB0> INFO: Test took 4205ms.
[09:59:09.245] <TB0> INFO: scanning low vcal = 120
[09:59:09.508] <TB0> INFO: Expecting 41600 events.
[09:59:13.447] <TB0> INFO: 41600 events read in total (3383ms).
[09:59:13.448] <TB0> INFO: Test took 4203ms.
[09:59:13.450] <TB0> INFO: scanning low vcal = 130
[09:59:13.714] <TB0> INFO: Expecting 41600 events.
[09:59:17.678] <TB0> INFO: 41600 events read in total (3408ms).
[09:59:17.678] <TB0> INFO: Test took 4228ms.
[09:59:17.682] <TB0> INFO: scanning low vcal = 140
[09:59:17.955] <TB0> INFO: Expecting 41600 events.
[09:59:21.913] <TB0> INFO: 41600 events read in total (3402ms).
[09:59:21.913] <TB0> INFO: Test took 4231ms.
[09:59:21.916] <TB0> INFO: scanning low vcal = 150
[09:59:22.196] <TB0> INFO: Expecting 41600 events.
[09:59:26.176] <TB0> INFO: 41600 events read in total (3424ms).
[09:59:26.177] <TB0> INFO: Test took 4261ms.
[09:59:26.179] <TB0> INFO: scanning low vcal = 160
[09:59:26.445] <TB0> INFO: Expecting 41600 events.
[09:59:30.401] <TB0> INFO: 41600 events read in total (3400ms).
[09:59:30.401] <TB0> INFO: Test took 4222ms.
[09:59:30.404] <TB0> INFO: scanning low vcal = 170
[09:59:30.674] <TB0> INFO: Expecting 41600 events.
[09:59:34.605] <TB0> INFO: 41600 events read in total (3375ms).
[09:59:34.606] <TB0> INFO: Test took 4202ms.
[09:59:34.611] <TB0> INFO: scanning low vcal = 180
[09:59:34.874] <TB0> INFO: Expecting 41600 events.
[09:59:38.817] <TB0> INFO: 41600 events read in total (3387ms).
[09:59:38.817] <TB0> INFO: Test took 4206ms.
[09:59:38.820] <TB0> INFO: scanning low vcal = 190
[09:59:39.080] <TB0> INFO: Expecting 41600 events.
[09:59:43.057] <TB0> INFO: 41600 events read in total (3421ms).
[09:59:43.057] <TB0> INFO: Test took 4237ms.
[09:59:43.061] <TB0> INFO: scanning low vcal = 200
[09:59:43.322] <TB0> INFO: Expecting 41600 events.
[09:59:47.257] <TB0> INFO: 41600 events read in total (3379ms).
[09:59:47.258] <TB0> INFO: Test took 4197ms.
[09:59:47.260] <TB0> INFO: scanning low vcal = 210
[09:59:47.526] <TB0> INFO: Expecting 41600 events.
[09:59:51.473] <TB0> INFO: 41600 events read in total (3391ms).
[09:59:51.474] <TB0> INFO: Test took 4214ms.
[09:59:51.476] <TB0> INFO: scanning low vcal = 220
[09:59:51.742] <TB0> INFO: Expecting 41600 events.
[09:59:55.670] <TB0> INFO: 41600 events read in total (3372ms).
[09:59:55.670] <TB0> INFO: Test took 4194ms.
[09:59:55.673] <TB0> INFO: scanning low vcal = 230
[09:59:55.931] <TB0> INFO: Expecting 41600 events.
[09:59:59.877] <TB0> INFO: 41600 events read in total (3390ms).
[09:59:59.878] <TB0> INFO: Test took 4205ms.
[09:59:59.880] <TB0> INFO: scanning low vcal = 240
[10:00:00.144] <TB0> INFO: Expecting 41600 events.
[10:00:04.064] <TB0> INFO: 41600 events read in total (3364ms).
[10:00:04.065] <TB0> INFO: Test took 4184ms.
[10:00:04.068] <TB0> INFO: scanning low vcal = 250
[10:00:04.332] <TB0> INFO: Expecting 41600 events.
[10:00:08.284] <TB0> INFO: 41600 events read in total (3396ms).
[10:00:08.284] <TB0> INFO: Test took 4216ms.
[10:00:08.288] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[10:00:08.556] <TB0> INFO: Expecting 41600 events.
[10:00:12.494] <TB0> INFO: 41600 events read in total (3382ms).
[10:00:12.494] <TB0> INFO: Test took 4206ms.
[10:00:12.497] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[10:00:12.773] <TB0> INFO: Expecting 41600 events.
[10:00:16.714] <TB0> INFO: 41600 events read in total (3384ms).
[10:00:16.715] <TB0> INFO: Test took 4218ms.
[10:00:16.718] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[10:00:16.986] <TB0> INFO: Expecting 41600 events.
[10:00:20.929] <TB0> INFO: 41600 events read in total (3386ms).
[10:00:20.929] <TB0> INFO: Test took 4211ms.
[10:00:20.932] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[10:00:21.199] <TB0> INFO: Expecting 41600 events.
[10:00:25.156] <TB0> INFO: 41600 events read in total (3401ms).
[10:00:25.157] <TB0> INFO: Test took 4225ms.
[10:00:25.159] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:00:25.434] <TB0> INFO: Expecting 41600 events.
[10:00:29.350] <TB0> INFO: 41600 events read in total (3360ms).
[10:00:29.351] <TB0> INFO: Test took 4191ms.
[10:00:29.815] <TB0> INFO: PixTestGainPedestal::measure() done
[10:01:02.616] <TB0> INFO: PixTestGainPedestal::fit() done
[10:01:02.616] <TB0> INFO: non-linearity mean: 0.949 0.956 0.948 0.955 0.959 0.956 0.959 0.949 0.961 0.953 0.952 0.953 0.952 0.956 0.954 0.951
[10:01:02.616] <TB0> INFO: non-linearity RMS: 0.008 0.008 0.007 0.006 0.009 0.006 0.006 0.008 0.006 0.007 0.007 0.007 0.007 0.005 0.007 0.008
[10:01:02.617] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:01:02.635] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:01:02.654] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:01:02.672] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:01:02.691] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:01:02.710] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:01:02.751] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:01:02.769] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:01:02.788] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:01:02.806] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:01:02.828] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:01:02.847] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:01:02.867] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:01:02.887] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:01:02.908] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:01:02.928] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:01:02.949] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[10:01:02.949] <TB0> INFO: Decoding statistics:
[10:01:02.949] <TB0> INFO: General information:
[10:01:02.949] <TB0> INFO: 16bit words read: 2662400
[10:01:02.949] <TB0> INFO: valid events total: 166400
[10:01:02.949] <TB0> INFO: empty events: 0
[10:01:02.949] <TB0> INFO: valid events with pixels: 166400
[10:01:02.949] <TB0> INFO: valid pixel hits: 665600
[10:01:02.949] <TB0> INFO: Event errors: 0
[10:01:02.949] <TB0> INFO: start marker: 0
[10:01:02.949] <TB0> INFO: stop marker: 0
[10:01:02.949] <TB0> INFO: overflow: 0
[10:01:02.949] <TB0> INFO: invalid 5bit words: 0
[10:01:02.949] <TB0> INFO: invalid XOR eye diagram: 0
[10:01:02.949] <TB0> INFO: frame (failed synchr.): 0
[10:01:02.949] <TB0> INFO: idle data (no TBM trl): 0
[10:01:02.949] <TB0> INFO: no data (only TBM hdr): 0
[10:01:02.949] <TB0> INFO: TBM errors: 0
[10:01:02.949] <TB0> INFO: flawed TBM headers: 0
[10:01:02.949] <TB0> INFO: flawed TBM trailers: 0
[10:01:02.949] <TB0> INFO: event ID mismatches: 0
[10:01:02.949] <TB0> INFO: ROC errors: 0
[10:01:02.949] <TB0> INFO: missing ROC header(s): 0
[10:01:02.949] <TB0> INFO: misplaced readback start: 0
[10:01:02.949] <TB0> INFO: Pixel decoding errors: 0
[10:01:02.949] <TB0> INFO: pixel data incomplete: 0
[10:01:02.949] <TB0> INFO: pixel address: 0
[10:01:02.949] <TB0> INFO: pulse height fill bit: 0
[10:01:02.949] <TB0> INFO: buffer corruption: 0
[10:01:02.957] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:01:02.977] <TB0> INFO: ######################################################################
[10:01:02.977] <TB0> INFO: PixTestReadback::doTest()
[10:01:02.977] <TB0> INFO: ######################################################################
[10:01:02.977] <TB0> INFO: ----------------------------------------------------------------------
[10:01:02.977] <TB0> INFO: PixTestReadback::CalibrateVd()
[10:01:02.977] <TB0> INFO: ----------------------------------------------------------------------
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:01:12.409] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:01:12.410] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:01:12.410] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:01:12.410] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:01:12.410] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:01:12.434] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:01:12.434] <TB0> INFO: ----------------------------------------------------------------------
[10:01:12.434] <TB0> INFO: PixTestReadback::CalibrateVa()
[10:01:12.434] <TB0> INFO: ----------------------------------------------------------------------
[10:01:21.886] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:01:21.886] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:01:21.886] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:01:21.887] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:01:21.888] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:01:21.888] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:01:21.888] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:01:21.888] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:01:21.918] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:01:21.918] <TB0> INFO: ----------------------------------------------------------------------
[10:01:21.918] <TB0> INFO: PixTestReadback::readbackVbg()
[10:01:21.918] <TB0> INFO: ----------------------------------------------------------------------
[10:01:29.205] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:01:29.205] <TB0> INFO: ----------------------------------------------------------------------
[10:01:29.205] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[10:01:29.205] <TB0> INFO: ----------------------------------------------------------------------
[10:01:29.205] <TB0> INFO: Vbg will be calibrated using Vd calibration
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148calibrated Vbg = 1.2329 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.9calibrated Vbg = 1.23053 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.8calibrated Vbg = 1.23081 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.1calibrated Vbg = 1.23551 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 162.2calibrated Vbg = 1.24544 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 147.9calibrated Vbg = 1.23819 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.2calibrated Vbg = 1.23841 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 145.3calibrated Vbg = 1.23679 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.1calibrated Vbg = 1.23589 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.7calibrated Vbg = 1.23794 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 157calibrated Vbg = 1.2417 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145calibrated Vbg = 1.24113 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.2calibrated Vbg = 1.23219 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148.9calibrated Vbg = 1.2321 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148.4calibrated Vbg = 1.23917 :::*/*/*/*/
[10:01:29.205] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154calibrated Vbg = 1.22966 :::*/*/*/*/
[10:01:29.207] <TB0> INFO: ----------------------------------------------------------------------
[10:01:29.207] <TB0> INFO: PixTestReadback::CalibrateIa()
[10:01:29.207] <TB0> INFO: ----------------------------------------------------------------------
[10:04:04.496] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:04:04.496] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:04:04.496] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:04:04.496] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:04:04.496] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:04:04.496] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:04:04.497] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2327_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:04:04.523] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:04:04.524] <TB0> INFO: PixTestReadback::doTest() done
[10:04:04.524] <TB0> INFO: Decoding statistics:
[10:04:04.524] <TB0> INFO: General information:
[10:04:04.524] <TB0> INFO: 16bit words read: 1024
[10:04:04.524] <TB0> INFO: valid events total: 128
[10:04:04.524] <TB0> INFO: empty events: 128
[10:04:04.524] <TB0> INFO: valid events with pixels: 0
[10:04:04.524] <TB0> INFO: valid pixel hits: 0
[10:04:04.524] <TB0> INFO: Event errors: 0
[10:04:04.524] <TB0> INFO: start marker: 0
[10:04:04.524] <TB0> INFO: stop marker: 0
[10:04:04.524] <TB0> INFO: overflow: 0
[10:04:04.524] <TB0> INFO: invalid 5bit words: 0
[10:04:04.524] <TB0> INFO: invalid XOR eye diagram: 0
[10:04:04.524] <TB0> INFO: frame (failed synchr.): 0
[10:04:04.524] <TB0> INFO: idle data (no TBM trl): 0
[10:04:04.524] <TB0> INFO: no data (only TBM hdr): 0
[10:04:04.524] <TB0> INFO: TBM errors: 0
[10:04:04.524] <TB0> INFO: flawed TBM headers: 0
[10:04:04.524] <TB0> INFO: flawed TBM trailers: 0
[10:04:04.524] <TB0> INFO: event ID mismatches: 0
[10:04:04.524] <TB0> INFO: ROC errors: 0
[10:04:04.524] <TB0> INFO: missing ROC header(s): 0
[10:04:04.524] <TB0> INFO: misplaced readback start: 0
[10:04:04.524] <TB0> INFO: Pixel decoding errors: 0
[10:04:04.524] <TB0> INFO: pixel data incomplete: 0
[10:04:04.524] <TB0> INFO: pixel address: 0
[10:04:04.524] <TB0> INFO: pulse height fill bit: 0
[10:04:04.524] <TB0> INFO: buffer corruption: 0
[10:04:04.542] <TB0> INFO: Decoding statistics:
[10:04:04.543] <TB0> INFO: General information:
[10:04:04.543] <TB0> INFO: 16bit words read: 2750344
[10:04:04.543] <TB0> INFO: valid events total: 176768
[10:04:04.543] <TB0> INFO: empty events: 7868
[10:04:04.543] <TB0> INFO: valid events with pixels: 168900
[10:04:04.543] <TB0> INFO: valid pixel hits: 668100
[10:04:04.543] <TB0> INFO: Event errors: 0
[10:04:04.543] <TB0> INFO: start marker: 0
[10:04:04.543] <TB0> INFO: stop marker: 0
[10:04:04.543] <TB0> INFO: overflow: 0
[10:04:04.543] <TB0> INFO: invalid 5bit words: 0
[10:04:04.543] <TB0> INFO: invalid XOR eye diagram: 0
[10:04:04.543] <TB0> INFO: frame (failed synchr.): 0
[10:04:04.543] <TB0> INFO: idle data (no TBM trl): 0
[10:04:04.543] <TB0> INFO: no data (only TBM hdr): 0
[10:04:04.543] <TB0> INFO: TBM errors: 0
[10:04:04.543] <TB0> INFO: flawed TBM headers: 0
[10:04:04.543] <TB0> INFO: flawed TBM trailers: 0
[10:04:04.543] <TB0> INFO: event ID mismatches: 0
[10:04:04.543] <TB0> INFO: ROC errors: 0
[10:04:04.543] <TB0> INFO: missing ROC header(s): 0
[10:04:04.543] <TB0> INFO: misplaced readback start: 0
[10:04:04.543] <TB0> INFO: Pixel decoding errors: 0
[10:04:04.543] <TB0> INFO: pixel data incomplete: 0
[10:04:04.543] <TB0> INFO: pixel address: 0
[10:04:04.543] <TB0> INFO: pulse height fill bit: 0
[10:04:04.543] <TB0> INFO: buffer corruption: 0
[10:04:04.543] <TB0> INFO: enter test to run
[10:04:04.543] <TB0> INFO: test: exit no parameter change
[10:04:04.727] <TB0> QUIET: Connection to board 73 closed.
[10:04:04.807] <TB0> INFO: pXar: this is the end, my friend