[10:31:45.822] <TB3> INFO: *** Welcome to pxar ***
[10:31:45.822] <TB3> INFO: *** Today: 2016/08/08
[10:31:45.929] <TB3> INFO: *** Version: 9751-dirty
[10:31:45.929] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C15.dat
[10:31:45.930] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:31:45.931] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//defaultMaskFile.dat
[10:31:45.931] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters_C15.dat
[10:31:46.004] <TB3> INFO: clk: 4
[10:31:46.004] <TB3> INFO: ctr: 4
[10:31:46.004] <TB3> INFO: sda: 19
[10:31:46.004] <TB3> INFO: tin: 9
[10:31:46.004] <TB3> INFO: level: 15
[10:31:46.004] <TB3> INFO: triggerdelay: 0
[10:31:46.004] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:31:46.004] <TB3> INFO: Log level: INFO
[10:31:46.011] <TB3> INFO: Found DTB DTB_WZ4I6J
[10:31:46.020] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[10:31:46.022] <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:
------------------------------------------------------
[10:31:46.024] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[10:31:47.581] <TB3> INFO: DUT info:
[10:31:47.581] <TB3> INFO: The DUT currently contains the following objects:
[10:31:47.581] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:31:47.581] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:31:47.581] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:31:47.581] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:31:47.581] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.581] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.582] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.582] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:47.982] <TB3> INFO: enter 'restricted' command line mode
[10:31:47.982] <TB3> INFO: enter test to run
[10:31:47.982] <TB3> INFO: test: pretest no parameter change
[10:31:47.982] <TB3> INFO: running: pretest
[10:31:47.988] <TB3> INFO: ----------------------------------------------------------------------
[10:31:47.988] <TB3> INFO: PixTestPretest::programROC()
[10:31:47.988] <TB3> INFO: ----------------------------------------------------------------------
[10:32:06.002] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:32:06.002] <TB3> INFO: IA differences per ROC: 16.9 18.5 16.1 20.1 18.5 17.7 16.9 16.9 20.1 20.1 16.9 20.1 19.3 20.9 17.7 16.9
[10:32:06.103] <TB3> INFO: enter test to run
[10:32:06.103] <TB3> INFO: test: pretest no parameter change
[10:32:06.103] <TB3> INFO: running: pretest
[10:32:06.104] <TB3> INFO: ----------------------------------------------------------------------
[10:32:06.104] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:32:06.104] <TB3> INFO: ----------------------------------------------------------------------
[10:32:27.373] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 395.5 mA = 24.7188 mA/ROC
[10:32:27.373] <TB3> INFO: i(loss) [mA/ROC]: 20.9 20.1 20.9 19.3 19.3 19.3 20.1 19.3 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3
[10:32:27.402] <TB3> INFO: enter test to run
[10:32:27.402] <TB3> INFO: test: pretest no parameter change
[10:32:27.403] <TB3> INFO: running: pretest
[10:32:27.403] <TB3> INFO: ----------------------------------------------------------------------
[10:32:27.403] <TB3> INFO: PixTestPretest::findTiming()
[10:32:27.403] <TB3> INFO: ----------------------------------------------------------------------
[10:32:27.403] <TB3> INFO: PixTestCmd::init()
[10:32:28.361] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[10:33:55.872] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:33:55.872] <TB3> INFO: (success/tries = 100/100), width = 5
[10:33:55.873] <TB3> INFO: enter test to run
[10:33:55.873] <TB3> INFO: test: pretest no parameter change
[10:33:55.873] <TB3> INFO: running: pretest
[10:33:55.875] <TB3> INFO: ----------------------------------------------------------------------
[10:33:55.875] <TB3> INFO: PixTestPretest::findWorkingPixel()
[10:33:55.875] <TB3> INFO: ----------------------------------------------------------------------
[10:33:55.965] <TB3> INFO: Expecting 231680 events.
[10:34:01.740] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[10:34:01.823] <TB3> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[10:34:06.171] <TB3> INFO: 231680 events read in total (9649ms).
[10:34:06.176] <TB3> INFO: Test took 10299ms.
[10:34:06.400] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:34:06.469] <TB3> INFO: enter test to run
[10:34:06.469] <TB3> INFO: test: pretest no parameter change
[10:34:06.469] <TB3> INFO: running: pretest
[10:34:06.471] <TB3> INFO: ----------------------------------------------------------------------
[10:34:06.471] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[10:34:06.471] <TB3> INFO: ----------------------------------------------------------------------
[10:34:06.563] <TB3> INFO: Expecting 231680 events.
[10:34:16.766] <TB3> INFO: 231680 events read in total (9647ms).
[10:34:16.770] <TB3> INFO: Test took 10296ms.
[10:34:16.990] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[10:34:16.990] <TB3> INFO: CalDel: 151 135 134 134 139 146 141 151 172 167 141 130 134 168 143 170
[10:34:16.990] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:34:17.026] <TB3> INFO: enter test to run
[10:34:17.026] <TB3> INFO: test: pretest no parameter change
[10:34:17.026] <TB3> INFO: running: pretest
[10:34:17.026] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C0.dat
[10:34:17.027] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C1.dat
[10:34:17.027] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C2.dat
[10:34:17.027] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C3.dat
[10:34:17.027] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C4.dat
[10:34:17.027] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C5.dat
[10:34:17.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C6.dat
[10:34:17.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C7.dat
[10:34:17.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C8.dat
[10:34:17.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C9.dat
[10:34:17.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C10.dat
[10:34:17.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C11.dat
[10:34:17.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C12.dat
[10:34:17.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C13.dat
[10:34:17.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C14.dat
[10:34:17.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C15.dat
[10:34:17.029] <TB3> INFO: enter test to run
[10:34:17.029] <TB3> INFO: test: fulltest no parameter change
[10:34:17.029] <TB3> INFO: running: fulltest
[10:34:17.029] <TB3> INFO: ######################################################################
[10:34:17.029] <TB3> INFO: PixTestFullTest::doTest()
[10:34:17.030] <TB3> INFO: ######################################################################
[10:34:17.031] <TB3> INFO: ######################################################################
[10:34:17.031] <TB3> INFO: PixTestAlive::doTest()
[10:34:17.031] <TB3> INFO: ######################################################################
[10:34:17.032] <TB3> INFO: ----------------------------------------------------------------------
[10:34:17.032] <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)
[10:34:17.032] <TB3> INFO: ----------------------------------------------------------------------
[10:34:17.308] <TB3> INFO: Expecting 41600 events.
[10:34:21.146] <TB3> INFO: 41600 events read in total (3282ms).
[10:34:21.147] <TB3> INFO: Test took 4113ms.
[10:34:21.372] <TB3> INFO: PixTestAlive::aliveTest() done
[10:34:21.372] <TB3> INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:34:21.374] <TB3> INFO: ----------------------------------------------------------------------
[10:34:21.374] <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)
[10:34:21.374] <TB3> INFO: ----------------------------------------------------------------------
[10:34:21.650] <TB3> INFO: Expecting 41600 events.
[10:34:24.607] <TB3> INFO: 41600 events read in total (2401ms).
[10:34:24.607] <TB3> INFO: Test took 3232ms.
[10:34:24.608] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:34:24.847] <TB3> INFO: PixTestAlive::maskTest() done
[10:34:24.847] <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
[10:34:24.848] <TB3> INFO: ----------------------------------------------------------------------
[10:34:24.848] <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)
[10:34:24.848] <TB3> INFO: ----------------------------------------------------------------------
[10:34:25.135] <TB3> INFO: Expecting 41600 events.
[10:34:28.959] <TB3> INFO: 41600 events read in total (3268ms).
[10:34:28.960] <TB3> INFO: Test took 4109ms.
[10:34:29.183] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[10:34:29.183] <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
[10:34:29.183] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:34:29.183] <TB3> INFO: Decoding statistics:
[10:34:29.183] <TB3> INFO: General information:
[10:34:29.183] <TB3> INFO: 16bit words read: 0
[10:34:29.183] <TB3> INFO: valid events total: 0
[10:34:29.183] <TB3> INFO: empty events: 0
[10:34:29.183] <TB3> INFO: valid events with pixels: 0
[10:34:29.183] <TB3> INFO: valid pixel hits: 0
[10:34:29.183] <TB3> INFO: Event errors: 0
[10:34:29.183] <TB3> INFO: start marker: 0
[10:34:29.183] <TB3> INFO: stop marker: 0
[10:34:29.183] <TB3> INFO: overflow: 0
[10:34:29.183] <TB3> INFO: invalid 5bit words: 0
[10:34:29.183] <TB3> INFO: invalid XOR eye diagram: 0
[10:34:29.183] <TB3> INFO: frame (failed synchr.): 0
[10:34:29.183] <TB3> INFO: idle data (no TBM trl): 0
[10:34:29.183] <TB3> INFO: no data (only TBM hdr): 0
[10:34:29.183] <TB3> INFO: TBM errors: 0
[10:34:29.183] <TB3> INFO: flawed TBM headers: 0
[10:34:29.183] <TB3> INFO: flawed TBM trailers: 0
[10:34:29.183] <TB3> INFO: event ID mismatches: 0
[10:34:29.183] <TB3> INFO: ROC errors: 0
[10:34:29.183] <TB3> INFO: missing ROC header(s): 0
[10:34:29.183] <TB3> INFO: misplaced readback start: 0
[10:34:29.183] <TB3> INFO: Pixel decoding errors: 0
[10:34:29.183] <TB3> INFO: pixel data incomplete: 0
[10:34:29.183] <TB3> INFO: pixel address: 0
[10:34:29.183] <TB3> INFO: pulse height fill bit: 0
[10:34:29.183] <TB3> INFO: buffer corruption: 0
[10:34:29.192] <TB3> INFO: ######################################################################
[10:34:29.193] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:34:29.193] <TB3> INFO: ######################################################################
[10:34:29.195] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:34:29.370] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:34:29.370] <TB3> INFO: run 1 of 1
[10:34:29.634] <TB3> INFO: Expecting 3120000 events.
[10:35:10.628] <TB3> INFO: 852330 events read in total (40438ms).
[10:35:51.112] <TB3> INFO: 1696275 events read in total (80922ms).
[10:36:31.261] <TB3> INFO: 2543915 events read in total (121072ms).
[10:36:58.511] <TB3> INFO: 3120000 events read in total (148321ms).
[10:36:58.557] <TB3> INFO: Test took 149187ms.
[10:37:22.253] <TB3> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[10:37:22.253] <TB3> INFO: number of dead bumps (per ROC): 4 14 0 3 5 4 1 0 1 3 1 5 19 0 5 1
[10:37:22.253] <TB3> INFO: separation cut (per ROC): 90 77 93 100 99 101 88 85 77 98 100 109 87 84 92 79
[10:37:22.253] <TB3> INFO: Decoding statistics:
[10:37:22.253] <TB3> INFO: General information:
[10:37:22.253] <TB3> INFO: 16bit words read: 0
[10:37:22.253] <TB3> INFO: valid events total: 0
[10:37:22.253] <TB3> INFO: empty events: 0
[10:37:22.253] <TB3> INFO: valid events with pixels: 0
[10:37:22.253] <TB3> INFO: valid pixel hits: 0
[10:37:22.253] <TB3> INFO: Event errors: 0
[10:37:22.253] <TB3> INFO: start marker: 0
[10:37:22.253] <TB3> INFO: stop marker: 0
[10:37:22.253] <TB3> INFO: overflow: 0
[10:37:22.253] <TB3> INFO: invalid 5bit words: 0
[10:37:22.253] <TB3> INFO: invalid XOR eye diagram: 0
[10:37:22.253] <TB3> INFO: frame (failed synchr.): 0
[10:37:22.253] <TB3> INFO: idle data (no TBM trl): 0
[10:37:22.253] <TB3> INFO: no data (only TBM hdr): 0
[10:37:22.253] <TB3> INFO: TBM errors: 0
[10:37:22.253] <TB3> INFO: flawed TBM headers: 0
[10:37:22.253] <TB3> INFO: flawed TBM trailers: 0
[10:37:22.253] <TB3> INFO: event ID mismatches: 0
[10:37:22.253] <TB3> INFO: ROC errors: 0
[10:37:22.253] <TB3> INFO: missing ROC header(s): 0
[10:37:22.253] <TB3> INFO: misplaced readback start: 0
[10:37:22.253] <TB3> INFO: Pixel decoding errors: 0
[10:37:22.253] <TB3> INFO: pixel data incomplete: 0
[10:37:22.253] <TB3> INFO: pixel address: 0
[10:37:22.253] <TB3> INFO: pulse height fill bit: 0
[10:37:22.253] <TB3> INFO: buffer corruption: 0
[10:37:22.324] <TB3> INFO: ######################################################################
[10:37:22.324] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:37:22.324] <TB3> INFO: ######################################################################
[10:37:22.324] <TB3> INFO: ----------------------------------------------------------------------
[10:37:22.324] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:37:22.324] <TB3> INFO: ----------------------------------------------------------------------
[10:37:22.324] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:37:22.333] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:37:22.333] <TB3> INFO: run 1 of 1
[10:37:22.625] <TB3> INFO: Expecting 26208000 events.
[10:37:55.857] <TB3> INFO: 893700 events read in total (32676ms).
[10:38:28.754] <TB3> INFO: 1777800 events read in total (65573ms).
[10:39:01.206] <TB3> INFO: 2659500 events read in total (98025ms).
[10:39:33.934] <TB3> INFO: 3539850 events read in total (130753ms).
[10:40:06.649] <TB3> INFO: 4418800 events read in total (163468ms).
[10:40:39.429] <TB3> INFO: 5297500 events read in total (196248ms).
[10:41:11.901] <TB3> INFO: 6177800 events read in total (228720ms).
[10:41:44.548] <TB3> INFO: 7053400 events read in total (261367ms).
[10:42:17.297] <TB3> INFO: 7930200 events read in total (294116ms).
[10:42:49.890] <TB3> INFO: 8806350 events read in total (326709ms).
[10:43:22.676] <TB3> INFO: 9684300 events read in total (359495ms).
[10:43:55.334] <TB3> INFO: 10558900 events read in total (392153ms).
[10:44:27.698] <TB3> INFO: 11433650 events read in total (424517ms).
[10:45:00.536] <TB3> INFO: 12309150 events read in total (457355ms).
[10:45:33.076] <TB3> INFO: 13181850 events read in total (489895ms).
[10:46:05.482] <TB3> INFO: 14046150 events read in total (522301ms).
[10:46:38.070] <TB3> INFO: 14910550 events read in total (554889ms).
[10:47:10.629] <TB3> INFO: 15775200 events read in total (587448ms).
[10:47:43.330] <TB3> INFO: 16637050 events read in total (620149ms).
[10:48:15.956] <TB3> INFO: 17498500 events read in total (652775ms).
[10:48:48.606] <TB3> INFO: 18360950 events read in total (685425ms).
[10:49:21.117] <TB3> INFO: 19221000 events read in total (717936ms).
[10:49:54.481] <TB3> INFO: 20081200 events read in total (751300ms).
[10:50:27.828] <TB3> INFO: 20941650 events read in total (784647ms).
[10:51:00.598] <TB3> INFO: 21803450 events read in total (817417ms).
[10:51:32.997] <TB3> INFO: 22663750 events read in total (849816ms).
[10:52:06.079] <TB3> INFO: 23524950 events read in total (882898ms).
[10:52:38.461] <TB3> INFO: 24387250 events read in total (915280ms).
[10:53:10.876] <TB3> INFO: 25249550 events read in total (947695ms).
[10:53:43.670] <TB3> INFO: 26124000 events read in total (980489ms).
[10:53:47.164] <TB3> INFO: 26208000 events read in total (983983ms).
[10:53:47.220] <TB3> INFO: Test took 984887ms.
[10:53:47.597] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:53:49.100] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:53:50.745] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:53:52.734] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:53:54.813] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:53:56.978] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:53:58.951] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:00.333] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:01.729] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:03.201] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:04.650] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:06.098] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:07.511] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:08.946] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:10.340] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:11.696] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:54:13.168] <TB3> INFO: PixTestScurves::scurves() done
[10:54:13.168] <TB3> INFO: Vcal mean: 93.83 82.47 101.44 101.40 92.63 89.33 84.87 95.22 77.36 81.32 91.65 97.34 87.56 79.91 95.32 78.92
[10:54:13.168] <TB3> INFO: Vcal RMS: 5.89 4.46 5.97 5.71 4.91 5.10 4.45 5.39 3.77 3.99 5.10 4.98 5.64 3.82 5.44 3.79
[10:54:13.168] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1010 seconds
[10:54:13.168] <TB3> INFO: Decoding statistics:
[10:54:13.168] <TB3> INFO: General information:
[10:54:13.168] <TB3> INFO: 16bit words read: 0
[10:54:13.168] <TB3> INFO: valid events total: 0
[10:54:13.168] <TB3> INFO: empty events: 0
[10:54:13.168] <TB3> INFO: valid events with pixels: 0
[10:54:13.168] <TB3> INFO: valid pixel hits: 0
[10:54:13.168] <TB3> INFO: Event errors: 0
[10:54:13.168] <TB3> INFO: start marker: 0
[10:54:13.168] <TB3> INFO: stop marker: 0
[10:54:13.168] <TB3> INFO: overflow: 0
[10:54:13.168] <TB3> INFO: invalid 5bit words: 0
[10:54:13.168] <TB3> INFO: invalid XOR eye diagram: 0
[10:54:13.168] <TB3> INFO: frame (failed synchr.): 0
[10:54:13.169] <TB3> INFO: idle data (no TBM trl): 0
[10:54:13.169] <TB3> INFO: no data (only TBM hdr): 0
[10:54:13.169] <TB3> INFO: TBM errors: 0
[10:54:13.169] <TB3> INFO: flawed TBM headers: 0
[10:54:13.169] <TB3> INFO: flawed TBM trailers: 0
[10:54:13.169] <TB3> INFO: event ID mismatches: 0
[10:54:13.169] <TB3> INFO: ROC errors: 0
[10:54:13.169] <TB3> INFO: missing ROC header(s): 0
[10:54:13.169] <TB3> INFO: misplaced readback start: 0
[10:54:13.169] <TB3> INFO: Pixel decoding errors: 0
[10:54:13.169] <TB3> INFO: pixel data incomplete: 0
[10:54:13.169] <TB3> INFO: pixel address: 0
[10:54:13.169] <TB3> INFO: pulse height fill bit: 0
[10:54:13.169] <TB3> INFO: buffer corruption: 0
[10:54:13.272] <TB3> INFO: ######################################################################
[10:54:13.272] <TB3> INFO: PixTestTrim::doTest()
[10:54:13.272] <TB3> INFO: ######################################################################
[10:54:13.273] <TB3> INFO: ----------------------------------------------------------------------
[10:54:13.273] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:54:13.273] <TB3> INFO: ----------------------------------------------------------------------
[10:54:13.376] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:54:13.376] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:54:13.384] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:54:13.384] <TB3> INFO: run 1 of 1
[10:54:13.643] <TB3> INFO: Expecting 6281600 events.
[10:54:53.521] <TB3> INFO: 1055850 events read in total (39320ms).
[10:55:32.456] <TB3> INFO: 2107360 events read in total (78256ms).
[10:56:11.033] <TB3> INFO: 3152450 events read in total (116832ms).
[10:56:49.485] <TB3> INFO: 4202090 events read in total (155284ms).
[10:57:28.332] <TB3> INFO: 5253870 events read in total (194132ms).
[10:58:06.946] <TB3> INFO: 6281600 events read in total (232745ms).
[10:58:06.979] <TB3> INFO: Test took 233595ms.
[10:58:27.370] <TB3> INFO: ROC 0 VthrComp = 97
[10:58:27.370] <TB3> INFO: ROC 1 VthrComp = 93
[10:58:27.370] <TB3> INFO: ROC 2 VthrComp = 100
[10:58:27.370] <TB3> INFO: ROC 3 VthrComp = 104
[10:58:27.370] <TB3> INFO: ROC 4 VthrComp = 99
[10:58:27.371] <TB3> INFO: ROC 5 VthrComp = 97
[10:58:27.371] <TB3> INFO: ROC 6 VthrComp = 93
[10:58:27.371] <TB3> INFO: ROC 7 VthrComp = 96
[10:58:27.371] <TB3> INFO: ROC 8 VthrComp = 83
[10:58:27.371] <TB3> INFO: ROC 9 VthrComp = 90
[10:58:27.371] <TB3> INFO: ROC 10 VthrComp = 97
[10:58:27.371] <TB3> INFO: ROC 11 VthrComp = 108
[10:58:27.371] <TB3> INFO: ROC 12 VthrComp = 95
[10:58:27.371] <TB3> INFO: ROC 13 VthrComp = 90
[10:58:27.371] <TB3> INFO: ROC 14 VthrComp = 98
[10:58:27.372] <TB3> INFO: ROC 15 VthrComp = 84
[10:58:27.372] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:58:27.372] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:58:27.380] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:58:27.380] <TB3> INFO: run 1 of 1
[10:58:27.666] <TB3> INFO: Expecting 6281600 events.
[10:59:02.668] <TB3> INFO: 755360 events read in total (34446ms).
[10:59:37.403] <TB3> INFO: 1509030 events read in total (69181ms).
[11:00:12.098] <TB3> INFO: 2262660 events read in total (103876ms).
[11:00:46.759] <TB3> INFO: 3016620 events read in total (138537ms).
[11:01:21.495] <TB3> INFO: 3765700 events read in total (173273ms).
[11:01:56.324] <TB3> INFO: 4511280 events read in total (208102ms).
[11:02:31.191] <TB3> INFO: 5254870 events read in total (242969ms).
[11:03:05.970] <TB3> INFO: 5998700 events read in total (277748ms).
[11:03:19.341] <TB3> INFO: 6281600 events read in total (291119ms).
[11:03:19.395] <TB3> INFO: Test took 292016ms.
[11:03:44.268] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 59.0869 for pixel 14/13 mean/min/max = 45.3466/31.4331/59.2601
[11:03:44.268] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 56.1251 for pixel 51/78 mean/min/max = 44.3115/32.2696/56.3535
[11:03:44.269] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 63.015 for pixel 1/57 mean/min/max = 47.3181/31.4841/63.1521
[11:03:44.269] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 62.0939 for pixel 34/6 mean/min/max = 47.4818/32.7507/62.2129
[11:03:44.269] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 57.8851 for pixel 1/57 mean/min/max = 45.0704/32.2217/57.919
[11:03:44.269] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 57.7955 for pixel 16/69 mean/min/max = 45.001/32.1663/57.8356
[11:03:44.269] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 56.2007 for pixel 47/7 mean/min/max = 44.2988/32.2748/56.3227
[11:03:44.270] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 60.0931 for pixel 50/3 mean/min/max = 46.0565/32.0181/60.0949
[11:03:44.270] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 55.4661 for pixel 51/33 mean/min/max = 44.2341/32.6727/55.7955
[11:03:44.270] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 55.6493 for pixel 12/18 mean/min/max = 44.3339/32.9064/55.7614
[11:03:44.270] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 57.734 for pixel 24/64 mean/min/max = 44.9619/32.1568/57.767
[11:03:44.271] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.7203 for pixel 5/72 mean/min/max = 45.9153/33.9704/57.8602
[11:03:44.271] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 61.6153 for pixel 0/63 mean/min/max = 46.8256/31.876/61.7752
[11:03:44.271] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 56.1224 for pixel 17/15 mean/min/max = 44.5653/32.944/56.1866
[11:03:44.271] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.0738 for pixel 17/1 mean/min/max = 45.2282/31.3279/59.1285
[11:03:44.272] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 56.009 for pixel 16/2 mean/min/max = 44.8113/32.7493/56.8733
[11:03:44.272] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:44.360] <TB3> INFO: Expecting 514560 events.
[11:03:57.332] <TB3> INFO: 514560 events read in total (12416ms).
[11:03:57.337] <TB3> INFO: Expecting 514560 events.
[11:04:10.210] <TB3> INFO: 514560 events read in total (12450ms).
[11:04:10.217] <TB3> INFO: Expecting 514560 events.
[11:04:23.107] <TB3> INFO: 514560 events read in total (12472ms).
[11:04:23.116] <TB3> INFO: Expecting 514560 events.
[11:04:36.092] <TB3> INFO: 514560 events read in total (12568ms).
[11:04:36.104] <TB3> INFO: Expecting 514560 events.
[11:04:49.034] <TB3> INFO: 514560 events read in total (12525ms).
[11:04:49.048] <TB3> INFO: Expecting 514560 events.
[11:05:01.893] <TB3> INFO: 514560 events read in total (12436ms).
[11:05:01.909] <TB3> INFO: Expecting 514560 events.
[11:05:14.834] <TB3> INFO: 514560 events read in total (12523ms).
[11:05:14.851] <TB3> INFO: Expecting 514560 events.
[11:05:27.877] <TB3> INFO: 514560 events read in total (12617ms).
[11:05:27.898] <TB3> INFO: Expecting 514560 events.
[11:05:40.684] <TB3> INFO: 514560 events read in total (12384ms).
[11:05:40.705] <TB3> INFO: Expecting 514560 events.
[11:05:53.610] <TB3> INFO: 514560 events read in total (12495ms).
[11:05:53.636] <TB3> INFO: Expecting 514560 events.
[11:06:06.527] <TB3> INFO: 514560 events read in total (12490ms).
[11:06:06.552] <TB3> INFO: Expecting 514560 events.
[11:06:19.514] <TB3> INFO: 514560 events read in total (12560ms).
[11:06:19.545] <TB3> INFO: Expecting 514560 events.
[11:06:32.450] <TB3> INFO: 514560 events read in total (12504ms).
[11:06:32.480] <TB3> INFO: Expecting 514560 events.
[11:06:45.424] <TB3> INFO: 514560 events read in total (12543ms).
[11:06:45.457] <TB3> INFO: Expecting 514560 events.
[11:06:58.351] <TB3> INFO: 514560 events read in total (12492ms).
[11:06:58.386] <TB3> INFO: Expecting 514560 events.
[11:07:11.286] <TB3> INFO: 514560 events read in total (12498ms).
[11:07:11.330] <TB3> INFO: Test took 207058ms.
[11:07:12.496] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:07:12.504] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:07:12.504] <TB3> INFO: run 1 of 1
[11:07:12.781] <TB3> INFO: Expecting 6281600 events.
[11:07:47.955] <TB3> INFO: 742310 events read in total (34618ms).
[11:08:22.531] <TB3> INFO: 1483620 events read in total (69194ms).
[11:08:56.830] <TB3> INFO: 2225070 events read in total (103493ms).
[11:09:31.010] <TB3> INFO: 2966780 events read in total (137673ms).
[11:10:05.382] <TB3> INFO: 3702510 events read in total (172045ms).
[11:10:39.604] <TB3> INFO: 4434650 events read in total (206267ms).
[11:11:14.201] <TB3> INFO: 5164610 events read in total (240864ms).
[11:11:48.410] <TB3> INFO: 5893590 events read in total (275073ms).
[11:12:07.456] <TB3> INFO: 6281600 events read in total (294119ms).
[11:12:07.520] <TB3> INFO: Test took 295016ms.
[11:12:32.311] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.055029 .. 255.000000
[11:12:32.386] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[11:12:32.394] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:12:32.394] <TB3> INFO: run 1 of 1
[11:12:32.653] <TB3> INFO: Expecting 10649600 events.
[11:13:07.526] <TB3> INFO: 712060 events read in total (34317ms).
[11:13:41.779] <TB3> INFO: 1424620 events read in total (68570ms).
[11:14:15.827] <TB3> INFO: 2137210 events read in total (102618ms).
[11:14:49.953] <TB3> INFO: 2849760 events read in total (136744ms).
[11:15:24.628] <TB3> INFO: 3562480 events read in total (171419ms).
[11:15:59.027] <TB3> INFO: 4275030 events read in total (205818ms).
[11:16:32.654] <TB3> INFO: 4987860 events read in total (239445ms).
[11:17:06.544] <TB3> INFO: 5700340 events read in total (273335ms).
[11:17:40.502] <TB3> INFO: 6412680 events read in total (307293ms).
[11:18:14.880] <TB3> INFO: 7124330 events read in total (341671ms).
[11:18:50.213] <TB3> INFO: 7835790 events read in total (377004ms).
[11:19:25.092] <TB3> INFO: 8546590 events read in total (411883ms).
[11:19:59.503] <TB3> INFO: 9257530 events read in total (446294ms).
[11:20:33.602] <TB3> INFO: 9968320 events read in total (480393ms).
[11:21:06.653] <TB3> INFO: 10649600 events read in total (513444ms).
[11:21:06.752] <TB3> INFO: Test took 514359ms.
[11:21:37.072] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 17.378748 .. 49.401091
[11:21:37.147] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 59 (-1/-1) hits flags = 528 (plus default)
[11:21:37.155] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:21:37.155] <TB3> INFO: run 1 of 1
[11:21:37.418] <TB3> INFO: Expecting 2204800 events.
[11:22:15.052] <TB3> INFO: 900290 events read in total (37077ms).
[11:22:52.037] <TB3> INFO: 1799280 events read in total (74063ms).
[11:23:09.320] <TB3> INFO: 2204800 events read in total (91346ms).
[11:23:09.340] <TB3> INFO: Test took 92185ms.
[11:23:24.351] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 22.627034 .. 46.991653
[11:23:24.442] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 56 (-1/-1) hits flags = 528 (plus default)
[11:23:24.453] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:23:24.453] <TB3> INFO: run 1 of 1
[11:23:24.713] <TB3> INFO: Expecting 1872000 events.
[11:24:02.331] <TB3> INFO: 892220 events read in total (37061ms).
[11:24:39.225] <TB3> INFO: 1783030 events read in total (73955ms).
[11:24:43.225] <TB3> INFO: 1872000 events read in total (77956ms).
[11:24:43.241] <TB3> INFO: Test took 78789ms.
[11:24:56.985] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 4.500000 .. 46.067808
[11:24:57.060] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 4 .. 56 (-1/-1) hits flags = 528 (plus default)
[11:24:57.068] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:24:57.068] <TB3> INFO: run 1 of 1
[11:24:57.328] <TB3> INFO: Expecting 2204800 events.
[11:25:35.237] <TB3> INFO: 938550 events read in total (37353ms).
[11:26:12.628] <TB3> INFO: 1876840 events read in total (74744ms).
[11:26:26.103] <TB3> INFO: 2204800 events read in total (88220ms).
[11:26:26.121] <TB3> INFO: Test took 89054ms.
[11:26:40.259] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:26:40.259] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:26:40.267] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:26:40.268] <TB3> INFO: run 1 of 1
[11:26:40.527] <TB3> INFO: Expecting 1705600 events.
[11:27:18.047] <TB3> INFO: 879370 events read in total (36964ms).
[11:27:53.069] <TB3> INFO: 1705600 events read in total (71986ms).
[11:27:53.091] <TB3> INFO: Test took 72824ms.
[11:28:07.839] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:28:07.839] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:28:07.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:28:07.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:28:07.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:28:07.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:28:07.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:28:07.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:28:07.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:28:07.840] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:28:07.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:28:07.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:28:07.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:28:07.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:28:07.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:28:07.841] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:28:07.842] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C0.dat
[11:28:07.849] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C1.dat
[11:28:07.857] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C2.dat
[11:28:07.864] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C3.dat
[11:28:07.871] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C4.dat
[11:28:07.877] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C5.dat
[11:28:07.883] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C6.dat
[11:28:07.889] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C7.dat
[11:28:07.895] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C8.dat
[11:28:07.901] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C9.dat
[11:28:07.908] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C10.dat
[11:28:07.914] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C11.dat
[11:28:07.920] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C12.dat
[11:28:07.926] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C13.dat
[11:28:07.932] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C14.dat
[11:28:07.938] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C15.dat
[11:28:07.944] <TB3> INFO: PixTestTrim::trimTest() done
[11:28:07.944] <TB3> INFO: vtrim: 97 95 122 122 107 117 87 111 89 98 102 108 108 97 102 91
[11:28:07.944] <TB3> INFO: vthrcomp: 97 93 100 104 99 97 93 96 83 90 97 108 95 90 98 84
[11:28:07.944] <TB3> INFO: vcal mean: 34.87 34.99 35.05 34.99 34.99 34.97 34.97 34.95 34.96 34.99 34.99 35.01 35.02 35.01 34.97 35.07
[11:28:07.944] <TB3> INFO: vcal RMS: 1.14 0.76 0.91 0.89 0.82 0.83 0.81 0.99 0.77 0.77 0.81 0.79 0.83 0.77 0.90 0.82
[11:28:07.944] <TB3> INFO: bits mean: 9.46 8.86 9.31 9.08 9.62 9.97 9.85 9.25 9.75 9.62 9.66 8.93 8.88 9.70 9.70 9.31
[11:28:07.944] <TB3> INFO: bits RMS: 2.81 3.03 2.73 2.60 2.64 2.52 2.57 2.74 2.55 2.55 2.60 2.63 2.91 2.52 2.70 2.76
[11:28:07.950] <TB3> INFO: ----------------------------------------------------------------------
[11:28:07.950] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:28:07.950] <TB3> INFO: ----------------------------------------------------------------------
[11:28:07.953] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:28:07.961] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:28:07.961] <TB3> INFO: run 1 of 1
[11:28:08.221] <TB3> INFO: Expecting 4160000 events.
[11:28:49.959] <TB3> INFO: 919630 events read in total (41182ms).
[11:29:31.108] <TB3> INFO: 1832930 events read in total (82331ms).
[11:30:11.598] <TB3> INFO: 2738670 events read in total (122822ms).
[11:30:52.166] <TB3> INFO: 3641475 events read in total (163389ms).
[11:31:15.615] <TB3> INFO: 4160000 events read in total (186838ms).
[11:31:15.656] <TB3> INFO: Test took 187695ms.
[11:31:44.482] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 174 (-1/-1) hits flags = 528 (plus default)
[11:31:44.490] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:31:44.490] <TB3> INFO: run 1 of 1
[11:31:44.754] <TB3> INFO: Expecting 3640000 events.
[11:32:26.720] <TB3> INFO: 936185 events read in total (41410ms).
[11:33:07.537] <TB3> INFO: 1865245 events read in total (82227ms).
[11:33:48.198] <TB3> INFO: 2783745 events read in total (122888ms).
[11:34:26.052] <TB3> INFO: 3640000 events read in total (160742ms).
[11:34:26.093] <TB3> INFO: Test took 161603ms.
[11:34:53.119] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[11:34:53.128] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:34:53.128] <TB3> INFO: run 1 of 1
[11:34:53.393] <TB3> INFO: Expecting 3369600 events.
[11:35:35.752] <TB3> INFO: 966900 events read in total (41803ms).
[11:36:17.534] <TB3> INFO: 1923680 events read in total (83586ms).
[11:36:59.133] <TB3> INFO: 2870490 events read in total (125184ms).
[11:37:21.198] <TB3> INFO: 3369600 events read in total (147249ms).
[11:37:21.234] <TB3> INFO: Test took 148106ms.
[11:37:47.440] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:37:47.448] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:37:47.448] <TB3> INFO: run 1 of 1
[11:37:47.708] <TB3> INFO: Expecting 3348800 events.
[11:38:30.084] <TB3> INFO: 968560 events read in total (41819ms).
[11:39:11.556] <TB3> INFO: 1926840 events read in total (83291ms).
[11:39:52.552] <TB3> INFO: 2874885 events read in total (124287ms).
[11:40:13.232] <TB3> INFO: 3348800 events read in total (144967ms).
[11:40:13.265] <TB3> INFO: Test took 145817ms.
[11:40:40.516] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 159 (-1/-1) hits flags = 528 (plus default)
[11:40:40.525] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:40:40.525] <TB3> INFO: run 1 of 1
[11:40:40.785] <TB3> INFO: Expecting 3328000 events.
[11:41:23.643] <TB3> INFO: 970725 events read in total (42301ms).
[11:42:05.682] <TB3> INFO: 1930680 events read in total (84340ms).
[11:42:47.253] <TB3> INFO: 2880665 events read in total (125911ms).
[11:43:07.054] <TB3> INFO: 3328000 events read in total (145712ms).
[11:43:07.093] <TB3> INFO: Test took 146568ms.
[11:43:32.397] <TB3> INFO: PixTestTrim::trimBitTest() done
[11:43:32.398] <TB3> INFO: PixTestTrim::doTest() done, duration: 2959 seconds
[11:43:32.398] <TB3> INFO: Decoding statistics:
[11:43:32.398] <TB3> INFO: General information:
[11:43:32.398] <TB3> INFO: 16bit words read: 0
[11:43:32.398] <TB3> INFO: valid events total: 0
[11:43:32.398] <TB3> INFO: empty events: 0
[11:43:32.398] <TB3> INFO: valid events with pixels: 0
[11:43:32.398] <TB3> INFO: valid pixel hits: 0
[11:43:32.398] <TB3> INFO: Event errors: 0
[11:43:32.398] <TB3> INFO: start marker: 0
[11:43:32.398] <TB3> INFO: stop marker: 0
[11:43:32.398] <TB3> INFO: overflow: 0
[11:43:32.398] <TB3> INFO: invalid 5bit words: 0
[11:43:32.398] <TB3> INFO: invalid XOR eye diagram: 0
[11:43:32.398] <TB3> INFO: frame (failed synchr.): 0
[11:43:32.398] <TB3> INFO: idle data (no TBM trl): 0
[11:43:32.398] <TB3> INFO: no data (only TBM hdr): 0
[11:43:32.398] <TB3> INFO: TBM errors: 0
[11:43:32.398] <TB3> INFO: flawed TBM headers: 0
[11:43:32.398] <TB3> INFO: flawed TBM trailers: 0
[11:43:32.398] <TB3> INFO: event ID mismatches: 0
[11:43:32.398] <TB3> INFO: ROC errors: 0
[11:43:32.398] <TB3> INFO: missing ROC header(s): 0
[11:43:32.398] <TB3> INFO: misplaced readback start: 0
[11:43:32.398] <TB3> INFO: Pixel decoding errors: 0
[11:43:32.398] <TB3> INFO: pixel data incomplete: 0
[11:43:32.398] <TB3> INFO: pixel address: 0
[11:43:32.398] <TB3> INFO: pulse height fill bit: 0
[11:43:32.398] <TB3> INFO: buffer corruption: 0
[11:43:33.112] <TB3> INFO: ######################################################################
[11:43:33.112] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:43:33.112] <TB3> INFO: ######################################################################
[11:43:33.378] <TB3> INFO: Expecting 41600 events.
[11:43:37.186] <TB3> INFO: 41600 events read in total (3252ms).
[11:43:37.187] <TB3> INFO: Test took 4074ms.
[11:43:37.659] <TB3> INFO: Expecting 41600 events.
[11:43:41.480] <TB3> INFO: 41600 events read in total (3265ms).
[11:43:41.480] <TB3> INFO: Test took 4089ms.
[11:43:41.767] <TB3> INFO: Expecting 41600 events.
[11:43:45.530] <TB3> INFO: 41600 events read in total (3206ms).
[11:43:45.531] <TB3> INFO: Test took 4029ms.
[11:43:45.785] <TB3> INFO: Expecting 2560 events.
[11:43:46.638] <TB3> INFO: 2560 events read in total (297ms).
[11:43:46.638] <TB3> INFO: Test took 1095ms.
[11:43:46.944] <TB3> INFO: Expecting 2560 events.
[11:43:47.802] <TB3> INFO: 2560 events read in total (302ms).
[11:43:47.802] <TB3> INFO: Test took 1164ms.
[11:43:48.107] <TB3> INFO: Expecting 2560 events.
[11:43:48.962] <TB3> INFO: 2560 events read in total (299ms).
[11:43:48.962] <TB3> INFO: Test took 1160ms.
[11:43:49.268] <TB3> INFO: Expecting 2560 events.
[11:43:50.121] <TB3> INFO: 2560 events read in total (297ms).
[11:43:50.122] <TB3> INFO: Test took 1159ms.
[11:43:50.427] <TB3> INFO: Expecting 2560 events.
[11:43:51.280] <TB3> INFO: 2560 events read in total (297ms).
[11:43:51.280] <TB3> INFO: Test took 1158ms.
[11:43:51.585] <TB3> INFO: Expecting 2560 events.
[11:43:52.438] <TB3> INFO: 2560 events read in total (297ms).
[11:43:52.438] <TB3> INFO: Test took 1158ms.
[11:43:52.744] <TB3> INFO: Expecting 2560 events.
[11:43:53.602] <TB3> INFO: 2560 events read in total (302ms).
[11:43:53.602] <TB3> INFO: Test took 1163ms.
[11:43:53.907] <TB3> INFO: Expecting 2560 events.
[11:43:54.761] <TB3> INFO: 2560 events read in total (297ms).
[11:43:54.761] <TB3> INFO: Test took 1159ms.
[11:43:55.066] <TB3> INFO: Expecting 2560 events.
[11:43:55.920] <TB3> INFO: 2560 events read in total (298ms).
[11:43:55.920] <TB3> INFO: Test took 1158ms.
[11:43:56.226] <TB3> INFO: Expecting 2560 events.
[11:43:57.079] <TB3> INFO: 2560 events read in total (297ms).
[11:43:57.079] <TB3> INFO: Test took 1158ms.
[11:43:57.384] <TB3> INFO: Expecting 2560 events.
[11:43:58.237] <TB3> INFO: 2560 events read in total (297ms).
[11:43:58.237] <TB3> INFO: Test took 1157ms.
[11:43:58.543] <TB3> INFO: Expecting 2560 events.
[11:43:59.396] <TB3> INFO: 2560 events read in total (297ms).
[11:43:59.396] <TB3> INFO: Test took 1158ms.
[11:43:59.701] <TB3> INFO: Expecting 2560 events.
[11:44:00.555] <TB3> INFO: 2560 events read in total (298ms).
[11:44:00.555] <TB3> INFO: Test took 1159ms.
[11:44:00.860] <TB3> INFO: Expecting 2560 events.
[11:44:01.713] <TB3> INFO: 2560 events read in total (297ms).
[11:44:01.713] <TB3> INFO: Test took 1157ms.
[11:44:02.018] <TB3> INFO: Expecting 2560 events.
[11:44:02.876] <TB3> INFO: 2560 events read in total (301ms).
[11:44:02.876] <TB3> INFO: Test took 1162ms.
[11:44:03.181] <TB3> INFO: Expecting 2560 events.
[11:44:04.034] <TB3> INFO: 2560 events read in total (297ms).
[11:44:04.034] <TB3> INFO: Test took 1158ms.
[11:44:04.037] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:44:04.341] <TB3> INFO: Expecting 655360 events.
[11:44:21.097] <TB3> INFO: 655360 events read in total (16200ms).
[11:44:21.106] <TB3> INFO: Expecting 655360 events.
[11:44:37.885] <TB3> INFO: 655360 events read in total (16378ms).
[11:44:37.897] <TB3> INFO: Expecting 655360 events.
[11:44:54.656] <TB3> INFO: 655360 events read in total (16358ms).
[11:44:54.678] <TB3> INFO: Expecting 655360 events.
[11:45:11.373] <TB3> INFO: 655360 events read in total (16293ms).
[11:45:11.392] <TB3> INFO: Expecting 655360 events.
[11:45:28.261] <TB3> INFO: 655360 events read in total (16468ms).
[11:45:28.284] <TB3> INFO: Expecting 655360 events.
[11:45:45.390] <TB3> INFO: 655360 events read in total (16704ms).
[11:45:45.416] <TB3> INFO: Expecting 655360 events.
[11:46:02.190] <TB3> INFO: 655360 events read in total (16372ms).
[11:46:02.221] <TB3> INFO: Expecting 655360 events.
[11:46:19.031] <TB3> INFO: 655360 events read in total (16408ms).
[11:46:19.067] <TB3> INFO: Expecting 655360 events.
[11:46:35.982] <TB3> INFO: 655360 events read in total (16513ms).
[11:46:36.018] <TB3> INFO: Expecting 655360 events.
[11:46:52.693] <TB3> INFO: 655360 events read in total (16273ms).
[11:46:52.736] <TB3> INFO: Expecting 655360 events.
[11:47:09.366] <TB3> INFO: 655360 events read in total (16229ms).
[11:47:09.408] <TB3> INFO: Expecting 655360 events.
[11:47:25.901] <TB3> INFO: 655360 events read in total (16091ms).
[11:47:25.949] <TB3> INFO: Expecting 655360 events.
[11:47:42.652] <TB3> INFO: 655360 events read in total (16301ms).
[11:47:42.725] <TB3> INFO: Expecting 655360 events.
[11:47:59.326] <TB3> INFO: 655360 events read in total (16200ms).
[11:47:59.380] <TB3> INFO: Expecting 655360 events.
[11:48:15.933] <TB3> INFO: 655360 events read in total (16151ms).
[11:48:16.021] <TB3> INFO: Expecting 655360 events.
[11:48:32.666] <TB3> INFO: 655360 events read in total (16243ms).
[11:48:32.777] <TB3> INFO: Test took 268740ms.
[11:48:32.859] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:48:33.088] <TB3> INFO: Expecting 655360 events.
[11:48:49.981] <TB3> INFO: 655360 events read in total (16337ms).
[11:48:49.990] <TB3> INFO: Expecting 655360 events.
[11:49:06.455] <TB3> INFO: 655360 events read in total (16063ms).
[11:49:06.467] <TB3> INFO: Expecting 655360 events.
[11:49:23.243] <TB3> INFO: 655360 events read in total (16374ms).
[11:49:23.259] <TB3> INFO: Expecting 655360 events.
[11:49:40.098] <TB3> INFO: 655360 events read in total (16437ms).
[11:49:40.119] <TB3> INFO: Expecting 655360 events.
[11:49:56.926] <TB3> INFO: 655360 events read in total (16405ms).
[11:49:56.951] <TB3> INFO: Expecting 655360 events.
[11:50:13.731] <TB3> INFO: 655360 events read in total (16378ms).
[11:50:13.757] <TB3> INFO: Expecting 655360 events.
[11:50:30.552] <TB3> INFO: 655360 events read in total (16393ms).
[11:50:30.581] <TB3> INFO: Expecting 655360 events.
[11:50:47.236] <TB3> INFO: 655360 events read in total (16253ms).
[11:50:47.269] <TB3> INFO: Expecting 655360 events.
[11:51:03.954] <TB3> INFO: 655360 events read in total (16283ms).
[11:51:03.990] <TB3> INFO: Expecting 655360 events.
[11:51:20.489] <TB3> INFO: 655360 events read in total (16097ms).
[11:51:20.529] <TB3> INFO: Expecting 655360 events.
[11:51:37.262] <TB3> INFO: 655360 events read in total (16332ms).
[11:51:37.305] <TB3> INFO: Expecting 655360 events.
[11:51:54.094] <TB3> INFO: 655360 events read in total (16387ms).
[11:51:54.140] <TB3> INFO: Expecting 655360 events.
[11:52:11.124] <TB3> INFO: 655360 events read in total (16583ms).
[11:52:11.177] <TB3> INFO: Expecting 655360 events.
[11:52:27.809] <TB3> INFO: 655360 events read in total (16231ms).
[11:52:27.863] <TB3> INFO: Expecting 655360 events.
[11:52:44.589] <TB3> INFO: 655360 events read in total (16324ms).
[11:52:44.646] <TB3> INFO: Expecting 655360 events.
[11:53:01.283] <TB3> INFO: 655360 events read in total (16235ms).
[11:53:01.359] <TB3> INFO: Test took 268500ms.
[11:53:01.550] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.556] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.563] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.569] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.576] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:53:01.585] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.594] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.604] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.612] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.624] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.633] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.641] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.648] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.655] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.661] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.668] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.674] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:53:01.713] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:53:01.713] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:53:01.713] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:53:01.713] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:53:01.714] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:53:01.715] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:53:01.715] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:53:01.715] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:53:02.004] <TB3> INFO: Expecting 41600 events.
[11:53:05.473] <TB3> INFO: 41600 events read in total (2913ms).
[11:53:05.473] <TB3> INFO: Test took 3755ms.
[11:53:05.921] <TB3> INFO: Expecting 41600 events.
[11:53:09.419] <TB3> INFO: 41600 events read in total (2941ms).
[11:53:09.420] <TB3> INFO: Test took 3767ms.
[11:53:09.890] <TB3> INFO: Expecting 41600 events.
[11:53:13.375] <TB3> INFO: 41600 events read in total (2929ms).
[11:53:13.375] <TB3> INFO: Test took 3776ms.
[11:53:13.556] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:13.644] <TB3> INFO: Expecting 2560 events.
[11:53:14.498] <TB3> INFO: 2560 events read in total (298ms).
[11:53:14.498] <TB3> INFO: Test took 942ms.
[11:53:14.501] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:14.803] <TB3> INFO: Expecting 2560 events.
[11:53:15.662] <TB3> INFO: 2560 events read in total (303ms).
[11:53:15.663] <TB3> INFO: Test took 1162ms.
[11:53:15.664] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:15.968] <TB3> INFO: Expecting 2560 events.
[11:53:16.825] <TB3> INFO: 2560 events read in total (301ms).
[11:53:16.826] <TB3> INFO: Test took 1162ms.
[11:53:16.828] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:17.132] <TB3> INFO: Expecting 2560 events.
[11:53:17.985] <TB3> INFO: 2560 events read in total (297ms).
[11:53:17.986] <TB3> INFO: Test took 1158ms.
[11:53:17.987] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:18.291] <TB3> INFO: Expecting 2560 events.
[11:53:19.145] <TB3> INFO: 2560 events read in total (298ms).
[11:53:19.146] <TB3> INFO: Test took 1159ms.
[11:53:19.148] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:19.450] <TB3> INFO: Expecting 2560 events.
[11:53:20.303] <TB3> INFO: 2560 events read in total (297ms).
[11:53:20.303] <TB3> INFO: Test took 1155ms.
[11:53:20.306] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:20.609] <TB3> INFO: Expecting 2560 events.
[11:53:21.463] <TB3> INFO: 2560 events read in total (297ms).
[11:53:21.464] <TB3> INFO: Test took 1158ms.
[11:53:21.466] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:21.769] <TB3> INFO: Expecting 2560 events.
[11:53:22.622] <TB3> INFO: 2560 events read in total (297ms).
[11:53:22.622] <TB3> INFO: Test took 1157ms.
[11:53:22.624] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:22.927] <TB3> INFO: Expecting 2560 events.
[11:53:23.780] <TB3> INFO: 2560 events read in total (297ms).
[11:53:23.780] <TB3> INFO: Test took 1156ms.
[11:53:23.783] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:24.086] <TB3> INFO: Expecting 2560 events.
[11:53:24.940] <TB3> INFO: 2560 events read in total (298ms).
[11:53:24.940] <TB3> INFO: Test took 1157ms.
[11:53:24.943] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:25.246] <TB3> INFO: Expecting 2560 events.
[11:53:26.103] <TB3> INFO: 2560 events read in total (301ms).
[11:53:26.104] <TB3> INFO: Test took 1161ms.
[11:53:26.106] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:26.410] <TB3> INFO: Expecting 2560 events.
[11:53:27.264] <TB3> INFO: 2560 events read in total (298ms).
[11:53:27.264] <TB3> INFO: Test took 1158ms.
[11:53:27.266] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:27.570] <TB3> INFO: Expecting 2560 events.
[11:53:28.424] <TB3> INFO: 2560 events read in total (298ms).
[11:53:28.424] <TB3> INFO: Test took 1158ms.
[11:53:28.426] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:28.730] <TB3> INFO: Expecting 2560 events.
[11:53:29.584] <TB3> INFO: 2560 events read in total (298ms).
[11:53:29.584] <TB3> INFO: Test took 1158ms.
[11:53:29.587] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:29.889] <TB3> INFO: Expecting 2560 events.
[11:53:30.750] <TB3> INFO: 2560 events read in total (304ms).
[11:53:30.750] <TB3> INFO: Test took 1163ms.
[11:53:30.752] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:31.055] <TB3> INFO: Expecting 2560 events.
[11:53:31.915] <TB3> INFO: 2560 events read in total (303ms).
[11:53:31.916] <TB3> INFO: Test took 1164ms.
[11:53:31.919] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:32.221] <TB3> INFO: Expecting 2560 events.
[11:53:33.073] <TB3> INFO: 2560 events read in total (296ms).
[11:53:33.074] <TB3> INFO: Test took 1155ms.
[11:53:33.076] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:33.379] <TB3> INFO: Expecting 2560 events.
[11:53:34.233] <TB3> INFO: 2560 events read in total (298ms).
[11:53:34.233] <TB3> INFO: Test took 1157ms.
[11:53:34.235] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:34.539] <TB3> INFO: Expecting 2560 events.
[11:53:35.392] <TB3> INFO: 2560 events read in total (297ms).
[11:53:35.393] <TB3> INFO: Test took 1158ms.
[11:53:35.394] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:35.698] <TB3> INFO: Expecting 2560 events.
[11:53:36.553] <TB3> INFO: 2560 events read in total (299ms).
[11:53:36.553] <TB3> INFO: Test took 1159ms.
[11:53:36.556] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:36.858] <TB3> INFO: Expecting 2560 events.
[11:53:37.713] <TB3> INFO: 2560 events read in total (299ms).
[11:53:37.713] <TB3> INFO: Test took 1157ms.
[11:53:37.715] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:38.019] <TB3> INFO: Expecting 2560 events.
[11:53:38.873] <TB3> INFO: 2560 events read in total (297ms).
[11:53:38.874] <TB3> INFO: Test took 1159ms.
[11:53:38.876] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:39.179] <TB3> INFO: Expecting 2560 events.
[11:53:40.032] <TB3> INFO: 2560 events read in total (297ms).
[11:53:40.032] <TB3> INFO: Test took 1156ms.
[11:53:40.034] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:40.338] <TB3> INFO: Expecting 2560 events.
[11:53:41.195] <TB3> INFO: 2560 events read in total (301ms).
[11:53:41.195] <TB3> INFO: Test took 1161ms.
[11:53:41.197] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:41.501] <TB3> INFO: Expecting 2560 events.
[11:53:42.354] <TB3> INFO: 2560 events read in total (297ms).
[11:53:42.355] <TB3> INFO: Test took 1158ms.
[11:53:42.358] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:42.660] <TB3> INFO: Expecting 2560 events.
[11:53:43.514] <TB3> INFO: 2560 events read in total (298ms).
[11:53:43.514] <TB3> INFO: Test took 1157ms.
[11:53:43.516] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:43.820] <TB3> INFO: Expecting 2560 events.
[11:53:44.673] <TB3> INFO: 2560 events read in total (297ms).
[11:53:44.673] <TB3> INFO: Test took 1157ms.
[11:53:44.675] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:44.979] <TB3> INFO: Expecting 2560 events.
[11:53:45.832] <TB3> INFO: 2560 events read in total (297ms).
[11:53:45.832] <TB3> INFO: Test took 1157ms.
[11:53:45.834] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:46.138] <TB3> INFO: Expecting 2560 events.
[11:53:46.991] <TB3> INFO: 2560 events read in total (297ms).
[11:53:46.991] <TB3> INFO: Test took 1157ms.
[11:53:46.992] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:47.297] <TB3> INFO: Expecting 2560 events.
[11:53:48.154] <TB3> INFO: 2560 events read in total (301ms).
[11:53:48.154] <TB3> INFO: Test took 1162ms.
[11:53:48.156] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:48.459] <TB3> INFO: Expecting 2560 events.
[11:53:49.314] <TB3> INFO: 2560 events read in total (299ms).
[11:53:49.314] <TB3> INFO: Test took 1158ms.
[11:53:49.316] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:53:49.620] <TB3> INFO: Expecting 2560 events.
[11:53:50.474] <TB3> INFO: 2560 events read in total (298ms).
[11:53:50.475] <TB3> INFO: Test took 1159ms.
[11:53:50.939] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[11:53:50.939] <TB3> INFO: PH scale (per ROC): 84 86 82 80 88 82 80 77 78 83 90 82 84 82 79 89
[11:53:50.939] <TB3> INFO: PH offset (per ROC): 157 140 166 165 144 162 164 166 147 134 154 145 158 140 158 141
[11:53:50.943] <TB3> INFO: Decoding statistics:
[11:53:50.943] <TB3> INFO: General information:
[11:53:50.943] <TB3> INFO: 16bit words read: 91928
[11:53:50.943] <TB3> INFO: valid events total: 10240
[11:53:50.943] <TB3> INFO: empty events: 7738
[11:53:50.943] <TB3> INFO: valid events with pixels: 2502
[11:53:50.943] <TB3> INFO: valid pixel hits: 2502
[11:53:50.943] <TB3> INFO: Event errors: 0
[11:53:50.943] <TB3> INFO: start marker: 0
[11:53:50.943] <TB3> INFO: stop marker: 0
[11:53:50.943] <TB3> INFO: overflow: 0
[11:53:50.943] <TB3> INFO: invalid 5bit words: 0
[11:53:50.943] <TB3> INFO: invalid XOR eye diagram: 0
[11:53:50.943] <TB3> INFO: frame (failed synchr.): 0
[11:53:50.943] <TB3> INFO: idle data (no TBM trl): 0
[11:53:50.943] <TB3> INFO: no data (only TBM hdr): 0
[11:53:50.943] <TB3> INFO: TBM errors: 0
[11:53:50.943] <TB3> INFO: flawed TBM headers: 0
[11:53:50.943] <TB3> INFO: flawed TBM trailers: 0
[11:53:50.943] <TB3> INFO: event ID mismatches: 0
[11:53:50.943] <TB3> INFO: ROC errors: 0
[11:53:50.943] <TB3> INFO: missing ROC header(s): 0
[11:53:50.943] <TB3> INFO: misplaced readback start: 0
[11:53:50.943] <TB3> INFO: Pixel decoding errors: 0
[11:53:50.943] <TB3> INFO: pixel data incomplete: 0
[11:53:50.943] <TB3> INFO: pixel address: 0
[11:53:50.943] <TB3> INFO: pulse height fill bit: 0
[11:53:50.943] <TB3> INFO: buffer corruption: 0
[11:53:51.118] <TB3> INFO: ######################################################################
[11:53:51.118] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:53:51.118] <TB3> INFO: ######################################################################
[11:53:51.129] <TB3> INFO: scanning low vcal = 10
[11:53:51.408] <TB3> INFO: Expecting 41600 events.
[11:53:54.930] <TB3> INFO: 41600 events read in total (2966ms).
[11:53:54.930] <TB3> INFO: Test took 3801ms.
[11:53:54.932] <TB3> INFO: scanning low vcal = 20
[11:53:55.235] <TB3> INFO: Expecting 41600 events.
[11:53:58.782] <TB3> INFO: 41600 events read in total (2990ms).
[11:53:58.782] <TB3> INFO: Test took 3850ms.
[11:53:58.783] <TB3> INFO: scanning low vcal = 30
[11:53:59.087] <TB3> INFO: Expecting 41600 events.
[11:54:02.645] <TB3> INFO: 41600 events read in total (3002ms).
[11:54:02.645] <TB3> INFO: Test took 3862ms.
[11:54:02.647] <TB3> INFO: scanning low vcal = 40
[11:54:02.942] <TB3> INFO: Expecting 41600 events.
[11:54:07.091] <TB3> INFO: 41600 events read in total (3593ms).
[11:54:07.092] <TB3> INFO: Test took 4445ms.
[11:54:07.095] <TB3> INFO: scanning low vcal = 50
[11:54:07.363] <TB3> INFO: Expecting 41600 events.
[11:54:11.584] <TB3> INFO: 41600 events read in total (3665ms).
[11:54:11.585] <TB3> INFO: Test took 4490ms.
[11:54:11.588] <TB3> INFO: scanning low vcal = 60
[11:54:11.868] <TB3> INFO: Expecting 41600 events.
[11:54:16.074] <TB3> INFO: 41600 events read in total (3649ms).
[11:54:16.074] <TB3> INFO: Test took 4486ms.
[11:54:16.077] <TB3> INFO: scanning low vcal = 70
[11:54:16.336] <TB3> INFO: Expecting 41600 events.
[11:54:20.538] <TB3> INFO: 41600 events read in total (3646ms).
[11:54:20.538] <TB3> INFO: Test took 4461ms.
[11:54:20.541] <TB3> INFO: scanning low vcal = 80
[11:54:20.802] <TB3> INFO: Expecting 41600 events.
[11:54:25.016] <TB3> INFO: 41600 events read in total (3657ms).
[11:54:25.017] <TB3> INFO: Test took 4476ms.
[11:54:25.019] <TB3> INFO: scanning low vcal = 90
[11:54:25.308] <TB3> INFO: Expecting 41600 events.
[11:54:29.488] <TB3> INFO: 41600 events read in total (3624ms).
[11:54:29.488] <TB3> INFO: Test took 4469ms.
[11:54:29.491] <TB3> INFO: scanning low vcal = 100
[11:54:29.752] <TB3> INFO: Expecting 41600 events.
[11:54:33.942] <TB3> INFO: 41600 events read in total (3634ms).
[11:54:33.943] <TB3> INFO: Test took 4451ms.
[11:54:33.945] <TB3> INFO: scanning low vcal = 110
[11:54:34.214] <TB3> INFO: Expecting 41600 events.
[11:54:38.402] <TB3> INFO: 41600 events read in total (3632ms).
[11:54:38.403] <TB3> INFO: Test took 4458ms.
[11:54:38.405] <TB3> INFO: scanning low vcal = 120
[11:54:38.665] <TB3> INFO: Expecting 41600 events.
[11:54:42.856] <TB3> INFO: 41600 events read in total (3635ms).
[11:54:42.857] <TB3> INFO: Test took 4452ms.
[11:54:42.859] <TB3> INFO: scanning low vcal = 130
[11:54:43.123] <TB3> INFO: Expecting 41600 events.
[11:54:47.313] <TB3> INFO: 41600 events read in total (3634ms).
[11:54:47.313] <TB3> INFO: Test took 4453ms.
[11:54:47.316] <TB3> INFO: scanning low vcal = 140
[11:54:47.576] <TB3> INFO: Expecting 41600 events.
[11:54:51.769] <TB3> INFO: 41600 events read in total (3637ms).
[11:54:51.770] <TB3> INFO: Test took 4454ms.
[11:54:51.772] <TB3> INFO: scanning low vcal = 150
[11:54:52.030] <TB3> INFO: Expecting 41600 events.
[11:54:56.250] <TB3> INFO: 41600 events read in total (3664ms).
[11:54:56.251] <TB3> INFO: Test took 4479ms.
[11:54:56.253] <TB3> INFO: scanning low vcal = 160
[11:54:56.518] <TB3> INFO: Expecting 41600 events.
[11:55:00.757] <TB3> INFO: 41600 events read in total (3683ms).
[11:55:00.757] <TB3> INFO: Test took 4504ms.
[11:55:00.760] <TB3> INFO: scanning low vcal = 170
[11:55:01.048] <TB3> INFO: Expecting 41600 events.
[11:55:05.259] <TB3> INFO: 41600 events read in total (3654ms).
[11:55:05.260] <TB3> INFO: Test took 4500ms.
[11:55:05.263] <TB3> INFO: scanning low vcal = 180
[11:55:05.542] <TB3> INFO: Expecting 41600 events.
[11:55:09.787] <TB3> INFO: 41600 events read in total (3690ms).
[11:55:09.788] <TB3> INFO: Test took 4524ms.
[11:55:09.791] <TB3> INFO: scanning low vcal = 190
[11:55:10.070] <TB3> INFO: Expecting 41600 events.
[11:55:14.327] <TB3> INFO: 41600 events read in total (3701ms).
[11:55:14.328] <TB3> INFO: Test took 4537ms.
[11:55:14.331] <TB3> INFO: scanning low vcal = 200
[11:55:14.623] <TB3> INFO: Expecting 41600 events.
[11:55:18.861] <TB3> INFO: 41600 events read in total (3681ms).
[11:55:18.862] <TB3> INFO: Test took 4531ms.
[11:55:18.864] <TB3> INFO: scanning low vcal = 210
[11:55:19.122] <TB3> INFO: Expecting 41600 events.
[11:55:23.334] <TB3> INFO: 41600 events read in total (3656ms).
[11:55:23.334] <TB3> INFO: Test took 4470ms.
[11:55:23.337] <TB3> INFO: scanning low vcal = 220
[11:55:23.604] <TB3> INFO: Expecting 41600 events.
[11:55:27.822] <TB3> INFO: 41600 events read in total (3662ms).
[11:55:27.823] <TB3> INFO: Test took 4486ms.
[11:55:27.825] <TB3> INFO: scanning low vcal = 230
[11:55:28.116] <TB3> INFO: Expecting 41600 events.
[11:55:32.341] <TB3> INFO: 41600 events read in total (3668ms).
[11:55:32.342] <TB3> INFO: Test took 4517ms.
[11:55:32.344] <TB3> INFO: scanning low vcal = 240
[11:55:32.623] <TB3> INFO: Expecting 41600 events.
[11:55:36.828] <TB3> INFO: 41600 events read in total (3648ms).
[11:55:36.828] <TB3> INFO: Test took 4483ms.
[11:55:36.831] <TB3> INFO: scanning low vcal = 250
[11:55:37.091] <TB3> INFO: Expecting 41600 events.
[11:55:41.317] <TB3> INFO: 41600 events read in total (3670ms).
[11:55:41.318] <TB3> INFO: Test took 4487ms.
[11:55:41.321] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[11:55:41.584] <TB3> INFO: Expecting 41600 events.
[11:55:45.791] <TB3> INFO: 41600 events read in total (3652ms).
[11:55:45.792] <TB3> INFO: Test took 4471ms.
[11:55:45.795] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[11:55:46.059] <TB3> INFO: Expecting 41600 events.
[11:55:50.237] <TB3> INFO: 41600 events read in total (3622ms).
[11:55:50.237] <TB3> INFO: Test took 4442ms.
[11:55:50.240] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[11:55:50.503] <TB3> INFO: Expecting 41600 events.
[11:55:54.714] <TB3> INFO: 41600 events read in total (3655ms).
[11:55:54.714] <TB3> INFO: Test took 4474ms.
[11:55:54.717] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[11:55:54.990] <TB3> INFO: Expecting 41600 events.
[11:55:59.214] <TB3> INFO: 41600 events read in total (3668ms).
[11:55:59.215] <TB3> INFO: Test took 4498ms.
[11:55:59.217] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:55:59.478] <TB3> INFO: Expecting 41600 events.
[11:56:03.710] <TB3> INFO: 41600 events read in total (3676ms).
[11:56:03.711] <TB3> INFO: Test took 4494ms.
[11:56:04.201] <TB3> INFO: PixTestGainPedestal::measure() done
[11:56:39.944] <TB3> INFO: PixTestGainPedestal::fit() done
[11:56:39.944] <TB3> INFO: non-linearity mean: 0.962 0.951 0.961 0.956 0.954 0.954 0.951 0.961 0.957 0.954 0.948 0.953 0.955 0.944 0.956 0.958
[11:56:39.944] <TB3> INFO: non-linearity RMS: 0.005 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.005 0.006 0.007 0.005
[11:56:39.945] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:56:39.963] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:56:39.981] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:56:39.999] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:56:40.016] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:56:40.034] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:56:40.051] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:56:40.069] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:56:40.087] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:56:40.105] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:56:40.124] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:56:40.142] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:56:40.160] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:56:40.178] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:56:40.196] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:56:40.214] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:56:40.232] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 169 seconds
[11:56:40.232] <TB3> INFO: Decoding statistics:
[11:56:40.232] <TB3> INFO: General information:
[11:56:40.232] <TB3> INFO: 16bit words read: 2662400
[11:56:40.232] <TB3> INFO: valid events total: 166400
[11:56:40.232] <TB3> INFO: empty events: 0
[11:56:40.232] <TB3> INFO: valid events with pixels: 166400
[11:56:40.232] <TB3> INFO: valid pixel hits: 665570
[11:56:40.232] <TB3> INFO: Event errors: 0
[11:56:40.232] <TB3> INFO: start marker: 0
[11:56:40.232] <TB3> INFO: stop marker: 0
[11:56:40.232] <TB3> INFO: overflow: 0
[11:56:40.232] <TB3> INFO: invalid 5bit words: 0
[11:56:40.232] <TB3> INFO: invalid XOR eye diagram: 0
[11:56:40.232] <TB3> INFO: frame (failed synchr.): 0
[11:56:40.232] <TB3> INFO: idle data (no TBM trl): 0
[11:56:40.232] <TB3> INFO: no data (only TBM hdr): 0
[11:56:40.232] <TB3> INFO: TBM errors: 0
[11:56:40.232] <TB3> INFO: flawed TBM headers: 0
[11:56:40.232] <TB3> INFO: flawed TBM trailers: 0
[11:56:40.232] <TB3> INFO: event ID mismatches: 0
[11:56:40.232] <TB3> INFO: ROC errors: 0
[11:56:40.232] <TB3> INFO: missing ROC header(s): 0
[11:56:40.232] <TB3> INFO: misplaced readback start: 0
[11:56:40.232] <TB3> INFO: Pixel decoding errors: 0
[11:56:40.232] <TB3> INFO: pixel data incomplete: 0
[11:56:40.232] <TB3> INFO: pixel address: 0
[11:56:40.232] <TB3> INFO: pulse height fill bit: 0
[11:56:40.232] <TB3> INFO: buffer corruption: 0
[11:56:40.239] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C15.dat
[11:56:40.241] <TB3> INFO: ######################################################################
[11:56:40.241] <TB3> INFO: PixTestReadback::doTest()
[11:56:40.241] <TB3> INFO: ######################################################################
[11:56:40.241] <TB3> INFO: ----------------------------------------------------------------------
[11:56:40.241] <TB3> INFO: PixTestReadback::CalibrateVd()
[11:56:40.241] <TB3> INFO: ----------------------------------------------------------------------
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C0.dat
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C1.dat
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C2.dat
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C3.dat
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C4.dat
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C5.dat
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C6.dat
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C7.dat
[11:56:49.716] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C8.dat
[11:56:49.717] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C9.dat
[11:56:49.717] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C10.dat
[11:56:49.717] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C11.dat
[11:56:49.717] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C12.dat
[11:56:49.717] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C13.dat
[11:56:49.717] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C14.dat
[11:56:49.717] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C15.dat
[11:56:49.748] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:56:49.748] <TB3> INFO: ----------------------------------------------------------------------
[11:56:49.748] <TB3> INFO: PixTestReadback::CalibrateVa()
[11:56:49.748] <TB3> INFO: ----------------------------------------------------------------------
[11:56:59.238] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C0.dat
[11:56:59.238] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C1.dat
[11:56:59.238] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C2.dat
[11:56:59.238] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C3.dat
[11:56:59.238] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C4.dat
[11:56:59.238] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C5.dat
[11:56:59.238] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C6.dat
[11:56:59.238] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C7.dat
[11:56:59.239] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C8.dat
[11:56:59.239] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C9.dat
[11:56:59.239] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C10.dat
[11:56:59.239] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C11.dat
[11:56:59.239] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C12.dat
[11:56:59.239] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C13.dat
[11:56:59.239] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C14.dat
[11:56:59.239] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C15.dat
[11:56:59.271] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:56:59.271] <TB3> INFO: ----------------------------------------------------------------------
[11:56:59.271] <TB3> INFO: PixTestReadback::readbackVbg()
[11:56:59.271] <TB3> INFO: ----------------------------------------------------------------------
[11:57:06.577] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:57:06.577] <TB3> INFO: ----------------------------------------------------------------------
[11:57:06.578] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[11:57:06.578] <TB3> INFO: ----------------------------------------------------------------------
[11:57:06.578] <TB3> INFO: Vbg will be calibrated using Vd calibration
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146.4calibrated Vbg = 1.19467 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.5calibrated Vbg = 1.20316 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 158.3calibrated Vbg = 1.20284 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.3calibrated Vbg = 1.20682 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 162calibrated Vbg = 1.20918 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 146.2calibrated Vbg = 1.2126 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 162.8calibrated Vbg = 1.21089 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.7calibrated Vbg = 1.21146 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 145.3calibrated Vbg = 1.22194 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150calibrated Vbg = 1.22099 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.5calibrated Vbg = 1.21298 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 158.4calibrated Vbg = 1.21446 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.1calibrated Vbg = 1.19838 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148.1calibrated Vbg = 1.20873 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 158.1calibrated Vbg = 1.20128 :::*/*/*/*/
[11:57:06.578] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 148.1calibrated Vbg = 1.20434 :::*/*/*/*/
[11:57:06.581] <TB3> INFO: ----------------------------------------------------------------------
[11:57:06.581] <TB3> INFO: PixTestReadback::CalibrateIa()
[11:57:06.581] <TB3> INFO: ----------------------------------------------------------------------
[11:59:41.947] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C0.dat
[11:59:41.947] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C1.dat
[11:59:41.947] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C2.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C3.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C4.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C5.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C6.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C7.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C8.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C9.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C10.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C11.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C12.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C13.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C14.dat
[11:59:41.948] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2286_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C15.dat
[11:59:41.970] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:59:41.971] <TB3> INFO: PixTestReadback::doTest() done
[11:59:41.971] <TB3> INFO: Decoding statistics:
[11:59:41.971] <TB3> INFO: General information:
[11:59:41.971] <TB3> INFO: 16bit words read: 1024
[11:59:41.971] <TB3> INFO: valid events total: 128
[11:59:41.971] <TB3> INFO: empty events: 128
[11:59:41.971] <TB3> INFO: valid events with pixels: 0
[11:59:41.971] <TB3> INFO: valid pixel hits: 0
[11:59:41.971] <TB3> INFO: Event errors: 0
[11:59:41.971] <TB3> INFO: start marker: 0
[11:59:41.971] <TB3> INFO: stop marker: 0
[11:59:41.971] <TB3> INFO: overflow: 0
[11:59:41.971] <TB3> INFO: invalid 5bit words: 0
[11:59:41.971] <TB3> INFO: invalid XOR eye diagram: 0
[11:59:41.971] <TB3> INFO: frame (failed synchr.): 0
[11:59:41.971] <TB3> INFO: idle data (no TBM trl): 0
[11:59:41.971] <TB3> INFO: no data (only TBM hdr): 0
[11:59:41.971] <TB3> INFO: TBM errors: 0
[11:59:41.971] <TB3> INFO: flawed TBM headers: 0
[11:59:41.971] <TB3> INFO: flawed TBM trailers: 0
[11:59:41.971] <TB3> INFO: event ID mismatches: 0
[11:59:41.971] <TB3> INFO: ROC errors: 0
[11:59:41.971] <TB3> INFO: missing ROC header(s): 0
[11:59:41.971] <TB3> INFO: misplaced readback start: 0
[11:59:41.971] <TB3> INFO: Pixel decoding errors: 0
[11:59:41.971] <TB3> INFO: pixel data incomplete: 0
[11:59:41.971] <TB3> INFO: pixel address: 0
[11:59:41.971] <TB3> INFO: pulse height fill bit: 0
[11:59:41.971] <TB3> INFO: buffer corruption: 0
[11:59:41.995] <TB3> INFO: Decoding statistics:
[11:59:41.995] <TB3> INFO: General information:
[11:59:41.995] <TB3> INFO: 16bit words read: 2755352
[11:59:41.995] <TB3> INFO: valid events total: 176768
[11:59:41.995] <TB3> INFO: empty events: 7866
[11:59:41.995] <TB3> INFO: valid events with pixels: 168902
[11:59:41.995] <TB3> INFO: valid pixel hits: 668072
[11:59:41.995] <TB3> INFO: Event errors: 0
[11:59:41.995] <TB3> INFO: start marker: 0
[11:59:41.995] <TB3> INFO: stop marker: 0
[11:59:41.995] <TB3> INFO: overflow: 0
[11:59:41.995] <TB3> INFO: invalid 5bit words: 0
[11:59:41.995] <TB3> INFO: invalid XOR eye diagram: 0
[11:59:41.995] <TB3> INFO: frame (failed synchr.): 0
[11:59:41.995] <TB3> INFO: idle data (no TBM trl): 0
[11:59:41.995] <TB3> INFO: no data (only TBM hdr): 0
[11:59:41.995] <TB3> INFO: TBM errors: 0
[11:59:41.995] <TB3> INFO: flawed TBM headers: 0
[11:59:41.995] <TB3> INFO: flawed TBM trailers: 0
[11:59:41.995] <TB3> INFO: event ID mismatches: 0
[11:59:41.995] <TB3> INFO: ROC errors: 0
[11:59:41.995] <TB3> INFO: missing ROC header(s): 0
[11:59:41.995] <TB3> INFO: misplaced readback start: 0
[11:59:41.995] <TB3> INFO: Pixel decoding errors: 0
[11:59:41.995] <TB3> INFO: pixel data incomplete: 0
[11:59:41.995] <TB3> INFO: pixel address: 0
[11:59:41.995] <TB3> INFO: pulse height fill bit: 0
[11:59:41.995] <TB3> INFO: buffer corruption: 0
[11:59:41.995] <TB3> INFO: enter test to run
[11:59:41.996] <TB3> INFO: test: exit no parameter change
[11:59:42.153] <TB3> QUIET: Connection to board 170 closed.
[11:59:42.232] <TB3> INFO: pXar: this is the end, my friend