[15:08:26.170] <TB1> INFO: *** Welcome to pxar ***
[15:08:26.170] <TB1> INFO: *** Today: 2015/09/10
[15:08:26.170] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C15.dat
[15:08:26.171] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:08:26.171] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//defaultMaskFile.dat
[15:08:26.171] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters_C15.dat
[15:08:26.238] <TB1> INFO: clk: 4
[15:08:26.238] <TB1> INFO: ctr: 4
[15:08:26.238] <TB1> INFO: sda: 19
[15:08:26.238] <TB1> INFO: tin: 9
[15:08:26.238] <TB1> INFO: level: 15
[15:08:26.238] <TB1> INFO: triggerdelay: 0
[15:08:26.238] <TB1> QUIET: Instanciating API for pxar prod-10+24~g09f6d2c
[15:08:26.238] <TB1> INFO: Log level: INFO
[15:08:26.246] <TB1> INFO: Found DTB DTB_WXBYFL
[15:08:26.255] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[15:08:26.258] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[15:08:26.261] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[15:08:27.809] <TB1> INFO: DUT info:
[15:08:27.809] <TB1> INFO: The DUT currently contains the following objects:
[15:08:27.809] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:08:27.809] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:08:27.809] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:08:27.809] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:08:27.809] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.809] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.810] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.810] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.810] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:27.810] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:28.211] <TB1> INFO: enter 'restricted' command line mode
[15:08:28.211] <TB1> INFO: enter test to run
[15:08:28.211] <TB1> INFO: test: pretest no parameter change
[15:08:28.211] <TB1> INFO: running: pretest
[15:08:28.219] <TB1> INFO: ######################################################################
[15:08:28.219] <TB1> INFO: PixTestPretest::doTest()
[15:08:28.219] <TB1> INFO: ######################################################################
[15:08:28.221] <TB1> INFO: ----------------------------------------------------------------------
[15:08:28.221] <TB1> INFO: PixTestPretest::programROC()
[15:08:28.221] <TB1> INFO: ----------------------------------------------------------------------
[15:08:46.244] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:08:46.244] <TB1> INFO: IA differences per ROC: 17.7 17.7 20.1 16.9 18.5 19.3 19.3 18.5 16.9 16.9 17.7 19.3 16.9 17.7 16.1 16.1
[15:08:46.337] <TB1> INFO: ----------------------------------------------------------------------
[15:08:46.337] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:08:46.337] <TB1> INFO: ----------------------------------------------------------------------
[15:09:05.929] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 389.1 mA = 24.3188 mA/ROC
[15:09:05.931] <TB1> INFO: ----------------------------------------------------------------------
[15:09:05.931] <TB1> INFO: PixTestPretest::findTiming()
[15:09:05.931] <TB1> INFO: ----------------------------------------------------------------------
[15:09:05.931] <TB1> INFO: PixTestCmd::init()

