[07:27:02.754] <TB2> INFO: *** Welcome to pxar ***
[07:27:02.755] <TB2> INFO: *** Today: 2015/11/20
[07:27:02.847] <TB2> INFO: *** Version: 9da6
[07:27:02.847] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C15.dat
[07:27:02.847] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:27:02.847] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//defaultMaskFile.dat
[07:27:02.847] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters_C15.dat
[07:27:02.939] <TB2> INFO: clk: 4
[07:27:02.939] <TB2> INFO: ctr: 4
[07:27:02.939] <TB2> INFO: sda: 19
[07:27:02.939] <TB2> INFO: tin: 9
[07:27:02.939] <TB2> INFO: level: 15
[07:27:02.939] <TB2> INFO: triggerdelay: 0
[07:27:02.939] <TB2> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[07:27:02.939] <TB2> INFO: Log level: INFO
[07:27:02.951] <TB2> INFO: Found DTB DTB_WRE1O5
[07:27:02.961] <TB2> QUIET: Connection to board DTB_WRE1O5 opened.
[07:27:02.964] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 20
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRE1O5
MAC address: 40D855118014
Hostname: pixelDTB020
Comment:
------------------------------------------------------
[07:27:02.967] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[07:27:04.518] <TB2> INFO: DUT info:
[07:27:04.518] <TB2> INFO: The DUT currently contains the following objects:
[07:27:04.518] <TB2> INFO: 2 TBM Cores tbm08c (2 ON)
[07:27:04.518] <TB2> INFO: TBM Core alpha (0): 7 registers set
[07:27:04.519] <TB2> INFO: TBM Core beta (1): 7 registers set
[07:27:04.519] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:27:04.519] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.519] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:27:04.920] <TB2> INFO: enter 'restricted' command line mode
[07:27:04.920] <TB2> INFO: enter test to run
[07:27:04.920] <TB2> INFO: test: pretest no parameter change
[07:27:04.920] <TB2> INFO: running: pretest
[07:27:04.925] <TB2> INFO: ######################################################################
[07:27:04.925] <TB2> INFO: PixTestPretest::doTest()
[07:27:04.925] <TB2> INFO: ######################################################################
[07:27:04.927] <TB2> INFO: ----------------------------------------------------------------------
[07:27:04.927] <TB2> INFO: PixTestPretest::programROC()
[07:27:04.927] <TB2> INFO: ----------------------------------------------------------------------
[07:27:22.947] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:27:22.948] <TB2> INFO: IA differences per ROC: 16.9 17.7 18.5 18.5 19.3 16.9 20.1 18.5 18.5 21.7 17.7 19.3 15.3 18.5 17.7 18.5
[07:27:23.035] <TB2> INFO: ----------------------------------------------------------------------
[07:27:23.035] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:27:23.035] <TB2> INFO: ----------------------------------------------------------------------
[07:27:44.354] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[07:27:44.354] <TB2> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 19.3 20.1 19.3 19.3 20.9 19.3 19.3 19.3 20.1 19.3 19.3 19.3 19.3
[07:27:44.400] <TB2> INFO: ----------------------------------------------------------------------
[07:27:44.401] <TB2> INFO: PixTestPretest::findTiming()
[07:27:44.401] <TB2> INFO: ----------------------------------------------------------------------
[07:27:44.401] <TB2> INFO: PixTestCmd::init()
[07:27:45.000] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[07:29:22.167] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[07:29:22.167] <TB2> INFO: (success/tries = 100/100), width = 4
[07:29:22.168] <TB2> INFO: ----------------------------------------------------------------------
[07:29:22.168] <TB2> INFO: PixTestPretest::findWorkingPixel()
[07:29:22.169] <TB2> INFO: ----------------------------------------------------------------------
[07:29:22.307] <TB2> INFO: Expecting 231680 events.
[07:29:26.916] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[07:29:26.919] <TB2> ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[07:29:30.325] <TB2> INFO: 231680 events read in total (7303ms).
[07:29:30.329] <TB2> INFO: Test took 8158ms.
[07:29:30.729] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:29:30.779] <TB2> INFO: ----------------------------------------------------------------------
[07:29:30.779] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[07:29:30.779] <TB2> INFO: ----------------------------------------------------------------------
[07:29:30.918] <TB2> INFO: Expecting 231680 events.
[07:29:39.645] <TB2> INFO: 231680 events read in total (8011ms).
[07:29:39.650] <TB2> INFO: Test took 8865ms.
[07:29:40.077] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[07:29:40.077] <TB2> INFO: CalDel: 109 147 124 127 141 138 128 106 124 138 133 132 124 112 130 137
[07:29:40.077] <TB2> INFO: VthrComp: 51 51 53 51 51 51 51 52 51 51 51 51 53 51 51 51
[07:29:40.079] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C0.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C1.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C2.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C3.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C4.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C5.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C6.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C7.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C8.dat
[07:29:40.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C9.dat
[07:29:40.081] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C10.dat
[07:29:40.081] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C11.dat
[07:29:40.081] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C12.dat
[07:29:40.081] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C13.dat
[07:29:40.081] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C14.dat
[07:29:40.081] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C15.dat
[07:29:40.081] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0a.dat
[07:29:40.081] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:29:40.081] <TB2> INFO: PixTestPretest::doTest() done, duration: 155 seconds
[07:29:40.147] <TB2> INFO: enter test to run
[07:29:40.147] <TB2> INFO: test: fulltest no parameter change
[07:29:40.147] <TB2> INFO: running: fulltest
[07:29:40.147] <TB2> INFO: ######################################################################
[07:29:40.147] <TB2> INFO: PixTestFullTest::doTest()
[07:29:40.147] <TB2> INFO: ######################################################################
[07:29:40.149] <TB2> INFO: ######################################################################
[07:29:40.149] <TB2> INFO: PixTestAlive::doTest()
[07:29:40.149] <TB2> INFO: ######################################################################
[07:29:40.151] <TB2> INFO: ----------------------------------------------------------------------
[07:29:40.151] <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)
[07:29:40.151] <TB2> INFO: ----------------------------------------------------------------------
[07:29:40.461] <TB2> INFO: Expecting 41600 events.
[07:29:44.580] <TB2> INFO: 41600 events read in total (3404ms).
[07:29:44.581] <TB2> INFO: Test took 4428ms.
[07:29:44.587] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:44.988] <TB2> INFO: PixTestAlive::aliveTest() done
[07:29:44.988] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 5 0 0 0 0 0 0
[07:29:44.990] <TB2> INFO: ----------------------------------------------------------------------
[07:29:44.990] <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)
[07:29:44.990] <TB2> INFO: ----------------------------------------------------------------------
[07:29:45.319] <TB2> INFO: Expecting 41600 events.
[07:29:48.282] <TB2> INFO: 41600 events read in total (2248ms).
[07:29:48.283] <TB2> INFO: Test took 3291ms.
[07:29:48.283] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:48.283] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:29:48.704] <TB2> INFO: PixTestAlive::maskTest() done
[07:29:48.704] <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
[07:29:48.706] <TB2> INFO: ----------------------------------------------------------------------
[07:29:48.706] <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)
[07:29:48.706] <TB2> INFO: ----------------------------------------------------------------------
[07:29:49.022] <TB2> INFO: Expecting 41600 events.
[07:29:53.236] <TB2> INFO: 41600 events read in total (3498ms).
[07:29:53.236] <TB2> INFO: Test took 4527ms.
[07:29:53.244] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:53.645] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[07:29:53.645] <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
[07:29:53.645] <TB2> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[07:29:53.645] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:53.645] <TB2> INFO: Decoding statistics:
[07:29:53.645] <TB2> INFO: General information:
[07:29:53.645] <TB2> INFO: 16bit words read: 0
[07:29:53.645] <TB2> INFO: valid events total: 0
[07:29:53.645] <TB2> INFO: empty events: 0
[07:29:53.645] <TB2> INFO: valid events with pixels: 0
[07:29:53.645] <TB2> INFO: valid pixel hits: 0
[07:29:53.645] <TB2> INFO: Event errors: 0
[07:29:53.645] <TB2> INFO: start marker: 0
[07:29:53.645] <TB2> INFO: stop marker: 0
[07:29:53.645] <TB2> INFO: overflow: 0
[07:29:53.645] <TB2> INFO: invalid 5bit words: 0
[07:29:53.645] <TB2> INFO: invalid XOR eye diagram: 0
[07:29:53.645] <TB2> INFO: TBM errors: 0
[07:29:53.645] <TB2> INFO: flawed TBM headers: 0
[07:29:53.645] <TB2> INFO: flawed TBM trailers: 0
[07:29:53.645] <TB2> INFO: event ID mismatches: 0
[07:29:53.645] <TB2> INFO: ROC errors: 0
[07:29:53.645] <TB2> INFO: missing ROC header(s): 0
[07:29:53.645] <TB2> INFO: misplaced readback start: 0
[07:29:53.645] <TB2> INFO: Pixel decoding errors: 0
[07:29:53.645] <TB2> INFO: pixel data incomplete: 0
[07:29:53.645] <TB2> INFO: pixel address: 0
[07:29:53.645] <TB2> INFO: pulse height fill bit: 0
[07:29:53.645] <TB2> INFO: buffer corruption: 0
[07:29:53.653] <TB2> INFO: ######################################################################
[07:29:53.653] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:29:53.653] <TB2> INFO: ######################################################################
[07:29:53.657] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:29:53.667] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:29:53.667] <TB2> INFO: run 1 of 1
[07:29:53.971] <TB2> INFO: Expecting 3120000 events.
[07:30:44.369] <TB2> INFO: 1068585 events read in total (49683ms).
[07:31:34.855] <TB2> INFO: 2135060 events read in total (100170ms).
[07:32:18.004] <TB2> INFO: 3120000 events read in total (143318ms).
[07:32:18.066] <TB2> INFO: Test took 144399ms.
[07:32:18.167] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:43.553] <TB2> INFO: PixTestBBMap::doTest() done, duration: 169 seconds
[07:32:43.553] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0
[07:32:43.553] <TB2> INFO: separation cut (per ROC): 116 95 119 101 106 99 118 157 118 98 95 100 106 115 104 134
[07:32:43.553] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:43.553] <TB2> INFO: Decoding statistics:
[07:32:43.553] <TB2> INFO: General information:
[07:32:43.553] <TB2> INFO: 16bit words read: 0
[07:32:43.553] <TB2> INFO: valid events total: 0
[07:32:43.553] <TB2> INFO: empty events: 0
[07:32:43.553] <TB2> INFO: valid events with pixels: 0
[07:32:43.553] <TB2> INFO: valid pixel hits: 0
[07:32:43.553] <TB2> INFO: Event errors: 0
[07:32:43.553] <TB2> INFO: start marker: 0
[07:32:43.553] <TB2> INFO: stop marker: 0
[07:32:43.553] <TB2> INFO: overflow: 0
[07:32:43.553] <TB2> INFO: invalid 5bit words: 0
[07:32:43.553] <TB2> INFO: invalid XOR eye diagram: 0
[07:32:43.553] <TB2> INFO: TBM errors: 0
[07:32:43.553] <TB2> INFO: flawed TBM headers: 0
[07:32:43.553] <TB2> INFO: flawed TBM trailers: 0
[07:32:43.553] <TB2> INFO: event ID mismatches: 0
[07:32:43.553] <TB2> INFO: ROC errors: 0
[07:32:43.553] <TB2> INFO: missing ROC header(s): 0
[07:32:43.553] <TB2> INFO: misplaced readback start: 0
[07:32:43.553] <TB2> INFO: Pixel decoding errors: 0
[07:32:43.553] <TB2> INFO: pixel data incomplete: 0
[07:32:43.553] <TB2> INFO: pixel address: 0
[07:32:43.553] <TB2> INFO: pulse height fill bit: 0
[07:32:43.553] <TB2> INFO: buffer corruption: 0
[07:32:43.623] <TB2> INFO: ######################################################################
[07:32:43.623] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:32:43.623] <TB2> INFO: ######################################################################
[07:32:43.623] <TB2> INFO: ----------------------------------------------------------------------
[07:32:43.623] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:32:43.623] <TB2> INFO: ----------------------------------------------------------------------
[07:32:43.623] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:32:43.631] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:43.631] <TB2> INFO: run 1 of 1
[07:32:43.936] <TB2> INFO: Expecting 26208000 events.
[07:33:17.745] <TB2> INFO: 1170800 events read in total (33094ms).
[07:33:51.146] <TB2> INFO: 2312200 events read in total (66495ms).
[07:34:23.791] <TB2> INFO: 3451800 events read in total (99141ms).
[07:34:55.502] <TB2> INFO: 4586850 events read in total (130851ms).
[07:35:27.543] <TB2> INFO: 5718150 events read in total (162892ms).
[07:35:59.358] <TB2> INFO: 6847650 events read in total (194707ms).
[07:36:32.530] <TB2> INFO: 7976300 events read in total (227879ms).
[07:37:06.034] <TB2> INFO: 9105400 events read in total (261383ms).
[07:37:38.490] <TB2> INFO: 10229600 events read in total (293839ms).
[07:38:11.718] <TB2> INFO: 11352200 events read in total (327067ms).
[07:38:45.752] <TB2> INFO: 12474950 events read in total (361101ms).
[07:39:20.222] <TB2> INFO: 13587300 events read in total (395571ms).
[07:39:54.094] <TB2> INFO: 14692750 events read in total (429443ms).
[07:40:27.138] <TB2> INFO: 15797900 events read in total (462487ms).
[07:41:00.246] <TB2> INFO: 16895650 events read in total (495595ms).
[07:41:32.576] <TB2> INFO: 17991400 events read in total (527925ms).
[07:42:05.772] <TB2> INFO: 19089000 events read in total (561121ms).
[07:42:38.614] <TB2> INFO: 20185250 events read in total (593963ms).
[07:43:10.815] <TB2> INFO: 21281300 events read in total (626164ms).
[07:43:42.678] <TB2> INFO: 22375850 events read in total (658027ms).
[07:44:14.734] <TB2> INFO: 23472800 events read in total (690083ms).
[07:44:46.700] <TB2> INFO: 24568850 events read in total (722049ms).
[07:45:19.885] <TB2> INFO: 25666700 events read in total (755234ms).
[07:45:37.260] <TB2> INFO: 26208000 events read in total (772609ms).
[07:45:37.297] <TB2> INFO: Test took 773666ms.
[07:45:37.372] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:45:37.515] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:39.083] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:40.752] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:42.368] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:44.062] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:45.762] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:47.409] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:49.081] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:50.706] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:52.242] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:53.714] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:55.105] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:56.479] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:57.856] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:45:59.204] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:00.590] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:02.025] <TB2> INFO: PixTestScurves::scurves() done
[07:46:02.025] <TB2> INFO: Vcal mean: 94.96 85.40 108.67 88.18 91.34 91.40 93.53 113.50 99.03 84.31 85.22 89.76 105.87 105.53 91.60 95.81
[07:46:02.025] <TB2> INFO: Vcal RMS: 5.14 5.91 5.41 5.22 5.79 6.05 5.53 6.24 7.06 5.63 5.19 5.40 5.97 5.60 5.97 5.56
[07:46:02.025] <TB2> INFO: PixTestScurves::fullTest() done, duration: 798 seconds
[07:46:02.025] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:46:02.025] <TB2> INFO: Decoding statistics:
[07:46:02.025] <TB2> INFO: General information:
[07:46:02.025] <TB2> INFO: 16bit words read: 0
[07:46:02.025] <TB2> INFO: valid events total: 0
[07:46:02.026] <TB2> INFO: empty events: 0
[07:46:02.026] <TB2> INFO: valid events with pixels: 0
[07:46:02.026] <TB2> INFO: valid pixel hits: 0
[07:46:02.026] <TB2> INFO: Event errors: 0
[07:46:02.026] <TB2> INFO: start marker: 0
[07:46:02.026] <TB2> INFO: stop marker: 0
[07:46:02.026] <TB2> INFO: overflow: 0
[07:46:02.026] <TB2> INFO: invalid 5bit words: 0
[07:46:02.026] <TB2> INFO: invalid XOR eye diagram: 0
[07:46:02.026] <TB2> INFO: TBM errors: 0
[07:46:02.026] <TB2> INFO: flawed TBM headers: 0
[07:46:02.026] <TB2> INFO: flawed TBM trailers: 0
[07:46:02.026] <TB2> INFO: event ID mismatches: 0
[07:46:02.026] <TB2> INFO: ROC errors: 0
[07:46:02.026] <TB2> INFO: missing ROC header(s): 0
[07:46:02.026] <TB2> INFO: misplaced readback start: 0
[07:46:02.026] <TB2> INFO: Pixel decoding errors: 0
[07:46:02.026] <TB2> INFO: pixel data incomplete: 0
[07:46:02.026] <TB2> INFO: pixel address: 0
[07:46:02.026] <TB2> INFO: pulse height fill bit: 0
[07:46:02.026] <TB2> INFO: buffer corruption: 0
[07:46:02.106] <TB2> INFO: ######################################################################
[07:46:02.106] <TB2> INFO: PixTestTrim::doTest()
[07:46:02.106] <TB2> INFO: ######################################################################
[07:46:02.107] <TB2> INFO: ----------------------------------------------------------------------
[07:46:02.107] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:46:02.107] <TB2> INFO: ----------------------------------------------------------------------
[07:46:02.203] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:46:02.203] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:46:02.214] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[07:46:02.214] <TB2> INFO: run 1 of 1
[07:46:02.542] <TB2> INFO: Expecting 6281600 events.
[07:46:47.578] <TB2> INFO: 1407090 events read in total (44319ms).
[07:47:27.562] <TB2> INFO: 2802080 events read in total (84303ms).
[07:48:12.927] <TB2> INFO: 4198880 events read in total (129668ms).
[07:48:57.239] <TB2> INFO: 5604470 events read in total (173981ms).
[07:49:16.912] <TB2> INFO: 6281600 events read in total (193653ms).
[07:49:16.954] <TB2> INFO: Test took 194741ms.
[07:49:17.007] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:49:36.233] <TB2> INFO: ROC 0 VthrComp = 100
[07:49:36.233] <TB2> INFO: ROC 1 VthrComp = 85
[07:49:36.233] <TB2> INFO: ROC 2 VthrComp = 107
[07:49:36.233] <TB2> INFO: ROC 3 VthrComp = 91
[07:49:36.233] <TB2> INFO: ROC 4 VthrComp = 91
[07:49:36.234] <TB2> INFO: ROC 5 VthrComp = 90
[07:49:36.234] <TB2> INFO: ROC 6 VthrComp = 94
[07:49:36.234] <TB2> INFO: ROC 7 VthrComp = 107
[07:49:36.234] <TB2> INFO: ROC 8 VthrComp = 98
[07:49:36.235] <TB2> INFO: ROC 9 VthrComp = 91
[07:49:36.235] <TB2> INFO: ROC 10 VthrComp = 89
[07:49:36.235] <TB2> INFO: ROC 11 VthrComp = 95
[07:49:36.235] <TB2> INFO: ROC 12 VthrComp = 100
[07:49:36.235] <TB2> INFO: ROC 13 VthrComp = 107
[07:49:36.235] <TB2> INFO: ROC 14 VthrComp = 94
[07:49:36.236] <TB2> INFO: ROC 15 VthrComp = 97
[07:49:36.236] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:49:36.236] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:49:36.244] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[07:49:36.244] <TB2> INFO: run 1 of 1
[07:49:36.574] <TB2> INFO: Expecting 6281600 events.
[07:50:11.977] <TB2> INFO: 895530 events read in total (34688ms).
[07:50:49.754] <TB2> INFO: 1786300 events read in total (72465ms).
[07:51:23.136] <TB2> INFO: 2677210 events read in total (105847ms).
[07:52:01.712] <TB2> INFO: 3562940 events read in total (144423ms).
[07:52:40.300] <TB2> INFO: 4441070 events read in total (183011ms).
[07:53:18.045] <TB2> INFO: 5315790 events read in total (220756ms).
[07:53:53.774] <TB2> INFO: 6190710 events read in total (256485ms).
[07:53:58.374] <TB2> INFO: 6281600 events read in total (261085ms).
[07:53:58.449] <TB2> INFO: Test took 262205ms.
[07:53:58.631] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:25.471] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 58.5456 for pixel 12/21 mean/min/max = 45.4026/32.2443/58.5609
[07:54:25.471] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 63.3425 for pixel 0/21 mean/min/max = 47.2305/31.0288/63.4322
[07:54:25.471] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 63.8658 for pixel 37/46 mean/min/max = 48.6271/33.3721/63.882
[07:54:25.471] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 58.6926 for pixel 22/79 mean/min/max = 46.0129/33.2297/58.7961
[07:54:25.472] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 62.0396 for pixel 3/0 mean/min/max = 46.9019/31.7134/62.0905
[07:54:25.472] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 62.8993 for pixel 1/42 mean/min/max = 47.155/31.3192/62.9907
[07:54:25.472] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 60.0181 for pixel 5/74 mean/min/max = 46.1873/32.0892/60.2855
[07:54:25.473] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 73.5341 for pixel 12/0 mean/min/max = 55.5802/37.3566/73.8037
[07:54:25.473] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 63.1953 for pixel 1/76 mean/min/max = 47.0941/30.7624/63.4258
[07:54:25.473] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 58.0454 for pixel 17/71 mean/min/max = 44.9843/31.9221/58.0465
[07:54:25.474] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 58.7892 for pixel 26/79 mean/min/max = 45.9052/32.9007/58.9097
[07:54:25.474] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 59.3352 for pixel 0/77 mean/min/max = 45.2083/30.8893/59.5273
[07:54:25.474] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 63.769 for pixel 13/5 mean/min/max = 47.9886/32.0611/63.9162
[07:54:25.475] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 63.2674 for pixel 26/79 mean/min/max = 48.7075/34.1172/63.2979
[07:54:25.475] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 59.8696 for pixel 0/59 mean/min/max = 46.0275/32.0525/60.0025
[07:54:25.475] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 59.5394 for pixel 21/79 mean/min/max = 45.5789/31.4331/59.7246
[07:54:25.476] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:54:25.609] <TB2> INFO: Expecting 514560 events.
[07:54:36.882] <TB2> INFO: 514560 events read in total (10558ms).
[07:54:36.887] <TB2> INFO: Expecting 514560 events.
[07:54:46.543] <TB2> INFO: 514560 events read in total (8987ms).
[07:54:46.550] <TB2> INFO: Expecting 514560 events.
[07:54:56.431] <TB2> INFO: 514560 events read in total (9208ms).
[07:54:56.440] <TB2> INFO: Expecting 514560 events.
[07:55:07.490] <TB2> INFO: 514560 events read in total (10382ms).
[07:55:07.499] <TB2> INFO: Expecting 514560 events.
[07:55:18.429] <TB2> INFO: 514560 events read in total (10257ms).
[07:55:18.441] <TB2> INFO: Expecting 514560 events.
[07:55:28.689] <TB2> INFO: 514560 events read in total (9579ms).
[07:55:28.703] <TB2> INFO: Expecting 514560 events.
[07:55:38.351] <TB2> INFO: 514560 events read in total (8984ms).
[07:55:38.371] <TB2> INFO: Expecting 514560 events.
[07:55:49.790] <TB2> INFO: 514560 events read in total (10775ms).
[07:55:49.809] <TB2> INFO: Expecting 514560 events.
[07:56:00.956] <TB2> INFO: 514560 events read in total (10496ms).
[07:56:00.975] <TB2> INFO: Expecting 514560 events.
[07:56:11.355] <TB2> INFO: 514560 events read in total (9719ms).
[07:56:11.379] <TB2> INFO: Expecting 514560 events.
[07:56:20.311] <TB2> INFO: 514560 events read in total (8285ms).
[07:56:20.358] <TB2> INFO: Expecting 514560 events.
[07:56:29.297] <TB2> INFO: 514560 events read in total (8307ms).
[07:56:29.324] <TB2> INFO: Expecting 514560 events.
[07:56:38.328] <TB2> INFO: 514560 events read in total (8359ms).
[07:56:38.354] <TB2> INFO: Expecting 514560 events.
[07:56:49.101] <TB2> INFO: 514560 events read in total (10091ms).
[07:56:49.131] <TB2> INFO: Expecting 514560 events.
[07:56:59.077] <TB2> INFO: 514560 events read in total (9304ms).
[07:56:59.112] <TB2> INFO: Expecting 514560 events.
[07:57:09.206] <TB2> INFO: 514560 events read in total (9453ms).
[07:57:09.239] <TB2> INFO: Test took 163763ms.
[07:57:10.268] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:57:10.276] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[07:57:10.276] <TB2> INFO: run 1 of 1
[07:57:10.602] <TB2> INFO: Expecting 6281600 events.
[07:57:49.767] <TB2> INFO: 871600 events read in total (38450ms).
[07:58:28.139] <TB2> INFO: 1740580 events read in total (76822ms).
[07:59:06.460] <TB2> INFO: 2609670 events read in total (115143ms).
[07:59:44.744] <TB2> INFO: 3475190 events read in total (153427ms).
[08:00:23.318] <TB2> INFO: 4332920 events read in total (192001ms).
[08:01:01.270] <TB2> INFO: 5187720 events read in total (229953ms).
[08:01:39.033] <TB2> INFO: 6041170 events read in total (267716ms).
[08:01:50.615] <TB2> INFO: 6281600 events read in total (279298ms).
[08:01:50.682] <TB2> INFO: Test took 280407ms.
[08:01:50.843] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:02:16.098] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.034332 .. 255.000000
[08:02:16.193] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[08:02:16.202] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:02:16.202] <TB2> INFO: run 1 of 1
[08:02:16.537] <TB2> INFO: Expecting 10649600 events.
[08:02:51.588] <TB2> INFO: 826620 events read in total (34336ms).
[08:03:25.058] <TB2> INFO: 1653530 events read in total (67806ms).
[08:03:58.360] <TB2> INFO: 2480760 events read in total (101108ms).
[08:04:34.083] <TB2> INFO: 3307950 events read in total (136831ms).
[08:05:05.583] <TB2> INFO: 4134790 events read in total (168331ms).
[08:05:43.524] <TB2> INFO: 4962200 events read in total (206272ms).
[08:06:20.574] <TB2> INFO: 5789240 events read in total (243322ms).
[08:06:57.337] <TB2> INFO: 6615550 events read in total (280085ms).
[08:07:32.074] <TB2> INFO: 7441240 events read in total (314822ms).
[08:08:05.369] <TB2> INFO: 8266010 events read in total (348117ms).
[08:08:39.324] <TB2> INFO: 9090460 events read in total (382072ms).
[08:09:13.868] <TB2> INFO: 9914850 events read in total (416616ms).
[08:09:47.554] <TB2> INFO: 10649600 events read in total (450302ms).
[08:09:47.651] <TB2> INFO: Test took 451449ms.
[08:09:47.930] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:20.253] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 1.023566 .. 74.569563
[08:10:20.335] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 84 (-1/-1) hits flags = 528 (plus default)
[08:10:20.343] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:10:20.343] <TB2> INFO: run 1 of 1
[08:10:20.651] <TB2> INFO: Expecting 3494400 events.
[08:11:01.022] <TB2> INFO: 997780 events read in total (39656ms).
[08:11:40.395] <TB2> INFO: 1994970 events read in total (79029ms).
[08:12:21.431] <TB2> INFO: 2991060 events read in total (120066ms).
[08:12:41.007] <TB2> INFO: 3494400 events read in total (139641ms).
[08:12:41.046] <TB2> INFO: Test took 140704ms.
[08:12:41.124] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:59.838] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 17.004863 .. 69.230707
[08:12:59.918] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 7 .. 79 (-1/-1) hits flags = 528 (plus default)
[08:12:59.927] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:12:59.927] <TB2> INFO: run 1 of 1
[08:13:00.249] <TB2> INFO: Expecting 3036800 events.
[08:13:38.224] <TB2> INFO: 983720 events read in total (37260ms).
[08:14:12.311] <TB2> INFO: 1966840 events read in total (71348ms).
[08:14:52.048] <TB2> INFO: 2948920 events read in total (111085ms).
[08:14:55.365] <TB2> INFO: 3036800 events read in total (114401ms).
[08:14:55.397] <TB2> INFO: Test took 115471ms.
[08:14:55.470] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:12.451] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 13.952269 .. 69.230707
[08:15:12.547] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 79 (-1/-1) hits flags = 528 (plus default)
[08:15:12.556] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:15:12.556] <TB2> INFO: run 1 of 1
[08:15:12.890] <TB2> INFO: Expecting 3203200 events.
[08:15:50.476] <TB2> INFO: 1007540 events read in total (36871ms).
[08:16:24.457] <TB2> INFO: 2014280 events read in total (70852ms).
[08:17:01.413] <TB2> INFO: 3021350 events read in total (107808ms).
[08:17:08.254] <TB2> INFO: 3203200 events read in total (114649ms).
[08:17:08.279] <TB2> INFO: Test took 115722ms.
[08:17:08.348] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:25.419] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:17:25.419] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:17:25.428] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:17:25.428] <TB2> INFO: run 1 of 1
[08:17:25.757] <TB2> INFO: Expecting 1705600 events.
[08:18:06.033] <TB2> INFO: 1076260 events read in total (39561ms).
[08:18:30.600] <TB2> INFO: 1705600 events read in total (64128ms).
[08:18:30.620] <TB2> INFO: Test took 65192ms.
[08:18:30.661] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:46.106] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:18:46.106] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:18:46.107] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:18:46.107] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:18:46.107] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:18:46.107] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:18:46.107] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:18:46.107] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:18:46.108] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:18:46.108] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:18:46.108] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:18:46.129] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:18:46.129] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:18:46.129] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:18:46.129] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:18:46.129] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:18:46.130] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:18:46.135] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:18:46.141] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:18:46.147] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:18:46.152] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:18:46.158] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:18:46.163] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:18:46.169] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:18:46.174] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:18:46.180] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:18:46.186] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:18:46.191] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:18:46.197] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:18:46.202] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:18:46.208] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:18:46.213] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:18:46.219] <TB2> INFO: PixTestTrim::trimTest() done
[08:18:46.219] <TB2> INFO: vtrim: 108 95 95 83 98 103 95 166 94 103 90 104 100 118 96 92
[08:18:46.219] <TB2> INFO: vthrcomp: 100 85 107 91 91 90 94 107 98 91 89 95 100 107 94 97
[08:18:46.219] <TB2> INFO: vcal mean: 34.96 34.97 35.02 34.99 34.99 35.02 34.99 35.00 34.97 35.07 35.03 34.99 35.00 35.00 34.99 34.95
[08:18:46.219] <TB2> INFO: vcal RMS: 0.85 1.17 0.92 0.86 1.07 0.94 0.91 1.05 0.93 3.64 0.80 0.84 1.00 0.90 0.88 1.02
[08:18:46.219] <TB2> INFO: bits mean: 9.79 9.56 8.51 9.17 9.56 9.55 9.38 7.89 9.31 9.82 9.04 9.68 9.07 8.61 9.26 9.41
[08:18:46.219] <TB2> INFO: bits RMS: 2.52 2.64 2.68 2.62 2.57 2.63 2.64 2.12 2.81 2.49 2.73 2.76 2.71 2.52 2.83 2.77
[08:18:46.228] <TB2> INFO: ----------------------------------------------------------------------
[08:18:46.228] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[08:18:46.228] <TB2> INFO: ----------------------------------------------------------------------
[08:18:46.232] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:18:46.240] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:18:46.240] <TB2> INFO: run 1 of 1
[08:18:46.550] <TB2> INFO: Expecting 8320000 events.
[08:19:28.737] <TB2> INFO: 1193620 events read in total (41472ms).
[08:20:09.563] <TB2> INFO: 2371150 events read in total (82298ms).
[08:20:51.069] <TB2> INFO: 3543830 events read in total (123804ms).
[08:21:32.885] <TB2> INFO: 4706110 events read in total (165620ms).
[08:22:13.621] <TB2> INFO: 5860030 events read in total (206356ms).
[08:22:50.956] <TB2> INFO: 7012490 events read in total (243691ms).
[08:23:32.700] <TB2> INFO: 8167700 events read in total (285435ms).
[08:23:38.553] <TB2> INFO: 8320000 events read in total (291288ms).
[08:23:38.610] <TB2> INFO: Test took 292370ms.
[08:23:38.725] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:07.268] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 183 (-1/-1) hits flags = 528 (plus default)
[08:24:07.276] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:24:07.276] <TB2> INFO: run 1 of 1
[08:24:07.581] <TB2> INFO: Expecting 7654400 events.
[08:24:48.101] <TB2> INFO: 1190370 events read in total (39805ms).
[08:25:25.321] <TB2> INFO: 2365680 events read in total (77025ms).
[08:26:04.793] <TB2> INFO: 3534690 events read in total (116497ms).
[08:26:40.728] <TB2> INFO: 4690700 events read in total (152432ms).
[08:27:20.255] <TB2> INFO: 5839700 events read in total (191959ms).
[08:28:01.855] <TB2> INFO: 6989460 events read in total (233559ms).
[08:28:26.248] <TB2> INFO: 7654400 events read in total (257952ms).
[08:28:26.305] <TB2> INFO: Test took 259029ms.
[08:28:26.421] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:50.371] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[08:28:50.379] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:28:50.379] <TB2> INFO: run 1 of 1
[08:28:50.685] <TB2> INFO: Expecting 7196800 events.
[08:29:29.734] <TB2> INFO: 1232380 events read in total (38334ms).
[08:30:09.768] <TB2> INFO: 2447130 events read in total (78368ms).
[08:30:49.507] <TB2> INFO: 3653790 events read in total (118108ms).
[08:31:31.359] <TB2> INFO: 4843870 events read in total (159959ms).
[08:32:08.402] <TB2> INFO: 6030280 events read in total (197003ms).
[08:32:47.213] <TB2> INFO: 7196800 events read in total (235813ms).
[08:32:47.266] <TB2> INFO: Test took 236888ms.
[08:32:47.358] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:11.035] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[08:33:11.043] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:33:11.043] <TB2> INFO: run 1 of 1
[08:33:11.348] <TB2> INFO: Expecting 7113600 events.
[08:33:55.864] <TB2> INFO: 1239430 events read in total (43801ms).
[08:34:38.279] <TB2> INFO: 2460240 events read in total (86217ms).
[08:35:20.450] <TB2> INFO: 3671740 events read in total (128388ms).
[08:36:03.042] <TB2> INFO: 4867370 events read in total (170979ms).
[08:36:43.803] <TB2> INFO: 6059900 events read in total (211741ms).
[08:37:19.563] <TB2> INFO: 7113600 events read in total (247500ms).
[08:37:19.610] <TB2> INFO: Test took 248568ms.
[08:37:19.695] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:44.897] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[08:37:44.905] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:37:44.905] <TB2> INFO: run 1 of 1
[08:37:45.211] <TB2> INFO: Expecting 7113600 events.
[08:38:27.801] <TB2> INFO: 1237920 events read in total (41875ms).
[08:39:10.809] <TB2> INFO: 2457460 events read in total (84883ms).
[08:39:53.354] <TB2> INFO: 3668000 events read in total (127429ms).
[08:40:33.626] <TB2> INFO: 4862670 events read in total (167700ms).
[08:41:15.112] <TB2> INFO: 6053610 events read in total (209186ms).
[08:41:51.830] <TB2> INFO: 7113600 events read in total (245904ms).
[08:41:51.863] <TB2> INFO: Test took 246958ms.
[08:41:51.944] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:17.649] <TB2> INFO: PixTestTrim::trimBitTest() done
[08:42:17.651] <TB2> INFO: PixTestTrim::doTest() done, duration: 3375 seconds
[08:42:17.651] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:17.651] <TB2> INFO: Decoding statistics:
[08:42:17.651] <TB2> INFO: General information:
[08:42:17.651] <TB2> INFO: 16bit words read: 0
[08:42:17.651] <TB2> INFO: valid events total: 0
[08:42:17.651] <TB2> INFO: empty events: 0
[08:42:17.651] <TB2> INFO: valid events with pixels: 0
[08:42:17.651] <TB2> INFO: valid pixel hits: 0
[08:42:17.651] <TB2> INFO: Event errors: 0
[08:42:17.651] <TB2> INFO: start marker: 0
[08:42:17.651] <TB2> INFO: stop marker: 0
[08:42:17.651] <TB2> INFO: overflow: 0
[08:42:17.651] <TB2> INFO: invalid 5bit words: 0
[08:42:17.651] <TB2> INFO: invalid XOR eye diagram: 0
[08:42:17.651] <TB2> INFO: TBM errors: 0
[08:42:17.651] <TB2> INFO: flawed TBM headers: 0
[08:42:17.651] <TB2> INFO: flawed TBM trailers: 0
[08:42:17.651] <TB2> INFO: event ID mismatches: 0
[08:42:17.651] <TB2> INFO: ROC errors: 0
[08:42:17.651] <TB2> INFO: missing ROC header(s): 0
[08:42:17.651] <TB2> INFO: misplaced readback start: 0
[08:42:17.651] <TB2> INFO: Pixel decoding errors: 0
[08:42:17.651] <TB2> INFO: pixel data incomplete: 0
[08:42:17.651] <TB2> INFO: pixel address: 0
[08:42:17.651] <TB2> INFO: pulse height fill bit: 0
[08:42:17.651] <TB2> INFO: buffer corruption: 0
[08:42:18.411] <TB2> INFO: ######################################################################
[08:42:18.411] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:42:18.411] <TB2> INFO: ######################################################################
[08:42:18.722] <TB2> INFO: Expecting 41600 events.
[08:42:23.031] <TB2> INFO: 41600 events read in total (3593ms).
[08:42:23.032] <TB2> INFO: Test took 4619ms.
[08:42:23.038] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:23.701] <TB2> INFO: Expecting 41600 events.
[08:42:27.795] <TB2> INFO: 41600 events read in total (3378ms).
[08:42:27.795] <TB2> INFO: Test took 4404ms.
[08:42:27.802] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:28.211] <TB2> INFO: Expecting 41600 events.
[08:42:32.222] <TB2> INFO: 41600 events read in total (3296ms).
[08:42:32.223] <TB2> INFO: Test took 4336ms.
[08:42:32.229] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:32.680] <TB2> INFO: Expecting 41600 events.
[08:42:36.682] <TB2> INFO: 41600 events read in total (3287ms).
[08:42:36.683] <TB2> INFO: Test took 4327ms.
[08:42:36.689] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:37.134] <TB2> INFO: Expecting 41600 events.
[08:42:41.217] <TB2> INFO: 41600 events read in total (3368ms).
[08:42:41.218] <TB2> INFO: Test took 4408ms.
[08:42:41.224] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:41.663] <TB2> INFO: Expecting 41600 events.
[08:42:45.705] <TB2> INFO: 41600 events read in total (3327ms).
[08:42:45.706] <TB2> INFO: Test took 4375ms.
[08:42:45.712] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:46.160] <TB2> INFO: Expecting 41600 events.
[08:42:50.279] <TB2> INFO: 41600 events read in total (3404ms).
[08:42:50.280] <TB2> INFO: Test took 4444ms.
[08:42:50.287] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:50.706] <TB2> INFO: Expecting 41600 events.
[08:42:54.829] <TB2> INFO: 41600 events read in total (3407ms).
[08:42:54.830] <TB2> INFO: Test took 4448ms.
[08:42:54.836] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:55.273] <TB2> INFO: Expecting 41600 events.
[08:42:59.375] <TB2> INFO: 41600 events read in total (3387ms).
[08:42:59.376] <TB2> INFO: Test took 4427ms.
[08:42:59.382] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:59.824] <TB2> INFO: Expecting 41600 events.
[08:43:03.926] <TB2> INFO: 41600 events read in total (3387ms).
[08:43:03.926] <TB2> INFO: Test took 4431ms.
[08:43:03.935] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:04.377] <TB2> INFO: Expecting 41600 events.
[08:43:08.903] <TB2> INFO: 41600 events read in total (3810ms).
[08:43:08.903] <TB2> INFO: Test took 4850ms.
[08:43:08.910] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:09.360] <TB2> INFO: Expecting 41600 events.
[08:43:13.812] <TB2> INFO: 41600 events read in total (3736ms).
[08:43:13.813] <TB2> INFO: Test took 4777ms.
[08:43:13.819] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:14.258] <TB2> INFO: Expecting 41600 events.
[08:43:18.621] <TB2> INFO: 41600 events read in total (3648ms).
[08:43:18.621] <TB2> INFO: Test took 4687ms.
[08:43:18.628] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:19.091] <TB2> INFO: Expecting 41600 events.
[08:43:23.551] <TB2> INFO: 41600 events read in total (3745ms).
[08:43:23.552] <TB2> INFO: Test took 4800ms.
[08:43:23.558] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:24.006] <TB2> INFO: Expecting 41600 events.
[08:43:28.371] <TB2> INFO: 41600 events read in total (3650ms).
[08:43:28.371] <TB2> INFO: Test took 4689ms.
[08:43:28.377] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:28.822] <TB2> INFO: Expecting 41600 events.
[08:43:33.007] <TB2> INFO: 41600 events read in total (3470ms).
[08:43:33.008] <TB2> INFO: Test took 4510ms.
[08:43:33.013] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:33.458] <TB2> INFO: Expecting 41600 events.
[08:43:37.603] <TB2> INFO: 41600 events read in total (3430ms).
[08:43:37.603] <TB2> INFO: Test took 4468ms.
[08:43:37.610] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:38.070] <TB2> INFO: Expecting 41600 events.
[08:43:42.202] <TB2> INFO: 41600 events read in total (3417ms).
[08:43:42.202] <TB2> INFO: Test took 4469ms.
[08:43:42.208] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:42.652] <TB2> INFO: Expecting 41600 events.
[08:43:46.733] <TB2> INFO: 41600 events read in total (3366ms).
[08:43:46.734] <TB2> INFO: Test took 4406ms.
[08:43:46.742] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:47.177] <TB2> INFO: Expecting 41600 events.
[08:43:51.581] <TB2> INFO: 41600 events read in total (3688ms).
[08:43:51.582] <TB2> INFO: Test took 4730ms.
[08:43:51.588] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:52.029] <TB2> INFO: Expecting 41600 events.
[08:43:56.448] <TB2> INFO: 41600 events read in total (3704ms).
[08:43:56.448] <TB2> INFO: Test took 4744ms.
[08:43:56.455] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:56.903] <TB2> INFO: Expecting 41600 events.
[08:44:01.376] <TB2> INFO: 41600 events read in total (3758ms).
[08:44:01.376] <TB2> INFO: Test took 4798ms.
[08:44:01.382] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:01.826] <TB2> INFO: Expecting 41600 events.
[08:44:06.169] <TB2> INFO: 41600 events read in total (3628ms).
[08:44:06.169] <TB2> INFO: Test took 4667ms.
[08:44:06.176] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:06.618] <TB2> INFO: Expecting 41600 events.
[08:44:11.001] <TB2> INFO: 41600 events read in total (3668ms).
[08:44:11.001] <TB2> INFO: Test took 4707ms.
[08:44:11.007] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:11.438] <TB2> INFO: Expecting 41600 events.
[08:44:15.690] <TB2> INFO: 41600 events read in total (3537ms).
[08:44:15.691] <TB2> INFO: Test took 4577ms.
[08:44:15.698] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:16.136] <TB2> INFO: Expecting 41600 events.
[08:44:20.312] <TB2> INFO: 41600 events read in total (3460ms).
[08:44:20.312] <TB2> INFO: Test took 4519ms.
[08:44:20.319] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:20.754] <TB2> INFO: Expecting 41600 events.
[08:44:24.811] <TB2> INFO: 41600 events read in total (3342ms).
[08:44:24.812] <TB2> INFO: Test took 4383ms.
[08:44:24.818] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:25.255] <TB2> INFO: Expecting 41600 events.
[08:44:29.413] <TB2> INFO: 41600 events read in total (3443ms).
[08:44:29.414] <TB2> INFO: Test took 4486ms.
[08:44:29.420] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:29.860] <TB2> INFO: Expecting 41600 events.
[08:44:34.188] <TB2> INFO: 41600 events read in total (3614ms).
[08:44:34.189] <TB2> INFO: Test took 4654ms.
[08:44:34.195] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:34.628] <TB2> INFO: Expecting 41600 events.
[08:44:39.094] <TB2> INFO: 41600 events read in total (3751ms).
[08:44:39.095] <TB2> INFO: Test took 4791ms.
[08:44:39.101] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:39.541] <TB2> INFO: Expecting 41600 events.
[08:44:43.880] <TB2> INFO: 41600 events read in total (3624ms).
[08:44:43.880] <TB2> INFO: Test took 4664ms.
[08:44:43.886] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:44.335] <TB2> INFO: Expecting 41600 events.
[08:44:48.626] <TB2> INFO: 41600 events read in total (3576ms).
[08:44:48.626] <TB2> INFO: Test took 4620ms.
[08:44:48.632] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:49.073] <TB2> INFO: Expecting 41600 events.
[08:44:53.383] <TB2> INFO: 41600 events read in total (3594ms).
[08:44:53.384] <TB2> INFO: Test took 4634ms.
[08:44:53.390] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:53.838] <TB2> INFO: Expecting 41600 events.
[08:44:57.918] <TB2> INFO: 41600 events read in total (3365ms).
[08:44:57.918] <TB2> INFO: Test took 4404ms.
[08:44:57.924] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:58.370] <TB2> INFO: Expecting 41600 events.
[08:45:02.423] <TB2> INFO: 41600 events read in total (3338ms).
[08:45:02.423] <TB2> INFO: Test took 4378ms.
[08:45:02.429] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:02.879] <TB2> INFO: Expecting 41600 events.
[08:45:07.182] <TB2> INFO: 41600 events read in total (3587ms).
[08:45:07.183] <TB2> INFO: Test took 4628ms.
[08:45:07.194] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:07.649] <TB2> INFO: Expecting 41600 events.
[08:45:12.002] <TB2> INFO: 41600 events read in total (3638ms).
[08:45:12.003] <TB2> INFO: Test took 4690ms.
[08:45:12.010] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:12.444] <TB2> INFO: Expecting 41600 events.
[08:45:16.904] <TB2> INFO: 41600 events read in total (3745ms).
[08:45:16.904] <TB2> INFO: Test took 4790ms.
[08:45:16.911] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:17.351] <TB2> INFO: Expecting 41600 events.
[08:45:21.813] <TB2> INFO: 41600 events read in total (3746ms).
[08:45:21.814] <TB2> INFO: Test took 4786ms.
[08:45:21.821] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:22.249] <TB2> INFO: Expecting 41600 events.
[08:45:26.669] <TB2> INFO: 41600 events read in total (3705ms).
[08:45:26.669] <TB2> INFO: Test took 4744ms.
[08:45:26.676] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:27.119] <TB2> INFO: Expecting 41600 events.
[08:45:31.532] <TB2> INFO: 41600 events read in total (3698ms).
[08:45:31.533] <TB2> INFO: Test took 4739ms.
[08:45:31.540] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:31.969] <TB2> INFO: Expecting 41600 events.
[08:45:36.462] <TB2> INFO: 41600 events read in total (3778ms).
[08:45:36.463] <TB2> INFO: Test took 4818ms.
[08:45:36.469] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:36.915] <TB2> INFO: Expecting 41600 events.
[08:45:41.122] <TB2> INFO: 41600 events read in total (3492ms).
[08:45:41.122] <TB2> INFO: Test took 4531ms.
[08:45:41.128] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:41.574] <TB2> INFO: Expecting 41600 events.
[08:45:45.831] <TB2> INFO: 41600 events read in total (3542ms).
[08:45:45.831] <TB2> INFO: Test took 4581ms.
[08:45:45.837] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:46.287] <TB2> INFO: Expecting 41600 events.
[08:45:50.493] <TB2> INFO: 41600 events read in total (3491ms).
[08:45:50.493] <TB2> INFO: Test took 4530ms.
[08:45:50.500] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:50.934] <TB2> INFO: Expecting 41600 events.
[08:45:55.184] <TB2> INFO: 41600 events read in total (3535ms).
[08:45:55.185] <TB2> INFO: Test took 4575ms.
[08:45:55.191] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:55.631] <TB2> INFO: Expecting 41600 events.
[08:46:00.035] <TB2> INFO: 41600 events read in total (3688ms).
[08:46:00.036] <TB2> INFO: Test took 4735ms.
[08:46:00.043] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:00.474] <TB2> INFO: Expecting 41600 events.
[08:46:04.881] <TB2> INFO: 41600 events read in total (3692ms).
[08:46:04.881] <TB2> INFO: Test took 4732ms.
[08:46:04.887] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:05.341] <TB2> INFO: Expecting 41600 events.
[08:46:09.723] <TB2> INFO: 41600 events read in total (3667ms).
[08:46:09.723] <TB2> INFO: Test took 4716ms.
[08:46:09.730] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:10.178] <TB2> INFO: Expecting 41600 events.
[08:46:14.500] <TB2> INFO: 41600 events read in total (3607ms).
[08:46:14.501] <TB2> INFO: Test took 4647ms.
[08:46:14.507] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:14.941] <TB2> INFO: Expecting 41600 events.
[08:46:19.418] <TB2> INFO: 41600 events read in total (3762ms).
[08:46:19.419] <TB2> INFO: Test took 4802ms.
[08:46:19.425] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:19.873] <TB2> INFO: Expecting 41600 events.
[08:46:24.157] <TB2> INFO: 41600 events read in total (3569ms).
[08:46:24.158] <TB2> INFO: Test took 4612ms.
[08:46:24.164] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:24.610] <TB2> INFO: Expecting 41600 events.
[08:46:28.936] <TB2> INFO: 41600 events read in total (3610ms).
[08:46:28.937] <TB2> INFO: Test took 4657ms.
[08:46:28.943] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:29.367] <TB2> INFO: Expecting 41600 events.
[08:46:33.699] <TB2> INFO: 41600 events read in total (3616ms).
[08:46:33.700] <TB2> INFO: Test took 4657ms.
[08:46:33.706] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:34.147] <TB2> INFO: Expecting 2560 events.
[08:46:35.112] <TB2> INFO: 2560 events read in total (250ms).
[08:46:35.112] <TB2> INFO: Test took 1398ms.
[08:46:35.621] <TB2> INFO: Expecting 2560 events.
[08:46:36.583] <TB2> INFO: 2560 events read in total (247ms).
[08:46:36.584] <TB2> INFO: Test took 1471ms.
[08:46:37.092] <TB2> INFO: Expecting 2560 events.
[08:46:38.055] <TB2> INFO: 2560 events read in total (248ms).
[08:46:38.056] <TB2> INFO: Test took 1472ms.
[08:46:38.565] <TB2> INFO: Expecting 2560 events.
[08:46:39.523] <TB2> INFO: 2560 events read in total (243ms).
[08:46:39.523] <TB2> INFO: Test took 1466ms.
[08:46:40.031] <TB2> INFO: Expecting 2560 events.
[08:46:40.989] <TB2> INFO: 2560 events read in total (242ms).
[08:46:40.989] <TB2> INFO: Test took 1465ms.
[08:46:41.498] <TB2> INFO: Expecting 2560 events.
[08:46:42.456] <TB2> INFO: 2560 events read in total (243ms).
[08:46:42.456] <TB2> INFO: Test took 1467ms.
[08:46:42.965] <TB2> INFO: Expecting 2560 events.
[08:46:43.937] <TB2> INFO: 2560 events read in total (256ms).
[08:46:43.937] <TB2> INFO: Test took 1481ms.
[08:46:44.447] <TB2> INFO: Expecting 2560 events.
[08:46:45.419] <TB2> INFO: 2560 events read in total (257ms).
[08:46:45.419] <TB2> INFO: Test took 1481ms.
[08:46:45.928] <TB2> INFO: Expecting 2560 events.
[08:46:46.886] <TB2> INFO: 2560 events read in total (243ms).
[08:46:46.886] <TB2> INFO: Test took 1466ms.
[08:46:47.395] <TB2> INFO: Expecting 2560 events.
[08:46:48.381] <TB2> INFO: 2560 events read in total (271ms).
[08:46:48.382] <TB2> INFO: Test took 1495ms.
[08:46:48.891] <TB2> INFO: Expecting 2560 events.
[08:46:49.855] <TB2> INFO: 2560 events read in total (249ms).
[08:46:49.855] <TB2> INFO: Test took 1473ms.
[08:46:50.364] <TB2> INFO: Expecting 2560 events.
[08:46:51.327] <TB2> INFO: 2560 events read in total (247ms).
[08:46:51.328] <TB2> INFO: Test took 1472ms.
[08:46:51.837] <TB2> INFO: Expecting 2560 events.
[08:46:52.809] <TB2> INFO: 2560 events read in total (257ms).
[08:46:52.809] <TB2> INFO: Test took 1481ms.
[08:46:53.318] <TB2> INFO: Expecting 2560 events.
[08:46:54.281] <TB2> INFO: 2560 events read in total (247ms).
[08:46:54.282] <TB2> INFO: Test took 1473ms.
[08:46:54.790] <TB2> INFO: Expecting 2560 events.
[08:46:55.762] <TB2> INFO: 2560 events read in total (257ms).
[08:46:55.763] <TB2> INFO: Test took 1481ms.
[08:46:56.272] <TB2> INFO: Expecting 2560 events.
[08:46:57.249] <TB2> INFO: 2560 events read in total (262ms).
[08:46:57.250] <TB2> INFO: Test took 1487ms.
[08:46:57.254] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:57.761] <TB2> INFO: Expecting 655360 events.
[08:47:10.254] <TB2> INFO: 655360 events read in total (11777ms).
[08:47:10.265] <TB2> INFO: Expecting 655360 events.
[08:47:23.941] <TB2> INFO: 655360 events read in total (13098ms).
[08:47:23.955] <TB2> INFO: Expecting 655360 events.
[08:47:35.448] <TB2> INFO: 655360 events read in total (10899ms).
[08:47:35.465] <TB2> INFO: Expecting 655360 events.
[08:47:46.948] <TB2> INFO: 655360 events read in total (10934ms).
[08:47:46.970] <TB2> INFO: Expecting 655360 events.
[08:48:00.053] <TB2> INFO: 655360 events read in total (12493ms).
[08:48:00.078] <TB2> INFO: Expecting 655360 events.
[08:48:12.268] <TB2> INFO: 655360 events read in total (11607ms).
[08:48:12.296] <TB2> INFO: Expecting 655360 events.
[08:48:24.682] <TB2> INFO: 655360 events read in total (11819ms).
[08:48:24.713] <TB2> INFO: Expecting 655360 events.
[08:48:37.658] <TB2> INFO: 655360 events read in total (12361ms).
[08:48:37.696] <TB2> INFO: Expecting 655360 events.
[08:48:50.151] <TB2> INFO: 655360 events read in total (11907ms).
[08:48:50.197] <TB2> INFO: Expecting 655360 events.
[08:49:02.855] <TB2> INFO: 655360 events read in total (12094ms).
[08:49:02.900] <TB2> INFO: Expecting 655360 events.
[08:49:15.527] <TB2> INFO: 655360 events read in total (12061ms).
[08:49:15.581] <TB2> INFO: Expecting 655360 events.
[08:49:28.742] <TB2> INFO: 655360 events read in total (12616ms).
[08:49:28.794] <TB2> INFO: Expecting 655360 events.
[08:49:41.890] <TB2> INFO: 655360 events read in total (12534ms).
[08:49:41.944] <TB2> INFO: Expecting 655360 events.
[08:49:54.989] <TB2> INFO: 655360 events read in total (12492ms).
[08:49:55.049] <TB2> INFO: Expecting 655360 events.
[08:50:08.147] <TB2> INFO: 655360 events read in total (12546ms).
[08:50:08.212] <TB2> INFO: Expecting 655360 events.
[08:50:21.993] <TB2> INFO: 655360 events read in total (13240ms).
[08:50:22.090] <TB2> INFO: Test took 204836ms.
[08:50:22.185] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:50:22.493] <TB2> INFO: Expecting 655360 events.
[08:50:36.932] <TB2> INFO: 655360 events read in total (13724ms).
[08:50:36.943] <TB2> INFO: Expecting 655360 events.
[08:50:51.078] <TB2> INFO: 655360 events read in total (13563ms).
[08:50:51.092] <TB2> INFO: Expecting 655360 events.
[08:51:04.643] <TB2> INFO: 655360 events read in total (12951ms).
[08:51:04.659] <TB2> INFO: Expecting 655360 events.
[08:51:18.345] <TB2> INFO: 655360 events read in total (13088ms).
[08:51:18.367] <TB2> INFO: Expecting 655360 events.
[08:51:31.292] <TB2> INFO: 655360 events read in total (12342ms).
[08:51:31.319] <TB2> INFO: Expecting 655360 events.
[08:51:43.782] <TB2> INFO: 655360 events read in total (11879ms).
[08:51:43.812] <TB2> INFO: Expecting 655360 events.
[08:51:56.423] <TB2> INFO: 655360 events read in total (12033ms).
[08:51:56.456] <TB2> INFO: Expecting 655360 events.
[08:52:09.096] <TB2> INFO: 655360 events read in total (12067ms).
[08:52:09.132] <TB2> INFO: Expecting 655360 events.
[08:52:22.063] <TB2> INFO: 655360 events read in total (12344ms).
[08:52:22.104] <TB2> INFO: Expecting 655360 events.
[08:52:35.175] <TB2> INFO: 655360 events read in total (12494ms).
[08:52:35.221] <TB2> INFO: Expecting 655360 events.
[08:52:49.288] <TB2> INFO: 655360 events read in total (13511ms).
[08:52:49.337] <TB2> INFO: Expecting 655360 events.
[08:53:03.430] <TB2> INFO: 655360 events read in total (13532ms).
[08:53:03.481] <TB2> INFO: Expecting 655360 events.
[08:53:17.630] <TB2> INFO: 655360 events read in total (13586ms).
[08:53:17.685] <TB2> INFO: Expecting 655360 events.
[08:53:32.718] <TB2> INFO: 655360 events read in total (14468ms).
[08:53:32.779] <TB2> INFO: Expecting 655360 events.
[08:53:48.528] <TB2> INFO: 655360 events read in total (15199ms).
[08:53:48.608] <TB2> INFO: Expecting 655360 events.
[08:54:03.653] <TB2> INFO: 655360 events read in total (14518ms).
[08:54:03.719] <TB2> INFO: Test took 221534ms.
[08:54:03.919] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.926] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.932] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.939] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.946] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.953] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.959] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.966] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.973] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.980] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.986] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:03.993] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:04.000] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:04.007] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:04.014] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:04.020] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:54:04.076] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:54:04.077] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:54:04.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:54:04.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:54:04.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:54:04.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:54:04.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:54:04.388] <TB2> INFO: Expecting 41600 events.
[08:54:08.674] <TB2> INFO: 41600 events read in total (3570ms).
[08:54:08.674] <TB2> INFO: Test took 4591ms.
[08:54:09.322] <TB2> INFO: Expecting 41600 events.
[08:54:13.577] <TB2> INFO: 41600 events read in total (3539ms).
[08:54:13.578] <TB2> INFO: Test took 4571ms.
[08:54:14.227] <TB2> INFO: Expecting 41600 events.
[08:54:18.262] <TB2> INFO: 41600 events read in total (3320ms).
[08:54:18.262] <TB2> INFO: Test took 4347ms.
[08:54:18.600] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:18.733] <TB2> INFO: Expecting 2560 events.
[08:54:19.711] <TB2> INFO: 2560 events read in total (263ms).
[08:54:19.711] <TB2> INFO: Test took 1111ms.
[08:54:19.714] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:20.223] <TB2> INFO: Expecting 2560 events.
[08:54:21.243] <TB2> INFO: 2560 events read in total (305ms).
[08:54:21.244] <TB2> INFO: Test took 1530ms.
[08:54:21.247] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:21.755] <TB2> INFO: Expecting 2560 events.
[08:54:22.732] <TB2> INFO: 2560 events read in total (262ms).
[08:54:22.733] <TB2> INFO: Test took 1487ms.
[08:54:22.736] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:23.244] <TB2> INFO: Expecting 2560 events.
[08:54:24.230] <TB2> INFO: 2560 events read in total (271ms).
[08:54:24.231] <TB2> INFO: Test took 1495ms.
[08:54:24.234] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:24.741] <TB2> INFO: Expecting 2560 events.
[08:54:25.699] <TB2> INFO: 2560 events read in total (242ms).
[08:54:25.699] <TB2> INFO: Test took 1465ms.
[08:54:25.702] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:26.211] <TB2> INFO: Expecting 2560 events.
[08:54:27.190] <TB2> INFO: 2560 events read in total (263ms).
[08:54:27.190] <TB2> INFO: Test took 1488ms.
[08:54:27.193] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:27.701] <TB2> INFO: Expecting 2560 events.
[08:54:28.664] <TB2> INFO: 2560 events read in total (247ms).
[08:54:28.665] <TB2> INFO: Test took 1472ms.
[08:54:28.668] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:29.176] <TB2> INFO: Expecting 2560 events.
[08:54:30.139] <TB2> INFO: 2560 events read in total (248ms).
[08:54:30.139] <TB2> INFO: Test took 1471ms.
[08:54:30.142] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:30.650] <TB2> INFO: Expecting 2560 events.
[08:54:31.615] <TB2> INFO: 2560 events read in total (250ms).
[08:54:31.616] <TB2> INFO: Test took 1474ms.
[08:54:31.619] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:32.127] <TB2> INFO: Expecting 2560 events.
[08:54:33.134] <TB2> INFO: 2560 events read in total (292ms).
[08:54:33.134] <TB2> INFO: Test took 1515ms.
[08:54:33.137] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:33.644] <TB2> INFO: Expecting 2560 events.
[08:54:34.623] <TB2> INFO: 2560 events read in total (264ms).
[08:54:34.624] <TB2> INFO: Test took 1487ms.
[08:54:34.627] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:35.135] <TB2> INFO: Expecting 2560 events.
[08:54:36.126] <TB2> INFO: 2560 events read in total (276ms).
[08:54:36.127] <TB2> INFO: Test took 1500ms.
[08:54:36.130] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:36.638] <TB2> INFO: Expecting 2560 events.
[08:54:37.629] <TB2> INFO: 2560 events read in total (277ms).
[08:54:37.630] <TB2> INFO: Test took 1500ms.
[08:54:37.634] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:38.140] <TB2> INFO: Expecting 2560 events.
[08:54:39.105] <TB2> INFO: 2560 events read in total (249ms).
[08:54:39.105] <TB2> INFO: Test took 1472ms.
[08:54:39.108] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:39.617] <TB2> INFO: Expecting 2560 events.
[08:54:40.582] <TB2> INFO: 2560 events read in total (250ms).
[08:54:40.583] <TB2> INFO: Test took 1475ms.
[08:54:40.586] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:41.094] <TB2> INFO: Expecting 2560 events.
[08:54:42.057] <TB2> INFO: 2560 events read in total (248ms).
[08:54:42.057] <TB2> INFO: Test took 1471ms.
[08:54:42.060] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:42.568] <TB2> INFO: Expecting 2560 events.
[08:54:43.531] <TB2> INFO: 2560 events read in total (247ms).
[08:54:43.531] <TB2> INFO: Test took 1471ms.
[08:54:43.534] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:44.042] <TB2> INFO: Expecting 2560 events.
[08:54:45.004] <TB2> INFO: 2560 events read in total (247ms).
[08:54:45.004] <TB2> INFO: Test took 1470ms.
[08:54:45.007] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:45.515] <TB2> INFO: Expecting 2560 events.
[08:54:46.493] <TB2> INFO: 2560 events read in total (262ms).
[08:54:46.493] <TB2> INFO: Test took 1486ms.
[08:54:46.496] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:47.004] <TB2> INFO: Expecting 2560 events.
[08:54:48.011] <TB2> INFO: 2560 events read in total (292ms).
[08:54:48.011] <TB2> INFO: Test took 1515ms.
[08:54:48.014] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:48.521] <TB2> INFO: Expecting 2560 events.
[08:54:49.543] <TB2> INFO: 2560 events read in total (307ms).
[08:54:49.543] <TB2> INFO: Test took 1529ms.
[08:54:49.547] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:50.054] <TB2> INFO: Expecting 2560 events.
[08:54:51.017] <TB2> INFO: 2560 events read in total (248ms).
[08:54:51.017] <TB2> INFO: Test took 1470ms.
[08:54:51.020] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:51.529] <TB2> INFO: Expecting 2560 events.
[08:54:52.488] <TB2> INFO: 2560 events read in total (244ms).
[08:54:52.489] <TB2> INFO: Test took 1469ms.
[08:54:52.492] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:53.000] <TB2> INFO: Expecting 2560 events.
[08:54:53.963] <TB2> INFO: 2560 events read in total (248ms).
[08:54:53.964] <TB2> INFO: Test took 1472ms.
[08:54:53.967] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:54.475] <TB2> INFO: Expecting 2560 events.
[08:54:55.438] <TB2> INFO: 2560 events read in total (248ms).
[08:54:55.438] <TB2> INFO: Test took 1472ms.
[08:54:55.441] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:55.949] <TB2> INFO: Expecting 2560 events.
[08:54:56.912] <TB2> INFO: 2560 events read in total (247ms).
[08:54:56.912] <TB2> INFO: Test took 1471ms.
[08:54:56.916] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:57.424] <TB2> INFO: Expecting 2560 events.
[08:54:58.396] <TB2> INFO: 2560 events read in total (257ms).
[08:54:58.397] <TB2> INFO: Test took 1482ms.
[08:54:58.399] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:58.909] <TB2> INFO: Expecting 2560 events.
[08:54:59.916] <TB2> INFO: 2560 events read in total (292ms).
[08:54:59.917] <TB2> INFO: Test took 1518ms.
[08:54:59.920] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:55:00.427] <TB2> INFO: Expecting 2560 events.
[08:55:01.401] <TB2> INFO: 2560 events read in total (258ms).
[08:55:01.401] <TB2> INFO: Test took 1481ms.
[08:55:01.405] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:55:01.912] <TB2> INFO: Expecting 2560 events.
[08:55:02.876] <TB2> INFO: 2560 events read in total (249ms).
[08:55:02.876] <TB2> INFO: Test took 1471ms.
[08:55:02.880] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:55:03.386] <TB2> INFO: Expecting 2560 events.
[08:55:04.378] <TB2> INFO: 2560 events read in total (277ms).
[08:55:04.379] <TB2> INFO: Test took 1499ms.
[08:55:04.382] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:55:04.889] <TB2> INFO: Expecting 2560 events.
[08:55:05.854] <TB2> INFO: 2560 events read in total (249ms).
[08:55:05.854] <TB2> INFO: Test took 1472ms.
[08:55:06.640] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 768 seconds
[08:55:06.640] <TB2> INFO: PH scale (per ROC): 80 71 74 77 76 80 74 74 78 83 77 80 75 82 76 78
[08:55:06.640] <TB2> INFO: PH offset (per ROC): 157 159 165 164 170 162 163 151 161 149 174 150 170 150 161 166
[08:55:06.647] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:55:06.647] <TB2> INFO: Decoding statistics:
[08:55:06.647] <TB2> INFO: General information:
[08:55:06.647] <TB2> INFO: 16bit words read: 66436
[08:55:06.647] <TB2> INFO: valid events total: 5120
[08:55:06.647] <TB2> INFO: empty events: 2622
[08:55:06.647] <TB2> INFO: valid events with pixels: 2498
[08:55:06.647] <TB2> INFO: valid pixel hits: 2498
[08:55:06.647] <TB2> INFO: Event errors: 0
[08:55:06.647] <TB2> INFO: start marker: 0
[08:55:06.647] <TB2> INFO: stop marker: 0
[08:55:06.647] <TB2> INFO: overflow: 0
[08:55:06.647] <TB2> INFO: invalid 5bit words: 0
[08:55:06.647] <TB2> INFO: invalid XOR eye diagram: 0
[08:55:06.647] <TB2> INFO: TBM errors: 0
[08:55:06.647] <TB2> INFO: flawed TBM headers: 0
[08:55:06.647] <TB2> INFO: flawed TBM trailers: 0
[08:55:06.647] <TB2> INFO: event ID mismatches: 0
[08:55:06.647] <TB2> INFO: ROC errors: 0
[08:55:06.647] <TB2> INFO: missing ROC header(s): 0
[08:55:06.647] <TB2> INFO: misplaced readback start: 0
[08:55:06.647] <TB2> INFO: Pixel decoding errors: 0
[08:55:06.647] <TB2> INFO: pixel data incomplete: 0
[08:55:06.647] <TB2> INFO: pixel address: 0
[08:55:06.647] <TB2> INFO: pulse height fill bit: 0
[08:55:06.647] <TB2> INFO: buffer corruption: 0
[08:55:06.867] <TB2> INFO: ######################################################################
[08:55:06.867] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:55:06.867] <TB2> INFO: ######################################################################
[08:55:06.879] <TB2> INFO: scanning low vcal = 10
[08:55:07.224] <TB2> INFO: Expecting 41600 events.
[08:55:11.235] <TB2> INFO: 41600 events read in total (3296ms).
[08:55:11.235] <TB2> INFO: Test took 4356ms.
[08:55:11.238] <TB2> INFO: scanning low vcal = 20
[08:55:11.746] <TB2> INFO: Expecting 41600 events.
[08:55:15.651] <TB2> INFO: 41600 events read in total (3190ms).
[08:55:15.651] <TB2> INFO: Test took 4413ms.
[08:55:15.654] <TB2> INFO: scanning low vcal = 30
[08:55:16.162] <TB2> INFO: Expecting 41600 events.
[08:55:20.024] <TB2> INFO: 41600 events read in total (3147ms).
[08:55:20.025] <TB2> INFO: Test took 4371ms.
[08:55:20.027] <TB2> INFO: scanning low vcal = 40
[08:55:20.524] <TB2> INFO: Expecting 41600 events.
[08:55:25.168] <TB2> INFO: 41600 events read in total (3929ms).
[08:55:25.169] <TB2> INFO: Test took 5142ms.
[08:55:25.172] <TB2> INFO: scanning low vcal = 50
[08:55:25.618] <TB2> INFO: Expecting 41600 events.
[08:55:30.280] <TB2> INFO: 41600 events read in total (3946ms).
[08:55:30.281] <TB2> INFO: Test took 5109ms.
[08:55:30.285] <TB2> INFO: scanning low vcal = 60
[08:55:30.715] <TB2> INFO: Expecting 41600 events.
[08:55:35.203] <TB2> INFO: 41600 events read in total (3773ms).
[08:55:35.204] <TB2> INFO: Test took 4919ms.
[08:55:35.207] <TB2> INFO: scanning low vcal = 70
[08:55:35.620] <TB2> INFO: Expecting 41600 events.
[08:55:40.643] <TB2> INFO: 41600 events read in total (4308ms).
[08:55:40.644] <TB2> INFO: Test took 5437ms.
[08:55:40.647] <TB2> INFO: scanning low vcal = 80
[08:55:41.086] <TB2> INFO: Expecting 41600 events.
[08:55:45.517] <TB2> INFO: 41600 events read in total (3715ms).
[08:55:45.518] <TB2> INFO: Test took 4871ms.
[08:55:45.522] <TB2> INFO: scanning low vcal = 90
[08:55:45.969] <TB2> INFO: Expecting 41600 events.
[08:55:50.576] <TB2> INFO: 41600 events read in total (3892ms).
[08:55:50.577] <TB2> INFO: Test took 5054ms.
[08:55:50.580] <TB2> INFO: scanning low vcal = 100
[08:55:51.031] <TB2> INFO: Expecting 41600 events.
[08:55:55.565] <TB2> INFO: 41600 events read in total (3818ms).
[08:55:55.566] <TB2> INFO: Test took 4986ms.
[08:55:55.569] <TB2> INFO: scanning low vcal = 110
[08:55:56.005] <TB2> INFO: Expecting 41600 events.
[08:56:00.356] <TB2> INFO: 41600 events read in total (3635ms).
[08:56:00.357] <TB2> INFO: Test took 4788ms.
[08:56:00.360] <TB2> INFO: scanning low vcal = 120
[08:56:00.798] <TB2> INFO: Expecting 41600 events.
[08:56:05.102] <TB2> INFO: 41600 events read in total (3589ms).
[08:56:05.103] <TB2> INFO: Test took 4743ms.
[08:56:05.106] <TB2> INFO: scanning low vcal = 130
[08:56:05.556] <TB2> INFO: Expecting 41600 events.
[08:56:09.935] <TB2> INFO: 41600 events read in total (3664ms).
[08:56:09.935] <TB2> INFO: Test took 4829ms.
[08:56:09.939] <TB2> INFO: scanning low vcal = 140
[08:56:10.391] <TB2> INFO: Expecting 41600 events.
[08:56:14.712] <TB2> INFO: 41600 events read in total (3606ms).
[08:56:14.713] <TB2> INFO: Test took 4774ms.
[08:56:14.716] <TB2> INFO: scanning low vcal = 150
[08:56:15.134] <TB2> INFO: Expecting 41600 events.
[08:56:19.441] <TB2> INFO: 41600 events read in total (3592ms).
[08:56:19.442] <TB2> INFO: Test took 4726ms.
[08:56:19.445] <TB2> INFO: scanning low vcal = 160
[08:56:19.895] <TB2> INFO: Expecting 41600 events.
[08:56:24.266] <TB2> INFO: 41600 events read in total (3656ms).
[08:56:24.267] <TB2> INFO: Test took 4822ms.
[08:56:24.270] <TB2> INFO: scanning low vcal = 170
[08:56:24.716] <TB2> INFO: Expecting 41600 events.
[08:56:29.271] <TB2> INFO: 41600 events read in total (3839ms).
[08:56:29.271] <TB2> INFO: Test took 5001ms.
[08:56:29.276] <TB2> INFO: scanning low vcal = 180
[08:56:29.690] <TB2> INFO: Expecting 41600 events.
[08:56:34.160] <TB2> INFO: 41600 events read in total (3754ms).
[08:56:34.160] <TB2> INFO: Test took 4884ms.
[08:56:34.163] <TB2> INFO: scanning low vcal = 190
[08:56:34.611] <TB2> INFO: Expecting 41600 events.
[08:56:39.041] <TB2> INFO: 41600 events read in total (3715ms).
[08:56:39.041] <TB2> INFO: Test took 4878ms.
[08:56:39.045] <TB2> INFO: scanning low vcal = 200
[08:56:39.493] <TB2> INFO: Expecting 41600 events.
[08:56:43.917] <TB2> INFO: 41600 events read in total (3708ms).
[08:56:43.918] <TB2> INFO: Test took 4873ms.
[08:56:43.921] <TB2> INFO: scanning low vcal = 210
[08:56:44.370] <TB2> INFO: Expecting 41600 events.
[08:56:48.978] <TB2> INFO: 41600 events read in total (3892ms).
[08:56:48.978] <TB2> INFO: Test took 5057ms.
[08:56:48.981] <TB2> INFO: scanning low vcal = 220
[08:56:49.432] <TB2> INFO: Expecting 41600 events.
[08:56:53.819] <TB2> INFO: 41600 events read in total (3672ms).
[08:56:53.819] <TB2> INFO: Test took 4838ms.
[08:56:53.823] <TB2> INFO: scanning low vcal = 230
[08:56:54.272] <TB2> INFO: Expecting 41600 events.
[08:56:58.727] <TB2> INFO: 41600 events read in total (3740ms).
[08:56:58.728] <TB2> INFO: Test took 4905ms.
[08:56:58.731] <TB2> INFO: scanning low vcal = 240
[08:56:59.180] <TB2> INFO: Expecting 41600 events.
[08:57:03.630] <TB2> INFO: 41600 events read in total (3735ms).
[08:57:03.631] <TB2> INFO: Test took 4900ms.
[08:57:03.634] <TB2> INFO: scanning low vcal = 250
[08:57:04.077] <TB2> INFO: Expecting 41600 events.
[08:57:08.690] <TB2> INFO: 41600 events read in total (3897ms).
[08:57:08.691] <TB2> INFO: Test took 5057ms.
[08:57:08.696] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[08:57:09.147] <TB2> INFO: Expecting 41600 events.
[08:57:13.906] <TB2> INFO: 41600 events read in total (4044ms).
[08:57:13.906] <TB2> INFO: Test took 5210ms.
[08:57:13.910] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[08:57:14.362] <TB2> INFO: Expecting 41600 events.
[08:57:19.084] <TB2> INFO: 41600 events read in total (4007ms).
[08:57:19.084] <TB2> INFO: Test took 5174ms.
[08:57:19.088] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[08:57:19.539] <TB2> INFO: Expecting 41600 events.
[08:57:24.118] <TB2> INFO: 41600 events read in total (3864ms).
[08:57:24.119] <TB2> INFO: Test took 5031ms.
[08:57:24.123] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[08:57:24.571] <TB2> INFO: Expecting 41600 events.
[08:57:29.117] <TB2> INFO: 41600 events read in total (3831ms).
[08:57:29.117] <TB2> INFO: Test took 4994ms.
[08:57:29.120] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:57:29.562] <TB2> INFO: Expecting 41600 events.
[08:57:34.062] <TB2> INFO: 41600 events read in total (3784ms).
[08:57:34.063] <TB2> INFO: Test took 4943ms.
[08:57:34.563] <TB2> INFO: PixTestGainPedestal::measure() done
[08:58:08.091] <TB2> INFO: PixTestGainPedestal::fit() done
[08:58:08.091] <TB2> INFO: non-linearity mean: 0.950 0.955 0.963 0.952 0.956 0.955 0.955 0.956 0.952 0.953 0.955 0.957 0.956 0.961 0.955 0.957
[08:58:08.091] <TB2> INFO: non-linearity RMS: 0.007 0.006 0.007 0.006 0.006 0.005 0.006 0.006 0.008 0.005 0.006 0.006 0.005 0.004 0.006 0.006
[08:58:08.091] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:58:08.109] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:58:08.126] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:58:08.144] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:58:08.162] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:58:08.179] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:58:08.196] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:58:08.214] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:58:08.231] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:58:08.248] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:58:08.266] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:58:08.284] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:58:08.301] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:58:08.319] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:58:08.336] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:58:08.354] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:58:08.371] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 181 seconds
[08:58:08.371] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:58:08.371] <TB2> INFO: Decoding statistics:
[08:58:08.371] <TB2> INFO: General information:
[08:58:08.371] <TB2> INFO: 16bit words read: 2329500
[08:58:08.371] <TB2> INFO: valid events total: 83200
[08:58:08.371] <TB2> INFO: empty events: 0
[08:58:08.371] <TB2> INFO: valid events with pixels: 83200
[08:58:08.371] <TB2> INFO: valid pixel hits: 665550
[08:58:08.371] <TB2> INFO: Event errors: 0
[08:58:08.371] <TB2> INFO: start marker: 0
[08:58:08.371] <TB2> INFO: stop marker: 0
[08:58:08.371] <TB2> INFO: overflow: 0
[08:58:08.371] <TB2> INFO: invalid 5bit words: 0
[08:58:08.371] <TB2> INFO: invalid XOR eye diagram: 0
[08:58:08.371] <TB2> INFO: TBM errors: 0
[08:58:08.371] <TB2> INFO: flawed TBM headers: 0
[08:58:08.371] <TB2> INFO: flawed TBM trailers: 0
[08:58:08.371] <TB2> INFO: event ID mismatches: 0
[08:58:08.371] <TB2> INFO: ROC errors: 0
[08:58:08.371] <TB2> INFO: missing ROC header(s): 0
[08:58:08.371] <TB2> INFO: misplaced readback start: 0
[08:58:08.371] <TB2> INFO: Pixel decoding errors: 0
[08:58:08.371] <TB2> INFO: pixel data incomplete: 0
[08:58:08.371] <TB2> INFO: pixel address: 0
[08:58:08.371] <TB2> INFO: pulse height fill bit: 0
[08:58:08.371] <TB2> INFO: buffer corruption: 0
[08:58:08.377] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat
[08:58:08.378] <TB2> INFO: ######################################################################
[08:58:08.378] <TB2> INFO: PixTestReadback::doTest()
[08:58:08.378] <TB2> INFO: ######################################################################
[08:58:08.379] <TB2> INFO: PixTestReadback::RES sent once
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat
[08:58:19.701] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat
[08:58:19.702] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat
[08:58:19.702] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat
[08:58:19.702] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat
[08:58:19.702] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat
[08:58:19.702] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat
[08:58:19.702] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat
[08:58:19.702] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat
[08:58:19.750] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:58:19.751] <TB2> INFO: PixTestReadback::RES sent once
[08:58:31.013] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat
[08:58:31.014] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat
[08:58:31.014] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat
[08:58:31.014] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat
[08:58:31.014] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat
[08:58:31.014] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat
[08:58:31.014] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat
[08:58:31.015] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat
[08:58:31.015] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat
[08:58:31.015] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat
[08:58:31.015] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat
[08:58:31.015] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat
[08:58:31.015] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat
[08:58:31.016] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat
[08:58:31.016] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat
[08:58:31.016] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat
[08:58:31.063] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:58:31.063] <TB2> INFO: PixTestReadback::RES sent once
[08:58:39.701] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:58:39.701] <TB2> INFO: Vbg will be calibrated using Vd calibration
[08:58:39.701] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.2calibrated Vbg = 1.21732 :::*/*/*/*/
[08:58:39.701] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.4calibrated Vbg = 1.23138 :::*/*/*/*/
[08:58:39.701] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.9calibrated Vbg = 1.22366 :::*/*/*/*/
[08:58:39.701] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.3calibrated Vbg = 1.2306 :::*/*/*/*/
[08:58:39.701] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.9calibrated Vbg = 1.2283 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 161.8calibrated Vbg = 1.22855 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151calibrated Vbg = 1.23432 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 159.8calibrated Vbg = 1.23356 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 160.6calibrated Vbg = 1.23705 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 142.3calibrated Vbg = 1.24066 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149calibrated Vbg = 1.23903 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 156.3calibrated Vbg = 1.23695 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 160calibrated Vbg = 1.22723 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 158.4calibrated Vbg = 1.21718 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.6calibrated Vbg = 1.21631 :::*/*/*/*/
[08:58:39.702] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147.9calibrated Vbg = 1.22642 :::*/*/*/*/
[08:58:39.706] <TB2> INFO: PixTestReadback::RES sent once
[09:01:34.551] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat
[09:01:34.552] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4562_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat
[09:01:34.598] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:01:34.599] <TB2> INFO: PixTestReadback::doTest() done
[09:01:34.599] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:34.599] <TB2> INFO: Decoding statistics:
[09:01:34.599] <TB2> INFO: General information:
[09:01:34.599] <TB2> INFO: 16bit words read: 768
[09:01:34.599] <TB2> INFO: valid events total: 64
[09:01:34.599] <TB2> INFO: empty events: 64
[09:01:34.599] <TB2> INFO: valid events with pixels: 0
[09:01:34.599] <TB2> INFO: valid pixel hits: 0
[09:01:34.599] <TB2> INFO: Event errors: 0
[09:01:34.599] <TB2> INFO: start marker: 0
[09:01:34.599] <TB2> INFO: stop marker: 0
[09:01:34.599] <TB2> INFO: overflow: 0
[09:01:34.599] <TB2> INFO: invalid 5bit words: 0
[09:01:34.599] <TB2> INFO: invalid XOR eye diagram: 0
[09:01:34.599] <TB2> INFO: TBM errors: 0
[09:01:34.599] <TB2> INFO: flawed TBM headers: 0
[09:01:34.599] <TB2> INFO: flawed TBM trailers: 0
[09:01:34.599] <TB2> INFO: event ID mismatches: 0
[09:01:34.599] <TB2> INFO: ROC errors: 0
[09:01:34.599] <TB2> INFO: missing ROC header(s): 0
[09:01:34.599] <TB2> INFO: misplaced readback start: 0
[09:01:34.599] <TB2> INFO: Pixel decoding errors: 0
[09:01:34.599] <TB2> INFO: pixel data incomplete: 0
[09:01:34.599] <TB2> INFO: pixel address: 0
[09:01:34.599] <TB2> INFO: pulse height fill bit: 0
[09:01:34.599] <TB2> INFO: buffer corruption: 0
[09:01:34.612] <TB2> INFO: Decoding statistics:
[09:01:34.612] <TB2> INFO: General information:
[09:01:34.612] <TB2> INFO: 16bit words read: 2396704
[09:01:34.612] <TB2> INFO: valid events total: 88384
[09:01:34.612] <TB2> INFO: empty events: 2686
[09:01:34.612] <TB2> INFO: valid events with pixels: 85698
[09:01:34.612] <TB2> INFO: valid pixel hits: 668048
[09:01:34.612] <TB2> INFO: Event errors: 0
[09:01:34.612] <TB2> INFO: start marker: 0
[09:01:34.612] <TB2> INFO: stop marker: 0
[09:01:34.612] <TB2> INFO: overflow: 0
[09:01:34.612] <TB2> INFO: invalid 5bit words: 0
[09:01:34.612] <TB2> INFO: invalid XOR eye diagram: 0
[09:01:34.612] <TB2> INFO: TBM errors: 0
[09:01:34.612] <TB2> INFO: flawed TBM headers: 0
[09:01:34.612] <TB2> INFO: flawed TBM trailers: 0
[09:01:34.612] <TB2> INFO: event ID mismatches: 0
[09:01:34.612] <TB2> INFO: ROC errors: 0
[09:01:34.612] <TB2> INFO: missing ROC header(s): 0
[09:01:34.612] <TB2> INFO: misplaced readback start: 0
[09:01:34.612] <TB2> INFO: Pixel decoding errors: 0
[09:01:34.612] <TB2> INFO: pixel data incomplete: 0
[09:01:34.612] <TB2> INFO: pixel address: 0
[09:01:34.612] <TB2> INFO: pulse height fill bit: 0
[09:01:34.612] <TB2> INFO: buffer corruption: 0
[09:01:34.612] <TB2> INFO: enter test to run
[09:01:34.612] <TB2> INFO: test: exit no parameter change
[09:01:34.913] <TB2> QUIET: Connection to board 20 closed.
[09:01:34.996] <TB2> INFO: pXar: this is the end, my friend