[13:31:06.895] <TB0> INFO: *** Welcome to pxar ***
[13:31:06.895] <TB0> INFO: *** Today: 2016/01/13
[13:31:07.376] <TB0> INFO: *** Version: 3197
[13:31:07.376] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C15.dat
[13:31:07.376] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//tbmParameters_C0b.dat
[13:31:07.376] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//defaultMaskFile.dat
[13:31:07.376] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters_C15.dat
[13:31:07.442] <TB0> INFO: clk: 4
[13:31:07.442] <TB0> INFO: ctr: 4
[13:31:07.442] <TB0> INFO: sda: 19
[13:31:07.442] <TB0> INFO: tin: 9
[13:31:07.442] <TB0> INFO: level: 15
[13:31:07.442] <TB0> INFO: triggerdelay: 0
[13:31:07.442] <TB0> QUIET: Instanciating API for pxar prod-12
[13:31:07.442] <TB0> INFO: Log level: INFO
[13:31:07.450] <TB0> INFO: Found DTB DTB_WS6AYH
[13:31:07.462] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[13:31:07.465] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[13:31:07.467] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[13:31:08.977] <TB0> INFO: DUT info:
[13:31:08.977] <TB0> INFO: The DUT currently contains the following objects:
[13:31:08.977] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[13:31:08.977] <TB0> INFO: TBM Core alpha (0): 7 registers set
[13:31:08.977] <TB0> INFO: TBM Core beta (1): 7 registers set
[13:31:08.977] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:31:08.977] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:08.977] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:09.378] <TB0> INFO: enter 'restricted' command line mode
[13:31:09.378] <TB0> INFO: enter test to run
[13:31:09.378] <TB0> INFO: test: pretest no parameter change
[13:31:09.378] <TB0> INFO: running: pretest
[13:31:09.382] <TB0> INFO: ######################################################################
[13:31:09.382] <TB0> INFO: PixTestPretest::doTest()
[13:31:09.382] <TB0> INFO: ######################################################################
[13:31:09.384] <TB0> INFO: ----------------------------------------------------------------------
[13:31:09.384] <TB0> INFO: PixTestPretest::programROC()
[13:31:09.384] <TB0> INFO: ----------------------------------------------------------------------
[13:31:27.402] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[13:31:27.402] <TB0> INFO: IA differences per ROC: 20.1 16.9 18.5 17.7 19.3 20.1 18.5 18.5 19.3 16.9 18.5 18.5 19.3 19.3 16.9 18.5
[13:31:27.468] <TB0> INFO: ----------------------------------------------------------------------
[13:31:27.468] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[13:31:27.468] <TB0> INFO: ----------------------------------------------------------------------
[13:31:33.363] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[13:31:33.363] <TB0> INFO: i(loss) [mA/ROC]: 19.3 18.5 19.3 19.3 18.5 19.3 19.3 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5
[13:31:33.397] <TB0> INFO: ----------------------------------------------------------------------
[13:31:33.397] <TB0> INFO: PixTestPretest::findTiming()
[13:31:33.397] <TB0> INFO: ----------------------------------------------------------------------
[13:31:33.397] <TB0> INFO: PixTestCmd::init()

