[08:51:57.673] <TB2> INFO: *** Welcome to pxar ***
[08:51:57.673] <TB2> INFO: *** Today: 2015/09/09
[08:51:57.673] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C15.dat
[08:51:57.674] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:51:57.674] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//defaultMaskFile.dat
[08:51:57.675] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters_C15.dat
[08:51:57.750] <TB2> INFO: clk: 4
[08:51:57.750] <TB2> INFO: ctr: 4
[08:51:57.750] <TB2> INFO: sda: 19
[08:51:57.750] <TB2> INFO: tin: 9
[08:51:57.750] <TB2> INFO: level: 15
[08:51:57.750] <TB2> INFO: triggerdelay: 0
[08:51:57.750] <TB2> QUIET: Instanciating API for pxar prod-10+14~g355f077
[08:51:57.750] <TB2> INFO: Log level: INFO
[08:51:57.757] <TB2> INFO: Found DTB DTB_WXC55Z
[08:51:57.765] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[08:51:57.768] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[08:51:57.771] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[08:51:59.298] <TB2> INFO: DUT info:
[08:51:59.298] <TB2> INFO: The DUT currently contains the following objects:
[08:51:59.298] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:51:59.299] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:51:59.299] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:51:59.299] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:51:59.299] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.299] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:51:59.700] <TB2> INFO: enter 'restricted' command line mode
[08:51:59.700] <TB2> INFO: enter test to run
[08:51:59.700] <TB2> INFO: test: pretest no parameter change
[08:51:59.700] <TB2> INFO: running: pretest
[08:51:59.707] <TB2> INFO: ######################################################################
[08:51:59.707] <TB2> INFO: PixTestPretest::doTest()
[08:51:59.707] <TB2> INFO: ######################################################################
[08:51:59.708] <TB2> INFO: ----------------------------------------------------------------------
[08:51:59.708] <TB2> INFO: PixTestPretest::programROC()
[08:51:59.709] <TB2> INFO: ----------------------------------------------------------------------
[08:52:17.727] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:52:17.727] <TB2> INFO: IA differences per ROC: 18.5 20.1 20.1 20.9 18.5 16.9 17.7 19.3 20.1 20.1 20.1 18.5 16.9 19.3 20.1 18.5
[08:52:17.799] <TB2> INFO: ----------------------------------------------------------------------
[08:52:17.799] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:52:17.799] <TB2> INFO: ----------------------------------------------------------------------
[08:52:37.355] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[08:52:37.358] <TB2> INFO: ----------------------------------------------------------------------
[08:52:37.358] <TB2> INFO: PixTestPretest::findWorkingPixel()
[08:52:37.358] <TB2> INFO: ----------------------------------------------------------------------
[08:52:37.492] <TB2> INFO: Expecting 231680 events.
[08:52:46.825] <TB2> INFO: 231680 events read in total (8614ms).
[08:52:46.883] <TB2> INFO: Test took 9523ms.
[08:52:47.132] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:52:47.162] <TB2> INFO: ----------------------------------------------------------------------
[08:52:47.162] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[08:52:47.162] <TB2> INFO: ----------------------------------------------------------------------
[08:52:47.298] <TB2> INFO: Expecting 231680 events.
[08:52:56.807] <TB2> INFO: 231680 events read in total (8792ms).
[08:52:56.812] <TB2> INFO: Test took 9646ms.
[08:52:57.110] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[08:52:57.110] <TB2> INFO: CalDel: 147 159 150 130 143 148 162 152 148 154 129 154 143 140 128 156
[08:52:57.110] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:52:57.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C0.dat
[08:52:57.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C1.dat
[08:52:57.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C2.dat
[08:52:57.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C3.dat
[08:52:57.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C4.dat
[08:52:57.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C5.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C6.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C7.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C8.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C9.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C10.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C11.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C12.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C13.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C14.dat
[08:52:57.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters_C15.dat
[08:52:57.116] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//tbmParameters_C0a.dat
[08:52:57.117] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:52:57.117] <TB2> INFO: PixTestPretest::doTest() done, duration: 57 seconds
[08:52:57.206] <TB2> INFO: enter test to run
[08:52:57.207] <TB2> INFO: test: fulltest no parameter change
[08:52:57.207] <TB2> INFO: running: fulltest
[08:52:57.207] <TB2> INFO: ######################################################################
[08:52:57.207] <TB2> INFO: PixTestFullTest::doTest()
[08:52:57.207] <TB2> INFO: ######################################################################
[08:52:57.208] <TB2> INFO: ######################################################################
[08:52:57.208] <TB2> INFO: PixTestAlive::doTest()
[08:52:57.208] <TB2> INFO: ######################################################################
[08:52:57.210] <TB2> INFO: ----------------------------------------------------------------------
[08:52:57.210] <TB2> 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:52:57.210] <TB2> INFO: ----------------------------------------------------------------------
[08:52:57.519] <TB2> INFO: Expecting 41600 events.
[08:53:02.547] <TB2> INFO: 41600 events read in total (4312ms).
[08:53:02.548] <TB2> INFO: Test took 5337ms.
[08:53:02.555] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:02.841] <TB2> INFO: PixTestAlive::aliveTest() done
[08:53:02.841] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 1 0 0 0 0 0
[08:53:02.842] <TB2> INFO: ----------------------------------------------------------------------
[08:53:02.842] <TB2> 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:53:02.842] <TB2> INFO: ----------------------------------------------------------------------
[08:53:03.152] <TB2> INFO: Expecting 41600 events.
[08:53:06.722] <TB2> INFO: 41600 events read in total (2854ms).
[08:53:06.722] <TB2> INFO: Test took 3878ms.
[08:53:06.722] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:06.723] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:53:07.028] <TB2> INFO: PixTestAlive::maskTest() done
[08:53:07.028] <TB2> 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:53:07.029] <TB2> INFO: ----------------------------------------------------------------------
[08:53:07.029] <TB2> 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:53:07.029] <TB2> INFO: ----------------------------------------------------------------------
[08:53:07.351] <TB2> INFO: Expecting 41600 events.
[08:53:12.397] <TB2> INFO: 41600 events read in total (4329ms).
[08:53:12.397] <TB2> INFO: Test took 5366ms.
[08:53:12.404] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:12.697] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[08:53:12.697] <TB2> 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:53:12.698] <TB2> INFO: PixTestAlive::doTest() done, duration: 15 seconds
[08:53:12.698] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:12.698] <TB2> INFO: Decoding statistics:
[08:53:12.698] <TB2> INFO: General information:
[08:53:12.698] <TB2> INFO: 16bit words read: 0
[08:53:12.698] <TB2> INFO: valid events total: 0
[08:53:12.698] <TB2> INFO: empty events: 0
[08:53:12.698] <TB2> INFO: valid events with pixels: 0
[08:53:12.698] <TB2> INFO: valid pixel hits: 0
[08:53:12.698] <TB2> INFO: Event errors: 0
[08:53:12.698] <TB2> INFO: start marker: 0
[08:53:12.698] <TB2> INFO: stop marker: 0
[08:53:12.698] <TB2> INFO: overflow: 0
[08:53:12.698] <TB2> INFO: invalid 5bit words: 0
[08:53:12.698] <TB2> INFO: invalid XOR eye diagram: 0
[08:53:12.698] <TB2> INFO: TBM errors: 0
[08:53:12.698] <TB2> INFO: flawed TBM headers: 0
[08:53:12.698] <TB2> INFO: flawed TBM trailers: 0
[08:53:12.698] <TB2> INFO: event ID mismatches: 0
[08:53:12.698] <TB2> INFO: ROC errors: 0
[08:53:12.698] <TB2> INFO: missing ROC header(s): 0
[08:53:12.698] <TB2> INFO: misplaced readback start: 0
[08:53:12.698] <TB2> INFO: Pixel decoding errors: 0
[08:53:12.698] <TB2> INFO: pixel data incomplete: 0
[08:53:12.698] <TB2> INFO: pixel address: 0
[08:53:12.698] <TB2> INFO: pulse height fill bit: 0
[08:53:12.698] <TB2> INFO: buffer corruption: 0
[08:53:12.709] <TB2> INFO: ######################################################################
[08:53:12.709] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:53:12.709] <TB2> INFO: ######################################################################
[08:53:12.712] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:53:12.861] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:53:12.861] <TB2> INFO: run 1 of 1
[08:53:13.188] <TB2> INFO: Expecting 3120000 events.
[08:53:57.635] <TB2> INFO: 847290 events read in total (43730ms).
[08:54:42.216] <TB2> INFO: 1688085 events read in total (88311ms).
[08:55:27.103] <TB2> INFO: 2546710 events read in total (133198ms).
[08:55:53.660] <TB2> INFO: 3120000 events read in total (159755ms).
[08:55:53.705] <TB2> INFO: Test took 160844ms.
[08:55:53.814] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:56:17.744] <TB2> INFO: PixTestBBMap::doTest() done, duration: 185 seconds
[08:56:17.744] <TB2> INFO: number of dead bumps (per ROC): 1 0 0 0 0 1 0 0 0 0 0 0 2 0 0 0
[08:56:17.744] <TB2> INFO: separation cut (per ROC): 78 85 67 89 86 73 84 93 87 87 86 87 93 84 83 75
[08:56:17.744] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:56:17.744] <TB2> INFO: Decoding statistics:
[08:56:17.744] <TB2> INFO: General information:
[08:56:17.744] <TB2> INFO: 16bit words read: 0
[08:56:17.744] <TB2> INFO: valid events total: 0
[08:56:17.744] <TB2> INFO: empty events: 0
[08:56:17.744] <TB2> INFO: valid events with pixels: 0
[08:56:17.744] <TB2> INFO: valid pixel hits: 0
[08:56:17.744] <TB2> INFO: Event errors: 0
[08:56:17.744] <TB2> INFO: start marker: 0
[08:56:17.744] <TB2> INFO: stop marker: 0
[08:56:17.744] <TB2> INFO: overflow: 0
[08:56:17.744] <TB2> INFO: invalid 5bit words: 0
[08:56:17.744] <TB2> INFO: invalid XOR eye diagram: 0
[08:56:17.744] <TB2> INFO: TBM errors: 0
[08:56:17.744] <TB2> INFO: flawed TBM headers: 0
[08:56:17.744] <TB2> INFO: flawed TBM trailers: 0
[08:56:17.744] <TB2> INFO: event ID mismatches: 0
[08:56:17.744] <TB2> INFO: ROC errors: 0
[08:56:17.744] <TB2> INFO: missing ROC header(s): 0
[08:56:17.745] <TB2> INFO: misplaced readback start: 0
[08:56:17.745] <TB2> INFO: Pixel decoding errors: 0
[08:56:17.745] <TB2> INFO: pixel data incomplete: 0
[08:56:17.745] <TB2> INFO: pixel address: 0
[08:56:17.745] <TB2> INFO: pulse height fill bit: 0
[08:56:17.745] <TB2> INFO: buffer corruption: 0
[08:56:17.822] <TB2> INFO: ######################################################################
[08:56:17.822] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:56:17.822] <TB2> INFO: ######################################################################
[08:56:17.822] <TB2> INFO: ----------------------------------------------------------------------
[08:56:17.822] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:56:17.822] <TB2> INFO: ----------------------------------------------------------------------
[08:56:17.822] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:56:17.831] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[08:56:17.831] <TB2> INFO: run 1 of 1
[08:56:18.136] <TB2> INFO: Expecting 26208000 events.
[08:56:52.392] <TB2> INFO: 893500 events read in total (33540ms).
[08:57:25.624] <TB2> INFO: 1774550 events read in total (66772ms).
[08:57:58.328] <TB2> INFO: 2652100 events read in total (99476ms).
[08:58:31.725] <TB2> INFO: 3530550 events read in total (132873ms).
[08:59:04.294] <TB2> INFO: 4403650 events read in total (165442ms).
[08:59:37.194] <TB2> INFO: 5277750 events read in total (198342ms).
[09:00:09.997] <TB2> INFO: 6153050 events read in total (231145ms).
[09:00:42.428] <TB2> INFO: 7023500 events read in total (263576ms).
[09:01:15.113] <TB2> INFO: 7893500 events read in total (296261ms).
[09:01:47.599] <TB2> INFO: 8765600 events read in total (328747ms).
[09:02:19.907] <TB2> INFO: 9637700 events read in total (361055ms).
[09:02:52.988] <TB2> INFO: 10505750 events read in total (394136ms).
[09:03:26.102] <TB2> INFO: 11375900 events read in total (427250ms).
[09:03:59.074] <TB2> INFO: 12248500 events read in total (460222ms).
[09:04:31.275] <TB2> INFO: 13115750 events read in total (492423ms).
[09:05:03.703] <TB2> INFO: 13973850 events read in total (524851ms).
[09:05:35.922] <TB2> INFO: 14834250 events read in total (557070ms).
[09:06:08.457] <TB2> INFO: 15694900 events read in total (589605ms).
[09:06:40.774] <TB2> INFO: 16549300 events read in total (621922ms).
[09:07:13.692] <TB2> INFO: 17406150 events read in total (654840ms).
[09:07:47.555] <TB2> INFO: 18264150 events read in total (688703ms).
[09:08:20.892] <TB2> INFO: 19119350 events read in total (722040ms).
[09:08:53.688] <TB2> INFO: 19974250 events read in total (754836ms).
[09:09:25.487] <TB2> INFO: 20829750 events read in total (786635ms).
[09:09:57.789] <TB2> INFO: 21686050 events read in total (818937ms).
[09:10:30.772] <TB2> INFO: 22539300 events read in total (851920ms).
[09:11:02.897] <TB2> INFO: 23396900 events read in total (884045ms).
[09:11:34.532] <TB2> INFO: 24254900 events read in total (915680ms).
[09:12:05.574] <TB2> INFO: 25113200 events read in total (946722ms).
[09:12:38.312] <TB2> INFO: 25982350 events read in total (979460ms).
[09:12:47.645] <TB2> INFO: 26208000 events read in total (988793ms).
[09:12:47.673] <TB2> INFO: Test took 989842ms.
[09:12:47.754] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:47.898] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:12:49.445] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:12:50.972] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:12:52.502] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:12:53.905] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:12:55.276] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:12:56.698] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:12:58.087] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:12:59.456] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:13:01.002] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:13:02.723] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:13:04.188] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:13:05.848] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:13:07.611] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:13:09.296] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:13:11.016] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:13:12.640] <TB2> INFO: PixTestScurves::scurves() done
[09:13:12.640] <TB2> INFO: Vcal mean: 84.33 86.48 73.76 91.18 93.72 78.19 84.47 92.19 95.16 88.41 88.32 87.87 91.66 85.37 90.07 83.81
[09:13:12.640] <TB2> INFO: Vcal RMS: 5.03 5.88 4.56 5.60 5.28 4.93 5.37 5.48 6.40 6.11 6.17 5.70 5.99 5.88 5.37 5.28
[09:13:12.641] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1014 seconds
[09:13:12.641] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:12.641] <TB2> INFO: Decoding statistics:
[09:13:12.641] <TB2> INFO: General information:
[09:13:12.641] <TB2> INFO: 16bit words read: 0
[09:13:12.641] <TB2> INFO: valid events total: 0
[09:13:12.641] <TB2> INFO: empty events: 0
[09:13:12.641] <TB2> INFO: valid events with pixels: 0
[09:13:12.641] <TB2> INFO: valid pixel hits: 0
[09:13:12.641] <TB2> INFO: Event errors: 0
[09:13:12.641] <TB2> INFO: start marker: 0
[09:13:12.641] <TB2> INFO: stop marker: 0
[09:13:12.641] <TB2> INFO: overflow: 0
[09:13:12.641] <TB2> INFO: invalid 5bit words: 0
[09:13:12.641] <TB2> INFO: invalid XOR eye diagram: 0
[09:13:12.641] <TB2> INFO: TBM errors: 0
[09:13:12.641] <TB2> INFO: flawed TBM headers: 0
[09:13:12.641] <TB2> INFO: flawed TBM trailers: 0
[09:13:12.641] <TB2> INFO: event ID mismatches: 0
[09:13:12.641] <TB2> INFO: ROC errors: 0
[09:13:12.641] <TB2> INFO: missing ROC header(s): 0
[09:13:12.641] <TB2> INFO: misplaced readback start: 0
[09:13:12.641] <TB2> INFO: Pixel decoding errors: 0
[09:13:12.641] <TB2> INFO: pixel data incomplete: 0
[09:13:12.641] <TB2> INFO: pixel address: 0
[09:13:12.641] <TB2> INFO: pulse height fill bit: 0
[09:13:12.641] <TB2> INFO: buffer corruption: 0
[09:13:12.712] <TB2> INFO: ######################################################################
[09:13:12.712] <TB2> INFO: PixTestTrim::doTest()
[09:13:12.712] <TB2> INFO: ######################################################################
[09:13:12.713] <TB2> INFO: ----------------------------------------------------------------------
[09:13:12.713] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:13:12.713] <TB2> INFO: ----------------------------------------------------------------------
[09:13:12.792] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:13:12.792] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:13:12.801] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:13:12.801] <TB2> INFO: run 1 of 1
[09:13:13.106] <TB2> INFO: Expecting 6281600 events.
[09:13:56.620] <TB2> INFO: 1080320 events read in total (42797ms).
[09:14:38.130] <TB2> INFO: 2153000 events read in total (84307ms).
[09:15:19.916] <TB2> INFO: 3218830 events read in total (126093ms).
[09:16:00.797] <TB2> INFO: 4292540 events read in total (166974ms).
[09:16:43.321] <TB2> INFO: 5369220 events read in total (209498ms).
[09:17:18.483] <TB2> INFO: 6281600 events read in total (244660ms).
[09:17:18.518] <TB2> INFO: Test took 245717ms.
[09:17:18.572] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:40.343] <TB2> INFO: ROC 0 VthrComp = 90
[09:17:40.343] <TB2> INFO: ROC 1 VthrComp = 90
[09:17:40.349] <TB2> INFO: ROC 2 VthrComp = 78
[09:17:40.349] <TB2> INFO: ROC 3 VthrComp = 94
[09:17:40.349] <TB2> INFO: ROC 4 VthrComp = 95
[09:17:40.349] <TB2> INFO: ROC 5 VthrComp = 79
[09:17:40.349] <TB2> INFO: ROC 6 VthrComp = 86
[09:17:40.349] <TB2> INFO: ROC 7 VthrComp = 95
[09:17:40.349] <TB2> INFO: ROC 8 VthrComp = 97
[09:17:40.350] <TB2> INFO: ROC 9 VthrComp = 90
[09:17:40.350] <TB2> INFO: ROC 10 VthrComp = 90
[09:17:40.351] <TB2> INFO: ROC 11 VthrComp = 91
[09:17:40.351] <TB2> INFO: ROC 12 VthrComp = 89
[09:17:40.351] <TB2> INFO: ROC 13 VthrComp = 88
[09:17:40.351] <TB2> INFO: ROC 14 VthrComp = 94
[09:17:40.351] <TB2> INFO: ROC 15 VthrComp = 86
[09:17:40.351] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:17:40.351] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:17:40.361] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:17:40.361] <TB2> INFO: run 1 of 1
[09:17:40.684] <TB2> INFO: Expecting 6281600 events.
[09:18:18.264] <TB2> INFO: 759360 events read in total (36864ms).
[09:18:54.569] <TB2> INFO: 1516470 events read in total (73169ms).
[09:19:31.788] <TB2> INFO: 2272550 events read in total (110388ms).
[09:20:08.403] <TB2> INFO: 3030140 events read in total (147003ms).
[09:20:43.425] <TB2> INFO: 3781790 events read in total (182025ms).
[09:21:19.083] <TB2> INFO: 4529590 events read in total (217683ms).
[09:21:56.437] <TB2> INFO: 5275170 events read in total (255037ms).
[09:22:33.548] <TB2> INFO: 6020360 events read in total (292148ms).
[09:22:46.485] <TB2> INFO: 6281600 events read in total (305085ms).
[09:22:46.526] <TB2> INFO: Test took 306165ms.
[09:22:46.664] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:11.329] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 57.8993 for pixel 19/4 mean/min/max = 45.4789/33.0035/57.9544
[09:23:11.330] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 60.3413 for pixel 0/76 mean/min/max = 46.4448/32.5218/60.3678
[09:23:11.330] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 58.7175 for pixel 4/0 mean/min/max = 46.5713/34.3863/58.7563
[09:23:11.330] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 59.57 for pixel 24/29 mean/min/max = 45.8046/31.9841/59.6251
[09:23:11.330] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 58.805 for pixel 26/0 mean/min/max = 45.3332/31.8582/58.8082
[09:23:11.331] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 63.0155 for pixel 0/8 mean/min/max = 48.686/34.286/63.0859
[09:23:11.331] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 61.0869 for pixel 17/12 mean/min/max = 46.4468/31.7581/61.1354
[09:23:11.331] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 59.9588 for pixel 6/2 mean/min/max = 46.0327/31.9235/60.142
[09:23:11.342] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 61.9854 for pixel 3/1 mean/min/max = 47.0001/31.9839/62.0162
[09:23:11.342] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 61.3596 for pixel 0/17 mean/min/max = 46.554/31.7045/61.4034
[09:23:11.343] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 62.8716 for pixel 6/5 mean/min/max = 47.3777/31.7877/62.9677
[09:23:11.343] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 60.1339 for pixel 46/79 mean/min/max = 46.241/32.2037/60.2783
[09:23:11.343] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 63.3965 for pixel 14/79 mean/min/max = 47.9412/32.312/63.5704
[09:23:11.344] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 63.5841 for pixel 6/11 mean/min/max = 47.6401/31.6032/63.677
[09:23:11.344] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 57.6862 for pixel 17/71 mean/min/max = 44.6451/31.4471/57.8432
[09:23:11.344] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 59.5262 for pixel 16/74 mean/min/max = 45.7633/31.9644/59.5623
[09:23:11.344] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:23:11.476] <TB2> INFO: Expecting 514560 events.
[09:23:25.645] <TB2> INFO: 514560 events read in total (13453ms).
[09:23:25.651] <TB2> INFO: Expecting 514560 events.
[09:23:39.275] <TB2> INFO: 514560 events read in total (13057ms).
[09:23:39.282] <TB2> INFO: Expecting 514560 events.
[09:23:53.094] <TB2> INFO: 514560 events read in total (13238ms).
[09:23:53.104] <TB2> INFO: Expecting 514560 events.
[09:24:06.530] <TB2> INFO: 514560 events read in total (12861ms).
[09:24:06.542] <TB2> INFO: Expecting 514560 events.
[09:24:20.237] <TB2> INFO: 514560 events read in total (13124ms).
[09:24:20.251] <TB2> INFO: Expecting 514560 events.
[09:24:33.782] <TB2> INFO: 514560 events read in total (12964ms).
[09:24:33.796] <TB2> INFO: Expecting 514560 events.
[09:24:46.985] <TB2> INFO: 514560 events read in total (12619ms).
[09:24:47.005] <TB2> INFO: Expecting 514560 events.
[09:25:00.259] <TB2> INFO: 514560 events read in total (12698ms).
[09:25:00.280] <TB2> INFO: Expecting 514560 events.
[09:25:14.028] <TB2> INFO: 514560 events read in total (13193ms).
[09:25:14.052] <TB2> INFO: Expecting 514560 events.
[09:25:27.355] <TB2> INFO: 514560 events read in total (12752ms).
[09:25:27.383] <TB2> INFO: Expecting 514560 events.
[09:25:41.125] <TB2> INFO: 514560 events read in total (13190ms).
[09:25:41.151] <TB2> INFO: Expecting 514560 events.
[09:25:54.513] <TB2> INFO: 514560 events read in total (12813ms).
[09:25:54.544] <TB2> INFO: Expecting 514560 events.
[09:26:07.957] <TB2> INFO: 514560 events read in total (12872ms).
[09:26:07.985] <TB2> INFO: Expecting 514560 events.
[09:26:21.639] <TB2> INFO: 514560 events read in total (13094ms).
[09:26:21.669] <TB2> INFO: Expecting 514560 events.
[09:26:35.274] <TB2> INFO: 514560 events read in total (13059ms).
[09:26:35.307] <TB2> INFO: Expecting 514560 events.
[09:26:49.175] <TB2> INFO: 514560 events read in total (13318ms).
[09:26:49.210] <TB2> INFO: Test took 217866ms.
[09:26:50.253] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:26:50.261] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:26:50.261] <TB2> INFO: run 1 of 1
[09:26:50.566] <TB2> INFO: Expecting 6281600 events.
[09:27:28.243] <TB2> INFO: 744470 events read in total (36960ms).
[09:28:05.416] <TB2> INFO: 1487730 events read in total (74133ms).
[09:28:42.913] <TB2> INFO: 2229850 events read in total (111630ms).
[09:29:17.953] <TB2> INFO: 2972350 events read in total (146670ms).
[09:29:54.548] <TB2> INFO: 3708250 events read in total (183265ms).
[09:30:31.920] <TB2> INFO: 4439160 events read in total (220637ms).
[09:31:08.468] <TB2> INFO: 5167570 events read in total (257185ms).
[09:31:45.287] <TB2> INFO: 5894520 events read in total (294004ms).
[09:32:05.119] <TB2> INFO: 6281600 events read in total (313836ms).
[09:32:05.176] <TB2> INFO: Test took 314915ms.
[09:32:05.352] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:29.724] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.061368 .. 255.000000
[09:32:29.801] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[09:32:29.809] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:32:29.809] <TB2> INFO: run 1 of 1
[09:32:30.113] <TB2> INFO: Expecting 10649600 events.
[09:33:07.129] <TB2> INFO: 712010 events read in total (36300ms).
[09:33:43.639] <TB2> INFO: 1424330 events read in total (72810ms).
[09:34:19.621] <TB2> INFO: 2136860 events read in total (108792ms).
[09:34:55.929] <TB2> INFO: 2849500 events read in total (145100ms).
[09:35:32.002] <TB2> INFO: 3561770 events read in total (181173ms).
[09:36:08.788] <TB2> INFO: 4274490 events read in total (217959ms).
[09:36:45.836] <TB2> INFO: 4987320 events read in total (255007ms).
[09:37:22.438] <TB2> INFO: 5699780 events read in total (291609ms).
[09:37:57.916] <TB2> INFO: 6412070 events read in total (327087ms).
[09:38:34.714] <TB2> INFO: 7123630 events read in total (363886ms).
[09:39:11.610] <TB2> INFO: 7835010 events read in total (400781ms).
[09:39:46.388] <TB2> INFO: 8545730 events read in total (435559ms).
[09:40:22.730] <TB2> INFO: 9256330 events read in total (471901ms).
[09:40:59.313] <TB2> INFO: 9966800 events read in total (508484ms).
[09:41:35.884] <TB2> INFO: 10649600 events read in total (545055ms).
[09:41:36.021] <TB2> INFO: Test took 546211ms.
[09:41:36.321] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:42:07.597] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 14.641571 .. 46.196216
[09:42:07.674] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 56 (-1/-1) hits flags = 528 (plus default)
[09:42:07.682] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:42:07.682] <TB2> INFO: run 1 of 1
[09:42:07.988] <TB2> INFO: Expecting 2204800 events.
[09:42:47.646] <TB2> INFO: 936480 events read in total (38938ms).
[09:43:28.013] <TB2> INFO: 1871140 events read in total (79305ms).
[09:43:41.639] <TB2> INFO: 2204800 events read in total (92932ms).
[09:43:41.659] <TB2> INFO: Test took 93977ms.
[09:43:41.697] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:57.114] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 11.192748 .. 43.511509
[09:43:57.207] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 53 (-1/-1) hits flags = 528 (plus default)
[09:43:57.217] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:43:57.217] <TB2> INFO: run 1 of 1
[09:43:57.555] <TB2> INFO: Expecting 2204800 events.
[09:44:37.701] <TB2> INFO: 978080 events read in total (39430ms).
[09:45:17.208] <TB2> INFO: 1954580 events read in total (78937ms).
[09:45:28.271] <TB2> INFO: 2204800 events read in total (90000ms).
[09:45:28.286] <TB2> INFO: Test took 91069ms.
[09:45:28.314] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:45:41.911] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 1.025844 .. 43.511509
[09:45:42.007] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 53 (-1/-1) hits flags = 528 (plus default)
[09:45:42.016] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:45:42.016] <TB2> INFO: run 1 of 1
[09:45:42.356] <TB2> INFO: Expecting 2204800 events.
[09:46:21.654] <TB2> INFO: 978140 events read in total (38582ms).
[09:47:01.581] <TB2> INFO: 1955620 events read in total (78509ms).
[09:47:12.121] <TB2> INFO: 2204800 events read in total (89050ms).
[09:47:12.135] <TB2> INFO: Test took 90120ms.
[09:47:12.165] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:47:26.572] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:47:26.572] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:47:26.581] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:47:26.581] <TB2> INFO: run 1 of 1
[09:47:26.896] <TB2> INFO: Expecting 1705600 events.
[09:48:04.202] <TB2> INFO: 879640 events read in total (36590ms).
[09:48:38.553] <TB2> INFO: 1705600 events read in total (70941ms).
[09:48:38.569] <TB2> INFO: Test took 71989ms.
[09:48:38.604] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:53.459] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:48:53.459] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:48:53.460] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:48:53.460] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:48:53.460] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:48:53.460] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:48:53.460] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:48:53.460] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:48:53.460] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:48:53.460] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:48:53.461] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:48:53.461] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:48:53.461] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:48:53.461] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:48:53.461] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:48:53.461] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:48:53.461] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:48:53.468] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:48:53.475] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:48:53.481] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:48:53.487] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:48:53.494] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:48:53.500] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:48:53.506] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:48:53.513] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:48:53.519] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:48:53.525] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:48:53.532] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:48:53.538] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:48:53.544] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:48:53.551] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:48:53.557] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:48:53.564] <TB2> INFO: PixTestTrim::trimTest() done
[09:48:53.564] <TB2> INFO: vtrim: 105 100 110 95 95 99 106 111 108 91 108 96 96 122 95 96
[09:48:53.564] <TB2> INFO: vthrcomp: 90 90 78 94 95 79 86 95 97 90 90 91 89 88 94 86
[09:48:53.564] <TB2> INFO: vcal mean: 34.98 34.93 35.00 34.99 34.94 35.03 34.99 35.01 34.95 34.96 34.98 34.96 34.98 35.01 34.92 34.99
[09:48:53.564] <TB2> INFO: vcal RMS: 0.80 0.83 0.80 0.85 0.82 0.83 0.87 1.03 1.19 1.00 1.02 0.84 0.92 0.87 0.89 0.92
[09:48:53.564] <TB2> INFO: bits mean: 9.50 8.76 8.70 9.50 9.36 8.16 9.44 9.39 9.46 8.99 9.32 8.49 8.39 9.29 9.74 9.53
[09:48:53.564] <TB2> INFO: bits RMS: 2.58 2.88 2.62 2.72 2.81 2.76 2.74 2.74 2.67 2.89 2.70 3.13 3.01 2.70 2.71 2.67
[09:48:53.571] <TB2> INFO: ----------------------------------------------------------------------
[09:48:53.571] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[09:48:53.572] <TB2> INFO: ----------------------------------------------------------------------
[09:48:53.575] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:48:53.588] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:48:53.588] <TB2> INFO: run 1 of 1
[09:48:53.895] <TB2> INFO: Expecting 8320000 events.
[09:49:33.052] <TB2> INFO: 925230 events read in total (38441ms).
[09:50:09.128] <TB2> INFO: 1842760 events read in total (74517ms).
[09:50:48.689] <TB2> INFO: 2757470 events read in total (114078ms).
[09:51:28.015] <TB2> INFO: 3670280 events read in total (153404ms).
[09:52:05.001] <TB2> INFO: 4579090 events read in total (190390ms).
[09:52:43.466] <TB2> INFO: 5483910 events read in total (228855ms).
[09:53:22.079] <TB2> INFO: 6387640 events read in total (267468ms).
[09:54:00.308] <TB2> INFO: 7291340 events read in total (305697ms).
[09:54:35.425] <TB2> INFO: 8199570 events read in total (340814ms).
[09:54:40.575] <TB2> INFO: 8320000 events read in total (345964ms).
[09:54:40.613] <TB2> INFO: Test took 347025ms.
[09:54:40.731] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:09.094] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[09:55:09.102] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:55:09.102] <TB2> INFO: run 1 of 1
[09:55:09.421] <TB2> INFO: Expecting 6905600 events.
[09:55:48.135] <TB2> INFO: 965330 events read in total (37998ms).
[09:56:25.897] <TB2> INFO: 1920580 events read in total (75760ms).
[09:57:02.779] <TB2> INFO: 2872420 events read in total (112642ms).
[09:57:41.204] <TB2> INFO: 3818620 events read in total (151067ms).
[09:58:16.027] <TB2> INFO: 4757550 events read in total (185890ms).
[09:58:52.623] <TB2> INFO: 5695370 events read in total (222486ms).
[09:59:30.799] <TB2> INFO: 6635450 events read in total (260662ms).
[09:59:42.382] <TB2> INFO: 6905600 events read in total (272245ms).
[09:59:42.415] <TB2> INFO: Test took 273312ms.
[09:59:42.495] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:06.070] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[10:00:06.079] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:00:06.079] <TB2> INFO: run 1 of 1
[10:00:06.386] <TB2> INFO: Expecting 6323200 events.
[10:00:47.087] <TB2> INFO: 1006070 events read in total (39985ms).
[10:01:26.639] <TB2> INFO: 2000050 events read in total (79537ms).
[10:02:06.267] <TB2> INFO: 2990570 events read in total (119165ms).
[10:02:43.228] <TB2> INFO: 3969010 events read in total (156126ms).
[10:03:17.882] <TB2> INFO: 4943470 events read in total (190780ms).
[10:03:58.149] <TB2> INFO: 5919040 events read in total (231047ms).
[10:04:13.772] <TB2> INFO: 6323200 events read in total (246670ms).
[10:04:13.802] <TB2> INFO: Test took 247724ms.
[10:04:13.866] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:36.682] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[10:04:36.690] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:04:36.690] <TB2> INFO: run 1 of 1
[10:04:37.010] <TB2> INFO: Expecting 6364800 events.
[10:05:17.680] <TB2> INFO: 1002070 events read in total (39954ms).
[10:05:57.282] <TB2> INFO: 1992330 events read in total (79556ms).
[10:06:36.668] <TB2> INFO: 2978910 events read in total (118942ms).
[10:07:13.798] <TB2> INFO: 3954520 events read in total (156072ms).
[10:07:53.785] <TB2> INFO: 4925060 events read in total (196059ms).
[10:08:32.866] <TB2> INFO: 5896590 events read in total (235140ms).
[10:08:52.569] <TB2> INFO: 6364800 events read in total (254843ms).
[10:08:52.601] <TB2> INFO: Test took 255911ms.
[10:08:52.669] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:09:14.929] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[10:09:14.939] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:09:14.939] <TB2> INFO: run 1 of 1
[10:09:15.276] <TB2> INFO: Expecting 6364800 events.
[10:09:56.949] <TB2> INFO: 1001510 events read in total (40957ms).
[10:10:36.773] <TB2> INFO: 1990950 events read in total (80781ms).
[10:11:17.285] <TB2> INFO: 2976840 events read in total (121295ms).
[10:11:57.537] <TB2> INFO: 3951190 events read in total (161545ms).
[10:12:38.044] <TB2> INFO: 4920920 events read in total (202052ms).
[10:13:12.756] <TB2> INFO: 5891500 events read in total (236765ms).
[10:13:32.477] <TB2> INFO: 6364800 events read in total (256485ms).
[10:13:32.509] <TB2> INFO: Test took 257570ms.
[10:13:32.581] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:56.152] <TB2> INFO: PixTestTrim::trimBitTest() done
[10:13:56.154] <TB2> INFO: PixTestTrim::doTest() done, duration: 3643 seconds
[10:13:56.154] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:56.154] <TB2> INFO: Decoding statistics:
[10:13:56.154] <TB2> INFO: General information:
[10:13:56.154] <TB2> INFO: 16bit words read: 0
[10:13:56.154] <TB2> INFO: valid events total: 0
[10:13:56.154] <TB2> INFO: empty events: 0
[10:13:56.154] <TB2> INFO: valid events with pixels: 0
[10:13:56.154] <TB2> INFO: valid pixel hits: 0
[10:13:56.154] <TB2> INFO: Event errors: 0
[10:13:56.154] <TB2> INFO: start marker: 0
[10:13:56.154] <TB2> INFO: stop marker: 0
[10:13:56.154] <TB2> INFO: overflow: 0
[10:13:56.154] <TB2> INFO: invalid 5bit words: 0
[10:13:56.154] <TB2> INFO: invalid XOR eye diagram: 0
[10:13:56.154] <TB2> INFO: TBM errors: 0
[10:13:56.154] <TB2> INFO: flawed TBM headers: 0
[10:13:56.154] <TB2> INFO: flawed TBM trailers: 0
[10:13:56.154] <TB2> INFO: event ID mismatches: 0
[10:13:56.154] <TB2> INFO: ROC errors: 0
[10:13:56.154] <TB2> INFO: missing ROC header(s): 0
[10:13:56.154] <TB2> INFO: misplaced readback start: 0
[10:13:56.154] <TB2> INFO: Pixel decoding errors: 0
[10:13:56.154] <TB2> INFO: pixel data incomplete: 0
[10:13:56.154] <TB2> INFO: pixel address: 0
[10:13:56.154] <TB2> INFO: pulse height fill bit: 0
[10:13:56.154] <TB2> INFO: buffer corruption: 0
[10:13:56.841] <TB2> INFO: ######################################################################
[10:13:56.841] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:13:56.841] <TB2> INFO: ######################################################################
[10:13:57.149] <TB2> INFO: Expecting 41600 events.
[10:14:02.117] <TB2> INFO: 41600 events read in total (4252ms).
[10:14:02.117] <TB2> INFO: Test took 5274ms.
[10:14:02.123] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:02.715] <TB2> INFO: Expecting 41600 events.
[10:14:07.889] <TB2> INFO: 41600 events read in total (4458ms).
[10:14:07.890] <TB2> INFO: Test took 5504ms.
[10:14:07.896] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:08.250] <TB2> INFO: Expecting 41600 events.
[10:14:13.534] <TB2> INFO: 41600 events read in total (4568ms).
[10:14:13.535] <TB2> INFO: Test took 5610ms.
[10:14:13.542] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:13.877] <TB2> INFO: Expecting 2560 events.
[10:14:14.959] <TB2> INFO: 2560 events read in total (366ms).
[10:14:14.959] <TB2> INFO: Test took 1409ms.
[10:14:15.374] <TB2> INFO: Expecting 2560 events.
[10:14:16.439] <TB2> INFO: 2560 events read in total (349ms).
[10:14:16.440] <TB2> INFO: Test took 1481ms.
[10:14:16.854] <TB2> INFO: Expecting 2560 events.
[10:14:17.922] <TB2> INFO: 2560 events read in total (352ms).
[10:14:17.922] <TB2> INFO: Test took 1481ms.
[10:14:18.337] <TB2> INFO: Expecting 2560 events.
[10:14:19.405] <TB2> INFO: 2560 events read in total (352ms).
[10:14:19.405] <TB2> INFO: Test took 1483ms.
[10:14:19.820] <TB2> INFO: Expecting 2560 events.
[10:14:20.896] <TB2> INFO: 2560 events read in total (361ms).
[10:14:20.897] <TB2> INFO: Test took 1491ms.
[10:14:21.312] <TB2> INFO: Expecting 2560 events.
[10:14:22.396] <TB2> INFO: 2560 events read in total (368ms).
[10:14:22.396] <TB2> INFO: Test took 1499ms.
[10:14:22.810] <TB2> INFO: Expecting 2560 events.
[10:14:23.888] <TB2> INFO: 2560 events read in total (362ms).
[10:14:23.888] <TB2> INFO: Test took 1492ms.
[10:14:24.303] <TB2> INFO: Expecting 2560 events.
[10:14:25.371] <TB2> INFO: 2560 events read in total (352ms).
[10:14:25.371] <TB2> INFO: Test took 1482ms.
[10:14:25.786] <TB2> INFO: Expecting 2560 events.
[10:14:26.848] <TB2> INFO: 2560 events read in total (346ms).
[10:14:26.848] <TB2> INFO: Test took 1477ms.
[10:14:27.263] <TB2> INFO: Expecting 2560 events.
[10:14:28.326] <TB2> INFO: 2560 events read in total (347ms).
[10:14:28.327] <TB2> INFO: Test took 1478ms.
[10:14:28.742] <TB2> INFO: Expecting 2560 events.
[10:14:29.804] <TB2> INFO: 2560 events read in total (346ms).
[10:14:29.805] <TB2> INFO: Test took 1478ms.
[10:14:30.219] <TB2> INFO: Expecting 2560 events.
[10:14:31.288] <TB2> INFO: 2560 events read in total (352ms).
[10:14:31.289] <TB2> INFO: Test took 1484ms.
[10:14:31.703] <TB2> INFO: Expecting 2560 events.
[10:14:32.773] <TB2> INFO: 2560 events read in total (353ms).
[10:14:32.774] <TB2> INFO: Test took 1485ms.
[10:14:33.188] <TB2> INFO: Expecting 2560 events.
[10:14:34.256] <TB2> INFO: 2560 events read in total (352ms).
[10:14:34.256] <TB2> INFO: Test took 1482ms.
[10:14:34.671] <TB2> INFO: Expecting 2560 events.
[10:14:35.741] <TB2> INFO: 2560 events read in total (354ms).
[10:14:35.741] <TB2> INFO: Test took 1484ms.
[10:14:36.156] <TB2> INFO: Expecting 2560 events.
[10:14:37.225] <TB2> INFO: 2560 events read in total (353ms).
[10:14:37.226] <TB2> INFO: Test took 1485ms.
[10:14:37.229] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:37.642] <TB2> INFO: Expecting 655360 events.
[10:14:55.888] <TB2> INFO: 655360 events read in total (17530ms).
[10:14:55.897] <TB2> INFO: Expecting 655360 events.
[10:15:13.088] <TB2> INFO: 655360 events read in total (16663ms).
[10:15:13.100] <TB2> INFO: Expecting 655360 events.
[10:15:31.155] <TB2> INFO: 655360 events read in total (17528ms).
[10:15:31.172] <TB2> INFO: Expecting 655360 events.
[10:15:49.687] <TB2> INFO: 655360 events read in total (17988ms).
[10:15:49.708] <TB2> INFO: Expecting 655360 events.
[10:16:06.813] <TB2> INFO: 655360 events read in total (16577ms).
[10:16:06.834] <TB2> INFO: Expecting 655360 events.
[10:16:25.003] <TB2> INFO: 655360 events read in total (17641ms).
[10:16:25.029] <TB2> INFO: Expecting 655360 events.
[10:16:41.733] <TB2> INFO: 655360 events read in total (16177ms).
[10:16:41.762] <TB2> INFO: Expecting 655360 events.
[10:17:00.296] <TB2> INFO: 655360 events read in total (18007ms).
[10:17:00.329] <TB2> INFO: Expecting 655360 events.
[10:17:16.558] <TB2> INFO: 655360 events read in total (15702ms).
[10:17:16.595] <TB2> INFO: Expecting 655360 events.
[10:17:34.279] <TB2> INFO: 655360 events read in total (17156ms).
[10:17:34.323] <TB2> INFO: Expecting 655360 events.
[10:17:51.539] <TB2> INFO: 655360 events read in total (16689ms).
[10:17:51.583] <TB2> INFO: Expecting 655360 events.
[10:18:07.198] <TB2> INFO: 655360 events read in total (15087ms).
[10:18:07.245] <TB2> INFO: Expecting 655360 events.
[10:18:23.108] <TB2> INFO: 655360 events read in total (15336ms).
[10:18:23.161] <TB2> INFO: Expecting 655360 events.
[10:18:39.573] <TB2> INFO: 655360 events read in total (15884ms).
[10:18:39.627] <TB2> INFO: Expecting 655360 events.
[10:18:56.157] <TB2> INFO: 655360 events read in total (16003ms).
[10:18:56.213] <TB2> INFO: Expecting 655360 events.
[10:19:14.572] <TB2> INFO: 655360 events read in total (17833ms).
[10:19:14.634] <TB2> INFO: Test took 277405ms.
[10:19:14.715] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:19:15.025] <TB2> INFO: Expecting 655360 events.
[10:19:32.474] <TB2> INFO: 655360 events read in total (16733ms).
[10:19:32.483] <TB2> INFO: Expecting 655360 events.
[10:19:49.950] <TB2> INFO: 655360 events read in total (16940ms).
[10:19:49.968] <TB2> INFO: Expecting 655360 events.
[10:20:07.537] <TB2> INFO: 655360 events read in total (17042ms).
[10:20:07.553] <TB2> INFO: Expecting 655360 events.
[10:20:25.689] <TB2> INFO: 655360 events read in total (17609ms).
[10:20:25.708] <TB2> INFO: Expecting 655360 events.
[10:20:43.928] <TB2> INFO: 655360 events read in total (17693ms).
[10:20:43.952] <TB2> INFO: Expecting 655360 events.
[10:21:02.283] <TB2> INFO: 655360 events read in total (17803ms).
[10:21:02.309] <TB2> INFO: Expecting 655360 events.
[10:21:20.946] <TB2> INFO: 655360 events read in total (18110ms).
[10:21:20.974] <TB2> INFO: Expecting 655360 events.
[10:21:39.349] <TB2> INFO: 655360 events read in total (17847ms).
[10:21:39.383] <TB2> INFO: Expecting 655360 events.
[10:21:57.659] <TB2> INFO: 655360 events read in total (17748ms).
[10:21:57.697] <TB2> INFO: Expecting 655360 events.
[10:22:15.962] <TB2> INFO: 655360 events read in total (17737ms).
[10:22:16.001] <TB2> INFO: Expecting 655360 events.
[10:22:34.109] <TB2> INFO: 655360 events read in total (17581ms).
[10:22:34.153] <TB2> INFO: Expecting 655360 events.
[10:22:52.185] <TB2> INFO: 655360 events read in total (17505ms).
[10:22:52.232] <TB2> INFO: Expecting 655360 events.
[10:23:10.780] <TB2> INFO: 655360 events read in total (18020ms).
[10:23:10.828] <TB2> INFO: Expecting 655360 events.
[10:23:29.634] <TB2> INFO: 655360 events read in total (18279ms).
[10:23:29.693] <TB2> INFO: Expecting 655360 events.
[10:23:48.441] <TB2> INFO: 655360 events read in total (18220ms).
[10:23:48.496] <TB2> INFO: Expecting 655360 events.
[10:24:06.663] <TB2> INFO: 655360 events read in total (17639ms).
[10:24:06.726] <TB2> INFO: Test took 292011ms.
[10:24:06.916] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.923] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.930] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.937] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[10:24:06.944] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[10:24:06.950] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[10:24:06.957] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.964] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.971] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.978] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.984] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.991] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:06.998] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:07.005] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:07.012] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:07.018] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:07.025] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:07.032] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:07.039] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:24:07.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:24:07.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:24:07.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:24:07.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:24:07.078] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:24:07.079] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:24:07.079] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:24:07.079] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:24:07.079] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:24:07.079] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:24:07.079] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:24:07.079] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:24:07.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:24:07.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:24:07.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:24:07.080] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:24:07.390] <TB2> INFO: Expecting 41600 events.
[10:24:11.848] <TB2> INFO: 41600 events read in total (3742ms).
[10:24:11.848] <TB2> INFO: Test took 4765ms.
[10:24:12.402] <TB2> INFO: Expecting 41600 events.
[10:24:17.128] <TB2> INFO: 41600 events read in total (4010ms).
[10:24:17.129] <TB2> INFO: Test took 5037ms.
[10:24:17.669] <TB2> INFO: Expecting 41600 events.
[10:24:22.448] <TB2> INFO: 41600 events read in total (4063ms).
[10:24:22.449] <TB2> INFO: Test took 5091ms.
[10:24:22.676] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:22.808] <TB2> INFO: Expecting 2560 events.
[10:24:23.878] <TB2> INFO: 2560 events read in total (354ms).
[10:24:23.879] <TB2> INFO: Test took 1203ms.
[10:24:23.881] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:24.294] <TB2> INFO: Expecting 2560 events.
[10:24:25.391] <TB2> INFO: 2560 events read in total (381ms).
[10:24:25.392] <TB2> INFO: Test took 1512ms.
[10:24:25.394] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:25.807] <TB2> INFO: Expecting 2560 events.
[10:24:26.877] <TB2> INFO: 2560 events read in total (354ms).
[10:24:26.877] <TB2> INFO: Test took 1483ms.
[10:24:26.884] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:27.292] <TB2> INFO: Expecting 2560 events.
[10:24:28.363] <TB2> INFO: 2560 events read in total (354ms).
[10:24:28.363] <TB2> INFO: Test took 1479ms.
[10:24:28.367] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:28.778] <TB2> INFO: Expecting 2560 events.
[10:24:29.847] <TB2> INFO: 2560 events read in total (352ms).
[10:24:29.847] <TB2> INFO: Test took 1480ms.
[10:24:29.850] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:30.263] <TB2> INFO: Expecting 2560 events.
[10:24:31.332] <TB2> INFO: 2560 events read in total (352ms).
[10:24:31.333] <TB2> INFO: Test took 1483ms.
[10:24:31.335] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:31.748] <TB2> INFO: Expecting 2560 events.
[10:24:32.817] <TB2> INFO: 2560 events read in total (353ms).
[10:24:32.817] <TB2> INFO: Test took 1482ms.
[10:24:32.820] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:33.233] <TB2> INFO: Expecting 2560 events.
[10:24:34.331] <TB2> INFO: 2560 events read in total (382ms).
[10:24:34.332] <TB2> INFO: Test took 1513ms.
[10:24:34.334] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:34.747] <TB2> INFO: Expecting 2560 events.
[10:24:35.831] <TB2> INFO: 2560 events read in total (368ms).
[10:24:35.832] <TB2> INFO: Test took 1498ms.
[10:24:35.834] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:36.248] <TB2> INFO: Expecting 2560 events.
[10:24:37.331] <TB2> INFO: 2560 events read in total (367ms).
[10:24:37.332] <TB2> INFO: Test took 1498ms.
[10:24:37.334] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:37.747] <TB2> INFO: Expecting 2560 events.
[10:24:38.832] <TB2> INFO: 2560 events read in total (368ms).
[10:24:38.832] <TB2> INFO: Test took 1498ms.
[10:24:38.834] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:39.248] <TB2> INFO: Expecting 2560 events.
[10:24:40.344] <TB2> INFO: 2560 events read in total (380ms).
[10:24:40.344] <TB2> INFO: Test took 1510ms.
[10:24:40.347] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:40.760] <TB2> INFO: Expecting 2560 events.
[10:24:41.832] <TB2> INFO: 2560 events read in total (356ms).
[10:24:41.833] <TB2> INFO: Test took 1486ms.
[10:24:41.835] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:42.248] <TB2> INFO: Expecting 2560 events.
[10:24:43.316] <TB2> INFO: 2560 events read in total (351ms).
[10:24:43.316] <TB2> INFO: Test took 1481ms.
[10:24:43.318] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:43.732] <TB2> INFO: Expecting 2560 events.
[10:24:44.800] <TB2> INFO: 2560 events read in total (352ms).
[10:24:44.800] <TB2> INFO: Test took 1482ms.
[10:24:44.803] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:45.216] <TB2> INFO: Expecting 2560 events.
[10:24:46.283] <TB2> INFO: 2560 events read in total (351ms).
[10:24:46.283] <TB2> INFO: Test took 1481ms.
[10:24:46.285] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:46.699] <TB2> INFO: Expecting 2560 events.
[10:24:47.767] <TB2> INFO: 2560 events read in total (352ms).
[10:24:47.767] <TB2> INFO: Test took 1482ms.
[10:24:47.769] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:48.182] <TB2> INFO: Expecting 2560 events.
[10:24:49.266] <TB2> INFO: 2560 events read in total (368ms).
[10:24:49.266] <TB2> INFO: Test took 1497ms.
[10:24:49.269] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:49.682] <TB2> INFO: Expecting 2560 events.
[10:24:50.780] <TB2> INFO: 2560 events read in total (382ms).
[10:24:50.780] <TB2> INFO: Test took 1511ms.
[10:24:50.782] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:51.195] <TB2> INFO: Expecting 2560 events.
[10:24:52.278] <TB2> INFO: 2560 events read in total (367ms).
[10:24:52.279] <TB2> INFO: Test took 1497ms.
[10:24:52.281] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:52.694] <TB2> INFO: Expecting 2560 events.
[10:24:53.761] <TB2> INFO: 2560 events read in total (351ms).
[10:24:53.762] <TB2> INFO: Test took 1481ms.
[10:24:53.764] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:54.178] <TB2> INFO: Expecting 2560 events.
[10:24:55.276] <TB2> INFO: 2560 events read in total (382ms).
[10:24:55.276] <TB2> INFO: Test took 1513ms.
[10:24:55.279] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:55.692] <TB2> INFO: Expecting 2560 events.
[10:24:56.760] <TB2> INFO: 2560 events read in total (352ms).
[10:24:56.760] <TB2> INFO: Test took 1481ms.
[10:24:56.763] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:57.176] <TB2> INFO: Expecting 2560 events.
[10:24:58.261] <TB2> INFO: 2560 events read in total (369ms).
[10:24:58.261] <TB2> INFO: Test took 1498ms.
[10:24:58.264] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:58.677] <TB2> INFO: Expecting 2560 events.
[10:24:59.759] <TB2> INFO: 2560 events read in total (366ms).
[10:24:59.759] <TB2> INFO: Test took 1495ms.
[10:24:59.762] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:00.174] <TB2> INFO: Expecting 2560 events.
[10:25:01.242] <TB2> INFO: 2560 events read in total (351ms).
[10:25:01.243] <TB2> INFO: Test took 1482ms.
[10:25:01.245] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:01.658] <TB2> INFO: Expecting 2560 events.
[10:25:02.726] <TB2> INFO: 2560 events read in total (352ms).
[10:25:02.727] <TB2> INFO: Test took 1482ms.
[10:25:02.729] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:03.142] <TB2> INFO: Expecting 2560 events.
[10:25:04.211] <TB2> INFO: 2560 events read in total (353ms).
[10:25:04.212] <TB2> INFO: Test took 1483ms.
[10:25:04.215] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:04.628] <TB2> INFO: Expecting 2560 events.
[10:25:05.696] <TB2> INFO: 2560 events read in total (352ms).
[10:25:05.696] <TB2> INFO: Test took 1481ms.
[10:25:05.699] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:06.112] <TB2> INFO: Expecting 2560 events.
[10:25:07.181] <TB2> INFO: 2560 events read in total (352ms).
[10:25:07.182] <TB2> INFO: Test took 1483ms.
[10:25:07.184] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:07.597] <TB2> INFO: Expecting 2560 events.
[10:25:08.664] <TB2> INFO: 2560 events read in total (351ms).
[10:25:08.665] <TB2> INFO: Test took 1481ms.
[10:25:08.667] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:09.081] <TB2> INFO: Expecting 2560 events.
[10:25:10.158] <TB2> INFO: 2560 events read in total (361ms).
[10:25:10.158] <TB2> INFO: Test took 1491ms.
[10:25:10.772] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 673 seconds
[10:25:10.772] <TB2> INFO: PH scale (per ROC): 78 84 79 82 81 77 77 79 81 91 82 81 77 85 73 80
[10:25:10.772] <TB2> INFO: PH offset (per ROC): 173 157 144 163 151 157 167 156 164 159 161 165 166 156 173 164
[10:25:10.782] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:25:10.782] <TB2> INFO: Decoding statistics:
[10:25:10.782] <TB2> INFO: General information:
[10:25:10.782] <TB2> INFO: 16bit words read: 91916
[10:25:10.782] <TB2> INFO: valid events total: 10240
[10:25:10.782] <TB2> INFO: empty events: 7741
[10:25:10.782] <TB2> INFO: valid events with pixels: 2499
[10:25:10.782] <TB2> INFO: valid pixel hits: 2499
[10:25:10.782] <TB2> INFO: Event errors: 0
[10:25:10.782] <TB2> INFO: start marker: 0
[10:25:10.782] <TB2> INFO: stop marker: 0
[10:25:10.782] <TB2> INFO: overflow: 0
[10:25:10.782] <TB2> INFO: invalid 5bit words: 0
[10:25:10.782] <TB2> INFO: invalid XOR eye diagram: 0
[10:25:10.782] <TB2> INFO: TBM errors: 0
[10:25:10.782] <TB2> INFO: flawed TBM headers: 0
[10:25:10.782] <TB2> INFO: flawed TBM trailers: 0
[10:25:10.782] <TB2> INFO: event ID mismatches: 0
[10:25:10.782] <TB2> INFO: ROC errors: 0
[10:25:10.782] <TB2> INFO: missing ROC header(s): 0
[10:25:10.782] <TB2> INFO: misplaced readback start: 0
[10:25:10.782] <TB2> INFO: Pixel decoding errors: 0
[10:25:10.782] <TB2> INFO: pixel data incomplete: 0
[10:25:10.782] <TB2> INFO: pixel address: 0
[10:25:10.782] <TB2> INFO: pulse height fill bit: 0
[10:25:10.782] <TB2> INFO: buffer corruption: 0
[10:25:10.965] <TB2> INFO: ######################################################################
[10:25:10.965] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:25:10.965] <TB2> INFO: ######################################################################
[10:25:10.976] <TB2> INFO: scanning low vcal = 10
[10:25:11.283] <TB2> INFO: Expecting 41600 events.
[10:25:15.092] <TB2> INFO: 41600 events read in total (3093ms).
[10:25:15.093] <TB2> INFO: Test took 4117ms.
[10:25:15.095] <TB2> INFO: scanning low vcal = 20
[10:25:15.508] <TB2> INFO: Expecting 41600 events.
[10:25:19.147] <TB2> INFO: 41600 events read in total (2923ms).
[10:25:19.147] <TB2> INFO: Test took 4052ms.
[10:25:19.149] <TB2> INFO: scanning low vcal = 30
[10:25:19.562] <TB2> INFO: Expecting 41600 events.
[10:25:23.227] <TB2> INFO: 41600 events read in total (2949ms).
[10:25:23.227] <TB2> INFO: Test took 4078ms.
[10:25:23.230] <TB2> INFO: scanning low vcal = 40
[10:25:23.633] <TB2> INFO: Expecting 41600 events.
[10:25:28.153] <TB2> INFO: 41600 events read in total (3804ms).
[10:25:28.154] <TB2> INFO: Test took 4924ms.
[10:25:28.157] <TB2> INFO: scanning low vcal = 50
[10:25:28.494] <TB2> INFO: Expecting 41600 events.
[10:25:33.002] <TB2> INFO: 41600 events read in total (3792ms).
[10:25:33.002] <TB2> INFO: Test took 4845ms.
[10:25:33.005] <TB2> INFO: scanning low vcal = 60
[10:25:33.359] <TB2> INFO: Expecting 41600 events.
[10:25:37.550] <TB2> INFO: 41600 events read in total (3475ms).
[10:25:37.550] <TB2> INFO: Test took 4545ms.
[10:25:37.553] <TB2> INFO: scanning low vcal = 70
[10:25:37.907] <TB2> INFO: Expecting 41600 events.
[10:25:42.323] <TB2> INFO: 41600 events read in total (3700ms).
[10:25:42.324] <TB2> INFO: Test took 4771ms.
[10:25:42.327] <TB2> INFO: scanning low vcal = 80
[10:25:42.663] <TB2> INFO: Expecting 41600 events.
[10:25:47.139] <TB2> INFO: 41600 events read in total (3759ms).
[10:25:47.140] <TB2> INFO: Test took 4813ms.
[10:25:47.142] <TB2> INFO: scanning low vcal = 90
[10:25:47.493] <TB2> INFO: Expecting 41600 events.
[10:25:51.840] <TB2> INFO: 41600 events read in total (3631ms).
[10:25:51.841] <TB2> INFO: Test took 4699ms.
[10:25:51.843] <TB2> INFO: scanning low vcal = 100
[10:25:52.197] <TB2> INFO: Expecting 41600 events.
[10:25:56.380] <TB2> INFO: 41600 events read in total (3467ms).
[10:25:56.380] <TB2> INFO: Test took 4537ms.
[10:25:56.383] <TB2> INFO: scanning low vcal = 110
[10:25:56.734] <TB2> INFO: Expecting 41600 events.
[10:26:01.207] <TB2> INFO: 41600 events read in total (3757ms).
[10:26:01.208] <TB2> INFO: Test took 4825ms.
[10:26:01.211] <TB2> INFO: scanning low vcal = 120
[10:26:01.565] <TB2> INFO: Expecting 41600 events.
[10:26:05.971] <TB2> INFO: 41600 events read in total (3690ms).
[10:26:05.971] <TB2> INFO: Test took 4760ms.
[10:26:05.974] <TB2> INFO: scanning low vcal = 130
[10:26:06.330] <TB2> INFO: Expecting 41600 events.
[10:26:10.528] <TB2> INFO: 41600 events read in total (3483ms).
[10:26:10.529] <TB2> INFO: Test took 4555ms.
[10:26:10.531] <TB2> INFO: scanning low vcal = 140
[10:26:10.882] <TB2> INFO: Expecting 41600 events.
[10:26:15.189] <TB2> INFO: 41600 events read in total (3591ms).
[10:26:15.190] <TB2> INFO: Test took 4659ms.
[10:26:15.192] <TB2> INFO: scanning low vcal = 150
[10:26:15.523] <TB2> INFO: Expecting 41600 events.
[10:26:20.107] <TB2> INFO: 41600 events read in total (3866ms).
[10:26:20.108] <TB2> INFO: Test took 4916ms.
[10:26:20.111] <TB2> INFO: scanning low vcal = 160
[10:26:20.463] <TB2> INFO: Expecting 41600 events.
[10:26:25.038] <TB2> INFO: 41600 events read in total (3859ms).
[10:26:25.038] <TB2> INFO: Test took 4927ms.
[10:26:25.041] <TB2> INFO: scanning low vcal = 170
[10:26:25.393] <TB2> INFO: Expecting 41600 events.
[10:26:29.585] <TB2> INFO: 41600 events read in total (3476ms).
[10:26:29.586] <TB2> INFO: Test took 4545ms.
[10:26:29.589] <TB2> INFO: scanning low vcal = 180
[10:26:29.940] <TB2> INFO: Expecting 41600 events.
[10:26:34.551] <TB2> INFO: 41600 events read in total (3895ms).
[10:26:34.551] <TB2> INFO: Test took 4962ms.
[10:26:34.554] <TB2> INFO: scanning low vcal = 190
[10:26:34.910] <TB2> INFO: Expecting 41600 events.
[10:26:39.327] <TB2> INFO: 41600 events read in total (3701ms).
[10:26:39.328] <TB2> INFO: Test took 4774ms.
[10:26:39.330] <TB2> INFO: scanning low vcal = 200
[10:26:39.682] <TB2> INFO: Expecting 41600 events.
[10:26:43.970] <TB2> INFO: 41600 events read in total (3572ms).
[10:26:43.970] <TB2> INFO: Test took 4640ms.
[10:26:43.973] <TB2> INFO: scanning low vcal = 210
[10:26:44.328] <TB2> INFO: Expecting 41600 events.
[10:26:48.632] <TB2> INFO: 41600 events read in total (3588ms).
[10:26:48.633] <TB2> INFO: Test took 4660ms.
[10:26:48.635] <TB2> INFO: scanning low vcal = 220
[10:26:48.970] <TB2> INFO: Expecting 41600 events.
[10:26:53.289] <TB2> INFO: 41600 events read in total (3603ms).
[10:26:53.289] <TB2> INFO: Test took 4654ms.
[10:26:53.292] <TB2> INFO: scanning low vcal = 230
[10:26:53.643] <TB2> INFO: Expecting 41600 events.
[10:26:58.130] <TB2> INFO: 41600 events read in total (3770ms).
[10:26:58.131] <TB2> INFO: Test took 4839ms.
[10:26:58.133] <TB2> INFO: scanning low vcal = 240
[10:26:58.490] <TB2> INFO: Expecting 41600 events.
[10:27:02.716] <TB2> INFO: 41600 events read in total (3510ms).
[10:27:02.716] <TB2> INFO: Test took 4583ms.
[10:27:02.719] <TB2> INFO: scanning low vcal = 250
[10:27:03.071] <TB2> INFO: Expecting 41600 events.
[10:27:07.288] <TB2> INFO: 41600 events read in total (3501ms).
[10:27:07.289] <TB2> INFO: Test took 4570ms.
[10:27:07.293] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[10:27:07.647] <TB2> INFO: Expecting 41600 events.
[10:27:12.119] <TB2> INFO: 41600 events read in total (3756ms).
[10:27:12.120] <TB2> INFO: Test took 4827ms.
[10:27:12.122] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[10:27:12.478] <TB2> INFO: Expecting 41600 events.
[10:27:16.909] <TB2> INFO: 41600 events read in total (3715ms).
[10:27:16.909] <TB2> INFO: Test took 4786ms.
[10:27:16.912] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[10:27:17.266] <TB2> INFO: Expecting 41600 events.
[10:27:21.486] <TB2> INFO: 41600 events read in total (3504ms).
[10:27:21.487] <TB2> INFO: Test took 4575ms.
[10:27:21.494] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[10:27:21.844] <TB2> INFO: Expecting 41600 events.
[10:27:26.150] <TB2> INFO: 41600 events read in total (3590ms).
[10:27:26.150] <TB2> INFO: Test took 4655ms.
[10:27:26.153] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:27:26.508] <TB2> INFO: Expecting 41600 events.
[10:27:30.961] <TB2> INFO: 41600 events read in total (3736ms).
[10:27:30.961] <TB2> INFO: Test took 4808ms.
[10:27:31.411] <TB2> INFO: PixTestGainPedestal::measure() done
[10:28:03.783] <TB2> INFO: PixTestGainPedestal::fit() done
[10:28:03.783] <TB2> INFO: non-linearity mean: 0.951 0.958 0.956 0.954 0.963 0.949 0.956 0.961 0.956 0.960 0.957 0.958 0.961 0.958 0.961 0.952
[10:28:03.783] <TB2> INFO: non-linearity RMS: 0.005 0.005 0.005 0.007 0.005 0.006 0.006 0.005 0.004 0.005 0.005 0.007 0.007 0.005 0.005 0.007
[10:28:03.783] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:28:03.802] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:28:03.819] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:28:03.842] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:28:03.861] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:28:03.880] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:28:03.899] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:28:03.919] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:28:03.937] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:28:03.955] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:28:03.973] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:28:03.991] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:28:04.010] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:28:04.028] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:28:04.046] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:28:04.064] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:28:04.081] <TB2> INFO: PixTestGainPedestal::doTest() done, duration: 173 seconds
[10:28:04.081] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:04.082] <TB2> INFO: Decoding statistics:
[10:28:04.082] <TB2> INFO: General information:
[10:28:04.082] <TB2> INFO: 16bit words read: 2662400
[10:28:04.082] <TB2> INFO: valid events total: 166400
[10:28:04.082] <TB2> INFO: empty events: 0
[10:28:04.082] <TB2> INFO: valid events with pixels: 166400
[10:28:04.082] <TB2> INFO: valid pixel hits: 665544
[10:28:04.082] <TB2> INFO: Event errors: 0
[10:28:04.082] <TB2> INFO: start marker: 0
[10:28:04.082] <TB2> INFO: stop marker: 0
[10:28:04.082] <TB2> INFO: overflow: 0
[10:28:04.082] <TB2> INFO: invalid 5bit words: 0
[10:28:04.082] <TB2> INFO: invalid XOR eye diagram: 0
[10:28:04.082] <TB2> INFO: TBM errors: 0
[10:28:04.082] <TB2> INFO: flawed TBM headers: 0
[10:28:04.082] <TB2> INFO: flawed TBM trailers: 0
[10:28:04.082] <TB2> INFO: event ID mismatches: 0
[10:28:04.082] <TB2> INFO: ROC errors: 0
[10:28:04.082] <TB2> INFO: missing ROC header(s): 0
[10:28:04.082] <TB2> INFO: misplaced readback start: 0
[10:28:04.082] <TB2> INFO: Pixel decoding errors: 0
[10:28:04.082] <TB2> INFO: pixel data incomplete: 0
[10:28:04.082] <TB2> INFO: pixel address: 0
[10:28:04.082] <TB2> INFO: pulse height fill bit: 0
[10:28:04.082] <TB2> INFO: buffer corruption: 0
[10:28:04.088] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C15.dat
[10:28:04.089] <TB2> INFO: ######################################################################
[10:28:04.089] <TB2> INFO: PixTestTrim::doTest()
[10:28:04.089] <TB2> INFO: ######################################################################
[10:28:04.090] <TB2> INFO: PixTestReadback::RES sent once
[10:28:25.843] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C0.dat
[10:28:25.843] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C1.dat
[10:28:25.843] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C2.dat
[10:28:25.843] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C3.dat
[10:28:25.843] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C4.dat
[10:28:25.843] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C5.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C6.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C7.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C8.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C9.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C10.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C11.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C12.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C13.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C14.dat
[10:28:25.844] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C15.dat
[10:28:25.865] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:28:25.866] <TB2> INFO: PixTestReadback::RES sent once
[10:28:47.567] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C0.dat
[10:28:47.567] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C1.dat
[10:28:47.567] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C2.dat
[10:28:47.567] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C3.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C4.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C5.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C6.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C7.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C8.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C9.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C10.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C11.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C12.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C13.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C14.dat
[10:28:47.568] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C15.dat
[10:28:47.600] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:28:47.601] <TB2> INFO: PixTestReadback::RES sent once
[10:29:04.457] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:29:04.457] <TB2> INFO: Vbg will be calibrated using Vd calibration
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 160.7calibrated Vbg = 1.19568 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150.7calibrated Vbg = 1.19055 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.9calibrated Vbg = 1.19501 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.7calibrated Vbg = 1.20256 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.4calibrated Vbg = 1.20627 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 159calibrated Vbg = 1.21072 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 158.9calibrated Vbg = 1.20564 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.1calibrated Vbg = 1.20369 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.1calibrated Vbg = 1.19931 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1775 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 163.8calibrated Vbg = 1.19258 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 150.5calibrated Vbg = 1.18984 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.2calibrated Vbg = 1.19514 :::*/*/*/*/
[10:29:04.457] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 248.6calibrated Vbg = 1.11839 :::*/*/*/*/
[10:29:04.460] <TB2> INFO: PixTestReadback::RES sent once
[10:33:44.689] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C0.dat
[10:33:44.690] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C1.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C2.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C3.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C4.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C5.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C6.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C7.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C8.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C9.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C10.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C11.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C12.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C13.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C14.dat
[10:33:44.691] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2111_FullQualification_2015-09-09_10h43m_1441788233//000_FulltestPxar_m20//readbackCal_C15.dat
[10:33:44.720] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:33:44.721] <TB2> INFO: PixTestReadback::doTest() done
[10:33:44.721] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:44.721] <TB2> INFO: Decoding statistics:
[10:33:44.721] <TB2> INFO: General information:
[10:33:44.721] <TB2> INFO: 16bit words read: 1024
[10:33:44.721] <TB2> INFO: valid events total: 128
[10:33:44.721] <TB2> INFO: empty events: 128
[10:33:44.721] <TB2> INFO: valid events with pixels: 0
[10:33:44.721] <TB2> INFO: valid pixel hits: 0
[10:33:44.721] <TB2> INFO: Event errors: 0
[10:33:44.721] <TB2> INFO: start marker: 0
[10:33:44.721] <TB2> INFO: stop marker: 0
[10:33:44.721] <TB2> INFO: overflow: 0
[10:33:44.721] <TB2> INFO: invalid 5bit words: 0
[10:33:44.721] <TB2> INFO: invalid XOR eye diagram: 0
[10:33:44.721] <TB2> INFO: TBM errors: 0
[10:33:44.721] <TB2> INFO: flawed TBM headers: 0
[10:33:44.721] <TB2> INFO: flawed TBM trailers: 0
[10:33:44.721] <TB2> INFO: event ID mismatches: 0
[10:33:44.721] <TB2> INFO: ROC errors: 0
[10:33:44.721] <TB2> INFO: missing ROC header(s): 0
[10:33:44.721] <TB2> INFO: misplaced readback start: 0
[10:33:44.721] <TB2> INFO: Pixel decoding errors: 0
[10:33:44.721] <TB2> INFO: pixel data incomplete: 0
[10:33:44.721] <TB2> INFO: pixel address: 0
[10:33:44.721] <TB2> INFO: pulse height fill bit: 0
[10:33:44.721] <TB2> INFO: buffer corruption: 0
[10:33:44.734] <TB2> INFO: Decoding statistics:
[10:33:44.734] <TB2> INFO: General information:
[10:33:44.734] <TB2> INFO: 16bit words read: 2755340
[10:33:44.734] <TB2> INFO: valid events total: 176768
[10:33:44.734] <TB2> INFO: empty events: 7869
[10:33:44.734] <TB2> INFO: valid events with pixels: 168899
[10:33:44.734] <TB2> INFO: valid pixel hits: 668043
[10:33:44.734] <TB2> INFO: Event errors: 0
[10:33:44.734] <TB2> INFO: start marker: 0
[10:33:44.734] <TB2> INFO: stop marker: 0
[10:33:44.734] <TB2> INFO: overflow: 0
[10:33:44.734] <TB2> INFO: invalid 5bit words: 0
[10:33:44.734] <TB2> INFO: invalid XOR eye diagram: 0
[10:33:44.734] <TB2> INFO: TBM errors: 0
[10:33:44.734] <TB2> INFO: flawed TBM headers: 0
[10:33:44.734] <TB2> INFO: flawed TBM trailers: 0
[10:33:44.734] <TB2> INFO: event ID mismatches: 0
[10:33:44.734] <TB2> INFO: ROC errors: 0
[10:33:44.734] <TB2> INFO: missing ROC header(s): 0
[10:33:44.734] <TB2> INFO: misplaced readback start: 0
[10:33:44.734] <TB2> INFO: Pixel decoding errors: 0
[10:33:44.734] <TB2> INFO: pixel data incomplete: 0
[10:33:44.734] <TB2> INFO: pixel address: 0
[10:33:44.734] <TB2> INFO: pulse height fill bit: 0
[10:33:44.734] <TB2> INFO: buffer corruption: 0
[10:33:44.734] <TB2> INFO: enter test to run
[10:33:44.734] <TB2> INFO: test: exit no parameter change
[10:33:45.334] <TB2> QUIET: Connection to board 156 closed.
[10:33:45.414] <TB2> INFO: pXar: this is the end, my friend