[09:22:20.399] <TB3> INFO: *** Welcome to pxar ***
[09:22:20.399] <TB3> INFO: *** Today: 2016/09/20
[09:22:20.487] <TB3> INFO: *** Version: fe94
[09:22:20.487] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C15.dat
[09:22:20.488] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:22:20.488] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//defaultMaskFile.dat
[09:22:20.488] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters_C15.dat
[09:22:20.567] <TB3> INFO: clk: 4
[09:22:20.567] <TB3> INFO: ctr: 4
[09:22:20.567] <TB3> INFO: sda: 19
[09:22:20.567] <TB3> INFO: tin: 9
[09:22:20.567] <TB3> INFO: level: 15
[09:22:20.567] <TB3> INFO: triggerdelay: 0
[09:22:20.567] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[09:22:20.567] <TB3> INFO: Log level: INFO
[09:22:20.573] <TB3> INFO: Found DTB DTB_WZ4I6J
[09:22:20.585] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[09:22:20.587] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[09:22:20.589] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[09:22:22.103] <TB3> INFO: DUT info:
[09:22:22.103] <TB3> INFO: The DUT currently contains the following objects:
[09:22:22.103] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:22:22.103] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:22:22.103] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:22:22.103] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:22:22.103] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.103] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.103] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.103] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.103] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.103] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.103] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.104] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:22.505] <TB3> INFO: enter 'restricted' command line mode
[09:22:22.505] <TB3> INFO: enter test to run
[09:22:22.505] <TB3> INFO: test: pretest no parameter change
[09:22:22.505] <TB3> INFO: running: pretest
[09:22:22.513] <TB3> INFO: ----------------------------------------------------------------------
[09:22:22.513] <TB3> INFO: PixTestPretest::programROC()
[09:22:22.513] <TB3> INFO: ----------------------------------------------------------------------
[09:22:40.530] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:22:40.530] <TB3> INFO: IA differences per ROC: 16.9 19.3 20.1 20.1 16.9 18.5 19.3 18.5 19.3 16.9 18.5 17.7 18.5 19.3 16.9 18.5
[09:22:40.639] <TB3> INFO: enter test to run
[09:22:40.639] <TB3> INFO: test: pretest no parameter change
[09:22:40.640] <TB3> INFO: running: pretest
[09:22:40.641] <TB3> INFO: ----------------------------------------------------------------------
[09:22:40.641] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:22:40.641] <TB3> INFO: ----------------------------------------------------------------------
[09:23:01.946] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[09:23:01.946] <TB3> INFO: i(loss) [mA/ROC]: 19.3 20.1 18.5 19.3 19.3 19.3 20.1 19.3 20.1 19.3 18.5 19.3 19.3 18.5 18.5 18.5
[09:23:01.993] <TB3> INFO: enter test to run
[09:23:01.993] <TB3> INFO: test: pretest no parameter change
[09:23:01.993] <TB3> INFO: running: pretest
[09:23:01.993] <TB3> INFO: ----------------------------------------------------------------------
[09:23:01.993] <TB3> INFO: PixTestPretest::findTiming()
[09:23:01.993] <TB3> INFO: ----------------------------------------------------------------------
[09:23:01.993] <TB3> INFO: PixTestCmd::init()

[09:24:32.708] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[09:24:32.708] <TB3> INFO: (success/tries = 100/100), width = 5
[09:24:32.710] <TB3> INFO: enter test to run
[09:24:32.710] <TB3> INFO: test: pretest no parameter change
[09:24:32.710] <TB3> INFO: running: pretest
[09:24:32.712] <TB3> INFO: ----------------------------------------------------------------------
[09:24:32.712] <TB3> INFO: PixTestPretest::findWorkingPixel()
[09:24:32.712] <TB3> INFO: ----------------------------------------------------------------------
[09:24:32.807] <TB3> INFO: Expecting 231680 events.


