[10:17:37.283] <TB1> INFO: *** Welcome to pxar ***
[10:17:37.283] <TB1> INFO: *** Today: 2016/07/18
[10:17:37.372] <TB1> INFO: *** Version: 9751-dirty
[10:17:37.372] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C15.dat
[10:17:37.374] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//tbmParameters_C0b.dat
[10:17:37.374] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//defaultMaskFile.dat
[10:17:37.374] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters_C15.dat
[10:17:37.447] <TB1> INFO: clk: 4
[10:17:37.447] <TB1> INFO: ctr: 4
[10:17:37.447] <TB1> INFO: sda: 19
[10:17:37.447] <TB1> INFO: tin: 9
[10:17:37.447] <TB1> INFO: level: 15
[10:17:37.447] <TB1> INFO: triggerdelay: 0
[10:17:37.447] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:17:37.447] <TB1> INFO: Log level: INFO
[10:17:37.455] <TB1> INFO: Found DTB DTB_WXBYFL
[10:17:37.465] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[10:17:37.467] <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:
------------------------------------------------------
[10:17:37.469] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[10:17:39.006] <TB1> INFO: DUT info:
[10:17:39.006] <TB1> INFO: The DUT currently contains the following objects:
[10:17:39.006] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:17:39.007] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:17:39.007] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:17:39.007] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:17:39.007] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.007] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:39.408] <TB1> INFO: enter 'restricted' command line mode
[10:17:39.408] <TB1> INFO: enter test to run
[10:17:39.408] <TB1> INFO: test: pretest no parameter change
[10:17:39.408] <TB1> INFO: running: pretest
[10:17:39.415] <TB1> INFO: ----------------------------------------------------------------------
[10:17:39.415] <TB1> INFO: PixTestPretest::programROC()
[10:17:39.415] <TB1> INFO: ----------------------------------------------------------------------
[10:17:57.430] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:17:57.430] <TB1> INFO: IA differences per ROC: 18.5 19.3 17.7 18.5 19.3 17.7 20.1 19.3 18.5 19.3 17.7 18.5 18.5 18.5 19.3 18.5
[10:17:57.517] <TB1> INFO: enter test to run
[10:17:57.517] <TB1> INFO: test: pretest no parameter change
[10:17:57.517] <TB1> INFO: running: pretest
[10:17:57.519] <TB1> INFO: ----------------------------------------------------------------------
[10:17:57.519] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:17:57.519] <TB1> INFO: ----------------------------------------------------------------------
[10:18:03.808] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[10:18:03.808] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 19.3 19.3 20.1 20.1 19.3 19.3 19.3 20.1 20.1 20.1 19.3 19.3 19.3
[10:18:03.841] <TB1> INFO: enter test to run
[10:18:03.841] <TB1> INFO: test: pretest no parameter change
[10:18:03.841] <TB1> INFO: running: pretest
[10:18:03.841] <TB1> INFO: ----------------------------------------------------------------------
[10:18:03.841] <TB1> INFO: PixTestPretest::findTiming()
[10:18:03.841] <TB1> INFO: ----------------------------------------------------------------------
[10:18:03.841] <TB1> INFO: PixTestCmd::init()

[10:19:31.911] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:19:31.911] <TB1> INFO: (success/tries = 100/100), width = 5
[10:19:31.912] <TB1> INFO: enter test to run
[10:19:31.912] <TB1> INFO: test: pretest no parameter change
[10:19:31.912] <TB1> INFO: running: pretest
[10:19:31.914] <TB1> INFO: ----------------------------------------------------------------------
[10:19:31.914] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:19:31.914] <TB1> INFO: ----------------------------------------------------------------------
[10:19:32.006] <TB1> INFO: Expecting 231680 events.


