[12:03:07.981] <TB3> INFO: *** Welcome to pxar ***
[12:03:07.981] <TB3> INFO: *** Today: 2016/06/29
[12:03:08.068] <TB3> INFO: *** Version: 9751-dirty
[12:03:08.068] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C15.dat
[12:03:08.068] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:03:08.068] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//defaultMaskFile.dat
[12:03:08.069] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters_C15.dat
[12:03:08.137] <TB3> INFO: clk: 4
[12:03:08.137] <TB3> INFO: ctr: 4
[12:03:08.137] <TB3> INFO: sda: 19
[12:03:08.137] <TB3> INFO: tin: 9
[12:03:08.137] <TB3> INFO: level: 15
[12:03:08.137] <TB3> INFO: triggerdelay: 0
[12:03:08.137] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[12:03:08.137] <TB3> INFO: Log level: INFO
[12:03:08.144] <TB3> INFO: Found DTB DTB_WZ4I6J
[12:03:08.156] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[12:03:08.158] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[12:03:08.160] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[12:03:09.705] <TB3> INFO: DUT info:
[12:03:09.705] <TB3> INFO: The DUT currently contains the following objects:
[12:03:09.705] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[12:03:09.705] <TB3> INFO: TBM Core alpha (0): 7 registers set
[12:03:09.705] <TB3> INFO: TBM Core beta (1): 7 registers set
[12:03:09.705] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:03:09.705] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.705] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.706] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.706] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:09.706] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:03:10.107] <TB3> INFO: enter 'restricted' command line mode
[12:03:10.107] <TB3> INFO: enter test to run
[12:03:10.107] <TB3> INFO: test: pretest no parameter change
[12:03:10.107] <TB3> INFO: running: pretest
[12:03:10.115] <TB3> INFO: ----------------------------------------------------------------------
[12:03:10.116] <TB3> INFO: PixTestPretest::programROC()
[12:03:10.116] <TB3> INFO: ----------------------------------------------------------------------
[12:03:28.130] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:03:28.130] <TB3> INFO: IA differences per ROC: 15.3 17.7 19.3 21.7 20.1 20.1 19.3 18.5 20.1 19.3 16.9 20.1 17.7 19.3 20.1 20.9
[12:03:28.230] <TB3> INFO: enter test to run
[12:03:28.230] <TB3> INFO: test: pretest no parameter change
[12:03:28.230] <TB3> INFO: running: pretest
[12:03:28.231] <TB3> INFO: ----------------------------------------------------------------------
[12:03:28.231] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:03:28.231] <TB3> INFO: ----------------------------------------------------------------------
[12:03:34.318] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 381 mA = 23.8125 mA/ROC
[12:03:34.318] <TB3> INFO: i(loss) [mA/ROC]: 20.1 19.2 19.2 19.2 19.2 19.2 19.2 19.2 18.4 19.2 19.2 19.2 19.2 19.2 19.2 19.2
[12:03:34.354] <TB3> INFO: enter test to run
[12:03:34.354] <TB3> INFO: test: pretest no parameter change
[12:03:34.354] <TB3> INFO: running: pretest
[12:03:34.355] <TB3> INFO: ----------------------------------------------------------------------
[12:03:34.355] <TB3> INFO: PixTestPretest::findTiming()
[12:03:34.355] <TB3> INFO: ----------------------------------------------------------------------
[12:03:34.355] <TB3> INFO: PixTestCmd::init()

[12:05:04.285] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[12:05:04.285] <TB3> INFO: (success/tries = 100/100), width = 3
[12:05:04.286] <TB3> INFO: enter test to run
[12:05:04.286] <TB3> INFO: test: pretest no parameter change
[12:05:04.287] <TB3> INFO: running: pretest
[12:05:04.288] <TB3> INFO: ----------------------------------------------------------------------
[12:05:04.288] <TB3> INFO: PixTestPretest::findWorkingPixel()
[12:05:04.288] <TB3> INFO: ----------------------------------------------------------------------
[12:05:04.383] <TB3> INFO: Expecting 231680 events.


