[15:28:47.437] <TB0> INFO: *** Welcome to pxar ***
[15:28:47.437] <TB0> INFO: *** Today: 2016/06/28
[15:28:47.942] <TB0> INFO: *** Version: 9751-dirty
[15:28:47.942] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C15.dat
[15:28:47.942] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:28:47.942] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//defaultMaskFile.dat
[15:28:47.942] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters_C15.dat
[15:28:48.010] <TB0> INFO: clk: 4
[15:28:48.010] <TB0> INFO: ctr: 4
[15:28:48.010] <TB0> INFO: sda: 19
[15:28:48.010] <TB0> INFO: tin: 9
[15:28:48.010] <TB0> INFO: level: 15
[15:28:48.010] <TB0> INFO: triggerdelay: 0
[15:28:48.010] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[15:28:48.010] <TB0> INFO: Log level: INFO
[15:28:48.017] <TB0> INFO: Found DTB DTB_WS6AYH
[15:28:48.028] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[15:28:48.030] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[15:28:48.031] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[15:28:49.559] <TB0> INFO: DUT info:
[15:28:49.559] <TB0> INFO: The DUT currently contains the following objects:
[15:28:49.559] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:28:49.559] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:28:49.559] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:28:49.559] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:28:49.559] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.559] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:28:49.960] <TB0> INFO: enter 'restricted' command line mode
[15:28:49.960] <TB0> INFO: enter test to run
[15:28:49.960] <TB0> INFO: test: pretest no parameter change
[15:28:49.960] <TB0> INFO: running: pretest
[15:28:49.964] <TB0> INFO: ----------------------------------------------------------------------
[15:28:49.964] <TB0> INFO: PixTestPretest::programROC()
[15:28:49.964] <TB0> INFO: ----------------------------------------------------------------------
[15:29:07.978] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:29:07.978] <TB0> INFO: IA differences per ROC: 20.1 20.1 17.7 18.5 18.5 20.1 19.3 19.3 19.3 19.3 23.3 19.3 19.3 17.7 18.5 20.1
[15:29:08.079] <TB0> INFO: enter test to run
[15:29:08.079] <TB0> INFO: test: pretest no parameter change
[15:29:08.079] <TB0> INFO: running: pretest
[15:29:08.080] <TB0> INFO: ----------------------------------------------------------------------
[15:29:08.080] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:29:08.081] <TB0> INFO: ----------------------------------------------------------------------
[15:29:15.677] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[15:29:15.677] <TB0> INFO: i(loss) [mA/ROC]: 19.3 20.1 19.3 18.5 19.3 18.5 19.3 19.3 19.3 19.3 18.5 18.5 18.5 19.3 19.3 18.5
[15:29:15.711] <TB0> INFO: enter test to run
[15:29:15.711] <TB0> INFO: test: pretest no parameter change
[15:29:15.711] <TB0> INFO: running: pretest
[15:29:15.711] <TB0> INFO: ----------------------------------------------------------------------
[15:29:15.711] <TB0> INFO: PixTestPretest::findTiming()
[15:29:15.711] <TB0> INFO: ----------------------------------------------------------------------
[15:29:15.711] <TB0> INFO: PixTestCmd::init()

[15:30:44.302] <TB0> INFO: TBM phases: 160MHz: 4, 400MHz: 4, TBM delays: ROC(0/1):3, header/trailer: 1, token: 0
[15:30:44.302] <TB0> INFO: (success/tries = 100/100), width = 4
[15:30:44.303] <TB0> INFO: enter test to run
[15:30:44.303] <TB0> INFO: test: pretest no parameter change
[15:30:44.303] <TB0> INFO: running: pretest
[15:30:44.305] <TB0> INFO: ----------------------------------------------------------------------
[15:30:44.305] <TB0> INFO: PixTestPretest::findWorkingPixel()
[15:30:44.305] <TB0> INFO: ----------------------------------------------------------------------
[15:30:44.399] <TB0> INFO: Expecting 231680 events.