[10:19:42.312] <TB1> INFO: 231680 events read in total (9750ms).
[10:19:42.316] <TB1> INFO: Test took 10399ms.
[10:19:42.534] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:19:42.601] <TB1> INFO: enter test to run
[10:19:42.601] <TB1> INFO: test: pretest no parameter change
[10:19:42.601] <TB1> INFO: running: pretest
[10:19:42.602] <TB1> INFO: ----------------------------------------------------------------------
[10:19:42.602] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:19:42.602] <TB1> INFO: ----------------------------------------------------------------------
[10:19:42.694] <TB1> INFO: Expecting 231680 events.
[10:19:52.997] <TB1> INFO: 231680 events read in total (9747ms).
[10:19:53.001] <TB1> INFO: Test took 10396ms.
[10:19:53.246] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:19:53.246] <TB1> INFO: CalDel: 132 143 165 135 139 138 172 156 134 129 139 144 148 143 150 144
[10:19:53.246] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:19:53.286] <TB1> INFO: enter test to run
[10:19:53.286] <TB1> INFO: test: pretest no parameter change
[10:19:53.286] <TB1> INFO: running: pretest
[10:19:53.286] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C0.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C1.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C2.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C3.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C4.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C5.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C6.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C7.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C8.dat
[10:19:53.287] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C9.dat
[10:19:53.288] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C10.dat
[10:19:53.288] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C11.dat
[10:19:53.288] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C12.dat
[10:19:53.288] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C13.dat
[10:19:53.288] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C14.dat
[10:19:53.288] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C15.dat
[10:19:53.288] <TB1> INFO: enter test to run
[10:19:53.288] <TB1> INFO: test: fulltest no parameter change
[10:19:53.288] <TB1> INFO: running: fulltest
[10:19:53.288] <TB1> INFO: ######################################################################
[10:19:53.288] <TB1> INFO: PixTestFullTest::doTest()
[10:19:53.288] <TB1> INFO: ######################################################################
[10:19:53.289] <TB1> INFO: ######################################################################
[10:19:53.289] <TB1> INFO: PixTestAlive::doTest()
[10:19:53.289] <TB1> INFO: ######################################################################
[10:19:53.291] <TB1> INFO: ----------------------------------------------------------------------
[10:19:53.291] <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)
[10:19:53.291] <TB1> INFO: ----------------------------------------------------------------------
[10:19:53.555] <TB1> INFO: Expecting 41600 events.
[10:19:57.300] <TB1> INFO: 41600 events read in total (3189ms).
[10:19:57.301] <TB1> INFO: Test took 4009ms.
[10:19:57.526] <TB1> INFO: PixTestAlive::aliveTest() done
[10:19:57.526] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 1
[10:19:57.528] <TB1> INFO: ----------------------------------------------------------------------
[10:19:57.528] <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)
[10:19:57.528] <TB1> INFO: ----------------------------------------------------------------------
[10:19:57.792] <TB1> INFO: Expecting 41600 events.
[10:20:00.775] <TB1> INFO: 41600 events read in total (2426ms).
[10:20:00.775] <TB1> INFO: Test took 3246ms.
[10:20:00.776] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:20:01.018] <TB1> INFO: PixTestAlive::maskTest() done
[10:20:01.018] <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
[10:20:01.020] <TB1> INFO: ----------------------------------------------------------------------
[10:20:01.020] <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)
[10:20:01.020] <TB1> INFO: ----------------------------------------------------------------------
[10:20:01.291] <TB1> INFO: Expecting 41600 events.
[10:20:05.024] <TB1> INFO: 41600 events read in total (3177ms).
[10:20:05.024] <TB1> INFO: Test took 4002ms.
[10:20:05.255] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:20:05.255] <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
[10:20:05.255] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[10:20:05.255] <TB1> INFO: Decoding statistics:
[10:20:05.255] <TB1> INFO: General information:
[10:20:05.255] <TB1> INFO: 16bit words read: 0
[10:20:05.255] <TB1> INFO: valid events total: 0
[10:20:05.255] <TB1> INFO: empty events: 0
[10:20:05.255] <TB1> INFO: valid events with pixels: 0
[10:20:05.255] <TB1> INFO: valid pixel hits: 0
[10:20:05.255] <TB1> INFO: Event errors: 0
[10:20:05.255] <TB1> INFO: start marker: 0
[10:20:05.255] <TB1> INFO: stop marker: 0
[10:20:05.255] <TB1> INFO: overflow: 0
[10:20:05.255] <TB1> INFO: invalid 5bit words: 0
[10:20:05.255] <TB1> INFO: invalid XOR eye diagram: 0
[10:20:05.255] <TB1> INFO: frame (failed synchr.): 0
[10:20:05.255] <TB1> INFO: idle data (no TBM trl): 0
[10:20:05.255] <TB1> INFO: no data (only TBM hdr): 0
[10:20:05.255] <TB1> INFO: TBM errors: 0
[10:20:05.255] <TB1> INFO: flawed TBM headers: 0
[10:20:05.255] <TB1> INFO: flawed TBM trailers: 0
[10:20:05.255] <TB1> INFO: event ID mismatches: 0
[10:20:05.255] <TB1> INFO: ROC errors: 0
[10:20:05.255] <TB1> INFO: missing ROC header(s): 0
[10:20:05.255] <TB1> INFO: misplaced readback start: 0
[10:20:05.255] <TB1> INFO: Pixel decoding errors: 0
[10:20:05.255] <TB1> INFO: pixel data incomplete: 0
[10:20:05.255] <TB1> INFO: pixel address: 0
[10:20:05.255] <TB1> INFO: pulse height fill bit: 0
[10:20:05.255] <TB1> INFO: buffer corruption: 0
[10:20:05.268] <TB1> INFO: ######################################################################
[10:20:05.268] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:20:05.268] <TB1> INFO: ######################################################################
[10:20:05.270] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:20:05.282] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:20:05.282] <TB1> INFO: run 1 of 1
[10:20:05.558] <TB1> INFO: Expecting 3120000 events.
[10:20:46.165] <TB1> INFO: 860710 events read in total (40051ms).
[10:21:26.797] <TB1> INFO: 1714780 events read in total (80683ms).
[10:22:07.588] <TB1> INFO: 2580625 events read in total (121474ms).
[10:22:32.906] <TB1> INFO: 3120000 events read in total (146792ms).
[10:22:32.955] <TB1> INFO: Test took 147673ms.
[10:22:56.495] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[10:22:56.495] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 5 0 0 4 1 2 1 1 0 2 1 0 2
[10:22:56.495] <TB1> INFO: separation cut (per ROC): 83 88 90 94 89 91 93 81 88 78 80 74 99 85 90 88
[10:22:56.495] <TB1> INFO: Decoding statistics:
[10:22:56.495] <TB1> INFO: General information:
[10:22:56.495] <TB1> INFO: 16bit words read: 0
[10:22:56.495] <TB1> INFO: valid events total: 0
[10:22:56.495] <TB1> INFO: empty events: 0
[10:22:56.495] <TB1> INFO: valid events with pixels: 0
[10:22:56.495] <TB1> INFO: valid pixel hits: 0
[10:22:56.495] <TB1> INFO: Event errors: 0
[10:22:56.495] <TB1> INFO: start marker: 0
[10:22:56.495] <TB1> INFO: stop marker: 0
[10:22:56.495] <TB1> INFO: overflow: 0
[10:22:56.495] <TB1> INFO: invalid 5bit words: 0
[10:22:56.495] <TB1> INFO: invalid XOR eye diagram: 0
[10:22:56.495] <TB1> INFO: frame (failed synchr.): 0
[10:22:56.495] <TB1> INFO: idle data (no TBM trl): 0
[10:22:56.495] <TB1> INFO: no data (only TBM hdr): 0
[10:22:56.495] <TB1> INFO: TBM errors: 0
[10:22:56.495] <TB1> INFO: flawed TBM headers: 0
[10:22:56.495] <TB1> INFO: flawed TBM trailers: 0
[10:22:56.495] <TB1> INFO: event ID mismatches: 0
[10:22:56.495] <TB1> INFO: ROC errors: 0
[10:22:56.495] <TB1> INFO: missing ROC header(s): 0
[10:22:56.495] <TB1> INFO: misplaced readback start: 0
[10:22:56.495] <TB1> INFO: Pixel decoding errors: 0
[10:22:56.495] <TB1> INFO: pixel data incomplete: 0
[10:22:56.495] <TB1> INFO: pixel address: 0
[10:22:56.495] <TB1> INFO: pulse height fill bit: 0
[10:22:56.495] <TB1> INFO: buffer corruption: 0
[10:22:56.588] <TB1> INFO: ######################################################################
[10:22:56.588] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:22:56.588] <TB1> INFO: ######################################################################
[10:22:56.588] <TB1> INFO: ----------------------------------------------------------------------
[10:22:56.588] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:22:56.588] <TB1> INFO: ----------------------------------------------------------------------
[10:22:56.589] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:22:56.597] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:22:56.597] <TB1> INFO: run 1 of 1
[10:22:56.884] <TB1> INFO: Expecting 26208000 events.
[10:23:30.276] <TB1> INFO: 922250 events read in total (32836ms).
[10:24:03.055] <TB1> INFO: 1829400 events read in total (65615ms).
[10:24:35.694] <TB1> INFO: 2734200 events read in total (98254ms).
[10:25:08.596] <TB1> INFO: 3639200 events read in total (131156ms).
[10:25:41.441] <TB1> INFO: 4540850 events read in total (164001ms).
[10:26:14.486] <TB1> INFO: 5441650 events read in total (197046ms).
[10:26:47.516] <TB1> INFO: 6343700 events read in total (230076ms).
[10:27:20.261] <TB1> INFO: 7243550 events read in total (262821ms).
[10:27:53.327] <TB1> INFO: 8144300 events read in total (295887ms).
[10:28:26.184] <TB1> INFO: 9043850 events read in total (328744ms).
[10:28:59.132] <TB1> INFO: 9940850 events read in total (361692ms).
[10:29:32.432] <TB1> INFO: 10840100 events read in total (394992ms).
[10:30:05.495] <TB1> INFO: 11736400 events read in total (428055ms).
[10:30:37.856] <TB1> INFO: 12634150 events read in total (460416ms).
[10:31:10.838] <TB1> INFO: 13521150 events read in total (493398ms).
[10:31:43.599] <TB1> INFO: 14406650 events read in total (526159ms).
[10:32:16.325] <TB1> INFO: 15291100 events read in total (558885ms).
[10:32:48.959] <TB1> INFO: 16172250 events read in total (591519ms).
[10:33:21.717] <TB1> INFO: 17052350 events read in total (624277ms).
[10:33:54.253] <TB1> INFO: 17933350 events read in total (656813ms).
[10:34:27.280] <TB1> INFO: 18812950 events read in total (689840ms).
[10:34:59.861] <TB1> INFO: 19690650 events read in total (722421ms).
[10:35:32.846] <TB1> INFO: 20567600 events read in total (755406ms).
[10:36:05.401] <TB1> INFO: 21444850 events read in total (787961ms).
[10:36:38.224] <TB1> INFO: 22320950 events read in total (820784ms).
[10:37:10.805] <TB1> INFO: 23199350 events read in total (853365ms).
[10:37:43.391] <TB1> INFO: 24076000 events read in total (885951ms).
[10:38:16.016] <TB1> INFO: 24953100 events read in total (918576ms).
[10:38:49.110] <TB1> INFO: 25835850 events read in total (951670ms).
[10:39:02.953] <TB1> INFO: 26208000 events read in total (965513ms).
[10:39:02.981] <TB1> INFO: Test took 966384ms.
[10:39:03.231] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:04.615] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:05.991] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:07.507] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:09.193] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:10.907] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:12.471] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:14.020] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:15.519] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:17.041] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:18.694] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:20.225] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:21.852] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:23.250] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:24.639] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:26.020] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:39:27.436] <TB1> INFO: PixTestScurves::scurves() done
[10:39:27.436] <TB1> INFO: Vcal mean: 95.68 92.64 101.75 96.26 90.38 94.11 102.68 90.15 95.18 89.76 91.62 82.55 90.44 86.31 92.49 94.43
[10:39:27.436] <TB1> INFO: Vcal RMS: 4.81 5.11 5.10 5.64 5.07 5.39 4.99 5.00 5.07 5.54 5.33 4.17 5.18 5.11 5.42 5.18
[10:39:27.436] <TB1> INFO: PixTestScurves::fullTest() done, duration: 990 seconds
[10:39:27.436] <TB1> INFO: Decoding statistics:
[10:39:27.436] <TB1> INFO: General information:
[10:39:27.436] <TB1> INFO: 16bit words read: 0
[10:39:27.436] <TB1> INFO: valid events total: 0
[10:39:27.436] <TB1> INFO: empty events: 0
[10:39:27.436] <TB1> INFO: valid events with pixels: 0
[10:39:27.436] <TB1> INFO: valid pixel hits: 0
[10:39:27.436] <TB1> INFO: Event errors: 0
[10:39:27.436] <TB1> INFO: start marker: 0
[10:39:27.436] <TB1> INFO: stop marker: 0
[10:39:27.436] <TB1> INFO: overflow: 0
[10:39:27.436] <TB1> INFO: invalid 5bit words: 0
[10:39:27.436] <TB1> INFO: invalid XOR eye diagram: 0
[10:39:27.436] <TB1> INFO: frame (failed synchr.): 0
[10:39:27.436] <TB1> INFO: idle data (no TBM trl): 0
[10:39:27.436] <TB1> INFO: no data (only TBM hdr): 0
[10:39:27.436] <TB1> INFO: TBM errors: 0
[10:39:27.436] <TB1> INFO: flawed TBM headers: 0
[10:39:27.436] <TB1> INFO: flawed TBM trailers: 0
[10:39:27.436] <TB1> INFO: event ID mismatches: 0
[10:39:27.436] <TB1> INFO: ROC errors: 0
[10:39:27.436] <TB1> INFO: missing ROC header(s): 0
[10:39:27.436] <TB1> INFO: misplaced readback start: 0
[10:39:27.436] <TB1> INFO: Pixel decoding errors: 0
[10:39:27.436] <TB1> INFO: pixel data incomplete: 0
[10:39:27.436] <TB1> INFO: pixel address: 0
[10:39:27.436] <TB1> INFO: pulse height fill bit: 0
[10:39:27.436] <TB1> INFO: buffer corruption: 0
[10:39:27.505] <TB1> INFO: ######################################################################
[10:39:27.505] <TB1> INFO: PixTestTrim::doTest()
[10:39:27.505] <TB1> INFO: ######################################################################
[10:39:27.506] <TB1> INFO: ----------------------------------------------------------------------
[10:39:27.506] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:39:27.506] <TB1> INFO: ----------------------------------------------------------------------
[10:39:27.584] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:39:27.584] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:39:27.592] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:39:27.592] <TB1> INFO: run 1 of 1
[10:39:27.851] <TB1> INFO: Expecting 6281600 events.
[10:40:07.483] <TB1> INFO: 1080670 events read in total (39076ms).
[10:40:46.180] <TB1> INFO: 2156370 events read in total (77773ms).
[10:41:25.170] <TB1> INFO: 3225140 events read in total (116764ms).
[10:42:03.962] <TB1> INFO: 4299960 events read in total (155555ms).
[10:42:43.160] <TB1> INFO: 5377200 events read in total (194753ms).
[10:43:15.759] <TB1> INFO: 6281600 events read in total (227352ms).
[10:43:15.786] <TB1> INFO: Test took 228194ms.
[10:43:35.319] <TB1> INFO: ROC 0 VthrComp = 98
[10:43:35.319] <TB1> INFO: ROC 1 VthrComp = 99
[10:43:35.319] <TB1> INFO: ROC 2 VthrComp = 99
[10:43:35.320] <TB1> INFO: ROC 3 VthrComp = 95
[10:43:35.320] <TB1> INFO: ROC 4 VthrComp = 93
[10:43:35.320] <TB1> INFO: ROC 5 VthrComp = 98
[10:43:35.320] <TB1> INFO: ROC 6 VthrComp = 102
[10:43:35.320] <TB1> INFO: ROC 7 VthrComp = 91
[10:43:35.320] <TB1> INFO: ROC 8 VthrComp = 98
[10:43:35.320] <TB1> INFO: ROC 9 VthrComp = 92
[10:43:35.320] <TB1> INFO: ROC 10 VthrComp = 88
[10:43:35.320] <TB1> INFO: ROC 11 VthrComp = 84
[10:43:35.320] <TB1> INFO: ROC 12 VthrComp = 95
[10:43:35.320] <TB1> INFO: ROC 13 VthrComp = 91
[10:43:35.320] <TB1> INFO: ROC 14 VthrComp = 96
[10:43:35.320] <TB1> INFO: ROC 15 VthrComp = 97
[10:43:35.320] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:43:35.320] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:43:35.329] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:43:35.329] <TB1> INFO: run 1 of 1
[10:43:35.591] <TB1> INFO: Expecting 6281600 events.
[10:44:11.244] <TB1> INFO: 757500 events read in total (35097ms).
[10:44:46.185] <TB1> INFO: 1512450 events read in total (70038ms).
[10:45:21.075] <TB1> INFO: 2267550 events read in total (104928ms).
[10:45:55.754] <TB1> INFO: 3023170 events read in total (139607ms).
[10:46:30.936] <TB1> INFO: 3773180 events read in total (174789ms).
[10:47:06.083] <TB1> INFO: 4519030 events read in total (209936ms).
[10:47:41.179] <TB1> INFO: 5262750 events read in total (245032ms).
[10:48:15.509] <TB1> INFO: 6005440 events read in total (279362ms).
[10:48:28.254] <TB1> INFO: 6281600 events read in total (292107ms).
[10:48:28.356] <TB1> INFO: Test took 293027ms.
[10:48:52.094] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 57.1644 for pixel 4/75 mean/min/max = 44.5102/31.769/57.2513
[10:48:52.095] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.2912 for pixel 4/58 mean/min/max = 44.8273/32.1893/57.4653
[10:48:52.095] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 58.1818 for pixel 26/25 mean/min/max = 45.8019/32.9786/58.6251
[10:48:52.095] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 60.1205 for pixel 21/3 mean/min/max = 46.5157/32.7859/60.2455
[10:48:52.095] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 56.7188 for pixel 0/65 mean/min/max = 44.9001/33.0055/56.7947
[10:48:52.096] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.9435 for pixel 0/18 mean/min/max = 45.1135/32.2164/58.0106
[10:48:52.096] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.3273 for pixel 18/32 mean/min/max = 45.2652/31.9159/58.6146
[10:48:52.096] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.1846 for pixel 1/8 mean/min/max = 46.1942/34.1798/58.2086
[10:48:52.096] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 55.8597 for pixel 8/0 mean/min/max = 44.1705/31.6934/56.6476
[10:48:52.097] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 58.8215 for pixel 7/0 mean/min/max = 46.0103/33.1712/58.8494
[10:48:52.097] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 62.2223 for pixel 0/76 mean/min/max = 48.2212/34.1404/62.302
[10:48:52.097] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 56.5905 for pixel 44/61 mean/min/max = 44.8546/32.9647/56.7446
[10:48:52.097] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 55.9483 for pixel 22/12 mean/min/max = 44.7376/33.496/55.9792
[10:48:52.098] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.2759 for pixel 16/2 mean/min/max = 45.4823/33.4972/57.4674
[10:48:52.098] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.8276 for pixel 25/2 mean/min/max = 45.1567/32.303/58.0103
[10:48:52.098] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 56.5479 for pixel 14/0 mean/min/max = 44.5735/32.1722/56.9749
[10:48:52.099] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:48:52.186] <TB1> INFO: Expecting 514560 events.
[10:49:05.045] <TB1> INFO: 514560 events read in total (12303ms).
[10:49:05.051] <TB1> INFO: Expecting 514560 events.
[10:49:17.883] <TB1> INFO: 514560 events read in total (12410ms).
[10:49:17.891] <TB1> INFO: Expecting 514560 events.
[10:49:30.584] <TB1> INFO: 514560 events read in total (12278ms).
[10:49:30.594] <TB1> INFO: Expecting 514560 events.
[10:49:43.489] <TB1> INFO: 514560 events read in total (12477ms).
[10:49:43.503] <TB1> INFO: Expecting 514560 events.
[10:49:56.422] <TB1> INFO: 514560 events read in total (12509ms).
[10:49:56.436] <TB1> INFO: Expecting 514560 events.
[10:50:09.213] <TB1> INFO: 514560 events read in total (12374ms).
[10:50:09.230] <TB1> INFO: Expecting 514560 events.
[10:50:22.203] <TB1> INFO: 514560 events read in total (12562ms).
[10:50:22.223] <TB1> INFO: Expecting 514560 events.
[10:50:35.054] <TB1> INFO: 514560 events read in total (12430ms).
[10:50:35.073] <TB1> INFO: Expecting 514560 events.
[10:50:47.967] <TB1> INFO: 514560 events read in total (12492ms).
[10:50:47.989] <TB1> INFO: Expecting 514560 events.
[10:51:00.977] <TB1> INFO: 514560 events read in total (12586ms).
[10:51:01.002] <TB1> INFO: Expecting 514560 events.
[10:51:13.735] <TB1> INFO: 514560 events read in total (12331ms).
[10:51:13.764] <TB1> INFO: Expecting 514560 events.
[10:51:26.612] <TB1> INFO: 514560 events read in total (12446ms).
[10:51:26.640] <TB1> INFO: Expecting 514560 events.
[10:51:39.546] <TB1> INFO: 514560 events read in total (12504ms).
[10:51:39.576] <TB1> INFO: Expecting 514560 events.
[10:51:52.273] <TB1> INFO: 514560 events read in total (12295ms).
[10:51:52.306] <TB1> INFO: Expecting 514560 events.
[10:52:05.148] <TB1> INFO: 514560 events read in total (12440ms).
[10:52:05.182] <TB1> INFO: Expecting 514560 events.
[10:52:18.062] <TB1> INFO: 514560 events read in total (12478ms).
[10:52:18.100] <TB1> INFO: Test took 206002ms.
[10:52:19.081] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:52:19.089] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:52:19.089] <TB1> INFO: run 1 of 1
[10:52:19.353] <TB1> INFO: Expecting 6281600 events.
[10:52:54.556] <TB1> INFO: 743630 events read in total (34647ms).
[10:53:28.858] <TB1> INFO: 1485040 events read in total (68949ms).
[10:54:03.311] <TB1> INFO: 2226810 events read in total (103402ms).
[10:54:37.729] <TB1> INFO: 2969460 events read in total (137820ms).
[10:55:11.878] <TB1> INFO: 3706090 events read in total (171969ms).
[10:55:46.289] <TB1> INFO: 4438280 events read in total (206380ms).
[10:56:20.848] <TB1> INFO: 5168360 events read in total (240939ms).
[10:56:55.273] <TB1> INFO: 5897020 events read in total (275364ms).
[10:57:13.518] <TB1> INFO: 6281600 events read in total (293609ms).
[10:57:13.564] <TB1> INFO: Test took 294475ms.
[10:57:37.828] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.180401 .. 255.000000
[10:57:37.906] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:57:37.914] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:57:37.914] <TB1> INFO: run 1 of 1
[10:57:38.180] <TB1> INFO: Expecting 10649600 events.
[10:58:13.055] <TB1> INFO: 712830 events read in total (34319ms).
[10:58:47.503] <TB1> INFO: 1425820 events read in total (68767ms).
[10:59:21.507] <TB1> INFO: 2138720 events read in total (102771ms).
[10:59:55.565] <TB1> INFO: 2851850 events read in total (136829ms).
[11:00:29.406] <TB1> INFO: 3565020 events read in total (170670ms).
[11:01:03.359] <TB1> INFO: 4278400 events read in total (204623ms).
[11:01:37.880] <TB1> INFO: 4991320 events read in total (239144ms).
[11:02:11.876] <TB1> INFO: 5704300 events read in total (273140ms).
[11:02:46.231] <TB1> INFO: 6416710 events read in total (307495ms).
[11:03:20.296] <TB1> INFO: 7128370 events read in total (341560ms).
[11:03:55.181] <TB1> INFO: 7839990 events read in total (376445ms).
[11:04:29.279] <TB1> INFO: 8550860 events read in total (410543ms).
[11:05:03.389] <TB1> INFO: 9261440 events read in total (444653ms).
[11:05:37.449] <TB1> INFO: 9972050 events read in total (478713ms).
[11:06:09.559] <TB1> INFO: 10649600 events read in total (510823ms).
[11:06:09.680] <TB1> INFO: Test took 511766ms.
[11:06:39.399] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 17.395895 .. 65.469929
[11:06:39.484] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 75 (-1/-1) hits flags = 528 (plus default)
[11:06:39.492] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:06:39.492] <TB1> INFO: run 1 of 1
[11:06:39.766] <TB1> INFO: Expecting 2870400 events.
[11:07:16.626] <TB1> INFO: 831790 events read in total (36303ms).
[11:07:53.076] <TB1> INFO: 1663240 events read in total (72754ms).
[11:08:28.605] <TB1> INFO: 2493640 events read in total (108282ms).
[11:08:44.909] <TB1> INFO: 2870400 events read in total (124586ms).
[11:08:44.935] <TB1> INFO: Test took 125443ms.
[11:09:01.553] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 2.261774 .. 42.949115
[11:09:01.631] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 2 .. 52 (-1/-1) hits flags = 528 (plus default)
[11:09:01.640] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:09:01.640] <TB1> INFO: run 1 of 1
[11:09:01.902] <TB1> INFO: Expecting 2121600 events.
[11:09:42.154] <TB1> INFO: 981970 events read in total (39696ms).
[11:10:20.217] <TB1> INFO: 1962760 events read in total (77759ms).
[11:10:26.805] <TB1> INFO: 2121600 events read in total (84348ms).
[11:10:26.822] <TB1> INFO: Test took 85183ms.
[11:10:40.963] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 4.531792 .. 42.906689
[11:10:41.041] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 4 .. 52 (-1/-1) hits flags = 528 (plus default)
[11:10:41.049] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:10:41.049] <TB1> INFO: run 1 of 1
[11:10:41.308] <TB1> INFO: Expecting 2038400 events.
[11:11:19.734] <TB1> INFO: 971810 events read in total (37870ms).
[11:11:57.825] <TB1> INFO: 1943280 events read in total (75961ms).
[11:12:01.856] <TB1> INFO: 2038400 events read in total (79992ms).
[11:12:01.869] <TB1> INFO: Test took 80821ms.
[11:12:15.847] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:12:15.847] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:12:15.856] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:12:15.856] <TB1> INFO: run 1 of 1
[11:12:16.129] <TB1> INFO: Expecting 1705600 events.
[11:12:53.809] <TB1> INFO: 879010 events read in total (37124ms).
[11:13:28.728] <TB1> INFO: 1705600 events read in total (72044ms).
[11:13:28.750] <TB1> INFO: Test took 72895ms.
[11:13:43.325] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C0.dat
[11:13:43.325] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C1.dat
[11:13:43.325] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C2.dat
[11:13:43.325] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C3.dat
[11:13:43.326] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C4.dat
[11:13:43.326] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C5.dat
[11:13:43.326] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C6.dat
[11:13:43.326] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C7.dat
[11:13:43.326] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C8.dat
[11:13:43.326] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C9.dat
[11:13:43.327] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C10.dat
[11:13:43.327] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C11.dat
[11:13:43.327] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C12.dat
[11:13:43.327] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C13.dat
[11:13:43.327] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C14.dat
[11:13:43.327] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C15.dat
[11:13:43.328] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C0.dat
[11:13:43.337] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C1.dat
[11:13:43.345] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C2.dat
[11:13:43.352] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C3.dat
[11:13:43.359] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C4.dat
[11:13:43.366] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C5.dat
[11:13:43.372] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C6.dat
[11:13:43.379] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C7.dat
[11:13:43.386] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C8.dat
[11:13:43.392] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C9.dat
[11:13:43.399] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C10.dat
[11:13:43.406] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C11.dat
[11:13:43.412] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C12.dat
[11:13:43.419] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C13.dat
[11:13:43.425] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C14.dat
[11:13:43.432] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C15.dat
[11:13:43.439] <TB1> INFO: PixTestTrim::trimTest() done
[11:13:43.439] <TB1> INFO: vtrim: 100 107 98 112 95 87 111 102 95 112 98 99 102 95 101 98
[11:13:43.439] <TB1> INFO: vthrcomp: 98 99 99 95 93 98 102 91 98 92 88 84 95 91 96 97
[11:13:43.439] <TB1> INFO: vcal mean: 34.94 34.97 34.97 34.99 35.00 34.99 35.02 34.97 34.98 34.99 34.98 34.99 34.97 35.00 34.93 34.96
[11:13:43.439] <TB1> INFO: vcal RMS: 0.88 0.84 0.89 1.07 0.82 0.82 0.84 0.93 0.89 0.88 0.87 0.81 0.83 0.82 0.98 0.97
[11:13:43.439] <TB1> INFO: bits mean: 10.12 9.73 9.27 9.70 9.17 9.22 9.67 9.22 10.04 9.54 8.19 9.69 9.80 9.47 9.63 10.07
[11:13:43.439] <TB1> INFO: bits RMS: 2.54 2.70 2.70 2.48 2.85 2.87 2.66 2.49 2.60 2.59 2.75 2.54 2.41 2.51 2.63 2.53
[11:13:43.445] <TB1> INFO: ----------------------------------------------------------------------
[11:13:43.445] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:13:43.445] <TB1> INFO: ----------------------------------------------------------------------
[11:13:43.449] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:13:43.457] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:13:43.457] <TB1> INFO: run 1 of 1
[11:13:43.737] <TB1> INFO: Expecting 4160000 events.
[11:14:25.602] <TB1> INFO: 945775 events read in total (41309ms).
[11:15:06.893] <TB1> INFO: 1883985 events read in total (82601ms).
[11:15:48.057] <TB1> INFO: 2812625 events read in total (123765ms).
[11:16:29.921] <TB1> INFO: 3736440 events read in total (165629ms).
[11:16:49.354] <TB1> INFO: 4160000 events read in total (185061ms).
[11:16:49.399] <TB1> INFO: Test took 185942ms.
[11:17:20.080] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[11:17:20.089] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:17:20.089] <TB1> INFO: run 1 of 1
[11:17:20.370] <TB1> INFO: Expecting 3598400 events.
[11:18:03.244] <TB1> INFO: 970500 events read in total (42318ms).
[11:18:44.633] <TB1> INFO: 1931975 events read in total (83707ms).
[11:19:26.610] <TB1> INFO: 2881190 events read in total (125685ms).
[11:19:57.689] <TB1> INFO: 3598400 events read in total (156763ms).
[11:19:57.724] <TB1> INFO: Test took 157634ms.
[11:20:23.603] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[11:20:23.612] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:20:23.612] <TB1> INFO: run 1 of 1
[11:20:23.874] <TB1> INFO: Expecting 3369600 events.
[11:21:06.812] <TB1> INFO: 1001405 events read in total (42381ms).
[11:21:49.061] <TB1> INFO: 1990425 events read in total (84630ms).
[11:22:32.373] <TB1> INFO: 2967075 events read in total (127942ms).
[11:22:49.788] <TB1> INFO: 3369600 events read in total (145357ms).
[11:22:49.828] <TB1> INFO: Test took 146216ms.
[11:23:14.773] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[11:23:14.781] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:23:14.782] <TB1> INFO: run 1 of 1
[11:23:15.051] <TB1> INFO: Expecting 3390400 events.
[11:23:58.145] <TB1> INFO: 997745 events read in total (42538ms).
[11:24:40.925] <TB1> INFO: 1983295 events read in total (85318ms).
[11:25:23.030] <TB1> INFO: 2956395 events read in total (127424ms).
[11:25:41.826] <TB1> INFO: 3390400 events read in total (146219ms).
[11:25:41.863] <TB1> INFO: Test took 147081ms.
[11:26:07.227] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[11:26:07.236] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:26:07.236] <TB1> INFO: run 1 of 1
[11:26:07.498] <TB1> INFO: Expecting 3390400 events.
[11:26:50.596] <TB1> INFO: 997195 events read in total (42542ms).
[11:27:32.878] <TB1> INFO: 1982370 events read in total (84824ms).
[11:28:15.137] <TB1> INFO: 2955110 events read in total (127084ms).
[11:28:34.335] <TB1> INFO: 3390400 events read in total (146281ms).
[11:28:34.368] <TB1> INFO: Test took 147132ms.
[11:28:58.392] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:28:58.393] <TB1> INFO: PixTestTrim::doTest() done, duration: 2970 seconds
[11:28:58.394] <TB1> INFO: Decoding statistics:
[11:28:58.394] <TB1> INFO: General information:
[11:28:58.394] <TB1> INFO: 16bit words read: 0
[11:28:58.394] <TB1> INFO: valid events total: 0
[11:28:58.394] <TB1> INFO: empty events: 0
[11:28:58.394] <TB1> INFO: valid events with pixels: 0
[11:28:58.394] <TB1> INFO: valid pixel hits: 0
[11:28:58.394] <TB1> INFO: Event errors: 0
[11:28:58.394] <TB1> INFO: start marker: 0
[11:28:58.394] <TB1> INFO: stop marker: 0
[11:28:58.394] <TB1> INFO: overflow: 0
[11:28:58.394] <TB1> INFO: invalid 5bit words: 0
[11:28:58.394] <TB1> INFO: invalid XOR eye diagram: 0
[11:28:58.394] <TB1> INFO: frame (failed synchr.): 0
[11:28:58.394] <TB1> INFO: idle data (no TBM trl): 0
[11:28:58.394] <TB1> INFO: no data (only TBM hdr): 0
[11:28:58.394] <TB1> INFO: TBM errors: 0
[11:28:58.394] <TB1> INFO: flawed TBM headers: 0
[11:28:58.394] <TB1> INFO: flawed TBM trailers: 0
[11:28:58.394] <TB1> INFO: event ID mismatches: 0
[11:28:58.394] <TB1> INFO: ROC errors: 0
[11:28:58.394] <TB1> INFO: missing ROC header(s): 0
[11:28:58.394] <TB1> INFO: misplaced readback start: 0
[11:28:58.394] <TB1> INFO: Pixel decoding errors: 0
[11:28:58.394] <TB1> INFO: pixel data incomplete: 0
[11:28:58.394] <TB1> INFO: pixel address: 0
[11:28:58.394] <TB1> INFO: pulse height fill bit: 0
[11:28:58.394] <TB1> INFO: buffer corruption: 0
[11:28:59.128] <TB1> INFO: ######################################################################
[11:28:59.128] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:28:59.128] <TB1> INFO: ######################################################################
[11:28:59.403] <TB1> INFO: Expecting 41600 events.
[11:29:03.145] <TB1> INFO: 41600 events read in total (3186ms).
[11:29:03.145] <TB1> INFO: Test took 4015ms.
[11:29:03.610] <TB1> INFO: Expecting 41600 events.
[11:29:07.400] <TB1> INFO: 41600 events read in total (3234ms).
[11:29:07.401] <TB1> INFO: Test took 4052ms.
[11:29:07.684] <TB1> INFO: Expecting 41600 events.
[11:29:11.539] <TB1> INFO: 41600 events read in total (3298ms).
[11:29:11.539] <TB1> INFO: Test took 4117ms.
[11:29:11.795] <TB1> INFO: Expecting 2560 events.
[11:29:12.647] <TB1> INFO: 2560 events read in total (297ms).
[11:29:12.648] <TB1> INFO: Test took 1096ms.
[11:29:12.953] <TB1> INFO: Expecting 2560 events.
[11:29:13.806] <TB1> INFO: 2560 events read in total (297ms).
[11:29:13.806] <TB1> INFO: Test took 1158ms.
[11:29:14.112] <TB1> INFO: Expecting 2560 events.
[11:29:14.964] <TB1> INFO: 2560 events read in total (296ms).
[11:29:14.965] <TB1> INFO: Test took 1159ms.
[11:29:15.270] <TB1> INFO: Expecting 2560 events.
[11:29:16.124] <TB1> INFO: 2560 events read in total (298ms).
[11:29:16.124] <TB1> INFO: Test took 1159ms.
[11:29:16.429] <TB1> INFO: Expecting 2560 events.
[11:29:17.283] <TB1> INFO: 2560 events read in total (298ms).
[11:29:17.283] <TB1> INFO: Test took 1158ms.
[11:29:17.587] <TB1> INFO: Expecting 2560 events.
[11:29:18.441] <TB1> INFO: 2560 events read in total (298ms).
[11:29:18.441] <TB1> INFO: Test took 1158ms.
[11:29:18.747] <TB1> INFO: Expecting 2560 events.
[11:29:19.600] <TB1> INFO: 2560 events read in total (297ms).
[11:29:19.600] <TB1> INFO: Test took 1158ms.
[11:29:19.906] <TB1> INFO: Expecting 2560 events.
[11:29:20.760] <TB1> INFO: 2560 events read in total (298ms).
[11:29:20.760] <TB1> INFO: Test took 1159ms.
[11:29:21.066] <TB1> INFO: Expecting 2560 events.
[11:29:21.919] <TB1> INFO: 2560 events read in total (297ms).
[11:29:21.919] <TB1> INFO: Test took 1158ms.
[11:29:22.224] <TB1> INFO: Expecting 2560 events.
[11:29:23.076] <TB1> INFO: 2560 events read in total (296ms).
[11:29:23.076] <TB1> INFO: Test took 1157ms.
[11:29:23.381] <TB1> INFO: Expecting 2560 events.
[11:29:24.234] <TB1> INFO: 2560 events read in total (297ms).
[11:29:24.234] <TB1> INFO: Test took 1157ms.
[11:29:24.539] <TB1> INFO: Expecting 2560 events.
[11:29:25.391] <TB1> INFO: 2560 events read in total (296ms).
[11:29:25.391] <TB1> INFO: Test took 1156ms.
[11:29:25.696] <TB1> INFO: Expecting 2560 events.
[11:29:26.551] <TB1> INFO: 2560 events read in total (299ms).
[11:29:26.551] <TB1> INFO: Test took 1160ms.
[11:29:26.856] <TB1> INFO: Expecting 2560 events.
[11:29:27.709] <TB1> INFO: 2560 events read in total (297ms).
[11:29:27.709] <TB1> INFO: Test took 1158ms.
[11:29:28.014] <TB1> INFO: Expecting 2560 events.
[11:29:28.867] <TB1> INFO: 2560 events read in total (297ms).
[11:29:28.867] <TB1> INFO: Test took 1157ms.
[11:29:29.172] <TB1> INFO: Expecting 2560 events.
[11:29:30.027] <TB1> INFO: 2560 events read in total (299ms).
[11:29:30.027] <TB1> INFO: Test took 1159ms.
[11:29:30.030] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:29:30.333] <TB1> INFO: Expecting 655360 events.
[11:29:46.879] <TB1> INFO: 655360 events read in total (15990ms).
[11:29:46.889] <TB1> INFO: Expecting 655360 events.
[11:30:03.493] <TB1> INFO: 655360 events read in total (16202ms).
[11:30:03.508] <TB1> INFO: Expecting 655360 events.
[11:30:20.218] <TB1> INFO: 655360 events read in total (16308ms).
[11:30:20.233] <TB1> INFO: Expecting 655360 events.
[11:30:36.899] <TB1> INFO: 655360 events read in total (16264ms).
[11:30:36.918] <TB1> INFO: Expecting 655360 events.
[11:30:53.676] <TB1> INFO: 655360 events read in total (16356ms).
[11:30:53.698] <TB1> INFO: Expecting 655360 events.
[11:31:10.252] <TB1> INFO: 655360 events read in total (16152ms).
[11:31:10.280] <TB1> INFO: Expecting 655360 events.
[11:31:26.949] <TB1> INFO: 655360 events read in total (16268ms).
[11:31:26.977] <TB1> INFO: Expecting 655360 events.
[11:31:43.681] <TB1> INFO: 655360 events read in total (16302ms).
[11:31:43.713] <TB1> INFO: Expecting 655360 events.
[11:32:00.277] <TB1> INFO: 655360 events read in total (16163ms).
[11:32:00.313] <TB1> INFO: Expecting 655360 events.
[11:32:17.050] <TB1> INFO: 655360 events read in total (16335ms).
[11:32:17.092] <TB1> INFO: Expecting 655360 events.
[11:32:33.719] <TB1> INFO: 655360 events read in total (16225ms).
[11:32:33.786] <TB1> INFO: Expecting 655360 events.
[11:32:50.431] <TB1> INFO: 655360 events read in total (16243ms).
[11:32:50.478] <TB1> INFO: Expecting 655360 events.
[11:33:07.064] <TB1> INFO: 655360 events read in total (16185ms).
[11:33:07.122] <TB1> INFO: Expecting 655360 events.
[11:33:23.670] <TB1> INFO: 655360 events read in total (16147ms).
[11:33:23.730] <TB1> INFO: Expecting 655360 events.
[11:33:40.587] <TB1> INFO: 655360 events read in total (16455ms).
[11:33:40.647] <TB1> INFO: Expecting 655360 events.
[11:33:57.401] <TB1> INFO: 655360 events read in total (16352ms).
[11:33:57.462] <TB1> INFO: Test took 267432ms.
[11:33:57.540] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:33:57.771] <TB1> INFO: Expecting 655360 events.
[11:34:14.582] <TB1> INFO: 655360 events read in total (16255ms).
[11:34:14.592] <TB1> INFO: Expecting 655360 events.
[11:34:31.168] <TB1> INFO: 655360 events read in total (16175ms).
[11:34:31.180] <TB1> INFO: Expecting 655360 events.
[11:34:47.873] <TB1> INFO: 655360 events read in total (16292ms).
[11:34:47.888] <TB1> INFO: Expecting 655360 events.
[11:35:04.612] <TB1> INFO: 655360 events read in total (16322ms).
[11:35:04.631] <TB1> INFO: Expecting 655360 events.
[11:35:21.372] <TB1> INFO: 655360 events read in total (16339ms).
[11:35:21.397] <TB1> INFO: Expecting 655360 events.
[11:35:38.081] <TB1> INFO: 655360 events read in total (16282ms).
[11:35:38.106] <TB1> INFO: Expecting 655360 events.
[11:35:54.835] <TB1> INFO: 655360 events read in total (16327ms).
[11:35:54.868] <TB1> INFO: Expecting 655360 events.
[11:36:11.562] <TB1> INFO: 655360 events read in total (16292ms).
[11:36:11.594] <TB1> INFO: Expecting 655360 events.
[11:36:28.364] <TB1> INFO: 655360 events read in total (16368ms).
[11:36:28.400] <TB1> INFO: Expecting 655360 events.
[11:36:45.183] <TB1> INFO: 655360 events read in total (16381ms).
[11:36:45.224] <TB1> INFO: Expecting 655360 events.
[11:37:01.732] <TB1> INFO: 655360 events read in total (16107ms).
[11:37:01.784] <TB1> INFO: Expecting 655360 events.
[11:37:18.480] <TB1> INFO: 655360 events read in total (16294ms).
[11:37:18.525] <TB1> INFO: Expecting 655360 events.
[11:37:35.291] <TB1> INFO: 655360 events read in total (16364ms).
[11:37:35.343] <TB1> INFO: Expecting 655360 events.
[11:37:51.986] <TB1> INFO: 655360 events read in total (16241ms).
[11:37:52.042] <TB1> INFO: Expecting 655360 events.
[11:38:08.789] <TB1> INFO: 655360 events read in total (16346ms).
[11:38:08.857] <TB1> INFO: Expecting 655360 events.
[11:38:25.517] <TB1> INFO: 655360 events read in total (16258ms).
[11:38:25.577] <TB1> INFO: Test took 268037ms.
[11:38:25.773] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.780] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.787] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.800] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.807] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:38:25.814] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:38:25.820] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.827] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.834] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.841] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.847] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.854] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:38:25.861] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.871] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.878] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.885] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.891] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.898] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.905] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:25.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C0.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C1.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C2.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C3.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C4.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C5.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C6.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C7.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C8.dat
[11:38:25.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C9.dat
[11:38:25.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C10.dat
[11:38:25.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C11.dat
[11:38:25.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C12.dat
[11:38:25.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C13.dat
[11:38:25.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C14.dat
[11:38:25.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C15.dat
[11:38:26.216] <TB1> INFO: Expecting 41600 events.
[11:38:29.723] <TB1> INFO: 41600 events read in total (2951ms).
[11:38:29.724] <TB1> INFO: Test took 3779ms.
[11:38:30.183] <TB1> INFO: Expecting 41600 events.
[11:38:33.650] <TB1> INFO: 41600 events read in total (2911ms).
[11:38:33.651] <TB1> INFO: Test took 3748ms.
[11:38:34.098] <TB1> INFO: Expecting 41600 events.
[11:38:37.605] <TB1> INFO: 41600 events read in total (2951ms).
[11:38:37.606] <TB1> INFO: Test took 3779ms.
[11:38:37.792] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:37.881] <TB1> INFO: Expecting 2560 events.
[11:38:38.734] <TB1> INFO: 2560 events read in total (297ms).
[11:38:38.734] <TB1> INFO: Test took 942ms.
[11:38:38.736] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:39.040] <TB1> INFO: Expecting 2560 events.
[11:38:39.895] <TB1> INFO: 2560 events read in total (299ms).
[11:38:39.895] <TB1> INFO: Test took 1159ms.
[11:38:39.897] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:40.201] <TB1> INFO: Expecting 2560 events.
[11:38:41.054] <TB1> INFO: 2560 events read in total (297ms).
[11:38:41.055] <TB1> INFO: Test took 1158ms.
[11:38:41.057] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:41.360] <TB1> INFO: Expecting 2560 events.
[11:38:42.218] <TB1> INFO: 2560 events read in total (302ms).
[11:38:42.218] <TB1> INFO: Test took 1161ms.
[11:38:42.219] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:42.523] <TB1> INFO: Expecting 2560 events.
[11:38:43.376] <TB1> INFO: 2560 events read in total (297ms).
[11:38:43.377] <TB1> INFO: Test took 1158ms.
[11:38:43.379] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:43.682] <TB1> INFO: Expecting 2560 events.
[11:38:44.535] <TB1> INFO: 2560 events read in total (297ms).
[11:38:44.535] <TB1> INFO: Test took 1156ms.
[11:38:44.536] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:44.840] <TB1> INFO: Expecting 2560 events.
[11:38:45.693] <TB1> INFO: 2560 events read in total (297ms).
[11:38:45.693] <TB1> INFO: Test took 1157ms.
[11:38:45.695] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:45.998] <TB1> INFO: Expecting 2560 events.
[11:38:46.851] <TB1> INFO: 2560 events read in total (296ms).
[11:38:46.851] <TB1> INFO: Test took 1157ms.
[11:38:46.853] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:47.156] <TB1> INFO: Expecting 2560 events.
[11:38:48.011] <TB1> INFO: 2560 events read in total (299ms).
[11:38:48.011] <TB1> INFO: Test took 1158ms.
[11:38:48.012] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:48.316] <TB1> INFO: Expecting 2560 events.
[11:38:49.171] <TB1> INFO: 2560 events read in total (299ms).
[11:38:49.171] <TB1> INFO: Test took 1159ms.
[11:38:49.173] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:49.476] <TB1> INFO: Expecting 2560 events.
[11:38:50.331] <TB1> INFO: 2560 events read in total (299ms).
[11:38:50.331] <TB1> INFO: Test took 1158ms.
[11:38:50.333] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:50.636] <TB1> INFO: Expecting 2560 events.
[11:38:51.489] <TB1> INFO: 2560 events read in total (296ms).
[11:38:51.489] <TB1> INFO: Test took 1156ms.
[11:38:51.491] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:51.794] <TB1> INFO: Expecting 2560 events.
[11:38:52.650] <TB1> INFO: 2560 events read in total (299ms).
[11:38:52.650] <TB1> INFO: Test took 1160ms.
[11:38:52.652] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:52.955] <TB1> INFO: Expecting 2560 events.
[11:38:53.808] <TB1> INFO: 2560 events read in total (297ms).
[11:38:53.808] <TB1> INFO: Test took 1156ms.
[11:38:53.810] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:54.113] <TB1> INFO: Expecting 2560 events.
[11:38:54.965] <TB1> INFO: 2560 events read in total (297ms).
[11:38:54.966] <TB1> INFO: Test took 1156ms.
[11:38:54.967] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:55.271] <TB1> INFO: Expecting 2560 events.
[11:38:56.125] <TB1> INFO: 2560 events read in total (298ms).
[11:38:56.125] <TB1> INFO: Test took 1158ms.
[11:38:56.126] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:56.430] <TB1> INFO: Expecting 2560 events.
[11:38:57.285] <TB1> INFO: 2560 events read in total (299ms).
[11:38:57.285] <TB1> INFO: Test took 1159ms.
[11:38:57.287] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:57.590] <TB1> INFO: Expecting 2560 events.
[11:38:58.443] <TB1> INFO: 2560 events read in total (296ms).
[11:38:58.443] <TB1> INFO: Test took 1156ms.
[11:38:58.445] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:58.749] <TB1> INFO: Expecting 2560 events.
[11:38:59.601] <TB1> INFO: 2560 events read in total (296ms).
[11:38:59.601] <TB1> INFO: Test took 1156ms.
[11:38:59.603] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:59.907] <TB1> INFO: Expecting 2560 events.
[11:39:00.760] <TB1> INFO: 2560 events read in total (297ms).
[11:39:00.760] <TB1> INFO: Test took 1157ms.
[11:39:00.762] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:01.066] <TB1> INFO: Expecting 2560 events.
[11:39:01.917] <TB1> INFO: 2560 events read in total (295ms).
[11:39:01.917] <TB1> INFO: Test took 1155ms.
[11:39:01.919] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:02.223] <TB1> INFO: Expecting 2560 events.
[11:39:03.075] <TB1> INFO: 2560 events read in total (296ms).
[11:39:03.075] <TB1> INFO: Test took 1156ms.
[11:39:03.077] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:03.380] <TB1> INFO: Expecting 2560 events.
[11:39:04.233] <TB1> INFO: 2560 events read in total (297ms).
[11:39:04.233] <TB1> INFO: Test took 1156ms.
[11:39:04.235] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:04.539] <TB1> INFO: Expecting 2560 events.
[11:39:05.393] <TB1> INFO: 2560 events read in total (298ms).
[11:39:05.393] <TB1> INFO: Test took 1158ms.
[11:39:05.395] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:05.699] <TB1> INFO: Expecting 2560 events.
[11:39:06.552] <TB1> INFO: 2560 events read in total (297ms).
[11:39:06.552] <TB1> INFO: Test took 1157ms.
[11:39:06.554] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:06.857] <TB1> INFO: Expecting 2560 events.
[11:39:07.710] <TB1> INFO: 2560 events read in total (296ms).
[11:39:07.710] <TB1> INFO: Test took 1156ms.
[11:39:07.712] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:08.015] <TB1> INFO: Expecting 2560 events.
[11:39:08.868] <TB1> INFO: 2560 events read in total (297ms).
[11:39:08.868] <TB1> INFO: Test took 1156ms.
[11:39:08.870] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:09.173] <TB1> INFO: Expecting 2560 events.
[11:39:10.026] <TB1> INFO: 2560 events read in total (297ms).
[11:39:10.026] <TB1> INFO: Test took 1156ms.
[11:39:10.028] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:10.332] <TB1> INFO: Expecting 2560 events.
[11:39:11.184] <TB1> INFO: 2560 events read in total (296ms).
[11:39:11.184] <TB1> INFO: Test took 1156ms.
[11:39:11.186] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:11.489] <TB1> INFO: Expecting 2560 events.
[11:39:12.342] <TB1> INFO: 2560 events read in total (297ms).
[11:39:12.342] <TB1> INFO: Test took 1156ms.
[11:39:12.344] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:12.648] <TB1> INFO: Expecting 2560 events.
[11:39:13.501] <TB1> INFO: 2560 events read in total (297ms).
[11:39:13.501] <TB1> INFO: Test took 1157ms.
[11:39:13.502] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:13.806] <TB1> INFO: Expecting 2560 events.
[11:39:14.660] <TB1> INFO: 2560 events read in total (298ms).
[11:39:14.660] <TB1> INFO: Test took 1158ms.
[11:39:15.120] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 615 seconds
[11:39:15.120] <TB1> INFO: PH scale (per ROC): 77 79 65 77 77 80 73 68 79 78 69 76 79 75 79 73
[11:39:15.120] <TB1> INFO: PH offset (per ROC): 180 162 179 178 180 175 173 175 176 176 184 159 172 165 173 181
[11:39:15.125] <TB1> INFO: Decoding statistics:
[11:39:15.126] <TB1> INFO: General information:
[11:39:15.126] <TB1> INFO: 16bit words read: 91928
[11:39:15.126] <TB1> INFO: valid events total: 10240
[11:39:15.126] <TB1> INFO: empty events: 7738
[11:39:15.126] <TB1> INFO: valid events with pixels: 2502
[11:39:15.126] <TB1> INFO: valid pixel hits: 2502
[11:39:15.126] <TB1> INFO: Event errors: 0
[11:39:15.126] <TB1> INFO: start marker: 0
[11:39:15.126] <TB1> INFO: stop marker: 0
[11:39:15.126] <TB1> INFO: overflow: 0
[11:39:15.126] <TB1> INFO: invalid 5bit words: 0
[11:39:15.126] <TB1> INFO: invalid XOR eye diagram: 0
[11:39:15.126] <TB1> INFO: frame (failed synchr.): 0
[11:39:15.126] <TB1> INFO: idle data (no TBM trl): 0
[11:39:15.126] <TB1> INFO: no data (only TBM hdr): 0
[11:39:15.126] <TB1> INFO: TBM errors: 0
[11:39:15.126] <TB1> INFO: flawed TBM headers: 0
[11:39:15.126] <TB1> INFO: flawed TBM trailers: 0
[11:39:15.126] <TB1> INFO: event ID mismatches: 0
[11:39:15.126] <TB1> INFO: ROC errors: 0
[11:39:15.126] <TB1> INFO: missing ROC header(s): 0
[11:39:15.126] <TB1> INFO: misplaced readback start: 0
[11:39:15.126] <TB1> INFO: Pixel decoding errors: 0
[11:39:15.126] <TB1> INFO: pixel data incomplete: 0
[11:39:15.126] <TB1> INFO: pixel address: 0
[11:39:15.126] <TB1> INFO: pulse height fill bit: 0
[11:39:15.126] <TB1> INFO: buffer corruption: 0
[11:39:15.332] <TB1> INFO: ######################################################################
[11:39:15.332] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:39:15.332] <TB1> INFO: ######################################################################
[11:39:15.341] <TB1> INFO: scanning low vcal = 10
[11:39:15.602] <TB1> INFO: Expecting 41600 events.
[11:39:19.148] <TB1> INFO: 41600 events read in total (2990ms).
[11:39:19.149] <TB1> INFO: Test took 3808ms.
[11:39:19.150] <TB1> INFO: scanning low vcal = 20
[11:39:19.454] <TB1> INFO: Expecting 41600 events.
[11:39:23.014] <TB1> INFO: 41600 events read in total (3004ms).
[11:39:23.014] <TB1> INFO: Test took 3864ms.
[11:39:23.016] <TB1> INFO: scanning low vcal = 30
[11:39:23.319] <TB1> INFO: Expecting 41600 events.
[11:39:26.869] <TB1> INFO: 41600 events read in total (2994ms).
[11:39:26.869] <TB1> INFO: Test took 3853ms.
[11:39:26.871] <TB1> INFO: scanning low vcal = 40
[11:39:27.167] <TB1> INFO: Expecting 41600 events.
[11:39:31.360] <TB1> INFO: 41600 events read in total (3637ms).
[11:39:31.360] <TB1> INFO: Test took 4489ms.
[11:39:31.363] <TB1> INFO: scanning low vcal = 50
[11:39:31.633] <TB1> INFO: Expecting 41600 events.
[11:39:35.860] <TB1> INFO: 41600 events read in total (3671ms).
[11:39:35.860] <TB1> INFO: Test took 4497ms.
[11:39:35.863] <TB1> INFO: scanning low vcal = 60
[11:39:36.137] <TB1> INFO: Expecting 41600 events.
[11:39:40.357] <TB1> INFO: 41600 events read in total (3664ms).
[11:39:40.357] <TB1> INFO: Test took 4494ms.
[11:39:40.360] <TB1> INFO: scanning low vcal = 70
[11:39:40.633] <TB1> INFO: Expecting 41600 events.
[11:39:44.849] <TB1> INFO: 41600 events read in total (3659ms).
[11:39:44.850] <TB1> INFO: Test took 4490ms.
[11:39:44.852] <TB1> INFO: scanning low vcal = 80
[11:39:45.116] <TB1> INFO: Expecting 41600 events.
[11:39:49.288] <TB1> INFO: 41600 events read in total (3616ms).
[11:39:49.289] <TB1> INFO: Test took 4437ms.
[11:39:49.291] <TB1> INFO: scanning low vcal = 90
[11:39:49.561] <TB1> INFO: Expecting 41600 events.
[11:39:53.808] <TB1> INFO: 41600 events read in total (3691ms).
[11:39:53.809] <TB1> INFO: Test took 4518ms.
[11:39:53.812] <TB1> INFO: scanning low vcal = 100
[11:39:54.076] <TB1> INFO: Expecting 41600 events.
[11:39:58.300] <TB1> INFO: 41600 events read in total (3668ms).
[11:39:58.301] <TB1> INFO: Test took 4489ms.
[11:39:58.303] <TB1> INFO: scanning low vcal = 110
[11:39:58.568] <TB1> INFO: Expecting 41600 events.
[11:40:02.771] <TB1> INFO: 41600 events read in total (3647ms).
[11:40:02.772] <TB1> INFO: Test took 4468ms.
[11:40:02.774] <TB1> INFO: scanning low vcal = 120
[11:40:03.039] <TB1> INFO: Expecting 41600 events.
[11:40:07.263] <TB1> INFO: 41600 events read in total (3668ms).
[11:40:07.264] <TB1> INFO: Test took 4490ms.
[11:40:07.266] <TB1> INFO: scanning low vcal = 130
[11:40:07.527] <TB1> INFO: Expecting 41600 events.
[11:40:11.783] <TB1> INFO: 41600 events read in total (3700ms).
[11:40:11.784] <TB1> INFO: Test took 4517ms.
[11:40:11.786] <TB1> INFO: scanning low vcal = 140
[11:40:12.076] <TB1> INFO: Expecting 41600 events.
[11:40:16.281] <TB1> INFO: 41600 events read in total (3649ms).
[11:40:16.281] <TB1> INFO: Test took 4494ms.
[11:40:16.284] <TB1> INFO: scanning low vcal = 150
[11:40:16.561] <TB1> INFO: Expecting 41600 events.
[11:40:20.737] <TB1> INFO: 41600 events read in total (3620ms).
[11:40:20.738] <TB1> INFO: Test took 4454ms.
[11:40:20.740] <TB1> INFO: scanning low vcal = 160
[11:40:21.002] <TB1> INFO: Expecting 41600 events.
[11:40:25.198] <TB1> INFO: 41600 events read in total (3640ms).
[11:40:25.198] <TB1> INFO: Test took 4458ms.
[11:40:25.200] <TB1> INFO: scanning low vcal = 170
[11:40:25.463] <TB1> INFO: Expecting 41600 events.
[11:40:29.696] <TB1> INFO: 41600 events read in total (3677ms).
[11:40:29.697] <TB1> INFO: Test took 4496ms.
[11:40:29.700] <TB1> INFO: scanning low vcal = 180
[11:40:29.971] <TB1> INFO: Expecting 41600 events.
[11:40:34.194] <TB1> INFO: 41600 events read in total (3667ms).
[11:40:34.195] <TB1> INFO: Test took 4495ms.
[11:40:34.197] <TB1> INFO: scanning low vcal = 190
[11:40:34.464] <TB1> INFO: Expecting 41600 events.
[11:40:38.671] <TB1> INFO: 41600 events read in total (3651ms).
[11:40:38.672] <TB1> INFO: Test took 4475ms.
[11:40:38.674] <TB1> INFO: scanning low vcal = 200
[11:40:38.948] <TB1> INFO: Expecting 41600 events.
[11:40:43.194] <TB1> INFO: 41600 events read in total (3690ms).
[11:40:43.195] <TB1> INFO: Test took 4521ms.
[11:40:43.197] <TB1> INFO: scanning low vcal = 210
[11:40:43.462] <TB1> INFO: Expecting 41600 events.
[11:40:47.691] <TB1> INFO: 41600 events read in total (3673ms).
[11:40:47.692] <TB1> INFO: Test took 4495ms.
[11:40:47.695] <TB1> INFO: scanning low vcal = 220
[11:40:47.968] <TB1> INFO: Expecting 41600 events.
[11:40:52.170] <TB1> INFO: 41600 events read in total (3646ms).
[11:40:52.170] <TB1> INFO: Test took 4475ms.
[11:40:52.173] <TB1> INFO: scanning low vcal = 230
[11:40:52.446] <TB1> INFO: Expecting 41600 events.
[11:40:56.650] <TB1> INFO: 41600 events read in total (3648ms).
[11:40:56.650] <TB1> INFO: Test took 4477ms.
[11:40:56.653] <TB1> INFO: scanning low vcal = 240
[11:40:56.935] <TB1> INFO: Expecting 41600 events.
[11:41:01.118] <TB1> INFO: 41600 events read in total (3627ms).
[11:41:01.119] <TB1> INFO: Test took 4466ms.
[11:41:01.121] <TB1> INFO: scanning low vcal = 250
[11:41:01.385] <TB1> INFO: Expecting 41600 events.
[11:41:05.568] <TB1> INFO: 41600 events read in total (3627ms).
[11:41:05.568] <TB1> INFO: Test took 4447ms.
[11:41:05.572] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[11:41:05.846] <TB1> INFO: Expecting 41600 events.
[11:41:10.025] <TB1> INFO: 41600 events read in total (3623ms).
[11:41:10.025] <TB1> INFO: Test took 4453ms.
[11:41:10.028] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[11:41:10.287] <TB1> INFO: Expecting 41600 events.
[11:41:14.457] <TB1> INFO: 41600 events read in total (3614ms).
[11:41:14.458] <TB1> INFO: Test took 4430ms.
[11:41:14.460] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[11:41:14.724] <TB1> INFO: Expecting 41600 events.
[11:41:18.899] <TB1> INFO: 41600 events read in total (3619ms).
[11:41:18.899] <TB1> INFO: Test took 4439ms.
[11:41:18.902] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[11:41:19.167] <TB1> INFO: Expecting 41600 events.
[11:41:23.364] <TB1> INFO: 41600 events read in total (3641ms).
[11:41:23.365] <TB1> INFO: Test took 4463ms.
[11:41:23.367] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:41:23.649] <TB1> INFO: Expecting 41600 events.
[11:41:27.831] <TB1> INFO: 41600 events read in total (3626ms).
[11:41:27.832] <TB1> INFO: Test took 4465ms.
[11:41:28.330] <TB1> INFO: PixTestGainPedestal::measure() done
[11:42:00.666] <TB1> INFO: PixTestGainPedestal::fit() done
[11:42:00.666] <TB1> INFO: non-linearity mean: 0.963 0.958 0.964 0.957 0.960 0.960 0.959 0.953 0.956 0.959 0.955 0.957 0.947 0.956 0.950 0.963
[11:42:00.666] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.005 0.007 0.007 0.005 0.006 0.007 0.006 0.006 0.008 0.006 0.007 0.006 0.007 0.006
[11:42:00.666] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[11:42:00.687] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[11:42:00.708] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[11:42:00.729] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[11:42:00.753] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[11:42:00.772] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[11:42:00.795] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[11:42:00.814] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[11:42:00.842] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[11:42:00.861] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[11:42:00.881] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[11:42:00.903] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[11:42:00.926] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[11:42:00.948] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[11:42:00.973] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[11:42:00.997] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[11:42:01.018] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[11:42:01.018] <TB1> INFO: Decoding statistics:
[11:42:01.018] <TB1> INFO: General information:
[11:42:01.018] <TB1> INFO: 16bit words read: 2662400
[11:42:01.018] <TB1> INFO: valid events total: 166400
[11:42:01.018] <TB1> INFO: empty events: 0
[11:42:01.018] <TB1> INFO: valid events with pixels: 166400
[11:42:01.018] <TB1> INFO: valid pixel hits: 665584
[11:42:01.018] <TB1> INFO: Event errors: 0
[11:42:01.018] <TB1> INFO: start marker: 0
[11:42:01.018] <TB1> INFO: stop marker: 0
[11:42:01.019] <TB1> INFO: overflow: 0
[11:42:01.019] <TB1> INFO: invalid 5bit words: 0
[11:42:01.019] <TB1> INFO: invalid XOR eye diagram: 0
[11:42:01.019] <TB1> INFO: frame (failed synchr.): 0
[11:42:01.019] <TB1> INFO: idle data (no TBM trl): 0
[11:42:01.019] <TB1> INFO: no data (only TBM hdr): 0
[11:42:01.019] <TB1> INFO: TBM errors: 0
[11:42:01.019] <TB1> INFO: flawed TBM headers: 0
[11:42:01.019] <TB1> INFO: flawed TBM trailers: 0
[11:42:01.019] <TB1> INFO: event ID mismatches: 0
[11:42:01.019] <TB1> INFO: ROC errors: 0
[11:42:01.019] <TB1> INFO: missing ROC header(s): 0
[11:42:01.019] <TB1> INFO: misplaced readback start: 0
[11:42:01.019] <TB1> INFO: Pixel decoding errors: 0
[11:42:01.019] <TB1> INFO: pixel data incomplete: 0
[11:42:01.019] <TB1> INFO: pixel address: 0
[11:42:01.019] <TB1> INFO: pulse height fill bit: 0
[11:42:01.019] <TB1> INFO: buffer corruption: 0
[11:42:01.027] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:42:01.028] <TB1> INFO: ######################################################################
[11:42:01.028] <TB1> INFO: PixTestReadback::doTest()
[11:42:01.028] <TB1> INFO: ######################################################################
[11:42:01.028] <TB1> INFO: ----------------------------------------------------------------------
[11:42:01.028] <TB1> INFO: PixTestReadback::CalibrateVd()
[11:42:01.028] <TB1> INFO: ----------------------------------------------------------------------
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:42:10.483] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:42:10.484] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:42:10.484] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:42:10.484] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:42:10.484] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:42:10.512] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:42:10.512] <TB1> INFO: ----------------------------------------------------------------------
[11:42:10.512] <TB1> INFO: PixTestReadback::CalibrateVa()
[11:42:10.512] <TB1> INFO: ----------------------------------------------------------------------
[11:42:19.985] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:42:19.985] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:42:19.985] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:42:19.985] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:42:19.986] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:42:20.014] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:42:20.014] <TB1> INFO: ----------------------------------------------------------------------
[11:42:20.014] <TB1> INFO: PixTestReadback::readbackVbg()
[11:42:20.014] <TB1> INFO: ----------------------------------------------------------------------
[11:42:27.316] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:42:27.316] <TB1> INFO: ----------------------------------------------------------------------
[11:42:27.316] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[11:42:27.316] <TB1> INFO: ----------------------------------------------------------------------
[11:42:27.316] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.4calibrated Vbg = 1.20489 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 145.2calibrated Vbg = 1.19643 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.4calibrated Vbg = 1.20907 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 159.1calibrated Vbg = 1.20736 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150calibrated Vbg = 1.21539 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 151.3calibrated Vbg = 1.21984 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.6calibrated Vbg = 1.21773 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 144.7calibrated Vbg = 1.21716 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.9calibrated Vbg = 1.21017 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157.8calibrated Vbg = 1.21371 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 147.3calibrated Vbg = 1.21203 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145.4calibrated Vbg = 1.20452 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156calibrated Vbg = 1.20334 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 144.9calibrated Vbg = 1.20368 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.1calibrated Vbg = 1.20511 :::*/*/*/*/
[11:42:27.316] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.9calibrated Vbg = 1.20266 :::*/*/*/*/
[11:42:27.318] <TB1> INFO: ----------------------------------------------------------------------
[11:42:27.318] <TB1> INFO: PixTestReadback::CalibrateIa()
[11:42:27.318] <TB1> INFO: ----------------------------------------------------------------------
[11:45:02.576] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:45:02.576] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:45:02.577] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:45:02.578] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:45:02.578] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2272_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:45:02.607] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:45:02.608] <TB1> INFO: PixTestReadback::doTest() done
[11:45:02.608] <TB1> INFO: Decoding statistics:
[11:45:02.608] <TB1> INFO: General information:
[11:45:02.608] <TB1> INFO: 16bit words read: 1024
[11:45:02.608] <TB1> INFO: valid events total: 128
[11:45:02.609] <TB1> INFO: empty events: 128
[11:45:02.609] <TB1> INFO: valid events with pixels: 0
[11:45:02.609] <TB1> INFO: valid pixel hits: 0
[11:45:02.609] <TB1> INFO: Event errors: 0
[11:45:02.609] <TB1> INFO: start marker: 0
[11:45:02.609] <TB1> INFO: stop marker: 0
[11:45:02.609] <TB1> INFO: overflow: 0
[11:45:02.609] <TB1> INFO: invalid 5bit words: 0
[11:45:02.609] <TB1> INFO: invalid XOR eye diagram: 0
[11:45:02.609] <TB1> INFO: frame (failed synchr.): 0
[11:45:02.609] <TB1> INFO: idle data (no TBM trl): 0
[11:45:02.609] <TB1> INFO: no data (only TBM hdr): 0
[11:45:02.609] <TB1> INFO: TBM errors: 0
[11:45:02.609] <TB1> INFO: flawed TBM headers: 0
[11:45:02.609] <TB1> INFO: flawed TBM trailers: 0
[11:45:02.609] <TB1> INFO: event ID mismatches: 0
[11:45:02.609] <TB1> INFO: ROC errors: 0
[11:45:02.609] <TB1> INFO: missing ROC header(s): 0
[11:45:02.609] <TB1> INFO: misplaced readback start: 0
[11:45:02.609] <TB1> INFO: Pixel decoding errors: 0
[11:45:02.609] <TB1> INFO: pixel data incomplete: 0
[11:45:02.609] <TB1> INFO: pixel address: 0
[11:45:02.609] <TB1> INFO: pulse height fill bit: 0
[11:45:02.609] <TB1> INFO: buffer corruption: 0
[11:45:02.635] <TB1> INFO: Decoding statistics:
[11:45:02.635] <TB1> INFO: General information:
[11:45:02.635] <TB1> INFO: 16bit words read: 2755352
[11:45:02.635] <TB1> INFO: valid events total: 176768
[11:45:02.635] <TB1> INFO: empty events: 7866
[11:45:02.635] <TB1> INFO: valid events with pixels: 168902
[11:45:02.635] <TB1> INFO: valid pixel hits: 668086
[11:45:02.635] <TB1> INFO: Event errors: 0
[11:45:02.635] <TB1> INFO: start marker: 0
[11:45:02.635] <TB1> INFO: stop marker: 0
[11:45:02.635] <TB1> INFO: overflow: 0
[11:45:02.635] <TB1> INFO: invalid 5bit words: 0
[11:45:02.635] <TB1> INFO: invalid XOR eye diagram: 0
[11:45:02.635] <TB1> INFO: frame (failed synchr.): 0
[11:45:02.635] <TB1> INFO: idle data (no TBM trl): 0
[11:45:02.635] <TB1> INFO: no data (only TBM hdr): 0
[11:45:02.635] <TB1> INFO: TBM errors: 0
[11:45:02.635] <TB1> INFO: flawed TBM headers: 0
[11:45:02.635] <TB1> INFO: flawed TBM trailers: 0
[11:45:02.635] <TB1> INFO: event ID mismatches: 0
[11:45:02.635] <TB1> INFO: ROC errors: 0
[11:45:02.635] <TB1> INFO: missing ROC header(s): 0
[11:45:02.636] <TB1> INFO: misplaced readback start: 0
[11:45:02.636] <TB1> INFO: Pixel decoding errors: 0
[11:45:02.636] <TB1> INFO: pixel data incomplete: 0
[11:45:02.636] <TB1> INFO: pixel address: 0
[11:45:02.636] <TB1> INFO: pulse height fill bit: 0
[11:45:02.636] <TB1> INFO: buffer corruption: 0
[11:45:02.636] <TB1> INFO: enter test to run
[11:45:02.636] <TB1> INFO: test: exit no parameter change
[11:45:02.769] <TB1> QUIET: Connection to board 153 closed.
[11:45:02.849] <TB1> INFO: pXar: this is the end, my friend