[11:00:40.970] <TB0> INFO: *** Welcome to pxar ***
[11:00:40.970] <TB0> INFO: *** Today: 2015/09/21
[11:00:41.436] <TB0> INFO: *** Version: bccb-dirty
[11:00:41.436] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C15.dat
[11:00:41.437] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//tbmParameters_C0b.dat
[11:00:41.437] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//defaultMaskFile.dat
[11:00:41.437] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters_C15.dat
[11:00:41.502] <TB0> INFO: clk: 4
[11:00:41.502] <TB0> INFO: ctr: 4
[11:00:41.502] <TB0> INFO: sda: 19
[11:00:41.502] <TB0> INFO: tin: 9
[11:00:41.502] <TB0> INFO: level: 15
[11:00:41.502] <TB0> INFO: triggerdelay: 0
[11:00:41.502] <TB0> QUIET: Instanciating API for pxar prod-11
[11:00:41.502] <TB0> INFO: Log level: INFO
[11:00:41.510] <TB0> INFO: Found DTB DTB_WS6AYH
[11:00:41.520] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[11:00:41.524] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[11:00:41.526] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[11:00:43.041] <TB0> INFO: DUT info:
[11:00:43.041] <TB0> INFO: The DUT currently contains the following objects:
[11:00:43.041] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[11:00:43.041] <TB0> INFO: TBM Core alpha (0): 7 registers set
[11:00:43.041] <TB0> INFO: TBM Core beta (1): 7 registers set
[11:00:43.041] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:00:43.041] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.041] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:43.442] <TB0> INFO: enter 'restricted' command line mode
[11:00:43.442] <TB0> INFO: enter test to run
[11:00:43.442] <TB0> INFO: test: pretest no parameter change
[11:00:43.442] <TB0> INFO: running: pretest
[11:00:43.447] <TB0> INFO: ######################################################################
[11:00:43.447] <TB0> INFO: PixTestPretest::doTest()
[11:00:43.447] <TB0> INFO: ######################################################################
[11:00:43.449] <TB0> INFO: ----------------------------------------------------------------------
[11:00:43.449] <TB0> INFO: PixTestPretest::programROC()
[11:00:43.449] <TB0> INFO: ----------------------------------------------------------------------
[11:01:01.468] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[11:01:01.468] <TB0> INFO: IA differences per ROC: 18.5 18.5 16.1 20.1 20.9 20.1 19.3 18.5 16.9 19.3 19.3 17.7 18.5 19.3 17.7 17.7
[11:01:01.545] <TB0> INFO: ----------------------------------------------------------------------
[11:01:01.545] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[11:01:01.545] <TB0> INFO: ----------------------------------------------------------------------
[11:01:05.317] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 389.1 mA = 24.3188 mA/ROC
[11:01:05.320] <TB0> INFO: ----------------------------------------------------------------------
[11:01:05.320] <TB0> INFO: PixTestPretest::findTiming()
[11:01:05.320] <TB0> INFO: ----------------------------------------------------------------------
[11:01:05.320] <TB0> INFO: PixTestCmd::init()

