[07:34:36.928] <TB0> INFO: *** Welcome to pxar ***
[07:34:36.928] <TB0> INFO: *** Today: 2015/11/24
[07:34:37.296] <TB0> INFO: *** Version: 9da6-dirty
[07:34:37.296] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C15.dat
[07:34:37.296] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:34:37.296] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//defaultMaskFile.dat
[07:34:37.296] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters_C15.dat
[07:34:37.364] <TB0> INFO: clk: 4
[07:34:37.364] <TB0> INFO: ctr: 4
[07:34:37.364] <TB0> INFO: sda: 19
[07:34:37.364] <TB0> INFO: tin: 9
[07:34:37.364] <TB0> INFO: level: 15
[07:34:37.364] <TB0> INFO: triggerdelay: 0
[07:34:37.364] <TB0> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[07:34:37.364] <TB0> INFO: Log level: INFO
[07:34:37.378] <TB0> QUIET: Connection to board DTB_WZ4BYI opened.
[07:34:37.381] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 169
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WZ4BYI
MAC address: 40D8551180A9
Hostname: pixelDTB169
Comment:
------------------------------------------------------
[07:34:37.384] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[07:34:38.922] <TB0> INFO: DUT info:
[07:34:38.922] <TB0> INFO: The DUT currently contains the following objects:
[07:34:38.922] <TB0> INFO: 2 TBM Cores tbm08c (2 ON)
[07:34:38.922] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:34:38.922] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:34:38.922] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:34:38.922] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.922] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.922] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:38.923] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:39.323] <TB0> INFO: enter 'restricted' command line mode
[07:34:39.323] <TB0> INFO: enter test to run
[07:34:39.323] <TB0> INFO: test: pretest no parameter change
[07:34:39.324] <TB0> INFO: running: pretest
[07:34:39.328] <TB0> INFO: ######################################################################
[07:34:39.328] <TB0> INFO: PixTestPretest::doTest()
[07:34:39.328] <TB0> INFO: ######################################################################
[07:34:39.330] <TB0> INFO: ----------------------------------------------------------------------
[07:34:39.330] <TB0> INFO: PixTestPretest::programROC()
[07:34:39.330] <TB0> INFO: ----------------------------------------------------------------------
[07:34:57.351] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:34:57.351] <TB0> INFO: IA differences per ROC: 16.9 19.3 18.5 16.9 18.5 16.9 17.7 16.1 17.7 18.5 17.7 17.7 16.9 17.7 17.7 16.9
[07:34:57.430] <TB0> INFO: ----------------------------------------------------------------------
[07:34:57.430] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:34:57.430] <TB0> INFO: ----------------------------------------------------------------------
[07:35:03.837] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 394.7 mA = 24.6687 mA/ROC
[07:35:03.837] <TB0> INFO: i(loss) [mA/ROC]: 20.9 20.9 20.1 20.9 20.9 20.1 20.9 20.1 20.1 20.9 20.1 19.3 20.1 20.9 19.3 19.3
[07:35:03.883] <TB0> INFO: ----------------------------------------------------------------------
[07:35:03.883] <TB0> INFO: PixTestPretest::findTiming()
[07:35:03.883] <TB0> INFO: ----------------------------------------------------------------------
[07:35:03.883] <TB0> INFO: PixTestCmd::init()

[07:36:41.015] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[07:36:41.016] <TB0> INFO: (success/tries = 100/100), width = 4
[07:36:41.017] <TB0> INFO: ----------------------------------------------------------------------
[07:36:41.017] <TB0> INFO: PixTestPretest::findWorkingPixel()
[07:36:41.017] <TB0> INFO: ----------------------------------------------------------------------
[07:36:41.155] <TB0> INFO: Expecting 231680 events.


