[07:46:06.373] <TB0> INFO: *** Welcome to pxar ***
[07:46:06.373] <TB0> INFO: *** Today: 2016/10/10
[07:46:07.499] <TB0> INFO: *** Version: 0b00-dirty
[07:46:07.499] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C15.dat
[07:46:07.500] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:46:07.500] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//defaultMaskFile.dat
[07:46:07.500] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters_C15.dat
[07:46:07.568] <TB0> INFO: clk: 4
[07:46:07.569] <TB0> INFO: ctr: 4
[07:46:07.569] <TB0> INFO: sda: 19
[07:46:07.569] <TB0> INFO: tin: 9
[07:46:07.569] <TB0> INFO: level: 15
[07:46:07.569] <TB0> INFO: triggerdelay: 0
[07:46:07.569] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[07:46:07.569] <TB0> INFO: Log level: INFO
[07:46:07.577] <TB0> INFO: Found DTB DTB_WS6AYH
[07:46:07.589] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[07:46:07.591] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[07:46:07.593] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[07:46:09.091] <TB0> INFO: DUT info:
[07:46:09.091] <TB0> INFO: The DUT currently contains the following objects:
[07:46:09.091] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[07:46:09.091] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:46:09.091] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:46:09.091] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:46:09.091] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.091] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:46:09.492] <TB0> INFO: enter 'restricted' command line mode
[07:46:09.492] <TB0> INFO: enter test to run
[07:46:09.492] <TB0> INFO: test: pretest no parameter change
[07:46:09.492] <TB0> INFO: running: pretest
[07:46:09.496] <TB0> INFO: ----------------------------------------------------------------------
[07:46:09.496] <TB0> INFO: PixTestPretest::programROC()
[07:46:09.496] <TB0> INFO: ----------------------------------------------------------------------
[07:46:27.510] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:46:27.510] <TB0> INFO: IA differences per ROC: 17.7 19.3 18.5 20.1 20.9 18.5 18.5 18.5 20.9 18.5 19.3 20.1 21.7 19.3 20.1 20.1
[07:46:27.603] <TB0> INFO: enter test to run
[07:46:27.603] <TB0> INFO: test: pretest no parameter change
[07:46:27.603] <TB0> INFO: running: pretest
[07:46:27.604] <TB0> INFO: ----------------------------------------------------------------------
[07:46:27.604] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:46:27.604] <TB0> INFO: ----------------------------------------------------------------------
[07:46:33.285] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 391.5 mA = 24.4688 mA/ROC
[07:46:33.285] <TB0> INFO: i(loss) [mA/ROC]: 19.3 17.7 19.3 18.5 19.3 18.5 18.5 19.3 18.5 19.3 18.5 18.5 19.3 19.3 18.5 18.5
[07:46:33.318] <TB0> INFO: enter test to run
[07:46:33.318] <TB0> INFO: test: pretest no parameter change
[07:46:33.318] <TB0> INFO: running: pretest
[07:46:33.318] <TB0> INFO: ----------------------------------------------------------------------
[07:46:33.318] <TB0> INFO: PixTestPretest::findTiming()
[07:46:33.318] <TB0> INFO: ----------------------------------------------------------------------
[07:46:33.318] <TB0> INFO: PixTestCmd::init()

[07:48:01.600] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 4, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[07:48:01.600] <TB0> INFO: (success/tries = 100/100), width = 4
[07:48:01.601] <TB0> INFO: enter test to run
[07:48:01.601] <TB0> INFO: test: pretest no parameter change
[07:48:01.601] <TB0> INFO: running: pretest
[07:48:01.602] <TB0> INFO: ----------------------------------------------------------------------
[07:48:01.602] <TB0> INFO: PixTestPretest::findWorkingPixel()
[07:48:01.602] <TB0> INFO: ----------------------------------------------------------------------
[07:48:01.696] <TB0> INFO: Expecting 231680 events.


