[15:49:27.502] <TB0> INFO: *** Welcome to pxar ***
[15:49:27.502] <TB0> INFO: *** Today: 2016/04/10
[15:49:28.004] <TB0> INFO: *** Version: 9751-dirty
[15:49:28.004] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C15.dat
[15:49:28.004] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:49:28.004] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//defaultMaskFile.dat
[15:49:28.004] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters_C15.dat
[15:49:28.072] <TB0> INFO: clk: 4
[15:49:28.072] <TB0> INFO: ctr: 4
[15:49:28.072] <TB0> INFO: sda: 19
[15:49:28.072] <TB0> INFO: tin: 9
[15:49:28.072] <TB0> INFO: level: 15
[15:49:28.072] <TB0> INFO: triggerdelay: 0
[15:49:28.072] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[15:49:28.072] <TB0> INFO: Log level: INFO
[15:49:28.079] <TB0> INFO: Found DTB DTB_WS6AYH
[15:49:28.090] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[15:49:28.092] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[15:49:28.094] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[15:49:29.623] <TB0> INFO: DUT info:
[15:49:29.623] <TB0> INFO: The DUT currently contains the following objects:
[15:49:29.623] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:49:29.623] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:49:29.623] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:49:29.623] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:49:29.623] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.623] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.623] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:29.624] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:30.024] <TB0> INFO: enter 'restricted' command line mode
[15:49:30.024] <TB0> INFO: enter test to run
[15:49:30.024] <TB0> INFO: test: pretest no parameter change
[15:49:30.024] <TB0> INFO: running: pretest
[15:49:30.028] <TB0> INFO: ----------------------------------------------------------------------
[15:49:30.028] <TB0> INFO: PixTestPretest::programROC()
[15:49:30.028] <TB0> INFO: ----------------------------------------------------------------------
[15:49:48.043] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:49:48.043] <TB0> INFO: IA differences per ROC: 19.3 19.3 17.7 18.5 20.1 19.3 17.7 17.7 20.1 20.1 20.1 20.1 17.7 19.3 18.5 19.3
[15:49:48.138] <TB0> INFO: enter test to run
[15:49:48.138] <TB0> INFO: test: pretest no parameter change
[15:49:48.138] <TB0> INFO: running: pretest
[15:49:48.139] <TB0> INFO: ----------------------------------------------------------------------
[15:49:48.139] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:49:48.139] <TB0> INFO: ----------------------------------------------------------------------
[15:49:54.025] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[15:49:54.025] <TB0> INFO: i(loss) [mA/ROC]: 18.5 19.3 18.5 18.5 18.5 17.6 18.5 17.6 17.6 18.5 17.6 18.5 18.5 17.6 18.5 17.6
[15:49:54.059] <TB0> INFO: enter test to run
[15:49:54.059] <TB0> INFO: test: pretest no parameter change
[15:49:54.059] <TB0> INFO: running: pretest
[15:49:54.059] <TB0> INFO: ----------------------------------------------------------------------
[15:49:54.059] <TB0> INFO: PixTestPretest::findTiming()
[15:49:54.059] <TB0> INFO: ----------------------------------------------------------------------
[15:49:54.059] <TB0> INFO: PixTestCmd::init()
[15:49:54.907] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[15:51:21.798] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[15:51:21.798] <TB0> INFO: (success/tries = 100/100), width = 3
[15:51:21.799] <TB0> INFO: enter test to run
[15:51:21.799] <TB0> INFO: test: pretest no parameter change
[15:51:21.799] <TB0> INFO: running: pretest
[15:51:21.800] <TB0> INFO: ----------------------------------------------------------------------
[15:51:21.801] <TB0> INFO: PixTestPretest::findWorkingPixel()
[15:51:21.801] <TB0> INFO: ----------------------------------------------------------------------
[15:51:21.894] <TB0> INFO: Expecting 231680 events.
[15:51:27.667] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[15:51:27.749] <TB0> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[15:51:32.150] <TB0> INFO: 231680 events read in total (9699ms).
[15:51:32.154] <TB0> INFO: Test took 10348ms.
[15:51:32.393] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:51:32.459] <TB0> INFO: enter test to run
[15:51:32.459] <TB0> INFO: test: pretest no parameter change
[15:51:32.459] <TB0> INFO: running: pretest
[15:51:32.460] <TB0> INFO: ----------------------------------------------------------------------
[15:51:32.460] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[15:51:32.460] <TB0> INFO: ----------------------------------------------------------------------
[15:51:32.552] <TB0> INFO: Expecting 231680 events.
[15:51:42.930] <TB0> INFO: 231680 events read in total (9822ms).
[15:51:42.934] <TB0> INFO: Test took 10470ms.
[15:51:43.196] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[15:51:43.196] <TB0> INFO: CalDel: 145 120 132 131 144 139 131 137 154 128 138 145 131 154 146 158
[15:51:43.196] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 53 51 51 51 51 51 51
[15:51:43.242] <TB0> INFO: enter test to run
[15:51:43.242] <TB0> INFO: test: pretest no parameter change
[15:51:43.242] <TB0> INFO: running: pretest
[15:51:43.242] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C0.dat
[15:51:43.242] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C1.dat
[15:51:43.242] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C2.dat
[15:51:43.242] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C3.dat
[15:51:43.242] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C4.dat
[15:51:43.242] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C5.dat
[15:51:43.242] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C6.dat
[15:51:43.243] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C7.dat
[15:51:43.243] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C8.dat
[15:51:43.243] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C9.dat
[15:51:43.243] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C10.dat
[15:51:43.243] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C11.dat
[15:51:43.243] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C12.dat
[15:51:43.243] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C13.dat
[15:51:43.243] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C14.dat
[15:51:43.244] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters_C15.dat
[15:51:43.244] <TB0> INFO: enter test to run
[15:51:43.244] <TB0> INFO: test: fulltest no parameter change
[15:51:43.244] <TB0> INFO: running: fulltest
[15:51:43.244] <TB0> INFO: ######################################################################
[15:51:43.244] <TB0> INFO: PixTestFullTest::doTest()
[15:51:43.244] <TB0> INFO: ######################################################################
[15:51:43.245] <TB0> INFO: ######################################################################
[15:51:43.245] <TB0> INFO: PixTestAlive::doTest()
[15:51:43.245] <TB0> INFO: ######################################################################
[15:51:43.246] <TB0> INFO: ----------------------------------------------------------------------
[15:51:43.246] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:51:43.246] <TB0> INFO: ----------------------------------------------------------------------
[15:51:43.530] <TB0> INFO: Expecting 41600 events.
[15:51:47.365] <TB0> INFO: 41600 events read in total (3278ms).
[15:51:47.366] <TB0> INFO: Test took 4119ms.
[15:51:47.594] <TB0> INFO: PixTestAlive::aliveTest() done
[15:51:47.594] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:51:47.596] <TB0> INFO: ----------------------------------------------------------------------
[15:51:47.596] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:51:47.596] <TB0> INFO: ----------------------------------------------------------------------
[15:51:47.895] <TB0> INFO: Expecting 41600 events.
[15:51:50.896] <TB0> INFO: 41600 events read in total (2445ms).
[15:51:50.897] <TB0> INFO: Test took 3299ms.
[15:51:50.897] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:51:51.144] <TB0> INFO: PixTestAlive::maskTest() done
[15:51:51.145] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:51:51.146] <TB0> INFO: ----------------------------------------------------------------------
[15:51:51.146] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:51:51.146] <TB0> INFO: ----------------------------------------------------------------------
[15:51:51.420] <TB0> INFO: Expecting 41600 events.
[15:51:55.201] <TB0> INFO: 41600 events read in total (3225ms).
[15:51:55.202] <TB0> INFO: Test took 4054ms.
[15:51:55.429] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[15:51:55.429] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:51:55.429] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:51:55.430] <TB0> INFO: Decoding statistics:
[15:51:55.430] <TB0> INFO: General information:
[15:51:55.430] <TB0> INFO: 16bit words read: 0
[15:51:55.430] <TB0> INFO: valid events total: 0
[15:51:55.430] <TB0> INFO: empty events: 0
[15:51:55.430] <TB0> INFO: valid events with pixels: 0
[15:51:55.430] <TB0> INFO: valid pixel hits: 0
[15:51:55.430] <TB0> INFO: Event errors: 0
[15:51:55.430] <TB0> INFO: start marker: 0
[15:51:55.430] <TB0> INFO: stop marker: 0
[15:51:55.430] <TB0> INFO: overflow: 0
[15:51:55.430] <TB0> INFO: invalid 5bit words: 0
[15:51:55.430] <TB0> INFO: invalid XOR eye diagram: 0
[15:51:55.430] <TB0> INFO: frame (failed synchr.): 0
[15:51:55.430] <TB0> INFO: idle data (no TBM trl): 0
[15:51:55.430] <TB0> INFO: no data (only TBM hdr): 0
[15:51:55.430] <TB0> INFO: TBM errors: 0
[15:51:55.430] <TB0> INFO: flawed TBM headers: 0
[15:51:55.430] <TB0> INFO: flawed TBM trailers: 0
[15:51:55.430] <TB0> INFO: event ID mismatches: 0
[15:51:55.430] <TB0> INFO: ROC errors: 0
[15:51:55.430] <TB0> INFO: missing ROC header(s): 0
[15:51:55.430] <TB0> INFO: misplaced readback start: 0
[15:51:55.430] <TB0> INFO: Pixel decoding errors: 0
[15:51:55.430] <TB0> INFO: pixel data incomplete: 0
[15:51:55.430] <TB0> INFO: pixel address: 0
[15:51:55.430] <TB0> INFO: pulse height fill bit: 0
[15:51:55.430] <TB0> INFO: buffer corruption: 0
[15:51:55.441] <TB0> INFO: ######################################################################
[15:51:55.441] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:51:55.441] <TB0> INFO: ######################################################################
[15:51:55.443] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:51:55.454] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:51:55.454] <TB0> INFO: run 1 of 1
[15:51:55.719] <TB0> INFO: Expecting 3120000 events.
[15:52:38.175] <TB0> INFO: 880965 events read in total (41900ms).
[15:53:19.084] <TB0> INFO: 1752655 events read in total (82810ms).
[15:53:59.730] <TB0> INFO: 2635075 events read in total (123456ms).
[15:54:22.115] <TB0> INFO: 3120000 events read in total (145840ms).
[15:54:22.167] <TB0> INFO: Test took 146713ms.
[15:54:45.698] <TB0> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[15:54:45.698] <TB0> INFO: number of dead bumps (per ROC): 0 1 0 0 0 0 0 2 4 0 0 0 0 0 1 2
[15:54:45.698] <TB0> INFO: separation cut (per ROC): 85 93 96 92 93 99 106 97 106 108 103 93 91 96 91 89
[15:54:45.698] <TB0> INFO: Decoding statistics:
[15:54:45.698] <TB0> INFO: General information:
[15:54:45.698] <TB0> INFO: 16bit words read: 0
[15:54:45.698] <TB0> INFO: valid events total: 0
[15:54:45.698] <TB0> INFO: empty events: 0
[15:54:45.698] <TB0> INFO: valid events with pixels: 0
[15:54:45.698] <TB0> INFO: valid pixel hits: 0
[15:54:45.698] <TB0> INFO: Event errors: 0
[15:54:45.698] <TB0> INFO: start marker: 0
[15:54:45.698] <TB0> INFO: stop marker: 0
[15:54:45.698] <TB0> INFO: overflow: 0
[15:54:45.698] <TB0> INFO: invalid 5bit words: 0
[15:54:45.698] <TB0> INFO: invalid XOR eye diagram: 0
[15:54:45.698] <TB0> INFO: frame (failed synchr.): 0
[15:54:45.698] <TB0> INFO: idle data (no TBM trl): 0
[15:54:45.698] <TB0> INFO: no data (only TBM hdr): 0
[15:54:45.698] <TB0> INFO: TBM errors: 0
[15:54:45.698] <TB0> INFO: flawed TBM headers: 0
[15:54:45.698] <TB0> INFO: flawed TBM trailers: 0
[15:54:45.698] <TB0> INFO: event ID mismatches: 0
[15:54:45.698] <TB0> INFO: ROC errors: 0
[15:54:45.698] <TB0> INFO: missing ROC header(s): 0
[15:54:45.698] <TB0> INFO: misplaced readback start: 0
[15:54:45.698] <TB0> INFO: Pixel decoding errors: 0
[15:54:45.698] <TB0> INFO: pixel data incomplete: 0
[15:54:45.698] <TB0> INFO: pixel address: 0
[15:54:45.698] <TB0> INFO: pulse height fill bit: 0
[15:54:45.698] <TB0> INFO: buffer corruption: 0
[15:54:45.768] <TB0> INFO: ######################################################################
[15:54:45.768] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:54:45.768] <TB0> INFO: ######################################################################
[15:54:45.768] <TB0> INFO: ----------------------------------------------------------------------
[15:54:45.768] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:54:45.768] <TB0> INFO: ----------------------------------------------------------------------
[15:54:45.768] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:54:45.776] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[15:54:45.776] <TB0> INFO: run 1 of 1
[15:54:46.036] <TB0> INFO: Expecting 26208000 events.
[15:55:20.328] <TB0> INFO: 936000 events read in total (33736ms).
[15:55:53.418] <TB0> INFO: 1855450 events read in total (66826ms).
[15:56:26.455] <TB0> INFO: 2775950 events read in total (99863ms).
[15:57:00.004] <TB0> INFO: 3694500 events read in total (133412ms).
[15:57:32.821] <TB0> INFO: 4613250 events read in total (166229ms).
[15:58:06.074] <TB0> INFO: 5529300 events read in total (199482ms).
[15:58:39.539] <TB0> INFO: 6445000 events read in total (232947ms).
[15:59:12.742] <TB0> INFO: 7358450 events read in total (266150ms).
[15:59:46.160] <TB0> INFO: 8273750 events read in total (299568ms).
[16:00:19.176] <TB0> INFO: 9187000 events read in total (332584ms).
[16:00:52.455] <TB0> INFO: 10097600 events read in total (365863ms).
[16:01:25.402] <TB0> INFO: 11005950 events read in total (398810ms).
[16:01:58.942] <TB0> INFO: 11916000 events read in total (432350ms).
[16:02:32.075] <TB0> INFO: 12824200 events read in total (465483ms).
[16:03:05.438] <TB0> INFO: 13722750 events read in total (498846ms).
[16:03:39.133] <TB0> INFO: 14620950 events read in total (532541ms).
[16:04:12.578] <TB0> INFO: 15519200 events read in total (565986ms).
[16:04:46.489] <TB0> INFO: 16414350 events read in total (599897ms).
[16:05:19.842] <TB0> INFO: 17309700 events read in total (633250ms).
[16:05:52.753] <TB0> INFO: 18204600 events read in total (666161ms).
[16:06:25.727] <TB0> INFO: 19096750 events read in total (699135ms).
[16:06:58.745] <TB0> INFO: 19988500 events read in total (732153ms).
[16:07:32.007] <TB0> INFO: 20881800 events read in total (765415ms).
[16:08:05.003] <TB0> INFO: 21774150 events read in total (798411ms).
[16:08:38.407] <TB0> INFO: 22666100 events read in total (831815ms).
[16:09:11.944] <TB0> INFO: 23560950 events read in total (865352ms).
[16:09:45.197] <TB0> INFO: 24456200 events read in total (898605ms).
[16:10:18.482] <TB0> INFO: 25354550 events read in total (931890ms).
[16:10:50.246] <TB0> INFO: 26208000 events read in total (963654ms).
[16:10:50.276] <TB0> INFO: Test took 964501ms.
[16:10:50.532] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:10:52.158] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:10:53.700] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:10:55.184] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:10:56.855] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:10:58.543] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:00.059] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:01.583] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:03.072] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:04.514] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:05.889] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:07.471] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:09.079] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:10.548] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:11.996] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:13.508] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:11:15.316] <TB0> INFO: PixTestScurves::scurves() done
[16:11:15.316] <TB0> INFO: Vcal mean: 85.99 88.80 95.27 96.70 93.70 95.67 103.18 96.82 104.94 106.31 97.27 95.59 94.93 101.87 89.30 87.61
[16:11:15.316] <TB0> INFO: Vcal RMS: 4.97 5.51 4.97 5.44 5.17 5.57 5.25 5.45 7.76 5.94 5.94 4.84 5.43 7.55 5.65 4.83
[16:11:15.316] <TB0> INFO: PixTestScurves::fullTest() done, duration: 989 seconds
[16:11:15.316] <TB0> INFO: Decoding statistics:
[16:11:15.316] <TB0> INFO: General information:
[16:11:15.316] <TB0> INFO: 16bit words read: 0
[16:11:15.316] <TB0> INFO: valid events total: 0
[16:11:15.316] <TB0> INFO: empty events: 0
[16:11:15.316] <TB0> INFO: valid events with pixels: 0
[16:11:15.316] <TB0> INFO: valid pixel hits: 0
[16:11:15.316] <TB0> INFO: Event errors: 0
[16:11:15.316] <TB0> INFO: start marker: 0
[16:11:15.316] <TB0> INFO: stop marker: 0
[16:11:15.316] <TB0> INFO: overflow: 0
[16:11:15.316] <TB0> INFO: invalid 5bit words: 0
[16:11:15.316] <TB0> INFO: invalid XOR eye diagram: 0
[16:11:15.316] <TB0> INFO: frame (failed synchr.): 0
[16:11:15.316] <TB0> INFO: idle data (no TBM trl): 0
[16:11:15.316] <TB0> INFO: no data (only TBM hdr): 0
[16:11:15.316] <TB0> INFO: TBM errors: 0
[16:11:15.316] <TB0> INFO: flawed TBM headers: 0
[16:11:15.316] <TB0> INFO: flawed TBM trailers: 0
[16:11:15.316] <TB0> INFO: event ID mismatches: 0
[16:11:15.316] <TB0> INFO: ROC errors: 0
[16:11:15.316] <TB0> INFO: missing ROC header(s): 0
[16:11:15.316] <TB0> INFO: misplaced readback start: 0
[16:11:15.316] <TB0> INFO: Pixel decoding errors: 0
[16:11:15.316] <TB0> INFO: pixel data incomplete: 0
[16:11:15.316] <TB0> INFO: pixel address: 0
[16:11:15.316] <TB0> INFO: pulse height fill bit: 0
[16:11:15.316] <TB0> INFO: buffer corruption: 0
[16:11:15.386] <TB0> INFO: ######################################################################
[16:11:15.386] <TB0> INFO: PixTestTrim::doTest()
[16:11:15.386] <TB0> INFO: ######################################################################
[16:11:15.387] <TB0> INFO: ----------------------------------------------------------------------
[16:11:15.387] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[16:11:15.387] <TB0> INFO: ----------------------------------------------------------------------
[16:11:15.465] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:11:15.465] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:11:15.472] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:11:15.473] <TB0> INFO: run 1 of 1
[16:11:15.736] <TB0> INFO: Expecting 6281600 events.
[16:11:55.683] <TB0> INFO: 1086260 events read in total (39389ms).
[16:12:35.075] <TB0> INFO: 2166880 events read in total (78781ms).
[16:13:14.364] <TB0> INFO: 3240370 events read in total (118070ms).
[16:13:53.996] <TB0> INFO: 4321460 events read in total (157702ms).
[16:14:33.560] <TB0> INFO: 5406060 events read in total (197266ms).
[16:15:05.865] <TB0> INFO: 6281600 events read in total (229571ms).
[16:15:05.904] <TB0> INFO: Test took 230431ms.
[16:15:26.555] <TB0> INFO: ROC 0 VthrComp = 86
[16:15:26.555] <TB0> INFO: ROC 1 VthrComp = 91
[16:15:26.555] <TB0> INFO: ROC 2 VthrComp = 97
[16:15:26.555] <TB0> INFO: ROC 3 VthrComp = 97
[16:15:26.555] <TB0> INFO: ROC 4 VthrComp = 95
[16:15:26.555] <TB0> INFO: ROC 5 VthrComp = 95
[16:15:26.555] <TB0> INFO: ROC 6 VthrComp = 104
[16:15:26.556] <TB0> INFO: ROC 7 VthrComp = 97
[16:15:26.556] <TB0> INFO: ROC 8 VthrComp = 96
[16:15:26.556] <TB0> INFO: ROC 9 VthrComp = 105
[16:15:26.556] <TB0> INFO: ROC 10 VthrComp = 96
[16:15:26.556] <TB0> INFO: ROC 11 VthrComp = 96
[16:15:26.556] <TB0> INFO: ROC 12 VthrComp = 91
[16:15:26.556] <TB0> INFO: ROC 13 VthrComp = 91
[16:15:26.556] <TB0> INFO: ROC 14 VthrComp = 87
[16:15:26.556] <TB0> INFO: ROC 15 VthrComp = 87
[16:15:26.556] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:15:26.556] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:15:26.565] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:15:26.565] <TB0> INFO: run 1 of 1
[16:15:26.853] <TB0> INFO: Expecting 6281600 events.
[16:16:02.112] <TB0> INFO: 757300 events read in total (34702ms).
[16:16:36.758] <TB0> INFO: 1512420 events read in total (69348ms).
[16:17:11.579] <TB0> INFO: 2267700 events read in total (104169ms).
[16:17:46.132] <TB0> INFO: 3023320 events read in total (138722ms).
[16:18:21.183] <TB0> INFO: 3773230 events read in total (173773ms).
[16:18:56.383] <TB0> INFO: 4519370 events read in total (208973ms).
[16:19:31.264] <TB0> INFO: 5263690 events read in total (243854ms).
[16:20:05.951] <TB0> INFO: 6008060 events read in total (278541ms).
[16:20:19.079] <TB0> INFO: 6281600 events read in total (291669ms).
[16:20:19.116] <TB0> INFO: Test took 292552ms.
[16:20:43.767] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 58.139 for pixel 22/78 mean/min/max = 45.6036/33.0615/58.1457
[16:20:43.768] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 59.6406 for pixel 14/77 mean/min/max = 46.2847/32.7829/59.7865
[16:20:43.768] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 56.7942 for pixel 9/37 mean/min/max = 44.3845/31.9684/56.8005
[16:20:43.768] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 58.6423 for pixel 4/15 mean/min/max = 45.0651/31.4445/58.6857
[16:20:43.768] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 57.7452 for pixel 19/77 mean/min/max = 45.0555/32.0667/58.0443
[16:20:43.768] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 59.2857 for pixel 0/46 mean/min/max = 46.3643/33.4318/59.2967
[16:20:43.769] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 59.2071 for pixel 30/1 mean/min/max = 46.8051/34.4031/59.2072
[16:20:43.769] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.4595 for pixel 15/11 mean/min/max = 44.8198/31.9141/57.7255
[16:20:43.769] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 69.6406 for pixel 19/79 mean/min/max = 50.1806/30.6389/69.7223
[16:20:43.769] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 65.5654 for pixel 23/79 mean/min/max = 49.6954/33.7601/65.6308
[16:20:43.770] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 59.3896 for pixel 0/22 mean/min/max = 45.861/32.3256/59.3964
[16:20:43.770] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 56.4671 for pixel 23/31 mean/min/max = 44.6667/32.4752/56.8581
[16:20:43.770] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 60.1047 for pixel 20/67 mean/min/max = 46.773/33.3898/60.1562
[16:20:43.770] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 70.7473 for pixel 13/79 mean/min/max = 51.8049/32.0768/71.533
[16:20:43.771] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.8035 for pixel 6/4 mean/min/max = 46.3144/32.6302/59.9985
[16:20:43.771] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 56.3017 for pixel 37/60 mean/min/max = 44.4568/32.4656/56.4479
[16:20:43.771] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:20:43.859] <TB0> INFO: Expecting 514560 events.
[16:20:56.866] <TB0> INFO: 514560 events read in total (12451ms).
[16:20:56.873] <TB0> INFO: Expecting 514560 events.
[16:21:09.738] <TB0> INFO: 514560 events read in total (12460ms).
[16:21:09.745] <TB0> INFO: Expecting 514560 events.
[16:21:22.550] <TB0> INFO: 514560 events read in total (12392ms).
[16:21:22.561] <TB0> INFO: Expecting 514560 events.
[16:21:35.447] <TB0> INFO: 514560 events read in total (12478ms).
[16:21:35.459] <TB0> INFO: Expecting 514560 events.
[16:21:48.397] <TB0> INFO: 514560 events read in total (12522ms).
[16:21:48.410] <TB0> INFO: Expecting 514560 events.
[16:22:01.297] <TB0> INFO: 514560 events read in total (12480ms).
[16:22:01.313] <TB0> INFO: Expecting 514560 events.
[16:22:14.168] <TB0> INFO: 514560 events read in total (12441ms).
[16:22:14.185] <TB0> INFO: Expecting 514560 events.
[16:22:26.998] <TB0> INFO: 514560 events read in total (12410ms).
[16:22:27.019] <TB0> INFO: Expecting 514560 events.
[16:22:39.867] <TB0> INFO: 514560 events read in total (12446ms).
[16:22:39.888] <TB0> INFO: Expecting 514560 events.
[16:22:52.895] <TB0> INFO: 514560 events read in total (12600ms).
[16:22:52.924] <TB0> INFO: Expecting 514560 events.
[16:23:05.638] <TB0> INFO: 514560 events read in total (12313ms).
[16:23:05.662] <TB0> INFO: Expecting 514560 events.
[16:23:18.534] <TB0> INFO: 514560 events read in total (12464ms).
[16:23:18.567] <TB0> INFO: Expecting 514560 events.
[16:23:31.396] <TB0> INFO: 514560 events read in total (12427ms).
[16:23:31.428] <TB0> INFO: Expecting 514560 events.
[16:23:44.149] <TB0> INFO: 514560 events read in total (12320ms).
[16:23:44.182] <TB0> INFO: Expecting 514560 events.
[16:23:57.004] <TB0> INFO: 514560 events read in total (12421ms).
[16:23:57.036] <TB0> INFO: Expecting 514560 events.
[16:24:09.923] <TB0> INFO: 514560 events read in total (12485ms).
[16:24:09.956] <TB0> INFO: Test took 206185ms.
[16:24:10.833] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:24:10.841] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:24:10.841] <TB0> INFO: run 1 of 1
[16:24:11.100] <TB0> INFO: Expecting 6281600 events.
[16:24:46.177] <TB0> INFO: 743650 events read in total (34520ms).
[16:25:21.570] <TB0> INFO: 1485450 events read in total (69913ms).
[16:25:56.541] <TB0> INFO: 2227740 events read in total (104884ms).
[16:26:31.611] <TB0> INFO: 2969910 events read in total (139954ms).
[16:27:06.130] <TB0> INFO: 3705980 events read in total (174473ms).
[16:27:40.611] <TB0> INFO: 4436720 events read in total (208954ms).
[16:28:14.932] <TB0> INFO: 5164890 events read in total (243275ms).
[16:28:49.352] <TB0> INFO: 5891290 events read in total (277695ms).
[16:29:07.811] <TB0> INFO: 6281600 events read in total (296154ms).
[16:29:07.858] <TB0> INFO: Test took 297017ms.
[16:29:33.185] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.005116 .. 255.000000
[16:29:33.261] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[16:29:33.269] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:29:33.269] <TB0> INFO: run 1 of 1
[16:29:33.530] <TB0> INFO: Expecting 10649600 events.
[16:30:08.322] <TB0> INFO: 711320 events read in total (34236ms).
[16:30:42.805] <TB0> INFO: 1423130 events read in total (68719ms).
[16:31:17.110] <TB0> INFO: 2135200 events read in total (103024ms).
[16:31:51.074] <TB0> INFO: 2847500 events read in total (136988ms).
[16:32:25.218] <TB0> INFO: 3559940 events read in total (171132ms).
[16:32:59.447] <TB0> INFO: 4272030 events read in total (205361ms).
[16:33:33.846] <TB0> INFO: 4984630 events read in total (239760ms).
[16:34:08.218] <TB0> INFO: 5697400 events read in total (274132ms).
[16:34:42.389] <TB0> INFO: 6409860 events read in total (308303ms).
[16:35:16.388] <TB0> INFO: 7121240 events read in total (342302ms).
[16:35:50.367] <TB0> INFO: 7832940 events read in total (376281ms).
[16:36:24.471] <TB0> INFO: 8544070 events read in total (410385ms).
[16:36:58.634] <TB0> INFO: 9255140 events read in total (444548ms).
[16:37:33.072] <TB0> INFO: 9965670 events read in total (478986ms).
[16:38:06.257] <TB0> INFO: 10649600 events read in total (512171ms).
[16:38:06.384] <TB0> INFO: Test took 513115ms.
[16:38:39.938] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 0.176374 .. 47.923971
[16:38:40.013] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 0 .. 57 (-1/-1) hits flags = 528 (plus default)
[16:38:40.021] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:38:40.021] <TB0> INFO: run 1 of 1
[16:38:40.280] <TB0> INFO: Expecting 2412800 events.
[16:39:18.548] <TB0> INFO: 946940 events read in total (37712ms).
[16:39:56.091] <TB0> INFO: 1893680 events read in total (75256ms).
[16:40:17.045] <TB0> INFO: 2412800 events read in total (96210ms).
[16:40:17.067] <TB0> INFO: Test took 97047ms.
[16:40:31.967] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 20.574470 .. 45.965270
[16:40:32.049] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:40:32.057] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:40:32.057] <TB0> INFO: run 1 of 1
[16:40:32.322] <TB0> INFO: Expecting 1913600 events.
[16:41:10.462] <TB0> INFO: 911130 events read in total (37583ms).
[16:41:48.695] <TB0> INFO: 1820990 events read in total (75816ms).
[16:41:52.864] <TB0> INFO: 1913600 events read in total (79986ms).
[16:41:52.881] <TB0> INFO: Test took 80825ms.
[16:42:05.860] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 2.798190 .. 45.647571
[16:42:05.935] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:42:05.943] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:42:05.943] <TB0> INFO: run 1 of 1
[16:42:06.202] <TB0> INFO: Expecting 2246400 events.
[16:42:44.889] <TB0> INFO: 955690 events read in total (38131ms).
[16:43:23.253] <TB0> INFO: 1911320 events read in total (76495ms).
[16:43:37.033] <TB0> INFO: 2246400 events read in total (90275ms).
[16:43:37.049] <TB0> INFO: Test took 91106ms.
[16:43:50.995] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:43:50.995] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:43:51.003] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:43:51.003] <TB0> INFO: run 1 of 1
[16:43:51.288] <TB0> INFO: Expecting 1705600 events.
[16:44:29.002] <TB0> INFO: 878190 events read in total (37157ms).
[16:45:04.049] <TB0> INFO: 1705600 events read in total (72204ms).
[16:45:04.081] <TB0> INFO: Test took 73079ms.
[16:45:18.098] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:45:18.098] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:45:18.098] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:45:18.098] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:45:18.098] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:45:18.099] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:45:18.100] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:45:18.100] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:45:18.100] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:45:18.107] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:45:18.113] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:45:18.119] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:45:18.125] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:45:18.132] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:45:18.139] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:45:18.145] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:45:18.151] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:45:18.157] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:45:18.164] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:45:18.171] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:45:18.177] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:45:18.183] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:45:18.190] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:45:18.196] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:45:18.202] <TB0> INFO: PixTestTrim::trimTest() done
[16:45:18.202] <TB0> INFO: vtrim: 95 113 83 106 104 93 101 95 111 118 90 93 99 115 100 84
[16:45:18.202] <TB0> INFO: vthrcomp: 86 91 97 97 95 95 104 97 96 105 96 96 91 91 87 87
[16:45:18.202] <TB0> INFO: vcal mean: 34.95 34.97 35.00 34.97 34.97 35.00 35.00 35.04 35.00 35.03 34.96 34.97 35.02 34.98 35.00 34.97
[16:45:18.202] <TB0> INFO: vcal RMS: 0.82 0.87 0.87 0.92 0.85 0.87 0.83 0.90 1.00 0.91 0.89 0.85 0.88 1.14 1.04 0.88
[16:45:18.202] <TB0> INFO: bits mean: 9.09 9.52 10.00 9.75 9.55 8.73 8.95 9.73 8.75 8.42 9.00 9.84 9.01 8.55 9.85 10.00
[16:45:18.202] <TB0> INFO: bits RMS: 2.78 2.56 2.58 2.69 2.75 2.82 2.50 2.70 2.84 2.59 2.88 2.56 2.66 2.68 2.47 2.49
[16:45:18.208] <TB0> INFO: ----------------------------------------------------------------------
[16:45:18.208] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:45:18.208] <TB0> INFO: ----------------------------------------------------------------------
[16:45:18.211] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:45:18.219] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:45:18.219] <TB0> INFO: run 1 of 1
[16:45:18.480] <TB0> INFO: Expecting 4160000 events.
[16:46:01.102] <TB0> INFO: 960925 events read in total (42066ms).
[16:46:43.338] <TB0> INFO: 1912515 events read in total (84302ms).
[16:47:25.453] <TB0> INFO: 2853935 events read in total (126418ms).
[16:48:07.429] <TB0> INFO: 3793350 events read in total (168393ms).
[16:48:23.933] <TB0> INFO: 4160000 events read in total (184897ms).
[16:48:23.969] <TB0> INFO: Test took 185750ms.
[16:48:55.771] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 198 (-1/-1) hits flags = 528 (plus default)
[16:48:55.780] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:48:55.780] <TB0> INFO: run 1 of 1
[16:48:56.064] <TB0> INFO: Expecting 4139200 events.
[16:49:38.298] <TB0> INFO: 928460 events read in total (41678ms).
[16:50:20.382] <TB0> INFO: 1850310 events read in total (83762ms).
[16:51:01.771] <TB0> INFO: 2763495 events read in total (125151ms).
[16:51:42.826] <TB0> INFO: 3674380 events read in total (166206ms).
[16:52:04.080] <TB0> INFO: 4139200 events read in total (187460ms).
[16:52:04.117] <TB0> INFO: Test took 188337ms.
[16:52:33.409] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 183 (-1/-1) hits flags = 528 (plus default)
[16:52:33.417] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:52:33.417] <TB0> INFO: run 1 of 1
[16:52:33.679] <TB0> INFO: Expecting 3827200 events.
[16:53:16.082] <TB0> INFO: 959245 events read in total (41847ms).
[16:53:58.035] <TB0> INFO: 1909355 events read in total (83800ms).
[16:54:40.159] <TB0> INFO: 2849120 events read in total (125925ms).
[16:55:22.304] <TB0> INFO: 3790630 events read in total (168069ms).
[16:55:24.315] <TB0> INFO: 3827200 events read in total (170080ms).
[16:55:24.347] <TB0> INFO: Test took 170930ms.
[16:55:53.791] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 183 (-1/-1) hits flags = 528 (plus default)
[16:55:53.803] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:55:53.803] <TB0> INFO: run 1 of 1
[16:55:54.090] <TB0> INFO: Expecting 3827200 events.
[16:56:36.653] <TB0> INFO: 958885 events read in total (42007ms).
[16:57:19.105] <TB0> INFO: 1908755 events read in total (84459ms).
[16:58:00.772] <TB0> INFO: 2848310 events read in total (126126ms).
[16:58:42.989] <TB0> INFO: 3789440 events read in total (168343ms).
[16:58:45.072] <TB0> INFO: 3827200 events read in total (170426ms).
[16:58:45.103] <TB0> INFO: Test took 171300ms.
[16:59:15.267] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[16:59:15.276] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:59:15.276] <TB0> INFO: run 1 of 1
[16:59:15.567] <TB0> INFO: Expecting 3848000 events.
[16:59:58.444] <TB0> INFO: 956060 events read in total (42321ms).
[17:00:40.127] <TB0> INFO: 1903405 events read in total (84004ms).
[17:01:21.959] <TB0> INFO: 2840745 events read in total (125836ms).
[17:02:03.876] <TB0> INFO: 3778710 events read in total (167753ms).
[17:02:07.352] <TB0> INFO: 3848000 events read in total (171229ms).
[17:02:07.385] <TB0> INFO: Test took 172109ms.
[17:02:35.510] <TB0> INFO: PixTestTrim::trimBitTest() done
[17:02:35.511] <TB0> INFO: PixTestTrim::doTest() done, duration: 3080 seconds
[17:02:35.511] <TB0> INFO: Decoding statistics:
[17:02:35.511] <TB0> INFO: General information:
[17:02:35.511] <TB0> INFO: 16bit words read: 0
[17:02:35.511] <TB0> INFO: valid events total: 0
[17:02:35.511] <TB0> INFO: empty events: 0
[17:02:35.511] <TB0> INFO: valid events with pixels: 0
[17:02:35.511] <TB0> INFO: valid pixel hits: 0
[17:02:35.511] <TB0> INFO: Event errors: 0
[17:02:35.511] <TB0> INFO: start marker: 0
[17:02:35.511] <TB0> INFO: stop marker: 0
[17:02:35.511] <TB0> INFO: overflow: 0
[17:02:35.511] <TB0> INFO: invalid 5bit words: 0
[17:02:35.511] <TB0> INFO: invalid XOR eye diagram: 0
[17:02:35.511] <TB0> INFO: frame (failed synchr.): 0
[17:02:35.512] <TB0> INFO: idle data (no TBM trl): 0
[17:02:35.512] <TB0> INFO: no data (only TBM hdr): 0
[17:02:35.512] <TB0> INFO: TBM errors: 0
[17:02:35.512] <TB0> INFO: flawed TBM headers: 0
[17:02:35.512] <TB0> INFO: flawed TBM trailers: 0
[17:02:35.512] <TB0> INFO: event ID mismatches: 0
[17:02:35.512] <TB0> INFO: ROC errors: 0
[17:02:35.512] <TB0> INFO: missing ROC header(s): 0
[17:02:35.512] <TB0> INFO: misplaced readback start: 0
[17:02:35.512] <TB0> INFO: Pixel decoding errors: 0
[17:02:35.512] <TB0> INFO: pixel data incomplete: 0
[17:02:35.512] <TB0> INFO: pixel address: 0
[17:02:35.512] <TB0> INFO: pulse height fill bit: 0
[17:02:35.512] <TB0> INFO: buffer corruption: 0
[17:02:36.182] <TB0> INFO: ######################################################################
[17:02:36.182] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:02:36.182] <TB0> INFO: ######################################################################
[17:02:36.442] <TB0> INFO: Expecting 41600 events.
[17:02:40.167] <TB0> INFO: 41600 events read in total (3169ms).
[17:02:40.167] <TB0> INFO: Test took 3984ms.
[17:02:40.642] <TB0> INFO: Expecting 41600 events.
[17:02:44.510] <TB0> INFO: 41600 events read in total (3312ms).
[17:02:44.511] <TB0> INFO: Test took 4140ms.
[17:02:44.826] <TB0> INFO: Expecting 41600 events.
[17:02:48.691] <TB0> INFO: 41600 events read in total (3309ms).
[17:02:48.691] <TB0> INFO: Test took 4152ms.
[17:02:48.946] <TB0> INFO: Expecting 2560 events.
[17:02:49.799] <TB0> INFO: 2560 events read in total (297ms).
[17:02:49.799] <TB0> INFO: Test took 1095ms.
[17:02:50.104] <TB0> INFO: Expecting 2560 events.
[17:02:50.957] <TB0> INFO: 2560 events read in total (297ms).
[17:02:50.957] <TB0> INFO: Test took 1158ms.
[17:02:51.262] <TB0> INFO: Expecting 2560 events.
[17:02:52.115] <TB0> INFO: 2560 events read in total (297ms).
[17:02:52.115] <TB0> INFO: Test took 1158ms.
[17:02:52.420] <TB0> INFO: Expecting 2560 events.
[17:02:53.273] <TB0> INFO: 2560 events read in total (297ms).
[17:02:53.273] <TB0> INFO: Test took 1158ms.
[17:02:53.578] <TB0> INFO: Expecting 2560 events.
[17:02:54.430] <TB0> INFO: 2560 events read in total (296ms).
[17:02:54.431] <TB0> INFO: Test took 1158ms.
[17:02:54.735] <TB0> INFO: Expecting 2560 events.
[17:02:55.587] <TB0> INFO: 2560 events read in total (296ms).
[17:02:55.588] <TB0> INFO: Test took 1157ms.
[17:02:55.893] <TB0> INFO: Expecting 2560 events.
[17:02:56.747] <TB0> INFO: 2560 events read in total (298ms).
[17:02:56.748] <TB0> INFO: Test took 1160ms.
[17:02:57.053] <TB0> INFO: Expecting 2560 events.
[17:02:57.904] <TB0> INFO: 2560 events read in total (295ms).
[17:02:57.904] <TB0> INFO: Test took 1156ms.
[17:02:58.209] <TB0> INFO: Expecting 2560 events.
[17:02:59.065] <TB0> INFO: 2560 events read in total (299ms).
[17:02:59.065] <TB0> INFO: Test took 1160ms.
[17:02:59.370] <TB0> INFO: Expecting 2560 events.
[17:03:00.223] <TB0> INFO: 2560 events read in total (297ms).
[17:03:00.223] <TB0> INFO: Test took 1158ms.
[17:03:00.528] <TB0> INFO: Expecting 2560 events.
[17:03:01.380] <TB0> INFO: 2560 events read in total (296ms).
[17:03:01.380] <TB0> INFO: Test took 1157ms.
[17:03:01.686] <TB0> INFO: Expecting 2560 events.
[17:03:02.539] <TB0> INFO: 2560 events read in total (297ms).
[17:03:02.539] <TB0> INFO: Test took 1158ms.
[17:03:02.844] <TB0> INFO: Expecting 2560 events.
[17:03:03.696] <TB0> INFO: 2560 events read in total (296ms).
[17:03:03.696] <TB0> INFO: Test took 1157ms.
[17:03:04.001] <TB0> INFO: Expecting 2560 events.
[17:03:04.856] <TB0> INFO: 2560 events read in total (299ms).
[17:03:04.856] <TB0> INFO: Test took 1159ms.
[17:03:05.161] <TB0> INFO: Expecting 2560 events.
[17:03:06.015] <TB0> INFO: 2560 events read in total (298ms).
[17:03:06.015] <TB0> INFO: Test took 1158ms.
[17:03:06.320] <TB0> INFO: Expecting 2560 events.
[17:03:07.175] <TB0> INFO: 2560 events read in total (299ms).
[17:03:07.175] <TB0> INFO: Test took 1160ms.
[17:03:07.178] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:03:07.481] <TB0> INFO: Expecting 655360 events.
[17:03:24.319] <TB0> INFO: 655360 events read in total (16282ms).
[17:03:24.328] <TB0> INFO: Expecting 655360 events.
[17:03:41.156] <TB0> INFO: 655360 events read in total (16426ms).
[17:03:41.168] <TB0> INFO: Expecting 655360 events.
[17:03:58.013] <TB0> INFO: 655360 events read in total (16444ms).
[17:03:58.031] <TB0> INFO: Expecting 655360 events.
[17:04:14.780] <TB0> INFO: 655360 events read in total (16348ms).
[17:04:14.798] <TB0> INFO: Expecting 655360 events.
[17:04:31.601] <TB0> INFO: 655360 events read in total (16401ms).
[17:04:31.623] <TB0> INFO: Expecting 655360 events.
[17:04:48.492] <TB0> INFO: 655360 events read in total (16467ms).
[17:04:48.517] <TB0> INFO: Expecting 655360 events.
[17:05:05.212] <TB0> INFO: 655360 events read in total (16293ms).
[17:05:05.241] <TB0> INFO: Expecting 655360 events.
[17:05:22.062] <TB0> INFO: 655360 events read in total (16419ms).
[17:05:22.095] <TB0> INFO: Expecting 655360 events.
[17:05:38.845] <TB0> INFO: 655360 events read in total (16348ms).
[17:05:38.881] <TB0> INFO: Expecting 655360 events.
[17:05:55.762] <TB0> INFO: 655360 events read in total (16479ms).
[17:05:55.811] <TB0> INFO: Expecting 655360 events.
[17:06:12.484] <TB0> INFO: 655360 events read in total (16271ms).
[17:06:12.535] <TB0> INFO: Expecting 655360 events.
[17:06:29.246] <TB0> INFO: 655360 events read in total (16310ms).
[17:06:29.292] <TB0> INFO: Expecting 655360 events.
[17:06:45.943] <TB0> INFO: 655360 events read in total (16250ms).
[17:06:45.992] <TB0> INFO: Expecting 655360 events.
[17:07:02.760] <TB0> INFO: 655360 events read in total (16366ms).
[17:07:02.812] <TB0> INFO: Expecting 655360 events.
[17:07:19.486] <TB0> INFO: 655360 events read in total (16272ms).
[17:07:19.542] <TB0> INFO: Expecting 655360 events.
[17:07:36.352] <TB0> INFO: 655360 events read in total (16408ms).
[17:07:36.415] <TB0> INFO: Test took 269237ms.
[17:07:36.504] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:07:36.736] <TB0> INFO: Expecting 655360 events.
[17:07:53.342] <TB0> INFO: 655360 events read in total (16050ms).
[17:07:53.350] <TB0> INFO: Expecting 655360 events.
[17:08:10.061] <TB0> INFO: 655360 events read in total (16309ms).
[17:08:10.075] <TB0> INFO: Expecting 655360 events.
[17:08:26.790] <TB0> INFO: 655360 events read in total (16313ms).
[17:08:26.806] <TB0> INFO: Expecting 655360 events.
[17:08:43.258] <TB0> INFO: 655360 events read in total (16050ms).
[17:08:43.279] <TB0> INFO: Expecting 655360 events.
[17:08:59.884] <TB0> INFO: 655360 events read in total (16203ms).
[17:08:59.906] <TB0> INFO: Expecting 655360 events.
[17:09:16.614] <TB0> INFO: 655360 events read in total (16306ms).
[17:09:16.640] <TB0> INFO: Expecting 655360 events.
[17:09:33.411] <TB0> INFO: 655360 events read in total (16369ms).
[17:09:33.443] <TB0> INFO: Expecting 655360 events.
[17:09:50.186] <TB0> INFO: 655360 events read in total (16342ms).
[17:09:50.219] <TB0> INFO: Expecting 655360 events.
[17:10:06.829] <TB0> INFO: 655360 events read in total (16208ms).
[17:10:06.870] <TB0> INFO: Expecting 655360 events.
[17:10:23.698] <TB0> INFO: 655360 events read in total (16426ms).
[17:10:23.749] <TB0> INFO: Expecting 655360 events.
[17:10:40.318] <TB0> INFO: 655360 events read in total (16167ms).
[17:10:40.365] <TB0> INFO: Expecting 655360 events.
[17:10:57.164] <TB0> INFO: 655360 events read in total (16398ms).
[17:10:57.215] <TB0> INFO: Expecting 655360 events.
[17:11:13.913] <TB0> INFO: 655360 events read in total (16296ms).
[17:11:13.968] <TB0> INFO: Expecting 655360 events.
[17:11:30.492] <TB0> INFO: 655360 events read in total (16122ms).
[17:11:30.550] <TB0> INFO: Expecting 655360 events.
[17:11:47.286] <TB0> INFO: 655360 events read in total (16334ms).
[17:11:47.350] <TB0> INFO: Expecting 655360 events.
[17:12:04.015] <TB0> INFO: 655360 events read in total (16263ms).
[17:12:04.073] <TB0> INFO: Test took 267569ms.
[17:12:04.256] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.262] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.269] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.275] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.282] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.288] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.295] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.301] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.308] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.314] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.321] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.329] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.337] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.345] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.353] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.361] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C0.dat
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C1.dat
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C2.dat
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C3.dat
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C4.dat
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C5.dat
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C6.dat
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C7.dat
[17:12:04.398] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C8.dat
[17:12:04.399] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C9.dat
[17:12:04.399] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C10.dat
[17:12:04.399] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C11.dat
[17:12:04.399] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C12.dat
[17:12:04.399] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C13.dat
[17:12:04.399] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C14.dat
[17:12:04.399] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//dacParameters35_C15.dat
[17:12:04.694] <TB0> INFO: Expecting 41600 events.
[17:12:08.215] <TB0> INFO: 41600 events read in total (2964ms).
[17:12:08.216] <TB0> INFO: Test took 3814ms.
[17:12:08.663] <TB0> INFO: Expecting 41600 events.
[17:12:12.145] <TB0> INFO: 41600 events read in total (2926ms).
[17:12:12.146] <TB0> INFO: Test took 3748ms.
[17:12:12.591] <TB0> INFO: Expecting 41600 events.
[17:12:16.071] <TB0> INFO: 41600 events read in total (2924ms).
[17:12:16.071] <TB0> INFO: Test took 3751ms.
[17:12:16.252] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:16.340] <TB0> INFO: Expecting 2560 events.
[17:12:17.196] <TB0> INFO: 2560 events read in total (300ms).
[17:12:17.197] <TB0> INFO: Test took 945ms.
[17:12:17.198] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:17.502] <TB0> INFO: Expecting 2560 events.
[17:12:18.357] <TB0> INFO: 2560 events read in total (299ms).
[17:12:18.357] <TB0> INFO: Test took 1159ms.
[17:12:18.358] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:18.662] <TB0> INFO: Expecting 2560 events.
[17:12:19.515] <TB0> INFO: 2560 events read in total (297ms).
[17:12:19.515] <TB0> INFO: Test took 1157ms.
[17:12:19.517] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:19.821] <TB0> INFO: Expecting 2560 events.
[17:12:20.679] <TB0> INFO: 2560 events read in total (302ms).
[17:12:20.679] <TB0> INFO: Test took 1162ms.
[17:12:20.681] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:20.985] <TB0> INFO: Expecting 2560 events.
[17:12:21.838] <TB0> INFO: 2560 events read in total (297ms).
[17:12:21.838] <TB0> INFO: Test took 1157ms.
[17:12:21.840] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:22.144] <TB0> INFO: Expecting 2560 events.
[17:12:23.003] <TB0> INFO: 2560 events read in total (303ms).
[17:12:23.003] <TB0> INFO: Test took 1163ms.
[17:12:23.005] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:23.310] <TB0> INFO: Expecting 2560 events.
[17:12:24.168] <TB0> INFO: 2560 events read in total (302ms).
[17:12:24.168] <TB0> INFO: Test took 1163ms.
[17:12:24.170] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:24.474] <TB0> INFO: Expecting 2560 events.
[17:12:25.329] <TB0> INFO: 2560 events read in total (299ms).
[17:12:25.329] <TB0> INFO: Test took 1159ms.
[17:12:25.332] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:25.634] <TB0> INFO: Expecting 2560 events.
[17:12:26.489] <TB0> INFO: 2560 events read in total (299ms).
[17:12:26.489] <TB0> INFO: Test took 1158ms.
[17:12:26.491] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:26.796] <TB0> INFO: Expecting 2560 events.
[17:12:27.654] <TB0> INFO: 2560 events read in total (302ms).
[17:12:27.654] <TB0> INFO: Test took 1163ms.
[17:12:27.656] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:27.959] <TB0> INFO: Expecting 2560 events.
[17:12:28.813] <TB0> INFO: 2560 events read in total (297ms).
[17:12:28.813] <TB0> INFO: Test took 1157ms.
[17:12:28.816] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:29.119] <TB0> INFO: Expecting 2560 events.
[17:12:29.972] <TB0> INFO: 2560 events read in total (297ms).
[17:12:29.972] <TB0> INFO: Test took 1156ms.
[17:12:29.973] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:30.277] <TB0> INFO: Expecting 2560 events.
[17:12:31.130] <TB0> INFO: 2560 events read in total (296ms).
[17:12:31.130] <TB0> INFO: Test took 1157ms.
[17:12:31.132] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:31.437] <TB0> INFO: Expecting 2560 events.
[17:12:32.291] <TB0> INFO: 2560 events read in total (298ms).
[17:12:32.291] <TB0> INFO: Test took 1159ms.
[17:12:32.293] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:32.596] <TB0> INFO: Expecting 2560 events.
[17:12:33.450] <TB0> INFO: 2560 events read in total (297ms).
[17:12:33.450] <TB0> INFO: Test took 1157ms.
[17:12:33.452] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:33.755] <TB0> INFO: Expecting 2560 events.
[17:12:34.613] <TB0> INFO: 2560 events read in total (302ms).
[17:12:34.614] <TB0> INFO: Test took 1162ms.
[17:12:34.615] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:34.919] <TB0> INFO: Expecting 2560 events.
[17:12:35.774] <TB0> INFO: 2560 events read in total (298ms).
[17:12:35.774] <TB0> INFO: Test took 1159ms.
[17:12:35.777] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:36.079] <TB0> INFO: Expecting 2560 events.
[17:12:36.931] <TB0> INFO: 2560 events read in total (296ms).
[17:12:36.931] <TB0> INFO: Test took 1154ms.
[17:12:36.933] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:37.237] <TB0> INFO: Expecting 2560 events.
[17:12:38.095] <TB0> INFO: 2560 events read in total (302ms).
[17:12:38.095] <TB0> INFO: Test took 1162ms.
[17:12:38.098] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:38.401] <TB0> INFO: Expecting 2560 events.
[17:12:39.255] <TB0> INFO: 2560 events read in total (298ms).
[17:12:39.255] <TB0> INFO: Test took 1157ms.
[17:12:39.262] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:39.561] <TB0> INFO: Expecting 2560 events.
[17:12:40.416] <TB0> INFO: 2560 events read in total (299ms).
[17:12:40.416] <TB0> INFO: Test took 1154ms.
[17:12:40.419] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:40.722] <TB0> INFO: Expecting 2560 events.
[17:12:41.577] <TB0> INFO: 2560 events read in total (299ms).
[17:12:41.577] <TB0> INFO: Test took 1158ms.
[17:12:41.579] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:41.884] <TB0> INFO: Expecting 2560 events.
[17:12:42.739] <TB0> INFO: 2560 events read in total (299ms).
[17:12:42.739] <TB0> INFO: Test took 1160ms.
[17:12:42.742] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:43.045] <TB0> INFO: Expecting 2560 events.
[17:12:43.898] <TB0> INFO: 2560 events read in total (296ms).
[17:12:43.898] <TB0> INFO: Test took 1156ms.
[17:12:43.900] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:44.204] <TB0> INFO: Expecting 2560 events.
[17:12:45.060] <TB0> INFO: 2560 events read in total (300ms).
[17:12:45.060] <TB0> INFO: Test took 1160ms.
[17:12:45.063] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:45.366] <TB0> INFO: Expecting 2560 events.
[17:12:46.219] <TB0> INFO: 2560 events read in total (297ms).
[17:12:46.219] <TB0> INFO: Test took 1156ms.
[17:12:46.221] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:46.525] <TB0> INFO: Expecting 2560 events.
[17:12:47.384] <TB0> INFO: 2560 events read in total (302ms).
[17:12:47.384] <TB0> INFO: Test took 1163ms.
[17:12:47.387] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:47.689] <TB0> INFO: Expecting 2560 events.
[17:12:48.543] <TB0> INFO: 2560 events read in total (297ms).
[17:12:48.543] <TB0> INFO: Test took 1157ms.
[17:12:48.546] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:48.848] <TB0> INFO: Expecting 2560 events.
[17:12:49.706] <TB0> INFO: 2560 events read in total (302ms).
[17:12:49.706] <TB0> INFO: Test took 1161ms.
[17:12:49.708] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:50.012] <TB0> INFO: Expecting 2560 events.
[17:12:50.865] <TB0> INFO: 2560 events read in total (297ms).
[17:12:50.865] <TB0> INFO: Test took 1158ms.
[17:12:50.867] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:51.171] <TB0> INFO: Expecting 2560 events.
[17:12:52.024] <TB0> INFO: 2560 events read in total (297ms).
[17:12:52.024] <TB0> INFO: Test took 1158ms.
[17:12:52.027] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:52.330] <TB0> INFO: Expecting 2560 events.
[17:12:53.187] <TB0> INFO: 2560 events read in total (301ms).
[17:12:53.187] <TB0> INFO: Test took 1161ms.
[17:12:53.650] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[17:12:53.650] <TB0> INFO: PH scale (per ROC): 66 79 74 69 68 72 78 64 66 71 63 66 62 67 69 65
[17:12:53.650] <TB0> INFO: PH offset (per ROC): 195 170 190 196 179 181 193 181 206 191 188 180 202 189 188 177
[17:12:53.657] <TB0> INFO: Decoding statistics:
[17:12:53.657] <TB0> INFO: General information:
[17:12:53.657] <TB0> INFO: 16bit words read: 91940
[17:12:53.657] <TB0> INFO: valid events total: 10240
[17:12:53.657] <TB0> INFO: empty events: 7735
[17:12:53.657] <TB0> INFO: valid events with pixels: 2505
[17:12:53.657] <TB0> INFO: valid pixel hits: 2505
[17:12:53.657] <TB0> INFO: Event errors: 0
[17:12:53.657] <TB0> INFO: start marker: 0
[17:12:53.657] <TB0> INFO: stop marker: 0
[17:12:53.657] <TB0> INFO: overflow: 0
[17:12:53.657] <TB0> INFO: invalid 5bit words: 0
[17:12:53.657] <TB0> INFO: invalid XOR eye diagram: 0
[17:12:53.657] <TB0> INFO: frame (failed synchr.): 0
[17:12:53.657] <TB0> INFO: idle data (no TBM trl): 0
[17:12:53.657] <TB0> INFO: no data (only TBM hdr): 0
[17:12:53.657] <TB0> INFO: TBM errors: 0
[17:12:53.657] <TB0> INFO: flawed TBM headers: 0
[17:12:53.657] <TB0> INFO: flawed TBM trailers: 0
[17:12:53.657] <TB0> INFO: event ID mismatches: 0
[17:12:53.657] <TB0> INFO: ROC errors: 0
[17:12:53.657] <TB0> INFO: missing ROC header(s): 0
[17:12:53.657] <TB0> INFO: misplaced readback start: 0
[17:12:53.657] <TB0> INFO: Pixel decoding errors: 0
[17:12:53.657] <TB0> INFO: pixel data incomplete: 0
[17:12:53.657] <TB0> INFO: pixel address: 0
[17:12:53.657] <TB0> INFO: pulse height fill bit: 0
[17:12:53.657] <TB0> INFO: buffer corruption: 0
[17:12:53.831] <TB0> INFO: ######################################################################
[17:12:53.831] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:12:53.831] <TB0> INFO: ######################################################################
[17:12:53.841] <TB0> INFO: scanning low vcal = 10
[17:12:54.104] <TB0> INFO: Expecting 41600 events.
[17:12:57.645] <TB0> INFO: 41600 events read in total (2985ms).
[17:12:57.645] <TB0> INFO: Test took 3804ms.
[17:12:57.647] <TB0> INFO: scanning low vcal = 20
[17:12:57.950] <TB0> INFO: Expecting 41600 events.
[17:13:01.495] <TB0> INFO: 41600 events read in total (2989ms).
[17:13:01.495] <TB0> INFO: Test took 3848ms.
[17:13:01.497] <TB0> INFO: scanning low vcal = 30
[17:13:01.800] <TB0> INFO: Expecting 41600 events.
[17:13:05.381] <TB0> INFO: 41600 events read in total (3024ms).
[17:13:05.382] <TB0> INFO: Test took 3885ms.
[17:13:05.384] <TB0> INFO: scanning low vcal = 40
[17:13:05.675] <TB0> INFO: Expecting 41600 events.
[17:13:09.802] <TB0> INFO: 41600 events read in total (3571ms).
[17:13:09.803] <TB0> INFO: Test took 4419ms.
[17:13:09.806] <TB0> INFO: scanning low vcal = 50
[17:13:10.071] <TB0> INFO: Expecting 41600 events.
[17:13:14.316] <TB0> INFO: 41600 events read in total (3689ms).
[17:13:14.317] <TB0> INFO: Test took 4511ms.
[17:13:14.319] <TB0> INFO: scanning low vcal = 60
[17:13:14.579] <TB0> INFO: Expecting 41600 events.
[17:13:18.799] <TB0> INFO: 41600 events read in total (3663ms).
[17:13:18.799] <TB0> INFO: Test took 4480ms.
[17:13:18.802] <TB0> INFO: scanning low vcal = 70
[17:13:19.066] <TB0> INFO: Expecting 41600 events.
[17:13:23.274] <TB0> INFO: 41600 events read in total (3652ms).
[17:13:23.274] <TB0> INFO: Test took 4472ms.
[17:13:23.277] <TB0> INFO: scanning low vcal = 80
[17:13:23.536] <TB0> INFO: Expecting 41600 events.
[17:13:27.731] <TB0> INFO: 41600 events read in total (3639ms).
[17:13:27.732] <TB0> INFO: Test took 4455ms.
[17:13:27.734] <TB0> INFO: scanning low vcal = 90
[17:13:28.019] <TB0> INFO: Expecting 41600 events.
[17:13:32.191] <TB0> INFO: 41600 events read in total (3616ms).
[17:13:32.192] <TB0> INFO: Test took 4458ms.
[17:13:32.195] <TB0> INFO: scanning low vcal = 100
[17:13:32.457] <TB0> INFO: Expecting 41600 events.
[17:13:36.626] <TB0> INFO: 41600 events read in total (3613ms).
[17:13:36.627] <TB0> INFO: Test took 4432ms.
[17:13:36.629] <TB0> INFO: scanning low vcal = 110
[17:13:36.893] <TB0> INFO: Expecting 41600 events.
[17:13:41.065] <TB0> INFO: 41600 events read in total (3616ms).
[17:13:41.065] <TB0> INFO: Test took 4436ms.
[17:13:41.068] <TB0> INFO: scanning low vcal = 120
[17:13:41.330] <TB0> INFO: Expecting 41600 events.
[17:13:45.508] <TB0> INFO: 41600 events read in total (3622ms).
[17:13:45.508] <TB0> INFO: Test took 4440ms.
[17:13:45.511] <TB0> INFO: scanning low vcal = 130
[17:13:45.773] <TB0> INFO: Expecting 41600 events.
[17:13:49.944] <TB0> INFO: 41600 events read in total (3615ms).
[17:13:49.945] <TB0> INFO: Test took 4434ms.
[17:13:49.947] <TB0> INFO: scanning low vcal = 140
[17:13:50.236] <TB0> INFO: Expecting 41600 events.
[17:13:54.404] <TB0> INFO: 41600 events read in total (3612ms).
[17:13:54.405] <TB0> INFO: Test took 4458ms.
[17:13:54.407] <TB0> INFO: scanning low vcal = 150
[17:13:54.673] <TB0> INFO: Expecting 41600 events.
[17:13:58.862] <TB0> INFO: 41600 events read in total (3633ms).
[17:13:58.862] <TB0> INFO: Test took 4455ms.
[17:13:58.865] <TB0> INFO: scanning low vcal = 160
[17:13:59.134] <TB0> INFO: Expecting 41600 events.
[17:14:03.341] <TB0> INFO: 41600 events read in total (3650ms).
[17:14:03.341] <TB0> INFO: Test took 4476ms.
[17:14:03.343] <TB0> INFO: scanning low vcal = 170
[17:14:03.613] <TB0> INFO: Expecting 41600 events.
[17:14:07.805] <TB0> INFO: 41600 events read in total (3635ms).
[17:14:07.805] <TB0> INFO: Test took 4461ms.
[17:14:07.808] <TB0> INFO: scanning low vcal = 180
[17:14:08.072] <TB0> INFO: Expecting 41600 events.
[17:14:12.300] <TB0> INFO: 41600 events read in total (3672ms).
[17:14:12.300] <TB0> INFO: Test took 4491ms.
[17:14:12.303] <TB0> INFO: scanning low vcal = 190
[17:14:12.564] <TB0> INFO: Expecting 41600 events.
[17:14:16.796] <TB0> INFO: 41600 events read in total (3676ms).
[17:14:16.797] <TB0> INFO: Test took 4494ms.
[17:14:16.799] <TB0> INFO: scanning low vcal = 200
[17:14:17.062] <TB0> INFO: Expecting 41600 events.
[17:14:21.275] <TB0> INFO: 41600 events read in total (3657ms).
[17:14:21.276] <TB0> INFO: Test took 4477ms.
[17:14:21.278] <TB0> INFO: scanning low vcal = 210
[17:14:21.541] <TB0> INFO: Expecting 41600 events.
[17:14:25.750] <TB0> INFO: 41600 events read in total (3653ms).
[17:14:25.750] <TB0> INFO: Test took 4472ms.
[17:14:25.753] <TB0> INFO: scanning low vcal = 220
[17:14:26.014] <TB0> INFO: Expecting 41600 events.
[17:14:30.233] <TB0> INFO: 41600 events read in total (3663ms).
[17:14:30.234] <TB0> INFO: Test took 4481ms.
[17:14:30.236] <TB0> INFO: scanning low vcal = 230
[17:14:30.503] <TB0> INFO: Expecting 41600 events.
[17:14:34.761] <TB0> INFO: 41600 events read in total (3702ms).
[17:14:34.762] <TB0> INFO: Test took 4526ms.
[17:14:34.764] <TB0> INFO: scanning low vcal = 240
[17:14:35.034] <TB0> INFO: Expecting 41600 events.
[17:14:39.275] <TB0> INFO: 41600 events read in total (3685ms).
[17:14:39.275] <TB0> INFO: Test took 4511ms.
[17:14:39.284] <TB0> INFO: scanning low vcal = 250
[17:14:39.562] <TB0> INFO: Expecting 41600 events.
[17:14:43.798] <TB0> INFO: 41600 events read in total (3680ms).
[17:14:43.798] <TB0> INFO: Test took 4514ms.
[17:14:43.802] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[17:14:44.097] <TB0> INFO: Expecting 41600 events.
[17:14:48.328] <TB0> INFO: 41600 events read in total (3675ms).
[17:14:48.328] <TB0> INFO: Test took 4526ms.
[17:14:48.331] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[17:14:48.593] <TB0> INFO: Expecting 41600 events.
[17:14:52.802] <TB0> INFO: 41600 events read in total (3653ms).
[17:14:52.803] <TB0> INFO: Test took 4472ms.
[17:14:52.805] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[17:14:53.068] <TB0> INFO: Expecting 41600 events.
[17:14:57.300] <TB0> INFO: 41600 events read in total (3676ms).
[17:14:57.301] <TB0> INFO: Test took 4495ms.
[17:14:57.303] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[17:14:57.575] <TB0> INFO: Expecting 41600 events.
[17:15:01.795] <TB0> INFO: 41600 events read in total (3664ms).
[17:15:01.796] <TB0> INFO: Test took 4493ms.
[17:15:01.805] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:15:02.077] <TB0> INFO: Expecting 41600 events.
[17:15:06.271] <TB0> INFO: 41600 events read in total (3638ms).
[17:15:06.272] <TB0> INFO: Test took 4467ms.
[17:15:06.753] <TB0> INFO: PixTestGainPedestal::measure() done
[17:15:40.963] <TB0> INFO: PixTestGainPedestal::fit() done
[17:15:40.963] <TB0> INFO: non-linearity mean: 0.959 0.967 0.960 0.960 0.955 0.961 0.953 0.952 0.961 0.958 0.956 0.954 0.959 0.962 0.964 0.953
[17:15:40.963] <TB0> INFO: non-linearity RMS: 0.006 0.005 0.006 0.006 0.008 0.006 0.008 0.007 0.007 0.006 0.007 0.007 0.008 0.006 0.006 0.007
[17:15:40.963] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[17:15:40.981] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[17:15:40.999] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[17:15:41.017] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[17:15:41.035] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[17:15:41.052] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[17:15:41.071] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[17:15:41.090] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[17:15:41.109] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[17:15:41.128] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[17:15:41.147] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[17:15:41.165] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[17:15:41.185] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[17:15:41.204] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[17:15:41.223] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[17:15:41.242] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[17:15:41.261] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[17:15:41.261] <TB0> INFO: Decoding statistics:
[17:15:41.261] <TB0> INFO: General information:
[17:15:41.261] <TB0> INFO: 16bit words read: 2662400
[17:15:41.261] <TB0> INFO: valid events total: 166400
[17:15:41.261] <TB0> INFO: empty events: 0
[17:15:41.261] <TB0> INFO: valid events with pixels: 166400
[17:15:41.261] <TB0> INFO: valid pixel hits: 665582
[17:15:41.261] <TB0> INFO: Event errors: 0
[17:15:41.261] <TB0> INFO: start marker: 0
[17:15:41.261] <TB0> INFO: stop marker: 0
[17:15:41.261] <TB0> INFO: overflow: 0
[17:15:41.261] <TB0> INFO: invalid 5bit words: 0
[17:15:41.261] <TB0> INFO: invalid XOR eye diagram: 0
[17:15:41.261] <TB0> INFO: frame (failed synchr.): 0
[17:15:41.261] <TB0> INFO: idle data (no TBM trl): 0
[17:15:41.261] <TB0> INFO: no data (only TBM hdr): 0
[17:15:41.261] <TB0> INFO: TBM errors: 0
[17:15:41.262] <TB0> INFO: flawed TBM headers: 0
[17:15:41.262] <TB0> INFO: flawed TBM trailers: 0
[17:15:41.262] <TB0> INFO: event ID mismatches: 0
[17:15:41.262] <TB0> INFO: ROC errors: 0
[17:15:41.262] <TB0> INFO: missing ROC header(s): 0
[17:15:41.262] <TB0> INFO: misplaced readback start: 0
[17:15:41.262] <TB0> INFO: Pixel decoding errors: 0
[17:15:41.262] <TB0> INFO: pixel data incomplete: 0
[17:15:41.262] <TB0> INFO: pixel address: 0
[17:15:41.262] <TB0> INFO: pulse height fill bit: 0
[17:15:41.262] <TB0> INFO: buffer corruption: 0
[17:15:41.269] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C15.dat
[17:15:41.270] <TB0> INFO: ######################################################################
[17:15:41.270] <TB0> INFO: PixTestReadback::doTest()
[17:15:41.270] <TB0> INFO: ######################################################################
[17:15:41.270] <TB0> INFO: ----------------------------------------------------------------------
[17:15:41.270] <TB0> INFO: PixTestReadback::CalibrateVd()
[17:15:41.270] <TB0> INFO: ----------------------------------------------------------------------
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C0.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C1.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C2.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C3.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C4.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C5.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C6.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C7.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C8.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C9.dat
[17:15:50.706] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C10.dat
[17:15:50.707] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C11.dat
[17:15:50.707] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C12.dat
[17:15:50.707] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C13.dat
[17:15:50.707] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C14.dat
[17:15:50.707] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C15.dat
[17:15:50.735] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[17:15:50.735] <TB0> INFO: ----------------------------------------------------------------------
[17:15:50.735] <TB0> INFO: PixTestReadback::CalibrateVa()
[17:15:50.735] <TB0> INFO: ----------------------------------------------------------------------
[17:16:00.203] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C0.dat
[17:16:00.203] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C1.dat
[17:16:00.203] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C2.dat
[17:16:00.203] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C3.dat
[17:16:00.203] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C4.dat
[17:16:00.203] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C5.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C6.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C7.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C8.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C9.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C10.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C11.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C12.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C13.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C14.dat
[17:16:00.204] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C15.dat
[17:16:00.233] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[17:16:00.233] <TB0> INFO: ----------------------------------------------------------------------
[17:16:00.233] <TB0> INFO: PixTestReadback::readbackVbg()
[17:16:00.233] <TB0> INFO: ----------------------------------------------------------------------
[17:16:07.513] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[17:16:07.513] <TB0> INFO: ----------------------------------------------------------------------
[17:16:07.513] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[17:16:07.513] <TB0> INFO: ----------------------------------------------------------------------
[17:16:07.513] <TB0> INFO: Vbg will be calibrated using Vd calibration
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.1calibrated Vbg = 1.22457 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.1calibrated Vbg = 1.2219 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.9calibrated Vbg = 1.22766 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.7calibrated Vbg = 1.22774 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 162calibrated Vbg = 1.23629 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 160.3calibrated Vbg = 1.23183 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 160calibrated Vbg = 1.2311 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 161.9calibrated Vbg = 1.22738 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 170.1calibrated Vbg = 1.23944 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 159.7calibrated Vbg = 1.23443 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149.7calibrated Vbg = 1.23065 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.3calibrated Vbg = 1.22892 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.4calibrated Vbg = 1.22619 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 157calibrated Vbg = 1.22043 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.5calibrated Vbg = 1.23228 :::*/*/*/*/
[17:16:07.513] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.5calibrated Vbg = 1.23257 :::*/*/*/*/
[17:16:07.514] <TB0> INFO: ----------------------------------------------------------------------
[17:16:07.514] <TB0> INFO: PixTestReadback::CalibrateIa()
[17:16:07.514] <TB0> INFO: ----------------------------------------------------------------------
[17:18:42.763] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C0.dat
[17:18:42.763] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C1.dat
[17:18:42.763] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C2.dat
[17:18:42.763] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C3.dat
[17:18:42.763] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C4.dat
[17:18:42.763] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C5.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C6.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C7.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C8.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C9.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C10.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C11.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C12.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C13.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C14.dat
[17:18:42.764] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2191_FullQualification_2016-04-10_11h32m_1460280744//004_FulltestPxar_p17//readbackCal_C15.dat
[17:18:42.794] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[17:18:42.795] <TB0> INFO: PixTestReadback::doTest() done
[17:18:42.796] <TB0> INFO: Decoding statistics:
[17:18:42.796] <TB0> INFO: General information:
[17:18:42.796] <TB0> INFO: 16bit words read: 1024
[17:18:42.796] <TB0> INFO: valid events total: 128
[17:18:42.796] <TB0> INFO: empty events: 128
[17:18:42.796] <TB0> INFO: valid events with pixels: 0
[17:18:42.796] <TB0> INFO: valid pixel hits: 0
[17:18:42.796] <TB0> INFO: Event errors: 0
[17:18:42.796] <TB0> INFO: start marker: 0
[17:18:42.796] <TB0> INFO: stop marker: 0
[17:18:42.796] <TB0> INFO: overflow: 0
[17:18:42.796] <TB0> INFO: invalid 5bit words: 0
[17:18:42.796] <TB0> INFO: invalid XOR eye diagram: 0
[17:18:42.796] <TB0> INFO: frame (failed synchr.): 0
[17:18:42.796] <TB0> INFO: idle data (no TBM trl): 0
[17:18:42.796] <TB0> INFO: no data (only TBM hdr): 0
[17:18:42.796] <TB0> INFO: TBM errors: 0
[17:18:42.796] <TB0> INFO: flawed TBM headers: 0
[17:18:42.796] <TB0> INFO: flawed TBM trailers: 0
[17:18:42.796] <TB0> INFO: event ID mismatches: 0
[17:18:42.796] <TB0> INFO: ROC errors: 0
[17:18:42.796] <TB0> INFO: missing ROC header(s): 0
[17:18:42.796] <TB0> INFO: misplaced readback start: 0
[17:18:42.796] <TB0> INFO: Pixel decoding errors: 0
[17:18:42.796] <TB0> INFO: pixel data incomplete: 0
[17:18:42.796] <TB0> INFO: pixel address: 0
[17:18:42.796] <TB0> INFO: pulse height fill bit: 0
[17:18:42.796] <TB0> INFO: buffer corruption: 0
[17:18:42.822] <TB0> INFO: Decoding statistics:
[17:18:42.822] <TB0> INFO: General information:
[17:18:42.822] <TB0> INFO: 16bit words read: 2755364
[17:18:42.822] <TB0> INFO: valid events total: 176768
[17:18:42.822] <TB0> INFO: empty events: 7863
[17:18:42.822] <TB0> INFO: valid events with pixels: 168905
[17:18:42.822] <TB0> INFO: valid pixel hits: 668087
[17:18:42.822] <TB0> INFO: Event errors: 0
[17:18:42.822] <TB0> INFO: start marker: 0
[17:18:42.822] <TB0> INFO: stop marker: 0
[17:18:42.822] <TB0> INFO: overflow: 0
[17:18:42.822] <TB0> INFO: invalid 5bit words: 0
[17:18:42.822] <TB0> INFO: invalid XOR eye diagram: 0
[17:18:42.822] <TB0> INFO: frame (failed synchr.): 0
[17:18:42.822] <TB0> INFO: idle data (no TBM trl): 0
[17:18:42.822] <TB0> INFO: no data (only TBM hdr): 0
[17:18:42.822] <TB0> INFO: TBM errors: 0
[17:18:42.822] <TB0> INFO: flawed TBM headers: 0
[17:18:42.822] <TB0> INFO: flawed TBM trailers: 0
[17:18:42.822] <TB0> INFO: event ID mismatches: 0
[17:18:42.822] <TB0> INFO: ROC errors: 0
[17:18:42.822] <TB0> INFO: missing ROC header(s): 0
[17:18:42.822] <TB0> INFO: misplaced readback start: 0
[17:18:42.822] <TB0> INFO: Pixel decoding errors: 0
[17:18:42.822] <TB0> INFO: pixel data incomplete: 0
[17:18:42.823] <TB0> INFO: pixel address: 0
[17:18:42.823] <TB0> INFO: pulse height fill bit: 0
[17:18:42.823] <TB0> INFO: buffer corruption: 0
[17:18:42.823] <TB0> INFO: enter test to run
[17:18:42.823] <TB0> INFO: test: exit no parameter change
[17:18:42.965] <TB0> QUIET: Connection to board 73 closed.
[17:18:43.044] <TB0> INFO: pXar: this is the end, my friend