[15:39:51.263] <TB1> INFO: *** Welcome to pxar ***
[15:39:51.263] <TB1> INFO: *** Today: 2016/09/20
[15:39:53.251] <TB1> INFO: *** Version: fe94
[15:39:53.251] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C15.dat
[15:39:53.251] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//tbmParameters_C0b.dat
[15:39:53.251] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//defaultMaskFile.dat
[15:39:53.251] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters_C15.dat
[15:39:53.319] <TB1> INFO: clk: 4
[15:39:53.319] <TB1> INFO: ctr: 4
[15:39:53.319] <TB1> INFO: sda: 19
[15:39:53.319] <TB1> INFO: tin: 9
[15:39:53.319] <TB1> INFO: level: 15
[15:39:53.319] <TB1> INFO: triggerdelay: 0
[15:39:53.319] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[15:39:53.319] <TB1> INFO: Log level: INFO
[15:39:53.326] <TB1> INFO: Found DTB DTB_WXBYFL
[15:39:53.334] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[15:39:53.336] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[15:39:53.338] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[15:39:54.839] <TB1> INFO: DUT info:
[15:39:54.839] <TB1> INFO: The DUT currently contains the following objects:
[15:39:54.839] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:39:54.840] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:39:54.840] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:39:54.840] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:39:54.840] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:54.840] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:39:55.240] <TB1> INFO: enter 'restricted' command line mode
[15:39:55.240] <TB1> INFO: enter test to run
[15:39:55.240] <TB1> INFO: test: pretest no parameter change
[15:39:55.240] <TB1> INFO: running: pretest
[15:39:55.245] <TB1> INFO: ----------------------------------------------------------------------
[15:39:55.245] <TB1> INFO: PixTestPretest::programROC()
[15:39:55.245] <TB1> INFO: ----------------------------------------------------------------------
[15:40:13.262] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:40:13.262] <TB1> INFO: IA differences per ROC: 18.5 17.7 17.7 16.1 20.9 19.3 17.7 17.7 18.5 20.1 18.5 17.7 20.9 22.5 20.1 19.3
[15:40:13.362] <TB1> INFO: enter test to run
[15:40:13.362] <TB1> INFO: test: pretest no parameter change
[15:40:13.362] <TB1> INFO: running: pretest
[15:40:13.363] <TB1> INFO: ----------------------------------------------------------------------
[15:40:13.363] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:40:13.363] <TB1> INFO: ----------------------------------------------------------------------
[15:40:34.636] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[15:40:34.636] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 20.1 19.3 18.5 19.3 20.1 19.3 19.3 20.1 19.3 19.3 19.3 18.5 18.5 19.3
[15:40:34.669] <TB1> INFO: enter test to run
[15:40:34.669] <TB1> INFO: test: pretest no parameter change
[15:40:34.669] <TB1> INFO: running: pretest
[15:40:34.669] <TB1> INFO: ----------------------------------------------------------------------
[15:40:34.669] <TB1> INFO: PixTestPretest::findTiming()
[15:40:34.669] <TB1> INFO: ----------------------------------------------------------------------
[15:40:34.669] <TB1> INFO: PixTestCmd::init()

[15:42:04.828] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[15:42:04.828] <TB1> INFO: (success/tries = 100/100), width = 5
[15:42:04.829] <TB1> INFO: enter test to run
[15:42:04.829] <TB1> INFO: test: pretest no parameter change
[15:42:04.829] <TB1> INFO: running: pretest
[15:42:04.831] <TB1> INFO: ----------------------------------------------------------------------
[15:42:04.831] <TB1> INFO: PixTestPretest::findWorkingPixel()
[15:42:04.831] <TB1> INFO: ----------------------------------------------------------------------
[15:42:04.924] <TB1> INFO: Expecting 231680 events.


