[09:52:16.071] <TB2> INFO: *** Welcome to pxar ***
[09:52:16.071] <TB2> INFO: *** Today: 2015/12/18
[09:52:16.140] <TB2> INFO: *** Version: 3197
[09:52:16.140] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C15.dat
[09:52:16.140] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:52:16.140] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//defaultMaskFile.dat
[09:52:16.140] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters_C15.dat
[09:52:16.208] <TB2> INFO: clk: 4
[09:52:16.208] <TB2> INFO: ctr: 4
[09:52:16.208] <TB2> INFO: sda: 19
[09:52:16.208] <TB2> INFO: tin: 9
[09:52:16.208] <TB2> INFO: level: 15
[09:52:16.208] <TB2> INFO: triggerdelay: 0
[09:52:16.208] <TB2> QUIET: Instanciating API for pxar prod-12
[09:52:16.208] <TB2> INFO: Log level: INFO
[09:52:16.216] <TB2> INFO: Found DTB DTB_WXC55Z
[09:52:16.227] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[09:52:16.230] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[09:52:16.232] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[09:52:17.757] <TB2> INFO: DUT info:
[09:52:17.758] <TB2> INFO: The DUT currently contains the following objects:
[09:52:17.758] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[09:52:17.758] <TB2> INFO: TBM Core alpha (0): 7 registers set
[09:52:17.758] <TB2> INFO: TBM Core beta (1): 7 registers set
[09:52:17.758] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:52:17.758] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:17.758] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:18.160] <TB2> INFO: enter 'restricted' command line mode
[09:52:18.160] <TB2> INFO: enter test to run
[09:52:18.160] <TB2> INFO: test: pretest no parameter change
[09:52:18.160] <TB2> INFO: running: pretest
[09:52:18.169] <TB2> INFO: ######################################################################
[09:52:18.169] <TB2> INFO: PixTestPretest::doTest()
[09:52:18.169] <TB2> INFO: ######################################################################
[09:52:18.171] <TB2> INFO: ----------------------------------------------------------------------
[09:52:18.171] <TB2> INFO: PixTestPretest::programROC()
[09:52:18.171] <TB2> INFO: ----------------------------------------------------------------------
[09:52:36.188] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:52:36.188] <TB2> INFO: IA differences per ROC: 16.9 20.1 16.9 19.3 20.9 18.5 17.7 18.5 18.5 20.9 16.9 20.9 18.5 17.7 18.5 17.7
[09:52:36.260] <TB2> INFO: ----------------------------------------------------------------------
[09:52:36.260] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:52:36.260] <TB2> INFO: ----------------------------------------------------------------------
[09:52:57.552] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[09:52:57.552] <TB2> INFO: i(loss) [mA/ROC]: 19.3 18.5 19.3 18.5 19.3 19.3 18.5 19.3 19.3 18.5 19.3 19.3 18.5 18.5 18.5 18.5
[09:52:57.593] <TB2> INFO: ----------------------------------------------------------------------
[09:52:57.594] <TB2> INFO: PixTestPretest::findTiming()
[09:52:57.594] <TB2> INFO: ----------------------------------------------------------------------
[09:52:57.594] <TB2> INFO: PixTestCmd::init()

