[10:31:25.806] <TB1> INFO: *** Welcome to pxar ***
[10:31:25.806] <TB1> INFO: *** Today: 2016/08/08
[10:31:25.903] <TB1> INFO: *** Version: 9751-dirty
[10:31:25.903] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C15.dat
[10:31:25.905] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:31:25.905] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//defaultMaskFile.dat
[10:31:25.905] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters_C15.dat
[10:31:25.981] <TB1> INFO: clk: 4
[10:31:25.981] <TB1> INFO: ctr: 4
[10:31:25.981] <TB1> INFO: sda: 19
[10:31:25.981] <TB1> INFO: tin: 9
[10:31:25.981] <TB1> INFO: level: 15
[10:31:25.981] <TB1> INFO: triggerdelay: 0
[10:31:25.981] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:31:25.981] <TB1> INFO: Log level: INFO
[10:31:25.988] <TB1> INFO: Found DTB DTB_WXBYFL
[10:31:25.998] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[10:31:26.000] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[10:31:26.002] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[10:31:27.560] <TB1> INFO: DUT info:
[10:31:27.560] <TB1> INFO: The DUT currently contains the following objects:
[10:31:27.560] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:31:27.560] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:31:27.560] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:31:27.560] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:31:27.560] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.560] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:27.961] <TB1> INFO: enter 'restricted' command line mode
[10:31:27.961] <TB1> INFO: enter test to run
[10:31:27.961] <TB1> INFO: test: pretest no parameter change
[10:31:27.961] <TB1> INFO: running: pretest
[10:31:27.967] <TB1> INFO: ----------------------------------------------------------------------
[10:31:27.967] <TB1> INFO: PixTestPretest::programROC()
[10:31:27.967] <TB1> INFO: ----------------------------------------------------------------------
[10:31:45.982] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:31:45.982] <TB1> INFO: IA differences per ROC: 17.7 20.1 16.9 16.9 17.7 19.3 18.5 19.3 18.5 17.7 19.3 14.5 20.1 20.1 18.5 19.3
[10:31:46.081] <TB1> INFO: enter test to run
[10:31:46.081] <TB1> INFO: test: pretest no parameter change
[10:31:46.081] <TB1> INFO: running: pretest
[10:31:46.082] <TB1> INFO: ----------------------------------------------------------------------
[10:31:46.082] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:31:46.082] <TB1> INFO: ----------------------------------------------------------------------
[10:32:07.347] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 388.3 mA = 24.2687 mA/ROC
[10:32:07.347] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 20.1 19.3 18.5 18.5 18.5 20.1 18.5 20.1 19.3 20.1 19.3 19.3 19.3
[10:32:07.374] <TB1> INFO: enter test to run
[10:32:07.374] <TB1> INFO: test: pretest no parameter change
[10:32:07.374] <TB1> INFO: running: pretest
[10:32:07.374] <TB1> INFO: ----------------------------------------------------------------------
[10:32:07.374] <TB1> INFO: PixTestPretest::findTiming()
[10:32:07.374] <TB1> INFO: ----------------------------------------------------------------------
[10:32:07.374] <TB1> INFO: PixTestCmd::init()

[10:33:36.971] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:33:36.971] <TB1> INFO: (success/tries = 100/100), width = 5
[10:33:36.972] <TB1> INFO: enter test to run
[10:33:36.972] <TB1> INFO: test: pretest no parameter change
[10:33:36.972] <TB1> INFO: running: pretest
[10:33:36.973] <TB1> INFO: ----------------------------------------------------------------------
[10:33:36.973] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:33:36.973] <TB1> INFO: ----------------------------------------------------------------------
[10:33:37.067] <TB1> INFO: Expecting 231680 events.


