[13:04:36.095] <TB1> INFO: *** Welcome to pxar ***
[13:04:36.095] <TB1> INFO: *** Today: 2016/04/18
[13:04:36.186] <TB1> INFO: *** Version: 9751-dirty
[13:04:36.186] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C15.dat
[13:04:36.187] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//tbmParameters_C0b.dat
[13:04:36.187] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//defaultMaskFile.dat
[13:04:36.187] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters_C15.dat
[13:04:36.260] <TB1> INFO: clk: 4
[13:04:36.260] <TB1> INFO: ctr: 4
[13:04:36.260] <TB1> INFO: sda: 19
[13:04:36.260] <TB1> INFO: tin: 9
[13:04:36.260] <TB1> INFO: level: 15
[13:04:36.260] <TB1> INFO: triggerdelay: 0
[13:04:36.260] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[13:04:36.260] <TB1> INFO: Log level: INFO
[13:04:36.267] <TB1> INFO: Found DTB DTB_WXBYFL
[13:04:36.277] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[13:04:36.279] <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:
------------------------------------------------------
[13:04:36.281] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[13:04:37.815] <TB1> INFO: DUT info:
[13:04:37.815] <TB1> INFO: The DUT currently contains the following objects:
[13:04:37.815] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:04:37.815] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:04:37.815] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:04:37.815] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:04:37.815] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.815] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.816] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.816] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.816] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.816] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.816] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:37.816] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:04:38.217] <TB1> INFO: enter 'restricted' command line mode
[13:04:38.217] <TB1> INFO: enter test to run
[13:04:38.217] <TB1> INFO: test: pretest no parameter change
[13:04:38.217] <TB1> INFO: running: pretest
[13:04:38.221] <TB1> INFO: ----------------------------------------------------------------------
[13:04:38.221] <TB1> INFO: PixTestPretest::programROC()
[13:04:38.221] <TB1> INFO: ----------------------------------------------------------------------
[13:04:56.235] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[13:04:56.235] <TB1> INFO: IA differences per ROC: 18.5 20.1 19.3 20.9 20.1 18.5 20.9 19.3 18.5 18.5 18.5 19.3 16.9 18.5 16.9 18.5
[13:04:56.328] <TB1> INFO: enter test to run
[13:04:56.328] <TB1> INFO: test: pretest no parameter change
[13:04:56.328] <TB1> INFO: running: pretest
[13:04:56.329] <TB1> INFO: ----------------------------------------------------------------------
[13:04:56.329] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[13:04:56.329] <TB1> INFO: ----------------------------------------------------------------------
[13:05:02.416] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[13:05:02.416] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 20.1 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3
[13:05:02.451] <TB1> INFO: enter test to run
[13:05:02.451] <TB1> INFO: test: pretest no parameter change
[13:05:02.451] <TB1> INFO: running: pretest
[13:05:02.451] <TB1> INFO: ----------------------------------------------------------------------
[13:05:02.451] <TB1> INFO: PixTestPretest::findTiming()
[13:05:02.451] <TB1> INFO: ----------------------------------------------------------------------
[13:05:02.451] <TB1> INFO: PixTestCmd::init()
[13:05:02.966] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[13:06:30.749] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[13:06:30.749] <TB1> INFO: (success/tries = 100/100), width = 4
[13:06:30.750] <TB1> INFO: enter test to run
[13:06:30.750] <TB1> INFO: test: pretest no parameter change
[13:06:30.750] <TB1> INFO: running: pretest
[13:06:30.751] <TB1> INFO: ----------------------------------------------------------------------
[13:06:30.752] <TB1> INFO: PixTestPretest::findWorkingPixel()
[13:06:30.752] <TB1> INFO: ----------------------------------------------------------------------
[13:06:30.846] <TB1> INFO: Expecting 231680 events.
[13:06:36.625] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[13:06:36.711] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[13:06:41.156] <TB1> INFO: 231680 events read in total (9754ms).
[13:06:41.160] <TB1> INFO: Test took 10402ms.
[13:06:41.385] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:06:41.451] <TB1> INFO: enter test to run
[13:06:41.451] <TB1> INFO: test: pretest no parameter change
[13:06:41.451] <TB1> INFO: running: pretest
[13:06:41.452] <TB1> INFO: ----------------------------------------------------------------------
[13:06:41.452] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[13:06:41.452] <TB1> INFO: ----------------------------------------------------------------------
[13:06:41.543] <TB1> INFO: Expecting 231680 events.
[13:06:51.784] <TB1> INFO: 231680 events read in total (9684ms).
[13:06:51.788] <TB1> INFO: Test took 10333ms.
[13:06:52.050] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[13:06:52.050] <TB1> INFO: CalDel: 159 161 147 157 138 141 132 131 131 142 122 120 124 126 129 117
[13:06:52.050] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 54
[13:06:52.089] <TB1> INFO: enter test to run
[13:06:52.089] <TB1> INFO: test: pretest no parameter change
[13:06:52.089] <TB1> INFO: running: pretest
[13:06:52.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C0.dat
[13:06:52.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C1.dat
[13:06:52.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C2.dat
[13:06:52.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C3.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C4.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C5.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C6.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C7.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C8.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C9.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C10.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C11.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C12.dat
[13:06:52.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C13.dat
[13:06:52.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C14.dat
[13:06:52.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters_C15.dat
[13:06:52.091] <TB1> INFO: enter test to run
[13:06:52.091] <TB1> INFO: test: fulltest no parameter change
[13:06:52.091] <TB1> INFO: running: fulltest
[13:06:52.091] <TB1> INFO: ######################################################################
[13:06:52.091] <TB1> INFO: PixTestFullTest::doTest()
[13:06:52.091] <TB1> INFO: ######################################################################
[13:06:52.092] <TB1> INFO: ######################################################################
[13:06:52.092] <TB1> INFO: PixTestAlive::doTest()
[13:06:52.092] <TB1> INFO: ######################################################################
[13:06:52.093] <TB1> INFO: ----------------------------------------------------------------------
[13:06:52.093] <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)
[13:06:52.093] <TB1> INFO: ----------------------------------------------------------------------
[13:06:52.378] <TB1> INFO: Expecting 41600 events.
[13:06:56.145] <TB1> INFO: 41600 events read in total (3210ms).
[13:06:56.146] <TB1> INFO: Test took 4052ms.
[13:06:56.378] <TB1> INFO: PixTestAlive::aliveTest() done
[13:06:56.378] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[13:06:56.379] <TB1> INFO: ----------------------------------------------------------------------
[13:06:56.379] <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)
[13:06:56.379] <TB1> INFO: ----------------------------------------------------------------------
[13:06:56.644] <TB1> INFO: Expecting 41600 events.
[13:06:59.609] <TB1> INFO: 41600 events read in total (2409ms).
[13:06:59.609] <TB1> INFO: Test took 3228ms.
[13:06:59.610] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:06:59.851] <TB1> INFO: PixTestAlive::maskTest() done
[13:06:59.851] <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
[13:06:59.852] <TB1> INFO: ----------------------------------------------------------------------
[13:06:59.852] <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)
[13:06:59.852] <TB1> INFO: ----------------------------------------------------------------------
[13:07:00.132] <TB1> INFO: Expecting 41600 events.
[13:07:03.938] <TB1> INFO: 41600 events read in total (3250ms).
[13:07:03.939] <TB1> INFO: Test took 4086ms.
[13:07:04.166] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[13:07:04.166] <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
[13:07:04.166] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[13:07:04.167] <TB1> INFO: Decoding statistics:
[13:07:04.167] <TB1> INFO: General information:
[13:07:04.167] <TB1> INFO: 16bit words read: 0
[13:07:04.167] <TB1> INFO: valid events total: 0
[13:07:04.167] <TB1> INFO: empty events: 0
[13:07:04.167] <TB1> INFO: valid events with pixels: 0
[13:07:04.167] <TB1> INFO: valid pixel hits: 0
[13:07:04.167] <TB1> INFO: Event errors: 0
[13:07:04.167] <TB1> INFO: start marker: 0
[13:07:04.167] <TB1> INFO: stop marker: 0
[13:07:04.167] <TB1> INFO: overflow: 0
[13:07:04.167] <TB1> INFO: invalid 5bit words: 0
[13:07:04.167] <TB1> INFO: invalid XOR eye diagram: 0
[13:07:04.167] <TB1> INFO: frame (failed synchr.): 0
[13:07:04.167] <TB1> INFO: idle data (no TBM trl): 0
[13:07:04.167] <TB1> INFO: no data (only TBM hdr): 0
[13:07:04.167] <TB1> INFO: TBM errors: 0
[13:07:04.167] <TB1> INFO: flawed TBM headers: 0
[13:07:04.167] <TB1> INFO: flawed TBM trailers: 0
[13:07:04.167] <TB1> INFO: event ID mismatches: 0
[13:07:04.167] <TB1> INFO: ROC errors: 0
[13:07:04.167] <TB1> INFO: missing ROC header(s): 0
[13:07:04.167] <TB1> INFO: misplaced readback start: 0
[13:07:04.167] <TB1> INFO: Pixel decoding errors: 0
[13:07:04.167] <TB1> INFO: pixel data incomplete: 0
[13:07:04.167] <TB1> INFO: pixel address: 0
[13:07:04.167] <TB1> INFO: pulse height fill bit: 0
[13:07:04.167] <TB1> INFO: buffer corruption: 0
[13:07:04.180] <TB1> INFO: ######################################################################
[13:07:04.180] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:07:04.180] <TB1> INFO: ######################################################################
[13:07:04.182] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:07:04.193] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:07:04.193] <TB1> INFO: run 1 of 1
[13:07:04.466] <TB1> INFO: Expecting 3120000 events.
[13:07:46.017] <TB1> INFO: 882490 events read in total (40995ms).
[13:08:27.204] <TB1> INFO: 1755430 events read in total (82183ms).
[13:09:08.479] <TB1> INFO: 2639035 events read in total (123458ms).
[13:09:30.587] <TB1> INFO: 3120000 events read in total (145565ms).
[13:09:30.633] <TB1> INFO: Test took 146440ms.
[13:09:54.746] <TB1> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[13:09:54.746] <TB1> INFO: number of dead bumps (per ROC): 6 18 6 8 5 4 6 25 6 3 7 8 4 5 15 10
[13:09:54.746] <TB1> INFO: separation cut (per ROC): 88 72 94 82 90 87 99 82 94 88 92 103 88 96 80 116
[13:09:54.746] <TB1> INFO: Decoding statistics:
[13:09:54.746] <TB1> INFO: General information:
[13:09:54.746] <TB1> INFO: 16bit words read: 0
[13:09:54.746] <TB1> INFO: valid events total: 0
[13:09:54.746] <TB1> INFO: empty events: 0
[13:09:54.746] <TB1> INFO: valid events with pixels: 0
[13:09:54.746] <TB1> INFO: valid pixel hits: 0
[13:09:54.746] <TB1> INFO: Event errors: 0
[13:09:54.746] <TB1> INFO: start marker: 0
[13:09:54.746] <TB1> INFO: stop marker: 0
[13:09:54.746] <TB1> INFO: overflow: 0
[13:09:54.746] <TB1> INFO: invalid 5bit words: 0
[13:09:54.746] <TB1> INFO: invalid XOR eye diagram: 0
[13:09:54.746] <TB1> INFO: frame (failed synchr.): 0
[13:09:54.746] <TB1> INFO: idle data (no TBM trl): 0
[13:09:54.746] <TB1> INFO: no data (only TBM hdr): 0
[13:09:54.746] <TB1> INFO: TBM errors: 0
[13:09:54.746] <TB1> INFO: flawed TBM headers: 0
[13:09:54.746] <TB1> INFO: flawed TBM trailers: 0
[13:09:54.746] <TB1> INFO: event ID mismatches: 0
[13:09:54.746] <TB1> INFO: ROC errors: 0
[13:09:54.746] <TB1> INFO: missing ROC header(s): 0
[13:09:54.746] <TB1> INFO: misplaced readback start: 0
[13:09:54.746] <TB1> INFO: Pixel decoding errors: 0
[13:09:54.746] <TB1> INFO: pixel data incomplete: 0
[13:09:54.746] <TB1> INFO: pixel address: 0
[13:09:54.746] <TB1> INFO: pulse height fill bit: 0
[13:09:54.746] <TB1> INFO: buffer corruption: 0
[13:09:54.814] <TB1> INFO: ######################################################################
[13:09:54.814] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:09:54.814] <TB1> INFO: ######################################################################
[13:09:54.815] <TB1> INFO: ----------------------------------------------------------------------
[13:09:54.815] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:09:54.815] <TB1> INFO: ----------------------------------------------------------------------
[13:09:54.815] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:09:54.823] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:09:54.823] <TB1> INFO: run 1 of 1
[13:09:55.082] <TB1> INFO: Expecting 26208000 events.
[13:10:29.307] <TB1> INFO: 942750 events read in total (33669ms).
[13:11:02.694] <TB1> INFO: 1867600 events read in total (67056ms).
[13:11:35.724] <TB1> INFO: 2790150 events read in total (100086ms).
[13:12:09.441] <TB1> INFO: 3714550 events read in total (133803ms).
[13:12:43.167] <TB1> INFO: 4636750 events read in total (167529ms).
[13:13:16.698] <TB1> INFO: 5555650 events read in total (201060ms).
[13:13:50.213] <TB1> INFO: 6475150 events read in total (234575ms).
[13:14:23.799] <TB1> INFO: 7394250 events read in total (268161ms).
[13:14:57.380] <TB1> INFO: 8313350 events read in total (301742ms).
[13:15:30.572] <TB1> INFO: 9233650 events read in total (334934ms).
[13:16:03.980] <TB1> INFO: 10150550 events read in total (368342ms).
[13:16:37.586] <TB1> INFO: 11066350 events read in total (401948ms).
[13:17:10.886] <TB1> INFO: 11981300 events read in total (435248ms).
[13:17:44.504] <TB1> INFO: 12895700 events read in total (468866ms).
[13:18:17.568] <TB1> INFO: 13801050 events read in total (501930ms).
[13:18:51.294] <TB1> INFO: 14702950 events read in total (535656ms).
[13:19:24.944] <TB1> INFO: 15604550 events read in total (569306ms).
[13:19:58.533] <TB1> INFO: 16504450 events read in total (602895ms).
[13:20:31.929] <TB1> INFO: 17404100 events read in total (636291ms).
[13:21:05.188] <TB1> INFO: 18303450 events read in total (669550ms).
[13:21:38.220] <TB1> INFO: 19202400 events read in total (702582ms).
[13:22:11.277] <TB1> INFO: 20098600 events read in total (735639ms).
[13:22:44.556] <TB1> INFO: 20996100 events read in total (768918ms).
[13:23:17.843] <TB1> INFO: 21895850 events read in total (802205ms).
[13:23:50.822] <TB1> INFO: 22793700 events read in total (835184ms).
[13:24:23.893] <TB1> INFO: 23693350 events read in total (868255ms).
[13:24:57.242] <TB1> INFO: 24592850 events read in total (901604ms).
[13:25:30.356] <TB1> INFO: 25494450 events read in total (934718ms).
[13:25:56.714] <TB1> INFO: 26208000 events read in total (961076ms).
[13:25:56.744] <TB1> INFO: Test took 961921ms.
[13:25:56.978] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:25:58.369] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:25:59.824] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:01.186] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:02.696] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:04.545] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:06.365] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:07.786] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:09.201] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:10.779] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:12.241] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:13.696] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:15.099] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:16.482] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:17.913] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:19.386] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:20.823] <TB1> INFO: PixTestScurves::scurves() done
[13:26:20.823] <TB1> INFO: Vcal mean: 99.54 89.52 106.84 88.32 91.82 90.99 101.40 91.47 100.84 91.73 88.13 103.08 92.28 98.27 82.16 115.07
[13:26:20.823] <TB1> INFO: Vcal RMS: 5.51 4.74 5.55 4.96 4.75 5.48 5.21 5.58 5.78 5.07 5.38 5.31 4.71 5.45 4.19 6.57
[13:26:20.823] <TB1> INFO: PixTestScurves::fullTest() done, duration: 986 seconds
[13:26:20.823] <TB1> INFO: Decoding statistics:
[13:26:20.823] <TB1> INFO: General information:
[13:26:20.823] <TB1> INFO: 16bit words read: 0
[13:26:20.823] <TB1> INFO: valid events total: 0
[13:26:20.823] <TB1> INFO: empty events: 0
[13:26:20.823] <TB1> INFO: valid events with pixels: 0
[13:26:20.823] <TB1> INFO: valid pixel hits: 0
[13:26:20.823] <TB1> INFO: Event errors: 0
[13:26:20.823] <TB1> INFO: start marker: 0
[13:26:20.823] <TB1> INFO: stop marker: 0
[13:26:20.823] <TB1> INFO: overflow: 0
[13:26:20.823] <TB1> INFO: invalid 5bit words: 0
[13:26:20.823] <TB1> INFO: invalid XOR eye diagram: 0
[13:26:20.823] <TB1> INFO: frame (failed synchr.): 0
[13:26:20.823] <TB1> INFO: idle data (no TBM trl): 0
[13:26:20.823] <TB1> INFO: no data (only TBM hdr): 0
[13:26:20.823] <TB1> INFO: TBM errors: 0
[13:26:20.823] <TB1> INFO: flawed TBM headers: 0
[13:26:20.823] <TB1> INFO: flawed TBM trailers: 0
[13:26:20.823] <TB1> INFO: event ID mismatches: 0
[13:26:20.823] <TB1> INFO: ROC errors: 0
[13:26:20.823] <TB1> INFO: missing ROC header(s): 0
[13:26:20.823] <TB1> INFO: misplaced readback start: 0
[13:26:20.824] <TB1> INFO: Pixel decoding errors: 0
[13:26:20.824] <TB1> INFO: pixel data incomplete: 0
[13:26:20.824] <TB1> INFO: pixel address: 0
[13:26:20.824] <TB1> INFO: pulse height fill bit: 0
[13:26:20.824] <TB1> INFO: buffer corruption: 0
[13:26:20.897] <TB1> INFO: ######################################################################
[13:26:20.897] <TB1> INFO: PixTestTrim::doTest()
[13:26:20.897] <TB1> INFO: ######################################################################
[13:26:20.898] <TB1> INFO: ----------------------------------------------------------------------
[13:26:20.898] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:26:20.898] <TB1> INFO: ----------------------------------------------------------------------
[13:26:20.978] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:26:20.978] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:26:20.987] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:26:20.987] <TB1> INFO: run 1 of 1
[13:26:21.251] <TB1> INFO: Expecting 6281600 events.
[13:27:01.507] <TB1> INFO: 1090420 events read in total (39697ms).
[13:27:40.772] <TB1> INFO: 2173800 events read in total (78962ms).
[13:28:20.048] <TB1> INFO: 3252600 events read in total (118239ms).
[13:28:59.914] <TB1> INFO: 4336150 events read in total (158104ms).
[13:29:39.704] <TB1> INFO: 5423420 events read in total (197894ms).
[13:30:10.977] <TB1> INFO: 6281600 events read in total (229167ms).
[13:30:11.009] <TB1> INFO: Test took 230022ms.
[13:30:30.969] <TB1> INFO: ROC 0 VthrComp = 95
[13:30:30.969] <TB1> INFO: ROC 1 VthrComp = 91
[13:30:30.970] <TB1> INFO: ROC 2 VthrComp = 98
[13:30:30.970] <TB1> INFO: ROC 3 VthrComp = 88
[13:30:30.970] <TB1> INFO: ROC 4 VthrComp = 95
[13:30:30.970] <TB1> INFO: ROC 5 VthrComp = 91
[13:30:30.970] <TB1> INFO: ROC 6 VthrComp = 102
[13:30:30.970] <TB1> INFO: ROC 7 VthrComp = 94
[13:30:30.970] <TB1> INFO: ROC 8 VthrComp = 100
[13:30:30.970] <TB1> INFO: ROC 9 VthrComp = 93
[13:30:30.970] <TB1> INFO: ROC 10 VthrComp = 90
[13:30:30.970] <TB1> INFO: ROC 11 VthrComp = 102
[13:30:30.970] <TB1> INFO: ROC 12 VthrComp = 92
[13:30:30.971] <TB1> INFO: ROC 13 VthrComp = 98
[13:30:30.971] <TB1> INFO: ROC 14 VthrComp = 87
[13:30:30.971] <TB1> INFO: ROC 15 VthrComp = 107
[13:30:30.971] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:30:30.971] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:30:30.979] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:30:30.979] <TB1> INFO: run 1 of 1
[13:30:31.246] <TB1> INFO: Expecting 6281600 events.
[13:31:06.897] <TB1> INFO: 757650 events read in total (35095ms).
[13:31:41.952] <TB1> INFO: 1513110 events read in total (70150ms).
[13:32:16.680] <TB1> INFO: 2268860 events read in total (104878ms).
[13:32:51.534] <TB1> INFO: 3025350 events read in total (139732ms).
[13:33:26.145] <TB1> INFO: 3775770 events read in total (174343ms).
[13:34:00.604] <TB1> INFO: 4522680 events read in total (208802ms).
[13:34:35.199] <TB1> INFO: 5267600 events read in total (243397ms).
[13:35:10.152] <TB1> INFO: 6012300 events read in total (278350ms).
[13:35:23.072] <TB1> INFO: 6281600 events read in total (291270ms).
[13:35:23.137] <TB1> INFO: Test took 292157ms.
[13:35:47.829] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 59.9126 for pixel 0/11 mean/min/max = 45.8878/31.83/59.9457
[13:35:47.830] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 56.5373 for pixel 22/4 mean/min/max = 45.4706/34.3758/56.5654
[13:35:47.830] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 62.3212 for pixel 3/4 mean/min/max = 47.3573/32.3765/62.338
[13:35:47.830] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.8189 for pixel 4/13 mean/min/max = 46.1765/34.5227/57.8303
[13:35:47.830] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 56.6481 for pixel 21/15 mean/min/max = 44.878/33.0345/56.7215
[13:35:47.831] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.3359 for pixel 8/36 mean/min/max = 46.1186/32.6894/59.5478
[13:35:47.831] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.2063 for pixel 0/75 mean/min/max = 44.5631/31.6626/57.4636
[13:35:47.831] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.1669 for pixel 20/6 mean/min/max = 45.6627/33.0819/58.2435
[13:35:47.831] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 59.5819 for pixel 12/79 mean/min/max = 45.5483/31.3937/59.703
[13:35:47.832] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 56.0863 for pixel 27/3 mean/min/max = 45.025/33.908/56.142
[13:35:47.832] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 58.6472 for pixel 0/72 mean/min/max = 46.3322/33.6078/59.0567
[13:35:47.832] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.8688 for pixel 0/74 mean/min/max = 45.4941/32.8858/58.1024
[13:35:47.832] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 56.8386 for pixel 5/53 mean/min/max = 45.328/33.7334/56.9225
[13:35:47.832] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.2172 for pixel 5/79 mean/min/max = 45.1035/31.6983/58.5086
[13:35:47.833] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 55.1663 for pixel 0/14 mean/min/max = 44.2541/33.2412/55.2669
[13:35:47.833] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 74.221 for pixel 12/14 mean/min/max = 55.6118/36.7636/74.4599
[13:35:47.833] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:35:47.922] <TB1> INFO: Expecting 514560 events.
[13:36:00.616] <TB1> INFO: 514560 events read in total (12138ms).
[13:36:00.620] <TB1> INFO: Expecting 514560 events.
[13:36:13.245] <TB1> INFO: 514560 events read in total (12195ms).
[13:36:13.251] <TB1> INFO: Expecting 514560 events.
[13:36:26.021] <TB1> INFO: 514560 events read in total (12352ms).
[13:36:26.030] <TB1> INFO: Expecting 514560 events.
[13:36:38.797] <TB1> INFO: 514560 events read in total (12349ms).
[13:36:38.807] <TB1> INFO: Expecting 514560 events.
[13:36:51.679] <TB1> INFO: 514560 events read in total (12453ms).
[13:36:51.691] <TB1> INFO: Expecting 514560 events.
[13:37:04.528] <TB1> INFO: 514560 events read in total (12421ms).
[13:37:04.541] <TB1> INFO: Expecting 514560 events.
[13:37:17.350] <TB1> INFO: 514560 events read in total (12394ms).
[13:37:17.365] <TB1> INFO: Expecting 514560 events.
[13:37:30.163] <TB1> INFO: 514560 events read in total (12383ms).
[13:37:30.180] <TB1> INFO: Expecting 514560 events.
[13:37:43.043] <TB1> INFO: 514560 events read in total (12453ms).
[13:37:43.061] <TB1> INFO: Expecting 514560 events.
[13:37:55.836] <TB1> INFO: 514560 events read in total (12368ms).
[13:37:55.860] <TB1> INFO: Expecting 514560 events.
[13:38:08.552] <TB1> INFO: 514560 events read in total (12291ms).
[13:38:08.573] <TB1> INFO: Expecting 514560 events.
[13:38:21.265] <TB1> INFO: 514560 events read in total (12284ms).
[13:38:21.290] <TB1> INFO: Expecting 514560 events.
[13:38:34.021] <TB1> INFO: 514560 events read in total (12326ms).
[13:38:34.046] <TB1> INFO: Expecting 514560 events.
[13:38:46.789] <TB1> INFO: 514560 events read in total (12341ms).
[13:38:46.818] <TB1> INFO: Expecting 514560 events.
[13:38:59.509] <TB1> INFO: 514560 events read in total (12289ms).
[13:38:59.539] <TB1> INFO: Expecting 514560 events.
[13:39:12.515] <TB1> INFO: 514560 events read in total (12575ms).
[13:39:12.561] <TB1> INFO: Test took 204728ms.
[13:39:13.463] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:39:13.472] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:39:13.472] <TB1> INFO: run 1 of 1
[13:39:13.738] <TB1> INFO: Expecting 6281600 events.
[13:39:49.251] <TB1> INFO: 744740 events read in total (34956ms).
[13:40:23.845] <TB1> INFO: 1487430 events read in total (69550ms).
[13:40:58.449] <TB1> INFO: 2230730 events read in total (104154ms).
[13:41:33.000] <TB1> INFO: 2974390 events read in total (138705ms).
[13:42:07.683] <TB1> INFO: 3711980 events read in total (173388ms).
[13:42:41.739] <TB1> INFO: 4445610 events read in total (207444ms).
[13:43:16.210] <TB1> INFO: 5177260 events read in total (241915ms).
[13:43:50.612] <TB1> INFO: 5908300 events read in total (276317ms).
[13:44:09.129] <TB1> INFO: 6281600 events read in total (294834ms).
[13:44:09.189] <TB1> INFO: Test took 295717ms.
[13:44:32.666] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 1.021796 .. 255.000000
[13:44:32.741] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:44:32.749] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:44:32.749] <TB1> INFO: run 1 of 1
[13:44:33.024] <TB1> INFO: Expecting 10608000 events.
[13:45:08.196] <TB1> INFO: 710750 events read in total (34616ms).
[13:45:42.593] <TB1> INFO: 1421990 events read in total (69013ms).
[13:46:16.836] <TB1> INFO: 2133310 events read in total (103256ms).
[13:46:51.136] <TB1> INFO: 2844390 events read in total (137556ms).
[13:47:25.028] <TB1> INFO: 3555440 events read in total (171448ms).
[13:47:59.222] <TB1> INFO: 4266920 events read in total (205642ms).
[13:48:33.618] <TB1> INFO: 4978080 events read in total (240038ms).
[13:49:08.015] <TB1> INFO: 5689630 events read in total (274435ms).
[13:49:42.859] <TB1> INFO: 6400950 events read in total (309279ms).
[13:50:17.176] <TB1> INFO: 7111490 events read in total (343596ms).
[13:50:51.482] <TB1> INFO: 7822260 events read in total (377902ms).
[13:51:25.439] <TB1> INFO: 8532210 events read in total (411859ms).
[13:51:59.276] <TB1> INFO: 9242460 events read in total (445696ms).
[13:52:33.279] <TB1> INFO: 9952300 events read in total (479699ms).
[13:53:05.004] <TB1> INFO: 10608000 events read in total (511424ms).
[13:53:05.179] <TB1> INFO: Test took 512430ms.
[13:53:36.157] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 16.675683 .. 45.542701
[13:53:36.232] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:53:36.240] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:53:36.240] <TB1> INFO: run 1 of 1
[13:53:36.511] <TB1> INFO: Expecting 2080000 events.
[13:54:14.760] <TB1> INFO: 934030 events read in total (37693ms).
[13:54:53.052] <TB1> INFO: 1865690 events read in total (75985ms).
[13:55:02.085] <TB1> INFO: 2080000 events read in total (85019ms).
[13:55:02.098] <TB1> INFO: Test took 85858ms.
[13:55:16.362] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 20.615907 .. 42.663692
[13:55:16.451] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:55:16.460] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:55:16.460] <TB1> INFO: run 1 of 1
[13:55:16.723] <TB1> INFO: Expecting 1788800 events.
[13:55:55.365] <TB1> INFO: 936600 events read in total (38085ms).
[13:56:30.158] <TB1> INFO: 1788800 events read in total (72878ms).
[13:56:30.184] <TB1> INFO: Test took 73725ms.
[13:56:43.594] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 23.649709 .. 42.598745
[13:56:43.699] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:56:43.707] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:56:43.707] <TB1> INFO: run 1 of 1
[13:56:43.988] <TB1> INFO: Expecting 1664000 events.
[13:57:22.590] <TB1> INFO: 916740 events read in total (38045ms).
[13:57:53.383] <TB1> INFO: 1664000 events read in total (68838ms).
[13:57:53.397] <TB1> INFO: Test took 69690ms.
[13:58:07.239] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:58:07.239] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:58:07.247] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:58:07.247] <TB1> INFO: run 1 of 1
[13:58:07.520] <TB1> INFO: Expecting 1705600 events.
[13:58:46.235] <TB1> INFO: 879270 events read in total (38159ms).
[13:59:21.591] <TB1> INFO: 1705600 events read in total (73515ms).
[13:59:21.610] <TB1> INFO: Test took 74362ms.
[13:59:34.579] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C0.dat
[13:59:34.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C1.dat
[13:59:34.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C2.dat
[13:59:34.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C3.dat
[13:59:34.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C4.dat
[13:59:34.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C5.dat
[13:59:34.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C6.dat
[13:59:34.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C7.dat
[13:59:34.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C8.dat
[13:59:34.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C9.dat
[13:59:34.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C10.dat
[13:59:34.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C11.dat
[13:59:34.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C12.dat
[13:59:34.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C13.dat
[13:59:34.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C14.dat
[13:59:34.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C15.dat
[13:59:34.582] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C0.dat
[13:59:34.588] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C1.dat
[13:59:34.594] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C2.dat
[13:59:34.600] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C3.dat
[13:59:34.606] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C4.dat
[13:59:34.612] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C5.dat
[13:59:34.618] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C6.dat
[13:59:34.623] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C7.dat
[13:59:34.629] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C8.dat
[13:59:34.635] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C9.dat
[13:59:34.641] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C10.dat
[13:59:34.647] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C11.dat
[13:59:34.653] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C12.dat
[13:59:34.660] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C13.dat
[13:59:34.666] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C14.dat
[13:59:34.672] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//trimParameters35_C15.dat
[13:59:34.679] <TB1> INFO: PixTestTrim::trimTest() done
[13:59:34.679] <TB1> INFO: vtrim: 88 92 105 98 92 93 97 95 96 90 84 81 91 86 86 141
[13:59:34.679] <TB1> INFO: vthrcomp: 95 91 98 88 95 91 102 94 100 93 90 102 92 98 87 107
[13:59:34.679] <TB1> INFO: vcal mean: 34.94 34.99 35.02 35.02 34.96 34.98 35.00 34.98 34.99 34.99 34.99 35.00 34.96 34.94 34.98 34.98
[13:59:34.679] <TB1> INFO: vcal RMS: 0.88 0.81 0.90 0.84 0.79 0.85 0.86 0.85 0.90 0.83 0.81 0.86 0.81 0.87 0.90 1.03
[13:59:34.679] <TB1> INFO: bits mean: 9.11 9.17 8.85 9.13 9.27 9.26 9.65 9.61 9.55 9.50 8.76 8.92 9.12 9.45 9.70 7.66
[13:59:34.679] <TB1> INFO: bits RMS: 2.90 2.53 2.80 2.54 2.77 2.66 2.79 2.54 2.81 2.57 2.77 2.88 2.67 2.83 2.57 2.29
[13:59:34.685] <TB1> INFO: ----------------------------------------------------------------------
[13:59:34.685] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:59:34.686] <TB1> INFO: ----------------------------------------------------------------------
[13:59:34.688] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:59:34.700] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:59:34.700] <TB1> INFO: run 1 of 1
[13:59:35.009] <TB1> INFO: Expecting 4160000 events.
[14:00:17.750] <TB1> INFO: 964340 events read in total (42184ms).
[14:01:00.388] <TB1> INFO: 1919820 events read in total (84822ms).
[14:01:42.461] <TB1> INFO: 2864135 events read in total (126895ms).
[14:02:24.679] <TB1> INFO: 3806620 events read in total (169113ms).
[14:02:40.709] <TB1> INFO: 4160000 events read in total (185143ms).
[14:02:40.744] <TB1> INFO: Test took 186044ms.
[14:03:09.631] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 192 (-1/-1) hits flags = 528 (plus default)
[14:03:09.646] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:03:09.646] <TB1> INFO: run 1 of 1
[14:03:09.923] <TB1> INFO: Expecting 4014400 events.
[14:03:52.534] <TB1> INFO: 942545 events read in total (42055ms).
[14:04:34.328] <TB1> INFO: 1878510 events read in total (83849ms).
[14:05:16.176] <TB1> INFO: 2803830 events read in total (125697ms).
[14:05:58.056] <TB1> INFO: 3727485 events read in total (167577ms).
[14:06:11.065] <TB1> INFO: 4014400 events read in total (180586ms).
[14:06:11.110] <TB1> INFO: Test took 181464ms.
[14:06:41.346] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[14:06:41.354] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:06:41.354] <TB1> INFO: run 1 of 1
[14:06:41.630] <TB1> INFO: Expecting 3744000 events.
[14:07:24.884] <TB1> INFO: 971100 events read in total (42697ms).
[14:08:07.036] <TB1> INFO: 1933895 events read in total (84849ms).
[14:08:49.067] <TB1> INFO: 2885005 events read in total (126880ms).
[14:09:26.856] <TB1> INFO: 3744000 events read in total (164669ms).
[14:09:26.906] <TB1> INFO: Test took 165552ms.
[14:09:55.607] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 181 (-1/-1) hits flags = 528 (plus default)
[14:09:55.615] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:09:55.615] <TB1> INFO: run 1 of 1
[14:09:55.878] <TB1> INFO: Expecting 3785600 events.
[14:10:39.091] <TB1> INFO: 965680 events read in total (42657ms).
[14:11:21.137] <TB1> INFO: 1923565 events read in total (84703ms).
[14:12:03.529] <TB1> INFO: 2869275 events read in total (127096ms).
[14:12:44.069] <TB1> INFO: 3785600 events read in total (167635ms).
[14:12:44.114] <TB1> INFO: Test took 168498ms.
[14:13:10.995] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 180 (-1/-1) hits flags = 528 (plus default)
[14:13:11.003] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:13:11.003] <TB1> INFO: run 1 of 1
[14:13:11.262] <TB1> INFO: Expecting 3764800 events.
[14:13:53.935] <TB1> INFO: 967350 events read in total (42118ms).
[14:14:36.345] <TB1> INFO: 1927005 events read in total (84527ms).
[14:15:18.808] <TB1> INFO: 2874895 events read in total (126990ms).
[14:15:58.321] <TB1> INFO: 3764800 events read in total (166503ms).
[14:15:58.362] <TB1> INFO: Test took 167359ms.
[14:16:25.072] <TB1> INFO: PixTestTrim::trimBitTest() done
[14:16:25.073] <TB1> INFO: PixTestTrim::doTest() done, duration: 3004 seconds
[14:16:25.073] <TB1> INFO: Decoding statistics:
[14:16:25.073] <TB1> INFO: General information:
[14:16:25.073] <TB1> INFO: 16bit words read: 0
[14:16:25.073] <TB1> INFO: valid events total: 0
[14:16:25.073] <TB1> INFO: empty events: 0
[14:16:25.074] <TB1> INFO: valid events with pixels: 0
[14:16:25.074] <TB1> INFO: valid pixel hits: 0
[14:16:25.074] <TB1> INFO: Event errors: 0
[14:16:25.074] <TB1> INFO: start marker: 0
[14:16:25.074] <TB1> INFO: stop marker: 0
[14:16:25.074] <TB1> INFO: overflow: 0
[14:16:25.074] <TB1> INFO: invalid 5bit words: 0
[14:16:25.074] <TB1> INFO: invalid XOR eye diagram: 0
[14:16:25.074] <TB1> INFO: frame (failed synchr.): 0
[14:16:25.074] <TB1> INFO: idle data (no TBM trl): 0
[14:16:25.074] <TB1> INFO: no data (only TBM hdr): 0
[14:16:25.074] <TB1> INFO: TBM errors: 0
[14:16:25.074] <TB1> INFO: flawed TBM headers: 0
[14:16:25.074] <TB1> INFO: flawed TBM trailers: 0
[14:16:25.074] <TB1> INFO: event ID mismatches: 0
[14:16:25.074] <TB1> INFO: ROC errors: 0
[14:16:25.074] <TB1> INFO: missing ROC header(s): 0
[14:16:25.074] <TB1> INFO: misplaced readback start: 0
[14:16:25.074] <TB1> INFO: Pixel decoding errors: 0
[14:16:25.074] <TB1> INFO: pixel data incomplete: 0
[14:16:25.074] <TB1> INFO: pixel address: 0
[14:16:25.074] <TB1> INFO: pulse height fill bit: 0
[14:16:25.074] <TB1> INFO: buffer corruption: 0
[14:16:25.845] <TB1> INFO: ######################################################################
[14:16:25.845] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:16:25.845] <TB1> INFO: ######################################################################
[14:16:26.109] <TB1> INFO: Expecting 41600 events.
[14:16:29.842] <TB1> INFO: 41600 events read in total (3177ms).
[14:16:29.842] <TB1> INFO: Test took 3995ms.
[14:16:30.337] <TB1> INFO: Expecting 41600 events.
[14:16:34.123] <TB1> INFO: 41600 events read in total (3230ms).
[14:16:34.123] <TB1> INFO: Test took 4078ms.
[14:16:34.414] <TB1> INFO: Expecting 41600 events.
[14:16:38.259] <TB1> INFO: 41600 events read in total (3289ms).
[14:16:38.259] <TB1> INFO: Test took 4112ms.
[14:16:38.514] <TB1> INFO: Expecting 2560 events.
[14:16:39.369] <TB1> INFO: 2560 events read in total (299ms).
[14:16:39.369] <TB1> INFO: Test took 1097ms.
[14:16:39.674] <TB1> INFO: Expecting 2560 events.
[14:16:40.527] <TB1> INFO: 2560 events read in total (297ms).
[14:16:40.527] <TB1> INFO: Test took 1158ms.
[14:16:40.832] <TB1> INFO: Expecting 2560 events.
[14:16:41.687] <TB1> INFO: 2560 events read in total (299ms).
[14:16:41.687] <TB1> INFO: Test took 1160ms.
[14:16:41.992] <TB1> INFO: Expecting 2560 events.
[14:16:42.845] <TB1> INFO: 2560 events read in total (297ms).
[14:16:42.845] <TB1> INFO: Test took 1158ms.
[14:16:43.151] <TB1> INFO: Expecting 2560 events.
[14:16:44.004] <TB1> INFO: 2560 events read in total (297ms).
[14:16:44.004] <TB1> INFO: Test took 1158ms.
[14:16:44.309] <TB1> INFO: Expecting 2560 events.
[14:16:45.162] <TB1> INFO: 2560 events read in total (297ms).
[14:16:45.162] <TB1> INFO: Test took 1158ms.
[14:16:45.467] <TB1> INFO: Expecting 2560 events.
[14:16:46.319] <TB1> INFO: 2560 events read in total (296ms).
[14:16:46.320] <TB1> INFO: Test took 1157ms.
[14:16:46.624] <TB1> INFO: Expecting 2560 events.
[14:16:47.477] <TB1> INFO: 2560 events read in total (296ms).
[14:16:47.477] <TB1> INFO: Test took 1157ms.
[14:16:47.781] <TB1> INFO: Expecting 2560 events.
[14:16:48.634] <TB1> INFO: 2560 events read in total (296ms).
[14:16:48.634] <TB1> INFO: Test took 1157ms.
[14:16:48.939] <TB1> INFO: Expecting 2560 events.
[14:16:49.792] <TB1> INFO: 2560 events read in total (297ms).
[14:16:49.792] <TB1> INFO: Test took 1157ms.
[14:16:50.097] <TB1> INFO: Expecting 2560 events.
[14:16:50.950] <TB1> INFO: 2560 events read in total (297ms).
[14:16:50.950] <TB1> INFO: Test took 1157ms.
[14:16:51.255] <TB1> INFO: Expecting 2560 events.
[14:16:52.108] <TB1> INFO: 2560 events read in total (297ms).
[14:16:52.108] <TB1> INFO: Test took 1157ms.
[14:16:52.413] <TB1> INFO: Expecting 2560 events.
[14:16:53.268] <TB1> INFO: 2560 events read in total (299ms).
[14:16:53.269] <TB1> INFO: Test took 1160ms.
[14:16:53.573] <TB1> INFO: Expecting 2560 events.
[14:16:54.426] <TB1> INFO: 2560 events read in total (296ms).
[14:16:54.426] <TB1> INFO: Test took 1157ms.
[14:16:54.731] <TB1> INFO: Expecting 2560 events.
[14:16:55.584] <TB1> INFO: 2560 events read in total (297ms).
[14:16:55.585] <TB1> INFO: Test took 1158ms.
[14:16:55.889] <TB1> INFO: Expecting 2560 events.
[14:16:56.742] <TB1> INFO: 2560 events read in total (297ms).
[14:16:56.742] <TB1> INFO: Test took 1157ms.
[14:16:56.745] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:16:57.048] <TB1> INFO: Expecting 655360 events.
[14:17:13.945] <TB1> INFO: 655360 events read in total (16341ms).
[14:17:13.954] <TB1> INFO: Expecting 655360 events.
[14:17:30.567] <TB1> INFO: 655360 events read in total (16211ms).
[14:17:30.579] <TB1> INFO: Expecting 655360 events.
[14:17:47.153] <TB1> INFO: 655360 events read in total (16172ms).
[14:17:47.175] <TB1> INFO: Expecting 655360 events.
[14:18:03.975] <TB1> INFO: 655360 events read in total (16398ms).
[14:18:03.994] <TB1> INFO: Expecting 655360 events.
[14:18:20.885] <TB1> INFO: 655360 events read in total (16489ms).
[14:18:20.910] <TB1> INFO: Expecting 655360 events.
[14:18:37.769] <TB1> INFO: 655360 events read in total (16458ms).
[14:18:37.795] <TB1> INFO: Expecting 655360 events.
[14:18:54.561] <TB1> INFO: 655360 events read in total (16364ms).
[14:18:54.594] <TB1> INFO: Expecting 655360 events.
[14:19:11.545] <TB1> INFO: 655360 events read in total (16549ms).
[14:19:11.582] <TB1> INFO: Expecting 655360 events.
[14:19:28.492] <TB1> INFO: 655360 events read in total (16508ms).
[14:19:28.533] <TB1> INFO: Expecting 655360 events.
[14:19:45.440] <TB1> INFO: 655360 events read in total (16506ms).
[14:19:45.484] <TB1> INFO: Expecting 655360 events.
[14:20:02.470] <TB1> INFO: 655360 events read in total (16584ms).
[14:20:02.517] <TB1> INFO: Expecting 655360 events.
[14:20:19.281] <TB1> INFO: 655360 events read in total (16363ms).
[14:20:19.328] <TB1> INFO: Expecting 655360 events.
[14:20:36.162] <TB1> INFO: 655360 events read in total (16432ms).
[14:20:36.214] <TB1> INFO: Expecting 655360 events.
[14:20:53.080] <TB1> INFO: 655360 events read in total (16464ms).
[14:20:53.134] <TB1> INFO: Expecting 655360 events.
[14:21:10.016] <TB1> INFO: 655360 events read in total (16480ms).
[14:21:10.074] <TB1> INFO: Expecting 655360 events.
[14:21:26.821] <TB1> INFO: 655360 events read in total (16345ms).
[14:21:26.886] <TB1> INFO: Test took 270141ms.
[14:21:26.968] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:21:27.197] <TB1> INFO: Expecting 655360 events.
[14:21:44.058] <TB1> INFO: 655360 events read in total (16305ms).
[14:21:44.067] <TB1> INFO: Expecting 655360 events.
[14:22:00.856] <TB1> INFO: 655360 events read in total (16388ms).
[14:22:00.871] <TB1> INFO: Expecting 655360 events.
[14:22:17.481] <TB1> INFO: 655360 events read in total (16208ms).
[14:22:17.499] <TB1> INFO: Expecting 655360 events.
[14:22:34.424] <TB1> INFO: 655360 events read in total (16523ms).
[14:22:34.447] <TB1> INFO: Expecting 655360 events.
[14:22:51.080] <TB1> INFO: 655360 events read in total (16231ms).
[14:22:51.106] <TB1> INFO: Expecting 655360 events.
[14:23:07.898] <TB1> INFO: 655360 events read in total (16390ms).
[14:23:07.926] <TB1> INFO: Expecting 655360 events.
[14:23:24.755] <TB1> INFO: 655360 events read in total (16427ms).
[14:23:24.788] <TB1> INFO: Expecting 655360 events.
[14:23:41.457] <TB1> INFO: 655360 events read in total (16267ms).
[14:23:41.496] <TB1> INFO: Expecting 655360 events.
[14:23:57.922] <TB1> INFO: 655360 events read in total (16025ms).
[14:23:57.964] <TB1> INFO: Expecting 655360 events.
[14:24:14.793] <TB1> INFO: 655360 events read in total (16427ms).
[14:24:14.834] <TB1> INFO: Expecting 655360 events.
[14:24:31.615] <TB1> INFO: 655360 events read in total (16379ms).
[14:24:31.670] <TB1> INFO: Expecting 655360 events.
[14:24:48.373] <TB1> INFO: 655360 events read in total (16301ms).
[14:24:48.423] <TB1> INFO: Expecting 655360 events.
[14:25:05.132] <TB1> INFO: 655360 events read in total (16307ms).
[14:25:05.192] <TB1> INFO: Expecting 655360 events.
[14:25:21.962] <TB1> INFO: 655360 events read in total (16369ms).
[14:25:22.020] <TB1> INFO: Expecting 655360 events.
[14:25:39.018] <TB1> INFO: 655360 events read in total (16596ms).
[14:25:39.092] <TB1> INFO: Expecting 655360 events.
[14:25:55.960] <TB1> INFO: 655360 events read in total (16466ms).
[14:25:56.065] <TB1> INFO: Test took 269097ms.
[14:25:56.281] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.288] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.295] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.302] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:25:56.310] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[14:25:56.318] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.326] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:25:56.333] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.340] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.347] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.355] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.362] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.369] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.380] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.387] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.395] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.402] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.409] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.417] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:25:56.450] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C0.dat
[14:25:56.450] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C1.dat
[14:25:56.450] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C2.dat
[14:25:56.451] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C3.dat
[14:25:56.451] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C4.dat
[14:25:56.451] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C5.dat
[14:25:56.451] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C6.dat
[14:25:56.451] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C7.dat
[14:25:56.451] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C8.dat
[14:25:56.451] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C9.dat
[14:25:56.451] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C10.dat
[14:25:56.452] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C11.dat
[14:25:56.452] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C12.dat
[14:25:56.452] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C13.dat
[14:25:56.452] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C14.dat
[14:25:56.452] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//dacParameters35_C15.dat
[14:25:56.742] <TB1> INFO: Expecting 41600 events.
[14:26:00.215] <TB1> INFO: 41600 events read in total (2917ms).
[14:26:00.216] <TB1> INFO: Test took 3761ms.
[14:26:00.692] <TB1> INFO: Expecting 41600 events.
[14:26:04.220] <TB1> INFO: 41600 events read in total (2971ms).
[14:26:04.221] <TB1> INFO: Test took 3827ms.
[14:26:04.746] <TB1> INFO: Expecting 41600 events.
[14:26:08.281] <TB1> INFO: 41600 events read in total (2979ms).
[14:26:08.281] <TB1> INFO: Test took 3879ms.
[14:26:08.472] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:08.560] <TB1> INFO: Expecting 2560 events.
[14:26:09.417] <TB1> INFO: 2560 events read in total (301ms).
[14:26:09.417] <TB1> INFO: Test took 945ms.
[14:26:09.418] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:09.723] <TB1> INFO: Expecting 2560 events.
[14:26:10.575] <TB1> INFO: 2560 events read in total (296ms).
[14:26:10.575] <TB1> INFO: Test took 1157ms.
[14:26:10.577] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:10.881] <TB1> INFO: Expecting 2560 events.
[14:26:11.735] <TB1> INFO: 2560 events read in total (298ms).
[14:26:11.735] <TB1> INFO: Test took 1158ms.
[14:26:11.737] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:12.040] <TB1> INFO: Expecting 2560 events.
[14:26:12.897] <TB1> INFO: 2560 events read in total (301ms).
[14:26:12.897] <TB1> INFO: Test took 1160ms.
[14:26:12.899] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:13.203] <TB1> INFO: Expecting 2560 events.
[14:26:14.061] <TB1> INFO: 2560 events read in total (302ms).
[14:26:14.061] <TB1> INFO: Test took 1162ms.
[14:26:14.063] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:14.367] <TB1> INFO: Expecting 2560 events.
[14:26:15.225] <TB1> INFO: 2560 events read in total (302ms).
[14:26:15.225] <TB1> INFO: Test took 1162ms.
[14:26:15.227] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:15.530] <TB1> INFO: Expecting 2560 events.
[14:26:16.385] <TB1> INFO: 2560 events read in total (298ms).
[14:26:16.385] <TB1> INFO: Test took 1158ms.
[14:26:16.387] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:16.690] <TB1> INFO: Expecting 2560 events.
[14:26:17.544] <TB1> INFO: 2560 events read in total (297ms).
[14:26:17.544] <TB1> INFO: Test took 1157ms.
[14:26:17.547] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:17.850] <TB1> INFO: Expecting 2560 events.
[14:26:18.707] <TB1> INFO: 2560 events read in total (301ms).
[14:26:18.708] <TB1> INFO: Test took 1161ms.
[14:26:18.709] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:19.013] <TB1> INFO: Expecting 2560 events.
[14:26:19.871] <TB1> INFO: 2560 events read in total (301ms).
[14:26:19.872] <TB1> INFO: Test took 1163ms.
[14:26:19.874] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:20.179] <TB1> INFO: Expecting 2560 events.
[14:26:21.034] <TB1> INFO: 2560 events read in total (299ms).
[14:26:21.034] <TB1> INFO: Test took 1160ms.
[14:26:21.036] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:21.339] <TB1> INFO: Expecting 2560 events.
[14:26:22.192] <TB1> INFO: 2560 events read in total (297ms).
[14:26:22.193] <TB1> INFO: Test took 1157ms.
[14:26:22.195] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:22.498] <TB1> INFO: Expecting 2560 events.
[14:26:23.352] <TB1> INFO: 2560 events read in total (298ms).
[14:26:23.353] <TB1> INFO: Test took 1158ms.
[14:26:23.354] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:23.659] <TB1> INFO: Expecting 2560 events.
[14:26:24.512] <TB1> INFO: 2560 events read in total (297ms).
[14:26:24.512] <TB1> INFO: Test took 1158ms.
[14:26:24.515] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:24.818] <TB1> INFO: Expecting 2560 events.
[14:26:25.676] <TB1> INFO: 2560 events read in total (302ms).
[14:26:25.676] <TB1> INFO: Test took 1161ms.
[14:26:25.678] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:25.983] <TB1> INFO: Expecting 2560 events.
[14:26:26.836] <TB1> INFO: 2560 events read in total (297ms).
[14:26:26.836] <TB1> INFO: Test took 1158ms.
[14:26:26.839] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:27.142] <TB1> INFO: Expecting 2560 events.
[14:26:27.996] <TB1> INFO: 2560 events read in total (298ms).
[14:26:27.996] <TB1> INFO: Test took 1157ms.
[14:26:27.998] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:28.301] <TB1> INFO: Expecting 2560 events.
[14:26:29.155] <TB1> INFO: 2560 events read in total (298ms).
[14:26:29.155] <TB1> INFO: Test took 1157ms.
[14:26:29.157] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:29.460] <TB1> INFO: Expecting 2560 events.
[14:26:30.314] <TB1> INFO: 2560 events read in total (297ms).
[14:26:30.315] <TB1> INFO: Test took 1158ms.
[14:26:30.317] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:30.620] <TB1> INFO: Expecting 2560 events.
[14:26:31.476] <TB1> INFO: 2560 events read in total (300ms).
[14:26:31.476] <TB1> INFO: Test took 1159ms.
[14:26:31.479] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:31.781] <TB1> INFO: Expecting 2560 events.
[14:26:32.638] <TB1> INFO: 2560 events read in total (301ms).
[14:26:32.638] <TB1> INFO: Test took 1159ms.
[14:26:32.640] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:32.944] <TB1> INFO: Expecting 2560 events.
[14:26:33.798] <TB1> INFO: 2560 events read in total (298ms).
[14:26:33.798] <TB1> INFO: Test took 1158ms.
[14:26:33.800] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:34.104] <TB1> INFO: Expecting 2560 events.
[14:26:34.960] <TB1> INFO: 2560 events read in total (300ms).
[14:26:34.960] <TB1> INFO: Test took 1160ms.
[14:26:34.963] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:35.266] <TB1> INFO: Expecting 2560 events.
[14:26:36.126] <TB1> INFO: 2560 events read in total (303ms).
[14:26:36.126] <TB1> INFO: Test took 1163ms.
[14:26:36.129] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:36.432] <TB1> INFO: Expecting 2560 events.
[14:26:37.290] <TB1> INFO: 2560 events read in total (302ms).
[14:26:37.291] <TB1> INFO: Test took 1162ms.
[14:26:37.292] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:37.597] <TB1> INFO: Expecting 2560 events.
[14:26:38.450] <TB1> INFO: 2560 events read in total (297ms).
[14:26:38.450] <TB1> INFO: Test took 1158ms.
[14:26:38.452] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:38.756] <TB1> INFO: Expecting 2560 events.
[14:26:39.615] <TB1> INFO: 2560 events read in total (302ms).
[14:26:39.616] <TB1> INFO: Test took 1164ms.
[14:26:39.618] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:39.921] <TB1> INFO: Expecting 2560 events.
[14:26:40.774] <TB1> INFO: 2560 events read in total (297ms).
[14:26:40.774] <TB1> INFO: Test took 1156ms.
[14:26:40.775] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:41.080] <TB1> INFO: Expecting 2560 events.
[14:26:41.936] <TB1> INFO: 2560 events read in total (300ms).
[14:26:41.936] <TB1> INFO: Test took 1161ms.
[14:26:41.938] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:42.242] <TB1> INFO: Expecting 2560 events.
[14:26:43.098] <TB1> INFO: 2560 events read in total (300ms).
[14:26:43.098] <TB1> INFO: Test took 1160ms.
[14:26:43.099] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:43.404] <TB1> INFO: Expecting 2560 events.
[14:26:44.258] <TB1> INFO: 2560 events read in total (298ms).
[14:26:44.259] <TB1> INFO: Test took 1160ms.
[14:26:44.260] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:26:44.565] <TB1> INFO: Expecting 2560 events.
[14:26:45.419] <TB1> INFO: 2560 events read in total (298ms).
[14:26:45.419] <TB1> INFO: Test took 1159ms.
[14:26:45.884] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 620 seconds
[14:26:45.884] <TB1> INFO: PH scale (per ROC): 61 65 65 66 72 72 71 73 74 76 79 76 77 75 78 62
[14:26:45.884] <TB1> INFO: PH offset (per ROC): 192 174 189 181 160 181 181 172 181 178 175 174 169 177 173 191
[14:26:45.888] <TB1> INFO: Decoding statistics:
[14:26:45.888] <TB1> INFO: General information:
[14:26:45.888] <TB1> INFO: 16bit words read: 91924
[14:26:45.888] <TB1> INFO: valid events total: 10240
[14:26:45.888] <TB1> INFO: empty events: 7739
[14:26:45.888] <TB1> INFO: valid events with pixels: 2501
[14:26:45.888] <TB1> INFO: valid pixel hits: 2501
[14:26:45.888] <TB1> INFO: Event errors: 0
[14:26:45.888] <TB1> INFO: start marker: 0
[14:26:45.888] <TB1> INFO: stop marker: 0
[14:26:45.888] <TB1> INFO: overflow: 0
[14:26:45.888] <TB1> INFO: invalid 5bit words: 0
[14:26:45.888] <TB1> INFO: invalid XOR eye diagram: 0
[14:26:45.888] <TB1> INFO: frame (failed synchr.): 0
[14:26:45.888] <TB1> INFO: idle data (no TBM trl): 0
[14:26:45.888] <TB1> INFO: no data (only TBM hdr): 0
[14:26:45.888] <TB1> INFO: TBM errors: 0
[14:26:45.888] <TB1> INFO: flawed TBM headers: 0
[14:26:45.888] <TB1> INFO: flawed TBM trailers: 0
[14:26:45.888] <TB1> INFO: event ID mismatches: 0
[14:26:45.888] <TB1> INFO: ROC errors: 0
[14:26:45.888] <TB1> INFO: missing ROC header(s): 0
[14:26:45.888] <TB1> INFO: misplaced readback start: 0
[14:26:45.888] <TB1> INFO: Pixel decoding errors: 0
[14:26:45.888] <TB1> INFO: pixel data incomplete: 0
[14:26:45.888] <TB1> INFO: pixel address: 0
[14:26:45.888] <TB1> INFO: pulse height fill bit: 0
[14:26:45.888] <TB1> INFO: buffer corruption: 0
[14:26:46.068] <TB1> INFO: ######################################################################
[14:26:46.068] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:26:46.068] <TB1> INFO: ######################################################################
[14:26:46.078] <TB1> INFO: scanning low vcal = 10
[14:26:46.337] <TB1> INFO: Expecting 41600 events.
[14:26:49.886] <TB1> INFO: 41600 events read in total (2992ms).
[14:26:49.886] <TB1> INFO: Test took 3808ms.
[14:26:49.887] <TB1> INFO: scanning low vcal = 20
[14:26:50.192] <TB1> INFO: Expecting 41600 events.
[14:26:53.756] <TB1> INFO: 41600 events read in total (3008ms).
[14:26:53.757] <TB1> INFO: Test took 3869ms.
[14:26:53.758] <TB1> INFO: scanning low vcal = 30
[14:26:54.061] <TB1> INFO: Expecting 41600 events.
[14:26:57.617] <TB1> INFO: 41600 events read in total (3000ms).
[14:26:57.617] <TB1> INFO: Test took 3858ms.
[14:26:57.619] <TB1> INFO: scanning low vcal = 40
[14:26:57.917] <TB1> INFO: Expecting 41600 events.
[14:27:02.141] <TB1> INFO: 41600 events read in total (3668ms).
[14:27:02.142] <TB1> INFO: Test took 4523ms.
[14:27:02.145] <TB1> INFO: scanning low vcal = 50
[14:27:02.410] <TB1> INFO: Expecting 41600 events.
[14:27:06.652] <TB1> INFO: 41600 events read in total (3686ms).
[14:27:06.652] <TB1> INFO: Test took 4507ms.
[14:27:06.655] <TB1> INFO: scanning low vcal = 60
[14:27:06.947] <TB1> INFO: Expecting 41600 events.
[14:27:11.153] <TB1> INFO: 41600 events read in total (3650ms).
[14:27:11.154] <TB1> INFO: Test took 4499ms.
[14:27:11.156] <TB1> INFO: scanning low vcal = 70
[14:27:11.422] <TB1> INFO: Expecting 41600 events.
[14:27:15.636] <TB1> INFO: 41600 events read in total (3658ms).
[14:27:15.637] <TB1> INFO: Test took 4480ms.
[14:27:15.639] <TB1> INFO: scanning low vcal = 80
[14:27:15.907] <TB1> INFO: Expecting 41600 events.
[14:27:20.094] <TB1> INFO: 41600 events read in total (3631ms).
[14:27:20.094] <TB1> INFO: Test took 4454ms.
[14:27:20.097] <TB1> INFO: scanning low vcal = 90
[14:27:20.359] <TB1> INFO: Expecting 41600 events.
[14:27:24.544] <TB1> INFO: 41600 events read in total (3628ms).
[14:27:24.545] <TB1> INFO: Test took 4448ms.
[14:27:24.547] <TB1> INFO: scanning low vcal = 100
[14:27:24.818] <TB1> INFO: Expecting 41600 events.
[14:27:29.069] <TB1> INFO: 41600 events read in total (3694ms).
[14:27:29.070] <TB1> INFO: Test took 4522ms.
[14:27:29.072] <TB1> INFO: scanning low vcal = 110
[14:27:29.459] <TB1> INFO: Expecting 41600 events.
[14:27:33.709] <TB1> INFO: 41600 events read in total (3693ms).
[14:27:33.709] <TB1> INFO: Test took 4637ms.
[14:27:33.712] <TB1> INFO: scanning low vcal = 120
[14:27:33.979] <TB1> INFO: Expecting 41600 events.
[14:27:38.210] <TB1> INFO: 41600 events read in total (3674ms).
[14:27:38.211] <TB1> INFO: Test took 4499ms.
[14:27:38.213] <TB1> INFO: scanning low vcal = 130
[14:27:38.476] <TB1> INFO: Expecting 41600 events.
[14:27:42.745] <TB1> INFO: 41600 events read in total (3713ms).
[14:27:42.745] <TB1> INFO: Test took 4532ms.
[14:27:42.748] <TB1> INFO: scanning low vcal = 140
[14:27:43.016] <TB1> INFO: Expecting 41600 events.
[14:27:47.227] <TB1> INFO: 41600 events read in total (3655ms).
[14:27:47.228] <TB1> INFO: Test took 4480ms.
[14:27:47.230] <TB1> INFO: scanning low vcal = 150
[14:27:47.491] <TB1> INFO: Expecting 41600 events.
[14:27:51.715] <TB1> INFO: 41600 events read in total (3668ms).
[14:27:51.715] <TB1> INFO: Test took 4485ms.
[14:27:51.718] <TB1> INFO: scanning low vcal = 160
[14:27:51.980] <TB1> INFO: Expecting 41600 events.
[14:27:56.233] <TB1> INFO: 41600 events read in total (3697ms).
[14:27:56.234] <TB1> INFO: Test took 4516ms.
[14:27:56.236] <TB1> INFO: scanning low vcal = 170
[14:27:56.501] <TB1> INFO: Expecting 41600 events.
[14:28:00.711] <TB1> INFO: 41600 events read in total (3654ms).
[14:28:00.712] <TB1> INFO: Test took 4476ms.
[14:28:00.715] <TB1> INFO: scanning low vcal = 180
[14:28:00.978] <TB1> INFO: Expecting 41600 events.
[14:28:05.209] <TB1> INFO: 41600 events read in total (3675ms).
[14:28:05.210] <TB1> INFO: Test took 4495ms.
[14:28:05.212] <TB1> INFO: scanning low vcal = 190
[14:28:05.475] <TB1> INFO: Expecting 41600 events.
[14:28:09.704] <TB1> INFO: 41600 events read in total (3673ms).
[14:28:09.705] <TB1> INFO: Test took 4493ms.
[14:28:09.707] <TB1> INFO: scanning low vcal = 200
[14:28:09.978] <TB1> INFO: Expecting 41600 events.
[14:28:14.198] <TB1> INFO: 41600 events read in total (3664ms).
[14:28:14.198] <TB1> INFO: Test took 4491ms.
[14:28:14.201] <TB1> INFO: scanning low vcal = 210
[14:28:14.462] <TB1> INFO: Expecting 41600 events.
[14:28:18.683] <TB1> INFO: 41600 events read in total (3665ms).
[14:28:18.683] <TB1> INFO: Test took 4482ms.
[14:28:18.686] <TB1> INFO: scanning low vcal = 220
[14:28:18.958] <TB1> INFO: Expecting 41600 events.
[14:28:23.151] <TB1> INFO: 41600 events read in total (3637ms).
[14:28:23.151] <TB1> INFO: Test took 4465ms.
[14:28:23.154] <TB1> INFO: scanning low vcal = 230
[14:28:23.426] <TB1> INFO: Expecting 41600 events.
[14:28:27.622] <TB1> INFO: 41600 events read in total (3639ms).
[14:28:27.622] <TB1> INFO: Test took 4468ms.
[14:28:27.624] <TB1> INFO: scanning low vcal = 240
[14:28:27.888] <TB1> INFO: Expecting 41600 events.
[14:28:32.130] <TB1> INFO: 41600 events read in total (3686ms).
[14:28:32.131] <TB1> INFO: Test took 4507ms.
[14:28:32.133] <TB1> INFO: scanning low vcal = 250
[14:28:32.390] <TB1> INFO: Expecting 41600 events.
[14:28:36.602] <TB1> INFO: 41600 events read in total (3656ms).
[14:28:36.602] <TB1> INFO: Test took 4469ms.
[14:28:36.606] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[14:28:36.880] <TB1> INFO: Expecting 41600 events.
[14:28:41.142] <TB1> INFO: 41600 events read in total (3706ms).
[14:28:41.142] <TB1> INFO: Test took 4536ms.
[14:28:41.145] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[14:28:41.420] <TB1> INFO: Expecting 41600 events.
[14:28:45.631] <TB1> INFO: 41600 events read in total (3655ms).
[14:28:45.631] <TB1> INFO: Test took 4486ms.
[14:28:45.634] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[14:28:45.898] <TB1> INFO: Expecting 41600 events.
[14:28:50.150] <TB1> INFO: 41600 events read in total (3696ms).
[14:28:50.151] <TB1> INFO: Test took 4517ms.
[14:28:50.153] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[14:28:50.429] <TB1> INFO: Expecting 41600 events.
[14:28:54.635] <TB1> INFO: 41600 events read in total (3650ms).
[14:28:54.635] <TB1> INFO: Test took 4481ms.
[14:28:54.637] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:28:54.900] <TB1> INFO: Expecting 41600 events.
[14:28:59.109] <TB1> INFO: 41600 events read in total (3653ms).
[14:28:59.109] <TB1> INFO: Test took 4472ms.
[14:28:59.597] <TB1> INFO: PixTestGainPedestal::measure() done
[14:29:33.144] <TB1> INFO: PixTestGainPedestal::fit() done
[14:29:33.144] <TB1> INFO: non-linearity mean: 0.956 0.963 0.957 0.956 0.948 0.961 0.946 0.952 0.957 0.958 0.957 0.955 0.961 0.957 0.953 0.955
[14:29:33.144] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.007 0.006 0.007 0.007 0.006 0.006 0.005 0.006 0.006 0.005 0.006 0.009
[14:29:33.144] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[14:29:33.167] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[14:29:33.195] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[14:29:33.218] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[14:29:33.237] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[14:29:33.255] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[14:29:33.274] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[14:29:33.292] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[14:29:33.311] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[14:29:33.330] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[14:29:33.348] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[14:29:33.367] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[14:29:33.385] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[14:29:33.404] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[14:29:33.422] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[14:29:33.441] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[14:29:33.459] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[14:29:33.459] <TB1> INFO: Decoding statistics:
[14:29:33.459] <TB1> INFO: General information:
[14:29:33.459] <TB1> INFO: 16bit words read: 2662400
[14:29:33.459] <TB1> INFO: valid events total: 166400
[14:29:33.459] <TB1> INFO: empty events: 0
[14:29:33.459] <TB1> INFO: valid events with pixels: 166400
[14:29:33.459] <TB1> INFO: valid pixel hits: 665590
[14:29:33.459] <TB1> INFO: Event errors: 0
[14:29:33.459] <TB1> INFO: start marker: 0
[14:29:33.459] <TB1> INFO: stop marker: 0
[14:29:33.459] <TB1> INFO: overflow: 0
[14:29:33.459] <TB1> INFO: invalid 5bit words: 0
[14:29:33.459] <TB1> INFO: invalid XOR eye diagram: 0
[14:29:33.459] <TB1> INFO: frame (failed synchr.): 0
[14:29:33.459] <TB1> INFO: idle data (no TBM trl): 0
[14:29:33.459] <TB1> INFO: no data (only TBM hdr): 0
[14:29:33.459] <TB1> INFO: TBM errors: 0
[14:29:33.459] <TB1> INFO: flawed TBM headers: 0
[14:29:33.459] <TB1> INFO: flawed TBM trailers: 0
[14:29:33.459] <TB1> INFO: event ID mismatches: 0
[14:29:33.459] <TB1> INFO: ROC errors: 0
[14:29:33.459] <TB1> INFO: missing ROC header(s): 0
[14:29:33.459] <TB1> INFO: misplaced readback start: 0
[14:29:33.459] <TB1> INFO: Pixel decoding errors: 0
[14:29:33.459] <TB1> INFO: pixel data incomplete: 0
[14:29:33.459] <TB1> INFO: pixel address: 0
[14:29:33.459] <TB1> INFO: pulse height fill bit: 0
[14:29:33.459] <TB1> INFO: buffer corruption: 0
[14:29:33.467] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C15.dat
[14:29:33.468] <TB1> INFO: ######################################################################
[14:29:33.468] <TB1> INFO: PixTestReadback::doTest()
[14:29:33.468] <TB1> INFO: ######################################################################
[14:29:33.468] <TB1> INFO: ----------------------------------------------------------------------
[14:29:33.468] <TB1> INFO: PixTestReadback::CalibrateVd()
[14:29:33.468] <TB1> INFO: ----------------------------------------------------------------------
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C0.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C1.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C2.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C3.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C4.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C5.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C6.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C7.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C8.dat
[14:29:42.930] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C9.dat
[14:29:42.931] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C10.dat
[14:29:42.931] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C11.dat
[14:29:42.931] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C12.dat
[14:29:42.931] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C13.dat
[14:29:42.931] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C14.dat
[14:29:42.931] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C15.dat
[14:29:42.955] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:29:42.955] <TB1> INFO: ----------------------------------------------------------------------
[14:29:42.955] <TB1> INFO: PixTestReadback::CalibrateVa()
[14:29:42.955] <TB1> INFO: ----------------------------------------------------------------------
[14:29:52.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C0.dat
[14:29:52.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C1.dat
[14:29:52.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C2.dat
[14:29:52.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C3.dat
[14:29:52.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C4.dat
[14:29:52.989] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C5.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C6.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C7.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C8.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C9.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C10.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C11.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C12.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C13.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C14.dat
[14:29:52.990] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C15.dat
[14:29:53.015] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:29:53.015] <TB1> INFO: ----------------------------------------------------------------------
[14:29:53.015] <TB1> INFO: PixTestReadback::readbackVbg()
[14:29:53.015] <TB1> INFO: ----------------------------------------------------------------------
[14:30:00.300] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:30:00.300] <TB1> INFO: ----------------------------------------------------------------------
[14:30:00.300] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[14:30:00.300] <TB1> INFO: ----------------------------------------------------------------------
[14:30:00.300] <TB1> INFO: Vbg will be calibrated using Vd calibration
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 149.4calibrated Vbg = 1.19789 :::*/*/*/*/
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.7calibrated Vbg = 1.19727 :::*/*/*/*/
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 165.5calibrated Vbg = 1.19563 :::*/*/*/*/
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 147.3calibrated Vbg = 1.21252 :::*/*/*/*/
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 147.8calibrated Vbg = 1.2106 :::*/*/*/*/
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.3calibrated Vbg = 1.21518 :::*/*/*/*/
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.7calibrated Vbg = 1.20992 :::*/*/*/*/
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 149.4calibrated Vbg = 1.20937 :::*/*/*/*/
[14:30:00.300] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 161.3calibrated Vbg = 1.21234 :::*/*/*/*/
[14:30:00.301] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153calibrated Vbg = 1.21092 :::*/*/*/*/
[14:30:00.301] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 152.3calibrated Vbg = 1.20946 :::*/*/*/*/
[14:30:00.301] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159.9calibrated Vbg = 1.20326 :::*/*/*/*/
[14:30:00.301] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 151.8calibrated Vbg = 1.19585 :::*/*/*/*/
[14:30:00.301] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 145.6calibrated Vbg = 1.19902 :::*/*/*/*/
[14:30:00.301] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 144.8calibrated Vbg = 1.20241 :::*/*/*/*/
[14:30:00.301] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 164calibrated Vbg = 1.20362 :::*/*/*/*/
[14:30:00.302] <TB1> INFO: ----------------------------------------------------------------------
[14:30:00.302] <TB1> INFO: PixTestReadback::CalibrateIa()
[14:30:00.302] <TB1> INFO: ----------------------------------------------------------------------
[14:32:35.521] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C0.dat
[14:32:35.521] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C1.dat
[14:32:35.521] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C2.dat
[14:32:35.521] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C3.dat
[14:32:35.521] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C4.dat
[14:32:35.521] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C5.dat
[14:32:35.521] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C6.dat
[14:32:35.521] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C7.dat
[14:32:35.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C8.dat
[14:32:35.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C9.dat
[14:32:35.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C10.dat
[14:32:35.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C11.dat
[14:32:35.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C12.dat
[14:32:35.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C13.dat
[14:32:35.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C14.dat
[14:32:35.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2207_FullQualification_2016-04-18_08h30m_1460961049//004_FulltestPxar_p17//readbackCal_C15.dat
[14:32:35.552] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:32:35.555] <TB1> INFO: PixTestReadback::doTest() done
[14:32:35.555] <TB1> INFO: Decoding statistics:
[14:32:35.555] <TB1> INFO: General information:
[14:32:35.555] <TB1> INFO: 16bit words read: 1024
[14:32:35.555] <TB1> INFO: valid events total: 128
[14:32:35.555] <TB1> INFO: empty events: 128
[14:32:35.555] <TB1> INFO: valid events with pixels: 0
[14:32:35.555] <TB1> INFO: valid pixel hits: 0
[14:32:35.555] <TB1> INFO: Event errors: 0
[14:32:35.555] <TB1> INFO: start marker: 0
[14:32:35.555] <TB1> INFO: stop marker: 0
[14:32:35.555] <TB1> INFO: overflow: 0
[14:32:35.555] <TB1> INFO: invalid 5bit words: 0
[14:32:35.555] <TB1> INFO: invalid XOR eye diagram: 0
[14:32:35.555] <TB1> INFO: frame (failed synchr.): 0
[14:32:35.555] <TB1> INFO: idle data (no TBM trl): 0
[14:32:35.555] <TB1> INFO: no data (only TBM hdr): 0
[14:32:35.555] <TB1> INFO: TBM errors: 0
[14:32:35.555] <TB1> INFO: flawed TBM headers: 0
[14:32:35.555] <TB1> INFO: flawed TBM trailers: 0
[14:32:35.555] <TB1> INFO: event ID mismatches: 0
[14:32:35.555] <TB1> INFO: ROC errors: 0
[14:32:35.555] <TB1> INFO: missing ROC header(s): 0
[14:32:35.555] <TB1> INFO: misplaced readback start: 0
[14:32:35.555] <TB1> INFO: Pixel decoding errors: 0
[14:32:35.555] <TB1> INFO: pixel data incomplete: 0
[14:32:35.555] <TB1> INFO: pixel address: 0
[14:32:35.555] <TB1> INFO: pulse height fill bit: 0
[14:32:35.555] <TB1> INFO: buffer corruption: 0
[14:32:35.581] <TB1> INFO: Decoding statistics:
[14:32:35.581] <TB1> INFO: General information:
[14:32:35.581] <TB1> INFO: 16bit words read: 2755348
[14:32:35.581] <TB1> INFO: valid events total: 176768
[14:32:35.581] <TB1> INFO: empty events: 7867
[14:32:35.581] <TB1> INFO: valid events with pixels: 168901
[14:32:35.581] <TB1> INFO: valid pixel hits: 668091
[14:32:35.581] <TB1> INFO: Event errors: 0
[14:32:35.581] <TB1> INFO: start marker: 0
[14:32:35.581] <TB1> INFO: stop marker: 0
[14:32:35.581] <TB1> INFO: overflow: 0
[14:32:35.581] <TB1> INFO: invalid 5bit words: 0
[14:32:35.581] <TB1> INFO: invalid XOR eye diagram: 0
[14:32:35.581] <TB1> INFO: frame (failed synchr.): 0
[14:32:35.581] <TB1> INFO: idle data (no TBM trl): 0
[14:32:35.581] <TB1> INFO: no data (only TBM hdr): 0
[14:32:35.581] <TB1> INFO: TBM errors: 0
[14:32:35.581] <TB1> INFO: flawed TBM headers: 0
[14:32:35.581] <TB1> INFO: flawed TBM trailers: 0
[14:32:35.581] <TB1> INFO: event ID mismatches: 0
[14:32:35.581] <TB1> INFO: ROC errors: 0
[14:32:35.581] <TB1> INFO: missing ROC header(s): 0
[14:32:35.581] <TB1> INFO: misplaced readback start: 0
[14:32:35.581] <TB1> INFO: Pixel decoding errors: 0
[14:32:35.581] <TB1> INFO: pixel data incomplete: 0
[14:32:35.581] <TB1> INFO: pixel address: 0
[14:32:35.581] <TB1> INFO: pulse height fill bit: 0
[14:32:35.581] <TB1> INFO: buffer corruption: 0
[14:32:35.581] <TB1> INFO: enter test to run
[14:32:35.581] <TB1> INFO: test: exit no parameter change
[14:32:35.694] <TB1> QUIET: Connection to board 153 closed.
[14:32:35.774] <TB1> INFO: pXar: this is the end, my friend