[09:55:34.786] <TB2> INFO: TBM phases: 160MHz: 3, 400MHz: 6, TBM delays: ROC(0/1):2, header/trailer: 1, token: 0
[09:55:34.786] <TB2> INFO: (success/tries = 100/100), width = 2
[09:55:34.788] <TB2> INFO: ----------------------------------------------------------------------
[09:55:34.788] <TB2> INFO: PixTestPretest::findWorkingPixel()
[09:55:34.788] <TB2> INFO: ----------------------------------------------------------------------
[09:55:34.925] <TB2> INFO: Expecting 231680 events.
[09:55:43.766] <TB2> INFO: 231680 events read in total (8125ms).
[09:55:43.770] <TB2> INFO: Test took 8980ms.
[09:55:44.068] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:55:44.096] <TB2> INFO: ----------------------------------------------------------------------
[09:55:44.096] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[09:55:44.097] <TB2> INFO: ----------------------------------------------------------------------
[09:55:44.232] <TB2> INFO: Expecting 231680 events.
[09:55:53.474] <TB2> INFO: 231680 events read in total (8526ms).
[09:55:53.478] <TB2> INFO: Test took 9378ms.
[09:55:53.788] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[09:55:53.788] <TB2> INFO: CalDel: 148 143 151 144 160 145 146 151 152 145 152 146 159 137 126 141
[09:55:53.788] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:55:53.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C0.dat
[09:55:53.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C1.dat
[09:55:53.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C2.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C3.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C4.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C5.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C6.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C7.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C8.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C9.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C10.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C11.dat
[09:55:53.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C12.dat
[09:55:53.792] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C13.dat
[09:55:53.792] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C14.dat
[09:55:53.792] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters_C15.dat
[09:55:53.792] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//tbmParameters_C0a.dat
[09:55:53.792] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:55:53.792] <TB2> INFO: PixTestPretest::doTest() done, duration: 215 seconds
[09:55:53.859] <TB2> INFO: enter test to run
[09:55:53.859] <TB2> INFO: test: fulltest no parameter change
[09:55:53.859] <TB2> INFO: running: fulltest
[09:55:53.859] <TB2> INFO: ######################################################################
[09:55:53.859] <TB2> INFO: PixTestFullTest::doTest()
[09:55:53.859] <TB2> INFO: ######################################################################
[09:55:53.860] <TB2> INFO: ######################################################################
[09:55:53.860] <TB2> INFO: PixTestAlive::doTest()
[09:55:53.860] <TB2> INFO: ######################################################################
[09:55:53.862] <TB2> INFO: ----------------------------------------------------------------------
[09:55:53.862] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:55:53.862] <TB2> INFO: ----------------------------------------------------------------------
[09:55:54.168] <TB2> INFO: Expecting 41600 events.
[09:55:58.590] <TB2> INFO: 41600 events read in total (3706ms).
[09:55:58.590] <TB2> INFO: Test took 4727ms.
[09:55:58.596] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:58.887] <TB2> INFO: PixTestAlive::aliveTest() done
[09:55:58.887] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[09:55:58.888] <TB2> INFO: ----------------------------------------------------------------------
[09:55:58.888] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:55:58.888] <TB2> INFO: ----------------------------------------------------------------------
[09:55:59.192] <TB2> INFO: Expecting 41600 events.
[09:56:02.465] <TB2> INFO: 41600 events read in total (2558ms).
[09:56:02.465] <TB2> INFO: Test took 3576ms.
[09:56:02.468] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:02.468] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:56:02.780] <TB2> INFO: PixTestAlive::maskTest() done
[09:56:02.780] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:56:02.782] <TB2> INFO: ----------------------------------------------------------------------
[09:56:02.782] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:56:02.782] <TB2> INFO: ----------------------------------------------------------------------
[09:56:03.090] <TB2> INFO: Expecting 41600 events.
[09:56:07.252] <TB2> INFO: 41600 events read in total (3446ms).
[09:56:07.253] <TB2> INFO: Test took 4467ms.
[09:56:07.259] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:07.540] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[09:56:07.541] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:56:07.541] <TB2> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:56:07.541] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:07.541] <TB2> INFO: Decoding statistics:
[09:56:07.541] <TB2> INFO: General information:
[09:56:07.541] <TB2> INFO: 16bit words read: 0
[09:56:07.541] <TB2> INFO: valid events total: 0
[09:56:07.541] <TB2> INFO: empty events: 0
[09:56:07.541] <TB2> INFO: valid events with pixels: 0
[09:56:07.541] <TB2> INFO: valid pixel hits: 0
[09:56:07.541] <TB2> INFO: Event errors: 0
[09:56:07.541] <TB2> INFO: start marker: 0
[09:56:07.541] <TB2> INFO: stop marker: 0
[09:56:07.541] <TB2> INFO: overflow: 0
[09:56:07.541] <TB2> INFO: invalid 5bit words: 0
[09:56:07.541] <TB2> INFO: invalid XOR eye diagram: 0
[09:56:07.541] <TB2> INFO: TBM errors: 0
[09:56:07.541] <TB2> INFO: flawed TBM headers: 0
[09:56:07.541] <TB2> INFO: flawed TBM trailers: 0
[09:56:07.541] <TB2> INFO: event ID mismatches: 0
[09:56:07.541] <TB2> INFO: ROC errors: 0
[09:56:07.541] <TB2> INFO: missing ROC header(s): 0
[09:56:07.541] <TB2> INFO: misplaced readback start: 0
[09:56:07.541] <TB2> INFO: Pixel decoding errors: 0
[09:56:07.541] <TB2> INFO: pixel data incomplete: 0
[09:56:07.541] <TB2> INFO: pixel address: 0
[09:56:07.541] <TB2> INFO: pulse height fill bit: 0
[09:56:07.541] <TB2> INFO: buffer corruption: 0
[09:56:07.548] <TB2> INFO: ######################################################################
[09:56:07.548] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:56:07.548] <TB2> INFO: ######################################################################
[09:56:07.550] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:56:07.560] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:56:07.560] <TB2> INFO: run 1 of 1
[09:56:07.868] <TB2> INFO: Expecting 3120000 events.
[09:56:49.510] <TB2> INFO: 849240 events read in total (40927ms).
[09:57:28.822] <TB2> INFO: 1687890 events read in total (80239ms).
[09:58:10.541] <TB2> INFO: 2537460 events read in total (121959ms).
[09:58:39.693] <TB2> INFO: 3120000 events read in total (151110ms).
[09:58:39.745] <TB2> INFO: Test took 152185ms.
[09:58:39.846] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:03.925] <TB2> INFO: PixTestBBMap::doTest() done, duration: 176 seconds
[09:59:03.925] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 64 0 0
[09:59:03.925] <TB2> INFO: separation cut (per ROC): 76 84 82 69 101 82 89 92 84 98 86 74 75 75 65 70
[09:59:03.925] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:03.925] <TB2> INFO: Decoding statistics:
[09:59:03.925] <TB2> INFO: General information:
[09:59:03.925] <TB2> INFO: 16bit words read: 0
[09:59:03.925] <TB2> INFO: valid events total: 0
[09:59:03.925] <TB2> INFO: empty events: 0
[09:59:03.926] <TB2> INFO: valid events with pixels: 0
[09:59:03.926] <TB2> INFO: valid pixel hits: 0
[09:59:03.926] <TB2> INFO: Event errors: 0
[09:59:03.926] <TB2> INFO: start marker: 0
[09:59:03.926] <TB2> INFO: stop marker: 0
[09:59:03.926] <TB2> INFO: overflow: 0
[09:59:03.926] <TB2> INFO: invalid 5bit words: 0
[09:59:03.926] <TB2> INFO: invalid XOR eye diagram: 0
[09:59:03.926] <TB2> INFO: TBM errors: 0
[09:59:03.926] <TB2> INFO: flawed TBM headers: 0
[09:59:03.926] <TB2> INFO: flawed TBM trailers: 0
[09:59:03.926] <TB2> INFO: event ID mismatches: 0
[09:59:03.926] <TB2> INFO: ROC errors: 0
[09:59:03.926] <TB2> INFO: missing ROC header(s): 0
[09:59:03.926] <TB2> INFO: misplaced readback start: 0
[09:59:03.926] <TB2> INFO: Pixel decoding errors: 0
[09:59:03.926] <TB2> INFO: pixel data incomplete: 0
[09:59:03.926] <TB2> INFO: pixel address: 0
[09:59:03.926] <TB2> INFO: pulse height fill bit: 0
[09:59:03.926] <TB2> INFO: buffer corruption: 0
[09:59:03.998] <TB2> INFO: ######################################################################
[09:59:03.998] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:59:03.998] <TB2> INFO: ######################################################################
[09:59:03.998] <TB2> INFO: ----------------------------------------------------------------------
[09:59:03.998] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:59:03.998] <TB2> INFO: ----------------------------------------------------------------------
[09:59:03.998] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:59:04.007] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:59:04.007] <TB2> INFO: run 1 of 1
[09:59:04.346] <TB2> INFO: Expecting 26208000 events.
[09:59:35.716] <TB2> INFO: 863850 events read in total (30655ms).
[10:00:06.064] <TB2> INFO: 1716300 events read in total (61003ms).
[10:00:36.289] <TB2> INFO: 2567250 events read in total (91228ms).
[10:01:06.787] <TB2> INFO: 3415750 events read in total (121726ms).
[10:01:36.157] <TB2> INFO: 4264600 events read in total (151096ms).
[10:02:05.937] <TB2> INFO: 5113550 events read in total (180876ms).
[10:02:35.798] <TB2> INFO: 5959750 events read in total (210737ms).
[10:03:06.141] <TB2> INFO: 6807950 events read in total (241080ms).
[10:03:36.241] <TB2> INFO: 7655050 events read in total (271180ms).
[10:04:07.254] <TB2> INFO: 8499550 events read in total (302193ms).
[10:04:37.743] <TB2> INFO: 9347700 events read in total (332682ms).
[10:05:08.441] <TB2> INFO: 10195250 events read in total (363380ms).
[10:05:38.777] <TB2> INFO: 11041650 events read in total (393716ms).
[10:06:08.781] <TB2> INFO: 11886150 events read in total (423720ms).
[10:06:39.190] <TB2> INFO: 12731950 events read in total (454129ms).
[10:07:08.746] <TB2> INFO: 13571350 events read in total (483685ms).
[10:07:38.322] <TB2> INFO: 14408750 events read in total (513261ms).
[10:08:08.761] <TB2> INFO: 15247100 events read in total (543700ms).
[10:08:38.545] <TB2> INFO: 16083300 events read in total (573484ms).
[10:09:08.587] <TB2> INFO: 16918900 events read in total (603526ms).
[10:09:39.032] <TB2> INFO: 17756200 events read in total (633971ms).
[10:10:09.405] <TB2> INFO: 18591750 events read in total (664344ms).
[10:10:39.508] <TB2> INFO: 19427950 events read in total (694447ms).
[10:11:09.205] <TB2> INFO: 20264950 events read in total (724144ms).
[10:11:39.476] <TB2> INFO: 21099450 events read in total (754415ms).
[10:12:09.642] <TB2> INFO: 21936300 events read in total (784581ms).
[10:12:39.811] <TB2> INFO: 22773550 events read in total (814750ms).
[10:13:06.868] <TB2> INFO: 23611700 events read in total (841807ms).
[10:13:37.611] <TB2> INFO: 24451850 events read in total (872550ms).
[10:14:06.156] <TB2> INFO: 25292800 events read in total (901095ms).
[10:14:34.714] <TB2> INFO: 26145050 events read in total (929653ms).
[10:14:37.373] <TB2> INFO: 26208000 events read in total (932312ms).
[10:14:37.403] <TB2> INFO: Test took 933396ms.
[10:14:37.488] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:37.666] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:39.387] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:40.886] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:42.566] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:44.179] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:45.549] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:46.942] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:48.317] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:49.706] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:51.126] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:52.483] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:53.890] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:55.342] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:56.903] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:14:58.582] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:15:00.225] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:15:01.778] <TB2> INFO: PixTestScurves::scurves() done
[10:15:01.778] <TB2> INFO: Vcal mean: 85.14 84.86 83.47 66.33 94.15 85.21 93.52 79.45 83.74 97.94 81.64 74.64 80.79 83.10 72.73 75.62
[10:15:01.778] <TB2> INFO: Vcal RMS: 5.39 5.19 5.13 4.50 6.43 5.85 5.37 4.63 5.41 6.26 4.97 4.43 4.80 4.35 4.33 4.63
[10:15:01.778] <TB2> INFO: PixTestScurves::fullTest() done, duration: 957 seconds
[10:15:01.778] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:01.778] <TB2> INFO: Decoding statistics:
[10:15:01.778] <TB2> INFO: General information:
[10:15:01.778] <TB2> INFO: 16bit words read: 0
[10:15:01.778] <TB2> INFO: valid events total: 0
[10:15:01.778] <TB2> INFO: empty events: 0
[10:15:01.778] <TB2> INFO: valid events with pixels: 0
[10:15:01.778] <TB2> INFO: valid pixel hits: 0
[10:15:01.778] <TB2> INFO: Event errors: 0
[10:15:01.778] <TB2> INFO: start marker: 0
[10:15:01.778] <TB2> INFO: stop marker: 0
[10:15:01.778] <TB2> INFO: overflow: 0
[10:15:01.778] <TB2> INFO: invalid 5bit words: 0
[10:15:01.778] <TB2> INFO: invalid XOR eye diagram: 0
[10:15:01.778] <TB2> INFO: TBM errors: 0
[10:15:01.778] <TB2> INFO: flawed TBM headers: 0
[10:15:01.778] <TB2> INFO: flawed TBM trailers: 0
[10:15:01.778] <TB2> INFO: event ID mismatches: 0
[10:15:01.778] <TB2> INFO: ROC errors: 0
[10:15:01.778] <TB2> INFO: missing ROC header(s): 0
[10:15:01.778] <TB2> INFO: misplaced readback start: 0
[10:15:01.778] <TB2> INFO: Pixel decoding errors: 0
[10:15:01.778] <TB2> INFO: pixel data incomplete: 0
[10:15:01.778] <TB2> INFO: pixel address: 0
[10:15:01.778] <TB2> INFO: pulse height fill bit: 0
[10:15:01.778] <TB2> INFO: buffer corruption: 0
[10:15:01.848] <TB2> INFO: ######################################################################
[10:15:01.848] <TB2> INFO: PixTestTrim::doTest()
[10:15:01.848] <TB2> INFO: ######################################################################
[10:15:01.849] <TB2> INFO: ----------------------------------------------------------------------
[10:15:01.849] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:15:01.849] <TB2> INFO: ----------------------------------------------------------------------
[10:15:01.929] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:15:01.929] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:15:01.937] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:15:01.937] <TB2> INFO: run 1 of 1
[10:15:02.246] <TB2> INFO: Expecting 6281600 events.
[10:15:41.423] <TB2> INFO: 1057890 events read in total (38461ms).
[10:16:18.844] <TB2> INFO: 2106930 events read in total (75882ms).
[10:16:55.070] <TB2> INFO: 3150490 events read in total (112108ms).
[10:17:32.765] <TB2> INFO: 4200830 events read in total (149803ms).
[10:18:11.308] <TB2> INFO: 5255490 events read in total (188347ms).
[10:18:49.166] <TB2> INFO: 6281600 events read in total (226204ms).
[10:18:49.200] <TB2> INFO: Test took 227262ms.
[10:18:49.249] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:08.582] <TB2> INFO: ROC 0 VthrComp = 89
[10:19:08.582] <TB2> INFO: ROC 1 VthrComp = 89
[10:19:08.583] <TB2> INFO: ROC 2 VthrComp = 88
[10:19:08.583] <TB2> INFO: ROC 3 VthrComp = 74
[10:19:08.583] <TB2> INFO: ROC 4 VthrComp = 99
[10:19:08.583] <TB2> INFO: ROC 5 VthrComp = 89
[10:19:08.583] <TB2> INFO: ROC 6 VthrComp = 98
[10:19:08.583] <TB2> INFO: ROC 7 VthrComp = 85
[10:19:08.583] <TB2> INFO: ROC 8 VthrComp = 86
[10:19:08.583] <TB2> INFO: ROC 9 VthrComp = 98
[10:19:08.583] <TB2> INFO: ROC 10 VthrComp = 85
[10:19:08.583] <TB2> INFO: ROC 11 VthrComp = 80
[10:19:08.583] <TB2> INFO: ROC 12 VthrComp = 83
[10:19:08.584] <TB2> INFO: ROC 13 VthrComp = 90
[10:19:08.584] <TB2> INFO: ROC 14 VthrComp = 78
[10:19:08.584] <TB2> INFO: ROC 15 VthrComp = 80
[10:19:08.584] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:19:08.584] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:19:08.592] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:19:08.592] <TB2> INFO: run 1 of 1
[10:19:08.895] <TB2> INFO: Expecting 6281600 events.
[10:19:43.162] <TB2> INFO: 757360 events read in total (33552ms).
[10:20:15.795] <TB2> INFO: 1512260 events read in total (66185ms).
[10:20:49.636] <TB2> INFO: 2266990 events read in total (100026ms).
[10:21:22.725] <TB2> INFO: 3022260 events read in total (133115ms).
[10:21:55.492] <TB2> INFO: 3772470 events read in total (165882ms).
[10:22:29.019] <TB2> INFO: 4519340 events read in total (199409ms).
[10:23:02.918] <TB2> INFO: 5264480 events read in total (233308ms).
[10:23:37.097] <TB2> INFO: 6009840 events read in total (267487ms).
[10:23:49.988] <TB2> INFO: 6281600 events read in total (280378ms).
[10:23:50.027] <TB2> INFO: Test took 281435ms.
[10:23:50.156] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:13.819] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 59.7017 for pixel 26/79 mean/min/max = 46.4211/33.0672/59.7751
[10:24:13.819] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 58.5028 for pixel 0/22 mean/min/max = 45.5475/32.4434/58.6516
[10:24:13.819] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 59.2148 for pixel 28/72 mean/min/max = 46.1697/32.9715/59.3679
[10:24:13.820] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 55.8366 for pixel 51/71 mean/min/max = 44.7542/33.6718/55.8366
[10:24:13.820] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 60.7136 for pixel 0/1 mean/min/max = 45.9627/31.1905/60.735
[10:24:13.820] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 61.5655 for pixel 0/71 mean/min/max = 46.7605/31.9396/61.5813
[10:24:13.820] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 58.836 for pixel 46/3 mean/min/max = 45.5063/32.1716/58.841
[10:24:13.821] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 57.5859 for pixel 18/52 mean/min/max = 44.8451/31.9433/57.747
[10:24:13.821] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 60.1423 for pixel 22/7 mean/min/max = 45.8662/31.5582/60.1742
[10:24:13.821] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 63.5379 for pixel 11/1 mean/min/max = 47.67/31.5131/63.8269
[10:24:13.821] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 58.1519 for pixel 32/76 mean/min/max = 45.1893/32.0246/58.354
[10:24:13.822] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 56.9086 for pixel 3/13 mean/min/max = 44.7681/32.5559/56.9803
[10:24:13.822] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 59.2215 for pixel 40/73 mean/min/max = 45.9238/32.6128/59.2348
[10:24:13.822] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 56.4048 for pixel 22/10 mean/min/max = 44.6601/32.8899/56.4302
[10:24:13.822] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 57.0697 for pixel 48/30 mean/min/max = 45.9044/34.7335/57.0753
[10:24:13.823] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 58.4465 for pixel 8/62 mean/min/max = 45.6581/32.8336/58.4826
[10:24:13.823] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:13.954] <TB2> INFO: Expecting 514560 events.
[10:24:24.971] <TB2> INFO: 514560 events read in total (10301ms).
[10:24:24.975] <TB2> INFO: Expecting 514560 events.
[10:24:36.062] <TB2> INFO: 514560 events read in total (10509ms).
[10:24:36.068] <TB2> INFO: Expecting 514560 events.
[10:24:46.997] <TB2> INFO: 514560 events read in total (10353ms).
[10:24:47.008] <TB2> INFO: Expecting 514560 events.
[10:24:58.034] <TB2> INFO: 514560 events read in total (10466ms).
[10:24:58.044] <TB2> INFO: Expecting 514560 events.
[10:25:08.890] <TB2> INFO: 514560 events read in total (10275ms).
[10:25:08.902] <TB2> INFO: Expecting 514560 events.
[10:25:19.704] <TB2> INFO: 514560 events read in total (10232ms).
[10:25:19.718] <TB2> INFO: Expecting 514560 events.
[10:25:31.523] <TB2> INFO: 514560 events read in total (11233ms).
[10:25:31.541] <TB2> INFO: Expecting 514560 events.
[10:25:43.947] <TB2> INFO: 514560 events read in total (11858ms).
[10:25:43.971] <TB2> INFO: Expecting 514560 events.
[10:25:54.987] <TB2> INFO: 514560 events read in total (10470ms).
[10:25:55.008] <TB2> INFO: Expecting 514560 events.
[10:26:07.486] <TB2> INFO: 514560 events read in total (11920ms).
[10:26:07.512] <TB2> INFO: Expecting 514560 events.
[10:26:20.035] <TB2> INFO: 514560 events read in total (11972ms).
[10:26:20.064] <TB2> INFO: Expecting 514560 events.
[10:26:30.794] <TB2> INFO: 514560 events read in total (10190ms).
[10:26:30.819] <TB2> INFO: Expecting 514560 events.
[10:26:42.998] <TB2> INFO: 514560 events read in total (11625ms).
[10:26:43.027] <TB2> INFO: Expecting 514560 events.
[10:26:54.781] <TB2> INFO: 514560 events read in total (11199ms).
[10:26:54.812] <TB2> INFO: Expecting 514560 events.
[10:27:05.347] <TB2> INFO: 514560 events read in total (9986ms).
[10:27:05.382] <TB2> INFO: Expecting 514560 events.
[10:27:17.751] <TB2> INFO: 514560 events read in total (11829ms).
[10:27:17.784] <TB2> INFO: Test took 183961ms.
[10:27:18.991] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:27:19.000] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:27:19.000] <TB2> INFO: run 1 of 1
[10:27:19.334] <TB2> INFO: Expecting 6281600 events.
[10:27:52.201] <TB2> INFO: 742480 events read in total (32151ms).
[10:28:24.730] <TB2> INFO: 1483220 events read in total (64680ms).
[10:28:57.808] <TB2> INFO: 2223320 events read in total (97758ms).
[10:29:30.842] <TB2> INFO: 2963840 events read in total (130792ms).
[10:30:04.182] <TB2> INFO: 3699420 events read in total (164132ms).
[10:30:34.886] <TB2> INFO: 4431610 events read in total (194836ms).
[10:31:07.880] <TB2> INFO: 5161920 events read in total (227830ms).
[10:31:41.057] <TB2> INFO: 5890970 events read in total (261007ms).
[10:32:00.221] <TB2> INFO: 6281600 events read in total (280171ms).
[10:32:00.261] <TB2> INFO: Test took 281260ms.
[10:32:00.414] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:25.470] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.128577 .. 52.785635
[10:32:25.565] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 62 (-1/-1) hits flags = 528 (plus default)
[10:32:25.574] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:32:25.574] <TB2> INFO: run 1 of 1
[10:32:25.911] <TB2> INFO: Expecting 2620800 events.
[10:33:03.863] <TB2> INFO: 919110 events read in total (37237ms).
[10:33:41.060] <TB2> INFO: 1837080 events read in total (74434ms).
[10:34:13.633] <TB2> INFO: 2620800 events read in total (107007ms).
[10:34:13.649] <TB2> INFO: Test took 108076ms.
[10:34:13.687] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:29.294] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 11.081204 .. 46.236332
[10:34:29.379] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 56 (-1/-1) hits flags = 528 (plus default)
[10:34:29.389] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:34:29.389] <TB2> INFO: run 1 of 1
[10:34:29.726] <TB2> INFO: Expecting 2329600 events.
[10:35:07.120] <TB2> INFO: 951810 events read in total (36679ms).
[10:35:44.923] <TB2> INFO: 1902180 events read in total (74482ms).
[10:36:01.194] <TB2> INFO: 2329600 events read in total (90753ms).
[10:36:01.213] <TB2> INFO: Test took 91825ms.
[10:36:01.250] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:17.212] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 18.718844 .. 43.073954
[10:36:17.290] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 8 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:36:17.298] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:36:17.298] <TB2> INFO: run 1 of 1
[10:36:17.601] <TB2> INFO: Expecting 1913600 events.
[10:36:55.811] <TB2> INFO: 941080 events read in total (37494ms).
[10:37:32.295] <TB2> INFO: 1880360 events read in total (73978ms).
[10:37:34.220] <TB2> INFO: 1913600 events read in total (75903ms).
[10:37:34.234] <TB2> INFO: Test took 76936ms.
[10:37:34.265] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:48.670] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 22.103242 .. 43.073954
[10:37:48.747] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 12 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:37:48.755] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:37:48.755] <TB2> INFO: run 1 of 1
[10:37:49.060] <TB2> INFO: Expecting 1747200 events.
[10:38:27.410] <TB2> INFO: 915420 events read in total (37634ms).
[10:38:58.977] <TB2> INFO: 1747200 events read in total (69202ms).
[10:38:59.000] <TB2> INFO: Test took 70246ms.
[10:38:59.035] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:12.396] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:39:12.396] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:39:12.405] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:39:12.405] <TB2> INFO: run 1 of 1
[10:39:12.739] <TB2> INFO: Expecting 1705600 events.
[10:39:48.154] <TB2> INFO: 880560 events read in total (34699ms).
[10:40:20.876] <TB2> INFO: 1705600 events read in total (67421ms).
[10:40:20.895] <TB2> INFO: Test took 68491ms.
[10:40:20.927] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:35.415] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:40:35.415] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:40:35.415] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:40:35.415] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:40:35.416] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:40:35.416] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:40:35.416] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:40:35.416] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:40:35.416] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:40:35.416] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:40:35.416] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:40:35.417] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:40:35.417] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:40:35.417] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:40:35.417] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:40:35.417] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:40:35.417] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:40:35.424] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:40:35.431] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:40:35.438] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:40:35.445] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:40:35.451] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:40:35.457] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:40:35.463] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:40:35.470] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:40:35.476] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:40:35.482] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:40:35.488] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:40:35.495] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:40:35.501] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:40:35.507] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:40:35.514] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:40:35.520] <TB2> INFO: PixTestTrim::trimTest() done
[10:40:35.520] <TB2> INFO: vtrim: 90 103 104 93 106 96 110 111 105 115 114 109 105 98 96 102
[10:40:35.520] <TB2> INFO: vthrcomp: 89 89 88 74 99 89 98 85 86 98 85 80 83 90 78 80
[10:40:35.520] <TB2> INFO: vcal mean: 35.03 34.97 35.00 35.00 34.99 34.93 35.06 34.98 34.96 34.93 35.03 34.98 35.00 34.98 34.99 34.99
[10:40:35.520] <TB2> INFO: vcal RMS: 0.82 0.83 0.80 0.71 0.99 0.85 0.82 0.78 0.83 0.92 0.84 0.77 0.83 0.79 0.73 0.82
[10:40:35.520] <TB2> INFO: bits mean: 8.92 9.27 9.24 9.01 9.32 8.78 9.40 9.87 9.69 9.55 9.83 9.82 9.18 9.45 8.72 9.42
[10:40:35.520] <TB2> INFO: bits RMS: 2.79 2.75 2.64 2.71 2.87 2.97 2.69 2.57 2.64 2.56 2.60 2.50 2.75 2.63 2.59 2.62
[10:40:35.526] <TB2> INFO: ----------------------------------------------------------------------
[10:40:35.526] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:40:35.526] <TB2> INFO: ----------------------------------------------------------------------
[10:40:35.528] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:40:35.536] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:40:35.536] <TB2> INFO: run 1 of 1
[10:40:35.842] <TB2> INFO: Expecting 4160000 events.
[10:41:18.806] <TB2> INFO: 900655 events read in total (42249ms).
[10:42:01.041] <TB2> INFO: 1795725 events read in total (84484ms).
[10:42:39.847] <TB2> INFO: 2684550 events read in total (123291ms).
[10:43:19.838] <TB2> INFO: 3571860 events read in total (163281ms).
[10:43:46.843] <TB2> INFO: 4160000 events read in total (190286ms).
[10:43:46.896] <TB2> INFO: Test took 191360ms.
[10:43:47.047] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:16.811] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 163 (-1/-1) hits flags = 528 (plus default)
[10:44:16.820] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:44:16.824] <TB2> INFO: run 1 of 1
[10:44:17.129] <TB2> INFO: Expecting 3411200 events.
[10:44:59.679] <TB2> INFO: 938790 events read in total (41834ms).
[10:45:40.093] <TB2> INFO: 1868680 events read in total (82248ms).
[10:46:19.717] <TB2> INFO: 2790990 events read in total (121873ms).
[10:46:47.508] <TB2> INFO: 3411200 events read in total (149663ms).
[10:46:47.549] <TB2> INFO: Test took 150725ms.
[10:46:47.635] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:47:13.449] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[10:47:13.458] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:47:13.458] <TB2> INFO: run 1 of 1
[10:47:13.765] <TB2> INFO: Expecting 3182400 events.
[10:47:57.544] <TB2> INFO: 967125 events read in total (43063ms).
[10:48:38.499] <TB2> INFO: 1922795 events read in total (84018ms).
[10:49:19.318] <TB2> INFO: 2872190 events read in total (124837ms).
[10:49:34.126] <TB2> INFO: 3182400 events read in total (139645ms).
[10:49:34.156] <TB2> INFO: Test took 140699ms.
[10:49:34.237] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:58.829] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[10:49:58.838] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:49:58.838] <TB2> INFO: run 1 of 1
[10:49:59.142] <TB2> INFO: Expecting 3182400 events.
[10:50:42.168] <TB2> INFO: 966805 events read in total (42311ms).
[10:51:23.504] <TB2> INFO: 1921975 events read in total (83647ms).
[10:52:03.161] <TB2> INFO: 2870870 events read in total (123304ms).
[10:52:16.548] <TB2> INFO: 3182400 events read in total (136691ms).
[10:52:16.579] <TB2> INFO: Test took 137741ms.
[10:52:16.647] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:41.098] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:52:41.106] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:52:41.106] <TB2> INFO: run 1 of 1
[10:52:41.410] <TB2> INFO: Expecting 3140800 events.
[10:53:25.203] <TB2> INFO: 972270 events read in total (43077ms).
[10:54:07.098] <TB2> INFO: 1931980 events read in total (84972ms).
[10:54:49.384] <TB2> INFO: 2886145 events read in total (127258ms).
[10:55:00.342] <TB2> INFO: 3140800 events read in total (138216ms).
[10:55:00.372] <TB2> INFO: Test took 139266ms.
[10:55:00.439] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:25.549] <TB2> INFO: PixTestTrim::trimBitTest() done
[10:55:25.550] <TB2> INFO: PixTestTrim::doTest() done, duration: 2423 seconds
[10:55:25.550] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:25.550] <TB2> INFO: Decoding statistics:
[10:55:25.550] <TB2> INFO: General information:
[10:55:25.550] <TB2> INFO: 16bit words read: 0
[10:55:25.550] <TB2> INFO: valid events total: 0
[10:55:25.550] <TB2> INFO: empty events: 0
[10:55:25.550] <TB2> INFO: valid events with pixels: 0
[10:55:25.550] <TB2> INFO: valid pixel hits: 0
[10:55:25.550] <TB2> INFO: Event errors: 0
[10:55:25.550] <TB2> INFO: start marker: 0
[10:55:25.550] <TB2> INFO: stop marker: 0
[10:55:25.550] <TB2> INFO: overflow: 0
[10:55:25.550] <TB2> INFO: invalid 5bit words: 0
[10:55:25.550] <TB2> INFO: invalid XOR eye diagram: 0
[10:55:25.550] <TB2> INFO: TBM errors: 0
[10:55:25.550] <TB2> INFO: flawed TBM headers: 0
[10:55:25.550] <TB2> INFO: flawed TBM trailers: 0
[10:55:25.550] <TB2> INFO: event ID mismatches: 0
[10:55:25.550] <TB2> INFO: ROC errors: 0
[10:55:25.550] <TB2> INFO: missing ROC header(s): 0
[10:55:25.550] <TB2> INFO: misplaced readback start: 0
[10:55:25.550] <TB2> INFO: Pixel decoding errors: 0
[10:55:25.550] <TB2> INFO: pixel data incomplete: 0
[10:55:25.550] <TB2> INFO: pixel address: 0
[10:55:25.550] <TB2> INFO: pulse height fill bit: 0
[10:55:25.550] <TB2> INFO: buffer corruption: 0
[10:55:26.272] <TB2> INFO: ######################################################################
[10:55:26.272] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:55:26.272] <TB2> INFO: ######################################################################
[10:55:26.587] <TB2> INFO: Expecting 41600 events.
[10:55:30.922] <TB2> INFO: 41600 events read in total (3619ms).
[10:55:30.923] <TB2> INFO: Test took 4650ms.
[10:55:30.929] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:31.573] <TB2> INFO: Expecting 41600 events.
[10:55:36.210] <TB2> INFO: 41600 events read in total (3922ms).
[10:55:36.211] <TB2> INFO: Test took 5023ms.
[10:55:36.217] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:36.570] <TB2> INFO: Expecting 41600 events.
[10:55:41.004] <TB2> INFO: 41600 events read in total (3718ms).
[10:55:41.005] <TB2> INFO: Test took 4757ms.
[10:55:41.011] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:41.350] <TB2> INFO: Expecting 2560 events.
[10:55:42.415] <TB2> INFO: 2560 events read in total (350ms).
[10:55:42.415] <TB2> INFO: Test took 1398ms.
[10:55:42.829] <TB2> INFO: Expecting 2560 events.
[10:55:43.890] <TB2> INFO: 2560 events read in total (346ms).
[10:55:43.890] <TB2> INFO: Test took 1476ms.
[10:55:44.305] <TB2> INFO: Expecting 2560 events.
[10:55:45.380] <TB2> INFO: 2560 events read in total (360ms).
[10:55:45.380] <TB2> INFO: Test took 1489ms.
[10:55:45.794] <TB2> INFO: Expecting 2560 events.
[10:55:46.869] <TB2> INFO: 2560 events read in total (360ms).
[10:55:46.870] <TB2> INFO: Test took 1490ms.
[10:55:47.283] <TB2> INFO: Expecting 2560 events.
[10:55:48.348] <TB2> INFO: 2560 events read in total (349ms).
[10:55:48.348] <TB2> INFO: Test took 1478ms.
[10:55:48.761] <TB2> INFO: Expecting 2560 events.
[10:55:49.833] <TB2> INFO: 2560 events read in total (352ms).
[10:55:49.833] <TB2> INFO: Test took 1484ms.
[10:55:50.247] <TB2> INFO: Expecting 2560 events.
[10:55:51.336] <TB2> INFO: 2560 events read in total (374ms).
[10:55:51.336] <TB2> INFO: Test took 1503ms.
[10:55:51.751] <TB2> INFO: Expecting 2560 events.
[10:55:52.811] <TB2> INFO: 2560 events read in total (345ms).
[10:55:52.811] <TB2> INFO: Test took 1474ms.
[10:55:53.225] <TB2> INFO: Expecting 2560 events.
[10:55:54.292] <TB2> INFO: 2560 events read in total (351ms).
[10:55:54.292] <TB2> INFO: Test took 1480ms.
[10:55:54.706] <TB2> INFO: Expecting 2560 events.
[10:55:55.769] <TB2> INFO: 2560 events read in total (348ms).
[10:55:55.770] <TB2> INFO: Test took 1478ms.
[10:55:56.184] <TB2> INFO: Expecting 2560 events.
[10:55:57.245] <TB2> INFO: 2560 events read in total (346ms).
[10:55:57.245] <TB2> INFO: Test took 1475ms.
[10:55:57.659] <TB2> INFO: Expecting 2560 events.
[10:55:58.725] <TB2> INFO: 2560 events read in total (350ms).
[10:55:58.725] <TB2> INFO: Test took 1480ms.
[10:55:59.139] <TB2> INFO: Expecting 2560 events.
[10:56:00.210] <TB2> INFO: 2560 events read in total (356ms).
[10:56:00.210] <TB2> INFO: Test took 1485ms.
[10:56:00.623] <TB2> INFO: Expecting 2560 events.
[10:56:01.690] <TB2> INFO: 2560 events read in total (351ms).
[10:56:01.690] <TB2> INFO: Test took 1480ms.
[10:56:02.103] <TB2> INFO: Expecting 2560 events.
[10:56:03.164] <TB2> INFO: 2560 events read in total (345ms).
[10:56:03.165] <TB2> INFO: Test took 1475ms.
[10:56:03.578] <TB2> INFO: Expecting 2560 events.
[10:56:04.641] <TB2> INFO: 2560 events read in total (347ms).
[10:56:04.641] <TB2> INFO: Test took 1476ms.
[10:56:04.644] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:56:05.056] <TB2> INFO: Expecting 655360 events.
[10:56:21.525] <TB2> INFO: 655360 events read in total (15753ms).
[10:56:21.534] <TB2> INFO: Expecting 655360 events.
[10:56:37.388] <TB2> INFO: 655360 events read in total (15327ms).
[10:56:37.401] <TB2> INFO: Expecting 655360 events.
[10:56:51.233] <TB2> INFO: 655360 events read in total (13305ms).
[10:56:51.250] <TB2> INFO: Expecting 655360 events.
[10:57:06.912] <TB2> INFO: 655360 events read in total (15134ms).
[10:57:06.932] <TB2> INFO: Expecting 655360 events.
[10:57:22.582] <TB2> INFO: 655360 events read in total (15122ms).
[10:57:22.604] <TB2> INFO: Expecting 655360 events.
[10:57:36.799] <TB2> INFO: 655360 events read in total (13668ms).
[10:57:36.831] <TB2> INFO: Expecting 655360 events.
[10:57:52.354] <TB2> INFO: 655360 events read in total (14996ms).
[10:57:52.384] <TB2> INFO: Expecting 655360 events.
[10:58:05.985] <TB2> INFO: 655360 events read in total (13074ms).
[10:58:06.025] <TB2> INFO: Expecting 655360 events.
[10:58:20.108] <TB2> INFO: 655360 events read in total (13556ms).
[10:58:20.162] <TB2> INFO: Expecting 655360 events.
[10:58:34.044] <TB2> INFO: 655360 events read in total (13355ms).
[10:58:34.084] <TB2> INFO: Expecting 655360 events.
[10:58:49.967] <TB2> INFO: 655360 events read in total (15356ms).
[10:58:50.020] <TB2> INFO: Expecting 655360 events.
[10:59:06.051] <TB2> INFO: 655360 events read in total (15504ms).
[10:59:06.098] <TB2> INFO: Expecting 655360 events.
[10:59:20.030] <TB2> INFO: 655360 events read in total (13404ms).
[10:59:20.080] <TB2> INFO: Expecting 655360 events.
[10:59:36.050] <TB2> INFO: 655360 events read in total (15442ms).
[10:59:36.108] <TB2> INFO: Expecting 655360 events.
[10:59:51.775] <TB2> INFO: 655360 events read in total (15139ms).
[10:59:51.832] <TB2> INFO: Expecting 655360 events.
[11:00:06.396] <TB2> INFO: 655360 events read in total (14037ms).
[11:00:06.466] <TB2> INFO: Test took 241822ms.
[11:00:06.545] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:06.855] <TB2> INFO: Expecting 655360 events.
[11:00:23.624] <TB2> INFO: 655360 events read in total (16053ms).
[11:00:23.635] <TB2> INFO: Expecting 655360 events.
[11:00:38.787] <TB2> INFO: 655360 events read in total (14625ms).
[11:00:38.799] <TB2> INFO: Expecting 655360 events.
[11:00:55.064] <TB2> INFO: 655360 events read in total (15737ms).
[11:00:55.080] <TB2> INFO: Expecting 655360 events.
[11:01:08.823] <TB2> INFO: 655360 events read in total (13216ms).
[11:01:08.842] <TB2> INFO: Expecting 655360 events.
[11:01:22.673] <TB2> INFO: 655360 events read in total (13304ms).
[11:01:22.695] <TB2> INFO: Expecting 655360 events.
[11:01:37.900] <TB2> INFO: 655360 events read in total (14678ms).
[11:01:37.924] <TB2> INFO: Expecting 655360 events.
[11:01:52.438] <TB2> INFO: 655360 events read in total (13986ms).
[11:01:52.467] <TB2> INFO: Expecting 655360 events.
[11:02:07.159] <TB2> INFO: 655360 events read in total (14165ms).
[11:02:07.191] <TB2> INFO: Expecting 655360 events.
[11:02:22.541] <TB2> INFO: 655360 events read in total (14823ms).
[11:02:22.579] <TB2> INFO: Expecting 655360 events.
[11:02:38.770] <TB2> INFO: 655360 events read in total (15663ms).
[11:02:38.812] <TB2> INFO: Expecting 655360 events.
[11:02:54.819] <TB2> INFO: 655360 events read in total (15479ms).
[11:02:54.865] <TB2> INFO: Expecting 655360 events.
[11:03:10.669] <TB2> INFO: 655360 events read in total (15276ms).
[11:03:10.719] <TB2> INFO: Expecting 655360 events.
[11:03:26.313] <TB2> INFO: 655360 events read in total (15067ms).
[11:03:26.374] <TB2> INFO: Expecting 655360 events.
[11:03:41.972] <TB2> INFO: 655360 events read in total (15071ms).
[11:03:42.027] <TB2> INFO: Expecting 655360 events.
[11:03:57.215] <TB2> INFO: 655360 events read in total (14660ms).
[11:03:57.273] <TB2> INFO: Expecting 655360 events.
[11:04:13.223] <TB2> INFO: 655360 events read in total (15423ms).
[11:04:13.287] <TB2> INFO: Test took 246742ms.
[11:04:13.481] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.488] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.495] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.502] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.509] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.516] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.524] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.531] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.538] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.545] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.552] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.559] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.566] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.573] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.580] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[11:04:13.586] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.593] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:04:13.622] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:04:13.632] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:04:13.632] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:04:13.633] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:04:13.633] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:04:13.633] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:04:13.633] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:04:13.633] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:04:13.633] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:04:13.633] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:04:13.633] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:04:13.634] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:04:13.634] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:04:13.634] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:04:13.634] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:04:13.634] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:04:13.950] <TB2> INFO: Expecting 41600 events.
[11:04:17.989] <TB2> INFO: 41600 events read in total (3323ms).
[11:04:17.990] <TB2> INFO: Test took 4353ms.
[11:04:18.538] <TB2> INFO: Expecting 41600 events.
[11:04:22.662] <TB2> INFO: 41600 events read in total (3408ms).
[11:04:22.663] <TB2> INFO: Test took 4440ms.
[11:04:23.207] <TB2> INFO: Expecting 41600 events.
[11:04:27.185] <TB2> INFO: 41600 events read in total (3262ms).
[11:04:27.186] <TB2> INFO: Test took 4281ms.
[11:04:27.425] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:27.556] <TB2> INFO: Expecting 2560 events.
[11:04:28.624] <TB2> INFO: 2560 events read in total (352ms).
[11:04:28.625] <TB2> INFO: Test took 1200ms.
[11:04:28.627] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:29.039] <TB2> INFO: Expecting 2560 events.
[11:04:30.107] <TB2> INFO: 2560 events read in total (352ms).
[11:04:30.107] <TB2> INFO: Test took 1480ms.
[11:04:30.109] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:30.522] <TB2> INFO: Expecting 2560 events.
[11:04:31.592] <TB2> INFO: 2560 events read in total (354ms).
[11:04:31.592] <TB2> INFO: Test took 1483ms.
[11:04:31.594] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:32.006] <TB2> INFO: Expecting 2560 events.
[11:04:33.075] <TB2> INFO: 2560 events read in total (353ms).
[11:04:33.075] <TB2> INFO: Test took 1481ms.
[11:04:33.078] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:33.490] <TB2> INFO: Expecting 2560 events.
[11:04:34.566] <TB2> INFO: 2560 events read in total (360ms).
[11:04:34.567] <TB2> INFO: Test took 1489ms.
[11:04:34.569] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:34.982] <TB2> INFO: Expecting 2560 events.
[11:04:36.051] <TB2> INFO: 2560 events read in total (353ms).
[11:04:36.051] <TB2> INFO: Test took 1482ms.
[11:04:36.053] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:36.466] <TB2> INFO: Expecting 2560 events.
[11:04:37.536] <TB2> INFO: 2560 events read in total (354ms).
[11:04:37.536] <TB2> INFO: Test took 1483ms.
[11:04:37.538] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:37.951] <TB2> INFO: Expecting 2560 events.
[11:04:39.021] <TB2> INFO: 2560 events read in total (354ms).
[11:04:39.021] <TB2> INFO: Test took 1483ms.
[11:04:39.024] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:39.435] <TB2> INFO: Expecting 2560 events.
[11:04:40.519] <TB2> INFO: 2560 events read in total (368ms).
[11:04:40.519] <TB2> INFO: Test took 1495ms.
[11:04:40.521] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:40.935] <TB2> INFO: Expecting 2560 events.
[11:04:42.003] <TB2> INFO: 2560 events read in total (352ms).
[11:04:42.004] <TB2> INFO: Test took 1483ms.
[11:04:42.007] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:42.418] <TB2> INFO: Expecting 2560 events.
[11:04:43.486] <TB2> INFO: 2560 events read in total (352ms).
[11:04:43.487] <TB2> INFO: Test took 1480ms.
[11:04:43.489] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:43.901] <TB2> INFO: Expecting 2560 events.
[11:04:44.971] <TB2> INFO: 2560 events read in total (354ms).
[11:04:44.971] <TB2> INFO: Test took 1482ms.
[11:04:44.974] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:45.385] <TB2> INFO: Expecting 2560 events.
[11:04:46.454] <TB2> INFO: 2560 events read in total (353ms).
[11:04:46.454] <TB2> INFO: Test took 1481ms.
[11:04:46.457] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:46.870] <TB2> INFO: Expecting 2560 events.
[11:04:47.933] <TB2> INFO: 2560 events read in total (348ms).
[11:04:47.933] <TB2> INFO: Test took 1476ms.
[11:04:47.935] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:48.348] <TB2> INFO: Expecting 2560 events.
[11:04:49.411] <TB2> INFO: 2560 events read in total (348ms).
[11:04:49.412] <TB2> INFO: Test took 1477ms.
[11:04:49.413] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:49.826] <TB2> INFO: Expecting 2560 events.
[11:04:50.896] <TB2> INFO: 2560 events read in total (354ms).
[11:04:50.896] <TB2> INFO: Test took 1483ms.
[11:04:50.898] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:51.312] <TB2> INFO: Expecting 2560 events.
[11:04:52.381] <TB2> INFO: 2560 events read in total (354ms).
[11:04:52.382] <TB2> INFO: Test took 1484ms.
[11:04:52.384] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:52.796] <TB2> INFO: Expecting 2560 events.
[11:04:53.879] <TB2> INFO: 2560 events read in total (367ms).
[11:04:53.880] <TB2> INFO: Test took 1496ms.
[11:04:53.881] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:54.294] <TB2> INFO: Expecting 2560 events.
[11:04:55.357] <TB2> INFO: 2560 events read in total (347ms).
[11:04:55.357] <TB2> INFO: Test took 1476ms.
[11:04:55.358] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:55.772] <TB2> INFO: Expecting 2560 events.
[11:04:56.842] <TB2> INFO: 2560 events read in total (354ms).
[11:04:56.842] <TB2> INFO: Test took 1484ms.
[11:04:56.845] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:57.257] <TB2> INFO: Expecting 2560 events.
[11:04:58.340] <TB2> INFO: 2560 events read in total (368ms).
[11:04:58.340] <TB2> INFO: Test took 1495ms.
[11:04:58.342] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:04:58.755] <TB2> INFO: Expecting 2560 events.
[11:04:59.816] <TB2> INFO: 2560 events read in total (346ms).
[11:04:59.816] <TB2> INFO: Test took 1474ms.
[11:04:59.820] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:00.231] <TB2> INFO: Expecting 2560 events.
[11:05:01.302] <TB2> INFO: 2560 events read in total (355ms).
[11:05:01.302] <TB2> INFO: Test took 1483ms.
[11:05:01.304] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:01.717] <TB2> INFO: Expecting 2560 events.
[11:05:02.810] <TB2> INFO: 2560 events read in total (377ms).
[11:05:02.811] <TB2> INFO: Test took 1508ms.
[11:05:02.816] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:03.225] <TB2> INFO: Expecting 2560 events.
[11:05:04.289] <TB2> INFO: 2560 events read in total (348ms).
[11:05:04.290] <TB2> INFO: Test took 1475ms.
[11:05:04.291] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:04.705] <TB2> INFO: Expecting 2560 events.
[11:05:05.781] <TB2> INFO: 2560 events read in total (360ms).
[11:05:05.781] <TB2> INFO: Test took 1490ms.
[11:05:05.784] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:06.196] <TB2> INFO: Expecting 2560 events.
[11:05:07.265] <TB2> INFO: 2560 events read in total (353ms).
[11:05:07.265] <TB2> INFO: Test took 1481ms.
[11:05:07.268] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:07.680] <TB2> INFO: Expecting 2560 events.
[11:05:08.764] <TB2> INFO: 2560 events read in total (368ms).
[11:05:08.765] <TB2> INFO: Test took 1497ms.
[11:05:08.768] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:09.181] <TB2> INFO: Expecting 2560 events.
[11:05:10.243] <TB2> INFO: 2560 events read in total (346ms).
[11:05:10.244] <TB2> INFO: Test took 1477ms.
[11:05:10.246] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:10.658] <TB2> INFO: Expecting 2560 events.
[11:05:11.726] <TB2> INFO: 2560 events read in total (352ms).
[11:05:11.726] <TB2> INFO: Test took 1480ms.
[11:05:11.729] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:12.142] <TB2> INFO: Expecting 2560 events.
[11:05:13.212] <TB2> INFO: 2560 events read in total (355ms).
[11:05:13.213] <TB2> INFO: Test took 1484ms.
[11:05:13.216] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:13.628] <TB2> INFO: Expecting 2560 events.
[11:05:14.693] <TB2> INFO: 2560 events read in total (350ms).
[11:05:14.693] <TB2> INFO: Test took 1477ms.
[11:05:15.301] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 589 seconds
[11:05:15.302] <TB2> INFO: PH scale (per ROC): 80 78 92 85 85 79 79 77 78 75 78 94 80 83 92 86
[11:05:15.302] <TB2> INFO: PH offset (per ROC): 159 153 150 147 158 158 169 149 155 166 176 145 151 144 144 141
[11:05:15.308] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:15.308] <TB2> INFO: Decoding statistics:
[11:05:15.308] <TB2> INFO: General information:
[11:05:15.308] <TB2> INFO: 16bit words read: 91924
[11:05:15.308] <TB2> INFO: valid events total: 10240
[11:05:15.308] <TB2> INFO: empty events: 7739
[11:05:15.308] <TB2> INFO: valid events with pixels: 2501
[11:05:15.309] <TB2> INFO: valid pixel hits: 2501
[11:05:15.309] <TB2> INFO: Event errors: 0
[11:05:15.309] <TB2> INFO: start marker: 0
[11:05:15.309] <TB2> INFO: stop marker: 0
[11:05:15.309] <TB2> INFO: overflow: 0
[11:05:15.309] <TB2> INFO: invalid 5bit words: 0
[11:05:15.309] <TB2> INFO: invalid XOR eye diagram: 0
[11:05:15.309] <TB2> INFO: TBM errors: 0
[11:05:15.309] <TB2> INFO: flawed TBM headers: 0
[11:05:15.309] <TB2> INFO: flawed TBM trailers: 0
[11:05:15.309] <TB2> INFO: event ID mismatches: 0
[11:05:15.309] <TB2> INFO: ROC errors: 0
[11:05:15.309] <TB2> INFO: missing ROC header(s): 0
[11:05:15.309] <TB2> INFO: misplaced readback start: 0
[11:05:15.309] <TB2> INFO: Pixel decoding errors: 0
[11:05:15.309] <TB2> INFO: pixel data incomplete: 0
[11:05:15.309] <TB2> INFO: pixel address: 0
[11:05:15.309] <TB2> INFO: pulse height fill bit: 0
[11:05:15.309] <TB2> INFO: buffer corruption: 0
[11:05:15.480] <TB2> INFO: ######################################################################
[11:05:15.480] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:05:15.480] <TB2> INFO: ######################################################################
[11:05:15.491] <TB2> INFO: scanning low vcal = 10
[11:05:15.807] <TB2> INFO: Expecting 41600 events.
[11:05:19.787] <TB2> INFO: 41600 events read in total (3265ms).
[11:05:19.788] <TB2> INFO: Test took 4297ms.
[11:05:19.797] <TB2> INFO: scanning low vcal = 20
[11:05:20.201] <TB2> INFO: Expecting 41600 events.
[11:05:24.136] <TB2> INFO: 41600 events read in total (3219ms).
[11:05:24.136] <TB2> INFO: Test took 4340ms.
[11:05:24.138] <TB2> INFO: scanning low vcal = 30
[11:05:24.549] <TB2> INFO: Expecting 41600 events.
[11:05:28.404] <TB2> INFO: 41600 events read in total (3139ms).
[11:05:28.405] <TB2> INFO: Test took 4267ms.
[11:05:28.408] <TB2> INFO: scanning low vcal = 40
[11:05:28.809] <TB2> INFO: Expecting 41600 events.
[11:05:33.402] <TB2> INFO: 41600 events read in total (3877ms).
[11:05:33.403] <TB2> INFO: Test took 4995ms.
[11:05:33.405] <TB2> INFO: scanning low vcal = 50
[11:05:33.757] <TB2> INFO: Expecting 41600 events.
[11:05:38.327] <TB2> INFO: 41600 events read in total (3854ms).
[11:05:38.328] <TB2> INFO: Test took 4923ms.
[11:05:38.332] <TB2> INFO: scanning low vcal = 60
[11:05:38.674] <TB2> INFO: Expecting 41600 events.
[11:05:43.026] <TB2> INFO: 41600 events read in total (3637ms).
[11:05:43.027] <TB2> INFO: Test took 4695ms.
[11:05:43.029] <TB2> INFO: scanning low vcal = 70
[11:05:43.375] <TB2> INFO: Expecting 41600 events.
[11:05:47.860] <TB2> INFO: 41600 events read in total (3769ms).
[11:05:47.861] <TB2> INFO: Test took 4832ms.
[11:05:47.863] <TB2> INFO: scanning low vcal = 80
[11:05:48.199] <TB2> INFO: Expecting 41600 events.
[11:05:52.662] <TB2> INFO: 41600 events read in total (3747ms).
[11:05:52.663] <TB2> INFO: Test took 4800ms.
[11:05:52.667] <TB2> INFO: scanning low vcal = 90
[11:05:53.003] <TB2> INFO: Expecting 41600 events.
[11:05:57.465] <TB2> INFO: 41600 events read in total (3746ms).
[11:05:57.466] <TB2> INFO: Test took 4799ms.
[11:05:57.469] <TB2> INFO: scanning low vcal = 100
[11:05:57.813] <TB2> INFO: Expecting 41600 events.
[11:06:02.579] <TB2> INFO: 41600 events read in total (4050ms).
[11:06:02.579] <TB2> INFO: Test took 5110ms.
[11:06:02.582] <TB2> INFO: scanning low vcal = 110
[11:06:02.932] <TB2> INFO: Expecting 41600 events.
[11:06:07.448] <TB2> INFO: 41600 events read in total (3801ms).
[11:06:07.448] <TB2> INFO: Test took 4866ms.
[11:06:07.451] <TB2> INFO: scanning low vcal = 120
[11:06:07.799] <TB2> INFO: Expecting 41600 events.
[11:06:12.218] <TB2> INFO: 41600 events read in total (3703ms).
[11:06:12.218] <TB2> INFO: Test took 4767ms.
[11:06:12.221] <TB2> INFO: scanning low vcal = 130
[11:06:12.563] <TB2> INFO: Expecting 41600 events.
[11:06:16.988] <TB2> INFO: 41600 events read in total (3709ms).
[11:06:16.989] <TB2> INFO: Test took 4768ms.
[11:06:16.991] <TB2> INFO: scanning low vcal = 140
[11:06:17.347] <TB2> INFO: Expecting 41600 events.
[11:06:21.964] <TB2> INFO: 41600 events read in total (3901ms).
[11:06:21.964] <TB2> INFO: Test took 4973ms.
[11:06:21.967] <TB2> INFO: scanning low vcal = 150
[11:06:22.316] <TB2> INFO: Expecting 41600 events.
[11:06:26.679] <TB2> INFO: 41600 events read in total (3648ms).
[11:06:26.680] <TB2> INFO: Test took 4713ms.
[11:06:26.683] <TB2> INFO: scanning low vcal = 160
[11:06:27.027] <TB2> INFO: Expecting 41600 events.
[11:06:31.479] <TB2> INFO: 41600 events read in total (3736ms).
[11:06:31.479] <TB2> INFO: Test took 4796ms.
[11:06:31.482] <TB2> INFO: scanning low vcal = 170
[11:06:31.821] <TB2> INFO: Expecting 41600 events.
[11:06:36.502] <TB2> INFO: 41600 events read in total (3967ms).
[11:06:36.503] <TB2> INFO: Test took 5021ms.
[11:06:36.507] <TB2> INFO: scanning low vcal = 180
[11:06:36.885] <TB2> INFO: Expecting 41600 events.
[11:06:41.265] <TB2> INFO: 41600 events read in total (3664ms).
[11:06:41.265] <TB2> INFO: Test took 4758ms.
[11:06:41.267] <TB2> INFO: scanning low vcal = 190
[11:06:41.619] <TB2> INFO: Expecting 41600 events.
[11:06:46.126] <TB2> INFO: 41600 events read in total (3791ms).
[11:06:46.126] <TB2> INFO: Test took 4859ms.
[11:06:46.129] <TB2> INFO: scanning low vcal = 200
[11:06:46.486] <TB2> INFO: Expecting 41600 events.
[11:06:51.123] <TB2> INFO: 41600 events read in total (3921ms).
[11:06:51.124] <TB2> INFO: Test took 4995ms.
[11:06:51.127] <TB2> INFO: scanning low vcal = 210
[11:06:51.481] <TB2> INFO: Expecting 41600 events.
[11:06:55.803] <TB2> INFO: 41600 events read in total (3606ms).
[11:06:55.804] <TB2> INFO: Test took 4677ms.
[11:06:55.806] <TB2> INFO: scanning low vcal = 220
[11:06:56.159] <TB2> INFO: Expecting 41600 events.
[11:07:00.561] <TB2> INFO: 41600 events read in total (3686ms).
[11:07:00.562] <TB2> INFO: Test took 4756ms.
[11:07:00.565] <TB2> INFO: scanning low vcal = 230
[11:07:00.904] <TB2> INFO: Expecting 41600 events.
[11:07:05.389] <TB2> INFO: 41600 events read in total (3769ms).
[11:07:05.389] <TB2> INFO: Test took 4824ms.
[11:07:05.393] <TB2> INFO: scanning low vcal = 240
[11:07:05.741] <TB2> INFO: Expecting 41600 events.
[11:07:10.079] <TB2> INFO: 41600 events read in total (3623ms).
[11:07:10.080] <TB2> INFO: Test took 4687ms.
[11:07:10.082] <TB2> INFO: scanning low vcal = 250
[11:07:10.437] <TB2> INFO: Expecting 41600 events.
[11:07:14.917] <TB2> INFO: 41600 events read in total (3765ms).
[11:07:14.917] <TB2> INFO: Test took 4835ms.
[11:07:14.921] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[11:07:15.277] <TB2> INFO: Expecting 41600 events.
[11:07:19.697] <TB2> INFO: 41600 events read in total (3704ms).
[11:07:19.698] <TB2> INFO: Test took 4777ms.
[11:07:19.700] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[11:07:20.048] <TB2> INFO: Expecting 41600 events.
[11:07:24.423] <TB2> INFO: 41600 events read in total (3660ms).
[11:07:24.423] <TB2> INFO: Test took 4723ms.
[11:07:24.426] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[11:07:24.775] <TB2> INFO: Expecting 41600 events.
[11:07:29.253] <TB2> INFO: 41600 events read in total (3763ms).
[11:07:29.253] <TB2> INFO: Test took 4827ms.
[11:07:29.256] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[11:07:29.609] <TB2> INFO: Expecting 41600 events.
[11:07:34.118] <TB2> INFO: 41600 events read in total (3794ms).
[11:07:34.119] <TB2> INFO: Test took 4863ms.
[11:07:34.121] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:07:34.470] <TB2> INFO: Expecting 41600 events.
[11:07:38.842] <TB2> INFO: 41600 events read in total (3656ms).
[11:07:38.843] <TB2> INFO: Test took 4722ms.
[11:07:39.362] <TB2> INFO: PixTestGainPedestal::measure() done
[11:08:15.884] <TB2> INFO: PixTestGainPedestal::fit() done
[11:08:15.884] <TB2> INFO: non-linearity mean: 0.955 0.959 0.954 0.953 0.959 0.954 0.958 0.952 0.956 0.957 0.956 0.955 0.959 0.956 0.963 0.954
[11:08:15.884] <TB2> INFO: non-linearity RMS: 0.006 0.006 0.006 0.005 0.005 0.006 0.006 0.005 0.006 0.005 0.006 0.006 0.006 0.006 0.005 0.006
[11:08:15.884] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:08:15.906] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:08:15.927] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:08:15.949] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:08:15.971] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:08:15.994] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:08:16.018] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:08:16.042] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:08:16.065] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:08:16.089] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:08:16.109] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:08:16.128] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:08:16.148] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:08:16.167] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:08:16.186] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:08:16.210] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:08:16.229] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 180 seconds
[11:08:16.229] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:16.229] <TB2> INFO: Decoding statistics:
[11:08:16.229] <TB2> INFO: General information:
[11:08:16.229] <TB2> INFO: 16bit words read: 2662400
[11:08:16.229] <TB2> INFO: valid events total: 166400
[11:08:16.229] <TB2> INFO: empty events: 0
[11:08:16.229] <TB2> INFO: valid events with pixels: 166400
[11:08:16.229] <TB2> INFO: valid pixel hits: 665590
[11:08:16.229] <TB2> INFO: Event errors: 0
[11:08:16.229] <TB2> INFO: start marker: 0
[11:08:16.229] <TB2> INFO: stop marker: 0
[11:08:16.229] <TB2> INFO: overflow: 0
[11:08:16.229] <TB2> INFO: invalid 5bit words: 0
[11:08:16.229] <TB2> INFO: invalid XOR eye diagram: 0
[11:08:16.229] <TB2> INFO: TBM errors: 0
[11:08:16.229] <TB2> INFO: flawed TBM headers: 0
[11:08:16.229] <TB2> INFO: flawed TBM trailers: 0
[11:08:16.229] <TB2> INFO: event ID mismatches: 0
[11:08:16.229] <TB2> INFO: ROC errors: 0
[11:08:16.229] <TB2> INFO: missing ROC header(s): 0
[11:08:16.229] <TB2> INFO: misplaced readback start: 0
[11:08:16.229] <TB2> INFO: Pixel decoding errors: 0
[11:08:16.229] <TB2> INFO: pixel data incomplete: 0
[11:08:16.229] <TB2> INFO: pixel address: 0
[11:08:16.229] <TB2> INFO: pulse height fill bit: 0
[11:08:16.229] <TB2> INFO: buffer corruption: 0
[11:08:16.236] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C15.dat
[11:08:16.237] <TB2> INFO: ######################################################################
[11:08:16.237] <TB2> INFO: PixTestReadback::doTest()
[11:08:16.237] <TB2> INFO: ######################################################################
[11:08:16.238] <TB2> INFO: PixTestReadback::RES sent once
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C0.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C1.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C2.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C3.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C4.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C5.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C6.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C7.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C8.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C9.dat
[11:08:28.019] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C10.dat
[11:08:28.020] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C11.dat
[11:08:28.020] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C12.dat
[11:08:28.020] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C13.dat
[11:08:28.020] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C14.dat
[11:08:28.020] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C15.dat
[11:08:28.050] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:08:28.050] <TB2> INFO: PixTestReadback::RES sent once
[11:08:39.229] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C0.dat
[11:08:39.229] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C1.dat
[11:08:39.229] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C2.dat
[11:08:39.229] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C3.dat
[11:08:39.229] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C4.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C5.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C6.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C7.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C8.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C9.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C10.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C11.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C12.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C13.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C14.dat
[11:08:39.230] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C15.dat
[11:08:39.265] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:08:39.265] <TB2> INFO: PixTestReadback::RES sent once
[11:08:47.861] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:08:47.861] <TB2> INFO: Vbg will be calibrated using Vd calibration
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.2calibrated Vbg = 1.19734 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 144.8calibrated Vbg = 1.19705 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157calibrated Vbg = 1.19873 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 143calibrated Vbg = 1.20387 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.9calibrated Vbg = 1.2068 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156calibrated Vbg = 1.2088 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156calibrated Vbg = 1.21011 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.9calibrated Vbg = 1.21539 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.6calibrated Vbg = 1.20725 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.1calibrated Vbg = 1.21433 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 150calibrated Vbg = 1.21145 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.5calibrated Vbg = 1.20929 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.8calibrated Vbg = 1.21151 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.7calibrated Vbg = 1.20765 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155calibrated Vbg = 1.20001 :::*/*/*/*/
[11:08:47.861] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 145.6calibrated Vbg = 1.19262 :::*/*/*/*/
[11:08:47.863] <TB2> INFO: PixTestReadback::RES sent once
[11:11:42.067] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C0.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C1.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C2.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C3.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C4.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C5.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C6.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C7.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C8.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C9.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C10.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C11.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C12.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C13.dat
[11:11:42.068] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C14.dat
[11:11:42.069] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2132_FullQualification_2015-12-18_10h43m_1450431782//000_FulltestPxar_m20//readbackCal_C15.dat
[11:11:42.090] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:11:42.091] <TB2> INFO: PixTestReadback::doTest() done
[11:11:42.091] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:42.091] <TB2> INFO: Decoding statistics:
[11:11:42.091] <TB2> INFO: General information:
[11:11:42.091] <TB2> INFO: 16bit words read: 1024
[11:11:42.091] <TB2> INFO: valid events total: 128
[11:11:42.091] <TB2> INFO: empty events: 128
[11:11:42.091] <TB2> INFO: valid events with pixels: 0
[11:11:42.091] <TB2> INFO: valid pixel hits: 0
[11:11:42.091] <TB2> INFO: Event errors: 0
[11:11:42.091] <TB2> INFO: start marker: 0
[11:11:42.091] <TB2> INFO: stop marker: 0
[11:11:42.091] <TB2> INFO: overflow: 0
[11:11:42.091] <TB2> INFO: invalid 5bit words: 0
[11:11:42.091] <TB2> INFO: invalid XOR eye diagram: 0
[11:11:42.091] <TB2> INFO: TBM errors: 0
[11:11:42.091] <TB2> INFO: flawed TBM headers: 0
[11:11:42.091] <TB2> INFO: flawed TBM trailers: 0
[11:11:42.091] <TB2> INFO: event ID mismatches: 0
[11:11:42.091] <TB2> INFO: ROC errors: 0
[11:11:42.091] <TB2> INFO: missing ROC header(s): 0
[11:11:42.091] <TB2> INFO: misplaced readback start: 0
[11:11:42.091] <TB2> INFO: Pixel decoding errors: 0
[11:11:42.091] <TB2> INFO: pixel data incomplete: 0
[11:11:42.091] <TB2> INFO: pixel address: 0
[11:11:42.091] <TB2> INFO: pulse height fill bit: 0
[11:11:42.091] <TB2> INFO: buffer corruption: 0
[11:11:42.104] <TB2> INFO: Decoding statistics:
[11:11:42.104] <TB2> INFO: General information:
[11:11:42.104] <TB2> INFO: 16bit words read: 2755348
[11:11:42.104] <TB2> INFO: valid events total: 176768
[11:11:42.104] <TB2> INFO: empty events: 7867
[11:11:42.104] <TB2> INFO: valid events with pixels: 168901
[11:11:42.104] <TB2> INFO: valid pixel hits: 668091
[11:11:42.104] <TB2> INFO: Event errors: 0
[11:11:42.104] <TB2> INFO: start marker: 0
[11:11:42.104] <TB2> INFO: stop marker: 0
[11:11:42.104] <TB2> INFO: overflow: 0
[11:11:42.104] <TB2> INFO: invalid 5bit words: 0
[11:11:42.104] <TB2> INFO: invalid XOR eye diagram: 0
[11:11:42.104] <TB2> INFO: TBM errors: 0
[11:11:42.104] <TB2> INFO: flawed TBM headers: 0
[11:11:42.104] <TB2> INFO: flawed TBM trailers: 0
[11:11:42.104] <TB2> INFO: event ID mismatches: 0
[11:11:42.104] <TB2> INFO: ROC errors: 0
[11:11:42.104] <TB2> INFO: missing ROC header(s): 0
[11:11:42.104] <TB2> INFO: misplaced readback start: 0
[11:11:42.105] <TB2> INFO: Pixel decoding errors: 0
[11:11:42.105] <TB2> INFO: pixel data incomplete: 0
[11:11:42.105] <TB2> INFO: pixel address: 0
[11:11:42.105] <TB2> INFO: pulse height fill bit: 0
[11:11:42.105] <TB2> INFO: buffer corruption: 0
[11:11:42.105] <TB2> INFO: enter test to run
[11:11:42.105] <TB2> INFO: test: exit no parameter change
[11:11:42.381] <TB2> QUIET: Connection to board 156 closed.
[11:11:42.461] <TB2> INFO: pXar: this is the end, my friend