[11:02:52.549] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[11:02:52.549] <TB0> INFO: (success/tries = 100/100), width = 4
[11:02:52.551] <TB0> INFO: ----------------------------------------------------------------------
[11:02:52.551] <TB0> INFO: PixTestPretest::findWorkingPixel()
[11:02:52.551] <TB0> INFO: ----------------------------------------------------------------------
[11:02:52.690] <TB0> INFO: Expecting 231680 events.
[11:03:01.764] <TB0> INFO: 231680 events read in total (8358ms).
[11:03:01.768] <TB0> INFO: Test took 9212ms.
[11:03:02.073] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[11:03:02.111] <TB0> INFO: ----------------------------------------------------------------------
[11:03:02.111] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[11:03:02.111] <TB0> INFO: ----------------------------------------------------------------------
[11:03:02.248] <TB0> INFO: Expecting 231680 events.
[11:03:11.650] <TB0> INFO: 231680 events read in total (8685ms).
[11:03:11.655] <TB0> INFO: Test took 9538ms.
[11:03:11.980] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[11:03:11.980] <TB0> INFO: CalDel: 129 157 138 154 162 154 182 143 143 143 143 149 137 133 133 155
[11:03:11.980] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[11:03:11.984] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C0.dat
[11:03:11.984] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C1.dat
[11:03:11.984] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C2.dat
[11:03:11.984] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C3.dat
[11:03:11.985] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C4.dat
[11:03:11.985] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C5.dat
[11:03:11.985] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C6.dat
[11:03:11.985] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C7.dat
[11:03:11.986] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C8.dat
[11:03:11.986] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C9.dat
[11:03:11.986] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C10.dat
[11:03:11.986] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C11.dat
[11:03:11.987] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C12.dat
[11:03:11.987] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C13.dat
[11:03:11.987] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C14.dat
[11:03:11.987] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters_C15.dat
[11:03:11.987] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//tbmParameters_C0a.dat
[11:03:11.988] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//tbmParameters_C0b.dat
[11:03:11.988] <TB0> INFO: PixTestPretest::doTest() done, duration: 148 seconds
[11:03:12.055] <TB0> INFO: enter test to run
[11:03:12.055] <TB0> INFO: test: fulltest no parameter change
[11:03:12.055] <TB0> INFO: running: fulltest
[11:03:12.055] <TB0> INFO: ######################################################################
[11:03:12.055] <TB0> INFO: PixTestFullTest::doTest()
[11:03:12.055] <TB0> INFO: ######################################################################
[11:03:12.056] <TB0> INFO: ######################################################################
[11:03:12.056] <TB0> INFO: PixTestAlive::doTest()
[11:03:12.056] <TB0> INFO: ######################################################################
[11:03:12.058] <TB0> INFO: ----------------------------------------------------------------------
[11:03:12.058] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:03:12.058] <TB0> INFO: ----------------------------------------------------------------------
[11:03:12.368] <TB0> INFO: Expecting 41600 events.
[11:03:17.305] <TB0> INFO: 41600 events read in total (4220ms).
[11:03:17.306] <TB0> INFO: Test took 5247ms.
[11:03:17.311] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:17.600] <TB0> INFO: PixTestAlive::aliveTest() done
[11:03:17.600] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[11:03:17.602] <TB0> INFO: ----------------------------------------------------------------------
[11:03:17.602] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:03:17.602] <TB0> INFO: ----------------------------------------------------------------------
[11:03:17.917] <TB0> INFO: Expecting 41600 events.
[11:03:21.357] <TB0> INFO: 41600 events read in total (2724ms).
[11:03:21.357] <TB0> INFO: Test took 3754ms.
[11:03:21.357] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:21.358] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:03:21.674] <TB0> INFO: PixTestAlive::maskTest() done
[11:03:21.674] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:03:21.675] <TB0> INFO: ----------------------------------------------------------------------
[11:03:21.675] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:03:21.675] <TB0> INFO: ----------------------------------------------------------------------
[11:03:21.988] <TB0> INFO: Expecting 41600 events.
[11:03:26.905] <TB0> INFO: 41600 events read in total (4201ms).
[11:03:26.906] <TB0> INFO: Test took 5229ms.
[11:03:26.911] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:27.205] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[11:03:27.205] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:03:27.206] <TB0> INFO: PixTestAlive::doTest() done, duration: 15 seconds
[11:03:27.206] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:27.206] <TB0> INFO: Decoding statistics:
[11:03:27.206] <TB0> INFO: General information:
[11:03:27.206] <TB0> INFO: 16bit words read: 0
[11:03:27.206] <TB0> INFO: valid events total: 0
[11:03:27.206] <TB0> INFO: empty events: 0
[11:03:27.206] <TB0> INFO: valid events with pixels: 0
[11:03:27.206] <TB0> INFO: valid pixel hits: 0
[11:03:27.206] <TB0> INFO: Event errors: 0
[11:03:27.206] <TB0> INFO: start marker: 0
[11:03:27.206] <TB0> INFO: stop marker: 0
[11:03:27.206] <TB0> INFO: overflow: 0
[11:03:27.206] <TB0> INFO: invalid 5bit words: 0
[11:03:27.206] <TB0> INFO: invalid XOR eye diagram: 0
[11:03:27.206] <TB0> INFO: TBM errors: 0
[11:03:27.206] <TB0> INFO: flawed TBM headers: 0
[11:03:27.206] <TB0> INFO: flawed TBM trailers: 0
[11:03:27.206] <TB0> INFO: event ID mismatches: 0
[11:03:27.206] <TB0> INFO: ROC errors: 0
[11:03:27.206] <TB0> INFO: missing ROC header(s): 0
[11:03:27.206] <TB0> INFO: misplaced readback start: 0
[11:03:27.206] <TB0> INFO: Pixel decoding errors: 0
[11:03:27.206] <TB0> INFO: pixel data incomplete: 0
[11:03:27.206] <TB0> INFO: pixel address: 0
[11:03:27.206] <TB0> INFO: pulse height fill bit: 0
[11:03:27.206] <TB0> INFO: buffer corruption: 0
[11:03:27.218] <TB0> INFO: ######################################################################
[11:03:27.218] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[11:03:27.218] <TB0> INFO: ######################################################################
[11:03:27.221] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[11:03:27.231] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:03:27.231] <TB0> INFO: run 1 of 1
[11:03:27.532] <TB0> INFO: Expecting 3120000 events.
[11:04:12.208] <TB0> INFO: 856730 events read in total (43960ms).
[11:04:56.524] <TB0> INFO: 1703465 events read in total (88276ms).
[11:05:40.700] <TB0> INFO: 2562310 events read in total (132453ms).
[11:06:09.394] <TB0> INFO: 3120000 events read in total (161146ms).
[11:06:09.453] <TB0> INFO: Test took 162221ms.
[11:06:09.557] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:33.084] <TB0> INFO: PixTestBBMap::doTest() done, duration: 185 seconds
[11:06:33.084] <TB0> INFO: number of dead bumps (per ROC): 4 1 0 1 0 0 0 1 2 1 1 3 2 4 0 8
[11:06:33.084] <TB0> INFO: separation cut (per ROC): 97 90 95 74 89 91 68 90 83 97 96 85 101 92 98 94
[11:06:33.084] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:33.084] <TB0> INFO: Decoding statistics:
[11:06:33.084] <TB0> INFO: General information:
[11:06:33.084] <TB0> INFO: 16bit words read: 0
[11:06:33.084] <TB0> INFO: valid events total: 0
[11:06:33.084] <TB0> INFO: empty events: 0
[11:06:33.084] <TB0> INFO: valid events with pixels: 0
[11:06:33.084] <TB0> INFO: valid pixel hits: 0
[11:06:33.084] <TB0> INFO: Event errors: 0
[11:06:33.084] <TB0> INFO: start marker: 0
[11:06:33.084] <TB0> INFO: stop marker: 0
[11:06:33.084] <TB0> INFO: overflow: 0
[11:06:33.084] <TB0> INFO: invalid 5bit words: 0
[11:06:33.084] <TB0> INFO: invalid XOR eye diagram: 0
[11:06:33.084] <TB0> INFO: TBM errors: 0
[11:06:33.084] <TB0> INFO: flawed TBM headers: 0
[11:06:33.084] <TB0> INFO: flawed TBM trailers: 0
[11:06:33.084] <TB0> INFO: event ID mismatches: 0
[11:06:33.084] <TB0> INFO: ROC errors: 0
[11:06:33.084] <TB0> INFO: missing ROC header(s): 0
[11:06:33.084] <TB0> INFO: misplaced readback start: 0
[11:06:33.084] <TB0> INFO: Pixel decoding errors: 0
[11:06:33.084] <TB0> INFO: pixel data incomplete: 0
[11:06:33.084] <TB0> INFO: pixel address: 0
[11:06:33.084] <TB0> INFO: pulse height fill bit: 0
[11:06:33.084] <TB0> INFO: buffer corruption: 0
[11:06:33.154] <TB0> INFO: ######################################################################
[11:06:33.154] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[11:06:33.154] <TB0> INFO: ######################################################################
[11:06:33.155] <TB0> INFO: ----------------------------------------------------------------------
[11:06:33.155] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[11:06:33.155] <TB0> INFO: ----------------------------------------------------------------------
[11:06:33.155] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:06:33.164] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[11:06:33.164] <TB0> INFO: run 1 of 1
[11:06:33.470] <TB0> INFO: Expecting 26208000 events.
[11:07:07.082] <TB0> INFO: 907650 events read in total (32896ms).
[11:07:38.540] <TB0> INFO: 1799100 events read in total (64354ms).
[11:08:08.291] <TB0> INFO: 2686800 events read in total (94105ms).
[11:08:41.341] <TB0> INFO: 3571600 events read in total (127155ms).
[11:09:14.169] <TB0> INFO: 4453200 events read in total (159983ms).
[11:09:47.457] <TB0> INFO: 5335450 events read in total (193271ms).
[11:10:21.475] <TB0> INFO: 6219700 events read in total (227289ms).
[11:10:55.049] <TB0> INFO: 7101250 events read in total (260863ms).
[11:11:28.541] <TB0> INFO: 7980550 events read in total (294355ms).
[11:12:01.526] <TB0> INFO: 8862300 events read in total (327340ms).
[11:12:33.616] <TB0> INFO: 9744000 events read in total (359430ms).
[11:13:05.548] <TB0> INFO: 10623150 events read in total (391362ms).
[11:13:37.645] <TB0> INFO: 11499850 events read in total (423459ms).
[11:14:10.874] <TB0> INFO: 12379550 events read in total (456688ms).
[11:14:43.912] <TB0> INFO: 13254750 events read in total (489726ms).
[11:15:18.208] <TB0> INFO: 14123100 events read in total (524022ms).
[11:15:51.891] <TB0> INFO: 14990300 events read in total (557705ms).
[11:16:26.064] <TB0> INFO: 15858100 events read in total (591878ms).
[11:16:59.568] <TB0> INFO: 16724250 events read in total (625382ms).
[11:17:31.683] <TB0> INFO: 17588350 events read in total (657497ms).
[11:18:05.032] <TB0> INFO: 18454500 events read in total (690846ms).
[11:18:38.147] <TB0> INFO: 19319200 events read in total (723961ms).
[11:19:11.086] <TB0> INFO: 20183350 events read in total (756900ms).
[11:19:44.422] <TB0> INFO: 21044550 events read in total (790236ms).
[11:20:17.594] <TB0> INFO: 21908000 events read in total (823408ms).
[11:20:50.450] <TB0> INFO: 22771150 events read in total (856264ms).
[11:21:23.767] <TB0> INFO: 23634600 events read in total (889581ms).
[11:21:57.099] <TB0> INFO: 24499100 events read in total (922913ms).
[11:22:28.259] <TB0> INFO: 25367100 events read in total (954073ms).
[11:22:59.872] <TB0> INFO: 26208000 events read in total (985686ms).
[11:22:59.908] <TB0> INFO: Test took 986744ms.
[11:23:00.001] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:00.210] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:01.596] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:03.041] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:04.787] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:06.469] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:07.996] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:09.430] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:10.923] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:12.466] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:13.963] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:15.536] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:17.069] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:18.674] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:20.215] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:21.770] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:23.311] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:23:24.834] <TB0> INFO: PixTestScurves::scurves() done
[11:23:24.834] <TB0> INFO: Vcal mean: 87.55 86.72 86.99 83.14 95.89 89.16 79.47 91.42 84.68 98.35 89.68 84.01 96.83 82.76 84.65 81.02
[11:23:24.834] <TB0> INFO: Vcal RMS: 5.91 5.29 5.63 5.24 5.90 5.40 4.66 5.39 5.30 6.00 5.92 4.91 6.34 5.36 5.53 5.08
[11:23:24.834] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1011 seconds
[11:23:24.834] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:24.834] <TB0> INFO: Decoding statistics:
[11:23:24.834] <TB0> INFO: General information:
[11:23:24.834] <TB0> INFO: 16bit words read: 0
[11:23:24.834] <TB0> INFO: valid events total: 0
[11:23:24.834] <TB0> INFO: empty events: 0
[11:23:24.834] <TB0> INFO: valid events with pixels: 0
[11:23:24.834] <TB0> INFO: valid pixel hits: 0
[11:23:24.834] <TB0> INFO: Event errors: 0
[11:23:24.834] <TB0> INFO: start marker: 0
[11:23:24.834] <TB0> INFO: stop marker: 0
[11:23:24.834] <TB0> INFO: overflow: 0
[11:23:24.834] <TB0> INFO: invalid 5bit words: 0
[11:23:24.834] <TB0> INFO: invalid XOR eye diagram: 0
[11:23:24.834] <TB0> INFO: TBM errors: 0
[11:23:24.834] <TB0> INFO: flawed TBM headers: 0
[11:23:24.834] <TB0> INFO: flawed TBM trailers: 0
[11:23:24.834] <TB0> INFO: event ID mismatches: 0
[11:23:24.834] <TB0> INFO: ROC errors: 0
[11:23:24.834] <TB0> INFO: missing ROC header(s): 0
[11:23:24.834] <TB0> INFO: misplaced readback start: 0
[11:23:24.834] <TB0> INFO: Pixel decoding errors: 0
[11:23:24.834] <TB0> INFO: pixel data incomplete: 0
[11:23:24.834] <TB0> INFO: pixel address: 0
[11:23:24.834] <TB0> INFO: pulse height fill bit: 0
[11:23:24.834] <TB0> INFO: buffer corruption: 0
[11:23:24.909] <TB0> INFO: ######################################################################
[11:23:24.909] <TB0> INFO: PixTestTrim::doTest()
[11:23:24.909] <TB0> INFO: ######################################################################
[11:23:24.911] <TB0> INFO: ----------------------------------------------------------------------
[11:23:24.911] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[11:23:24.911] <TB0> INFO: ----------------------------------------------------------------------
[11:23:24.991] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[11:23:24.991] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:23:25.000] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:23:25.000] <TB0> INFO: run 1 of 1
[11:23:25.316] <TB0> INFO: Expecting 5025280 events.
[11:24:09.976] <TB0> INFO: 1073912 events read in total (43943ms).
[11:24:54.962] <TB0> INFO: 2142640 events read in total (88929ms).
[11:25:39.511] <TB0> INFO: 3211912 events read in total (133478ms).
[11:26:21.393] <TB0> INFO: 4289104 events read in total (175360ms).
[11:26:50.875] <TB0> INFO: 5025280 events read in total (204842ms).
[11:26:50.910] <TB0> INFO: Test took 205911ms.
[11:26:50.963] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:12.151] <TB0> INFO: ROC 0 VthrComp = 91
[11:27:12.151] <TB0> INFO: ROC 1 VthrComp = 90
[11:27:12.151] <TB0> INFO: ROC 2 VthrComp = 93
[11:27:12.152] <TB0> INFO: ROC 3 VthrComp = 88
[11:27:12.152] <TB0> INFO: ROC 4 VthrComp = 95
[11:27:12.152] <TB0> INFO: ROC 5 VthrComp = 96
[11:27:12.152] <TB0> INFO: ROC 6 VthrComp = 81
[11:27:12.152] <TB0> INFO: ROC 7 VthrComp = 94
[11:27:12.152] <TB0> INFO: ROC 8 VthrComp = 87
[11:27:12.152] <TB0> INFO: ROC 9 VthrComp = 98
[11:27:12.152] <TB0> INFO: ROC 10 VthrComp = 94
[11:27:12.152] <TB0> INFO: ROC 11 VthrComp = 87
[11:27:12.152] <TB0> INFO: ROC 12 VthrComp = 99
[11:27:12.152] <TB0> INFO: ROC 13 VthrComp = 87
[11:27:12.153] <TB0> INFO: ROC 14 VthrComp = 91
[11:27:12.153] <TB0> INFO: ROC 15 VthrComp = 85
[11:27:12.153] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:27:12.153] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:27:12.162] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:27:12.162] <TB0> INFO: run 1 of 1
[11:27:12.476] <TB0> INFO: Expecting 5025280 events.
[11:27:52.257] <TB0> INFO: 756064 events read in total (39065ms).
[11:28:31.212] <TB0> INFO: 1510776 events read in total (78020ms).
[11:29:09.950] <TB0> INFO: 2266360 events read in total (116758ms).
[11:29:48.293] <TB0> INFO: 3017840 events read in total (155101ms).
[11:30:24.980] <TB0> INFO: 3763208 events read in total (191788ms).
[11:31:01.412] <TB0> INFO: 4506792 events read in total (228220ms).
[11:31:29.224] <TB0> INFO: 5025280 events read in total (256032ms).
[11:31:29.268] <TB0> INFO: Test took 257106ms.
[11:31:29.409] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:54.894] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 61.4813 for pixel 5/78 mean/min/max = 46.3111/31.1118/61.5105
[11:31:54.894] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 58.5571 for pixel 13/1 mean/min/max = 45.8167/32.6734/58.96
[11:31:54.894] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 59.6189 for pixel 18/78 mean/min/max = 45.6428/31.6118/59.6737
[11:31:54.895] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 60.4066 for pixel 45/1 mean/min/max = 46.4116/32.3509/60.4724
[11:31:54.895] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 59.532 for pixel 40/3 mean/min/max = 45.6804/31.7911/59.5698
[11:31:54.895] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 58.0055 for pixel 0/6 mean/min/max = 44.7081/31.3476/58.0686
[11:31:54.896] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 60.434 for pixel 12/6 mean/min/max = 46.3055/32.1538/60.4571
[11:31:54.896] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 59.3618 for pixel 23/22 mean/min/max = 45.6052/31.6666/59.5437
[11:31:54.896] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 59.4438 for pixel 0/7 mean/min/max = 45.629/31.7762/59.4818
[11:31:54.897] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 61.1742 for pixel 23/69 mean/min/max = 46.3276/31.2566/61.3987
[11:31:54.897] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 60.2191 for pixel 9/70 mean/min/max = 45.8324/31.3662/60.2985
[11:31:54.898] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 58.3105 for pixel 5/75 mean/min/max = 45.3052/32.183/58.4275
[11:31:54.898] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 61.2377 for pixel 2/68 mean/min/max = 46.2426/31.0892/61.3959
[11:31:54.898] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 59.6868 for pixel 10/69 mean/min/max = 45.4896/31.2059/59.7733
[11:31:54.899] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.1144 for pixel 10/78 mean/min/max = 45.5133/31.8839/59.1426
[11:31:54.899] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.5469 for pixel 4/5 mean/min/max = 45.5854/31.3451/59.8257
[11:31:54.900] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:31:55.031] <TB0> INFO: Expecting 411648 events.
[11:32:06.977] <TB0> INFO: 411648 events read in total (11230ms).
[11:32:06.984] <TB0> INFO: Expecting 411648 events.
[11:32:18.953] <TB0> INFO: 411648 events read in total (11395ms).
[11:32:18.960] <TB0> INFO: Expecting 411648 events.
[11:32:29.805] <TB0> INFO: 411648 events read in total (10266ms).
[11:32:29.815] <TB0> INFO: Expecting 411648 events.
[11:32:41.496] <TB0> INFO: 411648 events read in total (11110ms).
[11:32:41.509] <TB0> INFO: Expecting 411648 events.
[11:32:53.324] <TB0> INFO: 411648 events read in total (11248ms).
[11:32:53.338] <TB0> INFO: Expecting 411648 events.
[11:33:04.394] <TB0> INFO: 411648 events read in total (10484ms).
[11:33:04.411] <TB0> INFO: Expecting 411648 events.
[11:33:16.217] <TB0> INFO: 411648 events read in total (11241ms).
[11:33:16.235] <TB0> INFO: Expecting 411648 events.
[11:33:27.945] <TB0> INFO: 411648 events read in total (11137ms).
[11:33:27.968] <TB0> INFO: Expecting 411648 events.
[11:33:38.837] <TB0> INFO: 411648 events read in total (10310ms).
[11:33:38.856] <TB0> INFO: Expecting 411648 events.
[11:33:50.702] <TB0> INFO: 411648 events read in total (11272ms).
[11:33:50.725] <TB0> INFO: Expecting 411648 events.
[11:34:01.954] <TB0> INFO: 411648 events read in total (10669ms).
[11:34:01.977] <TB0> INFO: Expecting 411648 events.
[11:34:13.709] <TB0> INFO: 411648 events read in total (11171ms).
[11:34:13.740] <TB0> INFO: Expecting 411648 events.
[11:34:25.296] <TB0> INFO: 411648 events read in total (11004ms).
[11:34:25.329] <TB0> INFO: Expecting 411648 events.
[11:34:35.827] <TB0> INFO: 411648 events read in total (9949ms).
[11:34:35.858] <TB0> INFO: Expecting 411648 events.
[11:34:47.777] <TB0> INFO: 411648 events read in total (11368ms).
[11:34:47.813] <TB0> INFO: Expecting 411648 events.
[11:34:59.462] <TB0> INFO: 411648 events read in total (11103ms).
[11:34:59.503] <TB0> INFO: Test took 184604ms.
[11:35:00.638] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:35:00.650] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:35:00.650] <TB0> INFO: run 1 of 1
[11:35:00.976] <TB0> INFO: Expecting 5025280 events.
[11:35:40.739] <TB0> INFO: 741680 events read in total (39047ms).
[11:36:19.563] <TB0> INFO: 1482160 events read in total (77871ms).
[11:36:57.173] <TB0> INFO: 2222512 events read in total (115481ms).
[11:37:34.562] <TB0> INFO: 2957744 events read in total (152870ms).
[11:38:12.261] <TB0> INFO: 3687056 events read in total (190569ms).
[11:38:50.768] <TB0> INFO: 4413184 events read in total (229076ms).
[11:39:23.583] <TB0> INFO: 5025280 events read in total (261891ms).
[11:39:23.632] <TB0> INFO: Test took 262981ms.
[11:39:23.789] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:50.483] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.094777 .. 255.000000
[11:39:50.576] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[11:39:50.586] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:39:50.586] <TB0> INFO: run 1 of 1
[11:39:50.924] <TB0> INFO: Expecting 8519680 events.
[11:40:29.433] <TB0> INFO: 712392 events read in total (37793ms).
[11:41:07.419] <TB0> INFO: 1424944 events read in total (75779ms).
[11:41:45.348] <TB0> INFO: 2137464 events read in total (113708ms).
[11:42:23.177] <TB0> INFO: 2850216 events read in total (151537ms).
[11:42:59.149] <TB0> INFO: 3563064 events read in total (187509ms).
[11:43:34.668] <TB0> INFO: 4275952 events read in total (223028ms).
[11:44:12.707] <TB0> INFO: 4987944 events read in total (261067ms).
[11:44:49.743] <TB0> INFO: 5699064 events read in total (298103ms).
[11:45:24.902] <TB0> INFO: 6409920 events read in total (333262ms).
[11:46:03.222] <TB0> INFO: 7120248 events read in total (371582ms).
[11:46:39.543] <TB0> INFO: 7830168 events read in total (407904ms).
[11:47:15.387] <TB0> INFO: 8519680 events read in total (443747ms).
[11:47:15.476] <TB0> INFO: Test took 444890ms.
[11:47:15.736] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:45.926] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 1.782208 .. 45.764824
[11:47:46.004] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 1 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:47:46.013] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:47:46.013] <TB0> INFO: run 1 of 1
[11:47:46.316] <TB0> INFO: Expecting 1830400 events.
[11:48:29.321] <TB0> INFO: 959072 events read in total (42289ms).
[11:49:07.931] <TB0> INFO: 1830400 events read in total (80899ms).
[11:49:07.952] <TB0> INFO: Test took 81940ms.
[11:49:07.990] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:49:22.671] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 18.832893 .. 41.287327
[11:49:22.746] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 8 .. 51 (-1/-1) hits flags = 528 (plus default)
[11:49:22.755] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:49:22.755] <TB0> INFO: run 1 of 1
[11:49:23.058] <TB0> INFO: Expecting 1464320 events.
[11:50:04.887] <TB0> INFO: 958184 events read in total (41112ms).
[11:50:27.866] <TB0> INFO: 1464320 events read in total (64091ms).
[11:50:27.878] <TB0> INFO: Test took 65123ms.
[11:50:27.905] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:50:41.644] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 1.988082 .. 41.287327
[11:50:41.719] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 1 .. 51 (-1/-1) hits flags = 528 (plus default)
[11:50:41.728] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:50:41.728] <TB0> INFO: run 1 of 1
[11:50:42.034] <TB0> INFO: Expecting 1697280 events.
[11:51:25.044] <TB0> INFO: 996384 events read in total (42294ms).
[11:51:56.032] <TB0> INFO: 1697280 events read in total (73282ms).
[11:51:56.043] <TB0> INFO: Test took 74315ms.
[11:51:56.070] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:52:10.943] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:52:10.943] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:52:10.951] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:52:10.951] <TB0> INFO: run 1 of 1
[11:52:11.268] <TB0> INFO: Expecting 1364480 events.
[11:52:54.200] <TB0> INFO: 879496 events read in total (42216ms).
[11:53:18.177] <TB0> INFO: 1364480 events read in total (66193ms).
[11:53:18.192] <TB0> INFO: Test took 67242ms.
[11:53:18.226] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:53:34.512] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:53:34.513] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:53:34.513] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:53:34.514] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:53:34.514] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:53:34.514] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:53:34.514] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:53:34.515] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:53:34.515] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:53:34.515] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:53:34.515] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:53:34.516] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:53:34.516] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:53:34.516] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:53:34.516] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:53:34.517] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:53:34.517] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C0.dat
[11:53:34.530] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C1.dat
[11:53:34.539] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C2.dat
[11:53:34.547] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C3.dat
[11:53:34.555] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C4.dat
[11:53:34.563] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C5.dat
[11:53:34.571] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C6.dat
[11:53:34.579] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C7.dat
[11:53:34.587] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C8.dat
[11:53:34.594] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C9.dat
[11:53:34.601] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C10.dat
[11:53:34.609] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C11.dat
[11:53:34.618] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C12.dat
[11:53:34.627] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C13.dat
[11:53:34.634] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C14.dat
[11:53:34.643] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//trimParameters35_C15.dat
[11:53:34.652] <TB0> INFO: PixTestTrim::trimTest() done
[11:53:34.652] <TB0> INFO: vtrim: 110 103 116 102 106 91 88 96 92 114 114 105 107 116 100 97
[11:53:34.652] <TB0> INFO: vthrcomp: 91 90 93 88 95 96 81 94 87 98 94 87 99 87 91 85
[11:53:34.652] <TB0> INFO: vcal mean: 34.99 35.04 34.95 34.97 34.95 34.95 34.94 34.94 34.99 34.94 34.97 35.06 34.95 34.97 35.01 35.05
[11:53:34.652] <TB0> INFO: vcal RMS: 0.90 0.87 0.96 0.90 1.04 0.86 0.91 0.91 0.94 1.03 0.93 3.53 0.99 0.89 0.87 0.91
[11:53:34.652] <TB0> INFO: bits mean: 9.37 9.17 9.96 9.47 9.41 9.29 9.45 9.53 9.54 9.70 9.76 9.64 9.33 9.83 9.50 9.53
[11:53:34.652] <TB0> INFO: bits RMS: 2.78 2.70 2.55 2.67 2.71 2.90 2.69 2.68 2.76 2.63 2.66 2.60 2.84 2.64 2.75 2.76
[11:53:34.660] <TB0> INFO: ----------------------------------------------------------------------
[11:53:34.660] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:53:34.660] <TB0> INFO: ----------------------------------------------------------------------
[11:53:34.662] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:53:34.671] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:53:34.671] <TB0> INFO: run 1 of 1
[11:53:34.973] <TB0> INFO: Expecting 4160000 events.
[11:54:20.227] <TB0> INFO: 928575 events read in total (44538ms).
[11:55:03.560] <TB0> INFO: 1849075 events read in total (87871ms).
[11:55:50.720] <TB0> INFO: 2761755 events read in total (135031ms).
[11:56:37.621] <TB0> INFO: 3671395 events read in total (181932ms).
[11:57:02.712] <TB0> INFO: 4160000 events read in total (207023ms).
[11:57:02.758] <TB0> INFO: Test took 208087ms.
[11:57:02.879] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:57:33.025] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 183 (-1/-1) hits flags = 528 (plus default)
[11:57:33.035] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:57:33.035] <TB0> INFO: run 1 of 1
[11:57:33.374] <TB0> INFO: Expecting 3827200 events.
[11:58:20.806] <TB0> INFO: 927150 events read in total (46716ms).
[11:59:08.077] <TB0> INFO: 1846280 events read in total (93987ms).
[11:59:56.292] <TB0> INFO: 2755085 events read in total (142202ms).
[12:00:40.438] <TB0> INFO: 3662165 events read in total (186348ms).
[12:00:48.112] <TB0> INFO: 3827200 events read in total (194022ms).
[12:00:48.146] <TB0> INFO: Test took 195111ms.
[12:00:48.250] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:01:16.742] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[12:01:16.751] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:01:16.751] <TB0> INFO: run 1 of 1
[12:01:17.058] <TB0> INFO: Expecting 3452800 events.
[12:02:06.987] <TB0> INFO: 967435 events read in total (49213ms).
[12:02:55.849] <TB0> INFO: 1922700 events read in total (98075ms).
[12:03:40.437] <TB0> INFO: 2867540 events read in total (142664ms).
[12:04:10.617] <TB0> INFO: 3452800 events read in total (172843ms).
[12:04:10.651] <TB0> INFO: Test took 173900ms.
[12:04:10.733] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:38.890] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[12:04:38.900] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:04:38.900] <TB0> INFO: run 1 of 1
[12:04:39.226] <TB0> INFO: Expecting 3515200 events.
[12:05:28.436] <TB0> INFO: 959020 events read in total (48494ms).
[12:06:15.890] <TB0> INFO: 1906525 events read in total (95948ms).
[12:07:03.193] <TB0> INFO: 2843315 events read in total (143251ms).
[12:07:38.843] <TB0> INFO: 3515200 events read in total (178901ms).
[12:07:38.878] <TB0> INFO: Test took 179978ms.
[12:07:38.961] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:06.853] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[12:08:06.862] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:08:06.862] <TB0> INFO: run 1 of 1
[12:08:07.181] <TB0> INFO: Expecting 3224000 events.
[12:08:57.646] <TB0> INFO: 996755 events read in total (49749ms).
[12:09:48.737] <TB0> INFO: 1977725 events read in total (100840ms).
[12:10:39.150] <TB0> INFO: 2948855 events read in total (151253ms).
[12:10:53.382] <TB0> INFO: 3224000 events read in total (165485ms).
[12:10:53.411] <TB0> INFO: Test took 166549ms.
[12:10:53.480] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:17.882] <TB0> INFO: PixTestTrim::trimBitTest() done
[12:11:17.884] <TB0> INFO: PixTestTrim::doTest() done, duration: 2872 seconds
[12:11:17.884] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:17.884] <TB0> INFO: Decoding statistics:
[12:11:17.884] <TB0> INFO: General information:
[12:11:17.884] <TB0> INFO: 16bit words read: 0
[12:11:17.884] <TB0> INFO: valid events total: 0
[12:11:17.884] <TB0> INFO: empty events: 0
[12:11:17.884] <TB0> INFO: valid events with pixels: 0
[12:11:17.884] <TB0> INFO: valid pixel hits: 0
[12:11:17.884] <TB0> INFO: Event errors: 0
[12:11:17.884] <TB0> INFO: start marker: 0
[12:11:17.884] <TB0> INFO: stop marker: 0
[12:11:17.884] <TB0> INFO: overflow: 0
[12:11:17.884] <TB0> INFO: invalid 5bit words: 0
[12:11:17.884] <TB0> INFO: invalid XOR eye diagram: 0
[12:11:17.884] <TB0> INFO: TBM errors: 0
[12:11:17.884] <TB0> INFO: flawed TBM headers: 0
[12:11:17.884] <TB0> INFO: flawed TBM trailers: 0
[12:11:17.884] <TB0> INFO: event ID mismatches: 0
[12:11:17.884] <TB0> INFO: ROC errors: 0
[12:11:17.884] <TB0> INFO: missing ROC header(s): 0
[12:11:17.884] <TB0> INFO: misplaced readback start: 0
[12:11:17.884] <TB0> INFO: Pixel decoding errors: 0
[12:11:17.884] <TB0> INFO: pixel data incomplete: 0
[12:11:17.884] <TB0> INFO: pixel address: 0
[12:11:17.884] <TB0> INFO: pulse height fill bit: 0
[12:11:17.884] <TB0> INFO: buffer corruption: 0
[12:11:18.582] <TB0> INFO: ######################################################################
[12:11:18.583] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[12:11:18.583] <TB0> INFO: ######################################################################
[12:11:18.916] <TB0> INFO: Expecting 41600 events.
[12:11:23.740] <TB0> INFO: 41600 events read in total (4107ms).
[12:11:23.740] <TB0> INFO: Test took 5156ms.
[12:11:23.747] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:24.350] <TB0> INFO: Expecting 41600 events.
[12:11:29.610] <TB0> INFO: 41600 events read in total (4543ms).
[12:11:29.611] <TB0> INFO: Test took 5600ms.
[12:11:29.619] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:29.961] <TB0> INFO: Expecting 41600 events.
[12:11:35.341] <TB0> INFO: 41600 events read in total (4664ms).
[12:11:35.342] <TB0> INFO: Test took 5711ms.
[12:11:35.348] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:35.700] <TB0> INFO: Expecting 2560 events.
[12:11:36.770] <TB0> INFO: 2560 events read in total (354ms).
[12:11:36.770] <TB0> INFO: Test took 1414ms.
[12:11:37.185] <TB0> INFO: Expecting 2560 events.
[12:11:38.255] <TB0> INFO: 2560 events read in total (354ms).
[12:11:38.255] <TB0> INFO: Test took 1484ms.
[12:11:38.669] <TB0> INFO: Expecting 2560 events.
[12:11:39.768] <TB0> INFO: 2560 events read in total (382ms).
[12:11:39.768] <TB0> INFO: Test took 1512ms.
[12:11:40.183] <TB0> INFO: Expecting 2560 events.
[12:11:41.267] <TB0> INFO: 2560 events read in total (368ms).
[12:11:41.267] <TB0> INFO: Test took 1498ms.
[12:11:41.682] <TB0> INFO: Expecting 2560 events.
[12:11:42.779] <TB0> INFO: 2560 events read in total (381ms).
[12:11:42.779] <TB0> INFO: Test took 1511ms.
[12:11:43.193] <TB0> INFO: Expecting 2560 events.
[12:11:44.272] <TB0> INFO: 2560 events read in total (363ms).
[12:11:44.272] <TB0> INFO: Test took 1493ms.
[12:11:44.687] <TB0> INFO: Expecting 2560 events.
[12:11:45.755] <TB0> INFO: 2560 events read in total (352ms).
[12:11:45.755] <TB0> INFO: Test took 1482ms.
[12:11:46.170] <TB0> INFO: Expecting 2560 events.
[12:11:47.252] <TB0> INFO: 2560 events read in total (366ms).
[12:11:47.252] <TB0> INFO: Test took 1496ms.
[12:11:47.667] <TB0> INFO: Expecting 2560 events.
[12:11:48.736] <TB0> INFO: 2560 events read in total (353ms).
[12:11:48.736] <TB0> INFO: Test took 1483ms.
[12:11:49.151] <TB0> INFO: Expecting 2560 events.
[12:11:50.236] <TB0> INFO: 2560 events read in total (369ms).
[12:11:50.236] <TB0> INFO: Test took 1500ms.
[12:11:50.651] <TB0> INFO: Expecting 2560 events.
[12:11:51.721] <TB0> INFO: 2560 events read in total (354ms).
[12:11:51.721] <TB0> INFO: Test took 1485ms.
[12:11:52.137] <TB0> INFO: Expecting 2560 events.
[12:11:53.206] <TB0> INFO: 2560 events read in total (353ms).
[12:11:53.206] <TB0> INFO: Test took 1485ms.
[12:11:53.621] <TB0> INFO: Expecting 2560 events.
[12:11:54.692] <TB0> INFO: 2560 events read in total (355ms).
[12:11:54.693] <TB0> INFO: Test took 1486ms.
[12:11:55.107] <TB0> INFO: Expecting 2560 events.
[12:11:56.178] <TB0> INFO: 2560 events read in total (352ms).
[12:11:56.178] <TB0> INFO: Test took 1485ms.
[12:11:56.593] <TB0> INFO: Expecting 2560 events.
[12:11:57.662] <TB0> INFO: 2560 events read in total (353ms).
[12:11:57.662] <TB0> INFO: Test took 1483ms.
[12:11:58.077] <TB0> INFO: Expecting 2560 events.
[12:11:59.146] <TB0> INFO: 2560 events read in total (353ms).
[12:11:59.146] <TB0> INFO: Test took 1483ms.
[12:11:59.149] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:11:59.563] <TB0> INFO: Expecting 655360 events.
[12:12:17.129] <TB0> INFO: 655360 events read in total (16850ms).
[12:12:17.139] <TB0> INFO: Expecting 655360 events.
[12:12:33.779] <TB0> INFO: 655360 events read in total (16113ms).
[12:12:33.792] <TB0> INFO: Expecting 655360 events.
[12:12:50.968] <TB0> INFO: 655360 events read in total (16650ms).
[12:12:50.985] <TB0> INFO: Expecting 655360 events.
[12:13:09.079] <TB0> INFO: 655360 events read in total (17567ms).
[12:13:09.099] <TB0> INFO: Expecting 655360 events.
[12:13:24.771] <TB0> INFO: 655360 events read in total (15144ms).
[12:13:24.796] <TB0> INFO: Expecting 655360 events.
[12:13:43.559] <TB0> INFO: 655360 events read in total (18236ms).
[12:13:43.584] <TB0> INFO: Expecting 655360 events.
[12:14:01.541] <TB0> INFO: 655360 events read in total (17429ms).
[12:14:01.572] <TB0> INFO: Expecting 655360 events.
[12:14:20.281] <TB0> INFO: 655360 events read in total (18182ms).
[12:14:20.320] <TB0> INFO: Expecting 655360 events.
[12:14:38.791] <TB0> INFO: 655360 events read in total (17944ms).
[12:14:38.833] <TB0> INFO: Expecting 655360 events.
[12:14:56.987] <TB0> INFO: 655360 events read in total (17626ms).
[12:14:57.028] <TB0> INFO: Expecting 655360 events.
[12:15:15.665] <TB0> INFO: 655360 events read in total (18110ms).
[12:15:15.707] <TB0> INFO: Expecting 655360 events.
[12:15:33.063] <TB0> INFO: 655360 events read in total (16820ms).
[12:15:33.116] <TB0> INFO: Expecting 655360 events.
[12:15:51.868] <TB0> INFO: 655360 events read in total (18224ms).
[12:15:51.921] <TB0> INFO: Expecting 655360 events.
[12:16:08.959] <TB0> INFO: 655360 events read in total (16510ms).
[12:16:09.019] <TB0> INFO: Expecting 655360 events.
[12:16:27.796] <TB0> INFO: 655360 events read in total (18249ms).
[12:16:27.852] <TB0> INFO: Expecting 655360 events.
[12:16:45.997] <TB0> INFO: 655360 events read in total (17617ms).
[12:16:46.069] <TB0> INFO: Test took 286920ms.
[12:16:46.159] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:16:46.468] <TB0> INFO: Expecting 655360 events.
[12:17:05.050] <TB0> INFO: 655360 events read in total (17866ms).
[12:17:05.058] <TB0> INFO: Expecting 655360 events.
[12:17:23.464] <TB0> INFO: 655360 events read in total (17878ms).
[12:17:23.476] <TB0> INFO: Expecting 655360 events.
[12:17:41.447] <TB0> INFO: 655360 events read in total (17443ms).
[12:17:41.462] <TB0> INFO: Expecting 655360 events.
[12:17:59.945] <TB0> INFO: 655360 events read in total (17955ms).
[12:17:59.966] <TB0> INFO: Expecting 655360 events.
[12:18:16.931] <TB0> INFO: 655360 events read in total (16438ms).
[12:18:16.956] <TB0> INFO: Expecting 655360 events.
[12:18:35.779] <TB0> INFO: 655360 events read in total (18295ms).
[12:18:35.805] <TB0> INFO: Expecting 655360 events.
[12:18:53.087] <TB0> INFO: 655360 events read in total (16754ms).
[12:18:53.120] <TB0> INFO: Expecting 655360 events.
[12:19:11.497] <TB0> INFO: 655360 events read in total (17850ms).
[12:19:11.530] <TB0> INFO: Expecting 655360 events.
[12:19:29.077] <TB0> INFO: 655360 events read in total (17020ms).
[12:19:29.131] <TB0> INFO: Expecting 655360 events.
[12:19:46.289] <TB0> INFO: 655360 events read in total (16631ms).
[12:19:46.330] <TB0> INFO: Expecting 655360 events.
[12:20:04.878] <TB0> INFO: 655360 events read in total (18021ms).
[12:20:04.929] <TB0> INFO: Expecting 655360 events.
[12:20:22.362] <TB0> INFO: 655360 events read in total (16905ms).
[12:20:22.418] <TB0> INFO: Expecting 655360 events.
[12:20:41.558] <TB0> INFO: 655360 events read in total (18612ms).
[12:20:41.609] <TB0> INFO: Expecting 655360 events.
[12:20:59.414] <TB0> INFO: 655360 events read in total (17278ms).
[12:20:59.471] <TB0> INFO: Expecting 655360 events.
[12:21:18.273] <TB0> INFO: 655360 events read in total (18274ms).
[12:21:18.333] <TB0> INFO: Expecting 655360 events.
[12:21:36.526] <TB0> INFO: 655360 events read in total (17666ms).
[12:21:36.593] <TB0> INFO: Test took 290434ms.
[12:21:36.820] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.829] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.838] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.846] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.855] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.863] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.872] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.880] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.888] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.897] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.904] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.911] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.918] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.925] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.932] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.939] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:21:36.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C0.dat
[12:21:36.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C1.dat
[12:21:36.972] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C2.dat
[12:21:36.973] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C3.dat
[12:21:36.973] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C4.dat
[12:21:36.973] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C5.dat
[12:21:36.973] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C6.dat
[12:21:36.973] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C7.dat
[12:21:36.974] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C8.dat
[12:21:36.974] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C9.dat
[12:21:36.974] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C10.dat
[12:21:36.974] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C11.dat
[12:21:36.974] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C12.dat
[12:21:36.974] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C13.dat
[12:21:36.974] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C14.dat
[12:21:36.974] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//dacParameters35_C15.dat
[12:21:37.282] <TB0> INFO: Expecting 41600 events.
[12:21:42.143] <TB0> INFO: 41600 events read in total (4145ms).
[12:21:42.144] <TB0> INFO: Test took 5167ms.
[12:21:42.714] <TB0> INFO: Expecting 41600 events.
[12:21:47.307] <TB0> INFO: 41600 events read in total (3877ms).
[12:21:47.309] <TB0> INFO: Test took 4931ms.
[12:21:47.855] <TB0> INFO: Expecting 41600 events.
[12:21:52.630] <TB0> INFO: 41600 events read in total (4059ms).
[12:21:52.631] <TB0> INFO: Test took 5097ms.
[12:21:52.864] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:21:52.996] <TB0> INFO: Expecting 2560 events.
[12:21:54.078] <TB0> INFO: 2560 events read in total (366ms).
[12:21:54.078] <TB0> INFO: Test took 1215ms.
[12:21:54.081] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:21:54.494] <TB0> INFO: Expecting 2560 events.
[12:21:55.562] <TB0> INFO: 2560 events read in total (352ms).
[12:21:55.562] <TB0> INFO: Test took 1481ms.
[12:21:55.565] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:21:55.978] <TB0> INFO: Expecting 2560 events.
[12:21:57.045] <TB0> INFO: 2560 events read in total (351ms).
[12:21:57.046] <TB0> INFO: Test took 1481ms.
[12:21:57.048] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:21:57.461] <TB0> INFO: Expecting 2560 events.
[12:21:58.531] <TB0> INFO: 2560 events read in total (354ms).
[12:21:58.531] <TB0> INFO: Test took 1483ms.
[12:21:58.533] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:21:58.947] <TB0> INFO: Expecting 2560 events.
[12:22:00.017] <TB0> INFO: 2560 events read in total (354ms).
[12:22:00.017] <TB0> INFO: Test took 1484ms.
[12:22:00.019] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:00.433] <TB0> INFO: Expecting 2560 events.
[12:22:01.501] <TB0> INFO: 2560 events read in total (352ms).
[12:22:01.502] <TB0> INFO: Test took 1483ms.
[12:22:01.504] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:01.917] <TB0> INFO: Expecting 2560 events.
[12:22:02.991] <TB0> INFO: 2560 events read in total (357ms).
[12:22:02.991] <TB0> INFO: Test took 1487ms.
[12:22:02.993] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:03.407] <TB0> INFO: Expecting 2560 events.
[12:22:04.491] <TB0> INFO: 2560 events read in total (368ms).
[12:22:04.492] <TB0> INFO: Test took 1499ms.
[12:22:04.497] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:04.907] <TB0> INFO: Expecting 2560 events.
[12:22:05.976] <TB0> INFO: 2560 events read in total (353ms).
[12:22:05.976] <TB0> INFO: Test took 1479ms.
[12:22:05.980] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:06.391] <TB0> INFO: Expecting 2560 events.
[12:22:07.476] <TB0> INFO: 2560 events read in total (368ms).
[12:22:07.476] <TB0> INFO: Test took 1496ms.
[12:22:07.479] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:07.892] <TB0> INFO: Expecting 2560 events.
[12:22:08.964] <TB0> INFO: 2560 events read in total (355ms).
[12:22:08.965] <TB0> INFO: Test took 1487ms.
[12:22:08.967] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:09.380] <TB0> INFO: Expecting 2560 events.
[12:22:10.451] <TB0> INFO: 2560 events read in total (354ms).
[12:22:10.451] <TB0> INFO: Test took 1485ms.
[12:22:10.456] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:10.867] <TB0> INFO: Expecting 2560 events.
[12:22:11.936] <TB0> INFO: 2560 events read in total (353ms).
[12:22:11.937] <TB0> INFO: Test took 1481ms.
[12:22:11.939] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:12.352] <TB0> INFO: Expecting 2560 events.
[12:22:13.422] <TB0> INFO: 2560 events read in total (354ms).
[12:22:13.422] <TB0> INFO: Test took 1483ms.
[12:22:13.425] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:13.838] <TB0> INFO: Expecting 2560 events.
[12:22:14.910] <TB0> INFO: 2560 events read in total (356ms).
[12:22:14.910] <TB0> INFO: Test took 1485ms.
[12:22:14.913] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:15.325] <TB0> INFO: Expecting 2560 events.
[12:22:16.395] <TB0> INFO: 2560 events read in total (353ms).
[12:22:16.395] <TB0> INFO: Test took 1482ms.
[12:22:16.399] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:16.811] <TB0> INFO: Expecting 2560 events.
[12:22:17.879] <TB0> INFO: 2560 events read in total (352ms).
[12:22:17.880] <TB0> INFO: Test took 1482ms.
[12:22:17.883] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:18.295] <TB0> INFO: Expecting 2560 events.
[12:22:19.379] <TB0> INFO: 2560 events read in total (367ms).
[12:22:19.379] <TB0> INFO: Test took 1496ms.
[12:22:19.386] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:19.795] <TB0> INFO: Expecting 2560 events.
[12:22:20.866] <TB0> INFO: 2560 events read in total (355ms).
[12:22:20.866] <TB0> INFO: Test took 1480ms.
[12:22:20.869] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:21.282] <TB0> INFO: Expecting 2560 events.
[12:22:22.353] <TB0> INFO: 2560 events read in total (355ms).
[12:22:22.353] <TB0> INFO: Test took 1484ms.
[12:22:22.356] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:22.769] <TB0> INFO: Expecting 2560 events.
[12:22:23.839] <TB0> INFO: 2560 events read in total (354ms).
[12:22:23.839] <TB0> INFO: Test took 1483ms.
[12:22:23.842] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:24.255] <TB0> INFO: Expecting 2560 events.
[12:22:25.337] <TB0> INFO: 2560 events read in total (366ms).
[12:22:25.338] <TB0> INFO: Test took 1496ms.
[12:22:25.341] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:25.753] <TB0> INFO: Expecting 2560 events.
[12:22:26.820] <TB0> INFO: 2560 events read in total (350ms).
[12:22:26.820] <TB0> INFO: Test took 1480ms.
[12:22:26.823] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:27.236] <TB0> INFO: Expecting 2560 events.
[12:22:28.306] <TB0> INFO: 2560 events read in total (353ms).
[12:22:28.307] <TB0> INFO: Test took 1484ms.
[12:22:28.310] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:28.722] <TB0> INFO: Expecting 2560 events.
[12:22:29.792] <TB0> INFO: 2560 events read in total (354ms).
[12:22:29.792] <TB0> INFO: Test took 1483ms.
[12:22:29.795] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:30.208] <TB0> INFO: Expecting 2560 events.
[12:22:31.277] <TB0> INFO: 2560 events read in total (352ms).
[12:22:31.277] <TB0> INFO: Test took 1482ms.
[12:22:31.280] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:31.693] <TB0> INFO: Expecting 2560 events.
[12:22:32.775] <TB0> INFO: 2560 events read in total (366ms).
[12:22:32.775] <TB0> INFO: Test took 1495ms.
[12:22:32.778] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:33.192] <TB0> INFO: Expecting 2560 events.
[12:22:34.260] <TB0> INFO: 2560 events read in total (352ms).
[12:22:34.261] <TB0> INFO: Test took 1483ms.
[12:22:34.263] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:34.677] <TB0> INFO: Expecting 2560 events.
[12:22:35.745] <TB0> INFO: 2560 events read in total (352ms).
[12:22:35.745] <TB0> INFO: Test took 1482ms.
[12:22:35.747] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:36.161] <TB0> INFO: Expecting 2560 events.
[12:22:37.245] <TB0> INFO: 2560 events read in total (368ms).
[12:22:37.245] <TB0> INFO: Test took 1498ms.
[12:22:37.251] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:37.661] <TB0> INFO: Expecting 2560 events.
[12:22:38.731] <TB0> INFO: 2560 events read in total (354ms).
[12:22:38.731] <TB0> INFO: Test took 1481ms.
[12:22:38.734] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:39.146] <TB0> INFO: Expecting 2560 events.
[12:22:40.229] <TB0> INFO: 2560 events read in total (367ms).
[12:22:40.229] <TB0> INFO: Test took 1495ms.
[12:22:40.856] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 682 seconds
[12:22:40.856] <TB0> INFO: PH scale (per ROC): 80 83 93 86 71 82 78 80 86 73 83 90 86 87 95 76
[12:22:40.856] <TB0> INFO: PH offset (per ROC): 154 147 161 154 159 140 157 163 160 164 169 141 172 148 154 148
[12:22:40.864] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:40.864] <TB0> INFO: Decoding statistics:
[12:22:40.864] <TB0> INFO: General information:
[12:22:40.864] <TB0> INFO: 16bit words read: 91920
[12:22:40.864] <TB0> INFO: valid events total: 10240
[12:22:40.864] <TB0> INFO: empty events: 7740
[12:22:40.864] <TB0> INFO: valid events with pixels: 2500
[12:22:40.864] <TB0> INFO: valid pixel hits: 2500
[12:22:40.864] <TB0> INFO: Event errors: 0
[12:22:40.864] <TB0> INFO: start marker: 0
[12:22:40.864] <TB0> INFO: stop marker: 0
[12:22:40.864] <TB0> INFO: overflow: 0
[12:22:40.864] <TB0> INFO: invalid 5bit words: 0
[12:22:40.864] <TB0> INFO: invalid XOR eye diagram: 0
[12:22:40.864] <TB0> INFO: TBM errors: 0
[12:22:40.864] <TB0> INFO: flawed TBM headers: 0
[12:22:40.864] <TB0> INFO: flawed TBM trailers: 0
[12:22:40.864] <TB0> INFO: event ID mismatches: 0
[12:22:40.864] <TB0> INFO: ROC errors: 0
[12:22:40.864] <TB0> INFO: missing ROC header(s): 0
[12:22:40.864] <TB0> INFO: misplaced readback start: 0
[12:22:40.864] <TB0> INFO: Pixel decoding errors: 0
[12:22:40.864] <TB0> INFO: pixel data incomplete: 0
[12:22:40.864] <TB0> INFO: pixel address: 0
[12:22:40.864] <TB0> INFO: pulse height fill bit: 0
[12:22:40.864] <TB0> INFO: buffer corruption: 0
[12:22:41.032] <TB0> INFO: ######################################################################
[12:22:41.032] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[12:22:41.032] <TB0> INFO: ######################################################################
[12:22:41.042] <TB0> INFO: scanning low vcal = 10
[12:22:41.377] <TB0> INFO: Expecting 41600 events.
[12:22:45.221] <TB0> INFO: 41600 events read in total (3128ms).
[12:22:45.221] <TB0> INFO: Test took 4179ms.
[12:22:45.223] <TB0> INFO: scanning low vcal = 20
[12:22:45.636] <TB0> INFO: Expecting 41600 events.
[12:22:49.386] <TB0> INFO: 41600 events read in total (3034ms).
[12:22:49.387] <TB0> INFO: Test took 4164ms.
[12:22:49.389] <TB0> INFO: scanning low vcal = 30
[12:22:49.801] <TB0> INFO: Expecting 41600 events.
[12:22:53.587] <TB0> INFO: 41600 events read in total (3070ms).
[12:22:53.588] <TB0> INFO: Test took 4199ms.
[12:22:53.590] <TB0> INFO: scanning low vcal = 40
[12:22:53.994] <TB0> INFO: Expecting 41600 events.
[12:22:58.232] <TB0> INFO: 41600 events read in total (3521ms).
[12:22:58.234] <TB0> INFO: Test took 4644ms.
[12:22:58.237] <TB0> INFO: scanning low vcal = 50
[12:22:58.582] <TB0> INFO: Expecting 41600 events.
[12:23:02.834] <TB0> INFO: 41600 events read in total (3536ms).
[12:23:02.834] <TB0> INFO: Test took 4596ms.
[12:23:02.837] <TB0> INFO: scanning low vcal = 60
[12:23:03.190] <TB0> INFO: Expecting 41600 events.
[12:23:07.560] <TB0> INFO: 41600 events read in total (3654ms).
[12:23:07.560] <TB0> INFO: Test took 4723ms.
[12:23:07.569] <TB0> INFO: scanning low vcal = 70
[12:23:07.916] <TB0> INFO: Expecting 41600 events.
[12:23:12.510] <TB0> INFO: 41600 events read in total (3878ms).
[12:23:12.511] <TB0> INFO: Test took 4942ms.
[12:23:12.514] <TB0> INFO: scanning low vcal = 80
[12:23:12.865] <TB0> INFO: Expecting 41600 events.
[12:23:17.277] <TB0> INFO: 41600 events read in total (3695ms).
[12:23:17.278] <TB0> INFO: Test took 4764ms.
[12:23:17.281] <TB0> INFO: scanning low vcal = 90
[12:23:17.632] <TB0> INFO: Expecting 41600 events.
[12:23:22.244] <TB0> INFO: 41600 events read in total (3895ms).
[12:23:22.244] <TB0> INFO: Test took 4963ms.
[12:23:22.247] <TB0> INFO: scanning low vcal = 100
[12:23:22.592] <TB0> INFO: Expecting 41600 events.
[12:23:27.070] <TB0> INFO: 41600 events read in total (3762ms).
[12:23:27.071] <TB0> INFO: Test took 4824ms.
[12:23:27.073] <TB0> INFO: scanning low vcal = 110
[12:23:27.430] <TB0> INFO: Expecting 41600 events.
[12:23:31.631] <TB0> INFO: 41600 events read in total (3485ms).
[12:23:31.631] <TB0> INFO: Test took 4557ms.
[12:23:31.633] <TB0> INFO: scanning low vcal = 120
[12:23:31.989] <TB0> INFO: Expecting 41600 events.
[12:23:36.403] <TB0> INFO: 41600 events read in total (3698ms).
[12:23:36.404] <TB0> INFO: Test took 4771ms.
[12:23:36.407] <TB0> INFO: scanning low vcal = 130
[12:23:36.748] <TB0> INFO: Expecting 41600 events.
[12:23:41.142] <TB0> INFO: 41600 events read in total (3678ms).
[12:23:41.143] <TB0> INFO: Test took 4736ms.
[12:23:41.146] <TB0> INFO: scanning low vcal = 140
[12:23:41.499] <TB0> INFO: Expecting 41600 events.
[12:23:46.100] <TB0> INFO: 41600 events read in total (3885ms).
[12:23:46.101] <TB0> INFO: Test took 4955ms.
[12:23:46.104] <TB0> INFO: scanning low vcal = 150
[12:23:46.454] <TB0> INFO: Expecting 41600 events.
[12:23:50.800] <TB0> INFO: 41600 events read in total (3629ms).
[12:23:50.801] <TB0> INFO: Test took 4697ms.
[12:23:50.804] <TB0> INFO: scanning low vcal = 160
[12:23:51.158] <TB0> INFO: Expecting 41600 events.
[12:23:55.641] <TB0> INFO: 41600 events read in total (3767ms).
[12:23:55.642] <TB0> INFO: Test took 4838ms.
[12:23:55.645] <TB0> INFO: scanning low vcal = 170
[12:23:55.997] <TB0> INFO: Expecting 41600 events.
[12:24:00.227] <TB0> INFO: 41600 events read in total (3514ms).
[12:24:00.228] <TB0> INFO: Test took 4583ms.
[12:24:00.233] <TB0> INFO: scanning low vcal = 180
[12:24:00.563] <TB0> INFO: Expecting 41600 events.
[12:24:04.855] <TB0> INFO: 41600 events read in total (3576ms).
[12:24:04.856] <TB0> INFO: Test took 4623ms.
[12:24:04.858] <TB0> INFO: scanning low vcal = 190
[12:24:05.215] <TB0> INFO: Expecting 41600 events.
[12:24:09.669] <TB0> INFO: 41600 events read in total (3737ms).
[12:24:09.670] <TB0> INFO: Test took 4812ms.
[12:24:09.673] <TB0> INFO: scanning low vcal = 200
[12:24:10.031] <TB0> INFO: Expecting 41600 events.
[12:24:14.444] <TB0> INFO: 41600 events read in total (3697ms).
[12:24:14.445] <TB0> INFO: Test took 4772ms.
[12:24:14.448] <TB0> INFO: scanning low vcal = 210
[12:24:14.803] <TB0> INFO: Expecting 41600 events.
[12:24:19.245] <TB0> INFO: 41600 events read in total (3726ms).
[12:24:19.246] <TB0> INFO: Test took 4798ms.
[12:24:19.250] <TB0> INFO: scanning low vcal = 220
[12:24:19.601] <TB0> INFO: Expecting 41600 events.
[12:24:23.960] <TB0> INFO: 41600 events read in total (3643ms).
[12:24:23.961] <TB0> INFO: Test took 4711ms.
[12:24:23.964] <TB0> INFO: scanning low vcal = 230
[12:24:24.324] <TB0> INFO: Expecting 41600 events.
[12:24:28.539] <TB0> INFO: 41600 events read in total (3499ms).
[12:24:28.540] <TB0> INFO: Test took 4576ms.
[12:24:28.543] <TB0> INFO: scanning low vcal = 240
[12:24:28.897] <TB0> INFO: Expecting 41600 events.
[12:24:33.109] <TB0> INFO: 41600 events read in total (3495ms).
[12:24:33.110] <TB0> INFO: Test took 4567ms.
[12:24:33.113] <TB0> INFO: scanning low vcal = 250
[12:24:33.465] <TB0> INFO: Expecting 41600 events.
[12:24:37.867] <TB0> INFO: 41600 events read in total (3686ms).
[12:24:37.868] <TB0> INFO: Test took 4755ms.
[12:24:37.873] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[12:24:38.223] <TB0> INFO: Expecting 41600 events.
[12:24:42.738] <TB0> INFO: 41600 events read in total (3799ms).
[12:24:42.739] <TB0> INFO: Test took 4866ms.
[12:24:42.742] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[12:24:43.095] <TB0> INFO: Expecting 41600 events.
[12:24:47.519] <TB0> INFO: 41600 events read in total (3708ms).
[12:24:47.521] <TB0> INFO: Test took 4779ms.
[12:24:47.525] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[12:24:47.877] <TB0> INFO: Expecting 41600 events.
[12:24:52.334] <TB0> INFO: 41600 events read in total (3741ms).
[12:24:52.336] <TB0> INFO: Test took 4811ms.
[12:24:52.340] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[12:24:52.689] <TB0> INFO: Expecting 41600 events.
[12:24:57.308] <TB0> INFO: 41600 events read in total (3903ms).
[12:24:57.309] <TB0> INFO: Test took 4969ms.
[12:24:57.311] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[12:24:57.648] <TB0> INFO: Expecting 41600 events.
[12:25:01.873] <TB0> INFO: 41600 events read in total (3509ms).
[12:25:01.874] <TB0> INFO: Test took 4563ms.
[12:25:02.335] <TB0> INFO: PixTestGainPedestal::measure() done
[12:25:35.093] <TB0> INFO: PixTestGainPedestal::fit() done
[12:25:35.093] <TB0> INFO: non-linearity mean: 0.948 0.952 0.955 0.954 0.957 0.959 0.957 0.955 0.958 0.956 0.958 0.958 0.956 0.961 0.949 0.952
[12:25:35.093] <TB0> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.007 0.005 0.007 0.007 0.006 0.007 0.006 0.004 0.006 0.005 0.006 0.005
[12:25:35.093] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[12:25:35.111] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[12:25:35.129] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[12:25:35.147] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[12:25:35.164] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[12:25:35.182] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[12:25:35.200] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[12:25:35.218] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[12:25:35.236] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[12:25:35.253] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[12:25:35.271] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[12:25:35.289] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[12:25:35.307] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[12:25:35.325] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[12:25:35.342] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[12:25:35.360] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[12:25:35.378] <TB0> INFO: PixTestGainPedestal::doTest() done, duration: 174 seconds
[12:25:35.378] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:35.378] <TB0> INFO: Decoding statistics:
[12:25:35.378] <TB0> INFO: General information:
[12:25:35.378] <TB0> INFO: 16bit words read: 2662400
[12:25:35.378] <TB0> INFO: valid events total: 166400
[12:25:35.378] <TB0> INFO: empty events: 0
[12:25:35.378] <TB0> INFO: valid events with pixels: 166400
[12:25:35.378] <TB0> INFO: valid pixel hits: 665580
[12:25:35.378] <TB0> INFO: Event errors: 0
[12:25:35.378] <TB0> INFO: start marker: 0
[12:25:35.378] <TB0> INFO: stop marker: 0
[12:25:35.378] <TB0> INFO: overflow: 0
[12:25:35.378] <TB0> INFO: invalid 5bit words: 0
[12:25:35.378] <TB0> INFO: invalid XOR eye diagram: 0
[12:25:35.378] <TB0> INFO: TBM errors: 0
[12:25:35.378] <TB0> INFO: flawed TBM headers: 0
[12:25:35.378] <TB0> INFO: flawed TBM trailers: 0
[12:25:35.378] <TB0> INFO: event ID mismatches: 0
[12:25:35.378] <TB0> INFO: ROC errors: 0
[12:25:35.378] <TB0> INFO: missing ROC header(s): 0
[12:25:35.378] <TB0> INFO: misplaced readback start: 0
[12:25:35.378] <TB0> INFO: Pixel decoding errors: 0
[12:25:35.378] <TB0> INFO: pixel data incomplete: 0
[12:25:35.378] <TB0> INFO: pixel address: 0
[12:25:35.378] <TB0> INFO: pulse height fill bit: 0
[12:25:35.378] <TB0> INFO: buffer corruption: 0
[12:25:35.384] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C15.dat
[12:25:35.385] <TB0> INFO: ######################################################################
[12:25:35.386] <TB0> INFO: PixTestTrim::doTest()
[12:25:35.386] <TB0> INFO: ######################################################################
[12:25:35.386] <TB0> INFO: PixTestReadback::RES sent once
[12:25:46.703] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C0.dat
[12:25:46.704] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C1.dat
[12:25:46.704] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C2.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C3.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C4.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C5.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C6.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C7.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C8.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C9.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C10.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C11.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C12.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C13.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C14.dat
[12:25:46.705] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C15.dat
[12:25:46.736] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[12:25:46.736] <TB0> INFO: PixTestReadback::RES sent once
[12:25:57.944] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C0.dat
[12:25:57.944] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C1.dat
[12:25:57.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C2.dat
[12:25:57.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C3.dat
[12:25:57.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C4.dat
[12:25:57.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C5.dat
[12:25:57.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C6.dat
[12:25:57.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C7.dat
[12:25:57.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C8.dat
[12:25:57.945] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C9.dat
[12:25:57.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C10.dat
[12:25:57.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C11.dat
[12:25:57.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C12.dat
[12:25:57.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C13.dat
[12:25:57.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C14.dat
[12:25:57.946] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C15.dat
[12:25:57.976] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[12:25:57.977] <TB0> INFO: PixTestReadback::RES sent once
[12:26:06.597] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[12:26:06.597] <TB0> INFO: Vbg will be calibrated using Vd calibration
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 160.3calibrated Vbg = 1.22642 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.7calibrated Vbg = 1.21992 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 154.4calibrated Vbg = 1.21852 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 160.3calibrated Vbg = 1.23456 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.9calibrated Vbg = 1.23967 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 148.2calibrated Vbg = 1.23332 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 141.8calibrated Vbg = 1.24153 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 166.3calibrated Vbg = 1.23338 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.5calibrated Vbg = 1.23001 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 159.4calibrated Vbg = 1.23492 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 158.9calibrated Vbg = 1.23275 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.2calibrated Vbg = 1.23453 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 164.7calibrated Vbg = 1.22179 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.4calibrated Vbg = 1.22183 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 151.3calibrated Vbg = 1.22084 :::*/*/*/*/
[12:26:06.597] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.4calibrated Vbg = 1.2189 :::*/*/*/*/
[12:26:06.600] <TB0> INFO: PixTestReadback::RES sent once
[12:29:01.276] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C0.dat
[12:29:01.277] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C1.dat
[12:29:01.277] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C2.dat
[12:29:01.277] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C3.dat
[12:29:01.277] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C4.dat
[12:29:01.277] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C5.dat
[12:29:01.277] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C6.dat
[12:29:01.277] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C7.dat
[12:29:01.278] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C8.dat
[12:29:01.278] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C9.dat
[12:29:01.278] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C10.dat
[12:29:01.278] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C11.dat
[12:29:01.278] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C12.dat
[12:29:01.278] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C13.dat
[12:29:01.278] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C14.dat
[12:29:01.278] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2034_FullQualification_2015-09-21_09h54m_1442822099//002_FulltestPxar_m20//readbackCal_C15.dat
[12:29:01.310] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[12:29:01.312] <TB0> INFO: PixTestReadback::doTest() done
[12:29:01.312] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:01.312] <TB0> INFO: Decoding statistics:
[12:29:01.312] <TB0> INFO: General information:
[12:29:01.312] <TB0> INFO: 16bit words read: 1024
[12:29:01.312] <TB0> INFO: valid events total: 128
[12:29:01.312] <TB0> INFO: empty events: 128
[12:29:01.312] <TB0> INFO: valid events with pixels: 0
[12:29:01.312] <TB0> INFO: valid pixel hits: 0
[12:29:01.312] <TB0> INFO: Event errors: 0
[12:29:01.312] <TB0> INFO: start marker: 0
[12:29:01.312] <TB0> INFO: stop marker: 0
[12:29:01.312] <TB0> INFO: overflow: 0
[12:29:01.312] <TB0> INFO: invalid 5bit words: 0
[12:29:01.312] <TB0> INFO: invalid XOR eye diagram: 0
[12:29:01.312] <TB0> INFO: TBM errors: 0
[12:29:01.312] <TB0> INFO: flawed TBM headers: 0
[12:29:01.312] <TB0> INFO: flawed TBM trailers: 0
[12:29:01.312] <TB0> INFO: event ID mismatches: 0
[12:29:01.312] <TB0> INFO: ROC errors: 0
[12:29:01.312] <TB0> INFO: missing ROC header(s): 0
[12:29:01.312] <TB0> INFO: misplaced readback start: 0
[12:29:01.312] <TB0> INFO: Pixel decoding errors: 0
[12:29:01.312] <TB0> INFO: pixel data incomplete: 0
[12:29:01.312] <TB0> INFO: pixel address: 0
[12:29:01.312] <TB0> INFO: pulse height fill bit: 0
[12:29:01.312] <TB0> INFO: buffer corruption: 0
[12:29:01.327] <TB0> INFO: Decoding statistics:
[12:29:01.327] <TB0> INFO: General information:
[12:29:01.327] <TB0> INFO: 16bit words read: 2755344
[12:29:01.327] <TB0> INFO: valid events total: 176768
[12:29:01.328] <TB0> INFO: empty events: 7868
[12:29:01.328] <TB0> INFO: valid events with pixels: 168900
[12:29:01.328] <TB0> INFO: valid pixel hits: 668080
[12:29:01.328] <TB0> INFO: Event errors: 0
[12:29:01.328] <TB0> INFO: start marker: 0
[12:29:01.328] <TB0> INFO: stop marker: 0
[12:29:01.328] <TB0> INFO: overflow: 0
[12:29:01.328] <TB0> INFO: invalid 5bit words: 0
[12:29:01.328] <TB0> INFO: invalid XOR eye diagram: 0
[12:29:01.328] <TB0> INFO: TBM errors: 0
[12:29:01.328] <TB0> INFO: flawed TBM headers: 0
[12:29:01.328] <TB0> INFO: flawed TBM trailers: 0
[12:29:01.328] <TB0> INFO: event ID mismatches: 0
[12:29:01.328] <TB0> INFO: ROC errors: 0
[12:29:01.328] <TB0> INFO: missing ROC header(s): 0
[12:29:01.328] <TB0> INFO: misplaced readback start: 0
[12:29:01.328] <TB0> INFO: Pixel decoding errors: 0
[12:29:01.328] <TB0> INFO: pixel data incomplete: 0
[12:29:01.328] <TB0> INFO: pixel address: 0
[12:29:01.328] <TB0> INFO: pulse height fill bit: 0
[12:29:01.328] <TB0> INFO: buffer corruption: 0
[12:29:01.328] <TB0> INFO: enter test to run
[12:29:01.328] <TB0> INFO: test: exit no parameter change
[12:29:01.901] <TB0> QUIET: Connection to board 73 closed.
[12:29:01.981] <TB0> INFO: pXar: this is the end, my friend