[16:09:59.066] <TB2> INFO: *** Welcome to pxar ***
[16:09:59.066] <TB2> INFO: *** Today: 2016/01/20
[16:09:59.162] <TB2> INFO: *** Version: 3197-dirty
[16:09:59.162] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C15.dat
[16:09:59.163] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//tbmParameters_C0b.dat
[16:09:59.163] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//defaultMaskFile.dat
[16:09:59.163] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters_C15.dat
[16:09:59.244] <TB2> INFO: clk: 4
[16:09:59.244] <TB2> INFO: ctr: 4
[16:09:59.244] <TB2> INFO: sda: 19
[16:09:59.244] <TB2> INFO: tin: 9
[16:09:59.244] <TB2> INFO: level: 15
[16:09:59.244] <TB2> INFO: triggerdelay: 0
[16:09:59.244] <TB2> QUIET: Instanciating API for pxar prod-12
[16:09:59.244] <TB2> INFO: Log level: INFO
[16:09:59.250] <TB2> INFO: Found DTB DTB_WXC55Z
[16:09:59.257] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[16:09:59.260] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[16:09:59.262] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[16:10:00.788] <TB2> INFO: DUT info:
[16:10:00.788] <TB2> INFO: The DUT currently contains the following objects:
[16:10:00.788] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[16:10:00.788] <TB2> INFO: TBM Core alpha (0): 7 registers set
[16:10:00.788] <TB2> INFO: TBM Core beta (1): 7 registers set
[16:10:00.788] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:10:00.788] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.788] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.788] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.788] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.788] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.788] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:00.789] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:01.190] <TB2> INFO: enter 'restricted' command line mode
[16:10:01.190] <TB2> INFO: enter test to run
[16:10:01.190] <TB2> INFO: test: pretest no parameter change
[16:10:01.190] <TB2> INFO: running: pretest
[16:10:01.200] <TB2> INFO: ----------------------------------------------------------------------
[16:10:01.200] <TB2> INFO: PixTestPretest::programROC()
[16:10:01.200] <TB2> INFO: ----------------------------------------------------------------------
[16:10:19.218] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:10:19.218] <TB2> INFO: IA differences per ROC: 19.3 16.9 18.5 16.1 18.5 18.5 16.9 17.7 18.5 18.5 16.9 17.7 20.9 17.7 19.3 18.5
[16:10:19.314] <TB2> INFO: enter test to run
[16:10:19.314] <TB2> INFO: test: pretest no parameter change
[16:10:19.314] <TB2> INFO: running: pretest
[16:10:19.315] <TB2> INFO: ----------------------------------------------------------------------
[16:10:19.315] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:10:19.315] <TB2> INFO: ----------------------------------------------------------------------
[16:10:40.592] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 394.7 mA = 24.6687 mA/ROC
[16:10:40.592] <TB2> INFO: i(loss) [mA/ROC]: 20.9 19.3 20.1 20.1 19.3 19.3 18.5 19.3 19.3 20.1 19.3 18.5 19.3 20.1 20.1 20.1
[16:10:40.620] <TB2> INFO: enter test to run
[16:10:40.620] <TB2> INFO: test: pretest no parameter change
[16:10:40.620] <TB2> INFO: running: pretest
[16:10:40.620] <TB2> INFO: ----------------------------------------------------------------------
[16:10:40.620] <TB2> INFO: PixTestPretest::findTiming()
[16:10:40.620] <TB2> INFO: ----------------------------------------------------------------------
[16:10:40.620] <TB2> INFO: PixTestCmd::init()

