[14:48:22.543] <TB1> INFO: *** Welcome to pxar ***
[14:48:22.543] <TB1> INFO: *** Today: 2015/12/07
[14:48:22.833] <TB1> INFO: *** Version: 3197
[14:48:22.833] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C15.dat
[14:48:22.835] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:48:22.835] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//defaultMaskFile.dat
[14:48:22.835] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters_C15.dat
[14:48:22.912] <TB1> INFO: clk: 4
[14:48:22.912] <TB1> INFO: ctr: 4
[14:48:22.912] <TB1> INFO: sda: 19
[14:48:22.912] <TB1> INFO: tin: 9
[14:48:22.912] <TB1> INFO: level: 15
[14:48:22.912] <TB1> INFO: triggerdelay: 0
[14:48:22.912] <TB1> QUIET: Instanciating API for pxar prod-12
[14:48:22.913] <TB1> INFO: Log level: INFO
[14:48:22.920] <TB1> INFO: Found DTB DTB_WXBYFL
[14:48:22.931] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[14:48:22.935] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[14:48:22.937] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[14:48:24.462] <TB1> INFO: DUT info:
[14:48:24.462] <TB1> INFO: The DUT currently contains the following objects:
[14:48:24.462] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:48:24.462] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:48:24.462] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:48:24.462] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:48:24.462] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.462] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.463] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.463] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.463] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.463] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:24.864] <TB1> INFO: enter 'restricted' command line mode
[14:48:24.864] <TB1> INFO: enter test to run
[14:48:24.864] <TB1> INFO: test: pretest no parameter change
[14:48:24.864] <TB1> INFO: running: pretest
[14:48:24.871] <TB1> INFO: ######################################################################
[14:48:24.871] <TB1> INFO: PixTestPretest::doTest()
[14:48:24.871] <TB1> INFO: ######################################################################
[14:48:24.873] <TB1> INFO: ----------------------------------------------------------------------
[14:48:24.873] <TB1> INFO: PixTestPretest::programROC()
[14:48:24.873] <TB1> INFO: ----------------------------------------------------------------------
[14:48:42.895] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:48:42.895] <TB1> INFO: IA differences per ROC: 16.1 17.7 20.1 16.1 16.9 18.5 16.9 18.5 16.9 16.9 19.3 17.7 18.5 17.7 19.3 19.3
[14:48:42.982] <TB1> INFO: ----------------------------------------------------------------------
[14:48:42.982] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:48:42.982] <TB1> INFO: ----------------------------------------------------------------------
[14:49:04.295] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[14:49:04.295] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 18.5 18.5 19.3 20.1
[14:49:04.344] <TB1> INFO: ----------------------------------------------------------------------
[14:49:04.344] <TB1> INFO: PixTestPretest::findTiming()
[14:49:04.344] <TB1> INFO: ----------------------------------------------------------------------
[14:49:04.344] <TB1> INFO: PixTestCmd::init()