[15:30:54.595] <TB0> INFO: 231680 events read in total (9640ms).
[15:30:54.599] <TB0> INFO: Test took 10288ms.
[15:30:54.816] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:30:54.892] <TB0> INFO: enter test to run
[15:30:54.892] <TB0> INFO: test: pretest no parameter change
[15:30:54.893] <TB0> INFO: running: pretest
[15:30:54.894] <TB0> INFO: ----------------------------------------------------------------------
[15:30:54.894] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[15:30:54.894] <TB0> INFO: ----------------------------------------------------------------------
[15:30:54.985] <TB0> INFO: Expecting 231680 events.
[15:31:05.253] <TB0> INFO: 231680 events read in total (9711ms).
[15:31:05.257] <TB0> INFO: Test took 10360ms.
[15:31:05.512] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[15:31:05.512] <TB0> INFO: CalDel: 145 164 163 131 165 126 160 176 159 157 142 138 144 135 135 140
[15:31:05.512] <TB0> INFO: VthrComp: 51 52 53 52 51 51 51 51 51 51 51 53 51 51 51 51
[15:31:05.553] <TB0> INFO: enter test to run
[15:31:05.553] <TB0> INFO: test: pretest no parameter change
[15:31:05.553] <TB0> INFO: running: pretest
[15:31:05.554] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C0.dat
[15:31:05.554] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C1.dat
[15:31:05.554] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C2.dat
[15:31:05.554] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C3.dat
[15:31:05.554] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C4.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C5.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C6.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C7.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C8.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C9.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C10.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C11.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C12.dat
[15:31:05.555] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C13.dat
[15:31:05.556] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C14.dat
[15:31:05.556] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters_C15.dat
[15:31:05.556] <TB0> INFO: enter test to run
[15:31:05.556] <TB0> INFO: test: fulltest no parameter change
[15:31:05.556] <TB0> INFO: running: fulltest
[15:31:05.556] <TB0> INFO: ######################################################################
[15:31:05.556] <TB0> INFO: PixTestFullTest::doTest()
[15:31:05.556] <TB0> INFO: ######################################################################
[15:31:05.557] <TB0> INFO: ######################################################################
[15:31:05.557] <TB0> INFO: PixTestAlive::doTest()
[15:31:05.557] <TB0> INFO: ######################################################################
[15:31:05.558] <TB0> INFO: ----------------------------------------------------------------------
[15:31:05.558] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:31:05.558] <TB0> INFO: ----------------------------------------------------------------------
[15:31:05.824] <TB0> INFO: Expecting 41600 events.
[15:31:09.594] <TB0> INFO: 41600 events read in total (3214ms).
[15:31:09.595] <TB0> INFO: Test took 4036ms.
[15:31:09.828] <TB0> INFO: PixTestAlive::aliveTest() done
[15:31:09.828] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[15:31:09.830] <TB0> INFO: ----------------------------------------------------------------------
[15:31:09.830] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:31:09.830] <TB0> INFO: ----------------------------------------------------------------------
[15:31:10.100] <TB0> INFO: Expecting 41600 events.
[15:31:13.084] <TB0> INFO: 41600 events read in total (2428ms).
[15:31:13.084] <TB0> INFO: Test took 3253ms.
[15:31:13.085] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:31:13.328] <TB0> INFO: PixTestAlive::maskTest() done
[15:31:13.328] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:31:13.329] <TB0> INFO: ----------------------------------------------------------------------
[15:31:13.329] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:31:13.329] <TB0> INFO: ----------------------------------------------------------------------
[15:31:13.600] <TB0> INFO: Expecting 41600 events.
[15:31:17.409] <TB0> INFO: 41600 events read in total (3253ms).
[15:31:17.410] <TB0> INFO: Test took 4079ms.
[15:31:17.634] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[15:31:17.634] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:31:17.634] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:31:17.634] <TB0> INFO: Decoding statistics:
[15:31:17.634] <TB0> INFO: General information:
[15:31:17.634] <TB0> INFO: 16bit words read: 0
[15:31:17.634] <TB0> INFO: valid events total: 0
[15:31:17.634] <TB0> INFO: empty events: 0
[15:31:17.634] <TB0> INFO: valid events with pixels: 0
[15:31:17.634] <TB0> INFO: valid pixel hits: 0
[15:31:17.634] <TB0> INFO: Event errors: 0
[15:31:17.634] <TB0> INFO: start marker: 0
[15:31:17.634] <TB0> INFO: stop marker: 0
[15:31:17.634] <TB0> INFO: overflow: 0
[15:31:17.634] <TB0> INFO: invalid 5bit words: 0
[15:31:17.634] <TB0> INFO: invalid XOR eye diagram: 0
[15:31:17.634] <TB0> INFO: frame (failed synchr.): 0
[15:31:17.634] <TB0> INFO: idle data (no TBM trl): 0
[15:31:17.634] <TB0> INFO: no data (only TBM hdr): 0
[15:31:17.634] <TB0> INFO: TBM errors: 0
[15:31:17.634] <TB0> INFO: flawed TBM headers: 0
[15:31:17.635] <TB0> INFO: flawed TBM trailers: 0
[15:31:17.635] <TB0> INFO: event ID mismatches: 0
[15:31:17.635] <TB0> INFO: ROC errors: 0
[15:31:17.635] <TB0> INFO: missing ROC header(s): 0
[15:31:17.635] <TB0> INFO: misplaced readback start: 0
[15:31:17.635] <TB0> INFO: Pixel decoding errors: 0
[15:31:17.635] <TB0> INFO: pixel data incomplete: 0
[15:31:17.635] <TB0> INFO: pixel address: 0
[15:31:17.635] <TB0> INFO: pulse height fill bit: 0
[15:31:17.635] <TB0> INFO: buffer corruption: 0
[15:31:17.649] <TB0> INFO: ######################################################################
[15:31:17.649] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:31:17.649] <TB0> INFO: ######################################################################
[15:31:17.651] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:31:17.663] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:31:17.663] <TB0> INFO: run 1 of 1
[15:31:17.945] <TB0> INFO: Expecting 3120000 events.
[15:31:59.384] <TB0> INFO: 852290 events read in total (40883ms).
[15:32:39.492] <TB0> INFO: 1690040 events read in total (80991ms).
[15:33:19.995] <TB0> INFO: 2539190 events read in total (121495ms).
[15:33:47.615] <TB0> INFO: 3120000 events read in total (149114ms).
[15:33:47.662] <TB0> INFO: Test took 149998ms.
[15:34:10.813] <TB0> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[15:34:10.813] <TB0> INFO: number of dead bumps (per ROC): 43 35 19 10 25 13 20 21 21 22 1 10 13 16 11 31
[15:34:10.813] <TB0> INFO: separation cut (per ROC): 78 84 78 103 71 83 83 66 72 63 97 97 76 66 80 83
[15:34:10.813] <TB0> INFO: Decoding statistics:
[15:34:10.813] <TB0> INFO: General information:
[15:34:10.813] <TB0> INFO: 16bit words read: 0
[15:34:10.813] <TB0> INFO: valid events total: 0
[15:34:10.813] <TB0> INFO: empty events: 0
[15:34:10.813] <TB0> INFO: valid events with pixels: 0
[15:34:10.813] <TB0> INFO: valid pixel hits: 0
[15:34:10.813] <TB0> INFO: Event errors: 0
[15:34:10.813] <TB0> INFO: start marker: 0
[15:34:10.813] <TB0> INFO: stop marker: 0
[15:34:10.813] <TB0> INFO: overflow: 0
[15:34:10.813] <TB0> INFO: invalid 5bit words: 0
[15:34:10.813] <TB0> INFO: invalid XOR eye diagram: 0
[15:34:10.813] <TB0> INFO: frame (failed synchr.): 0
[15:34:10.813] <TB0> INFO: idle data (no TBM trl): 0
[15:34:10.813] <TB0> INFO: no data (only TBM hdr): 0
[15:34:10.813] <TB0> INFO: TBM errors: 0
[15:34:10.813] <TB0> INFO: flawed TBM headers: 0
[15:34:10.813] <TB0> INFO: flawed TBM trailers: 0
[15:34:10.813] <TB0> INFO: event ID mismatches: 0
[15:34:10.813] <TB0> INFO: ROC errors: 0
[15:34:10.813] <TB0> INFO: missing ROC header(s): 0
[15:34:10.813] <TB0> INFO: misplaced readback start: 0
[15:34:10.813] <TB0> INFO: Pixel decoding errors: 0
[15:34:10.813] <TB0> INFO: pixel data incomplete: 0
[15:34:10.813] <TB0> INFO: pixel address: 0
[15:34:10.813] <TB0> INFO: pulse height fill bit: 0
[15:34:10.813] <TB0> INFO: buffer corruption: 0
[15:34:10.896] <TB0> INFO: ######################################################################
[15:34:10.896] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:34:10.896] <TB0> INFO: ######################################################################
[15:34:10.896] <TB0> INFO: ----------------------------------------------------------------------
[15:34:10.896] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:34:10.896] <TB0> INFO: ----------------------------------------------------------------------
[15:34:10.896] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:34:10.906] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[15:34:10.906] <TB0> INFO: run 1 of 1
[15:34:11.183] <TB0> INFO: Expecting 26208000 events.
[15:34:46.437] <TB0> INFO: 943850 events read in total (34698ms).
[15:35:19.118] <TB0> INFO: 1871500 events read in total (67379ms).
[15:35:52.215] <TB0> INFO: 2799550 events read in total (100476ms).
[15:36:25.496] <TB0> INFO: 3728550 events read in total (133757ms).
[15:36:58.932] <TB0> INFO: 4654450 events read in total (167193ms).
[15:37:31.673] <TB0> INFO: 5579150 events read in total (199934ms).
[15:38:04.659] <TB0> INFO: 6500700 events read in total (232920ms).
[15:38:37.694] <TB0> INFO: 7423000 events read in total (265955ms).
[15:39:11.215] <TB0> INFO: 8346350 events read in total (299476ms).
[15:39:44.020] <TB0> INFO: 9270350 events read in total (332281ms).
[15:40:17.116] <TB0> INFO: 10191250 events read in total (365377ms).
[15:40:50.456] <TB0> INFO: 11111500 events read in total (398717ms).
[15:41:23.760] <TB0> INFO: 12030700 events read in total (432021ms).
[15:41:56.435] <TB0> INFO: 12946450 events read in total (464696ms).
[15:42:29.373] <TB0> INFO: 13855750 events read in total (497634ms).
[15:43:02.681] <TB0> INFO: 14763500 events read in total (530942ms).
[15:43:35.683] <TB0> INFO: 15670750 events read in total (563944ms).
[15:44:08.856] <TB0> INFO: 16571600 events read in total (597117ms).
[15:44:41.558] <TB0> INFO: 17474700 events read in total (629819ms).
[15:45:14.224] <TB0> INFO: 18376900 events read in total (662485ms).
[15:45:47.184] <TB0> INFO: 19278200 events read in total (695445ms).
[15:46:20.164] <TB0> INFO: 20179850 events read in total (728425ms).
[15:46:52.946] <TB0> INFO: 21078850 events read in total (761207ms).
[15:47:26.128] <TB0> INFO: 21979900 events read in total (794389ms).
[15:47:59.027] <TB0> INFO: 22880500 events read in total (827288ms).
[15:48:32.393] <TB0> INFO: 23783250 events read in total (860654ms).
[15:49:05.529] <TB0> INFO: 24686750 events read in total (893790ms).
[15:49:38.131] <TB0> INFO: 25586650 events read in total (926392ms).
[15:50:00.489] <TB0> INFO: 26208000 events read in total (948750ms).
[15:50:00.516] <TB0> INFO: Test took 949610ms.
[15:50:00.746] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:02.274] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:03.770] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:05.217] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:06.616] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:08.016] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:09.667] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:11.067] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:12.501] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:14.130] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:15.838] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:17.500] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:19.174] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:20.632] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:22.255] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:23.752] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:50:25.327] <TB0> INFO: PixTestScurves::scurves() done
[15:50:25.327] <TB0> INFO: Vcal mean: 97.97 109.63 105.31 110.07 98.39 104.65 103.40 91.54 95.11 83.73 98.48 112.35 92.10 87.51 97.40 102.06
[15:50:25.327] <TB0> INFO: Vcal RMS: 5.91 6.21 6.31 5.21 5.94 5.80 5.69 6.24 5.11 4.94 5.22 4.88 5.61 4.72 5.64 5.59
[15:50:25.327] <TB0> INFO: PixTestScurves::fullTest() done, duration: 974 seconds
[15:50:25.327] <TB0> INFO: Decoding statistics:
[15:50:25.327] <TB0> INFO: General information:
[15:50:25.327] <TB0> INFO: 16bit words read: 0
[15:50:25.327] <TB0> INFO: valid events total: 0
[15:50:25.327] <TB0> INFO: empty events: 0
[15:50:25.327] <TB0> INFO: valid events with pixels: 0
[15:50:25.327] <TB0> INFO: valid pixel hits: 0
[15:50:25.327] <TB0> INFO: Event errors: 0
[15:50:25.327] <TB0> INFO: start marker: 0
[15:50:25.327] <TB0> INFO: stop marker: 0
[15:50:25.327] <TB0> INFO: overflow: 0
[15:50:25.327] <TB0> INFO: invalid 5bit words: 0
[15:50:25.327] <TB0> INFO: invalid XOR eye diagram: 0
[15:50:25.327] <TB0> INFO: frame (failed synchr.): 0
[15:50:25.327] <TB0> INFO: idle data (no TBM trl): 0
[15:50:25.327] <TB0> INFO: no data (only TBM hdr): 0
[15:50:25.327] <TB0> INFO: TBM errors: 0
[15:50:25.327] <TB0> INFO: flawed TBM headers: 0
[15:50:25.327] <TB0> INFO: flawed TBM trailers: 0
[15:50:25.327] <TB0> INFO: event ID mismatches: 0
[15:50:25.327] <TB0> INFO: ROC errors: 0
[15:50:25.327] <TB0> INFO: missing ROC header(s): 0
[15:50:25.327] <TB0> INFO: misplaced readback start: 0
[15:50:25.327] <TB0> INFO: Pixel decoding errors: 0
[15:50:25.327] <TB0> INFO: pixel data incomplete: 0
[15:50:25.327] <TB0> INFO: pixel address: 0
[15:50:25.327] <TB0> INFO: pulse height fill bit: 0
[15:50:25.327] <TB0> INFO: buffer corruption: 0
[15:50:25.399] <TB0> INFO: ######################################################################
[15:50:25.399] <TB0> INFO: PixTestTrim::doTest()
[15:50:25.399] <TB0> INFO: ######################################################################
[15:50:25.400] <TB0> INFO: ----------------------------------------------------------------------
[15:50:25.400] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:50:25.400] <TB0> INFO: ----------------------------------------------------------------------
[15:50:25.479] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:50:25.479] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:50:25.488] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:50:25.488] <TB0> INFO: run 1 of 1
[15:50:25.751] <TB0> INFO: Expecting 6281600 events.
[15:51:06.116] <TB0> INFO: 1099750 events read in total (39809ms).
[15:51:46.314] <TB0> INFO: 2193830 events read in total (80007ms).
[15:52:26.409] <TB0> INFO: 3281910 events read in total (120102ms).
[15:53:05.818] <TB0> INFO: 4373500 events read in total (159511ms).
[15:53:45.523] <TB0> INFO: 5465910 events read in total (199216ms).
[15:54:15.385] <TB0> INFO: 6281600 events read in total (229078ms).
[15:54:15.421] <TB0> INFO: Test took 229933ms.
[15:54:34.916] <TB0> INFO: ROC 0 VthrComp = 97
[15:54:34.916] <TB0> INFO: ROC 1 VthrComp = 101
[15:54:34.916] <TB0> INFO: ROC 2 VthrComp = 94
[15:54:34.916] <TB0> INFO: ROC 3 VthrComp = 106
[15:54:34.916] <TB0> INFO: ROC 4 VthrComp = 93
[15:54:34.916] <TB0> INFO: ROC 5 VthrComp = 100
[15:54:34.916] <TB0> INFO: ROC 6 VthrComp = 99
[15:54:34.916] <TB0> INFO: ROC 7 VthrComp = 86
[15:54:34.916] <TB0> INFO: ROC 8 VthrComp = 92
[15:54:34.916] <TB0> INFO: ROC 9 VthrComp = 82
[15:54:34.917] <TB0> INFO: ROC 10 VthrComp = 102
[15:54:34.917] <TB0> INFO: ROC 11 VthrComp = 107
[15:54:34.917] <TB0> INFO: ROC 12 VthrComp = 92
[15:54:34.917] <TB0> INFO: ROC 13 VthrComp = 87
[15:54:34.917] <TB0> INFO: ROC 14 VthrComp = 97
[15:54:34.917] <TB0> INFO: ROC 15 VthrComp = 101
[15:54:34.917] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:54:34.917] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:54:34.926] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:54:34.926] <TB0> INFO: run 1 of 1
[15:54:35.222] <TB0> INFO: Expecting 6281600 events.
[15:55:10.827] <TB0> INFO: 758320 events read in total (35048ms).
[15:55:45.600] <TB0> INFO: 1515010 events read in total (69821ms).
[15:56:20.491] <TB0> INFO: 2272370 events read in total (104712ms).
[15:56:55.553] <TB0> INFO: 3030210 events read in total (139774ms).
[15:57:30.330] <TB0> INFO: 3782220 events read in total (174551ms).
[15:58:05.006] <TB0> INFO: 4530070 events read in total (209227ms).
[15:58:39.712] <TB0> INFO: 5276150 events read in total (243933ms).
[15:59:14.334] <TB0> INFO: 6022560 events read in total (278555ms).
[15:59:26.564] <TB0> INFO: 6281600 events read in total (290785ms).
[15:59:26.606] <TB0> INFO: Test took 291680ms.
[15:59:52.108] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 62.1475 for pixel 11/2 mean/min/max = 47.1945/32.1244/62.2646
[15:59:52.108] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 68.1265 for pixel 8/0 mean/min/max = 50.3673/32.3186/68.4159
[15:59:52.108] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 70.9916 for pixel 9/16 mean/min/max = 52.0397/32.4135/71.6659
[15:59:52.108] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 62.9794 for pixel 14/75 mean/min/max = 48.7095/34.3227/63.0963
[15:59:52.109] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 63.9283 for pixel 1/2 mean/min/max = 48.6725/33.3686/63.9765
[15:59:52.109] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 60.7171 for pixel 13/3 mean/min/max = 46.44/31.9929/60.887
[15:59:52.109] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 60.6671 for pixel 12/1 mean/min/max = 46.3268/31.802/60.8515
[15:59:52.110] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 64.7249 for pixel 23/15 mean/min/max = 48.0929/31.4273/64.7585
[15:59:52.110] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 59.8002 for pixel 18/43 mean/min/max = 47.0186/34.0889/59.9483
[15:59:52.110] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 60.2878 for pixel 3/9 mean/min/max = 46.7017/33.0091/60.3943
[15:59:52.111] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 56.481 for pixel 29/79 mean/min/max = 44.6163/32.5213/56.7113
[15:59:52.111] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 63.4535 for pixel 24/6 mean/min/max = 49.3739/35.222/63.5259
[15:59:52.112] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.1497 for pixel 5/3 mean/min/max = 46.6632/33.9596/59.3667
[15:59:52.112] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.114 for pixel 24/0 mean/min/max = 44.9821/32.5631/57.401
[15:59:52.112] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.0146 for pixel 1/78 mean/min/max = 45.6881/32.0676/59.3086
[15:59:52.113] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.3734 for pixel 6/2 mean/min/max = 45.6255/31.6918/59.5593
[15:59:52.113] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:59:52.201] <TB0> INFO: Expecting 514560 events.
[16:00:05.099] <TB0> INFO: 514560 events read in total (12342ms).
[16:00:05.104] <TB0> INFO: Expecting 514560 events.
[16:00:17.656] <TB0> INFO: 514560 events read in total (12133ms).
[16:00:17.663] <TB0> INFO: Expecting 514560 events.
[16:00:30.447] <TB0> INFO: 514560 events read in total (12365ms).
[16:00:30.456] <TB0> INFO: Expecting 514560 events.
[16:00:43.246] <TB0> INFO: 514560 events read in total (12380ms).
[16:00:43.256] <TB0> INFO: Expecting 514560 events.
[16:00:55.991] <TB0> INFO: 514560 events read in total (12322ms).
[16:00:56.005] <TB0> INFO: Expecting 514560 events.
[16:01:08.868] <TB0> INFO: 514560 events read in total (12455ms).
[16:01:08.883] <TB0> INFO: Expecting 514560 events.
[16:01:21.708] <TB0> INFO: 514560 events read in total (12423ms).
[16:01:21.726] <TB0> INFO: Expecting 514560 events.
[16:01:34.512] <TB0> INFO: 514560 events read in total (12379ms).
[16:01:34.530] <TB0> INFO: Expecting 514560 events.
[16:01:47.422] <TB0> INFO: 514560 events read in total (12482ms).
[16:01:47.443] <TB0> INFO: Expecting 514560 events.
[16:02:00.222] <TB0> INFO: 514560 events read in total (12377ms).
[16:02:00.246] <TB0> INFO: Expecting 514560 events.
[16:02:13.074] <TB0> INFO: 514560 events read in total (12426ms).
[16:02:13.099] <TB0> INFO: Expecting 514560 events.
[16:02:25.964] <TB0> INFO: 514560 events read in total (12463ms).
[16:02:25.992] <TB0> INFO: Expecting 514560 events.
[16:02:38.725] <TB0> INFO: 514560 events read in total (12332ms).
[16:02:38.752] <TB0> INFO: Expecting 514560 events.
[16:02:51.547] <TB0> INFO: 514560 events read in total (12393ms).
[16:02:51.575] <TB0> INFO: Expecting 514560 events.
[16:03:04.527] <TB0> INFO: 514560 events read in total (12550ms).
[16:03:04.558] <TB0> INFO: Expecting 514560 events.
[16:03:17.311] <TB0> INFO: 514560 events read in total (12352ms).
[16:03:17.342] <TB0> INFO: Test took 205229ms.
[16:03:18.227] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:03:18.235] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:03:18.235] <TB0> INFO: run 1 of 1
[16:03:18.498] <TB0> INFO: Expecting 6281600 events.
[16:03:54.067] <TB0> INFO: 743620 events read in total (35013ms).
[16:04:29.040] <TB0> INFO: 1486260 events read in total (69986ms).
[16:05:03.948] <TB0> INFO: 2228830 events read in total (104894ms).
[16:05:38.511] <TB0> INFO: 2971700 events read in total (139457ms).
[16:06:13.012] <TB0> INFO: 3707920 events read in total (173958ms).
[16:06:47.979] <TB0> INFO: 4439190 events read in total (208925ms).
[16:07:22.705] <TB0> INFO: 5168050 events read in total (243651ms).
[16:07:57.370] <TB0> INFO: 5896040 events read in total (278316ms).
[16:08:15.899] <TB0> INFO: 6281600 events read in total (296845ms).
[16:08:15.944] <TB0> INFO: Test took 297709ms.
[16:08:39.818] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.054609 .. 255.000000
[16:08:39.896] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[16:08:39.904] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:08:39.904] <TB0> INFO: run 1 of 1
[16:08:40.167] <TB0> INFO: Expecting 10649600 events.
[16:09:15.374] <TB0> INFO: 712160 events read in total (34651ms).
[16:09:49.779] <TB0> INFO: 1424650 events read in total (69056ms).
[16:10:23.978] <TB0> INFO: 2136800 events read in total (103255ms).
[16:10:58.302] <TB0> INFO: 2849480 events read in total (137579ms).
[16:11:32.531] <TB0> INFO: 3561950 events read in total (171808ms).
[16:12:06.457] <TB0> INFO: 4274570 events read in total (205734ms).
[16:12:41.112] <TB0> INFO: 4987260 events read in total (240389ms).
[16:13:14.917] <TB0> INFO: 5700030 events read in total (274194ms).
[16:13:48.878] <TB0> INFO: 6412630 events read in total (308155ms).
[16:14:23.291] <TB0> INFO: 7124580 events read in total (342568ms).
[16:14:57.616] <TB0> INFO: 7836550 events read in total (376893ms).
[16:15:31.894] <TB0> INFO: 8547840 events read in total (411171ms).
[16:16:05.811] <TB0> INFO: 9258870 events read in total (445088ms).
[16:16:40.256] <TB0> INFO: 9970010 events read in total (479533ms).
[16:17:13.129] <TB0> INFO: 10649600 events read in total (512406ms).
[16:17:13.229] <TB0> INFO: Test took 513324ms.
[16:17:42.772] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 1.010035 .. 56.144045
[16:17:42.860] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 66 (-1/-1) hits flags = 528 (plus default)
[16:17:42.870] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:17:42.870] <TB0> INFO: run 1 of 1
[16:17:43.154] <TB0> INFO: Expecting 2745600 events.
[16:18:20.948] <TB0> INFO: 891270 events read in total (37237ms).
[16:18:58.070] <TB0> INFO: 1782530 events read in total (74359ms).
[16:19:35.245] <TB0> INFO: 2672650 events read in total (111535ms).
[16:19:38.603] <TB0> INFO: 2745600 events read in total (114892ms).
[16:19:38.621] <TB0> INFO: Test took 115752ms.
[16:19:54.955] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 20.295339 .. 56.144045
[16:19:55.046] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 66 (-1/-1) hits flags = 528 (plus default)
[16:19:55.055] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:19:55.055] <TB0> INFO: run 1 of 1
[16:19:55.318] <TB0> INFO: Expecting 2371200 events.
[16:20:33.494] <TB0> INFO: 849820 events read in total (37619ms).
[16:21:09.535] <TB0> INFO: 1699160 events read in total (73660ms).
[16:21:39.159] <TB0> INFO: 2371200 events read in total (103284ms).
[16:21:39.177] <TB0> INFO: Test took 104122ms.
[16:21:55.485] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 2.798613 .. 56.144045
[16:21:55.567] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 66 (-1/-1) hits flags = 528 (plus default)
[16:21:55.580] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:21:55.580] <TB0> INFO: run 1 of 1
[16:21:55.900] <TB0> INFO: Expecting 2704000 events.
[16:22:34.903] <TB0> INFO: 889470 events read in total (38447ms).
[16:23:12.367] <TB0> INFO: 1778280 events read in total (75912ms).
[16:23:49.542] <TB0> INFO: 2667120 events read in total (113087ms).
[16:23:51.454] <TB0> INFO: 2704000 events read in total (114998ms).
[16:23:51.473] <TB0> INFO: Test took 115892ms.
[16:24:06.539] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:24:06.539] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:24:06.548] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[16:24:06.548] <TB0> INFO: run 1 of 1
[16:24:06.810] <TB0> INFO: Expecting 1705600 events.
[16:24:45.405] <TB0> INFO: 878090 events read in total (38038ms).
[16:25:20.545] <TB0> INFO: 1705600 events read in total (73178ms).
[16:25:20.557] <TB0> INFO: Test took 74009ms.
[16:25:34.025] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:25:34.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:25:34.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:25:34.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:25:34.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:25:34.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:25:34.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:25:34.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:25:34.026] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:25:34.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:25:34.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:25:34.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:25:34.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:25:34.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:25:34.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:25:34.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:25:34.027] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:25:34.034] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:25:34.041] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:25:34.048] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:25:34.055] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:25:34.062] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:25:34.069] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:25:34.076] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:25:34.083] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:25:34.090] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:25:34.097] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:25:34.104] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:25:34.111] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:25:34.118] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:25:34.125] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:25:34.132] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:25:34.138] <TB0> INFO: PixTestTrim::trimTest() done
[16:25:34.138] <TB0> INFO: vtrim: 107 108 123 106 110 107 108 111 107 97 96 118 100 91 108 105
[16:25:34.138] <TB0> INFO: vthrcomp: 97 101 94 106 93 100 99 86 92 82 102 107 92 87 97 101
[16:25:34.138] <TB0> INFO: vcal mean: 35.04 34.93 35.01 35.02 35.03 34.98 34.96 34.95 34.95 34.99 34.96 34.98 34.95 34.99 34.99 34.97
[16:25:34.138] <TB0> INFO: vcal RMS: 0.92 0.95 1.27 0.88 1.09 0.92 0.88 0.94 0.84 0.86 0.83 0.90 0.87 0.82 0.87 0.89
[16:25:34.138] <TB0> INFO: bits mean: 9.35 8.35 8.77 8.44 9.10 9.29 9.41 9.37 9.02 8.87 9.55 8.24 8.98 9.43 9.73 9.16
[16:25:34.138] <TB0> INFO: bits RMS: 2.64 2.79 2.58 2.54 2.49 2.74 2.71 2.65 2.54 2.75 2.68 2.48 2.61 2.74 2.60 2.94
[16:25:34.144] <TB0> INFO: ----------------------------------------------------------------------
[16:25:34.144] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:25:34.144] <TB0> INFO: ----------------------------------------------------------------------
[16:25:34.146] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:25:34.155] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:25:34.155] <TB0> INFO: run 1 of 1
[16:25:34.419] <TB0> INFO: Expecting 4160000 events.
[16:26:17.381] <TB0> INFO: 967895 events read in total (42406ms).
[16:26:58.962] <TB0> INFO: 1927410 events read in total (83987ms).
[16:27:40.970] <TB0> INFO: 2875405 events read in total (125995ms).
[16:28:22.964] <TB0> INFO: 3820175 events read in total (167989ms).
[16:28:38.188] <TB0> INFO: 4160000 events read in total (183213ms).
[16:28:38.226] <TB0> INFO: Test took 184071ms.
[16:29:07.751] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 189 (-1/-1) hits flags = 528 (plus default)
[16:29:07.760] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:29:07.760] <TB0> INFO: run 1 of 1
[16:29:08.026] <TB0> INFO: Expecting 3952000 events.
[16:29:50.354] <TB0> INFO: 953140 events read in total (41772ms).
[16:30:31.550] <TB0> INFO: 1898880 events read in total (82968ms).
[16:31:13.005] <TB0> INFO: 2833485 events read in total (124423ms).
[16:31:54.351] <TB0> INFO: 3765515 events read in total (165769ms).
[16:32:03.004] <TB0> INFO: 3952000 events read in total (174422ms).
[16:32:03.040] <TB0> INFO: Test took 175280ms.
[16:32:31.794] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[16:32:31.803] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:32:31.803] <TB0> INFO: run 1 of 1
[16:32:32.091] <TB0> INFO: Expecting 3681600 events.
[16:33:15.412] <TB0> INFO: 984220 events read in total (42765ms).
[16:33:58.058] <TB0> INFO: 1958435 events read in total (85411ms).
[16:34:40.300] <TB0> INFO: 2920425 events read in total (127653ms).
[16:35:13.343] <TB0> INFO: 3681600 events read in total (160696ms).
[16:35:13.381] <TB0> INFO: Test took 161576ms.
[16:35:40.314] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[16:35:40.323] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:35:40.323] <TB0> INFO: run 1 of 1
[16:35:40.585] <TB0> INFO: Expecting 3681600 events.
[16:36:23.353] <TB0> INFO: 984270 events read in total (42212ms).
[16:37:05.606] <TB0> INFO: 1958480 events read in total (84465ms).
[16:37:47.594] <TB0> INFO: 2920430 events read in total (126453ms).
[16:38:20.775] <TB0> INFO: 3681600 events read in total (159634ms).
[16:38:20.813] <TB0> INFO: Test took 160490ms.
[16:38:47.532] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[16:38:47.541] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:38:47.541] <TB0> INFO: run 1 of 1
[16:38:47.812] <TB0> INFO: Expecting 3556800 events.
[16:39:31.807] <TB0> INFO: 1000675 events read in total (43439ms).
[16:40:14.488] <TB0> INFO: 1989610 events read in total (86120ms).
[16:40:57.087] <TB0> INFO: 2966400 events read in total (128720ms).
[16:41:23.145] <TB0> INFO: 3556800 events read in total (154777ms).
[16:41:23.182] <TB0> INFO: Test took 155641ms.
[16:41:49.585] <TB0> INFO: PixTestTrim::trimBitTest() done
[16:41:49.587] <TB0> INFO: PixTestTrim::doTest() done, duration: 3084 seconds
[16:41:49.587] <TB0> INFO: Decoding statistics:
[16:41:49.587] <TB0> INFO: General information:
[16:41:49.587] <TB0> INFO: 16bit words read: 0
[16:41:49.587] <TB0> INFO: valid events total: 0
[16:41:49.587] <TB0> INFO: empty events: 0
[16:41:49.587] <TB0> INFO: valid events with pixels: 0
[16:41:49.587] <TB0> INFO: valid pixel hits: 0
[16:41:49.587] <TB0> INFO: Event errors: 0
[16:41:49.587] <TB0> INFO: start marker: 0
[16:41:49.587] <TB0> INFO: stop marker: 0
[16:41:49.587] <TB0> INFO: overflow: 0
[16:41:49.587] <TB0> INFO: invalid 5bit words: 0
[16:41:49.587] <TB0> INFO: invalid XOR eye diagram: 0
[16:41:49.587] <TB0> INFO: frame (failed synchr.): 0
[16:41:49.587] <TB0> INFO: idle data (no TBM trl): 0
[16:41:49.587] <TB0> INFO: no data (only TBM hdr): 0
[16:41:49.587] <TB0> INFO: TBM errors: 0
[16:41:49.587] <TB0> INFO: flawed TBM headers: 0
[16:41:49.587] <TB0> INFO: flawed TBM trailers: 0
[16:41:49.587] <TB0> INFO: event ID mismatches: 0
[16:41:49.587] <TB0> INFO: ROC errors: 0
[16:41:49.587] <TB0> INFO: missing ROC header(s): 0
[16:41:49.587] <TB0> INFO: misplaced readback start: 0
[16:41:49.587] <TB0> INFO: Pixel decoding errors: 0
[16:41:49.587] <TB0> INFO: pixel data incomplete: 0
[16:41:49.587] <TB0> INFO: pixel address: 0
[16:41:49.587] <TB0> INFO: pulse height fill bit: 0
[16:41:49.587] <TB0> INFO: buffer corruption: 0
[16:41:50.272] <TB0> INFO: ######################################################################
[16:41:50.272] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:41:50.272] <TB0> INFO: ######################################################################
[16:41:50.535] <TB0> INFO: Expecting 41600 events.
[16:41:54.309] <TB0> INFO: 41600 events read in total (3217ms).
[16:41:54.309] <TB0> INFO: Test took 4036ms.
[16:41:54.827] <TB0> INFO: Expecting 41600 events.
[16:41:58.672] <TB0> INFO: 41600 events read in total (3289ms).
[16:41:58.672] <TB0> INFO: Test took 4157ms.
[16:41:58.954] <TB0> INFO: Expecting 41600 events.
[16:42:02.747] <TB0> INFO: 41600 events read in total (3236ms).
[16:42:02.747] <TB0> INFO: Test took 4053ms.
[16:42:03.002] <TB0> INFO: Expecting 2560 events.
[16:42:03.855] <TB0> INFO: 2560 events read in total (297ms).
[16:42:03.855] <TB0> INFO: Test took 1095ms.
[16:42:04.160] <TB0> INFO: Expecting 2560 events.
[16:42:05.013] <TB0> INFO: 2560 events read in total (297ms).
[16:42:05.013] <TB0> INFO: Test took 1158ms.
[16:42:05.319] <TB0> INFO: Expecting 2560 events.
[16:42:06.171] <TB0> INFO: 2560 events read in total (296ms).
[16:42:06.171] <TB0> INFO: Test took 1157ms.
[16:42:06.476] <TB0> INFO: Expecting 2560 events.
[16:42:07.329] <TB0> INFO: 2560 events read in total (297ms).
[16:42:07.329] <TB0> INFO: Test took 1158ms.
[16:42:07.634] <TB0> INFO: Expecting 2560 events.
[16:42:08.486] <TB0> INFO: 2560 events read in total (296ms).
[16:42:08.486] <TB0> INFO: Test took 1157ms.
[16:42:08.791] <TB0> INFO: Expecting 2560 events.
[16:42:09.644] <TB0> INFO: 2560 events read in total (297ms).
[16:42:09.644] <TB0> INFO: Test took 1157ms.
[16:42:09.949] <TB0> INFO: Expecting 2560 events.
[16:42:10.802] <TB0> INFO: 2560 events read in total (297ms).
[16:42:10.802] <TB0> INFO: Test took 1158ms.
[16:42:11.107] <TB0> INFO: Expecting 2560 events.
[16:42:11.959] <TB0> INFO: 2560 events read in total (296ms).
[16:42:11.960] <TB0> INFO: Test took 1158ms.
[16:42:12.265] <TB0> INFO: Expecting 2560 events.
[16:42:13.121] <TB0> INFO: 2560 events read in total (300ms).
[16:42:13.122] <TB0> INFO: Test took 1161ms.
[16:42:13.427] <TB0> INFO: Expecting 2560 events.
[16:42:14.285] <TB0> INFO: 2560 events read in total (302ms).
[16:42:14.285] <TB0> INFO: Test took 1163ms.
[16:42:14.590] <TB0> INFO: Expecting 2560 events.
[16:42:15.448] <TB0> INFO: 2560 events read in total (302ms).
[16:42:15.448] <TB0> INFO: Test took 1164ms.
[16:42:15.754] <TB0> INFO: Expecting 2560 events.
[16:42:16.606] <TB0> INFO: 2560 events read in total (296ms).
[16:42:16.606] <TB0> INFO: Test took 1157ms.
[16:42:16.912] <TB0> INFO: Expecting 2560 events.
[16:42:17.765] <TB0> INFO: 2560 events read in total (297ms).
[16:42:17.765] <TB0> INFO: Test took 1158ms.
[16:42:18.070] <TB0> INFO: Expecting 2560 events.
[16:42:18.923] <TB0> INFO: 2560 events read in total (296ms).
[16:42:18.923] <TB0> INFO: Test took 1157ms.
[16:42:19.229] <TB0> INFO: Expecting 2560 events.
[16:42:20.083] <TB0> INFO: 2560 events read in total (298ms).
[16:42:20.084] <TB0> INFO: Test took 1160ms.
[16:42:20.388] <TB0> INFO: Expecting 2560 events.
[16:42:21.243] <TB0> INFO: 2560 events read in total (299ms).
[16:42:21.243] <TB0> INFO: Test took 1159ms.
[16:42:21.247] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:42:21.550] <TB0> INFO: Expecting 655360 events.
[16:42:38.495] <TB0> INFO: 655360 events read in total (16389ms).
[16:42:38.504] <TB0> INFO: Expecting 655360 events.
[16:42:55.362] <TB0> INFO: 655360 events read in total (16457ms).
[16:42:55.378] <TB0> INFO: Expecting 655360 events.
[16:43:12.139] <TB0> INFO: 655360 events read in total (16359ms).
[16:43:12.156] <TB0> INFO: Expecting 655360 events.
[16:43:29.034] <TB0> INFO: 655360 events read in total (16477ms).
[16:43:29.052] <TB0> INFO: Expecting 655360 events.
[16:43:45.929] <TB0> INFO: 655360 events read in total (16475ms).
[16:43:45.952] <TB0> INFO: Expecting 655360 events.
[16:44:02.747] <TB0> INFO: 655360 events read in total (16393ms).
[16:44:02.774] <TB0> INFO: Expecting 655360 events.
[16:44:19.543] <TB0> INFO: 655360 events read in total (16368ms).
[16:44:19.572] <TB0> INFO: Expecting 655360 events.
[16:44:36.229] <TB0> INFO: 655360 events read in total (16255ms).
[16:44:36.265] <TB0> INFO: Expecting 655360 events.
[16:44:53.049] <TB0> INFO: 655360 events read in total (16382ms).
[16:44:53.085] <TB0> INFO: Expecting 655360 events.
[16:45:09.895] <TB0> INFO: 655360 events read in total (16408ms).
[16:45:09.935] <TB0> INFO: Expecting 655360 events.
[16:45:26.584] <TB0> INFO: 655360 events read in total (16247ms).
[16:45:26.627] <TB0> INFO: Expecting 655360 events.
[16:45:43.366] <TB0> INFO: 655360 events read in total (16338ms).
[16:45:43.411] <TB0> INFO: Expecting 655360 events.
[16:45:59.828] <TB0> INFO: 655360 events read in total (16015ms).
[16:45:59.879] <TB0> INFO: Expecting 655360 events.
[16:46:16.467] <TB0> INFO: 655360 events read in total (16186ms).
[16:46:16.524] <TB0> INFO: Expecting 655360 events.
[16:46:33.356] <TB0> INFO: 655360 events read in total (16430ms).
[16:46:33.414] <TB0> INFO: Expecting 655360 events.
[16:46:50.207] <TB0> INFO: 655360 events read in total (16391ms).
[16:46:50.267] <TB0> INFO: Test took 269020ms.
[16:46:50.352] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:46:50.583] <TB0> INFO: Expecting 655360 events.
[16:47:07.551] <TB0> INFO: 655360 events read in total (16412ms).
[16:47:07.560] <TB0> INFO: Expecting 655360 events.
[16:47:24.471] <TB0> INFO: 655360 events read in total (16509ms).
[16:47:24.483] <TB0> INFO: Expecting 655360 events.
[16:47:41.287] <TB0> INFO: 655360 events read in total (16403ms).
[16:47:41.302] <TB0> INFO: Expecting 655360 events.
[16:47:58.059] <TB0> INFO: 655360 events read in total (16355ms).
[16:47:58.082] <TB0> INFO: Expecting 655360 events.
[16:48:14.750] <TB0> INFO: 655360 events read in total (16266ms).
[16:48:14.787] <TB0> INFO: Expecting 655360 events.
[16:48:31.529] <TB0> INFO: 655360 events read in total (16340ms).
[16:48:31.555] <TB0> INFO: Expecting 655360 events.
[16:48:48.427] <TB0> INFO: 655360 events read in total (16471ms).
[16:48:48.458] <TB0> INFO: Expecting 655360 events.
[16:49:05.106] <TB0> INFO: 655360 events read in total (16247ms).
[16:49:05.139] <TB0> INFO: Expecting 655360 events.
[16:49:21.854] <TB0> INFO: 655360 events read in total (16314ms).
[16:49:21.891] <TB0> INFO: Expecting 655360 events.
[16:49:38.558] <TB0> INFO: 655360 events read in total (16266ms).
[16:49:38.600] <TB0> INFO: Expecting 655360 events.
[16:49:55.315] <TB0> INFO: 655360 events read in total (16314ms).
[16:49:55.364] <TB0> INFO: Expecting 655360 events.
[16:50:12.034] <TB0> INFO: 655360 events read in total (16268ms).
[16:50:12.086] <TB0> INFO: Expecting 655360 events.
[16:50:28.733] <TB0> INFO: 655360 events read in total (16246ms).
[16:50:28.784] <TB0> INFO: Expecting 655360 events.
[16:50:45.460] <TB0> INFO: 655360 events read in total (16275ms).
[16:50:45.513] <TB0> INFO: Expecting 655360 events.
[16:51:02.139] <TB0> INFO: 655360 events read in total (16224ms).
[16:51:02.198] <TB0> INFO: Expecting 655360 events.
[16:51:18.518] <TB0> INFO: 655360 events read in total (15919ms).
[16:51:18.579] <TB0> INFO: Test took 268227ms.
[16:51:18.777] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.784] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.792] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.800] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.807] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.814] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.822] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.829] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.836] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.843] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.851] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.858] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[16:51:18.865] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[16:51:18.872] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[16:51:18.880] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.887] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.894] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.901] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.909] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:18.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:51:18.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:51:18.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:51:18.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:51:18.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:51:18.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:51:18.946] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:51:18.947] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:51:18.947] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:51:18.947] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:51:18.947] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:51:18.947] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:51:18.947] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:51:18.947] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:51:18.948] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:51:18.948] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:51:19.266] <TB0> INFO: Expecting 41600 events.
[16:51:22.771] <TB0> INFO: 41600 events read in total (2948ms).
[16:51:22.772] <TB0> INFO: Test took 3821ms.
[16:51:23.242] <TB0> INFO: Expecting 41600 events.
[16:51:26.708] <TB0> INFO: 41600 events read in total (2910ms).
[16:51:26.708] <TB0> INFO: Test took 3743ms.
[16:51:27.149] <TB0> INFO: Expecting 41600 events.
[16:51:30.603] <TB0> INFO: 41600 events read in total (2898ms).
[16:51:30.603] <TB0> INFO: Test took 3717ms.
[16:51:30.784] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:30.871] <TB0> INFO: Expecting 2560 events.
[16:51:31.725] <TB0> INFO: 2560 events read in total (297ms).
[16:51:31.727] <TB0> INFO: Test took 944ms.
[16:51:31.728] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:32.031] <TB0> INFO: Expecting 2560 events.
[16:51:32.885] <TB0> INFO: 2560 events read in total (298ms).
[16:51:32.885] <TB0> INFO: Test took 1157ms.
[16:51:32.888] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:33.191] <TB0> INFO: Expecting 2560 events.
[16:51:34.044] <TB0> INFO: 2560 events read in total (297ms).
[16:51:34.044] <TB0> INFO: Test took 1156ms.
[16:51:34.046] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:34.349] <TB0> INFO: Expecting 2560 events.
[16:51:35.208] <TB0> INFO: 2560 events read in total (303ms).
[16:51:35.208] <TB0> INFO: Test took 1162ms.
[16:51:35.210] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:35.514] <TB0> INFO: Expecting 2560 events.
[16:51:36.367] <TB0> INFO: 2560 events read in total (297ms).
[16:51:36.368] <TB0> INFO: Test took 1158ms.
[16:51:36.370] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:36.673] <TB0> INFO: Expecting 2560 events.
[16:51:37.526] <TB0> INFO: 2560 events read in total (297ms).
[16:51:37.526] <TB0> INFO: Test took 1156ms.
[16:51:37.528] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:37.832] <TB0> INFO: Expecting 2560 events.
[16:51:38.686] <TB0> INFO: 2560 events read in total (298ms).
[16:51:38.686] <TB0> INFO: Test took 1158ms.
[16:51:38.688] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:38.991] <TB0> INFO: Expecting 2560 events.
[16:51:39.847] <TB0> INFO: 2560 events read in total (300ms).
[16:51:39.848] <TB0> INFO: Test took 1160ms.
[16:51:39.850] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:40.153] <TB0> INFO: Expecting 2560 events.
[16:51:41.011] <TB0> INFO: 2560 events read in total (302ms).
[16:51:41.012] <TB0> INFO: Test took 1162ms.
[16:51:41.016] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:41.317] <TB0> INFO: Expecting 2560 events.
[16:51:42.171] <TB0> INFO: 2560 events read in total (298ms).
[16:51:42.171] <TB0> INFO: Test took 1155ms.
[16:51:42.174] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:42.477] <TB0> INFO: Expecting 2560 events.
[16:51:43.335] <TB0> INFO: 2560 events read in total (302ms).
[16:51:43.335] <TB0> INFO: Test took 1161ms.
[16:51:43.348] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:43.641] <TB0> INFO: Expecting 2560 events.
[16:51:44.494] <TB0> INFO: 2560 events read in total (297ms).
[16:51:44.494] <TB0> INFO: Test took 1146ms.
[16:51:44.497] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:44.800] <TB0> INFO: Expecting 2560 events.
[16:51:45.653] <TB0> INFO: 2560 events read in total (297ms).
[16:51:45.653] <TB0> INFO: Test took 1156ms.
[16:51:45.656] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:45.959] <TB0> INFO: Expecting 2560 events.
[16:51:46.815] <TB0> INFO: 2560 events read in total (300ms).
[16:51:46.815] <TB0> INFO: Test took 1160ms.
[16:51:46.817] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:47.121] <TB0> INFO: Expecting 2560 events.
[16:51:47.973] <TB0> INFO: 2560 events read in total (296ms).
[16:51:47.973] <TB0> INFO: Test took 1156ms.
[16:51:47.975] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:48.279] <TB0> INFO: Expecting 2560 events.
[16:51:49.135] <TB0> INFO: 2560 events read in total (300ms).
[16:51:49.135] <TB0> INFO: Test took 1160ms.
[16:51:49.137] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:49.441] <TB0> INFO: Expecting 2560 events.
[16:51:50.295] <TB0> INFO: 2560 events read in total (298ms).
[16:51:50.295] <TB0> INFO: Test took 1159ms.
[16:51:50.297] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:50.601] <TB0> INFO: Expecting 2560 events.
[16:51:51.454] <TB0> INFO: 2560 events read in total (297ms).
[16:51:51.454] <TB0> INFO: Test took 1157ms.
[16:51:51.456] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:51.760] <TB0> INFO: Expecting 2560 events.
[16:51:52.613] <TB0> INFO: 2560 events read in total (297ms).
[16:51:52.613] <TB0> INFO: Test took 1157ms.
[16:51:52.616] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:52.919] <TB0> INFO: Expecting 2560 events.
[16:51:53.771] <TB0> INFO: 2560 events read in total (296ms).
[16:51:53.772] <TB0> INFO: Test took 1156ms.
[16:51:53.773] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:54.077] <TB0> INFO: Expecting 2560 events.
[16:51:54.931] <TB0> INFO: 2560 events read in total (298ms).
[16:51:54.931] <TB0> INFO: Test took 1158ms.
[16:51:54.933] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:55.237] <TB0> INFO: Expecting 2560 events.
[16:51:56.096] <TB0> INFO: 2560 events read in total (302ms).
[16:51:56.096] <TB0> INFO: Test took 1163ms.
[16:51:56.098] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:56.403] <TB0> INFO: Expecting 2560 events.
[16:51:57.257] <TB0> INFO: 2560 events read in total (298ms).
[16:51:57.257] <TB0> INFO: Test took 1159ms.
[16:51:57.260] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:57.564] <TB0> INFO: Expecting 2560 events.
[16:51:58.422] <TB0> INFO: 2560 events read in total (302ms).
[16:51:58.422] <TB0> INFO: Test took 1162ms.
[16:51:58.424] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:58.729] <TB0> INFO: Expecting 2560 events.
[16:51:59.587] <TB0> INFO: 2560 events read in total (301ms).
[16:51:59.587] <TB0> INFO: Test took 1163ms.
[16:51:59.589] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:59.893] <TB0> INFO: Expecting 2560 events.
[16:52:00.754] <TB0> INFO: 2560 events read in total (304ms).
[16:52:00.754] <TB0> INFO: Test took 1165ms.
[16:52:00.757] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:01.059] <TB0> INFO: Expecting 2560 events.
[16:52:01.919] <TB0> INFO: 2560 events read in total (304ms).
[16:52:01.919] <TB0> INFO: Test took 1163ms.
[16:52:01.923] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:02.224] <TB0> INFO: Expecting 2560 events.
[16:52:03.078] <TB0> INFO: 2560 events read in total (298ms).
[16:52:03.078] <TB0> INFO: Test took 1156ms.
[16:52:03.079] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:03.383] <TB0> INFO: Expecting 2560 events.
[16:52:04.236] <TB0> INFO: 2560 events read in total (297ms).
[16:52:04.236] <TB0> INFO: Test took 1157ms.
[16:52:04.238] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:04.542] <TB0> INFO: Expecting 2560 events.
[16:52:05.397] <TB0> INFO: 2560 events read in total (299ms).
[16:52:05.397] <TB0> INFO: Test took 1159ms.
[16:52:05.399] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:05.703] <TB0> INFO: Expecting 2560 events.
[16:52:06.557] <TB0> INFO: 2560 events read in total (298ms).
[16:52:06.557] <TB0> INFO: Test took 1158ms.
[16:52:06.560] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:06.863] <TB0> INFO: Expecting 2560 events.
[16:52:07.721] <TB0> INFO: 2560 events read in total (302ms).
[16:52:07.721] <TB0> INFO: Test took 1161ms.
[16:52:08.183] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[16:52:08.183] <TB0> INFO: PH scale (per ROC): 67 62 65 69 68 65 63 68 66 68 80 70 75 76 71 64
[16:52:08.183] <TB0> INFO: PH offset (per ROC): 187 200 205 187 214 191 197 175 186 182 184 189 184 167 180 197
[16:52:08.188] <TB0> INFO: Decoding statistics:
[16:52:08.188] <TB0> INFO: General information:
[16:52:08.188] <TB0> INFO: 16bit words read: 91916
[16:52:08.188] <TB0> INFO: valid events total: 10240
[16:52:08.188] <TB0> INFO: empty events: 7741
[16:52:08.188] <TB0> INFO: valid events with pixels: 2499
[16:52:08.188] <TB0> INFO: valid pixel hits: 2499
[16:52:08.188] <TB0> INFO: Event errors: 0
[16:52:08.188] <TB0> INFO: start marker: 0
[16:52:08.188] <TB0> INFO: stop marker: 0
[16:52:08.188] <TB0> INFO: overflow: 0
[16:52:08.188] <TB0> INFO: invalid 5bit words: 0
[16:52:08.188] <TB0> INFO: invalid XOR eye diagram: 0
[16:52:08.188] <TB0> INFO: frame (failed synchr.): 0
[16:52:08.188] <TB0> INFO: idle data (no TBM trl): 0
[16:52:08.188] <TB0> INFO: no data (only TBM hdr): 0
[16:52:08.188] <TB0> INFO: TBM errors: 0
[16:52:08.188] <TB0> INFO: flawed TBM headers: 0
[16:52:08.188] <TB0> INFO: flawed TBM trailers: 0
[16:52:08.188] <TB0> INFO: event ID mismatches: 0
[16:52:08.188] <TB0> INFO: ROC errors: 0
[16:52:08.188] <TB0> INFO: missing ROC header(s): 0
[16:52:08.188] <TB0> INFO: misplaced readback start: 0
[16:52:08.188] <TB0> INFO: Pixel decoding errors: 0
[16:52:08.188] <TB0> INFO: pixel data incomplete: 0
[16:52:08.188] <TB0> INFO: pixel address: 0
[16:52:08.188] <TB0> INFO: pulse height fill bit: 0
[16:52:08.188] <TB0> INFO: buffer corruption: 0
[16:52:08.370] <TB0> INFO: ######################################################################
[16:52:08.370] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:52:08.370] <TB0> INFO: ######################################################################
[16:52:08.381] <TB0> INFO: scanning low vcal = 10
[16:52:08.660] <TB0> INFO: Expecting 41600 events.
[16:52:12.191] <TB0> INFO: 41600 events read in total (2974ms).
[16:52:12.191] <TB0> INFO: Test took 3810ms.
[16:52:12.193] <TB0> INFO: scanning low vcal = 20
[16:52:12.495] <TB0> INFO: Expecting 41600 events.
[16:52:16.071] <TB0> INFO: 41600 events read in total (3020ms).
[16:52:16.071] <TB0> INFO: Test took 3878ms.
[16:52:16.074] <TB0> INFO: scanning low vcal = 30
[16:52:16.374] <TB0> INFO: Expecting 41600 events.
[16:52:19.951] <TB0> INFO: 41600 events read in total (3020ms).
[16:52:19.951] <TB0> INFO: Test took 3877ms.
[16:52:19.953] <TB0> INFO: scanning low vcal = 40
[16:52:20.245] <TB0> INFO: Expecting 41600 events.
[16:52:24.418] <TB0> INFO: 41600 events read in total (3617ms).
[16:52:24.419] <TB0> INFO: Test took 4466ms.
[16:52:24.422] <TB0> INFO: scanning low vcal = 50
[16:52:24.711] <TB0> INFO: Expecting 41600 events.
[16:52:28.890] <TB0> INFO: 41600 events read in total (3623ms).
[16:52:28.890] <TB0> INFO: Test took 4468ms.
[16:52:28.893] <TB0> INFO: scanning low vcal = 60
[16:52:29.163] <TB0> INFO: Expecting 41600 events.
[16:52:33.395] <TB0> INFO: 41600 events read in total (3676ms).
[16:52:33.395] <TB0> INFO: Test took 4502ms.
[16:52:33.398] <TB0> INFO: scanning low vcal = 70
[16:52:33.685] <TB0> INFO: Expecting 41600 events.
[16:52:37.893] <TB0> INFO: 41600 events read in total (3652ms).
[16:52:37.894] <TB0> INFO: Test took 4496ms.
[16:52:37.896] <TB0> INFO: scanning low vcal = 80
[16:52:38.155] <TB0> INFO: Expecting 41600 events.
[16:52:42.352] <TB0> INFO: 41600 events read in total (3641ms).
[16:52:42.352] <TB0> INFO: Test took 4456ms.
[16:52:42.355] <TB0> INFO: scanning low vcal = 90
[16:52:42.615] <TB0> INFO: Expecting 41600 events.
[16:52:46.795] <TB0> INFO: 41600 events read in total (3624ms).
[16:52:46.796] <TB0> INFO: Test took 4441ms.
[16:52:46.799] <TB0> INFO: scanning low vcal = 100
[16:52:47.063] <TB0> INFO: Expecting 41600 events.
[16:52:51.229] <TB0> INFO: 41600 events read in total (3610ms).
[16:52:51.230] <TB0> INFO: Test took 4431ms.
[16:52:51.232] <TB0> INFO: scanning low vcal = 110
[16:52:51.504] <TB0> INFO: Expecting 41600 events.
[16:52:55.720] <TB0> INFO: 41600 events read in total (3659ms).
[16:52:55.720] <TB0> INFO: Test took 4488ms.
[16:52:55.723] <TB0> INFO: scanning low vcal = 120
[16:52:56.001] <TB0> INFO: Expecting 41600 events.
[16:53:00.255] <TB0> INFO: 41600 events read in total (3697ms).
[16:53:00.255] <TB0> INFO: Test took 4531ms.
[16:53:00.258] <TB0> INFO: scanning low vcal = 130
[16:53:00.529] <TB0> INFO: Expecting 41600 events.
[16:53:04.747] <TB0> INFO: 41600 events read in total (3662ms).
[16:53:04.748] <TB0> INFO: Test took 4490ms.
[16:53:04.750] <TB0> INFO: scanning low vcal = 140
[16:53:05.012] <TB0> INFO: Expecting 41600 events.
[16:53:09.226] <TB0> INFO: 41600 events read in total (3658ms).
[16:53:09.227] <TB0> INFO: Test took 4477ms.
[16:53:09.230] <TB0> INFO: scanning low vcal = 150
[16:53:09.566] <TB0> INFO: Expecting 41600 events.
[16:53:13.751] <TB0> INFO: 41600 events read in total (3629ms).
[16:53:13.751] <TB0> INFO: Test took 4521ms.
[16:53:13.754] <TB0> INFO: scanning low vcal = 160
[16:53:14.018] <TB0> INFO: Expecting 41600 events.
[16:53:18.190] <TB0> INFO: 41600 events read in total (3616ms).
[16:53:18.191] <TB0> INFO: Test took 4437ms.
[16:53:18.193] <TB0> INFO: scanning low vcal = 170
[16:53:18.454] <TB0> INFO: Expecting 41600 events.
[16:53:22.700] <TB0> INFO: 41600 events read in total (3690ms).
[16:53:22.701] <TB0> INFO: Test took 4508ms.
[16:53:22.705] <TB0> INFO: scanning low vcal = 180
[16:53:22.984] <TB0> INFO: Expecting 41600 events.
[16:53:27.168] <TB0> INFO: 41600 events read in total (3628ms).
[16:53:27.169] <TB0> INFO: Test took 4464ms.
[16:53:27.171] <TB0> INFO: scanning low vcal = 190
[16:53:27.440] <TB0> INFO: Expecting 41600 events.
[16:53:31.648] <TB0> INFO: 41600 events read in total (3652ms).
[16:53:31.649] <TB0> INFO: Test took 4478ms.
[16:53:31.651] <TB0> INFO: scanning low vcal = 200
[16:53:31.914] <TB0> INFO: Expecting 41600 events.
[16:53:36.116] <TB0> INFO: 41600 events read in total (3646ms).
[16:53:36.116] <TB0> INFO: Test took 4465ms.
[16:53:36.119] <TB0> INFO: scanning low vcal = 210
[16:53:36.397] <TB0> INFO: Expecting 41600 events.
[16:53:40.598] <TB0> INFO: 41600 events read in total (3645ms).
[16:53:40.599] <TB0> INFO: Test took 4480ms.
[16:53:40.602] <TB0> INFO: scanning low vcal = 220
[16:53:40.877] <TB0> INFO: Expecting 41600 events.
[16:53:45.070] <TB0> INFO: 41600 events read in total (3637ms).
[16:53:45.071] <TB0> INFO: Test took 4469ms.
[16:53:45.073] <TB0> INFO: scanning low vcal = 230
[16:53:45.346] <TB0> INFO: Expecting 41600 events.
[16:53:49.537] <TB0> INFO: 41600 events read in total (3634ms).
[16:53:49.537] <TB0> INFO: Test took 4464ms.
[16:53:49.541] <TB0> INFO: scanning low vcal = 240
[16:53:49.809] <TB0> INFO: Expecting 41600 events.
[16:53:53.994] <TB0> INFO: 41600 events read in total (3629ms).
[16:53:53.994] <TB0> INFO: Test took 4453ms.
[16:53:53.997] <TB0> INFO: scanning low vcal = 250
[16:53:54.259] <TB0> INFO: Expecting 41600 events.
[16:53:58.461] <TB0> INFO: 41600 events read in total (3646ms).
[16:53:58.461] <TB0> INFO: Test took 4464ms.
[16:53:58.465] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[16:53:58.727] <TB0> INFO: Expecting 41600 events.
[16:54:02.947] <TB0> INFO: 41600 events read in total (3664ms).
[16:54:02.947] <TB0> INFO: Test took 4482ms.
[16:54:02.950] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[16:54:03.212] <TB0> INFO: Expecting 41600 events.
[16:54:07.400] <TB0> INFO: 41600 events read in total (3632ms).
[16:54:07.400] <TB0> INFO: Test took 4450ms.
[16:54:07.403] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[16:54:07.676] <TB0> INFO: Expecting 41600 events.
[16:54:11.896] <TB0> INFO: 41600 events read in total (3664ms).
[16:54:11.896] <TB0> INFO: Test took 4493ms.
[16:54:11.899] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[16:54:12.161] <TB0> INFO: Expecting 41600 events.
[16:54:16.331] <TB0> INFO: 41600 events read in total (3613ms).
[16:54:16.331] <TB0> INFO: Test took 4432ms.
[16:54:16.334] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:54:16.593] <TB0> INFO: Expecting 41600 events.
[16:54:20.763] <TB0> INFO: 41600 events read in total (3614ms).
[16:54:20.763] <TB0> INFO: Test took 4429ms.
[16:54:21.245] <TB0> INFO: PixTestGainPedestal::measure() done
[16:54:56.503] <TB0> INFO: PixTestGainPedestal::fit() done
[16:54:56.503] <TB0> INFO: non-linearity mean: 0.944 0.950 0.965 0.959 0.968 0.960 0.957 0.958 0.962 0.954 0.963 0.962 0.960 0.960 0.959 0.958
[16:54:56.503] <TB0> INFO: non-linearity RMS: 0.016 0.010 0.006 0.005 0.006 0.005 0.007 0.007 0.007 0.007 0.004 0.007 0.006 0.007 0.006 0.008
[16:54:56.503] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:54:56.525] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:54:56.551] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:54:56.572] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:54:56.594] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:54:56.613] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:54:56.632] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:54:56.652] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:54:56.671] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:54:56.690] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:54:56.710] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:54:56.729] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:54:56.751] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:54:56.772] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:54:56.797] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:54:56.818] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:54:56.843] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 168 seconds
[16:54:56.843] <TB0> INFO: Decoding statistics:
[16:54:56.843] <TB0> INFO: General information:
[16:54:56.843] <TB0> INFO: 16bit words read: 2662400
[16:54:56.843] <TB0> INFO: valid events total: 166400
[16:54:56.843] <TB0> INFO: empty events: 0
[16:54:56.843] <TB0> INFO: valid events with pixels: 166400
[16:54:56.843] <TB0> INFO: valid pixel hits: 665569
[16:54:56.843] <TB0> INFO: Event errors: 0
[16:54:56.843] <TB0> INFO: start marker: 0
[16:54:56.843] <TB0> INFO: stop marker: 0
[16:54:56.843] <TB0> INFO: overflow: 0
[16:54:56.843] <TB0> INFO: invalid 5bit words: 0
[16:54:56.843] <TB0> INFO: invalid XOR eye diagram: 0
[16:54:56.843] <TB0> INFO: frame (failed synchr.): 0
[16:54:56.843] <TB0> INFO: idle data (no TBM trl): 0
[16:54:56.843] <TB0> INFO: no data (only TBM hdr): 0
[16:54:56.843] <TB0> INFO: TBM errors: 0
[16:54:56.843] <TB0> INFO: flawed TBM headers: 0
[16:54:56.843] <TB0> INFO: flawed TBM trailers: 0
[16:54:56.843] <TB0> INFO: event ID mismatches: 0
[16:54:56.843] <TB0> INFO: ROC errors: 0
[16:54:56.843] <TB0> INFO: missing ROC header(s): 0
[16:54:56.843] <TB0> INFO: misplaced readback start: 0
[16:54:56.843] <TB0> INFO: Pixel decoding errors: 0
[16:54:56.843] <TB0> INFO: pixel data incomplete: 0
[16:54:56.843] <TB0> INFO: pixel address: 0
[16:54:56.843] <TB0> INFO: pulse height fill bit: 0
[16:54:56.843] <TB0> INFO: buffer corruption: 0
[16:54:56.852] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C15.dat
[16:54:56.853] <TB0> INFO: ######################################################################
[16:54:56.853] <TB0> INFO: PixTestReadback::doTest()
[16:54:56.853] <TB0> INFO: ######################################################################
[16:54:56.853] <TB0> INFO: ----------------------------------------------------------------------
[16:54:56.853] <TB0> INFO: PixTestReadback::CalibrateVd()
[16:54:56.853] <TB0> INFO: ----------------------------------------------------------------------
[16:55:06.304] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C0.dat
[16:55:06.304] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C1.dat
[16:55:06.304] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C2.dat
[16:55:06.304] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C3.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C4.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C5.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C6.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C7.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C8.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C9.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C10.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C11.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C12.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C13.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C14.dat
[16:55:06.305] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C15.dat
[16:55:06.333] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:55:06.333] <TB0> INFO: ----------------------------------------------------------------------
[16:55:06.333] <TB0> INFO: PixTestReadback::CalibrateVa()
[16:55:06.333] <TB0> INFO: ----------------------------------------------------------------------
[16:55:15.844] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C0.dat
[16:55:15.844] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C1.dat
[16:55:15.844] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C2.dat
[16:55:15.844] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C3.dat
[16:55:15.844] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C4.dat
[16:55:15.844] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C5.dat
[16:55:15.844] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C6.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C7.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C8.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C9.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C10.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C11.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C12.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C13.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C14.dat
[16:55:15.845] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C15.dat
[16:55:15.873] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:55:15.873] <TB0> INFO: ----------------------------------------------------------------------
[16:55:15.873] <TB0> INFO: PixTestReadback::readbackVbg()
[16:55:15.873] <TB0> INFO: ----------------------------------------------------------------------
[16:55:23.174] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:55:23.174] <TB0> INFO: ----------------------------------------------------------------------
[16:55:23.174] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[16:55:23.174] <TB0> INFO: ----------------------------------------------------------------------
[16:55:23.174] <TB0> INFO: Vbg will be calibrated using Vd calibration
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 147.9calibrated Vbg = 1.22992 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 147.6calibrated Vbg = 1.23158 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 158.5calibrated Vbg = 1.23187 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 158.1calibrated Vbg = 1.2381 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.8calibrated Vbg = 1.24075 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.5calibrated Vbg = 1.24747 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.2calibrated Vbg = 1.24575 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155.2calibrated Vbg = 1.24862 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151.1calibrated Vbg = 1.25277 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.4calibrated Vbg = 1.24684 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 145.8calibrated Vbg = 1.2339 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 160.3calibrated Vbg = 1.23489 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 147.4calibrated Vbg = 1.22698 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 145.1calibrated Vbg = 1.22935 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.9calibrated Vbg = 1.23059 :::*/*/*/*/
[16:55:23.174] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.2calibrated Vbg = 1.23138 :::*/*/*/*/
[16:55:23.176] <TB0> INFO: ----------------------------------------------------------------------
[16:55:23.176] <TB0> INFO: PixTestReadback::CalibrateIa()
[16:55:23.176] <TB0> INFO: ----------------------------------------------------------------------
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C0.dat
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C1.dat
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C2.dat
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C3.dat
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C4.dat
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C5.dat
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C6.dat
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C7.dat
[16:57:58.459] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C8.dat
[16:57:58.460] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C9.dat
[16:57:58.460] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C10.dat
[16:57:58.460] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C11.dat
[16:57:58.460] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C12.dat
[16:57:58.460] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C13.dat
[16:57:58.460] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C14.dat
[16:57:58.460] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2262_FullQualification_2016-06-28_11h18m_1467105510//004_FulltestPxar_p17//readbackCal_C15.dat
[16:57:58.488] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:57:58.488] <TB0> INFO: PixTestReadback::doTest() done
[16:57:58.488] <TB0> INFO: Decoding statistics:
[16:57:58.488] <TB0> INFO: General information:
[16:57:58.488] <TB0> INFO: 16bit words read: 1024
[16:57:58.488] <TB0> INFO: valid events total: 128
[16:57:58.488] <TB0> INFO: empty events: 128
[16:57:58.488] <TB0> INFO: valid events with pixels: 0
[16:57:58.488] <TB0> INFO: valid pixel hits: 0
[16:57:58.488] <TB0> INFO: Event errors: 0
[16:57:58.488] <TB0> INFO: start marker: 0
[16:57:58.488] <TB0> INFO: stop marker: 0
[16:57:58.489] <TB0> INFO: overflow: 0
[16:57:58.489] <TB0> INFO: invalid 5bit words: 0
[16:57:58.489] <TB0> INFO: invalid XOR eye diagram: 0
[16:57:58.489] <TB0> INFO: frame (failed synchr.): 0
[16:57:58.489] <TB0> INFO: idle data (no TBM trl): 0
[16:57:58.489] <TB0> INFO: no data (only TBM hdr): 0
[16:57:58.489] <TB0> INFO: TBM errors: 0
[16:57:58.489] <TB0> INFO: flawed TBM headers: 0
[16:57:58.489] <TB0> INFO: flawed TBM trailers: 0
[16:57:58.489] <TB0> INFO: event ID mismatches: 0
[16:57:58.489] <TB0> INFO: ROC errors: 0
[16:57:58.489] <TB0> INFO: missing ROC header(s): 0
[16:57:58.489] <TB0> INFO: misplaced readback start: 0
[16:57:58.489] <TB0> INFO: Pixel decoding errors: 0
[16:57:58.489] <TB0> INFO: pixel data incomplete: 0
[16:57:58.489] <TB0> INFO: pixel address: 0
[16:57:58.489] <TB0> INFO: pulse height fill bit: 0
[16:57:58.489] <TB0> INFO: buffer corruption: 0
[16:57:58.507] <TB0> INFO: Decoding statistics:
[16:57:58.507] <TB0> INFO: General information:
[16:57:58.507] <TB0> INFO: 16bit words read: 2755340
[16:57:58.507] <TB0> INFO: valid events total: 176768
[16:57:58.507] <TB0> INFO: empty events: 7869
[16:57:58.507] <TB0> INFO: valid events with pixels: 168899
[16:57:58.507] <TB0> INFO: valid pixel hits: 668068
[16:57:58.507] <TB0> INFO: Event errors: 0
[16:57:58.507] <TB0> INFO: start marker: 0
[16:57:58.507] <TB0> INFO: stop marker: 0
[16:57:58.507] <TB0> INFO: overflow: 0
[16:57:58.507] <TB0> INFO: invalid 5bit words: 0
[16:57:58.507] <TB0> INFO: invalid XOR eye diagram: 0
[16:57:58.507] <TB0> INFO: frame (failed synchr.): 0
[16:57:58.507] <TB0> INFO: idle data (no TBM trl): 0
[16:57:58.507] <TB0> INFO: no data (only TBM hdr): 0
[16:57:58.507] <TB0> INFO: TBM errors: 0
[16:57:58.507] <TB0> INFO: flawed TBM headers: 0
[16:57:58.507] <TB0> INFO: flawed TBM trailers: 0
[16:57:58.507] <TB0> INFO: event ID mismatches: 0
[16:57:58.507] <TB0> INFO: ROC errors: 0
[16:57:58.507] <TB0> INFO: missing ROC header(s): 0
[16:57:58.507] <TB0> INFO: misplaced readback start: 0
[16:57:58.507] <TB0> INFO: Pixel decoding errors: 0
[16:57:58.507] <TB0> INFO: pixel data incomplete: 0
[16:57:58.507] <TB0> INFO: pixel address: 0
[16:57:58.507] <TB0> INFO: pulse height fill bit: 0
[16:57:58.507] <TB0> INFO: buffer corruption: 0
[16:57:58.507] <TB0> INFO: enter test to run
[16:57:58.507] <TB0> INFO: test: exit no parameter change
[16:57:58.627] <TB0> QUIET: Connection to board 73 closed.
[16:57:58.707] <TB0> INFO: pXar: this is the end, my friend