[15:11:15.727] <TB1> INFO: *** Welcome to pxar ***
[15:11:15.727] <TB1> INFO: *** Today: 2016/03/23
[15:11:15.815] <TB1> INFO: *** Version: 9751-dirty
[15:11:15.816] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C15.dat
[15:11:15.816] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:11:15.816] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//defaultMaskFile.dat
[15:11:15.816] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters_C15.dat
[15:11:15.905] <TB1> INFO: clk: 4
[15:11:15.905] <TB1> INFO: ctr: 4
[15:11:15.905] <TB1> INFO: sda: 19
[15:11:15.905] <TB1> INFO: tin: 9
[15:11:15.905] <TB1> INFO: level: 15
[15:11:15.905] <TB1> INFO: triggerdelay: 0
[15:11:15.905] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[15:11:15.905] <TB1> INFO: Log level: INFO
[15:11:15.912] <TB1> INFO: Found DTB DTB_WXBYFL
[15:11:15.923] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[15:11:15.924] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[15:11:15.926] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[15:11:17.481] <TB1> INFO: DUT info:
[15:11:17.481] <TB1> INFO: The DUT currently contains the following objects:
[15:11:17.481] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:11:17.481] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:11:17.481] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:11:17.481] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:11:17.481] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.481] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.482] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.883] <TB1> INFO: enter 'restricted' command line mode
[15:11:17.883] <TB1> INFO: enter test to run
[15:11:17.884] <TB1> INFO: test: pretest no parameter change
[15:11:17.884] <TB1> INFO: running: pretest
[15:11:17.890] <TB1> INFO: ----------------------------------------------------------------------
[15:11:17.890] <TB1> INFO: PixTestPretest::programROC()
[15:11:17.890] <TB1> INFO: ----------------------------------------------------------------------
[15:11:35.904] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:11:35.904] <TB1> INFO: IA differences per ROC: 19.3 20.1 18.5 17.7 16.9 19.3 16.9 17.7 18.5 19.3 18.5 18.5 18.5 17.7 20.9 19.3
[15:11:36.006] <TB1> INFO: enter test to run
[15:11:36.006] <TB1> INFO: test: pretest no parameter change
[15:11:36.006] <TB1> INFO: running: pretest
[15:11:36.007] <TB1> INFO: ----------------------------------------------------------------------
[15:11:36.007] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:11:36.007] <TB1> INFO: ----------------------------------------------------------------------
[15:11:57.291] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[15:11:57.291] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 20.1 18.5 18.5 19.3 18.5 18.5 20.1 18.5 18.5 18.5 18.5 18.5 18.5
[15:11:57.323] <TB1> INFO: enter test to run
[15:11:57.323] <TB1> INFO: test: pretest no parameter change
[15:11:57.323] <TB1> INFO: running: pretest
[15:11:57.323] <TB1> INFO: ----------------------------------------------------------------------
[15:11:57.323] <TB1> INFO: PixTestPretest::findTiming()
[15:11:57.323] <TB1> INFO: ----------------------------------------------------------------------
[15:11:57.323] <TB1> INFO: PixTestCmd::init()
[15:11:58.281] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[15:13:27.378] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[15:13:27.378] <TB1> INFO: (success/tries = 100/100), width = 4
[15:13:27.378] <TB1> INFO: enter test to run
[15:13:27.378] <TB1> INFO: test: pretest no parameter change
[15:13:27.378] <TB1> INFO: running: pretest
[15:13:27.379] <TB1> INFO: ----------------------------------------------------------------------
[15:13:27.379] <TB1> INFO: PixTestPretest::findWorkingPixel()
[15:13:27.379] <TB1> INFO: ----------------------------------------------------------------------
[15:13:27.470] <TB1> INFO: Expecting 231680 events.
[15:13:33.251] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[15:13:33.335] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[15:13:37.644] <TB1> INFO: 231680 events read in total (9617ms).
[15:13:37.648] <TB1> INFO: Test took 10265ms.
[15:13:37.886] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:13:37.957] <TB1> INFO: enter test to run
[15:13:37.957] <TB1> INFO: test: pretest no parameter change
[15:13:37.957] <TB1> INFO: running: pretest
[15:13:37.958] <TB1> INFO: ----------------------------------------------------------------------
[15:13:37.958] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[15:13:37.958] <TB1> INFO: ----------------------------------------------------------------------
[15:13:38.050] <TB1> INFO: Expecting 231680 events.
[15:13:48.212] <TB1> INFO: 231680 events read in total (9605ms).
[15:13:48.217] <TB1> INFO: Test took 10255ms.
[15:13:48.448] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[15:13:48.448] <TB1> INFO: CalDel: 162 135 127 141 148 126 162 133 139 135 121 142 124 124 145 149
[15:13:48.448] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:13:48.487] <TB1> INFO: enter test to run
[15:13:48.487] <TB1> INFO: test: pretest no parameter change
[15:13:48.487] <TB1> INFO: running: pretest
[15:13:48.487] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C0.dat
[15:13:48.487] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C1.dat
[15:13:48.487] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C2.dat
[15:13:48.487] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C3.dat
[15:13:48.487] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C4.dat
[15:13:48.487] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C5.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C6.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C7.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C8.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C9.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C10.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C11.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C12.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C13.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C14.dat
[15:13:48.488] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters_C15.dat
[15:13:48.489] <TB1> INFO: enter test to run
[15:13:48.489] <TB1> INFO: test: fulltest no parameter change
[15:13:48.489] <TB1> INFO: running: fulltest
[15:13:48.489] <TB1> INFO: ######################################################################
[15:13:48.489] <TB1> INFO: PixTestFullTest::doTest()
[15:13:48.489] <TB1> INFO: ######################################################################
[15:13:48.490] <TB1> INFO: ######################################################################
[15:13:48.490] <TB1> INFO: PixTestAlive::doTest()
[15:13:48.490] <TB1> INFO: ######################################################################
[15:13:48.491] <TB1> INFO: ----------------------------------------------------------------------
[15:13:48.491] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:13:48.491] <TB1> INFO: ----------------------------------------------------------------------
[15:13:48.755] <TB1> INFO: Expecting 41600 events.
[15:13:52.508] <TB1> INFO: 41600 events read in total (3197ms).
[15:13:52.508] <TB1> INFO: Test took 4016ms.
[15:13:52.732] <TB1> INFO: PixTestAlive::aliveTest() done
[15:13:52.732] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:13:52.733] <TB1> INFO: ----------------------------------------------------------------------
[15:13:52.733] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:13:52.733] <TB1> INFO: ----------------------------------------------------------------------
[15:13:53.006] <TB1> INFO: Expecting 41600 events.
[15:13:55.917] <TB1> INFO: 41600 events read in total (2355ms).
[15:13:55.917] <TB1> INFO: Test took 3182ms.
[15:13:55.917] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:13:56.156] <TB1> INFO: PixTestAlive::maskTest() done
[15:13:56.156] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:13:56.157] <TB1> INFO: ----------------------------------------------------------------------
[15:13:56.157] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:13:56.157] <TB1> INFO: ----------------------------------------------------------------------
[15:13:56.428] <TB1> INFO: Expecting 41600 events.
[15:14:00.220] <TB1> INFO: 41600 events read in total (3236ms).
[15:14:00.220] <TB1> INFO: Test took 4062ms.
[15:14:00.444] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:14:00.444] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:14:00.444] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[15:14:00.444] <TB1> INFO: Decoding statistics:
[15:14:00.444] <TB1> INFO: General information:
[15:14:00.444] <TB1> INFO: 16bit words read: 0
[15:14:00.444] <TB1> INFO: valid events total: 0
[15:14:00.444] <TB1> INFO: empty events: 0
[15:14:00.444] <TB1> INFO: valid events with pixels: 0
[15:14:00.444] <TB1> INFO: valid pixel hits: 0
[15:14:00.444] <TB1> INFO: Event errors: 0
[15:14:00.444] <TB1> INFO: start marker: 0
[15:14:00.444] <TB1> INFO: stop marker: 0
[15:14:00.444] <TB1> INFO: overflow: 0
[15:14:00.444] <TB1> INFO: invalid 5bit words: 0
[15:14:00.444] <TB1> INFO: invalid XOR eye diagram: 0
[15:14:00.444] <TB1> INFO: frame (failed synchr.): 0
[15:14:00.444] <TB1> INFO: idle data (no TBM trl): 0
[15:14:00.444] <TB1> INFO: no data (only TBM hdr): 0
[15:14:00.444] <TB1> INFO: TBM errors: 0
[15:14:00.444] <TB1> INFO: flawed TBM headers: 0
[15:14:00.444] <TB1> INFO: flawed TBM trailers: 0
[15:14:00.444] <TB1> INFO: event ID mismatches: 0
[15:14:00.444] <TB1> INFO: ROC errors: 0
[15:14:00.444] <TB1> INFO: missing ROC header(s): 0
[15:14:00.444] <TB1> INFO: misplaced readback start: 0
[15:14:00.444] <TB1> INFO: Pixel decoding errors: 0
[15:14:00.444] <TB1> INFO: pixel data incomplete: 0
[15:14:00.444] <TB1> INFO: pixel address: 0
[15:14:00.444] <TB1> INFO: pulse height fill bit: 0
[15:14:00.444] <TB1> INFO: buffer corruption: 0
[15:14:00.451] <TB1> INFO: ######################################################################
[15:14:00.451] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:14:00.451] <TB1> INFO: ######################################################################
[15:14:00.453] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:14:00.465] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:14:00.465] <TB1> INFO: run 1 of 1
[15:14:00.733] <TB1> INFO: Expecting 3120000 events.
[15:14:42.318] <TB1> INFO: 890670 events read in total (41029ms).
[15:15:22.876] <TB1> INFO: 1765150 events read in total (81587ms).
[15:16:03.722] <TB1> INFO: 2646370 events read in total (122434ms).
[15:16:26.358] <TB1> INFO: 3120000 events read in total (145069ms).
[15:16:26.408] <TB1> INFO: Test took 145943ms.
[15:16:52.173] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[15:16:52.173] <TB1> INFO: number of dead bumps (per ROC): 9 6 49 25 14 15 17 19 56 37 12 9 23 31 14 24
[15:16:52.173] <TB1> INFO: separation cut (per ROC): 90 111 85 86 77 102 82 81 78 85 80 93 95 84 87 82
[15:16:52.173] <TB1> INFO: Decoding statistics:
[15:16:52.173] <TB1> INFO: General information:
[15:16:52.173] <TB1> INFO: 16bit words read: 0
[15:16:52.173] <TB1> INFO: valid events total: 0
[15:16:52.173] <TB1> INFO: empty events: 0
[15:16:52.173] <TB1> INFO: valid events with pixels: 0
[15:16:52.173] <TB1> INFO: valid pixel hits: 0
[15:16:52.173] <TB1> INFO: Event errors: 0
[15:16:52.173] <TB1> INFO: start marker: 0
[15:16:52.173] <TB1> INFO: stop marker: 0
[15:16:52.173] <TB1> INFO: overflow: 0
[15:16:52.173] <TB1> INFO: invalid 5bit words: 0
[15:16:52.173] <TB1> INFO: invalid XOR eye diagram: 0
[15:16:52.173] <TB1> INFO: frame (failed synchr.): 0
[15:16:52.173] <TB1> INFO: idle data (no TBM trl): 0
[15:16:52.173] <TB1> INFO: no data (only TBM hdr): 0
[15:16:52.173] <TB1> INFO: TBM errors: 0
[15:16:52.173] <TB1> INFO: flawed TBM headers: 0
[15:16:52.173] <TB1> INFO: flawed TBM trailers: 0
[15:16:52.173] <TB1> INFO: event ID mismatches: 0
[15:16:52.173] <TB1> INFO: ROC errors: 0
[15:16:52.173] <TB1> INFO: missing ROC header(s): 0
[15:16:52.173] <TB1> INFO: misplaced readback start: 0
[15:16:52.173] <TB1> INFO: Pixel decoding errors: 0
[15:16:52.173] <TB1> INFO: pixel data incomplete: 0
[15:16:52.173] <TB1> INFO: pixel address: 0
[15:16:52.173] <TB1> INFO: pulse height fill bit: 0
[15:16:52.173] <TB1> INFO: buffer corruption: 0
[15:16:52.251] <TB1> INFO: ######################################################################
[15:16:52.251] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:16:52.251] <TB1> INFO: ######################################################################
[15:16:52.251] <TB1> INFO: ----------------------------------------------------------------------
[15:16:52.251] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:16:52.252] <TB1> INFO: ----------------------------------------------------------------------
[15:16:52.252] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:16:52.261] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[15:16:52.261] <TB1> INFO: run 1 of 1
[15:16:52.559] <TB1> INFO: Expecting 26208000 events.
[15:17:27.128] <TB1> INFO: 921750 events read in total (34013ms).
[15:18:00.572] <TB1> INFO: 1831200 events read in total (67457ms).
[15:18:33.530] <TB1> INFO: 2739750 events read in total (100416ms).
[15:19:06.437] <TB1> INFO: 3649950 events read in total (133322ms).
[15:19:39.246] <TB1> INFO: 4558300 events read in total (166131ms).
[15:20:12.210] <TB1> INFO: 5462000 events read in total (199095ms).
[15:20:45.203] <TB1> INFO: 6370250 events read in total (232088ms).
[15:21:18.175] <TB1> INFO: 7276100 events read in total (265060ms).
[15:21:51.139] <TB1> INFO: 8182300 events read in total (298024ms).
[15:22:24.291] <TB1> INFO: 9087950 events read in total (331176ms).
[15:22:57.134] <TB1> INFO: 9990500 events read in total (364019ms).
[15:23:29.503] <TB1> INFO: 10892700 events read in total (396388ms).
[15:24:02.094] <TB1> INFO: 11797050 events read in total (428979ms).
[15:24:34.928] <TB1> INFO: 12698000 events read in total (461813ms).
[15:25:07.919] <TB1> INFO: 13591250 events read in total (494804ms).
[15:25:40.416] <TB1> INFO: 14479550 events read in total (527301ms).
[15:26:12.977] <TB1> INFO: 15368500 events read in total (559862ms).
[15:26:45.280] <TB1> INFO: 16254850 events read in total (592165ms).
[15:27:17.682] <TB1> INFO: 17140950 events read in total (624567ms).
[15:27:50.759] <TB1> INFO: 18024300 events read in total (657644ms).
[15:28:23.886] <TB1> INFO: 18909650 events read in total (690771ms).
[15:28:56.346] <TB1> INFO: 19792650 events read in total (723231ms).
[15:29:29.435] <TB1> INFO: 20675600 events read in total (756320ms).
[15:30:01.889] <TB1> INFO: 21558500 events read in total (788774ms).
[15:30:34.776] <TB1> INFO: 22441000 events read in total (821661ms).
[15:31:07.449] <TB1> INFO: 23325700 events read in total (854334ms).
[15:31:39.779] <TB1> INFO: 24209950 events read in total (886664ms).
[15:32:12.378] <TB1> INFO: 25095800 events read in total (919263ms).
[15:32:45.582] <TB1> INFO: 25990900 events read in total (952467ms).
[15:32:53.532] <TB1> INFO: 26208000 events read in total (960417ms).
[15:32:53.560] <TB1> INFO: Test took 961299ms.
[15:32:53.787] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:55.593] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:57.082] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:32:58.746] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:00.653] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:02.413] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:04.347] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:06.521] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:08.262] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:10.218] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:11.740] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:13.180] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:14.891] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:16.486] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:18.052] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:19.541] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:33:21.166] <TB1> INFO: PixTestScurves::scurves() done
[15:33:21.166] <TB1> INFO: Vcal mean: 95.96 114.60 92.14 98.52 87.18 101.36 96.55 100.11 85.78 91.62 88.97 91.41 102.43 93.12 93.79 101.62
[15:33:21.166] <TB1> INFO: Vcal RMS: 5.64 5.85 4.97 5.93 5.27 5.50 5.38 5.54 4.87 4.53 4.67 5.62 5.63 4.89 5.26 5.11
[15:33:21.166] <TB1> INFO: PixTestScurves::fullTest() done, duration: 988 seconds
[15:33:21.166] <TB1> INFO: Decoding statistics:
[15:33:21.166] <TB1> INFO: General information:
[15:33:21.166] <TB1> INFO: 16bit words read: 0
[15:33:21.166] <TB1> INFO: valid events total: 0
[15:33:21.166] <TB1> INFO: empty events: 0
[15:33:21.166] <TB1> INFO: valid events with pixels: 0
[15:33:21.166] <TB1> INFO: valid pixel hits: 0
[15:33:21.166] <TB1> INFO: Event errors: 0
[15:33:21.166] <TB1> INFO: start marker: 0
[15:33:21.166] <TB1> INFO: stop marker: 0
[15:33:21.166] <TB1> INFO: overflow: 0
[15:33:21.166] <TB1> INFO: invalid 5bit words: 0
[15:33:21.166] <TB1> INFO: invalid XOR eye diagram: 0
[15:33:21.166] <TB1> INFO: frame (failed synchr.): 0
[15:33:21.166] <TB1> INFO: idle data (no TBM trl): 0
[15:33:21.166] <TB1> INFO: no data (only TBM hdr): 0
[15:33:21.166] <TB1> INFO: TBM errors: 0
[15:33:21.166] <TB1> INFO: flawed TBM headers: 0
[15:33:21.166] <TB1> INFO: flawed TBM trailers: 0
[15:33:21.166] <TB1> INFO: event ID mismatches: 0
[15:33:21.166] <TB1> INFO: ROC errors: 0
[15:33:21.166] <TB1> INFO: missing ROC header(s): 0
[15:33:21.166] <TB1> INFO: misplaced readback start: 0
[15:33:21.167] <TB1> INFO: Pixel decoding errors: 0
[15:33:21.167] <TB1> INFO: pixel data incomplete: 0
[15:33:21.167] <TB1> INFO: pixel address: 0
[15:33:21.167] <TB1> INFO: pulse height fill bit: 0
[15:33:21.167] <TB1> INFO: buffer corruption: 0
[15:33:21.236] <TB1> INFO: ######################################################################
[15:33:21.236] <TB1> INFO: PixTestTrim::doTest()
[15:33:21.236] <TB1> INFO: ######################################################################
[15:33:21.237] <TB1> INFO: ----------------------------------------------------------------------
[15:33:21.237] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:33:21.237] <TB1> INFO: ----------------------------------------------------------------------
[15:33:21.316] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:33:21.316] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:33:21.324] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:33:21.324] <TB1> INFO: run 1 of 1
[15:33:21.590] <TB1> INFO: Expecting 6281600 events.
[15:34:00.842] <TB1> INFO: 1093210 events read in total (38695ms).
[15:34:40.147] <TB1> INFO: 2180120 events read in total (78000ms).
[15:35:19.475] <TB1> INFO: 3258750 events read in total (117328ms).
[15:35:58.887] <TB1> INFO: 4344080 events read in total (156740ms).
[15:36:38.210] <TB1> INFO: 5431300 events read in total (196063ms).
[15:37:08.933] <TB1> INFO: 6281600 events read in total (226786ms).
[15:37:08.966] <TB1> INFO: Test took 227642ms.
[15:37:29.949] <TB1> INFO: ROC 0 VthrComp = 91
[15:37:29.949] <TB1> INFO: ROC 1 VthrComp = 107
[15:37:29.949] <TB1> INFO: ROC 2 VthrComp = 98
[15:37:29.950] <TB1> INFO: ROC 3 VthrComp = 94
[15:37:29.950] <TB1> INFO: ROC 4 VthrComp = 84
[15:37:29.950] <TB1> INFO: ROC 5 VthrComp = 99
[15:37:29.950] <TB1> INFO: ROC 6 VthrComp = 91
[15:37:29.950] <TB1> INFO: ROC 7 VthrComp = 95
[15:37:29.950] <TB1> INFO: ROC 8 VthrComp = 90
[15:37:29.950] <TB1> INFO: ROC 9 VthrComp = 98
[15:37:29.950] <TB1> INFO: ROC 10 VthrComp = 93
[15:37:29.950] <TB1> INFO: ROC 11 VthrComp = 89
[15:37:29.951] <TB1> INFO: ROC 12 VthrComp = 99
[15:37:29.951] <TB1> INFO: ROC 13 VthrComp = 97
[15:37:29.951] <TB1> INFO: ROC 14 VthrComp = 94
[15:37:29.951] <TB1> INFO: ROC 15 VthrComp = 99
[15:37:29.951] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:37:29.951] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:37:29.963] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:37:29.963] <TB1> INFO: run 1 of 1
[15:37:30.224] <TB1> INFO: Expecting 6281600 events.
[15:38:05.750] <TB1> INFO: 755440 events read in total (34970ms).
[15:38:40.709] <TB1> INFO: 1509510 events read in total (69929ms).
[15:39:15.752] <TB1> INFO: 2264700 events read in total (104972ms).
[15:39:50.333] <TB1> INFO: 3019870 events read in total (139553ms).
[15:40:24.732] <TB1> INFO: 3769720 events read in total (173952ms).
[15:40:59.142] <TB1> INFO: 4515360 events read in total (208362ms).
[15:41:33.865] <TB1> INFO: 5258920 events read in total (243085ms).
[15:42:08.495] <TB1> INFO: 6002490 events read in total (277715ms).
[15:42:22.057] <TB1> INFO: 6281600 events read in total (291277ms).
[15:42:22.104] <TB1> INFO: Test took 292141ms.
[15:42:49.603] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 62.2928 for pixel 7/1 mean/min/max = 47.9865/33.6289/62.3441
[15:42:49.603] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 65.6151 for pixel 51/4 mean/min/max = 49.7265/33.8082/65.6448
[15:42:49.604] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 55.2701 for pixel 0/2 mean/min/max = 43.6622/31.9667/55.3576
[15:42:49.604] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 60.3698 for pixel 22/79 mean/min/max = 46.523/31.9767/61.0693
[15:42:49.604] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.9713 for pixel 23/76 mean/min/max = 45.6835/32.3841/58.9829
[15:42:49.604] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.1027 for pixel 24/15 mean/min/max = 45.0903/31.0722/59.1083
[15:42:49.605] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.3251 for pixel 14/6 mean/min/max = 46.1051/33.8189/58.3913
[15:42:49.605] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 60.1933 for pixel 18/9 mean/min/max = 46.6267/33.0504/60.2031
[15:42:49.605] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 56.9618 for pixel 20/9 mean/min/max = 45.4757/33.7185/57.2329
[15:42:49.605] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 55.4222 for pixel 17/1 mean/min/max = 43.6434/31.5951/55.6916
[15:42:49.606] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.0041 for pixel 14/79 mean/min/max = 44.9343/33.7279/56.1407
[15:42:49.606] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 61.2445 for pixel 1/79 mean/min/max = 47.3784/33.511/61.2458
[15:42:49.606] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 60.225 for pixel 2/4 mean/min/max = 45.9585/31.691/60.2259
[15:42:49.606] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.586 for pixel 0/30 mean/min/max = 44.5047/32.3885/56.6209
[15:42:49.607] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.9334 for pixel 25/13 mean/min/max = 45.4221/32.7822/58.062
[15:42:49.607] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.9239 for pixel 1/16 mean/min/max = 44.7207/31.5021/57.9393
[15:42:49.607] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:42:49.695] <TB1> INFO: Expecting 514560 events.
[15:43:02.658] <TB1> INFO: 514560 events read in total (12407ms).
[15:43:02.663] <TB1> INFO: Expecting 514560 events.
[15:43:15.555] <TB1> INFO: 514560 events read in total (12472ms).
[15:43:15.562] <TB1> INFO: Expecting 514560 events.
[15:43:28.562] <TB1> INFO: 514560 events read in total (12579ms).
[15:43:28.571] <TB1> INFO: Expecting 514560 events.
[15:43:41.422] <TB1> INFO: 514560 events read in total (12440ms).
[15:43:41.432] <TB1> INFO: Expecting 514560 events.
[15:43:54.218] <TB1> INFO: 514560 events read in total (12367ms).
[15:43:54.231] <TB1> INFO: Expecting 514560 events.
[15:44:06.968] <TB1> INFO: 514560 events read in total (12323ms).
[15:44:06.982] <TB1> INFO: Expecting 514560 events.
[15:44:19.713] <TB1> INFO: 514560 events read in total (12321ms).
[15:44:19.728] <TB1> INFO: Expecting 514560 events.
[15:44:32.471] <TB1> INFO: 514560 events read in total (12328ms).
[15:44:32.489] <TB1> INFO: Expecting 514560 events.
[15:44:45.481] <TB1> INFO: 514560 events read in total (12578ms).
[15:44:45.501] <TB1> INFO: Expecting 514560 events.
[15:44:58.404] <TB1> INFO: 514560 events read in total (12501ms).
[15:44:58.427] <TB1> INFO: Expecting 514560 events.
[15:45:11.288] <TB1> INFO: 514560 events read in total (12460ms).
[15:45:11.312] <TB1> INFO: Expecting 514560 events.
[15:45:24.071] <TB1> INFO: 514560 events read in total (12356ms).
[15:45:24.097] <TB1> INFO: Expecting 514560 events.
[15:45:36.843] <TB1> INFO: 514560 events read in total (12343ms).
[15:45:36.869] <TB1> INFO: Expecting 514560 events.
[15:45:49.715] <TB1> INFO: 514560 events read in total (12441ms).
[15:45:49.744] <TB1> INFO: Expecting 514560 events.
[15:46:02.657] <TB1> INFO: 514560 events read in total (12511ms).
[15:46:02.693] <TB1> INFO: Expecting 514560 events.
[15:46:15.263] <TB1> INFO: 514560 events read in total (12168ms).
[15:46:15.295] <TB1> INFO: Test took 205688ms.
[15:46:16.245] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:46:16.254] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:46:16.254] <TB1> INFO: run 1 of 1
[15:46:16.532] <TB1> INFO: Expecting 6281600 events.
[15:46:52.859] <TB1> INFO: 737800 events read in total (35771ms).
[15:47:28.978] <TB1> INFO: 1475100 events read in total (71890ms).
[15:48:03.160] <TB1> INFO: 2213440 events read in total (106072ms).
[15:48:37.867] <TB1> INFO: 2951910 events read in total (140779ms).
[15:49:12.343] <TB1> INFO: 3685400 events read in total (175255ms).
[15:49:46.578] <TB1> INFO: 4415020 events read in total (209490ms).
[15:50:21.191] <TB1> INFO: 5142880 events read in total (244103ms).
[15:50:55.307] <TB1> INFO: 5870100 events read in total (278219ms).
[15:51:14.681] <TB1> INFO: 6281600 events read in total (297593ms).
[15:51:14.742] <TB1> INFO: Test took 298488ms.
[15:51:41.385] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.021884 .. 255.000000
[15:51:41.460] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:51:41.468] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:51:41.468] <TB1> INFO: run 1 of 1
[15:51:41.728] <TB1> INFO: Expecting 10649600 events.
[15:52:16.218] <TB1> INFO: 712390 events read in total (33934ms).
[15:52:50.249] <TB1> INFO: 1424200 events read in total (67965ms).
[15:53:24.480] <TB1> INFO: 2136500 events read in total (102196ms).
[15:53:58.327] <TB1> INFO: 2848740 events read in total (136043ms).
[15:54:32.781] <TB1> INFO: 3560930 events read in total (170497ms).
[15:55:06.489] <TB1> INFO: 4273640 events read in total (204205ms).
[15:55:40.975] <TB1> INFO: 4985920 events read in total (238691ms).
[15:56:15.039] <TB1> INFO: 5697990 events read in total (272755ms).
[15:56:49.629] <TB1> INFO: 6409810 events read in total (307345ms).
[15:57:23.931] <TB1> INFO: 7120510 events read in total (341647ms).
[15:57:57.937] <TB1> INFO: 7831090 events read in total (375653ms).
[15:58:31.850] <TB1> INFO: 8541070 events read in total (409566ms).
[15:59:06.178] <TB1> INFO: 9250940 events read in total (443894ms).
[15:59:40.213] <TB1> INFO: 9960830 events read in total (477929ms).
[16:00:13.622] <TB1> INFO: 10649600 events read in total (511338ms).
[16:00:13.723] <TB1> INFO: Test took 512255ms.
[16:00:48.385] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.979587 .. 45.377772
[16:00:48.463] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:00:48.471] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:00:48.471] <TB1> INFO: run 1 of 1
[16:00:48.731] <TB1> INFO: Expecting 2163200 events.
[16:01:26.650] <TB1> INFO: 943640 events read in total (37359ms).
[16:02:04.397] <TB1> INFO: 1885670 events read in total (75106ms).
[16:02:15.591] <TB1> INFO: 2163200 events read in total (86301ms).
[16:02:15.610] <TB1> INFO: Test took 87139ms.
[16:02:29.968] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 1.927674 .. 42.742575
[16:02:30.051] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 52 (-1/-1) hits flags = 528 (plus default)
[16:02:30.059] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:02:30.059] <TB1> INFO: run 1 of 1
[16:02:30.319] <TB1> INFO: Expecting 2163200 events.
[16:03:09.089] <TB1> INFO: 986470 events read in total (38214ms).
[16:03:47.595] <TB1> INFO: 1971610 events read in total (76720ms).
[16:03:55.346] <TB1> INFO: 2163200 events read in total (84471ms).
[16:03:55.365] <TB1> INFO: Test took 85306ms.
[16:04:08.403] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 0.371989 .. 41.438060
[16:04:08.490] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 0 .. 51 (-1/-1) hits flags = 528 (plus default)
[16:04:08.499] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:04:08.499] <TB1> INFO: run 1 of 1
[16:04:08.768] <TB1> INFO: Expecting 2163200 events.
[16:04:47.913] <TB1> INFO: 1000960 events read in total (38589ms).
[16:05:26.593] <TB1> INFO: 2001060 events read in total (77269ms).
[16:05:33.302] <TB1> INFO: 2163200 events read in total (83978ms).
[16:05:33.318] <TB1> INFO: Test took 84819ms.
[16:05:46.950] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:05:46.950] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:05:46.959] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:05:46.959] <TB1> INFO: run 1 of 1
[16:05:47.248] <TB1> INFO: Expecting 1705600 events.
[16:06:24.927] <TB1> INFO: 878870 events read in total (37123ms).
[16:06:59.345] <TB1> INFO: 1705600 events read in total (71541ms).
[16:06:59.373] <TB1> INFO: Test took 72416ms.
[16:07:12.593] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:07:12.593] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:07:12.594] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:07:12.594] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:07:12.594] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:07:12.594] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:07:12.594] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:07:12.594] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:07:12.595] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:07:12.595] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:07:12.595] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:07:12.595] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:07:12.595] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:07:12.595] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:07:12.596] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:07:12.596] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:07:12.596] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:07:12.606] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:07:12.616] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:07:12.626] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:07:12.636] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:07:12.646] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:07:12.657] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:07:12.667] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:07:12.677] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:07:12.687] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:07:12.697] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:07:12.708] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:07:12.718] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:07:12.728] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:07:12.738] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:07:12.748] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:07:12.758] <TB1> INFO: PixTestTrim::trimTest() done
[16:07:12.758] <TB1> INFO: vtrim: 103 115 99 91 93 94 85 93 106 178 99 91 94 95 99 85
[16:07:12.758] <TB1> INFO: vthrcomp: 91 107 98 94 84 99 91 95 90 98 93 89 99 97 94 99
[16:07:12.758] <TB1> INFO: vcal mean: 34.98 35.03 34.99 34.97 34.94 34.93 34.99 34.96 35.02 34.86 34.99 34.99 34.94 34.96 34.98 34.94
[16:07:12.758] <TB1> INFO: vcal RMS: 1.01 0.92 0.81 0.90 1.00 1.02 0.86 0.89 0.79 1.86 0.76 0.85 0.97 0.79 0.83 0.88
[16:07:12.758] <TB1> INFO: bits mean: 8.98 8.59 10.05 9.06 9.39 9.88 9.28 8.95 9.20 12.34 9.22 8.66 9.45 9.37 9.69 9.71
[16:07:12.758] <TB1> INFO: bits RMS: 2.60 2.54 2.61 2.86 2.71 2.68 2.54 2.78 2.62 1.47 2.57 2.77 2.68 2.80 2.54 2.78
[16:07:12.766] <TB1> INFO: ----------------------------------------------------------------------
[16:07:12.766] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:07:12.766] <TB1> INFO: ----------------------------------------------------------------------
[16:07:12.768] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:07:12.780] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:07:12.780] <TB1> INFO: run 1 of 1
[16:07:13.116] <TB1> INFO: Expecting 4160000 events.
[16:07:55.180] <TB1> INFO: 949770 events read in total (41508ms).
[16:08:36.391] <TB1> INFO: 1893080 events read in total (82719ms).
[16:09:18.037] <TB1> INFO: 2826070 events read in total (124366ms).
[16:09:59.363] <TB1> INFO: 3755935 events read in total (165691ms).
[16:10:17.348] <TB1> INFO: 4160000 events read in total (183676ms).
[16:10:17.385] <TB1> INFO: Test took 184605ms.
[16:10:48.469] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[16:10:48.478] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:10:48.478] <TB1> INFO: run 1 of 1
[16:10:48.741] <TB1> INFO: Expecting 3806400 events.
[16:11:30.617] <TB1> INFO: 952340 events read in total (41319ms).
[16:12:12.020] <TB1> INFO: 1898245 events read in total (82723ms).
[16:12:53.447] <TB1> INFO: 2832325 events read in total (124149ms).
[16:13:35.108] <TB1> INFO: 3766710 events read in total (165810ms).
[16:13:37.221] <TB1> INFO: 3806400 events read in total (167924ms).
[16:13:37.252] <TB1> INFO: Test took 168774ms.
[16:14:06.507] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[16:14:06.516] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:14:06.516] <TB1> INFO: run 1 of 1
[16:14:06.776] <TB1> INFO: Expecting 3515200 events.
[16:14:49.644] <TB1> INFO: 987415 events read in total (42312ms).
[16:15:31.655] <TB1> INFO: 1965310 events read in total (84324ms).
[16:16:13.999] <TB1> INFO: 2931150 events read in total (126668ms).
[16:16:39.515] <TB1> INFO: 3515200 events read in total (152183ms).
[16:16:39.554] <TB1> INFO: Test took 153038ms.
[16:17:07.806] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[16:17:07.819] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:17:07.819] <TB1> INFO: run 1 of 1
[16:17:08.107] <TB1> INFO: Expecting 3515200 events.
[16:17:53.243] <TB1> INFO: 987000 events read in total (44580ms).
[16:18:34.610] <TB1> INFO: 1964670 events read in total (85947ms).
[16:19:16.616] <TB1> INFO: 2930370 events read in total (127953ms).
[16:19:41.756] <TB1> INFO: 3515200 events read in total (153094ms).
[16:19:41.790] <TB1> INFO: Test took 153971ms.
[16:20:08.060] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[16:20:08.069] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:20:08.069] <TB1> INFO: run 1 of 1
[16:20:08.328] <TB1> INFO: Expecting 3536000 events.
[16:20:50.902] <TB1> INFO: 984045 events read in total (42018ms).
[16:21:33.428] <TB1> INFO: 1958930 events read in total (84544ms).
[16:22:14.898] <TB1> INFO: 2922170 events read in total (126015ms).
[16:22:41.895] <TB1> INFO: 3536000 events read in total (153011ms).
[16:22:41.928] <TB1> INFO: Test took 153859ms.
[16:23:08.344] <TB1> INFO: PixTestTrim::trimBitTest() done
[16:23:08.346] <TB1> INFO: PixTestTrim::doTest() done, duration: 2987 seconds
[16:23:08.346] <TB1> INFO: Decoding statistics:
[16:23:08.346] <TB1> INFO: General information:
[16:23:08.346] <TB1> INFO: 16bit words read: 0
[16:23:08.346] <TB1> INFO: valid events total: 0
[16:23:08.346] <TB1> INFO: empty events: 0
[16:23:08.346] <TB1> INFO: valid events with pixels: 0
[16:23:08.346] <TB1> INFO: valid pixel hits: 0
[16:23:08.346] <TB1> INFO: Event errors: 0
[16:23:08.346] <TB1> INFO: start marker: 0
[16:23:08.346] <TB1> INFO: stop marker: 0
[16:23:08.346] <TB1> INFO: overflow: 0
[16:23:08.346] <TB1> INFO: invalid 5bit words: 0
[16:23:08.354] <TB1> INFO: invalid XOR eye diagram: 0
[16:23:08.354] <TB1> INFO: frame (failed synchr.): 0
[16:23:08.354] <TB1> INFO: idle data (no TBM trl): 0
[16:23:08.354] <TB1> INFO: no data (only TBM hdr): 0
[16:23:08.354] <TB1> INFO: TBM errors: 0
[16:23:08.354] <TB1> INFO: flawed TBM headers: 0
[16:23:08.354] <TB1> INFO: flawed TBM trailers: 0
[16:23:08.354] <TB1> INFO: event ID mismatches: 0
[16:23:08.354] <TB1> INFO: ROC errors: 0
[16:23:08.354] <TB1> INFO: missing ROC header(s): 0
[16:23:08.354] <TB1> INFO: misplaced readback start: 0
[16:23:08.354] <TB1> INFO: Pixel decoding errors: 0
[16:23:08.354] <TB1> INFO: pixel data incomplete: 0
[16:23:08.354] <TB1> INFO: pixel address: 0
[16:23:08.354] <TB1> INFO: pulse height fill bit: 0
[16:23:08.354] <TB1> INFO: buffer corruption: 0
[16:23:09.028] <TB1> INFO: ######################################################################
[16:23:09.028] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:23:09.028] <TB1> INFO: ######################################################################
[16:23:09.288] <TB1> INFO: Expecting 41600 events.
[16:23:13.015] <TB1> INFO: 41600 events read in total (3171ms).
[16:23:13.015] <TB1> INFO: Test took 3985ms.
[16:23:13.480] <TB1> INFO: Expecting 41600 events.
[16:23:17.393] <TB1> INFO: 41600 events read in total (3357ms).
[16:23:17.394] <TB1> INFO: Test took 4173ms.
[16:23:17.737] <TB1> INFO: Expecting 41600 events.
[16:23:21.560] <TB1> INFO: 41600 events read in total (3266ms).
[16:23:21.561] <TB1> INFO: Test took 4140ms.
[16:23:21.823] <TB1> INFO: Expecting 2560 events.
[16:23:22.679] <TB1> INFO: 2560 events read in total (300ms).
[16:23:22.680] <TB1> INFO: Test took 1100ms.
[16:23:22.985] <TB1> INFO: Expecting 2560 events.
[16:23:23.840] <TB1> INFO: 2560 events read in total (299ms).
[16:23:23.840] <TB1> INFO: Test took 1160ms.
[16:23:24.145] <TB1> INFO: Expecting 2560 events.
[16:23:25.004] <TB1> INFO: 2560 events read in total (303ms).
[16:23:25.004] <TB1> INFO: Test took 1164ms.
[16:23:25.309] <TB1> INFO: Expecting 2560 events.
[16:23:26.165] <TB1> INFO: 2560 events read in total (300ms).
[16:23:26.166] <TB1> INFO: Test took 1161ms.
[16:23:26.471] <TB1> INFO: Expecting 2560 events.
[16:23:27.324] <TB1> INFO: 2560 events read in total (297ms).
[16:23:27.324] <TB1> INFO: Test took 1158ms.
[16:23:27.629] <TB1> INFO: Expecting 2560 events.
[16:23:28.482] <TB1> INFO: 2560 events read in total (297ms).
[16:23:28.482] <TB1> INFO: Test took 1158ms.
[16:23:28.787] <TB1> INFO: Expecting 2560 events.
[16:23:29.640] <TB1> INFO: 2560 events read in total (297ms).
[16:23:29.640] <TB1> INFO: Test took 1158ms.
[16:23:29.945] <TB1> INFO: Expecting 2560 events.
[16:23:30.797] <TB1> INFO: 2560 events read in total (296ms).
[16:23:30.797] <TB1> INFO: Test took 1157ms.
[16:23:31.102] <TB1> INFO: Expecting 2560 events.
[16:23:31.959] <TB1> INFO: 2560 events read in total (301ms).
[16:23:31.959] <TB1> INFO: Test took 1162ms.
[16:23:32.264] <TB1> INFO: Expecting 2560 events.
[16:23:33.118] <TB1> INFO: 2560 events read in total (298ms).
[16:23:33.118] <TB1> INFO: Test took 1158ms.
[16:23:33.424] <TB1> INFO: Expecting 2560 events.
[16:23:34.278] <TB1> INFO: 2560 events read in total (298ms).
[16:23:34.278] <TB1> INFO: Test took 1159ms.
[16:23:34.583] <TB1> INFO: Expecting 2560 events.
[16:23:35.438] <TB1> INFO: 2560 events read in total (299ms).
[16:23:35.438] <TB1> INFO: Test took 1159ms.
[16:23:35.744] <TB1> INFO: Expecting 2560 events.
[16:23:36.596] <TB1> INFO: 2560 events read in total (296ms).
[16:23:36.596] <TB1> INFO: Test took 1157ms.
[16:23:36.902] <TB1> INFO: Expecting 2560 events.
[16:23:37.753] <TB1> INFO: 2560 events read in total (295ms).
[16:23:37.753] <TB1> INFO: Test took 1156ms.
[16:23:38.058] <TB1> INFO: Expecting 2560 events.
[16:23:38.911] <TB1> INFO: 2560 events read in total (297ms).
[16:23:38.911] <TB1> INFO: Test took 1157ms.
[16:23:39.217] <TB1> INFO: Expecting 2560 events.
[16:23:40.069] <TB1> INFO: 2560 events read in total (296ms).
[16:23:40.069] <TB1> INFO: Test took 1157ms.
[16:23:40.072] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:40.375] <TB1> INFO: Expecting 655360 events.
[16:23:56.970] <TB1> INFO: 655360 events read in total (16039ms).
[16:23:56.980] <TB1> INFO: Expecting 655360 events.
[16:24:13.641] <TB1> INFO: 655360 events read in total (16259ms).
[16:24:13.653] <TB1> INFO: Expecting 655360 events.
[16:24:30.293] <TB1> INFO: 655360 events read in total (16238ms).
[16:24:30.309] <TB1> INFO: Expecting 655360 events.
[16:24:47.325] <TB1> INFO: 655360 events read in total (16614ms).
[16:24:47.348] <TB1> INFO: Expecting 655360 events.
[16:25:04.126] <TB1> INFO: 655360 events read in total (16376ms).
[16:25:04.149] <TB1> INFO: Expecting 655360 events.
[16:25:20.838] <TB1> INFO: 655360 events read in total (16288ms).
[16:25:20.867] <TB1> INFO: Expecting 655360 events.
[16:25:37.705] <TB1> INFO: 655360 events read in total (16436ms).
[16:25:37.739] <TB1> INFO: Expecting 655360 events.
[16:25:54.564] <TB1> INFO: 655360 events read in total (16423ms).
[16:25:54.600] <TB1> INFO: Expecting 655360 events.
[16:26:11.483] <TB1> INFO: 655360 events read in total (16481ms).
[16:26:11.519] <TB1> INFO: Expecting 655360 events.
[16:26:28.358] <TB1> INFO: 655360 events read in total (16437ms).
[16:26:28.397] <TB1> INFO: Expecting 655360 events.
[16:26:45.162] <TB1> INFO: 655360 events read in total (16363ms).
[16:26:45.205] <TB1> INFO: Expecting 655360 events.
[16:27:02.030] <TB1> INFO: 655360 events read in total (16424ms).
[16:27:02.080] <TB1> INFO: Expecting 655360 events.
[16:27:18.798] <TB1> INFO: 655360 events read in total (16316ms).
[16:27:18.853] <TB1> INFO: Expecting 655360 events.
[16:27:35.604] <TB1> INFO: 655360 events read in total (16350ms).
[16:27:35.657] <TB1> INFO: Expecting 655360 events.
[16:27:52.411] <TB1> INFO: 655360 events read in total (16353ms).
[16:27:52.471] <TB1> INFO: Expecting 655360 events.
[16:28:09.250] <TB1> INFO: 655360 events read in total (16377ms).
[16:28:09.311] <TB1> INFO: Test took 269239ms.
[16:28:09.389] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:28:09.620] <TB1> INFO: Expecting 655360 events.
[16:28:26.528] <TB1> INFO: 655360 events read in total (16351ms).
[16:28:26.538] <TB1> INFO: Expecting 655360 events.
[16:28:43.274] <TB1> INFO: 655360 events read in total (16334ms).
[16:28:43.286] <TB1> INFO: Expecting 655360 events.
[16:29:00.071] <TB1> INFO: 655360 events read in total (16383ms).
[16:29:00.086] <TB1> INFO: Expecting 655360 events.
[16:29:16.833] <TB1> INFO: 655360 events read in total (16345ms).
[16:29:16.852] <TB1> INFO: Expecting 655360 events.
[16:29:33.488] <TB1> INFO: 655360 events read in total (16234ms).
[16:29:33.513] <TB1> INFO: Expecting 655360 events.
[16:29:50.231] <TB1> INFO: 655360 events read in total (16317ms).
[16:29:50.259] <TB1> INFO: Expecting 655360 events.
[16:30:06.932] <TB1> INFO: 655360 events read in total (16271ms).
[16:30:06.965] <TB1> INFO: Expecting 655360 events.
[16:30:23.822] <TB1> INFO: 655360 events read in total (16456ms).
[16:30:23.856] <TB1> INFO: Expecting 655360 events.
[16:30:40.582] <TB1> INFO: 655360 events read in total (16324ms).
[16:30:40.621] <TB1> INFO: Expecting 655360 events.
[16:30:57.483] <TB1> INFO: 655360 events read in total (16460ms).
[16:30:57.525] <TB1> INFO: Expecting 655360 events.
[16:31:14.326] <TB1> INFO: 655360 events read in total (16399ms).
[16:31:14.372] <TB1> INFO: Expecting 655360 events.
[16:31:31.165] <TB1> INFO: 655360 events read in total (16391ms).
[16:31:31.215] <TB1> INFO: Expecting 655360 events.
[16:31:47.993] <TB1> INFO: 655360 events read in total (16376ms).
[16:31:48.043] <TB1> INFO: Expecting 655360 events.
[16:32:04.753] <TB1> INFO: 655360 events read in total (16308ms).
[16:32:04.806] <TB1> INFO: Expecting 655360 events.
[16:32:21.718] <TB1> INFO: 655360 events read in total (16510ms).
[16:32:21.776] <TB1> INFO: Expecting 655360 events.
[16:32:38.646] <TB1> INFO: 655360 events read in total (16468ms).
[16:32:38.721] <TB1> INFO: Test took 269332ms.
[16:32:38.911] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.918] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.925] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.932] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.939] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.946] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.953] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.960] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.967] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.974] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.981] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.988] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:38.994] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:39.001] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:39.008] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:39.015] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:39.044] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:32:39.044] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:32:39.045] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:32:39.046] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:32:39.046] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:32:39.046] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:32:39.312] <TB1> INFO: Expecting 41600 events.
[16:32:42.804] <TB1> INFO: 41600 events read in total (2936ms).
[16:32:42.804] <TB1> INFO: Test took 3756ms.
[16:32:43.253] <TB1> INFO: Expecting 41600 events.
[16:32:46.768] <TB1> INFO: 41600 events read in total (2959ms).
[16:32:46.768] <TB1> INFO: Test took 3787ms.
[16:32:47.209] <TB1> INFO: Expecting 41600 events.
[16:32:50.686] <TB1> INFO: 41600 events read in total (2920ms).
[16:32:50.687] <TB1> INFO: Test took 3742ms.
[16:32:50.872] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:32:50.962] <TB1> INFO: Expecting 2560 events.
[16:32:51.815] <TB1> INFO: 2560 events read in total (297ms).
[16:32:51.815] <TB1> INFO: Test took 943ms.
[16:32:51.817] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:32:52.120] <TB1> INFO: Expecting 2560 events.
[16:32:52.974] <TB1> INFO: 2560 events read in total (298ms).
[16:32:52.974] <TB1> INFO: Test took 1157ms.
[16:32:52.977] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:32:53.280] <TB1> INFO: Expecting 2560 events.
[16:32:54.133] <TB1> INFO: 2560 events read in total (297ms).
[16:32:54.134] <TB1> INFO: Test took 1157ms.
[16:32:54.135] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:32:54.440] <TB1> INFO: Expecting 2560 events.
[16:32:55.295] <TB1> INFO: 2560 events read in total (299ms).
[16:32:55.295] <TB1> INFO: Test took 1160ms.
[16:32:55.298] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:32:55.600] <TB1> INFO: Expecting 2560 events.
[16:32:56.455] <TB1> INFO: 2560 events read in total (299ms).
[16:32:56.456] <TB1> INFO: Test took 1159ms.
[16:32:56.459] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:32:56.762] <TB1> INFO: Expecting 2560 events.
[16:32:57.620] <TB1> INFO: 2560 events read in total (302ms).
[16:32:57.620] <TB1> INFO: Test took 1161ms.
[16:32:57.622] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:32:57.926] <TB1> INFO: Expecting 2560 events.
[16:32:58.780] <TB1> INFO: 2560 events read in total (298ms).
[16:32:58.780] <TB1> INFO: Test took 1158ms.
[16:32:58.783] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:32:59.086] <TB1> INFO: Expecting 2560 events.
[16:32:59.938] <TB1> INFO: 2560 events read in total (296ms).
[16:32:59.938] <TB1> INFO: Test took 1155ms.
[16:32:59.940] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:00.244] <TB1> INFO: Expecting 2560 events.
[16:33:01.096] <TB1> INFO: 2560 events read in total (296ms).
[16:33:01.096] <TB1> INFO: Test took 1156ms.
[16:33:01.098] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:01.402] <TB1> INFO: Expecting 2560 events.
[16:33:02.260] <TB1> INFO: 2560 events read in total (302ms).
[16:33:02.260] <TB1> INFO: Test took 1162ms.
[16:33:02.262] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:02.566] <TB1> INFO: Expecting 2560 events.
[16:33:03.419] <TB1> INFO: 2560 events read in total (297ms).
[16:33:03.419] <TB1> INFO: Test took 1157ms.
[16:33:03.421] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:03.725] <TB1> INFO: Expecting 2560 events.
[16:33:04.578] <TB1> INFO: 2560 events read in total (297ms).
[16:33:04.578] <TB1> INFO: Test took 1158ms.
[16:33:04.581] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:04.884] <TB1> INFO: Expecting 2560 events.
[16:33:05.738] <TB1> INFO: 2560 events read in total (297ms).
[16:33:05.738] <TB1> INFO: Test took 1157ms.
[16:33:05.740] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:06.043] <TB1> INFO: Expecting 2560 events.
[16:33:06.897] <TB1> INFO: 2560 events read in total (298ms).
[16:33:06.897] <TB1> INFO: Test took 1157ms.
[16:33:06.900] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:07.202] <TB1> INFO: Expecting 2560 events.
[16:33:08.057] <TB1> INFO: 2560 events read in total (298ms).
[16:33:08.058] <TB1> INFO: Test took 1158ms.
[16:33:08.060] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:08.363] <TB1> INFO: Expecting 2560 events.
[16:33:09.216] <TB1> INFO: 2560 events read in total (297ms).
[16:33:09.216] <TB1> INFO: Test took 1156ms.
[16:33:09.220] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:09.522] <TB1> INFO: Expecting 2560 events.
[16:33:10.382] <TB1> INFO: 2560 events read in total (304ms).
[16:33:10.382] <TB1> INFO: Test took 1162ms.
[16:33:10.385] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:10.688] <TB1> INFO: Expecting 2560 events.
[16:33:11.546] <TB1> INFO: 2560 events read in total (302ms).
[16:33:11.546] <TB1> INFO: Test took 1161ms.
[16:33:11.548] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:11.852] <TB1> INFO: Expecting 2560 events.
[16:33:12.710] <TB1> INFO: 2560 events read in total (302ms).
[16:33:12.710] <TB1> INFO: Test took 1162ms.
[16:33:12.712] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:13.016] <TB1> INFO: Expecting 2560 events.
[16:33:13.870] <TB1> INFO: 2560 events read in total (297ms).
[16:33:13.871] <TB1> INFO: Test took 1159ms.
[16:33:13.873] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:14.175] <TB1> INFO: Expecting 2560 events.
[16:33:15.029] <TB1> INFO: 2560 events read in total (298ms).
[16:33:15.030] <TB1> INFO: Test took 1157ms.
[16:33:15.034] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:15.335] <TB1> INFO: Expecting 2560 events.
[16:33:16.189] <TB1> INFO: 2560 events read in total (298ms).
[16:33:16.189] <TB1> INFO: Test took 1155ms.
[16:33:16.191] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:16.496] <TB1> INFO: Expecting 2560 events.
[16:33:17.354] <TB1> INFO: 2560 events read in total (302ms).
[16:33:17.354] <TB1> INFO: Test took 1163ms.
[16:33:17.357] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:17.662] <TB1> INFO: Expecting 2560 events.
[16:33:18.517] <TB1> INFO: 2560 events read in total (299ms).
[16:33:18.517] <TB1> INFO: Test took 1161ms.
[16:33:18.520] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:18.822] <TB1> INFO: Expecting 2560 events.
[16:33:19.676] <TB1> INFO: 2560 events read in total (298ms).
[16:33:19.676] <TB1> INFO: Test took 1156ms.
[16:33:19.679] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:19.982] <TB1> INFO: Expecting 2560 events.
[16:33:20.836] <TB1> INFO: 2560 events read in total (298ms).
[16:33:20.836] <TB1> INFO: Test took 1157ms.
[16:33:20.839] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:21.141] <TB1> INFO: Expecting 2560 events.
[16:33:21.993] <TB1> INFO: 2560 events read in total (296ms).
[16:33:21.993] <TB1> INFO: Test took 1154ms.
[16:33:21.995] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:22.299] <TB1> INFO: Expecting 2560 events.
[16:33:23.153] <TB1> INFO: 2560 events read in total (298ms).
[16:33:23.153] <TB1> INFO: Test took 1158ms.
[16:33:23.156] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:23.458] <TB1> INFO: Expecting 2560 events.
[16:33:24.315] <TB1> INFO: 2560 events read in total (301ms).
[16:33:24.315] <TB1> INFO: Test took 1159ms.
[16:33:24.317] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:24.620] <TB1> INFO: Expecting 2560 events.
[16:33:25.477] <TB1> INFO: 2560 events read in total (300ms).
[16:33:25.477] <TB1> INFO: Test took 1160ms.
[16:33:25.480] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:25.783] <TB1> INFO: Expecting 2560 events.
[16:33:26.639] <TB1> INFO: 2560 events read in total (300ms).
[16:33:26.639] <TB1> INFO: Test took 1159ms.
[16:33:26.641] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:26.945] <TB1> INFO: Expecting 2560 events.
[16:33:27.798] <TB1> INFO: 2560 events read in total (297ms).
[16:33:27.799] <TB1> INFO: Test took 1158ms.
[16:33:28.257] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 619 seconds
[16:33:28.257] <TB1> INFO: PH scale (per ROC): 62 65 76 63 71 70 69 69 76 80 76 71 70 70 66 63
[16:33:28.257] <TB1> INFO: PH offset (per ROC): 194 177 176 188 174 189 175 189 180 181 181 176 175 174 184 194
[16:33:28.261] <TB1> INFO: Decoding statistics:
[16:33:28.261] <TB1> INFO: General information:
[16:33:28.261] <TB1> INFO: 16bit words read: 91940
[16:33:28.261] <TB1> INFO: valid events total: 10240
[16:33:28.261] <TB1> INFO: empty events: 7735
[16:33:28.261] <TB1> INFO: valid events with pixels: 2505
[16:33:28.261] <TB1> INFO: valid pixel hits: 2505
[16:33:28.261] <TB1> INFO: Event errors: 0
[16:33:28.261] <TB1> INFO: start marker: 0
[16:33:28.261] <TB1> INFO: stop marker: 0
[16:33:28.261] <TB1> INFO: overflow: 0
[16:33:28.261] <TB1> INFO: invalid 5bit words: 0
[16:33:28.261] <TB1> INFO: invalid XOR eye diagram: 0
[16:33:28.261] <TB1> INFO: frame (failed synchr.): 0
[16:33:28.261] <TB1> INFO: idle data (no TBM trl): 0
[16:33:28.262] <TB1> INFO: no data (only TBM hdr): 0
[16:33:28.262] <TB1> INFO: TBM errors: 0
[16:33:28.262] <TB1> INFO: flawed TBM headers: 0
[16:33:28.262] <TB1> INFO: flawed TBM trailers: 0
[16:33:28.262] <TB1> INFO: event ID mismatches: 0
[16:33:28.262] <TB1> INFO: ROC errors: 0
[16:33:28.262] <TB1> INFO: missing ROC header(s): 0
[16:33:28.262] <TB1> INFO: misplaced readback start: 0
[16:33:28.262] <TB1> INFO: Pixel decoding errors: 0
[16:33:28.262] <TB1> INFO: pixel data incomplete: 0
[16:33:28.262] <TB1> INFO: pixel address: 0
[16:33:28.262] <TB1> INFO: pulse height fill bit: 0
[16:33:28.262] <TB1> INFO: buffer corruption: 0
[16:33:28.443] <TB1> INFO: ######################################################################
[16:33:28.443] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:33:28.443] <TB1> INFO: ######################################################################
[16:33:28.454] <TB1> INFO: scanning low vcal = 10
[16:33:28.718] <TB1> INFO: Expecting 41600 events.
[16:33:32.259] <TB1> INFO: 41600 events read in total (2984ms).
[16:33:32.259] <TB1> INFO: Test took 3805ms.
[16:33:32.261] <TB1> INFO: scanning low vcal = 20
[16:33:32.564] <TB1> INFO: Expecting 41600 events.
[16:33:36.107] <TB1> INFO: 41600 events read in total (2987ms).
[16:33:36.107] <TB1> INFO: Test took 3846ms.
[16:33:36.109] <TB1> INFO: scanning low vcal = 30
[16:33:36.412] <TB1> INFO: Expecting 41600 events.
[16:33:39.973] <TB1> INFO: 41600 events read in total (3005ms).
[16:33:39.974] <TB1> INFO: Test took 3865ms.
[16:33:39.975] <TB1> INFO: scanning low vcal = 40
[16:33:40.272] <TB1> INFO: Expecting 41600 events.
[16:33:44.503] <TB1> INFO: 41600 events read in total (3675ms).
[16:33:44.504] <TB1> INFO: Test took 4529ms.
[16:33:44.506] <TB1> INFO: scanning low vcal = 50
[16:33:44.770] <TB1> INFO: Expecting 41600 events.
[16:33:48.958] <TB1> INFO: 41600 events read in total (3631ms).
[16:33:48.959] <TB1> INFO: Test took 4452ms.
[16:33:48.962] <TB1> INFO: scanning low vcal = 60
[16:33:49.232] <TB1> INFO: Expecting 41600 events.
[16:33:53.457] <TB1> INFO: 41600 events read in total (3669ms).
[16:33:53.458] <TB1> INFO: Test took 4496ms.
[16:33:53.460] <TB1> INFO: scanning low vcal = 70
[16:33:53.731] <TB1> INFO: Expecting 41600 events.
[16:33:57.938] <TB1> INFO: 41600 events read in total (3651ms).
[16:33:57.938] <TB1> INFO: Test took 4478ms.
[16:33:57.941] <TB1> INFO: scanning low vcal = 80
[16:33:58.210] <TB1> INFO: Expecting 41600 events.
[16:34:02.407] <TB1> INFO: 41600 events read in total (3641ms).
[16:34:02.407] <TB1> INFO: Test took 4466ms.
[16:34:02.409] <TB1> INFO: scanning low vcal = 90
[16:34:02.682] <TB1> INFO: Expecting 41600 events.
[16:34:06.887] <TB1> INFO: 41600 events read in total (3649ms).
[16:34:06.887] <TB1> INFO: Test took 4478ms.
[16:34:06.890] <TB1> INFO: scanning low vcal = 100
[16:34:07.155] <TB1> INFO: Expecting 41600 events.
[16:34:11.349] <TB1> INFO: 41600 events read in total (3638ms).
[16:34:11.349] <TB1> INFO: Test took 4458ms.
[16:34:11.352] <TB1> INFO: scanning low vcal = 110
[16:34:11.614] <TB1> INFO: Expecting 41600 events.
[16:34:15.841] <TB1> INFO: 41600 events read in total (3670ms).
[16:34:15.841] <TB1> INFO: Test took 4489ms.
[16:34:15.844] <TB1> INFO: scanning low vcal = 120
[16:34:16.103] <TB1> INFO: Expecting 41600 events.
[16:34:20.306] <TB1> INFO: 41600 events read in total (3647ms).
[16:34:20.306] <TB1> INFO: Test took 4462ms.
[16:34:20.309] <TB1> INFO: scanning low vcal = 130
[16:34:20.574] <TB1> INFO: Expecting 41600 events.
[16:34:24.790] <TB1> INFO: 41600 events read in total (3660ms).
[16:34:24.790] <TB1> INFO: Test took 4481ms.
[16:34:24.793] <TB1> INFO: scanning low vcal = 140
[16:34:25.068] <TB1> INFO: Expecting 41600 events.
[16:34:29.338] <TB1> INFO: 41600 events read in total (3714ms).
[16:34:29.340] <TB1> INFO: Test took 4547ms.
[16:34:29.344] <TB1> INFO: scanning low vcal = 150
[16:34:29.677] <TB1> INFO: Expecting 41600 events.
[16:34:33.922] <TB1> INFO: 41600 events read in total (3689ms).
[16:34:33.923] <TB1> INFO: Test took 4579ms.
[16:34:33.926] <TB1> INFO: scanning low vcal = 160
[16:34:34.214] <TB1> INFO: Expecting 41600 events.
[16:34:38.422] <TB1> INFO: 41600 events read in total (3652ms).
[16:34:38.423] <TB1> INFO: Test took 4497ms.
[16:34:38.425] <TB1> INFO: scanning low vcal = 170
[16:34:38.708] <TB1> INFO: Expecting 41600 events.
[16:34:42.919] <TB1> INFO: 41600 events read in total (3655ms).
[16:34:42.920] <TB1> INFO: Test took 4495ms.
[16:34:42.923] <TB1> INFO: scanning low vcal = 180
[16:34:43.198] <TB1> INFO: Expecting 41600 events.
[16:34:47.432] <TB1> INFO: 41600 events read in total (3677ms).
[16:34:47.432] <TB1> INFO: Test took 4509ms.
[16:34:47.435] <TB1> INFO: scanning low vcal = 190
[16:34:47.706] <TB1> INFO: Expecting 41600 events.
[16:34:51.941] <TB1> INFO: 41600 events read in total (3679ms).
[16:34:51.942] <TB1> INFO: Test took 4507ms.
[16:34:51.944] <TB1> INFO: scanning low vcal = 200
[16:34:52.206] <TB1> INFO: Expecting 41600 events.
[16:34:56.437] <TB1> INFO: 41600 events read in total (3675ms).
[16:34:56.438] <TB1> INFO: Test took 4493ms.
[16:34:56.440] <TB1> INFO: scanning low vcal = 210
[16:34:56.712] <TB1> INFO: Expecting 41600 events.
[16:35:00.938] <TB1> INFO: 41600 events read in total (3670ms).
[16:35:00.939] <TB1> INFO: Test took 4499ms.
[16:35:00.942] <TB1> INFO: scanning low vcal = 220
[16:35:01.205] <TB1> INFO: Expecting 41600 events.
[16:35:05.412] <TB1> INFO: 41600 events read in total (3651ms).
[16:35:05.412] <TB1> INFO: Test took 4470ms.
[16:35:05.415] <TB1> INFO: scanning low vcal = 230
[16:35:05.696] <TB1> INFO: Expecting 41600 events.
[16:35:09.904] <TB1> INFO: 41600 events read in total (3652ms).
[16:35:09.905] <TB1> INFO: Test took 4490ms.
[16:35:09.913] <TB1> INFO: scanning low vcal = 240
[16:35:10.182] <TB1> INFO: Expecting 41600 events.
[16:35:14.406] <TB1> INFO: 41600 events read in total (3668ms).
[16:35:14.407] <TB1> INFO: Test took 4494ms.
[16:35:14.410] <TB1> INFO: scanning low vcal = 250
[16:35:14.704] <TB1> INFO: Expecting 41600 events.
[16:35:18.937] <TB1> INFO: 41600 events read in total (3676ms).
[16:35:18.938] <TB1> INFO: Test took 4528ms.
[16:35:18.942] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[16:35:19.216] <TB1> INFO: Expecting 41600 events.
[16:35:23.437] <TB1> INFO: 41600 events read in total (3665ms).
[16:35:23.438] <TB1> INFO: Test took 4496ms.
[16:35:23.440] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[16:35:23.709] <TB1> INFO: Expecting 41600 events.
[16:35:27.916] <TB1> INFO: 41600 events read in total (3651ms).
[16:35:27.916] <TB1> INFO: Test took 4476ms.
[16:35:27.919] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[16:35:28.204] <TB1> INFO: Expecting 41600 events.
[16:35:32.416] <TB1> INFO: 41600 events read in total (3656ms).
[16:35:32.416] <TB1> INFO: Test took 4497ms.
[16:35:32.419] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[16:35:32.698] <TB1> INFO: Expecting 41600 events.
[16:35:36.912] <TB1> INFO: 41600 events read in total (3657ms).
[16:35:36.913] <TB1> INFO: Test took 4494ms.
[16:35:36.915] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:35:37.184] <TB1> INFO: Expecting 41600 events.
[16:35:41.374] <TB1> INFO: 41600 events read in total (3634ms).
[16:35:41.375] <TB1> INFO: Test took 4460ms.
[16:35:41.831] <TB1> INFO: PixTestGainPedestal::measure() done
[16:36:15.671] <TB1> INFO: PixTestGainPedestal::fit() done
[16:36:15.671] <TB1> INFO: non-linearity mean: 0.959 0.969 0.952 0.959 0.958 0.966 0.958 0.962 0.944 0.957 0.958 0.959 0.962 0.958 0.951 0.964
[16:36:15.671] <TB1> INFO: non-linearity RMS: 0.006 0.005 0.007 0.006 0.006 0.005 0.006 0.008 0.007 0.006 0.007 0.006 0.006 0.007 0.007 0.006
[16:36:15.671] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:36:15.689] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:36:15.708] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:36:15.726] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:36:15.745] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:36:15.763] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:36:15.782] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:36:15.800] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:36:15.818] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:36:15.837] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:36:15.855] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:36:15.874] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:36:15.892] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:36:15.911] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:36:15.929] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:36:15.948] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:36:15.966] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[16:36:15.966] <TB1> INFO: Decoding statistics:
[16:36:15.966] <TB1> INFO: General information:
[16:36:15.966] <TB1> INFO: 16bit words read: 2662400
[16:36:15.966] <TB1> INFO: valid events total: 166400
[16:36:15.966] <TB1> INFO: empty events: 0
[16:36:15.966] <TB1> INFO: valid events with pixels: 166400
[16:36:15.966] <TB1> INFO: valid pixel hits: 665443
[16:36:15.966] <TB1> INFO: Event errors: 0
[16:36:15.966] <TB1> INFO: start marker: 0
[16:36:15.966] <TB1> INFO: stop marker: 0
[16:36:15.966] <TB1> INFO: overflow: 0
[16:36:15.966] <TB1> INFO: invalid 5bit words: 0
[16:36:15.966] <TB1> INFO: invalid XOR eye diagram: 0
[16:36:15.966] <TB1> INFO: frame (failed synchr.): 0
[16:36:15.966] <TB1> INFO: idle data (no TBM trl): 0
[16:36:15.966] <TB1> INFO: no data (only TBM hdr): 0
[16:36:15.966] <TB1> INFO: TBM errors: 0
[16:36:15.966] <TB1> INFO: flawed TBM headers: 0
[16:36:15.966] <TB1> INFO: flawed TBM trailers: 0
[16:36:15.966] <TB1> INFO: event ID mismatches: 0
[16:36:15.966] <TB1> INFO: ROC errors: 0
[16:36:15.966] <TB1> INFO: missing ROC header(s): 0
[16:36:15.967] <TB1> INFO: misplaced readback start: 0
[16:36:15.967] <TB1> INFO: Pixel decoding errors: 0
[16:36:15.967] <TB1> INFO: pixel data incomplete: 0
[16:36:15.967] <TB1> INFO: pixel address: 0
[16:36:15.967] <TB1> INFO: pulse height fill bit: 0
[16:36:15.967] <TB1> INFO: buffer corruption: 0
[16:36:15.974] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C15.dat
[16:36:15.975] <TB1> INFO: ######################################################################
[16:36:15.975] <TB1> INFO: PixTestReadback::doTest()
[16:36:15.975] <TB1> INFO: ######################################################################
[16:36:15.975] <TB1> INFO: ----------------------------------------------------------------------
[16:36:15.975] <TB1> INFO: PixTestReadback::CalibrateVd()
[16:36:15.975] <TB1> INFO: ----------------------------------------------------------------------
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C0.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C1.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C2.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C3.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C4.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C5.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C6.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C7.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C8.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C9.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C10.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C11.dat
[16:36:25.375] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C12.dat
[16:36:25.376] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C13.dat
[16:36:25.376] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C14.dat
[16:36:25.376] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C15.dat
[16:36:25.396] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:36:25.396] <TB1> INFO: ----------------------------------------------------------------------
[16:36:25.396] <TB1> INFO: PixTestReadback::CalibrateVa()
[16:36:25.396] <TB1> INFO: ----------------------------------------------------------------------
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C0.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C1.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C2.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C3.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C4.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C5.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C6.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C7.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C8.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C9.dat
[16:36:34.894] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C10.dat
[16:36:34.895] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C11.dat
[16:36:34.895] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C12.dat
[16:36:34.895] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C13.dat
[16:36:34.895] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C14.dat
[16:36:34.895] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C15.dat
[16:36:34.925] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:36:34.925] <TB1> INFO: ----------------------------------------------------------------------
[16:36:34.925] <TB1> INFO: PixTestReadback::readbackVbg()
[16:36:34.925] <TB1> INFO: ----------------------------------------------------------------------
[16:36:42.207] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:36:42.207] <TB1> INFO: ----------------------------------------------------------------------
[16:36:42.207] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[16:36:42.207] <TB1> INFO: ----------------------------------------------------------------------
[16:36:42.207] <TB1> INFO: Vbg will be calibrated using Vd calibration
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.2calibrated Vbg = 1.2025 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.4calibrated Vbg = 1.20123 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159.1calibrated Vbg = 1.19828 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.6calibrated Vbg = 1.21183 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.8calibrated Vbg = 1.21033 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.7calibrated Vbg = 1.21206 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.5calibrated Vbg = 1.2128 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152calibrated Vbg = 1.21421 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.1calibrated Vbg = 1.21386 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.8calibrated Vbg = 1.21566 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 146calibrated Vbg = 1.21413 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.9calibrated Vbg = 1.20753 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.4calibrated Vbg = 1.20441 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.9calibrated Vbg = 1.20398 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148.1calibrated Vbg = 1.20687 :::*/*/*/*/
[16:36:42.207] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.2calibrated Vbg = 1.2078 :::*/*/*/*/
[16:36:42.209] <TB1> INFO: ----------------------------------------------------------------------
[16:36:42.209] <TB1> INFO: PixTestReadback::CalibrateIa()
[16:36:42.209] <TB1> INFO: ----------------------------------------------------------------------
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C0.dat
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C1.dat
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C2.dat
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C3.dat
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C4.dat
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C5.dat
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C6.dat
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C7.dat
[16:39:17.480] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C8.dat
[16:39:17.481] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C9.dat
[16:39:17.481] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C10.dat
[16:39:17.481] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C11.dat
[16:39:17.481] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C12.dat
[16:39:17.481] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C13.dat
[16:39:17.481] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C14.dat
[16:39:17.481] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2189_FullQualification_2016-03-23_10h11m_1458724269//004_FulltestPxar_p17//readbackCal_C15.dat
[16:39:17.509] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:39:17.511] <TB1> INFO: PixTestReadback::doTest() done
[16:39:17.511] <TB1> INFO: Decoding statistics:
[16:39:17.511] <TB1> INFO: General information:
[16:39:17.511] <TB1> INFO: 16bit words read: 1024
[16:39:17.511] <TB1> INFO: valid events total: 128
[16:39:17.511] <TB1> INFO: empty events: 128
[16:39:17.511] <TB1> INFO: valid events with pixels: 0
[16:39:17.511] <TB1> INFO: valid pixel hits: 0
[16:39:17.511] <TB1> INFO: Event errors: 0
[16:39:17.511] <TB1> INFO: start marker: 0
[16:39:17.511] <TB1> INFO: stop marker: 0
[16:39:17.512] <TB1> INFO: overflow: 0
[16:39:17.512] <TB1> INFO: invalid 5bit words: 0
[16:39:17.512] <TB1> INFO: invalid XOR eye diagram: 0
[16:39:17.512] <TB1> INFO: frame (failed synchr.): 0
[16:39:17.512] <TB1> INFO: idle data (no TBM trl): 0
[16:39:17.512] <TB1> INFO: no data (only TBM hdr): 0
[16:39:17.512] <TB1> INFO: TBM errors: 0
[16:39:17.512] <TB1> INFO: flawed TBM headers: 0
[16:39:17.512] <TB1> INFO: flawed TBM trailers: 0
[16:39:17.512] <TB1> INFO: event ID mismatches: 0
[16:39:17.512] <TB1> INFO: ROC errors: 0
[16:39:17.512] <TB1> INFO: missing ROC header(s): 0
[16:39:17.512] <TB1> INFO: misplaced readback start: 0
[16:39:17.512] <TB1> INFO: Pixel decoding errors: 0
[16:39:17.512] <TB1> INFO: pixel data incomplete: 0
[16:39:17.512] <TB1> INFO: pixel address: 0
[16:39:17.512] <TB1> INFO: pulse height fill bit: 0
[16:39:17.512] <TB1> INFO: buffer corruption: 0
[16:39:17.536] <TB1> INFO: Decoding statistics:
[16:39:17.536] <TB1> INFO: General information:
[16:39:17.536] <TB1> INFO: 16bit words read: 2755364
[16:39:17.536] <TB1> INFO: valid events total: 176768
[16:39:17.536] <TB1> INFO: empty events: 7863
[16:39:17.536] <TB1> INFO: valid events with pixels: 168905
[16:39:17.536] <TB1> INFO: valid pixel hits: 667948
[16:39:17.536] <TB1> INFO: Event errors: 0
[16:39:17.536] <TB1> INFO: start marker: 0
[16:39:17.536] <TB1> INFO: stop marker: 0
[16:39:17.536] <TB1> INFO: overflow: 0
[16:39:17.536] <TB1> INFO: invalid 5bit words: 0
[16:39:17.536] <TB1> INFO: invalid XOR eye diagram: 0
[16:39:17.536] <TB1> INFO: frame (failed synchr.): 0
[16:39:17.536] <TB1> INFO: idle data (no TBM trl): 0
[16:39:17.536] <TB1> INFO: no data (only TBM hdr): 0
[16:39:17.536] <TB1> INFO: TBM errors: 0
[16:39:17.536] <TB1> INFO: flawed TBM headers: 0
[16:39:17.536] <TB1> INFO: flawed TBM trailers: 0
[16:39:17.536] <TB1> INFO: event ID mismatches: 0
[16:39:17.536] <TB1> INFO: ROC errors: 0
[16:39:17.536] <TB1> INFO: missing ROC header(s): 0
[16:39:17.536] <TB1> INFO: misplaced readback start: 0
[16:39:17.536] <TB1> INFO: Pixel decoding errors: 0
[16:39:17.536] <TB1> INFO: pixel data incomplete: 0
[16:39:17.536] <TB1> INFO: pixel address: 0
[16:39:17.536] <TB1> INFO: pulse height fill bit: 0
[16:39:17.536] <TB1> INFO: buffer corruption: 0
[16:39:17.536] <TB1> INFO: enter test to run
[16:39:17.536] <TB1> INFO: test: exit no parameter change
[16:39:17.661] <TB1> QUIET: Connection to board 153 closed.
[16:39:17.741] <TB1> INFO: pXar: this is the end, my friend