[12:05:14.700] <TB3> INFO: 231680 events read in total (9761ms).
[12:05:14.705] <TB3> INFO: Test took 10411ms.
[12:05:14.936] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:05:14.997] <TB3> INFO: enter test to run
[12:05:14.997] <TB3> INFO: test: pretest no parameter change
[12:05:14.997] <TB3> INFO: running: pretest
[12:05:14.998] <TB3> INFO: ----------------------------------------------------------------------
[12:05:14.998] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[12:05:14.998] <TB3> INFO: ----------------------------------------------------------------------
[12:05:15.089] <TB3> INFO: Expecting 231680 events.
[12:05:25.445] <TB3> INFO: 231680 events read in total (9800ms).
[12:05:25.449] <TB3> INFO: Test took 10448ms.
[12:05:25.688] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[12:05:25.688] <TB3> INFO: CalDel: 128 179 151 171 116 131 127 138 155 128 123 153 135 139 140 148
[12:05:25.688] <TB3> INFO: VthrComp: 51 52 55 51 51 51 51 51 51 51 54 51 51 51 51 51
[12:05:25.723] <TB3> INFO: enter test to run
[12:05:25.723] <TB3> INFO: test: pretest no parameter change
[12:05:25.723] <TB3> INFO: running: pretest
[12:05:25.723] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C0.dat
[12:05:25.724] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C1.dat
[12:05:25.724] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C2.dat
[12:05:25.724] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C3.dat
[12:05:25.724] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C4.dat
[12:05:25.725] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C5.dat
[12:05:25.725] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C6.dat
[12:05:25.725] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C7.dat
[12:05:25.725] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C8.dat
[12:05:25.725] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C9.dat
[12:05:25.726] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C10.dat
[12:05:25.726] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C11.dat
[12:05:25.726] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C12.dat
[12:05:25.727] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C13.dat
[12:05:25.727] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C14.dat
[12:05:25.727] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C15.dat
[12:05:25.727] <TB3> INFO: enter test to run
[12:05:25.727] <TB3> INFO: test: fulltest no parameter change
[12:05:25.727] <TB3> INFO: running: fulltest
[12:05:25.728] <TB3> INFO: ######################################################################
[12:05:25.728] <TB3> INFO: PixTestFullTest::doTest()
[12:05:25.728] <TB3> INFO: ######################################################################
[12:05:25.729] <TB3> INFO: ######################################################################
[12:05:25.729] <TB3> INFO: PixTestAlive::doTest()
[12:05:25.729] <TB3> INFO: ######################################################################
[12:05:25.730] <TB3> INFO: ----------------------------------------------------------------------
[12:05:25.730] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:05:25.730] <TB3> INFO: ----------------------------------------------------------------------
[12:05:26.004] <TB3> INFO: Expecting 41600 events.
[12:05:29.847] <TB3> INFO: 41600 events read in total (3287ms).
[12:05:29.847] <TB3> INFO: Test took 4116ms.
[12:05:30.073] <TB3> INFO: PixTestAlive::aliveTest() done
[12:05:30.073] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:05:30.074] <TB3> INFO: ----------------------------------------------------------------------
[12:05:30.074] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:05:30.074] <TB3> INFO: ----------------------------------------------------------------------
[12:05:30.338] <TB3> INFO: Expecting 41600 events.
[12:05:33.312] <TB3> INFO: 41600 events read in total (2418ms).
[12:05:33.312] <TB3> INFO: Test took 3237ms.
[12:05:33.312] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:05:33.551] <TB3> INFO: PixTestAlive::maskTest() done
[12:05:33.551] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:05:33.553] <TB3> INFO: ----------------------------------------------------------------------
[12:05:33.553] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:05:33.553] <TB3> INFO: ----------------------------------------------------------------------
[12:05:33.826] <TB3> INFO: Expecting 41600 events.
[12:05:37.699] <TB3> INFO: 41600 events read in total (3316ms).
[12:05:37.700] <TB3> INFO: Test took 4146ms.
[12:05:37.929] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[12:05:37.929] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:05:37.929] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[12:05:37.929] <TB3> INFO: Decoding statistics:
[12:05:37.929] <TB3> INFO: General information:
[12:05:37.929] <TB3> INFO: 16bit words read: 0
[12:05:37.929] <TB3> INFO: valid events total: 0
[12:05:37.929] <TB3> INFO: empty events: 0
[12:05:37.929] <TB3> INFO: valid events with pixels: 0
[12:05:37.929] <TB3> INFO: valid pixel hits: 0
[12:05:37.929] <TB3> INFO: Event errors: 0
[12:05:37.929] <TB3> INFO: start marker: 0
[12:05:37.929] <TB3> INFO: stop marker: 0
[12:05:37.929] <TB3> INFO: overflow: 0
[12:05:37.929] <TB3> INFO: invalid 5bit words: 0
[12:05:37.929] <TB3> INFO: invalid XOR eye diagram: 0
[12:05:37.929] <TB3> INFO: frame (failed synchr.): 0
[12:05:37.929] <TB3> INFO: idle data (no TBM trl): 0
[12:05:37.929] <TB3> INFO: no data (only TBM hdr): 0
[12:05:37.929] <TB3> INFO: TBM errors: 0
[12:05:37.929] <TB3> INFO: flawed TBM headers: 0
[12:05:37.929] <TB3> INFO: flawed TBM trailers: 0
[12:05:37.929] <TB3> INFO: event ID mismatches: 0
[12:05:37.930] <TB3> INFO: ROC errors: 0
[12:05:37.930] <TB3> INFO: missing ROC header(s): 0
[12:05:37.930] <TB3> INFO: misplaced readback start: 0
[12:05:37.930] <TB3> INFO: Pixel decoding errors: 0
[12:05:37.930] <TB3> INFO: pixel data incomplete: 0
[12:05:37.930] <TB3> INFO: pixel address: 0
[12:05:37.930] <TB3> INFO: pulse height fill bit: 0
[12:05:37.930] <TB3> INFO: buffer corruption: 0
[12:05:37.936] <TB3> INFO: ######################################################################
[12:05:37.936] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:05:37.936] <TB3> INFO: ######################################################################
[12:05:37.939] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:05:37.950] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:05:37.950] <TB3> INFO: run 1 of 1
[12:05:38.215] <TB3> INFO: Expecting 3120000 events.
[12:06:19.823] <TB3> INFO: 893640 events read in total (41051ms).
[12:07:00.289] <TB3> INFO: 1765975 events read in total (81518ms).
[12:07:41.125] <TB3> INFO: 2648120 events read in total (122354ms).
[12:08:03.650] <TB3> INFO: 3120000 events read in total (144878ms).
[12:08:03.698] <TB3> INFO: Test took 145748ms.
[12:08:30.493] <TB3> INFO: PixTestBBMap::doTest() done, duration: 172 seconds
[12:08:30.493] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:08:30.493] <TB3> INFO: separation cut (per ROC): 103 119 121 97 125 122 101 110 102 128 116 111 108 116 112 102
[12:08:30.493] <TB3> INFO: Decoding statistics:
[12:08:30.493] <TB3> INFO: General information:
[12:08:30.494] <TB3> INFO: 16bit words read: 0
[12:08:30.494] <TB3> INFO: valid events total: 0
[12:08:30.494] <TB3> INFO: empty events: 0
[12:08:30.494] <TB3> INFO: valid events with pixels: 0
[12:08:30.494] <TB3> INFO: valid pixel hits: 0
[12:08:30.494] <TB3> INFO: Event errors: 0
[12:08:30.494] <TB3> INFO: start marker: 0
[12:08:30.494] <TB3> INFO: stop marker: 0
[12:08:30.494] <TB3> INFO: overflow: 0
[12:08:30.494] <TB3> INFO: invalid 5bit words: 0
[12:08:30.494] <TB3> INFO: invalid XOR eye diagram: 0
[12:08:30.494] <TB3> INFO: frame (failed synchr.): 0
[12:08:30.494] <TB3> INFO: idle data (no TBM trl): 0
[12:08:30.494] <TB3> INFO: no data (only TBM hdr): 0
[12:08:30.494] <TB3> INFO: TBM errors: 0
[12:08:30.494] <TB3> INFO: flawed TBM headers: 0
[12:08:30.494] <TB3> INFO: flawed TBM trailers: 0
[12:08:30.494] <TB3> INFO: event ID mismatches: 0
[12:08:30.494] <TB3> INFO: ROC errors: 0
[12:08:30.494] <TB3> INFO: missing ROC header(s): 0
[12:08:30.494] <TB3> INFO: misplaced readback start: 0
[12:08:30.494] <TB3> INFO: Pixel decoding errors: 0
[12:08:30.494] <TB3> INFO: pixel data incomplete: 0
[12:08:30.494] <TB3> INFO: pixel address: 0
[12:08:30.494] <TB3> INFO: pulse height fill bit: 0
[12:08:30.494] <TB3> INFO: buffer corruption: 0
[12:08:30.575] <TB3> INFO: ######################################################################
[12:08:30.575] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:08:30.575] <TB3> INFO: ######################################################################
[12:08:30.576] <TB3> INFO: ----------------------------------------------------------------------
[12:08:30.576] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:08:30.576] <TB3> INFO: ----------------------------------------------------------------------
[12:08:30.576] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:08:30.584] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[12:08:30.584] <TB3> INFO: run 1 of 1
[12:08:30.859] <TB3> INFO: Expecting 26208000 events.
[12:09:04.666] <TB3> INFO: 928150 events read in total (33249ms).
[12:09:38.327] <TB3> INFO: 1838750 events read in total (66910ms).
[12:10:12.026] <TB3> INFO: 2746250 events read in total (100609ms).
[12:10:45.547] <TB3> INFO: 3656550 events read in total (134130ms).
[12:11:19.032] <TB3> INFO: 4564150 events read in total (167615ms).
[12:11:52.273] <TB3> INFO: 5468200 events read in total (200856ms).
[12:12:25.775] <TB3> INFO: 6374950 events read in total (234358ms).
[12:12:59.478] <TB3> INFO: 7280600 events read in total (268061ms).
[12:13:32.705] <TB3> INFO: 8186100 events read in total (301288ms).
[12:14:06.126] <TB3> INFO: 9091800 events read in total (334709ms).
[12:14:39.206] <TB3> INFO: 9994950 events read in total (367789ms).
[12:15:12.531] <TB3> INFO: 10897850 events read in total (401114ms).
[12:15:45.738] <TB3> INFO: 11800100 events read in total (434321ms).
[12:16:19.127] <TB3> INFO: 12701700 events read in total (467710ms).
[12:16:52.457] <TB3> INFO: 13596500 events read in total (501040ms).
[12:17:25.799] <TB3> INFO: 14486850 events read in total (534382ms).
[12:17:59.033] <TB3> INFO: 15377700 events read in total (567616ms).
[12:18:32.222] <TB3> INFO: 16266700 events read in total (600805ms).
[12:19:05.741] <TB3> INFO: 17155000 events read in total (634324ms).
[12:19:39.212] <TB3> INFO: 18042550 events read in total (667795ms).
[12:20:12.721] <TB3> INFO: 18931550 events read in total (701304ms).
[12:20:46.042] <TB3> INFO: 19819100 events read in total (734625ms).
[12:21:20.287] <TB3> INFO: 20707000 events read in total (768870ms).
[12:21:54.464] <TB3> INFO: 21593350 events read in total (803047ms).
[12:22:29.251] <TB3> INFO: 22478150 events read in total (837834ms).
[12:23:02.905] <TB3> INFO: 23368150 events read in total (871488ms).
[12:23:36.164] <TB3> INFO: 24254750 events read in total (904747ms).
[12:24:09.525] <TB3> INFO: 25143100 events read in total (938108ms).
[12:24:43.107] <TB3> INFO: 26044750 events read in total (971690ms).
[12:24:49.494] <TB3> INFO: 26208000 events read in total (978077ms).
[12:24:49.525] <TB3> INFO: Test took 978941ms.
[12:24:49.836] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:24:51.378] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:24:52.836] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:24:54.286] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:24:56.013] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:24:57.590] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:24:59.288] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:00.920] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:02.379] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:03.916] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:05.626] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:07.222] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:08.803] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:10.453] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:11.928] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:13.419] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:25:14.938] <TB3> INFO: PixTestScurves::scurves() done
[12:25:14.938] <TB3> INFO: Vcal mean: 84.31 105.08 104.33 82.71 104.49 93.19 87.56 81.63 81.83 103.08 101.69 89.48 88.39 106.06 87.41 84.93
[12:25:14.938] <TB3> INFO: Vcal RMS: 5.19 6.80 7.64 4.51 5.63 5.42 5.26 4.08 4.14 5.21 6.15 5.94 5.13 5.99 5.58 5.06
[12:25:14.939] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1004 seconds
[12:25:14.939] <TB3> INFO: Decoding statistics:
[12:25:14.939] <TB3> INFO: General information:
[12:25:14.939] <TB3> INFO: 16bit words read: 0
[12:25:14.939] <TB3> INFO: valid events total: 0
[12:25:14.939] <TB3> INFO: empty events: 0
[12:25:14.939] <TB3> INFO: valid events with pixels: 0
[12:25:14.939] <TB3> INFO: valid pixel hits: 0
[12:25:14.939] <TB3> INFO: Event errors: 0
[12:25:14.939] <TB3> INFO: start marker: 0
[12:25:14.939] <TB3> INFO: stop marker: 0
[12:25:14.939] <TB3> INFO: overflow: 0
[12:25:14.939] <TB3> INFO: invalid 5bit words: 0
[12:25:14.939] <TB3> INFO: invalid XOR eye diagram: 0
[12:25:14.939] <TB3> INFO: frame (failed synchr.): 0
[12:25:14.939] <TB3> INFO: idle data (no TBM trl): 0
[12:25:14.939] <TB3> INFO: no data (only TBM hdr): 0
[12:25:14.939] <TB3> INFO: TBM errors: 0
[12:25:14.939] <TB3> INFO: flawed TBM headers: 0
[12:25:14.939] <TB3> INFO: flawed TBM trailers: 0
[12:25:14.939] <TB3> INFO: event ID mismatches: 0
[12:25:14.939] <TB3> INFO: ROC errors: 0
[12:25:14.939] <TB3> INFO: missing ROC header(s): 0
[12:25:14.939] <TB3> INFO: misplaced readback start: 0
[12:25:14.939] <TB3> INFO: Pixel decoding errors: 0
[12:25:14.939] <TB3> INFO: pixel data incomplete: 0
[12:25:14.939] <TB3> INFO: pixel address: 0
[12:25:14.939] <TB3> INFO: pulse height fill bit: 0
[12:25:14.939] <TB3> INFO: buffer corruption: 0
[12:25:15.015] <TB3> INFO: ######################################################################
[12:25:15.015] <TB3> INFO: PixTestTrim::doTest()
[12:25:15.015] <TB3> INFO: ######################################################################
[12:25:15.016] <TB3> INFO: ----------------------------------------------------------------------
[12:25:15.016] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:25:15.016] <TB3> INFO: ----------------------------------------------------------------------
[12:25:15.096] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:25:15.096] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:25:15.104] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:25:15.104] <TB3> INFO: run 1 of 1
[12:25:15.369] <TB3> INFO: Expecting 6281600 events.
[12:25:55.165] <TB3> INFO: 1080540 events read in total (39240ms).
[12:26:34.410] <TB3> INFO: 2152490 events read in total (78485ms).
[12:27:13.730] <TB3> INFO: 3217600 events read in total (117805ms).
[12:27:53.038] <TB3> INFO: 4288760 events read in total (157113ms).
[12:28:32.789] <TB3> INFO: 5362760 events read in total (196864ms).
[12:29:06.422] <TB3> INFO: 6281600 events read in total (230497ms).
[12:29:06.455] <TB3> INFO: Test took 231351ms.
[12:29:26.534] <TB3> INFO: ROC 0 VthrComp = 86
[12:29:26.535] <TB3> INFO: ROC 1 VthrComp = 97
[12:29:26.535] <TB3> INFO: ROC 2 VthrComp = 100
[12:29:26.535] <TB3> INFO: ROC 3 VthrComp = 85
[12:29:26.535] <TB3> INFO: ROC 4 VthrComp = 105
[12:29:26.535] <TB3> INFO: ROC 5 VthrComp = 100
[12:29:26.535] <TB3> INFO: ROC 6 VthrComp = 92
[12:29:26.535] <TB3> INFO: ROC 7 VthrComp = 88
[12:29:26.535] <TB3> INFO: ROC 8 VthrComp = 83
[12:29:26.535] <TB3> INFO: ROC 9 VthrComp = 104
[12:29:26.535] <TB3> INFO: ROC 10 VthrComp = 101
[12:29:26.536] <TB3> INFO: ROC 11 VthrComp = 88
[12:29:26.536] <TB3> INFO: ROC 12 VthrComp = 90
[12:29:26.536] <TB3> INFO: ROC 13 VthrComp = 101
[12:29:26.536] <TB3> INFO: ROC 14 VthrComp = 90
[12:29:26.536] <TB3> INFO: ROC 15 VthrComp = 89
[12:29:26.536] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:29:26.536] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:29:26.544] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:29:26.544] <TB3> INFO: run 1 of 1
[12:29:26.803] <TB3> INFO: Expecting 6281600 events.
[12:30:02.355] <TB3> INFO: 758950 events read in total (34996ms).
[12:30:37.238] <TB3> INFO: 1515830 events read in total (69879ms).
[12:31:12.378] <TB3> INFO: 2273310 events read in total (105019ms).
[12:31:46.993] <TB3> INFO: 3030530 events read in total (139634ms).
[12:32:21.791] <TB3> INFO: 3782180 events read in total (174432ms).
[12:32:56.668] <TB3> INFO: 4530420 events read in total (209309ms).
[12:33:31.725] <TB3> INFO: 5276690 events read in total (244366ms).
[12:34:06.241] <TB3> INFO: 6022780 events read in total (278882ms).
[12:34:18.732] <TB3> INFO: 6281600 events read in total (291373ms).
[12:34:18.771] <TB3> INFO: Test took 292227ms.
[12:34:46.325] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 59.6003 for pixel 9/55 mean/min/max = 46.0274/32.376/59.6789
[12:34:46.325] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 69.043 for pixel 0/14 mean/min/max = 50.1089/31.1247/69.0931
[12:34:46.325] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 67.9883 for pixel 0/13 mean/min/max = 49.7055/31.144/68.2671
[12:34:46.326] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.083 for pixel 49/29 mean/min/max = 45.1814/32.9824/57.3803
[12:34:46.326] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 61.8904 for pixel 23/2 mean/min/max = 47.9917/33.971/62.0125
[12:34:46.326] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 58.9233 for pixel 10/0 mean/min/max = 45.4361/31.9456/58.9266
[12:34:46.326] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.8039 for pixel 6/20 mean/min/max = 45.1808/32.1404/58.2212
[12:34:46.327] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 55.1209 for pixel 0/2 mean/min/max = 44.012/32.5031/55.521
[12:34:46.327] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 57.542 for pixel 24/0 mean/min/max = 45.4402/33.2475/57.6329
[12:34:46.327] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.982 for pixel 14/16 mean/min/max = 47.0072/34.0317/59.9828
[12:34:46.328] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 61.5603 for pixel 28/79 mean/min/max = 46.6052/31.5661/61.6444
[12:34:46.328] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 62.2064 for pixel 6/41 mean/min/max = 47.4731/32.6647/62.2816
[12:34:46.328] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 58.0381 for pixel 17/36 mean/min/max = 45.7587/33.3913/58.1261
[12:34:46.328] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 64.8329 for pixel 14/76 mean/min/max = 48.3373/31.6931/64.9815
[12:34:46.329] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.1711 for pixel 51/67 mean/min/max = 45.8822/32.4536/59.3107
[12:34:46.329] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.1915 for pixel 0/22 mean/min/max = 45.325/32.4305/58.2195
[12:34:46.329] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:34:46.418] <TB3> INFO: Expecting 514560 events.
[12:34:59.446] <TB3> INFO: 514560 events read in total (12472ms).
[12:34:59.452] <TB3> INFO: Expecting 514560 events.
[12:35:11.948] <TB3> INFO: 514560 events read in total (12090ms).
[12:35:11.954] <TB3> INFO: Expecting 514560 events.
[12:35:24.581] <TB3> INFO: 514560 events read in total (12212ms).
[12:35:24.589] <TB3> INFO: Expecting 514560 events.
[12:35:37.434] <TB3> INFO: 514560 events read in total (12425ms).
[12:35:37.444] <TB3> INFO: Expecting 514560 events.
[12:35:50.413] <TB3> INFO: 514560 events read in total (12556ms).
[12:35:50.425] <TB3> INFO: Expecting 514560 events.
[12:36:03.519] <TB3> INFO: 514560 events read in total (12683ms).
[12:36:03.535] <TB3> INFO: Expecting 514560 events.
[12:36:16.363] <TB3> INFO: 514560 events read in total (12426ms).
[12:36:16.379] <TB3> INFO: Expecting 514560 events.
[12:36:29.333] <TB3> INFO: 514560 events read in total (12544ms).
[12:36:29.352] <TB3> INFO: Expecting 514560 events.
[12:36:42.190] <TB3> INFO: 514560 events read in total (12432ms).
[12:36:42.211] <TB3> INFO: Expecting 514560 events.
[12:36:55.091] <TB3> INFO: 514560 events read in total (12471ms).
[12:36:55.112] <TB3> INFO: Expecting 514560 events.
[12:37:07.857] <TB3> INFO: 514560 events read in total (12340ms).
[12:37:07.879] <TB3> INFO: Expecting 514560 events.
[12:37:20.702] <TB3> INFO: 514560 events read in total (12413ms).
[12:37:20.728] <TB3> INFO: Expecting 514560 events.
[12:37:33.615] <TB3> INFO: 514560 events read in total (12481ms).
[12:37:33.642] <TB3> INFO: Expecting 514560 events.
[12:37:46.488] <TB3> INFO: 514560 events read in total (12444ms).
[12:37:46.517] <TB3> INFO: Expecting 514560 events.
[12:37:59.313] <TB3> INFO: 514560 events read in total (12395ms).
[12:37:59.344] <TB3> INFO: Expecting 514560 events.
[12:38:12.191] <TB3> INFO: 514560 events read in total (12446ms).
[12:38:12.224] <TB3> INFO: Test took 205895ms.
[12:38:13.159] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:38:13.167] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:38:13.167] <TB3> INFO: run 1 of 1
[12:38:13.432] <TB3> INFO: Expecting 6281600 events.
[12:38:48.932] <TB3> INFO: 742760 events read in total (34944ms).
[12:39:23.557] <TB3> INFO: 1484160 events read in total (69569ms).
[12:39:58.101] <TB3> INFO: 2226170 events read in total (104113ms).
[12:40:33.188] <TB3> INFO: 2967840 events read in total (139200ms).
[12:41:08.166] <TB3> INFO: 3703920 events read in total (174178ms).
[12:41:43.146] <TB3> INFO: 4436020 events read in total (209158ms).
[12:42:17.322] <TB3> INFO: 5166160 events read in total (243334ms).
[12:42:52.289] <TB3> INFO: 5895070 events read in total (278301ms).
[12:43:10.786] <TB3> INFO: 6281600 events read in total (296798ms).
[12:43:10.828] <TB3> INFO: Test took 297661ms.
[12:43:35.304] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.025612 .. 255.000000
[12:43:35.379] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:43:35.387] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:43:35.387] <TB3> INFO: run 1 of 1
[12:43:35.647] <TB3> INFO: Expecting 10649600 events.
[12:44:10.969] <TB3> INFO: 712000 events read in total (34766ms).
[12:44:45.243] <TB3> INFO: 1424050 events read in total (69040ms).
[12:45:19.423] <TB3> INFO: 2136160 events read in total (103220ms).
[12:45:53.887] <TB3> INFO: 2848440 events read in total (137684ms).
[12:46:28.106] <TB3> INFO: 3560890 events read in total (171903ms).
[12:47:02.146] <TB3> INFO: 4273660 events read in total (205943ms).
[12:47:36.901] <TB3> INFO: 4985990 events read in total (240698ms).
[12:48:10.877] <TB3> INFO: 5698590 events read in total (274674ms).
[12:48:45.130] <TB3> INFO: 6410900 events read in total (308927ms).
[12:49:19.451] <TB3> INFO: 7122560 events read in total (343248ms).
[12:49:53.446] <TB3> INFO: 7833930 events read in total (377243ms).
[12:50:27.977] <TB3> INFO: 8545120 events read in total (411774ms).
[12:51:02.106] <TB3> INFO: 9256060 events read in total (445903ms).
[12:51:36.755] <TB3> INFO: 9967160 events read in total (480552ms).
[12:52:10.989] <TB3> INFO: 10649600 events read in total (514786ms).
[12:52:11.121] <TB3> INFO: Test took 515734ms.
[12:52:45.675] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 13.694474 .. 47.879448
[12:52:45.752] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 3 .. 57 (-1/-1) hits flags = 528 (plus default)
[12:52:45.760] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:52:45.760] <TB3> INFO: run 1 of 1
[12:52:46.031] <TB3> INFO: Expecting 2288000 events.
[12:53:24.692] <TB3> INFO: 932720 events read in total (38105ms).
[12:54:02.795] <TB3> INFO: 1864780 events read in total (76208ms).
[12:54:20.164] <TB3> INFO: 2288000 events read in total (93577ms).
[12:54:20.182] <TB3> INFO: Test took 94422ms.
[12:54:35.593] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 18.758615 .. 45.999771
[12:54:35.685] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 8 .. 55 (-1/-1) hits flags = 528 (plus default)
[12:54:35.696] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:54:35.696] <TB3> INFO: run 1 of 1
[12:54:35.981] <TB3> INFO: Expecting 1996800 events.
[12:55:14.472] <TB3> INFO: 922840 events read in total (37935ms).
[12:55:52.515] <TB3> INFO: 1844450 events read in total (75978ms).
[12:55:58.937] <TB3> INFO: 1996800 events read in total (82401ms).
[12:55:58.954] <TB3> INFO: Test took 83258ms.
[12:56:12.958] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 1.773223 .. 45.418932
[12:56:13.033] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 55 (-1/-1) hits flags = 528 (plus default)
[12:56:13.041] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:56:13.041] <TB3> INFO: run 1 of 1
[12:56:13.301] <TB3> INFO: Expecting 2288000 events.
[12:56:51.750] <TB3> INFO: 960080 events read in total (37893ms).
[12:57:29.771] <TB3> INFO: 1919760 events read in total (75914ms).
[12:57:44.497] <TB3> INFO: 2288000 events read in total (90641ms).
[12:57:44.516] <TB3> INFO: Test took 91475ms.
[12:57:59.010] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[12:57:59.010] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[12:57:59.018] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:57:59.018] <TB3> INFO: run 1 of 1
[12:57:59.292] <TB3> INFO: Expecting 1705600 events.
[12:58:36.800] <TB3> INFO: 877780 events read in total (36952ms).
[12:59:12.010] <TB3> INFO: 1705600 events read in total (72162ms).
[12:59:12.023] <TB3> INFO: Test took 73005ms.
[12:59:25.430] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C0.dat
[12:59:25.431] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C1.dat
[12:59:25.431] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C2.dat
[12:59:25.431] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C3.dat
[12:59:25.431] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C4.dat
[12:59:25.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C5.dat
[12:59:25.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C6.dat
[12:59:25.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C7.dat
[12:59:25.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C8.dat
[12:59:25.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C9.dat
[12:59:25.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C10.dat
[12:59:25.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C11.dat
[12:59:25.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C12.dat
[12:59:25.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C13.dat
[12:59:25.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C14.dat
[12:59:25.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C15.dat
[12:59:25.434] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C0.dat
[12:59:25.453] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C1.dat
[12:59:25.467] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C2.dat
[12:59:25.479] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C3.dat
[12:59:25.490] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C4.dat
[12:59:25.501] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C5.dat
[12:59:25.511] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C6.dat
[12:59:25.522] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C7.dat
[12:59:25.534] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C8.dat
[12:59:25.545] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C9.dat
[12:59:25.556] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C10.dat
[12:59:25.567] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C11.dat
[12:59:25.578] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C12.dat
[12:59:25.590] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C13.dat
[12:59:25.601] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C14.dat
[12:59:25.612] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C15.dat
[12:59:25.623] <TB3> INFO: PixTestTrim::trimTest() done
[12:59:25.623] <TB3> INFO: vtrim: 106 96 98 86 118 121 92 92 86 101 93 92 95 106 89 89
[12:59:25.623] <TB3> INFO: vthrcomp: 86 97 100 85 105 100 92 88 83 104 101 88 90 101 90 89
[12:59:25.623] <TB3> INFO: vcal mean: 35.13 34.96 35.10 34.96 35.01 34.98 34.98 34.95 34.98 34.99 34.97 35.02 34.97 34.97 34.98 34.99
[12:59:25.623] <TB3> INFO: vcal RMS: 0.90 1.02 1.02 0.87 0.90 0.86 0.87 0.76 0.84 0.90 1.08 1.12 0.84 1.09 0.84 0.84
[12:59:25.623] <TB3> INFO: bits mean: 9.46 8.37 8.37 9.58 9.14 9.84 9.44 9.47 9.25 8.89 9.08 9.41 9.39 9.17 9.15 8.89
[12:59:25.623] <TB3> INFO: bits RMS: 2.70 2.88 2.94 2.55 2.41 2.51 2.72 2.66 2.64 2.53 2.81 2.51 2.53 2.65 2.80 2.94
[12:59:25.630] <TB3> INFO: ----------------------------------------------------------------------
[12:59:25.630] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[12:59:25.630] <TB3> INFO: ----------------------------------------------------------------------
[12:59:25.633] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[12:59:25.641] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:59:25.641] <TB3> INFO: run 1 of 1
[12:59:25.900] <TB3> INFO: Expecting 4160000 events.
[13:00:08.486] <TB3> INFO: 947340 events read in total (42030ms).
[13:00:50.415] <TB3> INFO: 1886885 events read in total (83959ms).
[13:01:32.052] <TB3> INFO: 2818365 events read in total (125596ms).
[13:02:13.889] <TB3> INFO: 3748105 events read in total (167433ms).
[13:02:32.426] <TB3> INFO: 4160000 events read in total (185971ms).
[13:02:32.474] <TB3> INFO: Test took 186834ms.
[13:03:03.193] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 191 (-1/-1) hits flags = 528 (plus default)
[13:03:03.201] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:03:03.201] <TB3> INFO: run 1 of 1
[13:03:03.464] <TB3> INFO: Expecting 3993600 events.
[13:03:45.484] <TB3> INFO: 930025 events read in total (41463ms).
[13:04:27.008] <TB3> INFO: 1852910 events read in total (82988ms).
[13:05:08.138] <TB3> INFO: 2767270 events read in total (124117ms).
[13:05:49.923] <TB3> INFO: 3680285 events read in total (165902ms).
[13:06:04.498] <TB3> INFO: 3993600 events read in total (180477ms).
[13:06:04.539] <TB3> INFO: Test took 181338ms.
[13:06:34.958] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 177 (-1/-1) hits flags = 528 (plus default)
[13:06:34.966] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:06:34.966] <TB3> INFO: run 1 of 1
[13:06:35.253] <TB3> INFO: Expecting 3702400 events.
[13:07:18.064] <TB3> INFO: 959190 events read in total (42253ms).
[13:08:00.408] <TB3> INFO: 1909530 events read in total (84597ms).
[13:08:42.893] <TB3> INFO: 2850360 events read in total (127083ms).
[13:09:20.981] <TB3> INFO: 3702400 events read in total (165170ms).
[13:09:21.029] <TB3> INFO: Test took 166062ms.
[13:09:50.106] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[13:09:50.114] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:09:50.114] <TB3> INFO: run 1 of 1
[13:09:50.389] <TB3> INFO: Expecting 3723200 events.
[13:10:33.251] <TB3> INFO: 956265 events read in total (42306ms).
[13:11:15.512] <TB3> INFO: 1903825 events read in total (84568ms).
[13:11:58.064] <TB3> INFO: 2841630 events read in total (127120ms).
[13:12:38.138] <TB3> INFO: 3723200 events read in total (167193ms).
[13:12:38.188] <TB3> INFO: Test took 168073ms.
[13:13:06.031] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[13:13:06.039] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:13:06.039] <TB3> INFO: run 1 of 1
[13:13:06.298] <TB3> INFO: Expecting 3681600 events.
[13:13:49.347] <TB3> INFO: 960175 events read in total (42492ms).
[13:14:31.322] <TB3> INFO: 1911580 events read in total (84467ms).
[13:15:12.704] <TB3> INFO: 2853140 events read in total (125849ms).
[13:15:49.678] <TB3> INFO: 3681600 events read in total (162823ms).
[13:15:49.719] <TB3> INFO: Test took 163680ms.
[13:16:17.182] <TB3> INFO: PixTestTrim::trimBitTest() done
[13:16:17.183] <TB3> INFO: PixTestTrim::doTest() done, duration: 3062 seconds
[13:16:17.183] <TB3> INFO: Decoding statistics:
[13:16:17.183] <TB3> INFO: General information:
[13:16:17.183] <TB3> INFO: 16bit words read: 0
[13:16:17.183] <TB3> INFO: valid events total: 0
[13:16:17.183] <TB3> INFO: empty events: 0
[13:16:17.183] <TB3> INFO: valid events with pixels: 0
[13:16:17.183] <TB3> INFO: valid pixel hits: 0
[13:16:17.183] <TB3> INFO: Event errors: 0
[13:16:17.183] <TB3> INFO: start marker: 0
[13:16:17.183] <TB3> INFO: stop marker: 0
[13:16:17.183] <TB3> INFO: overflow: 0
[13:16:17.183] <TB3> INFO: invalid 5bit words: 0
[13:16:17.183] <TB3> INFO: invalid XOR eye diagram: 0
[13:16:17.183] <TB3> INFO: frame (failed synchr.): 0
[13:16:17.183] <TB3> INFO: idle data (no TBM trl): 0
[13:16:17.183] <TB3> INFO: no data (only TBM hdr): 0
[13:16:17.183] <TB3> INFO: TBM errors: 0
[13:16:17.183] <TB3> INFO: flawed TBM headers: 0
[13:16:17.183] <TB3> INFO: flawed TBM trailers: 0
[13:16:17.183] <TB3> INFO: event ID mismatches: 0
[13:16:17.183] <TB3> INFO: ROC errors: 0
[13:16:17.183] <TB3> INFO: missing ROC header(s): 0
[13:16:17.183] <TB3> INFO: misplaced readback start: 0
[13:16:17.183] <TB3> INFO: Pixel decoding errors: 0
[13:16:17.183] <TB3> INFO: pixel data incomplete: 0
[13:16:17.183] <TB3> INFO: pixel address: 0
[13:16:17.183] <TB3> INFO: pulse height fill bit: 0
[13:16:17.183] <TB3> INFO: buffer corruption: 0
[13:16:17.898] <TB3> INFO: ######################################################################
[13:16:17.898] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:16:17.898] <TB3> INFO: ######################################################################
[13:16:18.170] <TB3> INFO: Expecting 41600 events.
[13:16:22.020] <TB3> INFO: 41600 events read in total (3294ms).
[13:16:22.020] <TB3> INFO: Test took 4121ms.
[13:16:22.503] <TB3> INFO: Expecting 41600 events.
[13:16:26.374] <TB3> INFO: 41600 events read in total (3314ms).
[13:16:26.375] <TB3> INFO: Test took 4152ms.
[13:16:26.673] <TB3> INFO: Expecting 41600 events.
[13:16:30.473] <TB3> INFO: 41600 events read in total (3244ms).
[13:16:30.474] <TB3> INFO: Test took 4071ms.
[13:16:30.728] <TB3> INFO: Expecting 2560 events.
[13:16:31.585] <TB3> INFO: 2560 events read in total (301ms).
[13:16:31.586] <TB3> INFO: Test took 1100ms.
[13:16:31.891] <TB3> INFO: Expecting 2560 events.
[13:16:32.744] <TB3> INFO: 2560 events read in total (297ms).
[13:16:32.744] <TB3> INFO: Test took 1158ms.
[13:16:33.050] <TB3> INFO: Expecting 2560 events.
[13:16:33.903] <TB3> INFO: 2560 events read in total (297ms).
[13:16:33.904] <TB3> INFO: Test took 1159ms.
[13:16:34.208] <TB3> INFO: Expecting 2560 events.
[13:16:35.061] <TB3> INFO: 2560 events read in total (296ms).
[13:16:35.061] <TB3> INFO: Test took 1157ms.
[13:16:35.366] <TB3> INFO: Expecting 2560 events.
[13:16:36.220] <TB3> INFO: 2560 events read in total (298ms).
[13:16:36.220] <TB3> INFO: Test took 1159ms.
[13:16:36.525] <TB3> INFO: Expecting 2560 events.
[13:16:37.385] <TB3> INFO: 2560 events read in total (303ms).
[13:16:37.385] <TB3> INFO: Test took 1164ms.
[13:16:37.691] <TB3> INFO: Expecting 2560 events.
[13:16:38.547] <TB3> INFO: 2560 events read in total (300ms).
[13:16:38.547] <TB3> INFO: Test took 1162ms.
[13:16:38.852] <TB3> INFO: Expecting 2560 events.
[13:16:39.712] <TB3> INFO: 2560 events read in total (303ms).
[13:16:39.712] <TB3> INFO: Test took 1164ms.
[13:16:40.018] <TB3> INFO: Expecting 2560 events.
[13:16:40.875] <TB3> INFO: 2560 events read in total (301ms).
[13:16:40.876] <TB3> INFO: Test took 1163ms.
[13:16:41.181] <TB3> INFO: Expecting 2560 events.
[13:16:42.037] <TB3> INFO: 2560 events read in total (300ms).
[13:16:42.038] <TB3> INFO: Test took 1161ms.
[13:16:42.342] <TB3> INFO: Expecting 2560 events.
[13:16:43.199] <TB3> INFO: 2560 events read in total (301ms).
[13:16:43.200] <TB3> INFO: Test took 1162ms.
[13:16:43.505] <TB3> INFO: Expecting 2560 events.
[13:16:44.358] <TB3> INFO: 2560 events read in total (297ms).
[13:16:44.358] <TB3> INFO: Test took 1158ms.
[13:16:44.664] <TB3> INFO: Expecting 2560 events.
[13:16:45.517] <TB3> INFO: 2560 events read in total (297ms).
[13:16:45.517] <TB3> INFO: Test took 1158ms.
[13:16:45.822] <TB3> INFO: Expecting 2560 events.
[13:16:46.675] <TB3> INFO: 2560 events read in total (297ms).
[13:16:46.676] <TB3> INFO: Test took 1158ms.
[13:16:46.984] <TB3> INFO: Expecting 2560 events.
[13:16:47.842] <TB3> INFO: 2560 events read in total (302ms).
[13:16:47.842] <TB3> INFO: Test took 1166ms.
[13:16:48.147] <TB3> INFO: Expecting 2560 events.
[13:16:49.003] <TB3> INFO: 2560 events read in total (300ms).
[13:16:49.004] <TB3> INFO: Test took 1161ms.
[13:16:49.006] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:16:49.315] <TB3> INFO: Expecting 655360 events.
[13:17:06.304] <TB3> INFO: 655360 events read in total (16433ms).
[13:17:06.312] <TB3> INFO: Expecting 655360 events.
[13:17:23.177] <TB3> INFO: 655360 events read in total (16463ms).
[13:17:23.189] <TB3> INFO: Expecting 655360 events.
[13:17:40.019] <TB3> INFO: 655360 events read in total (16428ms).
[13:17:40.034] <TB3> INFO: Expecting 655360 events.
[13:17:56.825] <TB3> INFO: 655360 events read in total (16390ms).
[13:17:56.844] <TB3> INFO: Expecting 655360 events.
[13:18:13.711] <TB3> INFO: 655360 events read in total (16465ms).
[13:18:13.737] <TB3> INFO: Expecting 655360 events.
[13:18:30.485] <TB3> INFO: 655360 events read in total (16347ms).
[13:18:30.511] <TB3> INFO: Expecting 655360 events.
[13:18:47.210] <TB3> INFO: 655360 events read in total (16297ms).
[13:18:47.243] <TB3> INFO: Expecting 655360 events.
[13:19:04.002] <TB3> INFO: 655360 events read in total (16357ms).
[13:19:04.036] <TB3> INFO: Expecting 655360 events.
[13:19:20.628] <TB3> INFO: 655360 events read in total (16191ms).
[13:19:20.663] <TB3> INFO: Expecting 655360 events.
[13:19:37.549] <TB3> INFO: 655360 events read in total (16485ms).
[13:19:37.589] <TB3> INFO: Expecting 655360 events.
[13:19:54.365] <TB3> INFO: 655360 events read in total (16373ms).
[13:19:54.408] <TB3> INFO: Expecting 655360 events.
[13:20:11.099] <TB3> INFO: 655360 events read in total (16289ms).
[13:20:11.151] <TB3> INFO: Expecting 655360 events.
[13:20:27.946] <TB3> INFO: 655360 events read in total (16393ms).
[13:20:27.999] <TB3> INFO: Expecting 655360 events.
[13:20:44.789] <TB3> INFO: 655360 events read in total (16388ms).
[13:20:44.844] <TB3> INFO: Expecting 655360 events.
[13:21:01.649] <TB3> INFO: 655360 events read in total (16403ms).
[13:21:01.707] <TB3> INFO: Expecting 655360 events.
[13:21:18.306] <TB3> INFO: 655360 events read in total (16197ms).
[13:21:18.381] <TB3> INFO: Test took 269375ms.
[13:21:18.468] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:21:18.696] <TB3> INFO: Expecting 655360 events.
[13:21:35.399] <TB3> INFO: 655360 events read in total (16147ms).
[13:21:35.408] <TB3> INFO: Expecting 655360 events.
[13:21:51.897] <TB3> INFO: 655360 events read in total (16087ms).
[13:21:51.909] <TB3> INFO: Expecting 655360 events.
[13:22:08.488] <TB3> INFO: 655360 events read in total (16177ms).
[13:22:08.505] <TB3> INFO: Expecting 655360 events.
[13:22:25.231] <TB3> INFO: 655360 events read in total (16325ms).
[13:22:25.255] <TB3> INFO: Expecting 655360 events.
[13:22:41.800] <TB3> INFO: 655360 events read in total (16143ms).
[13:22:41.829] <TB3> INFO: Expecting 655360 events.
[13:22:58.629] <TB3> INFO: 655360 events read in total (16398ms).
[13:22:58.655] <TB3> INFO: Expecting 655360 events.
[13:23:15.292] <TB3> INFO: 655360 events read in total (16236ms).
[13:23:15.326] <TB3> INFO: Expecting 655360 events.
[13:23:32.192] <TB3> INFO: 655360 events read in total (16465ms).
[13:23:32.225] <TB3> INFO: Expecting 655360 events.
[13:23:49.024] <TB3> INFO: 655360 events read in total (16395ms).
[13:23:49.059] <TB3> INFO: Expecting 655360 events.
[13:24:05.835] <TB3> INFO: 655360 events read in total (16374ms).
[13:24:05.874] <TB3> INFO: Expecting 655360 events.
[13:24:22.725] <TB3> INFO: 655360 events read in total (16450ms).
[13:24:22.766] <TB3> INFO: Expecting 655360 events.
[13:24:39.565] <TB3> INFO: 655360 events read in total (16397ms).
[13:24:39.611] <TB3> INFO: Expecting 655360 events.
[13:24:56.274] <TB3> INFO: 655360 events read in total (16261ms).
[13:24:56.322] <TB3> INFO: Expecting 655360 events.
[13:25:13.041] <TB3> INFO: 655360 events read in total (16317ms).
[13:25:13.098] <TB3> INFO: Expecting 655360 events.
[13:25:29.995] <TB3> INFO: 655360 events read in total (16495ms).
[13:25:30.057] <TB3> INFO: Expecting 655360 events.
[13:25:46.818] <TB3> INFO: 655360 events read in total (16359ms).
[13:25:46.975] <TB3> INFO: Test took 268507ms.
[13:25:47.168] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.175] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.181] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.188] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.195] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.202] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.208] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.216] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.223] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.230] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.237] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.244] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.252] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.259] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.266] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.273] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:47.279] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[13:25:47.286] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[13:25:47.293] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[13:25:47.300] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[13:25:47.338] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:25:47.338] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:25:47.339] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:25:47.339] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:25:47.339] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:25:47.339] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:25:47.339] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:25:47.340] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:25:47.340] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:25:47.340] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:25:47.340] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:25:47.340] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:25:47.340] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:25:47.341] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:25:47.341] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:25:47.341] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:25:47.603] <TB3> INFO: Expecting 41600 events.
[13:25:51.070] <TB3> INFO: 41600 events read in total (2910ms).
[13:25:51.071] <TB3> INFO: Test took 3727ms.
[13:25:51.512] <TB3> INFO: Expecting 41600 events.
[13:25:54.987] <TB3> INFO: 41600 events read in total (2919ms).
[13:25:54.988] <TB3> INFO: Test took 3738ms.
[13:25:55.434] <TB3> INFO: Expecting 41600 events.
[13:25:58.950] <TB3> INFO: 41600 events read in total (2960ms).
[13:25:58.951] <TB3> INFO: Test took 3789ms.
[13:25:59.137] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:59.224] <TB3> INFO: Expecting 2560 events.
[13:26:00.083] <TB3> INFO: 2560 events read in total (302ms).
[13:26:00.084] <TB3> INFO: Test took 947ms.
[13:26:00.097] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:00.389] <TB3> INFO: Expecting 2560 events.
[13:26:01.246] <TB3> INFO: 2560 events read in total (301ms).
[13:26:01.246] <TB3> INFO: Test took 1149ms.
[13:26:01.247] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:01.552] <TB3> INFO: Expecting 2560 events.
[13:26:02.411] <TB3> INFO: 2560 events read in total (302ms).
[13:26:02.411] <TB3> INFO: Test took 1164ms.
[13:26:02.413] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:02.717] <TB3> INFO: Expecting 2560 events.
[13:26:03.576] <TB3> INFO: 2560 events read in total (303ms).
[13:26:03.576] <TB3> INFO: Test took 1163ms.
[13:26:03.578] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:03.881] <TB3> INFO: Expecting 2560 events.
[13:26:04.734] <TB3> INFO: 2560 events read in total (296ms).
[13:26:04.735] <TB3> INFO: Test took 1157ms.
[13:26:04.736] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:05.040] <TB3> INFO: Expecting 2560 events.
[13:26:05.894] <TB3> INFO: 2560 events read in total (297ms).
[13:26:05.894] <TB3> INFO: Test took 1158ms.
[13:26:05.897] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:06.200] <TB3> INFO: Expecting 2560 events.
[13:26:07.057] <TB3> INFO: 2560 events read in total (301ms).
[13:26:07.058] <TB3> INFO: Test took 1161ms.
[13:26:07.060] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:07.363] <TB3> INFO: Expecting 2560 events.
[13:26:08.217] <TB3> INFO: 2560 events read in total (297ms).
[13:26:08.217] <TB3> INFO: Test took 1157ms.
[13:26:08.220] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:08.524] <TB3> INFO: Expecting 2560 events.
[13:26:09.378] <TB3> INFO: 2560 events read in total (298ms).
[13:26:09.378] <TB3> INFO: Test took 1158ms.
[13:26:09.380] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:09.684] <TB3> INFO: Expecting 2560 events.
[13:26:10.539] <TB3> INFO: 2560 events read in total (299ms).
[13:26:10.539] <TB3> INFO: Test took 1159ms.
[13:26:10.542] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:10.845] <TB3> INFO: Expecting 2560 events.
[13:26:11.704] <TB3> INFO: 2560 events read in total (303ms).
[13:26:11.705] <TB3> INFO: Test took 1163ms.
[13:26:11.707] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:12.010] <TB3> INFO: Expecting 2560 events.
[13:26:12.866] <TB3> INFO: 2560 events read in total (300ms).
[13:26:12.867] <TB3> INFO: Test took 1160ms.
[13:26:12.869] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:13.172] <TB3> INFO: Expecting 2560 events.
[13:26:14.027] <TB3> INFO: 2560 events read in total (298ms).
[13:26:14.027] <TB3> INFO: Test took 1158ms.
[13:26:14.030] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:14.332] <TB3> INFO: Expecting 2560 events.
[13:26:15.188] <TB3> INFO: 2560 events read in total (300ms).
[13:26:15.188] <TB3> INFO: Test took 1158ms.
[13:26:15.190] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:15.494] <TB3> INFO: Expecting 2560 events.
[13:26:16.353] <TB3> INFO: 2560 events read in total (303ms).
[13:26:16.354] <TB3> INFO: Test took 1164ms.
[13:26:16.356] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:16.660] <TB3> INFO: Expecting 2560 events.
[13:26:17.517] <TB3> INFO: 2560 events read in total (301ms).
[13:26:17.517] <TB3> INFO: Test took 1161ms.
[13:26:17.519] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:17.823] <TB3> INFO: Expecting 2560 events.
[13:26:18.677] <TB3> INFO: 2560 events read in total (297ms).
[13:26:18.678] <TB3> INFO: Test took 1159ms.
[13:26:18.680] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:18.983] <TB3> INFO: Expecting 2560 events.
[13:26:19.841] <TB3> INFO: 2560 events read in total (302ms).
[13:26:19.842] <TB3> INFO: Test took 1162ms.
[13:26:19.844] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:20.147] <TB3> INFO: Expecting 2560 events.
[13:26:21.005] <TB3> INFO: 2560 events read in total (301ms).
[13:26:21.005] <TB3> INFO: Test took 1161ms.
[13:26:21.007] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:21.312] <TB3> INFO: Expecting 2560 events.
[13:26:22.166] <TB3> INFO: 2560 events read in total (298ms).
[13:26:22.166] <TB3> INFO: Test took 1159ms.
[13:26:22.168] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:22.474] <TB3> INFO: Expecting 2560 events.
[13:26:23.328] <TB3> INFO: 2560 events read in total (298ms).
[13:26:23.329] <TB3> INFO: Test took 1161ms.
[13:26:23.330] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:23.634] <TB3> INFO: Expecting 2560 events.
[13:26:24.493] <TB3> INFO: 2560 events read in total (302ms).
[13:26:24.493] <TB3> INFO: Test took 1163ms.
[13:26:24.496] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:24.799] <TB3> INFO: Expecting 2560 events.
[13:26:25.657] <TB3> INFO: 2560 events read in total (302ms).
[13:26:25.657] <TB3> INFO: Test took 1161ms.
[13:26:25.660] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:25.963] <TB3> INFO: Expecting 2560 events.
[13:26:26.817] <TB3> INFO: 2560 events read in total (298ms).
[13:26:26.817] <TB3> INFO: Test took 1157ms.
[13:26:26.820] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:27.123] <TB3> INFO: Expecting 2560 events.
[13:26:27.977] <TB3> INFO: 2560 events read in total (298ms).
[13:26:27.977] <TB3> INFO: Test took 1157ms.
[13:26:27.979] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:28.282] <TB3> INFO: Expecting 2560 events.
[13:26:29.142] <TB3> INFO: 2560 events read in total (304ms).
[13:26:29.142] <TB3> INFO: Test took 1164ms.
[13:26:29.145] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:29.448] <TB3> INFO: Expecting 2560 events.
[13:26:30.306] <TB3> INFO: 2560 events read in total (302ms).
[13:26:30.306] <TB3> INFO: Test took 1161ms.
[13:26:30.309] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:30.612] <TB3> INFO: Expecting 2560 events.
[13:26:31.467] <TB3> INFO: 2560 events read in total (298ms).
[13:26:31.467] <TB3> INFO: Test took 1158ms.
[13:26:31.470] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:31.773] <TB3> INFO: Expecting 2560 events.
[13:26:32.627] <TB3> INFO: 2560 events read in total (298ms).
[13:26:32.627] <TB3> INFO: Test took 1157ms.
[13:26:32.629] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:32.933] <TB3> INFO: Expecting 2560 events.
[13:26:33.793] <TB3> INFO: 2560 events read in total (303ms).
[13:26:33.794] <TB3> INFO: Test took 1165ms.
[13:26:33.796] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:34.099] <TB3> INFO: Expecting 2560 events.
[13:26:34.957] <TB3> INFO: 2560 events read in total (301ms).
[13:26:34.957] <TB3> INFO: Test took 1161ms.
[13:26:34.960] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:35.263] <TB3> INFO: Expecting 2560 events.
[13:26:36.121] <TB3> INFO: 2560 events read in total (302ms).
[13:26:36.121] <TB3> INFO: Test took 1161ms.
[13:26:36.593] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 618 seconds
[13:26:36.594] <TB3> INFO: PH scale (per ROC): 80 76 62 73 77 82 84 86 76 81 80 78 83 80 82 86
[13:26:36.594] <TB3> INFO: PH offset (per ROC): 165 179 160 175 161 163 153 159 176 176 175 170 165 180 158 144
[13:26:36.600] <TB3> INFO: Decoding statistics:
[13:26:36.600] <TB3> INFO: General information:
[13:26:36.600] <TB3> INFO: 16bit words read: 91912
[13:26:36.600] <TB3> INFO: valid events total: 10240
[13:26:36.600] <TB3> INFO: empty events: 7742
[13:26:36.600] <TB3> INFO: valid events with pixels: 2498
[13:26:36.600] <TB3> INFO: valid pixel hits: 2498
[13:26:36.600] <TB3> INFO: Event errors: 0
[13:26:36.600] <TB3> INFO: start marker: 0
[13:26:36.600] <TB3> INFO: stop marker: 0
[13:26:36.600] <TB3> INFO: overflow: 0
[13:26:36.600] <TB3> INFO: invalid 5bit words: 0
[13:26:36.600] <TB3> INFO: invalid XOR eye diagram: 0
[13:26:36.600] <TB3> INFO: frame (failed synchr.): 0
[13:26:36.600] <TB3> INFO: idle data (no TBM trl): 0
[13:26:36.600] <TB3> INFO: no data (only TBM hdr): 0
[13:26:36.600] <TB3> INFO: TBM errors: 0
[13:26:36.600] <TB3> INFO: flawed TBM headers: 0
[13:26:36.600] <TB3> INFO: flawed TBM trailers: 0
[13:26:36.600] <TB3> INFO: event ID mismatches: 0
[13:26:36.600] <TB3> INFO: ROC errors: 0
[13:26:36.600] <TB3> INFO: missing ROC header(s): 0
[13:26:36.600] <TB3> INFO: misplaced readback start: 0
[13:26:36.600] <TB3> INFO: Pixel decoding errors: 0
[13:26:36.600] <TB3> INFO: pixel data incomplete: 0
[13:26:36.600] <TB3> INFO: pixel address: 0
[13:26:36.600] <TB3> INFO: pulse height fill bit: 0
[13:26:36.600] <TB3> INFO: buffer corruption: 0
[13:26:36.783] <TB3> INFO: ######################################################################
[13:26:36.783] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:26:36.783] <TB3> INFO: ######################################################################
[13:26:36.793] <TB3> INFO: scanning low vcal = 10
[13:26:37.053] <TB3> INFO: Expecting 41600 events.
[13:26:40.615] <TB3> INFO: 41600 events read in total (3006ms).
[13:26:40.616] <TB3> INFO: Test took 3823ms.
[13:26:40.617] <TB3> INFO: scanning low vcal = 20
[13:26:40.921] <TB3> INFO: Expecting 41600 events.
[13:26:44.474] <TB3> INFO: 41600 events read in total (2997ms).
[13:26:44.474] <TB3> INFO: Test took 3856ms.
[13:26:44.475] <TB3> INFO: scanning low vcal = 30
[13:26:44.779] <TB3> INFO: Expecting 41600 events.
[13:26:48.324] <TB3> INFO: 41600 events read in total (2989ms).
[13:26:48.325] <TB3> INFO: Test took 3850ms.
[13:26:48.327] <TB3> INFO: scanning low vcal = 40
[13:26:48.623] <TB3> INFO: Expecting 41600 events.
[13:26:52.775] <TB3> INFO: 41600 events read in total (3596ms).
[13:26:52.775] <TB3> INFO: Test took 4448ms.
[13:26:52.778] <TB3> INFO: scanning low vcal = 50
[13:26:53.053] <TB3> INFO: Expecting 41600 events.
[13:26:57.305] <TB3> INFO: 41600 events read in total (3696ms).
[13:26:57.305] <TB3> INFO: Test took 4527ms.
[13:26:57.309] <TB3> INFO: scanning low vcal = 60
[13:26:57.655] <TB3> INFO: Expecting 41600 events.
[13:27:01.832] <TB3> INFO: 41600 events read in total (3621ms).
[13:27:01.832] <TB3> INFO: Test took 4523ms.
[13:27:01.835] <TB3> INFO: scanning low vcal = 70
[13:27:02.095] <TB3> INFO: Expecting 41600 events.
[13:27:06.274] <TB3> INFO: 41600 events read in total (3623ms).
[13:27:06.275] <TB3> INFO: Test took 4440ms.
[13:27:06.277] <TB3> INFO: scanning low vcal = 80
[13:27:06.540] <TB3> INFO: Expecting 41600 events.
[13:27:10.729] <TB3> INFO: 41600 events read in total (3633ms).
[13:27:10.729] <TB3> INFO: Test took 4452ms.
[13:27:10.732] <TB3> INFO: scanning low vcal = 90
[13:27:11.011] <TB3> INFO: Expecting 41600 events.
[13:27:15.215] <TB3> INFO: 41600 events read in total (3647ms).
[13:27:15.216] <TB3> INFO: Test took 4484ms.
[13:27:15.219] <TB3> INFO: scanning low vcal = 100
[13:27:15.495] <TB3> INFO: Expecting 41600 events.
[13:27:19.696] <TB3> INFO: 41600 events read in total (3645ms).
[13:27:19.697] <TB3> INFO: Test took 4478ms.
[13:27:19.700] <TB3> INFO: scanning low vcal = 110
[13:27:19.993] <TB3> INFO: Expecting 41600 events.
[13:27:24.212] <TB3> INFO: 41600 events read in total (3663ms).
[13:27:24.213] <TB3> INFO: Test took 4513ms.
[13:27:24.216] <TB3> INFO: scanning low vcal = 120
[13:27:24.479] <TB3> INFO: Expecting 41600 events.
[13:27:28.682] <TB3> INFO: 41600 events read in total (3647ms).
[13:27:28.683] <TB3> INFO: Test took 4467ms.
[13:27:28.685] <TB3> INFO: scanning low vcal = 130
[13:27:28.954] <TB3> INFO: Expecting 41600 events.
[13:27:33.201] <TB3> INFO: 41600 events read in total (3691ms).
[13:27:33.202] <TB3> INFO: Test took 4517ms.
[13:27:33.204] <TB3> INFO: scanning low vcal = 140
[13:27:33.464] <TB3> INFO: Expecting 41600 events.
[13:27:37.652] <TB3> INFO: 41600 events read in total (3631ms).
[13:27:37.652] <TB3> INFO: Test took 4448ms.
[13:27:37.655] <TB3> INFO: scanning low vcal = 150
[13:27:37.915] <TB3> INFO: Expecting 41600 events.
[13:27:42.159] <TB3> INFO: 41600 events read in total (3687ms).
[13:27:42.159] <TB3> INFO: Test took 4504ms.
[13:27:42.162] <TB3> INFO: scanning low vcal = 160
[13:27:42.449] <TB3> INFO: Expecting 41600 events.
[13:27:46.633] <TB3> INFO: 41600 events read in total (3628ms).
[13:27:46.633] <TB3> INFO: Test took 4471ms.
[13:27:46.636] <TB3> INFO: scanning low vcal = 170
[13:27:46.903] <TB3> INFO: Expecting 41600 events.
[13:27:51.109] <TB3> INFO: 41600 events read in total (3650ms).
[13:27:51.109] <TB3> INFO: Test took 4473ms.
[13:27:51.113] <TB3> INFO: scanning low vcal = 180
[13:27:51.376] <TB3> INFO: Expecting 41600 events.
[13:27:55.552] <TB3> INFO: 41600 events read in total (3620ms).
[13:27:55.553] <TB3> INFO: Test took 4440ms.
[13:27:55.555] <TB3> INFO: scanning low vcal = 190
[13:27:55.821] <TB3> INFO: Expecting 41600 events.
[13:28:00.016] <TB3> INFO: 41600 events read in total (3639ms).
[13:28:00.016] <TB3> INFO: Test took 4461ms.
[13:28:00.019] <TB3> INFO: scanning low vcal = 200
[13:28:00.279] <TB3> INFO: Expecting 41600 events.
[13:28:04.495] <TB3> INFO: 41600 events read in total (3659ms).
[13:28:04.495] <TB3> INFO: Test took 4476ms.
[13:28:04.498] <TB3> INFO: scanning low vcal = 210
[13:28:04.761] <TB3> INFO: Expecting 41600 events.
[13:28:08.929] <TB3> INFO: 41600 events read in total (3612ms).
[13:28:08.930] <TB3> INFO: Test took 4432ms.
[13:28:08.932] <TB3> INFO: scanning low vcal = 220
[13:28:09.194] <TB3> INFO: Expecting 41600 events.
[13:28:13.391] <TB3> INFO: 41600 events read in total (3641ms).
[13:28:13.391] <TB3> INFO: Test took 4458ms.
[13:28:13.393] <TB3> INFO: scanning low vcal = 230
[13:28:13.656] <TB3> INFO: Expecting 41600 events.
[13:28:17.846] <TB3> INFO: 41600 events read in total (3634ms).
[13:28:17.847] <TB3> INFO: Test took 4454ms.
[13:28:17.849] <TB3> INFO: scanning low vcal = 240
[13:28:18.123] <TB3> INFO: Expecting 41600 events.
[13:28:22.309] <TB3> INFO: 41600 events read in total (3629ms).
[13:28:22.310] <TB3> INFO: Test took 4460ms.
[13:28:22.312] <TB3> INFO: scanning low vcal = 250
[13:28:22.573] <TB3> INFO: Expecting 41600 events.
[13:28:26.764] <TB3> INFO: 41600 events read in total (3635ms).
[13:28:26.765] <TB3> INFO: Test took 4453ms.
[13:28:26.769] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[13:28:27.033] <TB3> INFO: Expecting 41600 events.
[13:28:31.268] <TB3> INFO: 41600 events read in total (3678ms).
[13:28:31.269] <TB3> INFO: Test took 4500ms.
[13:28:31.272] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[13:28:31.559] <TB3> INFO: Expecting 41600 events.
[13:28:35.763] <TB3> INFO: 41600 events read in total (3647ms).
[13:28:35.763] <TB3> INFO: Test took 4491ms.
[13:28:35.766] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[13:28:36.032] <TB3> INFO: Expecting 41600 events.
[13:28:40.236] <TB3> INFO: 41600 events read in total (3648ms).
[13:28:40.237] <TB3> INFO: Test took 4471ms.
[13:28:40.239] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[13:28:40.506] <TB3> INFO: Expecting 41600 events.
[13:28:44.718] <TB3> INFO: 41600 events read in total (3656ms).
[13:28:44.719] <TB3> INFO: Test took 4480ms.
[13:28:44.721] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:28:45.007] <TB3> INFO: Expecting 41600 events.
[13:28:49.216] <TB3> INFO: 41600 events read in total (3653ms).
[13:28:49.217] <TB3> INFO: Test took 4495ms.
[13:28:49.747] <TB3> INFO: PixTestGainPedestal::measure() done
[13:29:22.725] <TB3> INFO: PixTestGainPedestal::fit() done
[13:29:22.725] <TB3> INFO: non-linearity mean: 0.959 0.956 0.953 0.952 0.959 0.959 0.962 0.960 0.956 0.955 0.953 0.960 0.954 0.961 0.954 0.957
[13:29:22.725] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.007 0.009 0.007 0.004 0.005 0.005 0.007 0.006 0.007 0.005 0.006 0.006 0.007 0.004
[13:29:22.725] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:29:22.770] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:29:22.815] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:29:22.860] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:29:22.905] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:29:22.947] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:29:22.991] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:29:23.017] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:29:23.043] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:29:23.067] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:29:23.092] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:29:23.119] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:29:23.145] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:29:23.172] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:29:23.200] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:29:23.227] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:29:23.254] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[13:29:23.254] <TB3> INFO: Decoding statistics:
[13:29:23.254] <TB3> INFO: General information:
[13:29:23.254] <TB3> INFO: 16bit words read: 2662400
[13:29:23.254] <TB3> INFO: valid events total: 166400
[13:29:23.254] <TB3> INFO: empty events: 0
[13:29:23.254] <TB3> INFO: valid events with pixels: 166400
[13:29:23.254] <TB3> INFO: valid pixel hits: 665557
[13:29:23.254] <TB3> INFO: Event errors: 0
[13:29:23.254] <TB3> INFO: start marker: 0
[13:29:23.254] <TB3> INFO: stop marker: 0
[13:29:23.254] <TB3> INFO: overflow: 0
[13:29:23.254] <TB3> INFO: invalid 5bit words: 0
[13:29:23.254] <TB3> INFO: invalid XOR eye diagram: 0
[13:29:23.254] <TB3> INFO: frame (failed synchr.): 0
[13:29:23.254] <TB3> INFO: idle data (no TBM trl): 0
[13:29:23.254] <TB3> INFO: no data (only TBM hdr): 0
[13:29:23.254] <TB3> INFO: TBM errors: 0
[13:29:23.254] <TB3> INFO: flawed TBM headers: 0
[13:29:23.254] <TB3> INFO: flawed TBM trailers: 0
[13:29:23.254] <TB3> INFO: event ID mismatches: 0
[13:29:23.255] <TB3> INFO: ROC errors: 0
[13:29:23.255] <TB3> INFO: missing ROC header(s): 0
[13:29:23.255] <TB3> INFO: misplaced readback start: 0
[13:29:23.255] <TB3> INFO: Pixel decoding errors: 0
[13:29:23.255] <TB3> INFO: pixel data incomplete: 0
[13:29:23.255] <TB3> INFO: pixel address: 0
[13:29:23.255] <TB3> INFO: pulse height fill bit: 0
[13:29:23.255] <TB3> INFO: buffer corruption: 0
[13:29:23.263] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C15.dat
[13:29:23.264] <TB3> INFO: ######################################################################
[13:29:23.264] <TB3> INFO: PixTestReadback::doTest()
[13:29:23.264] <TB3> INFO: ######################################################################
[13:29:23.264] <TB3> INFO: ----------------------------------------------------------------------
[13:29:23.264] <TB3> INFO: PixTestReadback::CalibrateVd()
[13:29:23.264] <TB3> INFO: ----------------------------------------------------------------------
[13:29:32.741] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C0.dat
[13:29:32.741] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C1.dat
[13:29:32.741] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C2.dat
[13:29:32.741] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C3.dat
[13:29:32.741] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C4.dat
[13:29:32.741] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C5.dat
[13:29:32.741] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C6.dat
[13:29:32.741] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C7.dat
[13:29:32.742] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C8.dat
[13:29:32.742] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C9.dat
[13:29:32.743] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C10.dat
[13:29:32.743] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C11.dat
[13:29:32.743] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C12.dat
[13:29:32.743] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C13.dat
[13:29:32.743] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C14.dat
[13:29:32.743] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C15.dat
[13:29:32.777] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:29:32.777] <TB3> INFO: ----------------------------------------------------------------------
[13:29:32.777] <TB3> INFO: PixTestReadback::CalibrateVa()
[13:29:32.777] <TB3> INFO: ----------------------------------------------------------------------
[13:29:42.280] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C0.dat
[13:29:42.280] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C1.dat
[13:29:42.280] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C2.dat
[13:29:42.280] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C3.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C4.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C5.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C6.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C7.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C8.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C9.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C10.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C11.dat
[13:29:42.281] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C12.dat
[13:29:42.282] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C13.dat
[13:29:42.282] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C14.dat
[13:29:42.282] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C15.dat
[13:29:42.320] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:29:42.320] <TB3> INFO: ----------------------------------------------------------------------
[13:29:42.320] <TB3> INFO: PixTestReadback::readbackVbg()
[13:29:42.320] <TB3> INFO: ----------------------------------------------------------------------
[13:29:49.606] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:29:49.606] <TB3> INFO: ----------------------------------------------------------------------
[13:29:49.607] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[13:29:49.607] <TB3> INFO: ----------------------------------------------------------------------
[13:29:49.607] <TB3> INFO: Vbg will be calibrated using Vd calibration
[13:29:49.607] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146.6calibrated Vbg = 1.18695 :::*/*/*/*/
[13:29:49.607] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159.7calibrated Vbg = 1.18359 :::*/*/*/*/
[13:29:49.607] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.8calibrated Vbg = 1.20059 :::*/*/*/*/
[13:29:49.607] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.9calibrated Vbg = 1.19843 :::*/*/*/*/
[13:29:49.607] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 163.1calibrated Vbg = 1.19832 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 149calibrated Vbg = 1.19297 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.9calibrated Vbg = 1.20042 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.7calibrated Vbg = 1.21039 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155calibrated Vbg = 1.20742 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.7calibrated Vbg = 1.20398 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.9calibrated Vbg = 1.20689 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 148.2calibrated Vbg = 1.19615 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 146.7calibrated Vbg = 1.19029 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.1calibrated Vbg = 1.1977 :::*/*/*/*/
[13:29:49.608] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.2calibrated Vbg = 1.20006 :::*/*/*/*/
[13:29:49.609] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.9calibrated Vbg = 1.19781 :::*/*/*/*/
[13:29:49.612] <TB3> INFO: ----------------------------------------------------------------------
[13:29:49.612] <TB3> INFO: PixTestReadback::CalibrateIa()
[13:29:49.612] <TB3> INFO: ----------------------------------------------------------------------
[13:32:25.059] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C0.dat
[13:32:25.060] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C1.dat
[13:32:25.060] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C2.dat
[13:32:25.060] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C3.dat
[13:32:25.060] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C4.dat
[13:32:25.060] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C5.dat
[13:32:25.060] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C6.dat
[13:32:25.060] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C7.dat
[13:32:25.061] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C8.dat
[13:32:25.061] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C9.dat
[13:32:25.061] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C10.dat
[13:32:25.061] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C11.dat
[13:32:25.061] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C12.dat
[13:32:25.061] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C13.dat
[13:32:25.061] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C14.dat
[13:32:25.062] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2269_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C15.dat
[13:32:25.094] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:32:25.095] <TB3> INFO: PixTestReadback::doTest() done
[13:32:25.095] <TB3> INFO: Decoding statistics:
[13:32:25.095] <TB3> INFO: General information:
[13:32:25.095] <TB3> INFO: 16bit words read: 1024
[13:32:25.095] <TB3> INFO: valid events total: 128
[13:32:25.095] <TB3> INFO: empty events: 128
[13:32:25.095] <TB3> INFO: valid events with pixels: 0
[13:32:25.095] <TB3> INFO: valid pixel hits: 0
[13:32:25.095] <TB3> INFO: Event errors: 0
[13:32:25.095] <TB3> INFO: start marker: 0
[13:32:25.095] <TB3> INFO: stop marker: 0
[13:32:25.095] <TB3> INFO: overflow: 0
[13:32:25.095] <TB3> INFO: invalid 5bit words: 0
[13:32:25.095] <TB3> INFO: invalid XOR eye diagram: 0
[13:32:25.095] <TB3> INFO: frame (failed synchr.): 0
[13:32:25.095] <TB3> INFO: idle data (no TBM trl): 0
[13:32:25.095] <TB3> INFO: no data (only TBM hdr): 0
[13:32:25.095] <TB3> INFO: TBM errors: 0
[13:32:25.095] <TB3> INFO: flawed TBM headers: 0
[13:32:25.095] <TB3> INFO: flawed TBM trailers: 0
[13:32:25.095] <TB3> INFO: event ID mismatches: 0
[13:32:25.095] <TB3> INFO: ROC errors: 0
[13:32:25.095] <TB3> INFO: missing ROC header(s): 0
[13:32:25.095] <TB3> INFO: misplaced readback start: 0
[13:32:25.095] <TB3> INFO: Pixel decoding errors: 0
[13:32:25.095] <TB3> INFO: pixel data incomplete: 0
[13:32:25.095] <TB3> INFO: pixel address: 0
[13:32:25.095] <TB3> INFO: pulse height fill bit: 0
[13:32:25.095] <TB3> INFO: buffer corruption: 0
[13:32:25.117] <TB3> INFO: Decoding statistics:
[13:32:25.117] <TB3> INFO: General information:
[13:32:25.117] <TB3> INFO: 16bit words read: 2755336
[13:32:25.117] <TB3> INFO: valid events total: 176768
[13:32:25.117] <TB3> INFO: empty events: 7870
[13:32:25.117] <TB3> INFO: valid events with pixels: 168898
[13:32:25.117] <TB3> INFO: valid pixel hits: 668055
[13:32:25.117] <TB3> INFO: Event errors: 0
[13:32:25.117] <TB3> INFO: start marker: 0
[13:32:25.117] <TB3> INFO: stop marker: 0
[13:32:25.117] <TB3> INFO: overflow: 0
[13:32:25.117] <TB3> INFO: invalid 5bit words: 0
[13:32:25.117] <TB3> INFO: invalid XOR eye diagram: 0
[13:32:25.117] <TB3> INFO: frame (failed synchr.): 0
[13:32:25.117] <TB3> INFO: idle data (no TBM trl): 0
[13:32:25.117] <TB3> INFO: no data (only TBM hdr): 0
[13:32:25.117] <TB3> INFO: TBM errors: 0
[13:32:25.117] <TB3> INFO: flawed TBM headers: 0
[13:32:25.117] <TB3> INFO: flawed TBM trailers: 0
[13:32:25.117] <TB3> INFO: event ID mismatches: 0
[13:32:25.117] <TB3> INFO: ROC errors: 0
[13:32:25.117] <TB3> INFO: missing ROC header(s): 0
[13:32:25.117] <TB3> INFO: misplaced readback start: 0
[13:32:25.117] <TB3> INFO: Pixel decoding errors: 0
[13:32:25.117] <TB3> INFO: pixel data incomplete: 0
[13:32:25.117] <TB3> INFO: pixel address: 0
[13:32:25.118] <TB3> INFO: pulse height fill bit: 0
[13:32:25.118] <TB3> INFO: buffer corruption: 0
[13:32:25.118] <TB3> INFO: enter test to run
[13:32:25.118] <TB3> INFO: test: exit no parameter change
[13:32:25.247] <TB3> QUIET: Connection to board 170 closed.
[13:32:25.327] <TB3> INFO: pXar: this is the end, my friend