[08:45:50.503] <TB1> INFO: *** Welcome to pxar ***
[08:45:50.503] <TB1> INFO: *** Today: 2016/09/28
[08:45:51.028] <TB1> INFO: *** Version: 0b00-dirty
[08:45:51.028] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C15.dat
[08:45:51.029] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:45:51.029] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//defaultMaskFile.dat
[08:45:51.029] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters_C15.dat
[08:45:51.099] <TB1> INFO: clk: 4
[08:45:51.099] <TB1> INFO: ctr: 4
[08:45:51.099] <TB1> INFO: sda: 19
[08:45:51.099] <TB1> INFO: tin: 9
[08:45:51.099] <TB1> INFO: level: 15
[08:45:51.099] <TB1> INFO: triggerdelay: 0
[08:45:51.099] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:45:51.099] <TB1> INFO: Log level: INFO
[08:45:51.104] <TB1> INFO: Found DTB DTB_WXBYFL
[08:45:51.114] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[08:45:51.116] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[08:45:51.119] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[08:45:52.622] <TB1> INFO: DUT info:
[08:45:52.622] <TB1> INFO: The DUT currently contains the following objects:
[08:45:52.622] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:45:52.622] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:45:52.622] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:45:52.622] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:45:52.622] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.622] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.623] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.623] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.623] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:52.623] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:45:53.023] <TB1> INFO: enter 'restricted' command line mode
[08:45:53.023] <TB1> INFO: enter test to run
[08:45:53.023] <TB1> INFO: test: pretest no parameter change
[08:45:53.023] <TB1> INFO: running: pretest
[08:45:53.028] <TB1> INFO: ----------------------------------------------------------------------
[08:45:53.029] <TB1> INFO: PixTestPretest::programROC()
[08:45:53.029] <TB1> INFO: ----------------------------------------------------------------------
[08:46:11.043] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:46:11.043] <TB1> INFO: IA differences per ROC: 18.5 20.9 19.3 21.7 16.9 21.7 20.1 18.5 20.1 20.9 20.1 18.5 20.1 20.1 20.1 20.9
[08:46:11.147] <TB1> INFO: enter test to run
[08:46:11.147] <TB1> INFO: test: pretest no parameter change
[08:46:11.147] <TB1> INFO: running: pretest
[08:46:11.148] <TB1> INFO: ----------------------------------------------------------------------
[08:46:11.148] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:46:11.148] <TB1> INFO: ----------------------------------------------------------------------
[08:46:17.546] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[08:46:17.546] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 20.1 19.3 18.5 19.3 19.3 19.3 19.3 19.3
[08:46:17.582] <TB1> INFO: enter test to run
[08:46:17.582] <TB1> INFO: test: pretest no parameter change
[08:46:17.582] <TB1> INFO: running: pretest
[08:46:17.582] <TB1> INFO: ----------------------------------------------------------------------
[08:46:17.582] <TB1> INFO: PixTestPretest::findTiming()
[08:46:17.582] <TB1> INFO: ----------------------------------------------------------------------
[08:46:17.582] <TB1> INFO: PixTestCmd::init()

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


