[09:53:51.431] <TB0> INFO: *** Welcome to pxar ***
[09:53:51.431] <TB0> INFO: *** Today: 2015/11/24
[09:53:51.851] <TB0> INFO: *** Version: 9da6-dirty
[09:53:51.851] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C15.dat
[09:53:51.852] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//tbmParameters_C0b.dat
[09:53:51.852] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//defaultMaskFile.dat
[09:53:51.852] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters_C15.dat
[09:53:51.920] <TB0> INFO: clk: 4
[09:53:51.920] <TB0> INFO: ctr: 4
[09:53:51.920] <TB0> INFO: sda: 19
[09:53:51.920] <TB0> INFO: tin: 9
[09:53:51.920] <TB0> INFO: level: 15
[09:53:51.920] <TB0> INFO: triggerdelay: 0
[09:53:51.920] <TB0> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[09:53:51.920] <TB0> INFO: Log level: INFO
[09:53:51.934] <TB0> QUIET: Connection to board DTB_WZ4BYI opened.
[09:53:51.937] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 169
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WZ4BYI
MAC address: 40D8551180A9
Hostname: pixelDTB169
Comment:
------------------------------------------------------
[09:53:51.940] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[09:53:53.478] <TB0> INFO: DUT info:
[09:53:53.478] <TB0> INFO: The DUT currently contains the following objects:
[09:53:53.478] <TB0> INFO: 2 TBM Cores tbm08c (2 ON)
[09:53:53.478] <TB0> INFO: TBM Core alpha (0): 7 registers set
[09:53:53.478] <TB0> INFO: TBM Core beta (1): 7 registers set
[09:53:53.478] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:53:53.478] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.478] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:53:53.879] <TB0> INFO: enter 'restricted' command line mode
[09:53:53.879] <TB0> INFO: enter test to run
[09:53:53.879] <TB0> INFO: test: pretest no parameter change
[09:53:53.879] <TB0> INFO: running: pretest
[09:53:53.883] <TB0> INFO: ######################################################################
[09:53:53.883] <TB0> INFO: PixTestPretest::doTest()
[09:53:53.883] <TB0> INFO: ######################################################################
[09:53:53.885] <TB0> INFO: ----------------------------------------------------------------------
[09:53:53.885] <TB0> INFO: PixTestPretest::programROC()
[09:53:53.885] <TB0> INFO: ----------------------------------------------------------------------
[09:54:11.905] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:54:11.905] <TB0> INFO: IA differences per ROC: 16.1 18.5 17.7 16.9 18.5 16.1 16.9 16.1 17.7 18.5 17.7 18.5 16.9 17.7 17.7 16.9
[09:54:11.986] <TB0> INFO: ----------------------------------------------------------------------
[09:54:11.986] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:54:11.986] <TB0> INFO: ----------------------------------------------------------------------
[09:54:18.598] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 402 mA = 25.125 mA/ROC
[09:54:18.598] <TB0> INFO: i(loss) [mA/ROC]: 21.8 20.9 20.9 20.9 20.9 20.1 20.1 20.9 20.1 20.1 20.1 20.9 20.1 20.1 20.9 20.1
[09:54:18.644] <TB0> INFO: ----------------------------------------------------------------------
[09:54:18.644] <TB0> INFO: PixTestPretest::findTiming()
[09:54:18.644] <TB0> INFO: ----------------------------------------------------------------------
[09:54:18.644] <TB0> INFO: PixTestCmd::init()
[09:54:19.458] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[09:55:56.365] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:55:56.365] <TB0> INFO: (success/tries = 100/100), width = 4
[09:55:56.367] <TB0> INFO: ----------------------------------------------------------------------
[09:55:56.367] <TB0> INFO: PixTestPretest::findWorkingPixel()
[09:55:56.367] <TB0> INFO: ----------------------------------------------------------------------
[09:55:56.505] <TB0> INFO: Expecting 231680 events.
[09:56:01.115] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[09:56:01.118] <TB0> ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[09:56:03.720] <TB0> INFO: 231680 events read in total (6499ms).
[09:56:03.724] <TB0> INFO: Test took 7354ms.
[09:56:04.128] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:56:04.175] <TB0> INFO: ----------------------------------------------------------------------
[09:56:04.175] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[09:56:04.175] <TB0> INFO: ----------------------------------------------------------------------
[09:56:04.312] <TB0> INFO: Expecting 231680 events.
[09:56:12.460] <TB0> INFO: 231680 events read in total (7433ms).
[09:56:12.463] <TB0> INFO: Test took 8283ms.
[09:56:12.891] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[09:56:12.891] <TB0> INFO: CalDel: 148 130 142 119 136 158 120 142 137 133 133 128 133 146 160 124
[09:56:12.891] <TB0> INFO: VthrComp: 51 51 51 51 51 51 52 51 51 51 51 51 51 51 51 51
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C0.dat
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C1.dat
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C2.dat
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C3.dat
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C4.dat
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C5.dat
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C6.dat
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C7.dat
[09:56:12.895] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C8.dat
[09:56:12.896] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C9.dat
[09:56:12.896] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C10.dat
[09:56:12.896] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C11.dat
[09:56:12.896] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C12.dat
[09:56:12.896] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C13.dat
[09:56:12.896] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C14.dat
[09:56:12.896] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters_C15.dat
[09:56:12.896] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//tbmParameters_C0a.dat
[09:56:12.896] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//tbmParameters_C0b.dat
[09:56:12.896] <TB0> INFO: PixTestPretest::doTest() done, duration: 139 seconds
[09:56:12.958] <TB0> INFO: enter test to run
[09:56:12.958] <TB0> INFO: test: fulltest no parameter change
[09:56:12.958] <TB0> INFO: running: fulltest
[09:56:12.958] <TB0> INFO: ######################################################################
[09:56:12.958] <TB0> INFO: PixTestFullTest::doTest()
[09:56:12.958] <TB0> INFO: ######################################################################
[09:56:12.960] <TB0> INFO: ######################################################################
[09:56:12.960] <TB0> INFO: PixTestAlive::doTest()
[09:56:12.960] <TB0> INFO: ######################################################################
[09:56:12.962] <TB0> INFO: ----------------------------------------------------------------------
[09:56:12.962] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:56:12.962] <TB0> INFO: ----------------------------------------------------------------------
[09:56:13.261] <TB0> INFO: Expecting 41600 events.
[09:56:17.233] <TB0> INFO: 41600 events read in total (3257ms).
[09:56:17.233] <TB0> INFO: Test took 4269ms.
[09:56:17.239] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:17.642] <TB0> INFO: PixTestAlive::aliveTest() done
[09:56:17.642] <TB0> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[09:56:17.644] <TB0> INFO: ----------------------------------------------------------------------
[09:56:17.644] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:56:17.644] <TB0> INFO: ----------------------------------------------------------------------
[09:56:17.946] <TB0> INFO: Expecting 41600 events.
[09:56:20.860] <TB0> INFO: 41600 events read in total (2198ms).
[09:56:20.860] <TB0> INFO: Test took 3214ms.
[09:56:20.860] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:20.861] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:56:21.283] <TB0> INFO: PixTestAlive::maskTest() done
[09:56:21.283] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:56:21.284] <TB0> INFO: ----------------------------------------------------------------------
[09:56:21.284] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:56:21.284] <TB0> INFO: ----------------------------------------------------------------------
[09:56:21.587] <TB0> INFO: Expecting 41600 events.
[09:56:25.584] <TB0> INFO: 41600 events read in total (3282ms).
[09:56:25.584] <TB0> INFO: Test took 4298ms.
[09:56:25.590] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:25.993] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[09:56:25.993] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:56:25.993] <TB0> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:56:25.993] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:25.993] <TB0> INFO: Decoding statistics:
[09:56:25.993] <TB0> INFO: General information:
[09:56:25.993] <TB0> INFO: 16bit words read: 0
[09:56:25.993] <TB0> INFO: valid events total: 0
[09:56:25.993] <TB0> INFO: empty events: 0
[09:56:25.993] <TB0> INFO: valid events with pixels: 0
[09:56:25.993] <TB0> INFO: valid pixel hits: 0
[09:56:25.993] <TB0> INFO: Event errors: 0
[09:56:25.993] <TB0> INFO: start marker: 0
[09:56:25.993] <TB0> INFO: stop marker: 0
[09:56:25.993] <TB0> INFO: overflow: 0
[09:56:25.993] <TB0> INFO: invalid 5bit words: 0
[09:56:25.993] <TB0> INFO: invalid XOR eye diagram: 0
[09:56:25.993] <TB0> INFO: TBM errors: 0
[09:56:25.994] <TB0> INFO: flawed TBM headers: 0
[09:56:25.994] <TB0> INFO: flawed TBM trailers: 0
[09:56:25.994] <TB0> INFO: event ID mismatches: 0
[09:56:25.994] <TB0> INFO: ROC errors: 0
[09:56:25.994] <TB0> INFO: missing ROC header(s): 0
[09:56:25.994] <TB0> INFO: misplaced readback start: 0
[09:56:25.994] <TB0> INFO: Pixel decoding errors: 0
[09:56:25.994] <TB0> INFO: pixel data incomplete: 0
[09:56:25.994] <TB0> INFO: pixel address: 0
[09:56:25.994] <TB0> INFO: pulse height fill bit: 0
[09:56:25.994] <TB0> INFO: buffer corruption: 0
[09:56:26.000] <TB0> INFO: ######################################################################
[09:56:26.000] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:56:26.000] <TB0> INFO: ######################################################################
[09:56:26.005] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:56:26.015] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:56:26.015] <TB0> INFO: run 1 of 1
[09:56:26.315] <TB0> INFO: Expecting 3120000 events.
[09:57:09.762] <TB0> INFO: 1063450 events read in total (42731ms).
[09:57:51.453] <TB0> INFO: 2097215 events read in total (84423ms).
[09:58:32.247] <TB0> INFO: 3120000 events read in total (125217ms).
[09:58:32.298] <TB0> INFO: Test took 126284ms.
[09:58:32.395] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:54.624] <TB0> INFO: PixTestBBMap::doTest() done, duration: 148 seconds
[09:58:54.625] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[09:58:54.625] <TB0> INFO: separation cut (per ROC): 118 108 107 118 107 91 111 118 105 123 105 115 100 101 104 116
[09:58:54.625] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:54.625] <TB0> INFO: Decoding statistics:
[09:58:54.625] <TB0> INFO: General information:
[09:58:54.625] <TB0> INFO: 16bit words read: 0
[09:58:54.625] <TB0> INFO: valid events total: 0
[09:58:54.625] <TB0> INFO: empty events: 0
[09:58:54.625] <TB0> INFO: valid events with pixels: 0
[09:58:54.625] <TB0> INFO: valid pixel hits: 0
[09:58:54.625] <TB0> INFO: Event errors: 0
[09:58:54.625] <TB0> INFO: start marker: 0
[09:58:54.625] <TB0> INFO: stop marker: 0
[09:58:54.625] <TB0> INFO: overflow: 0
[09:58:54.625] <TB0> INFO: invalid 5bit words: 0
[09:58:54.625] <TB0> INFO: invalid XOR eye diagram: 0
[09:58:54.625] <TB0> INFO: TBM errors: 0
[09:58:54.625] <TB0> INFO: flawed TBM headers: 0
[09:58:54.625] <TB0> INFO: flawed TBM trailers: 0
[09:58:54.625] <TB0> INFO: event ID mismatches: 0
[09:58:54.625] <TB0> INFO: ROC errors: 0
[09:58:54.625] <TB0> INFO: missing ROC header(s): 0
[09:58:54.625] <TB0> INFO: misplaced readback start: 0
[09:58:54.625] <TB0> INFO: Pixel decoding errors: 0
[09:58:54.625] <TB0> INFO: pixel data incomplete: 0
[09:58:54.625] <TB0> INFO: pixel address: 0
[09:58:54.625] <TB0> INFO: pulse height fill bit: 0
[09:58:54.625] <TB0> INFO: buffer corruption: 0
[09:58:54.694] <TB0> INFO: ######################################################################
[09:58:54.694] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:58:54.694] <TB0> INFO: ######################################################################
[09:58:54.694] <TB0> INFO: ----------------------------------------------------------------------
[09:58:54.694] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:58:54.694] <TB0> INFO: ----------------------------------------------------------------------
[09:58:54.694] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:58:54.702] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:58:54.702] <TB0> INFO: run 1 of 1
[09:58:55.002] <TB0> INFO: Expecting 26208000 events.
[09:59:23.828] <TB0> INFO: 1166100 events read in total (28111ms).
[09:59:51.768] <TB0> INFO: 2303400 events read in total (56051ms).
[10:00:19.653] <TB0> INFO: 3438000 events read in total (83936ms).
[10:00:47.683] <TB0> INFO: 4566550 events read in total (111966ms).
[10:01:15.673] <TB0> INFO: 5691550 events read in total (139956ms).
[10:01:43.405] <TB0> INFO: 6815100 events read in total (167688ms).
[10:02:11.247] <TB0> INFO: 7935800 events read in total (195530ms).
[10:02:39.050] <TB0> INFO: 9057550 events read in total (223333ms).
[10:03:06.819] <TB0> INFO: 10179050 events read in total (251102ms).
[10:03:34.650] <TB0> INFO: 11298700 events read in total (278933ms).
[10:04:02.291] <TB0> INFO: 12414150 events read in total (306574ms).
[10:04:29.913] <TB0> INFO: 13518650 events read in total (334196ms).
[10:04:57.556] <TB0> INFO: 14616250 events read in total (361839ms).
[10:05:25.290] <TB0> INFO: 15711650 events read in total (389573ms).
[10:05:52.868] <TB0> INFO: 16800300 events read in total (417151ms).
[10:06:20.403] <TB0> INFO: 17890650 events read in total (444686ms).
[10:06:47.934] <TB0> INFO: 18979900 events read in total (472217ms).
[10:07:15.378] <TB0> INFO: 20063650 events read in total (499661ms).
[10:07:42.688] <TB0> INFO: 21147350 events read in total (526971ms).
[10:08:10.126] <TB0> INFO: 22231250 events read in total (554409ms).
[10:08:37.523] <TB0> INFO: 23317900 events read in total (581806ms).
[10:09:05.063] <TB0> INFO: 24399550 events read in total (609346ms).
[10:09:32.467] <TB0> INFO: 25483050 events read in total (636750ms).
[10:09:50.496] <TB0> INFO: 26208000 events read in total (654779ms).
[10:09:50.524] <TB0> INFO: Test took 655822ms.
[10:09:50.606] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:50.745] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:09:52.093] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:09:53.423] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:09:54.764] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:09:56.090] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:09:57.432] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:09:58.780] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:00.104] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:01.443] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:02.788] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:04.111] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:05.461] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:06.765] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:08.131] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:09.480] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:10.820] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:10:12.184] <TB0> INFO: PixTestScurves::scurves() done
[10:10:12.184] <TB0> INFO: Vcal mean: 90.07 97.52 90.95 98.35 91.85 84.90 102.70 91.77 88.52 102.84 91.11 112.55 86.58 88.66 96.18 105.99
[10:10:12.184] <TB0> INFO: Vcal RMS: 6.83 5.97 5.47 6.69 6.08 4.78 6.23 6.01 5.56 6.05 5.62 5.87 5.16 5.27 5.30 5.46
[10:10:12.184] <TB0> INFO: PixTestScurves::fullTest() done, duration: 677 seconds
[10:10:12.184] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:12.184] <TB0> INFO: Decoding statistics:
[10:10:12.184] <TB0> INFO: General information:
[10:10:12.184] <TB0> INFO: 16bit words read: 0
[10:10:12.184] <TB0> INFO: valid events total: 0
[10:10:12.184] <TB0> INFO: empty events: 0
[10:10:12.184] <TB0> INFO: valid events with pixels: 0
[10:10:12.184] <TB0> INFO: valid pixel hits: 0
[10:10:12.184] <TB0> INFO: Event errors: 0
[10:10:12.184] <TB0> INFO: start marker: 0
[10:10:12.184] <TB0> INFO: stop marker: 0
[10:10:12.184] <TB0> INFO: overflow: 0
[10:10:12.184] <TB0> INFO: invalid 5bit words: 0
[10:10:12.184] <TB0> INFO: invalid XOR eye diagram: 0
[10:10:12.184] <TB0> INFO: TBM errors: 0
[10:10:12.184] <TB0> INFO: flawed TBM headers: 0
[10:10:12.185] <TB0> INFO: flawed TBM trailers: 0
[10:10:12.185] <TB0> INFO: event ID mismatches: 0
[10:10:12.185] <TB0> INFO: ROC errors: 0
[10:10:12.185] <TB0> INFO: missing ROC header(s): 0
[10:10:12.185] <TB0> INFO: misplaced readback start: 0
[10:10:12.185] <TB0> INFO: Pixel decoding errors: 0
[10:10:12.185] <TB0> INFO: pixel data incomplete: 0
[10:10:12.185] <TB0> INFO: pixel address: 0
[10:10:12.185] <TB0> INFO: pulse height fill bit: 0
[10:10:12.185] <TB0> INFO: buffer corruption: 0
[10:10:12.254] <TB0> INFO: ######################################################################
[10:10:12.254] <TB0> INFO: PixTestTrim::doTest()
[10:10:12.254] <TB0> INFO: ######################################################################
[10:10:12.255] <TB0> INFO: ----------------------------------------------------------------------
[10:10:12.255] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:10:12.255] <TB0> INFO: ----------------------------------------------------------------------
[10:10:12.339] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:10:12.339] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:10:12.347] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:10:12.347] <TB0> INFO: run 1 of 1
[10:10:12.649] <TB0> INFO: Expecting 6281600 events.
[10:10:52.630] <TB0> INFO: 1390100 events read in total (39265ms).
[10:11:31.459] <TB0> INFO: 2768810 events read in total (78094ms).
[10:12:10.294] <TB0> INFO: 4149670 events read in total (116929ms).
[10:12:49.594] <TB0> INFO: 5542780 events read in total (156229ms).
[10:13:10.457] <TB0> INFO: 6281600 events read in total (177092ms).
[10:13:10.488] <TB0> INFO: Test took 178141ms.
[10:13:10.538] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:29.072] <TB0> INFO: ROC 0 VthrComp = 94
[10:13:29.072] <TB0> INFO: ROC 1 VthrComp = 100
[10:13:29.072] <TB0> INFO: ROC 2 VthrComp = 98
[10:13:29.073] <TB0> INFO: ROC 3 VthrComp = 100
[10:13:29.073] <TB0> INFO: ROC 4 VthrComp = 98
[10:13:29.073] <TB0> INFO: ROC 5 VthrComp = 92
[10:13:29.073] <TB0> INFO: ROC 6 VthrComp = 103
[10:13:29.073] <TB0> INFO: ROC 7 VthrComp = 91
[10:13:29.073] <TB0> INFO: ROC 8 VthrComp = 96
[10:13:29.073] <TB0> INFO: ROC 9 VthrComp = 105
[10:13:29.073] <TB0> INFO: ROC 10 VthrComp = 97
[10:13:29.074] <TB0> INFO: ROC 11 VthrComp = 107
[10:13:29.074] <TB0> INFO: ROC 12 VthrComp = 92
[10:13:29.074] <TB0> INFO: ROC 13 VthrComp = 96
[10:13:29.074] <TB0> INFO: ROC 14 VthrComp = 101
[10:13:29.074] <TB0> INFO: ROC 15 VthrComp = 102
[10:13:29.074] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:13:29.074] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:13:29.082] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:13:29.082] <TB0> INFO: run 1 of 1
[10:13:29.383] <TB0> INFO: Expecting 6281600 events.
[10:14:01.612] <TB0> INFO: 895690 events read in total (31514ms).
[10:14:33.500] <TB0> INFO: 1786920 events read in total (63402ms).
[10:15:05.108] <TB0> INFO: 2678540 events read in total (95010ms).
[10:15:36.750] <TB0> INFO: 3564400 events read in total (126652ms).
[10:16:08.192] <TB0> INFO: 4442170 events read in total (158094ms).
[10:16:39.525] <TB0> INFO: 5314570 events read in total (189427ms).
[10:17:10.782] <TB0> INFO: 6187280 events read in total (220684ms).
[10:17:14.501] <TB0> INFO: 6281600 events read in total (224403ms).
[10:17:14.554] <TB0> INFO: Test took 225473ms.
[10:17:14.693] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:36.840] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 63.4201 for pixel 6/5 mean/min/max = 47.3332/31.1478/63.5186
[10:17:36.840] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 60.9271 for pixel 7/59 mean/min/max = 46.3044/31.474/61.1348
[10:17:36.840] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 57.9752 for pixel 7/76 mean/min/max = 44.5436/30.8746/58.2126
[10:17:36.841] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 64.3541 for pixel 6/0 mean/min/max = 47.7225/31.0574/64.3876
[10:17:36.841] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 60.0945 for pixel 2/2 mean/min/max = 46.0473/31.8962/60.1984
[10:17:36.841] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 56.7726 for pixel 15/51 mean/min/max = 44.66/32.4589/56.861
[10:17:36.841] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 62.7357 for pixel 4/0 mean/min/max = 47.1741/31.6001/62.748
[10:17:36.842] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 62.362 for pixel 0/67 mean/min/max = 47.017/31.585/62.449
[10:17:36.842] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 57.9247 for pixel 2/79 mean/min/max = 44.791/31.6286/57.9535
[10:17:36.842] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 63.6658 for pixel 23/29 mean/min/max = 48.1431/32.5136/63.7726
[10:17:36.842] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 58.7021 for pixel 10/79 mean/min/max = 45.2834/31.8143/58.7526
[10:17:36.843] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 67.7633 for pixel 5/16 mean/min/max = 51.5476/34.8363/68.259
[10:17:36.843] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.0554 for pixel 20/6 mean/min/max = 45.7397/32.4175/59.062
[10:17:36.843] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.4017 for pixel 10/79 mean/min/max = 44.6532/31.7242/57.5822
[10:17:36.843] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.2527 for pixel 1/8 mean/min/max = 45.8676/32.2017/59.5335
[10:17:36.844] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 62.3023 for pixel 12/6 mean/min/max = 47.8755/33.3948/62.3561
[10:17:36.844] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:17:36.976] <TB0> INFO: Expecting 514560 events.
[10:17:46.011] <TB0> INFO: 514560 events read in total (8315ms).
[10:17:46.016] <TB0> INFO: Expecting 514560 events.
[10:17:54.939] <TB0> INFO: 514560 events read in total (8248ms).
[10:17:54.946] <TB0> INFO: Expecting 514560 events.
[10:18:03.856] <TB0> INFO: 514560 events read in total (8238ms).
[10:18:03.865] <TB0> INFO: Expecting 514560 events.
[10:18:12.866] <TB0> INFO: 514560 events read in total (8334ms).
[10:18:12.877] <TB0> INFO: Expecting 514560 events.
[10:18:21.799] <TB0> INFO: 514560 events read in total (8256ms).
[10:18:21.811] <TB0> INFO: Expecting 514560 events.
[10:18:30.750] <TB0> INFO: 514560 events read in total (8274ms).
[10:18:30.764] <TB0> INFO: Expecting 514560 events.
[10:18:39.661] <TB0> INFO: 514560 events read in total (8230ms).
[10:18:39.678] <TB0> INFO: Expecting 514560 events.
[10:18:48.660] <TB0> INFO: 514560 events read in total (8319ms).
[10:18:48.677] <TB0> INFO: Expecting 514560 events.
[10:18:57.781] <TB0> INFO: 514560 events read in total (8438ms).
[10:18:57.801] <TB0> INFO: Expecting 514560 events.
[10:19:06.789] <TB0> INFO: 514560 events read in total (8325ms).
[10:19:06.812] <TB0> INFO: Expecting 514560 events.
[10:19:15.709] <TB0> INFO: 514560 events read in total (8246ms).
[10:19:15.734] <TB0> INFO: Expecting 514560 events.
[10:19:24.643] <TB0> INFO: 514560 events read in total (8253ms).
[10:19:24.669] <TB0> INFO: Expecting 514560 events.
[10:19:33.642] <TB0> INFO: 514560 events read in total (8321ms).
[10:19:33.670] <TB0> INFO: Expecting 514560 events.
[10:19:42.720] <TB0> INFO: 514560 events read in total (8402ms).
[10:19:42.750] <TB0> INFO: Expecting 514560 events.
[10:19:51.743] <TB0> INFO: 514560 events read in total (8342ms).
[10:19:51.775] <TB0> INFO: Expecting 514560 events.
[10:20:00.747] <TB0> INFO: 514560 events read in total (8326ms).
[10:20:00.780] <TB0> INFO: Test took 143936ms.
[10:20:01.887] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:20:01.894] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:20:01.894] <TB0> INFO: run 1 of 1
[10:20:02.196] <TB0> INFO: Expecting 6281600 events.
[10:20:34.380] <TB0> INFO: 874870 events read in total (31469ms).
[10:21:05.647] <TB0> INFO: 1744550 events read in total (62736ms).
[10:21:36.953] <TB0> INFO: 2614130 events read in total (94042ms).
[10:22:08.158] <TB0> INFO: 3478530 events read in total (125247ms).
[10:22:39.230] <TB0> INFO: 4333310 events read in total (156319ms).
[10:23:10.160] <TB0> INFO: 5184620 events read in total (187249ms).
[10:23:41.087] <TB0> INFO: 6033780 events read in total (218177ms).
[10:23:50.367] <TB0> INFO: 6281600 events read in total (227456ms).
[10:23:50.427] <TB0> INFO: Test took 228533ms.
[10:23:50.566] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:12.423] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.094748 .. 255.000000
[10:24:12.499] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:24:12.507] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:24:12.507] <TB0> INFO: run 1 of 1
[10:24:12.806] <TB0> INFO: Expecting 10649600 events.
[10:24:43.922] <TB0> INFO: 825990 events read in total (30398ms).
[10:25:14.424] <TB0> INFO: 1652370 events read in total (60900ms).
[10:25:44.778] <TB0> INFO: 2479240 events read in total (91254ms).
[10:26:15.239] <TB0> INFO: 3306380 events read in total (121716ms).
[10:26:45.953] <TB0> INFO: 4133420 events read in total (152429ms).
[10:27:16.676] <TB0> INFO: 4960230 events read in total (183153ms).
[10:27:47.173] <TB0> INFO: 5786980 events read in total (213649ms).
[10:28:17.497] <TB0> INFO: 6612500 events read in total (243973ms).
[10:28:48.110] <TB0> INFO: 7438130 events read in total (274586ms).
[10:29:18.477] <TB0> INFO: 8262410 events read in total (304953ms).
[10:29:49.038] <TB0> INFO: 9085310 events read in total (335514ms).
[10:30:19.421] <TB0> INFO: 9908670 events read in total (365897ms).
[10:30:46.817] <TB0> INFO: 10649600 events read in total (393293ms).
[10:30:46.910] <TB0> INFO: Test took 394404ms.
[10:30:47.179] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:13.873] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 15.175288 .. 46.015615
[10:31:13.952] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 56 (-1/-1) hits flags = 528 (plus default)
[10:31:13.959] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:31:13.959] <TB0> INFO: run 1 of 1
[10:31:14.262] <TB0> INFO: Expecting 2163200 events.
[10:31:50.915] <TB0> INFO: 1153440 events read in total (35937ms).
[10:32:22.348] <TB0> INFO: 2163200 events read in total (67370ms).
[10:32:22.361] <TB0> INFO: Test took 68402ms.
[10:32:22.391] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:34.996] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 1.461449 .. 43.487323
[10:32:35.075] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:32:35.083] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:32:35.083] <TB0> INFO: run 1 of 1
[10:32:35.384] <TB0> INFO: Expecting 2204800 events.
[10:33:13.296] <TB0> INFO: 1221460 events read in total (37196ms).
[10:33:43.298] <TB0> INFO: 2204800 events read in total (67198ms).
[10:33:43.313] <TB0> INFO: Test took 68231ms.
[10:33:43.343] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:55.721] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 1.461449 .. 42.614540
[10:33:55.799] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 52 (-1/-1) hits flags = 528 (plus default)
[10:33:55.807] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:33:55.807] <TB0> INFO: run 1 of 1
[10:33:56.108] <TB0> INFO: Expecting 2163200 events.
[10:34:34.042] <TB0> INFO: 1237050 events read in total (37219ms).
[10:35:02.079] <TB0> INFO: 2163200 events read in total (65256ms).
[10:35:02.089] <TB0> INFO: Test took 66282ms.
[10:35:02.115] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:14.250] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:35:14.250] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:35:14.258] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:35:14.258] <TB0> INFO: run 1 of 1
[10:35:14.559] <TB0> INFO: Expecting 1705600 events.
[10:35:50.266] <TB0> INFO: 1075330 events read in total (34992ms).
[10:36:11.006] <TB0> INFO: 1705600 events read in total (55732ms).
[10:36:11.016] <TB0> INFO: Test took 56758ms.
[10:36:11.045] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:23.626] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C0.dat
[10:36:23.626] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C1.dat
[10:36:23.626] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C2.dat
[10:36:23.626] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C3.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C4.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C5.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C6.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C7.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C8.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C9.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C10.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C11.dat
[10:36:23.627] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C12.dat
[10:36:23.628] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C13.dat
[10:36:23.628] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C14.dat
[10:36:23.628] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C15.dat
[10:36:23.628] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C0.dat
[10:36:23.634] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C1.dat
[10:36:23.639] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C2.dat
[10:36:23.645] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C3.dat
[10:36:23.651] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C4.dat
[10:36:23.656] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C5.dat
[10:36:23.662] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C6.dat
[10:36:23.668] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C7.dat
[10:36:23.673] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C8.dat
[10:36:23.679] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C9.dat
[10:36:23.685] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C10.dat
[10:36:23.690] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C11.dat
[10:36:23.696] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C12.dat
[10:36:23.702] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C13.dat
[10:36:23.707] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C14.dat
[10:36:23.713] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//trimParameters35_C15.dat
[10:36:23.719] <TB0> INFO: PixTestTrim::trimTest() done
[10:36:23.719] <TB0> INFO: vtrim: 111 109 111 126 115 94 110 96 92 125 104 129 111 96 106 104
[10:36:23.719] <TB0> INFO: vthrcomp: 94 100 98 100 98 92 103 91 96 105 97 107 92 96 101 102
[10:36:23.719] <TB0> INFO: vcal mean: 34.97 34.96 34.92 34.94 34.96 34.96 34.96 35.00 34.96 35.00 34.97 34.96 34.97 34.96 35.00 35.00
[10:36:23.719] <TB0> INFO: vcal RMS: 1.04 0.91 0.87 0.96 0.88 0.86 0.99 0.92 1.01 1.03 0.88 0.96 0.88 0.88 0.87 0.92
[10:36:23.719] <TB0> INFO: bits mean: 8.99 9.21 10.05 9.36 9.49 9.71 9.40 8.90 9.41 8.91 9.51 7.95 9.62 9.60 9.10 8.58
[10:36:23.719] <TB0> INFO: bits RMS: 2.88 2.84 2.62 2.73 2.70 2.60 2.63 2.99 2.83 2.66 2.77 2.50 2.59 2.77 2.81 2.74
[10:36:23.728] <TB0> INFO: ----------------------------------------------------------------------
[10:36:23.728] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[10:36:23.728] <TB0> INFO: ----------------------------------------------------------------------
[10:36:23.732] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:36:23.739] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:36:23.739] <TB0> INFO: run 1 of 1
[10:36:24.039] <TB0> INFO: Expecting 8320000 events.
[10:37:00.541] <TB0> INFO: 1183330 events read in total (35786ms).
[10:37:36.049] <TB0> INFO: 2349440 events read in total (71294ms).
[10:38:11.550] <TB0> INFO: 3512140 events read in total (106795ms).
[10:38:46.954] <TB0> INFO: 4665230 events read in total (142199ms).
[10:39:22.098] <TB0> INFO: 5807960 events read in total (177343ms).
[10:39:57.287] <TB0> INFO: 6947500 events read in total (212532ms).
[10:40:32.364] <TB0> INFO: 8087190 events read in total (247609ms).
[10:40:39.766] <TB0> INFO: 8320000 events read in total (255011ms).
[10:40:39.813] <TB0> INFO: Test took 256074ms.
[10:40:39.915] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:04.468] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 177 (-1/-1) hits flags = 528 (plus default)
[10:41:04.476] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:41:04.476] <TB0> INFO: run 1 of 1
[10:41:04.779] <TB0> INFO: Expecting 7404800 events.
[10:41:41.515] <TB0> INFO: 1204940 events read in total (36021ms).
[10:42:17.265] <TB0> INFO: 2392820 events read in total (71771ms).
[10:42:52.935] <TB0> INFO: 3576710 events read in total (107441ms).
[10:43:28.371] <TB0> INFO: 4742350 events read in total (142877ms).
[10:44:03.681] <TB0> INFO: 5903520 events read in total (178187ms).
[10:44:39.037] <TB0> INFO: 7062620 events read in total (213543ms).
[10:44:49.634] <TB0> INFO: 7404800 events read in total (224140ms).
[10:44:49.668] <TB0> INFO: Test took 225193ms.
[10:44:49.756] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:12.206] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[10:45:12.214] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:45:12.214] <TB0> INFO: run 1 of 1
[10:45:12.512] <TB0> INFO: Expecting 6947200 events.
[10:45:50.284] <TB0> INFO: 1251330 events read in total (37056ms).
[10:46:26.802] <TB0> INFO: 2483480 events read in total (73574ms).
[10:47:03.048] <TB0> INFO: 3705670 events read in total (109820ms).
[10:47:39.142] <TB0> INFO: 4911390 events read in total (145914ms).
[10:48:15.280] <TB0> INFO: 6110490 events read in total (182052ms).
[10:48:40.560] <TB0> INFO: 6947200 events read in total (207332ms).
[10:48:40.594] <TB0> INFO: Test took 208380ms.
[10:48:40.667] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:02.253] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 164 (-1/-1) hits flags = 528 (plus default)
[10:49:02.261] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:49:02.261] <TB0> INFO: run 1 of 1
[10:49:02.562] <TB0> INFO: Expecting 6864000 events.
[10:49:40.292] <TB0> INFO: 1258760 events read in total (37015ms).
[10:50:16.906] <TB0> INFO: 2499180 events read in total (73629ms).
[10:50:53.545] <TB0> INFO: 3727780 events read in total (110269ms).
[10:51:29.808] <TB0> INFO: 4939700 events read in total (146531ms).
[10:52:05.916] <TB0> INFO: 6145570 events read in total (182639ms).
[10:52:27.491] <TB0> INFO: 6864000 events read in total (204214ms).
[10:52:27.527] <TB0> INFO: Test took 205266ms.
[10:52:27.601] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:48.758] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[10:52:48.766] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:52:48.766] <TB0> INFO: run 1 of 1
[10:52:49.064] <TB0> INFO: Expecting 6905600 events.
[10:53:26.593] <TB0> INFO: 1252240 events read in total (36814ms).
[10:54:03.147] <TB0> INFO: 2485650 events read in total (73368ms).
[10:54:39.342] <TB0> INFO: 3708380 events read in total (109563ms).
[10:55:15.507] <TB0> INFO: 4914990 events read in total (145728ms).
[10:55:51.704] <TB0> INFO: 6114990 events read in total (181925ms).
[10:56:15.507] <TB0> INFO: 6905600 events read in total (205728ms).
[10:56:15.544] <TB0> INFO: Test took 206779ms.
[10:56:15.619] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:37.464] <TB0> INFO: PixTestTrim::trimBitTest() done
[10:56:37.466] <TB0> INFO: PixTestTrim::doTest() done, duration: 2785 seconds
[10:56:37.466] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:37.466] <TB0> INFO: Decoding statistics:
[10:56:37.466] <TB0> INFO: General information:
[10:56:37.466] <TB0> INFO: 16bit words read: 0
[10:56:37.466] <TB0> INFO: valid events total: 0
[10:56:37.466] <TB0> INFO: empty events: 0
[10:56:37.466] <TB0> INFO: valid events with pixels: 0
[10:56:37.466] <TB0> INFO: valid pixel hits: 0
[10:56:37.466] <TB0> INFO: Event errors: 0
[10:56:37.466] <TB0> INFO: start marker: 0
[10:56:37.466] <TB0> INFO: stop marker: 0
[10:56:37.466] <TB0> INFO: overflow: 0
[10:56:37.466] <TB0> INFO: invalid 5bit words: 0
[10:56:37.466] <TB0> INFO: invalid XOR eye diagram: 0
[10:56:37.466] <TB0> INFO: TBM errors: 0
[10:56:37.466] <TB0> INFO: flawed TBM headers: 0
[10:56:37.466] <TB0> INFO: flawed TBM trailers: 0
[10:56:37.466] <TB0> INFO: event ID mismatches: 0
[10:56:37.466] <TB0> INFO: ROC errors: 0
[10:56:37.466] <TB0> INFO: missing ROC header(s): 0
[10:56:37.466] <TB0> INFO: misplaced readback start: 0
[10:56:37.466] <TB0> INFO: Pixel decoding errors: 0
[10:56:37.466] <TB0> INFO: pixel data incomplete: 0
[10:56:37.466] <TB0> INFO: pixel address: 0
[10:56:37.466] <TB0> INFO: pulse height fill bit: 0
[10:56:37.466] <TB0> INFO: buffer corruption: 0
[10:56:38.114] <TB0> INFO: ######################################################################
[10:56:38.114] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:56:38.114] <TB0> INFO: ######################################################################
[10:56:38.415] <TB0> INFO: Expecting 41600 events.
[10:56:42.382] <TB0> INFO: 41600 events read in total (3251ms).
[10:56:42.383] <TB0> INFO: Test took 4267ms.
[10:56:42.389] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:43.040] <TB0> INFO: Expecting 41600 events.
[10:56:47.047] <TB0> INFO: 41600 events read in total (3292ms).
[10:56:47.048] <TB0> INFO: Test took 4305ms.
[10:56:47.054] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:47.505] <TB0> INFO: Expecting 41600 events.
[10:56:51.511] <TB0> INFO: 41600 events read in total (3291ms).
[10:56:51.511] <TB0> INFO: Test took 4329ms.
[10:56:51.517] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:51.967] <TB0> INFO: Expecting 2560 events.
[10:56:52.925] <TB0> INFO: 2560 events read in total (242ms).
[10:56:52.926] <TB0> INFO: Test took 1401ms.
[10:56:53.435] <TB0> INFO: Expecting 2560 events.
[10:56:54.392] <TB0> INFO: 2560 events read in total (242ms).
[10:56:54.392] <TB0> INFO: Test took 1466ms.
[10:56:54.901] <TB0> INFO: Expecting 2560 events.
[10:56:55.858] <TB0> INFO: 2560 events read in total (241ms).
[10:56:55.858] <TB0> INFO: Test took 1465ms.
[10:56:56.367] <TB0> INFO: Expecting 2560 events.
[10:56:57.324] <TB0> INFO: 2560 events read in total (241ms).
[10:56:57.324] <TB0> INFO: Test took 1465ms.
[10:56:57.834] <TB0> INFO: Expecting 2560 events.
[10:56:58.790] <TB0> INFO: 2560 events read in total (241ms).
[10:56:58.791] <TB0> INFO: Test took 1466ms.
[10:56:59.300] <TB0> INFO: Expecting 2560 events.
[10:57:00.257] <TB0> INFO: 2560 events read in total (242ms).
[10:57:00.257] <TB0> INFO: Test took 1466ms.
[10:57:00.766] <TB0> INFO: Expecting 2560 events.
[10:57:01.723] <TB0> INFO: 2560 events read in total (242ms).
[10:57:01.723] <TB0> INFO: Test took 1466ms.
[10:57:02.231] <TB0> INFO: Expecting 2560 events.
[10:57:03.188] <TB0> INFO: 2560 events read in total (241ms).
[10:57:03.188] <TB0> INFO: Test took 1465ms.
[10:57:03.698] <TB0> INFO: Expecting 2560 events.
[10:57:04.655] <TB0> INFO: 2560 events read in total (242ms).
[10:57:04.655] <TB0> INFO: Test took 1467ms.
[10:57:05.164] <TB0> INFO: Expecting 2560 events.
[10:57:06.121] <TB0> INFO: 2560 events read in total (242ms).
[10:57:06.121] <TB0> INFO: Test took 1466ms.
[10:57:06.630] <TB0> INFO: Expecting 2560 events.
[10:57:07.587] <TB0> INFO: 2560 events read in total (242ms).
[10:57:07.587] <TB0> INFO: Test took 1465ms.
[10:57:08.097] <TB0> INFO: Expecting 2560 events.
[10:57:09.054] <TB0> INFO: 2560 events read in total (242ms).
[10:57:09.054] <TB0> INFO: Test took 1466ms.
[10:57:09.563] <TB0> INFO: Expecting 2560 events.
[10:57:10.520] <TB0> INFO: 2560 events read in total (241ms).
[10:57:10.520] <TB0> INFO: Test took 1466ms.
[10:57:11.029] <TB0> INFO: Expecting 2560 events.
[10:57:11.986] <TB0> INFO: 2560 events read in total (242ms).
[10:57:11.986] <TB0> INFO: Test took 1466ms.
[10:57:12.495] <TB0> INFO: Expecting 2560 events.
[10:57:13.451] <TB0> INFO: 2560 events read in total (241ms).
[10:57:13.451] <TB0> INFO: Test took 1464ms.
[10:57:13.961] <TB0> INFO: Expecting 2560 events.
[10:57:14.917] <TB0> INFO: 2560 events read in total (241ms).
[10:57:14.918] <TB0> INFO: Test took 1466ms.
[10:57:14.922] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:57:15.428] <TB0> INFO: Expecting 655360 events.
[10:57:26.775] <TB0> INFO: 655360 events read in total (10632ms).
[10:57:26.783] <TB0> INFO: Expecting 655360 events.
[10:57:38.105] <TB0> INFO: 655360 events read in total (10710ms).
[10:57:38.116] <TB0> INFO: Expecting 655360 events.
[10:57:49.487] <TB0> INFO: 655360 events read in total (10765ms).
[10:57:49.502] <TB0> INFO: Expecting 655360 events.
[10:58:00.849] <TB0> INFO: 655360 events read in total (10743ms).
[10:58:00.867] <TB0> INFO: Expecting 655360 events.
[10:58:12.331] <TB0> INFO: 655360 events read in total (10863ms).
[10:58:12.356] <TB0> INFO: Expecting 655360 events.
[10:58:23.687] <TB0> INFO: 655360 events read in total (10735ms).
[10:58:23.712] <TB0> INFO: Expecting 655360 events.
[10:58:34.939] <TB0> INFO: 655360 events read in total (10635ms).
[10:58:34.966] <TB0> INFO: Expecting 655360 events.
[10:58:46.253] <TB0> INFO: 655360 events read in total (10693ms).
[10:58:46.285] <TB0> INFO: Expecting 655360 events.
[10:58:57.709] <TB0> INFO: 655360 events read in total (10836ms).
[10:58:57.743] <TB0> INFO: Expecting 655360 events.
[10:59:09.240] <TB0> INFO: 655360 events read in total (10913ms).
[10:59:09.279] <TB0> INFO: Expecting 655360 events.
[10:59:20.846] <TB0> INFO: 655360 events read in total (10988ms).
[10:59:20.887] <TB0> INFO: Expecting 655360 events.
[10:59:32.297] <TB0> INFO: 655360 events read in total (10833ms).
[10:59:32.343] <TB0> INFO: Expecting 655360 events.
[10:59:43.700] <TB0> INFO: 655360 events read in total (10784ms).
[10:59:43.748] <TB0> INFO: Expecting 655360 events.
[10:59:55.085] <TB0> INFO: 655360 events read in total (10766ms).
[10:59:55.135] <TB0> INFO: Expecting 655360 events.
[11:00:06.536] <TB0> INFO: 655360 events read in total (10834ms).
[11:00:06.592] <TB0> INFO: Expecting 655360 events.
[11:00:17.939] <TB0> INFO: 655360 events read in total (10785ms).
[11:00:17.997] <TB0> INFO: Test took 183075ms.
[11:00:18.076] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:18.383] <TB0> INFO: Expecting 655360 events.
[11:00:29.808] <TB0> INFO: 655360 events read in total (10709ms).
[11:00:29.816] <TB0> INFO: Expecting 655360 events.
[11:00:41.081] <TB0> INFO: 655360 events read in total (10652ms).
[11:00:41.092] <TB0> INFO: Expecting 655360 events.
[11:00:52.454] <TB0> INFO: 655360 events read in total (10749ms).
[11:00:52.469] <TB0> INFO: Expecting 655360 events.
[11:01:03.890] <TB0> INFO: 655360 events read in total (10818ms).
[11:01:03.909] <TB0> INFO: Expecting 655360 events.
[11:01:15.177] <TB0> INFO: 655360 events read in total (10667ms).
[11:01:15.198] <TB0> INFO: Expecting 655360 events.
[11:01:26.444] <TB0> INFO: 655360 events read in total (10643ms).
[11:01:26.468] <TB0> INFO: Expecting 655360 events.
[11:01:37.701] <TB0> INFO: 655360 events read in total (10631ms).
[11:01:37.729] <TB0> INFO: Expecting 655360 events.
[11:01:49.018] <TB0> INFO: 655360 events read in total (10695ms).
[11:01:49.050] <TB0> INFO: Expecting 655360 events.
[11:02:00.365] <TB0> INFO: 655360 events read in total (10728ms).
[11:02:00.400] <TB0> INFO: Expecting 655360 events.
[11:02:11.726] <TB0> INFO: 655360 events read in total (10740ms).
[11:02:11.764] <TB0> INFO: Expecting 655360 events.
[11:02:23.104] <TB0> INFO: 655360 events read in total (10756ms).
[11:02:23.148] <TB0> INFO: Expecting 655360 events.
[11:02:34.452] <TB0> INFO: 655360 events read in total (10728ms).
[11:02:34.497] <TB0> INFO: Expecting 655360 events.
[11:02:45.826] <TB0> INFO: 655360 events read in total (10745ms).
[11:02:45.873] <TB0> INFO: Expecting 655360 events.
[11:02:57.239] <TB0> INFO: 655360 events read in total (10793ms).
[11:02:57.290] <TB0> INFO: Expecting 655360 events.
[11:03:08.667] <TB0> INFO: 655360 events read in total (10806ms).
[11:03:08.736] <TB0> INFO: Expecting 655360 events.
[11:03:20.064] <TB0> INFO: 655360 events read in total (10773ms).
[11:03:20.129] <TB0> INFO: Test took 182053ms.
[11:03:20.306] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.313] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.319] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.326] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.333] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.339] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:03:20.346] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:03:20.352] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:03:20.359] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:03:20.365] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.372] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:03:20.378] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:03:20.384] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:03:20.391] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:03:20.397] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[11:03:20.404] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[11:03:20.410] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[11:03:20.417] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[11:03:20.423] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.430] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.436] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.443] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.449] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.456] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.462] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.469] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.475] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.482] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:03:20.537] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:03:20.537] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:03:20.537] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:03:20.537] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:03:20.537] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:03:20.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:03:20.539] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:03:20.841] <TB0> INFO: Expecting 41600 events.
[11:03:24.613] <TB0> INFO: 41600 events read in total (3057ms).
[11:03:24.614] <TB0> INFO: Test took 4071ms.
[11:03:25.257] <TB0> INFO: Expecting 41600 events.
[11:03:28.994] <TB0> INFO: 41600 events read in total (3021ms).
[11:03:28.995] <TB0> INFO: Test took 4034ms.
[11:03:29.638] <TB0> INFO: Expecting 41600 events.
[11:03:33.399] <TB0> INFO: 41600 events read in total (3046ms).
[11:03:33.399] <TB0> INFO: Test took 4058ms.
[11:03:33.743] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:33.875] <TB0> INFO: Expecting 2560 events.
[11:03:34.832] <TB0> INFO: 2560 events read in total (242ms).
[11:03:34.832] <TB0> INFO: Test took 1089ms.
[11:03:34.835] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:35.342] <TB0> INFO: Expecting 2560 events.
[11:03:36.299] <TB0> INFO: 2560 events read in total (242ms).
[11:03:36.300] <TB0> INFO: Test took 1465ms.
[11:03:36.302] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:36.809] <TB0> INFO: Expecting 2560 events.
[11:03:37.780] <TB0> INFO: 2560 events read in total (256ms).
[11:03:37.781] <TB0> INFO: Test took 1479ms.
[11:03:37.783] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:38.290] <TB0> INFO: Expecting 2560 events.
[11:03:39.247] <TB0> INFO: 2560 events read in total (241ms).
[11:03:39.248] <TB0> INFO: Test took 1465ms.
[11:03:39.250] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:39.756] <TB0> INFO: Expecting 2560 events.
[11:03:40.713] <TB0> INFO: 2560 events read in total (241ms).
[11:03:40.713] <TB0> INFO: Test took 1463ms.
[11:03:40.716] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:41.223] <TB0> INFO: Expecting 2560 events.
[11:03:42.180] <TB0> INFO: 2560 events read in total (242ms).
[11:03:42.180] <TB0> INFO: Test took 1464ms.
[11:03:42.183] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:42.690] <TB0> INFO: Expecting 2560 events.
[11:03:43.647] <TB0> INFO: 2560 events read in total (241ms).
[11:03:43.647] <TB0> INFO: Test took 1464ms.
[11:03:43.650] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:44.157] <TB0> INFO: Expecting 2560 events.
[11:03:45.114] <TB0> INFO: 2560 events read in total (242ms).
[11:03:45.114] <TB0> INFO: Test took 1464ms.
[11:03:45.117] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:45.624] <TB0> INFO: Expecting 2560 events.
[11:03:46.581] <TB0> INFO: 2560 events read in total (241ms).
[11:03:46.581] <TB0> INFO: Test took 1464ms.
[11:03:46.584] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:47.091] <TB0> INFO: Expecting 2560 events.
[11:03:48.048] <TB0> INFO: 2560 events read in total (242ms).
[11:03:48.048] <TB0> INFO: Test took 1464ms.
[11:03:48.051] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:48.558] <TB0> INFO: Expecting 2560 events.
[11:03:49.515] <TB0> INFO: 2560 events read in total (242ms).
[11:03:49.515] <TB0> INFO: Test took 1464ms.
[11:03:49.518] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:50.025] <TB0> INFO: Expecting 2560 events.
[11:03:50.982] <TB0> INFO: 2560 events read in total (242ms).
[11:03:50.982] <TB0> INFO: Test took 1464ms.
[11:03:50.985] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:51.492] <TB0> INFO: Expecting 2560 events.
[11:03:52.449] <TB0> INFO: 2560 events read in total (242ms).
[11:03:52.449] <TB0> INFO: Test took 1464ms.
[11:03:52.451] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:52.959] <TB0> INFO: Expecting 2560 events.
[11:03:53.916] <TB0> INFO: 2560 events read in total (242ms).
[11:03:53.916] <TB0> INFO: Test took 1465ms.
[11:03:53.918] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:54.426] <TB0> INFO: Expecting 2560 events.
[11:03:55.383] <TB0> INFO: 2560 events read in total (242ms).
[11:03:55.383] <TB0> INFO: Test took 1465ms.
[11:03:55.386] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:55.893] <TB0> INFO: Expecting 2560 events.
[11:03:56.850] <TB0> INFO: 2560 events read in total (242ms).
[11:03:56.850] <TB0> INFO: Test took 1464ms.
[11:03:56.853] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:57.360] <TB0> INFO: Expecting 2560 events.
[11:03:58.317] <TB0> INFO: 2560 events read in total (242ms).
[11:03:58.317] <TB0> INFO: Test took 1464ms.
[11:03:58.320] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:58.827] <TB0> INFO: Expecting 2560 events.
[11:03:59.784] <TB0> INFO: 2560 events read in total (242ms).
[11:03:59.785] <TB0> INFO: Test took 1465ms.
[11:03:59.787] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:00.294] <TB0> INFO: Expecting 2560 events.
[11:04:01.252] <TB0> INFO: 2560 events read in total (242ms).
[11:04:01.252] <TB0> INFO: Test took 1465ms.
[11:04:01.255] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:01.761] <TB0> INFO: Expecting 2560 events.
[11:04:02.719] <TB0> INFO: 2560 events read in total (242ms).
[11:04:02.719] <TB0> INFO: Test took 1464ms.
[11:04:02.721] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:03.229] <TB0> INFO: Expecting 2560 events.
[11:04:04.186] <TB0> INFO: 2560 events read in total (242ms).
[11:04:04.186] <TB0> INFO: Test took 1465ms.
[11:04:04.189] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:04.696] <TB0> INFO: Expecting 2560 events.
[11:04:05.652] <TB0> INFO: 2560 events read in total (241ms).
[11:04:05.652] <TB0> INFO: Test took 1463ms.
[11:04:05.655] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:06.162] <TB0> INFO: Expecting 2560 events.
[11:04:07.119] <TB0> INFO: 2560 events read in total (242ms).
[11:04:07.119] <TB0> INFO: Test took 1464ms.
[11:04:07.122] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:07.629] <TB0> INFO: Expecting 2560 events.
[11:04:08.586] <TB0> INFO: 2560 events read in total (242ms).
[11:04:08.586] <TB0> INFO: Test took 1464ms.
[11:04:08.588] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:09.096] <TB0> INFO: Expecting 2560 events.
[11:04:10.053] <TB0> INFO: 2560 events read in total (242ms).
[11:04:10.053] <TB0> INFO: Test took 1465ms.
[11:04:10.056] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:10.563] <TB0> INFO: Expecting 2560 events.
[11:04:11.521] <TB0> INFO: 2560 events read in total (242ms).
[11:04:11.521] <TB0> INFO: Test took 1465ms.
[11:04:11.523] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:12.030] <TB0> INFO: Expecting 2560 events.
[11:04:12.988] <TB0> INFO: 2560 events read in total (242ms).
[11:04:12.988] <TB0> INFO: Test took 1465ms.
[11:04:12.990] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:13.497] <TB0> INFO: Expecting 2560 events.
[11:04:14.454] <TB0> INFO: 2560 events read in total (242ms).
[11:04:14.454] <TB0> INFO: Test took 1464ms.
[11:04:14.457] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:14.964] <TB0> INFO: Expecting 2560 events.
[11:04:15.921] <TB0> INFO: 2560 events read in total (242ms).
[11:04:15.921] <TB0> INFO: Test took 1464ms.
[11:04:15.924] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:16.431] <TB0> INFO: Expecting 2560 events.
[11:04:17.388] <TB0> INFO: 2560 events read in total (242ms).
[11:04:17.388] <TB0> INFO: Test took 1464ms.
[11:04:17.391] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:17.898] <TB0> INFO: Expecting 2560 events.
[11:04:18.855] <TB0> INFO: 2560 events read in total (242ms).
[11:04:18.855] <TB0> INFO: Test took 1465ms.
[11:04:18.858] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:19.365] <TB0> INFO: Expecting 2560 events.
[11:04:20.322] <TB0> INFO: 2560 events read in total (242ms).
[11:04:20.322] <TB0> INFO: Test took 1464ms.
[11:04:21.060] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 462 seconds
[11:04:21.060] <TB0> INFO: PH scale (per ROC): 76 81 86 81 80 84 80 75 87 83 83 81 84 87 77 83
[11:04:21.060] <TB0> INFO: PH offset (per ROC): 151 153 155 157 150 144 156 151 155 175 155 151 167 150 163 175
[11:04:21.063] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:21.063] <TB0> INFO: Decoding statistics:
[11:04:21.063] <TB0> INFO: General information:
[11:04:21.063] <TB0> INFO: 16bit words read: 66444
[11:04:21.063] <TB0> INFO: valid events total: 5120
[11:04:21.063] <TB0> INFO: empty events: 2618
[11:04:21.063] <TB0> INFO: valid events with pixels: 2502
[11:04:21.063] <TB0> INFO: valid pixel hits: 2502
[11:04:21.063] <TB0> INFO: Event errors: 0
[11:04:21.063] <TB0> INFO: start marker: 0
[11:04:21.063] <TB0> INFO: stop marker: 0
[11:04:21.063] <TB0> INFO: overflow: 0
[11:04:21.063] <TB0> INFO: invalid 5bit words: 0
[11:04:21.063] <TB0> INFO: invalid XOR eye diagram: 0
[11:04:21.063] <TB0> INFO: TBM errors: 0
[11:04:21.063] <TB0> INFO: flawed TBM headers: 0
[11:04:21.063] <TB0> INFO: flawed TBM trailers: 0
[11:04:21.063] <TB0> INFO: event ID mismatches: 0
[11:04:21.063] <TB0> INFO: ROC errors: 0
[11:04:21.063] <TB0> INFO: missing ROC header(s): 0
[11:04:21.063] <TB0> INFO: misplaced readback start: 0
[11:04:21.063] <TB0> INFO: Pixel decoding errors: 0
[11:04:21.063] <TB0> INFO: pixel data incomplete: 0
[11:04:21.063] <TB0> INFO: pixel address: 0
[11:04:21.063] <TB0> INFO: pulse height fill bit: 0
[11:04:21.063] <TB0> INFO: buffer corruption: 0
[11:04:21.224] <TB0> INFO: ######################################################################
[11:04:21.224] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:04:21.224] <TB0> INFO: ######################################################################
[11:04:21.234] <TB0> INFO: scanning low vcal = 10
[11:04:21.533] <TB0> INFO: Expecting 41600 events.
[11:04:25.234] <TB0> INFO: 41600 events read in total (2986ms).
[11:04:25.234] <TB0> INFO: Test took 4000ms.
[11:04:25.236] <TB0> INFO: scanning low vcal = 20
[11:04:25.744] <TB0> INFO: Expecting 41600 events.
[11:04:29.445] <TB0> INFO: 41600 events read in total (2986ms).
[11:04:29.445] <TB0> INFO: Test took 4209ms.
[11:04:29.447] <TB0> INFO: scanning low vcal = 30
[11:04:29.954] <TB0> INFO: Expecting 41600 events.
[11:04:33.675] <TB0> INFO: 41600 events read in total (3005ms).
[11:04:33.675] <TB0> INFO: Test took 4228ms.
[11:04:33.678] <TB0> INFO: scanning low vcal = 40
[11:04:34.178] <TB0> INFO: Expecting 41600 events.
[11:04:38.340] <TB0> INFO: 41600 events read in total (3447ms).
[11:04:38.340] <TB0> INFO: Test took 4662ms.
[11:04:38.344] <TB0> INFO: scanning low vcal = 50
[11:04:38.794] <TB0> INFO: Expecting 41600 events.
[11:04:42.984] <TB0> INFO: 41600 events read in total (3474ms).
[11:04:42.984] <TB0> INFO: Test took 4640ms.
[11:04:42.988] <TB0> INFO: scanning low vcal = 60
[11:04:43.440] <TB0> INFO: Expecting 41600 events.
[11:04:47.638] <TB0> INFO: 41600 events read in total (3482ms).
[11:04:47.639] <TB0> INFO: Test took 4651ms.
[11:04:47.642] <TB0> INFO: scanning low vcal = 70
[11:04:48.093] <TB0> INFO: Expecting 41600 events.
[11:04:52.287] <TB0> INFO: 41600 events read in total (3478ms).
[11:04:52.288] <TB0> INFO: Test took 4646ms.
[11:04:52.291] <TB0> INFO: scanning low vcal = 80
[11:04:52.743] <TB0> INFO: Expecting 41600 events.
[11:04:56.932] <TB0> INFO: 41600 events read in total (3474ms).
[11:04:56.933] <TB0> INFO: Test took 4642ms.
[11:04:56.936] <TB0> INFO: scanning low vcal = 90
[11:04:57.388] <TB0> INFO: Expecting 41600 events.
[11:05:01.568] <TB0> INFO: 41600 events read in total (3464ms).
[11:05:01.569] <TB0> INFO: Test took 4633ms.
[11:05:01.572] <TB0> INFO: scanning low vcal = 100
[11:05:02.024] <TB0> INFO: Expecting 41600 events.
[11:05:06.358] <TB0> INFO: 41600 events read in total (3619ms).
[11:05:06.358] <TB0> INFO: Test took 4786ms.
[11:05:06.362] <TB0> INFO: scanning low vcal = 110
[11:05:06.814] <TB0> INFO: Expecting 41600 events.
[11:05:11.017] <TB0> INFO: 41600 events read in total (3488ms).
[11:05:11.018] <TB0> INFO: Test took 4656ms.
[11:05:11.021] <TB0> INFO: scanning low vcal = 120
[11:05:11.473] <TB0> INFO: Expecting 41600 events.
[11:05:15.659] <TB0> INFO: 41600 events read in total (3471ms).
[11:05:15.659] <TB0> INFO: Test took 4638ms.
[11:05:15.663] <TB0> INFO: scanning low vcal = 130
[11:05:16.116] <TB0> INFO: Expecting 41600 events.
[11:05:20.304] <TB0> INFO: 41600 events read in total (3473ms).
[11:05:20.304] <TB0> INFO: Test took 4641ms.
[11:05:20.308] <TB0> INFO: scanning low vcal = 140
[11:05:20.759] <TB0> INFO: Expecting 41600 events.
[11:05:24.950] <TB0> INFO: 41600 events read in total (3476ms).
[11:05:24.951] <TB0> INFO: Test took 4643ms.
[11:05:24.954] <TB0> INFO: scanning low vcal = 150
[11:05:25.406] <TB0> INFO: Expecting 41600 events.
[11:05:29.607] <TB0> INFO: 41600 events read in total (3485ms).
[11:05:29.607] <TB0> INFO: Test took 4653ms.
[11:05:29.611] <TB0> INFO: scanning low vcal = 160
[11:05:30.062] <TB0> INFO: Expecting 41600 events.
[11:05:34.260] <TB0> INFO: 41600 events read in total (3482ms).
[11:05:34.260] <TB0> INFO: Test took 4649ms.
[11:05:34.263] <TB0> INFO: scanning low vcal = 170
[11:05:34.715] <TB0> INFO: Expecting 41600 events.
[11:05:38.916] <TB0> INFO: 41600 events read in total (3485ms).
[11:05:38.916] <TB0> INFO: Test took 4653ms.
[11:05:38.921] <TB0> INFO: scanning low vcal = 180
[11:05:39.372] <TB0> INFO: Expecting 41600 events.
[11:05:43.572] <TB0> INFO: 41600 events read in total (3485ms).
[11:05:43.573] <TB0> INFO: Test took 4652ms.
[11:05:43.576] <TB0> INFO: scanning low vcal = 190
[11:05:44.027] <TB0> INFO: Expecting 41600 events.
[11:05:48.238] <TB0> INFO: 41600 events read in total (3495ms).
[11:05:48.238] <TB0> INFO: Test took 4662ms.
[11:05:48.241] <TB0> INFO: scanning low vcal = 200
[11:05:48.693] <TB0> INFO: Expecting 41600 events.
[11:05:52.922] <TB0> INFO: 41600 events read in total (3513ms).
[11:05:52.922] <TB0> INFO: Test took 4681ms.
[11:05:52.926] <TB0> INFO: scanning low vcal = 210
[11:05:53.378] <TB0> INFO: Expecting 41600 events.
[11:05:57.584] <TB0> INFO: 41600 events read in total (3491ms).
[11:05:57.585] <TB0> INFO: Test took 4659ms.
[11:05:57.588] <TB0> INFO: scanning low vcal = 220
[11:05:58.041] <TB0> INFO: Expecting 41600 events.
[11:06:02.236] <TB0> INFO: 41600 events read in total (3480ms).
[11:06:02.236] <TB0> INFO: Test took 4648ms.
[11:06:02.239] <TB0> INFO: scanning low vcal = 230
[11:06:02.692] <TB0> INFO: Expecting 41600 events.
[11:06:06.888] <TB0> INFO: 41600 events read in total (3481ms).
[11:06:06.889] <TB0> INFO: Test took 4649ms.
[11:06:06.892] <TB0> INFO: scanning low vcal = 240
[11:06:07.345] <TB0> INFO: Expecting 41600 events.
[11:06:11.544] <TB0> INFO: 41600 events read in total (3483ms).
[11:06:11.544] <TB0> INFO: Test took 4652ms.
[11:06:11.548] <TB0> INFO: scanning low vcal = 250
[11:06:11.999] <TB0> INFO: Expecting 41600 events.
[11:06:16.197] <TB0> INFO: 41600 events read in total (3483ms).
[11:06:16.197] <TB0> INFO: Test took 4649ms.
[11:06:16.202] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[11:06:16.654] <TB0> INFO: Expecting 41600 events.
[11:06:20.852] <TB0> INFO: 41600 events read in total (3482ms).
[11:06:20.852] <TB0> INFO: Test took 4649ms.
[11:06:20.856] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[11:06:21.308] <TB0> INFO: Expecting 41600 events.
[11:06:25.505] <TB0> INFO: 41600 events read in total (3481ms).
[11:06:25.505] <TB0> INFO: Test took 4649ms.
[11:06:25.509] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[11:06:25.962] <TB0> INFO: Expecting 41600 events.
[11:06:30.157] <TB0> INFO: 41600 events read in total (3479ms).
[11:06:30.158] <TB0> INFO: Test took 4649ms.
[11:06:30.161] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[11:06:30.614] <TB0> INFO: Expecting 41600 events.
[11:06:34.807] <TB0> INFO: 41600 events read in total (3478ms).
[11:06:34.808] <TB0> INFO: Test took 4647ms.
[11:06:34.811] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:06:35.264] <TB0> INFO: Expecting 41600 events.
[11:06:39.462] <TB0> INFO: 41600 events read in total (3482ms).
[11:06:39.463] <TB0> INFO: Test took 4652ms.
[11:06:39.893] <TB0> INFO: PixTestGainPedestal::measure() done
[11:07:11.449] <TB0> INFO: PixTestGainPedestal::fit() done
[11:07:11.449] <TB0> INFO: non-linearity mean: 0.955 0.958 0.953 0.951 0.960 0.967 0.951 0.956 0.954 0.957 0.956 0.964 0.958 0.958 0.957 0.958
[11:07:11.449] <TB0> INFO: non-linearity RMS: 0.007 0.005 0.006 0.006 0.004 0.003 0.007 0.005 0.005 0.007 0.005 0.005 0.005 0.005 0.004 0.006
[11:07:11.451] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:07:11.469] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:07:11.486] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:07:11.504] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:07:11.522] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:07:11.540] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:07:11.557] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:07:11.575] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:07:11.592] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:07:11.610] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:07:11.628] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:07:11.645] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:07:11.663] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:07:11.680] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:07:11.698] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:07:11.715] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:07:11.733] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 170 seconds
[11:07:11.733] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:11.733] <TB0> INFO: Decoding statistics:
[11:07:11.733] <TB0> INFO: General information:
[11:07:11.733] <TB0> INFO: 16bit words read: 2329560
[11:07:11.733] <TB0> INFO: valid events total: 83200
[11:07:11.733] <TB0> INFO: empty events: 0
[11:07:11.733] <TB0> INFO: valid events with pixels: 83200
[11:07:11.733] <TB0> INFO: valid pixel hits: 665580
[11:07:11.733] <TB0> INFO: Event errors: 0
[11:07:11.733] <TB0> INFO: start marker: 0
[11:07:11.733] <TB0> INFO: stop marker: 0
[11:07:11.733] <TB0> INFO: overflow: 0
[11:07:11.733] <TB0> INFO: invalid 5bit words: 0
[11:07:11.733] <TB0> INFO: invalid XOR eye diagram: 0
[11:07:11.733] <TB0> INFO: TBM errors: 0
[11:07:11.733] <TB0> INFO: flawed TBM headers: 0
[11:07:11.733] <TB0> INFO: flawed TBM trailers: 0
[11:07:11.733] <TB0> INFO: event ID mismatches: 0
[11:07:11.733] <TB0> INFO: ROC errors: 0
[11:07:11.733] <TB0> INFO: missing ROC header(s): 0
[11:07:11.733] <TB0> INFO: misplaced readback start: 0
[11:07:11.733] <TB0> INFO: Pixel decoding errors: 0
[11:07:11.733] <TB0> INFO: pixel data incomplete: 0
[11:07:11.733] <TB0> INFO: pixel address: 0
[11:07:11.733] <TB0> INFO: pulse height fill bit: 0
[11:07:11.733] <TB0> INFO: buffer corruption: 0
[11:07:11.739] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C15.dat
[11:07:11.740] <TB0> INFO: ######################################################################
[11:07:11.740] <TB0> INFO: PixTestReadback::doTest()
[11:07:11.740] <TB0> INFO: ######################################################################
[11:07:11.741] <TB0> INFO: PixTestReadback::RES sent once
[11:07:22.976] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C0.dat
[11:07:22.976] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C1.dat
[11:07:22.976] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C2.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C3.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C4.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C5.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C6.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C7.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C8.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C9.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C10.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C11.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C12.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C13.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C14.dat
[11:07:22.977] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C15.dat
[11:07:23.030] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:07:23.030] <TB0> INFO: PixTestReadback::RES sent once
[11:07:34.235] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C0.dat
[11:07:34.235] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C1.dat
[11:07:34.235] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C2.dat
[11:07:34.235] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C3.dat
[11:07:34.235] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C4.dat
[11:07:34.235] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C5.dat
[11:07:34.235] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C6.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C7.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C8.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C9.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C10.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C11.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C12.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C13.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C14.dat
[11:07:34.236] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C15.dat
[11:07:34.283] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:07:34.284] <TB0> INFO: PixTestReadback::RES sent once
[11:07:42.903] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:07:42.903] <TB0> INFO: Vbg will be calibrated using Vd calibration
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.5calibrated Vbg = 1.22044 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 146.9calibrated Vbg = 1.21176 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.2calibrated Vbg = 1.21568 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.6calibrated Vbg = 1.21951 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.8calibrated Vbg = 1.22643 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.2calibrated Vbg = 1.2232 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 165.8calibrated Vbg = 1.21604 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.5calibrated Vbg = 1.22646 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.7calibrated Vbg = 1.22921 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.4calibrated Vbg = 1.22076 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.9calibrated Vbg = 1.23133 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.7calibrated Vbg = 1.22884 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 159.1calibrated Vbg = 1.21985 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 141.3calibrated Vbg = 1.22365 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156calibrated Vbg = 1.21964 :::*/*/*/*/
[11:07:42.903] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.7calibrated Vbg = 1.214 :::*/*/*/*/
[11:07:42.906] <TB0> INFO: PixTestReadback::RES sent once
[11:10:37.476] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C0.dat
[11:10:37.476] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C1.dat
[11:10:37.476] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C2.dat
[11:10:37.476] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C3.dat
[11:10:37.476] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C4.dat
[11:10:37.476] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C5.dat
[11:10:37.476] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C6.dat
[11:10:37.476] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C7.dat
[11:10:37.477] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C8.dat
[11:10:37.477] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C9.dat
[11:10:37.477] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C10.dat
[11:10:37.477] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C11.dat
[11:10:37.477] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C12.dat
[11:10:37.477] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C13.dat
[11:10:37.477] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C14.dat
[11:10:37.477] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//002_FulltestPxar_m20//readbackCal_C15.dat
[11:10:37.522] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:10:37.523] <TB0> INFO: PixTestReadback::doTest() done
[11:10:37.523] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:37.523] <TB0> INFO: Decoding statistics:
[11:10:37.523] <TB0> INFO: General information:
[11:10:37.523] <TB0> INFO: 16bit words read: 768
[11:10:37.523] <TB0> INFO: valid events total: 64
[11:10:37.523] <TB0> INFO: empty events: 64
[11:10:37.523] <TB0> INFO: valid events with pixels: 0
[11:10:37.523] <TB0> INFO: valid pixel hits: 0
[11:10:37.523] <TB0> INFO: Event errors: 0
[11:10:37.523] <TB0> INFO: start marker: 0
[11:10:37.523] <TB0> INFO: stop marker: 0
[11:10:37.523] <TB0> INFO: overflow: 0
[11:10:37.523] <TB0> INFO: invalid 5bit words: 0
[11:10:37.523] <TB0> INFO: invalid XOR eye diagram: 0
[11:10:37.523] <TB0> INFO: TBM errors: 0
[11:10:37.523] <TB0> INFO: flawed TBM headers: 0
[11:10:37.523] <TB0> INFO: flawed TBM trailers: 0
[11:10:37.523] <TB0> INFO: event ID mismatches: 0
[11:10:37.523] <TB0> INFO: ROC errors: 0
[11:10:37.523] <TB0> INFO: missing ROC header(s): 0
[11:10:37.523] <TB0> INFO: misplaced readback start: 0
[11:10:37.523] <TB0> INFO: Pixel decoding errors: 0
[11:10:37.523] <TB0> INFO: pixel data incomplete: 0
[11:10:37.523] <TB0> INFO: pixel address: 0
[11:10:37.523] <TB0> INFO: pulse height fill bit: 0
[11:10:37.523] <TB0> INFO: buffer corruption: 0
[11:10:37.537] <TB0> INFO: Decoding statistics:
[11:10:37.537] <TB0> INFO: General information:
[11:10:37.537] <TB0> INFO: 16bit words read: 2396772
[11:10:37.537] <TB0> INFO: valid events total: 88384
[11:10:37.537] <TB0> INFO: empty events: 2682
[11:10:37.537] <TB0> INFO: valid events with pixels: 85702
[11:10:37.537] <TB0> INFO: valid pixel hits: 668082
[11:10:37.537] <TB0> INFO: Event errors: 0
[11:10:37.537] <TB0> INFO: start marker: 0
[11:10:37.538] <TB0> INFO: stop marker: 0
[11:10:37.538] <TB0> INFO: overflow: 0
[11:10:37.538] <TB0> INFO: invalid 5bit words: 0
[11:10:37.538] <TB0> INFO: invalid XOR eye diagram: 0
[11:10:37.538] <TB0> INFO: TBM errors: 0
[11:10:37.538] <TB0> INFO: flawed TBM headers: 0
[11:10:37.538] <TB0> INFO: flawed TBM trailers: 0
[11:10:37.538] <TB0> INFO: event ID mismatches: 0
[11:10:37.538] <TB0> INFO: ROC errors: 0
[11:10:37.538] <TB0> INFO: missing ROC header(s): 0
[11:10:37.538] <TB0> INFO: misplaced readback start: 0
[11:10:37.538] <TB0> INFO: Pixel decoding errors: 0
[11:10:37.538] <TB0> INFO: pixel data incomplete: 0
[11:10:37.538] <TB0> INFO: pixel address: 0
[11:10:37.538] <TB0> INFO: pulse height fill bit: 0
[11:10:37.538] <TB0> INFO: buffer corruption: 0
[11:10:37.538] <TB0> INFO: enter test to run
[11:10:37.538] <TB0> INFO: test: exit no parameter change
[11:10:37.741] <TB0> QUIET: Connection to board 169 closed.
[11:10:37.820] <TB0> INFO: pXar: this is the end, my friend