[15:42:14.098] <TB1> INFO: 231680 events read in total (8617ms).
[15:42:14.104] <TB1> INFO: Test took 9269ms.
[15:42:14.309] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:42:14.386] <TB1> INFO: enter test to run
[15:42:14.386] <TB1> INFO: test: pretest no parameter change
[15:42:14.386] <TB1> INFO: running: pretest
[15:42:14.387] <TB1> INFO: ----------------------------------------------------------------------
[15:42:14.387] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[15:42:14.387] <TB1> INFO: ----------------------------------------------------------------------
[15:42:14.479] <TB1> INFO: Expecting 231680 events.
[15:42:23.641] <TB1> INFO: 231680 events read in total (8606ms).
[15:42:23.647] <TB1> INFO: Test took 9256ms.
[15:42:23.874] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[15:42:23.874] <TB1> INFO: CalDel: 121 143 129 126 136 132 171 143 141 147 156 130 154 152 164 144
[15:42:23.874] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:42:23.917] <TB1> INFO: enter test to run
[15:42:23.917] <TB1> INFO: test: pretest no parameter change
[15:42:23.917] <TB1> INFO: running: pretest
[15:42:23.917] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C0.dat
[15:42:23.918] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C1.dat
[15:42:23.918] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C2.dat
[15:42:23.918] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C3.dat
[15:42:23.918] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C4.dat
[15:42:23.918] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C5.dat
[15:42:23.918] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C6.dat
[15:42:23.918] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C7.dat
[15:42:23.919] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C8.dat
[15:42:23.919] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C9.dat
[15:42:23.919] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C10.dat
[15:42:23.919] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C11.dat
[15:42:23.919] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C12.dat
[15:42:23.919] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C13.dat
[15:42:23.920] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C14.dat
[15:42:23.920] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters_C15.dat
[15:42:23.920] <TB1> INFO: enter test to run
[15:42:23.920] <TB1> INFO: test: fulltest no parameter change
[15:42:23.920] <TB1> INFO: running: fulltest
[15:42:23.920] <TB1> INFO: ######################################################################
[15:42:23.920] <TB1> INFO: PixTestFullTest::doTest()
[15:42:23.920] <TB1> INFO: ######################################################################
[15:42:23.921] <TB1> INFO: ######################################################################
[15:42:23.921] <TB1> INFO: PixTestAlive::doTest()
[15:42:23.921] <TB1> INFO: ######################################################################
[15:42:23.923] <TB1> INFO: ----------------------------------------------------------------------
[15:42:23.923] <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)
[15:42:23.923] <TB1> INFO: ----------------------------------------------------------------------
[15:42:24.207] <TB1> INFO: Expecting 41600 events.
[15:42:27.745] <TB1> INFO: 41600 events read in total (2982ms).
[15:42:27.746] <TB1> INFO: Test took 3822ms.
[15:42:27.960] <TB1> INFO: PixTestAlive::aliveTest() done
[15:42:27.960] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:42:27.961] <TB1> INFO: ----------------------------------------------------------------------
[15:42:27.962] <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)
[15:42:27.962] <TB1> INFO: ----------------------------------------------------------------------
[15:42:28.227] <TB1> INFO: Expecting 41600 events.
[15:42:30.924] <TB1> INFO: 41600 events read in total (2141ms).
[15:42:30.924] <TB1> INFO: Test took 2961ms.
[15:42:30.924] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:42:31.165] <TB1> INFO: PixTestAlive::maskTest() done
[15:42:31.165] <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
[15:42:31.166] <TB1> INFO: ----------------------------------------------------------------------
[15:42:31.166] <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)
[15:42:31.166] <TB1> INFO: ----------------------------------------------------------------------
[15:42:31.430] <TB1> INFO: Expecting 41600 events.
[15:42:34.866] <TB1> INFO: 41600 events read in total (2880ms).
[15:42:34.867] <TB1> INFO: Test took 3699ms.
[15:42:35.084] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:42:35.084] <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
[15:42:35.084] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[15:42:35.084] <TB1> INFO: Decoding statistics:
[15:42:35.084] <TB1> INFO: General information:
[15:42:35.084] <TB1> INFO: 16bit words read: 0
[15:42:35.084] <TB1> INFO: valid events total: 0
[15:42:35.084] <TB1> INFO: empty events: 0
[15:42:35.084] <TB1> INFO: valid events with pixels: 0
[15:42:35.084] <TB1> INFO: valid pixel hits: 0
[15:42:35.084] <TB1> INFO: Event errors: 0
[15:42:35.084] <TB1> INFO: start marker: 0
[15:42:35.084] <TB1> INFO: stop marker: 0
[15:42:35.084] <TB1> INFO: overflow: 0
[15:42:35.085] <TB1> INFO: invalid 5bit words: 0
[15:42:35.085] <TB1> INFO: invalid XOR eye diagram: 0
[15:42:35.085] <TB1> INFO: frame (failed synchr.): 0
[15:42:35.085] <TB1> INFO: idle data (no TBM trl): 0
[15:42:35.085] <TB1> INFO: no data (only TBM hdr): 0
[15:42:35.085] <TB1> INFO: TBM errors: 0
[15:42:35.085] <TB1> INFO: flawed TBM headers: 0
[15:42:35.085] <TB1> INFO: flawed TBM trailers: 0
[15:42:35.085] <TB1> INFO: event ID mismatches: 0
[15:42:35.085] <TB1> INFO: ROC errors: 0
[15:42:35.085] <TB1> INFO: missing ROC header(s): 0
[15:42:35.085] <TB1> INFO: misplaced readback start: 0
[15:42:35.085] <TB1> INFO: Pixel decoding errors: 0
[15:42:35.085] <TB1> INFO: pixel data incomplete: 0
[15:42:35.085] <TB1> INFO: pixel address: 0
[15:42:35.085] <TB1> INFO: pulse height fill bit: 0
[15:42:35.085] <TB1> INFO: buffer corruption: 0
[15:42:35.091] <TB1> INFO: ######################################################################
[15:42:35.091] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:42:35.091] <TB1> INFO: ######################################################################
[15:42:35.094] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:42:35.104] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:42:35.104] <TB1> INFO: run 1 of 1
[15:42:35.363] <TB1> INFO: Expecting 3120000 events.
[15:43:11.703] <TB1> INFO: 846420 events read in total (35783ms).
[15:43:46.764] <TB1> INFO: 1674060 events read in total (70844ms).
[15:44:22.281] <TB1> INFO: 2508600 events read in total (106361ms).
[15:44:48.076] <TB1> INFO: 3120000 events read in total (132156ms).
[15:44:48.168] <TB1> INFO: Test took 133065ms.
[15:45:11.521] <TB1> INFO: PixTestBBMap::doTest() done, duration: 156 seconds
[15:45:11.521] <TB1> INFO: number of dead bumps (per ROC): 2 0 0 0 0 0 0 1 0 2 0 3 0 0 0 9
[15:45:11.521] <TB1> INFO: separation cut (per ROC): 95 78 111 75 72 71 81 79 70 85 81 79 87 92 82 82
[15:45:11.521] <TB1> INFO: Decoding statistics:
[15:45:11.521] <TB1> INFO: General information:
[15:45:11.521] <TB1> INFO: 16bit words read: 0
[15:45:11.521] <TB1> INFO: valid events total: 0
[15:45:11.521] <TB1> INFO: empty events: 0
[15:45:11.521] <TB1> INFO: valid events with pixels: 0
[15:45:11.521] <TB1> INFO: valid pixel hits: 0
[15:45:11.521] <TB1> INFO: Event errors: 0
[15:45:11.521] <TB1> INFO: start marker: 0
[15:45:11.521] <TB1> INFO: stop marker: 0
[15:45:11.521] <TB1> INFO: overflow: 0
[15:45:11.521] <TB1> INFO: invalid 5bit words: 0
[15:45:11.521] <TB1> INFO: invalid XOR eye diagram: 0
[15:45:11.521] <TB1> INFO: frame (failed synchr.): 0
[15:45:11.521] <TB1> INFO: idle data (no TBM trl): 0
[15:45:11.521] <TB1> INFO: no data (only TBM hdr): 0
[15:45:11.521] <TB1> INFO: TBM errors: 0
[15:45:11.521] <TB1> INFO: flawed TBM headers: 0
[15:45:11.521] <TB1> INFO: flawed TBM trailers: 0
[15:45:11.521] <TB1> INFO: event ID mismatches: 0
[15:45:11.521] <TB1> INFO: ROC errors: 0
[15:45:11.521] <TB1> INFO: missing ROC header(s): 0
[15:45:11.521] <TB1> INFO: misplaced readback start: 0
[15:45:11.521] <TB1> INFO: Pixel decoding errors: 0
[15:45:11.521] <TB1> INFO: pixel data incomplete: 0
[15:45:11.521] <TB1> INFO: pixel address: 0
[15:45:11.521] <TB1> INFO: pulse height fill bit: 0
[15:45:11.521] <TB1> INFO: buffer corruption: 0
[15:45:11.593] <TB1> INFO: ######################################################################
[15:45:11.593] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:45:11.593] <TB1> INFO: ######################################################################
[15:45:11.594] <TB1> INFO: ----------------------------------------------------------------------
[15:45:11.594] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:45:11.594] <TB1> INFO: ----------------------------------------------------------------------
[15:45:11.594] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[15:45:11.604] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[15:45:11.604] <TB1> INFO: run 1 of 1
[15:45:11.865] <TB1> INFO: Expecting 36608000 events.
[15:45:39.807] <TB1> INFO: 808900 events read in total (27386ms).
[15:46:07.344] <TB1> INFO: 1605800 events read in total (54923ms).
[15:46:34.857] <TB1> INFO: 2402950 events read in total (82436ms).
[15:47:02.895] <TB1> INFO: 3200200 events read in total (110474ms).
[15:47:30.561] <TB1> INFO: 3997700 events read in total (138140ms).
[15:47:57.795] <TB1> INFO: 4794500 events read in total (165374ms).
[15:48:25.657] <TB1> INFO: 5590900 events read in total (193236ms).
[15:48:53.288] <TB1> INFO: 6388550 events read in total (220867ms).
[15:49:21.074] <TB1> INFO: 7184950 events read in total (248653ms).
[15:49:48.862] <TB1> INFO: 7980750 events read in total (276441ms).
[15:50:16.193] <TB1> INFO: 8777250 events read in total (303772ms).
[15:50:43.602] <TB1> INFO: 9572250 events read in total (331181ms).
[15:51:11.217] <TB1> INFO: 10366750 events read in total (358796ms).
[15:51:38.996] <TB1> INFO: 11162200 events read in total (386575ms).
[15:52:06.040] <TB1> INFO: 11956600 events read in total (413619ms).
[15:52:33.757] <TB1> INFO: 12753300 events read in total (441336ms).
[15:53:01.186] <TB1> INFO: 13549600 events read in total (468765ms).
[15:53:28.843] <TB1> INFO: 14344500 events read in total (496422ms).
[15:53:56.089] <TB1> INFO: 15139650 events read in total (523668ms).
[15:54:23.721] <TB1> INFO: 15934100 events read in total (551300ms).
[15:54:51.578] <TB1> INFO: 16728450 events read in total (579157ms).
[15:55:19.300] <TB1> INFO: 17526200 events read in total (606879ms).
[15:55:47.519] <TB1> INFO: 18319600 events read in total (635098ms).
[15:56:15.087] <TB1> INFO: 19110100 events read in total (662666ms).
[15:56:43.429] <TB1> INFO: 19901200 events read in total (691008ms).
[15:57:11.340] <TB1> INFO: 20690750 events read in total (718919ms).
[15:57:38.700] <TB1> INFO: 21480250 events read in total (746279ms).
[15:58:06.422] <TB1> INFO: 22269200 events read in total (774001ms).
[15:58:34.521] <TB1> INFO: 23056800 events read in total (802100ms).
[15:59:02.133] <TB1> INFO: 23845050 events read in total (829712ms).
[15:59:30.009] <TB1> INFO: 24635150 events read in total (857588ms).
[15:59:57.566] <TB1> INFO: 25421750 events read in total (885145ms).
[16:00:25.254] <TB1> INFO: 26211850 events read in total (912833ms).
[16:00:52.370] <TB1> INFO: 26998050 events read in total (939949ms).
[16:01:19.930] <TB1> INFO: 27785850 events read in total (967509ms).
[16:01:47.349] <TB1> INFO: 28573050 events read in total (994928ms).
[16:02:15.375] <TB1> INFO: 29361750 events read in total (1022954ms).
[16:02:43.380] <TB1> INFO: 30151050 events read in total (1050959ms).
[16:03:11.184] <TB1> INFO: 30937950 events read in total (1078763ms).
[16:03:38.180] <TB1> INFO: 31727950 events read in total (1105759ms).
[16:04:05.644] <TB1> INFO: 32515200 events read in total (1133223ms).
[16:04:33.253] <TB1> INFO: 33305500 events read in total (1160832ms).
[16:05:00.638] <TB1> INFO: 34094250 events read in total (1188217ms).
[16:05:28.554] <TB1> INFO: 34882800 events read in total (1216133ms).
[16:05:56.018] <TB1> INFO: 35672800 events read in total (1243597ms).
[16:06:24.468] <TB1> INFO: 36472850 events read in total (1272047ms).
[16:06:29.904] <TB1> INFO: 36608000 events read in total (1277483ms).
[16:06:29.962] <TB1> INFO: Test took 1278358ms.
[16:06:30.478] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:32.270] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:33.999] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:35.937] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:37.593] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:39.316] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:40.974] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:42.736] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:44.499] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:46.398] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:48.291] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:50.057] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:51.867] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:53.818] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:55.691] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:57.679] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:06:59.470] <TB1> INFO: PixTestScurves::scurves() done
[16:06:59.470] <TB1> INFO: Vcal mean: 98.87 82.35 87.24 83.12 76.28 76.64 86.73 85.15 87.78 88.24 84.88 77.81 83.67 89.75 86.15 82.56
[16:06:59.470] <TB1> INFO: Vcal RMS: 5.75 4.21 5.03 4.16 4.11 3.92 4.51 4.76 5.24 5.47 5.34 4.49 4.44 5.39 4.67 4.31
[16:06:59.470] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1307 seconds
[16:06:59.470] <TB1> INFO: Decoding statistics:
[16:06:59.470] <TB1> INFO: General information:
[16:06:59.470] <TB1> INFO: 16bit words read: 0
[16:06:59.470] <TB1> INFO: valid events total: 0
[16:06:59.470] <TB1> INFO: empty events: 0
[16:06:59.470] <TB1> INFO: valid events with pixels: 0
[16:06:59.470] <TB1> INFO: valid pixel hits: 0
[16:06:59.470] <TB1> INFO: Event errors: 0
[16:06:59.470] <TB1> INFO: start marker: 0
[16:06:59.470] <TB1> INFO: stop marker: 0
[16:06:59.470] <TB1> INFO: overflow: 0
[16:06:59.470] <TB1> INFO: invalid 5bit words: 0
[16:06:59.470] <TB1> INFO: invalid XOR eye diagram: 0
[16:06:59.470] <TB1> INFO: frame (failed synchr.): 0
[16:06:59.470] <TB1> INFO: idle data (no TBM trl): 0
[16:06:59.470] <TB1> INFO: no data (only TBM hdr): 0
[16:06:59.470] <TB1> INFO: TBM errors: 0
[16:06:59.470] <TB1> INFO: flawed TBM headers: 0
[16:06:59.470] <TB1> INFO: flawed TBM trailers: 0
[16:06:59.470] <TB1> INFO: event ID mismatches: 0
[16:06:59.470] <TB1> INFO: ROC errors: 0
[16:06:59.470] <TB1> INFO: missing ROC header(s): 0
[16:06:59.470] <TB1> INFO: misplaced readback start: 0
[16:06:59.470] <TB1> INFO: Pixel decoding errors: 0
[16:06:59.470] <TB1> INFO: pixel data incomplete: 0
[16:06:59.470] <TB1> INFO: pixel address: 0
[16:06:59.470] <TB1> INFO: pulse height fill bit: 0
[16:06:59.470] <TB1> INFO: buffer corruption: 0
[16:06:59.543] <TB1> INFO: ######################################################################
[16:06:59.543] <TB1> INFO: PixTestTrim::doTest()
[16:06:59.543] <TB1> INFO: ######################################################################
[16:06:59.544] <TB1> INFO: ----------------------------------------------------------------------
[16:06:59.544] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[16:06:59.544] <TB1> INFO: ----------------------------------------------------------------------
[16:06:59.668] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:06:59.668] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:06:59.679] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:06:59.679] <TB1> INFO: run 1 of 1
[16:06:59.985] <TB1> INFO: Expecting 6281600 events.
[16:07:38.576] <TB1> INFO: 1095800 events read in total (38034ms).
[16:08:14.010] <TB1> INFO: 2184980 events read in total (73468ms).
[16:08:49.822] <TB1> INFO: 3265700 events read in total (109280ms).
[16:09:25.467] <TB1> INFO: 4352850 events read in total (144925ms).
[16:10:01.247] <TB1> INFO: 5442560 events read in total (180705ms).
[16:10:28.267] <TB1> INFO: 6281600 events read in total (207725ms).
[16:10:28.318] <TB1> INFO: Test took 208640ms.
[16:10:49.623] <TB1> INFO: ROC 0 VthrComp = 105
[16:10:49.623] <TB1> INFO: ROC 1 VthrComp = 91
[16:10:49.623] <TB1> INFO: ROC 2 VthrComp = 99
[16:10:49.623] <TB1> INFO: ROC 3 VthrComp = 91
[16:10:49.623] <TB1> INFO: ROC 4 VthrComp = 83
[16:10:49.623] <TB1> INFO: ROC 5 VthrComp = 84
[16:10:49.624] <TB1> INFO: ROC 6 VthrComp = 93
[16:10:49.624] <TB1> INFO: ROC 7 VthrComp = 94
[16:10:49.624] <TB1> INFO: ROC 8 VthrComp = 88
[16:10:49.624] <TB1> INFO: ROC 9 VthrComp = 92
[16:10:49.624] <TB1> INFO: ROC 10 VthrComp = 88
[16:10:49.624] <TB1> INFO: ROC 11 VthrComp = 81
[16:10:49.624] <TB1> INFO: ROC 12 VthrComp = 91
[16:10:49.624] <TB1> INFO: ROC 13 VthrComp = 96
[16:10:49.624] <TB1> INFO: ROC 14 VthrComp = 92
[16:10:49.624] <TB1> INFO: ROC 15 VthrComp = 89
[16:10:49.625] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:10:49.625] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:10:49.633] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:10:49.633] <TB1> INFO: run 1 of 1
[16:10:49.925] <TB1> INFO: Expecting 6281600 events.
[16:11:21.026] <TB1> INFO: 756640 events read in total (30544ms).
[16:11:51.483] <TB1> INFO: 1511680 events read in total (61001ms).
[16:12:21.922] <TB1> INFO: 2267090 events read in total (91440ms).
[16:12:52.217] <TB1> INFO: 3023740 events read in total (121735ms).
[16:13:22.177] <TB1> INFO: 3775700 events read in total (151695ms).
[16:13:52.410] <TB1> INFO: 4524400 events read in total (181928ms).
[16:14:22.665] <TB1> INFO: 5271990 events read in total (212183ms).
[16:14:53.088] <TB1> INFO: 6019720 events read in total (242606ms).
[16:15:03.907] <TB1> INFO: 6281600 events read in total (253425ms).
[16:15:03.962] <TB1> INFO: Test took 254329ms.
[16:15:28.128] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.3504 for pixel 24/6 mean/min/max = 47.0688/33.6043/60.5332
[16:15:28.128] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 56.4119 for pixel 24/25 mean/min/max = 44.4131/32.339/56.4872
[16:15:28.128] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.1541 for pixel 51/10 mean/min/max = 44.4013/31.5889/57.2138
[16:15:28.128] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 55.2949 for pixel 12/46 mean/min/max = 43.8986/32.4883/55.309
[16:15:28.129] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 56.0907 for pixel 0/79 mean/min/max = 44.3084/32.5191/56.0977
[16:15:28.129] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 55.5405 for pixel 12/70 mean/min/max = 43.9351/32.2301/55.6401
[16:15:28.129] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 56.0576 for pixel 17/1 mean/min/max = 44.5918/33.0133/56.1702
[16:15:28.129] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.5003 for pixel 13/9 mean/min/max = 44.9943/32.431/57.5576
[16:15:28.130] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.7007 for pixel 2/4 mean/min/max = 46.4887/32.2181/60.7593
[16:15:28.130] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 61.7452 for pixel 18/9 mean/min/max = 47.0556/32.1648/61.9464
[16:15:28.130] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 61.2268 for pixel 6/15 mean/min/max = 46.5955/31.86/61.3311
[16:15:28.131] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.8924 for pixel 13/6 mean/min/max = 45.1667/32.3632/57.9702
[16:15:28.131] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 56.7597 for pixel 0/16 mean/min/max = 44.6773/32.4942/56.8603
[16:15:28.131] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.8228 for pixel 18/0 mean/min/max = 45.2597/31.6651/58.8542
[16:15:28.132] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.6145 for pixel 19/33 mean/min/max = 44.9262/33.1404/56.7119
[16:15:28.132] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.1918 for pixel 49/8 mean/min/max = 45.0935/32.925/57.2619
[16:15:28.132] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:15:28.221] <TB1> INFO: Expecting 514560 events.
[16:15:39.174] <TB1> INFO: 514560 events read in total (10397ms).
[16:15:39.181] <TB1> INFO: Expecting 514560 events.
[16:15:49.886] <TB1> INFO: 514560 events read in total (10303ms).
[16:15:49.895] <TB1> INFO: Expecting 514560 events.
[16:16:00.736] <TB1> INFO: 514560 events read in total (10439ms).
[16:16:00.751] <TB1> INFO: Expecting 514560 events.
[16:16:11.482] <TB1> INFO: 514560 events read in total (10329ms).
[16:16:11.497] <TB1> INFO: Expecting 514560 events.
[16:16:22.100] <TB1> INFO: 514560 events read in total (10201ms).
[16:16:22.117] <TB1> INFO: Expecting 514560 events.
[16:16:32.791] <TB1> INFO: 514560 events read in total (10272ms).
[16:16:32.813] <TB1> INFO: Expecting 514560 events.
[16:16:43.552] <TB1> INFO: 514560 events read in total (10337ms).
[16:16:43.574] <TB1> INFO: Expecting 514560 events.
[16:16:54.342] <TB1> INFO: 514560 events read in total (10366ms).
[16:16:54.367] <TB1> INFO: Expecting 514560 events.
[16:17:05.125] <TB1> INFO: 514560 events read in total (10356ms).
[16:17:05.160] <TB1> INFO: Expecting 514560 events.
[16:17:15.938] <TB1> INFO: 514560 events read in total (10376ms).
[16:17:15.977] <TB1> INFO: Expecting 514560 events.
[16:17:26.708] <TB1> INFO: 514560 events read in total (10330ms).
[16:17:26.751] <TB1> INFO: Expecting 514560 events.
[16:17:37.545] <TB1> INFO: 514560 events read in total (10392ms).
[16:17:37.585] <TB1> INFO: Expecting 514560 events.
[16:17:48.338] <TB1> INFO: 514560 events read in total (10351ms).
[16:17:48.376] <TB1> INFO: Expecting 514560 events.
[16:17:59.123] <TB1> INFO: 514560 events read in total (10345ms).
[16:17:59.172] <TB1> INFO: Expecting 514560 events.
[16:18:09.908] <TB1> INFO: 514560 events read in total (10334ms).
[16:18:09.956] <TB1> INFO: Expecting 514560 events.
[16:18:20.695] <TB1> INFO: 514560 events read in total (10337ms).
[16:18:20.751] <TB1> INFO: Test took 172619ms.
[16:18:21.974] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:18:21.983] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:18:21.983] <TB1> INFO: run 1 of 1
[16:18:22.272] <TB1> INFO: Expecting 6281600 events.
[16:18:53.292] <TB1> INFO: 745780 events read in total (30465ms).
[16:19:23.208] <TB1> INFO: 1490420 events read in total (60380ms).
[16:19:53.343] <TB1> INFO: 2235060 events read in total (90515ms).
[16:20:23.836] <TB1> INFO: 2980130 events read in total (121008ms).
[16:20:54.288] <TB1> INFO: 3720390 events read in total (151460ms).
[16:21:24.418] <TB1> INFO: 4457110 events read in total (181590ms).
[16:21:54.222] <TB1> INFO: 5191290 events read in total (211394ms).
[16:22:23.940] <TB1> INFO: 5924380 events read in total (241112ms).
[16:22:38.654] <TB1> INFO: 6281600 events read in total (255826ms).
[16:22:38.712] <TB1> INFO: Test took 256730ms.
[16:23:01.224] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 7.085159 .. 51.818833
[16:23:01.500] <TB1> INFO: Expecting 208000 events.
[16:23:11.366] <TB1> INFO: 208000 events read in total (9309ms).
[16:23:11.367] <TB1> INFO: Test took 10141ms.
[16:23:11.458] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 7 .. 61 (-1/-1) hits flags = 528 (plus default)
[16:23:11.466] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:23:11.466] <TB1> INFO: run 1 of 1
[16:23:11.734] <TB1> INFO: Expecting 2288000 events.
[16:23:45.563] <TB1> INFO: 910240 events read in total (33273ms).
[16:24:18.430] <TB1> INFO: 1815270 events read in total (66140ms).
[16:24:36.130] <TB1> INFO: 2288000 events read in total (83840ms).
[16:24:36.158] <TB1> INFO: Test took 84691ms.
[16:24:50.803] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 20.425317 .. 46.618350
[16:24:51.066] <TB1> INFO: Expecting 208000 events.
[16:25:00.787] <TB1> INFO: 208000 events read in total (9165ms).
[16:25:00.788] <TB1> INFO: Test took 9983ms.
[16:25:00.894] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 10 .. 56 (-1/-1) hits flags = 528 (plus default)
[16:25:00.902] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:25:00.902] <TB1> INFO: run 1 of 1
[16:25:01.196] <TB1> INFO: Expecting 1955200 events.
[16:25:35.453] <TB1> INFO: 918790 events read in total (33701ms).
[16:26:09.045] <TB1> INFO: 1835790 events read in total (67293ms).
[16:26:13.825] <TB1> INFO: 1955200 events read in total (72073ms).
[16:26:13.850] <TB1> INFO: Test took 72947ms.
[16:26:28.049] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 21.939600 .. 43.266465
[16:26:28.320] <TB1> INFO: Expecting 208000 events.
[16:26:38.114] <TB1> INFO: 208000 events read in total (9237ms).
[16:26:38.115] <TB1> INFO: Test took 10064ms.
[16:26:38.199] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 53 (-1/-1) hits flags = 528 (plus default)
[16:26:38.207] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:26:38.207] <TB1> INFO: run 1 of 1
[16:26:38.474] <TB1> INFO: Expecting 1788800 events.
[16:27:12.709] <TB1> INFO: 937030 events read in total (33679ms).
[16:27:43.702] <TB1> INFO: 1788800 events read in total (64672ms).
[16:27:43.720] <TB1> INFO: Test took 65512ms.
[16:27:56.631] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.499870 .. 42.770023
[16:27:56.906] <TB1> INFO: Expecting 208000 events.
[16:28:06.944] <TB1> INFO: 208000 events read in total (9482ms).
[16:28:06.944] <TB1> INFO: Test took 10312ms.
[16:28:07.056] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 52 (-1/-1) hits flags = 528 (plus default)
[16:28:07.064] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:28:07.064] <TB1> INFO: run 1 of 1
[16:28:07.341] <TB1> INFO: Expecting 1622400 events.
[16:28:42.131] <TB1> INFO: 927760 events read in total (34234ms).
[16:29:07.783] <TB1> INFO: 1622400 events read in total (59886ms).
[16:29:07.800] <TB1> INFO: Test took 60736ms.
[16:29:21.224] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:29:21.224] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:29:21.234] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:29:21.235] <TB1> INFO: run 1 of 1
[16:29:21.495] <TB1> INFO: Expecting 1705600 events.
[16:29:55.025] <TB1> INFO: 893400 events read in total (32974ms).
[16:30:25.241] <TB1> INFO: 1705600 events read in total (63190ms).
[16:30:25.258] <TB1> INFO: Test took 64023ms.
[16:30:38.444] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C0.dat
[16:30:38.444] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C1.dat
[16:30:38.444] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C2.dat
[16:30:38.444] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C3.dat
[16:30:38.444] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C4.dat
[16:30:38.444] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C5.dat
[16:30:38.444] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C6.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C7.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C8.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C9.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C10.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C11.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C12.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C13.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C14.dat
[16:30:38.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C15.dat
[16:30:38.445] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C0.dat
[16:30:38.452] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C1.dat
[16:30:38.458] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C2.dat
[16:30:38.464] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C3.dat
[16:30:38.471] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C4.dat
[16:30:38.477] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C5.dat
[16:30:38.483] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C6.dat
[16:30:38.489] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C7.dat
[16:30:38.496] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C8.dat
[16:30:38.502] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C9.dat
[16:30:38.508] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C10.dat
[16:30:38.515] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C11.dat
[16:30:38.521] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C12.dat
[16:30:38.527] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C13.dat
[16:30:38.534] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C14.dat
[16:30:38.540] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//trimParameters35_C15.dat
[16:30:38.546] <TB1> INFO: PixTestTrim::trimTest() done
[16:30:38.546] <TB1> INFO: vtrim: 108 101 122 92 89 94 102 102 97 134 119 103 105 111 103 106
[16:30:38.546] <TB1> INFO: vthrcomp: 105 91 99 91 83 84 93 94 88 92 88 81 91 96 92 89
[16:30:38.546] <TB1> INFO: vcal mean: 34.99 34.96 34.95 34.98 34.82 34.93 34.97 34.94 34.98 34.97 34.97 35.01 35.01 34.93 34.99 34.99
[16:30:38.546] <TB1> INFO: vcal RMS: 1.14 0.80 0.84 0.79 0.77 0.79 0.78 0.80 0.85 0.87 0.86 0.79 0.77 0.86 0.84 0.77
[16:30:38.546] <TB1> INFO: bits mean: 8.75 9.67 9.80 9.77 9.53 10.04 9.53 9.66 8.82 9.44 9.50 9.51 9.37 9.60 9.66 9.56
[16:30:38.546] <TB1> INFO: bits RMS: 2.65 2.62 2.67 2.57 2.69 2.48 2.56 2.58 2.88 2.60 2.66 2.64 2.74 2.70 2.47 2.53
[16:30:38.553] <TB1> INFO: ----------------------------------------------------------------------
[16:30:38.553] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:30:38.553] <TB1> INFO: ----------------------------------------------------------------------
[16:30:38.555] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:30:38.563] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:30:38.563] <TB1> INFO: run 1 of 1
[16:30:38.823] <TB1> INFO: Expecting 4160000 events.
[16:31:16.217] <TB1> INFO: 908145 events read in total (36837ms).
[16:31:53.819] <TB1> INFO: 1811490 events read in total (74439ms).
[16:32:30.039] <TB1> INFO: 2708905 events read in total (110659ms).
[16:33:06.696] <TB1> INFO: 3603885 events read in total (147316ms).
[16:33:29.886] <TB1> INFO: 4160000 events read in total (170506ms).
[16:33:29.941] <TB1> INFO: Test took 171377ms.
[16:33:59.196] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 159 (-1/-1) hits flags = 528 (plus default)
[16:33:59.205] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:33:59.205] <TB1> INFO: run 1 of 1
[16:33:59.466] <TB1> INFO: Expecting 3328000 events.
[16:34:37.557] <TB1> INFO: 959060 events read in total (37535ms).
[16:35:15.862] <TB1> INFO: 1909810 events read in total (75840ms).
[16:35:54.030] <TB1> INFO: 2851880 events read in total (114008ms).
[16:36:13.162] <TB1> INFO: 3328000 events read in total (133140ms).
[16:36:13.232] <TB1> INFO: Test took 134027ms.
[16:36:39.650] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 147 (-1/-1) hits flags = 528 (plus default)
[16:36:39.658] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:36:39.659] <TB1> INFO: run 1 of 1
[16:36:39.924] <TB1> INFO: Expecting 3078400 events.
[16:37:18.987] <TB1> INFO: 993425 events read in total (38507ms).
[16:37:58.099] <TB1> INFO: 1975950 events read in total (77619ms).
[16:38:36.512] <TB1> INFO: 2950865 events read in total (116032ms).
[16:38:41.749] <TB1> INFO: 3078400 events read in total (121269ms).
[16:38:41.797] <TB1> INFO: Test took 122139ms.
[16:39:05.044] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[16:39:05.052] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:39:05.052] <TB1> INFO: run 1 of 1
[16:39:05.329] <TB1> INFO: Expecting 3120000 events.
[16:39:43.835] <TB1> INFO: 985625 events read in total (37949ms).
[16:40:21.245] <TB1> INFO: 1960700 events read in total (75359ms).
[16:40:58.961] <TB1> INFO: 2928125 events read in total (113075ms).
[16:41:06.630] <TB1> INFO: 3120000 events read in total (120744ms).
[16:41:06.678] <TB1> INFO: Test took 121627ms.
[16:41:30.456] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 148 (-1/-1) hits flags = 528 (plus default)
[16:41:30.464] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:41:30.464] <TB1> INFO: run 1 of 1
[16:41:30.725] <TB1> INFO: Expecting 3099200 events.
[16:42:09.326] <TB1> INFO: 987615 events read in total (38045ms).
[16:42:47.371] <TB1> INFO: 1964405 events read in total (76090ms).
[16:43:24.741] <TB1> INFO: 2933640 events read in total (113461ms).
[16:43:31.540] <TB1> INFO: 3099200 events read in total (120259ms).
[16:43:31.583] <TB1> INFO: Test took 121119ms.
[16:43:54.781] <TB1> INFO: PixTestTrim::trimBitTest() done
[16:43:54.782] <TB1> INFO: PixTestTrim::doTest() done, duration: 2215 seconds
[16:43:54.782] <TB1> INFO: Decoding statistics:
[16:43:54.782] <TB1> INFO: General information:
[16:43:54.782] <TB1> INFO: 16bit words read: 0
[16:43:54.782] <TB1> INFO: valid events total: 0
[16:43:54.782] <TB1> INFO: empty events: 0
[16:43:54.782] <TB1> INFO: valid events with pixels: 0
[16:43:54.782] <TB1> INFO: valid pixel hits: 0
[16:43:54.782] <TB1> INFO: Event errors: 0
[16:43:54.782] <TB1> INFO: start marker: 0
[16:43:54.782] <TB1> INFO: stop marker: 0
[16:43:54.782] <TB1> INFO: overflow: 0
[16:43:54.782] <TB1> INFO: invalid 5bit words: 0
[16:43:54.782] <TB1> INFO: invalid XOR eye diagram: 0
[16:43:54.782] <TB1> INFO: frame (failed synchr.): 0
[16:43:54.782] <TB1> INFO: idle data (no TBM trl): 0
[16:43:54.782] <TB1> INFO: no data (only TBM hdr): 0
[16:43:54.782] <TB1> INFO: TBM errors: 0
[16:43:54.782] <TB1> INFO: flawed TBM headers: 0
[16:43:54.782] <TB1> INFO: flawed TBM trailers: 0
[16:43:54.782] <TB1> INFO: event ID mismatches: 0
[16:43:54.782] <TB1> INFO: ROC errors: 0
[16:43:54.782] <TB1> INFO: missing ROC header(s): 0
[16:43:54.782] <TB1> INFO: misplaced readback start: 0
[16:43:54.782] <TB1> INFO: Pixel decoding errors: 0
[16:43:54.782] <TB1> INFO: pixel data incomplete: 0
[16:43:54.782] <TB1> INFO: pixel address: 0
[16:43:54.782] <TB1> INFO: pulse height fill bit: 0
[16:43:54.782] <TB1> INFO: buffer corruption: 0
[16:43:55.494] <TB1> INFO: ######################################################################
[16:43:55.494] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:43:55.494] <TB1> INFO: ######################################################################
[16:43:55.764] <TB1> INFO: Expecting 41600 events.
[16:43:59.194] <TB1> INFO: 41600 events read in total (2874ms).
[16:43:59.194] <TB1> INFO: Test took 3698ms.
[16:43:59.669] <TB1> INFO: Expecting 41600 events.
[16:44:03.159] <TB1> INFO: 41600 events read in total (2934ms).
[16:44:03.160] <TB1> INFO: Test took 3777ms.
[16:44:03.473] <TB1> INFO: Expecting 41600 events.
[16:44:06.993] <TB1> INFO: 41600 events read in total (2964ms).
[16:44:06.994] <TB1> INFO: Test took 3812ms.
[16:44:07.251] <TB1> INFO: Expecting 2560 events.
[16:44:08.104] <TB1> INFO: 2560 events read in total (297ms).
[16:44:08.104] <TB1> INFO: Test took 1095ms.
[16:44:08.409] <TB1> INFO: Expecting 2560 events.
[16:44:09.269] <TB1> INFO: 2560 events read in total (299ms).
[16:44:09.269] <TB1> INFO: Test took 1165ms.
[16:44:09.574] <TB1> INFO: Expecting 2560 events.
[16:44:10.426] <TB1> INFO: 2560 events read in total (296ms).
[16:44:10.426] <TB1> INFO: Test took 1156ms.
[16:44:10.730] <TB1> INFO: Expecting 2560 events.
[16:44:11.584] <TB1> INFO: 2560 events read in total (297ms).
[16:44:11.584] <TB1> INFO: Test took 1158ms.
[16:44:11.889] <TB1> INFO: Expecting 2560 events.
[16:44:12.741] <TB1> INFO: 2560 events read in total (295ms).
[16:44:12.741] <TB1> INFO: Test took 1156ms.
[16:44:13.047] <TB1> INFO: Expecting 2560 events.
[16:44:13.898] <TB1> INFO: 2560 events read in total (295ms).
[16:44:13.898] <TB1> INFO: Test took 1156ms.
[16:44:14.203] <TB1> INFO: Expecting 2560 events.
[16:44:15.056] <TB1> INFO: 2560 events read in total (296ms).
[16:44:15.057] <TB1> INFO: Test took 1158ms.
[16:44:15.362] <TB1> INFO: Expecting 2560 events.
[16:44:16.214] <TB1> INFO: 2560 events read in total (296ms).
[16:44:16.214] <TB1> INFO: Test took 1157ms.
[16:44:16.520] <TB1> INFO: Expecting 2560 events.
[16:44:17.371] <TB1> INFO: 2560 events read in total (295ms).
[16:44:17.371] <TB1> INFO: Test took 1157ms.
[16:44:17.676] <TB1> INFO: Expecting 2560 events.
[16:44:18.528] <TB1> INFO: 2560 events read in total (296ms).
[16:44:18.528] <TB1> INFO: Test took 1156ms.
[16:44:18.833] <TB1> INFO: Expecting 2560 events.
[16:44:19.685] <TB1> INFO: 2560 events read in total (296ms).
[16:44:19.685] <TB1> INFO: Test took 1157ms.
[16:44:19.990] <TB1> INFO: Expecting 2560 events.
[16:44:20.842] <TB1> INFO: 2560 events read in total (296ms).
[16:44:20.842] <TB1> INFO: Test took 1157ms.
[16:44:21.147] <TB1> INFO: Expecting 2560 events.
[16:44:21.999] <TB1> INFO: 2560 events read in total (296ms).
[16:44:21.999] <TB1> INFO: Test took 1156ms.
[16:44:22.304] <TB1> INFO: Expecting 2560 events.
[16:44:23.164] <TB1> INFO: 2560 events read in total (304ms).
[16:44:23.164] <TB1> INFO: Test took 1165ms.
[16:44:23.469] <TB1> INFO: Expecting 2560 events.
[16:44:24.322] <TB1> INFO: 2560 events read in total (297ms).
[16:44:24.323] <TB1> INFO: Test took 1158ms.
[16:44:24.628] <TB1> INFO: Expecting 2560 events.
[16:44:25.488] <TB1> INFO: 2560 events read in total (304ms).
[16:44:25.489] <TB1> INFO: Test took 1166ms.
[16:44:25.492] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:44:25.794] <TB1> INFO: Expecting 655360 events.
[16:44:39.680] <TB1> INFO: 655360 events read in total (13329ms).
[16:44:39.692] <TB1> INFO: Expecting 655360 events.
[16:44:53.461] <TB1> INFO: 655360 events read in total (13367ms).
[16:44:53.476] <TB1> INFO: Expecting 655360 events.
[16:45:07.365] <TB1> INFO: 655360 events read in total (13487ms).
[16:45:07.389] <TB1> INFO: Expecting 655360 events.
[16:45:21.203] <TB1> INFO: 655360 events read in total (13412ms).
[16:45:21.226] <TB1> INFO: Expecting 655360 events.
[16:45:35.049] <TB1> INFO: 655360 events read in total (13421ms).
[16:45:35.085] <TB1> INFO: Expecting 655360 events.
[16:45:48.799] <TB1> INFO: 655360 events read in total (13313ms).
[16:45:48.838] <TB1> INFO: Expecting 655360 events.
[16:46:02.654] <TB1> INFO: 655360 events read in total (13414ms).
[16:46:02.695] <TB1> INFO: Expecting 655360 events.
[16:46:16.477] <TB1> INFO: 655360 events read in total (13380ms).
[16:46:16.516] <TB1> INFO: Expecting 655360 events.
[16:46:30.310] <TB1> INFO: 655360 events read in total (13392ms).
[16:46:30.357] <TB1> INFO: Expecting 655360 events.
[16:46:44.129] <TB1> INFO: 655360 events read in total (13371ms).
[16:46:44.189] <TB1> INFO: Expecting 655360 events.
[16:46:58.059] <TB1> INFO: 655360 events read in total (13468ms).
[16:46:58.113] <TB1> INFO: Expecting 655360 events.
[16:47:12.064] <TB1> INFO: 655360 events read in total (13549ms).
[16:47:12.120] <TB1> INFO: Expecting 655360 events.
[16:47:25.932] <TB1> INFO: 655360 events read in total (13410ms).
[16:47:25.990] <TB1> INFO: Expecting 655360 events.
[16:47:39.653] <TB1> INFO: 655360 events read in total (13261ms).
[16:47:39.736] <TB1> INFO: Expecting 655360 events.
[16:47:53.487] <TB1> INFO: 655360 events read in total (13349ms).
[16:47:53.579] <TB1> INFO: Expecting 655360 events.
[16:48:07.347] <TB1> INFO: 655360 events read in total (13366ms).
[16:48:07.420] <TB1> INFO: Test took 221928ms.
[16:48:07.498] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:48:07.730] <TB1> INFO: Expecting 655360 events.
[16:48:21.540] <TB1> INFO: 655360 events read in total (13254ms).
[16:48:21.550] <TB1> INFO: Expecting 655360 events.
[16:48:35.388] <TB1> INFO: 655360 events read in total (13436ms).
[16:48:35.407] <TB1> INFO: Expecting 655360 events.
[16:48:49.233] <TB1> INFO: 655360 events read in total (13424ms).
[16:48:49.254] <TB1> INFO: Expecting 655360 events.
[16:49:02.878] <TB1> INFO: 655360 events read in total (13223ms).
[16:49:02.900] <TB1> INFO: Expecting 655360 events.
[16:49:16.730] <TB1> INFO: 655360 events read in total (13428ms).
[16:49:16.755] <TB1> INFO: Expecting 655360 events.
[16:49:30.485] <TB1> INFO: 655360 events read in total (13328ms).
[16:49:30.526] <TB1> INFO: Expecting 655360 events.
[16:49:44.139] <TB1> INFO: 655360 events read in total (13211ms).
[16:49:44.176] <TB1> INFO: Expecting 655360 events.
[16:49:57.921] <TB1> INFO: 655360 events read in total (13343ms).
[16:49:57.960] <TB1> INFO: Expecting 655360 events.
[16:50:11.676] <TB1> INFO: 655360 events read in total (13314ms).
[16:50:11.723] <TB1> INFO: Expecting 655360 events.
[16:50:25.439] <TB1> INFO: 655360 events read in total (13314ms).
[16:50:25.485] <TB1> INFO: Expecting 655360 events.
[16:50:39.245] <TB1> INFO: 655360 events read in total (13358ms).
[16:50:39.298] <TB1> INFO: Expecting 655360 events.
[16:50:53.039] <TB1> INFO: 655360 events read in total (13340ms).
[16:50:53.107] <TB1> INFO: Expecting 655360 events.
[16:51:06.864] <TB1> INFO: 655360 events read in total (13355ms).
[16:51:06.938] <TB1> INFO: Expecting 655360 events.
[16:51:20.638] <TB1> INFO: 655360 events read in total (13298ms).
[16:51:20.701] <TB1> INFO: Expecting 655360 events.
[16:51:34.602] <TB1> INFO: 655360 events read in total (13500ms).
[16:51:34.668] <TB1> INFO: Expecting 655360 events.
[16:51:48.508] <TB1> INFO: 655360 events read in total (13438ms).
[16:51:48.578] <TB1> INFO: Test took 221080ms.
[16:51:48.765] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.772] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.780] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.788] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:51:48.795] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.801] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.808] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.817] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.826] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.834] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.843] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.852] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.861] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.870] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.879] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.887] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.896] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:51:48.939] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C0.dat
[16:51:48.939] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C1.dat
[16:51:48.939] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C2.dat
[16:51:48.939] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C3.dat
[16:51:48.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C4.dat
[16:51:48.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C5.dat
[16:51:48.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C6.dat
[16:51:48.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C7.dat
[16:51:48.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C8.dat
[16:51:48.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C9.dat
[16:51:48.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C10.dat
[16:51:48.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C11.dat
[16:51:48.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C12.dat
[16:51:48.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C13.dat
[16:51:48.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C14.dat
[16:51:48.942] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//dacParameters35_C15.dat
[16:51:49.221] <TB1> INFO: Expecting 41600 events.
[16:51:52.391] <TB1> INFO: 41600 events read in total (2613ms).
[16:51:52.392] <TB1> INFO: Test took 3446ms.
[16:51:52.865] <TB1> INFO: Expecting 41600 events.
[16:51:55.939] <TB1> INFO: 41600 events read in total (2517ms).
[16:51:55.940] <TB1> INFO: Test took 3367ms.
[16:51:56.378] <TB1> INFO: Expecting 41600 events.
[16:51:59.513] <TB1> INFO: 41600 events read in total (2579ms).
[16:51:59.514] <TB1> INFO: Test took 3395ms.
[16:51:59.697] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:59.786] <TB1> INFO: Expecting 2560 events.
[16:52:00.637] <TB1> INFO: 2560 events read in total (295ms).
[16:52:00.638] <TB1> INFO: Test took 941ms.
[16:52:00.639] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:00.943] <TB1> INFO: Expecting 2560 events.
[16:52:01.795] <TB1> INFO: 2560 events read in total (296ms).
[16:52:01.796] <TB1> INFO: Test took 1157ms.
[16:52:01.797] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:02.101] <TB1> INFO: Expecting 2560 events.
[16:52:02.954] <TB1> INFO: 2560 events read in total (296ms).
[16:52:02.954] <TB1> INFO: Test took 1157ms.
[16:52:02.961] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:03.260] <TB1> INFO: Expecting 2560 events.
[16:52:04.115] <TB1> INFO: 2560 events read in total (298ms).
[16:52:04.116] <TB1> INFO: Test took 1155ms.
[16:52:04.118] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:04.421] <TB1> INFO: Expecting 2560 events.
[16:52:05.275] <TB1> INFO: 2560 events read in total (298ms).
[16:52:05.276] <TB1> INFO: Test took 1158ms.
[16:52:05.277] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:05.582] <TB1> INFO: Expecting 2560 events.
[16:52:06.437] <TB1> INFO: 2560 events read in total (299ms).
[16:52:06.438] <TB1> INFO: Test took 1161ms.
[16:52:06.440] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:06.743] <TB1> INFO: Expecting 2560 events.
[16:52:07.596] <TB1> INFO: 2560 events read in total (297ms).
[16:52:07.596] <TB1> INFO: Test took 1156ms.
[16:52:07.598] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:07.902] <TB1> INFO: Expecting 2560 events.
[16:52:08.756] <TB1> INFO: 2560 events read in total (297ms).
[16:52:08.756] <TB1> INFO: Test took 1158ms.
[16:52:08.758] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:09.062] <TB1> INFO: Expecting 2560 events.
[16:52:09.914] <TB1> INFO: 2560 events read in total (295ms).
[16:52:09.915] <TB1> INFO: Test took 1157ms.
[16:52:09.917] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:10.220] <TB1> INFO: Expecting 2560 events.
[16:52:11.072] <TB1> INFO: 2560 events read in total (296ms).
[16:52:11.072] <TB1> INFO: Test took 1155ms.
[16:52:11.074] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:11.377] <TB1> INFO: Expecting 2560 events.
[16:52:12.232] <TB1> INFO: 2560 events read in total (298ms).
[16:52:12.232] <TB1> INFO: Test took 1158ms.
[16:52:12.234] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:12.538] <TB1> INFO: Expecting 2560 events.
[16:52:13.395] <TB1> INFO: 2560 events read in total (301ms).
[16:52:13.396] <TB1> INFO: Test took 1162ms.
[16:52:13.401] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:13.699] <TB1> INFO: Expecting 2560 events.
[16:52:14.553] <TB1> INFO: 2560 events read in total (298ms).
[16:52:14.553] <TB1> INFO: Test took 1152ms.
[16:52:14.555] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:14.859] <TB1> INFO: Expecting 2560 events.
[16:52:15.713] <TB1> INFO: 2560 events read in total (298ms).
[16:52:15.714] <TB1> INFO: Test took 1159ms.
[16:52:15.717] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:16.019] <TB1> INFO: Expecting 2560 events.
[16:52:16.870] <TB1> INFO: 2560 events read in total (295ms).
[16:52:16.871] <TB1> INFO: Test took 1154ms.
[16:52:16.872] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:17.176] <TB1> INFO: Expecting 2560 events.
[16:52:18.033] <TB1> INFO: 2560 events read in total (300ms).
[16:52:18.033] <TB1> INFO: Test took 1161ms.
[16:52:18.035] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:18.339] <TB1> INFO: Expecting 2560 events.
[16:52:19.200] <TB1> INFO: 2560 events read in total (305ms).
[16:52:19.201] <TB1> INFO: Test took 1166ms.
[16:52:19.204] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:19.506] <TB1> INFO: Expecting 2560 events.
[16:52:20.365] <TB1> INFO: 2560 events read in total (303ms).
[16:52:20.365] <TB1> INFO: Test took 1162ms.
[16:52:20.368] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:20.671] <TB1> INFO: Expecting 2560 events.
[16:52:21.525] <TB1> INFO: 2560 events read in total (297ms).
[16:52:21.525] <TB1> INFO: Test took 1157ms.
[16:52:21.527] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:21.832] <TB1> INFO: Expecting 2560 events.
[16:52:22.683] <TB1> INFO: 2560 events read in total (295ms).
[16:52:22.684] <TB1> INFO: Test took 1157ms.
[16:52:22.686] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:22.990] <TB1> INFO: Expecting 2560 events.
[16:52:23.843] <TB1> INFO: 2560 events read in total (297ms).
[16:52:23.843] <TB1> INFO: Test took 1157ms.
[16:52:23.859] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:24.148] <TB1> INFO: Expecting 2560 events.
[16:52:25.001] <TB1> INFO: 2560 events read in total (296ms).
[16:52:25.001] <TB1> INFO: Test took 1142ms.
[16:52:25.003] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:25.305] <TB1> INFO: Expecting 2560 events.
[16:52:26.163] <TB1> INFO: 2560 events read in total (301ms).
[16:52:26.164] <TB1> INFO: Test took 1161ms.
[16:52:26.166] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:26.469] <TB1> INFO: Expecting 2560 events.
[16:52:27.324] <TB1> INFO: 2560 events read in total (299ms).
[16:52:27.324] <TB1> INFO: Test took 1158ms.
[16:52:27.327] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:27.628] <TB1> INFO: Expecting 2560 events.
[16:52:28.481] <TB1> INFO: 2560 events read in total (297ms).
[16:52:28.481] <TB1> INFO: Test took 1154ms.
[16:52:28.483] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:28.787] <TB1> INFO: Expecting 2560 events.
[16:52:29.642] <TB1> INFO: 2560 events read in total (299ms).
[16:52:29.642] <TB1> INFO: Test took 1159ms.
[16:52:29.646] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:29.947] <TB1> INFO: Expecting 2560 events.
[16:52:30.799] <TB1> INFO: 2560 events read in total (296ms).
[16:52:30.799] <TB1> INFO: Test took 1154ms.
[16:52:30.801] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:31.104] <TB1> INFO: Expecting 2560 events.
[16:52:31.957] <TB1> INFO: 2560 events read in total (296ms).
[16:52:31.958] <TB1> INFO: Test took 1157ms.
[16:52:31.960] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:32.263] <TB1> INFO: Expecting 2560 events.
[16:52:33.125] <TB1> INFO: 2560 events read in total (305ms).
[16:52:33.125] <TB1> INFO: Test took 1165ms.
[16:52:33.128] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:33.431] <TB1> INFO: Expecting 2560 events.
[16:52:34.285] <TB1> INFO: 2560 events read in total (298ms).
[16:52:34.285] <TB1> INFO: Test took 1158ms.
[16:52:34.292] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:34.590] <TB1> INFO: Expecting 2560 events.
[16:52:35.445] <TB1> INFO: 2560 events read in total (298ms).
[16:52:35.445] <TB1> INFO: Test took 1153ms.
[16:52:35.447] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:52:35.750] <TB1> INFO: Expecting 2560 events.
[16:52:36.607] <TB1> INFO: 2560 events read in total (301ms).
[16:52:36.607] <TB1> INFO: Test took 1160ms.
[16:52:37.093] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 521 seconds
[16:52:37.093] <TB1> INFO: PH scale (per ROC): 87 82 103 86 94 95 84 80 83 80 91 87 87 76 89 81
[16:52:37.093] <TB1> INFO: PH offset (per ROC): 154 147 144 145 142 162 133 169 149 143 147 139 157 165 145 150
[16:52:37.100] <TB1> INFO: Decoding statistics:
[16:52:37.100] <TB1> INFO: General information:
[16:52:37.100] <TB1> INFO: 16bit words read: 86922
[16:52:37.100] <TB1> INFO: valid events total: 10240
[16:52:37.100] <TB1> INFO: empty events: 7739
[16:52:37.100] <TB1> INFO: valid events with pixels: 2501
[16:52:37.100] <TB1> INFO: valid pixel hits: 2501
[16:52:37.100] <TB1> INFO: Event errors: 0
[16:52:37.100] <TB1> INFO: start marker: 0
[16:52:37.100] <TB1> INFO: stop marker: 0
[16:52:37.100] <TB1> INFO: overflow: 0
[16:52:37.100] <TB1> INFO: invalid 5bit words: 0
[16:52:37.100] <TB1> INFO: invalid XOR eye diagram: 0
[16:52:37.100] <TB1> INFO: frame (failed synchr.): 0
[16:52:37.100] <TB1> INFO: idle data (no TBM trl): 0
[16:52:37.100] <TB1> INFO: no data (only TBM hdr): 0
[16:52:37.100] <TB1> INFO: TBM errors: 0
[16:52:37.100] <TB1> INFO: flawed TBM headers: 0
[16:52:37.100] <TB1> INFO: flawed TBM trailers: 0
[16:52:37.100] <TB1> INFO: event ID mismatches: 0
[16:52:37.100] <TB1> INFO: ROC errors: 0
[16:52:37.100] <TB1> INFO: missing ROC header(s): 0
[16:52:37.100] <TB1> INFO: misplaced readback start: 0
[16:52:37.100] <TB1> INFO: Pixel decoding errors: 0
[16:52:37.100] <TB1> INFO: pixel data incomplete: 0
[16:52:37.100] <TB1> INFO: pixel address: 0
[16:52:37.100] <TB1> INFO: pulse height fill bit: 0
[16:52:37.100] <TB1> INFO: buffer corruption: 0
[16:52:37.315] <TB1> INFO: ######################################################################
[16:52:37.315] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:52:37.315] <TB1> INFO: ######################################################################
[16:52:37.324] <TB1> INFO: scanning low vcal = 10
[16:52:37.586] <TB1> INFO: Expecting 41600 events.
[16:52:40.959] <TB1> INFO: 41600 events read in total (2817ms).
[16:52:40.959] <TB1> INFO: Test took 3634ms.
[16:52:40.961] <TB1> INFO: scanning low vcal = 20
[16:52:41.257] <TB1> INFO: Expecting 41600 events.
[16:52:44.643] <TB1> INFO: 41600 events read in total (2830ms).
[16:52:44.643] <TB1> INFO: Test took 3682ms.
[16:52:44.644] <TB1> INFO: scanning low vcal = 30
[16:52:44.941] <TB1> INFO: Expecting 41600 events.
[16:52:48.368] <TB1> INFO: 41600 events read in total (2870ms).
[16:52:48.369] <TB1> INFO: Test took 3724ms.
[16:52:48.371] <TB1> INFO: scanning low vcal = 40
[16:52:48.664] <TB1> INFO: Expecting 41600 events.
[16:52:52.497] <TB1> INFO: 41600 events read in total (3277ms).
[16:52:52.498] <TB1> INFO: Test took 4127ms.
[16:52:52.501] <TB1> INFO: scanning low vcal = 50
[16:52:52.761] <TB1> INFO: Expecting 41600 events.
[16:52:56.683] <TB1> INFO: 41600 events read in total (3366ms).
[16:52:56.684] <TB1> INFO: Test took 4183ms.
[16:52:56.687] <TB1> INFO: scanning low vcal = 60
[16:52:56.950] <TB1> INFO: Expecting 41600 events.
[16:53:00.872] <TB1> INFO: 41600 events read in total (3366ms).
[16:53:00.872] <TB1> INFO: Test took 4185ms.
[16:53:00.875] <TB1> INFO: scanning low vcal = 70
[16:53:01.138] <TB1> INFO: Expecting 41600 events.
[16:53:05.108] <TB1> INFO: 41600 events read in total (3413ms).
[16:53:05.108] <TB1> INFO: Test took 4233ms.
[16:53:05.111] <TB1> INFO: scanning low vcal = 80
[16:53:05.407] <TB1> INFO: Expecting 41600 events.
[16:53:09.336] <TB1> INFO: 41600 events read in total (3372ms).
[16:53:09.337] <TB1> INFO: Test took 4226ms.
[16:53:09.339] <TB1> INFO: scanning low vcal = 90
[16:53:09.603] <TB1> INFO: Expecting 41600 events.
[16:53:13.561] <TB1> INFO: 41600 events read in total (3402ms).
[16:53:13.562] <TB1> INFO: Test took 4223ms.
[16:53:13.565] <TB1> INFO: scanning low vcal = 100
[16:53:13.825] <TB1> INFO: Expecting 41600 events.
[16:53:17.810] <TB1> INFO: 41600 events read in total (3429ms).
[16:53:17.810] <TB1> INFO: Test took 4245ms.
[16:53:17.813] <TB1> INFO: scanning low vcal = 110
[16:53:18.098] <TB1> INFO: Expecting 41600 events.
[16:53:22.053] <TB1> INFO: 41600 events read in total (3399ms).
[16:53:22.053] <TB1> INFO: Test took 4240ms.
[16:53:22.056] <TB1> INFO: scanning low vcal = 120
[16:53:22.320] <TB1> INFO: Expecting 41600 events.
[16:53:26.282] <TB1> INFO: 41600 events read in total (3405ms).
[16:53:26.283] <TB1> INFO: Test took 4227ms.
[16:53:26.285] <TB1> INFO: scanning low vcal = 130
[16:53:26.562] <TB1> INFO: Expecting 41600 events.
[16:53:30.512] <TB1> INFO: 41600 events read in total (3394ms).
[16:53:30.512] <TB1> INFO: Test took 4226ms.
[16:53:30.515] <TB1> INFO: scanning low vcal = 140
[16:53:30.775] <TB1> INFO: Expecting 41600 events.
[16:53:34.727] <TB1> INFO: 41600 events read in total (3396ms).
[16:53:34.727] <TB1> INFO: Test took 4212ms.
[16:53:34.730] <TB1> INFO: scanning low vcal = 150
[16:53:35.030] <TB1> INFO: Expecting 41600 events.
[16:53:38.989] <TB1> INFO: 41600 events read in total (3402ms).
[16:53:38.990] <TB1> INFO: Test took 4260ms.
[16:53:38.992] <TB1> INFO: scanning low vcal = 160
[16:53:39.257] <TB1> INFO: Expecting 41600 events.
[16:53:43.190] <TB1> INFO: 41600 events read in total (3377ms).
[16:53:43.191] <TB1> INFO: Test took 4199ms.
[16:53:43.194] <TB1> INFO: scanning low vcal = 170
[16:53:43.470] <TB1> INFO: Expecting 41600 events.
[16:53:47.415] <TB1> INFO: 41600 events read in total (3389ms).
[16:53:47.416] <TB1> INFO: Test took 4222ms.
[16:53:47.420] <TB1> INFO: scanning low vcal = 180
[16:53:47.679] <TB1> INFO: Expecting 41600 events.
[16:53:51.633] <TB1> INFO: 41600 events read in total (3397ms).
[16:53:51.634] <TB1> INFO: Test took 4214ms.
[16:53:51.637] <TB1> INFO: scanning low vcal = 190
[16:53:51.932] <TB1> INFO: Expecting 41600 events.
[16:53:55.931] <TB1> INFO: 41600 events read in total (3443ms).
[16:53:55.932] <TB1> INFO: Test took 4295ms.
[16:53:55.937] <TB1> INFO: scanning low vcal = 200
[16:53:56.205] <TB1> INFO: Expecting 41600 events.
[16:54:00.171] <TB1> INFO: 41600 events read in total (3409ms).
[16:54:00.172] <TB1> INFO: Test took 4235ms.
[16:54:00.174] <TB1> INFO: scanning low vcal = 210
[16:54:00.533] <TB1> INFO: Expecting 41600 events.
[16:54:04.462] <TB1> INFO: 41600 events read in total (3373ms).
[16:54:04.463] <TB1> INFO: Test took 4289ms.
[16:54:04.465] <TB1> INFO: scanning low vcal = 220
[16:54:04.727] <TB1> INFO: Expecting 41600 events.
[16:54:08.661] <TB1> INFO: 41600 events read in total (3378ms).
[16:54:08.661] <TB1> INFO: Test took 4197ms.
[16:54:08.665] <TB1> INFO: scanning low vcal = 230
[16:54:08.921] <TB1> INFO: Expecting 41600 events.
[16:54:12.859] <TB1> INFO: 41600 events read in total (3382ms).
[16:54:12.860] <TB1> INFO: Test took 4195ms.
[16:54:12.863] <TB1> INFO: scanning low vcal = 240
[16:54:13.130] <TB1> INFO: Expecting 41600 events.
[16:54:17.095] <TB1> INFO: 41600 events read in total (3408ms).
[16:54:17.095] <TB1> INFO: Test took 4232ms.
[16:54:17.098] <TB1> INFO: scanning low vcal = 250
[16:54:17.358] <TB1> INFO: Expecting 41600 events.
[16:54:21.279] <TB1> INFO: 41600 events read in total (3365ms).
[16:54:21.280] <TB1> INFO: Test took 4182ms.
[16:54:21.284] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[16:54:21.544] <TB1> INFO: Expecting 41600 events.
[16:54:25.521] <TB1> INFO: 41600 events read in total (3421ms).
[16:54:25.522] <TB1> INFO: Test took 4238ms.
[16:54:25.525] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[16:54:25.786] <TB1> INFO: Expecting 41600 events.
[16:54:29.740] <TB1> INFO: 41600 events read in total (3397ms).
[16:54:29.741] <TB1> INFO: Test took 4215ms.
[16:54:29.744] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[16:54:30.040] <TB1> INFO: Expecting 41600 events.
[16:54:33.975] <TB1> INFO: 41600 events read in total (3378ms).
[16:54:33.975] <TB1> INFO: Test took 4231ms.
[16:54:33.978] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[16:54:34.236] <TB1> INFO: Expecting 41600 events.
[16:54:38.196] <TB1> INFO: 41600 events read in total (3403ms).
[16:54:38.196] <TB1> INFO: Test took 4217ms.
[16:54:38.199] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:54:38.458] <TB1> INFO: Expecting 41600 events.
[16:54:42.410] <TB1> INFO: 41600 events read in total (3395ms).
[16:54:42.411] <TB1> INFO: Test took 4212ms.
[16:54:42.888] <TB1> INFO: PixTestGainPedestal::measure() done
[16:55:15.603] <TB1> INFO: PixTestGainPedestal::fit() done
[16:55:15.603] <TB1> INFO: non-linearity mean: 0.954 0.951 0.956 0.954 0.953 0.954 0.952 0.943 0.955 0.956 0.957 0.950 0.949 0.953 0.954 0.955
[16:55:15.603] <TB1> INFO: non-linearity RMS: 0.005 0.006 0.005 0.005 0.006 0.007 0.006 0.006 0.006 0.006 0.006 0.007 0.006 0.004 0.006 0.006
[16:55:15.603] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[16:55:15.622] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[16:55:15.640] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[16:55:15.663] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[16:55:15.681] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[16:55:15.700] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[16:55:15.718] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[16:55:15.736] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[16:55:15.755] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[16:55:15.773] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[16:55:15.791] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[16:55:15.809] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[16:55:15.827] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[16:55:15.846] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[16:55:15.865] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[16:55:15.884] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[16:55:15.902] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[16:55:15.903] <TB1> INFO: Decoding statistics:
[16:55:15.903] <TB1> INFO: General information:
[16:55:15.903] <TB1> INFO: 16bit words read: 2662360
[16:55:15.903] <TB1> INFO: valid events total: 166400
[16:55:15.903] <TB1> INFO: empty events: 0
[16:55:15.903] <TB1> INFO: valid events with pixels: 166400
[16:55:15.903] <TB1> INFO: valid pixel hits: 665580
[16:55:15.903] <TB1> INFO: Event errors: 0
[16:55:15.903] <TB1> INFO: start marker: 0
[16:55:15.903] <TB1> INFO: stop marker: 0
[16:55:15.903] <TB1> INFO: overflow: 0
[16:55:15.903] <TB1> INFO: invalid 5bit words: 0
[16:55:15.903] <TB1> INFO: invalid XOR eye diagram: 0
[16:55:15.903] <TB1> INFO: frame (failed synchr.): 0
[16:55:15.903] <TB1> INFO: idle data (no TBM trl): 0
[16:55:15.903] <TB1> INFO: no data (only TBM hdr): 0
[16:55:15.903] <TB1> INFO: TBM errors: 0
[16:55:15.903] <TB1> INFO: flawed TBM headers: 0
[16:55:15.903] <TB1> INFO: flawed TBM trailers: 0
[16:55:15.903] <TB1> INFO: event ID mismatches: 0
[16:55:15.903] <TB1> INFO: ROC errors: 0
[16:55:15.903] <TB1> INFO: missing ROC header(s): 0
[16:55:15.903] <TB1> INFO: misplaced readback start: 0
[16:55:15.903] <TB1> INFO: Pixel decoding errors: 0
[16:55:15.903] <TB1> INFO: pixel data incomplete: 0
[16:55:15.903] <TB1> INFO: pixel address: 0
[16:55:15.903] <TB1> INFO: pulse height fill bit: 0
[16:55:15.903] <TB1> INFO: buffer corruption: 0
[16:55:15.910] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C15.dat
[16:55:15.912] <TB1> INFO: ######################################################################
[16:55:15.912] <TB1> INFO: PixTestReadback::doTest()
[16:55:15.912] <TB1> INFO: ######################################################################
[16:55:15.912] <TB1> INFO: ----------------------------------------------------------------------
[16:55:15.912] <TB1> INFO: PixTestReadback::CalibrateVd()
[16:55:15.912] <TB1> INFO: ----------------------------------------------------------------------
[16:55:25.379] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C0.dat
[16:55:25.380] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C1.dat
[16:55:25.380] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C2.dat
[16:55:25.380] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C3.dat
[16:55:25.380] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C4.dat
[16:55:25.380] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C5.dat
[16:55:25.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C6.dat
[16:55:25.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C7.dat
[16:55:25.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C8.dat
[16:55:25.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C9.dat
[16:55:25.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C10.dat
[16:55:25.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C11.dat
[16:55:25.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C12.dat
[16:55:25.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C13.dat
[16:55:25.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C14.dat
[16:55:25.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C15.dat
[16:55:25.405] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:55:25.405] <TB1> INFO: ----------------------------------------------------------------------
[16:55:25.405] <TB1> INFO: PixTestReadback::CalibrateVa()
[16:55:25.405] <TB1> INFO: ----------------------------------------------------------------------
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C0.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C1.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C2.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C3.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C4.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C5.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C6.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C7.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C8.dat
[16:55:34.910] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C9.dat
[16:55:34.911] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C10.dat
[16:55:34.911] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C11.dat
[16:55:34.911] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C12.dat
[16:55:34.911] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C13.dat
[16:55:34.911] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C14.dat
[16:55:34.911] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C15.dat
[16:55:34.943] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:55:34.943] <TB1> INFO: ----------------------------------------------------------------------
[16:55:34.943] <TB1> INFO: PixTestReadback::readbackVbg()
[16:55:34.943] <TB1> INFO: ----------------------------------------------------------------------
[16:55:42.262] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:55:42.262] <TB1> INFO: ----------------------------------------------------------------------
[16:55:42.262] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[16:55:42.262] <TB1> INFO: ----------------------------------------------------------------------
[16:55:42.262] <TB1> INFO: Vbg will be calibrated using Vd calibration
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.1calibrated Vbg = 1.18252 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 145calibrated Vbg = 1.18756 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 145.7calibrated Vbg = 1.18238 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 147.2calibrated Vbg = 1.1924 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.4calibrated Vbg = 1.20252 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152calibrated Vbg = 1.20437 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 146.4calibrated Vbg = 1.19786 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 146.4calibrated Vbg = 1.19994 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.6calibrated Vbg = 1.20077 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 140.8calibrated Vbg = 1.20536 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.1calibrated Vbg = 1.1984 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.9calibrated Vbg = 1.19245 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.2calibrated Vbg = 1.1883 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.8calibrated Vbg = 1.19047 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.7calibrated Vbg = 1.18471 :::*/*/*/*/
[16:55:42.263] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147.3calibrated Vbg = 1.18508 :::*/*/*/*/
[16:55:42.265] <TB1> INFO: ----------------------------------------------------------------------
[16:55:42.266] <TB1> INFO: PixTestReadback::CalibrateIa()
[16:55:42.266] <TB1> INFO: ----------------------------------------------------------------------
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C0.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C1.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C2.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C3.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C4.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C5.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C6.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C7.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C8.dat
[16:58:17.622] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C9.dat
[16:58:17.623] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C10.dat
[16:58:17.623] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C11.dat
[16:58:17.623] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C12.dat
[16:58:17.623] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C13.dat
[16:58:17.623] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C14.dat
[16:58:17.623] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//002_FulltestPxar_m20//readbackCal_C15.dat
[16:58:17.653] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:58:17.653] <TB1> INFO: PixTestReadback::doTest() done
[16:58:17.654] <TB1> INFO: Decoding statistics:
[16:58:17.654] <TB1> INFO: General information:
[16:58:17.654] <TB1> INFO: 16bit words read: 1024
[16:58:17.654] <TB1> INFO: valid events total: 128
[16:58:17.654] <TB1> INFO: empty events: 128
[16:58:17.654] <TB1> INFO: valid events with pixels: 0
[16:58:17.654] <TB1> INFO: valid pixel hits: 0
[16:58:17.654] <TB1> INFO: Event errors: 0
[16:58:17.654] <TB1> INFO: start marker: 0
[16:58:17.654] <TB1> INFO: stop marker: 0
[16:58:17.654] <TB1> INFO: overflow: 0
[16:58:17.654] <TB1> INFO: invalid 5bit words: 0
[16:58:17.654] <TB1> INFO: invalid XOR eye diagram: 0
[16:58:17.654] <TB1> INFO: frame (failed synchr.): 0
[16:58:17.654] <TB1> INFO: idle data (no TBM trl): 0
[16:58:17.654] <TB1> INFO: no data (only TBM hdr): 0
[16:58:17.654] <TB1> INFO: TBM errors: 0
[16:58:17.654] <TB1> INFO: flawed TBM headers: 0
[16:58:17.654] <TB1> INFO: flawed TBM trailers: 0
[16:58:17.654] <TB1> INFO: event ID mismatches: 0
[16:58:17.654] <TB1> INFO: ROC errors: 0
[16:58:17.654] <TB1> INFO: missing ROC header(s): 0
[16:58:17.654] <TB1> INFO: misplaced readback start: 0
[16:58:17.654] <TB1> INFO: Pixel decoding errors: 0
[16:58:17.654] <TB1> INFO: pixel data incomplete: 0
[16:58:17.654] <TB1> INFO: pixel address: 0
[16:58:17.654] <TB1> INFO: pulse height fill bit: 0
[16:58:17.654] <TB1> INFO: buffer corruption: 0
[16:58:17.673] <TB1> INFO: Decoding statistics:
[16:58:17.673] <TB1> INFO: General information:
[16:58:17.673] <TB1> INFO: 16bit words read: 2750306
[16:58:17.673] <TB1> INFO: valid events total: 176768
[16:58:17.673] <TB1> INFO: empty events: 7867
[16:58:17.673] <TB1> INFO: valid events with pixels: 168901
[16:58:17.673] <TB1> INFO: valid pixel hits: 668081
[16:58:17.673] <TB1> INFO: Event errors: 0
[16:58:17.673] <TB1> INFO: start marker: 0
[16:58:17.673] <TB1> INFO: stop marker: 0
[16:58:17.673] <TB1> INFO: overflow: 0
[16:58:17.673] <TB1> INFO: invalid 5bit words: 0
[16:58:17.673] <TB1> INFO: invalid XOR eye diagram: 0
[16:58:17.673] <TB1> INFO: frame (failed synchr.): 0
[16:58:17.673] <TB1> INFO: idle data (no TBM trl): 0
[16:58:17.673] <TB1> INFO: no data (only TBM hdr): 0
[16:58:17.673] <TB1> INFO: TBM errors: 0
[16:58:17.673] <TB1> INFO: flawed TBM headers: 0
[16:58:17.673] <TB1> INFO: flawed TBM trailers: 0
[16:58:17.673] <TB1> INFO: event ID mismatches: 0
[16:58:17.673] <TB1> INFO: ROC errors: 0
[16:58:17.673] <TB1> INFO: missing ROC header(s): 0
[16:58:17.673] <TB1> INFO: misplaced readback start: 0
[16:58:17.673] <TB1> INFO: Pixel decoding errors: 0
[16:58:17.673] <TB1> INFO: pixel data incomplete: 0
[16:58:17.673] <TB1> INFO: pixel address: 0
[16:58:17.673] <TB1> INFO: pulse height fill bit: 0
[16:58:17.673] <TB1> INFO: buffer corruption: 0
[16:58:17.673] <TB1> INFO: enter test to run
[16:58:17.673] <TB1> INFO: test: exit no parameter change
[16:58:17.832] <TB1> QUIET: Connection to board 153 closed.
[16:58:17.912] <TB1> INFO: pXar: this is the end, my friend