[08:47:56.863] <TB1> INFO: 231680 events read in total (8763ms).
[08:47:56.869] <TB1> INFO: Test took 9414ms.
[08:47:57.072] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:47:57.137] <TB1> INFO: enter test to run
[08:47:57.137] <TB1> INFO: test: pretest no parameter change
[08:47:57.137] <TB1> INFO: running: pretest
[08:47:57.139] <TB1> INFO: ----------------------------------------------------------------------
[08:47:57.139] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[08:47:57.139] <TB1> INFO: ----------------------------------------------------------------------
[08:47:57.231] <TB1> INFO: Expecting 231680 events.
[08:48:06.462] <TB1> INFO: 231680 events read in total (8674ms).
[08:48:06.467] <TB1> INFO: Test took 9324ms.
[08:48:06.686] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[08:48:06.686] <TB1> INFO: CalDel: 142 172 146 158 152 147 173 158 145 137 144 165 141 140 156 140
[08:48:06.686] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:48:06.719] <TB1> INFO: enter test to run
[08:48:06.719] <TB1> INFO: test: pretest no parameter change
[08:48:06.719] <TB1> INFO: running: pretest
[08:48:06.719] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C0.dat
[08:48:06.719] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C1.dat
[08:48:06.719] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C2.dat
[08:48:06.719] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C3.dat
[08:48:06.719] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C4.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C5.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C6.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C7.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C8.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C9.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C10.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C11.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C12.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C13.dat
[08:48:06.720] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C14.dat
[08:48:06.721] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters_C15.dat
[08:48:06.721] <TB1> INFO: enter test to run
[08:48:06.721] <TB1> INFO: test: fulltest no parameter change
[08:48:06.721] <TB1> INFO: running: fulltest
[08:48:06.721] <TB1> INFO: ######################################################################
[08:48:06.721] <TB1> INFO: PixTestFullTest::doTest()
[08:48:06.721] <TB1> INFO: ######################################################################
[08:48:06.722] <TB1> INFO: ######################################################################
[08:48:06.722] <TB1> INFO: PixTestAlive::doTest()
[08:48:06.722] <TB1> INFO: ######################################################################
[08:48:06.723] <TB1> INFO: ----------------------------------------------------------------------
[08:48:06.723] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:48:06.723] <TB1> INFO: ----------------------------------------------------------------------
[08:48:06.987] <TB1> INFO: Expecting 41600 events.
[08:48:10.572] <TB1> INFO: 41600 events read in total (3029ms).
[08:48:10.573] <TB1> INFO: Test took 3849ms.
[08:48:10.798] <TB1> INFO: PixTestAlive::aliveTest() done
[08:48:10.798] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:48:10.799] <TB1> INFO: ----------------------------------------------------------------------
[08:48:10.799] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:48:10.799] <TB1> INFO: ----------------------------------------------------------------------
[08:48:11.079] <TB1> INFO: Expecting 41600 events.
[08:48:13.965] <TB1> INFO: 41600 events read in total (2330ms).
[08:48:13.966] <TB1> INFO: Test took 3165ms.
[08:48:13.966] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:48:14.202] <TB1> INFO: PixTestAlive::maskTest() done
[08:48:14.203] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:48:14.204] <TB1> INFO: ----------------------------------------------------------------------
[08:48:14.204] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:48:14.204] <TB1> INFO: ----------------------------------------------------------------------
[08:48:14.467] <TB1> INFO: Expecting 41600 events.
[08:48:17.973] <TB1> INFO: 41600 events read in total (2950ms).
[08:48:17.973] <TB1> INFO: Test took 3767ms.
[08:48:18.204] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[08:48:18.204] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:48:18.205] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:48:18.205] <TB1> INFO: Decoding statistics:
[08:48:18.205] <TB1> INFO: General information:
[08:48:18.205] <TB1> INFO: 16bit words read: 0
[08:48:18.205] <TB1> INFO: valid events total: 0
[08:48:18.205] <TB1> INFO: empty events: 0
[08:48:18.205] <TB1> INFO: valid events with pixels: 0
[08:48:18.205] <TB1> INFO: valid pixel hits: 0
[08:48:18.205] <TB1> INFO: Event errors: 0
[08:48:18.205] <TB1> INFO: start marker: 0
[08:48:18.205] <TB1> INFO: stop marker: 0
[08:48:18.205] <TB1> INFO: overflow: 0
[08:48:18.205] <TB1> INFO: invalid 5bit words: 0
[08:48:18.205] <TB1> INFO: invalid XOR eye diagram: 0
[08:48:18.205] <TB1> INFO: frame (failed synchr.): 0
[08:48:18.205] <TB1> INFO: idle data (no TBM trl): 0
[08:48:18.205] <TB1> INFO: no data (only TBM hdr): 0
[08:48:18.205] <TB1> INFO: TBM errors: 0
[08:48:18.205] <TB1> INFO: flawed TBM headers: 0
[08:48:18.205] <TB1> INFO: flawed TBM trailers: 0
[08:48:18.205] <TB1> INFO: event ID mismatches: 0
[08:48:18.205] <TB1> INFO: ROC errors: 0
[08:48:18.205] <TB1> INFO: missing ROC header(s): 0
[08:48:18.205] <TB1> INFO: misplaced readback start: 0
[08:48:18.205] <TB1> INFO: Pixel decoding errors: 0
[08:48:18.205] <TB1> INFO: pixel data incomplete: 0
[08:48:18.205] <TB1> INFO: pixel address: 0
[08:48:18.205] <TB1> INFO: pulse height fill bit: 0
[08:48:18.205] <TB1> INFO: buffer corruption: 0
[08:48:18.215] <TB1> INFO: ######################################################################
[08:48:18.215] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:48:18.215] <TB1> INFO: ######################################################################
[08:48:18.218] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:48:18.228] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:48:18.228] <TB1> INFO: run 1 of 1
[08:48:18.488] <TB1> INFO: Expecting 3120000 events.
[08:48:56.924] <TB1> INFO: 853705 events read in total (37880ms).
[08:49:33.692] <TB1> INFO: 1694145 events read in total (74648ms).
[08:50:10.415] <TB1> INFO: 2548840 events read in total (111371ms).
[08:50:35.787] <TB1> INFO: 3120000 events read in total (136743ms).
[08:50:35.865] <TB1> INFO: Test took 137637ms.
[08:50:58.399] <TB1> INFO: PixTestBBMap::doTest() done, duration: 160 seconds
[08:50:58.399] <TB1> INFO: number of dead bumps (per ROC): 4 1 0 4 4 1 2 2 3 2 0 2 1 2 5 1
[08:50:58.399] <TB1> INFO: separation cut (per ROC): 92 69 92 97 83 83 70 87 82 85 78 75 72 78 70 81
[08:50:58.399] <TB1> INFO: Decoding statistics:
[08:50:58.399] <TB1> INFO: General information:
[08:50:58.399] <TB1> INFO: 16bit words read: 0
[08:50:58.399] <TB1> INFO: valid events total: 0
[08:50:58.399] <TB1> INFO: empty events: 0
[08:50:58.399] <TB1> INFO: valid events with pixels: 0
[08:50:58.399] <TB1> INFO: valid pixel hits: 0
[08:50:58.399] <TB1> INFO: Event errors: 0
[08:50:58.399] <TB1> INFO: start marker: 0
[08:50:58.399] <TB1> INFO: stop marker: 0
[08:50:58.399] <TB1> INFO: overflow: 0
[08:50:58.399] <TB1> INFO: invalid 5bit words: 0
[08:50:58.399] <TB1> INFO: invalid XOR eye diagram: 0
[08:50:58.399] <TB1> INFO: frame (failed synchr.): 0
[08:50:58.399] <TB1> INFO: idle data (no TBM trl): 0
[08:50:58.399] <TB1> INFO: no data (only TBM hdr): 0
[08:50:58.399] <TB1> INFO: TBM errors: 0
[08:50:58.399] <TB1> INFO: flawed TBM headers: 0
[08:50:58.399] <TB1> INFO: flawed TBM trailers: 0
[08:50:58.399] <TB1> INFO: event ID mismatches: 0
[08:50:58.399] <TB1> INFO: ROC errors: 0
[08:50:58.399] <TB1> INFO: missing ROC header(s): 0
[08:50:58.399] <TB1> INFO: misplaced readback start: 0
[08:50:58.399] <TB1> INFO: Pixel decoding errors: 0
[08:50:58.399] <TB1> INFO: pixel data incomplete: 0
[08:50:58.399] <TB1> INFO: pixel address: 0
[08:50:58.399] <TB1> INFO: pulse height fill bit: 0
[08:50:58.399] <TB1> INFO: buffer corruption: 0
[08:50:58.471] <TB1> INFO: ######################################################################
[08:50:58.471] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:50:58.471] <TB1> INFO: ######################################################################
[08:50:58.471] <TB1> INFO: ----------------------------------------------------------------------
[08:50:58.471] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:50:58.471] <TB1> INFO: ----------------------------------------------------------------------
[08:50:58.471] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[08:50:58.480] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:50:58.480] <TB1> INFO: run 1 of 1
[08:50:58.740] <TB1> INFO: Expecting 36608000 events.
[08:51:28.597] <TB1> INFO: 819150 events read in total (29299ms).
[08:51:57.323] <TB1> INFO: 1623500 events read in total (58025ms).
[08:52:27.209] <TB1> INFO: 2427600 events read in total (87911ms).
[08:52:56.212] <TB1> INFO: 3230300 events read in total (116914ms).
[08:53:25.698] <TB1> INFO: 4034150 events read in total (146400ms).
[08:53:53.838] <TB1> INFO: 4838000 events read in total (174540ms).
[08:54:23.086] <TB1> INFO: 5639200 events read in total (203788ms).
[08:54:52.351] <TB1> INFO: 6443150 events read in total (233053ms).
[08:55:21.384] <TB1> INFO: 7245250 events read in total (262086ms).
[08:55:49.328] <TB1> INFO: 8047200 events read in total (290030ms).
[08:56:18.103] <TB1> INFO: 8849100 events read in total (318805ms).
[08:56:47.703] <TB1> INFO: 9649900 events read in total (348405ms).
[08:57:16.659] <TB1> INFO: 10450750 events read in total (377361ms).
[08:57:45.394] <TB1> INFO: 11251850 events read in total (406096ms).
[08:58:14.453] <TB1> INFO: 12053050 events read in total (435155ms).
[08:58:42.662] <TB1> INFO: 12854500 events read in total (463364ms).
[08:59:12.336] <TB1> INFO: 13654500 events read in total (493038ms).
[08:59:42.588] <TB1> INFO: 14454250 events read in total (523290ms).
[09:00:11.443] <TB1> INFO: 15254300 events read in total (552146ms).
[09:00:40.955] <TB1> INFO: 16053600 events read in total (581657ms).
[09:01:10.760] <TB1> INFO: 16853650 events read in total (611462ms).
[09:01:39.915] <TB1> INFO: 17654800 events read in total (640617ms).
[09:02:08.900] <TB1> INFO: 18450900 events read in total (669602ms).
[09:02:38.096] <TB1> INFO: 19245400 events read in total (698798ms).
[09:03:06.869] <TB1> INFO: 20040400 events read in total (727571ms).
[09:03:34.485] <TB1> INFO: 20834000 events read in total (755187ms).
[09:04:03.549] <TB1> INFO: 21627950 events read in total (784251ms).
[09:04:32.630] <TB1> INFO: 22420650 events read in total (813332ms).
[09:05:00.651] <TB1> INFO: 23213650 events read in total (841353ms).
[09:05:28.726] <TB1> INFO: 24007200 events read in total (869428ms).
[09:05:57.411] <TB1> INFO: 24800700 events read in total (898113ms).
[09:06:26.787] <TB1> INFO: 25594000 events read in total (927489ms).
[09:06:55.220] <TB1> INFO: 26386000 events read in total (955922ms).
[09:07:25.879] <TB1> INFO: 27176800 events read in total (986581ms).
[09:07:53.812] <TB1> INFO: 27968700 events read in total (1014514ms).
[09:08:22.833] <TB1> INFO: 28761150 events read in total (1043535ms).
[09:08:52.881] <TB1> INFO: 29552200 events read in total (1073583ms).
[09:09:22.478] <TB1> INFO: 30345550 events read in total (1103180ms).
[09:09:50.259] <TB1> INFO: 31135400 events read in total (1130961ms).
[09:10:19.359] <TB1> INFO: 31927200 events read in total (1160061ms).
[09:10:48.296] <TB1> INFO: 32720650 events read in total (1188998ms).
[09:11:16.867] <TB1> INFO: 33513600 events read in total (1217569ms).
[09:11:46.467] <TB1> INFO: 34306950 events read in total (1247169ms).
[09:12:16.164] <TB1> INFO: 35099400 events read in total (1276866ms).
[09:12:45.710] <TB1> INFO: 35893850 events read in total (1306412ms).
[09:13:12.330] <TB1> INFO: 36608000 events read in total (1333032ms).
[09:13:12.386] <TB1> INFO: Test took 1333906ms.
[09:13:12.860] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:14.559] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:16.306] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:18.064] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:19.709] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:21.470] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:23.097] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:24.744] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:26.406] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:28.034] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:29.711] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:31.361] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:33.011] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:34.687] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:36.369] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:38.021] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[09:13:39.678] <TB1> INFO: PixTestScurves::scurves() done
[09:13:39.678] <TB1> INFO: Vcal mean: 88.25 73.23 91.66 103.26 90.68 83.09 79.91 96.62 81.99 90.72 83.27 81.00 78.49 89.06 82.89 81.59
[09:13:39.678] <TB1> INFO: Vcal RMS: 5.81 4.41 5.48 6.29 5.10 4.61 4.19 5.52 4.67 5.23 4.69 4.23 3.99 5.18 4.23 4.40
[09:13:39.678] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1361 seconds
[09:13:39.678] <TB1> INFO: Decoding statistics:
[09:13:39.678] <TB1> INFO: General information:
[09:13:39.678] <TB1> INFO: 16bit words read: 0
[09:13:39.678] <TB1> INFO: valid events total: 0
[09:13:39.678] <TB1> INFO: empty events: 0
[09:13:39.678] <TB1> INFO: valid events with pixels: 0
[09:13:39.678] <TB1> INFO: valid pixel hits: 0
[09:13:39.678] <TB1> INFO: Event errors: 0
[09:13:39.678] <TB1> INFO: start marker: 0
[09:13:39.678] <TB1> INFO: stop marker: 0
[09:13:39.678] <TB1> INFO: overflow: 0
[09:13:39.678] <TB1> INFO: invalid 5bit words: 0
[09:13:39.678] <TB1> INFO: invalid XOR eye diagram: 0
[09:13:39.678] <TB1> INFO: frame (failed synchr.): 0
[09:13:39.678] <TB1> INFO: idle data (no TBM trl): 0
[09:13:39.678] <TB1> INFO: no data (only TBM hdr): 0
[09:13:39.678] <TB1> INFO: TBM errors: 0
[09:13:39.678] <TB1> INFO: flawed TBM headers: 0
[09:13:39.678] <TB1> INFO: flawed TBM trailers: 0
[09:13:39.678] <TB1> INFO: event ID mismatches: 0
[09:13:39.678] <TB1> INFO: ROC errors: 0
[09:13:39.678] <TB1> INFO: missing ROC header(s): 0
[09:13:39.678] <TB1> INFO: misplaced readback start: 0
[09:13:39.678] <TB1> INFO: Pixel decoding errors: 0
[09:13:39.678] <TB1> INFO: pixel data incomplete: 0
[09:13:39.678] <TB1> INFO: pixel address: 0
[09:13:39.678] <TB1> INFO: pulse height fill bit: 0
[09:13:39.678] <TB1> INFO: buffer corruption: 0
[09:13:39.748] <TB1> INFO: ######################################################################
[09:13:39.748] <TB1> INFO: PixTestTrim::doTest()
[09:13:39.748] <TB1> INFO: ######################################################################
[09:13:39.750] <TB1> INFO: ----------------------------------------------------------------------
[09:13:39.750] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:13:39.750] <TB1> INFO: ----------------------------------------------------------------------
[09:13:39.827] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:13:39.827] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:13:39.835] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:13:39.835] <TB1> INFO: run 1 of 1
[09:13:40.094] <TB1> INFO: Expecting 6281600 events.
[09:14:18.107] <TB1> INFO: 1098940 events read in total (37448ms).
[09:14:54.361] <TB1> INFO: 2191670 events read in total (73702ms).
[09:15:31.465] <TB1> INFO: 3278980 events read in total (110806ms).
[09:16:08.366] <TB1> INFO: 4373990 events read in total (147707ms).
[09:16:45.438] <TB1> INFO: 5474410 events read in total (184779ms).
[09:17:11.944] <TB1> INFO: 6281600 events read in total (211285ms).
[09:17:11.994] <TB1> INFO: Test took 212160ms.
[09:17:30.260] <TB1> INFO: ROC 0 VthrComp = 92
[09:17:30.260] <TB1> INFO: ROC 1 VthrComp = 78
[09:17:30.260] <TB1> INFO: ROC 2 VthrComp = 93
[09:17:30.260] <TB1> INFO: ROC 3 VthrComp = 99
[09:17:30.260] <TB1> INFO: ROC 4 VthrComp = 94
[09:17:30.260] <TB1> INFO: ROC 5 VthrComp = 91
[09:17:30.261] <TB1> INFO: ROC 6 VthrComp = 84
[09:17:30.261] <TB1> INFO: ROC 7 VthrComp = 97
[09:17:30.261] <TB1> INFO: ROC 8 VthrComp = 87
[09:17:30.261] <TB1> INFO: ROC 9 VthrComp = 95
[09:17:30.261] <TB1> INFO: ROC 10 VthrComp = 88
[09:17:30.261] <TB1> INFO: ROC 11 VthrComp = 86
[09:17:30.261] <TB1> INFO: ROC 12 VthrComp = 85
[09:17:30.261] <TB1> INFO: ROC 13 VthrComp = 95
[09:17:30.261] <TB1> INFO: ROC 14 VthrComp = 88
[09:17:30.261] <TB1> INFO: ROC 15 VthrComp = 90
[09:17:30.261] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:17:30.262] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:17:30.270] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:17:30.270] <TB1> INFO: run 1 of 1
[09:17:30.528] <TB1> INFO: Expecting 6281600 events.
[09:18:02.025] <TB1> INFO: 759950 events read in total (30940ms).
[09:18:33.536] <TB1> INFO: 1517980 events read in total (62451ms).
[09:19:04.692] <TB1> INFO: 2275490 events read in total (93607ms).
[09:19:35.850] <TB1> INFO: 3033240 events read in total (124765ms).
[09:20:06.922] <TB1> INFO: 3785550 events read in total (155837ms).
[09:20:37.663] <TB1> INFO: 4534820 events read in total (186578ms).
[09:21:09.434] <TB1> INFO: 5281700 events read in total (218349ms).
[09:21:40.855] <TB1> INFO: 6028820 events read in total (249770ms).
[09:21:51.369] <TB1> INFO: 6281600 events read in total (260284ms).
[09:21:51.421] <TB1> INFO: Test took 261152ms.
[09:22:14.215] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.3602 for pixel 2/78 mean/min/max = 46.1804/31.5866/60.7742
[09:22:14.215] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.1358 for pixel 0/13 mean/min/max = 46.0354/34.9174/57.1535
[09:22:14.215] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 59.8696 for pixel 8/5 mean/min/max = 46.3083/32.7354/59.8812
[09:22:14.215] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 64.1237 for pixel 15/63 mean/min/max = 47.6024/30.4451/64.7597
[09:22:14.216] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.2515 for pixel 21/4 mean/min/max = 45.3256/32.3693/58.2818
[09:22:14.216] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.2763 for pixel 4/72 mean/min/max = 44.9907/32.5028/57.4786
[09:22:14.216] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.0472 for pixel 19/77 mean/min/max = 44.8969/32.6239/57.17
[09:22:14.216] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 60.1777 for pixel 0/58 mean/min/max = 46.0395/31.8992/60.1798
[09:22:14.217] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 59.5478 for pixel 0/9 mean/min/max = 45.8063/31.9729/59.6398
[09:22:14.217] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 58.091 for pixel 0/2 mean/min/max = 44.9559/31.7038/58.208
[09:22:14.217] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.2781 for pixel 5/74 mean/min/max = 45.1667/32.9389/57.3945
[09:22:14.218] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 56.9193 for pixel 29/1 mean/min/max = 44.7136/32.4059/57.0214
[09:22:14.218] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 56.2956 for pixel 14/14 mean/min/max = 44.6254/32.648/56.6027
[09:22:14.218] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.8479 for pixel 16/20 mean/min/max = 44.8108/31.6679/57.9536
[09:22:14.219] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.8584 for pixel 20/71 mean/min/max = 44.9228/32.9062/56.9395
[09:22:14.219] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.3888 for pixel 13/0 mean/min/max = 45.0447/32.502/57.5875
[09:22:14.219] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:22:14.308] <TB1> INFO: Expecting 514560 events.
[09:22:25.253] <TB1> INFO: 514560 events read in total (10388ms).
[09:22:25.260] <TB1> INFO: Expecting 514560 events.
[09:22:35.966] <TB1> INFO: 514560 events read in total (10304ms).
[09:22:35.976] <TB1> INFO: Expecting 514560 events.
[09:22:46.930] <TB1> INFO: 514560 events read in total (10552ms).
[09:22:46.946] <TB1> INFO: Expecting 514560 events.
[09:22:57.869] <TB1> INFO: 514560 events read in total (10521ms).
[09:22:57.886] <TB1> INFO: Expecting 514560 events.
[09:23:08.873] <TB1> INFO: 514560 events read in total (10585ms).
[09:23:08.890] <TB1> INFO: Expecting 514560 events.
[09:23:19.897] <TB1> INFO: 514560 events read in total (10605ms).
[09:23:19.929] <TB1> INFO: Expecting 514560 events.
[09:23:30.637] <TB1> INFO: 514560 events read in total (10306ms).
[09:23:30.659] <TB1> INFO: Expecting 514560 events.
[09:23:41.584] <TB1> INFO: 514560 events read in total (10523ms).
[09:23:41.622] <TB1> INFO: Expecting 514560 events.
[09:23:52.625] <TB1> INFO: 514560 events read in total (10601ms).
[09:23:52.653] <TB1> INFO: Expecting 514560 events.
[09:24:03.661] <TB1> INFO: 514560 events read in total (10607ms).
[09:24:03.691] <TB1> INFO: Expecting 514560 events.
[09:24:14.568] <TB1> INFO: 514560 events read in total (10475ms).
[09:24:14.604] <TB1> INFO: Expecting 514560 events.
[09:24:25.708] <TB1> INFO: 514560 events read in total (10702ms).
[09:24:25.755] <TB1> INFO: Expecting 514560 events.
[09:24:36.797] <TB1> INFO: 514560 events read in total (10640ms).
[09:24:36.838] <TB1> INFO: Expecting 514560 events.
[09:24:47.509] <TB1> INFO: 514560 events read in total (10269ms).
[09:24:47.548] <TB1> INFO: Expecting 514560 events.
[09:24:58.529] <TB1> INFO: 514560 events read in total (10579ms).
[09:24:58.576] <TB1> INFO: Expecting 514560 events.
[09:25:09.378] <TB1> INFO: 514560 events read in total (10400ms).
[09:25:09.424] <TB1> INFO: Test took 175205ms.
[09:25:10.421] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:25:10.429] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:25:10.429] <TB1> INFO: run 1 of 1
[09:25:10.689] <TB1> INFO: Expecting 6281600 events.
[09:25:41.905] <TB1> INFO: 746260 events read in total (30660ms).
[09:26:13.205] <TB1> INFO: 1491370 events read in total (61960ms).
[09:26:44.613] <TB1> INFO: 2237000 events read in total (93368ms).
[09:27:15.872] <TB1> INFO: 2982580 events read in total (124627ms).
[09:27:47.360] <TB1> INFO: 3722710 events read in total (156115ms).
[09:28:18.669] <TB1> INFO: 4459570 events read in total (187424ms).
[09:28:50.271] <TB1> INFO: 5194100 events read in total (219026ms).
[09:29:21.086] <TB1> INFO: 5928760 events read in total (249841ms).
[09:29:36.008] <TB1> INFO: 6281600 events read in total (264763ms).
[09:29:36.064] <TB1> INFO: Test took 265635ms.
[09:29:58.233] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 1.500000 .. 53.072408
[09:29:58.495] <TB1> INFO: Expecting 208000 events.
[09:30:08.426] <TB1> INFO: 208000 events read in total (9375ms).
[09:30:08.427] <TB1> INFO: Test took 10192ms.
[09:30:08.514] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 63 (-1/-1) hits flags = 528 (plus default)
[09:30:08.522] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:30:08.522] <TB1> INFO: run 1 of 1
[09:30:08.781] <TB1> INFO: Expecting 2620800 events.
[09:30:44.469] <TB1> INFO: 927880 events read in total (35131ms).
[09:31:19.727] <TB1> INFO: 1852520 events read in total (70389ms).
[09:31:47.900] <TB1> INFO: 2620800 events read in total (98562ms).
[09:31:47.925] <TB1> INFO: Test took 99404ms.
[09:32:01.796] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 20.408900 .. 46.690109
[09:32:02.058] <TB1> INFO: Expecting 208000 events.
[09:32:12.969] <TB1> INFO: 208000 events read in total (10355ms).
[09:32:12.969] <TB1> INFO: Test took 11171ms.
[09:32:13.081] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 10 .. 56 (-1/-1) hits flags = 528 (plus default)
[09:32:13.091] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:32:13.091] <TB1> INFO: run 1 of 1
[09:32:13.404] <TB1> INFO: Expecting 1955200 events.
[09:32:48.959] <TB1> INFO: 920200 events read in total (34999ms).
[09:33:23.324] <TB1> INFO: 1837270 events read in total (69364ms).
[09:33:27.939] <TB1> INFO: 1955200 events read in total (73979ms).
[09:33:27.957] <TB1> INFO: Test took 74866ms.
[09:33:40.906] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 20.389916 .. 44.326051
[09:33:41.186] <TB1> INFO: Expecting 208000 events.
[09:33:51.812] <TB1> INFO: 208000 events read in total (10069ms).
[09:33:51.813] <TB1> INFO: Test took 10906ms.
[09:33:51.898] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 54 (-1/-1) hits flags = 528 (plus default)
[09:33:51.908] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:33:51.908] <TB1> INFO: run 1 of 1
[09:33:52.168] <TB1> INFO: Expecting 1872000 events.
[09:34:27.176] <TB1> INFO: 935880 events read in total (34452ms).
[09:35:01.565] <TB1> INFO: 1869630 events read in total (68841ms).
[09:35:02.082] <TB1> INFO: 1872000 events read in total (69359ms).
[09:35:02.099] <TB1> INFO: Test took 70190ms.
[09:35:14.886] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 23.230540 .. 44.326051
[09:35:15.148] <TB1> INFO: Expecting 208000 events.
[09:35:25.655] <TB1> INFO: 208000 events read in total (9951ms).
[09:35:25.656] <TB1> INFO: Test took 10769ms.
[09:35:25.768] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 54 (-1/-1) hits flags = 528 (plus default)
[09:35:25.776] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:35:25.776] <TB1> INFO: run 1 of 1
[09:35:26.036] <TB1> INFO: Expecting 1747200 events.
[09:36:01.633] <TB1> INFO: 917280 events read in total (35040ms).
[09:36:33.753] <TB1> INFO: 1747200 events read in total (67160ms).
[09:36:33.781] <TB1> INFO: Test took 68005ms.
[09:36:48.328] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:36:48.328] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:36:48.336] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:36:48.336] <TB1> INFO: run 1 of 1
[09:36:48.595] <TB1> INFO: Expecting 1705600 events.
[09:37:23.471] <TB1> INFO: 894340 events read in total (34320ms).
[09:37:54.052] <TB1> INFO: 1705600 events read in total (64901ms).
[09:37:54.070] <TB1> INFO: Test took 65734ms.
[09:38:07.081] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:38:07.082] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:38:07.082] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:38:07.082] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:38:07.082] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:38:07.082] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:38:07.082] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:38:07.082] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:38:07.083] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:38:07.083] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:38:07.083] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:38:07.083] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:38:07.083] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:38:07.083] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:38:07.083] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:38:07.084] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:38:07.084] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:38:07.090] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:38:07.097] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:38:07.104] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:38:07.110] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:38:07.116] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:38:07.122] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:38:07.128] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:38:07.135] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:38:07.141] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:38:07.148] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:38:07.155] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:38:07.161] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:38:07.167] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:38:07.173] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:38:07.179] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:38:07.185] <TB1> INFO: PixTestTrim::trimTest() done
[09:38:07.185] <TB1> INFO: vtrim: 113 109 97 131 101 118 94 103 107 107 93 106 108 88 103 114
[09:38:07.185] <TB1> INFO: vthrcomp: 92 78 93 99 94 91 84 97 87 95 88 86 85 95 88 90
[09:38:07.185] <TB1> INFO: vcal mean: 34.92 35.01 35.01 34.92 34.98 34.98 34.95 34.95 34.95 35.00 35.01 34.91 34.94 34.94 35.00 35.01
[09:38:07.185] <TB1> INFO: vcal RMS: 0.84 0.76 0.84 0.98 0.87 0.79 0.81 0.93 0.85 0.86 0.81 0.83 0.83 0.87 0.83 0.81
[09:38:07.185] <TB1> INFO: bits mean: 9.45 8.59 9.22 9.41 9.73 9.79 9.79 9.62 9.08 9.37 9.23 9.76 9.84 9.70 9.74 9.64
[09:38:07.185] <TB1> INFO: bits RMS: 2.72 2.66 2.64 2.77 2.52 2.53 2.47 2.65 2.88 2.90 2.69 2.57 2.44 2.69 2.45 2.61
[09:38:07.191] <TB1> INFO: ----------------------------------------------------------------------
[09:38:07.191] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:38:07.191] <TB1> INFO: ----------------------------------------------------------------------
[09:38:07.194] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:38:07.203] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:38:07.203] <TB1> INFO: run 1 of 1
[09:38:07.466] <TB1> INFO: Expecting 4160000 events.
[09:38:46.336] <TB1> INFO: 916775 events read in total (38314ms).
[09:39:24.417] <TB1> INFO: 1827285 events read in total (76395ms).
[09:40:02.472] <TB1> INFO: 2730995 events read in total (114450ms).
[09:40:40.932] <TB1> INFO: 3631930 events read in total (152910ms).
[09:41:02.947] <TB1> INFO: 4160000 events read in total (174925ms).
[09:41:03.003] <TB1> INFO: Test took 175800ms.
[09:41:30.771] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[09:41:30.779] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:41:30.779] <TB1> INFO: run 1 of 1
[09:41:31.039] <TB1> INFO: Expecting 3536000 events.
[09:42:10.849] <TB1> INFO: 945720 events read in total (39254ms).
[09:42:49.790] <TB1> INFO: 1882745 events read in total (78195ms).
[09:43:27.917] <TB1> INFO: 2810495 events read in total (116322ms).
[09:43:58.617] <TB1> INFO: 3536000 events read in total (147022ms).
[09:43:58.696] <TB1> INFO: Test took 147918ms.
[09:44:23.267] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[09:44:23.275] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:44:23.275] <TB1> INFO: run 1 of 1
[09:44:23.535] <TB1> INFO: Expecting 3286400 events.
[09:45:03.666] <TB1> INFO: 976165 events read in total (39574ms).
[09:45:42.178] <TB1> INFO: 1940970 events read in total (78086ms).
[09:46:21.790] <TB1> INFO: 2896575 events read in total (117698ms).
[09:46:38.220] <TB1> INFO: 3286400 events read in total (134128ms).
[09:46:38.272] <TB1> INFO: Test took 134998ms.
[09:47:02.182] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[09:47:02.191] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:47:02.191] <TB1> INFO: run 1 of 1
[09:47:02.450] <TB1> INFO: Expecting 3307200 events.
[09:47:41.307] <TB1> INFO: 972350 events read in total (38301ms).
[09:48:20.832] <TB1> INFO: 1933865 events read in total (77826ms).
[09:49:00.837] <TB1> INFO: 2885790 events read in total (117831ms).
[09:49:18.146] <TB1> INFO: 3307200 events read in total (135140ms).
[09:49:18.196] <TB1> INFO: Test took 136005ms.
[09:49:42.472] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[09:49:42.480] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:49:42.480] <TB1> INFO: run 1 of 1
[09:49:42.740] <TB1> INFO: Expecting 3307200 events.
[09:50:22.679] <TB1> INFO: 971730 events read in total (39383ms).
[09:51:03.090] <TB1> INFO: 1932470 events read in total (79794ms).
[09:51:42.681] <TB1> INFO: 2883995 events read in total (119385ms).
[09:51:59.671] <TB1> INFO: 3307200 events read in total (136375ms).
[09:51:59.722] <TB1> INFO: Test took 137242ms.
[09:52:23.563] <TB1> INFO: PixTestTrim::trimBitTest() done
[09:52:23.564] <TB1> INFO: PixTestTrim::doTest() done, duration: 2323 seconds
[09:52:23.569] <TB1> INFO: Decoding statistics:
[09:52:23.569] <TB1> INFO: General information:
[09:52:23.569] <TB1> INFO: 16bit words read: 0
[09:52:23.569] <TB1> INFO: valid events total: 0
[09:52:23.569] <TB1> INFO: empty events: 0
[09:52:23.569] <TB1> INFO: valid events with pixels: 0
[09:52:23.569] <TB1> INFO: valid pixel hits: 0
[09:52:23.569] <TB1> INFO: Event errors: 0
[09:52:23.569] <TB1> INFO: start marker: 0
[09:52:23.569] <TB1> INFO: stop marker: 0
[09:52:23.569] <TB1> INFO: overflow: 0
[09:52:23.569] <TB1> INFO: invalid 5bit words: 0
[09:52:23.569] <TB1> INFO: invalid XOR eye diagram: 0
[09:52:23.569] <TB1> INFO: frame (failed synchr.): 0
[09:52:23.569] <TB1> INFO: idle data (no TBM trl): 0
[09:52:23.569] <TB1> INFO: no data (only TBM hdr): 0
[09:52:23.569] <TB1> INFO: TBM errors: 0
[09:52:23.569] <TB1> INFO: flawed TBM headers: 0
[09:52:23.569] <TB1> INFO: flawed TBM trailers: 0
[09:52:23.569] <TB1> INFO: event ID mismatches: 0
[09:52:23.569] <TB1> INFO: ROC errors: 0
[09:52:23.569] <TB1> INFO: missing ROC header(s): 0
[09:52:23.569] <TB1> INFO: misplaced readback start: 0
[09:52:23.569] <TB1> INFO: Pixel decoding errors: 0
[09:52:23.569] <TB1> INFO: pixel data incomplete: 0
[09:52:23.569] <TB1> INFO: pixel address: 0
[09:52:23.569] <TB1> INFO: pulse height fill bit: 0
[09:52:23.569] <TB1> INFO: buffer corruption: 0
[09:52:24.314] <TB1> INFO: ######################################################################
[09:52:24.314] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:52:24.314] <TB1> INFO: ######################################################################
[09:52:24.599] <TB1> INFO: Expecting 41600 events.
[09:52:28.089] <TB1> INFO: 41600 events read in total (2934ms).
[09:52:28.090] <TB1> INFO: Test took 3774ms.
[09:52:28.551] <TB1> INFO: Expecting 41600 events.
[09:52:32.084] <TB1> INFO: 41600 events read in total (2976ms).
[09:52:32.085] <TB1> INFO: Test took 3796ms.
[09:52:32.411] <TB1> INFO: Expecting 41600 events.
[09:52:35.965] <TB1> INFO: 41600 events read in total (2998ms).
[09:52:35.966] <TB1> INFO: Test took 3855ms.
[09:52:36.221] <TB1> INFO: Expecting 2560 events.
[09:52:37.073] <TB1> INFO: 2560 events read in total (296ms).
[09:52:37.073] <TB1> INFO: Test took 1094ms.
[09:52:37.379] <TB1> INFO: Expecting 2560 events.
[09:52:38.233] <TB1> INFO: 2560 events read in total (297ms).
[09:52:38.233] <TB1> INFO: Test took 1159ms.
[09:52:38.540] <TB1> INFO: Expecting 2560 events.
[09:52:39.396] <TB1> INFO: 2560 events read in total (300ms).
[09:52:39.396] <TB1> INFO: Test took 1163ms.
[09:52:39.700] <TB1> INFO: Expecting 2560 events.
[09:52:40.555] <TB1> INFO: 2560 events read in total (298ms).
[09:52:40.555] <TB1> INFO: Test took 1158ms.
[09:52:40.861] <TB1> INFO: Expecting 2560 events.
[09:52:41.714] <TB1> INFO: 2560 events read in total (296ms).
[09:52:41.715] <TB1> INFO: Test took 1160ms.
[09:52:42.021] <TB1> INFO: Expecting 2560 events.
[09:52:42.875] <TB1> INFO: 2560 events read in total (298ms).
[09:52:42.875] <TB1> INFO: Test took 1160ms.
[09:52:43.181] <TB1> INFO: Expecting 2560 events.
[09:52:44.035] <TB1> INFO: 2560 events read in total (297ms).
[09:52:44.035] <TB1> INFO: Test took 1160ms.
[09:52:44.341] <TB1> INFO: Expecting 2560 events.
[09:52:45.195] <TB1> INFO: 2560 events read in total (298ms).
[09:52:45.196] <TB1> INFO: Test took 1161ms.
[09:52:45.501] <TB1> INFO: Expecting 2560 events.
[09:52:46.355] <TB1> INFO: 2560 events read in total (298ms).
[09:52:46.356] <TB1> INFO: Test took 1160ms.
[09:52:46.661] <TB1> INFO: Expecting 2560 events.
[09:52:47.516] <TB1> INFO: 2560 events read in total (299ms).
[09:52:47.516] <TB1> INFO: Test took 1160ms.
[09:52:47.821] <TB1> INFO: Expecting 2560 events.
[09:52:48.676] <TB1> INFO: 2560 events read in total (298ms).
[09:52:48.676] <TB1> INFO: Test took 1160ms.
[09:52:48.983] <TB1> INFO: Expecting 2560 events.
[09:52:49.844] <TB1> INFO: 2560 events read in total (305ms).
[09:52:49.844] <TB1> INFO: Test took 1167ms.
[09:52:50.149] <TB1> INFO: Expecting 2560 events.
[09:52:51.007] <TB1> INFO: 2560 events read in total (302ms).
[09:52:51.008] <TB1> INFO: Test took 1163ms.
[09:52:51.313] <TB1> INFO: Expecting 2560 events.
[09:52:52.166] <TB1> INFO: 2560 events read in total (297ms).
[09:52:52.166] <TB1> INFO: Test took 1158ms.
[09:52:52.472] <TB1> INFO: Expecting 2560 events.
[09:52:53.334] <TB1> INFO: 2560 events read in total (306ms).
[09:52:53.334] <TB1> INFO: Test took 1168ms.
[09:52:53.639] <TB1> INFO: Expecting 2560 events.
[09:52:54.492] <TB1> INFO: 2560 events read in total (297ms).
[09:52:54.492] <TB1> INFO: Test took 1158ms.
[09:52:54.495] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:52:54.798] <TB1> INFO: Expecting 655360 events.
[09:53:09.215] <TB1> INFO: 655360 events read in total (13860ms).
[09:53:09.228] <TB1> INFO: Expecting 655360 events.
[09:53:23.291] <TB1> INFO: 655360 events read in total (13661ms).
[09:53:23.306] <TB1> INFO: Expecting 655360 events.
[09:53:37.099] <TB1> INFO: 655360 events read in total (13392ms).
[09:53:37.118] <TB1> INFO: Expecting 655360 events.
[09:53:51.093] <TB1> INFO: 655360 events read in total (13573ms).
[09:53:51.128] <TB1> INFO: Expecting 655360 events.
[09:54:05.105] <TB1> INFO: 655360 events read in total (13575ms).
[09:54:05.144] <TB1> INFO: Expecting 655360 events.
[09:54:19.085] <TB1> INFO: 655360 events read in total (13539ms).
[09:54:19.128] <TB1> INFO: Expecting 655360 events.
[09:54:33.086] <TB1> INFO: 655360 events read in total (13556ms).
[09:54:33.122] <TB1> INFO: Expecting 655360 events.
[09:54:46.854] <TB1> INFO: 655360 events read in total (13330ms).
[09:54:46.893] <TB1> INFO: Expecting 655360 events.
[09:55:00.555] <TB1> INFO: 655360 events read in total (13260ms).
[09:55:00.599] <TB1> INFO: Expecting 655360 events.
[09:55:14.495] <TB1> INFO: 655360 events read in total (13494ms).
[09:55:14.572] <TB1> INFO: Expecting 655360 events.
[09:55:28.588] <TB1> INFO: 655360 events read in total (13614ms).
[09:55:28.643] <TB1> INFO: Expecting 655360 events.
[09:55:42.886] <TB1> INFO: 655360 events read in total (13841ms).
[09:55:42.966] <TB1> INFO: Expecting 655360 events.
[09:55:56.789] <TB1> INFO: 655360 events read in total (13421ms).
[09:55:56.852] <TB1> INFO: Expecting 655360 events.
[09:56:10.923] <TB1> INFO: 655360 events read in total (13669ms).
[09:56:10.990] <TB1> INFO: Expecting 655360 events.
[09:56:24.839] <TB1> INFO: 655360 events read in total (13447ms).
[09:56:24.911] <TB1> INFO: Expecting 655360 events.
[09:56:38.683] <TB1> INFO: 655360 events read in total (13370ms).
[09:56:38.760] <TB1> INFO: Test took 224265ms.
[09:56:38.840] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:56:39.070] <TB1> INFO: Expecting 655360 events.
[09:56:53.351] <TB1> INFO: 655360 events read in total (13725ms).
[09:56:53.361] <TB1> INFO: Expecting 655360 events.
[09:57:07.555] <TB1> INFO: 655360 events read in total (13792ms).
[09:57:07.569] <TB1> INFO: Expecting 655360 events.
[09:57:21.458] <TB1> INFO: 655360 events read in total (13487ms).
[09:57:21.476] <TB1> INFO: Expecting 655360 events.
[09:57:35.337] <TB1> INFO: 655360 events read in total (13459ms).
[09:57:35.363] <TB1> INFO: Expecting 655360 events.
[09:57:49.129] <TB1> INFO: 655360 events read in total (13364ms).
[09:57:49.173] <TB1> INFO: Expecting 655360 events.
[09:58:03.439] <TB1> INFO: 655360 events read in total (13864ms).
[09:58:03.474] <TB1> INFO: Expecting 655360 events.
[09:58:17.656] <TB1> INFO: 655360 events read in total (13780ms).
[09:58:17.691] <TB1> INFO: Expecting 655360 events.
[09:58:31.663] <TB1> INFO: 655360 events read in total (13570ms).
[09:58:31.706] <TB1> INFO: Expecting 655360 events.
[09:58:45.467] <TB1> INFO: 655360 events read in total (13359ms).
[09:58:45.511] <TB1> INFO: Expecting 655360 events.
[09:58:59.408] <TB1> INFO: 655360 events read in total (13495ms).
[09:58:59.456] <TB1> INFO: Expecting 655360 events.
[09:59:13.228] <TB1> INFO: 655360 events read in total (13370ms).
[09:59:13.281] <TB1> INFO: Expecting 655360 events.
[09:59:27.216] <TB1> INFO: 655360 events read in total (13533ms).
[09:59:27.303] <TB1> INFO: Expecting 655360 events.
[09:59:41.216] <TB1> INFO: 655360 events read in total (13511ms).
[09:59:41.276] <TB1> INFO: Expecting 655360 events.
[09:59:54.999] <TB1> INFO: 655360 events read in total (13321ms).
[09:59:55.084] <TB1> INFO: Expecting 655360 events.
[10:00:08.975] <TB1> INFO: 655360 events read in total (13489ms).
[10:00:09.075] <TB1> INFO: Expecting 655360 events.
[10:00:22.948] <TB1> INFO: 655360 events read in total (13471ms).
[10:00:23.027] <TB1> INFO: Test took 224187ms.
[10:00:23.212] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.219] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.227] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[10:00:23.234] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.241] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.248] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.254] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.261] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.268] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.274] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.281] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.287] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.294] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[10:00:23.304] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.314] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.324] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.334] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.344] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[10:00:23.384] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:00:23.384] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:00:23.384] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:00:23.384] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:00:23.385] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:00:23.385] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:00:23.385] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:00:23.385] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:00:23.385] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:00:23.385] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:00:23.386] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:00:23.386] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:00:23.386] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:00:23.386] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:00:23.386] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:00:23.386] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:00:23.694] <TB1> INFO: Expecting 41600 events.
[10:00:26.814] <TB1> INFO: 41600 events read in total (2564ms).
[10:00:26.815] <TB1> INFO: Test took 3426ms.
[10:00:27.260] <TB1> INFO: Expecting 41600 events.
[10:00:30.306] <TB1> INFO: 41600 events read in total (2489ms).
[10:00:30.307] <TB1> INFO: Test took 3313ms.
[10:00:30.758] <TB1> INFO: Expecting 41600 events.
[10:00:33.932] <TB1> INFO: 41600 events read in total (2617ms).
[10:00:33.933] <TB1> INFO: Test took 3444ms.
[10:00:34.126] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:34.215] <TB1> INFO: Expecting 2560 events.
[10:00:35.071] <TB1> INFO: 2560 events read in total (300ms).
[10:00:35.072] <TB1> INFO: Test took 946ms.
[10:00:35.074] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:35.376] <TB1> INFO: Expecting 2560 events.
[10:00:36.233] <TB1> INFO: 2560 events read in total (301ms).
[10:00:36.233] <TB1> INFO: Test took 1159ms.
[10:00:36.235] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:36.538] <TB1> INFO: Expecting 2560 events.
[10:00:37.398] <TB1> INFO: 2560 events read in total (303ms).
[10:00:37.398] <TB1> INFO: Test took 1163ms.
[10:00:37.400] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:37.704] <TB1> INFO: Expecting 2560 events.
[10:00:38.561] <TB1> INFO: 2560 events read in total (301ms).
[10:00:38.561] <TB1> INFO: Test took 1161ms.
[10:00:38.564] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:38.867] <TB1> INFO: Expecting 2560 events.
[10:00:39.723] <TB1> INFO: 2560 events read in total (300ms).
[10:00:39.723] <TB1> INFO: Test took 1159ms.
[10:00:39.726] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:40.028] <TB1> INFO: Expecting 2560 events.
[10:00:40.884] <TB1> INFO: 2560 events read in total (299ms).
[10:00:40.885] <TB1> INFO: Test took 1159ms.
[10:00:40.887] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:41.190] <TB1> INFO: Expecting 2560 events.
[10:00:42.045] <TB1> INFO: 2560 events read in total (298ms).
[10:00:42.045] <TB1> INFO: Test took 1159ms.
[10:00:42.047] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:42.352] <TB1> INFO: Expecting 2560 events.
[10:00:43.205] <TB1> INFO: 2560 events read in total (297ms).
[10:00:43.206] <TB1> INFO: Test took 1159ms.
[10:00:43.208] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:43.512] <TB1> INFO: Expecting 2560 events.
[10:00:44.375] <TB1> INFO: 2560 events read in total (307ms).
[10:00:44.376] <TB1> INFO: Test took 1168ms.
[10:00:44.382] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:44.682] <TB1> INFO: Expecting 2560 events.
[10:00:45.537] <TB1> INFO: 2560 events read in total (299ms).
[10:00:45.537] <TB1> INFO: Test took 1155ms.
[10:00:45.540] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:45.842] <TB1> INFO: Expecting 2560 events.
[10:00:46.700] <TB1> INFO: 2560 events read in total (302ms).
[10:00:46.700] <TB1> INFO: Test took 1160ms.
[10:00:46.702] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:47.006] <TB1> INFO: Expecting 2560 events.
[10:00:47.864] <TB1> INFO: 2560 events read in total (302ms).
[10:00:47.864] <TB1> INFO: Test took 1162ms.
[10:00:47.866] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:48.169] <TB1> INFO: Expecting 2560 events.
[10:00:49.024] <TB1> INFO: 2560 events read in total (299ms).
[10:00:49.024] <TB1> INFO: Test took 1158ms.
[10:00:49.026] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:49.331] <TB1> INFO: Expecting 2560 events.
[10:00:50.186] <TB1> INFO: 2560 events read in total (299ms).
[10:00:50.186] <TB1> INFO: Test took 1160ms.
[10:00:50.190] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:50.492] <TB1> INFO: Expecting 2560 events.
[10:00:51.355] <TB1> INFO: 2560 events read in total (307ms).
[10:00:51.355] <TB1> INFO: Test took 1165ms.
[10:00:51.358] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:51.661] <TB1> INFO: Expecting 2560 events.
[10:00:52.526] <TB1> INFO: 2560 events read in total (309ms).
[10:00:52.526] <TB1> INFO: Test took 1168ms.
[10:00:52.529] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:52.831] <TB1> INFO: Expecting 2560 events.
[10:00:53.686] <TB1> INFO: 2560 events read in total (299ms).
[10:00:53.686] <TB1> INFO: Test took 1157ms.
[10:00:53.689] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:53.991] <TB1> INFO: Expecting 2560 events.
[10:00:54.845] <TB1> INFO: 2560 events read in total (298ms).
[10:00:54.845] <TB1> INFO: Test took 1157ms.
[10:00:54.852] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:55.151] <TB1> INFO: Expecting 2560 events.
[10:00:56.005] <TB1> INFO: 2560 events read in total (298ms).
[10:00:56.005] <TB1> INFO: Test took 1154ms.
[10:00:56.007] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:56.310] <TB1> INFO: Expecting 2560 events.
[10:00:57.165] <TB1> INFO: 2560 events read in total (298ms).
[10:00:57.165] <TB1> INFO: Test took 1158ms.
[10:00:57.167] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:57.471] <TB1> INFO: Expecting 2560 events.
[10:00:58.323] <TB1> INFO: 2560 events read in total (296ms).
[10:00:58.323] <TB1> INFO: Test took 1157ms.
[10:00:58.326] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:58.628] <TB1> INFO: Expecting 2560 events.
[10:00:59.481] <TB1> INFO: 2560 events read in total (296ms).
[10:00:59.481] <TB1> INFO: Test took 1155ms.
[10:00:59.484] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:00:59.787] <TB1> INFO: Expecting 2560 events.
[10:01:00.643] <TB1> INFO: 2560 events read in total (299ms).
[10:01:00.643] <TB1> INFO: Test took 1160ms.
[10:01:00.646] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:00.948] <TB1> INFO: Expecting 2560 events.
[10:01:01.804] <TB1> INFO: 2560 events read in total (300ms).
[10:01:01.804] <TB1> INFO: Test took 1159ms.
[10:01:01.806] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:02.111] <TB1> INFO: Expecting 2560 events.
[10:01:02.964] <TB1> INFO: 2560 events read in total (297ms).
[10:01:02.964] <TB1> INFO: Test took 1158ms.
[10:01:02.967] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:03.269] <TB1> INFO: Expecting 2560 events.
[10:01:04.125] <TB1> INFO: 2560 events read in total (300ms).
[10:01:04.125] <TB1> INFO: Test took 1158ms.
[10:01:04.128] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:04.431] <TB1> INFO: Expecting 2560 events.
[10:01:05.292] <TB1> INFO: 2560 events read in total (304ms).
[10:01:05.292] <TB1> INFO: Test took 1164ms.
[10:01:05.294] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:05.597] <TB1> INFO: Expecting 2560 events.
[10:01:06.454] <TB1> INFO: 2560 events read in total (301ms).
[10:01:06.454] <TB1> INFO: Test took 1160ms.
[10:01:06.456] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:06.759] <TB1> INFO: Expecting 2560 events.
[10:01:07.612] <TB1> INFO: 2560 events read in total (297ms).
[10:01:07.612] <TB1> INFO: Test took 1156ms.
[10:01:07.614] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:07.918] <TB1> INFO: Expecting 2560 events.
[10:01:08.774] <TB1> INFO: 2560 events read in total (300ms).
[10:01:08.775] <TB1> INFO: Test took 1161ms.
[10:01:08.778] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:09.079] <TB1> INFO: Expecting 2560 events.
[10:01:09.935] <TB1> INFO: 2560 events read in total (299ms).
[10:01:09.935] <TB1> INFO: Test took 1158ms.
[10:01:09.937] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:01:10.240] <TB1> INFO: Expecting 2560 events.
[10:01:11.092] <TB1> INFO: 2560 events read in total (295ms).
[10:01:11.092] <TB1> INFO: Test took 1155ms.
[10:01:11.578] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 527 seconds
[10:01:11.578] <TB1> INFO: PH scale (per ROC): 80 80 77 79 79 81 83 73 93 80 80 81 91 76 81 92
[10:01:11.578] <TB1> INFO: PH offset (per ROC): 171 156 154 169 167 162 143 176 147 165 145 154 147 149 151 145
[10:01:11.583] <TB1> INFO: Decoding statistics:
[10:01:11.583] <TB1> INFO: General information:
[10:01:11.583] <TB1> INFO: 16bit words read: 86928
[10:01:11.583] <TB1> INFO: valid events total: 10240
[10:01:11.583] <TB1> INFO: empty events: 7736
[10:01:11.583] <TB1> INFO: valid events with pixels: 2504
[10:01:11.583] <TB1> INFO: valid pixel hits: 2504
[10:01:11.583] <TB1> INFO: Event errors: 0
[10:01:11.583] <TB1> INFO: start marker: 0
[10:01:11.583] <TB1> INFO: stop marker: 0
[10:01:11.583] <TB1> INFO: overflow: 0
[10:01:11.583] <TB1> INFO: invalid 5bit words: 0
[10:01:11.583] <TB1> INFO: invalid XOR eye diagram: 0
[10:01:11.583] <TB1> INFO: frame (failed synchr.): 0
[10:01:11.583] <TB1> INFO: idle data (no TBM trl): 0
[10:01:11.583] <TB1> INFO: no data (only TBM hdr): 0
[10:01:11.583] <TB1> INFO: TBM errors: 0
[10:01:11.583] <TB1> INFO: flawed TBM headers: 0
[10:01:11.583] <TB1> INFO: flawed TBM trailers: 0
[10:01:11.583] <TB1> INFO: event ID mismatches: 0
[10:01:11.583] <TB1> INFO: ROC errors: 0
[10:01:11.583] <TB1> INFO: missing ROC header(s): 0
[10:01:11.583] <TB1> INFO: misplaced readback start: 0
[10:01:11.583] <TB1> INFO: Pixel decoding errors: 0
[10:01:11.583] <TB1> INFO: pixel data incomplete: 0
[10:01:11.583] <TB1> INFO: pixel address: 0
[10:01:11.583] <TB1> INFO: pulse height fill bit: 0
[10:01:11.583] <TB1> INFO: buffer corruption: 0
[10:01:11.764] <TB1> INFO: ######################################################################
[10:01:11.764] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:01:11.764] <TB1> INFO: ######################################################################
[10:01:11.773] <TB1> INFO: scanning low vcal = 10
[10:01:12.042] <TB1> INFO: Expecting 41600 events.
[10:01:15.443] <TB1> INFO: 41600 events read in total (2845ms).
[10:01:15.443] <TB1> INFO: Test took 3669ms.
[10:01:15.445] <TB1> INFO: scanning low vcal = 20
[10:01:15.740] <TB1> INFO: Expecting 41600 events.
[10:01:19.146] <TB1> INFO: 41600 events read in total (2850ms).
[10:01:19.146] <TB1> INFO: Test took 3701ms.
[10:01:19.148] <TB1> INFO: scanning low vcal = 30
[10:01:19.440] <TB1> INFO: Expecting 41600 events.
[10:01:22.860] <TB1> INFO: 41600 events read in total (2863ms).
[10:01:22.860] <TB1> INFO: Test took 3712ms.
[10:01:22.862] <TB1> INFO: scanning low vcal = 40
[10:01:23.156] <TB1> INFO: Expecting 41600 events.
[10:01:27.030] <TB1> INFO: 41600 events read in total (3318ms).
[10:01:27.031] <TB1> INFO: Test took 4170ms.
[10:01:27.035] <TB1> INFO: scanning low vcal = 50
[10:01:27.303] <TB1> INFO: Expecting 41600 events.
[10:01:31.260] <TB1> INFO: 41600 events read in total (3401ms).
[10:01:31.260] <TB1> INFO: Test took 4225ms.
[10:01:31.263] <TB1> INFO: scanning low vcal = 60
[10:01:31.552] <TB1> INFO: Expecting 41600 events.
[10:01:35.512] <TB1> INFO: 41600 events read in total (3404ms).
[10:01:35.512] <TB1> INFO: Test took 4249ms.
[10:01:35.515] <TB1> INFO: scanning low vcal = 70
[10:01:35.798] <TB1> INFO: Expecting 41600 events.
[10:01:39.811] <TB1> INFO: 41600 events read in total (3457ms).
[10:01:39.812] <TB1> INFO: Test took 4297ms.
[10:01:39.815] <TB1> INFO: scanning low vcal = 80
[10:01:40.113] <TB1> INFO: Expecting 41600 events.
[10:01:44.150] <TB1> INFO: 41600 events read in total (3481ms).
[10:01:44.151] <TB1> INFO: Test took 4336ms.
[10:01:44.153] <TB1> INFO: scanning low vcal = 90
[10:01:44.473] <TB1> INFO: Expecting 41600 events.
[10:01:48.422] <TB1> INFO: 41600 events read in total (3392ms).
[10:01:48.422] <TB1> INFO: Test took 4268ms.
[10:01:48.425] <TB1> INFO: scanning low vcal = 100
[10:01:48.716] <TB1> INFO: Expecting 41600 events.
[10:01:52.740] <TB1> INFO: 41600 events read in total (3468ms).
[10:01:52.741] <TB1> INFO: Test took 4315ms.
[10:01:52.744] <TB1> INFO: scanning low vcal = 110
[10:01:53.042] <TB1> INFO: Expecting 41600 events.
[10:01:57.011] <TB1> INFO: 41600 events read in total (3413ms).
[10:01:57.012] <TB1> INFO: Test took 4268ms.
[10:01:57.015] <TB1> INFO: scanning low vcal = 120
[10:01:57.276] <TB1> INFO: Expecting 41600 events.
[10:02:01.304] <TB1> INFO: 41600 events read in total (3472ms).
[10:02:01.304] <TB1> INFO: Test took 4289ms.
[10:02:01.307] <TB1> INFO: scanning low vcal = 130
[10:02:01.575] <TB1> INFO: Expecting 41600 events.
[10:02:05.537] <TB1> INFO: 41600 events read in total (3406ms).
[10:02:05.537] <TB1> INFO: Test took 4230ms.
[10:02:05.540] <TB1> INFO: scanning low vcal = 140
[10:02:05.805] <TB1> INFO: Expecting 41600 events.
[10:02:09.780] <TB1> INFO: 41600 events read in total (3418ms).
[10:02:09.781] <TB1> INFO: Test took 4241ms.
[10:02:09.783] <TB1> INFO: scanning low vcal = 150
[10:02:10.049] <TB1> INFO: Expecting 41600 events.
[10:02:14.071] <TB1> INFO: 41600 events read in total (3465ms).
[10:02:14.072] <TB1> INFO: Test took 4288ms.
[10:02:14.075] <TB1> INFO: scanning low vcal = 160
[10:02:14.384] <TB1> INFO: Expecting 41600 events.
[10:02:18.320] <TB1> INFO: 41600 events read in total (3380ms).
[10:02:18.321] <TB1> INFO: Test took 4246ms.
[10:02:18.324] <TB1> INFO: scanning low vcal = 170
[10:02:18.583] <TB1> INFO: Expecting 41600 events.
[10:02:22.548] <TB1> INFO: 41600 events read in total (3408ms).
[10:02:22.549] <TB1> INFO: Test took 4225ms.
[10:02:22.552] <TB1> INFO: scanning low vcal = 180
[10:02:22.837] <TB1> INFO: Expecting 41600 events.
[10:02:26.810] <TB1> INFO: 41600 events read in total (3417ms).
[10:02:26.810] <TB1> INFO: Test took 4258ms.
[10:02:26.813] <TB1> INFO: scanning low vcal = 190
[10:02:27.109] <TB1> INFO: Expecting 41600 events.
[10:02:31.090] <TB1> INFO: 41600 events read in total (3425ms).
[10:02:31.091] <TB1> INFO: Test took 4278ms.
[10:02:31.094] <TB1> INFO: scanning low vcal = 200
[10:02:31.386] <TB1> INFO: Expecting 41600 events.
[10:02:35.362] <TB1> INFO: 41600 events read in total (3420ms).
[10:02:35.362] <TB1> INFO: Test took 4268ms.
[10:02:35.365] <TB1> INFO: scanning low vcal = 210
[10:02:35.624] <TB1> INFO: Expecting 41600 events.
[10:02:39.589] <TB1> INFO: 41600 events read in total (3408ms).
[10:02:39.590] <TB1> INFO: Test took 4225ms.
[10:02:39.593] <TB1> INFO: scanning low vcal = 220
[10:02:39.877] <TB1> INFO: Expecting 41600 events.
[10:02:43.818] <TB1> INFO: 41600 events read in total (3385ms).
[10:02:43.818] <TB1> INFO: Test took 4226ms.
[10:02:43.839] <TB1> INFO: scanning low vcal = 230
[10:02:44.108] <TB1> INFO: Expecting 41600 events.
[10:02:48.064] <TB1> INFO: 41600 events read in total (3399ms).
[10:02:48.064] <TB1> INFO: Test took 4225ms.
[10:02:48.067] <TB1> INFO: scanning low vcal = 240
[10:02:48.366] <TB1> INFO: Expecting 41600 events.
[10:02:52.390] <TB1> INFO: 41600 events read in total (3467ms).
[10:02:52.391] <TB1> INFO: Test took 4324ms.
[10:02:52.394] <TB1> INFO: scanning low vcal = 250
[10:02:52.670] <TB1> INFO: Expecting 41600 events.
[10:02:56.633] <TB1> INFO: 41600 events read in total (3407ms).
[10:02:56.633] <TB1> INFO: Test took 4239ms.
[10:02:56.637] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[10:02:56.929] <TB1> INFO: Expecting 41600 events.
[10:03:00.910] <TB1> INFO: 41600 events read in total (3424ms).
[10:03:00.911] <TB1> INFO: Test took 4274ms.
[10:03:00.914] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[10:03:01.178] <TB1> INFO: Expecting 41600 events.
[10:03:05.132] <TB1> INFO: 41600 events read in total (3398ms).
[10:03:05.133] <TB1> INFO: Test took 4219ms.
[10:03:05.136] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[10:03:05.396] <TB1> INFO: Expecting 41600 events.
[10:03:09.338] <TB1> INFO: 41600 events read in total (3385ms).
[10:03:09.339] <TB1> INFO: Test took 4203ms.
[10:03:09.342] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[10:03:09.616] <TB1> INFO: Expecting 41600 events.
[10:03:13.607] <TB1> INFO: 41600 events read in total (3435ms).
[10:03:13.608] <TB1> INFO: Test took 4266ms.
[10:03:13.611] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:03:13.874] <TB1> INFO: Expecting 41600 events.
[10:03:17.886] <TB1> INFO: 41600 events read in total (3456ms).
[10:03:17.887] <TB1> INFO: Test took 4276ms.
[10:03:18.374] <TB1> INFO: PixTestGainPedestal::measure() done
[10:03:50.035] <TB1> INFO: PixTestGainPedestal::fit() done
[10:03:50.035] <TB1> INFO: non-linearity mean: 0.953 0.957 0.958 0.959 0.958 0.958 0.951 0.961 0.956 0.956 0.960 0.954 0.952 0.960 0.951 0.952
[10:03:50.035] <TB1> INFO: non-linearity RMS: 0.006 0.005 0.005 0.006 0.006 0.006 0.006 0.006 0.006 0.005 0.005 0.007 0.007 0.006 0.007 0.006
[10:03:50.035] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:03:50.054] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:03:50.073] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:03:50.092] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:03:50.111] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:03:50.130] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:03:50.150] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:03:50.168] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:03:50.188] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:03:50.207] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:03:50.226] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:03:50.245] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:03:50.264] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:03:50.282] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:03:50.301] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:03:50.320] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:03:50.339] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[10:03:50.339] <TB1> INFO: Decoding statistics:
[10:03:50.339] <TB1> INFO: General information:
[10:03:50.339] <TB1> INFO: 16bit words read: 2662400
[10:03:50.339] <TB1> INFO: valid events total: 166400
[10:03:50.339] <TB1> INFO: empty events: 0
[10:03:50.339] <TB1> INFO: valid events with pixels: 166400
[10:03:50.339] <TB1> INFO: valid pixel hits: 665600
[10:03:50.339] <TB1> INFO: Event errors: 0
[10:03:50.339] <TB1> INFO: start marker: 0
[10:03:50.339] <TB1> INFO: stop marker: 0
[10:03:50.339] <TB1> INFO: overflow: 0
[10:03:50.339] <TB1> INFO: invalid 5bit words: 0
[10:03:50.339] <TB1> INFO: invalid XOR eye diagram: 0
[10:03:50.339] <TB1> INFO: frame (failed synchr.): 0
[10:03:50.339] <TB1> INFO: idle data (no TBM trl): 0
[10:03:50.339] <TB1> INFO: no data (only TBM hdr): 0
[10:03:50.339] <TB1> INFO: TBM errors: 0
[10:03:50.339] <TB1> INFO: flawed TBM headers: 0
[10:03:50.339] <TB1> INFO: flawed TBM trailers: 0
[10:03:50.339] <TB1> INFO: event ID mismatches: 0
[10:03:50.339] <TB1> INFO: ROC errors: 0
[10:03:50.339] <TB1> INFO: missing ROC header(s): 0
[10:03:50.339] <TB1> INFO: misplaced readback start: 0
[10:03:50.339] <TB1> INFO: Pixel decoding errors: 0
[10:03:50.339] <TB1> INFO: pixel data incomplete: 0
[10:03:50.339] <TB1> INFO: pixel address: 0
[10:03:50.339] <TB1> INFO: pulse height fill bit: 0
[10:03:50.340] <TB1> INFO: buffer corruption: 0
[10:03:50.347] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C15.dat
[10:03:50.348] <TB1> INFO: ######################################################################
[10:03:50.348] <TB1> INFO: PixTestReadback::doTest()
[10:03:50.348] <TB1> INFO: ######################################################################
[10:03:50.348] <TB1> INFO: ----------------------------------------------------------------------
[10:03:50.348] <TB1> INFO: PixTestReadback::CalibrateVd()
[10:03:50.348] <TB1> INFO: ----------------------------------------------------------------------
[10:03:59.863] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C0.dat
[10:03:59.863] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C1.dat
[10:03:59.863] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C2.dat
[10:03:59.863] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C3.dat
[10:03:59.863] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C4.dat
[10:03:59.863] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C5.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C6.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C7.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C8.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C9.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C10.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C11.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C12.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C13.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C14.dat
[10:03:59.864] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C15.dat
[10:03:59.896] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:03:59.896] <TB1> INFO: ----------------------------------------------------------------------
[10:03:59.896] <TB1> INFO: PixTestReadback::CalibrateVa()
[10:03:59.896] <TB1> INFO: ----------------------------------------------------------------------
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C0.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C1.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C2.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C3.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C4.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C5.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C6.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C7.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C8.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C9.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C10.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C11.dat
[10:04:09.423] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C12.dat
[10:04:09.424] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C13.dat
[10:04:09.424] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C14.dat
[10:04:09.424] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C15.dat
[10:04:09.459] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:04:09.459] <TB1> INFO: ----------------------------------------------------------------------
[10:04:09.459] <TB1> INFO: PixTestReadback::readbackVbg()
[10:04:09.459] <TB1> INFO: ----------------------------------------------------------------------
[10:04:16.782] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:04:16.782] <TB1> INFO: ----------------------------------------------------------------------
[10:04:16.782] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[10:04:16.782] <TB1> INFO: ----------------------------------------------------------------------
[10:04:16.783] <TB1> INFO: Vbg will be calibrated using Vd calibration
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.8calibrated Vbg = 1.18262 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 146calibrated Vbg = 1.18582 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157calibrated Vbg = 1.18328 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.4calibrated Vbg = 1.18876 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.6calibrated Vbg = 1.19486 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150calibrated Vbg = 1.19344 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 143.6calibrated Vbg = 1.1935 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 145.1calibrated Vbg = 1.19489 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 143.1calibrated Vbg = 1.1974 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.1calibrated Vbg = 1.20059 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.7calibrated Vbg = 1.19788 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.2calibrated Vbg = 1.19235 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.9calibrated Vbg = 1.18831 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 147.5calibrated Vbg = 1.18992 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 147.1calibrated Vbg = 1.18787 :::*/*/*/*/
[10:04:16.783] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 148.9calibrated Vbg = 1.18848 :::*/*/*/*/
[10:04:16.785] <TB1> INFO: ----------------------------------------------------------------------
[10:04:16.785] <TB1> INFO: PixTestReadback::CalibrateIa()
[10:04:16.785] <TB1> INFO: ----------------------------------------------------------------------
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C0.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C1.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C2.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C3.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C4.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C5.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C6.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C7.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C8.dat
[10:06:52.232] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C9.dat
[10:06:52.233] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C10.dat
[10:06:52.233] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C11.dat
[10:06:52.233] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C12.dat
[10:06:52.233] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C13.dat
[10:06:52.233] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C14.dat
[10:06:52.233] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2335_FullQualification_2016-09-28_10h35m_1475051705//000_FulltestPxar_m20//readbackCal_C15.dat
[10:06:52.263] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[10:06:52.264] <TB1> INFO: PixTestReadback::doTest() done
[10:06:52.264] <TB1> INFO: Decoding statistics:
[10:06:52.264] <TB1> INFO: General information:
[10:06:52.264] <TB1> INFO: 16bit words read: 1024
[10:06:52.264] <TB1> INFO: valid events total: 128
[10:06:52.264] <TB1> INFO: empty events: 128
[10:06:52.264] <TB1> INFO: valid events with pixels: 0
[10:06:52.264] <TB1> INFO: valid pixel hits: 0
[10:06:52.264] <TB1> INFO: Event errors: 0
[10:06:52.265] <TB1> INFO: start marker: 0
[10:06:52.265] <TB1> INFO: stop marker: 0
[10:06:52.265] <TB1> INFO: overflow: 0
[10:06:52.265] <TB1> INFO: invalid 5bit words: 0
[10:06:52.265] <TB1> INFO: invalid XOR eye diagram: 0
[10:06:52.265] <TB1> INFO: frame (failed synchr.): 0
[10:06:52.265] <TB1> INFO: idle data (no TBM trl): 0
[10:06:52.265] <TB1> INFO: no data (only TBM hdr): 0
[10:06:52.265] <TB1> INFO: TBM errors: 0
[10:06:52.265] <TB1> INFO: flawed TBM headers: 0
[10:06:52.265] <TB1> INFO: flawed TBM trailers: 0
[10:06:52.265] <TB1> INFO: event ID mismatches: 0
[10:06:52.265] <TB1> INFO: ROC errors: 0
[10:06:52.265] <TB1> INFO: missing ROC header(s): 0
[10:06:52.265] <TB1> INFO: misplaced readback start: 0
[10:06:52.265] <TB1> INFO: Pixel decoding errors: 0
[10:06:52.265] <TB1> INFO: pixel data incomplete: 0
[10:06:52.265] <TB1> INFO: pixel address: 0
[10:06:52.265] <TB1> INFO: pulse height fill bit: 0
[10:06:52.265] <TB1> INFO: buffer corruption: 0
[10:06:52.286] <TB1> INFO: Decoding statistics:
[10:06:52.286] <TB1> INFO: General information:
[10:06:52.286] <TB1> INFO: 16bit words read: 2750352
[10:06:52.286] <TB1> INFO: valid events total: 176768
[10:06:52.286] <TB1> INFO: empty events: 7864
[10:06:52.286] <TB1> INFO: valid events with pixels: 168904
[10:06:52.286] <TB1> INFO: valid pixel hits: 668104
[10:06:52.286] <TB1> INFO: Event errors: 0
[10:06:52.286] <TB1> INFO: start marker: 0
[10:06:52.286] <TB1> INFO: stop marker: 0
[10:06:52.286] <TB1> INFO: overflow: 0
[10:06:52.286] <TB1> INFO: invalid 5bit words: 0
[10:06:52.286] <TB1> INFO: invalid XOR eye diagram: 0
[10:06:52.286] <TB1> INFO: frame (failed synchr.): 0
[10:06:52.286] <TB1> INFO: idle data (no TBM trl): 0
[10:06:52.286] <TB1> INFO: no data (only TBM hdr): 0
[10:06:52.286] <TB1> INFO: TBM errors: 0
[10:06:52.286] <TB1> INFO: flawed TBM headers: 0
[10:06:52.286] <TB1> INFO: flawed TBM trailers: 0
[10:06:52.286] <TB1> INFO: event ID mismatches: 0
[10:06:52.286] <TB1> INFO: ROC errors: 0
[10:06:52.286] <TB1> INFO: missing ROC header(s): 0
[10:06:52.286] <TB1> INFO: misplaced readback start: 0
[10:06:52.286] <TB1> INFO: Pixel decoding errors: 0
[10:06:52.286] <TB1> INFO: pixel data incomplete: 0
[10:06:52.286] <TB1> INFO: pixel address: 0
[10:06:52.286] <TB1> INFO: pulse height fill bit: 0
[10:06:52.286] <TB1> INFO: buffer corruption: 0
[10:06:52.286] <TB1> INFO: enter test to run
[10:06:52.286] <TB1> INFO: test: exit no parameter change
[10:06:52.432] <TB1> QUIET: Connection to board 153 closed.
[10:06:52.513] <TB1> INFO: pXar: this is the end, my friend