[10:33:47.264] <TB1> INFO: 231680 events read in total (9641ms).
[10:33:47.268] <TB1> INFO: Test took 10289ms.
[10:33:47.484] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:33:47.560] <TB1> INFO: enter test to run
[10:33:47.560] <TB1> INFO: test: pretest no parameter change
[10:33:47.560] <TB1> INFO: running: pretest
[10:33:47.561] <TB1> INFO: ----------------------------------------------------------------------
[10:33:47.561] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:33:47.561] <TB1> INFO: ----------------------------------------------------------------------
[10:33:47.653] <TB1> INFO: Expecting 231680 events.
[10:33:57.885] <TB1> INFO: 231680 events read in total (9675ms).
[10:33:57.889] <TB1> INFO: Test took 10324ms.
[10:33:58.132] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:33:58.132] <TB1> INFO: CalDel: 147 137 127 130 145 143 135 122 143 145 131 137 148 157 131 155
[10:33:58.132] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:33:58.172] <TB1> INFO: enter test to run
[10:33:58.172] <TB1> INFO: test: pretest no parameter change
[10:33:58.172] <TB1> INFO: running: pretest
[10:33:58.172] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C0.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C1.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C2.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C3.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C4.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C5.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C6.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C7.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C8.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C9.dat
[10:33:58.173] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C10.dat
[10:33:58.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C11.dat
[10:33:58.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C12.dat
[10:33:58.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C13.dat
[10:33:58.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C14.dat
[10:33:58.174] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters_C15.dat
[10:33:58.174] <TB1> INFO: enter test to run
[10:33:58.174] <TB1> INFO: test: fulltest no parameter change
[10:33:58.174] <TB1> INFO: running: fulltest
[10:33:58.174] <TB1> INFO: ######################################################################
[10:33:58.174] <TB1> INFO: PixTestFullTest::doTest()
[10:33:58.174] <TB1> INFO: ######################################################################
[10:33:58.175] <TB1> INFO: ######################################################################
[10:33:58.175] <TB1> INFO: PixTestAlive::doTest()
[10:33:58.175] <TB1> INFO: ######################################################################
[10:33:58.176] <TB1> INFO: ----------------------------------------------------------------------
[10:33:58.176] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:33:58.176] <TB1> INFO: ----------------------------------------------------------------------
[10:33:58.463] <TB1> INFO: Expecting 41600 events.
[10:34:02.242] <TB1> INFO: 41600 events read in total (3223ms).
[10:34:02.243] <TB1> INFO: Test took 4065ms.
[10:34:02.466] <TB1> INFO: PixTestAlive::aliveTest() done
[10:34:02.466] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 6 1 0 0 0 0 0 0
[10:34:02.467] <TB1> INFO: ----------------------------------------------------------------------
[10:34:02.467] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:34:02.467] <TB1> INFO: ----------------------------------------------------------------------
[10:34:02.731] <TB1> INFO: Expecting 41600 events.
[10:34:05.755] <TB1> INFO: 41600 events read in total (2468ms).
[10:34:05.755] <TB1> INFO: Test took 3287ms.
[10:34:05.755] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:34:05.994] <TB1> INFO: PixTestAlive::maskTest() done
[10:34:05.994] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:34:05.995] <TB1> INFO: ----------------------------------------------------------------------
[10:34:05.995] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:34:05.995] <TB1> INFO: ----------------------------------------------------------------------
[10:34:06.279] <TB1> INFO: Expecting 41600 events.
[10:34:10.087] <TB1> INFO: 41600 events read in total (3252ms).
[10:34:10.087] <TB1> INFO: Test took 4090ms.
[10:34:10.317] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:34:10.317] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:34:10.318] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:34:10.318] <TB1> INFO: Decoding statistics:
[10:34:10.318] <TB1> INFO: General information:
[10:34:10.318] <TB1> INFO: 16bit words read: 0
[10:34:10.318] <TB1> INFO: valid events total: 0
[10:34:10.318] <TB1> INFO: empty events: 0
[10:34:10.318] <TB1> INFO: valid events with pixels: 0
[10:34:10.318] <TB1> INFO: valid pixel hits: 0
[10:34:10.318] <TB1> INFO: Event errors: 0
[10:34:10.318] <TB1> INFO: start marker: 0
[10:34:10.318] <TB1> INFO: stop marker: 0
[10:34:10.318] <TB1> INFO: overflow: 0
[10:34:10.318] <TB1> INFO: invalid 5bit words: 0
[10:34:10.318] <TB1> INFO: invalid XOR eye diagram: 0
[10:34:10.318] <TB1> INFO: frame (failed synchr.): 0
[10:34:10.318] <TB1> INFO: idle data (no TBM trl): 0
[10:34:10.318] <TB1> INFO: no data (only TBM hdr): 0
[10:34:10.318] <TB1> INFO: TBM errors: 0
[10:34:10.318] <TB1> INFO: flawed TBM headers: 0
[10:34:10.318] <TB1> INFO: flawed TBM trailers: 0
[10:34:10.318] <TB1> INFO: event ID mismatches: 0
[10:34:10.318] <TB1> INFO: ROC errors: 0
[10:34:10.318] <TB1> INFO: missing ROC header(s): 0
[10:34:10.318] <TB1> INFO: misplaced readback start: 0
[10:34:10.318] <TB1> INFO: Pixel decoding errors: 0
[10:34:10.318] <TB1> INFO: pixel data incomplete: 0
[10:34:10.318] <TB1> INFO: pixel address: 0
[10:34:10.318] <TB1> INFO: pulse height fill bit: 0
[10:34:10.318] <TB1> INFO: buffer corruption: 0
[10:34:10.324] <TB1> INFO: ######################################################################
[10:34:10.324] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:34:10.324] <TB1> INFO: ######################################################################
[10:34:10.327] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:34:10.490] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:34:10.490] <TB1> INFO: run 1 of 1
[10:34:10.775] <TB1> INFO: Expecting 3120000 events.
[10:34:51.988] <TB1> INFO: 828585 events read in total (40657ms).
[10:35:32.112] <TB1> INFO: 1650435 events read in total (80781ms).
[10:36:12.660] <TB1> INFO: 2479530 events read in total (121330ms).
[10:36:43.325] <TB1> INFO: 3120000 events read in total (151994ms).
[10:36:43.388] <TB1> INFO: Test took 152898ms.
[10:37:07.359] <TB1> INFO: PixTestBBMap::doTest() done, duration: 177 seconds
[10:37:07.359] <TB1> INFO: number of dead bumps (per ROC): 0 0 2 1 0 6 0 0 2 0 0 0 0 0 1 4
[10:37:07.359] <TB1> INFO: separation cut (per ROC): 73 88 74 81 65 74 86 71 88 68 75 78 102 91 71 79
[10:37:07.359] <TB1> INFO: Decoding statistics:
[10:37:07.359] <TB1> INFO: General information:
[10:37:07.359] <TB1> INFO: 16bit words read: 0
[10:37:07.359] <TB1> INFO: valid events total: 0
[10:37:07.359] <TB1> INFO: empty events: 0
[10:37:07.359] <TB1> INFO: valid events with pixels: 0
[10:37:07.359] <TB1> INFO: valid pixel hits: 0
[10:37:07.359] <TB1> INFO: Event errors: 0
[10:37:07.359] <TB1> INFO: start marker: 0
[10:37:07.359] <TB1> INFO: stop marker: 0
[10:37:07.359] <TB1> INFO: overflow: 0
[10:37:07.359] <TB1> INFO: invalid 5bit words: 0
[10:37:07.359] <TB1> INFO: invalid XOR eye diagram: 0
[10:37:07.359] <TB1> INFO: frame (failed synchr.): 0
[10:37:07.359] <TB1> INFO: idle data (no TBM trl): 0
[10:37:07.359] <TB1> INFO: no data (only TBM hdr): 0
[10:37:07.359] <TB1> INFO: TBM errors: 0
[10:37:07.359] <TB1> INFO: flawed TBM headers: 0
[10:37:07.359] <TB1> INFO: flawed TBM trailers: 0
[10:37:07.359] <TB1> INFO: event ID mismatches: 0
[10:37:07.359] <TB1> INFO: ROC errors: 0
[10:37:07.359] <TB1> INFO: missing ROC header(s): 0
[10:37:07.359] <TB1> INFO: misplaced readback start: 0
[10:37:07.359] <TB1> INFO: Pixel decoding errors: 0
[10:37:07.359] <TB1> INFO: pixel data incomplete: 0
[10:37:07.359] <TB1> INFO: pixel address: 0
[10:37:07.359] <TB1> INFO: pulse height fill bit: 0
[10:37:07.359] <TB1> INFO: buffer corruption: 0
[10:37:07.459] <TB1> INFO: ######################################################################
[10:37:07.459] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:37:07.459] <TB1> INFO: ######################################################################
[10:37:07.459] <TB1> INFO: ----------------------------------------------------------------------
[10:37:07.459] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:37:07.459] <TB1> INFO: ----------------------------------------------------------------------
[10:37:07.459] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:37:07.467] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:37:07.467] <TB1> INFO: run 1 of 1
[10:37:07.755] <TB1> INFO: Expecting 26208000 events.
[10:37:41.987] <TB1> INFO: 910000 events read in total (33676ms).
[10:38:15.007] <TB1> INFO: 1802700 events read in total (66696ms).
[10:38:47.916] <TB1> INFO: 2694050 events read in total (99605ms).
[10:39:20.909] <TB1> INFO: 3584850 events read in total (132598ms).
[10:39:54.534] <TB1> INFO: 4474600 events read in total (166223ms).
[10:40:27.843] <TB1> INFO: 5362950 events read in total (199532ms).
[10:41:00.676] <TB1> INFO: 6252250 events read in total (232365ms).
[10:41:34.213] <TB1> INFO: 7138450 events read in total (265902ms).
[10:42:07.137] <TB1> INFO: 8025200 events read in total (298826ms).
[10:42:40.110] <TB1> INFO: 8911250 events read in total (331799ms).
[10:43:13.124] <TB1> INFO: 9795500 events read in total (364813ms).
[10:43:46.609] <TB1> INFO: 10681150 events read in total (398298ms).
[10:44:19.685] <TB1> INFO: 11564100 events read in total (431374ms).
[10:44:52.887] <TB1> INFO: 12446600 events read in total (464576ms).
[10:45:26.248] <TB1> INFO: 13326500 events read in total (497937ms).
[10:45:59.721] <TB1> INFO: 14200050 events read in total (531410ms).
[10:46:33.027] <TB1> INFO: 15072650 events read in total (564716ms).
[10:47:06.183] <TB1> INFO: 15944050 events read in total (597872ms).
[10:47:39.184] <TB1> INFO: 16813700 events read in total (630873ms).
[10:48:12.206] <TB1> INFO: 17683000 events read in total (663895ms).
[10:48:46.246] <TB1> INFO: 18551950 events read in total (697935ms).
[10:49:18.796] <TB1> INFO: 19418550 events read in total (730485ms).
[10:49:53.066] <TB1> INFO: 20286150 events read in total (764755ms).
[10:50:26.950] <TB1> INFO: 21153250 events read in total (798639ms).
[10:51:00.445] <TB1> INFO: 22020150 events read in total (832134ms).
[10:51:33.551] <TB1> INFO: 22886850 events read in total (865240ms).
[10:52:06.501] <TB1> INFO: 23754100 events read in total (898190ms).
[10:52:39.364] <TB1> INFO: 24619400 events read in total (931053ms).
[10:53:12.304] <TB1> INFO: 25488250 events read in total (963993ms).
[10:53:39.198] <TB1> INFO: 26208000 events read in total (990887ms).
[10:53:39.227] <TB1> INFO: Test took 991760ms.
[10:53:39.468] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:40.950] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:42.548] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:44.191] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:45.682] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:47.194] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:48.608] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:50.042] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:52.005] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:53.373] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:54.799] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:56.358] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:57.911] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:53:59.250] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:54:00.726] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:54:02.221] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:54:03.649] <TB1> INFO: PixTestScurves::scurves() done
[10:54:03.649] <TB1> INFO: Vcal mean: 100.20 99.51 83.75 86.50 75.53 92.48 91.06 94.41 100.62 80.98 81.74 82.27 110.72 90.70 77.80 87.89
[10:54:03.649] <TB1> INFO: Vcal RMS: 5.92 5.44 4.47 5.08 3.98 5.11 5.11 5.65 6.72 4.47 4.32 5.33 5.36 4.82 3.80 4.80
[10:54:03.649] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1016 seconds
[10:54:03.649] <TB1> INFO: Decoding statistics:
[10:54:03.649] <TB1> INFO: General information:
[10:54:03.649] <TB1> INFO: 16bit words read: 0
[10:54:03.649] <TB1> INFO: valid events total: 0
[10:54:03.649] <TB1> INFO: empty events: 0
[10:54:03.649] <TB1> INFO: valid events with pixels: 0
[10:54:03.649] <TB1> INFO: valid pixel hits: 0
[10:54:03.649] <TB1> INFO: Event errors: 0
[10:54:03.649] <TB1> INFO: start marker: 0
[10:54:03.649] <TB1> INFO: stop marker: 0
[10:54:03.649] <TB1> INFO: overflow: 0
[10:54:03.649] <TB1> INFO: invalid 5bit words: 0
[10:54:03.649] <TB1> INFO: invalid XOR eye diagram: 0
[10:54:03.649] <TB1> INFO: frame (failed synchr.): 0
[10:54:03.649] <TB1> INFO: idle data (no TBM trl): 0
[10:54:03.649] <TB1> INFO: no data (only TBM hdr): 0
[10:54:03.649] <TB1> INFO: TBM errors: 0
[10:54:03.649] <TB1> INFO: flawed TBM headers: 0
[10:54:03.649] <TB1> INFO: flawed TBM trailers: 0
[10:54:03.649] <TB1> INFO: event ID mismatches: 0
[10:54:03.649] <TB1> INFO: ROC errors: 0
[10:54:03.649] <TB1> INFO: missing ROC header(s): 0
[10:54:03.649] <TB1> INFO: misplaced readback start: 0
[10:54:03.649] <TB1> INFO: Pixel decoding errors: 0
[10:54:03.649] <TB1> INFO: pixel data incomplete: 0
[10:54:03.649] <TB1> INFO: pixel address: 0
[10:54:03.649] <TB1> INFO: pulse height fill bit: 0
[10:54:03.649] <TB1> INFO: buffer corruption: 0
[10:54:03.719] <TB1> INFO: ######################################################################
[10:54:03.719] <TB1> INFO: PixTestTrim::doTest()
[10:54:03.719] <TB1> INFO: ######################################################################
[10:54:03.720] <TB1> INFO: ----------------------------------------------------------------------
[10:54:03.720] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:54:03.720] <TB1> INFO: ----------------------------------------------------------------------
[10:54:03.798] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:54:03.798] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:54:03.806] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:54:03.806] <TB1> INFO: run 1 of 1
[10:54:04.067] <TB1> INFO: Expecting 6281600 events.
[10:54:44.242] <TB1> INFO: 1068470 events read in total (39610ms).
[10:55:23.412] <TB1> INFO: 2128540 events read in total (78780ms).
[10:56:02.489] <TB1> INFO: 3181690 events read in total (117857ms).
[10:56:41.123] <TB1> INFO: 4236570 events read in total (156491ms).
[10:57:20.301] <TB1> INFO: 5292250 events read in total (195669ms).
[10:57:57.144] <TB1> INFO: 6281600 events read in total (232512ms).
[10:57:57.183] <TB1> INFO: Test took 233377ms.
[10:58:17.659] <TB1> INFO: ROC 0 VthrComp = 98
[10:58:17.659] <TB1> INFO: ROC 1 VthrComp = 103
[10:58:17.659] <TB1> INFO: ROC 2 VthrComp = 91
[10:58:17.659] <TB1> INFO: ROC 3 VthrComp = 92
[10:58:17.659] <TB1> INFO: ROC 4 VthrComp = 81
[10:58:17.660] <TB1> INFO: ROC 5 VthrComp = 96
[10:58:17.660] <TB1> INFO: ROC 6 VthrComp = 99
[10:58:17.660] <TB1> INFO: ROC 7 VthrComp = 100
[10:58:17.660] <TB1> INFO: ROC 8 VthrComp = 102
[10:58:17.660] <TB1> INFO: ROC 9 VthrComp = 85
[10:58:17.660] <TB1> INFO: ROC 10 VthrComp = 92
[10:58:17.660] <TB1> INFO: ROC 11 VthrComp = 87
[10:58:17.660] <TB1> INFO: ROC 12 VthrComp = 108
[10:58:17.660] <TB1> INFO: ROC 13 VthrComp = 99
[10:58:17.660] <TB1> INFO: ROC 14 VthrComp = 85
[10:58:17.660] <TB1> INFO: ROC 15 VthrComp = 96
[10:58:17.661] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:58:17.661] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:58:17.669] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:58:17.669] <TB1> INFO: run 1 of 1
[10:58:17.931] <TB1> INFO: Expecting 6281600 events.
[10:58:53.180] <TB1> INFO: 754470 events read in total (34693ms).
[10:59:28.053] <TB1> INFO: 1507310 events read in total (69566ms).
[11:00:03.223] <TB1> INFO: 2261070 events read in total (104736ms).
[11:00:38.194] <TB1> INFO: 3015460 events read in total (139707ms).
[11:01:13.180] <TB1> INFO: 3765020 events read in total (174693ms).
[11:01:47.923] <TB1> INFO: 4510310 events read in total (209436ms).
[11:02:22.892] <TB1> INFO: 5254080 events read in total (244405ms).
[11:02:57.528] <TB1> INFO: 5997180 events read in total (279041ms).
[11:03:11.081] <TB1> INFO: 6281600 events read in total (292594ms).
[11:03:11.136] <TB1> INFO: Test took 293467ms.
[11:03:36.986] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 61.5915 for pixel 9/17 mean/min/max = 46.6982/31.7701/61.6262
[11:03:36.987] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.2726 for pixel 9/18 mean/min/max = 45.5429/31.7131/59.3728
[11:03:36.987] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 55.893 for pixel 23/68 mean/min/max = 44.6442/32.941/56.3474
[11:03:36.987] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.689 for pixel 1/4 mean/min/max = 45.4334/33.1087/57.7582
[11:03:36.987] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 55.3966 for pixel 0/60 mean/min/max = 43.8774/32.2246/55.5303
[11:03:36.987] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 58.1155 for pixel 51/2 mean/min/max = 45.2671/32.3518/58.1824
[11:03:36.988] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.1859 for pixel 7/21 mean/min/max = 44.8365/32.4013/57.2716
[11:03:36.988] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.8749 for pixel 2/78 mean/min/max = 45.6682/32.3617/58.9747
[11:03:36.988] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 61.9125 for pixel 0/0 mean/min/max = 47.1282/32.1923/62.064
[11:03:36.989] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 57.002 for pixel 5/13 mean/min/max = 44.7174/32.0994/57.3354
[11:03:36.989] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.1317 for pixel 18/78 mean/min/max = 44.4096/32.653/56.1663
[11:03:36.989] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.1392 for pixel 15/53 mean/min/max = 45.1392/32.024/58.2545
[11:03:36.989] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 63.722 for pixel 9/8 mean/min/max = 48.6376/33.5444/63.7308
[11:03:36.990] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 55.955 for pixel 42/79 mean/min/max = 44.1057/31.9025/56.3089
[11:03:36.990] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 54.5595 for pixel 17/5 mean/min/max = 43.5355/32.4704/54.6006
[11:03:36.990] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 56.7124 for pixel 0/1 mean/min/max = 44.4136/32.0459/56.7813
[11:03:36.990] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:03:37.078] <TB1> INFO: Expecting 514560 events.
[11:03:50.107] <TB1> INFO: 514560 events read in total (12472ms).
[11:03:50.112] <TB1> INFO: Expecting 514560 events.
[11:04:02.935] <TB1> INFO: 514560 events read in total (12403ms).
[11:04:02.942] <TB1> INFO: Expecting 514560 events.
[11:04:15.733] <TB1> INFO: 514560 events read in total (12372ms).
[11:04:15.742] <TB1> INFO: Expecting 514560 events.
[11:04:28.701] <TB1> INFO: 514560 events read in total (12541ms).
[11:04:28.713] <TB1> INFO: Expecting 514560 events.
[11:04:41.528] <TB1> INFO: 514560 events read in total (12413ms).
[11:04:41.540] <TB1> INFO: Expecting 514560 events.
[11:04:54.310] <TB1> INFO: 514560 events read in total (12353ms).
[11:04:54.326] <TB1> INFO: Expecting 514560 events.
[11:05:07.248] <TB1> INFO: 514560 events read in total (12516ms).
[11:05:07.266] <TB1> INFO: Expecting 514560 events.
[11:05:20.227] <TB1> INFO: 514560 events read in total (12557ms).
[11:05:20.247] <TB1> INFO: Expecting 514560 events.
[11:05:33.068] <TB1> INFO: 514560 events read in total (12420ms).
[11:05:33.090] <TB1> INFO: Expecting 514560 events.
[11:05:45.888] <TB1> INFO: 514560 events read in total (12397ms).
[11:05:45.910] <TB1> INFO: Expecting 514560 events.
[11:05:58.831] <TB1> INFO: 514560 events read in total (12519ms).
[11:05:58.859] <TB1> INFO: Expecting 514560 events.
[11:06:11.713] <TB1> INFO: 514560 events read in total (12452ms).
[11:06:11.744] <TB1> INFO: Expecting 514560 events.
[11:06:24.685] <TB1> INFO: 514560 events read in total (12539ms).
[11:06:24.715] <TB1> INFO: Expecting 514560 events.
[11:06:37.704] <TB1> INFO: 514560 events read in total (12587ms).
[11:06:37.738] <TB1> INFO: Expecting 514560 events.
[11:06:50.802] <TB1> INFO: 514560 events read in total (12662ms).
[11:06:50.841] <TB1> INFO: Expecting 514560 events.
[11:07:03.897] <TB1> INFO: 514560 events read in total (12654ms).
[11:07:03.933] <TB1> INFO: Test took 206943ms.
[11:07:05.029] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:07:05.037] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:07:05.037] <TB1> INFO: run 1 of 1
[11:07:05.302] <TB1> INFO: Expecting 6281600 events.
[11:07:40.638] <TB1> INFO: 740530 events read in total (34780ms).
[11:08:15.174] <TB1> INFO: 1479520 events read in total (69316ms).
[11:08:49.699] <TB1> INFO: 2219930 events read in total (103841ms).
[11:09:24.215] <TB1> INFO: 2960880 events read in total (138357ms).
[11:09:58.542] <TB1> INFO: 3696580 events read in total (172684ms).
[11:10:33.160] <TB1> INFO: 4428590 events read in total (207302ms).
[11:11:07.720] <TB1> INFO: 5158740 events read in total (241862ms).
[11:11:42.307] <TB1> INFO: 5888200 events read in total (276449ms).
[11:12:00.862] <TB1> INFO: 6281600 events read in total (295004ms).
[11:12:00.910] <TB1> INFO: Test took 295873ms.
[11:12:25.411] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.027763 .. 255.000000
[11:12:25.502] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[11:12:25.510] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:12:25.510] <TB1> INFO: run 1 of 1
[11:12:25.769] <TB1> INFO: Expecting 10649600 events.
[11:13:00.181] <TB1> INFO: 712360 events read in total (33856ms).
[11:13:34.295] <TB1> INFO: 1424460 events read in total (67970ms).
[11:14:08.153] <TB1> INFO: 2136720 events read in total (101828ms).
[11:14:42.224] <TB1> INFO: 2849260 events read in total (135899ms).
[11:15:16.875] <TB1> INFO: 3561950 events read in total (170550ms).
[11:15:51.398] <TB1> INFO: 4274310 events read in total (205073ms).
[11:16:25.839] <TB1> INFO: 4987180 events read in total (239514ms).
[11:17:00.139] <TB1> INFO: 5699450 events read in total (273814ms).
[11:17:34.473] <TB1> INFO: 6411690 events read in total (308148ms).
[11:18:09.057] <TB1> INFO: 7122990 events read in total (342732ms).
[11:18:44.334] <TB1> INFO: 7834290 events read in total (378009ms).
[11:19:18.773] <TB1> INFO: 8545070 events read in total (412448ms).
[11:19:53.254] <TB1> INFO: 9255970 events read in total (446929ms).
[11:20:27.407] <TB1> INFO: 9966700 events read in total (481082ms).
[11:21:00.040] <TB1> INFO: 10649600 events read in total (513715ms).
[11:21:00.142] <TB1> INFO: Test took 514632ms.
[11:21:29.568] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.226364 .. 67.735525
[11:21:29.645] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 77 (-1/-1) hits flags = 528 (plus default)
[11:21:29.653] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:21:29.653] <TB1> INFO: run 1 of 1
[11:21:29.916] <TB1> INFO: Expecting 3078400 events.
[11:22:06.967] <TB1> INFO: 838240 events read in total (36495ms).
[11:22:43.394] <TB1> INFO: 1676270 events read in total (72922ms).
[11:23:20.061] <TB1> INFO: 2513340 events read in total (109589ms).
[11:23:44.459] <TB1> INFO: 3078400 events read in total (133987ms).
[11:23:44.482] <TB1> INFO: Test took 134829ms.
[11:24:00.909] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 19.364366 .. 56.819065
[11:24:00.988] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 66 (-1/-1) hits flags = 528 (plus default)
[11:24:00.996] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:24:00.996] <TB1> INFO: run 1 of 1
[11:24:01.258] <TB1> INFO: Expecting 2412800 events.
[11:24:38.039] <TB1> INFO: 855480 events read in total (36224ms).
[11:25:14.527] <TB1> INFO: 1710820 events read in total (72712ms).
[11:25:44.557] <TB1> INFO: 2412800 events read in total (102742ms).
[11:25:44.572] <TB1> INFO: Test took 103576ms.
[11:25:59.243] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 2.860131 .. 56.241327
[11:25:59.318] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 66 (-1/-1) hits flags = 528 (plus default)
[11:25:59.325] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:25:59.325] <TB1> INFO: run 1 of 1
[11:25:59.584] <TB1> INFO: Expecting 2704000 events.
[11:26:37.665] <TB1> INFO: 889490 events read in total (37524ms).
[11:27:14.398] <TB1> INFO: 1778800 events read in total (74258ms).
[11:27:51.434] <TB1> INFO: 2668090 events read in total (111293ms).
[11:27:53.283] <TB1> INFO: 2704000 events read in total (113142ms).
[11:27:53.297] <TB1> INFO: Test took 113971ms.
[11:28:08.883] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:28:08.883] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:28:08.891] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:28:08.891] <TB1> INFO: run 1 of 1
[11:28:09.154] <TB1> INFO: Expecting 1705600 events.
[11:28:46.875] <TB1> INFO: 878920 events read in total (37164ms).
[11:29:22.059] <TB1> INFO: 1705600 events read in total (72348ms).
[11:29:22.070] <TB1> INFO: Test took 73179ms.
[11:29:35.777] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:29:35.777] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:29:35.777] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:29:35.777] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:29:35.777] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:29:35.777] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:29:35.778] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:29:35.779] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:29:35.779] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C0.dat
[11:29:35.785] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C1.dat
[11:29:35.791] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C2.dat
[11:29:35.797] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C3.dat
[11:29:35.803] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C4.dat
[11:29:35.810] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C5.dat
[11:29:35.816] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C6.dat
[11:29:35.822] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C7.dat
[11:29:35.828] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C8.dat
[11:29:35.834] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C9.dat
[11:29:35.841] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C10.dat
[11:29:35.847] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C11.dat
[11:29:35.853] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C12.dat
[11:29:35.859] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C13.dat
[11:29:35.865] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C14.dat
[11:29:35.871] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//trimParameters35_C15.dat
[11:29:35.877] <TB1> INFO: PixTestTrim::trimTest() done
[11:29:35.877] <TB1> INFO: vtrim: 122 107 96 101 96 95 114 114 106 92 109 99 131 96 104 100
[11:29:35.877] <TB1> INFO: vthrcomp: 98 103 91 92 81 96 99 100 102 85 92 87 108 99 85 96
[11:29:35.877] <TB1> INFO: vcal mean: 34.97 34.98 34.98 35.03 34.94 34.94 34.99 34.99 34.94 34.96 34.98 34.98 35.02 34.98 34.96 34.96
[11:29:35.877] <TB1> INFO: vcal RMS: 1.19 0.88 0.82 0.83 0.82 0.88 0.85 0.87 1.50 1.02 0.77 0.83 0.99 0.81 0.80 0.83
[11:29:35.877] <TB1> INFO: bits mean: 9.33 9.43 9.68 9.35 10.16 9.06 9.81 9.50 8.68 9.65 9.62 9.52 9.01 9.38 10.01 9.80
[11:29:35.877] <TB1> INFO: bits RMS: 2.70 2.74 2.56 2.63 2.47 2.86 2.56 2.65 2.79 2.67 2.63 2.72 2.45 2.89 2.49 2.65
[11:29:35.883] <TB1> INFO: ----------------------------------------------------------------------
[11:29:35.883] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:29:35.883] <TB1> INFO: ----------------------------------------------------------------------
[11:29:35.886] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:29:35.894] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:29:35.894] <TB1> INFO: run 1 of 1
[11:29:36.153] <TB1> INFO: Expecting 4160000 events.
[11:30:18.494] <TB1> INFO: 931825 events read in total (41785ms).
[11:30:59.678] <TB1> INFO: 1856595 events read in total (82969ms).
[11:31:41.741] <TB1> INFO: 2771945 events read in total (125033ms).
[11:32:23.553] <TB1> INFO: 3682945 events read in total (166844ms).
[11:32:44.920] <TB1> INFO: 4160000 events read in total (188211ms).
[11:32:44.960] <TB1> INFO: Test took 189066ms.
[11:33:14.046] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[11:33:14.054] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:33:14.054] <TB1> INFO: run 1 of 1
[11:33:14.313] <TB1> INFO: Expecting 3619200 events.
[11:33:57.017] <TB1> INFO: 952600 events read in total (42148ms).
[11:34:40.215] <TB1> INFO: 1895570 events read in total (85346ms).
[11:35:22.539] <TB1> INFO: 2825725 events read in total (127670ms).
[11:35:57.885] <TB1> INFO: 3619200 events read in total (163016ms).
[11:35:57.929] <TB1> INFO: Test took 163875ms.
[11:36:24.485] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 163 (-1/-1) hits flags = 528 (plus default)
[11:36:24.492] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:36:24.492] <TB1> INFO: run 1 of 1
[11:36:24.768] <TB1> INFO: Expecting 3411200 events.
[11:37:07.592] <TB1> INFO: 977805 events read in total (42268ms).
[11:37:49.645] <TB1> INFO: 1942630 events read in total (84321ms).
[11:38:31.305] <TB1> INFO: 2895120 events read in total (125981ms).
[11:38:54.083] <TB1> INFO: 3411200 events read in total (148759ms).
[11:38:54.122] <TB1> INFO: Test took 149629ms.
[11:39:19.330] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[11:39:19.338] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:39:19.338] <TB1> INFO: run 1 of 1
[11:39:19.598] <TB1> INFO: Expecting 3369600 events.
[11:40:02.437] <TB1> INFO: 983045 events read in total (42283ms).
[11:40:45.115] <TB1> INFO: 1952435 events read in total (84961ms).
[11:41:26.715] <TB1> INFO: 2908635 events read in total (126561ms).
[11:41:47.138] <TB1> INFO: 3369600 events read in total (146984ms).
[11:41:47.172] <TB1> INFO: Test took 147834ms.
[11:42:12.836] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:42:12.844] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:42:12.845] <TB1> INFO: run 1 of 1
[11:42:13.108] <TB1> INFO: Expecting 3348800 events.
[11:42:56.555] <TB1> INFO: 985120 events read in total (42891ms).
[11:43:38.833] <TB1> INFO: 1956415 events read in total (85169ms).
[11:44:20.927] <TB1> INFO: 2914735 events read in total (127264ms).
[11:44:40.047] <TB1> INFO: 3348800 events read in total (146383ms).
[11:44:40.083] <TB1> INFO: Test took 147238ms.
[11:45:05.760] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:45:05.762] <TB1> INFO: PixTestTrim::doTest() done, duration: 3062 seconds
[11:45:05.762] <TB1> INFO: Decoding statistics:
[11:45:05.762] <TB1> INFO: General information:
[11:45:05.762] <TB1> INFO: 16bit words read: 0
[11:45:05.762] <TB1> INFO: valid events total: 0
[11:45:05.762] <TB1> INFO: empty events: 0
[11:45:05.762] <TB1> INFO: valid events with pixels: 0
[11:45:05.762] <TB1> INFO: valid pixel hits: 0
[11:45:05.762] <TB1> INFO: Event errors: 0
[11:45:05.762] <TB1> INFO: start marker: 0
[11:45:05.762] <TB1> INFO: stop marker: 0
[11:45:05.762] <TB1> INFO: overflow: 0
[11:45:05.762] <TB1> INFO: invalid 5bit words: 0
[11:45:05.762] <TB1> INFO: invalid XOR eye diagram: 0
[11:45:05.762] <TB1> INFO: frame (failed synchr.): 0
[11:45:05.762] <TB1> INFO: idle data (no TBM trl): 0
[11:45:05.762] <TB1> INFO: no data (only TBM hdr): 0
[11:45:05.762] <TB1> INFO: TBM errors: 0
[11:45:05.762] <TB1> INFO: flawed TBM headers: 0
[11:45:05.762] <TB1> INFO: flawed TBM trailers: 0
[11:45:05.762] <TB1> INFO: event ID mismatches: 0
[11:45:05.762] <TB1> INFO: ROC errors: 0
[11:45:05.762] <TB1> INFO: missing ROC header(s): 0
[11:45:05.762] <TB1> INFO: misplaced readback start: 0
[11:45:05.762] <TB1> INFO: Pixel decoding errors: 0
[11:45:05.762] <TB1> INFO: pixel data incomplete: 0
[11:45:05.762] <TB1> INFO: pixel address: 0
[11:45:05.762] <TB1> INFO: pulse height fill bit: 0
[11:45:05.762] <TB1> INFO: buffer corruption: 0
[11:45:06.461] <TB1> INFO: ######################################################################
[11:45:06.461] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:45:06.461] <TB1> INFO: ######################################################################
[11:45:06.725] <TB1> INFO: Expecting 41600 events.
[11:45:10.464] <TB1> INFO: 41600 events read in total (3183ms).
[11:45:10.465] <TB1> INFO: Test took 4003ms.
[11:45:10.928] <TB1> INFO: Expecting 41600 events.
[11:45:14.818] <TB1> INFO: 41600 events read in total (3334ms).
[11:45:14.818] <TB1> INFO: Test took 4150ms.
[11:45:15.104] <TB1> INFO: Expecting 41600 events.
[11:45:18.926] <TB1> INFO: 41600 events read in total (3266ms).
[11:45:18.927] <TB1> INFO: Test took 4087ms.
[11:45:19.181] <TB1> INFO: Expecting 2560 events.
[11:45:20.035] <TB1> INFO: 2560 events read in total (298ms).
[11:45:20.035] <TB1> INFO: Test took 1096ms.
[11:45:20.340] <TB1> INFO: Expecting 2560 events.
[11:45:21.194] <TB1> INFO: 2560 events read in total (298ms).
[11:45:21.194] <TB1> INFO: Test took 1158ms.
[11:45:21.499] <TB1> INFO: Expecting 2560 events.
[11:45:22.356] <TB1> INFO: 2560 events read in total (301ms).
[11:45:22.356] <TB1> INFO: Test took 1162ms.
[11:45:22.661] <TB1> INFO: Expecting 2560 events.
[11:45:23.516] <TB1> INFO: 2560 events read in total (299ms).
[11:45:23.516] <TB1> INFO: Test took 1160ms.
[11:45:23.821] <TB1> INFO: Expecting 2560 events.
[11:45:24.679] <TB1> INFO: 2560 events read in total (302ms).
[11:45:24.679] <TB1> INFO: Test took 1162ms.
[11:45:24.984] <TB1> INFO: Expecting 2560 events.
[11:45:25.838] <TB1> INFO: 2560 events read in total (298ms).
[11:45:25.838] <TB1> INFO: Test took 1158ms.
[11:45:26.143] <TB1> INFO: Expecting 2560 events.
[11:45:27.002] <TB1> INFO: 2560 events read in total (302ms).
[11:45:27.003] <TB1> INFO: Test took 1165ms.
[11:45:27.307] <TB1> INFO: Expecting 2560 events.
[11:45:28.162] <TB1> INFO: 2560 events read in total (299ms).
[11:45:28.162] <TB1> INFO: Test took 1158ms.
[11:45:28.466] <TB1> INFO: Expecting 2560 events.
[11:45:29.320] <TB1> INFO: 2560 events read in total (298ms).
[11:45:29.320] <TB1> INFO: Test took 1158ms.
[11:45:29.625] <TB1> INFO: Expecting 2560 events.
[11:45:30.483] <TB1> INFO: 2560 events read in total (302ms).
[11:45:30.483] <TB1> INFO: Test took 1163ms.
[11:45:30.788] <TB1> INFO: Expecting 2560 events.
[11:45:31.646] <TB1> INFO: 2560 events read in total (302ms).
[11:45:31.646] <TB1> INFO: Test took 1162ms.
[11:45:31.952] <TB1> INFO: Expecting 2560 events.
[11:45:32.810] <TB1> INFO: 2560 events read in total (302ms).
[11:45:32.810] <TB1> INFO: Test took 1163ms.
[11:45:33.116] <TB1> INFO: Expecting 2560 events.
[11:45:33.975] <TB1> INFO: 2560 events read in total (303ms).
[11:45:33.975] <TB1> INFO: Test took 1164ms.
[11:45:34.284] <TB1> INFO: Expecting 2560 events.
[11:45:35.139] <TB1> INFO: 2560 events read in total (298ms).
[11:45:35.139] <TB1> INFO: Test took 1163ms.
[11:45:35.445] <TB1> INFO: Expecting 2560 events.
[11:45:36.298] <TB1> INFO: 2560 events read in total (297ms).
[11:45:36.299] <TB1> INFO: Test took 1159ms.
[11:45:36.603] <TB1> INFO: Expecting 2560 events.
[11:45:37.457] <TB1> INFO: 2560 events read in total (297ms).
[11:45:37.457] <TB1> INFO: Test took 1158ms.
[11:45:37.461] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:45:37.763] <TB1> INFO: Expecting 655360 events.
[11:45:54.700] <TB1> INFO: 655360 events read in total (16381ms).
[11:45:54.710] <TB1> INFO: Expecting 655360 events.
[11:46:11.568] <TB1> INFO: 655360 events read in total (16457ms).
[11:46:11.583] <TB1> INFO: Expecting 655360 events.
[11:46:28.335] <TB1> INFO: 655360 events read in total (16350ms).
[11:46:28.351] <TB1> INFO: Expecting 655360 events.
[11:46:45.162] <TB1> INFO: 655360 events read in total (16409ms).
[11:46:45.181] <TB1> INFO: Expecting 655360 events.
[11:47:01.896] <TB1> INFO: 655360 events read in total (16313ms).
[11:47:01.919] <TB1> INFO: Expecting 655360 events.
[11:47:18.486] <TB1> INFO: 655360 events read in total (16165ms).
[11:47:18.524] <TB1> INFO: Expecting 655360 events.
[11:47:35.062] <TB1> INFO: 655360 events read in total (16136ms).
[11:47:35.091] <TB1> INFO: Expecting 655360 events.
[11:47:51.642] <TB1> INFO: 655360 events read in total (16149ms).
[11:47:51.678] <TB1> INFO: Expecting 655360 events.
[11:48:08.359] <TB1> INFO: 655360 events read in total (16279ms).
[11:48:08.411] <TB1> INFO: Expecting 655360 events.
[11:48:24.952] <TB1> INFO: 655360 events read in total (16139ms).
[11:48:24.993] <TB1> INFO: Expecting 655360 events.
[11:48:41.654] <TB1> INFO: 655360 events read in total (16259ms).
[11:48:41.702] <TB1> INFO: Expecting 655360 events.
[11:48:58.290] <TB1> INFO: 655360 events read in total (16186ms).
[11:48:58.339] <TB1> INFO: Expecting 655360 events.
[11:49:14.962] <TB1> INFO: 655360 events read in total (16221ms).
[11:49:15.013] <TB1> INFO: Expecting 655360 events.
[11:49:31.918] <TB1> INFO: 655360 events read in total (16504ms).
[11:49:31.979] <TB1> INFO: Expecting 655360 events.
[11:49:48.856] <TB1> INFO: 655360 events read in total (16476ms).
[11:49:48.912] <TB1> INFO: Expecting 655360 events.
[11:50:05.812] <TB1> INFO: 655360 events read in total (16498ms).
[11:50:05.874] <TB1> INFO: Test took 268413ms.
[11:50:05.960] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:50:06.189] <TB1> INFO: Expecting 655360 events.
[11:50:23.233] <TB1> INFO: 655360 events read in total (16487ms).
[11:50:23.242] <TB1> INFO: Expecting 655360 events.
[11:50:39.792] <TB1> INFO: 655360 events read in total (16149ms).
[11:50:39.803] <TB1> INFO: Expecting 655360 events.
[11:50:56.434] <TB1> INFO: 655360 events read in total (16229ms).
[11:50:56.449] <TB1> INFO: Expecting 655360 events.
[11:51:13.002] <TB1> INFO: 655360 events read in total (16151ms).
[11:51:13.022] <TB1> INFO: Expecting 655360 events.
[11:51:29.651] <TB1> INFO: 655360 events read in total (16227ms).
[11:51:29.672] <TB1> INFO: Expecting 655360 events.
[11:51:46.261] <TB1> INFO: 655360 events read in total (16187ms).
[11:51:46.286] <TB1> INFO: Expecting 655360 events.
[11:52:02.905] <TB1> INFO: 655360 events read in total (16217ms).
[11:52:02.934] <TB1> INFO: Expecting 655360 events.
[11:52:19.531] <TB1> INFO: 655360 events read in total (16196ms).
[11:52:19.563] <TB1> INFO: Expecting 655360 events.
[11:52:36.279] <TB1> INFO: 655360 events read in total (16314ms).
[11:52:36.314] <TB1> INFO: Expecting 655360 events.
[11:52:52.940] <TB1> INFO: 655360 events read in total (16225ms).
[11:52:52.981] <TB1> INFO: Expecting 655360 events.
[11:53:09.700] <TB1> INFO: 655360 events read in total (16317ms).
[11:53:09.744] <TB1> INFO: Expecting 655360 events.
[11:53:26.475] <TB1> INFO: 655360 events read in total (16330ms).
[11:53:26.521] <TB1> INFO: Expecting 655360 events.
[11:53:43.240] <TB1> INFO: 655360 events read in total (16318ms).
[11:53:43.292] <TB1> INFO: Expecting 655360 events.
[11:54:00.044] <TB1> INFO: 655360 events read in total (16350ms).
[11:54:00.095] <TB1> INFO: Expecting 655360 events.
[11:54:16.852] <TB1> INFO: 655360 events read in total (16355ms).
[11:54:16.916] <TB1> INFO: Expecting 655360 events.
[11:54:33.600] <TB1> INFO: 655360 events read in total (16282ms).
[11:54:33.675] <TB1> INFO: Test took 267715ms.
[11:54:33.869] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.879] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.890] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.901] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.907] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:54:33.914] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.920] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.927] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.933] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.940] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.947] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:33.954] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:54:33.960] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:54:33.966] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:54:33.973] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[11:54:33.980] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[11:54:33.986] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[11:54:33.993] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[11:54:33.999] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[11:54:34.006] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:34.012] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:34.019] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:54:34.025] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:54:34.032] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:54:34.038] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[11:54:34.045] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:34.051] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:34.058] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:34.064] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:54:34.071] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:54:34.078] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:54:34.084] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[11:54:34.091] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:34.126] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:54:34.127] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:54:34.128] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:54:34.128] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:54:34.128] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:54:34.128] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:54:34.128] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:54:34.397] <TB1> INFO: Expecting 41600 events.
[11:54:37.873] <TB1> INFO: 41600 events read in total (2920ms).
[11:54:37.874] <TB1> INFO: Test took 3743ms.
[11:54:38.330] <TB1> INFO: Expecting 41600 events.
[11:54:41.779] <TB1> INFO: 41600 events read in total (2892ms).
[11:54:41.779] <TB1> INFO: Test took 3724ms.
[11:54:42.255] <TB1> INFO: Expecting 41600 events.
[11:54:45.750] <TB1> INFO: 41600 events read in total (2939ms).
[11:54:45.750] <TB1> INFO: Test took 3788ms.
[11:54:45.933] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:46.022] <TB1> INFO: Expecting 2560 events.
[11:54:46.876] <TB1> INFO: 2560 events read in total (298ms).
[11:54:46.876] <TB1> INFO: Test took 943ms.
[11:54:46.878] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:47.182] <TB1> INFO: Expecting 2560 events.
[11:54:48.039] <TB1> INFO: 2560 events read in total (301ms).
[11:54:48.040] <TB1> INFO: Test took 1162ms.
[11:54:48.041] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:48.345] <TB1> INFO: Expecting 2560 events.
[11:54:49.202] <TB1> INFO: 2560 events read in total (301ms).
[11:54:49.203] <TB1> INFO: Test took 1162ms.
[11:54:49.205] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:49.509] <TB1> INFO: Expecting 2560 events.
[11:54:50.368] <TB1> INFO: 2560 events read in total (303ms).
[11:54:50.368] <TB1> INFO: Test took 1163ms.
[11:54:50.370] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:50.673] <TB1> INFO: Expecting 2560 events.
[11:54:51.527] <TB1> INFO: 2560 events read in total (297ms).
[11:54:51.528] <TB1> INFO: Test took 1158ms.
[11:54:51.531] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:51.832] <TB1> INFO: Expecting 2560 events.
[11:54:52.686] <TB1> INFO: 2560 events read in total (297ms).
[11:54:52.687] <TB1> INFO: Test took 1156ms.
[11:54:52.689] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:52.992] <TB1> INFO: Expecting 2560 events.
[11:54:53.850] <TB1> INFO: 2560 events read in total (302ms).
[11:54:53.850] <TB1> INFO: Test took 1161ms.
[11:54:53.853] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:54.158] <TB1> INFO: Expecting 2560 events.
[11:54:55.013] <TB1> INFO: 2560 events read in total (299ms).
[11:54:55.014] <TB1> INFO: Test took 1161ms.
[11:54:55.015] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:55.319] <TB1> INFO: Expecting 2560 events.
[11:54:56.173] <TB1> INFO: 2560 events read in total (298ms).
[11:54:56.174] <TB1> INFO: Test took 1159ms.
[11:54:56.177] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:56.478] <TB1> INFO: Expecting 2560 events.
[11:54:57.336] <TB1> INFO: 2560 events read in total (301ms).
[11:54:57.337] <TB1> INFO: Test took 1161ms.
[11:54:57.340] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:57.643] <TB1> INFO: Expecting 2560 events.
[11:54:58.498] <TB1> INFO: 2560 events read in total (299ms).
[11:54:58.498] <TB1> INFO: Test took 1159ms.
[11:54:58.501] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:58.804] <TB1> INFO: Expecting 2560 events.
[11:54:59.657] <TB1> INFO: 2560 events read in total (297ms).
[11:54:59.658] <TB1> INFO: Test took 1158ms.
[11:54:59.660] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:59.963] <TB1> INFO: Expecting 2560 events.
[11:55:00.822] <TB1> INFO: 2560 events read in total (303ms).
[11:55:00.822] <TB1> INFO: Test took 1162ms.
[11:55:00.825] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:01.128] <TB1> INFO: Expecting 2560 events.
[11:55:01.986] <TB1> INFO: 2560 events read in total (302ms).
[11:55:01.986] <TB1> INFO: Test took 1162ms.
[11:55:01.988] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:02.293] <TB1> INFO: Expecting 2560 events.
[11:55:03.152] <TB1> INFO: 2560 events read in total (303ms).
[11:55:03.152] <TB1> INFO: Test took 1164ms.
[11:55:03.155] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:03.457] <TB1> INFO: Expecting 2560 events.
[11:55:04.310] <TB1> INFO: 2560 events read in total (296ms).
[11:55:04.310] <TB1> INFO: Test took 1155ms.
[11:55:04.312] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:04.615] <TB1> INFO: Expecting 2560 events.
[11:55:05.469] <TB1> INFO: 2560 events read in total (297ms).
[11:55:05.469] <TB1> INFO: Test took 1157ms.
[11:55:05.471] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:05.775] <TB1> INFO: Expecting 2560 events.
[11:55:06.632] <TB1> INFO: 2560 events read in total (301ms).
[11:55:06.632] <TB1> INFO: Test took 1161ms.
[11:55:06.634] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:06.938] <TB1> INFO: Expecting 2560 events.
[11:55:07.796] <TB1> INFO: 2560 events read in total (301ms).
[11:55:07.796] <TB1> INFO: Test took 1162ms.
[11:55:07.798] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:08.102] <TB1> INFO: Expecting 2560 events.
[11:55:08.959] <TB1> INFO: 2560 events read in total (301ms).
[11:55:08.959] <TB1> INFO: Test took 1161ms.
[11:55:08.961] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:09.265] <TB1> INFO: Expecting 2560 events.
[11:55:10.119] <TB1> INFO: 2560 events read in total (297ms).
[11:55:10.119] <TB1> INFO: Test took 1158ms.
[11:55:10.122] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:10.424] <TB1> INFO: Expecting 2560 events.
[11:55:11.283] <TB1> INFO: 2560 events read in total (303ms).
[11:55:11.284] <TB1> INFO: Test took 1162ms.
[11:55:11.286] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:11.589] <TB1> INFO: Expecting 2560 events.
[11:55:12.446] <TB1> INFO: 2560 events read in total (301ms).
[11:55:12.446] <TB1> INFO: Test took 1160ms.
[11:55:12.449] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:12.752] <TB1> INFO: Expecting 2560 events.
[11:55:13.606] <TB1> INFO: 2560 events read in total (297ms).
[11:55:13.607] <TB1> INFO: Test took 1158ms.
[11:55:13.609] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:13.912] <TB1> INFO: Expecting 2560 events.
[11:55:14.765] <TB1> INFO: 2560 events read in total (297ms).
[11:55:14.766] <TB1> INFO: Test took 1157ms.
[11:55:14.767] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:15.071] <TB1> INFO: Expecting 2560 events.
[11:55:15.927] <TB1> INFO: 2560 events read in total (300ms).
[11:55:15.928] <TB1> INFO: Test took 1161ms.
[11:55:15.930] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:16.234] <TB1> INFO: Expecting 2560 events.
[11:55:17.092] <TB1> INFO: 2560 events read in total (302ms).
[11:55:17.092] <TB1> INFO: Test took 1162ms.
[11:55:17.095] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:17.398] <TB1> INFO: Expecting 2560 events.
[11:55:18.257] <TB1> INFO: 2560 events read in total (300ms).
[11:55:18.258] <TB1> INFO: Test took 1163ms.
[11:55:18.261] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:18.563] <TB1> INFO: Expecting 2560 events.
[11:55:19.421] <TB1> INFO: 2560 events read in total (302ms).
[11:55:19.421] <TB1> INFO: Test took 1161ms.
[11:55:19.424] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:19.727] <TB1> INFO: Expecting 2560 events.
[11:55:20.584] <TB1> INFO: 2560 events read in total (301ms).
[11:55:20.585] <TB1> INFO: Test took 1161ms.
[11:55:20.587] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:20.890] <TB1> INFO: Expecting 2560 events.
[11:55:21.744] <TB1> INFO: 2560 events read in total (298ms).
[11:55:21.744] <TB1> INFO: Test took 1157ms.
[11:55:21.746] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:22.050] <TB1> INFO: Expecting 2560 events.
[11:55:22.904] <TB1> INFO: 2560 events read in total (298ms).
[11:55:22.904] <TB1> INFO: Test took 1158ms.
[11:55:23.368] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 616 seconds
[11:55:23.368] <TB1> INFO: PH scale (per ROC): 76 78 90 85 89 80 88 83 85 83 89 84 76 83 95 90
[11:55:23.368] <TB1> INFO: PH offset (per ROC): 173 162 149 176 164 160 151 159 143 144 159 144 184 149 144 149
[11:55:23.373] <TB1> INFO: Decoding statistics:
[11:55:23.373] <TB1> INFO: General information:
[11:55:23.373] <TB1> INFO: 16bit words read: 91924
[11:55:23.373] <TB1> INFO: valid events total: 10240
[11:55:23.373] <TB1> INFO: empty events: 7739
[11:55:23.373] <TB1> INFO: valid events with pixels: 2501
[11:55:23.373] <TB1> INFO: valid pixel hits: 2501
[11:55:23.373] <TB1> INFO: Event errors: 0
[11:55:23.373] <TB1> INFO: start marker: 0
[11:55:23.373] <TB1> INFO: stop marker: 0
[11:55:23.373] <TB1> INFO: overflow: 0
[11:55:23.373] <TB1> INFO: invalid 5bit words: 0
[11:55:23.373] <TB1> INFO: invalid XOR eye diagram: 0
[11:55:23.373] <TB1> INFO: frame (failed synchr.): 0
[11:55:23.373] <TB1> INFO: idle data (no TBM trl): 0
[11:55:23.373] <TB1> INFO: no data (only TBM hdr): 0
[11:55:23.373] <TB1> INFO: TBM errors: 0
[11:55:23.373] <TB1> INFO: flawed TBM headers: 0
[11:55:23.373] <TB1> INFO: flawed TBM trailers: 0
[11:55:23.373] <TB1> INFO: event ID mismatches: 0
[11:55:23.373] <TB1> INFO: ROC errors: 0
[11:55:23.373] <TB1> INFO: missing ROC header(s): 0
[11:55:23.373] <TB1> INFO: misplaced readback start: 0
[11:55:23.373] <TB1> INFO: Pixel decoding errors: 0
[11:55:23.373] <TB1> INFO: pixel data incomplete: 0
[11:55:23.373] <TB1> INFO: pixel address: 0
[11:55:23.373] <TB1> INFO: pulse height fill bit: 0
[11:55:23.373] <TB1> INFO: buffer corruption: 0
[11:55:23.551] <TB1> INFO: ######################################################################
[11:55:23.551] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:55:23.551] <TB1> INFO: ######################################################################
[11:55:23.561] <TB1> INFO: scanning low vcal = 10
[11:55:23.823] <TB1> INFO: Expecting 41600 events.
[11:55:27.356] <TB1> INFO: 41600 events read in total (2976ms).
[11:55:27.356] <TB1> INFO: Test took 3796ms.
[11:55:27.359] <TB1> INFO: scanning low vcal = 20
[11:55:27.662] <TB1> INFO: Expecting 41600 events.
[11:55:31.222] <TB1> INFO: 41600 events read in total (3003ms).
[11:55:31.223] <TB1> INFO: Test took 3864ms.
[11:55:31.225] <TB1> INFO: scanning low vcal = 30
[11:55:31.527] <TB1> INFO: Expecting 41600 events.
[11:55:35.097] <TB1> INFO: 41600 events read in total (3013ms).
[11:55:35.098] <TB1> INFO: Test took 3873ms.
[11:55:35.100] <TB1> INFO: scanning low vcal = 40
[11:55:35.393] <TB1> INFO: Expecting 41600 events.
[11:55:39.564] <TB1> INFO: 41600 events read in total (3615ms).
[11:55:39.565] <TB1> INFO: Test took 4465ms.
[11:55:39.567] <TB1> INFO: scanning low vcal = 50
[11:55:39.828] <TB1> INFO: Expecting 41600 events.
[11:55:44.078] <TB1> INFO: 41600 events read in total (3694ms).
[11:55:44.078] <TB1> INFO: Test took 4511ms.
[11:55:44.081] <TB1> INFO: scanning low vcal = 60
[11:55:44.352] <TB1> INFO: Expecting 41600 events.
[11:55:48.609] <TB1> INFO: 41600 events read in total (3701ms).
[11:55:48.609] <TB1> INFO: Test took 4528ms.
[11:55:48.612] <TB1> INFO: scanning low vcal = 70
[11:55:48.898] <TB1> INFO: Expecting 41600 events.
[11:55:53.127] <TB1> INFO: 41600 events read in total (3673ms).
[11:55:53.127] <TB1> INFO: Test took 4515ms.
[11:55:53.130] <TB1> INFO: scanning low vcal = 80
[11:55:53.393] <TB1> INFO: Expecting 41600 events.
[11:55:57.614] <TB1> INFO: 41600 events read in total (3665ms).
[11:55:57.615] <TB1> INFO: Test took 4485ms.
[11:55:57.617] <TB1> INFO: scanning low vcal = 90
[11:55:57.876] <TB1> INFO: Expecting 41600 events.
[11:56:02.114] <TB1> INFO: 41600 events read in total (3682ms).
[11:56:02.115] <TB1> INFO: Test took 4498ms.
[11:56:02.118] <TB1> INFO: scanning low vcal = 100
[11:56:02.399] <TB1> INFO: Expecting 41600 events.
[11:56:06.570] <TB1> INFO: 41600 events read in total (3614ms).
[11:56:06.570] <TB1> INFO: Test took 4452ms.
[11:56:06.573] <TB1> INFO: scanning low vcal = 110
[11:56:06.848] <TB1> INFO: Expecting 41600 events.
[11:56:11.040] <TB1> INFO: 41600 events read in total (3636ms).
[11:56:11.041] <TB1> INFO: Test took 4468ms.
[11:56:11.044] <TB1> INFO: scanning low vcal = 120
[11:56:11.336] <TB1> INFO: Expecting 41600 events.
[11:56:15.499] <TB1> INFO: 41600 events read in total (3606ms).
[11:56:15.499] <TB1> INFO: Test took 4455ms.
[11:56:15.502] <TB1> INFO: scanning low vcal = 130
[11:56:15.767] <TB1> INFO: Expecting 41600 events.
[11:56:19.936] <TB1> INFO: 41600 events read in total (3613ms).
[11:56:19.936] <TB1> INFO: Test took 4434ms.
[11:56:19.939] <TB1> INFO: scanning low vcal = 140
[11:56:20.203] <TB1> INFO: Expecting 41600 events.
[11:56:24.409] <TB1> INFO: 41600 events read in total (3650ms).
[11:56:24.410] <TB1> INFO: Test took 4471ms.
[11:56:24.413] <TB1> INFO: scanning low vcal = 150
[11:56:24.693] <TB1> INFO: Expecting 41600 events.
[11:56:28.853] <TB1> INFO: 41600 events read in total (3604ms).
[11:56:28.854] <TB1> INFO: Test took 4441ms.
[11:56:28.856] <TB1> INFO: scanning low vcal = 160
[11:56:29.123] <TB1> INFO: Expecting 41600 events.
[11:56:33.282] <TB1> INFO: 41600 events read in total (3603ms).
[11:56:33.283] <TB1> INFO: Test took 4427ms.
[11:56:33.285] <TB1> INFO: scanning low vcal = 170
[11:56:33.552] <TB1> INFO: Expecting 41600 events.
[11:56:37.745] <TB1> INFO: 41600 events read in total (3637ms).
[11:56:37.746] <TB1> INFO: Test took 4460ms.
[11:56:37.749] <TB1> INFO: scanning low vcal = 180
[11:56:38.011] <TB1> INFO: Expecting 41600 events.
[11:56:42.198] <TB1> INFO: 41600 events read in total (3631ms).
[11:56:42.199] <TB1> INFO: Test took 4449ms.
[11:56:42.201] <TB1> INFO: scanning low vcal = 190
[11:56:42.467] <TB1> INFO: Expecting 41600 events.
[11:56:46.688] <TB1> INFO: 41600 events read in total (3664ms).
[11:56:46.688] <TB1> INFO: Test took 4487ms.
[11:56:46.691] <TB1> INFO: scanning low vcal = 200
[11:56:46.970] <TB1> INFO: Expecting 41600 events.
[11:56:51.155] <TB1> INFO: 41600 events read in total (3629ms).
[11:56:51.156] <TB1> INFO: Test took 4465ms.
[11:56:51.158] <TB1> INFO: scanning low vcal = 210
[11:56:51.429] <TB1> INFO: Expecting 41600 events.
[11:56:55.603] <TB1> INFO: 41600 events read in total (3618ms).
[11:56:55.604] <TB1> INFO: Test took 4446ms.
[11:56:55.607] <TB1> INFO: scanning low vcal = 220
[11:56:55.868] <TB1> INFO: Expecting 41600 events.
[11:57:00.068] <TB1> INFO: 41600 events read in total (3644ms).
[11:57:00.069] <TB1> INFO: Test took 4462ms.
[11:57:00.071] <TB1> INFO: scanning low vcal = 230
[11:57:00.331] <TB1> INFO: Expecting 41600 events.
[11:57:04.552] <TB1> INFO: 41600 events read in total (3665ms).
[11:57:04.553] <TB1> INFO: Test took 4482ms.
[11:57:04.555] <TB1> INFO: scanning low vcal = 240
[11:57:04.824] <TB1> INFO: Expecting 41600 events.
[11:57:09.056] <TB1> INFO: 41600 events read in total (3676ms).
[11:57:09.057] <TB1> INFO: Test took 4502ms.
[11:57:09.059] <TB1> INFO: scanning low vcal = 250
[11:57:09.327] <TB1> INFO: Expecting 41600 events.
[11:57:13.561] <TB1> INFO: 41600 events read in total (3678ms).
[11:57:13.561] <TB1> INFO: Test took 4502ms.
[11:57:13.565] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[11:57:13.850] <TB1> INFO: Expecting 41600 events.
[11:57:18.126] <TB1> INFO: 41600 events read in total (3720ms).
[11:57:18.127] <TB1> INFO: Test took 4562ms.
[11:57:18.129] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[11:57:18.401] <TB1> INFO: Expecting 41600 events.
[11:57:22.617] <TB1> INFO: 41600 events read in total (3660ms).
[11:57:22.618] <TB1> INFO: Test took 4489ms.
[11:57:22.620] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[11:57:22.887] <TB1> INFO: Expecting 41600 events.
[11:57:27.134] <TB1> INFO: 41600 events read in total (3691ms).
[11:57:27.134] <TB1> INFO: Test took 4514ms.
[11:57:27.137] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[11:57:27.420] <TB1> INFO: Expecting 41600 events.
[11:57:31.614] <TB1> INFO: 41600 events read in total (3638ms).
[11:57:31.614] <TB1> INFO: Test took 4477ms.
[11:57:31.616] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:57:31.873] <TB1> INFO: Expecting 41600 events.
[11:57:36.074] <TB1> INFO: 41600 events read in total (3645ms).
[11:57:36.074] <TB1> INFO: Test took 4457ms.
[11:57:36.696] <TB1> INFO: PixTestGainPedestal::measure() done
[11:58:10.669] <TB1> INFO: PixTestGainPedestal::fit() done
[11:58:10.669] <TB1> INFO: non-linearity mean: 0.954 0.957 0.958 0.960 0.956 0.958 0.952 0.950 0.956 0.964 0.954 0.956 0.960 0.949 0.957 0.954
[11:58:10.669] <TB1> INFO: non-linearity RMS: 0.007 0.007 0.005 0.005 0.007 0.005 0.006 0.006 0.006 0.004 0.005 0.004 0.006 0.006 0.004 0.006
[11:58:10.669] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:58:10.690] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:58:10.710] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:58:10.730] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:58:10.751] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:58:10.771] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:58:10.791] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:58:10.811] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:58:10.843] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:58:10.874] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:58:10.905] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:58:10.936] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:58:10.960] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:58:10.991] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:58:11.011] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:58:11.031] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:58:11.051] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[11:58:11.051] <TB1> INFO: Decoding statistics:
[11:58:11.051] <TB1> INFO: General information:
[11:58:11.051] <TB1> INFO: 16bit words read: 2662400
[11:58:11.051] <TB1> INFO: valid events total: 166400
[11:58:11.051] <TB1> INFO: empty events: 0
[11:58:11.051] <TB1> INFO: valid events with pixels: 166400
[11:58:11.051] <TB1> INFO: valid pixel hits: 665536
[11:58:11.051] <TB1> INFO: Event errors: 0
[11:58:11.051] <TB1> INFO: start marker: 0
[11:58:11.051] <TB1> INFO: stop marker: 0
[11:58:11.051] <TB1> INFO: overflow: 0
[11:58:11.051] <TB1> INFO: invalid 5bit words: 0
[11:58:11.051] <TB1> INFO: invalid XOR eye diagram: 0
[11:58:11.051] <TB1> INFO: frame (failed synchr.): 0
[11:58:11.051] <TB1> INFO: idle data (no TBM trl): 0
[11:58:11.051] <TB1> INFO: no data (only TBM hdr): 0
[11:58:11.051] <TB1> INFO: TBM errors: 0
[11:58:11.051] <TB1> INFO: flawed TBM headers: 0
[11:58:11.051] <TB1> INFO: flawed TBM trailers: 0
[11:58:11.051] <TB1> INFO: event ID mismatches: 0
[11:58:11.051] <TB1> INFO: ROC errors: 0
[11:58:11.051] <TB1> INFO: missing ROC header(s): 0
[11:58:11.051] <TB1> INFO: misplaced readback start: 0
[11:58:11.051] <TB1> INFO: Pixel decoding errors: 0
[11:58:11.051] <TB1> INFO: pixel data incomplete: 0
[11:58:11.051] <TB1> INFO: pixel address: 0
[11:58:11.051] <TB1> INFO: pulse height fill bit: 0
[11:58:11.051] <TB1> INFO: buffer corruption: 0
[11:58:11.058] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C15.dat
[11:58:11.059] <TB1> INFO: ######################################################################
[11:58:11.059] <TB1> INFO: PixTestReadback::doTest()
[11:58:11.059] <TB1> INFO: ######################################################################
[11:58:11.059] <TB1> INFO: ----------------------------------------------------------------------
[11:58:11.059] <TB1> INFO: PixTestReadback::CalibrateVd()
[11:58:11.059] <TB1> INFO: ----------------------------------------------------------------------
[11:58:20.525] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C0.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C1.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C2.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C3.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C4.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C5.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C6.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C7.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C8.dat
[11:58:20.526] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C9.dat
[11:58:20.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C10.dat
[11:58:20.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C11.dat
[11:58:20.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C12.dat
[11:58:20.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C13.dat
[11:58:20.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C14.dat
[11:58:20.527] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C15.dat
[11:58:20.556] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:58:20.556] <TB1> INFO: ----------------------------------------------------------------------
[11:58:20.556] <TB1> INFO: PixTestReadback::CalibrateVa()
[11:58:20.556] <TB1> INFO: ----------------------------------------------------------------------
[11:58:30.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C0.dat
[11:58:30.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C1.dat
[11:58:30.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C2.dat
[11:58:30.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C3.dat
[11:58:30.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C4.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C5.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C6.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C7.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C8.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C9.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C10.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C11.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C12.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C13.dat
[11:58:30.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C14.dat
[11:58:30.073] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C15.dat
[11:58:30.103] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:58:30.103] <TB1> INFO: ----------------------------------------------------------------------
[11:58:30.103] <TB1> INFO: PixTestReadback::readbackVbg()
[11:58:30.103] <TB1> INFO: ----------------------------------------------------------------------
[11:58:37.377] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:58:37.378] <TB1> INFO: ----------------------------------------------------------------------
[11:58:37.378] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[11:58:37.378] <TB1> INFO: ----------------------------------------------------------------------
[11:58:37.378] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 160.6calibrated Vbg = 1.18175 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 161.5calibrated Vbg = 1.17835 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.3calibrated Vbg = 1.18313 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.2calibrated Vbg = 1.18845 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.4calibrated Vbg = 1.18846 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.2calibrated Vbg = 1.19347 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.5calibrated Vbg = 1.19332 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 154.2calibrated Vbg = 1.19799 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.2calibrated Vbg = 1.20019 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.1calibrated Vbg = 1.19632 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.7calibrated Vbg = 1.2034 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 148.9calibrated Vbg = 1.19143 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157.3calibrated Vbg = 1.18614 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 144.7calibrated Vbg = 1.18538 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 145.6calibrated Vbg = 1.18374 :::*/*/*/*/
[11:58:37.378] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.4calibrated Vbg = 1.18298 :::*/*/*/*/
[11:58:37.382] <TB1> INFO: ----------------------------------------------------------------------
[11:58:37.382] <TB1> INFO: PixTestReadback::CalibrateIa()
[11:58:37.382] <TB1> INFO: ----------------------------------------------------------------------
[12:01:12.889] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C0.dat
[12:01:12.889] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C1.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C2.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C3.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C4.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C5.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C6.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C7.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C8.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C9.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C10.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C11.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C12.dat
[12:01:12.890] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C13.dat
[12:01:12.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C14.dat
[12:01:12.891] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2284_FullQualification_2016-08-08_08h38m_1470638289//002_FulltestPxar_m20//readbackCal_C15.dat
[12:01:12.920] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:01:12.920] <TB1> INFO: PixTestReadback::doTest() done
[12:01:12.921] <TB1> INFO: Decoding statistics:
[12:01:12.921] <TB1> INFO: General information:
[12:01:12.921] <TB1> INFO: 16bit words read: 1024
[12:01:12.921] <TB1> INFO: valid events total: 128
[12:01:12.921] <TB1> INFO: empty events: 128
[12:01:12.921] <TB1> INFO: valid events with pixels: 0
[12:01:12.921] <TB1> INFO: valid pixel hits: 0
[12:01:12.921] <TB1> INFO: Event errors: 0
[12:01:12.921] <TB1> INFO: start marker: 0
[12:01:12.921] <TB1> INFO: stop marker: 0
[12:01:12.921] <TB1> INFO: overflow: 0
[12:01:12.921] <TB1> INFO: invalid 5bit words: 0
[12:01:12.921] <TB1> INFO: invalid XOR eye diagram: 0
[12:01:12.921] <TB1> INFO: frame (failed synchr.): 0
[12:01:12.921] <TB1> INFO: idle data (no TBM trl): 0
[12:01:12.921] <TB1> INFO: no data (only TBM hdr): 0
[12:01:12.921] <TB1> INFO: TBM errors: 0
[12:01:12.921] <TB1> INFO: flawed TBM headers: 0
[12:01:12.921] <TB1> INFO: flawed TBM trailers: 0
[12:01:12.921] <TB1> INFO: event ID mismatches: 0
[12:01:12.921] <TB1> INFO: ROC errors: 0
[12:01:12.921] <TB1> INFO: missing ROC header(s): 0
[12:01:12.921] <TB1> INFO: misplaced readback start: 0
[12:01:12.921] <TB1> INFO: Pixel decoding errors: 0
[12:01:12.921] <TB1> INFO: pixel data incomplete: 0
[12:01:12.921] <TB1> INFO: pixel address: 0
[12:01:12.921] <TB1> INFO: pulse height fill bit: 0
[12:01:12.921] <TB1> INFO: buffer corruption: 0
[12:01:12.940] <TB1> INFO: Decoding statistics:
[12:01:12.940] <TB1> INFO: General information:
[12:01:12.940] <TB1> INFO: 16bit words read: 2755348
[12:01:12.940] <TB1> INFO: valid events total: 176768
[12:01:12.940] <TB1> INFO: empty events: 7867
[12:01:12.940] <TB1> INFO: valid events with pixels: 168901
[12:01:12.940] <TB1> INFO: valid pixel hits: 668037
[12:01:12.940] <TB1> INFO: Event errors: 0
[12:01:12.940] <TB1> INFO: start marker: 0
[12:01:12.940] <TB1> INFO: stop marker: 0
[12:01:12.940] <TB1> INFO: overflow: 0
[12:01:12.940] <TB1> INFO: invalid 5bit words: 0
[12:01:12.940] <TB1> INFO: invalid XOR eye diagram: 0
[12:01:12.940] <TB1> INFO: frame (failed synchr.): 0
[12:01:12.940] <TB1> INFO: idle data (no TBM trl): 0
[12:01:12.940] <TB1> INFO: no data (only TBM hdr): 0
[12:01:12.940] <TB1> INFO: TBM errors: 0
[12:01:12.940] <TB1> INFO: flawed TBM headers: 0
[12:01:12.940] <TB1> INFO: flawed TBM trailers: 0
[12:01:12.940] <TB1> INFO: event ID mismatches: 0
[12:01:12.940] <TB1> INFO: ROC errors: 0
[12:01:12.940] <TB1> INFO: missing ROC header(s): 0
[12:01:12.940] <TB1> INFO: misplaced readback start: 0
[12:01:12.940] <TB1> INFO: Pixel decoding errors: 0
[12:01:12.940] <TB1> INFO: pixel data incomplete: 0
[12:01:12.940] <TB1> INFO: pixel address: 0
[12:01:12.940] <TB1> INFO: pulse height fill bit: 0
[12:01:12.940] <TB1> INFO: buffer corruption: 0
[12:01:12.940] <TB1> INFO: enter test to run
[12:01:12.940] <TB1> INFO: test: exit no parameter change
[12:01:13.070] <TB1> QUIET: Connection to board 153 closed.
[12:01:13.150] <TB1> INFO: pXar: this is the end, my friend