[07:48:11.031] <TB0> INFO: 231680 events read in total (8779ms).
[07:48:11.038] <TB0> INFO: Test took 9430ms.
[07:48:11.244] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:48:11.316] <TB0> INFO: enter test to run
[07:48:11.316] <TB0> INFO: test: pretest no parameter change
[07:48:11.316] <TB0> INFO: running: pretest
[07:48:11.317] <TB0> INFO: ----------------------------------------------------------------------
[07:48:11.317] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[07:48:11.317] <TB0> INFO: ----------------------------------------------------------------------
[07:48:11.410] <TB0> INFO: Expecting 231680 events.
[07:48:20.724] <TB0> INFO: 231680 events read in total (8758ms).
[07:48:20.730] <TB0> INFO: Test took 9409ms.
[07:48:20.961] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[07:48:20.961] <TB0> INFO: CalDel: 138 151 125 167 140 133 154 153 145 141 144 162 145 119 166 145
[07:48:20.961] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:48:20.993] <TB0> INFO: enter test to run
[07:48:20.993] <TB0> INFO: test: pretest no parameter change
[07:48:20.993] <TB0> INFO: running: pretest
[07:48:20.993] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C0.dat
[07:48:20.993] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C1.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C2.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C3.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C4.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C5.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C6.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C7.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C8.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C9.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C10.dat
[07:48:20.994] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C11.dat
[07:48:20.995] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C12.dat
[07:48:20.995] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C13.dat
[07:48:20.995] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C14.dat
[07:48:20.995] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters_C15.dat
[07:48:20.995] <TB0> INFO: enter test to run
[07:48:20.995] <TB0> INFO: test: fulltest no parameter change
[07:48:20.995] <TB0> INFO: running: fulltest
[07:48:20.995] <TB0> INFO: ######################################################################
[07:48:20.995] <TB0> INFO: PixTestFullTest::doTest()
[07:48:20.995] <TB0> INFO: ######################################################################
[07:48:20.996] <TB0> INFO: ######################################################################
[07:48:20.996] <TB0> INFO: PixTestAlive::doTest()
[07:48:20.996] <TB0> INFO: ######################################################################
[07:48:20.997] <TB0> INFO: ----------------------------------------------------------------------
[07:48:20.997] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:48:20.997] <TB0> INFO: ----------------------------------------------------------------------
[07:48:21.261] <TB0> INFO: Expecting 41600 events.
[07:48:24.729] <TB0> INFO: 41600 events read in total (2912ms).
[07:48:24.729] <TB0> INFO: Test took 3730ms.
[07:48:24.949] <TB0> INFO: PixTestAlive::aliveTest() done
[07:48:24.949] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:48:24.950] <TB0> INFO: ----------------------------------------------------------------------
[07:48:24.950] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:48:24.950] <TB0> INFO: ----------------------------------------------------------------------
[07:48:25.221] <TB0> INFO: Expecting 41600 events.
[07:48:27.935] <TB0> INFO: 41600 events read in total (2158ms).
[07:48:27.935] <TB0> INFO: Test took 2983ms.
[07:48:27.935] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:48:28.170] <TB0> INFO: PixTestAlive::maskTest() done
[07:48:28.170] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:48:28.172] <TB0> INFO: ----------------------------------------------------------------------
[07:48:28.172] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:48:28.172] <TB0> INFO: ----------------------------------------------------------------------
[07:48:28.440] <TB0> INFO: Expecting 41600 events.
[07:48:31.924] <TB0> INFO: 41600 events read in total (2928ms).
[07:48:31.925] <TB0> INFO: Test took 3751ms.
[07:48:32.141] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[07:48:32.141] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:48:32.141] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[07:48:32.141] <TB0> INFO: Decoding statistics:
[07:48:32.141] <TB0> INFO: General information:
[07:48:32.141] <TB0> INFO: 16bit words read: 0
[07:48:32.141] <TB0> INFO: valid events total: 0
[07:48:32.141] <TB0> INFO: empty events: 0
[07:48:32.141] <TB0> INFO: valid events with pixels: 0
[07:48:32.141] <TB0> INFO: valid pixel hits: 0
[07:48:32.141] <TB0> INFO: Event errors: 0
[07:48:32.141] <TB0> INFO: start marker: 0
[07:48:32.141] <TB0> INFO: stop marker: 0
[07:48:32.141] <TB0> INFO: overflow: 0
[07:48:32.141] <TB0> INFO: invalid 5bit words: 0
[07:48:32.141] <TB0> INFO: invalid XOR eye diagram: 0
[07:48:32.141] <TB0> INFO: frame (failed synchr.): 0
[07:48:32.141] <TB0> INFO: idle data (no TBM trl): 0
[07:48:32.141] <TB0> INFO: no data (only TBM hdr): 0
[07:48:32.141] <TB0> INFO: TBM errors: 0
[07:48:32.141] <TB0> INFO: flawed TBM headers: 0
[07:48:32.141] <TB0> INFO: flawed TBM trailers: 0
[07:48:32.141] <TB0> INFO: event ID mismatches: 0
[07:48:32.141] <TB0> INFO: ROC errors: 0
[07:48:32.141] <TB0> INFO: missing ROC header(s): 0
[07:48:32.141] <TB0> INFO: misplaced readback start: 0
[07:48:32.141] <TB0> INFO: Pixel decoding errors: 0
[07:48:32.141] <TB0> INFO: pixel data incomplete: 0
[07:48:32.141] <TB0> INFO: pixel address: 0
[07:48:32.141] <TB0> INFO: pulse height fill bit: 0
[07:48:32.141] <TB0> INFO: buffer corruption: 0
[07:48:32.154] <TB0> INFO: ######################################################################
[07:48:32.154] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:48:32.154] <TB0> INFO: ######################################################################
[07:48:32.156] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:48:32.167] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[07:48:32.167] <TB0> INFO: run 1 of 1
[07:48:32.434] <TB0> INFO: Expecting 3120000 events.
[07:49:10.409] <TB0> INFO: 868730 events read in total (37419ms).
[07:49:46.690] <TB0> INFO: 1723150 events read in total (73701ms).
[07:50:23.090] <TB0> INFO: 2584955 events read in total (110100ms).
[07:50:45.687] <TB0> INFO: 3120000 events read in total (132697ms).
[07:50:45.765] <TB0> INFO: Test took 133598ms.
[07:51:09.987] <TB0> INFO: PixTestBBMap::doTest() done, duration: 157 seconds
[07:51:09.987] <TB0> INFO: number of dead bumps (per ROC): 2 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[07:51:09.987] <TB0> INFO: separation cut (per ROC): 100 78 108 82 96 92 82 91 77 75 86 81 89 93 64 79
[07:51:09.987] <TB0> INFO: Decoding statistics:
[07:51:09.987] <TB0> INFO: General information:
[07:51:09.987] <TB0> INFO: 16bit words read: 0
[07:51:09.987] <TB0> INFO: valid events total: 0
[07:51:09.987] <TB0> INFO: empty events: 0
[07:51:09.987] <TB0> INFO: valid events with pixels: 0
[07:51:09.987] <TB0> INFO: valid pixel hits: 0
[07:51:09.987] <TB0> INFO: Event errors: 0
[07:51:09.987] <TB0> INFO: start marker: 0
[07:51:09.987] <TB0> INFO: stop marker: 0
[07:51:09.987] <TB0> INFO: overflow: 0
[07:51:09.987] <TB0> INFO: invalid 5bit words: 0
[07:51:09.987] <TB0> INFO: invalid XOR eye diagram: 0
[07:51:09.988] <TB0> INFO: frame (failed synchr.): 0
[07:51:09.988] <TB0> INFO: idle data (no TBM trl): 0
[07:51:09.988] <TB0> INFO: no data (only TBM hdr): 0
[07:51:09.988] <TB0> INFO: TBM errors: 0
[07:51:09.988] <TB0> INFO: flawed TBM headers: 0
[07:51:09.988] <TB0> INFO: flawed TBM trailers: 0
[07:51:09.988] <TB0> INFO: event ID mismatches: 0
[07:51:09.988] <TB0> INFO: ROC errors: 0
[07:51:09.988] <TB0> INFO: missing ROC header(s): 0
[07:51:09.988] <TB0> INFO: misplaced readback start: 0
[07:51:09.988] <TB0> INFO: Pixel decoding errors: 0
[07:51:09.988] <TB0> INFO: pixel data incomplete: 0
[07:51:09.988] <TB0> INFO: pixel address: 0
[07:51:09.988] <TB0> INFO: pulse height fill bit: 0
[07:51:09.988] <TB0> INFO: buffer corruption: 0
[07:51:10.081] <TB0> INFO: ######################################################################
[07:51:10.081] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:51:10.081] <TB0> INFO: ######################################################################
[07:51:10.082] <TB0> INFO: ----------------------------------------------------------------------
[07:51:10.082] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:51:10.082] <TB0> INFO: ----------------------------------------------------------------------
[07:51:10.082] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[07:51:10.090] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:51:10.090] <TB0> INFO: run 1 of 1
[07:51:10.386] <TB0> INFO: Expecting 36608000 events.
[07:51:39.879] <TB0> INFO: 806300 events read in total (28937ms).
[07:52:07.743] <TB0> INFO: 1600550 events read in total (56801ms).
[07:52:35.470] <TB0> INFO: 2396150 events read in total (84528ms).
[07:53:03.419] <TB0> INFO: 3188850 events read in total (112477ms).
[07:53:31.311] <TB0> INFO: 3982450 events read in total (140369ms).
[07:53:58.840] <TB0> INFO: 4775850 events read in total (167898ms).
[07:54:26.466] <TB0> INFO: 5570500 events read in total (195524ms).
[07:54:54.375] <TB0> INFO: 6366800 events read in total (223433ms).
[07:55:21.609] <TB0> INFO: 7158900 events read in total (250667ms).
[07:55:49.383] <TB0> INFO: 7952900 events read in total (278441ms).
[07:56:16.734] <TB0> INFO: 8746450 events read in total (305792ms).
[07:56:44.569] <TB0> INFO: 9538600 events read in total (333627ms).
[07:57:12.730] <TB0> INFO: 10332850 events read in total (361788ms).
[07:57:40.553] <TB0> INFO: 11126950 events read in total (389611ms).
[07:58:08.783] <TB0> INFO: 11920450 events read in total (417841ms).
[07:58:37.008] <TB0> INFO: 12714450 events read in total (446066ms).
[07:59:04.956] <TB0> INFO: 13508600 events read in total (474014ms).
[07:59:32.901] <TB0> INFO: 14302100 events read in total (501959ms).
[08:00:01.283] <TB0> INFO: 15095600 events read in total (530341ms).
[08:00:29.439] <TB0> INFO: 15887300 events read in total (558497ms).
[08:00:57.427] <TB0> INFO: 16680500 events read in total (586485ms).
[08:01:25.289] <TB0> INFO: 17474100 events read in total (614347ms).
[08:01:53.240] <TB0> INFO: 18265900 events read in total (642298ms).
[08:02:21.265] <TB0> INFO: 19053950 events read in total (670323ms).
[08:02:49.453] <TB0> INFO: 19841100 events read in total (698511ms).
[08:03:18.037] <TB0> INFO: 20627150 events read in total (727095ms).
[08:03:45.910] <TB0> INFO: 21415350 events read in total (754968ms).
[08:04:14.053] <TB0> INFO: 22201650 events read in total (783111ms).
[08:04:41.898] <TB0> INFO: 22987550 events read in total (810956ms).
[08:05:09.617] <TB0> INFO: 23773450 events read in total (838675ms).
[08:05:37.654] <TB0> INFO: 24559450 events read in total (866712ms).
[08:06:05.961] <TB0> INFO: 25345250 events read in total (895019ms).
[08:06:34.084] <TB0> INFO: 26132750 events read in total (923142ms).
[08:07:01.442] <TB0> INFO: 26917200 events read in total (950500ms).
[08:07:29.114] <TB0> INFO: 27701550 events read in total (978172ms).
[08:07:56.891] <TB0> INFO: 28486550 events read in total (1005949ms).
[08:08:25.143] <TB0> INFO: 29272950 events read in total (1034201ms).
[08:08:53.056] <TB0> INFO: 30058100 events read in total (1062114ms).
[08:09:21.434] <TB0> INFO: 30842650 events read in total (1090492ms).
[08:09:49.998] <TB0> INFO: 31629550 events read in total (1119056ms).
[08:10:19.317] <TB0> INFO: 32415950 events read in total (1148375ms).
[08:10:48.597] <TB0> INFO: 33202350 events read in total (1177655ms).
[08:11:17.660] <TB0> INFO: 33989350 events read in total (1206718ms).
[08:11:45.815] <TB0> INFO: 34776750 events read in total (1234873ms).
[08:12:14.123] <TB0> INFO: 35565800 events read in total (1263181ms).
[08:12:42.582] <TB0> INFO: 36360500 events read in total (1291640ms).
[08:12:51.536] <TB0> INFO: 36608000 events read in total (1300594ms).
[08:12:51.600] <TB0> INFO: Test took 1301510ms.
[08:12:52.028] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:12:53.971] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:12:55.749] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:12:57.410] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:12:59.073] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:00.751] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:02.461] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:04.198] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:05.912] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:07.599] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:09.313] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:11.006] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:12.726] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:14.414] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:16.275] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:18.192] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:13:19.940] <TB0> INFO: PixTestScurves::scurves() done
[08:13:19.940] <TB0> INFO: Vcal mean: 100.47 85.66 94.31 84.87 88.43 96.74 87.10 90.28 82.32 84.65 81.14 85.35 84.05 90.26 66.26 80.62
[08:13:19.940] <TB0> INFO: Vcal RMS: 5.30 6.09 5.19 4.54 5.12 5.94 5.55 4.87 4.48 5.14 4.80 4.91 4.47 4.97 4.03 3.89
[08:13:19.940] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1329 seconds
[08:13:19.940] <TB0> INFO: Decoding statistics:
[08:13:19.940] <TB0> INFO: General information:
[08:13:19.940] <TB0> INFO: 16bit words read: 0
[08:13:19.940] <TB0> INFO: valid events total: 0
[08:13:19.940] <TB0> INFO: empty events: 0
[08:13:19.940] <TB0> INFO: valid events with pixels: 0
[08:13:19.940] <TB0> INFO: valid pixel hits: 0
[08:13:19.940] <TB0> INFO: Event errors: 0
[08:13:19.940] <TB0> INFO: start marker: 0
[08:13:19.940] <TB0> INFO: stop marker: 0
[08:13:19.940] <TB0> INFO: overflow: 0
[08:13:19.940] <TB0> INFO: invalid 5bit words: 0
[08:13:19.940] <TB0> INFO: invalid XOR eye diagram: 0
[08:13:19.940] <TB0> INFO: frame (failed synchr.): 0
[08:13:19.940] <TB0> INFO: idle data (no TBM trl): 0
[08:13:19.940] <TB0> INFO: no data (only TBM hdr): 0
[08:13:19.940] <TB0> INFO: TBM errors: 0
[08:13:19.940] <TB0> INFO: flawed TBM headers: 0
[08:13:19.940] <TB0> INFO: flawed TBM trailers: 0
[08:13:19.940] <TB0> INFO: event ID mismatches: 0
[08:13:19.940] <TB0> INFO: ROC errors: 0
[08:13:19.940] <TB0> INFO: missing ROC header(s): 0
[08:13:19.940] <TB0> INFO: misplaced readback start: 0
[08:13:19.940] <TB0> INFO: Pixel decoding errors: 0
[08:13:19.940] <TB0> INFO: pixel data incomplete: 0
[08:13:19.940] <TB0> INFO: pixel address: 0
[08:13:19.940] <TB0> INFO: pulse height fill bit: 0
[08:13:19.940] <TB0> INFO: buffer corruption: 0
[08:13:20.012] <TB0> INFO: ######################################################################
[08:13:20.012] <TB0> INFO: PixTestTrim::doTest()
[08:13:20.012] <TB0> INFO: ######################################################################
[08:13:20.013] <TB0> INFO: ----------------------------------------------------------------------
[08:13:20.013] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:13:20.013] <TB0> INFO: ----------------------------------------------------------------------
[08:13:20.092] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:13:20.092] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:13:20.101] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:13:20.101] <TB0> INFO: run 1 of 1
[08:13:20.363] <TB0> INFO: Expecting 6281600 events.
[08:13:57.514] <TB0> INFO: 1096110 events read in total (36594ms).
[08:14:34.985] <TB0> INFO: 2186830 events read in total (74065ms).
[08:15:10.137] <TB0> INFO: 3271660 events read in total (109217ms).
[08:15:46.224] <TB0> INFO: 4362220 events read in total (145304ms).
[08:16:21.955] <TB0> INFO: 5456800 events read in total (181035ms).
[08:16:48.396] <TB0> INFO: 6281600 events read in total (207476ms).
[08:16:48.450] <TB0> INFO: Test took 208349ms.
[08:17:08.437] <TB0> INFO: ROC 0 VthrComp = 106
[08:17:08.437] <TB0> INFO: ROC 1 VthrComp = 87
[08:17:08.438] <TB0> INFO: ROC 2 VthrComp = 103
[08:17:08.438] <TB0> INFO: ROC 3 VthrComp = 90
[08:17:08.438] <TB0> INFO: ROC 4 VthrComp = 97
[08:17:08.438] <TB0> INFO: ROC 5 VthrComp = 99
[08:17:08.438] <TB0> INFO: ROC 6 VthrComp = 88
[08:17:08.438] <TB0> INFO: ROC 7 VthrComp = 96
[08:17:08.438] <TB0> INFO: ROC 8 VthrComp = 90
[08:17:08.438] <TB0> INFO: ROC 9 VthrComp = 91
[08:17:08.438] <TB0> INFO: ROC 10 VthrComp = 85
[08:17:08.438] <TB0> INFO: ROC 11 VthrComp = 88
[08:17:08.438] <TB0> INFO: ROC 12 VthrComp = 93
[08:17:08.439] <TB0> INFO: ROC 13 VthrComp = 99
[08:17:08.439] <TB0> INFO: ROC 14 VthrComp = 74
[08:17:08.439] <TB0> INFO: ROC 15 VthrComp = 89
[08:17:08.439] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:17:08.439] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:17:08.448] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:17:08.448] <TB0> INFO: run 1 of 1
[08:17:08.713] <TB0> INFO: Expecting 6281600 events.
[08:17:40.392] <TB0> INFO: 756840 events read in total (31123ms).
[08:18:11.988] <TB0> INFO: 1512650 events read in total (62719ms).
[08:18:42.671] <TB0> INFO: 2269000 events read in total (93402ms).
[08:19:13.265] <TB0> INFO: 3025820 events read in total (123996ms).
[08:19:44.339] <TB0> INFO: 3777590 events read in total (155070ms).
[08:20:15.257] <TB0> INFO: 4526540 events read in total (185988ms).
[08:20:45.900] <TB0> INFO: 5273600 events read in total (216631ms).
[08:21:16.842] <TB0> INFO: 6020770 events read in total (247573ms).
[08:21:27.740] <TB0> INFO: 6281600 events read in total (258471ms).
[08:21:27.795] <TB0> INFO: Test took 259347ms.
[08:21:51.797] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 60.4358 for pixel 3/5 mean/min/max = 47.128/33.7955/60.4604
[08:21:51.797] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 63.4313 for pixel 2/17 mean/min/max = 47.38/31.1428/63.6172
[08:21:51.802] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 58.4306 for pixel 5/69 mean/min/max = 45.4677/32.4036/58.5319
[08:21:51.803] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 57.0926 for pixel 51/65 mean/min/max = 45.0487/32.9961/57.1012
[08:21:51.803] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 56.8855 for pixel 7/0 mean/min/max = 44.3158/31.5717/57.0599
[08:21:51.803] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 61.0197 for pixel 8/5 mean/min/max = 46.2435/31.3267/61.1602
[08:21:51.803] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 62.2616 for pixel 2/3 mean/min/max = 46.9965/31.708/62.2851
[08:21:51.804] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.0887 for pixel 0/39 mean/min/max = 44.5082/31.8649/57.1515
[08:21:51.804] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.1699 for pixel 18/7 mean/min/max = 45.7639/33.1413/58.3866
[08:21:51.804] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 60.71 for pixel 16/0 mean/min/max = 46.3918/31.7433/61.0404
[08:21:51.804] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 57.6381 for pixel 2/72 mean/min/max = 45.0439/32.1537/57.9341
[08:21:51.805] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 59.406 for pixel 4/10 mean/min/max = 46.2238/32.9358/59.5117
[08:21:51.805] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 56.4998 for pixel 14/53 mean/min/max = 44.4314/32.0749/56.788
[08:21:51.805] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 56.8048 for pixel 18/6 mean/min/max = 44.2902/31.5858/56.9946
[08:21:51.805] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 55.1234 for pixel 14/17 mean/min/max = 44.6695/34.069/55.2699
[08:21:51.806] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 55.6513 for pixel 17/6 mean/min/max = 44.6419/33.3948/55.889
[08:21:51.806] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:21:51.895] <TB0> INFO: Expecting 514560 events.
[08:22:02.861] <TB0> INFO: 514560 events read in total (10410ms).
[08:22:02.871] <TB0> INFO: Expecting 514560 events.
[08:22:13.595] <TB0> INFO: 514560 events read in total (10323ms).
[08:22:13.605] <TB0> INFO: Expecting 514560 events.
[08:22:24.371] <TB0> INFO: 514560 events read in total (10364ms).
[08:22:24.383] <TB0> INFO: Expecting 514560 events.
[08:22:35.176] <TB0> INFO: 514560 events read in total (10391ms).
[08:22:35.191] <TB0> INFO: Expecting 514560 events.
[08:22:46.014] <TB0> INFO: 514560 events read in total (10422ms).
[08:22:46.040] <TB0> INFO: Expecting 514560 events.
[08:22:56.894] <TB0> INFO: 514560 events read in total (10452ms).
[08:22:56.915] <TB0> INFO: Expecting 514560 events.
[08:23:07.771] <TB0> INFO: 514560 events read in total (10454ms).
[08:23:07.795] <TB0> INFO: Expecting 514560 events.
[08:23:18.483] <TB0> INFO: 514560 events read in total (10286ms).
[08:23:18.509] <TB0> INFO: Expecting 514560 events.
[08:23:29.317] <TB0> INFO: 514560 events read in total (10406ms).
[08:23:29.346] <TB0> INFO: Expecting 514560 events.
[08:23:40.208] <TB0> INFO: 514560 events read in total (10460ms).
[08:23:40.240] <TB0> INFO: Expecting 514560 events.
[08:23:51.086] <TB0> INFO: 514560 events read in total (10444ms).
[08:23:51.126] <TB0> INFO: Expecting 514560 events.
[08:24:01.895] <TB0> INFO: 514560 events read in total (10368ms).
[08:24:01.933] <TB0> INFO: Expecting 514560 events.
[08:24:12.856] <TB0> INFO: 514560 events read in total (10522ms).
[08:24:12.895] <TB0> INFO: Expecting 514560 events.
[08:24:23.782] <TB0> INFO: 514560 events read in total (10485ms).
[08:24:23.837] <TB0> INFO: Expecting 514560 events.
[08:24:34.608] <TB0> INFO: 514560 events read in total (10370ms).
[08:24:34.662] <TB0> INFO: Expecting 514560 events.
[08:24:45.526] <TB0> INFO: 514560 events read in total (10463ms).
[08:24:45.579] <TB0> INFO: Test took 173773ms.
[08:24:46.671] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:24:46.679] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:24:46.679] <TB0> INFO: run 1 of 1
[08:24:46.947] <TB0> INFO: Expecting 6281600 events.
[08:25:17.875] <TB0> INFO: 742410 events read in total (30372ms).
[08:25:47.968] <TB0> INFO: 1483520 events read in total (60465ms).
[08:26:18.352] <TB0> INFO: 2224150 events read in total (90849ms).
[08:26:48.489] <TB0> INFO: 2964730 events read in total (120986ms).
[08:27:18.603] <TB0> INFO: 3701230 events read in total (151100ms).
[08:27:48.969] <TB0> INFO: 4434730 events read in total (181466ms).
[08:28:18.790] <TB0> INFO: 5167890 events read in total (211287ms).
[08:28:48.935] <TB0> INFO: 5900020 events read in total (241432ms).
[08:29:04.811] <TB0> INFO: 6281600 events read in total (257308ms).
[08:29:04.864] <TB0> INFO: Test took 258186ms.
[08:29:28.836] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 2.500000 .. 49.098772
[08:29:29.130] <TB0> INFO: Expecting 208000 events.
[08:29:39.058] <TB0> INFO: 208000 events read in total (9372ms).
[08:29:39.059] <TB0> INFO: Test took 10221ms.
[08:29:39.148] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 2 .. 59 (-1/-1) hits flags = 528 (plus default)
[08:29:39.156] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:29:39.156] <TB0> INFO: run 1 of 1
[08:29:39.422] <TB0> INFO: Expecting 2412800 events.
[08:30:14.270] <TB0> INFO: 941340 events read in total (34291ms).
[08:30:47.844] <TB0> INFO: 1873000 events read in total (67866ms).
[08:31:07.459] <TB0> INFO: 2412800 events read in total (87480ms).
[08:31:07.482] <TB0> INFO: Test took 88326ms.
[08:31:21.124] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 21.799556 .. 44.229034
[08:31:21.389] <TB0> INFO: Expecting 208000 events.
[08:31:31.397] <TB0> INFO: 208000 events read in total (9452ms).
[08:31:31.397] <TB0> INFO: Test took 10271ms.
[08:31:31.505] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 11 .. 54 (-1/-1) hits flags = 528 (plus default)
[08:31:31.516] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:31:31.516] <TB0> INFO: run 1 of 1
[08:31:31.810] <TB0> INFO: Expecting 1830400 events.
[08:32:06.955] <TB0> INFO: 929500 events read in total (34589ms).
[08:32:40.108] <TB0> INFO: 1830400 events read in total (67742ms).
[08:32:40.129] <TB0> INFO: Test took 68612ms.
[08:32:53.813] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 20.682299 .. 40.112911
[08:32:54.078] <TB0> INFO: Expecting 208000 events.
[08:33:03.879] <TB0> INFO: 208000 events read in total (9245ms).
[08:33:03.879] <TB0> INFO: Test took 10065ms.
[08:33:03.980] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 50 (-1/-1) hits flags = 528 (plus default)
[08:33:03.988] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:33:03.988] <TB0> INFO: run 1 of 1
[08:33:04.254] <TB0> INFO: Expecting 1705600 events.
[08:33:40.084] <TB0> INFO: 974570 events read in total (35274ms).
[08:34:06.780] <TB0> INFO: 1705600 events read in total (61970ms).
[08:34:06.797] <TB0> INFO: Test took 62809ms.
[08:34:20.105] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 23.023885 .. 40.112911
[08:34:20.368] <TB0> INFO: Expecting 208000 events.
[08:34:30.300] <TB0> INFO: 208000 events read in total (9376ms).
[08:34:30.301] <TB0> INFO: Test took 10194ms.
[08:34:30.418] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 50 (-1/-1) hits flags = 528 (plus default)
[08:34:30.427] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:34:30.427] <TB0> INFO: run 1 of 1
[08:34:30.742] <TB0> INFO: Expecting 1580800 events.
[08:35:05.714] <TB0> INFO: 955980 events read in total (34416ms).
[08:35:28.482] <TB0> INFO: 1580800 events read in total (57184ms).
[08:35:28.499] <TB0> INFO: Test took 58072ms.
[08:35:41.369] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:35:41.369] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:35:41.377] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:35:41.377] <TB0> INFO: run 1 of 1
[08:35:41.643] <TB0> INFO: Expecting 1705600 events.
[08:36:15.977] <TB0> INFO: 893980 events read in total (33778ms).
[08:36:46.428] <TB0> INFO: 1705600 events read in total (64229ms).
[08:36:46.446] <TB0> INFO: Test took 65070ms.
[08:37:01.298] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:37:01.298] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:37:01.298] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:37:01.298] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:37:01.298] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:37:01.298] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:37:01.299] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:37:01.300] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:37:01.300] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:37:01.306] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:37:01.312] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:37:01.318] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:37:01.325] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:37:01.331] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:37:01.338] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:37:01.344] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:37:01.351] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:37:01.360] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:37:01.367] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:37:01.373] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:37:01.379] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:37:01.385] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:37:01.391] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:37:01.397] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:37:01.404] <TB0> INFO: PixTestTrim::trimTest() done
[08:37:01.404] <TB0> INFO: vtrim: 109 135 110 102 109 115 113 105 116 121 92 107 103 106 88 175
[08:37:01.404] <TB0> INFO: vthrcomp: 106 87 103 90 97 99 88 96 90 91 85 88 93 99 74 89
[08:37:01.404] <TB0> INFO: vcal mean: 35.00 34.95 34.94 34.99 34.96 34.94 34.96 34.97 34.98 34.99 34.98 34.94 34.94 34.94 35.01 34.94
[08:37:01.404] <TB0> INFO: vcal RMS: 0.85 0.91 0.80 0.83 0.82 0.97 0.89 0.83 0.81 0.85 0.81 0.81 0.83 0.89 0.76 1.07
[08:37:01.404] <TB0> INFO: bits mean: 8.83 9.54 9.49 9.59 9.96 9.73 9.45 9.64 9.46 9.20 9.19 9.43 9.79 10.03 9.32 11.97
[08:37:01.404] <TB0> INFO: bits RMS: 2.61 2.69 2.61 2.52 2.63 2.63 2.65 2.72 2.56 2.85 2.86 2.56 2.66 2.60 2.48 1.40
[08:37:01.410] <TB0> INFO: ----------------------------------------------------------------------
[08:37:01.410] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[08:37:01.410] <TB0> INFO: ----------------------------------------------------------------------
[08:37:01.412] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:37:01.421] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:37:01.421] <TB0> INFO: run 1 of 1
[08:37:01.684] <TB0> INFO: Expecting 4160000 events.
[08:37:39.199] <TB0> INFO: 904640 events read in total (36959ms).
[08:38:15.808] <TB0> INFO: 1805925 events read in total (73568ms).
[08:38:52.757] <TB0> INFO: 2701345 events read in total (110517ms).
[08:39:29.278] <TB0> INFO: 3593975 events read in total (147038ms).
[08:39:53.349] <TB0> INFO: 4160000 events read in total (171109ms).
[08:39:53.434] <TB0> INFO: Test took 172013ms.
[08:40:24.003] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[08:40:24.013] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:40:24.013] <TB0> INFO: run 1 of 1
[08:40:24.304] <TB0> INFO: Expecting 3286400 events.
[08:41:03.533] <TB0> INFO: 960325 events read in total (38673ms).
[08:41:41.165] <TB0> INFO: 1913810 events read in total (76305ms).
[08:42:18.693] <TB0> INFO: 2858735 events read in total (113833ms).
[08:42:35.855] <TB0> INFO: 3286400 events read in total (130995ms).
[08:42:35.910] <TB0> INFO: Test took 131897ms.
[08:43:02.760] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[08:43:02.768] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:43:02.769] <TB0> INFO: run 1 of 1
[08:43:03.048] <TB0> INFO: Expecting 3161600 events.
[08:43:41.715] <TB0> INFO: 976905 events read in total (38111ms).
[08:44:19.696] <TB0> INFO: 1945535 events read in total (76092ms).
[08:44:57.592] <TB0> INFO: 2906585 events read in total (113988ms).
[08:45:08.036] <TB0> INFO: 3161600 events read in total (124432ms).
[08:45:08.101] <TB0> INFO: Test took 125332ms.
[08:45:33.298] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:45:33.307] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:45:33.307] <TB0> INFO: run 1 of 1
[08:45:33.572] <TB0> INFO: Expecting 3140800 events.
[08:46:12.092] <TB0> INFO: 979385 events read in total (37964ms).
[08:46:49.958] <TB0> INFO: 1949955 events read in total (75830ms).
[08:47:28.010] <TB0> INFO: 2912975 events read in total (113882ms).
[08:47:37.672] <TB0> INFO: 3140800 events read in total (123544ms).
[08:47:37.737] <TB0> INFO: Test took 124431ms.
[08:48:02.938] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[08:48:02.947] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:48:02.947] <TB0> INFO: run 1 of 1
[08:48:03.210] <TB0> INFO: Expecting 3120000 events.
[08:48:42.365] <TB0> INFO: 981155 events read in total (38598ms).
[08:49:20.658] <TB0> INFO: 1953705 events read in total (76891ms).
[08:49:59.081] <TB0> INFO: 2919130 events read in total (115314ms).
[08:50:07.547] <TB0> INFO: 3120000 events read in total (123780ms).
[08:50:07.592] <TB0> INFO: Test took 124646ms.
[08:50:31.931] <TB0> INFO: PixTestTrim::trimBitTest() done
[08:50:31.947] <TB0> INFO: PixTestTrim::doTest() done, duration: 2231 seconds
[08:50:31.947] <TB0> INFO: Decoding statistics:
[08:50:31.947] <TB0> INFO: General information:
[08:50:31.947] <TB0> INFO: 16bit words read: 0
[08:50:31.947] <TB0> INFO: valid events total: 0
[08:50:31.947] <TB0> INFO: empty events: 0
[08:50:31.947] <TB0> INFO: valid events with pixels: 0
[08:50:31.947] <TB0> INFO: valid pixel hits: 0
[08:50:31.947] <TB0> INFO: Event errors: 0
[08:50:31.947] <TB0> INFO: start marker: 0
[08:50:31.947] <TB0> INFO: stop marker: 0
[08:50:31.947] <TB0> INFO: overflow: 0
[08:50:31.947] <TB0> INFO: invalid 5bit words: 0
[08:50:31.947] <TB0> INFO: invalid XOR eye diagram: 0
[08:50:31.947] <TB0> INFO: frame (failed synchr.): 0
[08:50:31.947] <TB0> INFO: idle data (no TBM trl): 0
[08:50:31.947] <TB0> INFO: no data (only TBM hdr): 0
[08:50:31.947] <TB0> INFO: TBM errors: 0
[08:50:31.947] <TB0> INFO: flawed TBM headers: 0
[08:50:31.947] <TB0> INFO: flawed TBM trailers: 0
[08:50:31.947] <TB0> INFO: event ID mismatches: 0
[08:50:31.947] <TB0> INFO: ROC errors: 0
[08:50:31.947] <TB0> INFO: missing ROC header(s): 0
[08:50:31.947] <TB0> INFO: misplaced readback start: 0
[08:50:31.947] <TB0> INFO: Pixel decoding errors: 0
[08:50:31.947] <TB0> INFO: pixel data incomplete: 0
[08:50:31.947] <TB0> INFO: pixel address: 0
[08:50:31.947] <TB0> INFO: pulse height fill bit: 0
[08:50:31.947] <TB0> INFO: buffer corruption: 0
[08:50:32.681] <TB0> INFO: ######################################################################
[08:50:32.681] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:50:32.681] <TB0> INFO: ######################################################################
[08:50:32.960] <TB0> INFO: Expecting 41600 events.
[08:50:36.393] <TB0> INFO: 41600 events read in total (2877ms).
[08:50:36.393] <TB0> INFO: Test took 3711ms.
[08:50:36.864] <TB0> INFO: Expecting 41600 events.
[08:50:40.379] <TB0> INFO: 41600 events read in total (2959ms).
[08:50:40.380] <TB0> INFO: Test took 3791ms.
[08:50:40.672] <TB0> INFO: Expecting 41600 events.
[08:50:44.134] <TB0> INFO: 41600 events read in total (2906ms).
[08:50:44.135] <TB0> INFO: Test took 3732ms.
[08:50:44.390] <TB0> INFO: Expecting 2560 events.
[08:50:45.248] <TB0> INFO: 2560 events read in total (302ms).
[08:50:45.248] <TB0> INFO: Test took 1101ms.
[08:50:45.553] <TB0> INFO: Expecting 2560 events.
[08:50:46.405] <TB0> INFO: 2560 events read in total (296ms).
[08:50:46.407] <TB0> INFO: Test took 1159ms.
[08:50:46.710] <TB0> INFO: Expecting 2560 events.
[08:50:47.562] <TB0> INFO: 2560 events read in total (296ms).
[08:50:47.562] <TB0> INFO: Test took 1155ms.
[08:50:47.867] <TB0> INFO: Expecting 2560 events.
[08:50:48.720] <TB0> INFO: 2560 events read in total (297ms).
[08:50:48.720] <TB0> INFO: Test took 1158ms.
[08:50:49.025] <TB0> INFO: Expecting 2560 events.
[08:50:49.876] <TB0> INFO: 2560 events read in total (295ms).
[08:50:49.876] <TB0> INFO: Test took 1156ms.
[08:50:50.181] <TB0> INFO: Expecting 2560 events.
[08:50:51.036] <TB0> INFO: 2560 events read in total (299ms).
[08:50:51.036] <TB0> INFO: Test took 1157ms.
[08:50:51.340] <TB0> INFO: Expecting 2560 events.
[08:50:52.193] <TB0> INFO: 2560 events read in total (297ms).
[08:50:52.194] <TB0> INFO: Test took 1158ms.
[08:50:52.498] <TB0> INFO: Expecting 2560 events.
[08:50:53.351] <TB0> INFO: 2560 events read in total (297ms).
[08:50:53.352] <TB0> INFO: Test took 1158ms.
[08:50:53.656] <TB0> INFO: Expecting 2560 events.
[08:50:54.509] <TB0> INFO: 2560 events read in total (297ms).
[08:50:54.509] <TB0> INFO: Test took 1157ms.
[08:50:54.814] <TB0> INFO: Expecting 2560 events.
[08:50:55.670] <TB0> INFO: 2560 events read in total (300ms).
[08:50:55.670] <TB0> INFO: Test took 1161ms.
[08:50:55.975] <TB0> INFO: Expecting 2560 events.
[08:50:56.830] <TB0> INFO: 2560 events read in total (299ms).
[08:50:56.830] <TB0> INFO: Test took 1159ms.
[08:50:57.135] <TB0> INFO: Expecting 2560 events.
[08:50:57.991] <TB0> INFO: 2560 events read in total (300ms).
[08:50:57.991] <TB0> INFO: Test took 1161ms.
[08:50:58.296] <TB0> INFO: Expecting 2560 events.
[08:50:59.149] <TB0> INFO: 2560 events read in total (297ms).
[08:50:59.149] <TB0> INFO: Test took 1157ms.
[08:50:59.454] <TB0> INFO: Expecting 2560 events.
[08:51:00.306] <TB0> INFO: 2560 events read in total (296ms).
[08:51:00.306] <TB0> INFO: Test took 1157ms.
[08:51:00.611] <TB0> INFO: Expecting 2560 events.
[08:51:01.462] <TB0> INFO: 2560 events read in total (295ms).
[08:51:01.462] <TB0> INFO: Test took 1153ms.
[08:51:01.767] <TB0> INFO: Expecting 2560 events.
[08:51:02.623] <TB0> INFO: 2560 events read in total (300ms).
[08:51:02.623] <TB0> INFO: Test took 1160ms.
[08:51:02.626] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:51:02.929] <TB0> INFO: Expecting 655360 events.
[08:51:16.838] <TB0> INFO: 655360 events read in total (13353ms).
[08:51:16.851] <TB0> INFO: Expecting 655360 events.
[08:51:30.735] <TB0> INFO: 655360 events read in total (13482ms).
[08:51:30.754] <TB0> INFO: Expecting 655360 events.
[08:51:44.522] <TB0> INFO: 655360 events read in total (13366ms).
[08:51:44.545] <TB0> INFO: Expecting 655360 events.
[08:51:58.214] <TB0> INFO: 655360 events read in total (13268ms).
[08:51:58.241] <TB0> INFO: Expecting 655360 events.
[08:52:12.039] <TB0> INFO: 655360 events read in total (13396ms).
[08:52:12.070] <TB0> INFO: Expecting 655360 events.
[08:52:25.908] <TB0> INFO: 655360 events read in total (13437ms).
[08:52:25.952] <TB0> INFO: Expecting 655360 events.
[08:52:39.747] <TB0> INFO: 655360 events read in total (13393ms).
[08:52:39.789] <TB0> INFO: Expecting 655360 events.
[08:52:53.689] <TB0> INFO: 655360 events read in total (13498ms).
[08:52:53.737] <TB0> INFO: Expecting 655360 events.
[08:53:07.411] <TB0> INFO: 655360 events read in total (13272ms).
[08:53:07.462] <TB0> INFO: Expecting 655360 events.
[08:53:21.339] <TB0> INFO: 655360 events read in total (13476ms).
[08:53:21.392] <TB0> INFO: Expecting 655360 events.
[08:53:35.176] <TB0> INFO: 655360 events read in total (13383ms).
[08:53:35.237] <TB0> INFO: Expecting 655360 events.
[08:53:49.054] <TB0> INFO: 655360 events read in total (13416ms).
[08:53:49.117] <TB0> INFO: Expecting 655360 events.
[08:54:02.982] <TB0> INFO: 655360 events read in total (13463ms).
[08:54:03.053] <TB0> INFO: Expecting 655360 events.
[08:54:16.880] <TB0> INFO: 655360 events read in total (13426ms).
[08:54:16.953] <TB0> INFO: Expecting 655360 events.
[08:54:30.727] <TB0> INFO: 655360 events read in total (13373ms).
[08:54:30.805] <TB0> INFO: Expecting 655360 events.
[08:54:44.581] <TB0> INFO: 655360 events read in total (13374ms).
[08:54:44.660] <TB0> INFO: Test took 222034ms.
[08:54:44.738] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:54:44.968] <TB0> INFO: Expecting 655360 events.
[08:54:58.825] <TB0> INFO: 655360 events read in total (13300ms).
[08:54:58.840] <TB0> INFO: Expecting 655360 events.
[08:55:12.612] <TB0> INFO: 655360 events read in total (13371ms).
[08:55:12.630] <TB0> INFO: Expecting 655360 events.
[08:55:26.374] <TB0> INFO: 655360 events read in total (13343ms).
[08:55:26.397] <TB0> INFO: Expecting 655360 events.
[08:55:40.103] <TB0> INFO: 655360 events read in total (13304ms).
[08:55:40.134] <TB0> INFO: Expecting 655360 events.
[08:55:53.856] <TB0> INFO: 655360 events read in total (13321ms).
[08:55:53.889] <TB0> INFO: Expecting 655360 events.
[08:56:07.645] <TB0> INFO: 655360 events read in total (13354ms).
[08:56:07.677] <TB0> INFO: Expecting 655360 events.
[08:56:21.417] <TB0> INFO: 655360 events read in total (13338ms).
[08:56:21.457] <TB0> INFO: Expecting 655360 events.
[08:56:35.216] <TB0> INFO: 655360 events read in total (13357ms).
[08:56:35.262] <TB0> INFO: Expecting 655360 events.
[08:56:49.037] <TB0> INFO: 655360 events read in total (13374ms).
[08:56:49.102] <TB0> INFO: Expecting 655360 events.
[08:57:03.048] <TB0> INFO: 655360 events read in total (13544ms).
[08:57:03.102] <TB0> INFO: Expecting 655360 events.
[08:57:17.059] <TB0> INFO: 655360 events read in total (13556ms).
[08:57:17.115] <TB0> INFO: Expecting 655360 events.
[08:57:30.960] <TB0> INFO: 655360 events read in total (13443ms).
[08:57:31.024] <TB0> INFO: Expecting 655360 events.
[08:57:44.750] <TB0> INFO: 655360 events read in total (13324ms).
[08:57:44.813] <TB0> INFO: Expecting 655360 events.
[08:57:58.577] <TB0> INFO: 655360 events read in total (13363ms).
[08:57:58.647] <TB0> INFO: Expecting 655360 events.
[08:58:12.502] <TB0> INFO: 655360 events read in total (13453ms).
[08:58:12.577] <TB0> INFO: Expecting 655360 events.
[08:58:26.511] <TB0> INFO: 655360 events read in total (13532ms).
[08:58:26.593] <TB0> INFO: Test took 221855ms.
[08:58:26.812] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.819] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.826] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.834] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.841] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.848] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.855] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.862] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.869] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.877] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.884] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.891] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.898] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.909] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.917] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.924] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:58:26.954] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:58:26.954] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:58:26.954] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:58:26.954] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:58:26.954] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:58:26.954] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:58:26.954] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:58:26.955] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:58:27.237] <TB0> INFO: Expecting 41600 events.
[08:58:30.370] <TB0> INFO: 41600 events read in total (2577ms).
[08:58:30.371] <TB0> INFO: Test took 3413ms.
[08:58:30.808] <TB0> INFO: Expecting 41600 events.
[08:58:33.881] <TB0> INFO: 41600 events read in total (2517ms).
[08:58:33.881] <TB0> INFO: Test took 3333ms.
[08:58:34.319] <TB0> INFO: Expecting 41600 events.
[08:58:37.442] <TB0> INFO: 41600 events read in total (2567ms).
[08:58:37.442] <TB0> INFO: Test took 3386ms.
[08:58:37.624] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:37.713] <TB0> INFO: Expecting 2560 events.
[08:58:38.565] <TB0> INFO: 2560 events read in total (296ms).
[08:58:38.565] <TB0> INFO: Test took 941ms.
[08:58:38.567] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:38.871] <TB0> INFO: Expecting 2560 events.
[08:58:39.724] <TB0> INFO: 2560 events read in total (297ms).
[08:58:39.724] <TB0> INFO: Test took 1157ms.
[08:58:39.726] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:40.031] <TB0> INFO: Expecting 2560 events.
[08:58:40.882] <TB0> INFO: 2560 events read in total (295ms).
[08:58:40.883] <TB0> INFO: Test took 1157ms.
[08:58:40.884] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:41.188] <TB0> INFO: Expecting 2560 events.
[08:58:42.044] <TB0> INFO: 2560 events read in total (299ms).
[08:58:42.044] <TB0> INFO: Test took 1160ms.
[08:58:42.045] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:42.350] <TB0> INFO: Expecting 2560 events.
[08:58:43.202] <TB0> INFO: 2560 events read in total (296ms).
[08:58:43.203] <TB0> INFO: Test took 1158ms.
[08:58:43.204] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:43.509] <TB0> INFO: Expecting 2560 events.
[08:58:44.365] <TB0> INFO: 2560 events read in total (300ms).
[08:58:44.366] <TB0> INFO: Test took 1162ms.
[08:58:44.368] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:44.670] <TB0> INFO: Expecting 2560 events.
[08:58:45.522] <TB0> INFO: 2560 events read in total (296ms).
[08:58:45.522] <TB0> INFO: Test took 1154ms.
[08:58:45.524] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:45.829] <TB0> INFO: Expecting 2560 events.
[08:58:46.684] <TB0> INFO: 2560 events read in total (299ms).
[08:58:46.684] <TB0> INFO: Test took 1160ms.
[08:58:46.685] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:46.990] <TB0> INFO: Expecting 2560 events.
[08:58:47.843] <TB0> INFO: 2560 events read in total (297ms).
[08:58:47.843] <TB0> INFO: Test took 1158ms.
[08:58:47.845] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:48.148] <TB0> INFO: Expecting 2560 events.
[08:58:49.000] <TB0> INFO: 2560 events read in total (296ms).
[08:58:49.000] <TB0> INFO: Test took 1155ms.
[08:58:49.001] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:49.306] <TB0> INFO: Expecting 2560 events.
[08:58:50.157] <TB0> INFO: 2560 events read in total (295ms).
[08:58:50.157] <TB0> INFO: Test took 1156ms.
[08:58:50.159] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:50.463] <TB0> INFO: Expecting 2560 events.
[08:58:51.314] <TB0> INFO: 2560 events read in total (295ms).
[08:58:51.314] <TB0> INFO: Test took 1155ms.
[08:58:51.316] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:51.620] <TB0> INFO: Expecting 2560 events.
[08:58:52.479] <TB0> INFO: 2560 events read in total (303ms).
[08:58:52.479] <TB0> INFO: Test took 1163ms.
[08:58:52.481] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:52.784] <TB0> INFO: Expecting 2560 events.
[08:58:53.634] <TB0> INFO: 2560 events read in total (294ms).
[08:58:53.634] <TB0> INFO: Test took 1153ms.
[08:58:53.636] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:53.940] <TB0> INFO: Expecting 2560 events.
[08:58:54.791] <TB0> INFO: 2560 events read in total (296ms).
[08:58:54.792] <TB0> INFO: Test took 1156ms.
[08:58:54.793] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:55.097] <TB0> INFO: Expecting 2560 events.
[08:58:55.950] <TB0> INFO: 2560 events read in total (296ms).
[08:58:55.950] <TB0> INFO: Test took 1157ms.
[08:58:55.952] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:56.256] <TB0> INFO: Expecting 2560 events.
[08:58:57.115] <TB0> INFO: 2560 events read in total (303ms).
[08:58:57.115] <TB0> INFO: Test took 1163ms.
[08:58:57.118] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:57.421] <TB0> INFO: Expecting 2560 events.
[08:58:58.277] <TB0> INFO: 2560 events read in total (300ms).
[08:58:58.277] <TB0> INFO: Test took 1159ms.
[08:58:58.280] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:58.583] <TB0> INFO: Expecting 2560 events.
[08:58:59.437] <TB0> INFO: 2560 events read in total (298ms).
[08:58:59.438] <TB0> INFO: Test took 1158ms.
[08:58:59.454] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:58:59.743] <TB0> INFO: Expecting 2560 events.
[08:59:00.598] <TB0> INFO: 2560 events read in total (299ms).
[08:59:00.598] <TB0> INFO: Test took 1145ms.
[08:59:00.601] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:00.903] <TB0> INFO: Expecting 2560 events.
[08:59:01.761] <TB0> INFO: 2560 events read in total (302ms).
[08:59:01.761] <TB0> INFO: Test took 1160ms.
[08:59:01.764] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:02.067] <TB0> INFO: Expecting 2560 events.
[08:59:02.918] <TB0> INFO: 2560 events read in total (295ms).
[08:59:02.918] <TB0> INFO: Test took 1154ms.
[08:59:02.920] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:03.224] <TB0> INFO: Expecting 2560 events.
[08:59:04.083] <TB0> INFO: 2560 events read in total (302ms).
[08:59:04.083] <TB0> INFO: Test took 1163ms.
[08:59:04.085] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:04.389] <TB0> INFO: Expecting 2560 events.
[08:59:05.240] <TB0> INFO: 2560 events read in total (294ms).
[08:59:05.241] <TB0> INFO: Test took 1156ms.
[08:59:05.242] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:05.546] <TB0> INFO: Expecting 2560 events.
[08:59:06.399] <TB0> INFO: 2560 events read in total (297ms).
[08:59:06.399] <TB0> INFO: Test took 1157ms.
[08:59:06.401] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:06.704] <TB0> INFO: Expecting 2560 events.
[08:59:07.556] <TB0> INFO: 2560 events read in total (296ms).
[08:59:07.556] <TB0> INFO: Test took 1156ms.
[08:59:07.558] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:07.862] <TB0> INFO: Expecting 2560 events.
[08:59:08.723] <TB0> INFO: 2560 events read in total (305ms).
[08:59:08.723] <TB0> INFO: Test took 1165ms.
[08:59:08.726] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:09.027] <TB0> INFO: Expecting 2560 events.
[08:59:09.880] <TB0> INFO: 2560 events read in total (297ms).
[08:59:09.881] <TB0> INFO: Test took 1156ms.
[08:59:09.883] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:10.187] <TB0> INFO: Expecting 2560 events.
[08:59:11.045] <TB0> INFO: 2560 events read in total (302ms).
[08:59:11.046] <TB0> INFO: Test took 1164ms.
[08:59:11.048] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:11.351] <TB0> INFO: Expecting 2560 events.
[08:59:12.213] <TB0> INFO: 2560 events read in total (306ms).
[08:59:12.213] <TB0> INFO: Test took 1165ms.
[08:59:12.216] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:12.518] <TB0> INFO: Expecting 2560 events.
[08:59:13.380] <TB0> INFO: 2560 events read in total (306ms).
[08:59:13.380] <TB0> INFO: Test took 1165ms.
[08:59:13.384] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:59:13.686] <TB0> INFO: Expecting 2560 events.
[08:59:14.537] <TB0> INFO: 2560 events read in total (295ms).
[08:59:14.538] <TB0> INFO: Test took 1155ms.
[08:59:14.996] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 522 seconds
[08:59:14.996] <TB0> INFO: PH scale (per ROC): 80 79 90 78 91 78 78 88 80 82 86 85 81 91 87 89
[08:59:14.996] <TB0> INFO: PH offset (per ROC): 147 160 150 150 143 156 167 149 141 162 144 148 145 154 137 137
[08:59:15.000] <TB0> INFO: Decoding statistics:
[08:59:15.000] <TB0> INFO: General information:
[08:59:15.000] <TB0> INFO: 16bit words read: 86926
[08:59:15.000] <TB0> INFO: valid events total: 10240
[08:59:15.000] <TB0> INFO: empty events: 7737
[08:59:15.000] <TB0> INFO: valid events with pixels: 2503
[08:59:15.000] <TB0> INFO: valid pixel hits: 2503
[08:59:15.000] <TB0> INFO: Event errors: 0
[08:59:15.000] <TB0> INFO: start marker: 0
[08:59:15.000] <TB0> INFO: stop marker: 0
[08:59:15.000] <TB0> INFO: overflow: 0
[08:59:15.000] <TB0> INFO: invalid 5bit words: 0
[08:59:15.000] <TB0> INFO: invalid XOR eye diagram: 0
[08:59:15.000] <TB0> INFO: frame (failed synchr.): 0
[08:59:15.001] <TB0> INFO: idle data (no TBM trl): 0
[08:59:15.001] <TB0> INFO: no data (only TBM hdr): 0
[08:59:15.001] <TB0> INFO: TBM errors: 0
[08:59:15.001] <TB0> INFO: flawed TBM headers: 0
[08:59:15.001] <TB0> INFO: flawed TBM trailers: 0
[08:59:15.001] <TB0> INFO: event ID mismatches: 0
[08:59:15.001] <TB0> INFO: ROC errors: 0
[08:59:15.001] <TB0> INFO: missing ROC header(s): 0
[08:59:15.001] <TB0> INFO: misplaced readback start: 0
[08:59:15.001] <TB0> INFO: Pixel decoding errors: 0
[08:59:15.001] <TB0> INFO: pixel data incomplete: 0
[08:59:15.001] <TB0> INFO: pixel address: 0
[08:59:15.001] <TB0> INFO: pulse height fill bit: 0
[08:59:15.001] <TB0> INFO: buffer corruption: 0
[08:59:15.173] <TB0> INFO: ######################################################################
[08:59:15.173] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:59:15.173] <TB0> INFO: ######################################################################
[08:59:15.183] <TB0> INFO: scanning low vcal = 10
[08:59:15.443] <TB0> INFO: Expecting 41600 events.
[08:59:18.817] <TB0> INFO: 41600 events read in total (2818ms).
[08:59:18.817] <TB0> INFO: Test took 3634ms.
[08:59:18.819] <TB0> INFO: scanning low vcal = 20
[08:59:19.115] <TB0> INFO: Expecting 41600 events.
[08:59:22.504] <TB0> INFO: 41600 events read in total (2833ms).
[08:59:22.504] <TB0> INFO: Test took 3685ms.
[08:59:22.505] <TB0> INFO: scanning low vcal = 30
[08:59:22.802] <TB0> INFO: Expecting 41600 events.
[08:59:26.233] <TB0> INFO: 41600 events read in total (2874ms).
[08:59:26.233] <TB0> INFO: Test took 3728ms.
[08:59:26.235] <TB0> INFO: scanning low vcal = 40
[08:59:26.524] <TB0> INFO: Expecting 41600 events.
[08:59:30.388] <TB0> INFO: 41600 events read in total (3308ms).
[08:59:30.389] <TB0> INFO: Test took 4154ms.
[08:59:30.392] <TB0> INFO: scanning low vcal = 50
[08:59:30.655] <TB0> INFO: Expecting 41600 events.
[08:59:34.590] <TB0> INFO: 41600 events read in total (3379ms).
[08:59:34.591] <TB0> INFO: Test took 4199ms.
[08:59:34.593] <TB0> INFO: scanning low vcal = 60
[08:59:34.855] <TB0> INFO: Expecting 41600 events.
[08:59:38.800] <TB0> INFO: 41600 events read in total (3389ms).
[08:59:38.800] <TB0> INFO: Test took 4207ms.
[08:59:38.803] <TB0> INFO: scanning low vcal = 70
[08:59:39.072] <TB0> INFO: Expecting 41600 events.
[08:59:43.033] <TB0> INFO: 41600 events read in total (3405ms).
[08:59:43.034] <TB0> INFO: Test took 4231ms.
[08:59:43.036] <TB0> INFO: scanning low vcal = 80
[08:59:43.303] <TB0> INFO: Expecting 41600 events.
[08:59:47.263] <TB0> INFO: 41600 events read in total (3404ms).
[08:59:47.263] <TB0> INFO: Test took 4227ms.
[08:59:47.266] <TB0> INFO: scanning low vcal = 90
[08:59:47.542] <TB0> INFO: Expecting 41600 events.
[08:59:51.484] <TB0> INFO: 41600 events read in total (3386ms).
[08:59:51.485] <TB0> INFO: Test took 4219ms.
[08:59:51.488] <TB0> INFO: scanning low vcal = 100
[08:59:51.753] <TB0> INFO: Expecting 41600 events.
[08:59:55.730] <TB0> INFO: 41600 events read in total (3421ms).
[08:59:55.731] <TB0> INFO: Test took 4243ms.
[08:59:55.734] <TB0> INFO: scanning low vcal = 110
[08:59:56.000] <TB0> INFO: Expecting 41600 events.
[08:59:59.971] <TB0> INFO: 41600 events read in total (3415ms).
[08:59:59.971] <TB0> INFO: Test took 4237ms.
[08:59:59.974] <TB0> INFO: scanning low vcal = 120
[09:00:00.245] <TB0> INFO: Expecting 41600 events.
[09:00:04.202] <TB0> INFO: 41600 events read in total (3401ms).
[09:00:04.203] <TB0> INFO: Test took 4229ms.
[09:00:04.206] <TB0> INFO: scanning low vcal = 130
[09:00:04.480] <TB0> INFO: Expecting 41600 events.
[09:00:08.482] <TB0> INFO: 41600 events read in total (3446ms).
[09:00:08.482] <TB0> INFO: Test took 4276ms.
[09:00:08.485] <TB0> INFO: scanning low vcal = 140
[09:00:08.762] <TB0> INFO: Expecting 41600 events.
[09:00:12.712] <TB0> INFO: 41600 events read in total (3394ms).
[09:00:12.713] <TB0> INFO: Test took 4228ms.
[09:00:12.715] <TB0> INFO: scanning low vcal = 150
[09:00:12.976] <TB0> INFO: Expecting 41600 events.
[09:00:16.939] <TB0> INFO: 41600 events read in total (3407ms).
[09:00:16.940] <TB0> INFO: Test took 4225ms.
[09:00:16.943] <TB0> INFO: scanning low vcal = 160
[09:00:17.221] <TB0> INFO: Expecting 41600 events.
[09:00:21.205] <TB0> INFO: 41600 events read in total (3428ms).
[09:00:21.206] <TB0> INFO: Test took 4263ms.
[09:00:21.208] <TB0> INFO: scanning low vcal = 170
[09:00:21.484] <TB0> INFO: Expecting 41600 events.
[09:00:25.441] <TB0> INFO: 41600 events read in total (3401ms).
[09:00:25.441] <TB0> INFO: Test took 4233ms.
[09:00:25.445] <TB0> INFO: scanning low vcal = 180
[09:00:25.709] <TB0> INFO: Expecting 41600 events.
[09:00:29.661] <TB0> INFO: 41600 events read in total (3396ms).
[09:00:29.661] <TB0> INFO: Test took 4216ms.
[09:00:29.664] <TB0> INFO: scanning low vcal = 190
[09:00:29.939] <TB0> INFO: Expecting 41600 events.
[09:00:33.913] <TB0> INFO: 41600 events read in total (3417ms).
[09:00:33.914] <TB0> INFO: Test took 4250ms.
[09:00:33.916] <TB0> INFO: scanning low vcal = 200
[09:00:34.180] <TB0> INFO: Expecting 41600 events.
[09:00:38.173] <TB0> INFO: 41600 events read in total (3436ms).
[09:00:38.173] <TB0> INFO: Test took 4257ms.
[09:00:38.176] <TB0> INFO: scanning low vcal = 210
[09:00:38.441] <TB0> INFO: Expecting 41600 events.
[09:00:42.379] <TB0> INFO: 41600 events read in total (3382ms).
[09:00:42.380] <TB0> INFO: Test took 4204ms.
[09:00:42.382] <TB0> INFO: scanning low vcal = 220
[09:00:42.645] <TB0> INFO: Expecting 41600 events.
[09:00:46.613] <TB0> INFO: 41600 events read in total (3411ms).
[09:00:46.614] <TB0> INFO: Test took 4231ms.
[09:00:46.616] <TB0> INFO: scanning low vcal = 230
[09:00:46.885] <TB0> INFO: Expecting 41600 events.
[09:00:50.864] <TB0> INFO: 41600 events read in total (3423ms).
[09:00:50.865] <TB0> INFO: Test took 4249ms.
[09:00:50.868] <TB0> INFO: scanning low vcal = 240
[09:00:51.135] <TB0> INFO: Expecting 41600 events.
[09:00:55.094] <TB0> INFO: 41600 events read in total (3402ms).
[09:00:55.095] <TB0> INFO: Test took 4227ms.
[09:00:55.098] <TB0> INFO: scanning low vcal = 250
[09:00:55.371] <TB0> INFO: Expecting 41600 events.
[09:00:59.314] <TB0> INFO: 41600 events read in total (3387ms).
[09:00:59.315] <TB0> INFO: Test took 4217ms.
[09:00:59.319] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[09:00:59.578] <TB0> INFO: Expecting 41600 events.
[09:01:03.530] <TB0> INFO: 41600 events read in total (3395ms).
[09:01:03.530] <TB0> INFO: Test took 4211ms.
[09:01:03.533] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[09:01:03.801] <TB0> INFO: Expecting 41600 events.
[09:01:07.717] <TB0> INFO: 41600 events read in total (3360ms).
[09:01:07.717] <TB0> INFO: Test took 4184ms.
[09:01:07.721] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[09:01:07.984] <TB0> INFO: Expecting 41600 events.
[09:01:11.924] <TB0> INFO: 41600 events read in total (3384ms).
[09:01:11.925] <TB0> INFO: Test took 4204ms.
[09:01:11.927] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[09:01:12.189] <TB0> INFO: Expecting 41600 events.
[09:01:16.109] <TB0> INFO: 41600 events read in total (3364ms).
[09:01:16.109] <TB0> INFO: Test took 4182ms.
[09:01:16.112] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:01:16.375] <TB0> INFO: Expecting 41600 events.
[09:01:20.295] <TB0> INFO: 41600 events read in total (3364ms).
[09:01:20.295] <TB0> INFO: Test took 4183ms.
[09:01:20.767] <TB0> INFO: PixTestGainPedestal::measure() done
[09:01:53.180] <TB0> INFO: PixTestGainPedestal::fit() done
[09:01:53.180] <TB0> INFO: non-linearity mean: 0.956 0.950 0.958 0.955 0.953 0.961 0.950 0.956 0.953 0.955 0.949 0.957 0.957 0.960 0.959 0.950
[09:01:53.180] <TB0> INFO: non-linearity RMS: 0.007 0.006 0.005 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.005 0.006 0.006 0.006 0.005 0.006
[09:01:53.180] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:01:53.202] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:01:53.221] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:01:53.241] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:01:53.261] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:01:53.282] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:01:53.321] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:01:53.341] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:01:53.361] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:01:53.381] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:01:53.400] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:01:53.420] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:01:53.440] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:01:53.460] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:01:53.484] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:01:53.505] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:01:53.526] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[09:01:53.526] <TB0> INFO: Decoding statistics:
[09:01:53.526] <TB0> INFO: General information:
[09:01:53.526] <TB0> INFO: 16bit words read: 2662400
[09:01:53.526] <TB0> INFO: valid events total: 166400
[09:01:53.526] <TB0> INFO: empty events: 0
[09:01:53.526] <TB0> INFO: valid events with pixels: 166400
[09:01:53.526] <TB0> INFO: valid pixel hits: 665600
[09:01:53.526] <TB0> INFO: Event errors: 0
[09:01:53.526] <TB0> INFO: start marker: 0
[09:01:53.526] <TB0> INFO: stop marker: 0
[09:01:53.526] <TB0> INFO: overflow: 0
[09:01:53.526] <TB0> INFO: invalid 5bit words: 0
[09:01:53.526] <TB0> INFO: invalid XOR eye diagram: 0
[09:01:53.526] <TB0> INFO: frame (failed synchr.): 0
[09:01:53.526] <TB0> INFO: idle data (no TBM trl): 0
[09:01:53.526] <TB0> INFO: no data (only TBM hdr): 0
[09:01:53.526] <TB0> INFO: TBM errors: 0
[09:01:53.526] <TB0> INFO: flawed TBM headers: 0
[09:01:53.526] <TB0> INFO: flawed TBM trailers: 0
[09:01:53.526] <TB0> INFO: event ID mismatches: 0
[09:01:53.526] <TB0> INFO: ROC errors: 0
[09:01:53.526] <TB0> INFO: missing ROC header(s): 0
[09:01:53.526] <TB0> INFO: misplaced readback start: 0
[09:01:53.526] <TB0> INFO: Pixel decoding errors: 0
[09:01:53.526] <TB0> INFO: pixel data incomplete: 0
[09:01:53.526] <TB0> INFO: pixel address: 0
[09:01:53.526] <TB0> INFO: pulse height fill bit: 0
[09:01:53.526] <TB0> INFO: buffer corruption: 0
[09:01:53.535] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C15.dat
[09:01:53.536] <TB0> INFO: ######################################################################
[09:01:53.536] <TB0> INFO: PixTestReadback::doTest()
[09:01:53.536] <TB0> INFO: ######################################################################
[09:01:53.536] <TB0> INFO: ----------------------------------------------------------------------
[09:01:53.536] <TB0> INFO: PixTestReadback::CalibrateVd()
[09:01:53.536] <TB0> INFO: ----------------------------------------------------------------------
[09:02:03.019] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C0.dat
[09:02:03.020] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C1.dat
[09:02:03.020] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C2.dat
[09:02:03.020] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C3.dat
[09:02:03.020] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C4.dat
[09:02:03.021] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C5.dat
[09:02:03.021] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C6.dat
[09:02:03.021] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C7.dat
[09:02:03.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C8.dat
[09:02:03.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C9.dat
[09:02:03.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C10.dat
[09:02:03.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C11.dat
[09:02:03.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C12.dat
[09:02:03.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C13.dat
[09:02:03.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C14.dat
[09:02:03.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C15.dat
[09:02:03.048] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:02:03.048] <TB0> INFO: ----------------------------------------------------------------------
[09:02:03.048] <TB0> INFO: PixTestReadback::CalibrateVa()
[09:02:03.048] <TB0> INFO: ----------------------------------------------------------------------
[09:02:12.516] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C0.dat
[09:02:12.516] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C1.dat
[09:02:12.516] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C2.dat
[09:02:12.516] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C3.dat
[09:02:12.516] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C4.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C5.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C6.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C7.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C8.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C9.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C10.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C11.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C12.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C13.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C14.dat
[09:02:12.517] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C15.dat
[09:02:12.541] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:02:12.541] <TB0> INFO: ----------------------------------------------------------------------
[09:02:12.541] <TB0> INFO: PixTestReadback::readbackVbg()
[09:02:12.541] <TB0> INFO: ----------------------------------------------------------------------
[09:02:19.819] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:02:19.819] <TB0> INFO: ----------------------------------------------------------------------
[09:02:19.819] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[09:02:19.819] <TB0> INFO: ----------------------------------------------------------------------
[09:02:19.819] <TB0> INFO: Vbg will be calibrated using Vd calibration
[09:02:19.819] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152calibrated Vbg = 1.23397 :::*/*/*/*/
[09:02:19.819] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 141.6calibrated Vbg = 1.23426 :::*/*/*/*/
[09:02:19.819] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.1calibrated Vbg = 1.2343 :::*/*/*/*/
[09:02:19.819] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149.3calibrated Vbg = 1.24143 :::*/*/*/*/
[09:02:19.819] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 143.7calibrated Vbg = 1.23876 :::*/*/*/*/
[09:02:19.819] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.5calibrated Vbg = 1.24429 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.6calibrated Vbg = 1.24333 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.8calibrated Vbg = 1.24794 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 144calibrated Vbg = 1.24387 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 148calibrated Vbg = 1.2458 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 157calibrated Vbg = 1.24637 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 158.8calibrated Vbg = 1.24242 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.3calibrated Vbg = 1.23913 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 157.6calibrated Vbg = 1.22803 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 136calibrated Vbg = 1.23551 :::*/*/*/*/
[09:02:19.820] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 152.9calibrated Vbg = 1.23429 :::*/*/*/*/
[09:02:19.822] <TB0> INFO: ----------------------------------------------------------------------
[09:02:19.822] <TB0> INFO: PixTestReadback::CalibrateIa()
[09:02:19.822] <TB0> INFO: ----------------------------------------------------------------------
[09:04:54.966] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C0.dat
[09:04:54.966] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C1.dat
[09:04:54.966] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C2.dat
[09:04:54.966] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C3.dat
[09:04:54.966] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C4.dat
[09:04:54.966] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C5.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C6.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C7.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C8.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C9.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C10.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C11.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C12.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C13.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C14.dat
[09:04:54.967] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2356_FullQualification_2016-10-10_09h35m_1476084938//000_FulltestPxar_m20//readbackCal_C15.dat
[09:04:54.996] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:04:54.998] <TB0> INFO: PixTestReadback::doTest() done
[09:04:54.999] <TB0> INFO: Decoding statistics:
[09:04:54.999] <TB0> INFO: General information:
[09:04:54.999] <TB0> INFO: 16bit words read: 1024
[09:04:54.999] <TB0> INFO: valid events total: 128
[09:04:54.999] <TB0> INFO: empty events: 128
[09:04:54.999] <TB0> INFO: valid events with pixels: 0
[09:04:54.999] <TB0> INFO: valid pixel hits: 0
[09:04:54.999] <TB0> INFO: Event errors: 0
[09:04:54.999] <TB0> INFO: start marker: 0
[09:04:54.999] <TB0> INFO: stop marker: 0
[09:04:54.999] <TB0> INFO: overflow: 0
[09:04:54.999] <TB0> INFO: invalid 5bit words: 0
[09:04:54.999] <TB0> INFO: invalid XOR eye diagram: 0
[09:04:54.999] <TB0> INFO: frame (failed synchr.): 0
[09:04:54.999] <TB0> INFO: idle data (no TBM trl): 0
[09:04:54.999] <TB0> INFO: no data (only TBM hdr): 0
[09:04:54.999] <TB0> INFO: TBM errors: 0
[09:04:54.999] <TB0> INFO: flawed TBM headers: 0
[09:04:54.999] <TB0> INFO: flawed TBM trailers: 0
[09:04:54.999] <TB0> INFO: event ID mismatches: 0
[09:04:54.999] <TB0> INFO: ROC errors: 0
[09:04:54.999] <TB0> INFO: missing ROC header(s): 0
[09:04:54.999] <TB0> INFO: misplaced readback start: 0
[09:04:54.999] <TB0> INFO: Pixel decoding errors: 0
[09:04:54.999] <TB0> INFO: pixel data incomplete: 0
[09:04:54.999] <TB0> INFO: pixel address: 0
[09:04:54.999] <TB0> INFO: pulse height fill bit: 0
[09:04:54.999] <TB0> INFO: buffer corruption: 0
[09:04:55.021] <TB0> INFO: Decoding statistics:
[09:04:55.021] <TB0> INFO: General information:
[09:04:55.021] <TB0> INFO: 16bit words read: 2750350
[09:04:55.021] <TB0> INFO: valid events total: 176768
[09:04:55.021] <TB0> INFO: empty events: 7865
[09:04:55.021] <TB0> INFO: valid events with pixels: 168903
[09:04:55.021] <TB0> INFO: valid pixel hits: 668103
[09:04:55.021] <TB0> INFO: Event errors: 0
[09:04:55.021] <TB0> INFO: start marker: 0
[09:04:55.021] <TB0> INFO: stop marker: 0
[09:04:55.021] <TB0> INFO: overflow: 0
[09:04:55.021] <TB0> INFO: invalid 5bit words: 0
[09:04:55.021] <TB0> INFO: invalid XOR eye diagram: 0
[09:04:55.021] <TB0> INFO: frame (failed synchr.): 0
[09:04:55.021] <TB0> INFO: idle data (no TBM trl): 0
[09:04:55.021] <TB0> INFO: no data (only TBM hdr): 0
[09:04:55.021] <TB0> INFO: TBM errors: 0
[09:04:55.021] <TB0> INFO: flawed TBM headers: 0
[09:04:55.021] <TB0> INFO: flawed TBM trailers: 0
[09:04:55.021] <TB0> INFO: event ID mismatches: 0
[09:04:55.021] <TB0> INFO: ROC errors: 0
[09:04:55.021] <TB0> INFO: missing ROC header(s): 0
[09:04:55.021] <TB0> INFO: misplaced readback start: 0
[09:04:55.021] <TB0> INFO: Pixel decoding errors: 0
[09:04:55.021] <TB0> INFO: pixel data incomplete: 0
[09:04:55.021] <TB0> INFO: pixel address: 0
[09:04:55.021] <TB0> INFO: pulse height fill bit: 0
[09:04:55.021] <TB0> INFO: buffer corruption: 0
[09:04:55.021] <TB0> INFO: enter test to run
[09:04:55.021] <TB0> INFO: test: exit no parameter change
[09:04:55.207] <TB0> QUIET: Connection to board 73 closed.
[09:04:55.287] <TB0> INFO: pXar: this is the end, my friend