[16:12:20.492] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[16:12:20.492] <TB2> INFO: (success/tries = 100/100), width = 4
[16:12:20.492] <TB2> INFO: enter test to run
[16:12:20.492] <TB2> INFO: test: pretest no parameter change
[16:12:20.492] <TB2> INFO: running: pretest
[16:12:20.494] <TB2> INFO: ----------------------------------------------------------------------
[16:12:20.494] <TB2> INFO: PixTestPretest::findWorkingPixel()
[16:12:20.494] <TB2> INFO: ----------------------------------------------------------------------
[16:12:20.631] <TB2> INFO: Expecting 231680 events.
[16:12:29.001] <TB2> INFO: 231680 events read in total (7654ms).
[16:12:29.004] <TB2> INFO: Test took 8508ms.
[16:12:29.310] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[16:12:29.371] <TB2> INFO: enter test to run
[16:12:29.371] <TB2> INFO: test: pretest no parameter change
[16:12:29.371] <TB2> INFO: running: pretest
[16:12:29.372] <TB2> INFO: ----------------------------------------------------------------------
[16:12:29.372] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[16:12:29.372] <TB2> INFO: ----------------------------------------------------------------------
[16:12:29.506] <TB2> INFO: Expecting 231680 events.
[16:12:38.009] <TB2> INFO: 231680 events read in total (7788ms).
[16:12:38.013] <TB2> INFO: Test took 8638ms.
[16:12:38.318] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[16:12:38.319] <TB2> INFO: CalDel: 141 144 156 130 138 142 133 125 132 130 127 146 154 128 146 128
[16:12:38.319] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 54 51 51 51
[16:12:38.353] <TB2> INFO: enter test to run
[16:12:38.353] <TB2> INFO: test: pretest no parameter change
[16:12:38.353] <TB2> INFO: running: pretest
[16:12:38.353] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C0.dat
[16:12:38.353] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C1.dat
[16:12:38.353] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C2.dat
[16:12:38.353] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C3.dat
[16:12:38.353] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C4.dat
[16:12:38.354] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C5.dat
[16:12:38.354] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C6.dat
[16:12:38.354] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C7.dat
[16:12:38.354] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C8.dat
[16:12:38.354] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C9.dat
[16:12:38.354] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C10.dat
[16:12:38.354] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C11.dat
[16:12:38.355] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C12.dat
[16:12:38.355] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C13.dat
[16:12:38.355] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C14.dat
[16:12:38.355] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters_C15.dat
[16:12:38.355] <TB2> INFO: enter test to run
[16:12:38.355] <TB2> INFO: test: fulltest no parameter change
[16:12:38.355] <TB2> INFO: running: fulltest
[16:12:38.355] <TB2> INFO: ######################################################################
[16:12:38.355] <TB2> INFO: PixTestFullTest::doTest()
[16:12:38.355] <TB2> INFO: ######################################################################
[16:12:38.357] <TB2> INFO: ######################################################################
[16:12:38.357] <TB2> INFO: PixTestAlive::doTest()
[16:12:38.357] <TB2> INFO: ######################################################################
[16:12:38.358] <TB2> INFO: ----------------------------------------------------------------------
[16:12:38.358] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:12:38.358] <TB2> INFO: ----------------------------------------------------------------------
[16:12:38.661] <TB2> INFO: Expecting 41600 events.
[16:12:42.961] <TB2> INFO: 41600 events read in total (3584ms).
[16:12:42.962] <TB2> INFO: Test took 4603ms.
[16:12:42.968] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:43.246] <TB2> INFO: PixTestAlive::aliveTest() done
[16:12:43.246] <TB2> INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 0 2 0 0 0 0 0 0
[16:12:43.248] <TB2> INFO: ----------------------------------------------------------------------
[16:12:43.248] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:12:43.248] <TB2> INFO: ----------------------------------------------------------------------
[16:12:43.555] <TB2> INFO: Expecting 41600 events.
[16:12:46.699] <TB2> INFO: 41600 events read in total (2428ms).
[16:12:46.699] <TB2> INFO: Test took 3450ms.
[16:12:46.699] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:46.699] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:12:47.004] <TB2> INFO: PixTestAlive::maskTest() done
[16:12:47.005] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:12:47.006] <TB2> INFO: ----------------------------------------------------------------------
[16:12:47.006] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:12:47.006] <TB2> INFO: ----------------------------------------------------------------------
[16:12:47.351] <TB2> INFO: Expecting 41600 events.
[16:12:51.612] <TB2> INFO: 41600 events read in total (3546ms).
[16:12:51.613] <TB2> INFO: Test took 4606ms.
[16:12:51.620] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:51.905] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[16:12:51.905] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:12:51.905] <TB2> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:12:51.905] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:51.905] <TB2> INFO: Decoding statistics:
[16:12:51.905] <TB2> INFO: General information:
[16:12:51.905] <TB2> INFO: 16bit words read: 0
[16:12:51.905] <TB2> INFO: valid events total: 0
[16:12:51.905] <TB2> INFO: empty events: 0
[16:12:51.905] <TB2> INFO: valid events with pixels: 0
[16:12:51.905] <TB2> INFO: valid pixel hits: 0
[16:12:51.905] <TB2> INFO: Event errors: 0
[16:12:51.905] <TB2> INFO: start marker: 0
[16:12:51.905] <TB2> INFO: stop marker: 0
[16:12:51.905] <TB2> INFO: overflow: 0
[16:12:51.905] <TB2> INFO: invalid 5bit words: 0
[16:12:51.905] <TB2> INFO: invalid XOR eye diagram: 0
[16:12:51.905] <TB2> INFO: TBM errors: 0
[16:12:51.905] <TB2> INFO: flawed TBM headers: 0
[16:12:51.905] <TB2> INFO: flawed TBM trailers: 0
[16:12:51.905] <TB2> INFO: event ID mismatches: 0
[16:12:51.905] <TB2> INFO: ROC errors: 0
[16:12:51.905] <TB2> INFO: missing ROC header(s): 0
[16:12:51.905] <TB2> INFO: misplaced readback start: 0
[16:12:51.905] <TB2> INFO: Pixel decoding errors: 0
[16:12:51.905] <TB2> INFO: pixel data incomplete: 0
[16:12:51.905] <TB2> INFO: pixel address: 0
[16:12:51.905] <TB2> INFO: pulse height fill bit: 0
[16:12:51.905] <TB2> INFO: buffer corruption: 0
[16:12:51.913] <TB2> INFO: ######################################################################
[16:12:51.913] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:12:51.913] <TB2> INFO: ######################################################################
[16:12:51.916] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[16:12:51.926] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[16:12:51.926] <TB2> INFO: run 1 of 1
[16:12:52.233] <TB2> INFO: Expecting 3120000 events.
[16:13:34.394] <TB2> INFO: 923925 events read in total (41445ms).
[16:14:15.503] <TB2> INFO: 1836180 events read in total (82555ms).
[16:14:58.316] <TB2> INFO: 2753630 events read in total (125368ms).
[16:15:14.572] <TB2> INFO: 3120000 events read in total (141623ms).
[16:15:14.619] <TB2> INFO: Test took 142693ms.
[16:15:14.713] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:37.863] <TB2> INFO: PixTestBBMap::doTest() done, duration: 165 seconds
[16:15:37.863] <TB2> INFO: number of dead bumps (per ROC): 9 7 3 0 0 0 1 2 1 8 3 1 8 9 23 17
[16:15:37.863] <TB2> INFO: separation cut (per ROC): 111 109 103 107 102 117 114 103 126 127 94 99 119 102 102 106
[16:15:37.864] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:37.864] <TB2> INFO: Decoding statistics:
[16:15:37.864] <TB2> INFO: General information:
[16:15:37.864] <TB2> INFO: 16bit words read: 0
[16:15:37.864] <TB2> INFO: valid events total: 0
[16:15:37.864] <TB2> INFO: empty events: 0
[16:15:37.864] <TB2> INFO: valid events with pixels: 0
[16:15:37.864] <TB2> INFO: valid pixel hits: 0
[16:15:37.864] <TB2> INFO: Event errors: 0
[16:15:37.864] <TB2> INFO: start marker: 0
[16:15:37.864] <TB2> INFO: stop marker: 0
[16:15:37.864] <TB2> INFO: overflow: 0
[16:15:37.864] <TB2> INFO: invalid 5bit words: 0
[16:15:37.864] <TB2> INFO: invalid XOR eye diagram: 0
[16:15:37.864] <TB2> INFO: TBM errors: 0
[16:15:37.864] <TB2> INFO: flawed TBM headers: 0
[16:15:37.864] <TB2> INFO: flawed TBM trailers: 0
[16:15:37.864] <TB2> INFO: event ID mismatches: 0
[16:15:37.864] <TB2> INFO: ROC errors: 0
[16:15:37.864] <TB2> INFO: missing ROC header(s): 0
[16:15:37.864] <TB2> INFO: misplaced readback start: 0
[16:15:37.864] <TB2> INFO: Pixel decoding errors: 0
[16:15:37.864] <TB2> INFO: pixel data incomplete: 0
[16:15:37.864] <TB2> INFO: pixel address: 0
[16:15:37.864] <TB2> INFO: pulse height fill bit: 0
[16:15:37.864] <TB2> INFO: buffer corruption: 0
[16:15:37.954] <TB2> INFO: ######################################################################
[16:15:37.955] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:15:37.955] <TB2> INFO: ######################################################################
[16:15:37.955] <TB2> INFO: ----------------------------------------------------------------------
[16:15:37.955] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:15:37.955] <TB2> INFO: ----------------------------------------------------------------------
[16:15:37.955] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:15:37.962] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:15:37.962] <TB2> INFO: run 1 of 1
[16:15:38.262] <TB2> INFO: Expecting 26208000 events.
[16:16:09.595] <TB2> INFO: 899900 events read in total (30618ms).
[16:16:38.407] <TB2> INFO: 1785800 events read in total (59430ms).
[16:17:09.137] <TB2> INFO: 2669200 events read in total (90160ms).
[16:17:37.582] <TB2> INFO: 3552100 events read in total (118605ms).
[16:18:07.051] <TB2> INFO: 4432650 events read in total (148074ms).
[16:18:37.231] <TB2> INFO: 5312300 events read in total (178254ms).
[16:19:07.998] <TB2> INFO: 6192400 events read in total (209021ms).
[16:19:39.191] <TB2> INFO: 7071800 events read in total (240214ms).
[16:20:08.903] <TB2> INFO: 7949200 events read in total (269926ms).
[16:20:38.703] <TB2> INFO: 8828200 events read in total (299726ms).
[16:21:09.848] <TB2> INFO: 9707400 events read in total (330871ms).
[16:21:39.528] <TB2> INFO: 10582900 events read in total (360552ms).
[16:22:10.114] <TB2> INFO: 11458450 events read in total (391137ms).
[16:22:40.336] <TB2> INFO: 12334650 events read in total (421359ms).
[16:23:11.924] <TB2> INFO: 13208100 events read in total (452947ms).
[16:23:41.458] <TB2> INFO: 14073250 events read in total (482481ms).
[16:24:12.336] <TB2> INFO: 14937550 events read in total (513359ms).
[16:24:42.455] <TB2> INFO: 15804950 events read in total (543478ms).
[16:25:12.406] <TB2> INFO: 16668500 events read in total (573429ms).
[16:25:43.379] <TB2> INFO: 17531050 events read in total (604402ms).
[16:26:13.652] <TB2> INFO: 18395150 events read in total (634675ms).
[16:26:43.325] <TB2> INFO: 19258500 events read in total (664348ms).
[16:27:13.871] <TB2> INFO: 20119700 events read in total (694894ms).
[16:27:44.583] <TB2> INFO: 20982050 events read in total (725606ms).
[16:28:15.146] <TB2> INFO: 21845450 events read in total (756169ms).
[16:28:44.947] <TB2> INFO: 22706950 events read in total (785970ms).
[16:29:14.994] <TB2> INFO: 23571000 events read in total (816017ms).
[16:29:45.131] <TB2> INFO: 24436050 events read in total (846154ms).
[16:30:14.317] <TB2> INFO: 25302300 events read in total (875340ms).
[16:30:44.365] <TB2> INFO: 26181200 events read in total (905388ms).
[16:30:45.705] <TB2> INFO: 26208000 events read in total (906728ms).
[16:30:45.734] <TB2> INFO: Test took 907772ms.
[16:30:45.820] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:45.994] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:47.463] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:48.934] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:50.392] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:51.853] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:53.335] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:54.815] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:56.287] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:57.739] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:30:59.142] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:31:00.577] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:31:02.077] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:31:03.771] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:31:05.317] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:31:06.846] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:31:08.286] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[16:31:09.687] <TB2> INFO: PixTestScurves::scurves() done
[16:31:09.687] <TB2> INFO: Vcal mean: 93.56 84.98 84.62 90.16 83.51 83.61 82.18 90.66 109.70 107.74 72.97 78.25 106.17 88.66 100.13 99.78
[16:31:09.687] <TB2> INFO: Vcal RMS: 5.18 5.21 4.77 5.87 4.32 4.94 4.95 5.13 5.42 5.68 4.08 4.33 7.21 5.01 5.57 5.62
[16:31:09.687] <TB2> INFO: PixTestScurves::fullTest() done, duration: 931 seconds
[16:31:09.687] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:09.687] <TB2> INFO: Decoding statistics:
[16:31:09.687] <TB2> INFO: General information:
[16:31:09.687] <TB2> INFO: 16bit words read: 0
[16:31:09.687] <TB2> INFO: valid events total: 0
[16:31:09.687] <TB2> INFO: empty events: 0
[16:31:09.687] <TB2> INFO: valid events with pixels: 0
[16:31:09.687] <TB2> INFO: valid pixel hits: 0
[16:31:09.687] <TB2> INFO: Event errors: 0
[16:31:09.687] <TB2> INFO: start marker: 0
[16:31:09.687] <TB2> INFO: stop marker: 0
[16:31:09.688] <TB2> INFO: overflow: 0
[16:31:09.688] <TB2> INFO: invalid 5bit words: 0
[16:31:09.688] <TB2> INFO: invalid XOR eye diagram: 0
[16:31:09.688] <TB2> INFO: TBM errors: 0
[16:31:09.688] <TB2> INFO: flawed TBM headers: 0
[16:31:09.688] <TB2> INFO: flawed TBM trailers: 0
[16:31:09.688] <TB2> INFO: event ID mismatches: 0
[16:31:09.688] <TB2> INFO: ROC errors: 0
[16:31:09.688] <TB2> INFO: missing ROC header(s): 0
[16:31:09.688] <TB2> INFO: misplaced readback start: 0
[16:31:09.688] <TB2> INFO: Pixel decoding errors: 0
[16:31:09.688] <TB2> INFO: pixel data incomplete: 0
[16:31:09.688] <TB2> INFO: pixel address: 0
[16:31:09.688] <TB2> INFO: pulse height fill bit: 0
[16:31:09.688] <TB2> INFO: buffer corruption: 0
[16:31:09.758] <TB2> INFO: ######################################################################
[16:31:09.758] <TB2> INFO: PixTestTrim::doTest()
[16:31:09.758] <TB2> INFO: ######################################################################
[16:31:09.759] <TB2> INFO: ----------------------------------------------------------------------
[16:31:09.759] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[16:31:09.759] <TB2> INFO: ----------------------------------------------------------------------
[16:31:09.837] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:31:09.837] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:31:09.845] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[16:31:09.845] <TB2> INFO: run 1 of 1
[16:31:10.147] <TB2> INFO: Expecting 6281600 events.
[16:31:49.892] <TB2> INFO: 1067830 events read in total (39028ms).
[16:32:29.545] <TB2> INFO: 2128350 events read in total (78681ms).
[16:33:06.853] <TB2> INFO: 3184510 events read in total (115989ms).
[16:33:44.118] <TB2> INFO: 4242760 events read in total (153254ms).
[16:34:21.794] <TB2> INFO: 5303740 events read in total (190930ms).
[16:34:56.599] <TB2> INFO: 6281600 events read in total (225735ms).
[16:34:56.635] <TB2> INFO: Test took 226790ms.
[16:34:56.688] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:16.508] <TB2> INFO: ROC 0 VthrComp = 102
[16:35:16.508] <TB2> INFO: ROC 1 VthrComp = 91
[16:35:16.508] <TB2> INFO: ROC 2 VthrComp = 89
[16:35:16.508] <TB2> INFO: ROC 3 VthrComp = 93
[16:35:16.510] <TB2> INFO: ROC 4 VthrComp = 90
[16:35:16.510] <TB2> INFO: ROC 5 VthrComp = 87
[16:35:16.510] <TB2> INFO: ROC 6 VthrComp = 87
[16:35:16.510] <TB2> INFO: ROC 7 VthrComp = 96
[16:35:16.510] <TB2> INFO: ROC 8 VthrComp = 109
[16:35:16.510] <TB2> INFO: ROC 9 VthrComp = 107
[16:35:16.510] <TB2> INFO: ROC 10 VthrComp = 81
[16:35:16.510] <TB2> INFO: ROC 11 VthrComp = 85
[16:35:16.510] <TB2> INFO: ROC 12 VthrComp = 101
[16:35:16.510] <TB2> INFO: ROC 13 VthrComp = 93
[16:35:16.510] <TB2> INFO: ROC 14 VthrComp = 103
[16:35:16.510] <TB2> INFO: ROC 15 VthrComp = 104
[16:35:16.510] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:35:16.510] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:35:16.519] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[16:35:16.519] <TB2> INFO: run 1 of 1
[16:35:16.824] <TB2> INFO: Expecting 6281600 events.
[16:35:51.893] <TB2> INFO: 756150 events read in total (34354ms).
[16:36:26.186] <TB2> INFO: 1509870 events read in total (68647ms).
[16:37:00.376] <TB2> INFO: 2263640 events read in total (102837ms).
[16:37:34.818] <TB2> INFO: 3017690 events read in total (137279ms).
[16:38:08.028] <TB2> INFO: 3766680 events read in total (170489ms).
[16:38:42.120] <TB2> INFO: 4512290 events read in total (204581ms).
[16:39:15.553] <TB2> INFO: 5255900 events read in total (238014ms).
[16:39:49.263] <TB2> INFO: 5999980 events read in total (271724ms).
[16:40:03.043] <TB2> INFO: 6281600 events read in total (285504ms).
[16:40:03.087] <TB2> INFO: Test took 286568ms.
[16:40:03.225] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:26.673] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 57.1937 for pixel 36/3 mean/min/max = 44.513/31.7482/57.2779
[16:40:26.673] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 57.2738 for pixel 35/79 mean/min/max = 45.018/32.2238/57.8121
[16:40:26.674] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 57.4864 for pixel 8/2 mean/min/max = 45.7375/33.8308/57.6442
[16:40:26.674] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 61.2402 for pixel 0/43 mean/min/max = 46.6004/31.8562/61.3445
[16:40:26.674] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 55.9276 for pixel 10/72 mean/min/max = 44.5463/32.9896/56.1031
[16:40:26.674] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 58.1213 for pixel 23/79 mean/min/max = 45.2085/32.1331/58.284
[16:40:26.675] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 58.8627 for pixel 2/75 mean/min/max = 45.0825/31.2887/58.8763
[16:40:26.675] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 57.7672 for pixel 1/7 mean/min/max = 44.8495/31.6187/58.0803
[16:40:26.675] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 64.3962 for pixel 0/76 mean/min/max = 49.2575/34.0205/64.4945
[16:40:26.675] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 62.3921 for pixel 0/25 mean/min/max = 48.4449/34.4356/62.4541
[16:40:26.676] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 53.6228 for pixel 32/71 mean/min/max = 43.0587/31.9417/54.1758
[16:40:26.676] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 57.2537 for pixel 0/72 mean/min/max = 44.7924/32.3302/57.2546
[16:40:26.676] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 68.0902 for pixel 19/0 mean/min/max = 49.422/30.6538/68.1901
[16:40:26.676] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 57.5732 for pixel 51/10 mean/min/max = 44.9982/32.3731/57.6234
[16:40:26.677] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 59.7336 for pixel 42/78 mean/min/max = 46.0275/32.307/59.748
[16:40:26.677] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 59.3309 for pixel 2/17 mean/min/max = 46.2468/33.0426/59.4511
[16:40:26.677] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:40:26.808] <TB2> INFO: Expecting 514560 events.
[16:40:38.790] <TB2> INFO: 514560 events read in total (11266ms).
[16:40:38.796] <TB2> INFO: Expecting 514560 events.
[16:40:51.214] <TB2> INFO: 514560 events read in total (11856ms).
[16:40:51.223] <TB2> INFO: Expecting 514560 events.
[16:41:03.852] <TB2> INFO: 514560 events read in total (12078ms).
[16:41:03.862] <TB2> INFO: Expecting 514560 events.
[16:41:15.052] <TB2> INFO: 514560 events read in total (10638ms).
[16:41:15.062] <TB2> INFO: Expecting 514560 events.
[16:41:27.398] <TB2> INFO: 514560 events read in total (11764ms).
[16:41:27.411] <TB2> INFO: Expecting 514560 events.
[16:41:40.188] <TB2> INFO: 514560 events read in total (12211ms).
[16:41:40.203] <TB2> INFO: Expecting 514560 events.
[16:41:52.498] <TB2> INFO: 514560 events read in total (11729ms).
[16:41:52.518] <TB2> INFO: Expecting 514560 events.
[16:42:04.874] <TB2> INFO: 514560 events read in total (11803ms).
[16:42:04.894] <TB2> INFO: Expecting 514560 events.
[16:42:16.597] <TB2> INFO: 514560 events read in total (11145ms).
[16:42:16.618] <TB2> INFO: Expecting 514560 events.
[16:42:29.223] <TB2> INFO: 514560 events read in total (12050ms).
[16:42:29.246] <TB2> INFO: Expecting 514560 events.
[16:42:42.095] <TB2> INFO: 514560 events read in total (12297ms).
[16:42:42.120] <TB2> INFO: Expecting 514560 events.
[16:42:54.016] <TB2> INFO: 514560 events read in total (11351ms).
[16:42:54.044] <TB2> INFO: Expecting 514560 events.
[16:43:06.424] <TB2> INFO: 514560 events read in total (11820ms).
[16:43:06.454] <TB2> INFO: Expecting 514560 events.
[16:43:18.066] <TB2> INFO: 514560 events read in total (11060ms).
[16:43:18.094] <TB2> INFO: Expecting 514560 events.
[16:43:30.774] <TB2> INFO: 514560 events read in total (12126ms).
[16:43:30.805] <TB2> INFO: Expecting 514560 events.
[16:43:43.539] <TB2> INFO: 514560 events read in total (12183ms).
[16:43:43.574] <TB2> INFO: Test took 196897ms.
[16:43:44.680] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:43:44.689] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[16:43:44.689] <TB2> INFO: run 1 of 1
[16:43:45.035] <TB2> INFO: Expecting 6281600 events.
[16:44:20.045] <TB2> INFO: 742260 events read in total (34294ms).
[16:44:53.586] <TB2> INFO: 1483190 events read in total (67835ms).
[16:45:27.477] <TB2> INFO: 2224390 events read in total (101726ms).
[16:46:01.486] <TB2> INFO: 2965950 events read in total (135735ms).
[16:46:35.801] <TB2> INFO: 3701890 events read in total (170050ms).
[16:47:10.022] <TB2> INFO: 4434460 events read in total (204271ms).
[16:47:45.156] <TB2> INFO: 5165010 events read in total (239405ms).
[16:48:18.999] <TB2> INFO: 5895360 events read in total (273248ms).
[16:48:36.508] <TB2> INFO: 6281600 events read in total (290757ms).
[16:48:36.555] <TB2> INFO: Test took 291866ms.
[16:48:36.694] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:49:00.187] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.078222 .. 255.000000
[16:49:00.265] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[16:49:00.273] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[16:49:00.273] <TB2> INFO: run 1 of 1
[16:49:00.581] <TB2> INFO: Expecting 10649600 events.
[16:49:34.510] <TB2> INFO: 712260 events read in total (33213ms).
[16:50:07.759] <TB2> INFO: 1424270 events read in total (66462ms).
[16:50:42.182] <TB2> INFO: 2136470 events read in total (100885ms).
[16:51:15.952] <TB2> INFO: 2848920 events read in total (134655ms).
[16:51:50.165] <TB2> INFO: 3561450 events read in total (168868ms).
[16:52:24.082] <TB2> INFO: 4273950 events read in total (202785ms).
[16:52:58.114] <TB2> INFO: 4986220 events read in total (236817ms).
[16:53:31.284] <TB2> INFO: 5698570 events read in total (269987ms).
[16:54:04.874] <TB2> INFO: 6410830 events read in total (303577ms).
[16:54:38.043] <TB2> INFO: 7122420 events read in total (336746ms).
[16:55:10.830] <TB2> INFO: 7833990 events read in total (369533ms).
[16:55:45.008] <TB2> INFO: 8544640 events read in total (403711ms).
[16:56:19.143] <TB2> INFO: 9255460 events read in total (437846ms).
[16:56:52.402] <TB2> INFO: 9966510 events read in total (471105ms).
[16:57:24.695] <TB2> INFO: 10649600 events read in total (503398ms).
[16:57:24.787] <TB2> INFO: Test took 504514ms.
[16:57:25.046] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:52.379] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 15.681934 .. 87.142999
[16:57:52.455] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 97 (-1/-1) hits flags = 528 (plus default)
[16:57:52.463] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[16:57:52.463] <TB2> INFO: run 1 of 1
[16:57:52.765] <TB2> INFO: Expecting 3868800 events.
[16:58:28.796] <TB2> INFO: 790240 events read in total (35316ms).
[16:59:03.372] <TB2> INFO: 1580900 events read in total (69892ms).
[16:59:38.244] <TB2> INFO: 2371150 events read in total (104764ms).
[17:00:14.136] <TB2> INFO: 3160780 events read in total (140656ms).
[17:00:45.360] <TB2> INFO: 3868800 events read in total (171880ms).
[17:00:45.394] <TB2> INFO: Test took 172931ms.
[17:00:45.474] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:03.549] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 21.118670 .. 56.425221
[17:01:03.624] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 66 (-1/-1) hits flags = 528 (plus default)
[17:01:03.632] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[17:01:03.632] <TB2> INFO: run 1 of 1
[17:01:03.936] <TB2> INFO: Expecting 2329600 events.
[17:01:40.219] <TB2> INFO: 844880 events read in total (35567ms).
[17:02:12.009] <TB2> INFO: 1689170 events read in total (67357ms).
[17:02:40.282] <TB2> INFO: 2329600 events read in total (95631ms).
[17:02:40.301] <TB2> INFO: Test took 96670ms.
[17:02:40.349] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:55.918] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 3.700669 .. 56.425221
[17:02:56.000] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 66 (-1/-1) hits flags = 528 (plus default)
[17:02:56.007] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[17:02:56.007] <TB2> INFO: run 1 of 1
[17:02:56.311] <TB2> INFO: Expecting 2662400 events.
[17:03:32.068] <TB2> INFO: 884930 events read in total (35041ms).
[17:04:06.531] <TB2> INFO: 1769850 events read in total (69504ms).
[17:04:41.510] <TB2> INFO: 2654260 events read in total (104484ms).
[17:04:42.308] <TB2> INFO: 2662400 events read in total (105281ms).
[17:04:42.326] <TB2> INFO: Test took 106320ms.
[17:04:42.373] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:57.028] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:04:57.028] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:04:57.035] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[17:04:57.035] <TB2> INFO: run 1 of 1
[17:04:57.342] <TB2> INFO: Expecting 1705600 events.
[17:05:33.821] <TB2> INFO: 878570 events read in total (35764ms).
[17:06:07.718] <TB2> INFO: 1705600 events read in total (69661ms).
[17:06:07.743] <TB2> INFO: Test took 70708ms.
[17:06:07.780] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:21.170] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C0.dat
[17:06:21.170] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C1.dat
[17:06:21.170] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C2.dat
[17:06:21.170] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C3.dat
[17:06:21.170] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C4.dat
[17:06:21.170] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C5.dat
[17:06:21.170] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C6.dat
[17:06:21.171] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C7.dat
[17:06:21.171] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C8.dat
[17:06:21.171] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C9.dat
[17:06:21.171] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C10.dat
[17:06:21.171] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C11.dat
[17:06:21.171] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C12.dat
[17:06:21.171] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C13.dat
[17:06:21.172] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C14.dat
[17:06:21.172] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C15.dat
[17:06:21.172] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C0.dat
[17:06:21.178] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C1.dat
[17:06:21.185] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C2.dat
[17:06:21.192] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C3.dat
[17:06:21.198] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C4.dat
[17:06:21.205] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C5.dat
[17:06:21.212] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C6.dat
[17:06:21.218] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C7.dat
[17:06:21.225] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C8.dat
[17:06:21.232] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C9.dat
[17:06:21.238] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C10.dat
[17:06:21.245] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C11.dat
[17:06:21.252] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C12.dat
[17:06:21.259] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C13.dat
[17:06:21.266] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C14.dat
[17:06:21.275] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//trimParameters35_C15.dat
[17:06:21.285] <TB2> INFO: PixTestTrim::trimTest() done
[17:06:21.285] <TB2> INFO: vtrim: 110 100 115 103 95 101 103 104 113 136 94 87 122 92 105 108
[17:06:21.285] <TB2> INFO: vthrcomp: 102 91 89 93 90 87 87 96 109 107 81 85 101 93 103 104
[17:06:21.285] <TB2> INFO: vcal mean: 34.99 35.01 35.02 34.96 35.00 35.01 34.92 34.96 35.00 34.99 34.96 35.01 34.93 35.01 35.00 35.00
[17:06:21.285] <TB2> INFO: vcal RMS: 0.85 0.83 0.78 1.03 0.81 0.86 0.85 0.88 0.90 1.12 0.76 0.85 1.36 0.84 0.88 0.88
[17:06:21.285] <TB2> INFO: bits mean: 9.92 9.45 8.76 8.96 9.77 9.46 9.71 9.82 7.97 8.32 10.06 9.29 9.57 9.06 9.38 9.10
[17:06:21.285] <TB2> INFO: bits RMS: 2.60 2.73 2.75 2.92 2.48 2.77 2.72 2.65 2.73 2.58 2.53 2.85 2.49 2.90 2.66 2.68
[17:06:21.293] <TB2> INFO: ----------------------------------------------------------------------
[17:06:21.293] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[17:06:21.293] <TB2> INFO: ----------------------------------------------------------------------
[17:06:21.295] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[17:06:21.304] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[17:06:21.304] <TB2> INFO: run 1 of 1
[17:06:21.625] <TB2> INFO: Expecting 4160000 events.
[17:07:04.057] <TB2> INFO: 926655 events read in total (41716ms).
[17:07:47.456] <TB2> INFO: 1846685 events read in total (85116ms).
[17:08:29.064] <TB2> INFO: 2758685 events read in total (126724ms).
[17:09:11.270] <TB2> INFO: 3668220 events read in total (168929ms).
[17:09:34.162] <TB2> INFO: 4160000 events read in total (191821ms).
[17:09:34.203] <TB2> INFO: Test took 192899ms.
[17:09:34.310] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:01.821] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 186 (-1/-1) hits flags = 528 (plus default)
[17:10:01.830] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[17:10:01.830] <TB2> INFO: run 1 of 1
[17:10:02.148] <TB2> INFO: Expecting 3889600 events.
[17:10:43.899] <TB2> INFO: 921725 events read in total (41035ms).
[17:11:27.355] <TB2> INFO: 1836335 events read in total (84491ms).
[17:12:09.476] <TB2> INFO: 2741235 events read in total (126613ms).
[17:12:51.978] <TB2> INFO: 3644810 events read in total (169114ms).
[17:13:03.029] <TB2> INFO: 3889600 events read in total (180165ms).
[17:13:03.065] <TB2> INFO: Test took 181235ms.
[17:13:03.165] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:30.756] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[17:13:30.765] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[17:13:30.765] <TB2> INFO: run 1 of 1
[17:13:31.094] <TB2> INFO: Expecting 3723200 events.
[17:14:12.850] <TB2> INFO: 937850 events read in total (41041ms).
[17:14:55.164] <TB2> INFO: 1867675 events read in total (83355ms).
[17:15:38.009] <TB2> INFO: 2786180 events read in total (126201ms).
[17:16:20.927] <TB2> INFO: 3706895 events read in total (169118ms).
[17:16:22.179] <TB2> INFO: 3723200 events read in total (170370ms).
[17:16:22.218] <TB2> INFO: Test took 171453ms.
[17:16:22.305] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:48.493] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 174 (-1/-1) hits flags = 528 (plus default)
[17:16:48.501] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[17:16:48.503] <TB2> INFO: run 1 of 1
[17:16:48.805] <TB2> INFO: Expecting 3640000 events.
[17:17:33.615] <TB2> INFO: 946415 events read in total (44094ms).
[17:18:15.431] <TB2> INFO: 1883700 events read in total (85910ms).
[17:18:57.829] <TB2> INFO: 2810305 events read in total (128309ms).
[17:19:34.958] <TB2> INFO: 3640000 events read in total (165437ms).
[17:19:35.000] <TB2> INFO: Test took 166497ms.
[17:19:35.088] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:00.550] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[17:20:00.560] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[17:20:00.560] <TB2> INFO: run 1 of 1
[17:20:00.865] <TB2> INFO: Expecting 3619200 events.
[17:20:45.463] <TB2> INFO: 948185 events read in total (43882ms).
[17:21:26.948] <TB2> INFO: 1887485 events read in total (85367ms).
[17:22:09.868] <TB2> INFO: 2815715 events read in total (128287ms).
[17:22:46.078] <TB2> INFO: 3619200 events read in total (164497ms).
[17:22:46.117] <TB2> INFO: Test took 165557ms.
[17:22:46.200] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:12.313] <TB2> INFO: PixTestTrim::trimBitTest() done
[17:23:12.314] <TB2> INFO: PixTestTrim::doTest() done, duration: 3122 seconds
[17:23:12.314] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:12.314] <TB2> INFO: Decoding statistics:
[17:23:12.314] <TB2> INFO: General information:
[17:23:12.314] <TB2> INFO: 16bit words read: 0
[17:23:12.314] <TB2> INFO: valid events total: 0
[17:23:12.314] <TB2> INFO: empty events: 0
[17:23:12.314] <TB2> INFO: valid events with pixels: 0
[17:23:12.314] <TB2> INFO: valid pixel hits: 0
[17:23:12.314] <TB2> INFO: Event errors: 0
[17:23:12.314] <TB2> INFO: start marker: 0
[17:23:12.314] <TB2> INFO: stop marker: 0
[17:23:12.314] <TB2> INFO: overflow: 0
[17:23:12.314] <TB2> INFO: invalid 5bit words: 0
[17:23:12.314] <TB2> INFO: invalid XOR eye diagram: 0
[17:23:12.314] <TB2> INFO: TBM errors: 0
[17:23:12.314] <TB2> INFO: flawed TBM headers: 0
[17:23:12.314] <TB2> INFO: flawed TBM trailers: 0
[17:23:12.314] <TB2> INFO: event ID mismatches: 0
[17:23:12.314] <TB2> INFO: ROC errors: 0
[17:23:12.314] <TB2> INFO: missing ROC header(s): 0
[17:23:12.314] <TB2> INFO: misplaced readback start: 0
[17:23:12.314] <TB2> INFO: Pixel decoding errors: 0
[17:23:12.314] <TB2> INFO: pixel data incomplete: 0
[17:23:12.314] <TB2> INFO: pixel address: 0
[17:23:12.314] <TB2> INFO: pulse height fill bit: 0
[17:23:12.314] <TB2> INFO: buffer corruption: 0
[17:23:12.993] <TB2> INFO: ######################################################################
[17:23:12.993] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:23:12.993] <TB2> INFO: ######################################################################
[17:23:13.298] <TB2> INFO: Expecting 41600 events.
[17:23:17.767] <TB2> INFO: 41600 events read in total (3753ms).
[17:23:17.767] <TB2> INFO: Test took 4773ms.
[17:23:17.774] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:18.339] <TB2> INFO: Expecting 41600 events.
[17:23:22.518] <TB2> INFO: 41600 events read in total (3464ms).
[17:23:22.518] <TB2> INFO: Test took 4483ms.
[17:23:22.524] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:22.869] <TB2> INFO: Expecting 41600 events.
[17:23:27.213] <TB2> INFO: 41600 events read in total (3628ms).
[17:23:27.213] <TB2> INFO: Test took 4666ms.
[17:23:27.221] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:27.555] <TB2> INFO: Expecting 41600 events.
[17:23:31.931] <TB2> INFO: 41600 events read in total (3660ms).
[17:23:31.932] <TB2> INFO: Test took 4700ms.
[17:23:31.938] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:32.291] <TB2> INFO: Expecting 41600 events.
[17:23:36.886] <TB2> INFO: 41600 events read in total (3879ms).
[17:23:36.886] <TB2> INFO: Test took 4921ms.
[17:23:36.892] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:37.242] <TB2> INFO: Expecting 2560 events.
[17:23:38.348] <TB2> INFO: 2560 events read in total (390ms).
[17:23:38.348] <TB2> INFO: Test took 1449ms.
[17:23:38.762] <TB2> INFO: Expecting 2560 events.
[17:23:39.837] <TB2> INFO: 2560 events read in total (360ms).
[17:23:39.837] <TB2> INFO: Test took 1489ms.
[17:23:40.251] <TB2> INFO: Expecting 2560 events.
[17:23:41.327] <TB2> INFO: 2560 events read in total (361ms).
[17:23:41.327] <TB2> INFO: Test took 1490ms.
[17:23:41.741] <TB2> INFO: Expecting 2560 events.
[17:23:42.804] <TB2> INFO: 2560 events read in total (347ms).
[17:23:42.804] <TB2> INFO: Test took 1476ms.
[17:23:43.217] <TB2> INFO: Expecting 2560 events.
[17:23:44.295] <TB2> INFO: 2560 events read in total (363ms).
[17:23:44.295] <TB2> INFO: Test took 1491ms.
[17:23:44.709] <TB2> INFO: Expecting 2560 events.
[17:23:45.770] <TB2> INFO: 2560 events read in total (346ms).
[17:23:45.770] <TB2> INFO: Test took 1475ms.
[17:23:46.185] <TB2> INFO: Expecting 2560 events.
[17:23:47.250] <TB2> INFO: 2560 events read in total (349ms).
[17:23:47.250] <TB2> INFO: Test took 1479ms.
[17:23:47.664] <TB2> INFO: Expecting 2560 events.
[17:23:48.741] <TB2> INFO: 2560 events read in total (362ms).
[17:23:48.741] <TB2> INFO: Test took 1490ms.
[17:23:49.155] <TB2> INFO: Expecting 2560 events.
[17:23:50.219] <TB2> INFO: 2560 events read in total (348ms).
[17:23:50.219] <TB2> INFO: Test took 1477ms.
[17:23:50.633] <TB2> INFO: Expecting 2560 events.
[17:23:51.696] <TB2> INFO: 2560 events read in total (347ms).
[17:23:51.696] <TB2> INFO: Test took 1477ms.
[17:23:52.110] <TB2> INFO: Expecting 2560 events.
[17:23:53.180] <TB2> INFO: 2560 events read in total (354ms).
[17:23:53.180] <TB2> INFO: Test took 1483ms.
[17:23:53.594] <TB2> INFO: Expecting 2560 events.
[17:23:54.664] <TB2> INFO: 2560 events read in total (355ms).
[17:23:54.664] <TB2> INFO: Test took 1484ms.
[17:23:55.078] <TB2> INFO: Expecting 2560 events.
[17:23:56.144] <TB2> INFO: 2560 events read in total (351ms).
[17:23:56.144] <TB2> INFO: Test took 1480ms.
[17:23:56.558] <TB2> INFO: Expecting 2560 events.
[17:23:57.622] <TB2> INFO: 2560 events read in total (348ms).
[17:23:57.622] <TB2> INFO: Test took 1477ms.
[17:23:58.035] <TB2> INFO: Expecting 2560 events.
[17:23:59.098] <TB2> INFO: 2560 events read in total (347ms).
[17:23:59.098] <TB2> INFO: Test took 1476ms.
[17:23:59.512] <TB2> INFO: Expecting 2560 events.
[17:24:00.573] <TB2> INFO: 2560 events read in total (346ms).
[17:24:00.573] <TB2> INFO: Test took 1474ms.
[17:24:00.577] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:24:00.988] <TB2> INFO: Expecting 655360 events.
[17:24:17.768] <TB2> INFO: 655360 events read in total (16064ms).
[17:24:17.776] <TB2> INFO: Expecting 655360 events.
[17:24:32.752] <TB2> INFO: 655360 events read in total (14449ms).
[17:24:32.765] <TB2> INFO: Expecting 655360 events.
[17:24:49.245] <TB2> INFO: 655360 events read in total (15953ms).
[17:24:49.261] <TB2> INFO: Expecting 655360 events.
[17:25:04.142] <TB2> INFO: 655360 events read in total (14354ms).
[17:25:04.161] <TB2> INFO: Expecting 655360 events.
[17:25:17.931] <TB2> INFO: 655360 events read in total (13242ms).
[17:25:17.952] <TB2> INFO: Expecting 655360 events.
[17:25:32.001] <TB2> INFO: 655360 events read in total (13521ms).
[17:25:32.026] <TB2> INFO: Expecting 655360 events.
[17:25:46.476] <TB2> INFO: 655360 events read in total (13922ms).
[17:25:46.511] <TB2> INFO: Expecting 655360 events.
[17:26:00.247] <TB2> INFO: 655360 events read in total (13209ms).
[17:26:00.280] <TB2> INFO: Expecting 655360 events.
[17:26:14.943] <TB2> INFO: 655360 events read in total (14135ms).
[17:26:14.985] <TB2> INFO: Expecting 655360 events.
[17:26:28.966] <TB2> INFO: 655360 events read in total (13453ms).
[17:26:29.015] <TB2> INFO: Expecting 655360 events.
[17:26:42.988] <TB2> INFO: 655360 events read in total (13445ms).
[17:26:43.032] <TB2> INFO: Expecting 655360 events.
[17:26:57.995] <TB2> INFO: 655360 events read in total (14435ms).
[17:26:58.050] <TB2> INFO: Expecting 655360 events.
[17:27:11.950] <TB2> INFO: 655360 events read in total (13372ms).
[17:27:12.000] <TB2> INFO: Expecting 655360 events.
[17:27:25.976] <TB2> INFO: 655360 events read in total (13449ms).
[17:27:26.027] <TB2> INFO: Expecting 655360 events.
[17:27:39.654] <TB2> INFO: 655360 events read in total (13099ms).
[17:27:39.710] <TB2> INFO: Expecting 655360 events.
[17:27:53.412] <TB2> INFO: 655360 events read in total (13175ms).
[17:27:53.473] <TB2> INFO: Test took 232896ms.
[17:27:53.551] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:27:53.860] <TB2> INFO: Expecting 655360 events.
[17:28:07.929] <TB2> INFO: 655360 events read in total (13353ms).
[17:28:07.940] <TB2> INFO: Expecting 655360 events.
[17:28:22.351] <TB2> INFO: 655360 events read in total (13883ms).
[17:28:22.365] <TB2> INFO: Expecting 655360 events.
[17:28:36.784] <TB2> INFO: 655360 events read in total (13892ms).
[17:28:36.800] <TB2> INFO: Expecting 655360 events.
[17:28:51.117] <TB2> INFO: 655360 events read in total (13790ms).
[17:28:51.136] <TB2> INFO: Expecting 655360 events.
[17:29:05.473] <TB2> INFO: 655360 events read in total (13810ms).
[17:29:05.500] <TB2> INFO: Expecting 655360 events.
[17:29:20.024] <TB2> INFO: 655360 events read in total (13996ms).
[17:29:20.050] <TB2> INFO: Expecting 655360 events.
[17:29:34.395] <TB2> INFO: 655360 events read in total (13817ms).
[17:29:34.425] <TB2> INFO: Expecting 655360 events.
[17:29:48.802] <TB2> INFO: 655360 events read in total (13850ms).
[17:29:48.844] <TB2> INFO: Expecting 655360 events.
[17:30:03.406] <TB2> INFO: 655360 events read in total (14035ms).
[17:30:03.443] <TB2> INFO: Expecting 655360 events.
[17:30:17.896] <TB2> INFO: 655360 events read in total (13926ms).
[17:30:17.944] <TB2> INFO: Expecting 655360 events.
[17:30:32.239] <TB2> INFO: 655360 events read in total (13767ms).
[17:30:32.292] <TB2> INFO: Expecting 655360 events.
[17:30:46.761] <TB2> INFO: 655360 events read in total (13942ms).
[17:30:46.806] <TB2> INFO: Expecting 655360 events.
[17:31:01.160] <TB2> INFO: 655360 events read in total (13827ms).
[17:31:01.218] <TB2> INFO: Expecting 655360 events.
[17:31:15.417] <TB2> INFO: 655360 events read in total (13672ms).
[17:31:15.468] <TB2> INFO: Expecting 655360 events.
[17:31:29.912] <TB2> INFO: 655360 events read in total (13916ms).
[17:31:29.984] <TB2> INFO: Expecting 655360 events.
[17:31:44.411] <TB2> INFO: 655360 events read in total (13900ms).
[17:31:44.479] <TB2> INFO: Test took 230928ms.
[17:31:44.667] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.674] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.680] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.687] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.694] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.700] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.707] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.713] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.720] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.726] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.733] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.740] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.746] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.753] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.759] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.766] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:44.807] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C0.dat
[17:31:44.807] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C1.dat
[17:31:44.807] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C2.dat
[17:31:44.807] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C3.dat
[17:31:44.807] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C4.dat
[17:31:44.807] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C5.dat
[17:31:44.808] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C6.dat
[17:31:44.808] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C7.dat
[17:31:44.808] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C8.dat
[17:31:44.808] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C9.dat
[17:31:44.808] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C10.dat
[17:31:44.808] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C11.dat
[17:31:44.808] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C12.dat
[17:31:44.808] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C13.dat
[17:31:44.809] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C14.dat
[17:31:44.809] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//dacParameters35_C15.dat
[17:31:45.129] <TB2> INFO: Expecting 41600 events.
[17:31:49.118] <TB2> INFO: 41600 events read in total (3273ms).
[17:31:49.119] <TB2> INFO: Test took 4307ms.
[17:31:49.655] <TB2> INFO: Expecting 41600 events.
[17:31:53.672] <TB2> INFO: 41600 events read in total (3302ms).
[17:31:53.673] <TB2> INFO: Test took 4323ms.
[17:31:54.231] <TB2> INFO: Expecting 41600 events.
[17:31:58.214] <TB2> INFO: 41600 events read in total (3267ms).
[17:31:58.214] <TB2> INFO: Test took 4312ms.
[17:31:58.431] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:58.563] <TB2> INFO: Expecting 2560 events.
[17:31:59.630] <TB2> INFO: 2560 events read in total (351ms).
[17:31:59.630] <TB2> INFO: Test took 1199ms.
[17:31:59.633] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:00.044] <TB2> INFO: Expecting 2560 events.
[17:32:01.105] <TB2> INFO: 2560 events read in total (346ms).
[17:32:01.105] <TB2> INFO: Test took 1473ms.
[17:32:01.107] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:01.520] <TB2> INFO: Expecting 2560 events.
[17:32:02.587] <TB2> INFO: 2560 events read in total (352ms).
[17:32:02.587] <TB2> INFO: Test took 1480ms.
[17:32:02.590] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:03.002] <TB2> INFO: Expecting 2560 events.
[17:32:04.064] <TB2> INFO: 2560 events read in total (346ms).
[17:32:04.064] <TB2> INFO: Test took 1474ms.
[17:32:04.066] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:04.480] <TB2> INFO: Expecting 2560 events.
[17:32:05.541] <TB2> INFO: 2560 events read in total (345ms).
[17:32:05.541] <TB2> INFO: Test took 1475ms.
[17:32:05.543] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:05.956] <TB2> INFO: Expecting 2560 events.
[17:32:07.024] <TB2> INFO: 2560 events read in total (353ms).
[17:32:07.024] <TB2> INFO: Test took 1481ms.
[17:32:07.026] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:07.439] <TB2> INFO: Expecting 2560 events.
[17:32:08.507] <TB2> INFO: 2560 events read in total (353ms).
[17:32:08.507] <TB2> INFO: Test took 1481ms.
[17:32:08.510] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:08.922] <TB2> INFO: Expecting 2560 events.
[17:32:09.984] <TB2> INFO: 2560 events read in total (346ms).
[17:32:09.984] <TB2> INFO: Test took 1474ms.
[17:32:09.986] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:10.399] <TB2> INFO: Expecting 2560 events.
[17:32:11.461] <TB2> INFO: 2560 events read in total (347ms).
[17:32:11.462] <TB2> INFO: Test took 1476ms.
[17:32:11.463] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:11.878] <TB2> INFO: Expecting 2560 events.
[17:32:12.946] <TB2> INFO: 2560 events read in total (352ms).
[17:32:12.946] <TB2> INFO: Test took 1483ms.
[17:32:12.948] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:13.362] <TB2> INFO: Expecting 2560 events.
[17:32:14.424] <TB2> INFO: 2560 events read in total (346ms).
[17:32:14.425] <TB2> INFO: Test took 1477ms.
[17:32:14.426] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:14.839] <TB2> INFO: Expecting 2560 events.
[17:32:15.901] <TB2> INFO: 2560 events read in total (347ms).
[17:32:15.902] <TB2> INFO: Test took 1476ms.
[17:32:15.904] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:16.317] <TB2> INFO: Expecting 2560 events.
[17:32:17.382] <TB2> INFO: 2560 events read in total (350ms).
[17:32:17.382] <TB2> INFO: Test took 1478ms.
[17:32:17.385] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:17.797] <TB2> INFO: Expecting 2560 events.
[17:32:18.866] <TB2> INFO: 2560 events read in total (353ms).
[17:32:18.866] <TB2> INFO: Test took 1481ms.
[17:32:18.869] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:19.281] <TB2> INFO: Expecting 2560 events.
[17:32:20.351] <TB2> INFO: 2560 events read in total (354ms).
[17:32:20.352] <TB2> INFO: Test took 1483ms.
[17:32:20.354] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:20.766] <TB2> INFO: Expecting 2560 events.
[17:32:21.833] <TB2> INFO: 2560 events read in total (351ms).
[17:32:21.834] <TB2> INFO: Test took 1480ms.
[17:32:21.836] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:22.248] <TB2> INFO: Expecting 2560 events.
[17:32:23.316] <TB2> INFO: 2560 events read in total (352ms).
[17:32:23.316] <TB2> INFO: Test took 1480ms.
[17:32:23.318] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:23.731] <TB2> INFO: Expecting 2560 events.
[17:32:24.799] <TB2> INFO: 2560 events read in total (352ms).
[17:32:24.799] <TB2> INFO: Test took 1481ms.
[17:32:24.801] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:25.214] <TB2> INFO: Expecting 2560 events.
[17:32:26.281] <TB2> INFO: 2560 events read in total (352ms).
[17:32:26.282] <TB2> INFO: Test took 1481ms.
[17:32:26.284] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:26.696] <TB2> INFO: Expecting 2560 events.
[17:32:27.764] <TB2> INFO: 2560 events read in total (352ms).
[17:32:27.764] <TB2> INFO: Test took 1480ms.
[17:32:27.767] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:28.179] <TB2> INFO: Expecting 2560 events.
[17:32:29.246] <TB2> INFO: 2560 events read in total (351ms).
[17:32:29.246] <TB2> INFO: Test took 1481ms.
[17:32:29.249] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:29.662] <TB2> INFO: Expecting 2560 events.
[17:32:30.729] <TB2> INFO: 2560 events read in total (352ms).
[17:32:30.729] <TB2> INFO: Test took 1480ms.
[17:32:30.731] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:31.143] <TB2> INFO: Expecting 2560 events.
[17:32:32.210] <TB2> INFO: 2560 events read in total (352ms).
[17:32:32.210] <TB2> INFO: Test took 1479ms.
[17:32:32.212] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:32.625] <TB2> INFO: Expecting 2560 events.
[17:32:33.696] <TB2> INFO: 2560 events read in total (355ms).
[17:32:33.696] <TB2> INFO: Test took 1484ms.
[17:32:33.699] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:34.112] <TB2> INFO: Expecting 2560 events.
[17:32:35.183] <TB2> INFO: 2560 events read in total (355ms).
[17:32:35.184] <TB2> INFO: Test took 1486ms.
[17:32:35.187] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:35.599] <TB2> INFO: Expecting 2560 events.
[17:32:36.665] <TB2> INFO: 2560 events read in total (350ms).
[17:32:36.665] <TB2> INFO: Test took 1478ms.
[17:32:36.668] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:37.080] <TB2> INFO: Expecting 2560 events.
[17:32:38.149] <TB2> INFO: 2560 events read in total (353ms).
[17:32:38.150] <TB2> INFO: Test took 1482ms.
[17:32:38.151] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:38.564] <TB2> INFO: Expecting 2560 events.
[17:32:39.631] <TB2> INFO: 2560 events read in total (352ms).
[17:32:39.631] <TB2> INFO: Test took 1480ms.
[17:32:39.633] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:40.046] <TB2> INFO: Expecting 2560 events.
[17:32:41.112] <TB2> INFO: 2560 events read in total (351ms).
[17:32:41.113] <TB2> INFO: Test took 1480ms.
[17:32:41.115] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:41.528] <TB2> INFO: Expecting 2560 events.
[17:32:42.595] <TB2> INFO: 2560 events read in total (352ms).
[17:32:42.595] <TB2> INFO: Test took 1480ms.
[17:32:42.598] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:43.010] <TB2> INFO: Expecting 2560 events.
[17:32:44.076] <TB2> INFO: 2560 events read in total (350ms).
[17:32:44.076] <TB2> INFO: Test took 1480ms.
[17:32:44.079] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:44.492] <TB2> INFO: Expecting 2560 events.
[17:32:45.557] <TB2> INFO: 2560 events read in total (350ms).
[17:32:45.557] <TB2> INFO: Test took 1478ms.
[17:32:46.172] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 573 seconds
[17:32:46.172] <TB2> INFO: PH scale (per ROC): 84 81 80 79 80 80 74 85 82 75 89 82 67 87 79 79
[17:32:46.172] <TB2> INFO: PH offset (per ROC): 163 153 159 169 171 164 158 154 173 170 146 161 176 166 147 171
[17:32:46.178] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:32:46.178] <TB2> INFO: Decoding statistics:
[17:32:46.178] <TB2> INFO: General information:
[17:32:46.178] <TB2> INFO: 16bit words read: 91916
[17:32:46.178] <TB2> INFO: valid events total: 10240
[17:32:46.178] <TB2> INFO: empty events: 7741
[17:32:46.178] <TB2> INFO: valid events with pixels: 2499
[17:32:46.178] <TB2> INFO: valid pixel hits: 2499
[17:32:46.178] <TB2> INFO: Event errors: 0
[17:32:46.178] <TB2> INFO: start marker: 0
[17:32:46.178] <TB2> INFO: stop marker: 0
[17:32:46.178] <TB2> INFO: overflow: 0
[17:32:46.178] <TB2> INFO: invalid 5bit words: 0
[17:32:46.178] <TB2> INFO: invalid XOR eye diagram: 0
[17:32:46.178] <TB2> INFO: TBM errors: 0
[17:32:46.178] <TB2> INFO: flawed TBM headers: 0
[17:32:46.178] <TB2> INFO: flawed TBM trailers: 0
[17:32:46.178] <TB2> INFO: event ID mismatches: 0
[17:32:46.178] <TB2> INFO: ROC errors: 0
[17:32:46.178] <TB2> INFO: missing ROC header(s): 0
[17:32:46.178] <TB2> INFO: misplaced readback start: 0
[17:32:46.178] <TB2> INFO: Pixel decoding errors: 0
[17:32:46.178] <TB2> INFO: pixel data incomplete: 0
[17:32:46.178] <TB2> INFO: pixel address: 0
[17:32:46.178] <TB2> INFO: pulse height fill bit: 0
[17:32:46.178] <TB2> INFO: buffer corruption: 0
[17:32:46.344] <TB2> INFO: ######################################################################
[17:32:46.344] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:32:46.344] <TB2> INFO: ######################################################################
[17:32:46.353] <TB2> INFO: scanning low vcal = 10
[17:32:46.664] <TB2> INFO: Expecting 41600 events.
[17:32:50.452] <TB2> INFO: 41600 events read in total (3073ms).
[17:32:50.453] <TB2> INFO: Test took 4100ms.
[17:32:50.455] <TB2> INFO: scanning low vcal = 20
[17:32:50.867] <TB2> INFO: Expecting 41600 events.
[17:32:54.669] <TB2> INFO: 41600 events read in total (3087ms).
[17:32:54.669] <TB2> INFO: Test took 4214ms.
[17:32:54.671] <TB2> INFO: scanning low vcal = 30
[17:32:55.083] <TB2> INFO: Expecting 41600 events.
[17:32:58.953] <TB2> INFO: 41600 events read in total (3154ms).
[17:32:58.954] <TB2> INFO: Test took 4283ms.
[17:32:58.956] <TB2> INFO: scanning low vcal = 40
[17:32:59.355] <TB2> INFO: Expecting 41600 events.
[17:33:03.696] <TB2> INFO: 41600 events read in total (3625ms).
[17:33:03.697] <TB2> INFO: Test took 4741ms.
[17:33:03.699] <TB2> INFO: scanning low vcal = 50
[17:33:04.056] <TB2> INFO: Expecting 41600 events.
[17:33:08.344] <TB2> INFO: 41600 events read in total (3573ms).
[17:33:08.344] <TB2> INFO: Test took 4645ms.
[17:33:08.347] <TB2> INFO: scanning low vcal = 60
[17:33:08.698] <TB2> INFO: Expecting 41600 events.
[17:33:13.094] <TB2> INFO: 41600 events read in total (3681ms).
[17:33:13.094] <TB2> INFO: Test took 4747ms.
[17:33:13.097] <TB2> INFO: scanning low vcal = 70
[17:33:13.446] <TB2> INFO: Expecting 41600 events.
[17:33:17.738] <TB2> INFO: 41600 events read in total (3576ms).
[17:33:17.739] <TB2> INFO: Test took 4642ms.
[17:33:17.741] <TB2> INFO: scanning low vcal = 80
[17:33:18.091] <TB2> INFO: Expecting 41600 events.
[17:33:22.497] <TB2> INFO: 41600 events read in total (3690ms).
[17:33:22.498] <TB2> INFO: Test took 4757ms.
[17:33:22.500] <TB2> INFO: scanning low vcal = 90
[17:33:22.852] <TB2> INFO: Expecting 41600 events.
[17:33:27.245] <TB2> INFO: 41600 events read in total (3677ms).
[17:33:27.246] <TB2> INFO: Test took 4746ms.
[17:33:27.249] <TB2> INFO: scanning low vcal = 100
[17:33:27.584] <TB2> INFO: Expecting 41600 events.
[17:33:32.037] <TB2> INFO: 41600 events read in total (3738ms).
[17:33:32.038] <TB2> INFO: Test took 4789ms.
[17:33:32.040] <TB2> INFO: scanning low vcal = 110
[17:33:32.393] <TB2> INFO: Expecting 41600 events.
[17:33:36.701] <TB2> INFO: 41600 events read in total (3592ms).
[17:33:36.702] <TB2> INFO: Test took 4662ms.
[17:33:36.704] <TB2> INFO: scanning low vcal = 120
[17:33:37.060] <TB2> INFO: Expecting 41600 events.
[17:33:41.415] <TB2> INFO: 41600 events read in total (3639ms).
[17:33:41.416] <TB2> INFO: Test took 4712ms.
[17:33:41.418] <TB2> INFO: scanning low vcal = 130
[17:33:41.767] <TB2> INFO: Expecting 41600 events.
[17:33:46.072] <TB2> INFO: 41600 events read in total (3589ms).
[17:33:46.073] <TB2> INFO: Test took 4655ms.
[17:33:46.075] <TB2> INFO: scanning low vcal = 140
[17:33:46.424] <TB2> INFO: Expecting 41600 events.
[17:33:50.734] <TB2> INFO: 41600 events read in total (3595ms).
[17:33:50.734] <TB2> INFO: Test took 4659ms.
[17:33:50.737] <TB2> INFO: scanning low vcal = 150
[17:33:51.089] <TB2> INFO: Expecting 41600 events.
[17:33:55.512] <TB2> INFO: 41600 events read in total (3708ms).
[17:33:55.513] <TB2> INFO: Test took 4776ms.
[17:33:55.516] <TB2> INFO: scanning low vcal = 160
[17:33:55.852] <TB2> INFO: Expecting 41600 events.
[17:34:00.232] <TB2> INFO: 41600 events read in total (3664ms).
[17:34:00.232] <TB2> INFO: Test took 4716ms.
[17:34:00.235] <TB2> INFO: scanning low vcal = 170
[17:34:00.581] <TB2> INFO: Expecting 41600 events.
[17:34:04.939] <TB2> INFO: 41600 events read in total (3643ms).
[17:34:04.940] <TB2> INFO: Test took 4705ms.
[17:34:04.944] <TB2> INFO: scanning low vcal = 180
[17:34:05.287] <TB2> INFO: Expecting 41600 events.
[17:34:09.676] <TB2> INFO: 41600 events read in total (3673ms).
[17:34:09.677] <TB2> INFO: Test took 4733ms.
[17:34:09.679] <TB2> INFO: scanning low vcal = 190
[17:34:10.033] <TB2> INFO: Expecting 41600 events.
[17:34:14.415] <TB2> INFO: 41600 events read in total (3667ms).
[17:34:14.415] <TB2> INFO: Test took 4736ms.
[17:34:14.418] <TB2> INFO: scanning low vcal = 200
[17:34:14.765] <TB2> INFO: Expecting 41600 events.
[17:34:19.079] <TB2> INFO: 41600 events read in total (3598ms).
[17:34:19.080] <TB2> INFO: Test took 4662ms.
[17:34:19.082] <TB2> INFO: scanning low vcal = 210
[17:34:19.436] <TB2> INFO: Expecting 41600 events.
[17:34:23.861] <TB2> INFO: 41600 events read in total (3709ms).
[17:34:23.862] <TB2> INFO: Test took 4780ms.
[17:34:23.864] <TB2> INFO: scanning low vcal = 220
[17:34:24.209] <TB2> INFO: Expecting 41600 events.
[17:34:28.528] <TB2> INFO: 41600 events read in total (3603ms).
[17:34:28.529] <TB2> INFO: Test took 4665ms.
[17:34:28.537] <TB2> INFO: scanning low vcal = 230
[17:34:28.879] <TB2> INFO: Expecting 41600 events.
[17:34:33.163] <TB2> INFO: 41600 events read in total (3568ms).
[17:34:33.164] <TB2> INFO: Test took 4627ms.
[17:34:33.166] <TB2> INFO: scanning low vcal = 240
[17:34:33.517] <TB2> INFO: Expecting 41600 events.
[17:34:37.839] <TB2> INFO: 41600 events read in total (3607ms).
[17:34:37.839] <TB2> INFO: Test took 4673ms.
[17:34:37.842] <TB2> INFO: scanning low vcal = 250
[17:34:38.183] <TB2> INFO: Expecting 41600 events.
[17:34:42.574] <TB2> INFO: 41600 events read in total (3675ms).
[17:34:42.575] <TB2> INFO: Test took 4733ms.
[17:34:42.578] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[17:34:42.926] <TB2> INFO: Expecting 41600 events.
[17:34:47.216] <TB2> INFO: 41600 events read in total (3575ms).
[17:34:47.217] <TB2> INFO: Test took 4639ms.
[17:34:47.219] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[17:34:47.557] <TB2> INFO: Expecting 41600 events.
[17:34:51.854] <TB2> INFO: 41600 events read in total (3581ms).
[17:34:51.854] <TB2> INFO: Test took 4635ms.
[17:34:51.857] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[17:34:52.189] <TB2> INFO: Expecting 41600 events.
[17:34:56.525] <TB2> INFO: 41600 events read in total (3620ms).
[17:34:56.525] <TB2> INFO: Test took 4668ms.
[17:34:56.528] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[17:34:56.872] <TB2> INFO: Expecting 41600 events.
[17:35:01.324] <TB2> INFO: 41600 events read in total (3736ms).
[17:35:01.324] <TB2> INFO: Test took 4796ms.
[17:35:01.327] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:35:01.660] <TB2> INFO: Expecting 41600 events.
[17:35:06.048] <TB2> INFO: 41600 events read in total (3672ms).
[17:35:06.048] <TB2> INFO: Test took 4721ms.
[17:35:06.509] <TB2> INFO: PixTestGainPedestal::measure() done
[17:35:39.405] <TB2> INFO: PixTestGainPedestal::fit() done
[17:35:39.405] <TB2> INFO: non-linearity mean: 0.956 0.946 0.952 0.958 0.949 0.956 0.952 0.951 0.964 0.951 0.946 0.950 0.960 0.960 0.961 0.959
[17:35:39.405] <TB2> INFO: non-linearity RMS: 0.006 0.005 0.007 0.006 0.006 0.006 0.006 0.006 0.006 0.007 0.006 0.006 0.007 0.006 0.006 0.006
[17:35:39.405] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[17:35:39.425] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[17:35:39.444] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[17:35:39.464] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[17:35:39.483] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[17:35:39.504] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[17:35:39.523] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[17:35:39.543] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[17:35:39.562] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[17:35:39.582] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[17:35:39.601] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[17:35:39.621] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[17:35:39.641] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[17:35:39.660] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[17:35:39.679] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[17:35:39.699] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[17:35:39.718] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 173 seconds
[17:35:39.718] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:39.718] <TB2> INFO: Decoding statistics:
[17:35:39.718] <TB2> INFO: General information:
[17:35:39.718] <TB2> INFO: 16bit words read: 2662400
[17:35:39.718] <TB2> INFO: valid events total: 166400
[17:35:39.718] <TB2> INFO: empty events: 0
[17:35:39.718] <TB2> INFO: valid events with pixels: 166400
[17:35:39.718] <TB2> INFO: valid pixel hits: 665563
[17:35:39.718] <TB2> INFO: Event errors: 0
[17:35:39.718] <TB2> INFO: start marker: 0
[17:35:39.718] <TB2> INFO: stop marker: 0
[17:35:39.718] <TB2> INFO: overflow: 0
[17:35:39.718] <TB2> INFO: invalid 5bit words: 0
[17:35:39.718] <TB2> INFO: invalid XOR eye diagram: 0
[17:35:39.718] <TB2> INFO: TBM errors: 0
[17:35:39.718] <TB2> INFO: flawed TBM headers: 0
[17:35:39.718] <TB2> INFO: flawed TBM trailers: 0
[17:35:39.718] <TB2> INFO: event ID mismatches: 0
[17:35:39.718] <TB2> INFO: ROC errors: 0
[17:35:39.718] <TB2> INFO: missing ROC header(s): 0
[17:35:39.718] <TB2> INFO: misplaced readback start: 0
[17:35:39.718] <TB2> INFO: Pixel decoding errors: 0
[17:35:39.718] <TB2> INFO: pixel data incomplete: 0
[17:35:39.718] <TB2> INFO: pixel address: 0
[17:35:39.718] <TB2> INFO: pulse height fill bit: 0
[17:35:39.718] <TB2> INFO: buffer corruption: 0
[17:35:39.725] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C15.dat
[17:35:39.726] <TB2> INFO: ######################################################################
[17:35:39.726] <TB2> INFO: PixTestReadback::doTest()
[17:35:39.726] <TB2> INFO: ######################################################################
[17:35:39.727] <TB2> INFO: PixTestReadback::RES sent once
[17:35:50.955] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C0.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C1.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C2.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C3.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C4.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C5.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C6.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C7.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C8.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C9.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C10.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C11.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C12.dat
[17:35:50.956] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C13.dat
[17:35:50.957] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C14.dat
[17:35:50.957] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C15.dat
[17:35:50.985] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[17:35:50.985] <TB2> INFO: PixTestReadback::RES sent once
[17:36:02.167] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C0.dat
[17:36:02.168] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C1.dat
[17:36:02.168] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C2.dat
[17:36:02.168] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C3.dat
[17:36:02.168] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C4.dat
[17:36:02.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C5.dat
[17:36:02.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C6.dat
[17:36:02.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C7.dat
[17:36:02.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C8.dat
[17:36:02.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C9.dat
[17:36:02.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C10.dat
[17:36:02.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C11.dat
[17:36:02.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C12.dat
[17:36:02.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C13.dat
[17:36:02.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C14.dat
[17:36:02.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C15.dat
[17:36:02.199] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[17:36:02.199] <TB2> INFO: PixTestReadback::RES sent once
[17:36:10.803] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[17:36:10.803] <TB2> INFO: Vbg will be calibrated using Vd calibration
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148.2calibrated Vbg = 1.17961 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.4calibrated Vbg = 1.17566 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159.3calibrated Vbg = 1.18102 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.9calibrated Vbg = 1.19404 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150calibrated Vbg = 1.19081 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.8calibrated Vbg = 1.19338 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 143.3calibrated Vbg = 1.18967 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.7calibrated Vbg = 1.20005 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 146.9calibrated Vbg = 1.20172 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.1calibrated Vbg = 1.19697 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.1calibrated Vbg = 1.194 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 144.6calibrated Vbg = 1.19686 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 151.2calibrated Vbg = 1.19463 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.1calibrated Vbg = 1.19828 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 146.6calibrated Vbg = 1.20491 :::*/*/*/*/
[17:36:10.803] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151.3calibrated Vbg = 1.18484 :::*/*/*/*/
[17:36:10.806] <TB2> INFO: PixTestReadback::RES sent once
[17:39:05.113] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C0.dat
[17:39:05.113] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C1.dat
[17:39:05.113] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C2.dat
[17:39:05.113] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C3.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C4.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C5.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C6.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C7.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C8.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C9.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C10.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C11.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C12.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C13.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C14.dat
[17:39:05.114] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2143_FullQualification_2016-01-20_11h57m_1453287477//002_FulltestPxar_m20//readbackCal_C15.dat
[17:39:05.143] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[17:39:05.144] <TB2> INFO: PixTestReadback::doTest() done
[17:39:05.144] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:39:05.144] <TB2> INFO: Decoding statistics:
[17:39:05.144] <TB2> INFO: General information:
[17:39:05.144] <TB2> INFO: 16bit words read: 1024
[17:39:05.144] <TB2> INFO: valid events total: 128
[17:39:05.144] <TB2> INFO: empty events: 128
[17:39:05.144] <TB2> INFO: valid events with pixels: 0
[17:39:05.145] <TB2> INFO: valid pixel hits: 0
[17:39:05.145] <TB2> INFO: Event errors: 0
[17:39:05.145] <TB2> INFO: start marker: 0
[17:39:05.145] <TB2> INFO: stop marker: 0
[17:39:05.145] <TB2> INFO: overflow: 0
[17:39:05.145] <TB2> INFO: invalid 5bit words: 0
[17:39:05.145] <TB2> INFO: invalid XOR eye diagram: 0
[17:39:05.145] <TB2> INFO: TBM errors: 0
[17:39:05.145] <TB2> INFO: flawed TBM headers: 0
[17:39:05.145] <TB2> INFO: flawed TBM trailers: 0
[17:39:05.145] <TB2> INFO: event ID mismatches: 0
[17:39:05.145] <TB2> INFO: ROC errors: 0
[17:39:05.145] <TB2> INFO: missing ROC header(s): 0
[17:39:05.145] <TB2> INFO: misplaced readback start: 0
[17:39:05.145] <TB2> INFO: Pixel decoding errors: 0
[17:39:05.145] <TB2> INFO: pixel data incomplete: 0
[17:39:05.145] <TB2> INFO: pixel address: 0
[17:39:05.145] <TB2> INFO: pulse height fill bit: 0
[17:39:05.145] <TB2> INFO: buffer corruption: 0
[17:39:05.159] <TB2> INFO: Decoding statistics:
[17:39:05.159] <TB2> INFO: General information:
[17:39:05.159] <TB2> INFO: 16bit words read: 2755340
[17:39:05.159] <TB2> INFO: valid events total: 176768
[17:39:05.159] <TB2> INFO: empty events: 7869
[17:39:05.159] <TB2> INFO: valid events with pixels: 168899
[17:39:05.159] <TB2> INFO: valid pixel hits: 668062
[17:39:05.159] <TB2> INFO: Event errors: 0
[17:39:05.159] <TB2> INFO: start marker: 0
[17:39:05.159] <TB2> INFO: stop marker: 0
[17:39:05.159] <TB2> INFO: overflow: 0
[17:39:05.159] <TB2> INFO: invalid 5bit words: 0
[17:39:05.159] <TB2> INFO: invalid XOR eye diagram: 0
[17:39:05.159] <TB2> INFO: TBM errors: 0
[17:39:05.159] <TB2> INFO: flawed TBM headers: 0
[17:39:05.159] <TB2> INFO: flawed TBM trailers: 0
[17:39:05.159] <TB2> INFO: event ID mismatches: 0
[17:39:05.159] <TB2> INFO: ROC errors: 0
[17:39:05.159] <TB2> INFO: missing ROC header(s): 0
[17:39:05.159] <TB2> INFO: misplaced readback start: 0
[17:39:05.159] <TB2> INFO: Pixel decoding errors: 0
[17:39:05.159] <TB2> INFO: pixel data incomplete: 0
[17:39:05.159] <TB2> INFO: pixel address: 0
[17:39:05.159] <TB2> INFO: pulse height fill bit: 0
[17:39:05.159] <TB2> INFO: buffer corruption: 0
[17:39:05.159] <TB2> INFO: enter test to run
[17:39:05.159] <TB2> INFO: test: exit no parameter change
[17:39:05.364] <TB2> QUIET: Connection to board 156 closed.
[17:39:05.443] <TB2> INFO: pXar: this is the end, my friend