[13:33:32.990] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 7, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[13:33:32.990] <TB0> INFO: (success/tries = 100/100), width = 5
[13:33:32.992] <TB0> INFO: ----------------------------------------------------------------------
[13:33:32.992] <TB0> INFO: PixTestPretest::findWorkingPixel()
[13:33:32.992] <TB0> INFO: ----------------------------------------------------------------------
[13:33:33.131] <TB0> INFO: Expecting 231680 events.
[13:33:41.707] <TB0> INFO: 231680 events read in total (7860ms).
[13:33:41.711] <TB0> INFO: Test took 8716ms.
[13:33:42.022] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:33:42.060] <TB0> INFO: ----------------------------------------------------------------------
[13:33:42.060] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[13:33:42.060] <TB0> INFO: ----------------------------------------------------------------------
[13:33:42.196] <TB0> INFO: Expecting 231680 events.
[13:33:51.446] <TB0> INFO: 231680 events read in total (8534ms).
[13:33:51.450] <TB0> INFO: Test took 9386ms.
[13:33:51.757] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[13:33:51.757] <TB0> INFO: CalDel: 118 129 137 153 142 164 130 135 126 145 120 141 135 141 112 114
[13:33:51.757] <TB0> INFO: VthrComp: 51 51 52 51 51 51 51 51 51 51 51 51 51 52 51 51
[13:33:51.760] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C0.dat
[13:33:51.761] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C1.dat
[13:33:51.761] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C2.dat
[13:33:51.761] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C3.dat
[13:33:51.762] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C4.dat
[13:33:51.762] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C5.dat
[13:33:51.762] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C6.dat
[13:33:51.762] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C7.dat
[13:33:51.763] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C8.dat
[13:33:51.763] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C9.dat
[13:33:51.763] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C10.dat
[13:33:51.763] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C11.dat
[13:33:51.764] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C12.dat
[13:33:51.764] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C13.dat
[13:33:51.764] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C14.dat
[13:33:51.764] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters_C15.dat
[13:33:51.765] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//tbmParameters_C0a.dat
[13:33:51.765] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//tbmParameters_C0b.dat
[13:33:51.765] <TB0> INFO: PixTestPretest::doTest() done, duration: 162 seconds
[13:33:51.833] <TB0> INFO: enter test to run
[13:33:51.833] <TB0> INFO: test: fulltest no parameter change
[13:33:51.833] <TB0> INFO: running: fulltest
[13:33:51.833] <TB0> INFO: ######################################################################
[13:33:51.833] <TB0> INFO: PixTestFullTest::doTest()
[13:33:51.833] <TB0> INFO: ######################################################################
[13:33:51.834] <TB0> INFO: ######################################################################
[13:33:51.834] <TB0> INFO: PixTestAlive::doTest()
[13:33:51.834] <TB0> INFO: ######################################################################
[13:33:51.836] <TB0> INFO: ----------------------------------------------------------------------
[13:33:51.836] <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)
[13:33:51.836] <TB0> INFO: ----------------------------------------------------------------------
[13:33:52.146] <TB0> INFO: Expecting 41600 events.
[13:33:56.383] <TB0> INFO: 41600 events read in total (3522ms).
[13:33:56.384] <TB0> INFO: Test took 4546ms.
[13:33:56.391] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:56.680] <TB0> INFO: PixTestAlive::aliveTest() done
[13:33:56.680] <TB0> INFO: number of dead pixels (per ROC): 12 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:33:56.682] <TB0> INFO: ----------------------------------------------------------------------
[13:33:56.682] <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)
[13:33:56.682] <TB0> INFO: ----------------------------------------------------------------------
[13:33:57.012] <TB0> INFO: Expecting 41600 events.
[13:34:00.304] <TB0> INFO: 41600 events read in total (2577ms).
[13:34:00.304] <TB0> INFO: Test took 3621ms.
[13:34:00.304] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:00.304] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:34:00.616] <TB0> INFO: PixTestAlive::maskTest() done
[13:34:00.616] <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
[13:34:00.618] <TB0> INFO: ----------------------------------------------------------------------
[13:34:00.618] <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)
[13:34:00.618] <TB0> INFO: ----------------------------------------------------------------------
[13:34:00.929] <TB0> INFO: Expecting 41600 events.
[13:34:05.262] <TB0> INFO: 41600 events read in total (3617ms).
[13:34:05.263] <TB0> INFO: Test took 4643ms.
[13:34:05.269] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:05.554] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[13:34:05.554] <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
[13:34:05.555] <TB0> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[13:34:05.555] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:05.555] <TB0> INFO: Decoding statistics:
[13:34:05.555] <TB0> INFO: General information:
[13:34:05.555] <TB0> INFO: 16bit words read: 0
[13:34:05.555] <TB0> INFO: valid events total: 0
[13:34:05.555] <TB0> INFO: empty events: 0
[13:34:05.555] <TB0> INFO: valid events with pixels: 0
[13:34:05.555] <TB0> INFO: valid pixel hits: 0
[13:34:05.555] <TB0> INFO: Event errors: 0
[13:34:05.555] <TB0> INFO: start marker: 0
[13:34:05.555] <TB0> INFO: stop marker: 0
[13:34:05.555] <TB0> INFO: overflow: 0
[13:34:05.555] <TB0> INFO: invalid 5bit words: 0
[13:34:05.555] <TB0> INFO: invalid XOR eye diagram: 0
[13:34:05.555] <TB0> INFO: TBM errors: 0
[13:34:05.555] <TB0> INFO: flawed TBM headers: 0
[13:34:05.555] <TB0> INFO: flawed TBM trailers: 0
[13:34:05.555] <TB0> INFO: event ID mismatches: 0
[13:34:05.555] <TB0> INFO: ROC errors: 0
[13:34:05.555] <TB0> INFO: missing ROC header(s): 0
[13:34:05.555] <TB0> INFO: misplaced readback start: 0
[13:34:05.555] <TB0> INFO: Pixel decoding errors: 0
[13:34:05.555] <TB0> INFO: pixel data incomplete: 0
[13:34:05.555] <TB0> INFO: pixel address: 0
[13:34:05.555] <TB0> INFO: pulse height fill bit: 0
[13:34:05.555] <TB0> INFO: buffer corruption: 0
[13:34:05.562] <TB0> INFO: ######################################################################
[13:34:05.562] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:34:05.562] <TB0> INFO: ######################################################################
[13:34:05.563] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:34:05.574] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:34:05.574] <TB0> INFO: run 1 of 1
[13:34:05.880] <TB0> INFO: Expecting 3120000 events.
[13:34:47.742] <TB0> INFO: 861430 events read in total (41147ms).
[13:35:27.551] <TB0> INFO: 1707570 events read in total (80956ms).
[13:36:07.308] <TB0> INFO: 2567705 events read in total (120714ms).
[13:36:31.894] <TB0> INFO: 3120000 events read in total (145299ms).
[13:36:31.941] <TB0> INFO: Test took 146367ms.
[13:36:32.039] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:56.016] <TB0> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[13:36:56.016] <TB0> INFO: number of dead bumps (per ROC): 48 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2
[13:36:56.016] <TB0> INFO: separation cut (per ROC): 83 83 97 82 88 94 77 83 89 80 82 88 73 104 88 87
[13:36:56.016] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:56.016] <TB0> INFO: Decoding statistics:
[13:36:56.016] <TB0> INFO: General information:
[13:36:56.016] <TB0> INFO: 16bit words read: 0
[13:36:56.016] <TB0> INFO: valid events total: 0
[13:36:56.016] <TB0> INFO: empty events: 0
[13:36:56.016] <TB0> INFO: valid events with pixels: 0
[13:36:56.016] <TB0> INFO: valid pixel hits: 0
[13:36:56.016] <TB0> INFO: Event errors: 0
[13:36:56.016] <TB0> INFO: start marker: 0
[13:36:56.016] <TB0> INFO: stop marker: 0
[13:36:56.016] <TB0> INFO: overflow: 0
[13:36:56.016] <TB0> INFO: invalid 5bit words: 0
[13:36:56.016] <TB0> INFO: invalid XOR eye diagram: 0
[13:36:56.016] <TB0> INFO: TBM errors: 0
[13:36:56.016] <TB0> INFO: flawed TBM headers: 0
[13:36:56.016] <TB0> INFO: flawed TBM trailers: 0
[13:36:56.016] <TB0> INFO: event ID mismatches: 0
[13:36:56.016] <TB0> INFO: ROC errors: 0
[13:36:56.016] <TB0> INFO: missing ROC header(s): 0
[13:36:56.016] <TB0> INFO: misplaced readback start: 0
[13:36:56.016] <TB0> INFO: Pixel decoding errors: 0
[13:36:56.016] <TB0> INFO: pixel data incomplete: 0
[13:36:56.016] <TB0> INFO: pixel address: 0
[13:36:56.016] <TB0> INFO: pulse height fill bit: 0
[13:36:56.016] <TB0> INFO: buffer corruption: 0
[13:36:56.091] <TB0> INFO: ######################################################################
[13:36:56.092] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:36:56.092] <TB0> INFO: ######################################################################
[13:36:56.092] <TB0> INFO: ----------------------------------------------------------------------
[13:36:56.092] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:36:56.092] <TB0> INFO: ----------------------------------------------------------------------
[13:36:56.092] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:36:56.101] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[13:36:56.101] <TB0> INFO: run 1 of 1
[13:36:56.413] <TB0> INFO: Expecting 26208000 events.
[13:37:27.713] <TB0> INFO: 949350 events read in total (30584ms).
[13:37:58.944] <TB0> INFO: 1879300 events read in total (61815ms).
[13:38:30.628] <TB0> INFO: 2807550 events read in total (93499ms).
[13:39:00.612] <TB0> INFO: 3734200 events read in total (123483ms).
[13:39:31.202] <TB0> INFO: 4661300 events read in total (154073ms).
[13:40:01.170] <TB0> INFO: 5585800 events read in total (184041ms).
[13:40:31.749] <TB0> INFO: 6510000 events read in total (214620ms).
[13:41:02.633] <TB0> INFO: 7432750 events read in total (245504ms).
[13:41:33.183] <TB0> INFO: 8354300 events read in total (276054ms).
[13:42:03.723] <TB0> INFO: 9279150 events read in total (306594ms).
[13:42:34.292] <TB0> INFO: 10202550 events read in total (337163ms).
[13:43:04.992] <TB0> INFO: 11123750 events read in total (367863ms).
[13:43:35.849] <TB0> INFO: 12043100 events read in total (398720ms).
[13:44:06.210] <TB0> INFO: 12959200 events read in total (429081ms).
[13:44:37.117] <TB0> INFO: 13867650 events read in total (459988ms).
[13:45:08.073] <TB0> INFO: 14774450 events read in total (490944ms).
[13:45:39.104] <TB0> INFO: 15681450 events read in total (521975ms).
[13:46:09.419] <TB0> INFO: 16582550 events read in total (552290ms).
[13:46:39.210] <TB0> INFO: 17486150 events read in total (582081ms).
[13:47:09.438] <TB0> INFO: 18390000 events read in total (612309ms).
[13:47:40.197] <TB0> INFO: 19293550 events read in total (643068ms).
[13:48:11.297] <TB0> INFO: 20194800 events read in total (674168ms).
[13:48:41.753] <TB0> INFO: 21094250 events read in total (704624ms).
[13:49:13.069] <TB0> INFO: 21993600 events read in total (735940ms).
[13:49:43.774] <TB0> INFO: 22896350 events read in total (766645ms).
[13:50:15.315] <TB0> INFO: 23800650 events read in total (798187ms).
[13:50:45.374] <TB0> INFO: 24706800 events read in total (828245ms).
[13:51:15.587] <TB0> INFO: 25613100 events read in total (858458ms).
[13:51:35.563] <TB0> INFO: 26208000 events read in total (878434ms).
[13:51:35.591] <TB0> INFO: Test took 879490ms.
[13:51:35.664] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:35.826] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:37.468] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:38.928] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:40.374] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:41.884] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:43.500] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:44.925] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:46.466] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:47.929] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:49.368] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:50.864] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:52.404] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:53.861] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:55.501] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:56.961] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:51:58.529] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:52:00.051] <TB0> INFO: PixTestScurves::scurves() done
[13:52:00.052] <TB0> INFO: Vcal mean: 103.87 88.45 105.85 98.39 96.87 95.72 87.93 95.43 105.87 90.43 94.56 93.80 86.20 110.43 98.64 93.87
[13:52:00.052] <TB0> INFO: Vcal RMS: 8.15 5.53 7.15 6.45 4.96 5.36 4.79 6.22 5.29 5.37 5.83 5.86 5.59 6.05 5.23 5.34
[13:52:00.052] <TB0> INFO: PixTestScurves::fullTest() done, duration: 903 seconds
[13:52:00.052] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:00.052] <TB0> INFO: Decoding statistics:
[13:52:00.052] <TB0> INFO: General information:
[13:52:00.052] <TB0> INFO: 16bit words read: 0
[13:52:00.052] <TB0> INFO: valid events total: 0
[13:52:00.052] <TB0> INFO: empty events: 0
[13:52:00.052] <TB0> INFO: valid events with pixels: 0
[13:52:00.052] <TB0> INFO: valid pixel hits: 0
[13:52:00.052] <TB0> INFO: Event errors: 0
[13:52:00.052] <TB0> INFO: start marker: 0
[13:52:00.052] <TB0> INFO: stop marker: 0
[13:52:00.052] <TB0> INFO: overflow: 0
[13:52:00.052] <TB0> INFO: invalid 5bit words: 0
[13:52:00.052] <TB0> INFO: invalid XOR eye diagram: 0
[13:52:00.052] <TB0> INFO: TBM errors: 0
[13:52:00.052] <TB0> INFO: flawed TBM headers: 0
[13:52:00.052] <TB0> INFO: flawed TBM trailers: 0
[13:52:00.052] <TB0> INFO: event ID mismatches: 0
[13:52:00.052] <TB0> INFO: ROC errors: 0
[13:52:00.052] <TB0> INFO: missing ROC header(s): 0
[13:52:00.052] <TB0> INFO: misplaced readback start: 0
[13:52:00.052] <TB0> INFO: Pixel decoding errors: 0
[13:52:00.052] <TB0> INFO: pixel data incomplete: 0
[13:52:00.052] <TB0> INFO: pixel address: 0
[13:52:00.052] <TB0> INFO: pulse height fill bit: 0
[13:52:00.052] <TB0> INFO: buffer corruption: 0
[13:52:00.122] <TB0> INFO: ######################################################################
[13:52:00.122] <TB0> INFO: PixTestTrim::doTest()
[13:52:00.122] <TB0> INFO: ######################################################################
[13:52:00.123] <TB0> INFO: ----------------------------------------------------------------------
[13:52:00.123] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:52:00.123] <TB0> INFO: ----------------------------------------------------------------------
[13:52:00.203] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:52:00.203] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:52:00.212] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:52:00.212] <TB0> INFO: run 1 of 1
[13:52:00.525] <TB0> INFO: Expecting 6281600 events.
[13:52:39.264] <TB0> INFO: 1098920 events read in total (38024ms).
[13:53:18.989] <TB0> INFO: 2191530 events read in total (77749ms).
[13:53:57.975] <TB0> INFO: 3279500 events read in total (116736ms).
[13:54:36.791] <TB0> INFO: 4376600 events read in total (155551ms).
[13:55:17.445] <TB0> INFO: 5479250 events read in total (196205ms).
[13:55:45.446] <TB0> INFO: 6281600 events read in total (224206ms).
[13:55:45.477] <TB0> INFO: Test took 225265ms.
[13:55:45.522] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:05.521] <TB0> INFO: ROC 0 VthrComp = 99
[13:56:05.521] <TB0> INFO: ROC 1 VthrComp = 88
[13:56:05.521] <TB0> INFO: ROC 2 VthrComp = 101
[13:56:05.521] <TB0> INFO: ROC 3 VthrComp = 96
[13:56:05.521] <TB0> INFO: ROC 4 VthrComp = 96
[13:56:05.521] <TB0> INFO: ROC 5 VthrComp = 94
[13:56:05.521] <TB0> INFO: ROC 6 VthrComp = 92
[13:56:05.521] <TB0> INFO: ROC 7 VthrComp = 97
[13:56:05.522] <TB0> INFO: ROC 8 VthrComp = 103
[13:56:05.522] <TB0> INFO: ROC 9 VthrComp = 87
[13:56:05.522] <TB0> INFO: ROC 10 VthrComp = 92
[13:56:05.522] <TB0> INFO: ROC 11 VthrComp = 92
[13:56:05.522] <TB0> INFO: ROC 12 VthrComp = 86
[13:56:05.522] <TB0> INFO: ROC 13 VthrComp = 103
[13:56:05.522] <TB0> INFO: ROC 14 VthrComp = 97
[13:56:05.522] <TB0> INFO: ROC 15 VthrComp = 93
[13:56:05.522] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:56:05.522] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:56:05.531] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:56:05.531] <TB0> INFO: run 1 of 1
[13:56:05.840] <TB0> INFO: Expecting 6281600 events.
[13:56:41.398] <TB0> INFO: 760680 events read in total (34843ms).
[13:57:16.181] <TB0> INFO: 1519020 events read in total (69626ms).
[13:57:50.740] <TB0> INFO: 2277700 events read in total (104185ms).
[13:58:25.492] <TB0> INFO: 3036200 events read in total (138937ms).
[13:59:00.184] <TB0> INFO: 3788130 events read in total (173629ms).
[13:59:34.379] <TB0> INFO: 4535900 events read in total (207824ms).
[14:00:08.625] <TB0> INFO: 5281190 events read in total (242070ms).
[14:00:42.848] <TB0> INFO: 6026190 events read in total (276293ms).
[14:00:54.844] <TB0> INFO: 6281600 events read in total (288289ms).
[14:00:54.887] <TB0> INFO: Test took 289356ms.
[14:00:55.037] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:20.935] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 61.9344 for pixel 9/79 mean/min/max = 47.3195/32.6849/61.9541
[14:01:20.935] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 60.0871 for pixel 0/76 mean/min/max = 47.0083/33.8924/60.1242
[14:01:20.936] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 63.4282 for pixel 16/75 mean/min/max = 47.35/31.0831/63.6169
[14:01:20.936] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 62.4405 for pixel 7/10 mean/min/max = 46.9441/31.3931/62.4951
[14:01:20.936] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 57.2722 for pixel 17/79 mean/min/max = 45/32.6817/57.3183
[14:01:20.936] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 59.9744 for pixel 51/11 mean/min/max = 46.8379/33.6016/60.0743
[14:01:20.937] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 56.8787 for pixel 51/63 mean/min/max = 45.326/33.7048/56.9472
[14:01:20.937] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 60.119 for pixel 49/79 mean/min/max = 46.0688/31.9893/60.1484
[14:01:20.937] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 59.5669 for pixel 20/75 mean/min/max = 46.0164/32.2722/59.7605
[14:01:20.938] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 60.8563 for pixel 23/1 mean/min/max = 47.0624/33.2202/60.9046
[14:01:20.938] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 62.5799 for pixel 2/0 mean/min/max = 47.8464/33.0928/62.6
[14:01:20.939] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 61.5488 for pixel 12/16 mean/min/max = 47.285/32.7391/61.8308
[14:01:20.939] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.7543 for pixel 1/79 mean/min/max = 46.1579/32.3606/59.9551
[14:01:20.939] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 63.0473 for pixel 0/66 mean/min/max = 47.7292/31.7836/63.6748
[14:01:20.940] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.3327 for pixel 0/4 mean/min/max = 45.8017/32.143/59.4604
[14:01:20.940] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.3769 for pixel 19/76 mean/min/max = 45.9512/32.3896/59.5128
[14:01:20.940] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:01:21.071] <TB0> INFO: Expecting 514560 events.
[14:01:33.252] <TB0> INFO: 514560 events read in total (11465ms).
[14:01:33.257] <TB0> INFO: Expecting 514560 events.
[14:01:44.990] <TB0> INFO: 514560 events read in total (11148ms).
[14:01:44.996] <TB0> INFO: Expecting 514560 events.
[14:01:56.868] <TB0> INFO: 514560 events read in total (11293ms).
[14:01:56.878] <TB0> INFO: Expecting 514560 events.
[14:02:08.814] <TB0> INFO: 514560 events read in total (11366ms).
[14:02:08.825] <TB0> INFO: Expecting 514560 events.
[14:02:20.987] <TB0> INFO: 514560 events read in total (11586ms).
[14:02:21.001] <TB0> INFO: Expecting 514560 events.
[14:02:33.230] <TB0> INFO: 514560 events read in total (11658ms).
[14:02:33.248] <TB0> INFO: Expecting 514560 events.
[14:02:45.369] <TB0> INFO: 514560 events read in total (11553ms).
[14:02:45.386] <TB0> INFO: Expecting 514560 events.
[14:02:57.372] <TB0> INFO: 514560 events read in total (11413ms).
[14:02:57.393] <TB0> INFO: Expecting 514560 events.
[14:03:09.412] <TB0> INFO: 514560 events read in total (11456ms).
[14:03:09.434] <TB0> INFO: Expecting 514560 events.
[14:03:21.050] <TB0> INFO: 514560 events read in total (11057ms).
[14:03:21.074] <TB0> INFO: Expecting 514560 events.
[14:03:33.031] <TB0> INFO: 514560 events read in total (11395ms).
[14:03:33.058] <TB0> INFO: Expecting 514560 events.
[14:03:45.103] <TB0> INFO: 514560 events read in total (11491ms).
[14:03:45.137] <TB0> INFO: Expecting 514560 events.
[14:03:57.267] <TB0> INFO: 514560 events read in total (11587ms).
[14:03:57.299] <TB0> INFO: Expecting 514560 events.
[14:04:08.806] <TB0> INFO: 514560 events read in total (10951ms).
[14:04:08.839] <TB0> INFO: Expecting 514560 events.
[14:04:20.687] <TB0> INFO: 514560 events read in total (11294ms).
[14:04:20.723] <TB0> INFO: Expecting 514560 events.
[14:04:32.977] <TB0> INFO: 514560 events read in total (11702ms).
[14:04:33.014] <TB0> INFO: Test took 192075ms.
[14:04:33.950] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:04:33.959] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:04:33.959] <TB0> INFO: run 1 of 1
[14:04:34.264] <TB0> INFO: Expecting 6281600 events.
[14:05:09.344] <TB0> INFO: 745810 events read in total (34364ms).
[14:05:43.325] <TB0> INFO: 1489420 events read in total (68345ms).
[14:06:17.457] <TB0> INFO: 2232620 events read in total (102477ms).
[14:06:52.021] <TB0> INFO: 2975750 events read in total (137041ms).
[14:07:26.335] <TB0> INFO: 3712390 events read in total (171355ms).
[14:07:59.202] <TB0> INFO: 4443880 events read in total (204222ms).
[14:08:33.985] <TB0> INFO: 5172900 events read in total (239005ms).
[14:09:07.622] <TB0> INFO: 5900550 events read in total (272642ms).
[14:09:24.852] <TB0> INFO: 6281600 events read in total (289873ms).
[14:09:24.897] <TB0> INFO: Test took 290938ms.
[14:09:25.036] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:51.116] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.040847 .. 255.000000
[14:09:51.211] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[14:09:51.220] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:09:51.220] <TB0> INFO: run 1 of 1
[14:09:51.552] <TB0> INFO: Expecting 10649600 events.
[14:10:24.469] <TB0> INFO: 712200 events read in total (32201ms).
[14:10:58.323] <TB0> INFO: 1424450 events read in total (66055ms).
[14:11:32.281] <TB0> INFO: 2136570 events read in total (100013ms).
[14:12:05.026] <TB0> INFO: 2849200 events read in total (132758ms).
[14:12:38.120] <TB0> INFO: 3562100 events read in total (165852ms).
[14:13:10.920] <TB0> INFO: 4274630 events read in total (198652ms).
[14:13:42.723] <TB0> INFO: 4987570 events read in total (230455ms).
[14:14:14.786] <TB0> INFO: 5700370 events read in total (262518ms).
[14:14:46.329] <TB0> INFO: 6413080 events read in total (294061ms).
[14:15:18.647] <TB0> INFO: 7124730 events read in total (326379ms).
[14:15:50.378] <TB0> INFO: 7836540 events read in total (358110ms).
[14:16:22.973] <TB0> INFO: 8547570 events read in total (390705ms).
[14:16:54.993] <TB0> INFO: 9258240 events read in total (422725ms).
[14:17:26.510] <TB0> INFO: 9968630 events read in total (454242ms).
[14:17:58.133] <TB0> INFO: 10649600 events read in total (485865ms).
[14:17:58.229] <TB0> INFO: Test took 487009ms.
[14:17:58.482] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:27.762] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 1.023325 .. 255.000000
[14:18:27.854] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 255 (-1/-1) hits flags = 528 (plus default)
[14:18:27.861] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:18:27.861] <TB0> INFO: run 1 of 1
[14:18:28.184] <TB0> INFO: Expecting 10608000 events.
[14:19:01.191] <TB0> INFO: 711180 events read in total (32291ms).
[14:19:34.167] <TB0> INFO: 1422410 events read in total (65267ms).
[14:20:04.870] <TB0> INFO: 2133500 events read in total (95970ms).
[14:20:38.328] <TB0> INFO: 2844620 events read in total (129428ms).
[14:21:11.199] <TB0> INFO: 3555830 events read in total (162299ms).
[14:21:42.246] <TB0> INFO: 4266950 events read in total (193346ms).
[14:22:13.172] <TB0> INFO: 4978140 events read in total (224272ms).
[14:22:45.837] <TB0> INFO: 5689050 events read in total (256938ms).
[14:23:17.278] <TB0> INFO: 6400180 events read in total (288378ms).
[14:23:48.168] <TB0> INFO: 7111100 events read in total (319268ms).
[14:24:19.789] <TB0> INFO: 7821970 events read in total (350889ms).
[14:24:52.784] <TB0> INFO: 8532790 events read in total (383884ms).
[14:25:23.459] <TB0> INFO: 9243550 events read in total (414559ms).
[14:25:55.180] <TB0> INFO: 9954340 events read in total (446280ms).
[14:26:26.020] <TB0> INFO: 10608000 events read in total (477120ms).
[14:26:26.141] <TB0> INFO: Test took 478280ms.
[14:26:26.430] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:56.174] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 0.994224 .. 219.167404
[14:26:56.251] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 0 .. 229 (-1/-1) hits flags = 528 (plus default)
[14:26:56.259] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:26:56.259] <TB0> INFO: run 1 of 1
[14:26:56.561] <TB0> INFO: Expecting 9568000 events.
[14:27:29.245] <TB0> INFO: 718820 events read in total (31969ms).
[14:27:59.992] <TB0> INFO: 1437420 events read in total (62716ms).
[14:28:33.950] <TB0> INFO: 2156180 events read in total (96674ms).
[14:29:05.064] <TB0> INFO: 2874810 events read in total (127788ms).
[14:29:35.739] <TB0> INFO: 3593650 events read in total (158463ms).
[14:30:09.299] <TB0> INFO: 4312250 events read in total (192023ms).
[14:30:41.999] <TB0> INFO: 5031020 events read in total (224723ms).
[14:31:12.851] <TB0> INFO: 5749610 events read in total (255575ms).
[14:31:45.693] <TB0> INFO: 6468250 events read in total (288418ms).
[14:32:17.527] <TB0> INFO: 7186660 events read in total (320251ms).
[14:32:48.197] <TB0> INFO: 7905090 events read in total (350921ms).
[14:33:21.351] <TB0> INFO: 8623560 events read in total (384075ms).
[14:33:52.288] <TB0> INFO: 9341940 events read in total (415012ms).
[14:34:02.266] <TB0> INFO: 9568000 events read in total (424990ms).
[14:34:02.332] <TB0> INFO: Test took 426073ms.
[14:34:02.576] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:32.574] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 0.421462 .. 88.340635
[14:34:32.648] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 0 .. 98 (-1/-1) hits flags = 528 (plus default)
[14:34:32.656] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:34:32.656] <TB0> INFO: run 1 of 1
[14:34:32.958] <TB0> INFO: Expecting 4118400 events.
[14:35:05.446] <TB0> INFO: 806640 events read in total (31771ms).
[14:35:38.571] <TB0> INFO: 1613380 events read in total (64896ms).
[14:36:13.217] <TB0> INFO: 2420140 events read in total (99542ms).
[14:36:45.015] <TB0> INFO: 3226960 events read in total (131340ms).
[14:37:16.930] <TB0> INFO: 4033240 events read in total (163255ms).
[14:37:21.339] <TB0> INFO: 4118400 events read in total (167664ms).
[14:37:21.374] <TB0> INFO: Test took 168718ms.
[14:37:21.469] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:40.665] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[14:37:40.665] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:37:40.673] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:37:40.673] <TB0> INFO: run 1 of 1
[14:37:40.982] <TB0> INFO: Expecting 1705600 events.
[14:38:18.356] <TB0> INFO: 878240 events read in total (36659ms).
[14:38:51.742] <TB0> INFO: 1705600 events read in total (70045ms).
[14:38:51.771] <TB0> INFO: Test took 71099ms.
[14:38:51.812] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:06.264] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C0.dat
[14:39:06.264] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C1.dat
[14:39:06.264] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C2.dat
[14:39:06.265] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C3.dat
[14:39:06.265] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C4.dat
[14:39:06.265] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C5.dat
[14:39:06.266] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C6.dat
[14:39:06.266] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C7.dat
[14:39:06.266] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C8.dat
[14:39:06.267] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C9.dat
[14:39:06.267] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C10.dat
[14:39:06.267] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C11.dat
[14:39:06.268] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C12.dat
[14:39:06.268] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C13.dat
[14:39:06.268] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C14.dat
[14:39:06.269] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C15.dat
[14:39:06.269] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C0.dat
[14:39:06.283] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C1.dat
[14:39:06.291] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C2.dat
[14:39:06.299] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C3.dat
[14:39:06.307] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C4.dat
[14:39:06.313] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C5.dat
[14:39:06.319] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C6.dat
[14:39:06.324] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C7.dat
[14:39:06.330] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C8.dat
[14:39:06.336] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C9.dat
[14:39:06.342] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C10.dat
[14:39:06.348] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C11.dat
[14:39:06.354] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C12.dat
[14:39:06.360] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C13.dat
[14:39:06.366] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C14.dat
[14:39:06.372] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//trimParameters35_C15.dat
[14:39:06.378] <TB0> INFO: PixTestTrim::trimTest() done
[14:39:06.378] <TB0> INFO: vtrim: 99 92 107 115 89 98 85 103 99 95 99 112 97 107 95 100
[14:39:06.378] <TB0> INFO: vthrcomp: 99 88 101 96 96 94 92 97 103 87 92 92 86 103 97 93
[14:39:06.378] <TB0> INFO: vcal mean: 34.91 34.96 34.95 34.93 34.94 34.97 34.98 35.03 34.98 34.99 34.98 34.93 34.98 34.93 34.96 34.97
[14:39:06.378] <TB0> INFO: vcal RMS: 1.94 0.85 1.00 1.27 0.92 0.99 0.80 0.86 0.87 0.91 0.91 0.89 0.87 0.91 0.90 0.85
[14:39:06.378] <TB0> INFO: bits mean: 8.70 8.61 9.26 9.69 9.31 8.69 8.90 9.11 9.31 8.87 9.06 9.45 8.96 8.90 9.44 9.50
[14:39:06.378] <TB0> INFO: bits RMS: 2.84 2.76 2.78 2.60 2.72 2.83 2.74 2.92 2.76 2.77 2.62 2.57 2.95 2.82 2.76 2.64
[14:39:06.385] <TB0> INFO: ----------------------------------------------------------------------
[14:39:06.385] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[14:39:06.385] <TB0> INFO: ----------------------------------------------------------------------
[14:39:06.387] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[14:39:06.395] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:39:06.395] <TB0> INFO: run 1 of 1
[14:39:06.698] <TB0> INFO: Expecting 4160000 events.
[14:39:48.209] <TB0> INFO: 966845 events read in total (40796ms).
[14:40:29.639] <TB0> INFO: 1925365 events read in total (82227ms).
[14:41:12.252] <TB0> INFO: 2872770 events read in total (124839ms).
[14:41:55.734] <TB0> INFO: 3818770 events read in total (168321ms).
[14:42:11.070] <TB0> INFO: 4160000 events read in total (183657ms).
[14:42:11.105] <TB0> INFO: Test took 184710ms.
[14:42:11.198] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:40.267] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 187 (-1/-1) hits flags = 528 (plus default)
[14:42:40.276] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:42:40.277] <TB0> INFO: run 1 of 1
[14:42:40.592] <TB0> INFO: Expecting 3910400 events.
[14:43:24.685] <TB0> INFO: 955020 events read in total (43378ms).
[14:44:08.032] <TB0> INFO: 1903060 events read in total (86726ms).
[14:44:51.949] <TB0> INFO: 2839920 events read in total (130643ms).
[14:45:35.978] <TB0> INFO: 3776810 events read in total (174671ms).
[14:45:42.282] <TB0> INFO: 3910400 events read in total (180976ms).
[14:45:42.318] <TB0> INFO: Test took 182041ms.
[14:45:42.412] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:12.032] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 174 (-1/-1) hits flags = 528 (plus default)
[14:46:12.040] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:46:12.040] <TB0> INFO: run 1 of 1
[14:46:12.376] <TB0> INFO: Expecting 3640000 events.
[14:46:58.437] <TB0> INFO: 987085 events read in total (45346ms).
[14:47:42.174] <TB0> INFO: 1963515 events read in total (89083ms).
[14:48:27.000] <TB0> INFO: 2928815 events read in total (133910ms).
[14:48:57.736] <TB0> INFO: 3640000 events read in total (164645ms).
[14:48:57.780] <TB0> INFO: Test took 165740ms.
[14:48:57.857] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:24.246] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[14:49:24.255] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:49:24.255] <TB0> INFO: run 1 of 1
[14:49:24.567] <TB0> INFO: Expecting 3619200 events.
[14:50:06.772] <TB0> INFO: 989445 events read in total (41489ms).
[14:50:50.334] <TB0> INFO: 1967930 events read in total (85051ms).
[14:51:33.187] <TB0> INFO: 2935600 events read in total (127904ms).
[14:52:01.534] <TB0> INFO: 3619200 events read in total (156251ms).
[14:52:01.570] <TB0> INFO: Test took 157315ms.
[14:52:01.644] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:28.022] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 174 (-1/-1) hits flags = 528 (plus default)
[14:52:28.031] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:52:28.031] <TB0> INFO: run 1 of 1
[14:52:28.334] <TB0> INFO: Expecting 3640000 events.
[14:53:10.849] <TB0> INFO: 986525 events read in total (41799ms).
[14:53:52.114] <TB0> INFO: 1962180 events read in total (83064ms).
[14:54:32.861] <TB0> INFO: 2926560 events read in total (123811ms).
[14:55:03.559] <TB0> INFO: 3640000 events read in total (154509ms).
[14:55:03.591] <TB0> INFO: Test took 155560ms.
[14:55:03.665] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:28.665] <TB0> INFO: PixTestTrim::trimBitTest() done
[14:55:28.666] <TB0> INFO: PixTestTrim::doTest() done, duration: 3808 seconds
[14:55:28.666] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:28.666] <TB0> INFO: Decoding statistics:
[14:55:28.666] <TB0> INFO: General information:
[14:55:28.666] <TB0> INFO: 16bit words read: 0
[14:55:28.666] <TB0> INFO: valid events total: 0
[14:55:28.666] <TB0> INFO: empty events: 0
[14:55:28.666] <TB0> INFO: valid events with pixels: 0
[14:55:28.667] <TB0> INFO: valid pixel hits: 0
[14:55:28.667] <TB0> INFO: Event errors: 0
[14:55:28.667] <TB0> INFO: start marker: 0
[14:55:28.667] <TB0> INFO: stop marker: 0
[14:55:28.667] <TB0> INFO: overflow: 0
[14:55:28.667] <TB0> INFO: invalid 5bit words: 0
[14:55:28.667] <TB0> INFO: invalid XOR eye diagram: 0
[14:55:28.667] <TB0> INFO: TBM errors: 0
[14:55:28.667] <TB0> INFO: flawed TBM headers: 0
[14:55:28.667] <TB0> INFO: flawed TBM trailers: 0
[14:55:28.667] <TB0> INFO: event ID mismatches: 0
[14:55:28.667] <TB0> INFO: ROC errors: 0
[14:55:28.667] <TB0> INFO: missing ROC header(s): 0
[14:55:28.667] <TB0> INFO: misplaced readback start: 0
[14:55:28.667] <TB0> INFO: Pixel decoding errors: 0
[14:55:28.667] <TB0> INFO: pixel data incomplete: 0
[14:55:28.667] <TB0> INFO: pixel address: 0
[14:55:28.667] <TB0> INFO: pulse height fill bit: 0
[14:55:28.667] <TB0> INFO: buffer corruption: 0
[14:55:29.323] <TB0> INFO: ######################################################################
[14:55:29.323] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:55:29.323] <TB0> INFO: ######################################################################
[14:55:29.626] <TB0> INFO: Expecting 41600 events.
[14:55:33.910] <TB0> INFO: 41600 events read in total (3569ms).
[14:55:33.910] <TB0> INFO: Test took 4586ms.
[14:55:33.916] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:34.477] <TB0> INFO: Expecting 41600 events.
[14:55:38.708] <TB0> INFO: 41600 events read in total (3515ms).
[14:55:38.709] <TB0> INFO: Test took 4531ms.
[14:55:38.715] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:39.071] <TB0> INFO: Expecting 41600 events.
[14:55:43.274] <TB0> INFO: 41600 events read in total (3488ms).
[14:55:43.274] <TB0> INFO: Test took 4531ms.
[14:55:43.280] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:43.630] <TB0> INFO: Expecting 2560 events.
[14:55:44.693] <TB0> INFO: 2560 events read in total (347ms).
[14:55:44.693] <TB0> INFO: Test took 1406ms.
[14:55:45.107] <TB0> INFO: Expecting 2560 events.
[14:55:46.169] <TB0> INFO: 2560 events read in total (347ms).
[14:55:46.169] <TB0> INFO: Test took 1476ms.
[14:55:46.583] <TB0> INFO: Expecting 2560 events.
[14:55:47.644] <TB0> INFO: 2560 events read in total (345ms).
[14:55:47.645] <TB0> INFO: Test took 1476ms.
[14:55:48.059] <TB0> INFO: Expecting 2560 events.
[14:55:49.120] <TB0> INFO: 2560 events read in total (346ms).
[14:55:49.120] <TB0> INFO: Test took 1475ms.
[14:55:49.534] <TB0> INFO: Expecting 2560 events.
[14:55:50.595] <TB0> INFO: 2560 events read in total (345ms).
[14:55:50.596] <TB0> INFO: Test took 1476ms.
[14:55:51.010] <TB0> INFO: Expecting 2560 events.
[14:55:52.072] <TB0> INFO: 2560 events read in total (346ms).
[14:55:52.072] <TB0> INFO: Test took 1476ms.
[14:55:52.486] <TB0> INFO: Expecting 2560 events.
[14:55:53.548] <TB0> INFO: 2560 events read in total (346ms).
[14:55:53.548] <TB0> INFO: Test took 1476ms.
[14:55:53.962] <TB0> INFO: Expecting 2560 events.
[14:55:55.024] <TB0> INFO: 2560 events read in total (346ms).
[14:55:55.024] <TB0> INFO: Test took 1476ms.
[14:55:55.438] <TB0> INFO: Expecting 2560 events.
[14:55:56.500] <TB0> INFO: 2560 events read in total (346ms).
[14:55:56.500] <TB0> INFO: Test took 1475ms.
[14:55:56.914] <TB0> INFO: Expecting 2560 events.
[14:55:57.976] <TB0> INFO: 2560 events read in total (346ms).
[14:55:57.976] <TB0> INFO: Test took 1476ms.
[14:55:58.390] <TB0> INFO: Expecting 2560 events.
[14:55:59.452] <TB0> INFO: 2560 events read in total (346ms).
[14:55:59.452] <TB0> INFO: Test took 1476ms.
[14:55:59.866] <TB0> INFO: Expecting 2560 events.
[14:56:00.928] <TB0> INFO: 2560 events read in total (346ms).
[14:56:00.928] <TB0> INFO: Test took 1476ms.
[14:56:01.342] <TB0> INFO: Expecting 2560 events.
[14:56:02.403] <TB0> INFO: 2560 events read in total (345ms).
[14:56:02.403] <TB0> INFO: Test took 1475ms.
[14:56:02.817] <TB0> INFO: Expecting 2560 events.
[14:56:03.879] <TB0> INFO: 2560 events read in total (346ms).
[14:56:03.879] <TB0> INFO: Test took 1475ms.
[14:56:04.293] <TB0> INFO: Expecting 2560 events.
[14:56:05.355] <TB0> INFO: 2560 events read in total (346ms).
[14:56:05.355] <TB0> INFO: Test took 1475ms.
[14:56:05.769] <TB0> INFO: Expecting 2560 events.
[14:56:06.831] <TB0> INFO: 2560 events read in total (346ms).
[14:56:06.831] <TB0> INFO: Test took 1476ms.
[14:56:06.834] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:56:07.247] <TB0> INFO: Expecting 655360 events.
[14:56:20.900] <TB0> INFO: 655360 events read in total (12937ms).
[14:56:20.908] <TB0> INFO: Expecting 655360 events.
[14:56:34.420] <TB0> INFO: 655360 events read in total (12985ms).
[14:56:34.431] <TB0> INFO: Expecting 655360 events.
[14:56:47.887] <TB0> INFO: 655360 events read in total (12928ms).
[14:56:47.902] <TB0> INFO: Expecting 655360 events.
[14:57:01.374] <TB0> INFO: 655360 events read in total (12945ms).
[14:57:01.392] <TB0> INFO: Expecting 655360 events.
[14:57:14.984] <TB0> INFO: 655360 events read in total (13064ms).
[14:57:15.006] <TB0> INFO: Expecting 655360 events.
[14:57:28.470] <TB0> INFO: 655360 events read in total (12937ms).
[14:57:28.494] <TB0> INFO: Expecting 655360 events.
[14:57:41.921] <TB0> INFO: 655360 events read in total (12900ms).
[14:57:41.948] <TB0> INFO: Expecting 655360 events.
[14:57:55.490] <TB0> INFO: 655360 events read in total (13014ms).
[14:57:55.521] <TB0> INFO: Expecting 655360 events.
[14:58:08.991] <TB0> INFO: 655360 events read in total (12942ms).
[14:58:09.029] <TB0> INFO: Expecting 655360 events.
[14:58:22.503] <TB0> INFO: 655360 events read in total (12946ms).
[14:58:22.543] <TB0> INFO: Expecting 655360 events.
[14:58:35.977] <TB0> INFO: 655360 events read in total (12906ms).
[14:58:36.022] <TB0> INFO: Expecting 655360 events.
[14:58:49.444] <TB0> INFO: 655360 events read in total (12895ms).
[14:58:49.492] <TB0> INFO: Expecting 655360 events.
[14:59:02.922] <TB0> INFO: 655360 events read in total (12903ms).
[14:59:02.973] <TB0> INFO: Expecting 655360 events.
[14:59:16.419] <TB0> INFO: 655360 events read in total (12918ms).
[14:59:16.475] <TB0> INFO: Expecting 655360 events.
[14:59:29.912] <TB0> INFO: 655360 events read in total (12910ms).
[14:59:29.971] <TB0> INFO: Expecting 655360 events.
[14:59:43.402] <TB0> INFO: 655360 events read in total (12904ms).
[14:59:43.465] <TB0> INFO: Test took 216631ms.
[14:59:43.542] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:59:43.850] <TB0> INFO: Expecting 655360 events.
[14:59:57.419] <TB0> INFO: 655360 events read in total (12853ms).
[14:59:57.427] <TB0> INFO: Expecting 655360 events.
[15:00:10.771] <TB0> INFO: 655360 events read in total (12816ms).
[15:00:10.784] <TB0> INFO: Expecting 655360 events.
[15:00:24.182] <TB0> INFO: 655360 events read in total (12870ms).
[15:00:24.196] <TB0> INFO: Expecting 655360 events.
[15:00:37.585] <TB0> INFO: 655360 events read in total (12861ms).
[15:00:37.603] <TB0> INFO: Expecting 655360 events.
[15:00:51.047] <TB0> INFO: 655360 events read in total (12916ms).
[15:00:51.068] <TB0> INFO: Expecting 655360 events.
[15:01:04.490] <TB0> INFO: 655360 events read in total (12894ms).
[15:01:04.514] <TB0> INFO: Expecting 655360 events.
[15:01:17.931] <TB0> INFO: 655360 events read in total (12890ms).
[15:01:17.958] <TB0> INFO: Expecting 655360 events.
[15:01:31.288] <TB0> INFO: 655360 events read in total (12802ms).
[15:01:31.320] <TB0> INFO: Expecting 655360 events.
[15:01:44.755] <TB0> INFO: 655360 events read in total (12908ms).
[15:01:44.793] <TB0> INFO: Expecting 655360 events.
[15:01:58.205] <TB0> INFO: 655360 events read in total (12885ms).
[15:01:58.248] <TB0> INFO: Expecting 655360 events.
[15:02:11.624] <TB0> INFO: 655360 events read in total (12849ms).
[15:02:11.669] <TB0> INFO: Expecting 655360 events.
[15:02:25.135] <TB0> INFO: 655360 events read in total (12938ms).
[15:02:25.184] <TB0> INFO: Expecting 655360 events.
[15:02:38.609] <TB0> INFO: 655360 events read in total (12897ms).
[15:02:38.661] <TB0> INFO: Expecting 655360 events.
[15:02:52.047] <TB0> INFO: 655360 events read in total (12858ms).
[15:02:52.103] <TB0> INFO: Expecting 655360 events.
[15:03:05.532] <TB0> INFO: 655360 events read in total (12901ms).
[15:03:05.593] <TB0> INFO: Expecting 655360 events.
[15:03:19.003] <TB0> INFO: 655360 events read in total (12883ms).
[15:03:19.066] <TB0> INFO: Test took 215524ms.
[15:03:19.247] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.254] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.260] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.267] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.273] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.279] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.286] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.293] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.300] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.306] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.312] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.319] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.325] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.332] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.338] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.344] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:03:19.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:03:19.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:03:19.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:03:19.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:03:19.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:03:19.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:03:19.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:03:19.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:03:19.681] <TB0> INFO: Expecting 41600 events.
[15:03:23.524] <TB0> INFO: 41600 events read in total (3128ms).
[15:03:23.525] <TB0> INFO: Test took 4141ms.
[15:03:24.065] <TB0> INFO: Expecting 41600 events.
[15:03:27.890] <TB0> INFO: 41600 events read in total (3110ms).
[15:03:27.890] <TB0> INFO: Test took 4121ms.
[15:03:28.431] <TB0> INFO: Expecting 41600 events.
[15:03:32.274] <TB0> INFO: 41600 events read in total (3128ms).
[15:03:32.274] <TB0> INFO: Test took 4141ms.
[15:03:32.517] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:32.647] <TB0> INFO: Expecting 2560 events.
[15:03:33.708] <TB0> INFO: 2560 events read in total (345ms).
[15:03:33.708] <TB0> INFO: Test took 1191ms.
[15:03:33.709] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:34.122] <TB0> INFO: Expecting 2560 events.
[15:03:35.182] <TB0> INFO: 2560 events read in total (344ms).
[15:03:35.182] <TB0> INFO: Test took 1473ms.
[15:03:35.184] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:35.597] <TB0> INFO: Expecting 2560 events.
[15:03:36.657] <TB0> INFO: 2560 events read in total (345ms).
[15:03:36.657] <TB0> INFO: Test took 1473ms.
[15:03:36.659] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:37.072] <TB0> INFO: Expecting 2560 events.
[15:03:38.131] <TB0> INFO: 2560 events read in total (344ms).
[15:03:38.131] <TB0> INFO: Test took 1472ms.
[15:03:38.133] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:38.546] <TB0> INFO: Expecting 2560 events.
[15:03:39.606] <TB0> INFO: 2560 events read in total (345ms).
[15:03:39.606] <TB0> INFO: Test took 1473ms.
[15:03:39.608] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:40.020] <TB0> INFO: Expecting 2560 events.
[15:03:41.080] <TB0> INFO: 2560 events read in total (344ms).
[15:03:41.081] <TB0> INFO: Test took 1473ms.
[15:03:41.082] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:41.495] <TB0> INFO: Expecting 2560 events.
[15:03:42.555] <TB0> INFO: 2560 events read in total (344ms).
[15:03:42.555] <TB0> INFO: Test took 1473ms.
[15:03:42.563] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:42.969] <TB0> INFO: Expecting 2560 events.
[15:03:44.030] <TB0> INFO: 2560 events read in total (345ms).
[15:03:44.030] <TB0> INFO: Test took 1467ms.
[15:03:44.031] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:44.444] <TB0> INFO: Expecting 2560 events.
[15:03:45.504] <TB0> INFO: 2560 events read in total (344ms).
[15:03:45.504] <TB0> INFO: Test took 1473ms.
[15:03:45.506] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:45.919] <TB0> INFO: Expecting 2560 events.
[15:03:46.979] <TB0> INFO: 2560 events read in total (345ms).
[15:03:46.979] <TB0> INFO: Test took 1473ms.
[15:03:46.980] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:47.393] <TB0> INFO: Expecting 2560 events.
[15:03:48.453] <TB0> INFO: 2560 events read in total (344ms).
[15:03:48.454] <TB0> INFO: Test took 1474ms.
[15:03:48.455] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:48.868] <TB0> INFO: Expecting 2560 events.
[15:03:49.928] <TB0> INFO: 2560 events read in total (345ms).
[15:03:49.928] <TB0> INFO: Test took 1473ms.
[15:03:49.930] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:50.343] <TB0> INFO: Expecting 2560 events.
[15:03:51.402] <TB0> INFO: 2560 events read in total (344ms).
[15:03:51.403] <TB0> INFO: Test took 1473ms.
[15:03:51.404] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:51.817] <TB0> INFO: Expecting 2560 events.
[15:03:52.877] <TB0> INFO: 2560 events read in total (344ms).
[15:03:52.877] <TB0> INFO: Test took 1473ms.
[15:03:52.879] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:53.292] <TB0> INFO: Expecting 2560 events.
[15:03:54.352] <TB0> INFO: 2560 events read in total (345ms).
[15:03:54.352] <TB0> INFO: Test took 1473ms.
[15:03:54.353] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:54.766] <TB0> INFO: Expecting 2560 events.
[15:03:55.826] <TB0> INFO: 2560 events read in total (344ms).
[15:03:55.826] <TB0> INFO: Test took 1473ms.
[15:03:55.828] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:56.241] <TB0> INFO: Expecting 2560 events.
[15:03:57.301] <TB0> INFO: 2560 events read in total (345ms).
[15:03:57.301] <TB0> INFO: Test took 1473ms.
[15:03:57.303] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:57.716] <TB0> INFO: Expecting 2560 events.
[15:03:58.776] <TB0> INFO: 2560 events read in total (345ms).
[15:03:58.776] <TB0> INFO: Test took 1473ms.
[15:03:58.783] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:03:59.190] <TB0> INFO: Expecting 2560 events.
[15:04:00.250] <TB0> INFO: 2560 events read in total (344ms).
[15:04:00.250] <TB0> INFO: Test took 1467ms.
[15:04:00.252] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:00.665] <TB0> INFO: Expecting 2560 events.
[15:04:01.725] <TB0> INFO: 2560 events read in total (345ms).
[15:04:01.725] <TB0> INFO: Test took 1473ms.
[15:04:01.727] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:02.140] <TB0> INFO: Expecting 2560 events.
[15:04:03.200] <TB0> INFO: 2560 events read in total (345ms).
[15:04:03.200] <TB0> INFO: Test took 1473ms.
[15:04:03.202] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:03.614] <TB0> INFO: Expecting 2560 events.
[15:04:04.674] <TB0> INFO: 2560 events read in total (344ms).
[15:04:04.675] <TB0> INFO: Test took 1474ms.
[15:04:04.676] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:05.089] <TB0> INFO: Expecting 2560 events.
[15:04:06.149] <TB0> INFO: 2560 events read in total (344ms).
[15:04:06.149] <TB0> INFO: Test took 1473ms.
[15:04:06.151] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:06.564] <TB0> INFO: Expecting 2560 events.
[15:04:07.624] <TB0> INFO: 2560 events read in total (345ms).
[15:04:07.624] <TB0> INFO: Test took 1473ms.
[15:04:07.625] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:08.038] <TB0> INFO: Expecting 2560 events.
[15:04:09.098] <TB0> INFO: 2560 events read in total (344ms).
[15:04:09.098] <TB0> INFO: Test took 1473ms.
[15:04:09.100] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:09.513] <TB0> INFO: Expecting 2560 events.
[15:04:10.573] <TB0> INFO: 2560 events read in total (345ms).
[15:04:10.573] <TB0> INFO: Test took 1473ms.
[15:04:10.575] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:10.987] <TB0> INFO: Expecting 2560 events.
[15:04:12.048] <TB0> INFO: 2560 events read in total (345ms).
[15:04:12.048] <TB0> INFO: Test took 1473ms.
[15:04:12.050] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:12.463] <TB0> INFO: Expecting 2560 events.
[15:04:13.523] <TB0> INFO: 2560 events read in total (345ms).
[15:04:13.523] <TB0> INFO: Test took 1474ms.
[15:04:13.525] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:13.937] <TB0> INFO: Expecting 2560 events.
[15:04:14.998] <TB0> INFO: 2560 events read in total (345ms).
[15:04:14.998] <TB0> INFO: Test took 1473ms.
[15:04:15.000] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:15.413] <TB0> INFO: Expecting 2560 events.
[15:04:16.473] <TB0> INFO: 2560 events read in total (345ms).
[15:04:16.473] <TB0> INFO: Test took 1473ms.
[15:04:16.475] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:16.887] <TB0> INFO: Expecting 2560 events.
[15:04:17.948] <TB0> INFO: 2560 events read in total (345ms).
[15:04:17.948] <TB0> INFO: Test took 1474ms.
[15:04:17.950] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:04:18.363] <TB0> INFO: Expecting 2560 events.
[15:04:19.423] <TB0> INFO: 2560 events read in total (345ms).
[15:04:19.423] <TB0> INFO: Test took 1474ms.
[15:04:20.021] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 530 seconds
[15:04:20.021] <TB0> INFO: PH scale (per ROC): 67 72 66 68 67 76 79 75 73 70 77 73 75 72 75 77
[15:04:20.021] <TB0> INFO: PH offset (per ROC): 176 174 188 174 184 164 171 181 178 177 178 176 167 184 179 189
[15:04:20.025] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:20.025] <TB0> INFO: Decoding statistics:
[15:04:20.025] <TB0> INFO: General information:
[15:04:20.025] <TB0> INFO: 16bit words read: 91936
[15:04:20.025] <TB0> INFO: valid events total: 10240
[15:04:20.025] <TB0> INFO: empty events: 7736
[15:04:20.025] <TB0> INFO: valid events with pixels: 2504
[15:04:20.025] <TB0> INFO: valid pixel hits: 2504
[15:04:20.025] <TB0> INFO: Event errors: 0
[15:04:20.025] <TB0> INFO: start marker: 0
[15:04:20.025] <TB0> INFO: stop marker: 0
[15:04:20.025] <TB0> INFO: overflow: 0
[15:04:20.025] <TB0> INFO: invalid 5bit words: 0
[15:04:20.025] <TB0> INFO: invalid XOR eye diagram: 0
[15:04:20.025] <TB0> INFO: TBM errors: 0
[15:04:20.025] <TB0> INFO: flawed TBM headers: 0
[15:04:20.025] <TB0> INFO: flawed TBM trailers: 0
[15:04:20.025] <TB0> INFO: event ID mismatches: 0
[15:04:20.025] <TB0> INFO: ROC errors: 0
[15:04:20.025] <TB0> INFO: missing ROC header(s): 0
[15:04:20.025] <TB0> INFO: misplaced readback start: 0
[15:04:20.025] <TB0> INFO: Pixel decoding errors: 0
[15:04:20.025] <TB0> INFO: pixel data incomplete: 0
[15:04:20.025] <TB0> INFO: pixel address: 0
[15:04:20.025] <TB0> INFO: pulse height fill bit: 0
[15:04:20.025] <TB0> INFO: buffer corruption: 0
[15:04:20.185] <TB0> INFO: ######################################################################
[15:04:20.185] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[15:04:20.185] <TB0> INFO: ######################################################################
[15:04:20.194] <TB0> INFO: scanning low vcal = 10
[15:04:20.490] <TB0> INFO: Expecting 41600 events.
[15:04:24.220] <TB0> INFO: 41600 events read in total (3014ms).
[15:04:24.221] <TB0> INFO: Test took 4027ms.
[15:04:24.222] <TB0> INFO: scanning low vcal = 20
[15:04:24.635] <TB0> INFO: Expecting 41600 events.
[15:04:28.364] <TB0> INFO: 41600 events read in total (3014ms).
[15:04:28.364] <TB0> INFO: Test took 4142ms.
[15:04:28.365] <TB0> INFO: scanning low vcal = 30
[15:04:28.778] <TB0> INFO: Expecting 41600 events.
[15:04:32.536] <TB0> INFO: 41600 events read in total (3042ms).
[15:04:32.537] <TB0> INFO: Test took 4172ms.
[15:04:32.538] <TB0> INFO: scanning low vcal = 40
[15:04:32.945] <TB0> INFO: Expecting 41600 events.
[15:04:37.142] <TB0> INFO: 41600 events read in total (3481ms).
[15:04:37.143] <TB0> INFO: Test took 4604ms.
[15:04:37.145] <TB0> INFO: scanning low vcal = 50
[15:04:37.504] <TB0> INFO: Expecting 41600 events.
[15:04:41.712] <TB0> INFO: 41600 events read in total (3493ms).
[15:04:41.712] <TB0> INFO: Test took 4567ms.
[15:04:41.715] <TB0> INFO: scanning low vcal = 60
[15:04:42.073] <TB0> INFO: Expecting 41600 events.
[15:04:46.285] <TB0> INFO: 41600 events read in total (3496ms).
[15:04:46.286] <TB0> INFO: Test took 4571ms.
[15:04:46.289] <TB0> INFO: scanning low vcal = 70
[15:04:46.649] <TB0> INFO: Expecting 41600 events.
[15:04:50.866] <TB0> INFO: 41600 events read in total (3502ms).
[15:04:50.867] <TB0> INFO: Test took 4578ms.
[15:04:50.869] <TB0> INFO: scanning low vcal = 80
[15:04:51.229] <TB0> INFO: Expecting 41600 events.
[15:04:55.443] <TB0> INFO: 41600 events read in total (3498ms).
[15:04:55.444] <TB0> INFO: Test took 4575ms.
[15:04:55.446] <TB0> INFO: scanning low vcal = 90
[15:04:55.805] <TB0> INFO: Expecting 41600 events.
[15:05:00.017] <TB0> INFO: 41600 events read in total (3496ms).
[15:05:00.017] <TB0> INFO: Test took 4571ms.
[15:05:00.020] <TB0> INFO: scanning low vcal = 100
[15:05:00.378] <TB0> INFO: Expecting 41600 events.
[15:05:04.701] <TB0> INFO: 41600 events read in total (3608ms).
[15:05:04.702] <TB0> INFO: Test took 4682ms.
[15:05:04.704] <TB0> INFO: scanning low vcal = 110
[15:05:05.064] <TB0> INFO: Expecting 41600 events.
[15:05:09.268] <TB0> INFO: 41600 events read in total (3488ms).
[15:05:09.269] <TB0> INFO: Test took 4565ms.
[15:05:09.271] <TB0> INFO: scanning low vcal = 120
[15:05:09.630] <TB0> INFO: Expecting 41600 events.
[15:05:13.830] <TB0> INFO: 41600 events read in total (3484ms).
[15:05:13.831] <TB0> INFO: Test took 4560ms.
[15:05:13.833] <TB0> INFO: scanning low vcal = 130
[15:05:14.191] <TB0> INFO: Expecting 41600 events.
[15:05:18.391] <TB0> INFO: 41600 events read in total (3484ms).
[15:05:18.391] <TB0> INFO: Test took 4558ms.
[15:05:18.394] <TB0> INFO: scanning low vcal = 140
[15:05:18.752] <TB0> INFO: Expecting 41600 events.
[15:05:22.960] <TB0> INFO: 41600 events read in total (3492ms).
[15:05:22.960] <TB0> INFO: Test took 4566ms.
[15:05:22.963] <TB0> INFO: scanning low vcal = 150
[15:05:23.322] <TB0> INFO: Expecting 41600 events.
[15:05:27.535] <TB0> INFO: 41600 events read in total (3498ms).
[15:05:27.536] <TB0> INFO: Test took 4573ms.
[15:05:27.539] <TB0> INFO: scanning low vcal = 160
[15:05:27.898] <TB0> INFO: Expecting 41600 events.
[15:05:32.113] <TB0> INFO: 41600 events read in total (3499ms).
[15:05:32.113] <TB0> INFO: Test took 4574ms.
[15:05:32.116] <TB0> INFO: scanning low vcal = 170
[15:05:32.475] <TB0> INFO: Expecting 41600 events.
[15:05:36.681] <TB0> INFO: 41600 events read in total (3491ms).
[15:05:36.682] <TB0> INFO: Test took 4566ms.
[15:05:36.685] <TB0> INFO: scanning low vcal = 180
[15:05:37.044] <TB0> INFO: Expecting 41600 events.
[15:05:41.243] <TB0> INFO: 41600 events read in total (3483ms).
[15:05:41.243] <TB0> INFO: Test took 4558ms.
[15:05:41.246] <TB0> INFO: scanning low vcal = 190
[15:05:41.605] <TB0> INFO: Expecting 41600 events.
[15:05:45.818] <TB0> INFO: 41600 events read in total (3497ms).
[15:05:45.819] <TB0> INFO: Test took 4573ms.
[15:05:45.821] <TB0> INFO: scanning low vcal = 200
[15:05:46.182] <TB0> INFO: Expecting 41600 events.
[15:05:50.385] <TB0> INFO: 41600 events read in total (3488ms).
[15:05:50.386] <TB0> INFO: Test took 4565ms.
[15:05:50.388] <TB0> INFO: scanning low vcal = 210
[15:05:50.748] <TB0> INFO: Expecting 41600 events.
[15:05:54.945] <TB0> INFO: 41600 events read in total (3482ms).
[15:05:54.946] <TB0> INFO: Test took 4558ms.
[15:05:54.948] <TB0> INFO: scanning low vcal = 220
[15:05:55.308] <TB0> INFO: Expecting 41600 events.
[15:05:59.508] <TB0> INFO: 41600 events read in total (3485ms).
[15:05:59.508] <TB0> INFO: Test took 4560ms.
[15:05:59.511] <TB0> INFO: scanning low vcal = 230
[15:05:59.869] <TB0> INFO: Expecting 41600 events.
[15:06:04.080] <TB0> INFO: 41600 events read in total (3495ms).
[15:06:04.080] <TB0> INFO: Test took 4569ms.
[15:06:04.083] <TB0> INFO: scanning low vcal = 240
[15:06:04.441] <TB0> INFO: Expecting 41600 events.
[15:06:08.655] <TB0> INFO: 41600 events read in total (3498ms).
[15:06:08.656] <TB0> INFO: Test took 4573ms.
[15:06:08.659] <TB0> INFO: scanning low vcal = 250
[15:06:09.018] <TB0> INFO: Expecting 41600 events.
[15:06:13.234] <TB0> INFO: 41600 events read in total (3500ms).
[15:06:13.234] <TB0> INFO: Test took 4575ms.
[15:06:13.238] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[15:06:13.596] <TB0> INFO: Expecting 41600 events.
[15:06:17.813] <TB0> INFO: 41600 events read in total (3501ms).
[15:06:17.813] <TB0> INFO: Test took 4575ms.
[15:06:17.816] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[15:06:18.175] <TB0> INFO: Expecting 41600 events.
[15:06:22.378] <TB0> INFO: 41600 events read in total (3487ms).
[15:06:22.379] <TB0> INFO: Test took 4563ms.
[15:06:22.381] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[15:06:22.739] <TB0> INFO: Expecting 41600 events.
[15:06:26.951] <TB0> INFO: 41600 events read in total (3496ms).
[15:06:26.951] <TB0> INFO: Test took 4570ms.
[15:06:26.954] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[15:06:27.313] <TB0> INFO: Expecting 41600 events.
[15:06:31.539] <TB0> INFO: 41600 events read in total (3510ms).
[15:06:31.540] <TB0> INFO: Test took 4586ms.
[15:06:31.543] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[15:06:31.901] <TB0> INFO: Expecting 41600 events.
[15:06:36.121] <TB0> INFO: 41600 events read in total (3505ms).
[15:06:36.121] <TB0> INFO: Test took 4578ms.
[15:06:36.531] <TB0> INFO: PixTestGainPedestal::measure() done
[15:07:08.061] <TB0> INFO: PixTestGainPedestal::fit() done
[15:07:08.061] <TB0> INFO: non-linearity mean: 0.954 0.952 0.950 0.954 0.953 0.961 0.952 0.959 0.957 0.957 0.959 0.960 0.961 0.955 0.966 0.951
[15:07:08.061] <TB0> INFO: non-linearity RMS: 0.006 0.008 0.008 0.006 0.006 0.006 0.007 0.006 0.007 0.007 0.009 0.007 0.006 0.008 0.006 0.008
[15:07:08.061] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[15:07:08.079] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[15:07:08.097] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[15:07:08.115] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[15:07:08.133] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[15:07:08.151] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[15:07:08.169] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[15:07:08.186] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[15:07:08.204] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[15:07:08.222] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[15:07:08.240] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[15:07:08.258] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[15:07:08.275] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[15:07:08.293] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[15:07:08.311] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[15:07:08.329] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[15:07:08.347] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 168 seconds
[15:07:08.347] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:08.347] <TB0> INFO: Decoding statistics:
[15:07:08.347] <TB0> INFO: General information:
[15:07:08.347] <TB0> INFO: 16bit words read: 2662400
[15:07:08.347] <TB0> INFO: valid events total: 166400
[15:07:08.347] <TB0> INFO: empty events: 0
[15:07:08.347] <TB0> INFO: valid events with pixels: 166400
[15:07:08.347] <TB0> INFO: valid pixel hits: 665526
[15:07:08.347] <TB0> INFO: Event errors: 0
[15:07:08.347] <TB0> INFO: start marker: 0
[15:07:08.347] <TB0> INFO: stop marker: 0
[15:07:08.347] <TB0> INFO: overflow: 0
[15:07:08.347] <TB0> INFO: invalid 5bit words: 0
[15:07:08.347] <TB0> INFO: invalid XOR eye diagram: 0
[15:07:08.347] <TB0> INFO: TBM errors: 0
[15:07:08.347] <TB0> INFO: flawed TBM headers: 0
[15:07:08.347] <TB0> INFO: flawed TBM trailers: 0
[15:07:08.347] <TB0> INFO: event ID mismatches: 0
[15:07:08.347] <TB0> INFO: ROC errors: 0
[15:07:08.347] <TB0> INFO: missing ROC header(s): 0
[15:07:08.347] <TB0> INFO: misplaced readback start: 0
[15:07:08.347] <TB0> INFO: Pixel decoding errors: 0
[15:07:08.347] <TB0> INFO: pixel data incomplete: 0
[15:07:08.347] <TB0> INFO: pixel address: 0
[15:07:08.347] <TB0> INFO: pulse height fill bit: 0
[15:07:08.347] <TB0> INFO: buffer corruption: 0
[15:07:08.353] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C15.dat
[15:07:08.354] <TB0> INFO: ######################################################################
[15:07:08.354] <TB0> INFO: PixTestReadback::doTest()
[15:07:08.354] <TB0> INFO: ######################################################################
[15:07:08.355] <TB0> INFO: PixTestReadback::RES sent once
[15:07:19.535] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C0.dat
[15:07:19.535] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C1.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C2.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C3.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C4.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C5.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C6.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C7.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C8.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C9.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C10.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C11.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C12.dat
[15:07:19.536] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C13.dat
[15:07:19.537] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C14.dat
[15:07:19.537] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C15.dat
[15:07:19.565] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[15:07:19.565] <TB0> INFO: PixTestReadback::RES sent once
[15:07:30.715] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C0.dat
[15:07:30.715] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C1.dat
[15:07:30.715] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C2.dat
[15:07:30.715] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C3.dat
[15:07:30.715] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C4.dat
[15:07:30.715] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C5.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C6.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C7.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C8.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C9.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C10.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C11.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C12.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C13.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C14.dat
[15:07:30.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C15.dat
[15:07:30.746] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[15:07:30.747] <TB0> INFO: PixTestReadback::RES sent once
[15:07:39.334] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[15:07:39.334] <TB0> INFO: Vbg will be calibrated using Vd calibration
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.6calibrated Vbg = 1.23122 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.9calibrated Vbg = 1.23504 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.8calibrated Vbg = 1.2387 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.5calibrated Vbg = 1.24495 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.7calibrated Vbg = 1.25249 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.1calibrated Vbg = 1.25384 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.4calibrated Vbg = 1.24351 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155.8calibrated Vbg = 1.25331 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.7calibrated Vbg = 1.25314 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 148.8calibrated Vbg = 1.25448 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 161calibrated Vbg = 1.25477 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 155.1calibrated Vbg = 1.25263 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157.2calibrated Vbg = 1.2451 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 162.2calibrated Vbg = 1.24064 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 143calibrated Vbg = 1.24185 :::*/*/*/*/
[15:07:39.334] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.1calibrated Vbg = 1.24328 :::*/*/*/*/
[15:07:39.336] <TB0> INFO: PixTestReadback::RES sent once
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C0.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C1.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C2.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C3.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C4.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C5.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C6.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C7.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C8.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C9.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C10.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C11.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C12.dat
[15:10:33.454] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C13.dat
[15:10:33.455] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C14.dat
[15:10:33.455] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2130_FullQualification_2016-01-13_08h36m_1452670570//004_FulltestPxar_p17//readbackCal_C15.dat
[15:10:33.482] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[15:10:33.482] <TB0> INFO: PixTestReadback::doTest() done
[15:10:33.482] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:33.482] <TB0> INFO: Decoding statistics:
[15:10:33.482] <TB0> INFO: General information:
[15:10:33.482] <TB0> INFO: 16bit words read: 1024
[15:10:33.482] <TB0> INFO: valid events total: 128
[15:10:33.482] <TB0> INFO: empty events: 128
[15:10:33.482] <TB0> INFO: valid events with pixels: 0
[15:10:33.482] <TB0> INFO: valid pixel hits: 0
[15:10:33.482] <TB0> INFO: Event errors: 0
[15:10:33.482] <TB0> INFO: start marker: 0
[15:10:33.483] <TB0> INFO: stop marker: 0
[15:10:33.483] <TB0> INFO: overflow: 0
[15:10:33.483] <TB0> INFO: invalid 5bit words: 0
[15:10:33.483] <TB0> INFO: invalid XOR eye diagram: 0
[15:10:33.483] <TB0> INFO: TBM errors: 0
[15:10:33.483] <TB0> INFO: flawed TBM headers: 0
[15:10:33.483] <TB0> INFO: flawed TBM trailers: 0
[15:10:33.483] <TB0> INFO: event ID mismatches: 0
[15:10:33.483] <TB0> INFO: ROC errors: 0
[15:10:33.483] <TB0> INFO: missing ROC header(s): 0
[15:10:33.483] <TB0> INFO: misplaced readback start: 0
[15:10:33.483] <TB0> INFO: Pixel decoding errors: 0
[15:10:33.483] <TB0> INFO: pixel data incomplete: 0
[15:10:33.483] <TB0> INFO: pixel address: 0
[15:10:33.483] <TB0> INFO: pulse height fill bit: 0
[15:10:33.483] <TB0> INFO: buffer corruption: 0
[15:10:33.497] <TB0> INFO: Decoding statistics:
[15:10:33.498] <TB0> INFO: General information:
[15:10:33.498] <TB0> INFO: 16bit words read: 2755360
[15:10:33.498] <TB0> INFO: valid events total: 176768
[15:10:33.498] <TB0> INFO: empty events: 7864
[15:10:33.498] <TB0> INFO: valid events with pixels: 168904
[15:10:33.498] <TB0> INFO: valid pixel hits: 668030
[15:10:33.498] <TB0> INFO: Event errors: 0
[15:10:33.498] <TB0> INFO: start marker: 0
[15:10:33.498] <TB0> INFO: stop marker: 0
[15:10:33.498] <TB0> INFO: overflow: 0
[15:10:33.498] <TB0> INFO: invalid 5bit words: 0
[15:10:33.498] <TB0> INFO: invalid XOR eye diagram: 0
[15:10:33.498] <TB0> INFO: TBM errors: 0
[15:10:33.498] <TB0> INFO: flawed TBM headers: 0
[15:10:33.498] <TB0> INFO: flawed TBM trailers: 0
[15:10:33.498] <TB0> INFO: event ID mismatches: 0
[15:10:33.498] <TB0> INFO: ROC errors: 0
[15:10:33.498] <TB0> INFO: missing ROC header(s): 0
[15:10:33.498] <TB0> INFO: misplaced readback start: 0
[15:10:33.498] <TB0> INFO: Pixel decoding errors: 0
[15:10:33.498] <TB0> INFO: pixel data incomplete: 0
[15:10:33.498] <TB0> INFO: pixel address: 0
[15:10:33.498] <TB0> INFO: pulse height fill bit: 0
[15:10:33.498] <TB0> INFO: buffer corruption: 0
[15:10:33.498] <TB0> INFO: enter test to run
[15:10:33.498] <TB0> INFO: test: exit no parameter change
[15:10:33.726] <TB0> QUIET: Connection to board 73 closed.
[15:10:33.806] <TB0> INFO: pXar: this is the end, my friend