[09:46:06.374] <TB3> INFO: *** Welcome to pxar ***
[09:46:06.374] <TB3> INFO: *** Today: 2016/09/19
[09:46:06.467] <TB3> INFO: *** Version: fe94
[09:46:06.467] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C15.dat
[09:46:06.468] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//tbmParameters_C0b.dat
[09:46:06.468] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//defaultMaskFile.dat
[09:46:06.468] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters_C15.dat
[09:46:06.541] <TB3> INFO: clk: 4
[09:46:06.541] <TB3> INFO: ctr: 4
[09:46:06.541] <TB3> INFO: sda: 19
[09:46:06.541] <TB3> INFO: tin: 9
[09:46:06.541] <TB3> INFO: level: 15
[09:46:06.541] <TB3> INFO: triggerdelay: 0
[09:46:06.541] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[09:46:06.541] <TB3> INFO: Log level: INFO
[09:46:06.548] <TB3> INFO: Found DTB DTB_WZ4I6J
[09:46:06.557] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[09:46:06.559] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[09:46:06.560] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[09:46:08.085] <TB3> INFO: DUT info:
[09:46:08.085] <TB3> INFO: The DUT currently contains the following objects:
[09:46:08.085] <TB3> INFO: 2 TBM Cores tbm08c (2 ON)
[09:46:08.085] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:46:08.085] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:46:08.085] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:46:08.085] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.085] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:46:08.486] <TB3> INFO: enter 'restricted' command line mode
[09:46:08.486] <TB3> INFO: enter test to run
[09:46:08.486] <TB3> INFO: test: pretest no parameter change
[09:46:08.486] <TB3> INFO: running: pretest
[09:46:08.495] <TB3> INFO: ----------------------------------------------------------------------
[09:46:08.495] <TB3> INFO: PixTestPretest::programROC()
[09:46:08.495] <TB3> INFO: ----------------------------------------------------------------------
[09:46:26.509] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:46:26.509] <TB3> INFO: IA differences per ROC: 16.9 18.5 17.7 17.7 14.5 18.5 17.7 16.9 16.1 17.7 16.9 17.7 18.5 18.5 16.1 18.5
[09:46:26.602] <TB3> INFO: enter test to run
[09:46:26.602] <TB3> INFO: test: pretest no parameter change
[09:46:26.602] <TB3> INFO: running: pretest
[09:46:26.603] <TB3> INFO: ----------------------------------------------------------------------
[09:46:26.603] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:46:26.603] <TB3> INFO: ----------------------------------------------------------------------
[09:46:32.790] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[09:46:32.790] <TB3> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 20.1 20.1 19.3 20.1 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3
[09:46:32.824] <TB3> INFO: enter test to run
[09:46:32.824] <TB3> INFO: test: pretest no parameter change
[09:46:32.824] <TB3> INFO: running: pretest
[09:46:32.825] <TB3> INFO: ----------------------------------------------------------------------
[09:46:32.825] <TB3> INFO: PixTestPretest::findTiming()
[09:46:32.825] <TB3> INFO: ----------------------------------------------------------------------
[09:46:32.825] <TB3> INFO: PixTestCmd::init()

[09:47:26.636] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:47:26.636] <TB3> INFO: (success/tries = 100/100), width = 4
[09:47:26.637] <TB3> INFO: enter test to run
[09:47:26.637] <TB3> INFO: test: pretest no parameter change
[09:47:26.637] <TB3> INFO: running: pretest
[09:47:26.639] <TB3> INFO: ----------------------------------------------------------------------
[09:47:26.639] <TB3> INFO: PixTestPretest::findWorkingPixel()
[09:47:26.639] <TB3> INFO: ----------------------------------------------------------------------
[09:47:26.732] <TB3> INFO: Expecting 231680 events.