[09:24:56.981] <TB3> INFO: 231680 events read in total (23617ms).
[09:24:56.987] <TB3> INFO: Test took 24269ms.
[09:24:57.192] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:24:57.271] <TB3> INFO: enter test to run
[09:24:57.271] <TB3> INFO: test: pretest no parameter change
[09:24:57.271] <TB3> INFO: running: pretest
[09:24:57.272] <TB3> INFO: ----------------------------------------------------------------------
[09:24:57.272] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[09:24:57.272] <TB3> INFO: ----------------------------------------------------------------------
[09:24:57.367] <TB3> INFO: Expecting 231680 events.
[09:25:21.495] <TB3> INFO: 231680 events read in total (23570ms).
[09:25:21.501] <TB3> INFO: Test took 24223ms.
[09:25:21.738] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[09:25:21.738] <TB3> INFO: CalDel: 142 133 158 149 140 148 151 148 137 119 153 141 148 131 140 120
[09:25:21.739] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:25:21.775] <TB3> INFO: enter test to run
[09:25:21.775] <TB3> INFO: test: pretest no parameter change
[09:25:21.775] <TB3> INFO: running: pretest
[09:25:21.776] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C0.dat
[09:25:21.776] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C1.dat
[09:25:21.776] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C2.dat
[09:25:21.776] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C3.dat
[09:25:21.776] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C4.dat
[09:25:21.777] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C5.dat
[09:25:21.777] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C6.dat
[09:25:21.777] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C7.dat
[09:25:21.777] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C8.dat
[09:25:21.777] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C9.dat
[09:25:21.777] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C10.dat
[09:25:21.777] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C11.dat
[09:25:21.778] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C12.dat
[09:25:21.778] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C13.dat
[09:25:21.778] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C14.dat
[09:25:21.778] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C15.dat
[09:25:21.778] <TB3> INFO: enter test to run
[09:25:21.778] <TB3> INFO: test: fulltest no parameter change
[09:25:21.778] <TB3> INFO: running: fulltest
[09:25:21.778] <TB3> INFO: ######################################################################
[09:25:21.778] <TB3> INFO: PixTestFullTest::doTest()
[09:25:21.778] <TB3> INFO: ######################################################################
[09:25:21.779] <TB3> INFO: ######################################################################
[09:25:21.779] <TB3> INFO: PixTestAlive::doTest()
[09:25:21.779] <TB3> INFO: ######################################################################
[09:25:21.781] <TB3> INFO: ----------------------------------------------------------------------
[09:25:21.781] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:25:21.781] <TB3> INFO: ----------------------------------------------------------------------
[09:25:22.064] <TB3> INFO: Expecting 41600 events.
[09:25:30.078] <TB3> INFO: 41600 events read in total (7458ms).
[09:25:30.078] <TB3> INFO: Test took 8295ms.
[09:25:30.319] <TB3> INFO: PixTestAlive::aliveTest() done
[09:25:30.319] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:25:30.321] <TB3> INFO: ----------------------------------------------------------------------
[09:25:30.321] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:25:30.321] <TB3> INFO: ----------------------------------------------------------------------
[09:25:30.611] <TB3> INFO: Expecting 41600 events.
[09:25:35.654] <TB3> INFO: 41600 events read in total (4486ms).
[09:25:35.655] <TB3> INFO: Test took 5331ms.
[09:25:35.664] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:25:35.904] <TB3> INFO: PixTestAlive::maskTest() done
[09:25:35.904] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:25:35.906] <TB3> INFO: ----------------------------------------------------------------------
[09:25:35.906] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:25:35.906] <TB3> INFO: ----------------------------------------------------------------------
[09:25:36.170] <TB3> INFO: Expecting 41600 events.
[09:25:44.138] <TB3> INFO: 41600 events read in total (7411ms).
[09:25:44.139] <TB3> INFO: Test took 8230ms.
[09:25:44.382] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[09:25:44.382] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:25:44.382] <TB3> INFO: PixTestAlive::doTest() done, duration: 22 seconds
[09:25:44.382] <TB3> INFO: Decoding statistics:
[09:25:44.382] <TB3> INFO: General information:
[09:25:44.382] <TB3> INFO: 16bit words read: 0
[09:25:44.382] <TB3> INFO: valid events total: 0
[09:25:44.382] <TB3> INFO: empty events: 0
[09:25:44.383] <TB3> INFO: valid events with pixels: 0
[09:25:44.383] <TB3> INFO: valid pixel hits: 0
[09:25:44.383] <TB3> INFO: Event errors: 0
[09:25:44.383] <TB3> INFO: start marker: 0
[09:25:44.383] <TB3> INFO: stop marker: 0
[09:25:44.383] <TB3> INFO: overflow: 0
[09:25:44.383] <TB3> INFO: invalid 5bit words: 0
[09:25:44.383] <TB3> INFO: invalid XOR eye diagram: 0
[09:25:44.383] <TB3> INFO: frame (failed synchr.): 0
[09:25:44.383] <TB3> INFO: idle data (no TBM trl): 0
[09:25:44.383] <TB3> INFO: no data (only TBM hdr): 0
[09:25:44.383] <TB3> INFO: TBM errors: 0
[09:25:44.383] <TB3> INFO: flawed TBM headers: 0
[09:25:44.383] <TB3> INFO: flawed TBM trailers: 0
[09:25:44.383] <TB3> INFO: event ID mismatches: 0
[09:25:44.383] <TB3> INFO: ROC errors: 0
[09:25:44.383] <TB3> INFO: missing ROC header(s): 0
[09:25:44.383] <TB3> INFO: misplaced readback start: 0
[09:25:44.383] <TB3> INFO: Pixel decoding errors: 0
[09:25:44.383] <TB3> INFO: pixel data incomplete: 0
[09:25:44.383] <TB3> INFO: pixel address: 0
[09:25:44.383] <TB3> INFO: pulse height fill bit: 0
[09:25:44.383] <TB3> INFO: buffer corruption: 0
[09:25:44.395] <TB3> INFO: ######################################################################
[09:25:44.395] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:25:44.395] <TB3> INFO: ######################################################################
[09:25:44.399] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:25:44.411] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:25:44.411] <TB3> INFO: run 1 of 1
[09:25:44.682] <TB3> INFO: Expecting 3120000 events.
[09:27:34.100] <TB3> INFO: 859310 events read in total (108862ms).
[09:29:23.799] <TB3> INFO: 1706700 events read in total (218561ms).
[09:31:13.217] <TB3> INFO: 2567455 events read in total (327979ms).
[09:32:22.368] <TB3> INFO: 3120000 events read in total (397130ms).
[09:32:22.438] <TB3> INFO: Test took 398027ms.
[09:32:45.918] <TB3> INFO: PixTestBBMap::doTest() done, duration: 421 seconds
[09:32:45.918] <TB3> INFO: number of dead bumps (per ROC): 10 0 0 1 0 0 0 0 0 0 0 0 0 1 1 18
[09:32:45.918] <TB3> INFO: separation cut (per ROC): 81 89 82 92 70 84 81 86 92 88 69 74 97 78 80 92
[09:32:45.918] <TB3> INFO: Decoding statistics:
[09:32:45.918] <TB3> INFO: General information:
[09:32:45.918] <TB3> INFO: 16bit words read: 0
[09:32:45.918] <TB3> INFO: valid events total: 0
[09:32:45.918] <TB3> INFO: empty events: 0
[09:32:45.918] <TB3> INFO: valid events with pixels: 0
[09:32:45.918] <TB3> INFO: valid pixel hits: 0
[09:32:45.918] <TB3> INFO: Event errors: 0
[09:32:45.918] <TB3> INFO: start marker: 0
[09:32:45.918] <TB3> INFO: stop marker: 0
[09:32:45.918] <TB3> INFO: overflow: 0
[09:32:45.918] <TB3> INFO: invalid 5bit words: 0
[09:32:45.918] <TB3> INFO: invalid XOR eye diagram: 0
[09:32:45.918] <TB3> INFO: frame (failed synchr.): 0
[09:32:45.918] <TB3> INFO: idle data (no TBM trl): 0
[09:32:45.918] <TB3> INFO: no data (only TBM hdr): 0
[09:32:45.918] <TB3> INFO: TBM errors: 0
[09:32:45.918] <TB3> INFO: flawed TBM headers: 0
[09:32:45.918] <TB3> INFO: flawed TBM trailers: 0
[09:32:45.918] <TB3> INFO: event ID mismatches: 0
[09:32:45.918] <TB3> INFO: ROC errors: 0
[09:32:45.918] <TB3> INFO: missing ROC header(s): 0
[09:32:45.918] <TB3> INFO: misplaced readback start: 0
[09:32:45.918] <TB3> INFO: Pixel decoding errors: 0
[09:32:45.918] <TB3> INFO: pixel data incomplete: 0
[09:32:45.918] <TB3> INFO: pixel address: 0
[09:32:45.918] <TB3> INFO: pulse height fill bit: 0
[09:32:45.918] <TB3> INFO: buffer corruption: 0
[09:32:45.990] <TB3> INFO: ######################################################################
[09:32:45.990] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:32:45.990] <TB3> INFO: ######################################################################
[09:32:45.990] <TB3> INFO: ----------------------------------------------------------------------
[09:32:45.990] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:32:45.990] <TB3> INFO: ----------------------------------------------------------------------
[09:32:45.990] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[09:32:45.998] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:45.998] <TB3> INFO: run 1 of 1
[09:32:46.261] <TB3> INFO: Expecting 36608000 events.
[09:34:26.810] <TB3> INFO: 822400 events read in total (99993ms).
[09:36:07.966] <TB3> INFO: 1629800 events read in total (201149ms).
[09:37:47.789] <TB3> INFO: 2437000 events read in total (300972ms).
[09:39:27.292] <TB3> INFO: 3244350 events read in total (400475ms).
[09:41:07.670] <TB3> INFO: 4052750 events read in total (500853ms).
[09:42:48.759] <TB3> INFO: 4861200 events read in total (601942ms).
[09:44:30.173] <TB3> INFO: 5666600 events read in total (703356ms).
[09:46:10.634] <TB3> INFO: 6474300 events read in total (803817ms).
[09:47:50.895] <TB3> INFO: 7280500 events read in total (904078ms).
[09:49:32.569] <TB3> INFO: 8087200 events read in total (1005752ms).
[09:51:12.415] <TB3> INFO: 8895050 events read in total (1105598ms).
[09:52:53.846] <TB3> INFO: 9701950 events read in total (1207029ms).
[09:54:34.821] <TB3> INFO: 10508600 events read in total (1308004ms).
[09:56:15.970] <TB3> INFO: 11314350 events read in total (1409153ms).
[09:57:56.441] <TB3> INFO: 12121500 events read in total (1509624ms).
[09:59:37.455] <TB3> INFO: 12926850 events read in total (1610638ms).
[10:01:17.883] <TB3> INFO: 13732750 events read in total (1711066ms).
[10:02:58.440] <TB3> INFO: 14536700 events read in total (1811623ms).
[10:04:39.263] <TB3> INFO: 15341800 events read in total (1912446ms).
[10:06:19.577] <TB3> INFO: 16146050 events read in total (2012760ms).
[10:07:59.670] <TB3> INFO: 16953000 events read in total (2112853ms).
[10:09:41.160] <TB3> INFO: 17758000 events read in total (2214343ms).
[10:11:21.906] <TB3> INFO: 18561000 events read in total (2315089ms).
[10:13:03.197] <TB3> INFO: 19359300 events read in total (2416380ms).
[10:14:43.344] <TB3> INFO: 20158350 events read in total (2516527ms).
[10:16:24.541] <TB3> INFO: 20957850 events read in total (2617724ms).
[10:18:05.275] <TB3> INFO: 21758750 events read in total (2718458ms).
[10:19:46.495] <TB3> INFO: 22558100 events read in total (2819678ms).
[10:21:27.017] <TB3> INFO: 23356750 events read in total (2920200ms).
[10:23:08.018] <TB3> INFO: 24155200 events read in total (3021201ms).
[10:24:48.510] <TB3> INFO: 24953400 events read in total (3121693ms).
[10:26:29.753] <TB3> INFO: 25751400 events read in total (3222936ms).
[10:28:10.676] <TB3> INFO: 26549000 events read in total (3323859ms).
[10:29:51.743] <TB3> INFO: 27346200 events read in total (3424926ms).
[10:31:32.736] <TB3> INFO: 28143800 events read in total (3525919ms).
[10:33:13.417] <TB3> INFO: 28941950 events read in total (3626600ms).
[10:34:53.960] <TB3> INFO: 29738950 events read in total (3727143ms).
[10:36:34.311] <TB3> INFO: 30534100 events read in total (3827494ms).
[10:38:14.627] <TB3> INFO: 31330250 events read in total (3927810ms).
[10:39:55.149] <TB3> INFO: 32127000 events read in total (4028332ms).
[10:41:36.103] <TB3> INFO: 32925450 events read in total (4129286ms).
[10:43:17.131] <TB3> INFO: 33720800 events read in total (4230314ms).
[10:44:57.804] <TB3> INFO: 34519950 events read in total (4330987ms).
[10:46:38.095] <TB3> INFO: 35315450 events read in total (4431278ms).
[10:48:19.038] <TB3> INFO: 36116450 events read in total (4532221ms).
[10:49:19.973] <TB3> INFO: 36608000 events read in total (4593156ms).
[10:49:20.042] <TB3> INFO: Test took 4594043ms.
[10:49:20.480] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:22.210] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:23.894] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:25.545] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:27.412] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:29.104] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:30.845] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:32.688] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:34.466] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:36.263] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:38.019] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:39.781] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:41.567] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:43.418] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:45.302] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:47.081] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:49:48.935] <TB3> INFO: PixTestScurves::scurves() done
[10:49:48.935] <TB3> INFO: Vcal mean: 84.22 83.86 80.22 95.28 76.27 88.08 89.90 90.90 100.11 85.08 80.38 78.37 94.75 77.74 77.28 90.87
[10:49:48.935] <TB3> INFO: Vcal RMS: 5.47 4.79 4.27 5.58 3.96 5.26 5.04 5.20 5.91 5.54 4.00 4.00 4.82 3.59 4.03 4.88
[10:49:48.935] <TB3> INFO: PixTestScurves::fullTest() done, duration: 4622 seconds
[10:49:48.935] <TB3> INFO: Decoding statistics:
[10:49:48.935] <TB3> INFO: General information:
[10:49:48.935] <TB3> INFO: 16bit words read: 0
[10:49:48.935] <TB3> INFO: valid events total: 0
[10:49:48.935] <TB3> INFO: empty events: 0
[10:49:48.935] <TB3> INFO: valid events with pixels: 0
[10:49:48.935] <TB3> INFO: valid pixel hits: 0
[10:49:48.935] <TB3> INFO: Event errors: 0
[10:49:48.935] <TB3> INFO: start marker: 0
[10:49:48.935] <TB3> INFO: stop marker: 0
[10:49:48.935] <TB3> INFO: overflow: 0
[10:49:48.935] <TB3> INFO: invalid 5bit words: 0
[10:49:48.935] <TB3> INFO: invalid XOR eye diagram: 0
[10:49:48.935] <TB3> INFO: frame (failed synchr.): 0
[10:49:48.935] <TB3> INFO: idle data (no TBM trl): 0
[10:49:48.935] <TB3> INFO: no data (only TBM hdr): 0
[10:49:48.935] <TB3> INFO: TBM errors: 0
[10:49:48.935] <TB3> INFO: flawed TBM headers: 0
[10:49:48.935] <TB3> INFO: flawed TBM trailers: 0
[10:49:48.935] <TB3> INFO: event ID mismatches: 0
[10:49:48.935] <TB3> INFO: ROC errors: 0
[10:49:48.935] <TB3> INFO: missing ROC header(s): 0
[10:49:48.935] <TB3> INFO: misplaced readback start: 0
[10:49:48.935] <TB3> INFO: Pixel decoding errors: 0
[10:49:48.935] <TB3> INFO: pixel data incomplete: 0
[10:49:48.936] <TB3> INFO: pixel address: 0
[10:49:48.936] <TB3> INFO: pulse height fill bit: 0
[10:49:48.936] <TB3> INFO: buffer corruption: 0
[10:49:49.005] <TB3> INFO: ######################################################################
[10:49:49.005] <TB3> INFO: PixTestTrim::doTest()
[10:49:49.005] <TB3> INFO: ######################################################################
[10:49:49.007] <TB3> INFO: ----------------------------------------------------------------------
[10:49:49.007] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:49:49.007] <TB3> INFO: ----------------------------------------------------------------------
[10:49:49.105] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:49:49.105] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:49:49.114] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:49:49.114] <TB3> INFO: run 1 of 1
[10:49:49.377] <TB3> INFO: Expecting 6281600 events.
[10:51:37.963] <TB3> INFO: 1096330 events read in total (108028ms).
[10:53:26.171] <TB3> INFO: 2184920 events read in total (216237ms).
[10:55:13.495] <TB3> INFO: 3269130 events read in total (323560ms).
[10:57:02.508] <TB3> INFO: 4359280 events read in total (432573ms).
[10:58:50.636] <TB3> INFO: 5449690 events read in total (540701ms).
[11:00:12.538] <TB3> INFO: 6281600 events read in total (622603ms).
[11:00:12.586] <TB3> INFO: Test took 623473ms.
[11:00:30.958] <TB3> INFO: ROC 0 VthrComp = 87
[11:00:30.958] <TB3> INFO: ROC 1 VthrComp = 93
[11:00:30.958] <TB3> INFO: ROC 2 VthrComp = 86
[11:00:30.958] <TB3> INFO: ROC 3 VthrComp = 99
[11:00:30.958] <TB3> INFO: ROC 4 VthrComp = 83
[11:00:30.958] <TB3> INFO: ROC 5 VthrComp = 93
[11:00:30.959] <TB3> INFO: ROC 6 VthrComp = 95
[11:00:30.959] <TB3> INFO: ROC 7 VthrComp = 92
[11:00:30.959] <TB3> INFO: ROC 8 VthrComp = 101
[11:00:30.959] <TB3> INFO: ROC 9 VthrComp = 92
[11:00:30.960] <TB3> INFO: ROC 10 VthrComp = 87
[11:00:30.960] <TB3> INFO: ROC 11 VthrComp = 85
[11:00:30.960] <TB3> INFO: ROC 12 VthrComp = 105
[11:00:30.960] <TB3> INFO: ROC 13 VthrComp = 88
[11:00:30.961] <TB3> INFO: ROC 14 VthrComp = 84
[11:00:30.961] <TB3> INFO: ROC 15 VthrComp = 100
[11:00:30.961] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:00:30.961] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:00:30.970] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:00:30.970] <TB3> INFO: run 1 of 1
[11:00:31.232] <TB3> INFO: Expecting 6281600 events.
[11:02:14.268] <TB3> INFO: 756360 events read in total (102479ms).
[11:03:57.683] <TB3> INFO: 1511660 events read in total (205894ms).
[11:05:40.407] <TB3> INFO: 2267010 events read in total (308618ms).
[11:07:22.645] <TB3> INFO: 3022640 events read in total (410856ms).
[11:09:05.560] <TB3> INFO: 3774220 events read in total (513771ms).
[11:10:48.563] <TB3> INFO: 4523420 events read in total (616774ms).
[11:12:31.696] <TB3> INFO: 5270780 events read in total (719907ms).
[11:14:14.304] <TB3> INFO: 6017770 events read in total (822515ms).
[11:14:50.469] <TB3> INFO: 6281600 events read in total (858680ms).
[11:14:50.517] <TB3> INFO: Test took 859548ms.
[11:15:15.057] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 61.0511 for pixel 0/2 mean/min/max = 46.0203/30.9526/61.0881
[11:15:15.057] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 57.287 for pixel 30/13 mean/min/max = 45.3457/33.2598/57.4316
[11:15:15.057] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.5656 for pixel 6/50 mean/min/max = 45.2169/32.7847/57.6491
[11:15:15.057] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 60.0822 for pixel 7/63 mean/min/max = 45.8449/31.5945/60.0953
[11:15:15.058] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 55.1817 for pixel 5/21 mean/min/max = 43.7406/32.1741/55.3072
[11:15:15.058] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 59.6142 for pixel 23/8 mean/min/max = 45.8675/32.0336/59.7014
[11:15:15.058] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.186 for pixel 4/79 mean/min/max = 44.2341/31.2711/57.197
[11:15:15.058] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 59.7445 for pixel 11/10 mean/min/max = 46.4107/33.0437/59.7776
[11:15:15.059] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 62.5128 for pixel 1/2 mean/min/max = 47.0564/31.5206/62.5922
[11:15:15.059] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.6489 for pixel 6/79 mean/min/max = 46.3648/31.7272/61.0024
[11:15:15.059] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 56.2498 for pixel 27/0 mean/min/max = 44.5677/32.6308/56.5047
[11:15:15.059] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 56.0281 for pixel 41/3 mean/min/max = 44.2795/32.5083/56.0506
[11:15:15.060] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 56.7111 for pixel 13/7 mean/min/max = 45.5101/34.2311/56.789
[11:15:15.060] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 54.1436 for pixel 32/2 mean/min/max = 44.0039/33.2475/54.7603
[11:15:15.060] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 56 for pixel 0/24 mean/min/max = 44.5313/33.0382/56.0243
[11:15:15.060] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 56.5553 for pixel 18/14 mean/min/max = 44.1468/31.7196/56.5739
[11:15:15.061] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:15.151] <TB3> INFO: Expecting 514560 events.
[11:15:54.876] <TB3> INFO: 514560 events read in total (39168ms).
[11:15:54.884] <TB3> INFO: Expecting 514560 events.
[11:16:34.858] <TB3> INFO: 514560 events read in total (39571ms).
[11:16:34.869] <TB3> INFO: Expecting 514560 events.
[11:17:14.506] <TB3> INFO: 514560 events read in total (39235ms).
[11:17:14.519] <TB3> INFO: Expecting 514560 events.
[11:17:54.062] <TB3> INFO: 514560 events read in total (39140ms).
[11:17:54.077] <TB3> INFO: Expecting 514560 events.
[11:18:33.642] <TB3> INFO: 514560 events read in total (39163ms).
[11:18:33.662] <TB3> INFO: Expecting 514560 events.
[11:19:13.239] <TB3> INFO: 514560 events read in total (39174ms).
[11:19:13.264] <TB3> INFO: Expecting 514560 events.
[11:19:52.534] <TB3> INFO: 514560 events read in total (38867ms).
[11:19:52.556] <TB3> INFO: Expecting 514560 events.
[11:20:32.027] <TB3> INFO: 514560 events read in total (39068ms).
[11:20:32.054] <TB3> INFO: Expecting 514560 events.
[11:21:11.562] <TB3> INFO: 514560 events read in total (39106ms).
[11:21:11.591] <TB3> INFO: Expecting 514560 events.
[11:21:51.390] <TB3> INFO: 514560 events read in total (39397ms).
[11:21:51.424] <TB3> INFO: Expecting 514560 events.
[11:22:30.828] <TB3> INFO: 514560 events read in total (39002ms).
[11:22:30.863] <TB3> INFO: Expecting 514560 events.
[11:23:10.888] <TB3> INFO: 514560 events read in total (39623ms).
[11:23:11.001] <TB3> INFO: Expecting 514560 events.
[11:23:50.338] <TB3> INFO: 514560 events read in total (38935ms).
[11:23:50.376] <TB3> INFO: Expecting 514560 events.
[11:24:29.847] <TB3> INFO: 514560 events read in total (39069ms).
[11:24:29.888] <TB3> INFO: Expecting 514560 events.
[11:25:09.116] <TB3> INFO: 514560 events read in total (38826ms).
[11:25:09.169] <TB3> INFO: Expecting 514560 events.
[11:25:48.577] <TB3> INFO: 514560 events read in total (39006ms).
[11:25:48.623] <TB3> INFO: Test took 633562ms.
[11:25:49.693] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:25:49.701] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:25:49.701] <TB3> INFO: run 1 of 1
[11:25:49.963] <TB3> INFO: Expecting 6281600 events.
[11:27:33.530] <TB3> INFO: 746130 events read in total (103010ms).
[11:29:16.956] <TB3> INFO: 1491390 events read in total (206436ms).
[11:31:00.204] <TB3> INFO: 2236960 events read in total (309684ms).
[11:32:43.721] <TB3> INFO: 2982720 events read in total (413201ms).
[11:34:26.976] <TB3> INFO: 3724250 events read in total (516456ms).
[11:36:09.841] <TB3> INFO: 4460530 events read in total (619321ms).
[11:37:52.498] <TB3> INFO: 5195100 events read in total (721978ms).
[11:39:34.440] <TB3> INFO: 5928720 events read in total (823920ms).
[11:40:23.862] <TB3> INFO: 6281600 events read in total (873342ms).
[11:40:23.913] <TB3> INFO: Test took 874211ms.
[11:40:47.451] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 11.916289 .. 55.192715
[11:40:47.733] <TB3> INFO: Expecting 208000 events.
[11:41:19.806] <TB3> INFO: 208000 events read in total (31516ms).
[11:41:19.807] <TB3> INFO: Test took 32354ms.
[11:41:19.892] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 65 (-1/-1) hits flags = 528 (plus default)
[11:41:19.900] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:41:19.900] <TB3> INFO: run 1 of 1
[11:41:20.170] <TB3> INFO: Expecting 2704000 events.
[11:43:07.477] <TB3> INFO: 915010 events read in total (106750ms).
[11:44:53.748] <TB3> INFO: 1828180 events read in total (213021ms).
[11:46:36.120] <TB3> INFO: 2704000 events read in total (315393ms).
[11:46:36.146] <TB3> INFO: Test took 316246ms.
[11:46:50.243] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 20.986995 .. 46.409278
[11:46:50.519] <TB3> INFO: Expecting 208000 events.
[11:47:22.891] <TB3> INFO: 208000 events read in total (31815ms).
[11:47:22.893] <TB3> INFO: Test took 32648ms.
[11:47:22.982] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 10 .. 56 (-1/-1) hits flags = 528 (plus default)
[11:47:22.991] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:47:22.991] <TB3> INFO: run 1 of 1
[11:47:23.253] <TB3> INFO: Expecting 1955200 events.
[11:49:10.843] <TB3> INFO: 919260 events read in total (107033ms).
[11:50:56.484] <TB3> INFO: 1837240 events read in total (212674ms).
[11:51:10.392] <TB3> INFO: 1955200 events read in total (226582ms).
[11:51:10.409] <TB3> INFO: Test took 227419ms.
[11:51:24.014] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 21.674128 .. 43.916616
[11:51:24.302] <TB3> INFO: Expecting 208000 events.
[11:51:56.412] <TB3> INFO: 208000 events read in total (31553ms).
[11:51:56.413] <TB3> INFO: Test took 32396ms.
[11:51:56.499] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 53 (-1/-1) hits flags = 528 (plus default)
[11:51:56.507] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:51:56.507] <TB3> INFO: run 1 of 1
[11:51:56.769] <TB3> INFO: Expecting 1788800 events.
[11:53:44.126] <TB3> INFO: 937970 events read in total (106800ms).
[11:55:21.645] <TB3> INFO: 1788800 events read in total (204319ms).
[11:55:21.665] <TB3> INFO: Test took 205157ms.
[11:55:34.980] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.647722 .. 43.916616
[11:55:35.284] <TB3> INFO: Expecting 208000 events.
[11:56:07.249] <TB3> INFO: 208000 events read in total (31408ms).
[11:56:07.250] <TB3> INFO: Test took 32268ms.
[11:56:07.336] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 53 (-1/-1) hits flags = 528 (plus default)
[11:56:07.346] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:56:07.346] <TB3> INFO: run 1 of 1
[11:56:07.624] <TB3> INFO: Expecting 1664000 events.
[11:57:54.642] <TB3> INFO: 919080 events read in total (106461ms).
[11:59:21.069] <TB3> INFO: 1664000 events read in total (192888ms).
[11:59:21.086] <TB3> INFO: Test took 193740ms.
[11:59:33.683] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:59:33.683] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:59:33.691] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:59:33.691] <TB3> INFO: run 1 of 1
[11:59:33.970] <TB3> INFO: Expecting 1705600 events.
[12:01:20.781] <TB3> INFO: 894510 events read in total (106254ms).
[12:02:57.114] <TB3> INFO: 1705600 events read in total (202587ms).
[12:02:57.131] <TB3> INFO: Test took 203440ms.
[12:03:10.601] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C0.dat
[12:03:10.601] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C1.dat
[12:03:10.601] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C2.dat
[12:03:10.601] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C3.dat
[12:03:10.602] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C4.dat
[12:03:10.602] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C5.dat
[12:03:10.602] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C6.dat
[12:03:10.602] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C7.dat
[12:03:10.602] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C8.dat
[12:03:10.603] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C9.dat
[12:03:10.603] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C10.dat
[12:03:10.603] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C11.dat
[12:03:10.603] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C12.dat
[12:03:10.603] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C13.dat
[12:03:10.603] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C14.dat
[12:03:10.603] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C15.dat
[12:03:10.604] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C0.dat
[12:03:10.614] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C1.dat
[12:03:10.624] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C2.dat
[12:03:10.630] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C3.dat
[12:03:10.638] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C4.dat
[12:03:10.644] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C5.dat
[12:03:10.651] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C6.dat
[12:03:10.660] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C7.dat
[12:03:10.671] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C8.dat
[12:03:10.681] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C9.dat
[12:03:10.692] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C10.dat
[12:03:10.702] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C11.dat
[12:03:10.713] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C12.dat
[12:03:10.723] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C13.dat
[12:03:10.734] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C14.dat
[12:03:10.744] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C15.dat
[12:03:10.753] <TB3> INFO: PixTestTrim::trimTest() done
[12:03:10.753] <TB3> INFO: vtrim: 116 111 105 105 97 108 96 100 108 113 96 108 94 86 83 97
[12:03:10.753] <TB3> INFO: vthrcomp: 87 93 86 99 83 93 95 92 101 92 87 85 105 88 84 100
[12:03:10.753] <TB3> INFO: vcal mean: 34.98 35.02 34.97 34.97 34.98 34.97 35.06 34.98 35.01 35.00 34.97 34.99 34.94 34.97 34.96 34.98
[12:03:10.753] <TB3> INFO: vcal RMS: 0.87 0.77 0.81 0.87 0.81 0.83 0.83 0.85 0.89 0.82 0.80 0.79 0.80 0.77 0.79 0.82
[12:03:10.753] <TB3> INFO: bits mean: 9.57 9.19 9.34 9.48 10.06 9.11 9.46 9.30 8.93 9.03 9.56 9.66 9.19 9.65 9.50 9.82
[12:03:10.753] <TB3> INFO: bits RMS: 2.78 2.68 2.71 2.71 2.50 2.85 2.83 2.59 2.86 2.85 2.61 2.59 2.46 2.51 2.60 2.69
[12:03:10.761] <TB3> INFO: ----------------------------------------------------------------------
[12:03:10.761] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[12:03:10.761] <TB3> INFO: ----------------------------------------------------------------------
[12:03:10.767] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[12:03:10.775] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:03:10.775] <TB3> INFO: run 1 of 1
[12:03:11.079] <TB3> INFO: Expecting 4160000 events.
[12:05:01.073] <TB3> INFO: 921565 events read in total (109437ms).
[12:06:51.296] <TB3> INFO: 1837745 events read in total (219660ms).
[12:08:41.003] <TB3> INFO: 2746925 events read in total (329367ms).
[12:10:31.031] <TB3> INFO: 3653830 events read in total (439395ms).
[12:11:31.922] <TB3> INFO: 4160000 events read in total (500286ms).
[12:11:31.978] <TB3> INFO: Test took 501203ms.
[12:12:01.162] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[12:12:01.170] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:12:01.170] <TB3> INFO: run 1 of 1
[12:12:01.431] <TB3> INFO: Expecting 3473600 events.
[12:13:51.978] <TB3> INFO: 955965 events read in total (109990ms).
[12:15:42.385] <TB3> INFO: 1904165 events read in total (220397ms).
[12:17:32.113] <TB3> INFO: 2844105 events read in total (330125ms).
[12:18:46.809] <TB3> INFO: 3473600 events read in total (404821ms).
[12:18:46.859] <TB3> INFO: Test took 405689ms.
[12:19:12.490] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[12:19:12.498] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:19:12.498] <TB3> INFO: run 1 of 1
[12:19:12.759] <TB3> INFO: Expecting 3224000 events.
[12:21:04.584] <TB3> INFO: 988455 events read in total (111268ms).
[12:22:55.316] <TB3> INFO: 1965895 events read in total (222000ms).
[12:24:47.097] <TB3> INFO: 2936870 events read in total (333781ms).
[12:25:19.846] <TB3> INFO: 3224000 events read in total (366530ms).
[12:25:19.891] <TB3> INFO: Test took 367394ms.
[12:25:44.437] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[12:25:44.445] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:25:44.445] <TB3> INFO: run 1 of 1
[12:25:44.706] <TB3> INFO: Expecting 3203200 events.
[12:27:36.610] <TB3> INFO: 990545 events read in total (111347ms).
[12:29:26.793] <TB3> INFO: 1969655 events read in total (221530ms).
[12:31:18.656] <TB3> INFO: 2942205 events read in total (333393ms).
[12:31:48.396] <TB3> INFO: 3203200 events read in total (363133ms).
[12:31:48.444] <TB3> INFO: Test took 363999ms.
[12:32:12.191] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[12:32:12.199] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:32:12.199] <TB3> INFO: run 1 of 1
[12:32:12.460] <TB3> INFO: Expecting 3203200 events.
[12:34:03.579] <TB3> INFO: 989930 events read in total (110562ms).
[12:35:54.744] <TB3> INFO: 1968310 events read in total (221727ms).
[12:37:45.931] <TB3> INFO: 2939920 events read in total (332914ms).
[12:38:15.684] <TB3> INFO: 3203200 events read in total (362667ms).
[12:38:15.738] <TB3> INFO: Test took 363540ms.
[12:38:40.547] <TB3> INFO: PixTestTrim::trimBitTest() done
[12:38:40.549] <TB3> INFO: PixTestTrim::doTest() done, duration: 6531 seconds
[12:38:40.551] <TB3> INFO: Decoding statistics:
[12:38:40.551] <TB3> INFO: General information:
[12:38:40.551] <TB3> INFO: 16bit words read: 0
[12:38:40.551] <TB3> INFO: valid events total: 0
[12:38:40.551] <TB3> INFO: empty events: 0
[12:38:40.551] <TB3> INFO: valid events with pixels: 0
[12:38:40.551] <TB3> INFO: valid pixel hits: 0
[12:38:40.551] <TB3> INFO: Event errors: 0
[12:38:40.551] <TB3> INFO: start marker: 0
[12:38:40.551] <TB3> INFO: stop marker: 0
[12:38:40.551] <TB3> INFO: overflow: 0
[12:38:40.551] <TB3> INFO: invalid 5bit words: 0
[12:38:40.551] <TB3> INFO: invalid XOR eye diagram: 0
[12:38:40.551] <TB3> INFO: frame (failed synchr.): 0
[12:38:40.551] <TB3> INFO: idle data (no TBM trl): 0
[12:38:40.551] <TB3> INFO: no data (only TBM hdr): 0
[12:38:40.551] <TB3> INFO: TBM errors: 0
[12:38:40.551] <TB3> INFO: flawed TBM headers: 0
[12:38:40.551] <TB3> INFO: flawed TBM trailers: 0
[12:38:40.551] <TB3> INFO: event ID mismatches: 0
[12:38:40.551] <TB3> INFO: ROC errors: 0
[12:38:40.551] <TB3> INFO: missing ROC header(s): 0
[12:38:40.551] <TB3> INFO: misplaced readback start: 0
[12:38:40.551] <TB3> INFO: Pixel decoding errors: 0
[12:38:40.551] <TB3> INFO: pixel data incomplete: 0
[12:38:40.551] <TB3> INFO: pixel address: 0
[12:38:40.551] <TB3> INFO: pulse height fill bit: 0
[12:38:40.551] <TB3> INFO: buffer corruption: 0
[12:38:41.242] <TB3> INFO: ######################################################################
[12:38:41.242] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[12:38:41.242] <TB3> INFO: ######################################################################
[12:38:41.512] <TB3> INFO: Expecting 41600 events.
[12:38:49.482] <TB3> INFO: 41600 events read in total (7413ms).
[12:38:49.482] <TB3> INFO: Test took 8238ms.
[12:38:49.938] <TB3> INFO: Expecting 41600 events.
[12:38:57.935] <TB3> INFO: 41600 events read in total (7440ms).
[12:38:57.936] <TB3> INFO: Test took 8264ms.
[12:38:58.268] <TB3> INFO: Expecting 41600 events.
[12:39:06.183] <TB3> INFO: 41600 events read in total (7358ms).
[12:39:06.183] <TB3> INFO: Test took 8224ms.
[12:39:06.440] <TB3> INFO: Expecting 2560 events.
[12:39:07.418] <TB3> INFO: 2560 events read in total (422ms).
[12:39:07.419] <TB3> INFO: Test took 1222ms.
[12:39:07.724] <TB3> INFO: Expecting 2560 events.
[12:39:08.705] <TB3> INFO: 2560 events read in total (424ms).
[12:39:08.706] <TB3> INFO: Test took 1287ms.
[12:39:09.011] <TB3> INFO: Expecting 2560 events.
[12:39:09.990] <TB3> INFO: 2560 events read in total (423ms).
[12:39:09.990] <TB3> INFO: Test took 1283ms.
[12:39:10.296] <TB3> INFO: Expecting 2560 events.
[12:39:11.272] <TB3> INFO: 2560 events read in total (419ms).
[12:39:11.272] <TB3> INFO: Test took 1278ms.
[12:39:11.577] <TB3> INFO: Expecting 2560 events.
[12:39:12.571] <TB3> INFO: 2560 events read in total (437ms).
[12:39:12.571] <TB3> INFO: Test took 1298ms.
[12:39:12.878] <TB3> INFO: Expecting 2560 events.
[12:39:13.872] <TB3> INFO: 2560 events read in total (437ms).
[12:39:13.872] <TB3> INFO: Test took 1300ms.
[12:39:14.177] <TB3> INFO: Expecting 2560 events.
[12:39:15.176] <TB3> INFO: 2560 events read in total (442ms).
[12:39:15.176] <TB3> INFO: Test took 1303ms.
[12:39:15.483] <TB3> INFO: Expecting 2560 events.
[12:39:16.479] <TB3> INFO: 2560 events read in total (439ms).
[12:39:16.479] <TB3> INFO: Test took 1302ms.
[12:39:16.784] <TB3> INFO: Expecting 2560 events.
[12:39:17.766] <TB3> INFO: 2560 events read in total (425ms).
[12:39:17.766] <TB3> INFO: Test took 1286ms.
[12:39:18.072] <TB3> INFO: Expecting 2560 events.
[12:39:19.052] <TB3> INFO: 2560 events read in total (423ms).
[12:39:19.052] <TB3> INFO: Test took 1285ms.
[12:39:19.358] <TB3> INFO: Expecting 2560 events.
[12:39:20.341] <TB3> INFO: 2560 events read in total (426ms).
[12:39:20.342] <TB3> INFO: Test took 1289ms.
[12:39:20.647] <TB3> INFO: Expecting 2560 events.
[12:39:21.624] <TB3> INFO: 2560 events read in total (421ms).
[12:39:21.624] <TB3> INFO: Test took 1277ms.
[12:39:21.930] <TB3> INFO: Expecting 2560 events.
[12:39:22.927] <TB3> INFO: 2560 events read in total (440ms).
[12:39:22.927] <TB3> INFO: Test took 1303ms.
[12:39:23.232] <TB3> INFO: Expecting 2560 events.
[12:39:24.223] <TB3> INFO: 2560 events read in total (434ms).
[12:39:24.223] <TB3> INFO: Test took 1294ms.
[12:39:24.529] <TB3> INFO: Expecting 2560 events.
[12:39:25.528] <TB3> INFO: 2560 events read in total (442ms).
[12:39:25.529] <TB3> INFO: Test took 1304ms.
[12:39:25.834] <TB3> INFO: Expecting 2560 events.
[12:39:26.828] <TB3> INFO: 2560 events read in total (437ms).
[12:39:26.828] <TB3> INFO: Test took 1276ms.
[12:39:26.833] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:39:27.135] <TB3> INFO: Expecting 655360 events.
[12:40:18.542] <TB3> INFO: 655360 events read in total (50850ms).
[12:40:18.553] <TB3> INFO: Expecting 655360 events.
[12:41:09.883] <TB3> INFO: 655360 events read in total (50928ms).
[12:41:09.897] <TB3> INFO: Expecting 655360 events.
[12:42:01.138] <TB3> INFO: 655360 events read in total (50838ms).
[12:42:01.161] <TB3> INFO: Expecting 655360 events.
[12:42:52.351] <TB3> INFO: 655360 events read in total (50788ms).
[12:42:52.373] <TB3> INFO: Expecting 655360 events.
[12:43:43.717] <TB3> INFO: 655360 events read in total (50942ms).
[12:43:43.749] <TB3> INFO: Expecting 655360 events.
[12:44:35.137] <TB3> INFO: 655360 events read in total (50985ms).
[12:44:35.167] <TB3> INFO: Expecting 655360 events.
[12:45:26.604] <TB3> INFO: 655360 events read in total (51034ms).
[12:45:26.644] <TB3> INFO: Expecting 655360 events.
[12:46:17.988] <TB3> INFO: 655360 events read in total (50941ms).
[12:46:18.027] <TB3> INFO: Expecting 655360 events.
[12:47:09.373] <TB3> INFO: 655360 events read in total (50943ms).
[12:47:09.428] <TB3> INFO: Expecting 655360 events.
[12:48:00.772] <TB3> INFO: 655360 events read in total (50942ms).
[12:48:00.819] <TB3> INFO: Expecting 655360 events.
[12:48:52.196] <TB3> INFO: 655360 events read in total (50975ms).
[12:48:52.254] <TB3> INFO: Expecting 655360 events.
[12:49:43.703] <TB3> INFO: 655360 events read in total (51047ms).
[12:49:43.758] <TB3> INFO: Expecting 655360 events.
[12:50:35.023] <TB3> INFO: 655360 events read in total (50863ms).
[12:50:35.089] <TB3> INFO: Expecting 655360 events.
[12:51:26.457] <TB3> INFO: 655360 events read in total (50966ms).
[12:51:26.521] <TB3> INFO: Expecting 655360 events.
[12:52:17.945] <TB3> INFO: 655360 events read in total (51022ms).
[12:52:18.019] <TB3> INFO: Expecting 655360 events.
[12:53:09.391] <TB3> INFO: 655360 events read in total (50970ms).
[12:53:09.462] <TB3> INFO: Test took 822629ms.
[12:53:09.545] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:53:09.773] <TB3> INFO: Expecting 655360 events.
[12:54:01.088] <TB3> INFO: 655360 events read in total (50758ms).
[12:54:01.100] <TB3> INFO: Expecting 655360 events.
[12:54:52.404] <TB3> INFO: 655360 events read in total (50901ms).
[12:54:52.418] <TB3> INFO: Expecting 655360 events.
[12:55:43.572] <TB3> INFO: 655360 events read in total (50752ms).
[12:55:43.591] <TB3> INFO: Expecting 655360 events.
[12:56:35.015] <TB3> INFO: 655360 events read in total (51021ms).
[12:56:35.039] <TB3> INFO: Expecting 655360 events.
[12:57:26.438] <TB3> INFO: 655360 events read in total (50997ms).
[12:57:26.465] <TB3> INFO: Expecting 655360 events.
[12:58:17.701] <TB3> INFO: 655360 events read in total (50833ms).
[12:58:17.733] <TB3> INFO: Expecting 655360 events.
[12:59:08.930] <TB3> INFO: 655360 events read in total (50794ms).
[12:59:08.974] <TB3> INFO: Expecting 655360 events.
[13:00:00.132] <TB3> INFO: 655360 events read in total (50756ms).
[13:00:00.173] <TB3> INFO: Expecting 655360 events.
[13:00:51.363] <TB3> INFO: 655360 events read in total (50788ms).
[13:00:51.408] <TB3> INFO: Expecting 655360 events.
[13:01:42.917] <TB3> INFO: 655360 events read in total (51106ms).
[13:01:42.964] <TB3> INFO: Expecting 655360 events.
[13:02:34.153] <TB3> INFO: 655360 events read in total (50787ms).
[13:02:34.218] <TB3> INFO: Expecting 655360 events.
[13:03:25.592] <TB3> INFO: 655360 events read in total (50972ms).
[13:03:25.649] <TB3> INFO: Expecting 655360 events.
[13:04:16.903] <TB3> INFO: 655360 events read in total (50851ms).
[13:04:16.968] <TB3> INFO: Expecting 655360 events.
[13:05:08.442] <TB3> INFO: 655360 events read in total (51072ms).
[13:05:08.504] <TB3> INFO: Expecting 655360 events.
[13:05:59.843] <TB3> INFO: 655360 events read in total (50936ms).
[13:05:59.914] <TB3> INFO: Expecting 655360 events.
[13:06:51.036] <TB3> INFO: 655360 events read in total (50720ms).
[13:06:51.110] <TB3> INFO: Test took 821565ms.
[13:06:51.362] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.369] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.376] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.382] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.389] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.396] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.402] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.409] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.416] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.423] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.431] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.438] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.445] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.452] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.459] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[13:06:51.466] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[13:06:51.473] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.480] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:06:51.536] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C0.dat
[13:06:51.536] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C1.dat
[13:06:51.536] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C2.dat
[13:06:51.536] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C3.dat
[13:06:51.536] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C4.dat
[13:06:51.536] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C5.dat
[13:06:51.536] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C6.dat
[13:06:51.536] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C7.dat
[13:06:51.537] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C8.dat
[13:06:51.537] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C9.dat
[13:06:51.537] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C10.dat
[13:06:51.537] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C11.dat
[13:06:51.537] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C12.dat
[13:06:51.537] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C13.dat
[13:06:51.537] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C14.dat
[13:06:51.537] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C15.dat
[13:06:51.810] <TB3> INFO: Expecting 41600 events.
[13:06:59.371] <TB3> INFO: 41600 events read in total (7004ms).
[13:06:59.372] <TB3> INFO: Test took 7830ms.
[13:06:59.824] <TB3> INFO: Expecting 41600 events.
[13:07:07.326] <TB3> INFO: 41600 events read in total (6945ms).
[13:07:07.327] <TB3> INFO: Test took 7764ms.
[13:07:07.810] <TB3> INFO: Expecting 41600 events.
[13:07:15.438] <TB3> INFO: 41600 events read in total (7072ms).
[13:07:15.438] <TB3> INFO: Test took 7924ms.
[13:07:15.630] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:15.721] <TB3> INFO: Expecting 2560 events.
[13:07:16.717] <TB3> INFO: 2560 events read in total (440ms).
[13:07:16.719] <TB3> INFO: Test took 1089ms.
[13:07:16.722] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:17.023] <TB3> INFO: Expecting 2560 events.
[13:07:18.015] <TB3> INFO: 2560 events read in total (435ms).
[13:07:18.015] <TB3> INFO: Test took 1293ms.
[13:07:18.018] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:18.321] <TB3> INFO: Expecting 2560 events.
[13:07:19.316] <TB3> INFO: 2560 events read in total (438ms).
[13:07:19.316] <TB3> INFO: Test took 1299ms.
[13:07:19.324] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:19.623] <TB3> INFO: Expecting 2560 events.
[13:07:20.619] <TB3> INFO: 2560 events read in total (439ms).
[13:07:20.619] <TB3> INFO: Test took 1295ms.
[13:07:20.624] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:20.926] <TB3> INFO: Expecting 2560 events.
[13:07:21.919] <TB3> INFO: 2560 events read in total (437ms).
[13:07:21.919] <TB3> INFO: Test took 1295ms.
[13:07:21.923] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:22.225] <TB3> INFO: Expecting 2560 events.
[13:07:23.217] <TB3> INFO: 2560 events read in total (435ms).
[13:07:23.217] <TB3> INFO: Test took 1294ms.
[13:07:23.221] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:23.523] <TB3> INFO: Expecting 2560 events.
[13:07:24.519] <TB3> INFO: 2560 events read in total (439ms).
[13:07:24.520] <TB3> INFO: Test took 1299ms.
[13:07:24.532] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:24.826] <TB3> INFO: Expecting 2560 events.
[13:07:25.824] <TB3> INFO: 2560 events read in total (441ms).
[13:07:25.825] <TB3> INFO: Test took 1293ms.
[13:07:25.828] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:26.131] <TB3> INFO: Expecting 2560 events.
[13:07:27.123] <TB3> INFO: 2560 events read in total (435ms).
[13:07:27.124] <TB3> INFO: Test took 1296ms.
[13:07:27.127] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:27.430] <TB3> INFO: Expecting 2560 events.
[13:07:28.426] <TB3> INFO: 2560 events read in total (439ms).
[13:07:28.426] <TB3> INFO: Test took 1299ms.
[13:07:28.430] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:28.734] <TB3> INFO: Expecting 2560 events.
[13:07:29.732] <TB3> INFO: 2560 events read in total (441ms).
[13:07:29.733] <TB3> INFO: Test took 1303ms.
[13:07:29.746] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:30.040] <TB3> INFO: Expecting 2560 events.
[13:07:31.038] <TB3> INFO: 2560 events read in total (442ms).
[13:07:31.039] <TB3> INFO: Test took 1293ms.
[13:07:31.041] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:31.346] <TB3> INFO: Expecting 2560 events.
[13:07:32.338] <TB3> INFO: 2560 events read in total (435ms).
[13:07:32.338] <TB3> INFO: Test took 1297ms.
[13:07:32.341] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:32.646] <TB3> INFO: Expecting 2560 events.
[13:07:33.645] <TB3> INFO: 2560 events read in total (443ms).
[13:07:33.646] <TB3> INFO: Test took 1305ms.
[13:07:33.649] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:33.951] <TB3> INFO: Expecting 2560 events.
[13:07:34.943] <TB3> INFO: 2560 events read in total (435ms).
[13:07:34.944] <TB3> INFO: Test took 1295ms.
[13:07:34.948] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:35.250] <TB3> INFO: Expecting 2560 events.
[13:07:36.248] <TB3> INFO: 2560 events read in total (442ms).
[13:07:36.249] <TB3> INFO: Test took 1301ms.
[13:07:36.252] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:36.555] <TB3> INFO: Expecting 2560 events.
[13:07:37.554] <TB3> INFO: 2560 events read in total (443ms).
[13:07:37.554] <TB3> INFO: Test took 1302ms.
[13:07:37.559] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:37.860] <TB3> INFO: Expecting 2560 events.
[13:07:38.854] <TB3> INFO: 2560 events read in total (437ms).
[13:07:38.854] <TB3> INFO: Test took 1295ms.
[13:07:38.857] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:39.161] <TB3> INFO: Expecting 2560 events.
[13:07:40.152] <TB3> INFO: 2560 events read in total (434ms).
[13:07:40.152] <TB3> INFO: Test took 1295ms.
[13:07:40.154] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:40.458] <TB3> INFO: Expecting 2560 events.
[13:07:41.449] <TB3> INFO: 2560 events read in total (434ms).
[13:07:41.449] <TB3> INFO: Test took 1295ms.
[13:07:41.453] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:41.755] <TB3> INFO: Expecting 2560 events.
[13:07:42.745] <TB3> INFO: 2560 events read in total (433ms).
[13:07:42.746] <TB3> INFO: Test took 1293ms.
[13:07:42.750] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:43.052] <TB3> INFO: Expecting 2560 events.
[13:07:44.043] <TB3> INFO: 2560 events read in total (434ms).
[13:07:44.044] <TB3> INFO: Test took 1294ms.
[13:07:44.047] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:44.350] <TB3> INFO: Expecting 2560 events.
[13:07:45.341] <TB3> INFO: 2560 events read in total (434ms).
[13:07:45.342] <TB3> INFO: Test took 1295ms.
[13:07:45.352] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:45.649] <TB3> INFO: Expecting 2560 events.
[13:07:46.642] <TB3> INFO: 2560 events read in total (436ms).
[13:07:46.642] <TB3> INFO: Test took 1290ms.
[13:07:46.645] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:46.949] <TB3> INFO: Expecting 2560 events.
[13:07:47.942] <TB3> INFO: 2560 events read in total (436ms).
[13:07:47.942] <TB3> INFO: Test took 1297ms.
[13:07:47.945] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:48.249] <TB3> INFO: Expecting 2560 events.
[13:07:49.237] <TB3> INFO: 2560 events read in total (432ms).
[13:07:49.238] <TB3> INFO: Test took 1293ms.
[13:07:49.241] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:49.545] <TB3> INFO: Expecting 2560 events.
[13:07:50.537] <TB3> INFO: 2560 events read in total (435ms).
[13:07:50.537] <TB3> INFO: Test took 1297ms.
[13:07:50.546] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:50.845] <TB3> INFO: Expecting 2560 events.
[13:07:51.838] <TB3> INFO: 2560 events read in total (437ms).
[13:07:51.838] <TB3> INFO: Test took 1292ms.
[13:07:51.842] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:52.144] <TB3> INFO: Expecting 2560 events.
[13:07:53.138] <TB3> INFO: 2560 events read in total (437ms).
[13:07:53.138] <TB3> INFO: Test took 1296ms.
[13:07:53.141] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:53.445] <TB3> INFO: Expecting 2560 events.
[13:07:54.438] <TB3> INFO: 2560 events read in total (436ms).
[13:07:54.439] <TB3> INFO: Test took 1298ms.
[13:07:54.442] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:54.745] <TB3> INFO: Expecting 2560 events.
[13:07:55.739] <TB3> INFO: 2560 events read in total (437ms).
[13:07:55.739] <TB3> INFO: Test took 1298ms.
[13:07:55.743] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:07:56.045] <TB3> INFO: Expecting 2560 events.
[13:07:57.038] <TB3> INFO: 2560 events read in total (436ms).
[13:07:57.039] <TB3> INFO: Test took 1296ms.
[13:07:57.531] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 1756 seconds
[13:07:57.531] <TB3> INFO: PH scale (per ROC): 80 93 90 79 86 80 81 76 84 90 86 87 91 97 88 85
[13:07:57.531] <TB3> INFO: PH offset (per ROC): 151 157 155 165 151 149 150 173 160 151 164 147 142 144 139 155
[13:07:57.537] <TB3> INFO: Decoding statistics:
[13:07:57.537] <TB3> INFO: General information:
[13:07:57.537] <TB3> INFO: 16bit words read: 86928
[13:07:57.537] <TB3> INFO: valid events total: 10240
[13:07:57.537] <TB3> INFO: empty events: 7736
[13:07:57.537] <TB3> INFO: valid events with pixels: 2504
[13:07:57.537] <TB3> INFO: valid pixel hits: 2504
[13:07:57.537] <TB3> INFO: Event errors: 0
[13:07:57.537] <TB3> INFO: start marker: 0
[13:07:57.537] <TB3> INFO: stop marker: 0
[13:07:57.537] <TB3> INFO: overflow: 0
[13:07:57.537] <TB3> INFO: invalid 5bit words: 0
[13:07:57.537] <TB3> INFO: invalid XOR eye diagram: 0
[13:07:57.537] <TB3> INFO: frame (failed synchr.): 0
[13:07:57.537] <TB3> INFO: idle data (no TBM trl): 0
[13:07:57.537] <TB3> INFO: no data (only TBM hdr): 0
[13:07:57.537] <TB3> INFO: TBM errors: 0
[13:07:57.537] <TB3> INFO: flawed TBM headers: 0
[13:07:57.537] <TB3> INFO: flawed TBM trailers: 0
[13:07:57.537] <TB3> INFO: event ID mismatches: 0
[13:07:57.537] <TB3> INFO: ROC errors: 0
[13:07:57.537] <TB3> INFO: missing ROC header(s): 0
[13:07:57.537] <TB3> INFO: misplaced readback start: 0
[13:07:57.537] <TB3> INFO: Pixel decoding errors: 0
[13:07:57.537] <TB3> INFO: pixel data incomplete: 0
[13:07:57.537] <TB3> INFO: pixel address: 0
[13:07:57.537] <TB3> INFO: pulse height fill bit: 0
[13:07:57.537] <TB3> INFO: buffer corruption: 0
[13:07:57.720] <TB3> INFO: ######################################################################
[13:07:57.720] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:07:57.720] <TB3> INFO: ######################################################################
[13:07:57.730] <TB3> INFO: scanning low vcal = 10
[13:07:58.000] <TB3> INFO: Expecting 41600 events.
[13:08:03.579] <TB3> INFO: 41600 events read in total (5022ms).
[13:08:03.579] <TB3> INFO: Test took 5848ms.
[13:08:03.583] <TB3> INFO: scanning low vcal = 20
[13:08:03.877] <TB3> INFO: Expecting 41600 events.
[13:08:09.464] <TB3> INFO: 41600 events read in total (5030ms).
[13:08:09.465] <TB3> INFO: Test took 5882ms.
[13:08:09.469] <TB3> INFO: scanning low vcal = 30
[13:08:09.791] <TB3> INFO: Expecting 41600 events.
[13:08:15.499] <TB3> INFO: 41600 events read in total (5151ms).
[13:08:15.500] <TB3> INFO: Test took 6031ms.
[13:08:15.502] <TB3> INFO: scanning low vcal = 40
[13:08:15.805] <TB3> INFO: Expecting 41600 events.
[13:08:23.879] <TB3> INFO: 41600 events read in total (7517ms).
[13:08:23.880] <TB3> INFO: Test took 8378ms.
[13:08:23.884] <TB3> INFO: scanning low vcal = 50
[13:08:24.154] <TB3> INFO: Expecting 41600 events.
[13:08:32.488] <TB3> INFO: 41600 events read in total (7777ms).
[13:08:32.489] <TB3> INFO: Test took 8605ms.
[13:08:32.493] <TB3> INFO: scanning low vcal = 60
[13:08:32.776] <TB3> INFO: Expecting 41600 events.
[13:08:41.159] <TB3> INFO: 41600 events read in total (7826ms).
[13:08:41.160] <TB3> INFO: Test took 8667ms.
[13:08:41.164] <TB3> INFO: scanning low vcal = 70
[13:08:41.440] <TB3> INFO: Expecting 41600 events.
[13:08:49.803] <TB3> INFO: 41600 events read in total (7806ms).
[13:08:49.803] <TB3> INFO: Test took 8639ms.
[13:08:49.807] <TB3> INFO: scanning low vcal = 80
[13:08:50.072] <TB3> INFO: Expecting 41600 events.
[13:08:58.414] <TB3> INFO: 41600 events read in total (7785ms).
[13:08:58.415] <TB3> INFO: Test took 8608ms.
[13:08:58.418] <TB3> INFO: scanning low vcal = 90
[13:08:58.683] <TB3> INFO: Expecting 41600 events.
[13:09:07.025] <TB3> INFO: 41600 events read in total (7785ms).
[13:09:07.025] <TB3> INFO: Test took 8607ms.
[13:09:07.029] <TB3> INFO: scanning low vcal = 100
[13:09:07.296] <TB3> INFO: Expecting 41600 events.
[13:09:15.682] <TB3> INFO: 41600 events read in total (7829ms).
[13:09:15.683] <TB3> INFO: Test took 8653ms.
[13:09:15.686] <TB3> INFO: scanning low vcal = 110
[13:09:15.948] <TB3> INFO: Expecting 41600 events.
[13:09:24.311] <TB3> INFO: 41600 events read in total (7806ms).
[13:09:24.313] <TB3> INFO: Test took 8627ms.
[13:09:24.317] <TB3> INFO: scanning low vcal = 120
[13:09:24.593] <TB3> INFO: Expecting 41600 events.
[13:09:33.034] <TB3> INFO: 41600 events read in total (7884ms).
[13:09:33.035] <TB3> INFO: Test took 8718ms.
[13:09:33.039] <TB3> INFO: scanning low vcal = 130
[13:09:33.304] <TB3> INFO: Expecting 41600 events.
[13:09:41.704] <TB3> INFO: 41600 events read in total (7843ms).
[13:09:41.705] <TB3> INFO: Test took 8666ms.
[13:09:41.708] <TB3> INFO: scanning low vcal = 140
[13:09:41.977] <TB3> INFO: Expecting 41600 events.
[13:09:50.396] <TB3> INFO: 41600 events read in total (7862ms).
[13:09:50.397] <TB3> INFO: Test took 8688ms.
[13:09:50.401] <TB3> INFO: scanning low vcal = 150
[13:09:50.666] <TB3> INFO: Expecting 41600 events.
[13:09:59.085] <TB3> INFO: 41600 events read in total (7863ms).
[13:09:59.086] <TB3> INFO: Test took 8685ms.
[13:09:59.089] <TB3> INFO: scanning low vcal = 160
[13:09:59.359] <TB3> INFO: Expecting 41600 events.
[13:10:07.750] <TB3> INFO: 41600 events read in total (7833ms).
[13:10:07.750] <TB3> INFO: Test took 8660ms.
[13:10:07.754] <TB3> INFO: scanning low vcal = 170
[13:10:08.018] <TB3> INFO: Expecting 41600 events.
[13:10:16.397] <TB3> INFO: 41600 events read in total (7823ms).
[13:10:16.397] <TB3> INFO: Test took 8643ms.
[13:10:16.403] <TB3> INFO: scanning low vcal = 180
[13:10:16.677] <TB3> INFO: Expecting 41600 events.
[13:10:25.084] <TB3> INFO: 41600 events read in total (7851ms).
[13:10:25.084] <TB3> INFO: Test took 8681ms.
[13:10:25.088] <TB3> INFO: scanning low vcal = 190
[13:10:25.360] <TB3> INFO: Expecting 41600 events.
[13:10:33.753] <TB3> INFO: 41600 events read in total (7836ms).
[13:10:33.753] <TB3> INFO: Test took 8665ms.
[13:10:33.757] <TB3> INFO: scanning low vcal = 200
[13:10:34.035] <TB3> INFO: Expecting 41600 events.
[13:10:42.419] <TB3> INFO: 41600 events read in total (7827ms).
[13:10:42.419] <TB3> INFO: Test took 8662ms.
[13:10:42.423] <TB3> INFO: scanning low vcal = 210
[13:10:42.701] <TB3> INFO: Expecting 41600 events.
[13:10:51.074] <TB3> INFO: 41600 events read in total (7817ms).
[13:10:51.075] <TB3> INFO: Test took 8652ms.
[13:10:51.079] <TB3> INFO: scanning low vcal = 220
[13:10:51.348] <TB3> INFO: Expecting 41600 events.
[13:10:59.755] <TB3> INFO: 41600 events read in total (7850ms).
[13:10:59.755] <TB3> INFO: Test took 8676ms.
[13:10:59.760] <TB3> INFO: scanning low vcal = 230
[13:11:00.045] <TB3> INFO: Expecting 41600 events.
[13:11:08.455] <TB3> INFO: 41600 events read in total (7853ms).
[13:11:08.455] <TB3> INFO: Test took 8695ms.
[13:11:08.459] <TB3> INFO: scanning low vcal = 240
[13:11:08.724] <TB3> INFO: Expecting 41600 events.
[13:11:17.105] <TB3> INFO: 41600 events read in total (7824ms).
[13:11:17.106] <TB3> INFO: Test took 8647ms.
[13:11:17.109] <TB3> INFO: scanning low vcal = 250
[13:11:17.370] <TB3> INFO: Expecting 41600 events.
[13:11:25.748] <TB3> INFO: 41600 events read in total (7821ms).
[13:11:25.748] <TB3> INFO: Test took 8638ms.
[13:11:25.754] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[13:11:26.015] <TB3> INFO: Expecting 41600 events.
[13:11:34.480] <TB3> INFO: 41600 events read in total (7908ms).
[13:11:34.481] <TB3> INFO: Test took 8727ms.
[13:11:34.492] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[13:11:34.757] <TB3> INFO: Expecting 41600 events.
[13:11:43.169] <TB3> INFO: 41600 events read in total (7855ms).
[13:11:43.171] <TB3> INFO: Test took 8679ms.
[13:11:43.175] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[13:11:43.440] <TB3> INFO: Expecting 41600 events.
[13:11:51.808] <TB3> INFO: 41600 events read in total (7811ms).
[13:11:51.809] <TB3> INFO: Test took 8634ms.
[13:11:51.812] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[13:11:52.074] <TB3> INFO: Expecting 41600 events.
[13:12:00.347] <TB3> INFO: 41600 events read in total (7716ms).
[13:12:00.347] <TB3> INFO: Test took 8534ms.
[13:12:00.350] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:12:00.606] <TB3> INFO: Expecting 41600 events.
[13:12:08.874] <TB3> INFO: 41600 events read in total (7711ms).
[13:12:08.875] <TB3> INFO: Test took 8524ms.
[13:12:09.318] <TB3> INFO: PixTestGainPedestal::measure() done
[13:12:39.077] <TB3> INFO: PixTestGainPedestal::fit() done
[13:12:39.077] <TB3> INFO: non-linearity mean: 0.957 0.950 0.955 0.953 0.950 0.953 0.956 0.948 0.956 0.946 0.956 0.947 0.954 0.963 0.947 0.951
[13:12:39.077] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.005 0.005 0.005 0.007 0.005 0.007 0.007 0.007 0.006 0.006 0.005 0.004 0.006 0.006
[13:12:39.077] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:12:39.095] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:12:39.112] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:12:39.130] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:12:39.147] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:12:39.165] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:12:39.182] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:12:39.200] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:12:39.217] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:12:39.235] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:12:39.252] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:12:39.270] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:12:39.287] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:12:39.304] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:12:39.322] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:12:39.340] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:12:39.357] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 281 seconds
[13:12:39.357] <TB3> INFO: Decoding statistics:
[13:12:39.357] <TB3> INFO: General information:
[13:12:39.357] <TB3> INFO: 16bit words read: 2662400
[13:12:39.357] <TB3> INFO: valid events total: 166400
[13:12:39.357] <TB3> INFO: empty events: 0
[13:12:39.357] <TB3> INFO: valid events with pixels: 166400
[13:12:39.357] <TB3> INFO: valid pixel hits: 665600
[13:12:39.357] <TB3> INFO: Event errors: 0
[13:12:39.357] <TB3> INFO: start marker: 0
[13:12:39.357] <TB3> INFO: stop marker: 0
[13:12:39.357] <TB3> INFO: overflow: 0
[13:12:39.357] <TB3> INFO: invalid 5bit words: 0
[13:12:39.357] <TB3> INFO: invalid XOR eye diagram: 0
[13:12:39.357] <TB3> INFO: frame (failed synchr.): 0
[13:12:39.357] <TB3> INFO: idle data (no TBM trl): 0
[13:12:39.357] <TB3> INFO: no data (only TBM hdr): 0
[13:12:39.357] <TB3> INFO: TBM errors: 0
[13:12:39.357] <TB3> INFO: flawed TBM headers: 0
[13:12:39.357] <TB3> INFO: flawed TBM trailers: 0
[13:12:39.357] <TB3> INFO: event ID mismatches: 0
[13:12:39.357] <TB3> INFO: ROC errors: 0
[13:12:39.357] <TB3> INFO: missing ROC header(s): 0
[13:12:39.357] <TB3> INFO: misplaced readback start: 0
[13:12:39.357] <TB3> INFO: Pixel decoding errors: 0
[13:12:39.357] <TB3> INFO: pixel data incomplete: 0
[13:12:39.357] <TB3> INFO: pixel address: 0
[13:12:39.357] <TB3> INFO: pulse height fill bit: 0
[13:12:39.357] <TB3> INFO: buffer corruption: 0
[13:12:39.365] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C15.dat
[13:12:39.366] <TB3> INFO: ######################################################################
[13:12:39.366] <TB3> INFO: PixTestReadback::doTest()
[13:12:39.366] <TB3> INFO: ######################################################################
[13:12:39.366] <TB3> INFO: ----------------------------------------------------------------------
[13:12:39.366] <TB3> INFO: PixTestReadback::CalibrateVd()
[13:12:39.366] <TB3> INFO: ----------------------------------------------------------------------
[13:12:48.871] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C0.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C1.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C2.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C3.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C4.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C5.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C6.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C7.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C8.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C9.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C10.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C11.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C12.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C13.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C14.dat
[13:12:48.872] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C15.dat
[13:12:48.918] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:12:48.918] <TB3> INFO: ----------------------------------------------------------------------
[13:12:48.918] <TB3> INFO: PixTestReadback::CalibrateVa()
[13:12:48.918] <TB3> INFO: ----------------------------------------------------------------------
[13:12:58.435] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C0.dat
[13:12:58.435] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C1.dat
[13:12:58.435] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C2.dat
[13:12:58.435] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C3.dat
[13:12:58.435] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C4.dat
[13:12:58.435] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C5.dat
[13:12:58.435] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C6.dat
[13:12:58.435] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C7.dat
[13:12:58.436] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C8.dat
[13:12:58.436] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C9.dat
[13:12:58.436] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C10.dat
[13:12:58.436] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C11.dat
[13:12:58.436] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C12.dat
[13:12:58.436] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C13.dat
[13:12:58.436] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C14.dat
[13:12:58.436] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C15.dat
[13:12:58.482] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:12:58.482] <TB3> INFO: ----------------------------------------------------------------------
[13:12:58.482] <TB3> INFO: PixTestReadback::readbackVbg()
[13:12:58.482] <TB3> INFO: ----------------------------------------------------------------------
[13:13:05.813] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:13:05.813] <TB3> INFO: ----------------------------------------------------------------------
[13:13:05.813] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[13:13:05.813] <TB3> INFO: ----------------------------------------------------------------------
[13:13:05.813] <TB3> INFO: Vbg will be calibrated using Vd calibration
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.4calibrated Vbg = 1.1933 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.3calibrated Vbg = 1.19548 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.2calibrated Vbg = 1.20327 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.9calibrated Vbg = 1.20354 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 147.8calibrated Vbg = 1.21224 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 145.8calibrated Vbg = 1.22008 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150calibrated Vbg = 1.213 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.7calibrated Vbg = 1.21594 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.1calibrated Vbg = 1.2133 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 158.2calibrated Vbg = 1.20977 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 145.2calibrated Vbg = 1.21298 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 143.4calibrated Vbg = 1.20737 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 146.6calibrated Vbg = 1.20219 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 140.6calibrated Vbg = 1.20419 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 149.3calibrated Vbg = 1.20371 :::*/*/*/*/
[13:13:05.813] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151.3calibrated Vbg = 1.19668 :::*/*/*/*/
[13:13:05.816] <TB3> INFO: ----------------------------------------------------------------------
[13:13:05.816] <TB3> INFO: PixTestReadback::CalibrateIa()
[13:13:05.816] <TB3> INFO: ----------------------------------------------------------------------
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C0.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C1.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C2.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C3.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C4.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C5.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C6.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C7.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C8.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C9.dat
[13:15:41.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C10.dat
[13:15:41.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C11.dat
[13:15:41.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C12.dat
[13:15:41.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C13.dat
[13:15:41.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C14.dat
[13:15:41.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2344_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C15.dat
[13:15:41.609] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:15:41.610] <TB3> INFO: PixTestReadback::doTest() done
[13:15:41.610] <TB3> INFO: Decoding statistics:
[13:15:41.610] <TB3> INFO: General information:
[13:15:41.610] <TB3> INFO: 16bit words read: 1024
[13:15:41.610] <TB3> INFO: valid events total: 128
[13:15:41.610] <TB3> INFO: empty events: 128
[13:15:41.610] <TB3> INFO: valid events with pixels: 0
[13:15:41.610] <TB3> INFO: valid pixel hits: 0
[13:15:41.610] <TB3> INFO: Event errors: 0
[13:15:41.610] <TB3> INFO: start marker: 0
[13:15:41.610] <TB3> INFO: stop marker: 0
[13:15:41.610] <TB3> INFO: overflow: 0
[13:15:41.610] <TB3> INFO: invalid 5bit words: 0
[13:15:41.610] <TB3> INFO: invalid XOR eye diagram: 0
[13:15:41.610] <TB3> INFO: frame (failed synchr.): 0
[13:15:41.610] <TB3> INFO: idle data (no TBM trl): 0
[13:15:41.610] <TB3> INFO: no data (only TBM hdr): 0
[13:15:41.610] <TB3> INFO: TBM errors: 0
[13:15:41.610] <TB3> INFO: flawed TBM headers: 0
[13:15:41.610] <TB3> INFO: flawed TBM trailers: 0
[13:15:41.610] <TB3> INFO: event ID mismatches: 0
[13:15:41.610] <TB3> INFO: ROC errors: 0
[13:15:41.610] <TB3> INFO: missing ROC header(s): 0
[13:15:41.610] <TB3> INFO: misplaced readback start: 0
[13:15:41.610] <TB3> INFO: Pixel decoding errors: 0
[13:15:41.610] <TB3> INFO: pixel data incomplete: 0
[13:15:41.610] <TB3> INFO: pixel address: 0
[13:15:41.610] <TB3> INFO: pulse height fill bit: 0
[13:15:41.610] <TB3> INFO: buffer corruption: 0
[13:15:41.629] <TB3> INFO: Decoding statistics:
[13:15:41.629] <TB3> INFO: General information:
[13:15:41.629] <TB3> INFO: 16bit words read: 2750352
[13:15:41.629] <TB3> INFO: valid events total: 176768
[13:15:41.629] <TB3> INFO: empty events: 7864
[13:15:41.629] <TB3> INFO: valid events with pixels: 168904
[13:15:41.629] <TB3> INFO: valid pixel hits: 668104
[13:15:41.629] <TB3> INFO: Event errors: 0
[13:15:41.629] <TB3> INFO: start marker: 0
[13:15:41.629] <TB3> INFO: stop marker: 0
[13:15:41.629] <TB3> INFO: overflow: 0
[13:15:41.629] <TB3> INFO: invalid 5bit words: 0
[13:15:41.629] <TB3> INFO: invalid XOR eye diagram: 0
[13:15:41.629] <TB3> INFO: frame (failed synchr.): 0
[13:15:41.629] <TB3> INFO: idle data (no TBM trl): 0
[13:15:41.629] <TB3> INFO: no data (only TBM hdr): 0
[13:15:41.629] <TB3> INFO: TBM errors: 0
[13:15:41.629] <TB3> INFO: flawed TBM headers: 0
[13:15:41.629] <TB3> INFO: flawed TBM trailers: 0
[13:15:41.629] <TB3> INFO: event ID mismatches: 0
[13:15:41.629] <TB3> INFO: ROC errors: 0
[13:15:41.630] <TB3> INFO: missing ROC header(s): 0
[13:15:41.630] <TB3> INFO: misplaced readback start: 0
[13:15:41.630] <TB3> INFO: Pixel decoding errors: 0
[13:15:41.630] <TB3> INFO: pixel data incomplete: 0
[13:15:41.630] <TB3> INFO: pixel address: 0
[13:15:41.630] <TB3> INFO: pulse height fill bit: 0
[13:15:41.630] <TB3> INFO: buffer corruption: 0
[13:15:41.630] <TB3> INFO: enter test to run
[13:15:41.630] <TB3> INFO: test: exit no parameter change
[13:15:41.828] <TB3> QUIET: Connection to board 170 closed.
[13:15:41.907] <TB3> INFO: pXar: this is the end, my friend