[12:03:07.069] <TB2> INFO: *** Welcome to pxar ***
[12:03:07.069] <TB2> INFO: *** Today: 2015/11/20
[12:03:07.106] <TB2> INFO: *** Version: 9da6
[12:03:07.106] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C15.dat
[12:03:07.107] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//tbmParameters_C0b.dat
[12:03:07.107] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//defaultMaskFile.dat
[12:03:07.107] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters_C15.dat
[12:03:07.184] <TB2> INFO: clk: 4
[12:03:07.184] <TB2> INFO: ctr: 4
[12:03:07.184] <TB2> INFO: sda: 19
[12:03:07.184] <TB2> INFO: tin: 9
[12:03:07.184] <TB2> INFO: level: 15
[12:03:07.184] <TB2> INFO: triggerdelay: 0
[12:03:07.184] <TB2> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:03:07.184] <TB2> INFO: Log level: INFO
[12:03:07.194] <TB2> INFO: Found DTB DTB_WRE1O5
[12:03:07.204] <TB2> QUIET: Connection to board DTB_WRE1O5 opened.
[12:03:07.207] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 20
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRE1O5
MAC address: 40D855118014
Hostname: pixelDTB020
Comment:
------------------------------------------------------
[12:03:07.210] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[12:03:08.776] <TB2> INFO: DUT info:
[12:03:08.777] <TB2> INFO: The DUT currently contains the following objects:
[12:03:08.777] <TB2> INFO: 2 TBM Cores tbm08c (2 ON)
[12:03:08.777] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:03:08.777] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:03:08.777] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:03:08.777] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:08.777] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.179] <TB2> INFO: enter 'restricted' command line mode
[12:03:09.179] <TB2> INFO: enter test to run
[12:03:09.179] <TB2> INFO: test: pretest no parameter change
[12:03:09.179] <TB2> INFO: running: pretest
[12:03:09.186] <TB2> INFO: ######################################################################
[12:03:09.186] <TB2> INFO: PixTestPretest::doTest()
[12:03:09.186] <TB2> INFO: ######################################################################
[12:03:09.188] <TB2> INFO: ----------------------------------------------------------------------
[12:03:09.188] <TB2> INFO: PixTestPretest::programROC()
[12:03:09.188] <TB2> INFO: ----------------------------------------------------------------------
[12:03:27.209] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:03:27.209] <TB2> INFO: IA differences per ROC: 16.9 17.7 18.5 17.7 18.5 16.1 19.3 18.5 17.7 20.1 16.9 18.5 15.3 18.5 17.7 17.7
[12:03:27.295] <TB2> INFO: ----------------------------------------------------------------------
[12:03:27.295] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:03:27.295] <TB2> INFO: ----------------------------------------------------------------------
[12:03:48.631] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[12:03:48.631] <TB2> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 20.1 19.3 19.3 20.1 19.3
[12:03:48.678] <TB2> INFO: ----------------------------------------------------------------------
[12:03:48.678] <TB2> INFO: PixTestPretest::findTiming()
[12:03:48.678] <TB2> INFO: ----------------------------------------------------------------------
[12:03:48.678] <TB2> INFO: PixTestCmd::init()
[12:03:49.277] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[12:05:33.722] <TB2> INFO: TBM phases: 160MHz: 3, 400MHz: 2, TBM delays: ROC(0/1):0, header/trailer: 1, token: 0
[12:05:33.722] <TB2> INFO: (success/tries = 100/100), width = 1
[12:05:33.724] <TB2> INFO: ----------------------------------------------------------------------
[12:05:33.724] <TB2> INFO: PixTestPretest::findWorkingPixel()
[12:05:33.724] <TB2> INFO: ----------------------------------------------------------------------
[12:05:33.861] <TB2> INFO: Expecting 231680 events.
[12:05:38.472] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[12:05:38.475] <TB2> ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[12:05:41.788] <TB2> INFO: 231680 events read in total (7212ms).
[12:05:41.793] <TB2> INFO: Test took 8067ms.
[12:05:42.211] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:05:42.264] <TB2> INFO: ----------------------------------------------------------------------
[12:05:42.264] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[12:05:42.264] <TB2> INFO: ----------------------------------------------------------------------
[12:05:42.404] <TB2> INFO: Expecting 231680 events.
[12:05:51.007] <TB2> INFO: 231680 events read in total (7888ms).
[12:05:51.012] <TB2> INFO: Test took 8741ms.
[12:05:51.451] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[12:05:51.451] <TB2> INFO: CalDel: 103 141 117 117 135 127 121 100 118 129 124 125 116 105 123 127
[12:05:51.451] <TB2> INFO: VthrComp: 51 51 53 51 51 51 51 52 51 51 51 51 54 51 51 51
[12:05:51.455] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C0.dat
[12:05:51.455] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C1.dat
[12:05:51.456] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C2.dat
[12:05:51.456] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C3.dat
[12:05:51.456] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C4.dat
[12:05:51.456] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C5.dat
[12:05:51.457] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C6.dat
[12:05:51.457] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C7.dat
[12:05:51.457] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C8.dat
[12:05:51.457] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C9.dat
[12:05:51.457] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C10.dat
[12:05:51.458] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C11.dat
[12:05:51.458] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C12.dat
[12:05:51.458] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C13.dat
[12:05:51.458] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C14.dat
[12:05:51.458] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters_C15.dat
[12:05:51.459] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//tbmParameters_C0a.dat
[12:05:51.459] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//tbmParameters_C0b.dat
[12:05:51.459] <TB2> INFO: PixTestPretest::doTest() done, duration: 162 seconds
[12:05:51.528] <TB2> INFO: enter test to run
[12:05:51.528] <TB2> INFO: test: fulltest no parameter change
[12:05:51.528] <TB2> INFO: running: fulltest
[12:05:51.528] <TB2> INFO: ######################################################################
[12:05:51.528] <TB2> INFO: PixTestFullTest::doTest()
[12:05:51.528] <TB2> INFO: ######################################################################
[12:05:51.530] <TB2> INFO: ######################################################################
[12:05:51.530] <TB2> INFO: PixTestAlive::doTest()
[12:05:51.530] <TB2> INFO: ######################################################################
[12:05:51.531] <TB2> INFO: ----------------------------------------------------------------------
[12:05:51.531] <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)
[12:05:51.531] <TB2> INFO: ----------------------------------------------------------------------
[12:05:51.936] <TB2> INFO: Expecting 41600 events.
[12:05:56.080] <TB2> INFO: 41600 events read in total (3429ms).
[12:05:56.081] <TB2> INFO: Test took 4548ms.
[12:05:56.089] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:56.478] <TB2> INFO: PixTestAlive::aliveTest() done
[12:05:56.478] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 5 0 0 0 0 0 0
[12:05:56.480] <TB2> INFO: ----------------------------------------------------------------------
[12:05:56.480] <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)
[12:05:56.480] <TB2> INFO: ----------------------------------------------------------------------
[12:05:56.882] <TB2> INFO: Expecting 41600 events.
[12:05:59.861] <TB2> INFO: 41600 events read in total (2263ms).
[12:05:59.861] <TB2> INFO: Test took 3378ms.
[12:05:59.861] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:59.862] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:06:00.283] <TB2> INFO: PixTestAlive::maskTest() done
[12:06:00.283] <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
[12:06:00.285] <TB2> INFO: ----------------------------------------------------------------------
[12:06:00.285] <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)
[12:06:00.285] <TB2> INFO: ----------------------------------------------------------------------
[12:06:00.599] <TB2> INFO: Expecting 41600 events.
[12:06:04.751] <TB2> INFO: 41600 events read in total (3436ms).
[12:06:04.751] <TB2> INFO: Test took 4463ms.
[12:06:04.757] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:05.152] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[12:06:05.152] <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
[12:06:05.152] <TB2> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[12:06:05.152] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:05.152] <TB2> INFO: Decoding statistics:
[12:06:05.152] <TB2> INFO: General information:
[12:06:05.152] <TB2> INFO: 16bit words read: 0
[12:06:05.152] <TB2> INFO: valid events total: 0
[12:06:05.152] <TB2> INFO: empty events: 0
[12:06:05.152] <TB2> INFO: valid events with pixels: 0
[12:06:05.152] <TB2> INFO: valid pixel hits: 0
[12:06:05.152] <TB2> INFO: Event errors: 0
[12:06:05.152] <TB2> INFO: start marker: 0
[12:06:05.152] <TB2> INFO: stop marker: 0
[12:06:05.152] <TB2> INFO: overflow: 0
[12:06:05.152] <TB2> INFO: invalid 5bit words: 0
[12:06:05.152] <TB2> INFO: invalid XOR eye diagram: 0
[12:06:05.152] <TB2> INFO: TBM errors: 0
[12:06:05.152] <TB2> INFO: flawed TBM headers: 0
[12:06:05.152] <TB2> INFO: flawed TBM trailers: 0
[12:06:05.152] <TB2> INFO: event ID mismatches: 0
[12:06:05.152] <TB2> INFO: ROC errors: 0
[12:06:05.152] <TB2> INFO: missing ROC header(s): 0
[12:06:05.152] <TB2> INFO: misplaced readback start: 0
[12:06:05.152] <TB2> INFO: Pixel decoding errors: 0
[12:06:05.152] <TB2> INFO: pixel data incomplete: 0
[12:06:05.152] <TB2> INFO: pixel address: 0
[12:06:05.152] <TB2> INFO: pulse height fill bit: 0
[12:06:05.152] <TB2> INFO: buffer corruption: 0
[12:06:05.162] <TB2> INFO: ######################################################################
[12:06:05.162] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:06:05.162] <TB2> INFO: ######################################################################
[12:06:05.166] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:06:05.177] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[12:06:05.177] <TB2> INFO: run 1 of 1
[12:06:05.485] <TB2> INFO: Expecting 3120000 events.
[12:06:53.901] <TB2> INFO: 1084315 events read in total (47701ms).
[12:07:43.130] <TB2> INFO: 2171325 events read in total (96930ms).
[12:08:24.446] <TB2> INFO: 3120000 events read in total (138247ms).
[12:08:24.496] <TB2> INFO: Test took 139320ms.
[12:08:24.593] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:50.075] <TB2> INFO: PixTestBBMap::doTest() done, duration: 164 seconds
[12:08:50.075] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 2 0 4 0 0 0 0 0 0
[12:08:50.075] <TB2> INFO: separation cut (per ROC): 117 105 116 115 111 106 120 117 119 103 99 105 105 113 111 135
[12:08:50.075] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:50.075] <TB2> INFO: Decoding statistics:
[12:08:50.075] <TB2> INFO: General information:
[12:08:50.075] <TB2> INFO: 16bit words read: 0
[12:08:50.075] <TB2> INFO: valid events total: 0
[12:08:50.075] <TB2> INFO: empty events: 0
[12:08:50.075] <TB2> INFO: valid events with pixels: 0
[12:08:50.075] <TB2> INFO: valid pixel hits: 0
[12:08:50.075] <TB2> INFO: Event errors: 0
[12:08:50.075] <TB2> INFO: start marker: 0
[12:08:50.075] <TB2> INFO: stop marker: 0
[12:08:50.075] <TB2> INFO: overflow: 0
[12:08:50.075] <TB2> INFO: invalid 5bit words: 0
[12:08:50.075] <TB2> INFO: invalid XOR eye diagram: 0
[12:08:50.075] <TB2> INFO: TBM errors: 0
[12:08:50.075] <TB2> INFO: flawed TBM headers: 0
[12:08:50.075] <TB2> INFO: flawed TBM trailers: 0
[12:08:50.075] <TB2> INFO: event ID mismatches: 0
[12:08:50.075] <TB2> INFO: ROC errors: 0
[12:08:50.075] <TB2> INFO: missing ROC header(s): 0
[12:08:50.075] <TB2> INFO: misplaced readback start: 0
[12:08:50.075] <TB2> INFO: Pixel decoding errors: 0
[12:08:50.075] <TB2> INFO: pixel data incomplete: 0
[12:08:50.075] <TB2> INFO: pixel address: 0
[12:08:50.075] <TB2> INFO: pulse height fill bit: 0
[12:08:50.075] <TB2> INFO: buffer corruption: 0
[12:08:50.155] <TB2> INFO: ######################################################################
[12:08:50.155] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:08:50.155] <TB2> INFO: ######################################################################
[12:08:50.155] <TB2> INFO: ----------------------------------------------------------------------
[12:08:50.155] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:08:50.155] <TB2> INFO: ----------------------------------------------------------------------
[12:08:50.155] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:08:50.164] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[12:08:50.164] <TB2> INFO: run 1 of 1
[12:08:50.492] <TB2> INFO: Expecting 26208000 events.
[12:09:25.375] <TB2> INFO: 1260200 events read in total (34167ms).
[12:09:59.294] <TB2> INFO: 2493000 events read in total (68086ms).
[12:10:32.340] <TB2> INFO: 3716600 events read in total (101132ms).
[12:11:04.969] <TB2> INFO: 4933700 events read in total (133761ms).
[12:11:37.020] <TB2> INFO: 6152850 events read in total (165812ms).
[12:12:08.964] <TB2> INFO: 7362350 events read in total (197756ms).
[12:12:43.054] <TB2> INFO: 8575000 events read in total (231846ms).
[12:13:17.922] <TB2> INFO: 9782600 events read in total (266714ms).
[12:13:53.432] <TB2> INFO: 10987050 events read in total (302224ms).
[12:14:28.540] <TB2> INFO: 12192000 events read in total (337332ms).
[12:15:02.896] <TB2> INFO: 13382350 events read in total (371688ms).
[12:15:36.278] <TB2> INFO: 14564500 events read in total (405070ms).
[12:16:08.837] <TB2> INFO: 15746300 events read in total (437629ms).
[12:16:41.257] <TB2> INFO: 16919000 events read in total (470049ms).
[12:17:15.355] <TB2> INFO: 18088650 events read in total (504147ms).
[12:17:50.813] <TB2> INFO: 19261650 events read in total (539605ms).
[12:18:24.904] <TB2> INFO: 20429250 events read in total (573696ms).
[12:18:57.207] <TB2> INFO: 21598350 events read in total (605999ms).
[12:19:30.379] <TB2> INFO: 22766350 events read in total (639171ms).
[12:20:04.070] <TB2> INFO: 23934750 events read in total (672862ms).
[12:20:38.026] <TB2> INFO: 25104750 events read in total (706818ms).
[12:21:10.671] <TB2> INFO: 26208000 events read in total (739463ms).
[12:21:10.700] <TB2> INFO: Test took 740536ms.
[12:21:10.767] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:10.895] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:12.289] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:14.208] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:15.917] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:17.367] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:18.851] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:20.323] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:21.791] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:23.177] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:24.588] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:26.045] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:27.491] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:28.913] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:30.329] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:31.751] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:33.177] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:21:34.650] <TB2> INFO: PixTestScurves::scurves() done
[12:21:34.650] <TB2> INFO: Vcal mean: 106.95 99.61 113.06 102.97 102.01 103.86 105.23 117.92 109.84 94.90 97.01 101.31 110.30 113.08 104.91 104.94
[12:21:34.650] <TB2> INFO: Vcal RMS: 4.78 6.22 5.10 5.23 5.58 6.18 5.32 6.55 6.90 5.98 5.56 5.44 5.28 5.30 5.81 5.45
[12:21:34.650] <TB2> INFO: PixTestScurves::fullTest() done, duration: 764 seconds
[12:21:34.650] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:34.650] <TB2> INFO: Decoding statistics:
[12:21:34.650] <TB2> INFO: General information:
[12:21:34.650] <TB2> INFO: 16bit words read: 0
[12:21:34.650] <TB2> INFO: valid events total: 0
[12:21:34.650] <TB2> INFO: empty events: 0
[12:21:34.650] <TB2> INFO: valid events with pixels: 0
[12:21:34.651] <TB2> INFO: valid pixel hits: 0
[12:21:34.651] <TB2> INFO: Event errors: 0
[12:21:34.651] <TB2> INFO: start marker: 0
[12:21:34.651] <TB2> INFO: stop marker: 0
[12:21:34.651] <TB2> INFO: overflow: 0
[12:21:34.651] <TB2> INFO: invalid 5bit words: 0
[12:21:34.651] <TB2> INFO: invalid XOR eye diagram: 0
[12:21:34.651] <TB2> INFO: TBM errors: 0
[12:21:34.651] <TB2> INFO: flawed TBM headers: 0
[12:21:34.651] <TB2> INFO: flawed TBM trailers: 0
[12:21:34.651] <TB2> INFO: event ID mismatches: 0
[12:21:34.651] <TB2> INFO: ROC errors: 0
[12:21:34.651] <TB2> INFO: missing ROC header(s): 0
[12:21:34.651] <TB2> INFO: misplaced readback start: 0
[12:21:34.651] <TB2> INFO: Pixel decoding errors: 0
[12:21:34.651] <TB2> INFO: pixel data incomplete: 0
[12:21:34.651] <TB2> INFO: pixel address: 0
[12:21:34.651] <TB2> INFO: pulse height fill bit: 0
[12:21:34.651] <TB2> INFO: buffer corruption: 0
[12:21:34.723] <TB2> INFO: ######################################################################
[12:21:34.723] <TB2> INFO: PixTestTrim::doTest()
[12:21:34.723] <TB2> INFO: ######################################################################
[12:21:34.725] <TB2> INFO: ----------------------------------------------------------------------
[12:21:34.725] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:21:34.725] <TB2> INFO: ----------------------------------------------------------------------
[12:21:34.811] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:21:34.811] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:21:34.819] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:21:34.819] <TB2> INFO: run 1 of 1
[12:21:35.127] <TB2> INFO: Expecting 6281600 events.
[12:22:22.643] <TB2> INFO: 1429920 events read in total (46801ms).
[12:23:02.410] <TB2> INFO: 2846090 events read in total (86568ms).
[12:23:48.602] <TB2> INFO: 4262620 events read in total (132760ms).
[12:24:32.634] <TB2> INFO: 5688200 events read in total (176792ms).
[12:24:49.638] <TB2> INFO: 6281600 events read in total (193796ms).
[12:24:49.666] <TB2> INFO: Test took 194847ms.
[12:24:49.715] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:09.100] <TB2> INFO: ROC 0 VthrComp = 107
[12:25:09.100] <TB2> INFO: ROC 1 VthrComp = 94
[12:25:09.100] <TB2> INFO: ROC 2 VthrComp = 108
[12:25:09.100] <TB2> INFO: ROC 3 VthrComp = 102
[12:25:09.100] <TB2> INFO: ROC 4 VthrComp = 97
[12:25:09.100] <TB2> INFO: ROC 5 VthrComp = 99
[12:25:09.100] <TB2> INFO: ROC 6 VthrComp = 101
[12:25:09.101] <TB2> INFO: ROC 7 VthrComp = 109
[12:25:09.101] <TB2> INFO: ROC 8 VthrComp = 103
[12:25:09.101] <TB2> INFO: ROC 9 VthrComp = 99
[12:25:09.101] <TB2> INFO: ROC 10 VthrComp = 97
[12:25:09.101] <TB2> INFO: ROC 11 VthrComp = 101
[12:25:09.101] <TB2> INFO: ROC 12 VthrComp = 101
[12:25:09.102] <TB2> INFO: ROC 13 VthrComp = 110
[12:25:09.102] <TB2> INFO: ROC 14 VthrComp = 102
[12:25:09.102] <TB2> INFO: ROC 15 VthrComp = 102
[12:25:09.102] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:25:09.102] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:25:09.111] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:25:09.111] <TB2> INFO: run 1 of 1
[12:25:09.416] <TB2> INFO: Expecting 6281600 events.
[12:25:45.351] <TB2> INFO: 897840 events read in total (35219ms).
[12:26:22.079] <TB2> INFO: 1791170 events read in total (71947ms).
[12:26:55.290] <TB2> INFO: 2684430 events read in total (105158ms).
[12:27:33.284] <TB2> INFO: 3571920 events read in total (143152ms).
[12:28:11.178] <TB2> INFO: 4450920 events read in total (181046ms).
[12:28:48.970] <TB2> INFO: 5325590 events read in total (218838ms).
[12:29:25.804] <TB2> INFO: 6200390 events read in total (255672ms).
[12:29:29.580] <TB2> INFO: 6281600 events read in total (259448ms).
[12:29:29.639] <TB2> INFO: Test took 260529ms.
[12:29:29.795] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:54.362] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 59.3228 for pixel 0/14 mean/min/max = 47.1857/34.8592/59.5123
[12:29:54.362] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 64.5627 for pixel 0/21 mean/min/max = 48.1335/31.6718/64.5953
[12:29:54.362] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 63.9448 for pixel 11/0 mean/min/max = 49.6424/35.1571/64.1277
[12:29:54.363] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 57.6537 for pixel 0/37 mean/min/max = 44.9347/32.0745/57.7949
[12:29:54.363] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 61.505 for pixel 22/15 mean/min/max = 46.9354/32.0417/61.8292
[12:29:54.363] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 62.0474 for pixel 9/70 mean/min/max = 46.5253/30.6659/62.3847
[12:29:54.363] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 59.757 for pixel 41/10 mean/min/max = 46.1687/32.5571/59.7803
[12:29:54.364] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 72.0543 for pixel 1/79 mean/min/max = 54.6045/37.0466/72.1623
[12:29:54.364] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 64.3287 for pixel 0/33 mean/min/max = 48.0453/31.6803/64.4104
[12:29:54.364] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 57.6792 for pixel 0/5 mean/min/max = 44.154/30.4495/57.8586
[12:29:54.364] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 57.6572 for pixel 23/4 mean/min/max = 44.6014/31.3271/57.8757
[12:29:54.365] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 60.0189 for pixel 12/79 mean/min/max = 46.1102/32.0014/60.2191
[12:29:54.365] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 64.4301 for pixel 3/79 mean/min/max = 49.6523/34.6085/64.6962
[12:29:54.365] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 63.3305 for pixel 51/60 mean/min/max = 49.3234/34.9946/63.6523
[12:29:54.365] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 60.3428 for pixel 5/67 mean/min/max = 46.4249/32.2813/60.5684
[12:29:54.366] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 59.3642 for pixel 35/73 mean/min/max = 45.9108/32.3834/59.4383
[12:29:54.366] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:29:54.498] <TB2> INFO: Expecting 514560 events.
[12:30:05.638] <TB2> INFO: 514560 events read in total (10425ms).
[12:30:05.643] <TB2> INFO: Expecting 514560 events.
[12:30:16.997] <TB2> INFO: 514560 events read in total (10686ms).
[12:30:17.003] <TB2> INFO: Expecting 514560 events.
[12:30:26.646] <TB2> INFO: 514560 events read in total (8967ms).
[12:30:26.654] <TB2> INFO: Expecting 514560 events.
[12:30:38.014] <TB2> INFO: 514560 events read in total (10694ms).
[12:30:38.024] <TB2> INFO: Expecting 514560 events.
[12:30:48.856] <TB2> INFO: 514560 events read in total (10159ms).
[12:30:48.867] <TB2> INFO: Expecting 514560 events.
[12:31:00.109] <TB2> INFO: 514560 events read in total (10578ms).
[12:31:00.126] <TB2> INFO: Expecting 514560 events.
[12:31:09.811] <TB2> INFO: 514560 events read in total (9034ms).
[12:31:09.832] <TB2> INFO: Expecting 514560 events.
[12:31:21.334] <TB2> INFO: 514560 events read in total (10860ms).
[12:31:21.353] <TB2> INFO: Expecting 514560 events.
[12:31:32.252] <TB2> INFO: 514560 events read in total (10259ms).
[12:31:32.275] <TB2> INFO: Expecting 514560 events.
[12:31:42.980] <TB2> INFO: 514560 events read in total (10051ms).
[12:31:43.003] <TB2> INFO: Expecting 514560 events.
[12:31:52.940] <TB2> INFO: 514560 events read in total (9287ms).
[12:31:52.963] <TB2> INFO: Expecting 514560 events.
[12:32:01.995] <TB2> INFO: 514560 events read in total (8382ms).
[12:32:02.021] <TB2> INFO: Expecting 514560 events.
[12:32:10.886] <TB2> INFO: 514560 events read in total (8219ms).
[12:32:10.919] <TB2> INFO: Expecting 514560 events.
[12:32:20.597] <TB2> INFO: 514560 events read in total (9038ms).
[12:32:20.632] <TB2> INFO: Expecting 514560 events.
[12:32:30.255] <TB2> INFO: 514560 events read in total (8989ms).
[12:32:30.285] <TB2> INFO: Expecting 514560 events.
[12:32:40.247] <TB2> INFO: 514560 events read in total (9319ms).
[12:32:40.282] <TB2> INFO: Test took 165916ms.
[12:32:41.277] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:32:41.285] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:32:41.285] <TB2> INFO: run 1 of 1
[12:32:41.597] <TB2> INFO: Expecting 6281600 events.
[12:33:21.467] <TB2> INFO: 873780 events read in total (39155ms).
[12:34:02.487] <TB2> INFO: 1745210 events read in total (80175ms).
[12:34:42.811] <TB2> INFO: 2616590 events read in total (120499ms).
[12:35:20.218] <TB2> INFO: 3483730 events read in total (157906ms).
[12:35:59.153] <TB2> INFO: 4341690 events read in total (196841ms).
[12:36:36.838] <TB2> INFO: 5195880 events read in total (234526ms).
[12:37:14.099] <TB2> INFO: 6048630 events read in total (271787ms).
[12:37:26.211] <TB2> INFO: 6281600 events read in total (283899ms).
[12:37:26.275] <TB2> INFO: Test took 284990ms.
[12:37:26.424] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:51.388] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.077851 .. 255.000000
[12:37:51.468] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:37:51.476] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:37:51.476] <TB2> INFO: run 1 of 1
[12:37:51.782] <TB2> INFO: Expecting 10649600 events.
[12:38:30.850] <TB2> INFO: 827180 events read in total (38350ms).
[12:39:10.610] <TB2> INFO: 1654350 events read in total (78110ms).
[12:39:43.529] <TB2> INFO: 2481580 events read in total (111029ms).
[12:40:23.069] <TB2> INFO: 3309170 events read in total (150569ms).
[12:41:04.303] <TB2> INFO: 4136460 events read in total (191803ms).
[12:41:45.963] <TB2> INFO: 4964540 events read in total (233463ms).
[12:42:25.600] <TB2> INFO: 5791950 events read in total (273100ms).
[12:43:03.595] <TB2> INFO: 6618690 events read in total (311095ms).
[12:43:41.601] <TB2> INFO: 7445000 events read in total (349101ms).
[12:44:20.080] <TB2> INFO: 8270070 events read in total (387580ms).
[12:44:58.170] <TB2> INFO: 9094770 events read in total (425670ms).
[12:45:35.130] <TB2> INFO: 9919370 events read in total (462630ms).
[12:46:08.950] <TB2> INFO: 10649600 events read in total (496450ms).
[12:46:09.061] <TB2> INFO: Test took 497584ms.
[12:46:09.357] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:41.246] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 14.033081 .. 93.318991
[12:46:41.344] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 103 (-1/-1) hits flags = 528 (plus default)
[12:46:41.354] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:46:41.354] <TB2> INFO: run 1 of 1
[12:46:41.660] <TB2> INFO: Expecting 4160000 events.
[12:47:25.330] <TB2> INFO: 930740 events read in total (42954ms).
[12:48:01.044] <TB2> INFO: 1861090 events read in total (78668ms).
[12:48:44.137] <TB2> INFO: 2790840 events read in total (121761ms).
[12:49:26.542] <TB2> INFO: 3719990 events read in total (164166ms).
[12:49:43.411] <TB2> INFO: 4160000 events read in total (181035ms).
[12:49:43.457] <TB2> INFO: Test took 182103ms.
[12:49:43.568] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:04.367] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 0.371903 .. 80.778586
[12:50:04.451] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 0 .. 90 (-1/-1) hits flags = 528 (plus default)
[12:50:04.459] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:50:04.459] <TB2> INFO: run 1 of 1
[12:50:04.780] <TB2> INFO: Expecting 3785600 events.
[12:50:43.922] <TB2> INFO: 982770 events read in total (38426ms).
[12:51:24.097] <TB2> INFO: 1965390 events read in total (78602ms).
[12:52:00.855] <TB2> INFO: 2947050 events read in total (115360ms).
[12:52:33.529] <TB2> INFO: 3785600 events read in total (148033ms).
[12:52:33.570] <TB2> INFO: Test took 149111ms.
[12:52:33.650] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:51.656] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 0.351079 .. 59.723043
[12:52:51.736] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 0 .. 69 (-1/-1) hits flags = 528 (plus default)
[12:52:51.743] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:52:51.743] <TB2> INFO: run 1 of 1
[12:52:52.049] <TB2> INFO: Expecting 2912000 events.
[12:53:30.461] <TB2> INFO: 1078770 events read in total (37697ms).
[12:54:15.234] <TB2> INFO: 2157130 events read in total (82470ms).
[12:54:41.844] <TB2> INFO: 2912000 events read in total (109080ms).
[12:54:41.864] <TB2> INFO: Test took 110121ms.
[12:54:41.912] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:56.585] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[12:54:56.585] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[12:54:56.593] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:54:56.593] <TB2> INFO: run 1 of 1
[12:54:56.907] <TB2> INFO: Expecting 1705600 events.
[12:55:41.285] <TB2> INFO: 1077040 events read in total (43663ms).
[12:56:07.939] <TB2> INFO: 1705600 events read in total (70317ms).
[12:56:07.957] <TB2> INFO: Test took 71365ms.
[12:56:07.992] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:22.101] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C0.dat
[12:56:22.102] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C1.dat
[12:56:22.102] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C2.dat
[12:56:22.102] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C3.dat
[12:56:22.102] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C4.dat
[12:56:22.102] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C5.dat
[12:56:22.103] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C6.dat
[12:56:22.103] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C7.dat
[12:56:22.103] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C8.dat
[12:56:22.103] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C9.dat
[12:56:22.103] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C10.dat
[12:56:22.103] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C11.dat
[12:56:22.104] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C12.dat
[12:56:22.104] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C13.dat
[12:56:22.104] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C14.dat
[12:56:22.104] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C15.dat
[12:56:22.104] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C0.dat
[12:56:22.111] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C1.dat
[12:56:22.119] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C2.dat
[12:56:22.127] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C3.dat
[12:56:22.134] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C4.dat
[12:56:22.141] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C5.dat
[12:56:22.149] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C6.dat
[12:56:22.157] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C7.dat
[12:56:22.164] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C8.dat
[12:56:22.172] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C9.dat
[12:56:22.179] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C10.dat
[12:56:22.187] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C11.dat
[12:56:22.194] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C12.dat
[12:56:22.203] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C13.dat
[12:56:22.210] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C14.dat
[12:56:22.218] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//trimParameters35_C15.dat
[12:56:22.225] <TB2> INFO: PixTestTrim::trimTest() done
[12:56:22.225] <TB2> INFO: vtrim: 100 99 109 86 100 105 102 144 93 98 89 105 104 118 113 95
[12:56:22.225] <TB2> INFO: vthrcomp: 107 94 108 102 97 99 101 109 103 99 97 101 101 110 102 102
[12:56:22.225] <TB2> INFO: vcal mean: 34.98 34.93 35.02 34.99 34.99 34.94 34.98 34.99 34.95 34.89 34.94 35.00 35.00 34.99 34.96 34.98
[12:56:22.225] <TB2> INFO: vcal RMS: 0.84 1.39 0.94 3.53 0.94 0.95 1.00 1.02 0.92 1.16 0.89 0.85 1.17 0.90 0.94 0.87
[12:56:22.225] <TB2> INFO: bits mean: 8.32 9.24 8.70 9.82 9.66 9.82 9.69 7.44 8.70 10.15 9.81 9.22 8.42 8.40 9.87 9.40
[12:56:22.225] <TB2> INFO: bits RMS: 2.66 2.65 2.32 2.61 2.51 2.67 2.47 2.30 2.92 2.55 2.72 2.79 2.52 2.44 2.49 2.65
[12:56:22.235] <TB2> INFO: ----------------------------------------------------------------------
[12:56:22.235] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[12:56:22.235] <TB2> INFO: ----------------------------------------------------------------------
[12:56:22.239] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[12:56:22.248] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:56:22.248] <TB2> INFO: run 1 of 1
[12:56:22.582] <TB2> INFO: Expecting 8320000 events.
[12:57:06.698] <TB2> INFO: 1273040 events read in total (43401ms).
[12:57:47.955] <TB2> INFO: 2525540 events read in total (84658ms).
[12:58:30.190] <TB2> INFO: 3769230 events read in total (126893ms).
[12:59:13.649] <TB2> INFO: 4997450 events read in total (170352ms).
[12:59:53.046] <TB2> INFO: 6215840 events read in total (209749ms).
[13:00:39.899] <TB2> INFO: 7433830 events read in total (256602ms).
[13:01:12.198] <TB2> INFO: 8320000 events read in total (288901ms).
[13:01:12.242] <TB2> INFO: Test took 289994ms.
[13:01:12.338] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:39.385] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 189 (-1/-1) hits flags = 528 (plus default)
[13:01:39.394] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[13:01:39.394] <TB2> INFO: run 1 of 1
[13:01:39.728] <TB2> INFO: Expecting 7904000 events.
[13:02:27.752] <TB2> INFO: 1243920 events read in total (47309ms).
[13:03:12.790] <TB2> INFO: 2467790 events read in total (92347ms).
[13:03:57.140] <TB2> INFO: 3683730 events read in total (136697ms).
[13:04:39.880] <TB2> INFO: 4883070 events read in total (179437ms).
[13:05:20.726] <TB2> INFO: 6075530 events read in total (220283ms).
[13:06:00.237] <TB2> INFO: 7266800 events read in total (259794ms).
[13:06:24.732] <TB2> INFO: 7904000 events read in total (284289ms).
[13:06:24.776] <TB2> INFO: Test took 285382ms.
[13:06:24.865] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:52.109] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[13:06:52.116] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[13:06:52.117] <TB2> INFO: run 1 of 1
[13:06:52.443] <TB2> INFO: Expecting 7488000 events.
[13:07:41.625] <TB2> INFO: 1286670 events read in total (48467ms).
[13:08:27.992] <TB2> INFO: 2551280 events read in total (94835ms).
[13:09:14.825] <TB2> INFO: 3805020 events read in total (141668ms).
[13:09:56.478] <TB2> INFO: 5039140 events read in total (183320ms).
[13:10:39.189] <TB2> INFO: 6268350 events read in total (226032ms).
[13:11:23.432] <TB2> INFO: 7488000 events read in total (270274ms).
[13:11:23.477] <TB2> INFO: Test took 271361ms.
[13:11:23.556] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:50.082] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[13:11:50.090] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[13:11:50.090] <TB2> INFO: run 1 of 1
[13:11:50.399] <TB2> INFO: Expecting 7488000 events.
[13:12:34.853] <TB2> INFO: 1284910 events read in total (43739ms).
[13:13:18.087] <TB2> INFO: 2548120 events read in total (86973ms).
[13:13:59.739] <TB2> INFO: 3799600 events read in total (128625ms).
[13:14:42.563] <TB2> INFO: 5032290 events read in total (171449ms).
[13:15:26.247] <TB2> INFO: 6260060 events read in total (215133ms).
[13:16:09.885] <TB2> INFO: 7488000 events read in total (258771ms).
[13:16:09.929] <TB2> INFO: Test took 259839ms.
[13:16:10.008] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:34.919] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[13:16:34.927] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[13:16:34.927] <TB2> INFO: run 1 of 1
[13:16:35.232] <TB2> INFO: Expecting 7446400 events.
[13:17:22.233] <TB2> INFO: 1288750 events read in total (46286ms).
[13:18:07.807] <TB2> INFO: 2554630 events read in total (91860ms).
[13:18:48.639] <TB2> INFO: 3808940 events read in total (132692ms).
[13:19:32.038] <TB2> INFO: 5044540 events read in total (176091ms).
[13:20:20.221] <TB2> INFO: 6275130 events read in total (224274ms).
[13:21:03.631] <TB2> INFO: 7446400 events read in total (267684ms).
[13:21:03.673] <TB2> INFO: Test took 268746ms.
[13:21:03.748] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:26.930] <TB2> INFO: PixTestTrim::trimBitTest() done
[13:21:26.931] <TB2> INFO: PixTestTrim::doTest() done, duration: 3592 seconds
[13:21:26.931] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:26.931] <TB2> INFO: Decoding statistics:
[13:21:26.931] <TB2> INFO: General information:
[13:21:26.931] <TB2> INFO: 16bit words read: 0
[13:21:26.931] <TB2> INFO: valid events total: 0
[13:21:26.931] <TB2> INFO: empty events: 0
[13:21:26.931] <TB2> INFO: valid events with pixels: 0
[13:21:26.931] <TB2> INFO: valid pixel hits: 0
[13:21:26.931] <TB2> INFO: Event errors: 0
[13:21:26.931] <TB2> INFO: start marker: 0
[13:21:26.931] <TB2> INFO: stop marker: 0
[13:21:26.931] <TB2> INFO: overflow: 0
[13:21:26.931] <TB2> INFO: invalid 5bit words: 0
[13:21:26.931] <TB2> INFO: invalid XOR eye diagram: 0
[13:21:26.931] <TB2> INFO: TBM errors: 0
[13:21:26.931] <TB2> INFO: flawed TBM headers: 0
[13:21:26.931] <TB2> INFO: flawed TBM trailers: 0
[13:21:26.931] <TB2> INFO: event ID mismatches: 0
[13:21:26.931] <TB2> INFO: ROC errors: 0
[13:21:26.931] <TB2> INFO: missing ROC header(s): 0
[13:21:26.931] <TB2> INFO: misplaced readback start: 0
[13:21:26.931] <TB2> INFO: Pixel decoding errors: 0
[13:21:26.931] <TB2> INFO: pixel data incomplete: 0
[13:21:26.931] <TB2> INFO: pixel address: 0
[13:21:26.931] <TB2> INFO: pulse height fill bit: 0
[13:21:26.932] <TB2> INFO: buffer corruption: 0
[13:21:27.596] <TB2> INFO: ######################################################################
[13:21:27.596] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:21:27.596] <TB2> INFO: ######################################################################
[13:21:27.909] <TB2> INFO: Expecting 41600 events.
[13:21:32.781] <TB2> INFO: 41600 events read in total (4157ms).
[13:21:32.781] <TB2> INFO: Test took 5183ms.
[13:21:32.787] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:33.470] <TB2> INFO: Expecting 41600 events.
[13:21:37.941] <TB2> INFO: 41600 events read in total (3755ms).
[13:21:37.942] <TB2> INFO: Test took 4796ms.
[13:21:37.948] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:38.387] <TB2> INFO: Expecting 41600 events.
[13:21:43.343] <TB2> INFO: 41600 events read in total (4241ms).
[13:21:43.343] <TB2> INFO: Test took 5281ms.
[13:21:43.349] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:43.798] <TB2> INFO: Expecting 41600 events.
[13:21:48.027] <TB2> INFO: 41600 events read in total (3514ms).
[13:21:48.028] <TB2> INFO: Test took 4555ms.
[13:21:48.034] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:48.465] <TB2> INFO: Expecting 2560 events.
[13:21:49.429] <TB2> INFO: 2560 events read in total (249ms).
[13:21:49.429] <TB2> INFO: Test took 1387ms.
[13:21:49.940] <TB2> INFO: Expecting 2560 events.
[13:21:50.960] <TB2> INFO: 2560 events read in total (305ms).
[13:21:50.960] <TB2> INFO: Test took 1530ms.
[13:21:51.470] <TB2> INFO: Expecting 2560 events.
[13:21:52.501] <TB2> INFO: 2560 events read in total (315ms).
[13:21:52.502] <TB2> INFO: Test took 1541ms.
[13:21:53.011] <TB2> INFO: Expecting 2560 events.
[13:21:54.002] <TB2> INFO: 2560 events read in total (275ms).
[13:21:54.002] <TB2> INFO: Test took 1500ms.
[13:21:54.511] <TB2> INFO: Expecting 2560 events.
[13:21:55.473] <TB2> INFO: 2560 events read in total (247ms).
[13:21:55.474] <TB2> INFO: Test took 1471ms.
[13:21:55.983] <TB2> INFO: Expecting 2560 events.
[13:21:57.004] <TB2> INFO: 2560 events read in total (306ms).
[13:21:57.005] <TB2> INFO: Test took 1531ms.
[13:21:57.515] <TB2> INFO: Expecting 2560 events.
[13:21:58.506] <TB2> INFO: 2560 events read in total (276ms).
[13:21:58.507] <TB2> INFO: Test took 1502ms.
[13:21:59.017] <TB2> INFO: Expecting 2560 events.
[13:21:59.995] <TB2> INFO: 2560 events read in total (263ms).
[13:21:59.995] <TB2> INFO: Test took 1488ms.
[13:22:00.504] <TB2> INFO: Expecting 2560 events.
[13:22:01.467] <TB2> INFO: 2560 events read in total (247ms).
[13:22:01.467] <TB2> INFO: Test took 1471ms.
[13:22:01.978] <TB2> INFO: Expecting 2560 events.
[13:22:02.940] <TB2> INFO: 2560 events read in total (247ms).
[13:22:02.941] <TB2> INFO: Test took 1473ms.
[13:22:03.451] <TB2> INFO: Expecting 2560 events.
[13:22:04.470] <TB2> INFO: 2560 events read in total (304ms).
[13:22:04.471] <TB2> INFO: Test took 1529ms.
[13:22:04.980] <TB2> INFO: Expecting 2560 events.
[13:22:05.954] <TB2> INFO: 2560 events read in total (258ms).
[13:22:05.954] <TB2> INFO: Test took 1483ms.
[13:22:06.464] <TB2> INFO: Expecting 2560 events.
[13:22:07.426] <TB2> INFO: 2560 events read in total (247ms).
[13:22:07.427] <TB2> INFO: Test took 1472ms.
[13:22:07.936] <TB2> INFO: Expecting 2560 events.
[13:22:08.914] <TB2> INFO: 2560 events read in total (262ms).
[13:22:08.914] <TB2> INFO: Test took 1487ms.
[13:22:09.424] <TB2> INFO: Expecting 2560 events.
[13:22:10.388] <TB2> INFO: 2560 events read in total (249ms).
[13:22:10.388] <TB2> INFO: Test took 1474ms.
[13:22:10.899] <TB2> INFO: Expecting 2560 events.
[13:22:11.862] <TB2> INFO: 2560 events read in total (248ms).
[13:22:11.863] <TB2> INFO: Test took 1474ms.
[13:22:11.867] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:22:12.375] <TB2> INFO: Expecting 655360 events.
[13:22:26.979] <TB2> INFO: 655360 events read in total (13888ms).
[13:22:26.989] <TB2> INFO: Expecting 655360 events.
[13:22:41.094] <TB2> INFO: 655360 events read in total (13509ms).
[13:22:41.106] <TB2> INFO: Expecting 655360 events.
[13:22:54.768] <TB2> INFO: 655360 events read in total (13065ms).
[13:22:54.784] <TB2> INFO: Expecting 655360 events.
[13:23:08.441] <TB2> INFO: 655360 events read in total (13078ms).
[13:23:08.460] <TB2> INFO: Expecting 655360 events.
[13:23:22.512] <TB2> INFO: 655360 events read in total (13460ms).
[13:23:22.535] <TB2> INFO: Expecting 655360 events.
[13:23:36.444] <TB2> INFO: 655360 events read in total (13328ms).
[13:23:36.472] <TB2> INFO: Expecting 655360 events.
[13:23:50.614] <TB2> INFO: 655360 events read in total (13565ms).
[13:23:50.643] <TB2> INFO: Expecting 655360 events.
[13:24:05.181] <TB2> INFO: 655360 events read in total (13955ms).
[13:24:05.214] <TB2> INFO: Expecting 655360 events.
[13:24:19.927] <TB2> INFO: 655360 events read in total (14140ms).
[13:24:19.963] <TB2> INFO: Expecting 655360 events.
[13:24:34.982] <TB2> INFO: 655360 events read in total (14456ms).
[13:24:35.025] <TB2> INFO: Expecting 655360 events.
[13:24:50.342] <TB2> INFO: 655360 events read in total (14767ms).
[13:24:50.386] <TB2> INFO: Expecting 655360 events.
[13:25:05.548] <TB2> INFO: 655360 events read in total (14594ms).
[13:25:05.602] <TB2> INFO: Expecting 655360 events.
[13:25:20.521] <TB2> INFO: 655360 events read in total (14376ms).
[13:25:20.576] <TB2> INFO: Expecting 655360 events.
[13:25:35.226] <TB2> INFO: 655360 events read in total (14092ms).
[13:25:35.292] <TB2> INFO: Expecting 655360 events.
[13:25:49.418] <TB2> INFO: 655360 events read in total (13599ms).
[13:25:49.478] <TB2> INFO: Expecting 655360 events.
[13:26:03.067] <TB2> INFO: 655360 events read in total (13044ms).
[13:26:03.127] <TB2> INFO: Test took 231260ms.
[13:26:03.207] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:03.516] <TB2> INFO: Expecting 655360 events.
[13:26:17.302] <TB2> INFO: 655360 events read in total (13071ms).
[13:26:17.311] <TB2> INFO: Expecting 655360 events.
[13:26:30.924] <TB2> INFO: 655360 events read in total (13023ms).
[13:26:30.936] <TB2> INFO: Expecting 655360 events.
[13:26:44.169] <TB2> INFO: 655360 events read in total (12645ms).
[13:26:44.185] <TB2> INFO: Expecting 655360 events.
[13:26:57.450] <TB2> INFO: 655360 events read in total (12670ms).
[13:26:57.469] <TB2> INFO: Expecting 655360 events.
[13:27:10.606] <TB2> INFO: 655360 events read in total (12546ms).
[13:27:10.629] <TB2> INFO: Expecting 655360 events.
[13:27:22.899] <TB2> INFO: 655360 events read in total (11685ms).
[13:27:22.924] <TB2> INFO: Expecting 655360 events.
[13:27:34.913] <TB2> INFO: 655360 events read in total (11422ms).
[13:27:34.942] <TB2> INFO: Expecting 655360 events.
[13:27:47.927] <TB2> INFO: 655360 events read in total (12397ms).
[13:27:47.964] <TB2> INFO: Expecting 655360 events.
[13:28:00.741] <TB2> INFO: 655360 events read in total (12213ms).
[13:28:00.778] <TB2> INFO: Expecting 655360 events.
[13:28:13.265] <TB2> INFO: 655360 events read in total (11920ms).
[13:28:13.308] <TB2> INFO: Expecting 655360 events.
[13:28:26.394] <TB2> INFO: 655360 events read in total (12507ms).
[13:28:26.438] <TB2> INFO: Expecting 655360 events.
[13:28:40.229] <TB2> INFO: 655360 events read in total (13222ms).
[13:28:40.284] <TB2> INFO: Expecting 655360 events.
[13:28:53.575] <TB2> INFO: 655360 events read in total (12749ms).
[13:28:53.630] <TB2> INFO: Expecting 655360 events.
[13:29:06.993] <TB2> INFO: 655360 events read in total (12798ms).
[13:29:07.053] <TB2> INFO: Expecting 655360 events.
[13:29:20.773] <TB2> INFO: 655360 events read in total (13162ms).
[13:29:20.841] <TB2> INFO: Expecting 655360 events.
[13:29:34.578] <TB2> INFO: 655360 events read in total (13210ms).
[13:29:34.639] <TB2> INFO: Test took 211432ms.
[13:29:34.868] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.877] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.886] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.895] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.904] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.913] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[13:29:34.921] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.930] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.939] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.946] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.953] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.961] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.967] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.974] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.981] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:34.987] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[13:29:34.994] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[13:29:35.000] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[13:29:35.007] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[13:29:35.014] <TB2> INFO: safety margin for low PH: adding 5, margin is now 25
[13:29:35.020] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:35.027] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:29:35.082] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C0.dat
[13:29:35.082] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C1.dat
[13:29:35.082] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C2.dat
[13:29:35.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C3.dat
[13:29:35.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C4.dat
[13:29:35.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C5.dat
[13:29:35.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C6.dat
[13:29:35.083] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C7.dat
[13:29:35.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C8.dat
[13:29:35.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C9.dat
[13:29:35.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C10.dat
[13:29:35.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C11.dat
[13:29:35.084] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C12.dat
[13:29:35.085] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C13.dat
[13:29:35.085] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C14.dat
[13:29:35.085] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//dacParameters35_C15.dat
[13:29:35.421] <TB2> INFO: Expecting 41600 events.
[13:29:39.960] <TB2> INFO: 41600 events read in total (3824ms).
[13:29:39.960] <TB2> INFO: Test took 4870ms.
[13:29:40.639] <TB2> INFO: Expecting 41600 events.
[13:29:44.851] <TB2> INFO: 41600 events read in total (3497ms).
[13:29:44.852] <TB2> INFO: Test took 4548ms.
[13:29:45.510] <TB2> INFO: Expecting 41600 events.
[13:29:49.709] <TB2> INFO: 41600 events read in total (3483ms).
[13:29:49.710] <TB2> INFO: Test took 4516ms.
[13:29:50.048] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:29:50.181] <TB2> INFO: Expecting 2560 events.
[13:29:51.145] <TB2> INFO: 2560 events read in total (248ms).
[13:29:51.145] <TB2> INFO: Test took 1097ms.
[13:29:51.148] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:29:51.656] <TB2> INFO: Expecting 2560 events.
[13:29:52.635] <TB2> INFO: 2560 events read in total (263ms).
[13:29:52.636] <TB2> INFO: Test took 1488ms.
[13:29:52.639] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:29:53.147] <TB2> INFO: Expecting 2560 events.
[13:29:54.125] <TB2> INFO: 2560 events read in total (263ms).
[13:29:54.125] <TB2> INFO: Test took 1488ms.
[13:29:54.128] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:29:54.637] <TB2> INFO: Expecting 2560 events.
[13:29:55.616] <TB2> INFO: 2560 events read in total (264ms).
[13:29:55.616] <TB2> INFO: Test took 1488ms.
[13:29:55.620] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:29:56.127] <TB2> INFO: Expecting 2560 events.
[13:29:57.090] <TB2> INFO: 2560 events read in total (247ms).
[13:29:57.091] <TB2> INFO: Test took 1471ms.
[13:29:57.094] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:29:57.602] <TB2> INFO: Expecting 2560 events.
[13:29:58.607] <TB2> INFO: 2560 events read in total (290ms).
[13:29:58.608] <TB2> INFO: Test took 1514ms.
[13:29:58.611] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:29:59.119] <TB2> INFO: Expecting 2560 events.
[13:30:00.083] <TB2> INFO: 2560 events read in total (249ms).
[13:30:00.083] <TB2> INFO: Test took 1472ms.
[13:30:00.086] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:00.594] <TB2> INFO: Expecting 2560 events.
[13:30:01.567] <TB2> INFO: 2560 events read in total (257ms).
[13:30:01.568] <TB2> INFO: Test took 1482ms.
[13:30:01.571] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:02.079] <TB2> INFO: Expecting 2560 events.
[13:30:03.043] <TB2> INFO: 2560 events read in total (248ms).
[13:30:03.043] <TB2> INFO: Test took 1472ms.
[13:30:03.047] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:03.555] <TB2> INFO: Expecting 2560 events.
[13:30:04.513] <TB2> INFO: 2560 events read in total (243ms).
[13:30:04.513] <TB2> INFO: Test took 1467ms.
[13:30:04.516] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:05.024] <TB2> INFO: Expecting 2560 events.
[13:30:05.997] <TB2> INFO: 2560 events read in total (258ms).
[13:30:05.997] <TB2> INFO: Test took 1481ms.
[13:30:06.000] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:06.507] <TB2> INFO: Expecting 2560 events.
[13:30:07.465] <TB2> INFO: 2560 events read in total (243ms).
[13:30:07.466] <TB2> INFO: Test took 1466ms.
[13:30:07.468] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:07.976] <TB2> INFO: Expecting 2560 events.
[13:30:08.933] <TB2> INFO: 2560 events read in total (242ms).
[13:30:08.934] <TB2> INFO: Test took 1466ms.
[13:30:08.936] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:09.444] <TB2> INFO: Expecting 2560 events.
[13:30:10.402] <TB2> INFO: 2560 events read in total (243ms).
[13:30:10.403] <TB2> INFO: Test took 1467ms.
[13:30:10.405] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:10.913] <TB2> INFO: Expecting 2560 events.
[13:30:11.871] <TB2> INFO: 2560 events read in total (243ms).
[13:30:11.871] <TB2> INFO: Test took 1466ms.
[13:30:11.874] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:12.381] <TB2> INFO: Expecting 2560 events.
[13:30:13.355] <TB2> INFO: 2560 events read in total (258ms).
[13:30:13.355] <TB2> INFO: Test took 1481ms.
[13:30:13.358] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:13.865] <TB2> INFO: Expecting 2560 events.
[13:30:14.824] <TB2> INFO: 2560 events read in total (243ms).
[13:30:14.824] <TB2> INFO: Test took 1466ms.
[13:30:14.827] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:15.334] <TB2> INFO: Expecting 2560 events.
[13:30:16.308] <TB2> INFO: 2560 events read in total (258ms).
[13:30:16.309] <TB2> INFO: Test took 1482ms.
[13:30:16.311] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:16.819] <TB2> INFO: Expecting 2560 events.
[13:30:17.778] <TB2> INFO: 2560 events read in total (244ms).
[13:30:17.778] <TB2> INFO: Test took 1467ms.
[13:30:17.783] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:18.288] <TB2> INFO: Expecting 2560 events.
[13:30:19.261] <TB2> INFO: 2560 events read in total (258ms).
[13:30:19.261] <TB2> INFO: Test took 1478ms.
[13:30:19.263] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:19.771] <TB2> INFO: Expecting 2560 events.
[13:30:20.729] <TB2> INFO: 2560 events read in total (242ms).
[13:30:20.730] <TB2> INFO: Test took 1467ms.
[13:30:20.732] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:21.240] <TB2> INFO: Expecting 2560 events.
[13:30:22.199] <TB2> INFO: 2560 events read in total (244ms).
[13:30:22.199] <TB2> INFO: Test took 1467ms.
[13:30:22.202] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:22.709] <TB2> INFO: Expecting 2560 events.
[13:30:23.667] <TB2> INFO: 2560 events read in total (242ms).
[13:30:23.668] <TB2> INFO: Test took 1466ms.
[13:30:23.670] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:24.178] <TB2> INFO: Expecting 2560 events.
[13:30:25.136] <TB2> INFO: 2560 events read in total (243ms).
[13:30:25.137] <TB2> INFO: Test took 1467ms.
[13:30:25.139] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:25.647] <TB2> INFO: Expecting 2560 events.
[13:30:26.619] <TB2> INFO: 2560 events read in total (257ms).
[13:30:26.619] <TB2> INFO: Test took 1480ms.
[13:30:26.622] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:27.129] <TB2> INFO: Expecting 2560 events.
[13:30:28.087] <TB2> INFO: 2560 events read in total (243ms).
[13:30:28.088] <TB2> INFO: Test took 1466ms.
[13:30:28.090] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:28.598] <TB2> INFO: Expecting 2560 events.
[13:30:29.556] <TB2> INFO: 2560 events read in total (243ms).
[13:30:29.556] <TB2> INFO: Test took 1466ms.
[13:30:29.559] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:30.067] <TB2> INFO: Expecting 2560 events.
[13:30:31.025] <TB2> INFO: 2560 events read in total (243ms).
[13:30:31.025] <TB2> INFO: Test took 1466ms.
[13:30:31.028] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:31.535] <TB2> INFO: Expecting 2560 events.
[13:30:32.493] <TB2> INFO: 2560 events read in total (242ms).
[13:30:32.493] <TB2> INFO: Test took 1465ms.
[13:30:32.496] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:33.004] <TB2> INFO: Expecting 2560 events.
[13:30:33.963] <TB2> INFO: 2560 events read in total (244ms).
[13:30:33.963] <TB2> INFO: Test took 1467ms.
[13:30:33.965] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:34.473] <TB2> INFO: Expecting 2560 events.
[13:30:35.431] <TB2> INFO: 2560 events read in total (243ms).
[13:30:35.431] <TB2> INFO: Test took 1466ms.
[13:30:35.434] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:30:35.942] <TB2> INFO: Expecting 2560 events.
[13:30:36.900] <TB2> INFO: 2560 events read in total (243ms).
[13:30:36.900] <TB2> INFO: Test took 1466ms.
[13:30:37.674] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 550 seconds
[13:30:37.674] <TB2> INFO: PH scale (per ROC): 70 58 64 65 65 68 63 65 67 74 66 75 64 70 65 65
[13:30:37.674] <TB2> INFO: PH offset (per ROC): 178 184 188 186 190 184 186 174 184 173 194 173 190 176 184 189
[13:30:37.678] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:37.678] <TB2> INFO: Decoding statistics:
[13:30:37.678] <TB2> INFO: General information:
[13:30:37.678] <TB2> INFO: 16bit words read: 66436
[13:30:37.678] <TB2> INFO: valid events total: 5120
[13:30:37.678] <TB2> INFO: empty events: 2622
[13:30:37.678] <TB2> INFO: valid events with pixels: 2498
[13:30:37.678] <TB2> INFO: valid pixel hits: 2498
[13:30:37.678] <TB2> INFO: Event errors: 0
[13:30:37.678] <TB2> INFO: start marker: 0
[13:30:37.678] <TB2> INFO: stop marker: 0
[13:30:37.678] <TB2> INFO: overflow: 0
[13:30:37.678] <TB2> INFO: invalid 5bit words: 0
[13:30:37.678] <TB2> INFO: invalid XOR eye diagram: 0
[13:30:37.678] <TB2> INFO: TBM errors: 0
[13:30:37.678] <TB2> INFO: flawed TBM headers: 0
[13:30:37.678] <TB2> INFO: flawed TBM trailers: 0
[13:30:37.678] <TB2> INFO: event ID mismatches: 0
[13:30:37.678] <TB2> INFO: ROC errors: 0
[13:30:37.678] <TB2> INFO: missing ROC header(s): 0
[13:30:37.678] <TB2> INFO: misplaced readback start: 0
[13:30:37.678] <TB2> INFO: Pixel decoding errors: 0
[13:30:37.678] <TB2> INFO: pixel data incomplete: 0
[13:30:37.678] <TB2> INFO: pixel address: 0
[13:30:37.678] <TB2> INFO: pulse height fill bit: 0
[13:30:37.678] <TB2> INFO: buffer corruption: 0
[13:30:37.850] <TB2> INFO: ######################################################################
[13:30:37.850] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:30:37.850] <TB2> INFO: ######################################################################
[13:30:37.860] <TB2> INFO: scanning low vcal = 10
[13:30:38.169] <TB2> INFO: Expecting 41600 events.
[13:30:41.977] <TB2> INFO: 41600 events read in total (3093ms).
[13:30:41.977] <TB2> INFO: Test took 4118ms.
[13:30:41.980] <TB2> INFO: scanning low vcal = 20
[13:30:42.488] <TB2> INFO: Expecting 41600 events.
[13:30:46.373] <TB2> INFO: 41600 events read in total (3170ms).
[13:30:46.373] <TB2> INFO: Test took 4393ms.
[13:30:46.376] <TB2> INFO: scanning low vcal = 30
[13:30:46.884] <TB2> INFO: Expecting 41600 events.
[13:30:50.762] <TB2> INFO: 41600 events read in total (3163ms).
[13:30:50.763] <TB2> INFO: Test took 4387ms.
[13:30:50.765] <TB2> INFO: scanning low vcal = 40
[13:30:51.264] <TB2> INFO: Expecting 41600 events.
[13:30:55.667] <TB2> INFO: 41600 events read in total (3687ms).
[13:30:55.668] <TB2> INFO: Test took 4903ms.
[13:30:55.672] <TB2> INFO: scanning low vcal = 50
[13:30:56.112] <TB2> INFO: Expecting 41600 events.
[13:31:00.595] <TB2> INFO: 41600 events read in total (3767ms).
[13:31:00.596] <TB2> INFO: Test took 4924ms.
[13:31:00.599] <TB2> INFO: scanning low vcal = 60
[13:31:01.032] <TB2> INFO: Expecting 41600 events.
[13:31:05.509] <TB2> INFO: 41600 events read in total (3762ms).
[13:31:05.509] <TB2> INFO: Test took 4910ms.
[13:31:05.513] <TB2> INFO: scanning low vcal = 70
[13:31:05.957] <TB2> INFO: Expecting 41600 events.
[13:31:10.580] <TB2> INFO: 41600 events read in total (3908ms).
[13:31:10.580] <TB2> INFO: Test took 5067ms.
[13:31:10.584] <TB2> INFO: scanning low vcal = 80
[13:31:11.031] <TB2> INFO: Expecting 41600 events.
[13:31:15.636] <TB2> INFO: 41600 events read in total (3890ms).
[13:31:15.636] <TB2> INFO: Test took 5052ms.
[13:31:15.640] <TB2> INFO: scanning low vcal = 90
[13:31:16.083] <TB2> INFO: Expecting 41600 events.
[13:31:20.753] <TB2> INFO: 41600 events read in total (3954ms).
[13:31:20.753] <TB2> INFO: Test took 5113ms.
[13:31:20.757] <TB2> INFO: scanning low vcal = 100
[13:31:21.208] <TB2> INFO: Expecting 41600 events.
[13:31:25.930] <TB2> INFO: 41600 events read in total (4007ms).
[13:31:25.930] <TB2> INFO: Test took 5173ms.
[13:31:25.933] <TB2> INFO: scanning low vcal = 110
[13:31:26.376] <TB2> INFO: Expecting 41600 events.
[13:31:30.982] <TB2> INFO: 41600 events read in total (3891ms).
[13:31:30.982] <TB2> INFO: Test took 5049ms.
[13:31:30.985] <TB2> INFO: scanning low vcal = 120
[13:31:31.404] <TB2> INFO: Expecting 41600 events.
[13:31:36.061] <TB2> INFO: 41600 events read in total (3941ms).
[13:31:36.061] <TB2> INFO: Test took 5076ms.
[13:31:36.064] <TB2> INFO: scanning low vcal = 130
[13:31:36.504] <TB2> INFO: Expecting 41600 events.
[13:31:41.127] <TB2> INFO: 41600 events read in total (3908ms).
[13:31:41.128] <TB2> INFO: Test took 5063ms.
[13:31:41.131] <TB2> INFO: scanning low vcal = 140
[13:31:41.577] <TB2> INFO: Expecting 41600 events.
[13:31:46.212] <TB2> INFO: 41600 events read in total (3920ms).
[13:31:46.213] <TB2> INFO: Test took 5082ms.
[13:31:46.216] <TB2> INFO: scanning low vcal = 150
[13:31:46.662] <TB2> INFO: Expecting 41600 events.
[13:31:51.244] <TB2> INFO: 41600 events read in total (3867ms).
[13:31:51.245] <TB2> INFO: Test took 5029ms.
[13:31:51.248] <TB2> INFO: scanning low vcal = 160
[13:31:51.662] <TB2> INFO: Expecting 41600 events.
[13:31:56.218] <TB2> INFO: 41600 events read in total (3840ms).
[13:31:56.219] <TB2> INFO: Test took 4971ms.
[13:31:56.222] <TB2> INFO: scanning low vcal = 170
[13:31:56.668] <TB2> INFO: Expecting 41600 events.
[13:32:01.348] <TB2> INFO: 41600 events read in total (3965ms).
[13:32:01.349] <TB2> INFO: Test took 5127ms.
[13:32:01.354] <TB2> INFO: scanning low vcal = 180
[13:32:01.787] <TB2> INFO: Expecting 41600 events.
[13:32:06.518] <TB2> INFO: 41600 events read in total (4016ms).
[13:32:06.518] <TB2> INFO: Test took 5164ms.
[13:32:06.522] <TB2> INFO: scanning low vcal = 190
[13:32:06.944] <TB2> INFO: Expecting 41600 events.
[13:32:11.798] <TB2> INFO: 41600 events read in total (4139ms).
[13:32:11.798] <TB2> INFO: Test took 5276ms.
[13:32:11.801] <TB2> INFO: scanning low vcal = 200
[13:32:12.248] <TB2> INFO: Expecting 41600 events.
[13:32:16.779] <TB2> INFO: 41600 events read in total (3815ms).
[13:32:16.779] <TB2> INFO: Test took 4978ms.
[13:32:16.782] <TB2> INFO: scanning low vcal = 210
[13:32:17.231] <TB2> INFO: Expecting 41600 events.
[13:32:21.820] <TB2> INFO: 41600 events read in total (3874ms).
[13:32:21.821] <TB2> INFO: Test took 5039ms.
[13:32:21.824] <TB2> INFO: scanning low vcal = 220
[13:32:22.275] <TB2> INFO: Expecting 41600 events.
[13:32:26.850] <TB2> INFO: 41600 events read in total (3859ms).
[13:32:26.850] <TB2> INFO: Test took 5026ms.
[13:32:26.854] <TB2> INFO: scanning low vcal = 230
[13:32:27.283] <TB2> INFO: Expecting 41600 events.
[13:32:31.816] <TB2> INFO: 41600 events read in total (3818ms).
[13:32:31.816] <TB2> INFO: Test took 4962ms.
[13:32:31.819] <TB2> INFO: scanning low vcal = 240
[13:32:32.266] <TB2> INFO: Expecting 41600 events.
[13:32:36.909] <TB2> INFO: 41600 events read in total (3928ms).
[13:32:36.909] <TB2> INFO: Test took 5090ms.
[13:32:36.913] <TB2> INFO: scanning low vcal = 250
[13:32:37.354] <TB2> INFO: Expecting 41600 events.
[13:32:42.050] <TB2> INFO: 41600 events read in total (3980ms).
[13:32:42.051] <TB2> INFO: Test took 5138ms.
[13:32:42.056] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[13:32:42.500] <TB2> INFO: Expecting 41600 events.
[13:32:47.078] <TB2> INFO: 41600 events read in total (3862ms).
[13:32:47.079] <TB2> INFO: Test took 5023ms.
[13:32:47.082] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[13:32:47.527] <TB2> INFO: Expecting 41600 events.
[13:32:52.193] <TB2> INFO: 41600 events read in total (3950ms).
[13:32:52.193] <TB2> INFO: Test took 5111ms.
[13:32:52.197] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[13:32:52.646] <TB2> INFO: Expecting 41600 events.
[13:32:57.277] <TB2> INFO: 41600 events read in total (3916ms).
[13:32:57.277] <TB2> INFO: Test took 5080ms.
[13:32:57.296] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[13:32:57.709] <TB2> INFO: Expecting 41600 events.
[13:33:02.392] <TB2> INFO: 41600 events read in total (3968ms).
[13:33:02.392] <TB2> INFO: Test took 5096ms.
[13:33:02.396] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:33:02.845] <TB2> INFO: Expecting 41600 events.
[13:33:07.471] <TB2> INFO: 41600 events read in total (3911ms).
[13:33:07.471] <TB2> INFO: Test took 5075ms.
[13:33:08.010] <TB2> INFO: PixTestGainPedestal::measure() done
[13:33:40.615] <TB2> INFO: PixTestGainPedestal::fit() done
[13:33:40.615] <TB2> INFO: non-linearity mean: 0.954 0.950 0.964 0.951 0.956 0.951 0.957 0.958 0.952 0.958 0.957 0.963 0.953 0.962 0.958 0.960
[13:33:40.615] <TB2> INFO: non-linearity RMS: 0.008 0.009 0.007 0.009 0.007 0.008 0.007 0.006 0.009 0.005 0.007 0.006 0.008 0.005 0.007 0.006
[13:33:40.615] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[13:33:40.632] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[13:33:40.650] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[13:33:40.667] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[13:33:40.685] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[13:33:40.702] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[13:33:40.720] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[13:33:40.737] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[13:33:40.754] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[13:33:40.771] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[13:33:40.789] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[13:33:40.806] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[13:33:40.823] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[13:33:40.840] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[13:33:40.857] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[13:33:40.874] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[13:33:40.891] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 183 seconds
[13:33:40.891] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:40.891] <TB2> INFO: Decoding statistics:
[13:33:40.891] <TB2> INFO: General information:
[13:33:40.891] <TB2> INFO: 16bit words read: 2329384
[13:33:40.891] <TB2> INFO: valid events total: 83200
[13:33:40.891] <TB2> INFO: empty events: 0
[13:33:40.891] <TB2> INFO: valid events with pixels: 83200
[13:33:40.891] <TB2> INFO: valid pixel hits: 665492
[13:33:40.891] <TB2> INFO: Event errors: 0
[13:33:40.891] <TB2> INFO: start marker: 0
[13:33:40.891] <TB2> INFO: stop marker: 0
[13:33:40.891] <TB2> INFO: overflow: 0
[13:33:40.891] <TB2> INFO: invalid 5bit words: 0
[13:33:40.891] <TB2> INFO: invalid XOR eye diagram: 0
[13:33:40.891] <TB2> INFO: TBM errors: 0
[13:33:40.891] <TB2> INFO: flawed TBM headers: 0
[13:33:40.891] <TB2> INFO: flawed TBM trailers: 0
[13:33:40.891] <TB2> INFO: event ID mismatches: 0
[13:33:40.891] <TB2> INFO: ROC errors: 0
[13:33:40.891] <TB2> INFO: missing ROC header(s): 0
[13:33:40.891] <TB2> INFO: misplaced readback start: 0
[13:33:40.891] <TB2> INFO: Pixel decoding errors: 0
[13:33:40.891] <TB2> INFO: pixel data incomplete: 0
[13:33:40.891] <TB2> INFO: pixel address: 0
[13:33:40.891] <TB2> INFO: pulse height fill bit: 0
[13:33:40.891] <TB2> INFO: buffer corruption: 0
[13:33:40.897] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C15.dat
[13:33:40.898] <TB2> INFO: ######################################################################
[13:33:40.898] <TB2> INFO: PixTestReadback::doTest()
[13:33:40.898] <TB2> INFO: ######################################################################
[13:33:40.900] <TB2> INFO: PixTestReadback::RES sent once
[13:33:52.233] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C0.dat
[13:33:52.233] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C1.dat
[13:33:52.233] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C2.dat
[13:33:52.233] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C3.dat
[13:33:52.233] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C4.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C5.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C6.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C7.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C8.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C9.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C10.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C11.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C12.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C13.dat
[13:33:52.234] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C14.dat
[13:33:52.235] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C15.dat
[13:33:52.282] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:33:52.282] <TB2> INFO: PixTestReadback::RES sent once
[13:34:03.557] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C0.dat
[13:34:03.558] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C1.dat
[13:34:03.558] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C2.dat
[13:34:03.558] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C3.dat
[13:34:03.558] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C4.dat
[13:34:03.558] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C5.dat
[13:34:03.558] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C6.dat
[13:34:03.558] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C7.dat
[13:34:03.558] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C8.dat
[13:34:03.559] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C9.dat
[13:34:03.559] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C10.dat
[13:34:03.559] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C11.dat
[13:34:03.559] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C12.dat
[13:34:03.559] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C13.dat
[13:34:03.559] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C14.dat
[13:34:03.559] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C15.dat
[13:34:03.606] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:34:03.607] <TB2> INFO: PixTestReadback::RES sent once
[13:34:12.236] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:34:12.236] <TB2> INFO: Vbg will be calibrated using Vd calibration
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.8calibrated Vbg = 1.23931 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158calibrated Vbg = 1.25341 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.7calibrated Vbg = 1.24786 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.7calibrated Vbg = 1.25522 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.3calibrated Vbg = 1.25144 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 162calibrated Vbg = 1.2493 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 152.6calibrated Vbg = 1.25684 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 159.6calibrated Vbg = 1.25092 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 160.8calibrated Vbg = 1.25481 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 142.9calibrated Vbg = 1.25872 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 148.6calibrated Vbg = 1.24636 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 155.7calibrated Vbg = 1.25447 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.7calibrated Vbg = 1.24094 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 158.8calibrated Vbg = 1.2404 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.9calibrated Vbg = 1.2437 :::*/*/*/*/
[13:34:12.236] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 149.3calibrated Vbg = 1.24647 :::*/*/*/*/
[13:34:12.239] <TB2> INFO: PixTestReadback::RES sent once
[13:37:06.735] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C0.dat
[13:37:06.735] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C1.dat
[13:37:06.735] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C2.dat
[13:37:06.735] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C3.dat
[13:37:06.735] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C4.dat
[13:37:06.735] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C5.dat
[13:37:06.735] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C6.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C7.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C8.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C9.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C10.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C11.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C12.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C13.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C14.dat
[13:37:06.736] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//004_FulltestPxar_p17//readbackCal_C15.dat
[13:37:06.782] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:37:06.783] <TB2> INFO: PixTestReadback::doTest() done
[13:37:06.783] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:06.783] <TB2> INFO: Decoding statistics:
[13:37:06.783] <TB2> INFO: General information:
[13:37:06.783] <TB2> INFO: 16bit words read: 768
[13:37:06.783] <TB2> INFO: valid events total: 64
[13:37:06.783] <TB2> INFO: empty events: 64
[13:37:06.783] <TB2> INFO: valid events with pixels: 0
[13:37:06.783] <TB2> INFO: valid pixel hits: 0
[13:37:06.783] <TB2> INFO: Event errors: 0
[13:37:06.783] <TB2> INFO: start marker: 0
[13:37:06.783] <TB2> INFO: stop marker: 0
[13:37:06.783] <TB2> INFO: overflow: 0
[13:37:06.783] <TB2> INFO: invalid 5bit words: 0
[13:37:06.783] <TB2> INFO: invalid XOR eye diagram: 0
[13:37:06.783] <TB2> INFO: TBM errors: 0
[13:37:06.783] <TB2> INFO: flawed TBM headers: 0
[13:37:06.783] <TB2> INFO: flawed TBM trailers: 0
[13:37:06.783] <TB2> INFO: event ID mismatches: 0
[13:37:06.783] <TB2> INFO: ROC errors: 0
[13:37:06.783] <TB2> INFO: missing ROC header(s): 0
[13:37:06.783] <TB2> INFO: misplaced readback start: 0
[13:37:06.783] <TB2> INFO: Pixel decoding errors: 0
[13:37:06.783] <TB2> INFO: pixel data incomplete: 0
[13:37:06.783] <TB2> INFO: pixel address: 0
[13:37:06.783] <TB2> INFO: pulse height fill bit: 0
[13:37:06.783] <TB2> INFO: buffer corruption: 0
[13:37:06.797] <TB2> INFO: Decoding statistics:
[13:37:06.797] <TB2> INFO: General information:
[13:37:06.798] <TB2> INFO: 16bit words read: 2396588
[13:37:06.798] <TB2> INFO: valid events total: 88384
[13:37:06.798] <TB2> INFO: empty events: 2686
[13:37:06.798] <TB2> INFO: valid events with pixels: 85698
[13:37:06.798] <TB2> INFO: valid pixel hits: 667990
[13:37:06.798] <TB2> INFO: Event errors: 0
[13:37:06.798] <TB2> INFO: start marker: 0
[13:37:06.798] <TB2> INFO: stop marker: 0
[13:37:06.798] <TB2> INFO: overflow: 0
[13:37:06.798] <TB2> INFO: invalid 5bit words: 0
[13:37:06.798] <TB2> INFO: invalid XOR eye diagram: 0
[13:37:06.798] <TB2> INFO: TBM errors: 0
[13:37:06.798] <TB2> INFO: flawed TBM headers: 0
[13:37:06.798] <TB2> INFO: flawed TBM trailers: 0
[13:37:06.798] <TB2> INFO: event ID mismatches: 0
[13:37:06.798] <TB2> INFO: ROC errors: 0
[13:37:06.798] <TB2> INFO: missing ROC header(s): 0
[13:37:06.798] <TB2> INFO: misplaced readback start: 0
[13:37:06.798] <TB2> INFO: Pixel decoding errors: 0
[13:37:06.798] <TB2> INFO: pixel data incomplete: 0
[13:37:06.798] <TB2> INFO: pixel address: 0
[13:37:06.798] <TB2> INFO: pulse height fill bit: 0
[13:37:06.798] <TB2> INFO: buffer corruption: 0
[13:37:06.798] <TB2> INFO: enter test to run
[13:37:06.798] <TB2> INFO: test: exit no parameter change
[13:37:06.988] <TB2> QUIET: Connection to board 20 closed.
[13:37:07.067] <TB2> INFO: pXar: this is the end, my friend