[15:12:08.545] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[15:12:08.545] <TB1> INFO: (success/tries = 100/100), width = 3
[15:12:08.546] <TB1> INFO: ----------------------------------------------------------------------
[15:12:08.546] <TB1> INFO: PixTestPretest::findWorkingPixel()
[15:12:08.546] <TB1> INFO: ----------------------------------------------------------------------
[15:12:08.691] <TB1> INFO: Expecting 231680 events.
[15:12:17.234] <TB1> INFO: 231680 events read in total (7827ms).
[15:12:17.238] <TB1> INFO: Test took 8689ms.
[15:12:17.548] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:12:17.596] <TB1> INFO: ----------------------------------------------------------------------
[15:12:17.596] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[15:12:17.596] <TB1> INFO: ----------------------------------------------------------------------
[15:12:17.734] <TB1> INFO: Expecting 231680 events.
[15:12:26.384] <TB1> INFO: 231680 events read in total (7934ms).
[15:12:26.388] <TB1> INFO: Test took 8787ms.
[15:12:26.742] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[15:12:26.742] <TB1> INFO: CalDel: 135 136 148 119 123 154 150 146 140 113 125 143 131 128 131 127
[15:12:26.742] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:12:26.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C0.dat
[15:12:26.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C1.dat
[15:12:26.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C2.dat
[15:12:26.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C3.dat
[15:12:26.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C4.dat
[15:12:26.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C5.dat
[15:12:26.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C6.dat
[15:12:26.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C7.dat
[15:12:26.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C8.dat
[15:12:26.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C9.dat
[15:12:26.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C10.dat
[15:12:26.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C11.dat
[15:12:26.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C12.dat
[15:12:26.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C13.dat
[15:12:26.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C14.dat
[15:12:26.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C15.dat
[15:12:26.747] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//tbmParameters_C0a.dat
[15:12:26.747] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:12:26.747] <TB1> INFO: PixTestPretest::doTest() done, duration: 238 seconds
[15:12:26.829] <TB1> INFO: enter test to run
[15:12:26.829] <TB1> INFO: test: fulltest no parameter change
[15:12:26.829] <TB1> INFO: running: fulltest
[15:12:26.829] <TB1> INFO: ######################################################################
[15:12:26.829] <TB1> INFO: PixTestFullTest::doTest()
[15:12:26.829] <TB1> INFO: ######################################################################
[15:12:26.831] <TB1> INFO: ######################################################################
[15:12:26.831] <TB1> INFO: PixTestAlive::doTest()
[15:12:26.831] <TB1> INFO: ######################################################################
[15:12:26.833] <TB1> INFO: ----------------------------------------------------------------------
[15:12:26.833] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:12:26.833] <TB1> INFO: ----------------------------------------------------------------------
[15:12:27.175] <TB1> INFO: Expecting 41600 events.
[15:12:31.657] <TB1> INFO: 41600 events read in total (3766ms).
[15:12:31.658] <TB1> INFO: Test took 4823ms.
[15:12:31.665] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:31.965] <TB1> INFO: PixTestAlive::aliveTest() done
[15:12:31.965] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:12:31.968] <TB1> INFO: ----------------------------------------------------------------------
[15:12:31.968] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:12:31.968] <TB1> INFO: ----------------------------------------------------------------------
[15:12:32.305] <TB1> INFO: Expecting 41600 events.
[15:12:35.532] <TB1> INFO: 41600 events read in total (2511ms).
[15:12:35.532] <TB1> INFO: Test took 3562ms.
[15:12:35.533] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:35.533] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:12:35.860] <TB1> INFO: PixTestAlive::maskTest() done
[15:12:35.860] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:12:35.863] <TB1> INFO: ----------------------------------------------------------------------
[15:12:35.863] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:12:35.863] <TB1> INFO: ----------------------------------------------------------------------
[15:12:36.177] <TB1> INFO: Expecting 41600 events.
[15:12:40.596] <TB1> INFO: 41600 events read in total (3703ms).
[15:12:40.596] <TB1> INFO: Test took 4731ms.
[15:12:40.602] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:40.905] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:12:40.905] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:12:40.905] <TB1> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:12:40.905] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:40.905] <TB1> INFO: Decoding statistics:
[15:12:40.905] <TB1> INFO: General information:
[15:12:40.905] <TB1> INFO: 16bit words read: 0
[15:12:40.905] <TB1> INFO: valid events total: 0
[15:12:40.905] <TB1> INFO: empty events: 0
[15:12:40.905] <TB1> INFO: valid events with pixels: 0
[15:12:40.905] <TB1> INFO: valid pixel hits: 0
[15:12:40.905] <TB1> INFO: Event errors: 0
[15:12:40.905] <TB1> INFO: start marker: 0
[15:12:40.905] <TB1> INFO: stop marker: 0
[15:12:40.905] <TB1> INFO: overflow: 0
[15:12:40.905] <TB1> INFO: invalid 5bit words: 0
[15:12:40.905] <TB1> INFO: invalid XOR eye diagram: 0
[15:12:40.905] <TB1> INFO: TBM errors: 0
[15:12:40.905] <TB1> INFO: flawed TBM headers: 0
[15:12:40.905] <TB1> INFO: flawed TBM trailers: 0
[15:12:40.905] <TB1> INFO: event ID mismatches: 0
[15:12:40.905] <TB1> INFO: ROC errors: 0
[15:12:40.905] <TB1> INFO: missing ROC header(s): 0
[15:12:40.905] <TB1> INFO: misplaced readback start: 0
[15:12:40.905] <TB1> INFO: Pixel decoding errors: 0
[15:12:40.906] <TB1> INFO: pixel data incomplete: 0
[15:12:40.906] <TB1> INFO: pixel address: 0
[15:12:40.906] <TB1> INFO: pulse height fill bit: 0
[15:12:40.906] <TB1> INFO: buffer corruption: 0
[15:12:40.917] <TB1> INFO: ######################################################################
[15:12:40.917] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:12:40.917] <TB1> INFO: ######################################################################
[15:12:40.922] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:12:40.934] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:12:40.934] <TB1> INFO: run 1 of 1
[15:12:41.239] <TB1> INFO: Expecting 3120000 events.
[15:13:25.456] <TB1> INFO: 862505 events read in total (43501ms).
[15:14:07.328] <TB1> INFO: 1710370 events read in total (85373ms).
[15:14:46.476] <TB1> INFO: 2568515 events read in total (124522ms).
[15:15:11.901] <TB1> INFO: 3120000 events read in total (149946ms).
[15:15:11.950] <TB1> INFO: Test took 151016ms.
[15:15:12.049] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:39.414] <TB1> INFO: PixTestBBMap::doTest() done, duration: 178 seconds
[15:15:39.414] <TB1> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 0 0 0 0 0 1 0 0 4 7
[15:15:39.414] <TB1> INFO: separation cut (per ROC): 98 100 100 90 92 85 86 92 96 65 98 92 81 92 87 91
[15:15:39.414] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:39.414] <TB1> INFO: Decoding statistics:
[15:15:39.414] <TB1> INFO: General information:
[15:15:39.414] <TB1> INFO: 16bit words read: 0
[15:15:39.414] <TB1> INFO: valid events total: 0
[15:15:39.414] <TB1> INFO: empty events: 0
[15:15:39.414] <TB1> INFO: valid events with pixels: 0
[15:15:39.414] <TB1> INFO: valid pixel hits: 0
[15:15:39.414] <TB1> INFO: Event errors: 0
[15:15:39.414] <TB1> INFO: start marker: 0
[15:15:39.414] <TB1> INFO: stop marker: 0
[15:15:39.414] <TB1> INFO: overflow: 0
[15:15:39.414] <TB1> INFO: invalid 5bit words: 0
[15:15:39.414] <TB1> INFO: invalid XOR eye diagram: 0
[15:15:39.414] <TB1> INFO: TBM errors: 0
[15:15:39.414] <TB1> INFO: flawed TBM headers: 0
[15:15:39.414] <TB1> INFO: flawed TBM trailers: 0
[15:15:39.414] <TB1> INFO: event ID mismatches: 0
[15:15:39.414] <TB1> INFO: ROC errors: 0
[15:15:39.414] <TB1> INFO: missing ROC header(s): 0
[15:15:39.414] <TB1> INFO: misplaced readback start: 0
[15:15:39.414] <TB1> INFO: Pixel decoding errors: 0
[15:15:39.414] <TB1> INFO: pixel data incomplete: 0
[15:15:39.414] <TB1> INFO: pixel address: 0
[15:15:39.414] <TB1> INFO: pulse height fill bit: 0
[15:15:39.414] <TB1> INFO: buffer corruption: 0
[15:15:39.500] <TB1> INFO: ######################################################################
[15:15:39.501] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:15:39.501] <TB1> INFO: ######################################################################
[15:15:39.501] <TB1> INFO: ----------------------------------------------------------------------
[15:15:39.501] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:15:39.501] <TB1> INFO: ----------------------------------------------------------------------
[15:15:39.501] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:15:39.510] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[15:15:39.510] <TB1> INFO: run 1 of 1
[15:15:39.845] <TB1> INFO: Expecting 26208000 events.
[15:16:11.981] <TB1> INFO: 938000 events read in total (31419ms).
[15:16:43.024] <TB1> INFO: 1861950 events read in total (62462ms).
[15:17:13.898] <TB1> INFO: 2780900 events read in total (93336ms).
[15:17:43.691] <TB1> INFO: 3698950 events read in total (123129ms).
[15:18:14.073] <TB1> INFO: 4617550 events read in total (153511ms).
[15:18:45.111] <TB1> INFO: 5533800 events read in total (184549ms).
[15:19:16.431] <TB1> INFO: 6450550 events read in total (215869ms).
[15:19:48.117] <TB1> INFO: 7366750 events read in total (247555ms).
[15:20:19.336] <TB1> INFO: 8281200 events read in total (278774ms).
[15:20:51.237] <TB1> INFO: 9198400 events read in total (310675ms).
[15:21:22.699] <TB1> INFO: 10113850 events read in total (342137ms).
[15:21:54.177] <TB1> INFO: 11023500 events read in total (373615ms).
[15:22:26.051] <TB1> INFO: 11934500 events read in total (405489ms).
[15:22:57.460] <TB1> INFO: 12844450 events read in total (436898ms).
[15:23:29.034] <TB1> INFO: 13746000 events read in total (468472ms).
[15:24:00.296] <TB1> INFO: 14645150 events read in total (499734ms).
[15:24:31.645] <TB1> INFO: 15542050 events read in total (531083ms).
[15:25:02.498] <TB1> INFO: 16437450 events read in total (561936ms).
[15:25:33.601] <TB1> INFO: 17335700 events read in total (593039ms).
[15:26:04.665] <TB1> INFO: 18232900 events read in total (624103ms).
[15:26:35.580] <TB1> INFO: 19127600 events read in total (655018ms).
[15:27:06.434] <TB1> INFO: 20020650 events read in total (685872ms).
[15:27:37.265] <TB1> INFO: 20915050 events read in total (716703ms).
[15:28:09.187] <TB1> INFO: 21810250 events read in total (748625ms).
[15:28:40.038] <TB1> INFO: 22703900 events read in total (779476ms).
[15:29:10.663] <TB1> INFO: 23598800 events read in total (810101ms).
[15:29:41.960] <TB1> INFO: 24497600 events read in total (841398ms).
[15:30:12.393] <TB1> INFO: 25397250 events read in total (871831ms).
[15:30:38.932] <TB1> INFO: 26208000 events read in total (898370ms).
[15:30:38.967] <TB1> INFO: Test took 899457ms.
[15:30:39.060] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:39.314] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:40.736] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:42.187] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:43.858] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:45.304] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:46.704] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:48.288] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:50.037] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:51.477] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:52.976] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:54.463] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:55.844] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:57.237] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:30:58.875] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:31:00.304] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:31:02.212] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:31:03.964] <TB1> INFO: PixTestScurves::scurves() done
[15:31:03.964] <TB1> INFO: Vcal mean: 95.17 99.87 99.33 95.35 99.75 88.00 89.67 98.17 100.66 81.38 100.94 98.66 86.29 85.98 99.79 101.02
[15:31:03.964] <TB1> INFO: Vcal RMS: 5.76 5.83 5.35 5.71 5.43 5.30 5.77 5.33 5.47 4.07 5.67 5.46 5.40 5.64 5.16 5.54
[15:31:03.964] <TB1> INFO: PixTestScurves::fullTest() done, duration: 924 seconds
[15:31:03.964] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:03.964] <TB1> INFO: Decoding statistics:
[15:31:03.964] <TB1> INFO: General information:
[15:31:03.964] <TB1> INFO: 16bit words read: 0
[15:31:03.964] <TB1> INFO: valid events total: 0
[15:31:03.964] <TB1> INFO: empty events: 0
[15:31:03.964] <TB1> INFO: valid events with pixels: 0
[15:31:03.964] <TB1> INFO: valid pixel hits: 0
[15:31:03.965] <TB1> INFO: Event errors: 0
[15:31:03.965] <TB1> INFO: start marker: 0
[15:31:03.965] <TB1> INFO: stop marker: 0
[15:31:03.965] <TB1> INFO: overflow: 0
[15:31:03.965] <TB1> INFO: invalid 5bit words: 0
[15:31:03.965] <TB1> INFO: invalid XOR eye diagram: 0
[15:31:03.965] <TB1> INFO: TBM errors: 0
[15:31:03.965] <TB1> INFO: flawed TBM headers: 0
[15:31:03.965] <TB1> INFO: flawed TBM trailers: 0
[15:31:03.965] <TB1> INFO: event ID mismatches: 0
[15:31:03.965] <TB1> INFO: ROC errors: 0
[15:31:03.965] <TB1> INFO: missing ROC header(s): 0
[15:31:03.965] <TB1> INFO: misplaced readback start: 0
[15:31:03.965] <TB1> INFO: Pixel decoding errors: 0
[15:31:03.965] <TB1> INFO: pixel data incomplete: 0
[15:31:03.965] <TB1> INFO: pixel address: 0
[15:31:03.965] <TB1> INFO: pulse height fill bit: 0
[15:31:03.965] <TB1> INFO: buffer corruption: 0
[15:31:04.045] <TB1> INFO: ######################################################################
[15:31:04.045] <TB1> INFO: PixTestTrim::doTest()
[15:31:04.045] <TB1> INFO: ######################################################################
[15:31:04.047] <TB1> INFO: ----------------------------------------------------------------------
[15:31:04.047] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:31:04.047] <TB1> INFO: ----------------------------------------------------------------------
[15:31:04.139] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:31:04.139] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:31:04.148] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:31:04.148] <TB1> INFO: run 1 of 1
[15:31:04.472] <TB1> INFO: Expecting 6281600 events.
[15:31:42.938] <TB1> INFO: 1088070 events read in total (37749ms).
[15:32:21.160] <TB1> INFO: 2170230 events read in total (75971ms).
[15:32:59.541] <TB1> INFO: 3245100 events read in total (114352ms).
[15:33:37.688] <TB1> INFO: 4325820 events read in total (152499ms).
[15:34:15.478] <TB1> INFO: 5409660 events read in total (190289ms).
[15:34:45.808] <TB1> INFO: 6281600 events read in total (220619ms).
[15:34:45.838] <TB1> INFO: Test took 221690ms.
[15:34:45.886] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:05.912] <TB1> INFO: ROC 0 VthrComp = 94
[15:35:05.912] <TB1> INFO: ROC 1 VthrComp = 102
[15:35:05.912] <TB1> INFO: ROC 2 VthrComp = 102
[15:35:05.913] <TB1> INFO: ROC 3 VthrComp = 94
[15:35:05.914] <TB1> INFO: ROC 4 VthrComp = 101
[15:35:05.914] <TB1> INFO: ROC 5 VthrComp = 87
[15:35:05.914] <TB1> INFO: ROC 6 VthrComp = 89
[15:35:05.914] <TB1> INFO: ROC 7 VthrComp = 98
[15:35:05.914] <TB1> INFO: ROC 8 VthrComp = 101
[15:35:05.915] <TB1> INFO: ROC 9 VthrComp = 83
[15:35:05.915] <TB1> INFO: ROC 10 VthrComp = 102
[15:35:05.915] <TB1> INFO: ROC 11 VthrComp = 100
[15:35:05.915] <TB1> INFO: ROC 12 VthrComp = 87
[15:35:05.915] <TB1> INFO: ROC 13 VthrComp = 88
[15:35:05.915] <TB1> INFO: ROC 14 VthrComp = 99
[15:35:05.915] <TB1> INFO: ROC 15 VthrComp = 101
[15:35:05.915] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:35:05.915] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:35:05.927] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:35:05.927] <TB1> INFO: run 1 of 1
[15:35:06.241] <TB1> INFO: Expecting 6281600 events.
[15:35:38.219] <TB1> INFO: 755490 events read in total (31262ms).
[15:36:11.104] <TB1> INFO: 1509330 events read in total (64147ms).
[15:36:44.668] <TB1> INFO: 2263350 events read in total (97711ms).
[15:37:18.083] <TB1> INFO: 3017690 events read in total (131126ms).
[15:37:51.298] <TB1> INFO: 3766850 events read in total (164341ms).
[15:38:24.610] <TB1> INFO: 4512320 events read in total (197653ms).
[15:38:57.919] <TB1> INFO: 5255870 events read in total (230962ms).
[15:39:28.862] <TB1> INFO: 5999330 events read in total (261905ms).
[15:39:41.759] <TB1> INFO: 6281600 events read in total (274802ms).
[15:39:41.798] <TB1> INFO: Test took 275871ms.
[15:39:41.949] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:07.741] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.8274 for pixel 3/76 mean/min/max = 46.6244/32.392/60.8568
[15:40:07.741] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.0641 for pixel 1/76 mean/min/max = 45.4221/31.6945/59.1498
[15:40:07.741] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.4173 for pixel 6/79 mean/min/max = 44.5675/31.7101/57.4249
[15:40:07.742] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 60.0456 for pixel 0/6 mean/min/max = 46.1422/32.2316/60.0529
[15:40:07.765] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.2018 for pixel 0/11 mean/min/max = 45.1344/31.9956/58.2731
[15:40:07.765] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.0254 for pixel 19/78 mean/min/max = 45.1248/33.0468/57.2027
[15:40:07.765] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 59.867 for pixel 8/79 mean/min/max = 46.7742/33.6507/59.8977
[15:40:07.765] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 59.2471 for pixel 21/15 mean/min/max = 45.8146/32.3633/59.2659
[15:40:07.766] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.8508 for pixel 1/47 mean/min/max = 44.9414/31.9322/57.9505
[15:40:07.766] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 57.1803 for pixel 6/3 mean/min/max = 45.0285/32.8044/57.2526
[15:40:07.766] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 58.4092 for pixel 0/3 mean/min/max = 45.5594/32.6767/58.4421
[15:40:07.767] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.3368 for pixel 17/79 mean/min/max = 45.0325/31.7048/58.3603
[15:40:07.767] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.4649 for pixel 8/2 mean/min/max = 45.1573/32.7318/57.5827
[15:40:07.767] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.6968 for pixel 18/78 mean/min/max = 45.8157/33.8393/57.7922
[15:40:07.767] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.8875 for pixel 2/77 mean/min/max = 44.7158/31.5209/57.9106
[15:40:07.768] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.0845 for pixel 51/47 mean/min/max = 44.6777/32.2452/57.1102
[15:40:07.768] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:40:07.900] <TB1> INFO: Expecting 514560 events.
[15:40:18.791] <TB1> INFO: 514560 events read in total (10175ms).
[15:40:18.796] <TB1> INFO: Expecting 514560 events.
[15:40:29.504] <TB1> INFO: 514560 events read in total (10128ms).
[15:40:29.513] <TB1> INFO: Expecting 514560 events.
[15:40:40.195] <TB1> INFO: 514560 events read in total (10111ms).
[15:40:40.204] <TB1> INFO: Expecting 514560 events.
[15:40:50.836] <TB1> INFO: 514560 events read in total (10056ms).
[15:40:50.846] <TB1> INFO: Expecting 514560 events.
[15:41:02.543] <TB1> INFO: 514560 events read in total (11122ms).
[15:41:02.556] <TB1> INFO: Expecting 514560 events.
[15:41:14.201] <TB1> INFO: 514560 events read in total (11072ms).
[15:41:14.219] <TB1> INFO: Expecting 514560 events.
[15:41:25.887] <TB1> INFO: 514560 events read in total (11103ms).
[15:41:25.904] <TB1> INFO: Expecting 514560 events.
[15:41:37.653] <TB1> INFO: 514560 events read in total (11180ms).
[15:41:37.670] <TB1> INFO: Expecting 514560 events.
[15:41:49.456] <TB1> INFO: 514560 events read in total (11229ms).
[15:41:49.475] <TB1> INFO: Expecting 514560 events.
[15:42:01.245] <TB1> INFO: 514560 events read in total (11216ms).
[15:42:01.267] <TB1> INFO: Expecting 514560 events.
[15:42:12.946] <TB1> INFO: 514560 events read in total (11123ms).
[15:42:12.972] <TB1> INFO: Expecting 514560 events.
[15:42:24.677] <TB1> INFO: 514560 events read in total (11153ms).
[15:42:24.709] <TB1> INFO: Expecting 514560 events.
[15:42:36.380] <TB1> INFO: 514560 events read in total (11124ms).
[15:42:36.409] <TB1> INFO: Expecting 514560 events.
[15:42:47.716] <TB1> INFO: 514560 events read in total (10761ms).
[15:42:47.752] <TB1> INFO: Expecting 514560 events.
[15:42:59.038] <TB1> INFO: 514560 events read in total (10758ms).
[15:42:59.068] <TB1> INFO: Expecting 514560 events.
[15:43:10.738] <TB1> INFO: 514560 events read in total (11118ms).
[15:43:10.771] <TB1> INFO: Test took 183003ms.
[15:43:11.768] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:43:11.777] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:43:11.777] <TB1> INFO: run 1 of 1
[15:43:12.082] <TB1> INFO: Expecting 6281600 events.
[15:43:47.555] <TB1> INFO: 742110 events read in total (34756ms).
[15:44:20.677] <TB1> INFO: 1483180 events read in total (67878ms).
[15:44:54.404] <TB1> INFO: 2224450 events read in total (101605ms).
[15:45:27.447] <TB1> INFO: 2966070 events read in total (134648ms).
[15:45:59.955] <TB1> INFO: 3702310 events read in total (167156ms).
[15:46:32.881] <TB1> INFO: 4434510 events read in total (200082ms).
[15:47:05.773] <TB1> INFO: 5164950 events read in total (232974ms).
[15:47:37.378] <TB1> INFO: 5894670 events read in total (264579ms).
[15:47:54.416] <TB1> INFO: 6281600 events read in total (281617ms).
[15:47:54.494] <TB1> INFO: Test took 282717ms.
[15:47:54.655] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:20.600] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.163001 .. 255.000000
[15:48:20.681] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:48:20.690] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:48:20.690] <TB1> INFO: run 1 of 1
[15:48:20.995] <TB1> INFO: Expecting 10649600 events.
[15:48:53.409] <TB1> INFO: 712520 events read in total (31698ms).
[15:49:24.446] <TB1> INFO: 1425270 events read in total (62735ms).
[15:49:57.497] <TB1> INFO: 2138380 events read in total (95786ms).
[15:50:30.801] <TB1> INFO: 2851010 events read in total (129090ms).
[15:51:04.099] <TB1> INFO: 3563990 events read in total (162388ms).
[15:51:36.915] <TB1> INFO: 4276850 events read in total (195204ms).
[15:52:09.786] <TB1> INFO: 4989790 events read in total (228075ms).
[15:52:42.369] <TB1> INFO: 5702370 events read in total (260658ms).
[15:53:15.347] <TB1> INFO: 6414560 events read in total (293636ms).
[15:53:47.484] <TB1> INFO: 7125970 events read in total (325773ms).
[15:54:19.788] <TB1> INFO: 7837510 events read in total (358077ms).
[15:54:52.279] <TB1> INFO: 8548240 events read in total (390568ms).
[15:55:24.979] <TB1> INFO: 9258990 events read in total (423268ms).
[15:55:55.477] <TB1> INFO: 9969770 events read in total (453766ms).
[15:56:26.374] <TB1> INFO: 10649600 events read in total (484663ms).
[15:56:26.470] <TB1> INFO: Test took 485781ms.
[15:56:26.743] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:55.577] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 17.750064 .. 44.535878
[15:56:55.656] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 54 (-1/-1) hits flags = 528 (plus default)
[15:56:55.665] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:56:55.665] <TB1> INFO: run 1 of 1
[15:56:55.995] <TB1> INFO: Expecting 1996800 events.
[15:57:34.321] <TB1> INFO: 935720 events read in total (37607ms).
[15:58:08.595] <TB1> INFO: 1868000 events read in total (71881ms).
[15:58:13.785] <TB1> INFO: 1996800 events read in total (77072ms).
[15:58:13.804] <TB1> INFO: Test took 78140ms.
[15:58:13.838] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:27.928] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 21.651689 .. 42.662819
[15:58:28.014] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 52 (-1/-1) hits flags = 528 (plus default)
[15:58:28.022] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:58:28.022] <TB1> INFO: run 1 of 1
[15:58:28.331] <TB1> INFO: Expecting 1747200 events.
[15:59:05.935] <TB1> INFO: 930030 events read in total (36888ms).
[15:59:38.459] <TB1> INFO: 1747200 events read in total (69412ms).
[15:59:38.472] <TB1> INFO: Test took 70450ms.
[15:59:38.500] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:51.283] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 5.049233 .. 42.078700
[15:59:51.386] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 5 .. 52 (-1/-1) hits flags = 528 (plus default)
[15:59:51.399] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:59:51.399] <TB1> INFO: run 1 of 1
[15:59:51.703] <TB1> INFO: Expecting 1996800 events.
[16:00:29.310] <TB1> INFO: 966800 events read in total (36891ms).
[16:01:04.917] <TB1> INFO: 1933300 events read in total (72498ms).
[16:01:08.064] <TB1> INFO: 1996800 events read in total (75645ms).
[16:01:08.081] <TB1> INFO: Test took 76683ms.
[16:01:08.114] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:21.866] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:01:21.866] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:01:21.875] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:01:21.875] <TB1> INFO: run 1 of 1
[16:01:22.199] <TB1> INFO: Expecting 1705600 events.
[16:01:59.382] <TB1> INFO: 879250 events read in total (36467ms).
[16:02:34.088] <TB1> INFO: 1705600 events read in total (71173ms).
[16:02:34.113] <TB1> INFO: Test took 72239ms.
[16:02:34.151] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:02:48.779] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:02:48.779] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:02:48.779] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:02:48.780] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:02:48.780] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:02:48.780] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:02:48.780] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:02:48.780] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:02:48.780] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:02:48.780] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:02:48.780] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:02:48.781] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:02:48.781] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:02:48.781] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:02:48.781] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:02:48.781] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:02:48.781] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:02:48.791] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:02:48.798] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:02:48.804] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:02:48.811] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:02:48.817] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:02:48.824] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:02:48.831] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:02:48.837] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:02:48.844] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:02:48.850] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:02:48.857] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:02:48.864] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:02:48.871] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:02:48.877] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:02:48.884] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:02:48.890] <TB1> INFO: PixTestTrim::trimTest() done
[16:02:48.890] <TB1> INFO: vtrim: 99 103 94 99 89 81 93 108 106 100 95 99 98 91 104 85
[16:02:48.890] <TB1> INFO: vthrcomp: 94 102 102 94 101 87 89 98 101 83 102 100 87 88 99 101
[16:02:48.890] <TB1> INFO: vcal mean: 34.97 34.99 34.98 34.96 34.98 35.01 34.98 34.93 34.98 35.00 34.99 34.92 34.95 34.96 34.95 34.92
[16:02:48.890] <TB1> INFO: vcal RMS: 0.85 0.86 0.82 0.83 0.87 0.79 0.81 0.84 0.85 0.81 0.85 0.93 0.95 0.82 0.90 0.85
[16:02:48.890] <TB1> INFO: bits mean: 8.93 9.50 9.47 9.10 9.46 9.46 8.60 9.47 9.68 9.78 9.13 9.74 9.76 9.22 9.91 9.51
[16:02:48.890] <TB1> INFO: bits RMS: 2.87 2.75 2.86 2.80 2.78 2.67 2.81 2.63 2.66 2.55 2.82 2.71 2.55 2.55 2.69 2.72
[16:02:48.900] <TB1> INFO: ----------------------------------------------------------------------
[16:02:48.900] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[16:02:48.900] <TB1> INFO: ----------------------------------------------------------------------
[16:02:48.905] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:02:48.914] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:02:48.914] <TB1> INFO: run 1 of 1
[16:02:49.225] <TB1> INFO: Expecting 8320000 events.
[16:03:24.448] <TB1> INFO: 965360 events read in total (34506ms).
[16:04:01.335] <TB1> INFO: 1922150 events read in total (71393ms).
[16:04:39.327] <TB1> INFO: 2876910 events read in total (109385ms).
[16:05:16.314] <TB1> INFO: 3827610 events read in total (146372ms).
[16:05:53.089] <TB1> INFO: 4770990 events read in total (183147ms).
[16:06:29.657] <TB1> INFO: 5711210 events read in total (219715ms).
[16:07:03.223] <TB1> INFO: 6650350 events read in total (253281ms).
[16:07:39.100] <TB1> INFO: 7590550 events read in total (289158ms).
[16:08:07.003] <TB1> INFO: 8320000 events read in total (317061ms).
[16:08:07.035] <TB1> INFO: Test took 318122ms.
[16:08:07.138] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:33.531] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[16:08:33.540] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:08:33.540] <TB1> INFO: run 1 of 1
[16:08:33.848] <TB1> INFO: Expecting 7072000 events.
[16:09:12.384] <TB1> INFO: 997960 events read in total (37820ms).
[16:09:49.537] <TB1> INFO: 1988120 events read in total (74973ms).
[16:10:23.676] <TB1> INFO: 2974630 events read in total (109112ms).
[16:11:00.376] <TB1> INFO: 3952290 events read in total (145812ms).
[16:11:37.942] <TB1> INFO: 4923470 events read in total (183378ms).
[16:12:13.641] <TB1> INFO: 5893300 events read in total (219077ms).
[16:12:50.578] <TB1> INFO: 6865280 events read in total (256014ms).
[16:12:58.885] <TB1> INFO: 7072000 events read in total (264321ms).
[16:12:58.918] <TB1> INFO: Test took 265378ms.
[16:12:59.000] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:25.938] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[16:13:25.948] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:13:25.948] <TB1> INFO: run 1 of 1
[16:13:26.281] <TB1> INFO: Expecting 6614400 events.
[16:14:05.230] <TB1> INFO: 1033820 events read in total (38233ms).
[16:14:43.064] <TB1> INFO: 2057930 events read in total (76067ms).
[16:15:19.410] <TB1> INFO: 3077360 events read in total (112413ms).
[16:15:56.880] <TB1> INFO: 4083740 events read in total (149883ms).
[16:16:31.757] <TB1> INFO: 5085880 events read in total (184760ms).
[16:17:08.717] <TB1> INFO: 6087890 events read in total (221720ms).
[16:17:27.180] <TB1> INFO: 6614400 events read in total (240183ms).
[16:17:27.209] <TB1> INFO: Test took 241261ms.
[16:17:27.271] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:54.754] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[16:17:54.764] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:17:54.764] <TB1> INFO: run 1 of 1
[16:17:55.105] <TB1> INFO: Expecting 6572800 events.
[16:18:33.010] <TB1> INFO: 1036320 events read in total (37189ms).
[16:19:10.095] <TB1> INFO: 2063160 events read in total (74274ms).
[16:19:47.330] <TB1> INFO: 3084930 events read in total (111509ms).
[16:20:23.315] <TB1> INFO: 4093160 events read in total (147494ms).
[16:21:00.418] <TB1> INFO: 5098010 events read in total (184597ms).
[16:21:38.454] <TB1> INFO: 6103180 events read in total (222633ms).
[16:21:55.501] <TB1> INFO: 6572800 events read in total (239680ms).
[16:21:55.539] <TB1> INFO: Test took 240775ms.
[16:21:55.607] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:19.577] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[16:22:19.588] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:22:19.588] <TB1> INFO: run 1 of 1
[16:22:19.892] <TB1> INFO: Expecting 6572800 events.
[16:22:58.676] <TB1> INFO: 1035760 events read in total (38068ms).
[16:23:35.987] <TB1> INFO: 2061620 events read in total (75379ms).
[16:24:13.450] <TB1> INFO: 3082500 events read in total (112843ms).
[16:24:49.856] <TB1> INFO: 4090010 events read in total (149248ms).
[16:25:26.044] <TB1> INFO: 5093850 events read in total (185436ms).
[16:26:00.800] <TB1> INFO: 6098180 events read in total (220192ms).
[16:26:19.365] <TB1> INFO: 6572800 events read in total (238757ms).
[16:26:19.394] <TB1> INFO: Test took 239806ms.
[16:26:19.456] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:42.121] <TB1> INFO: PixTestTrim::trimBitTest() done
[16:26:42.123] <TB1> INFO: PixTestTrim::doTest() done, duration: 3338 seconds
[16:26:42.124] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:42.124] <TB1> INFO: Decoding statistics:
[16:26:42.124] <TB1> INFO: General information:
[16:26:42.124] <TB1> INFO: 16bit words read: 0
[16:26:42.124] <TB1> INFO: valid events total: 0
[16:26:42.124] <TB1> INFO: empty events: 0
[16:26:42.124] <TB1> INFO: valid events with pixels: 0
[16:26:42.124] <TB1> INFO: valid pixel hits: 0
[16:26:42.124] <TB1> INFO: Event errors: 0
[16:26:42.124] <TB1> INFO: start marker: 0
[16:26:42.124] <TB1> INFO: stop marker: 0
[16:26:42.124] <TB1> INFO: overflow: 0
[16:26:42.124] <TB1> INFO: invalid 5bit words: 0
[16:26:42.124] <TB1> INFO: invalid XOR eye diagram: 0
[16:26:42.124] <TB1> INFO: TBM errors: 0
[16:26:42.124] <TB1> INFO: flawed TBM headers: 0
[16:26:42.124] <TB1> INFO: flawed TBM trailers: 0
[16:26:42.124] <TB1> INFO: event ID mismatches: 0
[16:26:42.124] <TB1> INFO: ROC errors: 0
[16:26:42.124] <TB1> INFO: missing ROC header(s): 0
[16:26:42.124] <TB1> INFO: misplaced readback start: 0
[16:26:42.124] <TB1> INFO: Pixel decoding errors: 0
[16:26:42.124] <TB1> INFO: pixel data incomplete: 0
[16:26:42.124] <TB1> INFO: pixel address: 0
[16:26:42.124] <TB1> INFO: pulse height fill bit: 0
[16:26:42.124] <TB1> INFO: buffer corruption: 0
[16:26:42.924] <TB1> INFO: ######################################################################
[16:26:42.924] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:26:42.924] <TB1> INFO: ######################################################################
[16:26:43.264] <TB1> INFO: Expecting 41600 events.
[16:26:47.892] <TB1> INFO: 41600 events read in total (3912ms).
[16:26:47.893] <TB1> INFO: Test took 4967ms.
[16:26:47.900] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:48.496] <TB1> INFO: Expecting 41600 events.
[16:26:53.086] <TB1> INFO: 41600 events read in total (3873ms).
[16:26:53.086] <TB1> INFO: Test took 4931ms.
[16:26:53.093] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:53.448] <TB1> INFO: Expecting 41600 events.
[16:26:57.851] <TB1> INFO: 41600 events read in total (3687ms).
[16:26:57.852] <TB1> INFO: Test took 4744ms.
[16:26:57.858] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:58.192] <TB1> INFO: Expecting 2560 events.
[16:26:59.260] <TB1> INFO: 2560 events read in total (351ms).
[16:26:59.260] <TB1> INFO: Test took 1394ms.
[16:26:59.676] <TB1> INFO: Expecting 2560 events.
[16:27:00.745] <TB1> INFO: 2560 events read in total (353ms).
[16:27:00.746] <TB1> INFO: Test took 1486ms.
[16:27:01.162] <TB1> INFO: Expecting 2560 events.
[16:27:02.235] <TB1> INFO: 2560 events read in total (357ms).
[16:27:02.235] <TB1> INFO: Test took 1489ms.
[16:27:02.650] <TB1> INFO: Expecting 2560 events.
[16:27:03.717] <TB1> INFO: 2560 events read in total (351ms).
[16:27:03.717] <TB1> INFO: Test took 1482ms.
[16:27:04.133] <TB1> INFO: Expecting 2560 events.
[16:27:05.200] <TB1> INFO: 2560 events read in total (351ms).
[16:27:05.201] <TB1> INFO: Test took 1484ms.
[16:27:05.616] <TB1> INFO: Expecting 2560 events.
[16:27:06.686] <TB1> INFO: 2560 events read in total (354ms).
[16:27:06.686] <TB1> INFO: Test took 1485ms.
[16:27:07.101] <TB1> INFO: Expecting 2560 events.
[16:27:08.167] <TB1> INFO: 2560 events read in total (349ms).
[16:27:08.167] <TB1> INFO: Test took 1481ms.
[16:27:08.582] <TB1> INFO: Expecting 2560 events.
[16:27:09.651] <TB1> INFO: 2560 events read in total (353ms).
[16:27:09.651] <TB1> INFO: Test took 1483ms.
[16:27:10.066] <TB1> INFO: Expecting 2560 events.
[16:27:11.129] <TB1> INFO: 2560 events read in total (347ms).
[16:27:11.130] <TB1> INFO: Test took 1478ms.
[16:27:11.545] <TB1> INFO: Expecting 2560 events.
[16:27:12.613] <TB1> INFO: 2560 events read in total (352ms).
[16:27:12.613] <TB1> INFO: Test took 1483ms.
[16:27:13.029] <TB1> INFO: Expecting 2560 events.
[16:27:14.094] <TB1> INFO: 2560 events read in total (349ms).
[16:27:14.095] <TB1> INFO: Test took 1482ms.
[16:27:14.510] <TB1> INFO: Expecting 2560 events.
[16:27:15.574] <TB1> INFO: 2560 events read in total (348ms).
[16:27:15.574] <TB1> INFO: Test took 1478ms.
[16:27:15.990] <TB1> INFO: Expecting 2560 events.
[16:27:17.056] <TB1> INFO: 2560 events read in total (350ms).
[16:27:17.057] <TB1> INFO: Test took 1483ms.
[16:27:17.472] <TB1> INFO: Expecting 2560 events.
[16:27:18.544] <TB1> INFO: 2560 events read in total (356ms).
[16:27:18.544] <TB1> INFO: Test took 1487ms.
[16:27:18.960] <TB1> INFO: Expecting 2560 events.
[16:27:20.028] <TB1> INFO: 2560 events read in total (351ms).
[16:27:20.028] <TB1> INFO: Test took 1484ms.
[16:27:20.443] <TB1> INFO: Expecting 2560 events.
[16:27:21.506] <TB1> INFO: 2560 events read in total (347ms).
[16:27:21.507] <TB1> INFO: Test took 1478ms.
[16:27:21.511] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:27:21.923] <TB1> INFO: Expecting 655360 events.
[16:27:35.961] <TB1> INFO: 655360 events read in total (13322ms).
[16:27:35.971] <TB1> INFO: Expecting 655360 events.
[16:27:49.955] <TB1> INFO: 655360 events read in total (13456ms).
[16:27:49.969] <TB1> INFO: Expecting 655360 events.
[16:28:04.759] <TB1> INFO: 655360 events read in total (14261ms).
[16:28:04.775] <TB1> INFO: Expecting 655360 events.
[16:28:19.157] <TB1> INFO: 655360 events read in total (13854ms).
[16:28:19.176] <TB1> INFO: Expecting 655360 events.
[16:28:33.874] <TB1> INFO: 655360 events read in total (14170ms).
[16:28:33.896] <TB1> INFO: Expecting 655360 events.
[16:28:48.416] <TB1> INFO: 655360 events read in total (13992ms).
[16:28:48.441] <TB1> INFO: Expecting 655360 events.
[16:29:02.896] <TB1> INFO: 655360 events read in total (13927ms).
[16:29:02.931] <TB1> INFO: Expecting 655360 events.
[16:29:17.749] <TB1> INFO: 655360 events read in total (14290ms).
[16:29:17.787] <TB1> INFO: Expecting 655360 events.
[16:29:32.147] <TB1> INFO: 655360 events read in total (13832ms).
[16:29:32.184] <TB1> INFO: Expecting 655360 events.
[16:29:46.169] <TB1> INFO: 655360 events read in total (13457ms).
[16:29:46.215] <TB1> INFO: Expecting 655360 events.
[16:30:00.590] <TB1> INFO: 655360 events read in total (13847ms).
[16:30:00.631] <TB1> INFO: Expecting 655360 events.
[16:30:15.144] <TB1> INFO: 655360 events read in total (13984ms).
[16:30:15.189] <TB1> INFO: Expecting 655360 events.
[16:30:30.043] <TB1> INFO: 655360 events read in total (14326ms).
[16:30:30.094] <TB1> INFO: Expecting 655360 events.
[16:30:44.745] <TB1> INFO: 655360 events read in total (14123ms).
[16:30:44.797] <TB1> INFO: Expecting 655360 events.
[16:30:59.419] <TB1> INFO: 655360 events read in total (14094ms).
[16:30:59.481] <TB1> INFO: Expecting 655360 events.
[16:31:14.541] <TB1> INFO: 655360 events read in total (14531ms).
[16:31:14.631] <TB1> INFO: Test took 233120ms.
[16:31:14.714] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:31:15.021] <TB1> INFO: Expecting 655360 events.
[16:31:29.686] <TB1> INFO: 655360 events read in total (13949ms).
[16:31:29.696] <TB1> INFO: Expecting 655360 events.
[16:31:44.067] <TB1> INFO: 655360 events read in total (13843ms).
[16:31:44.079] <TB1> INFO: Expecting 655360 events.
[16:31:59.159] <TB1> INFO: 655360 events read in total (14552ms).
[16:31:59.179] <TB1> INFO: Expecting 655360 events.
[16:32:13.621] <TB1> INFO: 655360 events read in total (13914ms).
[16:32:13.640] <TB1> INFO: Expecting 655360 events.
[16:32:28.690] <TB1> INFO: 655360 events read in total (14522ms).
[16:32:28.714] <TB1> INFO: Expecting 655360 events.
[16:32:43.179] <TB1> INFO: 655360 events read in total (13937ms).
[16:32:43.207] <TB1> INFO: Expecting 655360 events.
[16:32:57.947] <TB1> INFO: 655360 events read in total (14212ms).
[16:32:57.983] <TB1> INFO: Expecting 655360 events.
[16:33:12.134] <TB1> INFO: 655360 events read in total (13622ms).
[16:33:12.166] <TB1> INFO: Expecting 655360 events.
[16:33:26.115] <TB1> INFO: 655360 events read in total (13420ms).
[16:33:26.150] <TB1> INFO: Expecting 655360 events.
[16:33:40.702] <TB1> INFO: 655360 events read in total (14024ms).
[16:33:40.751] <TB1> INFO: Expecting 655360 events.
[16:33:54.628] <TB1> INFO: 655360 events read in total (13349ms).
[16:33:54.669] <TB1> INFO: Expecting 655360 events.
[16:34:08.424] <TB1> INFO: 655360 events read in total (13227ms).
[16:34:08.476] <TB1> INFO: Expecting 655360 events.
[16:34:22.221] <TB1> INFO: 655360 events read in total (13217ms).
[16:34:22.277] <TB1> INFO: Expecting 655360 events.
[16:34:37.332] <TB1> INFO: 655360 events read in total (14527ms).
[16:34:37.386] <TB1> INFO: Expecting 655360 events.
[16:34:51.829] <TB1> INFO: 655360 events read in total (13915ms).
[16:34:51.888] <TB1> INFO: Expecting 655360 events.
[16:35:06.939] <TB1> INFO: 655360 events read in total (14523ms).
[16:35:07.042] <TB1> INFO: Test took 232328ms.
[16:35:07.247] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.255] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.264] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.273] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.282] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.291] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.299] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.307] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:35:07.316] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:35:07.324] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:35:07.333] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[16:35:07.342] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[16:35:07.351] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[16:35:07.360] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[16:35:07.369] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.379] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.387] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.396] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.405] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.413] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:35:07.422] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:35:07.434] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:35:07.443] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[16:35:07.452] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[16:35:07.460] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[16:35:07.470] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[16:35:07.479] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[16:35:07.488] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.497] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.506] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.515] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:35:07.524] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:35:07.533] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:35:07.543] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:35:07.601] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:35:07.601] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:35:07.601] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:35:07.601] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:35:07.602] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:35:07.602] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:35:07.602] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:35:07.602] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:35:07.603] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:35:07.603] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:35:07.603] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:35:07.604] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:35:07.604] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:35:07.604] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:35:07.605] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:35:07.605] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:35:07.953] <TB1> INFO: Expecting 41600 events.
[16:35:12.027] <TB1> INFO: 41600 events read in total (3357ms).
[16:35:12.028] <TB1> INFO: Test took 4418ms.
[16:35:12.614] <TB1> INFO: Expecting 41600 events.
[16:35:16.624] <TB1> INFO: 41600 events read in total (3294ms).
[16:35:16.625] <TB1> INFO: Test took 4348ms.
[16:35:17.178] <TB1> INFO: Expecting 41600 events.
[16:35:21.195] <TB1> INFO: 41600 events read in total (3300ms).
[16:35:21.196] <TB1> INFO: Test took 4341ms.
[16:35:21.431] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:21.563] <TB1> INFO: Expecting 2560 events.
[16:35:22.633] <TB1> INFO: 2560 events read in total (353ms).
[16:35:22.634] <TB1> INFO: Test took 1203ms.
[16:35:22.637] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:23.049] <TB1> INFO: Expecting 2560 events.
[16:35:24.117] <TB1> INFO: 2560 events read in total (352ms).
[16:35:24.118] <TB1> INFO: Test took 1481ms.
[16:35:24.121] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:24.533] <TB1> INFO: Expecting 2560 events.
[16:35:25.608] <TB1> INFO: 2560 events read in total (358ms).
[16:35:25.609] <TB1> INFO: Test took 1488ms.
[16:35:25.611] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:26.025] <TB1> INFO: Expecting 2560 events.
[16:35:27.090] <TB1> INFO: 2560 events read in total (349ms).
[16:35:27.090] <TB1> INFO: Test took 1479ms.
[16:35:27.093] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:27.507] <TB1> INFO: Expecting 2560 events.
[16:35:28.575] <TB1> INFO: 2560 events read in total (351ms).
[16:35:28.576] <TB1> INFO: Test took 1483ms.
[16:35:28.579] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:28.992] <TB1> INFO: Expecting 2560 events.
[16:35:30.060] <TB1> INFO: 2560 events read in total (351ms).
[16:35:30.061] <TB1> INFO: Test took 1482ms.
[16:35:30.063] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:30.477] <TB1> INFO: Expecting 2560 events.
[16:35:31.549] <TB1> INFO: 2560 events read in total (355ms).
[16:35:31.549] <TB1> INFO: Test took 1486ms.
[16:35:31.552] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:31.967] <TB1> INFO: Expecting 2560 events.
[16:35:33.033] <TB1> INFO: 2560 events read in total (349ms).
[16:35:33.034] <TB1> INFO: Test took 1483ms.
[16:35:33.036] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:33.451] <TB1> INFO: Expecting 2560 events.
[16:35:34.520] <TB1> INFO: 2560 events read in total (353ms).
[16:35:34.520] <TB1> INFO: Test took 1484ms.
[16:35:34.522] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:34.937] <TB1> INFO: Expecting 2560 events.
[16:35:36.007] <TB1> INFO: 2560 events read in total (354ms).
[16:35:36.007] <TB1> INFO: Test took 1485ms.
[16:35:36.010] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:36.423] <TB1> INFO: Expecting 2560 events.
[16:35:37.492] <TB1> INFO: 2560 events read in total (352ms).
[16:35:37.492] <TB1> INFO: Test took 1482ms.
[16:35:37.495] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:37.909] <TB1> INFO: Expecting 2560 events.
[16:35:38.977] <TB1> INFO: 2560 events read in total (352ms).
[16:35:38.978] <TB1> INFO: Test took 1483ms.
[16:35:38.984] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:39.394] <TB1> INFO: Expecting 2560 events.
[16:35:40.464] <TB1> INFO: 2560 events read in total (353ms).
[16:35:40.464] <TB1> INFO: Test took 1480ms.
[16:35:40.469] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:40.880] <TB1> INFO: Expecting 2560 events.
[16:35:41.944] <TB1> INFO: 2560 events read in total (347ms).
[16:35:41.945] <TB1> INFO: Test took 1477ms.
[16:35:41.948] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:42.361] <TB1> INFO: Expecting 2560 events.
[16:35:43.432] <TB1> INFO: 2560 events read in total (354ms).
[16:35:43.433] <TB1> INFO: Test took 1486ms.
[16:35:43.436] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:43.849] <TB1> INFO: Expecting 2560 events.
[16:35:44.921] <TB1> INFO: 2560 events read in total (356ms).
[16:35:44.921] <TB1> INFO: Test took 1486ms.
[16:35:44.924] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:45.337] <TB1> INFO: Expecting 2560 events.
[16:35:46.408] <TB1> INFO: 2560 events read in total (355ms).
[16:35:46.409] <TB1> INFO: Test took 1485ms.
[16:35:46.413] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:46.825] <TB1> INFO: Expecting 2560 events.
[16:35:47.895] <TB1> INFO: 2560 events read in total (354ms).
[16:35:47.895] <TB1> INFO: Test took 1483ms.
[16:35:47.898] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:48.312] <TB1> INFO: Expecting 2560 events.
[16:35:49.385] <TB1> INFO: 2560 events read in total (356ms).
[16:35:49.385] <TB1> INFO: Test took 1487ms.
[16:35:49.388] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:49.802] <TB1> INFO: Expecting 2560 events.
[16:35:50.873] <TB1> INFO: 2560 events read in total (354ms).
[16:35:50.873] <TB1> INFO: Test took 1486ms.
[16:35:50.877] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:51.290] <TB1> INFO: Expecting 2560 events.
[16:35:52.360] <TB1> INFO: 2560 events read in total (354ms).
[16:35:52.360] <TB1> INFO: Test took 1484ms.
[16:35:52.363] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:52.776] <TB1> INFO: Expecting 2560 events.
[16:35:53.842] <TB1> INFO: 2560 events read in total (350ms).
[16:35:53.843] <TB1> INFO: Test took 1480ms.
[16:35:53.846] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:54.258] <TB1> INFO: Expecting 2560 events.
[16:35:55.328] <TB1> INFO: 2560 events read in total (354ms).
[16:35:55.328] <TB1> INFO: Test took 1482ms.
[16:35:55.332] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:55.744] <TB1> INFO: Expecting 2560 events.
[16:35:56.810] <TB1> INFO: 2560 events read in total (350ms).
[16:35:56.810] <TB1> INFO: Test took 1479ms.
[16:35:56.813] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:57.226] <TB1> INFO: Expecting 2560 events.
[16:35:58.296] <TB1> INFO: 2560 events read in total (353ms).
[16:35:58.296] <TB1> INFO: Test took 1483ms.
[16:35:58.299] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:35:58.712] <TB1> INFO: Expecting 2560 events.
[16:35:59.782] <TB1> INFO: 2560 events read in total (354ms).
[16:35:59.782] <TB1> INFO: Test took 1484ms.
[16:35:59.786] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:36:00.197] <TB1> INFO: Expecting 2560 events.
[16:36:01.271] <TB1> INFO: 2560 events read in total (357ms).
[16:36:01.271] <TB1> INFO: Test took 1486ms.
[16:36:01.274] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:36:01.687] <TB1> INFO: Expecting 2560 events.
[16:36:02.755] <TB1> INFO: 2560 events read in total (352ms).
[16:36:02.755] <TB1> INFO: Test took 1481ms.
[16:36:02.758] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:36:03.171] <TB1> INFO: Expecting 2560 events.
[16:36:04.236] <TB1> INFO: 2560 events read in total (348ms).
[16:36:04.236] <TB1> INFO: Test took 1478ms.
[16:36:04.241] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:36:04.653] <TB1> INFO: Expecting 2560 events.
[16:36:05.724] <TB1> INFO: 2560 events read in total (355ms).
[16:36:05.724] <TB1> INFO: Test took 1484ms.
[16:36:05.728] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:36:06.140] <TB1> INFO: Expecting 2560 events.
[16:36:07.206] <TB1> INFO: 2560 events read in total (349ms).
[16:36:07.207] <TB1> INFO: Test took 1479ms.
[16:36:07.210] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:36:07.623] <TB1> INFO: Expecting 2560 events.
[16:36:08.687] <TB1> INFO: 2560 events read in total (348ms).
[16:36:08.688] <TB1> INFO: Test took 1478ms.
[16:36:09.359] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 566 seconds
[16:36:09.359] <TB1> INFO: PH scale (per ROC): 69 75 68 70 75 68 75 72 66 76 70 77 79 80 78 67
[16:36:09.359] <TB1> INFO: PH offset (per ROC): 192 167 181 180 172 176 176 176 183 165 183 176 159 163 180 176
[16:36:09.364] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:09.364] <TB1> INFO: Decoding statistics:
[16:36:09.364] <TB1> INFO: General information:
[16:36:09.364] <TB1> INFO: 16bit words read: 91936
[16:36:09.364] <TB1> INFO: valid events total: 10240
[16:36:09.364] <TB1> INFO: empty events: 7736
[16:36:09.364] <TB1> INFO: valid events with pixels: 2504
[16:36:09.364] <TB1> INFO: valid pixel hits: 2504
[16:36:09.364] <TB1> INFO: Event errors: 0
[16:36:09.364] <TB1> INFO: start marker: 0
[16:36:09.364] <TB1> INFO: stop marker: 0
[16:36:09.364] <TB1> INFO: overflow: 0
[16:36:09.364] <TB1> INFO: invalid 5bit words: 0
[16:36:09.364] <TB1> INFO: invalid XOR eye diagram: 0
[16:36:09.364] <TB1> INFO: TBM errors: 0
[16:36:09.364] <TB1> INFO: flawed TBM headers: 0
[16:36:09.364] <TB1> INFO: flawed TBM trailers: 0
[16:36:09.364] <TB1> INFO: event ID mismatches: 0
[16:36:09.364] <TB1> INFO: ROC errors: 0
[16:36:09.364] <TB1> INFO: missing ROC header(s): 0
[16:36:09.364] <TB1> INFO: misplaced readback start: 0
[16:36:09.364] <TB1> INFO: Pixel decoding errors: 0
[16:36:09.364] <TB1> INFO: pixel data incomplete: 0
[16:36:09.364] <TB1> INFO: pixel address: 0
[16:36:09.364] <TB1> INFO: pulse height fill bit: 0
[16:36:09.364] <TB1> INFO: buffer corruption: 0
[16:36:09.537] <TB1> INFO: ######################################################################
[16:36:09.537] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:36:09.537] <TB1> INFO: ######################################################################
[16:36:09.550] <TB1> INFO: scanning low vcal = 10
[16:36:09.874] <TB1> INFO: Expecting 41600 events.
[16:36:13.727] <TB1> INFO: 41600 events read in total (3137ms).
[16:36:13.728] <TB1> INFO: Test took 4178ms.
[16:36:13.730] <TB1> INFO: scanning low vcal = 20
[16:36:14.144] <TB1> INFO: Expecting 41600 events.
[16:36:17.942] <TB1> INFO: 41600 events read in total (3082ms).
[16:36:17.942] <TB1> INFO: Test took 4212ms.
[16:36:17.945] <TB1> INFO: scanning low vcal = 30
[16:36:18.357] <TB1> INFO: Expecting 41600 events.
[16:36:22.188] <TB1> INFO: 41600 events read in total (3115ms).
[16:36:22.189] <TB1> INFO: Test took 4244ms.
[16:36:22.191] <TB1> INFO: scanning low vcal = 40
[16:36:22.595] <TB1> INFO: Expecting 41600 events.
[16:36:26.979] <TB1> INFO: 41600 events read in total (3667ms).
[16:36:26.980] <TB1> INFO: Test took 4789ms.
[16:36:26.984] <TB1> INFO: scanning low vcal = 50
[16:36:27.325] <TB1> INFO: Expecting 41600 events.
[16:36:31.747] <TB1> INFO: 41600 events read in total (3706ms).
[16:36:31.748] <TB1> INFO: Test took 4764ms.
[16:36:31.751] <TB1> INFO: scanning low vcal = 60
[16:36:32.093] <TB1> INFO: Expecting 41600 events.
[16:36:36.508] <TB1> INFO: 41600 events read in total (3698ms).
[16:36:36.509] <TB1> INFO: Test took 4758ms.
[16:36:36.512] <TB1> INFO: scanning low vcal = 70
[16:36:36.862] <TB1> INFO: Expecting 41600 events.
[16:36:41.273] <TB1> INFO: 41600 events read in total (3694ms).
[16:36:41.273] <TB1> INFO: Test took 4761ms.
[16:36:41.277] <TB1> INFO: scanning low vcal = 80
[16:36:41.619] <TB1> INFO: Expecting 41600 events.
[16:36:46.010] <TB1> INFO: 41600 events read in total (3674ms).
[16:36:46.010] <TB1> INFO: Test took 4733ms.
[16:36:46.014] <TB1> INFO: scanning low vcal = 90
[16:36:46.347] <TB1> INFO: Expecting 41600 events.
[16:36:50.693] <TB1> INFO: 41600 events read in total (3630ms).
[16:36:50.694] <TB1> INFO: Test took 4680ms.
[16:36:50.699] <TB1> INFO: scanning low vcal = 100
[16:36:51.039] <TB1> INFO: Expecting 41600 events.
[16:36:55.572] <TB1> INFO: 41600 events read in total (3816ms).
[16:36:55.572] <TB1> INFO: Test took 4873ms.
[16:36:55.576] <TB1> INFO: scanning low vcal = 110
[16:36:55.906] <TB1> INFO: Expecting 41600 events.
[16:37:00.276] <TB1> INFO: 41600 events read in total (3654ms).
[16:37:00.276] <TB1> INFO: Test took 4700ms.
[16:37:00.280] <TB1> INFO: scanning low vcal = 120
[16:37:00.627] <TB1> INFO: Expecting 41600 events.
[16:37:05.070] <TB1> INFO: 41600 events read in total (3727ms).
[16:37:05.071] <TB1> INFO: Test took 4791ms.
[16:37:05.075] <TB1> INFO: scanning low vcal = 130
[16:37:05.415] <TB1> INFO: Expecting 41600 events.
[16:37:09.831] <TB1> INFO: 41600 events read in total (3700ms).
[16:37:09.832] <TB1> INFO: Test took 4757ms.
[16:37:09.835] <TB1> INFO: scanning low vcal = 140
[16:37:10.186] <TB1> INFO: Expecting 41600 events.
[16:37:14.573] <TB1> INFO: 41600 events read in total (3670ms).
[16:37:14.573] <TB1> INFO: Test took 4738ms.
[16:37:14.577] <TB1> INFO: scanning low vcal = 150
[16:37:14.922] <TB1> INFO: Expecting 41600 events.
[16:37:19.361] <TB1> INFO: 41600 events read in total (3723ms).
[16:37:19.362] <TB1> INFO: Test took 4785ms.
[16:37:19.365] <TB1> INFO: scanning low vcal = 160
[16:37:19.718] <TB1> INFO: Expecting 41600 events.
[16:37:24.064] <TB1> INFO: 41600 events read in total (3630ms).
[16:37:24.065] <TB1> INFO: Test took 4700ms.
[16:37:24.069] <TB1> INFO: scanning low vcal = 170
[16:37:24.413] <TB1> INFO: Expecting 41600 events.
[16:37:28.770] <TB1> INFO: 41600 events read in total (3640ms).
[16:37:28.771] <TB1> INFO: Test took 4702ms.
[16:37:28.776] <TB1> INFO: scanning low vcal = 180
[16:37:29.127] <TB1> INFO: Expecting 41600 events.
[16:37:33.490] <TB1> INFO: 41600 events read in total (3646ms).
[16:37:33.490] <TB1> INFO: Test took 4714ms.
[16:37:33.494] <TB1> INFO: scanning low vcal = 190
[16:37:33.849] <TB1> INFO: Expecting 41600 events.
[16:37:38.244] <TB1> INFO: 41600 events read in total (3679ms).
[16:37:38.245] <TB1> INFO: Test took 4751ms.
[16:37:38.249] <TB1> INFO: scanning low vcal = 200
[16:37:38.595] <TB1> INFO: Expecting 41600 events.
[16:37:43.009] <TB1> INFO: 41600 events read in total (3697ms).
[16:37:43.009] <TB1> INFO: Test took 4760ms.
[16:37:43.013] <TB1> INFO: scanning low vcal = 210
[16:37:43.360] <TB1> INFO: Expecting 41600 events.
[16:37:47.806] <TB1> INFO: 41600 events read in total (3730ms).
[16:37:47.806] <TB1> INFO: Test took 4793ms.
[16:37:47.810] <TB1> INFO: scanning low vcal = 220
[16:37:48.155] <TB1> INFO: Expecting 41600 events.
[16:37:52.541] <TB1> INFO: 41600 events read in total (3670ms).
[16:37:52.542] <TB1> INFO: Test took 4732ms.
[16:37:52.545] <TB1> INFO: scanning low vcal = 230
[16:37:52.896] <TB1> INFO: Expecting 41600 events.
[16:37:57.247] <TB1> INFO: 41600 events read in total (3635ms).
[16:37:57.248] <TB1> INFO: Test took 4703ms.
[16:37:57.252] <TB1> INFO: scanning low vcal = 240
[16:37:57.604] <TB1> INFO: Expecting 41600 events.
[16:38:01.939] <TB1> INFO: 41600 events read in total (3619ms).
[16:38:01.940] <TB1> INFO: Test took 4688ms.
[16:38:01.943] <TB1> INFO: scanning low vcal = 250
[16:38:02.297] <TB1> INFO: Expecting 41600 events.
[16:38:06.610] <TB1> INFO: 41600 events read in total (3596ms).
[16:38:06.610] <TB1> INFO: Test took 4667ms.
[16:38:06.616] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[16:38:06.967] <TB1> INFO: Expecting 41600 events.
[16:38:11.340] <TB1> INFO: 41600 events read in total (3657ms).
[16:38:11.341] <TB1> INFO: Test took 4725ms.
[16:38:11.346] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[16:38:11.689] <TB1> INFO: Expecting 41600 events.
[16:38:16.037] <TB1> INFO: 41600 events read in total (3631ms).
[16:38:16.037] <TB1> INFO: Test took 4691ms.
[16:38:16.041] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[16:38:16.388] <TB1> INFO: Expecting 41600 events.
[16:38:20.820] <TB1> INFO: 41600 events read in total (3715ms).
[16:38:20.821] <TB1> INFO: Test took 4780ms.
[16:38:20.824] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[16:38:21.178] <TB1> INFO: Expecting 41600 events.
[16:38:25.606] <TB1> INFO: 41600 events read in total (3712ms).
[16:38:25.607] <TB1> INFO: Test took 4783ms.
[16:38:25.610] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:38:25.953] <TB1> INFO: Expecting 41600 events.
[16:38:30.326] <TB1> INFO: 41600 events read in total (3657ms).
[16:38:30.327] <TB1> INFO: Test took 4717ms.
[16:38:30.948] <TB1> INFO: PixTestGainPedestal::measure() done
[16:39:07.444] <TB1> INFO: PixTestGainPedestal::fit() done
[16:39:07.444] <TB1> INFO: non-linearity mean: 0.950 0.958 0.952 0.963 0.963 0.955 0.964 0.963 0.957 0.964 0.956 0.961 0.952 0.955 0.961 0.955
[16:39:07.444] <TB1> INFO: non-linearity RMS: 0.007 0.006 0.008 0.005 0.006 0.007 0.003 0.007 0.005 0.006 0.006 0.004 0.007 0.005 0.006 0.005
[16:39:07.444] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:39:07.465] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:39:07.484] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:39:07.503] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:39:07.523] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:39:07.542] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:39:07.561] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:39:07.580] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:39:07.598] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:39:07.615] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:39:07.633] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:39:07.651] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:39:07.668] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:39:07.686] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:39:07.703] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:39:07.721] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:39:07.738] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 178 seconds
[16:39:07.738] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:07.738] <TB1> INFO: Decoding statistics:
[16:39:07.738] <TB1> INFO: General information:
[16:39:07.738] <TB1> INFO: 16bit words read: 2662400
[16:39:07.738] <TB1> INFO: valid events total: 166400
[16:39:07.738] <TB1> INFO: empty events: 0
[16:39:07.738] <TB1> INFO: valid events with pixels: 166400
[16:39:07.738] <TB1> INFO: valid pixel hits: 665580
[16:39:07.738] <TB1> INFO: Event errors: 0
[16:39:07.738] <TB1> INFO: start marker: 0
[16:39:07.738] <TB1> INFO: stop marker: 0
[16:39:07.738] <TB1> INFO: overflow: 0
[16:39:07.738] <TB1> INFO: invalid 5bit words: 0
[16:39:07.738] <TB1> INFO: invalid XOR eye diagram: 0
[16:39:07.739] <TB1> INFO: TBM errors: 0
[16:39:07.739] <TB1> INFO: flawed TBM headers: 0
[16:39:07.739] <TB1> INFO: flawed TBM trailers: 0
[16:39:07.739] <TB1> INFO: event ID mismatches: 0
[16:39:07.739] <TB1> INFO: ROC errors: 0
[16:39:07.739] <TB1> INFO: missing ROC header(s): 0
[16:39:07.739] <TB1> INFO: misplaced readback start: 0
[16:39:07.739] <TB1> INFO: Pixel decoding errors: 0
[16:39:07.739] <TB1> INFO: pixel data incomplete: 0
[16:39:07.739] <TB1> INFO: pixel address: 0
[16:39:07.739] <TB1> INFO: pulse height fill bit: 0
[16:39:07.739] <TB1> INFO: buffer corruption: 0
[16:39:07.745] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C15.dat
[16:39:07.746] <TB1> INFO: ######################################################################
[16:39:07.746] <TB1> INFO: PixTestTrim::doTest()
[16:39:07.746] <TB1> INFO: ######################################################################
[16:39:07.747] <TB1> INFO: PixTestReadback::RES sent once
[16:39:19.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C0.dat
[16:39:19.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C1.dat
[16:39:19.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C2.dat
[16:39:19.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C3.dat
[16:39:19.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C4.dat
[16:39:19.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C5.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C6.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C7.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C8.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C9.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C10.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C11.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C12.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C13.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C14.dat
[16:39:19.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C15.dat
[16:39:19.130] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:39:19.131] <TB1> INFO: PixTestReadback::RES sent once
[16:39:30.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C0.dat
[16:39:30.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C1.dat
[16:39:30.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C2.dat
[16:39:30.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C3.dat
[16:39:30.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C4.dat
[16:39:30.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C5.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C6.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C7.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C8.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C9.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C10.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C11.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C12.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C13.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C14.dat
[16:39:30.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C15.dat
[16:39:30.463] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:39:30.464] <TB1> INFO: PixTestReadback::RES sent once
[16:39:39.133] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:39:39.134] <TB1> INFO: Vbg will be calibrated using Vd calibration
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155calibrated Vbg = 1.19717 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.1calibrated Vbg = 1.20035 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.8calibrated Vbg = 1.19948 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.9calibrated Vbg = 1.21234 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 158.5calibrated Vbg = 1.20432 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 147calibrated Vbg = 1.20824 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.3calibrated Vbg = 1.219 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.4calibrated Vbg = 1.21681 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 160.9calibrated Vbg = 1.20767 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.1calibrated Vbg = 1.21211 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 157.2calibrated Vbg = 1.20313 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 158calibrated Vbg = 1.19726 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155calibrated Vbg = 1.19692 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151.4calibrated Vbg = 1.19388 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.6calibrated Vbg = 1.19343 :::*/*/*/*/
[16:39:39.134] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 159calibrated Vbg = 1.19588 :::*/*/*/*/
[16:39:39.138] <TB1> INFO: PixTestReadback::RES sent once
[16:42:34.404] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C0.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C1.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C2.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C3.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C4.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C5.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C6.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C7.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C8.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C9.dat
[16:42:34.405] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C10.dat
[16:42:34.406] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C11.dat
[16:42:34.406] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C12.dat
[16:42:34.406] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C13.dat
[16:42:34.406] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C14.dat
[16:42:34.406] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C15.dat
[16:42:34.451] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:42:34.452] <TB1> INFO: PixTestReadback::doTest() done
[16:42:34.452] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:34.452] <TB1> INFO: Decoding statistics:
[16:42:34.452] <TB1> INFO: General information:
[16:42:34.452] <TB1> INFO: 16bit words read: 1024
[16:42:34.452] <TB1> INFO: valid events total: 128
[16:42:34.452] <TB1> INFO: empty events: 128
[16:42:34.452] <TB1> INFO: valid events with pixels: 0
[16:42:34.452] <TB1> INFO: valid pixel hits: 0
[16:42:34.452] <TB1> INFO: Event errors: 0
[16:42:34.452] <TB1> INFO: start marker: 0
[16:42:34.452] <TB1> INFO: stop marker: 0
[16:42:34.452] <TB1> INFO: overflow: 0
[16:42:34.452] <TB1> INFO: invalid 5bit words: 0
[16:42:34.452] <TB1> INFO: invalid XOR eye diagram: 0
[16:42:34.452] <TB1> INFO: TBM errors: 0
[16:42:34.452] <TB1> INFO: flawed TBM headers: 0
[16:42:34.452] <TB1> INFO: flawed TBM trailers: 0
[16:42:34.452] <TB1> INFO: event ID mismatches: 0
[16:42:34.452] <TB1> INFO: ROC errors: 0
[16:42:34.452] <TB1> INFO: missing ROC header(s): 0
[16:42:34.452] <TB1> INFO: misplaced readback start: 0
[16:42:34.452] <TB1> INFO: Pixel decoding errors: 0
[16:42:34.452] <TB1> INFO: pixel data incomplete: 0
[16:42:34.452] <TB1> INFO: pixel address: 0
[16:42:34.452] <TB1> INFO: pulse height fill bit: 0
[16:42:34.452] <TB1> INFO: buffer corruption: 0
[16:42:34.465] <TB1> INFO: Decoding statistics:
[16:42:34.465] <TB1> INFO: General information:
[16:42:34.465] <TB1> INFO: 16bit words read: 2755360
[16:42:34.465] <TB1> INFO: valid events total: 176768
[16:42:34.465] <TB1> INFO: empty events: 7864
[16:42:34.465] <TB1> INFO: valid events with pixels: 168904
[16:42:34.465] <TB1> INFO: valid pixel hits: 668084
[16:42:34.465] <TB1> INFO: Event errors: 0
[16:42:34.465] <TB1> INFO: start marker: 0
[16:42:34.465] <TB1> INFO: stop marker: 0
[16:42:34.465] <TB1> INFO: overflow: 0
[16:42:34.465] <TB1> INFO: invalid 5bit words: 0
[16:42:34.465] <TB1> INFO: invalid XOR eye diagram: 0
[16:42:34.465] <TB1> INFO: TBM errors: 0
[16:42:34.465] <TB1> INFO: flawed TBM headers: 0
[16:42:34.465] <TB1> INFO: flawed TBM trailers: 0
[16:42:34.465] <TB1> INFO: event ID mismatches: 0
[16:42:34.465] <TB1> INFO: ROC errors: 0
[16:42:34.465] <TB1> INFO: missing ROC header(s): 0
[16:42:34.465] <TB1> INFO: misplaced readback start: 0
[16:42:34.465] <TB1> INFO: Pixel decoding errors: 0
[16:42:34.465] <TB1> INFO: pixel data incomplete: 0
[16:42:34.465] <TB1> INFO: pixel address: 0
[16:42:34.465] <TB1> INFO: pulse height fill bit: 0
[16:42:34.465] <TB1> INFO: buffer corruption: 0
[16:42:34.465] <TB1> INFO: enter test to run
[16:42:34.465] <TB1> INFO: test: exit no parameter change
[16:42:35.073] <TB1> QUIET: Connection to board 153 closed.
[16:42:35.156] <TB1> INFO: pXar: this is the end, my friend