[09:47:47.187] <TB3> INFO: 231680 events read in total (19899ms).
[09:47:47.194] <TB3> INFO: Test took 20550ms.
[09:47:47.413] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:47:47.485] <TB3> INFO: enter test to run
[09:47:47.485] <TB3> INFO: test: pretest no parameter change
[09:47:47.485] <TB3> INFO: running: pretest
[09:47:47.486] <TB3> INFO: ----------------------------------------------------------------------
[09:47:47.486] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[09:47:47.486] <TB3> INFO: ----------------------------------------------------------------------
[09:47:47.578] <TB3> INFO: Expecting 231680 events.
[09:48:07.902] <TB3> INFO: 231680 events read in total (19768ms).
[09:48:07.908] <TB3> INFO: Test took 20419ms.
[09:48:08.162] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[09:48:08.162] <TB3> INFO: CalDel: 143 163 129 144 124 141 141 156 120 110 142 143 122 143 129 133
[09:48:08.162] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 59 52 51 51 51 51 51 51
[09:48:08.196] <TB3> INFO: enter test to run
[09:48:08.196] <TB3> INFO: test: pretest no parameter change
[09:48:08.196] <TB3> INFO: running: pretest
[09:48:08.196] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C0.dat
[09:48:08.196] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C1.dat
[09:48:08.197] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C2.dat
[09:48:08.197] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C3.dat
[09:48:08.197] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C4.dat
[09:48:08.197] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C5.dat
[09:48:08.197] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C6.dat
[09:48:08.197] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C7.dat
[09:48:08.197] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C8.dat
[09:48:08.197] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C9.dat
[09:48:08.198] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C10.dat
[09:48:08.198] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C11.dat
[09:48:08.198] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C12.dat
[09:48:08.198] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C13.dat
[09:48:08.198] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C14.dat
[09:48:08.198] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters_C15.dat
[09:48:08.198] <TB3> INFO: enter test to run
[09:48:08.198] <TB3> INFO: test: fulltest no parameter change
[09:48:08.198] <TB3> INFO: running: fulltest
[09:48:08.198] <TB3> INFO: ######################################################################
[09:48:08.198] <TB3> INFO: PixTestFullTest::doTest()
[09:48:08.198] <TB3> INFO: ######################################################################
[09:48:08.199] <TB3> INFO: ######################################################################
[09:48:08.199] <TB3> INFO: PixTestAlive::doTest()
[09:48:08.199] <TB3> INFO: ######################################################################
[09:48:08.201] <TB3> INFO: ----------------------------------------------------------------------
[09:48:08.201] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:48:08.201] <TB3> INFO: ----------------------------------------------------------------------
[09:48:08.476] <TB3> INFO: Expecting 41600 events.
[09:48:15.531] <TB3> INFO: 41600 events read in total (6499ms).
[09:48:15.531] <TB3> INFO: Test took 7329ms.
[09:48:15.823] <TB3> INFO: PixTestAlive::aliveTest() done
[09:48:15.823] <TB3> INFO: number of dead pixels (per ROC): 2 3 0 1 1 0 0 1 0 0 0 0 0 0 0 2
[09:48:15.824] <TB3> INFO: ----------------------------------------------------------------------
[09:48:15.824] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:48:15.824] <TB3> INFO: ----------------------------------------------------------------------
[09:48:16.094] <TB3> INFO: Expecting 41600 events.
[09:48:20.316] <TB3> INFO: 41600 events read in total (3666ms).
[09:48:20.316] <TB3> INFO: Test took 4490ms.
[09:48:20.317] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:48:20.623] <TB3> INFO: PixTestAlive::maskTest() done
[09:48:20.623] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:48:20.624] <TB3> INFO: ----------------------------------------------------------------------
[09:48:20.624] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:48:20.624] <TB3> INFO: ----------------------------------------------------------------------
[09:48:20.906] <TB3> INFO: Expecting 41600 events.
[09:48:28.144] <TB3> INFO: 41600 events read in total (6682ms).
[09:48:28.144] <TB3> INFO: Test took 7518ms.
[09:48:28.431] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[09:48:28.432] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:48:28.432] <TB3> INFO: PixTestAlive::doTest() done, duration: 20 seconds
[09:48:28.432] <TB3> INFO: Decoding statistics:
[09:48:28.432] <TB3> INFO: General information:
[09:48:28.432] <TB3> INFO: 16bit words read: 0
[09:48:28.432] <TB3> INFO: valid events total: 0
[09:48:28.432] <TB3> INFO: empty events: 0
[09:48:28.432] <TB3> INFO: valid events with pixels: 0
[09:48:28.432] <TB3> INFO: valid pixel hits: 0
[09:48:28.432] <TB3> INFO: Event errors: 0
[09:48:28.432] <TB3> INFO: start marker: 0
[09:48:28.432] <TB3> INFO: stop marker: 0
[09:48:28.432] <TB3> INFO: overflow: 0
[09:48:28.432] <TB3> INFO: invalid 5bit words: 0
[09:48:28.432] <TB3> INFO: invalid XOR eye diagram: 0
[09:48:28.432] <TB3> INFO: frame (failed synchr.): 0
[09:48:28.432] <TB3> INFO: idle data (no TBM trl): 0
[09:48:28.432] <TB3> INFO: no data (only TBM hdr): 0
[09:48:28.432] <TB3> INFO: TBM errors: 0
[09:48:28.432] <TB3> INFO: flawed TBM headers: 0
[09:48:28.432] <TB3> INFO: flawed TBM trailers: 0
[09:48:28.432] <TB3> INFO: event ID mismatches: 0
[09:48:28.432] <TB3> INFO: ROC errors: 0
[09:48:28.432] <TB3> INFO: missing ROC header(s): 0
[09:48:28.432] <TB3> INFO: misplaced readback start: 0
[09:48:28.432] <TB3> INFO: Pixel decoding errors: 0
[09:48:28.432] <TB3> INFO: pixel data incomplete: 0
[09:48:28.432] <TB3> INFO: pixel address: 0
[09:48:28.433] <TB3> INFO: pulse height fill bit: 0
[09:48:28.433] <TB3> INFO: buffer corruption: 0
[09:48:28.445] <TB3> INFO: ######################################################################
[09:48:28.445] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:48:28.445] <TB3> INFO: ######################################################################
[09:48:28.448] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:48:28.468] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:48:28.468] <TB3> INFO: run 1 of 1
[09:48:28.755] <TB3> INFO: Expecting 3120000 events.
[09:50:23.219] <TB3> INFO: 1269605 events read in total (113908ms).
[09:52:16.324] <TB3> INFO: 2518420 events read in total (227013ms).
[09:53:10.279] <TB3> INFO: 3120000 events read in total (280968ms).
[09:53:10.340] <TB3> INFO: Test took 281873ms.
[09:53:33.688] <TB3> INFO: PixTestBBMap::doTest() done, duration: 305 seconds
[09:53:33.688] <TB3> INFO: number of dead bumps (per ROC): 1 1 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[09:53:33.688] <TB3> INFO: separation cut (per ROC): 137 130 132 122 142 133 143 127 146 146 131 125 139 133 127 138
[09:53:33.688] <TB3> INFO: Decoding statistics:
[09:53:33.688] <TB3> INFO: General information:
[09:53:33.688] <TB3> INFO: 16bit words read: 0
[09:53:33.688] <TB3> INFO: valid events total: 0
[09:53:33.688] <TB3> INFO: empty events: 0
[09:53:33.688] <TB3> INFO: valid events with pixels: 0
[09:53:33.688] <TB3> INFO: valid pixel hits: 0
[09:53:33.688] <TB3> INFO: Event errors: 0
[09:53:33.688] <TB3> INFO: start marker: 0
[09:53:33.688] <TB3> INFO: stop marker: 0
[09:53:33.688] <TB3> INFO: overflow: 0
[09:53:33.688] <TB3> INFO: invalid 5bit words: 0
[09:53:33.688] <TB3> INFO: invalid XOR eye diagram: 0
[09:53:33.688] <TB3> INFO: frame (failed synchr.): 0
[09:53:33.688] <TB3> INFO: idle data (no TBM trl): 0
[09:53:33.688] <TB3> INFO: no data (only TBM hdr): 0
[09:53:33.688] <TB3> INFO: TBM errors: 0
[09:53:33.688] <TB3> INFO: flawed TBM headers: 0
[09:53:33.688] <TB3> INFO: flawed TBM trailers: 0
[09:53:33.688] <TB3> INFO: event ID mismatches: 0
[09:53:33.688] <TB3> INFO: ROC errors: 0
[09:53:33.688] <TB3> INFO: missing ROC header(s): 0
[09:53:33.688] <TB3> INFO: misplaced readback start: 0
[09:53:33.688] <TB3> INFO: Pixel decoding errors: 0
[09:53:33.688] <TB3> INFO: pixel data incomplete: 0
[09:53:33.688] <TB3> INFO: pixel address: 0
[09:53:33.688] <TB3> INFO: pulse height fill bit: 0
[09:53:33.688] <TB3> INFO: buffer corruption: 0
[09:53:33.761] <TB3> INFO: ######################################################################
[09:53:33.761] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:53:33.761] <TB3> INFO: ######################################################################
[09:53:33.761] <TB3> INFO: ----------------------------------------------------------------------
[09:53:33.761] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:53:33.761] <TB3> INFO: ----------------------------------------------------------------------
[09:53:33.762] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[09:53:33.770] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:53:33.770] <TB3> INFO: run 1 of 1
[09:53:34.035] <TB3> INFO: Expecting 36608000 events.
[09:55:16.074] <TB3> INFO: 1089350 events read in total (101484ms).
[09:56:56.955] <TB3> INFO: 2163850 events read in total (202365ms).
[09:58:38.048] <TB3> INFO: 3234700 events read in total (303458ms).
[10:00:19.203] <TB3> INFO: 4305300 events read in total (404613ms).
[10:02:00.782] <TB3> INFO: 5373550 events read in total (506192ms).
[10:03:41.958] <TB3> INFO: 6440350 events read in total (607368ms).
[10:05:23.048] <TB3> INFO: 7504800 events read in total (708458ms).
[10:07:03.837] <TB3> INFO: 8572300 events read in total (809247ms).
[10:08:44.719] <TB3> INFO: 9635400 events read in total (910129ms).
[10:10:26.063] <TB3> INFO: 10699900 events read in total (1011473ms).
[10:12:07.523] <TB3> INFO: 11762250 events read in total (1112933ms).
[10:13:49.042] <TB3> INFO: 12827650 events read in total (1214452ms).
[10:15:30.124] <TB3> INFO: 13885850 events read in total (1315534ms).
[10:17:11.378] <TB3> INFO: 14947700 events read in total (1416788ms).
[10:18:52.406] <TB3> INFO: 16005450 events read in total (1517816ms).
[10:20:33.965] <TB3> INFO: 17067350 events read in total (1619375ms).
[10:22:14.841] <TB3> INFO: 18120550 events read in total (1720251ms).
[10:23:55.549] <TB3> INFO: 19168900 events read in total (1820959ms).
[10:25:36.265] <TB3> INFO: 20213400 events read in total (1921675ms).
[10:27:17.377] <TB3> INFO: 21262450 events read in total (2022787ms).
[10:28:58.171] <TB3> INFO: 22306600 events read in total (2123581ms).
[10:30:38.859] <TB3> INFO: 23349800 events read in total (2224269ms).
[10:32:19.743] <TB3> INFO: 24391150 events read in total (2325153ms).
[10:34:00.347] <TB3> INFO: 25434050 events read in total (2425757ms).
[10:35:41.203] <TB3> INFO: 26473650 events read in total (2526613ms).
[10:37:21.150] <TB3> INFO: 27513350 events read in total (2626560ms).
[10:39:01.827] <TB3> INFO: 28553150 events read in total (2727237ms).
[10:40:42.136] <TB3> INFO: 29592150 events read in total (2827546ms).
[10:42:23.007] <TB3> INFO: 30630150 events read in total (2928417ms).
[10:44:03.793] <TB3> INFO: 31668700 events read in total (3029203ms).
[10:45:44.401] <TB3> INFO: 32711200 events read in total (3129811ms).
[10:47:25.005] <TB3> INFO: 33751750 events read in total (3230415ms).
[10:49:05.646] <TB3> INFO: 34792900 events read in total (3331056ms).
[10:50:46.141] <TB3> INFO: 35833550 events read in total (3431551ms).
[10:51:58.477] <TB3> INFO: 36608000 events read in total (3503887ms).
[10:51:58.526] <TB3> INFO: Test took 3504755ms.
[10:51:58.857] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:00.633] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:02.489] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:04.375] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:06.220] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:07.921] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:09.610] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:11.249] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:12.955] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:14.649] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:16.279] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:18.002] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:19.783] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:21.456] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:23.137] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:24.824] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:52:26.554] <TB3> INFO: PixTestScurves::scurves() done
[10:52:26.554] <TB3> INFO: Vcal mean: 113.20 108.33 108.00 101.98 121.56 104.42 118.90 106.29 122.98 121.12 105.84 100.85 100.33 105.20 106.34 124.87
[10:52:26.554] <TB3> INFO: Vcal RMS: 5.64 6.19 5.16 5.39 6.50 4.68 6.05 5.24 6.10 6.11 5.13 4.76 5.13 5.07 4.68 5.95
[10:52:26.554] <TB3> INFO: PixTestScurves::fullTest() done, duration: 3532 seconds
[10:52:26.554] <TB3> INFO: Decoding statistics:
[10:52:26.554] <TB3> INFO: General information:
[10:52:26.554] <TB3> INFO: 16bit words read: 0
[10:52:26.554] <TB3> INFO: valid events total: 0
[10:52:26.554] <TB3> INFO: empty events: 0
[10:52:26.554] <TB3> INFO: valid events with pixels: 0
[10:52:26.554] <TB3> INFO: valid pixel hits: 0
[10:52:26.554] <TB3> INFO: Event errors: 0
[10:52:26.554] <TB3> INFO: start marker: 0
[10:52:26.554] <TB3> INFO: stop marker: 0
[10:52:26.554] <TB3> INFO: overflow: 0
[10:52:26.554] <TB3> INFO: invalid 5bit words: 0
[10:52:26.554] <TB3> INFO: invalid XOR eye diagram: 0
[10:52:26.554] <TB3> INFO: frame (failed synchr.): 0
[10:52:26.554] <TB3> INFO: idle data (no TBM trl): 0
[10:52:26.554] <TB3> INFO: no data (only TBM hdr): 0
[10:52:26.554] <TB3> INFO: TBM errors: 0
[10:52:26.554] <TB3> INFO: flawed TBM headers: 0
[10:52:26.554] <TB3> INFO: flawed TBM trailers: 0
[10:52:26.554] <TB3> INFO: event ID mismatches: 0
[10:52:26.554] <TB3> INFO: ROC errors: 0
[10:52:26.554] <TB3> INFO: missing ROC header(s): 0
[10:52:26.554] <TB3> INFO: misplaced readback start: 0
[10:52:26.554] <TB3> INFO: Pixel decoding errors: 0
[10:52:26.554] <TB3> INFO: pixel data incomplete: 0
[10:52:26.554] <TB3> INFO: pixel address: 0
[10:52:26.554] <TB3> INFO: pulse height fill bit: 0
[10:52:26.554] <TB3> INFO: buffer corruption: 0
[10:52:26.630] <TB3> INFO: ######################################################################
[10:52:26.630] <TB3> INFO: PixTestTrim::doTest()
[10:52:26.630] <TB3> INFO: ######################################################################
[10:52:26.631] <TB3> INFO: ----------------------------------------------------------------------
[10:52:26.631] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:52:26.631] <TB3> INFO: ----------------------------------------------------------------------
[10:52:26.709] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:52:26.709] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:52:26.717] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:52:26.717] <TB3> INFO: run 1 of 1
[10:52:26.979] <TB3> INFO: Expecting 6281600 events.
[10:54:16.887] <TB3> INFO: 1443420 events read in total (109353ms).
[10:56:06.715] <TB3> INFO: 2873280 events read in total (219181ms).
[10:57:56.266] <TB3> INFO: 4295910 events read in total (328733ms).
[10:59:45.450] <TB3> INFO: 5720980 events read in total (437916ms).
[11:00:28.608] <TB3> INFO: 6281600 events read in total (481074ms).
[11:00:28.648] <TB3> INFO: Test took 481932ms.
[11:00:48.800] <TB3> INFO: ROC 0 VthrComp = 107
[11:00:48.800] <TB3> INFO: ROC 1 VthrComp = 103
[11:00:48.801] <TB3> INFO: ROC 2 VthrComp = 106
[11:00:48.801] <TB3> INFO: ROC 3 VthrComp = 100
[11:00:48.801] <TB3> INFO: ROC 4 VthrComp = 117
[11:00:48.801] <TB3> INFO: ROC 5 VthrComp = 106
[11:00:48.801] <TB3> INFO: ROC 6 VthrComp = 113
[11:00:48.801] <TB3> INFO: ROC 7 VthrComp = 102
[11:00:48.801] <TB3> INFO: ROC 8 VthrComp = 120
[11:00:48.801] <TB3> INFO: ROC 9 VthrComp = 113
[11:00:48.801] <TB3> INFO: ROC 10 VthrComp = 105
[11:00:48.801] <TB3> INFO: ROC 11 VthrComp = 105
[11:00:48.801] <TB3> INFO: ROC 12 VthrComp = 108
[11:00:48.801] <TB3> INFO: ROC 13 VthrComp = 105
[11:00:48.802] <TB3> INFO: ROC 14 VthrComp = 107
[11:00:48.802] <TB3> INFO: ROC 15 VthrComp = 122
[11:00:48.802] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:00:48.802] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:00:48.809] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:00:48.809] <TB3> INFO: run 1 of 1
[11:00:49.078] <TB3> INFO: Expecting 6281600 events.
[11:02:31.718] <TB3> INFO: 897850 events read in total (102084ms).
[11:04:14.189] <TB3> INFO: 1790590 events read in total (204555ms).
[11:05:56.731] <TB3> INFO: 2684850 events read in total (307097ms).
[11:07:38.081] <TB3> INFO: 3573510 events read in total (408447ms).
[11:09:20.977] <TB3> INFO: 4453310 events read in total (511343ms).
[11:11:03.269] <TB3> INFO: 5328440 events read in total (613635ms).
[11:12:45.321] <TB3> INFO: 6203800 events read in total (715687ms).
[11:12:54.794] <TB3> INFO: 6281600 events read in total (725160ms).
[11:12:54.846] <TB3> INFO: Test took 726036ms.
[11:13:20.086] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 64.0638 for pixel 5/63 mean/min/max = 48.8467/33.5456/64.1479
[11:13:20.086] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 60.7888 for pixel 0/3 mean/min/max = 46.7977/32.4529/61.1425
[11:13:20.086] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 61.5959 for pixel 3/76 mean/min/max = 47.6495/33.5866/61.7124
[11:13:20.086] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.7038 for pixel 17/8 mean/min/max = 45.3941/32.7843/58.0038
[11:13:20.087] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 60.7009 for pixel 3/10 mean/min/max = 45.8557/30.9897/60.7218
[11:13:20.087] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 56.7998 for pixel 7/11 mean/min/max = 45.8694/34.732/57.0069
[11:13:20.087] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 63.6189 for pixel 21/11 mean/min/max = 47.6583/31.6467/63.6699
[11:13:20.088] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 60.7467 for pixel 12/2 mean/min/max = 46.8943/32.947/60.8415
[11:13:20.088] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 67.1342 for pixel 5/72 mean/min/max = 49.2226/30.8574/67.5878
[11:13:20.088] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 64.092 for pixel 0/13 mean/min/max = 47.6922/31.1358/64.2486
[11:13:20.088] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 62.9341 for pixel 0/79 mean/min/max = 48.5348/34.0655/63.0042
[11:13:20.089] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 56.1307 for pixel 37/52 mean/min/max = 45.3465/34.5218/56.1712
[11:13:20.089] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.5842 for pixel 21/74 mean/min/max = 46.0198/34.3301/57.7096
[11:13:20.089] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 62.0894 for pixel 14/3 mean/min/max = 48.3936/34.6712/62.116
[11:13:20.090] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.1533 for pixel 22/20 mean/min/max = 46.9426/34.6436/59.2416
[11:13:20.090] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.761 for pixel 25/1 mean/min/max = 45.9019/33.0405/58.7633
[11:13:20.090] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:13:20.179] <TB3> INFO: Expecting 514560 events.
[11:13:51.190] <TB3> INFO: 514560 events read in total (30456ms).
[11:13:51.196] <TB3> INFO: Expecting 514560 events.
[11:14:22.004] <TB3> INFO: 514560 events read in total (30407ms).
[11:14:22.014] <TB3> INFO: Expecting 514560 events.
[11:14:52.770] <TB3> INFO: 514560 events read in total (30355ms).
[11:14:52.786] <TB3> INFO: Expecting 514560 events.
[11:15:23.264] <TB3> INFO: 514560 events read in total (30077ms).
[11:15:23.282] <TB3> INFO: Expecting 514560 events.
[11:15:54.113] <TB3> INFO: 514560 events read in total (30430ms).
[11:15:54.132] <TB3> INFO: Expecting 514560 events.
[11:16:25.076] <TB3> INFO: 514560 events read in total (30543ms).
[11:16:25.101] <TB3> INFO: Expecting 514560 events.
[11:16:55.884] <TB3> INFO: 514560 events read in total (30382ms).
[11:16:55.909] <TB3> INFO: Expecting 514560 events.
[11:17:26.837] <TB3> INFO: 514560 events read in total (30527ms).
[11:17:26.867] <TB3> INFO: Expecting 514560 events.
[11:17:57.885] <TB3> INFO: 514560 events read in total (30617ms).
[11:17:57.916] <TB3> INFO: Expecting 514560 events.
[11:18:28.911] <TB3> INFO: 514560 events read in total (30594ms).
[11:18:28.948] <TB3> INFO: Expecting 514560 events.
[11:18:59.639] <TB3> INFO: 514560 events read in total (30290ms).
[11:18:59.677] <TB3> INFO: Expecting 514560 events.
[11:19:30.760] <TB3> INFO: 514560 events read in total (30682ms).
[11:19:30.806] <TB3> INFO: Expecting 514560 events.
[11:20:01.922] <TB3> INFO: 514560 events read in total (30715ms).
[11:20:01.968] <TB3> INFO: Expecting 514560 events.
[11:20:33.081] <TB3> INFO: 514560 events read in total (30712ms).
[11:20:33.132] <TB3> INFO: Expecting 514560 events.
[11:21:03.701] <TB3> INFO: 514560 events read in total (30168ms).
[11:21:03.750] <TB3> INFO: Expecting 514560 events.
[11:21:34.420] <TB3> INFO: 514560 events read in total (30269ms).
[11:21:34.474] <TB3> INFO: Test took 494384ms.
[11:21:35.495] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:21:35.503] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:21:35.503] <TB3> INFO: run 1 of 1
[11:21:35.771] <TB3> INFO: Expecting 6281600 events.
[11:23:19.233] <TB3> INFO: 874520 events read in total (102906ms).
[11:25:01.776] <TB3> INFO: 1744980 events read in total (205449ms).
[11:26:44.781] <TB3> INFO: 2616480 events read in total (308454ms).
[11:28:27.194] <TB3> INFO: 3483500 events read in total (410867ms).
[11:30:09.556] <TB3> INFO: 4341870 events read in total (513229ms).
[11:31:51.761] <TB3> INFO: 5195650 events read in total (615434ms).
[11:33:33.274] <TB3> INFO: 6049530 events read in total (716947ms).
[11:34:01.380] <TB3> INFO: 6281600 events read in total (745053ms).
[11:34:01.453] <TB3> INFO: Test took 745950ms.
[11:34:26.178] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 6.863257 .. 87.968153
[11:34:26.449] <TB3> INFO: Expecting 208000 events.
[11:34:54.529] <TB3> INFO: 208000 events read in total (27511ms).
[11:34:54.529] <TB3> INFO: Test took 28350ms.
[11:34:54.618] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 6 .. 97 (-1/-1) hits flags = 528 (plus default)
[11:34:54.627] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:34:54.627] <TB3> INFO: run 1 of 1
[11:34:54.887] <TB3> INFO: Expecting 3827200 events.
[11:36:37.651] <TB3> INFO: 940700 events read in total (102208ms).
[11:38:20.277] <TB3> INFO: 1882590 events read in total (204834ms).
[11:40:04.565] <TB3> INFO: 2821070 events read in total (309122ms).
[11:41:47.736] <TB3> INFO: 3751390 events read in total (412293ms).
[11:41:56.307] <TB3> INFO: 3827200 events read in total (420864ms).
[11:41:56.341] <TB3> INFO: Test took 421714ms.
[11:42:14.838] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 23.199345 .. 51.473014
[11:42:15.100] <TB3> INFO: Expecting 208000 events.
[11:42:43.534] <TB3> INFO: 208000 events read in total (27878ms).
[11:42:43.535] <TB3> INFO: Test took 28696ms.
[11:42:43.621] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 13 .. 61 (-1/-1) hits flags = 528 (plus default)
[11:42:43.629] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:42:43.629] <TB3> INFO: run 1 of 1
[11:42:43.901] <TB3> INFO: Expecting 2038400 events.
[11:44:30.380] <TB3> INFO: 1041250 events read in total (105924ms).
[11:46:11.065] <TB3> INFO: 2038400 events read in total (206610ms).
[11:46:11.091] <TB3> INFO: Test took 207462ms.
[11:46:25.517] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 23.134974 .. 45.524497
[11:46:25.778] <TB3> INFO: Expecting 208000 events.
[11:46:53.761] <TB3> INFO: 208000 events read in total (27427ms).
[11:46:53.762] <TB3> INFO: Test took 28243ms.
[11:46:53.846] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 13 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:46:53.854] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:46:53.854] <TB3> INFO: run 1 of 1
[11:46:54.115] <TB3> INFO: Expecting 1788800 events.
[11:48:40.936] <TB3> INFO: 1098070 events read in total (106266ms).
[11:49:48.178] <TB3> INFO: 1788800 events read in total (173508ms).
[11:49:48.198] <TB3> INFO: Test took 174344ms.
[11:50:00.998] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.499253 .. 44.214753
[11:50:01.266] <TB3> INFO: Expecting 208000 events.
[11:50:29.310] <TB3> INFO: 208000 events read in total (27489ms).
[11:50:29.310] <TB3> INFO: Test took 28311ms.
[11:50:29.397] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 54 (-1/-1) hits flags = 528 (plus default)
[11:50:29.405] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:50:29.405] <TB3> INFO: run 1 of 1
[11:50:29.684] <TB3> INFO: Expecting 1705600 events.
[11:52:16.440] <TB3> INFO: 1101580 events read in total (106201ms).
[11:53:15.472] <TB3> INFO: 1705600 events read in total (165233ms).
[11:53:15.490] <TB3> INFO: Test took 166085ms.
[11:53:28.564] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:53:28.564] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:53:28.572] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:53:28.572] <TB3> INFO: run 1 of 1
[11:53:28.832] <TB3> INFO: Expecting 1705600 events.
[11:55:15.459] <TB3> INFO: 1076910 events read in total (106072ms).
[11:56:17.559] <TB3> INFO: 1705600 events read in total (168172ms).
[11:56:17.578] <TB3> INFO: Test took 169006ms.
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C0.dat
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C1.dat
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C2.dat
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C3.dat
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C4.dat
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C5.dat
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C6.dat
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C7.dat
[11:56:30.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C8.dat
[11:56:30.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C9.dat
[11:56:30.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C10.dat
[11:56:30.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C11.dat
[11:56:30.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C12.dat
[11:56:30.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C13.dat
[11:56:30.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C14.dat
[11:56:30.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C15.dat
[11:56:30.653] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C0.dat
[11:56:30.660] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C1.dat
[11:56:30.669] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C2.dat
[11:56:30.676] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C3.dat
[11:56:30.682] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C4.dat
[11:56:30.688] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C5.dat
[11:56:30.694] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C6.dat
[11:56:30.700] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C7.dat
[11:56:30.706] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C8.dat
[11:56:30.712] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C9.dat
[11:56:30.718] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C10.dat
[11:56:30.724] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C11.dat
[11:56:30.730] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C12.dat
[11:56:30.735] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C13.dat
[11:56:30.741] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C14.dat
[11:56:30.747] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//trimParameters35_C15.dat
[11:56:30.754] <TB3> INFO: PixTestTrim::trimTest() done
[11:56:30.754] <TB3> INFO: vtrim: 130 116 116 94 110 109 116 118 141 129 117 110 122 137 107 104
[11:56:30.754] <TB3> INFO: vthrcomp: 107 103 106 100 117 106 113 102 120 113 105 105 108 105 107 122
[11:56:30.754] <TB3> INFO: vcal mean: 35.01 34.95 35.00 35.00 34.96 35.00 34.96 35.01 34.95 34.96 35.03 34.90 35.00 34.96 34.97 34.96
[11:56:30.754] <TB3> INFO: vcal RMS: 1.12 1.32 0.88 1.07 1.10 0.83 0.94 1.06 1.09 0.99 0.89 0.84 0.82 0.89 0.85 1.16
[11:56:30.754] <TB3> INFO: bits mean: 9.00 8.88 9.13 9.48 9.65 8.93 9.43 9.31 9.32 9.50 8.32 9.49 9.19 8.83 8.86 9.26
[11:56:30.754] <TB3> INFO: bits RMS: 2.45 2.88 2.51 2.64 2.76 2.57 2.62 2.57 2.70 2.74 2.72 2.37 2.48 2.42 2.49 2.62
[11:56:30.760] <TB3> INFO: ----------------------------------------------------------------------
[11:56:30.760] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:56:30.760] <TB3> INFO: ----------------------------------------------------------------------
[11:56:30.762] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:56:30.771] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:56:30.771] <TB3> INFO: run 1 of 1
[11:56:31.030] <TB3> INFO: Expecting 4160000 events.
[11:58:24.986] <TB3> INFO: 1293820 events read in total (113401ms).
[12:00:17.362] <TB3> INFO: 2559785 events read in total (225777ms).
[12:02:09.240] <TB3> INFO: 3808600 events read in total (337655ms).
[12:02:40.769] <TB3> INFO: 4160000 events read in total (369184ms).
[12:02:40.826] <TB3> INFO: Test took 370056ms.
[12:03:08.007] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 200 (-1/-1) hits flags = 528 (plus default)
[12:03:08.019] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:03:08.019] <TB3> INFO: run 1 of 1
[12:03:08.281] <TB3> INFO: Expecting 4180800 events.
[12:05:01.071] <TB3> INFO: 1224985 events read in total (112234ms).
[12:06:52.622] <TB3> INFO: 2427810 events read in total (223786ms).
[12:08:43.882] <TB3> INFO: 3612945 events read in total (335045ms).
[12:09:37.214] <TB3> INFO: 4180800 events read in total (388377ms).
[12:09:37.287] <TB3> INFO: Test took 389268ms.
[12:10:06.691] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 194 (-1/-1) hits flags = 528 (plus default)
[12:10:06.700] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:10:06.700] <TB3> INFO: run 1 of 1
[12:10:06.970] <TB3> INFO: Expecting 4056000 events.
[12:11:59.814] <TB3> INFO: 1247915 events read in total (112288ms).
[12:13:51.314] <TB3> INFO: 2470530 events read in total (223788ms).
[12:15:42.824] <TB3> INFO: 3676560 events read in total (335298ms).
[12:16:17.948] <TB3> INFO: 4056000 events read in total (370422ms).
[12:16:18.005] <TB3> INFO: Test took 371305ms.
[12:16:45.623] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 195 (-1/-1) hits flags = 528 (plus default)
[12:16:45.632] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:16:45.632] <TB3> INFO: run 1 of 1
[12:16:45.896] <TB3> INFO: Expecting 4076800 events.
[12:18:39.054] <TB3> INFO: 1243255 events read in total (112602ms).
[12:20:30.700] <TB3> INFO: 2461185 events read in total (224248ms).
[12:22:22.449] <TB3> INFO: 3662975 events read in total (335997ms).
[12:23:00.721] <TB3> INFO: 4076800 events read in total (374269ms).
[12:23:00.802] <TB3> INFO: Test took 375170ms.
[12:23:27.324] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 195 (-1/-1) hits flags = 528 (plus default)
[12:23:27.332] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:23:27.332] <TB3> INFO: run 1 of 1
[12:23:27.591] <TB3> INFO: Expecting 4076800 events.
[12:25:20.516] <TB3> INFO: 1242070 events read in total (112369ms).
[12:27:14.056] <TB3> INFO: 2459735 events read in total (225909ms).
[12:29:06.793] <TB3> INFO: 3660600 events read in total (338646ms).
[12:29:45.785] <TB3> INFO: 4076800 events read in total (377638ms).
[12:29:45.844] <TB3> INFO: Test took 378513ms.
[12:30:14.574] <TB3> INFO: PixTestTrim::trimBitTest() done
[12:30:14.575] <TB3> INFO: PixTestTrim::doTest() done, duration: 5867 seconds
[12:30:14.575] <TB3> INFO: Decoding statistics:
[12:30:14.575] <TB3> INFO: General information:
[12:30:14.575] <TB3> INFO: 16bit words read: 0
[12:30:14.575] <TB3> INFO: valid events total: 0
[12:30:14.575] <TB3> INFO: empty events: 0
[12:30:14.575] <TB3> INFO: valid events with pixels: 0
[12:30:14.575] <TB3> INFO: valid pixel hits: 0
[12:30:14.575] <TB3> INFO: Event errors: 0
[12:30:14.575] <TB3> INFO: start marker: 0
[12:30:14.575] <TB3> INFO: stop marker: 0
[12:30:14.575] <TB3> INFO: overflow: 0
[12:30:14.575] <TB3> INFO: invalid 5bit words: 0
[12:30:14.575] <TB3> INFO: invalid XOR eye diagram: 0
[12:30:14.575] <TB3> INFO: frame (failed synchr.): 0
[12:30:14.575] <TB3> INFO: idle data (no TBM trl): 0
[12:30:14.575] <TB3> INFO: no data (only TBM hdr): 0
[12:30:14.576] <TB3> INFO: TBM errors: 0
[12:30:14.576] <TB3> INFO: flawed TBM headers: 0
[12:30:14.576] <TB3> INFO: flawed TBM trailers: 0
[12:30:14.576] <TB3> INFO: event ID mismatches: 0
[12:30:14.576] <TB3> INFO: ROC errors: 0
[12:30:14.576] <TB3> INFO: missing ROC header(s): 0
[12:30:14.576] <TB3> INFO: misplaced readback start: 0
[12:30:14.576] <TB3> INFO: Pixel decoding errors: 0
[12:30:14.576] <TB3> INFO: pixel data incomplete: 0
[12:30:14.576] <TB3> INFO: pixel address: 0
[12:30:14.576] <TB3> INFO: pulse height fill bit: 0
[12:30:14.576] <TB3> INFO: buffer corruption: 0
[12:30:15.313] <TB3> INFO: ######################################################################
[12:30:15.313] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[12:30:15.313] <TB3> INFO: ######################################################################
[12:30:15.583] <TB3> INFO: Expecting 41600 events.
[12:30:22.662] <TB3> INFO: 41600 events read in total (6523ms).
[12:30:22.663] <TB3> INFO: Test took 7348ms.
[12:30:23.179] <TB3> INFO: Expecting 41600 events.
[12:30:30.342] <TB3> INFO: 41600 events read in total (6607ms).
[12:30:30.343] <TB3> INFO: Test took 7430ms.
[12:30:30.668] <TB3> INFO: Expecting 41600 events.
[12:30:37.757] <TB3> INFO: 41600 events read in total (6534ms).
[12:30:37.757] <TB3> INFO: Test took 7348ms.
[12:30:38.051] <TB3> INFO: Expecting 2560 events.
[12:30:38.909] <TB3> INFO: 2560 events read in total (302ms).
[12:30:38.909] <TB3> INFO: Test took 1139ms.
[12:30:39.277] <TB3> INFO: Expecting 2560 events.
[12:30:40.145] <TB3> INFO: 2560 events read in total (312ms).
[12:30:40.145] <TB3> INFO: Test took 1235ms.
[12:30:40.511] <TB3> INFO: Expecting 2560 events.
[12:30:41.373] <TB3> INFO: 2560 events read in total (306ms).
[12:30:41.373] <TB3> INFO: Test took 1227ms.
[12:30:41.740] <TB3> INFO: Expecting 2560 events.
[12:30:42.602] <TB3> INFO: 2560 events read in total (307ms).
[12:30:42.602] <TB3> INFO: Test took 1228ms.
[12:30:42.969] <TB3> INFO: Expecting 2560 events.
[12:30:43.836] <TB3> INFO: 2560 events read in total (305ms).
[12:30:43.837] <TB3> INFO: Test took 1234ms.
[12:30:44.204] <TB3> INFO: Expecting 2560 events.
[12:30:45.065] <TB3> INFO: 2560 events read in total (305ms).
[12:30:45.065] <TB3> INFO: Test took 1228ms.
[12:30:45.432] <TB3> INFO: Expecting 2560 events.
[12:30:46.291] <TB3> INFO: 2560 events read in total (303ms).
[12:30:46.291] <TB3> INFO: Test took 1226ms.
[12:30:46.658] <TB3> INFO: Expecting 2560 events.
[12:30:47.523] <TB3> INFO: 2560 events read in total (310ms).
[12:30:47.523] <TB3> INFO: Test took 1231ms.
[12:30:47.890] <TB3> INFO: Expecting 2560 events.
[12:30:48.750] <TB3> INFO: 2560 events read in total (304ms).
[12:30:48.750] <TB3> INFO: Test took 1227ms.
[12:30:49.116] <TB3> INFO: Expecting 2560 events.
[12:30:49.978] <TB3> INFO: 2560 events read in total (306ms).
[12:30:49.978] <TB3> INFO: Test took 1227ms.
[12:30:50.346] <TB3> INFO: Expecting 2560 events.
[12:30:51.209] <TB3> INFO: 2560 events read in total (308ms).
[12:30:51.210] <TB3> INFO: Test took 1231ms.
[12:30:51.576] <TB3> INFO: Expecting 2560 events.
[12:30:52.440] <TB3> INFO: 2560 events read in total (308ms).
[12:30:52.440] <TB3> INFO: Test took 1230ms.
[12:30:52.807] <TB3> INFO: Expecting 2560 events.
[12:30:53.671] <TB3> INFO: 2560 events read in total (309ms).
[12:30:53.671] <TB3> INFO: Test took 1231ms.
[12:30:54.038] <TB3> INFO: Expecting 2560 events.
[12:30:54.901] <TB3> INFO: 2560 events read in total (307ms).
[12:30:54.901] <TB3> INFO: Test took 1230ms.
[12:30:55.269] <TB3> INFO: Expecting 2560 events.
[12:30:56.131] <TB3> INFO: 2560 events read in total (307ms).
[12:30:56.131] <TB3> INFO: Test took 1229ms.
[12:30:56.497] <TB3> INFO: Expecting 2560 events.
[12:30:57.361] <TB3> INFO: 2560 events read in total (308ms).
[12:30:57.361] <TB3> INFO: Test took 1230ms.
[12:30:57.363] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:57.730] <TB3> INFO: Expecting 655360 events.
[12:31:37.774] <TB3> INFO: 655360 events read in total (39488ms).
[12:31:37.792] <TB3> INFO: Expecting 655360 events.
[12:32:18.035] <TB3> INFO: 655360 events read in total (39842ms).
[12:32:18.052] <TB3> INFO: Expecting 655360 events.
[12:32:58.267] <TB3> INFO: 655360 events read in total (39814ms).
[12:32:58.286] <TB3> INFO: Expecting 655360 events.
[12:33:38.579] <TB3> INFO: 655360 events read in total (39892ms).
[12:33:38.601] <TB3> INFO: Expecting 655360 events.
[12:34:18.837] <TB3> INFO: 655360 events read in total (39835ms).
[12:34:18.863] <TB3> INFO: Expecting 655360 events.
[12:34:59.192] <TB3> INFO: 655360 events read in total (39928ms).
[12:34:59.223] <TB3> INFO: Expecting 655360 events.
[12:35:39.557] <TB3> INFO: 655360 events read in total (39933ms).
[12:35:39.592] <TB3> INFO: Expecting 655360 events.
[12:36:19.658] <TB3> INFO: 655360 events read in total (39665ms).
[12:36:19.699] <TB3> INFO: Expecting 655360 events.
[12:36:59.629] <TB3> INFO: 655360 events read in total (39529ms).
[12:36:59.677] <TB3> INFO: Expecting 655360 events.
[12:37:39.595] <TB3> INFO: 655360 events read in total (39517ms).
[12:37:39.642] <TB3> INFO: Expecting 655360 events.
[12:38:19.941] <TB3> INFO: 655360 events read in total (39898ms).
[12:38:19.996] <TB3> INFO: Expecting 655360 events.
[12:39:00.266] <TB3> INFO: 655360 events read in total (39869ms).
[12:39:00.327] <TB3> INFO: Expecting 655360 events.
[12:39:40.340] <TB3> INFO: 655360 events read in total (39612ms).
[12:39:40.418] <TB3> INFO: Expecting 655360 events.
[12:40:20.750] <TB3> INFO: 655360 events read in total (39931ms).
[12:40:20.825] <TB3> INFO: Expecting 655360 events.
[12:41:01.027] <TB3> INFO: 655360 events read in total (39801ms).
[12:41:01.101] <TB3> INFO: Expecting 655360 events.
[12:41:41.504] <TB3> INFO: 655360 events read in total (40002ms).
[12:41:41.571] <TB3> INFO: Test took 644208ms.
[12:41:41.655] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:41:41.883] <TB3> INFO: Expecting 655360 events.
[12:42:22.171] <TB3> INFO: 655360 events read in total (39732ms).
[12:42:22.182] <TB3> INFO: Expecting 655360 events.
[12:43:01.979] <TB3> INFO: 655360 events read in total (39396ms).
[12:43:01.994] <TB3> INFO: Expecting 655360 events.
[12:43:41.856] <TB3> INFO: 655360 events read in total (39461ms).
[12:43:41.876] <TB3> INFO: Expecting 655360 events.
[12:44:21.907] <TB3> INFO: 655360 events read in total (39630ms).
[12:44:21.933] <TB3> INFO: Expecting 655360 events.
[12:45:02.059] <TB3> INFO: 655360 events read in total (39726ms).
[12:45:02.092] <TB3> INFO: Expecting 655360 events.
[12:45:42.311] <TB3> INFO: 655360 events read in total (39818ms).
[12:45:42.345] <TB3> INFO: Expecting 655360 events.
[12:46:22.543] <TB3> INFO: 655360 events read in total (39797ms).
[12:46:22.584] <TB3> INFO: Expecting 655360 events.
[12:47:02.494] <TB3> INFO: 655360 events read in total (39509ms).
[12:47:02.535] <TB3> INFO: Expecting 655360 events.
[12:47:42.386] <TB3> INFO: 655360 events read in total (39450ms).
[12:47:42.431] <TB3> INFO: Expecting 655360 events.
[12:48:22.424] <TB3> INFO: 655360 events read in total (39592ms).
[12:48:22.472] <TB3> INFO: Expecting 655360 events.
[12:49:02.855] <TB3> INFO: 655360 events read in total (39982ms).
[12:49:02.906] <TB3> INFO: Expecting 655360 events.
[12:49:43.031] <TB3> INFO: 655360 events read in total (39724ms).
[12:49:43.088] <TB3> INFO: Expecting 655360 events.
[12:50:23.035] <TB3> INFO: 655360 events read in total (39546ms).
[12:50:23.116] <TB3> INFO: Expecting 655360 events.
[12:51:03.491] <TB3> INFO: 655360 events read in total (39974ms).
[12:51:03.560] <TB3> INFO: Expecting 655360 events.
[12:51:43.346] <TB3> INFO: 655360 events read in total (39385ms).
[12:51:43.425] <TB3> INFO: Expecting 655360 events.
[12:52:22.846] <TB3> INFO: 655360 events read in total (39020ms).
[12:52:22.936] <TB3> INFO: Test took 641282ms.
[12:52:23.128] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.135] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.143] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.151] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.158] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.166] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.174] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.182] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.190] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.198] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.206] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.214] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.222] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[12:52:23.230] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[12:52:23.239] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[12:52:23.246] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[12:52:23.252] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[12:52:23.259] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[12:52:23.266] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[12:52:23.273] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.280] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[12:52:23.286] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[12:52:23.293] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[12:52:23.300] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[12:52:23.307] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[12:52:23.313] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[12:52:23.320] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[12:52:23.327] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[12:52:23.334] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[12:52:23.342] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[12:52:23.349] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.355] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.362] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[12:52:23.399] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C0.dat
[12:52:23.399] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C1.dat
[12:52:23.399] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C2.dat
[12:52:23.399] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C3.dat
[12:52:23.399] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C4.dat
[12:52:23.399] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C5.dat
[12:52:23.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C6.dat
[12:52:23.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C7.dat
[12:52:23.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C8.dat
[12:52:23.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C9.dat
[12:52:23.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C10.dat
[12:52:23.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C11.dat
[12:52:23.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C12.dat
[12:52:23.401] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C13.dat
[12:52:23.401] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C14.dat
[12:52:23.401] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//dacParameters35_C15.dat
[12:52:23.730] <TB3> INFO: Expecting 41600 events.
[12:52:30.535] <TB3> INFO: 41600 events read in total (6250ms).
[12:52:30.536] <TB3> INFO: Test took 7132ms.
[12:52:30.972] <TB3> INFO: Expecting 41600 events.
[12:52:37.431] <TB3> INFO: 41600 events read in total (5903ms).
[12:52:37.432] <TB3> INFO: Test took 6719ms.
[12:52:37.942] <TB3> INFO: Expecting 41600 events.
[12:52:44.688] <TB3> INFO: 41600 events read in total (6191ms).
[12:52:44.688] <TB3> INFO: Test took 7027ms.
[12:52:44.917] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:45.005] <TB3> INFO: Expecting 2560 events.
[12:52:45.861] <TB3> INFO: 2560 events read in total (301ms).
[12:52:45.861] <TB3> INFO: Test took 944ms.
[12:52:45.863] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:46.229] <TB3> INFO: Expecting 2560 events.
[12:52:47.086] <TB3> INFO: 2560 events read in total (302ms).
[12:52:47.086] <TB3> INFO: Test took 1223ms.
[12:52:47.088] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:47.454] <TB3> INFO: Expecting 2560 events.
[12:52:48.313] <TB3> INFO: 2560 events read in total (303ms).
[12:52:48.313] <TB3> INFO: Test took 1225ms.
[12:52:48.315] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:48.682] <TB3> INFO: Expecting 2560 events.
[12:52:49.541] <TB3> INFO: 2560 events read in total (304ms).
[12:52:49.542] <TB3> INFO: Test took 1227ms.
[12:52:49.544] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:49.908] <TB3> INFO: Expecting 2560 events.
[12:52:50.765] <TB3> INFO: 2560 events read in total (302ms).
[12:52:50.766] <TB3> INFO: Test took 1222ms.
[12:52:50.767] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:51.134] <TB3> INFO: Expecting 2560 events.
[12:52:51.992] <TB3> INFO: 2560 events read in total (303ms).
[12:52:51.992] <TB3> INFO: Test took 1225ms.
[12:52:51.994] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:52.360] <TB3> INFO: Expecting 2560 events.
[12:52:53.218] <TB3> INFO: 2560 events read in total (303ms).
[12:52:53.218] <TB3> INFO: Test took 1224ms.
[12:52:53.220] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:53.586] <TB3> INFO: Expecting 2560 events.
[12:52:54.446] <TB3> INFO: 2560 events read in total (305ms).
[12:52:54.446] <TB3> INFO: Test took 1226ms.
[12:52:54.449] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:54.814] <TB3> INFO: Expecting 2560 events.
[12:52:55.676] <TB3> INFO: 2560 events read in total (307ms).
[12:52:55.677] <TB3> INFO: Test took 1228ms.
[12:52:55.678] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:56.045] <TB3> INFO: Expecting 2560 events.
[12:52:56.904] <TB3> INFO: 2560 events read in total (304ms).
[12:52:56.904] <TB3> INFO: Test took 1226ms.
[12:52:56.906] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:57.272] <TB3> INFO: Expecting 2560 events.
[12:52:58.134] <TB3> INFO: 2560 events read in total (307ms).
[12:52:58.135] <TB3> INFO: Test took 1229ms.
[12:52:58.137] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:58.502] <TB3> INFO: Expecting 2560 events.
[12:52:59.366] <TB3> INFO: 2560 events read in total (308ms).
[12:52:59.366] <TB3> INFO: Test took 1229ms.
[12:52:59.369] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:52:59.733] <TB3> INFO: Expecting 2560 events.
[12:53:00.595] <TB3> INFO: 2560 events read in total (306ms).
[12:53:00.596] <TB3> INFO: Test took 1227ms.
[12:53:00.598] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:00.963] <TB3> INFO: Expecting 2560 events.
[12:53:01.822] <TB3> INFO: 2560 events read in total (304ms).
[12:53:01.822] <TB3> INFO: Test took 1224ms.
[12:53:01.827] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:02.189] <TB3> INFO: Expecting 2560 events.
[12:53:03.048] <TB3> INFO: 2560 events read in total (303ms).
[12:53:03.049] <TB3> INFO: Test took 1222ms.
[12:53:03.051] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:03.418] <TB3> INFO: Expecting 2560 events.
[12:53:04.277] <TB3> INFO: 2560 events read in total (303ms).
[12:53:04.277] <TB3> INFO: Test took 1226ms.
[12:53:04.279] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:04.645] <TB3> INFO: Expecting 2560 events.
[12:53:05.505] <TB3> INFO: 2560 events read in total (304ms).
[12:53:05.505] <TB3> INFO: Test took 1226ms.
[12:53:05.507] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:05.872] <TB3> INFO: Expecting 2560 events.
[12:53:06.734] <TB3> INFO: 2560 events read in total (306ms).
[12:53:06.734] <TB3> INFO: Test took 1227ms.
[12:53:06.737] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:07.101] <TB3> INFO: Expecting 2560 events.
[12:53:07.964] <TB3> INFO: 2560 events read in total (307ms).
[12:53:07.965] <TB3> INFO: Test took 1228ms.
[12:53:07.966] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:08.335] <TB3> INFO: Expecting 2560 events.
[12:53:09.195] <TB3> INFO: 2560 events read in total (305ms).
[12:53:09.195] <TB3> INFO: Test took 1230ms.
[12:53:09.198] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:09.562] <TB3> INFO: Expecting 2560 events.
[12:53:10.425] <TB3> INFO: 2560 events read in total (307ms).
[12:53:10.426] <TB3> INFO: Test took 1228ms.
[12:53:10.429] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:10.791] <TB3> INFO: Expecting 2560 events.
[12:53:11.656] <TB3> INFO: 2560 events read in total (309ms).
[12:53:11.656] <TB3> INFO: Test took 1228ms.
[12:53:11.659] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:12.024] <TB3> INFO: Expecting 2560 events.
[12:53:12.887] <TB3> INFO: 2560 events read in total (308ms).
[12:53:12.888] <TB3> INFO: Test took 1229ms.
[12:53:12.893] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:13.254] <TB3> INFO: Expecting 2560 events.
[12:53:14.116] <TB3> INFO: 2560 events read in total (306ms).
[12:53:14.117] <TB3> INFO: Test took 1224ms.
[12:53:14.121] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:14.485] <TB3> INFO: Expecting 2560 events.
[12:53:15.344] <TB3> INFO: 2560 events read in total (304ms).
[12:53:15.345] <TB3> INFO: Test took 1224ms.
[12:53:15.348] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:15.710] <TB3> INFO: Expecting 2560 events.
[12:53:16.570] <TB3> INFO: 2560 events read in total (304ms).
[12:53:16.570] <TB3> INFO: Test took 1222ms.
[12:53:16.572] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:16.938] <TB3> INFO: Expecting 2560 events.
[12:53:17.798] <TB3> INFO: 2560 events read in total (304ms).
[12:53:17.798] <TB3> INFO: Test took 1226ms.
[12:53:17.800] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:18.166] <TB3> INFO: Expecting 2560 events.
[12:53:19.030] <TB3> INFO: 2560 events read in total (308ms).
[12:53:19.030] <TB3> INFO: Test took 1230ms.
[12:53:19.032] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:19.398] <TB3> INFO: Expecting 2560 events.
[12:53:20.262] <TB3> INFO: 2560 events read in total (309ms).
[12:53:20.262] <TB3> INFO: Test took 1230ms.
[12:53:20.265] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:20.629] <TB3> INFO: Expecting 2560 events.
[12:53:21.490] <TB3> INFO: 2560 events read in total (305ms).
[12:53:21.491] <TB3> INFO: Test took 1227ms.
[12:53:21.492] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:21.858] <TB3> INFO: Expecting 2560 events.
[12:53:22.718] <TB3> INFO: 2560 events read in total (304ms).
[12:53:22.718] <TB3> INFO: Test took 1226ms.
[12:53:22.720] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:23.089] <TB3> INFO: Expecting 2560 events.
[12:53:23.953] <TB3> INFO: 2560 events read in total (308ms).
[12:53:23.954] <TB3> INFO: Test took 1234ms.
[12:53:24.494] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 1389 seconds
[12:53:24.494] <TB3> INFO: PH scale (per ROC): 66 67 66 65 69 76 59 63 60 71 70 76 80 67 67 73
[12:53:24.494] <TB3> INFO: PH offset (per ROC): 188 196 187 181 197 166 189 178 193 188 172 176 176 187 172 180
[12:53:24.499] <TB3> INFO: Decoding statistics:
[12:53:24.499] <TB3> INFO: General information:
[12:53:24.499] <TB3> INFO: 16bit words read: 66446
[12:53:24.499] <TB3> INFO: valid events total: 5120
[12:53:24.499] <TB3> INFO: empty events: 2617
[12:53:24.499] <TB3> INFO: valid events with pixels: 2503
[12:53:24.499] <TB3> INFO: valid pixel hits: 2503
[12:53:24.499] <TB3> INFO: Event errors: 0
[12:53:24.499] <TB3> INFO: start marker: 0
[12:53:24.499] <TB3> INFO: stop marker: 0
[12:53:24.499] <TB3> INFO: overflow: 0
[12:53:24.499] <TB3> INFO: invalid 5bit words: 0
[12:53:24.499] <TB3> INFO: invalid XOR eye diagram: 0
[12:53:24.499] <TB3> INFO: frame (failed synchr.): 0
[12:53:24.499] <TB3> INFO: idle data (no TBM trl): 0
[12:53:24.499] <TB3> INFO: no data (only TBM hdr): 0
[12:53:24.499] <TB3> INFO: TBM errors: 0
[12:53:24.499] <TB3> INFO: flawed TBM headers: 0
[12:53:24.499] <TB3> INFO: flawed TBM trailers: 0
[12:53:24.499] <TB3> INFO: event ID mismatches: 0
[12:53:24.499] <TB3> INFO: ROC errors: 0
[12:53:24.499] <TB3> INFO: missing ROC header(s): 0
[12:53:24.499] <TB3> INFO: misplaced readback start: 0
[12:53:24.499] <TB3> INFO: Pixel decoding errors: 0
[12:53:24.499] <TB3> INFO: pixel data incomplete: 0
[12:53:24.499] <TB3> INFO: pixel address: 0
[12:53:24.499] <TB3> INFO: pulse height fill bit: 0
[12:53:24.499] <TB3> INFO: buffer corruption: 0
[12:53:24.677] <TB3> INFO: ######################################################################
[12:53:24.677] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[12:53:24.677] <TB3> INFO: ######################################################################
[12:53:24.687] <TB3> INFO: scanning low vcal = 10
[12:53:24.967] <TB3> INFO: Expecting 41600 events.
[12:53:29.785] <TB3> INFO: 41600 events read in total (4263ms).
[12:53:29.785] <TB3> INFO: Test took 5098ms.
[12:53:29.787] <TB3> INFO: scanning low vcal = 20
[12:53:30.138] <TB3> INFO: Expecting 41600 events.
[12:53:34.985] <TB3> INFO: 41600 events read in total (4291ms).
[12:53:34.985] <TB3> INFO: Test took 5198ms.
[12:53:34.987] <TB3> INFO: scanning low vcal = 30
[12:53:35.338] <TB3> INFO: Expecting 41600 events.
[12:53:40.247] <TB3> INFO: 41600 events read in total (4353ms).
[12:53:40.248] <TB3> INFO: Test took 5261ms.
[12:53:40.250] <TB3> INFO: scanning low vcal = 40
[12:53:40.601] <TB3> INFO: Expecting 41600 events.
[12:53:47.849] <TB3> INFO: 41600 events read in total (6693ms).
[12:53:47.850] <TB3> INFO: Test took 7600ms.
[12:53:47.852] <TB3> INFO: scanning low vcal = 50
[12:53:48.140] <TB3> INFO: Expecting 41600 events.
[12:53:55.702] <TB3> INFO: 41600 events read in total (7007ms).
[12:53:55.703] <TB3> INFO: Test took 7850ms.
[12:53:55.706] <TB3> INFO: scanning low vcal = 60
[12:53:56.008] <TB3> INFO: Expecting 41600 events.
[12:54:03.575] <TB3> INFO: 41600 events read in total (7011ms).
[12:54:03.575] <TB3> INFO: Test took 7869ms.
[12:54:03.578] <TB3> INFO: scanning low vcal = 70
[12:54:03.876] <TB3> INFO: Expecting 41600 events.
[12:54:11.517] <TB3> INFO: 41600 events read in total (7085ms).
[12:54:11.518] <TB3> INFO: Test took 7940ms.
[12:54:11.521] <TB3> INFO: scanning low vcal = 80
[12:54:11.817] <TB3> INFO: Expecting 41600 events.
[12:54:19.451] <TB3> INFO: 41600 events read in total (7078ms).
[12:54:19.452] <TB3> INFO: Test took 7931ms.
[12:54:19.454] <TB3> INFO: scanning low vcal = 90
[12:54:19.752] <TB3> INFO: Expecting 41600 events.
[12:54:27.346] <TB3> INFO: 41600 events read in total (7038ms).
[12:54:27.346] <TB3> INFO: Test took 7892ms.
[12:54:27.349] <TB3> INFO: scanning low vcal = 100
[12:54:27.650] <TB3> INFO: Expecting 41600 events.
[12:54:35.241] <TB3> INFO: 41600 events read in total (7035ms).
[12:54:35.242] <TB3> INFO: Test took 7893ms.
[12:54:35.245] <TB3> INFO: scanning low vcal = 110
[12:54:35.526] <TB3> INFO: Expecting 41600 events.
[12:54:43.131] <TB3> INFO: 41600 events read in total (7050ms).
[12:54:43.131] <TB3> INFO: Test took 7886ms.
[12:54:43.134] <TB3> INFO: scanning low vcal = 120
[12:54:43.428] <TB3> INFO: Expecting 41600 events.
[12:54:51.040] <TB3> INFO: 41600 events read in total (7056ms).
[12:54:51.040] <TB3> INFO: Test took 7906ms.
[12:54:51.043] <TB3> INFO: scanning low vcal = 130
[12:54:51.342] <TB3> INFO: Expecting 41600 events.
[12:54:58.932] <TB3> INFO: 41600 events read in total (7034ms).
[12:54:58.933] <TB3> INFO: Test took 7890ms.
[12:54:58.936] <TB3> INFO: scanning low vcal = 140
[12:54:59.236] <TB3> INFO: Expecting 41600 events.
[12:55:06.847] <TB3> INFO: 41600 events read in total (7056ms).
[12:55:06.848] <TB3> INFO: Test took 7912ms.
[12:55:06.851] <TB3> INFO: scanning low vcal = 150
[12:55:07.134] <TB3> INFO: Expecting 41600 events.
[12:55:14.758] <TB3> INFO: 41600 events read in total (7069ms).
[12:55:14.759] <TB3> INFO: Test took 7908ms.
[12:55:14.761] <TB3> INFO: scanning low vcal = 160
[12:55:15.053] <TB3> INFO: Expecting 41600 events.
[12:55:22.666] <TB3> INFO: 41600 events read in total (7057ms).
[12:55:22.667] <TB3> INFO: Test took 7905ms.
[12:55:22.669] <TB3> INFO: scanning low vcal = 170
[12:55:22.969] <TB3> INFO: Expecting 41600 events.
[12:55:30.572] <TB3> INFO: 41600 events read in total (7048ms).
[12:55:30.573] <TB3> INFO: Test took 7903ms.
[12:55:30.576] <TB3> INFO: scanning low vcal = 180
[12:55:30.874] <TB3> INFO: Expecting 41600 events.
[12:55:38.505] <TB3> INFO: 41600 events read in total (7075ms).
[12:55:38.506] <TB3> INFO: Test took 7929ms.
[12:55:38.508] <TB3> INFO: scanning low vcal = 190
[12:55:38.800] <TB3> INFO: Expecting 41600 events.
[12:55:46.395] <TB3> INFO: 41600 events read in total (7039ms).
[12:55:46.396] <TB3> INFO: Test took 7888ms.
[12:55:46.399] <TB3> INFO: scanning low vcal = 200
[12:55:46.696] <TB3> INFO: Expecting 41600 events.
[12:55:54.346] <TB3> INFO: 41600 events read in total (7095ms).
[12:55:54.347] <TB3> INFO: Test took 7948ms.
[12:55:54.349] <TB3> INFO: scanning low vcal = 210
[12:55:54.661] <TB3> INFO: Expecting 41600 events.
[12:56:02.325] <TB3> INFO: 41600 events read in total (7108ms).
[12:56:02.326] <TB3> INFO: Test took 7977ms.
[12:56:02.328] <TB3> INFO: scanning low vcal = 220
[12:56:02.621] <TB3> INFO: Expecting 41600 events.
[12:56:10.262] <TB3> INFO: 41600 events read in total (7086ms).
[12:56:10.262] <TB3> INFO: Test took 7934ms.
[12:56:10.265] <TB3> INFO: scanning low vcal = 230
[12:56:10.569] <TB3> INFO: Expecting 41600 events.
[12:56:18.188] <TB3> INFO: 41600 events read in total (7064ms).
[12:56:18.188] <TB3> INFO: Test took 7923ms.
[12:56:18.191] <TB3> INFO: scanning low vcal = 240
[12:56:18.472] <TB3> INFO: Expecting 41600 events.
[12:56:26.085] <TB3> INFO: 41600 events read in total (7058ms).
[12:56:26.085] <TB3> INFO: Test took 7894ms.
[12:56:26.088] <TB3> INFO: scanning low vcal = 250
[12:56:26.403] <TB3> INFO: Expecting 41600 events.
[12:56:34.048] <TB3> INFO: 41600 events read in total (7090ms).
[12:56:34.049] <TB3> INFO: Test took 7961ms.
[12:56:34.053] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[12:56:34.363] <TB3> INFO: Expecting 41600 events.
[12:56:42.007] <TB3> INFO: 41600 events read in total (7088ms).
[12:56:42.008] <TB3> INFO: Test took 7955ms.
[12:56:42.011] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[12:56:42.298] <TB3> INFO: Expecting 41600 events.
[12:56:49.900] <TB3> INFO: 41600 events read in total (7046ms).
[12:56:49.900] <TB3> INFO: Test took 7889ms.
[12:56:49.906] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[12:56:50.208] <TB3> INFO: Expecting 41600 events.
[12:56:57.765] <TB3> INFO: 41600 events read in total (7002ms).
[12:56:57.765] <TB3> INFO: Test took 7859ms.
[12:56:57.768] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[12:56:58.072] <TB3> INFO: Expecting 41600 events.
[12:57:05.631] <TB3> INFO: 41600 events read in total (7004ms).
[12:57:05.632] <TB3> INFO: Test took 7864ms.
[12:57:05.635] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[12:57:05.938] <TB3> INFO: Expecting 41600 events.
[12:57:13.501] <TB3> INFO: 41600 events read in total (7007ms).
[12:57:13.502] <TB3> INFO: Test took 7867ms.
[12:57:13.939] <TB3> INFO: PixTestGainPedestal::measure() done
[12:57:45.945] <TB3> INFO: PixTestGainPedestal::fit() done
[12:57:45.945] <TB3> INFO: non-linearity mean: 0.952 0.954 0.957 0.956 0.963 0.955 0.962 0.961 0.959 0.963 0.961 0.967 0.966 0.953 0.958 0.956
[12:57:45.945] <TB3> INFO: non-linearity RMS: 0.009 0.007 0.006 0.009 0.007 0.006 0.006 0.007 0.008 0.007 0.007 0.004 0.004 0.008 0.007 0.009
[12:57:45.945] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[12:57:45.986] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[12:57:46.009] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[12:57:46.028] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[12:57:46.046] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[12:57:46.065] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[12:57:46.097] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[12:57:46.134] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[12:57:46.174] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[12:57:46.207] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[12:57:46.227] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[12:57:46.246] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[12:57:46.264] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[12:57:46.283] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[12:57:46.302] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[12:57:46.321] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[12:57:46.339] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 261 seconds
[12:57:46.339] <TB3> INFO: Decoding statistics:
[12:57:46.339] <TB3> INFO: General information:
[12:57:46.339] <TB3> INFO: 16bit words read: 2329178
[12:57:46.339] <TB3> INFO: valid events total: 83200
[12:57:46.339] <TB3> INFO: empty events: 0
[12:57:46.339] <TB3> INFO: valid events with pixels: 83200
[12:57:46.340] <TB3> INFO: valid pixel hits: 665389
[12:57:46.340] <TB3> INFO: Event errors: 0
[12:57:46.340] <TB3> INFO: start marker: 0
[12:57:46.340] <TB3> INFO: stop marker: 0
[12:57:46.340] <TB3> INFO: overflow: 0
[12:57:46.340] <TB3> INFO: invalid 5bit words: 0
[12:57:46.340] <TB3> INFO: invalid XOR eye diagram: 0
[12:57:46.340] <TB3> INFO: frame (failed synchr.): 0
[12:57:46.340] <TB3> INFO: idle data (no TBM trl): 0
[12:57:46.340] <TB3> INFO: no data (only TBM hdr): 0
[12:57:46.340] <TB3> INFO: TBM errors: 0
[12:57:46.340] <TB3> INFO: flawed TBM headers: 0
[12:57:46.340] <TB3> INFO: flawed TBM trailers: 0
[12:57:46.340] <TB3> INFO: event ID mismatches: 0
[12:57:46.340] <TB3> INFO: ROC errors: 0
[12:57:46.340] <TB3> INFO: missing ROC header(s): 0
[12:57:46.340] <TB3> INFO: misplaced readback start: 0
[12:57:46.340] <TB3> INFO: Pixel decoding errors: 0
[12:57:46.340] <TB3> INFO: pixel data incomplete: 0
[12:57:46.340] <TB3> INFO: pixel address: 0
[12:57:46.340] <TB3> INFO: pulse height fill bit: 0
[12:57:46.340] <TB3> INFO: buffer corruption: 0
[12:57:46.347] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C15.dat
[12:57:46.348] <TB3> INFO: ######################################################################
[12:57:46.348] <TB3> INFO: PixTestReadback::doTest()
[12:57:46.348] <TB3> INFO: ######################################################################
[12:57:46.348] <TB3> INFO: ----------------------------------------------------------------------
[12:57:46.348] <TB3> INFO: PixTestReadback::CalibrateVd()
[12:57:46.348] <TB3> INFO: ----------------------------------------------------------------------
[12:57:55.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C0.dat
[12:57:55.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C1.dat
[12:57:55.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C2.dat
[12:57:55.593] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C3.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C4.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C5.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C6.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C7.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C8.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C9.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C10.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C11.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C12.dat
[12:57:55.594] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C13.dat
[12:57:55.595] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C14.dat
[12:57:55.595] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C15.dat
[12:57:55.623] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[12:57:55.623] <TB3> INFO: ----------------------------------------------------------------------
[12:57:55.623] <TB3> INFO: PixTestReadback::CalibrateVa()
[12:57:55.623] <TB3> INFO: ----------------------------------------------------------------------
[12:58:04.881] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C0.dat
[12:58:04.881] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C1.dat
[12:58:04.881] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C2.dat
[12:58:04.881] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C3.dat
[12:58:04.881] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C4.dat
[12:58:04.881] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C5.dat
[12:58:04.881] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C6.dat
[12:58:04.881] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C7.dat
[12:58:04.882] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C8.dat
[12:58:04.882] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C9.dat
[12:58:04.882] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C10.dat
[12:58:04.882] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C11.dat
[12:58:04.882] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C12.dat
[12:58:04.882] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C13.dat
[12:58:04.882] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C14.dat
[12:58:04.882] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C15.dat
[12:58:04.912] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[12:58:04.912] <TB3> INFO: ----------------------------------------------------------------------
[12:58:04.912] <TB3> INFO: PixTestReadback::readbackVbg()
[12:58:04.912] <TB3> INFO: ----------------------------------------------------------------------
[12:58:12.016] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[12:58:12.016] <TB3> INFO: ----------------------------------------------------------------------
[12:58:12.016] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[12:58:12.016] <TB3> INFO: ----------------------------------------------------------------------
[12:58:12.016] <TB3> INFO: Vbg will be calibrated using Vd calibration
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.1calibrated Vbg = 1.20582 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.9calibrated Vbg = 1.21013 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.5calibrated Vbg = 1.21602 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148calibrated Vbg = 1.22164 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.7calibrated Vbg = 1.21801 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 143.8calibrated Vbg = 1.22606 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148.1calibrated Vbg = 1.22255 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 145.1calibrated Vbg = 1.21682 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 157.8calibrated Vbg = 1.22311 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153calibrated Vbg = 1.2241 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151.2calibrated Vbg = 1.21467 :::*/*/*/*/
[12:58:12.016] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 146.7calibrated Vbg = 1.21814 :::*/*/*/*/
[12:58:12.017] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.9calibrated Vbg = 1.21091 :::*/*/*/*/
[12:58:12.017] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.6calibrated Vbg = 1.20923 :::*/*/*/*/
[12:58:12.017] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148calibrated Vbg = 1.2116 :::*/*/*/*/
[12:58:12.017] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 146.6calibrated Vbg = 1.21131 :::*/*/*/*/
[12:58:12.018] <TB3> INFO: ----------------------------------------------------------------------
[12:58:12.018] <TB3> INFO: PixTestReadback::CalibrateIa()
[12:58:12.018] <TB3> INFO: ----------------------------------------------------------------------
[13:00:44.379] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C0.dat
[13:00:44.379] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C1.dat
[13:00:44.379] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C2.dat
[13:00:44.379] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C3.dat
[13:00:44.379] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C4.dat
[13:00:44.379] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C5.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C6.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C7.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C8.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C9.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C10.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C11.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C12.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C13.dat
[13:00:44.380] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C14.dat
[13:00:44.381] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3633_FullQualification_2016-09-19_09h32m_1474270348//001_FulltestPxar_p17//readbackCal_C15.dat
[13:00:44.410] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:00:44.411] <TB3> INFO: PixTestReadback::doTest() done
[13:00:44.411] <TB3> INFO: Decoding statistics:
[13:00:44.411] <TB3> INFO: General information:
[13:00:44.411] <TB3> INFO: 16bit words read: 768
[13:00:44.411] <TB3> INFO: valid events total: 64
[13:00:44.411] <TB3> INFO: empty events: 64
[13:00:44.411] <TB3> INFO: valid events with pixels: 0
[13:00:44.411] <TB3> INFO: valid pixel hits: 0
[13:00:44.411] <TB3> INFO: Event errors: 0
[13:00:44.411] <TB3> INFO: start marker: 0
[13:00:44.411] <TB3> INFO: stop marker: 0
[13:00:44.412] <TB3> INFO: overflow: 0
[13:00:44.412] <TB3> INFO: invalid 5bit words: 0
[13:00:44.412] <TB3> INFO: invalid XOR eye diagram: 0
[13:00:44.412] <TB3> INFO: frame (failed synchr.): 0
[13:00:44.412] <TB3> INFO: idle data (no TBM trl): 0
[13:00:44.412] <TB3> INFO: no data (only TBM hdr): 0
[13:00:44.412] <TB3> INFO: TBM errors: 0
[13:00:44.412] <TB3> INFO: flawed TBM headers: 0
[13:00:44.412] <TB3> INFO: flawed TBM trailers: 0
[13:00:44.412] <TB3> INFO: event ID mismatches: 0
[13:00:44.412] <TB3> INFO: ROC errors: 0
[13:00:44.412] <TB3> INFO: missing ROC header(s): 0
[13:00:44.412] <TB3> INFO: misplaced readback start: 0
[13:00:44.412] <TB3> INFO: Pixel decoding errors: 0
[13:00:44.412] <TB3> INFO: pixel data incomplete: 0
[13:00:44.412] <TB3> INFO: pixel address: 0
[13:00:44.412] <TB3> INFO: pulse height fill bit: 0
[13:00:44.412] <TB3> INFO: buffer corruption: 0
[13:00:44.438] <TB3> INFO: Decoding statistics:
[13:00:44.438] <TB3> INFO: General information:
[13:00:44.438] <TB3> INFO: 16bit words read: 2396392
[13:00:44.438] <TB3> INFO: valid events total: 88384
[13:00:44.439] <TB3> INFO: empty events: 2681
[13:00:44.439] <TB3> INFO: valid events with pixels: 85703
[13:00:44.439] <TB3> INFO: valid pixel hits: 667892
[13:00:44.439] <TB3> INFO: Event errors: 0
[13:00:44.439] <TB3> INFO: start marker: 0
[13:00:44.439] <TB3> INFO: stop marker: 0
[13:00:44.439] <TB3> INFO: overflow: 0
[13:00:44.439] <TB3> INFO: invalid 5bit words: 0
[13:00:44.439] <TB3> INFO: invalid XOR eye diagram: 0
[13:00:44.439] <TB3> INFO: frame (failed synchr.): 0
[13:00:44.439] <TB3> INFO: idle data (no TBM trl): 0
[13:00:44.439] <TB3> INFO: no data (only TBM hdr): 0
[13:00:44.439] <TB3> INFO: TBM errors: 0
[13:00:44.439] <TB3> INFO: flawed TBM headers: 0
[13:00:44.439] <TB3> INFO: flawed TBM trailers: 0
[13:00:44.439] <TB3> INFO: event ID mismatches: 0
[13:00:44.439] <TB3> INFO: ROC errors: 0
[13:00:44.439] <TB3> INFO: missing ROC header(s): 0
[13:00:44.439] <TB3> INFO: misplaced readback start: 0
[13:00:44.439] <TB3> INFO: Pixel decoding errors: 0
[13:00:44.439] <TB3> INFO: pixel data incomplete: 0
[13:00:44.439] <TB3> INFO: pixel address: 0
[13:00:44.439] <TB3> INFO: pulse height fill bit: 0
[13:00:44.439] <TB3> INFO: buffer corruption: 0
[13:00:44.439] <TB3> INFO: enter test to run
[13:00:44.439] <TB3> INFO: test: exit no parameter change
[13:00:44.658] <TB3> QUIET: Connection to board 170 closed.
[13:00:44.738] <TB3> INFO: pXar: this is the end, my friend