[14:50:51.160] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[14:50:51.160] <TB1> INFO: (success/tries = 100/100), width = 4
[14:50:51.162] <TB1> INFO: ----------------------------------------------------------------------
[14:50:51.162] <TB1> INFO: PixTestPretest::findWorkingPixel()
[14:50:51.162] <TB1> INFO: ----------------------------------------------------------------------
[14:50:51.302] <TB1> INFO: Expecting 231680 events.
[14:50:59.951] <TB1> INFO: 231680 events read in total (7932ms).
[14:50:59.955] <TB1> INFO: Test took 8790ms.
[14:51:00.277] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:51:00.327] <TB1> INFO: ----------------------------------------------------------------------
[14:51:00.327] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[14:51:00.327] <TB1> INFO: ----------------------------------------------------------------------
[14:51:00.466] <TB1> INFO: Expecting 231680 events.
[14:51:09.463] <TB1> INFO: 231680 events read in total (8280ms).
[14:51:09.467] <TB1> INFO: Test took 9134ms.
[14:51:09.819] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[14:51:09.820] <TB1> INFO: CalDel: 133 136 142 134 136 153 114 131 152 135 152 142 162 140 147 164
[14:51:09.820] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:51:09.823] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C0.dat
[14:51:09.824] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C1.dat
[14:51:09.824] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C2.dat
[14:51:09.824] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C3.dat
[14:51:09.825] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C4.dat
[14:51:09.825] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C5.dat
[14:51:09.825] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C6.dat
[14:51:09.825] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C7.dat
[14:51:09.825] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C8.dat
[14:51:09.826] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C9.dat
[14:51:09.826] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C10.dat
[14:51:09.826] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C11.dat
[14:51:09.827] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C12.dat
[14:51:09.827] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C13.dat
[14:51:09.827] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C14.dat
[14:51:09.827] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters_C15.dat
[14:51:09.828] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//tbmParameters_C0a.dat
[14:51:09.828] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:51:09.828] <TB1> INFO: PixTestPretest::doTest() done, duration: 164 seconds
[14:51:09.912] <TB1> INFO: enter test to run
[14:51:09.912] <TB1> INFO: test: fulltest no parameter change
[14:51:09.912] <TB1> INFO: running: fulltest
[14:51:09.912] <TB1> INFO: ######################################################################
[14:51:09.912] <TB1> INFO: PixTestFullTest::doTest()
[14:51:09.912] <TB1> INFO: ######################################################################
[14:51:09.913] <TB1> INFO: ######################################################################
[14:51:09.913] <TB1> INFO: PixTestAlive::doTest()
[14:51:09.913] <TB1> INFO: ######################################################################
[14:51:09.915] <TB1> INFO: ----------------------------------------------------------------------
[14:51:09.915] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:51:09.915] <TB1> INFO: ----------------------------------------------------------------------
[14:51:10.260] <TB1> INFO: Expecting 41600 events.
[14:51:14.802] <TB1> INFO: 41600 events read in total (3826ms).
[14:51:14.803] <TB1> INFO: Test took 4886ms.
[14:51:14.810] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:15.112] <TB1> INFO: PixTestAlive::aliveTest() done
[14:51:15.112] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:51:15.115] <TB1> INFO: ----------------------------------------------------------------------
[14:51:15.115] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:51:15.115] <TB1> INFO: ----------------------------------------------------------------------
[14:51:15.429] <TB1> INFO: Expecting 41600 events.
[14:51:18.708] <TB1> INFO: 41600 events read in total (2563ms).
[14:51:18.708] <TB1> INFO: Test took 3591ms.
[14:51:18.708] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:18.709] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:51:19.036] <TB1> INFO: PixTestAlive::maskTest() done
[14:51:19.036] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:51:19.038] <TB1> INFO: ----------------------------------------------------------------------
[14:51:19.038] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:51:19.038] <TB1> INFO: ----------------------------------------------------------------------
[14:51:19.359] <TB1> INFO: Expecting 41600 events.
[14:51:23.885] <TB1> INFO: 41600 events read in total (3810ms).
[14:51:23.886] <TB1> INFO: Test took 4846ms.
[14:51:23.893] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:24.192] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[14:51:24.192] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:51:24.192] <TB1> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:51:24.192] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:24.192] <TB1> INFO: Decoding statistics:
[14:51:24.192] <TB1> INFO: General information:
[14:51:24.192] <TB1> INFO: 16bit words read: 0
[14:51:24.192] <TB1> INFO: valid events total: 0
[14:51:24.192] <TB1> INFO: empty events: 0
[14:51:24.192] <TB1> INFO: valid events with pixels: 0
[14:51:24.192] <TB1> INFO: valid pixel hits: 0
[14:51:24.192] <TB1> INFO: Event errors: 0
[14:51:24.192] <TB1> INFO: start marker: 0
[14:51:24.192] <TB1> INFO: stop marker: 0
[14:51:24.192] <TB1> INFO: overflow: 0
[14:51:24.192] <TB1> INFO: invalid 5bit words: 0
[14:51:24.192] <TB1> INFO: invalid XOR eye diagram: 0
[14:51:24.192] <TB1> INFO: TBM errors: 0
[14:51:24.192] <TB1> INFO: flawed TBM headers: 0
[14:51:24.192] <TB1> INFO: flawed TBM trailers: 0
[14:51:24.192] <TB1> INFO: event ID mismatches: 0
[14:51:24.192] <TB1> INFO: ROC errors: 0
[14:51:24.192] <TB1> INFO: missing ROC header(s): 0
[14:51:24.192] <TB1> INFO: misplaced readback start: 0
[14:51:24.192] <TB1> INFO: Pixel decoding errors: 0
[14:51:24.193] <TB1> INFO: pixel data incomplete: 0
[14:51:24.193] <TB1> INFO: pixel address: 0
[14:51:24.193] <TB1> INFO: pulse height fill bit: 0
[14:51:24.193] <TB1> INFO: buffer corruption: 0
[14:51:24.200] <TB1> INFO: ######################################################################
[14:51:24.200] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:51:24.200] <TB1> INFO: ######################################################################
[14:51:24.204] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:51:24.215] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:51:24.216] <TB1> INFO: run 1 of 1
[14:51:24.519] <TB1> INFO: Expecting 3120000 events.
[14:52:08.492] <TB1> INFO: 862820 events read in total (43257ms).
[14:52:50.818] <TB1> INFO: 1714745 events read in total (85583ms).
[14:53:32.608] <TB1> INFO: 2573860 events read in total (127373ms).
[14:53:58.186] <TB1> INFO: 3120000 events read in total (152951ms).
[14:53:58.234] <TB1> INFO: Test took 154018ms.
[14:53:58.343] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:23.282] <TB1> INFO: PixTestBBMap::doTest() done, duration: 179 seconds
[14:54:23.283] <TB1> INFO: number of dead bumps (per ROC): 1 1 0 0 0 0 0 3 0 0 0 0 0 0 0 0
[14:54:23.283] <TB1> INFO: separation cut (per ROC): 91 96 103 86 99 81 86 105 80 90 77 81 96 93 97 80
[14:54:23.283] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:23.283] <TB1> INFO: Decoding statistics:
[14:54:23.283] <TB1> INFO: General information:
[14:54:23.283] <TB1> INFO: 16bit words read: 0
[14:54:23.283] <TB1> INFO: valid events total: 0
[14:54:23.283] <TB1> INFO: empty events: 0
[14:54:23.283] <TB1> INFO: valid events with pixels: 0
[14:54:23.283] <TB1> INFO: valid pixel hits: 0
[14:54:23.283] <TB1> INFO: Event errors: 0
[14:54:23.283] <TB1> INFO: start marker: 0
[14:54:23.283] <TB1> INFO: stop marker: 0
[14:54:23.283] <TB1> INFO: overflow: 0
[14:54:23.283] <TB1> INFO: invalid 5bit words: 0
[14:54:23.283] <TB1> INFO: invalid XOR eye diagram: 0
[14:54:23.283] <TB1> INFO: TBM errors: 0
[14:54:23.283] <TB1> INFO: flawed TBM headers: 0
[14:54:23.283] <TB1> INFO: flawed TBM trailers: 0
[14:54:23.283] <TB1> INFO: event ID mismatches: 0
[14:54:23.283] <TB1> INFO: ROC errors: 0
[14:54:23.283] <TB1> INFO: missing ROC header(s): 0
[14:54:23.283] <TB1> INFO: misplaced readback start: 0
[14:54:23.283] <TB1> INFO: Pixel decoding errors: 0
[14:54:23.283] <TB1> INFO: pixel data incomplete: 0
[14:54:23.283] <TB1> INFO: pixel address: 0
[14:54:23.283] <TB1> INFO: pulse height fill bit: 0
[14:54:23.283] <TB1> INFO: buffer corruption: 0
[14:54:23.353] <TB1> INFO: ######################################################################
[14:54:23.353] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:54:23.353] <TB1> INFO: ######################################################################
[14:54:23.353] <TB1> INFO: ----------------------------------------------------------------------
[14:54:23.353] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:54:23.353] <TB1> INFO: ----------------------------------------------------------------------
[14:54:23.353] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:54:23.362] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[14:54:23.362] <TB1> INFO: run 1 of 1
[14:54:23.667] <TB1> INFO: Expecting 26208000 events.
[14:54:55.428] <TB1> INFO: 956800 events read in total (31045ms).
[14:55:24.678] <TB1> INFO: 1891050 events read in total (60295ms).
[14:55:55.541] <TB1> INFO: 2821550 events read in total (91158ms).
[14:56:26.465] <TB1> INFO: 3752800 events read in total (122082ms).
[14:56:57.409] <TB1> INFO: 4681550 events read in total (153026ms).
[14:57:29.164] <TB1> INFO: 5607650 events read in total (184781ms).
[14:58:00.247] <TB1> INFO: 6536350 events read in total (215864ms).
[14:58:32.485] <TB1> INFO: 7463350 events read in total (248102ms).
[14:59:03.896] <TB1> INFO: 8389300 events read in total (279513ms).
[14:59:35.172] <TB1> INFO: 9315700 events read in total (310789ms).
[15:00:06.374] <TB1> INFO: 10241150 events read in total (341991ms).
[15:00:38.046] <TB1> INFO: 11163900 events read in total (373663ms).
[15:01:09.986] <TB1> INFO: 12087550 events read in total (405603ms).
[15:01:40.981] <TB1> INFO: 13007800 events read in total (436598ms).
[15:02:12.052] <TB1> INFO: 13919650 events read in total (467670ms).
[15:02:42.846] <TB1> INFO: 14829300 events read in total (498463ms).
[15:03:13.618] <TB1> INFO: 15740900 events read in total (529235ms).
[15:03:44.070] <TB1> INFO: 16647350 events read in total (559687ms).
[15:04:15.068] <TB1> INFO: 17551050 events read in total (590685ms).
[15:04:46.256] <TB1> INFO: 18457600 events read in total (621873ms).
[15:05:17.314] <TB1> INFO: 19363650 events read in total (652931ms).
[15:05:48.515] <TB1> INFO: 20266950 events read in total (684132ms).
[15:06:19.716] <TB1> INFO: 21170500 events read in total (715333ms).
[15:06:51.901] <TB1> INFO: 22073900 events read in total (747518ms).
[15:07:24.168] <TB1> INFO: 22979550 events read in total (779785ms).
[15:07:55.327] <TB1> INFO: 23888750 events read in total (810944ms).
[15:08:27.101] <TB1> INFO: 24801500 events read in total (842718ms).
[15:08:55.634] <TB1> INFO: 25714950 events read in total (871251ms).
[15:09:13.010] <TB1> INFO: 26208000 events read in total (888627ms).
[15:09:13.042] <TB1> INFO: Test took 889680ms.
[15:09:13.120] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:13.294] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:15.079] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:16.852] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:18.528] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:20.247] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:21.921] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:23.687] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:25.435] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:27.004] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:28.678] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:30.332] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:31.990] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:33.717] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:35.363] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:37.086] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:38.782] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:09:40.563] <TB1> INFO: PixTestScurves::scurves() done
[15:09:40.563] <TB1> INFO: Vcal mean: 97.13 105.96 93.01 97.25 108.37 99.85 91.68 113.23 96.88 110.55 86.95 89.08 104.33 93.69 99.64 95.18
[15:09:40.563] <TB1> INFO: Vcal RMS: 6.75 5.51 5.68 5.54 5.55 5.99 5.89 5.57 5.35 5.36 4.94 5.90 5.89 6.18 6.43 5.91
[15:09:40.563] <TB1> INFO: PixTestScurves::fullTest() done, duration: 917 seconds
[15:09:40.563] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:40.563] <TB1> INFO: Decoding statistics:
[15:09:40.563] <TB1> INFO: General information:
[15:09:40.563] <TB1> INFO: 16bit words read: 0
[15:09:40.563] <TB1> INFO: valid events total: 0
[15:09:40.563] <TB1> INFO: empty events: 0
[15:09:40.563] <TB1> INFO: valid events with pixels: 0
[15:09:40.563] <TB1> INFO: valid pixel hits: 0
[15:09:40.563] <TB1> INFO: Event errors: 0
[15:09:40.563] <TB1> INFO: start marker: 0
[15:09:40.563] <TB1> INFO: stop marker: 0
[15:09:40.563] <TB1> INFO: overflow: 0
[15:09:40.563] <TB1> INFO: invalid 5bit words: 0
[15:09:40.563] <TB1> INFO: invalid XOR eye diagram: 0
[15:09:40.563] <TB1> INFO: TBM errors: 0
[15:09:40.563] <TB1> INFO: flawed TBM headers: 0
[15:09:40.563] <TB1> INFO: flawed TBM trailers: 0
[15:09:40.563] <TB1> INFO: event ID mismatches: 0
[15:09:40.563] <TB1> INFO: ROC errors: 0
[15:09:40.563] <TB1> INFO: missing ROC header(s): 0
[15:09:40.563] <TB1> INFO: misplaced readback start: 0
[15:09:40.563] <TB1> INFO: Pixel decoding errors: 0
[15:09:40.563] <TB1> INFO: pixel data incomplete: 0
[15:09:40.563] <TB1> INFO: pixel address: 0
[15:09:40.563] <TB1> INFO: pulse height fill bit: 0
[15:09:40.563] <TB1> INFO: buffer corruption: 0
[15:09:40.633] <TB1> INFO: ######################################################################
[15:09:40.633] <TB1> INFO: PixTestTrim::doTest()
[15:09:40.633] <TB1> INFO: ######################################################################
[15:09:40.634] <TB1> INFO: ----------------------------------------------------------------------
[15:09:40.634] <TB1> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[15:09:40.634] <TB1> INFO: ----------------------------------------------------------------------
[15:09:40.735] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:09:40.735] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:09:40.744] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[15:09:40.744] <TB1> INFO: run 1 of 1
[15:09:41.084] <TB1> INFO: Expecting 5025280 events.
[15:10:23.116] <TB1> INFO: 1094752 events read in total (41314ms).
[15:11:05.143] <TB1> INFO: 2180584 events read in total (83341ms).
[15:11:45.008] <TB1> INFO: 3264368 events read in total (123206ms).
[15:12:23.407] <TB1> INFO: 4354144 events read in total (161605ms).
[15:12:48.923] <TB1> INFO: 5025280 events read in total (187121ms).
[15:12:48.949] <TB1> INFO: Test took 188206ms.
[15:12:49.014] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:12.852] <TB1> INFO: ROC 0 VthrComp = 94
[15:13:12.852] <TB1> INFO: ROC 1 VthrComp = 102
[15:13:12.858] <TB1> INFO: ROC 2 VthrComp = 98
[15:13:12.858] <TB1> INFO: ROC 3 VthrComp = 98
[15:13:12.858] <TB1> INFO: ROC 4 VthrComp = 105
[15:13:12.858] <TB1> INFO: ROC 5 VthrComp = 92
[15:13:12.858] <TB1> INFO: ROC 6 VthrComp = 92
[15:13:12.859] <TB1> INFO: ROC 7 VthrComp = 109
[15:13:12.859] <TB1> INFO: ROC 8 VthrComp = 95
[15:13:12.859] <TB1> INFO: ROC 9 VthrComp = 103
[15:13:12.859] <TB1> INFO: ROC 10 VthrComp = 88
[15:13:12.860] <TB1> INFO: ROC 11 VthrComp = 89
[15:13:12.860] <TB1> INFO: ROC 12 VthrComp = 101
[15:13:12.860] <TB1> INFO: ROC 13 VthrComp = 94
[15:13:12.861] <TB1> INFO: ROC 14 VthrComp = 96
[15:13:12.861] <TB1> INFO: ROC 15 VthrComp = 94
[15:13:12.861] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:13:12.861] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:13:12.871] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[15:13:12.871] <TB1> INFO: run 1 of 1
[15:13:13.178] <TB1> INFO: Expecting 5025280 events.
[15:13:47.148] <TB1> INFO: 760432 events read in total (33254ms).
[15:14:24.329] <TB1> INFO: 1519296 events read in total (70435ms).
[15:15:00.587] <TB1> INFO: 2278424 events read in total (106693ms).
[15:15:34.606] <TB1> INFO: 3032544 events read in total (140712ms).
[15:16:10.295] <TB1> INFO: 3781400 events read in total (176401ms).
[15:16:42.963] <TB1> INFO: 4528144 events read in total (209069ms).
[15:17:06.975] <TB1> INFO: 5025280 events read in total (233081ms).
[15:17:07.020] <TB1> INFO: Test took 234149ms.
[15:17:07.171] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:32.754] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 61.9997 for pixel 3/68 mean/min/max = 47.1457/31.9926/62.2988
[15:17:32.754] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.8813 for pixel 10/0 mean/min/max = 46.1831/32.4012/59.9649
[15:17:32.755] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 58.8133 for pixel 24/77 mean/min/max = 45.0689/31.2458/58.8919
[15:17:32.755] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 58.3582 for pixel 15/79 mean/min/max = 45.1675/31.7119/58.6232
[15:17:32.755] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 61.9013 for pixel 20/76 mean/min/max = 47.8915/33.8578/61.9253
[15:17:32.756] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 64.2383 for pixel 1/13 mean/min/max = 48.6164/32.8892/64.3436
[15:17:32.756] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 61.4754 for pixel 10/2 mean/min/max = 47.3521/33.1091/61.5951
[15:17:32.756] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 64.1692 for pixel 9/62 mean/min/max = 49.4241/34.573/64.2753
[15:17:32.756] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 59.8188 for pixel 0/18 mean/min/max = 45.7851/31.7395/59.8307
[15:17:32.757] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 61.8876 for pixel 48/78 mean/min/max = 47.3422/32.6856/61.9987
[15:17:32.757] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 59.0075 for pixel 0/4 mean/min/max = 46.2584/33.5003/59.0164
[15:17:32.757] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 61.7286 for pixel 11/69 mean/min/max = 47.7928/33.7432/61.8424
[15:17:32.757] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 60.9456 for pixel 20/7 mean/min/max = 46.6801/32.3795/60.9807
[15:17:32.758] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 60.2677 for pixel 17/79 mean/min/max = 46.1211/31.9197/60.3225
[15:17:32.758] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 63.2286 for pixel 0/5 mean/min/max = 47.3504/31.3211/63.3796
[15:17:32.758] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 62.5833 for pixel 2/6 mean/min/max = 47.379/32.1301/62.6279
[15:17:32.758] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:17:32.891] <TB1> INFO: Expecting 411648 events.
[15:17:42.475] <TB1> INFO: 411648 events read in total (8863ms).
[15:17:42.480] <TB1> INFO: Expecting 411648 events.
[15:17:51.513] <TB1> INFO: 411648 events read in total (8449ms).
[15:17:51.520] <TB1> INFO: Expecting 411648 events.
[15:18:00.699] <TB1> INFO: 411648 events read in total (8594ms).
[15:18:00.711] <TB1> INFO: Expecting 411648 events.
[15:18:09.529] <TB1> INFO: 411648 events read in total (8256ms).
[15:18:09.539] <TB1> INFO: Expecting 411648 events.
[15:18:18.885] <TB1> INFO: 411648 events read in total (8764ms).
[15:18:18.900] <TB1> INFO: Expecting 411648 events.
[15:18:28.317] <TB1> INFO: 411648 events read in total (8850ms).
[15:18:28.333] <TB1> INFO: Expecting 411648 events.
[15:18:37.783] <TB1> INFO: 411648 events read in total (8879ms).
[15:18:37.800] <TB1> INFO: Expecting 411648 events.
[15:18:47.589] <TB1> INFO: 411648 events read in total (9223ms).
[15:18:47.606] <TB1> INFO: Expecting 411648 events.
[15:18:57.048] <TB1> INFO: 411648 events read in total (8883ms).
[15:18:57.068] <TB1> INFO: Expecting 411648 events.
[15:19:06.355] <TB1> INFO: 411648 events read in total (8715ms).
[15:19:06.382] <TB1> INFO: Expecting 411648 events.
[15:19:15.952] <TB1> INFO: 411648 events read in total (9018ms).
[15:19:15.976] <TB1> INFO: Expecting 411648 events.
[15:19:25.401] <TB1> INFO: 411648 events read in total (8861ms).
[15:19:25.432] <TB1> INFO: Expecting 411648 events.
[15:19:34.652] <TB1> INFO: 411648 events read in total (8660ms).
[15:19:34.680] <TB1> INFO: Expecting 411648 events.
[15:19:43.927] <TB1> INFO: 411648 events read in total (8685ms).
[15:19:43.957] <TB1> INFO: Expecting 411648 events.
[15:19:52.781] <TB1> INFO: 411648 events read in total (8270ms).
[15:19:52.812] <TB1> INFO: Expecting 411648 events.
[15:20:01.909] <TB1> INFO: 411648 events read in total (8535ms).
[15:20:01.946] <TB1> INFO: Test took 149188ms.
[15:20:02.980] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:20:02.988] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[15:20:02.988] <TB1> INFO: run 1 of 1
[15:20:03.294] <TB1> INFO: Expecting 5025280 events.
[15:20:40.102] <TB1> INFO: 743320 events read in total (36092ms).
[15:21:16.294] <TB1> INFO: 1485216 events read in total (72284ms).
[15:21:51.972] <TB1> INFO: 2227016 events read in total (107962ms).
[15:22:27.243] <TB1> INFO: 2963912 events read in total (143234ms).
[15:23:01.862] <TB1> INFO: 3695168 events read in total (177852ms).
[15:23:35.342] <TB1> INFO: 4423472 events read in total (211332ms).
[15:24:03.689] <TB1> INFO: 5025280 events read in total (239679ms).
[15:24:03.749] <TB1> INFO: Test took 240761ms.
[15:24:03.898] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:30.812] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.068620 .. 255.000000
[15:24:30.887] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:24:30.896] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[15:24:30.896] <TB1> INFO: run 1 of 1
[15:24:31.200] <TB1> INFO: Expecting 8519680 events.
[15:25:05.056] <TB1> INFO: 712384 events read in total (33140ms).
[15:25:38.076] <TB1> INFO: 1425368 events read in total (66160ms).
[15:26:12.242] <TB1> INFO: 2138184 events read in total (100326ms).
[15:26:46.614] <TB1> INFO: 2851232 events read in total (134698ms).
[15:27:19.426] <TB1> INFO: 3564424 events read in total (167510ms).
[15:27:53.950] <TB1> INFO: 4277368 events read in total (202034ms).
[15:28:28.137] <TB1> INFO: 4989936 events read in total (236221ms).
[15:29:02.009] <TB1> INFO: 5702024 events read in total (270093ms).
[15:29:36.264] <TB1> INFO: 6413552 events read in total (304348ms).
[15:30:08.553] <TB1> INFO: 7124688 events read in total (336637ms).
[15:30:40.717] <TB1> INFO: 7835616 events read in total (368801ms).
[15:31:12.879] <TB1> INFO: 8519680 events read in total (400963ms).
[15:31:12.968] <TB1> INFO: Test took 402072ms.
[15:31:13.233] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:46.763] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.360541 .. 48.456092
[15:31:46.838] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 5 .. 58 (-1/-1) hits flags = 528 (plus default)
[15:31:46.846] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[15:31:46.846] <TB1> INFO: run 1 of 1
[15:31:47.153] <TB1> INFO: Expecting 1797120 events.
[15:32:25.523] <TB1> INFO: 917128 events read in total (37647ms).
[15:33:00.417] <TB1> INFO: 1797120 events read in total (72541ms).
[15:33:00.442] <TB1> INFO: Test took 73597ms.
[15:33:00.484] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:15.124] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 9.248815 .. 45.112663
[15:33:15.223] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 9 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:33:15.232] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[15:33:15.232] <TB1> INFO: run 1 of 1
[15:33:15.562] <TB1> INFO: Expecting 1564160 events.
[15:33:55.277] <TB1> INFO: 911304 events read in total (38998ms).
[15:34:21.060] <TB1> INFO: 1564160 events read in total (64781ms).
[15:34:21.077] <TB1> INFO: Test took 65845ms.
[15:34:21.112] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:35.011] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 2.895969 .. 45.112663
[15:34:35.087] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 2 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:34:35.095] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[15:34:35.095] <TB1> INFO: run 1 of 1
[15:34:35.398] <TB1> INFO: Expecting 1797120 events.
[15:35:15.220] <TB1> INFO: 950336 events read in total (39105ms).
[15:35:47.971] <TB1> INFO: 1797120 events read in total (71856ms).
[15:35:47.984] <TB1> INFO: Test took 72889ms.
[15:35:48.016] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:02.055] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:36:02.071] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:36:02.081] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[15:36:02.081] <TB1> INFO: run 1 of 1
[15:36:02.385] <TB1> INFO: Expecting 1364480 events.
[15:36:42.517] <TB1> INFO: 878280 events read in total (39415ms).
[15:37:02.973] <TB1> INFO: 1364480 events read in total (59871ms).
[15:37:02.989] <TB1> INFO: Test took 60909ms.
[15:37:03.022] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:18.038] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:37:18.038] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:37:18.038] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:37:18.039] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:37:18.039] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:37:18.039] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:37:18.039] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:37:18.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:37:18.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:37:18.040] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:37:18.041] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:37:18.041] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:37:18.041] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:37:18.042] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:37:18.042] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:37:18.042] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:37:18.043] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:37:18.054] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:37:18.064] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:37:18.071] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:37:18.079] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:37:18.089] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:37:18.097] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:37:18.106] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:37:18.116] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:37:18.125] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:37:18.134] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:37:18.142] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:37:18.152] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:37:18.161] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:37:18.171] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:37:18.180] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:37:18.189] <TB1> INFO: PixTestTrim::trimTest() done
[15:37:18.189] <TB1> INFO: vtrim: 120 102 102 102 92 129 105 114 87 115 102 109 113 95 106 109
[15:37:18.189] <TB1> INFO: vthrcomp: 94 102 98 98 105 92 92 109 95 103 88 89 101 94 96 94
[15:37:18.189] <TB1> INFO: vcal mean: 34.96 34.98 34.95 34.93 34.89 34.94 34.97 34.99 34.94 34.95 34.99 34.98 34.95 34.96 34.97 34.96
[15:37:18.189] <TB1> INFO: vcal RMS: 1.04 0.93 0.88 0.94 1.42 1.03 0.95 1.07 0.97 1.01 0.90 1.10 1.02 0.94 0.96 0.96
[15:37:18.189] <TB1> INFO: bits mean: 9.33 9.49 9.86 9.83 8.27 9.53 9.19 8.54 9.63 9.21 9.04 9.09 9.60 9.31 8.52 9.29
[15:37:18.189] <TB1> INFO: bits RMS: 2.67 2.59 2.68 2.61 2.73 2.36 2.58 2.46 2.67 2.58 2.65 2.48 2.50 2.73 3.07 2.63
[15:37:18.198] <TB1> INFO: ----------------------------------------------------------------------
[15:37:18.198] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:37:18.198] <TB1> INFO: ----------------------------------------------------------------------
[15:37:18.203] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:37:18.217] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:37:18.217] <TB1> INFO: run 1 of 1
[15:37:18.558] <TB1> INFO: Expecting 4160000 events.
[15:37:59.858] <TB1> INFO: 969800 events read in total (40584ms).
[15:38:41.646] <TB1> INFO: 1929945 events read in total (82372ms).
[15:39:23.576] <TB1> INFO: 2878720 events read in total (124302ms).
[15:40:07.582] <TB1> INFO: 3824925 events read in total (168308ms).
[15:40:23.199] <TB1> INFO: 4160000 events read in total (183925ms).
[15:40:23.236] <TB1> INFO: Test took 185019ms.
[15:40:23.340] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:57.476] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 188 (-1/-1) hits flags = 528 (plus default)
[15:40:57.486] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:40:57.486] <TB1> INFO: run 1 of 1
[15:40:57.821] <TB1> INFO: Expecting 3931200 events.
[15:41:40.602] <TB1> INFO: 956010 events read in total (42064ms).
[15:42:21.412] <TB1> INFO: 1903585 events read in total (82874ms).
[15:43:01.433] <TB1> INFO: 2839075 events read in total (122895ms).
[15:43:44.306] <TB1> INFO: 3774215 events read in total (165768ms).
[15:43:52.152] <TB1> INFO: 3931200 events read in total (173614ms).
[15:43:52.190] <TB1> INFO: Test took 174704ms.
[15:43:52.298] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:23.521] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 175 (-1/-1) hits flags = 528 (plus default)
[15:44:23.531] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:44:23.531] <TB1> INFO: run 1 of 1
[15:44:23.874] <TB1> INFO: Expecting 3660800 events.
[15:45:08.876] <TB1> INFO: 987755 events read in total (44285ms).
[15:45:49.854] <TB1> INFO: 1963730 events read in total (85263ms).
[15:46:33.601] <TB1> INFO: 2926810 events read in total (129010ms).
[15:47:07.008] <TB1> INFO: 3660800 events read in total (162417ms).
[15:47:07.044] <TB1> INFO: Test took 163513ms.
[15:47:07.121] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:33.842] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[15:47:33.851] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:47:33.851] <TB1> INFO: run 1 of 1
[15:47:34.178] <TB1> INFO: Expecting 3723200 events.
[15:48:18.515] <TB1> INFO: 979545 events read in total (43621ms).
[15:49:00.579] <TB1> INFO: 1948375 events read in total (85685ms).
[15:49:43.331] <TB1> INFO: 2904165 events read in total (128437ms).
[15:50:19.533] <TB1> INFO: 3723200 events read in total (164639ms).
[15:50:19.573] <TB1> INFO: Test took 165722ms.
[15:50:19.658] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:46.223] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[15:50:46.232] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:50:46.232] <TB1> INFO: run 1 of 1
[15:50:46.536] <TB1> INFO: Expecting 3681600 events.
[15:51:33.319] <TB1> INFO: 983830 events read in total (46067ms).
[15:52:16.754] <TB1> INFO: 1956445 events read in total (89502ms).
[15:53:02.593] <TB1> INFO: 2916455 events read in total (135342ms).
[15:53:37.848] <TB1> INFO: 3681600 events read in total (170596ms).
[15:53:37.888] <TB1> INFO: Test took 171656ms.
[15:53:37.969] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:04.024] <TB1> INFO: PixTestTrim::trimBitTest() done
[15:54:04.026] <TB1> INFO: PixTestTrim::doTest() done, duration: 2663 seconds
[15:54:04.026] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:04.026] <TB1> INFO: Decoding statistics:
[15:54:04.026] <TB1> INFO: General information:
[15:54:04.026] <TB1> INFO: 16bit words read: 0
[15:54:04.026] <TB1> INFO: valid events total: 0
[15:54:04.026] <TB1> INFO: empty events: 0
[15:54:04.026] <TB1> INFO: valid events with pixels: 0
[15:54:04.026] <TB1> INFO: valid pixel hits: 0
[15:54:04.026] <TB1> INFO: Event errors: 0
[15:54:04.026] <TB1> INFO: start marker: 0
[15:54:04.026] <TB1> INFO: stop marker: 0
[15:54:04.026] <TB1> INFO: overflow: 0
[15:54:04.026] <TB1> INFO: invalid 5bit words: 0
[15:54:04.026] <TB1> INFO: invalid XOR eye diagram: 0
[15:54:04.026] <TB1> INFO: TBM errors: 0
[15:54:04.026] <TB1> INFO: flawed TBM headers: 0
[15:54:04.026] <TB1> INFO: flawed TBM trailers: 0
[15:54:04.026] <TB1> INFO: event ID mismatches: 0
[15:54:04.026] <TB1> INFO: ROC errors: 0
[15:54:04.026] <TB1> INFO: missing ROC header(s): 0
[15:54:04.026] <TB1> INFO: misplaced readback start: 0
[15:54:04.026] <TB1> INFO: Pixel decoding errors: 0
[15:54:04.026] <TB1> INFO: pixel data incomplete: 0
[15:54:04.026] <TB1> INFO: pixel address: 0
[15:54:04.026] <TB1> INFO: pulse height fill bit: 0
[15:54:04.026] <TB1> INFO: buffer corruption: 0
[15:54:04.781] <TB1> INFO: ######################################################################
[15:54:04.781] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:54:04.781] <TB1> INFO: ######################################################################
[15:54:05.124] <TB1> INFO: Expecting 41600 events.
[15:54:09.506] <TB1> INFO: 41600 events read in total (3666ms).
[15:54:09.507] <TB1> INFO: Test took 4724ms.
[15:54:09.515] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:10.104] <TB1> INFO: Expecting 41600 events.
[15:54:14.492] <TB1> INFO: 41600 events read in total (3671ms).
[15:54:14.493] <TB1> INFO: Test took 4723ms.
[15:54:14.500] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:14.838] <TB1> INFO: Expecting 41600 events.
[15:54:19.396] <TB1> INFO: 41600 events read in total (3841ms).
[15:54:19.397] <TB1> INFO: Test took 4883ms.
[15:54:19.404] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:19.734] <TB1> INFO: Expecting 2560 events.
[15:54:20.808] <TB1> INFO: 2560 events read in total (357ms).
[15:54:20.813] <TB1> INFO: Test took 1400ms.
[15:54:21.223] <TB1> INFO: Expecting 2560 events.
[15:54:22.293] <TB1> INFO: 2560 events read in total (353ms).
[15:54:22.294] <TB1> INFO: Test took 1481ms.
[15:54:22.709] <TB1> INFO: Expecting 2560 events.
[15:54:23.780] <TB1> INFO: 2560 events read in total (355ms).
[15:54:23.780] <TB1> INFO: Test took 1485ms.
[15:54:24.196] <TB1> INFO: Expecting 2560 events.
[15:54:25.266] <TB1> INFO: 2560 events read in total (354ms).
[15:54:25.266] <TB1> INFO: Test took 1485ms.
[15:54:25.681] <TB1> INFO: Expecting 2560 events.
[15:54:26.747] <TB1> INFO: 2560 events read in total (349ms).
[15:54:26.748] <TB1> INFO: Test took 1481ms.
[15:54:27.163] <TB1> INFO: Expecting 2560 events.
[15:54:28.235] <TB1> INFO: 2560 events read in total (356ms).
[15:54:28.235] <TB1> INFO: Test took 1487ms.
[15:54:28.650] <TB1> INFO: Expecting 2560 events.
[15:54:29.717] <TB1> INFO: 2560 events read in total (350ms).
[15:54:29.718] <TB1> INFO: Test took 1482ms.
[15:54:30.133] <TB1> INFO: Expecting 2560 events.
[15:54:31.203] <TB1> INFO: 2560 events read in total (353ms).
[15:54:31.203] <TB1> INFO: Test took 1482ms.
[15:54:31.619] <TB1> INFO: Expecting 2560 events.
[15:54:32.691] <TB1> INFO: 2560 events read in total (356ms).
[15:54:32.691] <TB1> INFO: Test took 1488ms.
[15:54:33.106] <TB1> INFO: Expecting 2560 events.
[15:54:34.176] <TB1> INFO: 2560 events read in total (353ms).
[15:54:34.176] <TB1> INFO: Test took 1484ms.
[15:54:34.592] <TB1> INFO: Expecting 2560 events.
[15:54:35.664] <TB1> INFO: 2560 events read in total (356ms).
[15:54:35.665] <TB1> INFO: Test took 1489ms.
[15:54:36.079] <TB1> INFO: Expecting 2560 events.
[15:54:37.152] <TB1> INFO: 2560 events read in total (356ms).
[15:54:37.152] <TB1> INFO: Test took 1487ms.
[15:54:37.567] <TB1> INFO: Expecting 2560 events.
[15:54:38.641] <TB1> INFO: 2560 events read in total (357ms).
[15:54:38.641] <TB1> INFO: Test took 1488ms.
[15:54:39.057] <TB1> INFO: Expecting 2560 events.
[15:54:40.129] <TB1> INFO: 2560 events read in total (355ms).
[15:54:40.129] <TB1> INFO: Test took 1487ms.
[15:54:40.545] <TB1> INFO: Expecting 2560 events.
[15:54:41.612] <TB1> INFO: 2560 events read in total (351ms).
[15:54:41.612] <TB1> INFO: Test took 1483ms.
[15:54:42.028] <TB1> INFO: Expecting 2560 events.
[15:54:43.099] <TB1> INFO: 2560 events read in total (355ms).
[15:54:43.100] <TB1> INFO: Test took 1487ms.
[15:54:43.104] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:54:43.517] <TB1> INFO: Expecting 655360 events.
[15:54:57.423] <TB1> INFO: 655360 events read in total (13189ms).
[15:54:57.432] <TB1> INFO: Expecting 655360 events.
[15:55:11.140] <TB1> INFO: 655360 events read in total (13180ms).
[15:55:11.152] <TB1> INFO: Expecting 655360 events.
[15:55:26.195] <TB1> INFO: 655360 events read in total (14515ms).
[15:55:26.212] <TB1> INFO: Expecting 655360 events.
[15:55:41.518] <TB1> INFO: 655360 events read in total (14777ms).
[15:55:41.538] <TB1> INFO: Expecting 655360 events.
[15:55:56.874] <TB1> INFO: 655360 events read in total (14808ms).
[15:55:56.898] <TB1> INFO: Expecting 655360 events.
[15:56:12.122] <TB1> INFO: 655360 events read in total (14696ms).
[15:56:12.155] <TB1> INFO: Expecting 655360 events.
[15:56:27.341] <TB1> INFO: 655360 events read in total (14658ms).
[15:56:27.384] <TB1> INFO: Expecting 655360 events.
[15:56:42.660] <TB1> INFO: 655360 events read in total (14747ms).
[15:56:42.711] <TB1> INFO: Expecting 655360 events.
[15:56:57.966] <TB1> INFO: 655360 events read in total (14726ms).
[15:56:58.009] <TB1> INFO: Expecting 655360 events.
[15:57:13.179] <TB1> INFO: 655360 events read in total (14642ms).
[15:57:13.222] <TB1> INFO: Expecting 655360 events.
[15:57:28.474] <TB1> INFO: 655360 events read in total (14724ms).
[15:57:28.524] <TB1> INFO: Expecting 655360 events.
[15:57:43.679] <TB1> INFO: 655360 events read in total (14627ms).
[15:57:43.736] <TB1> INFO: Expecting 655360 events.
[15:57:59.057] <TB1> INFO: 655360 events read in total (14793ms).
[15:57:59.109] <TB1> INFO: Expecting 655360 events.
[15:58:14.373] <TB1> INFO: 655360 events read in total (14736ms).
[15:58:14.429] <TB1> INFO: Expecting 655360 events.
[15:58:29.675] <TB1> INFO: 655360 events read in total (14718ms).
[15:58:29.729] <TB1> INFO: Expecting 655360 events.
[15:58:45.012] <TB1> INFO: 655360 events read in total (14754ms).
[15:58:45.101] <TB1> INFO: Test took 241997ms.
[15:58:45.190] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:58:45.494] <TB1> INFO: Expecting 655360 events.
[15:59:00.829] <TB1> INFO: 655360 events read in total (14618ms).
[15:59:00.838] <TB1> INFO: Expecting 655360 events.
[15:59:15.933] <TB1> INFO: 655360 events read in total (14567ms).
[15:59:15.947] <TB1> INFO: Expecting 655360 events.
[15:59:31.164] <TB1> INFO: 655360 events read in total (14689ms).
[15:59:31.180] <TB1> INFO: Expecting 655360 events.
[15:59:46.075] <TB1> INFO: 655360 events read in total (14367ms).
[15:59:46.094] <TB1> INFO: Expecting 655360 events.
[16:00:01.202] <TB1> INFO: 655360 events read in total (14580ms).
[16:00:01.226] <TB1> INFO: Expecting 655360 events.
[16:00:16.283] <TB1> INFO: 655360 events read in total (14529ms).
[16:00:16.315] <TB1> INFO: Expecting 655360 events.
[16:00:31.619] <TB1> INFO: 655360 events read in total (14776ms).
[16:00:31.649] <TB1> INFO: Expecting 655360 events.
[16:00:46.719] <TB1> INFO: 655360 events read in total (14542ms).
[16:00:46.753] <TB1> INFO: Expecting 655360 events.
[16:01:01.964] <TB1> INFO: 655360 events read in total (14682ms).
[16:01:02.004] <TB1> INFO: Expecting 655360 events.
[16:01:17.219] <TB1> INFO: 655360 events read in total (14687ms).
[16:01:17.263] <TB1> INFO: Expecting 655360 events.
[16:01:32.490] <TB1> INFO: 655360 events read in total (14699ms).
[16:01:32.533] <TB1> INFO: Expecting 655360 events.
[16:01:47.707] <TB1> INFO: 655360 events read in total (14646ms).
[16:01:47.757] <TB1> INFO: Expecting 655360 events.
[16:02:02.848] <TB1> INFO: 655360 events read in total (14563ms).
[16:02:02.902] <TB1> INFO: Expecting 655360 events.
[16:02:17.964] <TB1> INFO: 655360 events read in total (14534ms).
[16:02:18.019] <TB1> INFO: Expecting 655360 events.
[16:02:33.147] <TB1> INFO: 655360 events read in total (14600ms).
[16:02:33.207] <TB1> INFO: Expecting 655360 events.
[16:02:48.240] <TB1> INFO: 655360 events read in total (14504ms).
[16:02:48.309] <TB1> INFO: Test took 243119ms.
[16:02:48.503] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.510] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:02:48.517] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:02:48.524] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:02:48.531] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.538] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:02:48.546] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:02:48.552] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:02:48.559] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.567] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.574] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.581] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.587] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.594] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.601] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.608] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.615] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.622] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:02:48.629] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.636] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.643] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.650] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.657] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:02:48.719] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:02:48.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:02:48.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:02:48.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:02:48.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:02:48.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:02:48.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:02:48.722] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:02:49.048] <TB1> INFO: Expecting 41600 events.
[16:02:53.080] <TB1> INFO: 41600 events read in total (3317ms).
[16:02:53.081] <TB1> INFO: Test took 4355ms.
[16:02:53.642] <TB1> INFO: Expecting 41600 events.
[16:02:57.647] <TB1> INFO: 41600 events read in total (3288ms).
[16:02:57.648] <TB1> INFO: Test took 4343ms.
[16:02:58.204] <TB1> INFO: Expecting 41600 events.
[16:03:02.237] <TB1> INFO: 41600 events read in total (3316ms).
[16:03:02.238] <TB1> INFO: Test took 4356ms.
[16:03:02.468] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:02.601] <TB1> INFO: Expecting 2560 events.
[16:03:03.672] <TB1> INFO: 2560 events read in total (354ms).
[16:03:03.673] <TB1> INFO: Test took 1206ms.
[16:03:03.676] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:04.090] <TB1> INFO: Expecting 2560 events.
[16:03:05.161] <TB1> INFO: 2560 events read in total (355ms).
[16:03:05.162] <TB1> INFO: Test took 1486ms.
[16:03:05.165] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:05.577] <TB1> INFO: Expecting 2560 events.
[16:03:06.649] <TB1> INFO: 2560 events read in total (355ms).
[16:03:06.650] <TB1> INFO: Test took 1485ms.
[16:03:06.653] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:07.065] <TB1> INFO: Expecting 2560 events.
[16:03:08.137] <TB1> INFO: 2560 events read in total (355ms).
[16:03:08.137] <TB1> INFO: Test took 1484ms.
[16:03:08.140] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:08.554] <TB1> INFO: Expecting 2560 events.
[16:03:09.625] <TB1> INFO: 2560 events read in total (355ms).
[16:03:09.625] <TB1> INFO: Test took 1485ms.
[16:03:09.628] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:10.042] <TB1> INFO: Expecting 2560 events.
[16:03:11.114] <TB1> INFO: 2560 events read in total (356ms).
[16:03:11.114] <TB1> INFO: Test took 1486ms.
[16:03:11.117] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:11.531] <TB1> INFO: Expecting 2560 events.
[16:03:12.595] <TB1> INFO: 2560 events read in total (348ms).
[16:03:12.595] <TB1> INFO: Test took 1478ms.
[16:03:12.599] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:13.011] <TB1> INFO: Expecting 2560 events.
[16:03:14.076] <TB1> INFO: 2560 events read in total (349ms).
[16:03:14.077] <TB1> INFO: Test took 1478ms.
[16:03:14.080] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:14.493] <TB1> INFO: Expecting 2560 events.
[16:03:15.558] <TB1> INFO: 2560 events read in total (349ms).
[16:03:15.558] <TB1> INFO: Test took 1478ms.
[16:03:15.562] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:15.974] <TB1> INFO: Expecting 2560 events.
[16:03:17.041] <TB1> INFO: 2560 events read in total (350ms).
[16:03:17.041] <TB1> INFO: Test took 1479ms.
[16:03:17.045] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:17.457] <TB1> INFO: Expecting 2560 events.
[16:03:18.522] <TB1> INFO: 2560 events read in total (349ms).
[16:03:18.523] <TB1> INFO: Test took 1478ms.
[16:03:18.525] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:18.939] <TB1> INFO: Expecting 2560 events.
[16:03:20.003] <TB1> INFO: 2560 events read in total (348ms).
[16:03:20.003] <TB1> INFO: Test took 1478ms.
[16:03:20.006] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:20.419] <TB1> INFO: Expecting 2560 events.
[16:03:21.483] <TB1> INFO: 2560 events read in total (347ms).
[16:03:21.484] <TB1> INFO: Test took 1479ms.
[16:03:21.486] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:21.899] <TB1> INFO: Expecting 2560 events.
[16:03:22.962] <TB1> INFO: 2560 events read in total (346ms).
[16:03:22.963] <TB1> INFO: Test took 1477ms.
[16:03:22.965] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:23.379] <TB1> INFO: Expecting 2560 events.
[16:03:24.443] <TB1> INFO: 2560 events read in total (347ms).
[16:03:24.443] <TB1> INFO: Test took 1478ms.
[16:03:24.446] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:24.859] <TB1> INFO: Expecting 2560 events.
[16:03:25.923] <TB1> INFO: 2560 events read in total (347ms).
[16:03:25.923] <TB1> INFO: Test took 1477ms.
[16:03:25.926] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:26.339] <TB1> INFO: Expecting 2560 events.
[16:03:27.404] <TB1> INFO: 2560 events read in total (348ms).
[16:03:27.404] <TB1> INFO: Test took 1479ms.
[16:03:27.407] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:27.820] <TB1> INFO: Expecting 2560 events.
[16:03:28.886] <TB1> INFO: 2560 events read in total (350ms).
[16:03:28.887] <TB1> INFO: Test took 1480ms.
[16:03:28.889] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:29.302] <TB1> INFO: Expecting 2560 events.
[16:03:30.367] <TB1> INFO: 2560 events read in total (348ms).
[16:03:30.367] <TB1> INFO: Test took 1478ms.
[16:03:30.371] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:30.784] <TB1> INFO: Expecting 2560 events.
[16:03:31.850] <TB1> INFO: 2560 events read in total (350ms).
[16:03:31.851] <TB1> INFO: Test took 1480ms.
[16:03:31.854] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:32.266] <TB1> INFO: Expecting 2560 events.
[16:03:33.330] <TB1> INFO: 2560 events read in total (348ms).
[16:03:33.330] <TB1> INFO: Test took 1476ms.
[16:03:33.332] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:33.748] <TB1> INFO: Expecting 2560 events.
[16:03:34.812] <TB1> INFO: 2560 events read in total (347ms).
[16:03:34.812] <TB1> INFO: Test took 1480ms.
[16:03:34.815] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:35.229] <TB1> INFO: Expecting 2560 events.
[16:03:36.293] <TB1> INFO: 2560 events read in total (348ms).
[16:03:36.293] <TB1> INFO: Test took 1478ms.
[16:03:36.296] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:36.710] <TB1> INFO: Expecting 2560 events.
[16:03:37.776] <TB1> INFO: 2560 events read in total (350ms).
[16:03:37.776] <TB1> INFO: Test took 1480ms.
[16:03:37.780] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:38.192] <TB1> INFO: Expecting 2560 events.
[16:03:39.257] <TB1> INFO: 2560 events read in total (349ms).
[16:03:39.257] <TB1> INFO: Test took 1477ms.
[16:03:39.260] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:39.673] <TB1> INFO: Expecting 2560 events.
[16:03:40.736] <TB1> INFO: 2560 events read in total (346ms).
[16:03:40.737] <TB1> INFO: Test took 1477ms.
[16:03:40.743] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:41.153] <TB1> INFO: Expecting 2560 events.
[16:03:42.217] <TB1> INFO: 2560 events read in total (348ms).
[16:03:42.217] <TB1> INFO: Test took 1474ms.
[16:03:42.220] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:42.633] <TB1> INFO: Expecting 2560 events.
[16:03:43.696] <TB1> INFO: 2560 events read in total (347ms).
[16:03:43.696] <TB1> INFO: Test took 1477ms.
[16:03:43.699] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:44.113] <TB1> INFO: Expecting 2560 events.
[16:03:45.176] <TB1> INFO: 2560 events read in total (347ms).
[16:03:45.176] <TB1> INFO: Test took 1477ms.
[16:03:45.179] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:45.592] <TB1> INFO: Expecting 2560 events.
[16:03:46.656] <TB1> INFO: 2560 events read in total (348ms).
[16:03:46.657] <TB1> INFO: Test took 1478ms.
[16:03:46.659] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:47.073] <TB1> INFO: Expecting 2560 events.
[16:03:48.137] <TB1> INFO: 2560 events read in total (348ms).
[16:03:48.137] <TB1> INFO: Test took 1478ms.
[16:03:48.140] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:03:48.553] <TB1> INFO: Expecting 2560 events.
[16:03:49.618] <TB1> INFO: 2560 events read in total (349ms).
[16:03:49.618] <TB1> INFO: Test took 1479ms.
[16:03:50.277] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 585 seconds
[16:03:50.277] <TB1> INFO: PH scale (per ROC): 69 79 74 74 66 72 79 65 63 67 74 64 73 71 73 66
[16:03:50.277] <TB1> INFO: PH offset (per ROC): 176 176 176 172 189 200 172 184 182 184 175 177 190 160 181 186
[16:03:50.282] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:50.282] <TB1> INFO: Decoding statistics:
[16:03:50.282] <TB1> INFO: General information:
[16:03:50.282] <TB1> INFO: 16bit words read: 91932
[16:03:50.282] <TB1> INFO: valid events total: 10240
[16:03:50.282] <TB1> INFO: empty events: 7737
[16:03:50.282] <TB1> INFO: valid events with pixels: 2503
[16:03:50.282] <TB1> INFO: valid pixel hits: 2503
[16:03:50.282] <TB1> INFO: Event errors: 0
[16:03:50.282] <TB1> INFO: start marker: 0
[16:03:50.282] <TB1> INFO: stop marker: 0
[16:03:50.282] <TB1> INFO: overflow: 0
[16:03:50.282] <TB1> INFO: invalid 5bit words: 0
[16:03:50.282] <TB1> INFO: invalid XOR eye diagram: 0
[16:03:50.282] <TB1> INFO: TBM errors: 0
[16:03:50.282] <TB1> INFO: flawed TBM headers: 0
[16:03:50.282] <TB1> INFO: flawed TBM trailers: 0
[16:03:50.282] <TB1> INFO: event ID mismatches: 0
[16:03:50.282] <TB1> INFO: ROC errors: 0
[16:03:50.282] <TB1> INFO: missing ROC header(s): 0
[16:03:50.282] <TB1> INFO: misplaced readback start: 0
[16:03:50.282] <TB1> INFO: Pixel decoding errors: 0
[16:03:50.282] <TB1> INFO: pixel data incomplete: 0
[16:03:50.282] <TB1> INFO: pixel address: 0
[16:03:50.282] <TB1> INFO: pulse height fill bit: 0
[16:03:50.282] <TB1> INFO: buffer corruption: 0
[16:03:50.477] <TB1> INFO: ######################################################################
[16:03:50.477] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:03:50.477] <TB1> INFO: ######################################################################
[16:03:50.488] <TB1> INFO: scanning low vcal = 10
[16:03:50.800] <TB1> INFO: Expecting 41600 events.
[16:03:54.635] <TB1> INFO: 41600 events read in total (3119ms).
[16:03:54.635] <TB1> INFO: Test took 4147ms.
[16:03:54.640] <TB1> INFO: scanning low vcal = 20
[16:03:55.051] <TB1> INFO: Expecting 41600 events.
[16:03:58.884] <TB1> INFO: 41600 events read in total (3116ms).
[16:03:58.885] <TB1> INFO: Test took 4245ms.
[16:03:58.887] <TB1> INFO: scanning low vcal = 30
[16:03:59.301] <TB1> INFO: Expecting 41600 events.
[16:04:03.180] <TB1> INFO: 41600 events read in total (3162ms).
[16:04:03.180] <TB1> INFO: Test took 4293ms.
[16:04:03.186] <TB1> INFO: scanning low vcal = 40
[16:04:03.584] <TB1> INFO: Expecting 41600 events.
[16:04:07.870] <TB1> INFO: 41600 events read in total (3570ms).
[16:04:07.871] <TB1> INFO: Test took 4685ms.
[16:04:07.874] <TB1> INFO: scanning low vcal = 50
[16:04:08.222] <TB1> INFO: Expecting 41600 events.
[16:04:12.539] <TB1> INFO: 41600 events read in total (3601ms).
[16:04:12.539] <TB1> INFO: Test took 4665ms.
[16:04:12.543] <TB1> INFO: scanning low vcal = 60
[16:04:12.889] <TB1> INFO: Expecting 41600 events.
[16:04:17.152] <TB1> INFO: 41600 events read in total (3547ms).
[16:04:17.153] <TB1> INFO: Test took 4610ms.
[16:04:17.156] <TB1> INFO: scanning low vcal = 70
[16:04:17.494] <TB1> INFO: Expecting 41600 events.
[16:04:21.765] <TB1> INFO: 41600 events read in total (3555ms).
[16:04:21.765] <TB1> INFO: Test took 4608ms.
[16:04:21.769] <TB1> INFO: scanning low vcal = 80
[16:04:22.119] <TB1> INFO: Expecting 41600 events.
[16:04:26.378] <TB1> INFO: 41600 events read in total (3543ms).
[16:04:26.379] <TB1> INFO: Test took 4610ms.
[16:04:26.382] <TB1> INFO: scanning low vcal = 90
[16:04:26.737] <TB1> INFO: Expecting 41600 events.
[16:04:31.019] <TB1> INFO: 41600 events read in total (3566ms).
[16:04:31.019] <TB1> INFO: Test took 4637ms.
[16:04:31.023] <TB1> INFO: scanning low vcal = 100
[16:04:31.366] <TB1> INFO: Expecting 41600 events.
[16:04:35.738] <TB1> INFO: 41600 events read in total (3655ms).
[16:04:35.739] <TB1> INFO: Test took 4716ms.
[16:04:35.742] <TB1> INFO: scanning low vcal = 110
[16:04:36.084] <TB1> INFO: Expecting 41600 events.
[16:04:40.349] <TB1> INFO: 41600 events read in total (3549ms).
[16:04:40.350] <TB1> INFO: Test took 4608ms.
[16:04:40.353] <TB1> INFO: scanning low vcal = 120
[16:04:40.691] <TB1> INFO: Expecting 41600 events.
[16:04:45.135] <TB1> INFO: 41600 events read in total (3728ms).
[16:04:45.136] <TB1> INFO: Test took 4783ms.
[16:04:45.140] <TB1> INFO: scanning low vcal = 130
[16:04:45.477] <TB1> INFO: Expecting 41600 events.
[16:04:49.946] <TB1> INFO: 41600 events read in total (3752ms).
[16:04:49.947] <TB1> INFO: Test took 4807ms.
[16:04:49.950] <TB1> INFO: scanning low vcal = 140
[16:04:50.289] <TB1> INFO: Expecting 41600 events.
[16:04:54.685] <TB1> INFO: 41600 events read in total (3680ms).
[16:04:54.686] <TB1> INFO: Test took 4736ms.
[16:04:54.690] <TB1> INFO: scanning low vcal = 150
[16:04:55.042] <TB1> INFO: Expecting 41600 events.
[16:04:59.471] <TB1> INFO: 41600 events read in total (3712ms).
[16:04:59.472] <TB1> INFO: Test took 4782ms.
[16:04:59.475] <TB1> INFO: scanning low vcal = 160
[16:04:59.830] <TB1> INFO: Expecting 41600 events.
[16:05:04.266] <TB1> INFO: 41600 events read in total (3720ms).
[16:05:04.267] <TB1> INFO: Test took 4792ms.
[16:05:04.270] <TB1> INFO: scanning low vcal = 170
[16:05:04.617] <TB1> INFO: Expecting 41600 events.
[16:05:09.038] <TB1> INFO: 41600 events read in total (3704ms).
[16:05:09.039] <TB1> INFO: Test took 4769ms.
[16:05:09.052] <TB1> INFO: scanning low vcal = 180
[16:05:09.380] <TB1> INFO: Expecting 41600 events.
[16:05:13.822] <TB1> INFO: 41600 events read in total (3726ms).
[16:05:13.822] <TB1> INFO: Test took 4770ms.
[16:05:13.826] <TB1> INFO: scanning low vcal = 190
[16:05:14.174] <TB1> INFO: Expecting 41600 events.
[16:05:18.622] <TB1> INFO: 41600 events read in total (3732ms).
[16:05:18.623] <TB1> INFO: Test took 4797ms.
[16:05:18.626] <TB1> INFO: scanning low vcal = 200
[16:05:18.961] <TB1> INFO: Expecting 41600 events.
[16:05:23.439] <TB1> INFO: 41600 events read in total (3762ms).
[16:05:23.440] <TB1> INFO: Test took 4814ms.
[16:05:23.443] <TB1> INFO: scanning low vcal = 210
[16:05:23.784] <TB1> INFO: Expecting 41600 events.
[16:05:28.220] <TB1> INFO: 41600 events read in total (3718ms).
[16:05:28.221] <TB1> INFO: Test took 4778ms.
[16:05:28.224] <TB1> INFO: scanning low vcal = 220
[16:05:28.577] <TB1> INFO: Expecting 41600 events.
[16:05:33.040] <TB1> INFO: 41600 events read in total (3746ms).
[16:05:33.041] <TB1> INFO: Test took 4817ms.
[16:05:33.044] <TB1> INFO: scanning low vcal = 230
[16:05:33.385] <TB1> INFO: Expecting 41600 events.
[16:05:37.820] <TB1> INFO: 41600 events read in total (3719ms).
[16:05:37.820] <TB1> INFO: Test took 4776ms.
[16:05:37.824] <TB1> INFO: scanning low vcal = 240
[16:05:38.165] <TB1> INFO: Expecting 41600 events.
[16:05:42.598] <TB1> INFO: 41600 events read in total (3717ms).
[16:05:42.599] <TB1> INFO: Test took 4775ms.
[16:05:42.602] <TB1> INFO: scanning low vcal = 250
[16:05:42.943] <TB1> INFO: Expecting 41600 events.
[16:05:47.397] <TB1> INFO: 41600 events read in total (3738ms).
[16:05:47.397] <TB1> INFO: Test took 4795ms.
[16:05:47.403] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[16:05:47.746] <TB1> INFO: Expecting 41600 events.
[16:05:52.180] <TB1> INFO: 41600 events read in total (3717ms).
[16:05:52.180] <TB1> INFO: Test took 4777ms.
[16:05:52.184] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[16:05:52.534] <TB1> INFO: Expecting 41600 events.
[16:05:56.986] <TB1> INFO: 41600 events read in total (3736ms).
[16:05:56.987] <TB1> INFO: Test took 4803ms.
[16:05:56.991] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[16:05:57.332] <TB1> INFO: Expecting 41600 events.
[16:06:01.758] <TB1> INFO: 41600 events read in total (3709ms).
[16:06:01.759] <TB1> INFO: Test took 4768ms.
[16:06:01.762] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[16:06:02.117] <TB1> INFO: Expecting 41600 events.
[16:06:06.555] <TB1> INFO: 41600 events read in total (3722ms).
[16:06:06.555] <TB1> INFO: Test took 4793ms.
[16:06:06.559] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:06:06.916] <TB1> INFO: Expecting 41600 events.
[16:06:11.393] <TB1> INFO: 41600 events read in total (3761ms).
[16:06:11.394] <TB1> INFO: Test took 4835ms.
[16:06:11.992] <TB1> INFO: PixTestGainPedestal::measure() done
[16:06:53.362] <TB1> INFO: PixTestGainPedestal::fit() done
[16:06:53.362] <TB1> INFO: non-linearity mean: 0.957 0.964 0.958 0.961 0.956 0.959 0.962 0.955 0.951 0.956 0.956 0.959 0.950 0.958 0.956 0.954
[16:06:53.362] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.008 0.007 0.005 0.007 0.008 0.007 0.006 0.006 0.008 0.007 0.006 0.007
[16:06:53.362] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:06:53.385] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:06:53.408] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:06:53.431] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:06:53.455] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:06:53.477] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:06:53.499] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:06:53.525] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:06:53.548] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:06:53.574] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:06:53.600] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:06:53.626] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:06:53.652] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:06:53.679] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:06:53.703] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:06:53.730] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:06:53.756] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 183 seconds
[16:06:53.757] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:53.757] <TB1> INFO: Decoding statistics:
[16:06:53.757] <TB1> INFO: General information:
[16:06:53.757] <TB1> INFO: 16bit words read: 2662400
[16:06:53.757] <TB1> INFO: valid events total: 166400
[16:06:53.757] <TB1> INFO: empty events: 0
[16:06:53.757] <TB1> INFO: valid events with pixels: 166400
[16:06:53.757] <TB1> INFO: valid pixel hits: 665542
[16:06:53.757] <TB1> INFO: Event errors: 0
[16:06:53.757] <TB1> INFO: start marker: 0
[16:06:53.757] <TB1> INFO: stop marker: 0
[16:06:53.757] <TB1> INFO: overflow: 0
[16:06:53.757] <TB1> INFO: invalid 5bit words: 0
[16:06:53.757] <TB1> INFO: invalid XOR eye diagram: 0
[16:06:53.757] <TB1> INFO: TBM errors: 0
[16:06:53.757] <TB1> INFO: flawed TBM headers: 0
[16:06:53.757] <TB1> INFO: flawed TBM trailers: 0
[16:06:53.757] <TB1> INFO: event ID mismatches: 0
[16:06:53.757] <TB1> INFO: ROC errors: 0
[16:06:53.757] <TB1> INFO: missing ROC header(s): 0
[16:06:53.757] <TB1> INFO: misplaced readback start: 0
[16:06:53.757] <TB1> INFO: Pixel decoding errors: 0
[16:06:53.757] <TB1> INFO: pixel data incomplete: 0
[16:06:53.757] <TB1> INFO: pixel address: 0
[16:06:53.757] <TB1> INFO: pulse height fill bit: 0
[16:06:53.757] <TB1> INFO: buffer corruption: 0
[16:06:53.764] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C15.dat
[16:06:53.766] <TB1> INFO: ######################################################################
[16:06:53.766] <TB1> INFO: PixTestReadback::doTest()
[16:06:53.766] <TB1> INFO: ######################################################################
[16:06:53.767] <TB1> INFO: PixTestReadback::RES sent once
[16:07:05.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C0.dat
[16:07:05.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C1.dat
[16:07:05.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C2.dat
[16:07:05.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C3.dat
[16:07:05.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C4.dat
[16:07:05.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C5.dat
[16:07:05.141] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C6.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C7.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C8.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C9.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C10.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C11.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C12.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C13.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C14.dat
[16:07:05.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C15.dat
[16:07:05.191] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:07:05.191] <TB1> INFO: PixTestReadback::RES sent once
[16:07:16.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C0.dat
[16:07:16.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C1.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C2.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C3.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C4.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C5.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C6.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C7.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C8.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C9.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C10.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C11.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C12.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C13.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C14.dat
[16:07:16.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C15.dat
[16:07:16.535] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:07:16.535] <TB1> INFO: PixTestReadback::RES sent once
[16:07:25.211] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:07:25.211] <TB1> INFO: Vbg will be calibrated using Vd calibration
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 161.6calibrated Vbg = 1.18988 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159.6calibrated Vbg = 1.19641 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 158.7calibrated Vbg = 1.19893 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 159calibrated Vbg = 1.20618 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.3calibrated Vbg = 1.20478 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 157calibrated Vbg = 1.21372 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.9calibrated Vbg = 1.2093 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 158calibrated Vbg = 1.20653 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.8calibrated Vbg = 1.21349 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.4calibrated Vbg = 1.20171 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149calibrated Vbg = 1.2081 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.6calibrated Vbg = 1.2024 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 167.1calibrated Vbg = 1.19289 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151.4calibrated Vbg = 1.19703 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 161.7calibrated Vbg = 1.19803 :::*/*/*/*/
[16:07:25.212] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 152.8calibrated Vbg = 1.20375 :::*/*/*/*/
[16:07:25.219] <TB1> INFO: PixTestReadback::RES sent once
[16:10:20.655] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C0.dat
[16:10:20.655] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C1.dat
[16:10:20.655] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C2.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C3.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C4.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C5.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C6.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C7.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C8.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C9.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C10.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C11.dat
[16:10:20.656] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C12.dat
[16:10:20.657] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C13.dat
[16:10:20.657] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C14.dat
[16:10:20.657] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2122_FullQualification_2015-12-07_10h25m_1449480354//004_FulltestPxar_p17//readbackCal_C15.dat
[16:10:20.703] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:10:20.704] <TB1> INFO: PixTestReadback::doTest() done
[16:10:20.704] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:20.704] <TB1> INFO: Decoding statistics:
[16:10:20.704] <TB1> INFO: General information:
[16:10:20.704] <TB1> INFO: 16bit words read: 1024
[16:10:20.704] <TB1> INFO: valid events total: 128
[16:10:20.704] <TB1> INFO: empty events: 128
[16:10:20.704] <TB1> INFO: valid events with pixels: 0
[16:10:20.705] <TB1> INFO: valid pixel hits: 0
[16:10:20.705] <TB1> INFO: Event errors: 0
[16:10:20.705] <TB1> INFO: start marker: 0
[16:10:20.705] <TB1> INFO: stop marker: 0
[16:10:20.705] <TB1> INFO: overflow: 0
[16:10:20.705] <TB1> INFO: invalid 5bit words: 0
[16:10:20.705] <TB1> INFO: invalid XOR eye diagram: 0
[16:10:20.705] <TB1> INFO: TBM errors: 0
[16:10:20.705] <TB1> INFO: flawed TBM headers: 0
[16:10:20.705] <TB1> INFO: flawed TBM trailers: 0
[16:10:20.705] <TB1> INFO: event ID mismatches: 0
[16:10:20.705] <TB1> INFO: ROC errors: 0
[16:10:20.705] <TB1> INFO: missing ROC header(s): 0
[16:10:20.705] <TB1> INFO: misplaced readback start: 0
[16:10:20.705] <TB1> INFO: Pixel decoding errors: 0
[16:10:20.705] <TB1> INFO: pixel data incomplete: 0
[16:10:20.705] <TB1> INFO: pixel address: 0
[16:10:20.705] <TB1> INFO: pulse height fill bit: 0
[16:10:20.705] <TB1> INFO: buffer corruption: 0
[16:10:20.724] <TB1> INFO: Decoding statistics:
[16:10:20.724] <TB1> INFO: General information:
[16:10:20.724] <TB1> INFO: 16bit words read: 2755356
[16:10:20.724] <TB1> INFO: valid events total: 176768
[16:10:20.724] <TB1> INFO: empty events: 7865
[16:10:20.724] <TB1> INFO: valid events with pixels: 168903
[16:10:20.724] <TB1> INFO: valid pixel hits: 668045
[16:10:20.724] <TB1> INFO: Event errors: 0
[16:10:20.724] <TB1> INFO: start marker: 0
[16:10:20.724] <TB1> INFO: stop marker: 0
[16:10:20.724] <TB1> INFO: overflow: 0
[16:10:20.724] <TB1> INFO: invalid 5bit words: 0
[16:10:20.724] <TB1> INFO: invalid XOR eye diagram: 0
[16:10:20.724] <TB1> INFO: TBM errors: 0
[16:10:20.724] <TB1> INFO: flawed TBM headers: 0
[16:10:20.724] <TB1> INFO: flawed TBM trailers: 0
[16:10:20.724] <TB1> INFO: event ID mismatches: 0
[16:10:20.724] <TB1> INFO: ROC errors: 0
[16:10:20.724] <TB1> INFO: missing ROC header(s): 0
[16:10:20.724] <TB1> INFO: misplaced readback start: 0
[16:10:20.724] <TB1> INFO: Pixel decoding errors: 0
[16:10:20.724] <TB1> INFO: pixel data incomplete: 0
[16:10:20.725] <TB1> INFO: pixel address: 0
[16:10:20.725] <TB1> INFO: pulse height fill bit: 0
[16:10:20.725] <TB1> INFO: buffer corruption: 0
[16:10:20.725] <TB1> INFO: enter test to run
[16:10:20.725] <TB1> INFO: test: exit no parameter change
[16:10:20.973] <TB1> QUIET: Connection to board 153 closed.
[16:10:21.058] <TB1> INFO: pXar: this is the end, my friend