[07:36:48.316] <TB0> INFO: 231680 events read in total (6445ms).
[07:36:48.320] <TB0> INFO: Test took 7301ms.
[07:36:48.720] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:36:48.768] <TB0> INFO: ----------------------------------------------------------------------
[07:36:48.768] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[07:36:48.768] <TB0> INFO: ----------------------------------------------------------------------
[07:36:48.906] <TB0> INFO: Expecting 231680 events.
[07:36:57.021] <TB0> INFO: 231680 events read in total (7400ms).
[07:36:57.024] <TB0> INFO: Test took 8251ms.
[07:36:57.452] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[07:36:57.452] <TB0> INFO: CalDel: 148 131 142 120 136 158 121 142 138 133 133 129 133 146 160 125
[07:36:57.452] <TB0> INFO: VthrComp: 51 51 51 51 51 51 52 51 51 51 51 51 51 51 51 51
[07:36:57.454] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C0.dat
[07:36:57.454] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C1.dat
[07:36:57.454] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C2.dat
[07:36:57.454] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C3.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C4.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C5.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C6.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C7.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C8.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C9.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C10.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C11.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C12.dat
[07:36:57.455] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C13.dat
[07:36:57.456] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C14.dat
[07:36:57.456] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters_C15.dat
[07:36:57.456] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//tbmParameters_C0a.dat
[07:36:57.456] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:36:57.456] <TB0> INFO: PixTestPretest::doTest() done, duration: 138 seconds
[07:36:57.519] <TB0> INFO: enter test to run
[07:36:57.519] <TB0> INFO: test: fulltest no parameter change
[07:36:57.519] <TB0> INFO: running: fulltest
[07:36:57.519] <TB0> INFO: ######################################################################
[07:36:57.519] <TB0> INFO: PixTestFullTest::doTest()
[07:36:57.519] <TB0> INFO: ######################################################################
[07:36:57.521] <TB0> INFO: ######################################################################
[07:36:57.521] <TB0> INFO: PixTestAlive::doTest()
[07:36:57.521] <TB0> INFO: ######################################################################
[07:36:57.523] <TB0> INFO: ----------------------------------------------------------------------
[07:36:57.523] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:36:57.523] <TB0> INFO: ----------------------------------------------------------------------
[07:36:57.826] <TB0> INFO: Expecting 41600 events.
[07:37:01.761] <TB0> INFO: 41600 events read in total (3220ms).
[07:37:01.762] <TB0> INFO: Test took 4237ms.
[07:37:01.768] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:02.172] <TB0> INFO: PixTestAlive::aliveTest() done
[07:37:02.172] <TB0> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[07:37:02.173] <TB0> INFO: ----------------------------------------------------------------------
[07:37:02.173] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:37:02.173] <TB0> INFO: ----------------------------------------------------------------------
[07:37:02.477] <TB0> INFO: Expecting 41600 events.
[07:37:05.372] <TB0> INFO: 41600 events read in total (2180ms).
[07:37:05.373] <TB0> INFO: Test took 3198ms.
[07:37:05.381] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:05.381] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:37:05.795] <TB0> INFO: PixTestAlive::maskTest() done
[07:37:05.795] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:37:05.797] <TB0> INFO: ----------------------------------------------------------------------
[07:37:05.797] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:37:05.797] <TB0> INFO: ----------------------------------------------------------------------
[07:37:06.096] <TB0> INFO: Expecting 41600 events.
[07:37:10.043] <TB0> INFO: 41600 events read in total (3232ms).
[07:37:10.043] <TB0> INFO: Test took 4244ms.
[07:37:10.049] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:10.452] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[07:37:10.452] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:37:10.452] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[07:37:10.452] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:10.453] <TB0> INFO: Decoding statistics:
[07:37:10.453] <TB0> INFO: General information:
[07:37:10.453] <TB0> INFO: 16bit words read: 0
[07:37:10.453] <TB0> INFO: valid events total: 0
[07:37:10.453] <TB0> INFO: empty events: 0
[07:37:10.453] <TB0> INFO: valid events with pixels: 0
[07:37:10.453] <TB0> INFO: valid pixel hits: 0
[07:37:10.453] <TB0> INFO: Event errors: 0
[07:37:10.453] <TB0> INFO: start marker: 0
[07:37:10.453] <TB0> INFO: stop marker: 0
[07:37:10.453] <TB0> INFO: overflow: 0
[07:37:10.453] <TB0> INFO: invalid 5bit words: 0
[07:37:10.453] <TB0> INFO: invalid XOR eye diagram: 0
[07:37:10.453] <TB0> INFO: TBM errors: 0
[07:37:10.453] <TB0> INFO: flawed TBM headers: 0
[07:37:10.453] <TB0> INFO: flawed TBM trailers: 0
[07:37:10.453] <TB0> INFO: event ID mismatches: 0
[07:37:10.453] <TB0> INFO: ROC errors: 0
[07:37:10.453] <TB0> INFO: missing ROC header(s): 0
[07:37:10.453] <TB0> INFO: misplaced readback start: 0
[07:37:10.453] <TB0> INFO: Pixel decoding errors: 0
[07:37:10.453] <TB0> INFO: pixel data incomplete: 0
[07:37:10.453] <TB0> INFO: pixel address: 0
[07:37:10.453] <TB0> INFO: pulse height fill bit: 0
[07:37:10.453] <TB0> INFO: buffer corruption: 0
[07:37:10.459] <TB0> INFO: ######################################################################
[07:37:10.459] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:37:10.459] <TB0> INFO: ######################################################################
[07:37:10.464] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:37:10.474] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[07:37:10.474] <TB0> INFO: run 1 of 1
[07:37:10.770] <TB0> INFO: Expecting 3120000 events.
[07:37:53.865] <TB0> INFO: 1066425 events read in total (42379ms).
[07:38:35.313] <TB0> INFO: 2103765 events read in total (83827ms).
[07:39:15.703] <TB0> INFO: 3120000 events read in total (124218ms).
[07:39:15.756] <TB0> INFO: Test took 125283ms.
[07:39:15.860] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:39:37.809] <TB0> INFO: PixTestBBMap::doTest() done, duration: 147 seconds
[07:39:37.809] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[07:39:37.809] <TB0> INFO: separation cut (per ROC): 112 106 102 115 104 89 111 115 100 123 102 110 92 102 95 112
[07:39:37.809] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:39:37.809] <TB0> INFO: Decoding statistics:
[07:39:37.809] <TB0> INFO: General information:
[07:39:37.809] <TB0> INFO: 16bit words read: 0
[07:39:37.809] <TB0> INFO: valid events total: 0
[07:39:37.809] <TB0> INFO: empty events: 0
[07:39:37.809] <TB0> INFO: valid events with pixels: 0
[07:39:37.809] <TB0> INFO: valid pixel hits: 0
[07:39:37.809] <TB0> INFO: Event errors: 0
[07:39:37.809] <TB0> INFO: start marker: 0
[07:39:37.809] <TB0> INFO: stop marker: 0
[07:39:37.809] <TB0> INFO: overflow: 0
[07:39:37.809] <TB0> INFO: invalid 5bit words: 0
[07:39:37.809] <TB0> INFO: invalid XOR eye diagram: 0
[07:39:37.809] <TB0> INFO: TBM errors: 0
[07:39:37.809] <TB0> INFO: flawed TBM headers: 0
[07:39:37.809] <TB0> INFO: flawed TBM trailers: 0
[07:39:37.809] <TB0> INFO: event ID mismatches: 0
[07:39:37.809] <TB0> INFO: ROC errors: 0
[07:39:37.809] <TB0> INFO: missing ROC header(s): 0
[07:39:37.809] <TB0> INFO: misplaced readback start: 0
[07:39:37.809] <TB0> INFO: Pixel decoding errors: 0
[07:39:37.809] <TB0> INFO: pixel data incomplete: 0
[07:39:37.809] <TB0> INFO: pixel address: 0
[07:39:37.809] <TB0> INFO: pulse height fill bit: 0
[07:39:37.809] <TB0> INFO: buffer corruption: 0
[07:39:37.879] <TB0> INFO: ######################################################################
[07:39:37.879] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:39:37.879] <TB0> INFO: ######################################################################
[07:39:37.879] <TB0> INFO: ----------------------------------------------------------------------
[07:39:37.879] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:39:37.879] <TB0> INFO: ----------------------------------------------------------------------
[07:39:37.879] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:39:37.887] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:39:37.887] <TB0> INFO: run 1 of 1
[07:39:38.190] <TB0> INFO: Expecting 26208000 events.
[07:40:06.677] <TB0> INFO: 1146550 events read in total (27770ms).
[07:40:34.072] <TB0> INFO: 2266000 events read in total (55165ms).
[07:41:01.494] <TB0> INFO: 3381950 events read in total (82587ms).
[07:41:28.918] <TB0> INFO: 4492300 events read in total (110011ms).
[07:41:56.149] <TB0> INFO: 5600100 events read in total (137242ms).
[07:42:23.341] <TB0> INFO: 6707400 events read in total (164434ms).
[07:42:50.532] <TB0> INFO: 7810000 events read in total (191625ms).
[07:43:17.963] <TB0> INFO: 8912650 events read in total (219056ms).
[07:43:45.427] <TB0> INFO: 10015500 events read in total (246520ms).
[07:44:12.780] <TB0> INFO: 11118450 events read in total (273873ms).
[07:44:39.891] <TB0> INFO: 12218050 events read in total (300984ms).
[07:45:07.195] <TB0> INFO: 13309100 events read in total (328288ms).
[07:45:34.390] <TB0> INFO: 14389750 events read in total (355483ms).
[07:46:01.394] <TB0> INFO: 15466800 events read in total (382487ms).
[07:46:28.329] <TB0> INFO: 16540800 events read in total (409422ms).
[07:46:55.452] <TB0> INFO: 17614750 events read in total (436545ms).
[07:47:22.710] <TB0> INFO: 18693400 events read in total (463803ms).
[07:47:49.804] <TB0> INFO: 19761000 events read in total (490897ms).
[07:48:16.877] <TB0> INFO: 20828350 events read in total (517970ms).
[07:48:43.909] <TB0> INFO: 21896250 events read in total (545003ms).
[07:49:10.811] <TB0> INFO: 22962350 events read in total (571904ms).
[07:49:37.630] <TB0> INFO: 24031500 events read in total (598723ms).
[07:50:04.437] <TB0> INFO: 25099000 events read in total (625530ms).
[07:50:31.559] <TB0> INFO: 26191250 events read in total (652652ms).
[07:50:32.364] <TB0> INFO: 26208000 events read in total (653457ms).
[07:50:32.392] <TB0> INFO: Test took 654505ms.
[07:50:32.471] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:50:32.610] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:33.969] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:35.310] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:36.655] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:37.992] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:39.333] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:40.703] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:42.032] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:43.387] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:44.733] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:46.047] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:47.391] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:48.702] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:50.078] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:51.423] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:52.769] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[07:50:54.140] <TB0> INFO: PixTestScurves::scurves() done
[07:50:54.140] <TB0> INFO: Vcal mean: 85.84 95.36 87.38 94.44 90.63 82.83 102.85 89.84 86.25 104.00 89.95 107.13 82.87 89.60 90.10 102.22
[07:50:54.140] <TB0> INFO: Vcal RMS: 6.44 5.91 5.36 6.67 6.04 4.55 6.21 5.96 5.41 6.03 5.57 5.85 4.77 5.32 5.28 5.72
[07:50:54.140] <TB0> INFO: PixTestScurves::fullTest() done, duration: 676 seconds
[07:50:54.140] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:50:54.140] <TB0> INFO: Decoding statistics:
[07:50:54.140] <TB0> INFO: General information:
[07:50:54.140] <TB0> INFO: 16bit words read: 0
[07:50:54.140] <TB0> INFO: valid events total: 0
[07:50:54.140] <TB0> INFO: empty events: 0
[07:50:54.140] <TB0> INFO: valid events with pixels: 0
[07:50:54.140] <TB0> INFO: valid pixel hits: 0
[07:50:54.140] <TB0> INFO: Event errors: 0
[07:50:54.140] <TB0> INFO: start marker: 0
[07:50:54.140] <TB0> INFO: stop marker: 0
[07:50:54.140] <TB0> INFO: overflow: 0
[07:50:54.140] <TB0> INFO: invalid 5bit words: 0
[07:50:54.140] <TB0> INFO: invalid XOR eye diagram: 0
[07:50:54.140] <TB0> INFO: TBM errors: 0
[07:50:54.140] <TB0> INFO: flawed TBM headers: 0
[07:50:54.140] <TB0> INFO: flawed TBM trailers: 0
[07:50:54.140] <TB0> INFO: event ID mismatches: 0
[07:50:54.140] <TB0> INFO: ROC errors: 0
[07:50:54.140] <TB0> INFO: missing ROC header(s): 0
[07:50:54.140] <TB0> INFO: misplaced readback start: 0
[07:50:54.140] <TB0> INFO: Pixel decoding errors: 0
[07:50:54.140] <TB0> INFO: pixel data incomplete: 0
[07:50:54.140] <TB0> INFO: pixel address: 0
[07:50:54.140] <TB0> INFO: pulse height fill bit: 0
[07:50:54.140] <TB0> INFO: buffer corruption: 0
[07:50:54.209] <TB0> INFO: ######################################################################
[07:50:54.209] <TB0> INFO: PixTestTrim::doTest()
[07:50:54.209] <TB0> INFO: ######################################################################
[07:50:54.210] <TB0> INFO: ----------------------------------------------------------------------
[07:50:54.210] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:50:54.210] <TB0> INFO: ----------------------------------------------------------------------
[07:50:54.295] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:50:54.295] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:50:54.302] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[07:50:54.302] <TB0> INFO: run 1 of 1
[07:50:54.605] <TB0> INFO: Expecting 6281600 events.
[07:51:34.195] <TB0> INFO: 1394690 events read in total (38868ms).
[07:52:12.760] <TB0> INFO: 2778020 events read in total (77433ms).
[07:52:51.326] <TB0> INFO: 4165690 events read in total (116000ms).
[07:53:30.188] <TB0> INFO: 5565830 events read in total (154862ms).
[07:53:50.276] <TB0> INFO: 6281600 events read in total (174949ms).
[07:53:50.307] <TB0> INFO: Test took 176005ms.
[07:53:50.357] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:08.567] <TB0> INFO: ROC 0 VthrComp = 90
[07:54:08.568] <TB0> INFO: ROC 1 VthrComp = 98
[07:54:08.568] <TB0> INFO: ROC 2 VthrComp = 93
[07:54:08.568] <TB0> INFO: ROC 3 VthrComp = 96
[07:54:08.568] <TB0> INFO: ROC 4 VthrComp = 97
[07:54:08.568] <TB0> INFO: ROC 5 VthrComp = 89
[07:54:08.568] <TB0> INFO: ROC 6 VthrComp = 103
[07:54:08.569] <TB0> INFO: ROC 7 VthrComp = 89
[07:54:08.569] <TB0> INFO: ROC 8 VthrComp = 92
[07:54:08.569] <TB0> INFO: ROC 9 VthrComp = 105
[07:54:08.569] <TB0> INFO: ROC 10 VthrComp = 96
[07:54:08.569] <TB0> INFO: ROC 11 VthrComp = 104
[07:54:08.569] <TB0> INFO: ROC 12 VthrComp = 87
[07:54:08.569] <TB0> INFO: ROC 13 VthrComp = 97
[07:54:08.570] <TB0> INFO: ROC 14 VthrComp = 94
[07:54:08.570] <TB0> INFO: ROC 15 VthrComp = 100
[07:54:08.570] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:54:08.570] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:54:08.579] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[07:54:08.579] <TB0> INFO: run 1 of 1
[07:54:08.878] <TB0> INFO: Expecting 6281600 events.
[07:54:40.841] <TB0> INFO: 894180 events read in total (31248ms).
[07:55:12.147] <TB0> INFO: 1784180 events read in total (62554ms).
[07:55:43.451] <TB0> INFO: 2674880 events read in total (93858ms).
[07:56:14.505] <TB0> INFO: 3560400 events read in total (124912ms).
[07:56:45.496] <TB0> INFO: 4438160 events read in total (155903ms).
[07:57:16.317] <TB0> INFO: 5310410 events read in total (186724ms).
[07:57:47.367] <TB0> INFO: 6182950 events read in total (217774ms).
[07:57:51.201] <TB0> INFO: 6281600 events read in total (221608ms).
[07:57:51.255] <TB0> INFO: Test took 222676ms.
[07:57:51.393] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:58:13.872] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 62.4158 for pixel 3/15 mean/min/max = 46.6603/30.7327/62.5879
[07:58:13.872] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 60.7067 for pixel 22/15 mean/min/max = 46.0301/31.33/60.7302
[07:58:13.873] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 58.5721 for pixel 12/66 mean/min/max = 45.326/31.9637/58.6882
[07:58:13.873] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 63.8568 for pixel 0/15 mean/min/max = 47.502/30.993/64.0111
[07:58:13.873] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 59.7566 for pixel 19/16 mean/min/max = 45.7731/31.6754/59.8708
[07:58:13.873] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 56.7775 for pixel 29/4 mean/min/max = 44.7686/32.6074/56.9298
[07:58:13.874] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 62.5746 for pixel 4/0 mean/min/max = 47.2306/31.6841/62.7772
[07:58:13.874] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 62.2478 for pixel 7/18 mean/min/max = 47.1226/31.7787/62.4665
[07:58:13.874] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.5987 for pixel 28/79 mean/min/max = 45.7868/32.8771/58.6965
[07:58:13.874] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 64.8607 for pixel 4/0 mean/min/max = 49.1049/33.2391/64.9706
[07:58:13.875] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 58.413 for pixel 5/16 mean/min/max = 45.1044/31.6099/58.5988
[07:58:13.875] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 64.4954 for pixel 21/77 mean/min/max = 48.8079/32.9513/64.6645
[07:58:13.875] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 58.5361 for pixel 5/79 mean/min/max = 45.4801/32.1702/58.7901
[07:58:13.875] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.4727 for pixel 10/79 mean/min/max = 44.7229/31.7356/57.7103
[07:58:13.876] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.2988 for pixel 2/74 mean/min/max = 45.7433/32.184/59.3025
[07:58:13.876] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 60.6229 for pixel 1/76 mean/min/max = 46.3522/32.065/60.6395
[07:58:13.876] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:58:14.009] <TB0> INFO: Expecting 514560 events.
[07:58:23.137] <TB0> INFO: 514560 events read in total (8407ms).
[07:58:23.143] <TB0> INFO: Expecting 514560 events.
[07:58:32.126] <TB0> INFO: 514560 events read in total (8313ms).
[07:58:32.134] <TB0> INFO: Expecting 514560 events.
[07:58:41.103] <TB0> INFO: 514560 events read in total (8304ms).
[07:58:41.113] <TB0> INFO: Expecting 514560 events.
[07:58:50.051] <TB0> INFO: 514560 events read in total (8276ms).
[07:58:50.063] <TB0> INFO: Expecting 514560 events.
[07:58:58.946] <TB0> INFO: 514560 events read in total (8219ms).
[07:58:58.960] <TB0> INFO: Expecting 514560 events.
[07:59:07.804] <TB0> INFO: 514560 events read in total (8181ms).
[07:59:07.820] <TB0> INFO: Expecting 514560 events.
[07:59:16.749] <TB0> INFO: 514560 events read in total (8268ms).
[07:59:16.770] <TB0> INFO: Expecting 514560 events.
[07:59:25.601] <TB0> INFO: 514560 events read in total (8176ms).
[07:59:25.620] <TB0> INFO: Expecting 514560 events.
[07:59:34.523] <TB0> INFO: 514560 events read in total (8243ms).
[07:59:34.547] <TB0> INFO: Expecting 514560 events.
[07:59:43.509] <TB0> INFO: 514560 events read in total (8306ms).
[07:59:43.533] <TB0> INFO: Expecting 514560 events.
[07:59:52.495] <TB0> INFO: 514560 events read in total (8314ms).
[07:59:52.524] <TB0> INFO: Expecting 514560 events.
[08:00:01.445] <TB0> INFO: 514560 events read in total (8276ms).
[08:00:01.474] <TB0> INFO: Expecting 514560 events.
[08:00:10.454] <TB0> INFO: 514560 events read in total (8329ms).
[08:00:10.483] <TB0> INFO: Expecting 514560 events.
[08:00:19.418] <TB0> INFO: 514560 events read in total (8283ms).
[08:00:19.455] <TB0> INFO: Expecting 514560 events.
[08:00:28.452] <TB0> INFO: 514560 events read in total (8356ms).
[08:00:28.484] <TB0> INFO: Expecting 514560 events.
[08:00:37.422] <TB0> INFO: 514560 events read in total (8291ms).
[08:00:37.460] <TB0> INFO: Test took 143584ms.
[08:00:38.536] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:00:38.544] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:00:38.544] <TB0> INFO: run 1 of 1
[08:00:38.844] <TB0> INFO: Expecting 6281600 events.
[08:01:10.462] <TB0> INFO: 873280 events read in total (30902ms).
[08:01:41.185] <TB0> INFO: 1741030 events read in total (61625ms).
[08:02:11.997] <TB0> INFO: 2609010 events read in total (92437ms).
[08:02:42.687] <TB0> INFO: 3471990 events read in total (123127ms).
[08:03:13.378] <TB0> INFO: 4325570 events read in total (153818ms).
[08:03:43.972] <TB0> INFO: 5175920 events read in total (184412ms).
[08:04:14.438] <TB0> INFO: 6025150 events read in total (214878ms).
[08:04:23.847] <TB0> INFO: 6281600 events read in total (224287ms).
[08:04:23.905] <TB0> INFO: Test took 225361ms.
[08:04:24.052] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:45.867] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.074427 .. 255.000000
[08:04:45.944] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[08:04:45.952] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:04:45.952] <TB0> INFO: run 1 of 1
[08:04:46.253] <TB0> INFO: Expecting 10649600 events.
[08:05:16.820] <TB0> INFO: 826390 events read in total (29850ms).
[08:05:46.752] <TB0> INFO: 1653130 events read in total (59782ms).
[08:06:16.764] <TB0> INFO: 2480000 events read in total (89794ms).
[08:06:46.820] <TB0> INFO: 3307300 events read in total (119850ms).
[08:07:16.939] <TB0> INFO: 4134280 events read in total (149969ms).
[08:07:47.034] <TB0> INFO: 4961210 events read in total (180065ms).
[08:08:16.975] <TB0> INFO: 5787910 events read in total (210005ms).
[08:08:46.888] <TB0> INFO: 6613640 events read in total (239918ms).
[08:09:16.880] <TB0> INFO: 7439080 events read in total (269910ms).
[08:09:46.872] <TB0> INFO: 8263150 events read in total (299902ms).
[08:10:16.898] <TB0> INFO: 9085890 events read in total (329928ms).
[08:10:46.963] <TB0> INFO: 9908830 events read in total (359993ms).
[08:11:13.882] <TB0> INFO: 10649600 events read in total (386912ms).
[08:11:13.982] <TB0> INFO: Test took 388030ms.
[08:11:14.266] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:40.898] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 14.829620 .. 45.469018
[08:11:40.976] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:11:40.983] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:11:40.983] <TB0> INFO: run 1 of 1
[08:11:41.284] <TB0> INFO: Expecting 2163200 events.
[08:12:17.799] <TB0> INFO: 1172950 events read in total (35800ms).
[08:12:48.206] <TB0> INFO: 2163200 events read in total (66207ms).
[08:12:48.223] <TB0> INFO: Test took 67240ms.
[08:12:48.254] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:00.787] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 0.351079 .. 41.974200
[08:13:00.865] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 0 .. 51 (-1/-1) hits flags = 528 (plus default)
[08:13:00.873] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:13:00.873] <TB0> INFO: run 1 of 1
[08:13:01.174] <TB0> INFO: Expecting 2163200 events.
[08:13:39.109] <TB0> INFO: 1258860 events read in total (37220ms).
[08:14:06.002] <TB0> INFO: 2163200 events read in total (64113ms).
[08:14:06.013] <TB0> INFO: Test took 65140ms.
[08:14:06.038] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:18.247] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 0.351079 .. 41.974200
[08:14:18.325] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 0 .. 51 (-1/-1) hits flags = 528 (plus default)
[08:14:18.333] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:14:18.333] <TB0> INFO: run 1 of 1
[08:14:18.636] <TB0> INFO: Expecting 2163200 events.
[08:14:56.603] <TB0> INFO: 1259800 events read in total (37252ms).
[08:15:23.346] <TB0> INFO: 2163200 events read in total (63995ms).
[08:15:23.359] <TB0> INFO: Test took 65026ms.
[08:15:23.386] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:35.519] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:15:35.519] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:15:35.527] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:15:35.527] <TB0> INFO: run 1 of 1
[08:15:35.833] <TB0> INFO: Expecting 1705600 events.
[08:16:11.315] <TB0> INFO: 1075870 events read in total (34767ms).
[08:16:31.685] <TB0> INFO: 1705600 events read in total (55137ms).
[08:16:31.696] <TB0> INFO: Test took 56169ms.
[08:16:31.725] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:44.301] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:16:44.301] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:16:44.301] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:16:44.301] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:16:44.301] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:16:44.302] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:16:44.302] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:16:44.302] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:16:44.302] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:16:44.302] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:16:44.302] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:16:44.302] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:16:44.303] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:16:44.303] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:16:44.303] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:16:44.303] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:16:44.303] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:16:44.308] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:16:44.314] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:16:44.319] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:16:44.325] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:16:44.330] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:16:44.335] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:16:44.341] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:16:44.346] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:16:44.352] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:16:44.357] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:16:44.363] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:16:44.368] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:16:44.374] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:16:44.379] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:16:44.385] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:16:44.390] <TB0> INFO: PixTestTrim::trimTest() done
[08:16:44.390] <TB0> INFO: vtrim: 115 117 109 118 111 90 108 105 94 126 105 115 100 96 105 99
[08:16:44.390] <TB0> INFO: vthrcomp: 90 98 93 96 97 89 103 89 92 105 96 104 87 97 94 100
[08:16:44.390] <TB0> INFO: vcal mean: 35.02 34.97 35.00 34.96 35.04 34.94 35.01 34.97 35.01 34.97 34.93 35.03 34.99 34.97 34.96 35.03
[08:16:44.390] <TB0> INFO: vcal RMS: 1.09 0.95 0.83 0.95 0.88 0.83 0.98 0.93 0.99 0.89 1.04 0.94 0.87 0.97 0.88 0.93
[08:16:44.390] <TB0> INFO: bits mean: 9.82 9.92 9.77 9.29 9.43 9.55 9.25 9.37 9.12 8.45 9.72 8.78 9.46 9.53 9.44 9.28
[08:16:44.390] <TB0> INFO: bits RMS: 2.62 2.55 2.58 2.78 2.79 2.64 2.68 2.74 2.71 2.69 2.73 2.61 2.73 2.79 2.66 2.75
[08:16:44.399] <TB0> INFO: ----------------------------------------------------------------------
[08:16:44.399] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[08:16:44.399] <TB0> INFO: ----------------------------------------------------------------------
[08:16:44.403] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:16:44.411] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:16:44.411] <TB0> INFO: run 1 of 1
[08:16:44.710] <TB0> INFO: Expecting 8320000 events.
[08:17:20.555] <TB0> INFO: 1171530 events read in total (35130ms).
[08:17:55.456] <TB0> INFO: 2325920 events read in total (70031ms).
[08:18:30.197] <TB0> INFO: 3477460 events read in total (104772ms).
[08:19:04.852] <TB0> INFO: 4619790 events read in total (139427ms).
[08:19:39.436] <TB0> INFO: 5752580 events read in total (174011ms).
[08:20:13.873] <TB0> INFO: 6882460 events read in total (208448ms).
[08:20:48.242] <TB0> INFO: 8011430 events read in total (242817ms).
[08:20:57.825] <TB0> INFO: 8320000 events read in total (252400ms).
[08:20:57.873] <TB0> INFO: Test took 253462ms.
[08:20:57.976] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:21.994] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[08:21:22.002] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:21:22.002] <TB0> INFO: run 1 of 1
[08:21:22.301] <TB0> INFO: Expecting 7488000 events.
[08:21:58.503] <TB0> INFO: 1184200 events read in total (35487ms).
[08:22:33.666] <TB0> INFO: 2352270 events read in total (70650ms).
[08:23:08.552] <TB0> INFO: 3516020 events read in total (105536ms).
[08:23:43.230] <TB0> INFO: 4664010 events read in total (140214ms).
[08:24:18.037] <TB0> INFO: 5806670 events read in total (175022ms).
[08:24:52.635] <TB0> INFO: 6946190 events read in total (209619ms).
[08:25:09.179] <TB0> INFO: 7488000 events read in total (226163ms).
[08:25:09.215] <TB0> INFO: Test took 227213ms.
[08:25:09.304] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:31.922] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[08:25:31.930] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:25:31.930] <TB0> INFO: run 1 of 1
[08:25:32.231] <TB0> INFO: Expecting 6988800 events.
[08:26:08.866] <TB0> INFO: 1231180 events read in total (35920ms).
[08:26:44.785] <TB0> INFO: 2443920 events read in total (71839ms).
[08:27:20.532] <TB0> INFO: 3648280 events read in total (107586ms).
[08:27:56.019] <TB0> INFO: 4834870 events read in total (143073ms).
[08:28:31.398] <TB0> INFO: 6015800 events read in total (178452ms).
[08:29:00.427] <TB0> INFO: 6988800 events read in total (207481ms).
[08:29:00.458] <TB0> INFO: Test took 208528ms.
[08:29:00.531] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:22.157] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[08:29:22.165] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:29:22.165] <TB0> INFO: run 1 of 1
[08:29:22.466] <TB0> INFO: Expecting 6988800 events.
[08:29:59.217] <TB0> INFO: 1229450 events read in total (36036ms).
[08:30:34.764] <TB0> INFO: 2440650 events read in total (71583ms).
[08:31:10.254] <TB0> INFO: 3643490 events read in total (107073ms).
[08:31:45.525] <TB0> INFO: 4828660 events read in total (142344ms).
[08:32:20.796] <TB0> INFO: 6007890 events read in total (177615ms).
[08:32:50.058] <TB0> INFO: 6988800 events read in total (206877ms).
[08:32:50.090] <TB0> INFO: Test took 207925ms.
[08:32:50.166] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:11.871] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[08:33:11.879] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:33:11.879] <TB0> INFO: run 1 of 1
[08:33:12.182] <TB0> INFO: Expecting 6947200 events.
[08:33:48.989] <TB0> INFO: 1232190 events read in total (36092ms).
[08:34:24.778] <TB0> INFO: 2446090 events read in total (71881ms).
[08:35:00.518] <TB0> INFO: 3650820 events read in total (107622ms).
[08:35:35.904] <TB0> INFO: 4838900 events read in total (143007ms).
[08:36:11.370] <TB0> INFO: 6020040 events read in total (178473ms).
[08:36:39.071] <TB0> INFO: 6947200 events read in total (206174ms).
[08:36:39.113] <TB0> INFO: Test took 207234ms.
[08:36:39.189] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:00.838] <TB0> INFO: PixTestTrim::trimBitTest() done
[08:37:00.839] <TB0> INFO: PixTestTrim::doTest() done, duration: 2766 seconds
[08:37:00.839] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:00.839] <TB0> INFO: Decoding statistics:
[08:37:00.839] <TB0> INFO: General information:
[08:37:00.839] <TB0> INFO: 16bit words read: 0
[08:37:00.839] <TB0> INFO: valid events total: 0
[08:37:00.839] <TB0> INFO: empty events: 0
[08:37:00.839] <TB0> INFO: valid events with pixels: 0
[08:37:00.839] <TB0> INFO: valid pixel hits: 0
[08:37:00.839] <TB0> INFO: Event errors: 0
[08:37:00.839] <TB0> INFO: start marker: 0
[08:37:00.839] <TB0> INFO: stop marker: 0
[08:37:00.839] <TB0> INFO: overflow: 0
[08:37:00.839] <TB0> INFO: invalid 5bit words: 0
[08:37:00.839] <TB0> INFO: invalid XOR eye diagram: 0
[08:37:00.839] <TB0> INFO: TBM errors: 0
[08:37:00.839] <TB0> INFO: flawed TBM headers: 0
[08:37:00.839] <TB0> INFO: flawed TBM trailers: 0
[08:37:00.839] <TB0> INFO: event ID mismatches: 0
[08:37:00.839] <TB0> INFO: ROC errors: 0
[08:37:00.839] <TB0> INFO: missing ROC header(s): 0
[08:37:00.839] <TB0> INFO: misplaced readback start: 0
[08:37:00.839] <TB0> INFO: Pixel decoding errors: 0
[08:37:00.839] <TB0> INFO: pixel data incomplete: 0
[08:37:00.839] <TB0> INFO: pixel address: 0
[08:37:00.839] <TB0> INFO: pulse height fill bit: 0
[08:37:00.839] <TB0> INFO: buffer corruption: 0
[08:37:01.492] <TB0> INFO: ######################################################################
[08:37:01.492] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:37:01.492] <TB0> INFO: ######################################################################
[08:37:01.794] <TB0> INFO: Expecting 41600 events.
[08:37:05.731] <TB0> INFO: 41600 events read in total (3221ms).
[08:37:05.731] <TB0> INFO: Test took 4237ms.
[08:37:05.737] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:06.400] <TB0> INFO: Expecting 41600 events.
[08:37:10.376] <TB0> INFO: 41600 events read in total (3261ms).
[08:37:10.376] <TB0> INFO: Test took 4277ms.
[08:37:10.382] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:10.835] <TB0> INFO: Expecting 41600 events.
[08:37:14.813] <TB0> INFO: 41600 events read in total (3263ms).
[08:37:14.813] <TB0> INFO: Test took 4302ms.
[08:37:14.819] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:15.270] <TB0> INFO: Expecting 2560 events.
[08:37:16.227] <TB0> INFO: 2560 events read in total (242ms).
[08:37:16.227] <TB0> INFO: Test took 1401ms.
[08:37:16.736] <TB0> INFO: Expecting 2560 events.
[08:37:17.693] <TB0> INFO: 2560 events read in total (241ms).
[08:37:17.693] <TB0> INFO: Test took 1465ms.
[08:37:18.202] <TB0> INFO: Expecting 2560 events.
[08:37:19.159] <TB0> INFO: 2560 events read in total (242ms).
[08:37:19.159] <TB0> INFO: Test took 1465ms.
[08:37:19.667] <TB0> INFO: Expecting 2560 events.
[08:37:20.623] <TB0> INFO: 2560 events read in total (241ms).
[08:37:20.624] <TB0> INFO: Test took 1465ms.
[08:37:21.132] <TB0> INFO: Expecting 2560 events.
[08:37:22.089] <TB0> INFO: 2560 events read in total (241ms).
[08:37:22.089] <TB0> INFO: Test took 1465ms.
[08:37:22.598] <TB0> INFO: Expecting 2560 events.
[08:37:23.555] <TB0> INFO: 2560 events read in total (241ms).
[08:37:23.555] <TB0> INFO: Test took 1466ms.
[08:37:24.064] <TB0> INFO: Expecting 2560 events.
[08:37:25.020] <TB0> INFO: 2560 events read in total (241ms).
[08:37:25.021] <TB0> INFO: Test took 1466ms.
[08:37:25.530] <TB0> INFO: Expecting 2560 events.
[08:37:26.486] <TB0> INFO: 2560 events read in total (241ms).
[08:37:26.486] <TB0> INFO: Test took 1465ms.
[08:37:26.995] <TB0> INFO: Expecting 2560 events.
[08:37:27.951] <TB0> INFO: 2560 events read in total (241ms).
[08:37:27.952] <TB0> INFO: Test took 1465ms.
[08:37:28.461] <TB0> INFO: Expecting 2560 events.
[08:37:29.419] <TB0> INFO: 2560 events read in total (242ms).
[08:37:29.419] <TB0> INFO: Test took 1467ms.
[08:37:29.929] <TB0> INFO: Expecting 2560 events.
[08:37:30.887] <TB0> INFO: 2560 events read in total (243ms).
[08:37:30.887] <TB0> INFO: Test took 1467ms.
[08:37:31.396] <TB0> INFO: Expecting 2560 events.
[08:37:32.354] <TB0> INFO: 2560 events read in total (242ms).
[08:37:32.354] <TB0> INFO: Test took 1467ms.
[08:37:32.863] <TB0> INFO: Expecting 2560 events.
[08:37:33.822] <TB0> INFO: 2560 events read in total (243ms).
[08:37:33.822] <TB0> INFO: Test took 1467ms.
[08:37:34.331] <TB0> INFO: Expecting 2560 events.
[08:37:35.289] <TB0> INFO: 2560 events read in total (242ms).
[08:37:35.289] <TB0> INFO: Test took 1459ms.
[08:37:35.799] <TB0> INFO: Expecting 2560 events.
[08:37:36.757] <TB0> INFO: 2560 events read in total (243ms).
[08:37:36.757] <TB0> INFO: Test took 1467ms.
[08:37:37.266] <TB0> INFO: Expecting 2560 events.
[08:37:38.225] <TB0> INFO: 2560 events read in total (243ms).
[08:37:38.225] <TB0> INFO: Test took 1468ms.
[08:37:38.229] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:37:38.736] <TB0> INFO: Expecting 655360 events.
[08:37:50.248] <TB0> INFO: 655360 events read in total (10797ms).
[08:37:50.257] <TB0> INFO: Expecting 655360 events.
[08:38:01.748] <TB0> INFO: 655360 events read in total (10879ms).
[08:38:01.760] <TB0> INFO: Expecting 655360 events.
[08:38:13.132] <TB0> INFO: 655360 events read in total (10766ms).
[08:38:13.148] <TB0> INFO: Expecting 655360 events.
[08:38:24.448] <TB0> INFO: 655360 events read in total (10697ms).
[08:38:24.467] <TB0> INFO: Expecting 655360 events.
[08:38:35.887] <TB0> INFO: 655360 events read in total (10821ms).
[08:38:35.908] <TB0> INFO: Expecting 655360 events.
[08:38:47.381] <TB0> INFO: 655360 events read in total (10875ms).
[08:38:47.406] <TB0> INFO: Expecting 655360 events.
[08:38:58.833] <TB0> INFO: 655360 events read in total (10835ms).
[08:38:58.862] <TB0> INFO: Expecting 655360 events.
[08:39:10.227] <TB0> INFO: 655360 events read in total (10775ms).
[08:39:10.259] <TB0> INFO: Expecting 655360 events.
[08:39:21.840] <TB0> INFO: 655360 events read in total (10993ms).
[08:39:21.874] <TB0> INFO: Expecting 655360 events.
[08:39:33.243] <TB0> INFO: 655360 events read in total (10785ms).
[08:39:33.282] <TB0> INFO: Expecting 655360 events.
[08:39:44.655] <TB0> INFO: 655360 events read in total (10793ms).
[08:39:44.696] <TB0> INFO: Expecting 655360 events.
[08:39:56.025] <TB0> INFO: 655360 events read in total (10750ms).
[08:39:56.069] <TB0> INFO: Expecting 655360 events.
[08:40:07.543] <TB0> INFO: 655360 events read in total (10900ms).
[08:40:07.591] <TB0> INFO: Expecting 655360 events.
[08:40:19.039] <TB0> INFO: 655360 events read in total (10878ms).
[08:40:19.090] <TB0> INFO: Expecting 655360 events.
[08:40:30.478] <TB0> INFO: 655360 events read in total (10820ms).
[08:40:30.533] <TB0> INFO: Expecting 655360 events.
[08:40:41.955] <TB0> INFO: 655360 events read in total (10859ms).
[08:40:42.015] <TB0> INFO: Test took 183786ms.
[08:40:42.094] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:40:42.401] <TB0> INFO: Expecting 655360 events.
[08:40:53.819] <TB0> INFO: 655360 events read in total (10703ms).
[08:40:53.828] <TB0> INFO: Expecting 655360 events.
[08:41:05.268] <TB0> INFO: 655360 events read in total (10827ms).
[08:41:05.279] <TB0> INFO: Expecting 655360 events.
[08:41:16.546] <TB0> INFO: 655360 events read in total (10657ms).
[08:41:16.561] <TB0> INFO: Expecting 655360 events.
[08:41:27.884] <TB0> INFO: 655360 events read in total (10712ms).
[08:41:27.902] <TB0> INFO: Expecting 655360 events.
[08:41:39.197] <TB0> INFO: 655360 events read in total (10693ms).
[08:41:39.218] <TB0> INFO: Expecting 655360 events.
[08:41:50.460] <TB0> INFO: 655360 events read in total (10642ms).
[08:41:50.485] <TB0> INFO: Expecting 655360 events.
[08:42:01.756] <TB0> INFO: 655360 events read in total (10675ms).
[08:42:01.784] <TB0> INFO: Expecting 655360 events.
[08:42:13.060] <TB0> INFO: 655360 events read in total (10685ms).
[08:42:13.091] <TB0> INFO: Expecting 655360 events.
[08:42:24.454] <TB0> INFO: 655360 events read in total (10774ms).
[08:42:24.489] <TB0> INFO: Expecting 655360 events.
[08:42:35.904] <TB0> INFO: 655360 events read in total (10827ms).
[08:42:35.942] <TB0> INFO: Expecting 655360 events.
[08:42:47.366] <TB0> INFO: 655360 events read in total (10839ms).
[08:42:47.408] <TB0> INFO: Expecting 655360 events.
[08:42:58.770] <TB0> INFO: 655360 events read in total (10786ms).
[08:42:58.814] <TB0> INFO: Expecting 655360 events.
[08:43:10.221] <TB0> INFO: 655360 events read in total (10827ms).
[08:43:10.269] <TB0> INFO: Expecting 655360 events.
[08:43:21.757] <TB0> INFO: 655360 events read in total (10915ms).
[08:43:21.808] <TB0> INFO: Expecting 655360 events.
[08:43:33.083] <TB0> INFO: 655360 events read in total (10704ms).
[08:43:33.135] <TB0> INFO: Expecting 655360 events.
[08:43:44.435] <TB0> INFO: 655360 events read in total (10728ms).
[08:43:44.494] <TB0> INFO: Test took 182400ms.
[08:43:44.672] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.679] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.685] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.692] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.698] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.705] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.711] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[08:43:44.717] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[08:43:44.724] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[08:43:44.730] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[08:43:44.737] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[08:43:44.743] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[08:43:44.750] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[08:43:44.756] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[08:43:44.762] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[08:43:44.769] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[08:43:44.775] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.782] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.788] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.795] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.801] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.808] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.814] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.821] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.827] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.834] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:44.889] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:43:44.890] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:43:44.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:43:44.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:43:44.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:43:44.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:43:44.891] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:43:45.194] <TB0> INFO: Expecting 41600 events.
[08:43:48.974] <TB0> INFO: 41600 events read in total (3064ms).
[08:43:48.975] <TB0> INFO: Test took 4080ms.
[08:43:49.618] <TB0> INFO: Expecting 41600 events.
[08:43:53.367] <TB0> INFO: 41600 events read in total (3033ms).
[08:43:53.368] <TB0> INFO: Test took 4045ms.
[08:43:54.010] <TB0> INFO: Expecting 41600 events.
[08:43:57.782] <TB0> INFO: 41600 events read in total (3057ms).
[08:43:57.782] <TB0> INFO: Test took 4067ms.
[08:43:58.129] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:43:58.261] <TB0> INFO: Expecting 2560 events.
[08:43:59.218] <TB0> INFO: 2560 events read in total (242ms).
[08:43:59.218] <TB0> INFO: Test took 1089ms.
[08:43:59.221] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:43:59.728] <TB0> INFO: Expecting 2560 events.
[08:44:00.686] <TB0> INFO: 2560 events read in total (242ms).
[08:44:00.686] <TB0> INFO: Test took 1465ms.
[08:44:00.690] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:01.197] <TB0> INFO: Expecting 2560 events.
[08:44:02.154] <TB0> INFO: 2560 events read in total (242ms).
[08:44:02.155] <TB0> INFO: Test took 1465ms.
[08:44:02.158] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:02.665] <TB0> INFO: Expecting 2560 events.
[08:44:03.623] <TB0> INFO: 2560 events read in total (243ms).
[08:44:03.623] <TB0> INFO: Test took 1465ms.
[08:44:03.626] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:04.133] <TB0> INFO: Expecting 2560 events.
[08:44:05.091] <TB0> INFO: 2560 events read in total (242ms).
[08:44:05.091] <TB0> INFO: Test took 1465ms.
[08:44:05.095] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:05.602] <TB0> INFO: Expecting 2560 events.
[08:44:06.560] <TB0> INFO: 2560 events read in total (243ms).
[08:44:06.560] <TB0> INFO: Test took 1465ms.
[08:44:06.562] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:07.070] <TB0> INFO: Expecting 2560 events.
[08:44:08.028] <TB0> INFO: 2560 events read in total (242ms).
[08:44:08.029] <TB0> INFO: Test took 1467ms.
[08:44:08.031] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:08.539] <TB0> INFO: Expecting 2560 events.
[08:44:09.497] <TB0> INFO: 2560 events read in total (243ms).
[08:44:09.497] <TB0> INFO: Test took 1466ms.
[08:44:09.501] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:10.007] <TB0> INFO: Expecting 2560 events.
[08:44:10.965] <TB0> INFO: 2560 events read in total (242ms).
[08:44:10.966] <TB0> INFO: Test took 1465ms.
[08:44:10.968] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:11.475] <TB0> INFO: Expecting 2560 events.
[08:44:12.433] <TB0> INFO: 2560 events read in total (242ms).
[08:44:12.433] <TB0> INFO: Test took 1465ms.
[08:44:12.435] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:12.942] <TB0> INFO: Expecting 2560 events.
[08:44:13.900] <TB0> INFO: 2560 events read in total (242ms).
[08:44:13.900] <TB0> INFO: Test took 1465ms.
[08:44:13.902] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:14.409] <TB0> INFO: Expecting 2560 events.
[08:44:15.366] <TB0> INFO: 2560 events read in total (242ms).
[08:44:15.367] <TB0> INFO: Test took 1465ms.
[08:44:15.369] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:15.876] <TB0> INFO: Expecting 2560 events.
[08:44:16.833] <TB0> INFO: 2560 events read in total (242ms).
[08:44:16.834] <TB0> INFO: Test took 1465ms.
[08:44:16.836] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:17.343] <TB0> INFO: Expecting 2560 events.
[08:44:18.300] <TB0> INFO: 2560 events read in total (241ms).
[08:44:18.301] <TB0> INFO: Test took 1465ms.
[08:44:18.303] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:18.811] <TB0> INFO: Expecting 2560 events.
[08:44:19.768] <TB0> INFO: 2560 events read in total (242ms).
[08:44:19.768] <TB0> INFO: Test took 1465ms.
[08:44:19.770] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:20.277] <TB0> INFO: Expecting 2560 events.
[08:44:21.234] <TB0> INFO: 2560 events read in total (241ms).
[08:44:21.234] <TB0> INFO: Test took 1464ms.
[08:44:21.237] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:21.744] <TB0> INFO: Expecting 2560 events.
[08:44:22.702] <TB0> INFO: 2560 events read in total (243ms).
[08:44:22.702] <TB0> INFO: Test took 1465ms.
[08:44:22.704] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:23.212] <TB0> INFO: Expecting 2560 events.
[08:44:24.169] <TB0> INFO: 2560 events read in total (242ms).
[08:44:24.169] <TB0> INFO: Test took 1465ms.
[08:44:24.172] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:24.679] <TB0> INFO: Expecting 2560 events.
[08:44:25.637] <TB0> INFO: 2560 events read in total (242ms).
[08:44:25.637] <TB0> INFO: Test took 1465ms.
[08:44:25.640] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:26.148] <TB0> INFO: Expecting 2560 events.
[08:44:27.105] <TB0> INFO: 2560 events read in total (242ms).
[08:44:27.106] <TB0> INFO: Test took 1466ms.
[08:44:27.108] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:27.616] <TB0> INFO: Expecting 2560 events.
[08:44:28.573] <TB0> INFO: 2560 events read in total (242ms).
[08:44:28.574] <TB0> INFO: Test took 1466ms.
[08:44:28.576] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:29.084] <TB0> INFO: Expecting 2560 events.
[08:44:30.042] <TB0> INFO: 2560 events read in total (243ms).
[08:44:30.042] <TB0> INFO: Test took 1466ms.
[08:44:30.044] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:30.552] <TB0> INFO: Expecting 2560 events.
[08:44:31.509] <TB0> INFO: 2560 events read in total (242ms).
[08:44:31.510] <TB0> INFO: Test took 1466ms.
[08:44:31.512] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:32.020] <TB0> INFO: Expecting 2560 events.
[08:44:32.978] <TB0> INFO: 2560 events read in total (243ms).
[08:44:32.986] <TB0> INFO: Test took 1474ms.
[08:44:32.989] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:33.488] <TB0> INFO: Expecting 2560 events.
[08:44:34.446] <TB0> INFO: 2560 events read in total (243ms).
[08:44:34.446] <TB0> INFO: Test took 1457ms.
[08:44:34.449] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:34.956] <TB0> INFO: Expecting 2560 events.
[08:44:35.914] <TB0> INFO: 2560 events read in total (242ms).
[08:44:35.914] <TB0> INFO: Test took 1465ms.
[08:44:35.916] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:36.424] <TB0> INFO: Expecting 2560 events.
[08:44:37.382] <TB0> INFO: 2560 events read in total (242ms).
[08:44:37.382] <TB0> INFO: Test took 1466ms.
[08:44:37.385] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:37.892] <TB0> INFO: Expecting 2560 events.
[08:44:38.850] <TB0> INFO: 2560 events read in total (242ms).
[08:44:38.850] <TB0> INFO: Test took 1466ms.
[08:44:38.860] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:39.360] <TB0> INFO: Expecting 2560 events.
[08:44:40.318] <TB0> INFO: 2560 events read in total (242ms).
[08:44:40.319] <TB0> INFO: Test took 1459ms.
[08:44:40.322] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:40.829] <TB0> INFO: Expecting 2560 events.
[08:44:41.788] <TB0> INFO: 2560 events read in total (244ms).
[08:44:41.788] <TB0> INFO: Test took 1467ms.
[08:44:41.790] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:42.298] <TB0> INFO: Expecting 2560 events.
[08:44:43.256] <TB0> INFO: 2560 events read in total (243ms).
[08:44:43.256] <TB0> INFO: Test took 1466ms.
[08:44:43.259] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:43.767] <TB0> INFO: Expecting 2560 events.
[08:44:44.724] <TB0> INFO: 2560 events read in total (242ms).
[08:44:44.724] <TB0> INFO: Test took 1465ms.
[08:44:45.487] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 463 seconds
[08:44:45.487] <TB0> INFO: PH scale (per ROC): 76 80 89 80 80 84 80 75 86 82 82 80 85 87 77 82
[08:44:45.487] <TB0> INFO: PH offset (per ROC): 151 151 155 158 148 144 156 150 155 175 155 151 166 151 163 175
[08:44:45.491] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:45.491] <TB0> INFO: Decoding statistics:
[08:44:45.491] <TB0> INFO: General information:
[08:44:45.491] <TB0> INFO: 16bit words read: 66440
[08:44:45.491] <TB0> INFO: valid events total: 5120
[08:44:45.491] <TB0> INFO: empty events: 2620
[08:44:45.491] <TB0> INFO: valid events with pixels: 2500
[08:44:45.491] <TB0> INFO: valid pixel hits: 2500
[08:44:45.491] <TB0> INFO: Event errors: 0
[08:44:45.491] <TB0> INFO: start marker: 0
[08:44:45.491] <TB0> INFO: stop marker: 0
[08:44:45.491] <TB0> INFO: overflow: 0
[08:44:45.491] <TB0> INFO: invalid 5bit words: 0
[08:44:45.491] <TB0> INFO: invalid XOR eye diagram: 0
[08:44:45.491] <TB0> INFO: TBM errors: 0
[08:44:45.491] <TB0> INFO: flawed TBM headers: 0
[08:44:45.491] <TB0> INFO: flawed TBM trailers: 0
[08:44:45.491] <TB0> INFO: event ID mismatches: 0
[08:44:45.491] <TB0> INFO: ROC errors: 0
[08:44:45.491] <TB0> INFO: missing ROC header(s): 0
[08:44:45.491] <TB0> INFO: misplaced readback start: 0
[08:44:45.491] <TB0> INFO: Pixel decoding errors: 0
[08:44:45.491] <TB0> INFO: pixel data incomplete: 0
[08:44:45.491] <TB0> INFO: pixel address: 0
[08:44:45.491] <TB0> INFO: pulse height fill bit: 0
[08:44:45.491] <TB0> INFO: buffer corruption: 0
[08:44:45.655] <TB0> INFO: ######################################################################
[08:44:45.655] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:44:45.655] <TB0> INFO: ######################################################################
[08:44:45.665] <TB0> INFO: scanning low vcal = 10
[08:44:45.964] <TB0> INFO: Expecting 41600 events.
[08:44:49.669] <TB0> INFO: 41600 events read in total (2990ms).
[08:44:49.669] <TB0> INFO: Test took 4004ms.
[08:44:49.672] <TB0> INFO: scanning low vcal = 20
[08:44:50.179] <TB0> INFO: Expecting 41600 events.
[08:44:53.884] <TB0> INFO: 41600 events read in total (2989ms).
[08:44:53.884] <TB0> INFO: Test took 4212ms.
[08:44:53.887] <TB0> INFO: scanning low vcal = 30
[08:44:54.395] <TB0> INFO: Expecting 41600 events.
[08:44:58.125] <TB0> INFO: 41600 events read in total (3015ms).
[08:44:58.125] <TB0> INFO: Test took 4238ms.
[08:44:58.128] <TB0> INFO: scanning low vcal = 40
[08:44:58.628] <TB0> INFO: Expecting 41600 events.
[08:45:02.771] <TB0> INFO: 41600 events read in total (3427ms).
[08:45:02.772] <TB0> INFO: Test took 4644ms.
[08:45:02.775] <TB0> INFO: scanning low vcal = 50
[08:45:03.226] <TB0> INFO: Expecting 41600 events.
[08:45:07.438] <TB0> INFO: 41600 events read in total (3496ms).
[08:45:07.438] <TB0> INFO: Test took 4663ms.
[08:45:07.442] <TB0> INFO: scanning low vcal = 60
[08:45:07.894] <TB0> INFO: Expecting 41600 events.
[08:45:12.092] <TB0> INFO: 41600 events read in total (3483ms).
[08:45:12.093] <TB0> INFO: Test took 4651ms.
[08:45:12.096] <TB0> INFO: scanning low vcal = 70
[08:45:12.549] <TB0> INFO: Expecting 41600 events.
[08:45:16.746] <TB0> INFO: 41600 events read in total (3482ms).
[08:45:16.746] <TB0> INFO: Test took 4651ms.
[08:45:16.750] <TB0> INFO: scanning low vcal = 80
[08:45:17.202] <TB0> INFO: Expecting 41600 events.
[08:45:21.407] <TB0> INFO: 41600 events read in total (3490ms).
[08:45:21.408] <TB0> INFO: Test took 4658ms.
[08:45:21.411] <TB0> INFO: scanning low vcal = 90
[08:45:21.864] <TB0> INFO: Expecting 41600 events.
[08:45:26.062] <TB0> INFO: 41600 events read in total (3483ms).
[08:45:26.063] <TB0> INFO: Test took 4652ms.
[08:45:26.067] <TB0> INFO: scanning low vcal = 100
[08:45:26.519] <TB0> INFO: Expecting 41600 events.
[08:45:30.885] <TB0> INFO: 41600 events read in total (3651ms).
[08:45:30.886] <TB0> INFO: Test took 4819ms.
[08:45:30.889] <TB0> INFO: scanning low vcal = 110
[08:45:31.341] <TB0> INFO: Expecting 41600 events.
[08:45:35.539] <TB0> INFO: 41600 events read in total (3482ms).
[08:45:35.540] <TB0> INFO: Test took 4651ms.
[08:45:35.543] <TB0> INFO: scanning low vcal = 120
[08:45:35.995] <TB0> INFO: Expecting 41600 events.
[08:45:40.196] <TB0> INFO: 41600 events read in total (3485ms).
[08:45:40.196] <TB0> INFO: Test took 4653ms.
[08:45:40.200] <TB0> INFO: scanning low vcal = 130
[08:45:40.652] <TB0> INFO: Expecting 41600 events.
[08:45:44.861] <TB0> INFO: 41600 events read in total (3494ms).
[08:45:44.862] <TB0> INFO: Test took 4662ms.
[08:45:44.865] <TB0> INFO: scanning low vcal = 140
[08:45:45.317] <TB0> INFO: Expecting 41600 events.
[08:45:49.528] <TB0> INFO: 41600 events read in total (3495ms).
[08:45:49.529] <TB0> INFO: Test took 4664ms.
[08:45:49.532] <TB0> INFO: scanning low vcal = 150
[08:45:49.985] <TB0> INFO: Expecting 41600 events.
[08:45:54.211] <TB0> INFO: 41600 events read in total (3510ms).
[08:45:54.211] <TB0> INFO: Test took 4679ms.
[08:45:54.214] <TB0> INFO: scanning low vcal = 160
[08:45:54.667] <TB0> INFO: Expecting 41600 events.
[08:45:58.862] <TB0> INFO: 41600 events read in total (3480ms).
[08:45:58.862] <TB0> INFO: Test took 4648ms.
[08:45:58.865] <TB0> INFO: scanning low vcal = 170
[08:45:59.319] <TB0> INFO: Expecting 41600 events.
[08:46:03.517] <TB0> INFO: 41600 events read in total (3483ms).
[08:46:03.517] <TB0> INFO: Test took 4651ms.
[08:46:03.522] <TB0> INFO: scanning low vcal = 180
[08:46:03.973] <TB0> INFO: Expecting 41600 events.
[08:46:08.165] <TB0> INFO: 41600 events read in total (3477ms).
[08:46:08.165] <TB0> INFO: Test took 4643ms.
[08:46:08.168] <TB0> INFO: scanning low vcal = 190
[08:46:08.620] <TB0> INFO: Expecting 41600 events.
[08:46:12.835] <TB0> INFO: 41600 events read in total (3500ms).
[08:46:12.836] <TB0> INFO: Test took 4667ms.
[08:46:12.839] <TB0> INFO: scanning low vcal = 200
[08:46:13.292] <TB0> INFO: Expecting 41600 events.
[08:46:17.520] <TB0> INFO: 41600 events read in total (3513ms).
[08:46:17.520] <TB0> INFO: Test took 4681ms.
[08:46:17.524] <TB0> INFO: scanning low vcal = 210
[08:46:17.975] <TB0> INFO: Expecting 41600 events.
[08:46:22.225] <TB0> INFO: 41600 events read in total (3534ms).
[08:46:22.226] <TB0> INFO: Test took 4702ms.
[08:46:22.229] <TB0> INFO: scanning low vcal = 220
[08:46:22.682] <TB0> INFO: Expecting 41600 events.
[08:46:26.923] <TB0> INFO: 41600 events read in total (3526ms).
[08:46:26.924] <TB0> INFO: Test took 4695ms.
[08:46:26.927] <TB0> INFO: scanning low vcal = 230
[08:46:27.380] <TB0> INFO: Expecting 41600 events.
[08:46:31.595] <TB0> INFO: 41600 events read in total (3500ms).
[08:46:31.595] <TB0> INFO: Test took 4668ms.
[08:46:31.598] <TB0> INFO: scanning low vcal = 240
[08:46:32.051] <TB0> INFO: Expecting 41600 events.
[08:46:36.269] <TB0> INFO: 41600 events read in total (3503ms).
[08:46:36.270] <TB0> INFO: Test took 4672ms.
[08:46:36.273] <TB0> INFO: scanning low vcal = 250
[08:46:36.725] <TB0> INFO: Expecting 41600 events.
[08:46:40.955] <TB0> INFO: 41600 events read in total (3515ms).
[08:46:40.956] <TB0> INFO: Test took 4683ms.
[08:46:40.965] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[08:46:41.412] <TB0> INFO: Expecting 41600 events.
[08:46:45.610] <TB0> INFO: 41600 events read in total (3482ms).
[08:46:45.610] <TB0> INFO: Test took 4645ms.
[08:46:45.613] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[08:46:46.065] <TB0> INFO: Expecting 41600 events.
[08:46:50.256] <TB0> INFO: 41600 events read in total (3475ms).
[08:46:50.257] <TB0> INFO: Test took 4644ms.
[08:46:50.260] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[08:46:50.713] <TB0> INFO: Expecting 41600 events.
[08:46:54.922] <TB0> INFO: 41600 events read in total (3494ms).
[08:46:54.923] <TB0> INFO: Test took 4663ms.
[08:46:54.927] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[08:46:55.380] <TB0> INFO: Expecting 41600 events.
[08:46:59.572] <TB0> INFO: 41600 events read in total (3477ms).
[08:46:59.573] <TB0> INFO: Test took 4646ms.
[08:46:59.576] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:47:00.029] <TB0> INFO: Expecting 41600 events.
[08:47:04.210] <TB0> INFO: 41600 events read in total (3466ms).
[08:47:04.211] <TB0> INFO: Test took 4635ms.
[08:47:04.645] <TB0> INFO: PixTestGainPedestal::measure() done
[08:47:36.246] <TB0> INFO: PixTestGainPedestal::fit() done
[08:47:36.246] <TB0> INFO: non-linearity mean: 0.956 0.954 0.957 0.951 0.957 0.967 0.951 0.954 0.952 0.956 0.954 0.963 0.958 0.959 0.957 0.956
[08:47:36.246] <TB0> INFO: non-linearity RMS: 0.006 0.006 0.005 0.006 0.005 0.003 0.007 0.006 0.005 0.006 0.006 0.006 0.005 0.005 0.005 0.007
[08:47:36.246] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:47:36.264] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:47:36.281] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:47:36.298] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:47:36.315] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:47:36.333] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:47:36.350] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:47:36.367] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:47:36.384] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:47:36.402] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:47:36.420] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:47:36.437] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:47:36.455] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:47:36.472] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:47:36.490] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:47:36.507] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:47:36.524] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 170 seconds
[08:47:36.524] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:47:36.524] <TB0> INFO: Decoding statistics:
[08:47:36.524] <TB0> INFO: General information:
[08:47:36.524] <TB0> INFO: 16bit words read: 2329540
[08:47:36.524] <TB0> INFO: valid events total: 83200
[08:47:36.524] <TB0> INFO: empty events: 0
[08:47:36.524] <TB0> INFO: valid events with pixels: 83200
[08:47:36.524] <TB0> INFO: valid pixel hits: 665570
[08:47:36.524] <TB0> INFO: Event errors: 0
[08:47:36.524] <TB0> INFO: start marker: 0
[08:47:36.524] <TB0> INFO: stop marker: 0
[08:47:36.524] <TB0> INFO: overflow: 0
[08:47:36.524] <TB0> INFO: invalid 5bit words: 0
[08:47:36.524] <TB0> INFO: invalid XOR eye diagram: 0
[08:47:36.524] <TB0> INFO: TBM errors: 0
[08:47:36.524] <TB0> INFO: flawed TBM headers: 0
[08:47:36.524] <TB0> INFO: flawed TBM trailers: 0
[08:47:36.524] <TB0> INFO: event ID mismatches: 0
[08:47:36.524] <TB0> INFO: ROC errors: 0
[08:47:36.524] <TB0> INFO: missing ROC header(s): 0
[08:47:36.524] <TB0> INFO: misplaced readback start: 0
[08:47:36.524] <TB0> INFO: Pixel decoding errors: 0
[08:47:36.524] <TB0> INFO: pixel data incomplete: 0
[08:47:36.524] <TB0> INFO: pixel address: 0
[08:47:36.524] <TB0> INFO: pulse height fill bit: 0
[08:47:36.524] <TB0> INFO: buffer corruption: 0
[08:47:36.530] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C15.dat
[08:47:36.532] <TB0> INFO: ######################################################################
[08:47:36.532] <TB0> INFO: PixTestReadback::doTest()
[08:47:36.532] <TB0> INFO: ######################################################################
[08:47:36.533] <TB0> INFO: PixTestReadback::RES sent once
[08:47:48.310] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C0.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C1.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C2.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C3.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C4.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C5.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C6.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C7.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C8.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C9.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C10.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C11.dat
[08:47:48.311] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C12.dat
[08:47:48.312] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C13.dat
[08:47:48.312] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C14.dat
[08:47:48.312] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C15.dat
[08:47:48.358] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[08:47:48.359] <TB0> INFO: PixTestReadback::RES sent once
[08:47:59.593] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C0.dat
[08:47:59.593] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C1.dat
[08:47:59.593] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C2.dat
[08:47:59.593] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C3.dat
[08:47:59.593] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C4.dat
[08:47:59.593] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C5.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C6.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C7.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C8.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C9.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C10.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C11.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C12.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C13.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C14.dat
[08:47:59.594] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C15.dat
[08:47:59.643] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[08:47:59.644] <TB0> INFO: PixTestReadback::RES sent once
[08:48:08.270] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[08:48:08.270] <TB0> INFO: Vbg will be calibrated using Vd calibration
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.4calibrated Vbg = 1.21499 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 146.9calibrated Vbg = 1.20801 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.1calibrated Vbg = 1.2133 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.6calibrated Vbg = 1.21686 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.9calibrated Vbg = 1.22528 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155calibrated Vbg = 1.22171 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 166calibrated Vbg = 1.21657 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.7calibrated Vbg = 1.22435 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.9calibrated Vbg = 1.22711 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.5calibrated Vbg = 1.22013 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.6calibrated Vbg = 1.22596 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.4calibrated Vbg = 1.22509 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.9calibrated Vbg = 1.21663 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 141.3calibrated Vbg = 1.22451 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.9calibrated Vbg = 1.21466 :::*/*/*/*/
[08:48:08.270] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156calibrated Vbg = 1.21532 :::*/*/*/*/
[08:48:08.273] <TB0> INFO: PixTestReadback::RES sent once
[08:51:02.833] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C0.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C1.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C2.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C3.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C4.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C5.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C6.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C7.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C8.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C9.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C10.dat
[08:51:02.835] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C11.dat
[08:51:02.836] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C12.dat
[08:51:02.836] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C13.dat
[08:51:02.836] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C14.dat
[08:51:02.836] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4556_FullQualification_2015-11-24_08h22m_1448349734//000_FulltestPxar_m20//readbackCal_C15.dat
[08:51:02.881] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[08:51:02.882] <TB0> INFO: PixTestReadback::doTest() done
[08:51:02.882] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:51:02.882] <TB0> INFO: Decoding statistics:
[08:51:02.882] <TB0> INFO: General information:
[08:51:02.882] <TB0> INFO: 16bit words read: 768
[08:51:02.882] <TB0> INFO: valid events total: 64
[08:51:02.882] <TB0> INFO: empty events: 64
[08:51:02.882] <TB0> INFO: valid events with pixels: 0
[08:51:02.882] <TB0> INFO: valid pixel hits: 0
[08:51:02.882] <TB0> INFO: Event errors: 0
[08:51:02.882] <TB0> INFO: start marker: 0
[08:51:02.882] <TB0> INFO: stop marker: 0
[08:51:02.882] <TB0> INFO: overflow: 0
[08:51:02.882] <TB0> INFO: invalid 5bit words: 0
[08:51:02.882] <TB0> INFO: invalid XOR eye diagram: 0
[08:51:02.882] <TB0> INFO: TBM errors: 0
[08:51:02.882] <TB0> INFO: flawed TBM headers: 0
[08:51:02.882] <TB0> INFO: flawed TBM trailers: 0
[08:51:02.882] <TB0> INFO: event ID mismatches: 0
[08:51:02.882] <TB0> INFO: ROC errors: 0
[08:51:02.882] <TB0> INFO: missing ROC header(s): 0
[08:51:02.882] <TB0> INFO: misplaced readback start: 0
[08:51:02.882] <TB0> INFO: Pixel decoding errors: 0
[08:51:02.882] <TB0> INFO: pixel data incomplete: 0
[08:51:02.882] <TB0> INFO: pixel address: 0
[08:51:02.882] <TB0> INFO: pulse height fill bit: 0
[08:51:02.882] <TB0> INFO: buffer corruption: 0
[08:51:02.895] <TB0> INFO: Decoding statistics:
[08:51:02.895] <TB0> INFO: General information:
[08:51:02.895] <TB0> INFO: 16bit words read: 2396748
[08:51:02.895] <TB0> INFO: valid events total: 88384
[08:51:02.895] <TB0> INFO: empty events: 2684
[08:51:02.895] <TB0> INFO: valid events with pixels: 85700
[08:51:02.895] <TB0> INFO: valid pixel hits: 668070
[08:51:02.895] <TB0> INFO: Event errors: 0
[08:51:02.895] <TB0> INFO: start marker: 0
[08:51:02.895] <TB0> INFO: stop marker: 0
[08:51:02.895] <TB0> INFO: overflow: 0
[08:51:02.895] <TB0> INFO: invalid 5bit words: 0
[08:51:02.895] <TB0> INFO: invalid XOR eye diagram: 0
[08:51:02.895] <TB0> INFO: TBM errors: 0
[08:51:02.895] <TB0> INFO: flawed TBM headers: 0
[08:51:02.895] <TB0> INFO: flawed TBM trailers: 0
[08:51:02.895] <TB0> INFO: event ID mismatches: 0
[08:51:02.895] <TB0> INFO: ROC errors: 0
[08:51:02.895] <TB0> INFO: missing ROC header(s): 0
[08:51:02.895] <TB0> INFO: misplaced readback start: 0
[08:51:02.895] <TB0> INFO: Pixel decoding errors: 0
[08:51:02.895] <TB0> INFO: pixel data incomplete: 0
[08:51:02.895] <TB0> INFO: pixel address: 0
[08:51:02.895] <TB0> INFO: pulse height fill bit: 0
[08:51:02.895] <TB0> INFO: buffer corruption: 0
[08:51:02.895] <TB0> INFO: enter test to run
[08:51:02.895] <TB0> INFO: test: exit no parameter change
[08:51:03.089] <TB0> QUIET: Connection to board 169 closed.
[08:51:03.169] <TB0> INFO: pXar: this is the end, my friend