[07:26:52.805] <TB1> INFO: *** Welcome to pxar ***
[07:26:52.805] <TB1> INFO: *** Today: 2015/11/20
[07:26:53.258] <TB1> INFO: *** Version: 9da6
[07:26:53.258] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C15.dat
[07:26:53.259] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:26:53.259] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//defaultMaskFile.dat
[07:26:53.259] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters_C15.dat
[07:26:53.331] <TB1> INFO: clk: 4
[07:26:53.331] <TB1> INFO: ctr: 4
[07:26:53.331] <TB1> INFO: sda: 19
[07:26:53.331] <TB1> INFO: tin: 9
[07:26:53.331] <TB1> INFO: level: 15
[07:26:53.331] <TB1> INFO: triggerdelay: 0
[07:26:53.331] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[07:26:53.331] <TB1> INFO: Log level: INFO
[07:26:53.341] <TB1> INFO: Found DTB DTB_WXC03A
[07:26:53.350] <TB1> QUIET: Connection to board DTB_WXC03A opened.
[07:26:53.354] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 154
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXC03A
MAC address: 40D85511809A
Hostname: pixelDTB154
Comment:
------------------------------------------------------
[07:26:53.356] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[07:26:54.938] <TB1> INFO: DUT info:
[07:26:54.938] <TB1> INFO: The DUT currently contains the following objects:
[07:26:54.938] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[07:26:54.938] <TB1> INFO: TBM Core alpha (0): 7 registers set
[07:26:54.938] <TB1> INFO: TBM Core beta (1): 7 registers set
[07:26:54.938] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:26:54.938] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.938] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.939] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.939] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.939] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:54.939] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:55.340] <TB1> INFO: enter 'restricted' command line mode
[07:26:55.340] <TB1> INFO: enter test to run
[07:26:55.340] <TB1> INFO: test: pretest no parameter change
[07:26:55.340] <TB1> INFO: running: pretest
[07:26:55.349] <TB1> INFO: ######################################################################
[07:26:55.349] <TB1> INFO: PixTestPretest::doTest()
[07:26:55.349] <TB1> INFO: ######################################################################
[07:26:55.351] <TB1> INFO: ----------------------------------------------------------------------
[07:26:55.351] <TB1> INFO: PixTestPretest::programROC()
[07:26:55.351] <TB1> INFO: ----------------------------------------------------------------------
[07:27:13.369] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:27:13.369] <TB1> INFO: IA differences per ROC: 18.5 19.3 18.5 20.9 19.3 16.9 16.9 18.5 19.3 16.9 17.7 18.5 17.7 18.5 19.3 17.7
[07:27:13.443] <TB1> INFO: ----------------------------------------------------------------------
[07:27:13.443] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:27:13.443] <TB1> INFO: ----------------------------------------------------------------------
[07:27:34.706] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[07:27:34.706] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 19.3 18.5 18.5 19.3 19.3 20.1 20.1 18.5 19.3 19.3 20.1 19.3 19.3
[07:27:34.738] <TB1> INFO: ----------------------------------------------------------------------
[07:27:34.739] <TB1> INFO: PixTestPretest::findTiming()
[07:27:34.739] <TB1> INFO: ----------------------------------------------------------------------
[07:27:34.739] <TB1> INFO: PixTestCmd::init()
[07:27:35.333] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[07:29:13.929] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 1, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[07:29:13.929] <TB1> INFO: (success/tries = 100/100), width = 5
[07:29:13.931] <TB1> INFO: ----------------------------------------------------------------------
[07:29:13.931] <TB1> INFO: PixTestPretest::findWorkingPixel()
[07:29:13.931] <TB1> INFO: ----------------------------------------------------------------------
[07:29:14.068] <TB1> INFO: Expecting 231680 events.
[07:29:18.692] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[07:29:18.695] <TB1> ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[07:29:21.953] <TB1> INFO: 231680 events read in total (7170ms).
[07:29:21.958] <TB1> INFO: Test took 8025ms.
[07:29:22.363] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:29:22.400] <TB1> INFO: ----------------------------------------------------------------------
[07:29:22.400] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[07:29:22.400] <TB1> INFO: ----------------------------------------------------------------------
[07:29:22.537] <TB1> INFO: Expecting 231680 events.
[07:29:31.491] <TB1> INFO: 231680 events read in total (8239ms).
[07:29:31.496] <TB1> INFO: Test took 9092ms.
[07:29:31.917] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[07:29:31.917] <TB1> INFO: CalDel: 111 111 113 97 119 120 142 134 117 133 154 136 110 120 131 144
[07:29:31.917] <TB1> INFO: VthrComp: 51 52 51 51 51 51 51 51 51 52 51 51 51 51 51 51
[07:29:31.921] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C0.dat
[07:29:31.922] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C1.dat
[07:29:31.922] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C2.dat
[07:29:31.923] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C3.dat
[07:29:31.923] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C4.dat
[07:29:31.923] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C5.dat
[07:29:31.923] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C6.dat
[07:29:31.923] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C7.dat
[07:29:31.924] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C8.dat
[07:29:31.924] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C9.dat
[07:29:31.924] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C10.dat
[07:29:31.924] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C11.dat
[07:29:31.925] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C12.dat
[07:29:31.925] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C13.dat
[07:29:31.925] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C14.dat
[07:29:31.925] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C15.dat
[07:29:31.925] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0a.dat
[07:29:31.926] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:29:31.926] <TB1> INFO: PixTestPretest::doTest() done, duration: 156 seconds
[07:29:31.992] <TB1> INFO: enter test to run
[07:29:31.992] <TB1> INFO: test: fulltest no parameter change
[07:29:31.992] <TB1> INFO: running: fulltest
[07:29:31.992] <TB1> INFO: ######################################################################
[07:29:31.992] <TB1> INFO: PixTestFullTest::doTest()
[07:29:31.992] <TB1> INFO: ######################################################################
[07:29:31.994] <TB1> INFO: ######################################################################
[07:29:31.994] <TB1> INFO: PixTestAlive::doTest()
[07:29:31.994] <TB1> INFO: ######################################################################
[07:29:31.995] <TB1> INFO: ----------------------------------------------------------------------
[07:29:31.995] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:29:31.995] <TB1> INFO: ----------------------------------------------------------------------
[07:29:32.302] <TB1> INFO: Expecting 41600 events.
[07:29:36.718] <TB1> INFO: 41600 events read in total (3701ms).
[07:29:36.719] <TB1> INFO: Test took 4722ms.
[07:29:36.724] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:37.106] <TB1> INFO: PixTestAlive::aliveTest() done
[07:29:37.106] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:29:37.108] <TB1> INFO: ----------------------------------------------------------------------
[07:29:37.108] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:29:37.108] <TB1> INFO: ----------------------------------------------------------------------
[07:29:37.423] <TB1> INFO: Expecting 41600 events.
[07:29:40.482] <TB1> INFO: 41600 events read in total (2344ms).
[07:29:40.482] <TB1> INFO: Test took 3373ms.
[07:29:40.482] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:40.483] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:29:40.892] <TB1> INFO: PixTestAlive::maskTest() done
[07:29:40.892] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:29:40.894] <TB1> INFO: ----------------------------------------------------------------------
[07:29:40.894] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:29:40.894] <TB1> INFO: ----------------------------------------------------------------------
[07:29:41.214] <TB1> INFO: Expecting 41600 events.
[07:29:45.427] <TB1> INFO: 41600 events read in total (3498ms).
[07:29:45.427] <TB1> INFO: Test took 4531ms.
[07:29:45.434] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:45.815] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[07:29:45.815] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:29:45.815] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[07:29:45.815] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:45.815] <TB1> INFO: Decoding statistics:
[07:29:45.815] <TB1> INFO: General information:
[07:29:45.815] <TB1> INFO: 16bit words read: 0
[07:29:45.815] <TB1> INFO: valid events total: 0
[07:29:45.815] <TB1> INFO: empty events: 0
[07:29:45.815] <TB1> INFO: valid events with pixels: 0
[07:29:45.815] <TB1> INFO: valid pixel hits: 0
[07:29:45.815] <TB1> INFO: Event errors: 0
[07:29:45.815] <TB1> INFO: start marker: 0
[07:29:45.815] <TB1> INFO: stop marker: 0
[07:29:45.815] <TB1> INFO: overflow: 0
[07:29:45.815] <TB1> INFO: invalid 5bit words: 0
[07:29:45.815] <TB1> INFO: invalid XOR eye diagram: 0
[07:29:45.815] <TB1> INFO: TBM errors: 0
[07:29:45.815] <TB1> INFO: flawed TBM headers: 0
[07:29:45.815] <TB1> INFO: flawed TBM trailers: 0
[07:29:45.815] <TB1> INFO: event ID mismatches: 0
[07:29:45.815] <TB1> INFO: ROC errors: 0
[07:29:45.815] <TB1> INFO: missing ROC header(s): 0
[07:29:45.815] <TB1> INFO: misplaced readback start: 0
[07:29:45.815] <TB1> INFO: Pixel decoding errors: 0
[07:29:45.815] <TB1> INFO: pixel data incomplete: 0
[07:29:45.815] <TB1> INFO: pixel address: 0
[07:29:45.815] <TB1> INFO: pulse height fill bit: 0
[07:29:45.815] <TB1> INFO: buffer corruption: 0
[07:29:45.829] <TB1> INFO: ######################################################################
[07:29:45.829] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:29:45.829] <TB1> INFO: ######################################################################
[07:29:45.831] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:29:45.844] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:29:45.844] <TB1> INFO: run 1 of 1
[07:29:46.173] <TB1> INFO: Expecting 3120000 events.
[07:30:34.978] <TB1> INFO: 1068390 events read in total (48090ms).
[07:31:23.485] <TB1> INFO: 2136465 events read in total (96597ms).
[07:32:07.287] <TB1> INFO: 3120000 events read in total (140400ms).
[07:32:07.352] <TB1> INFO: Test took 141509ms.
[07:32:07.453] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:34.400] <TB1> INFO: PixTestBBMap::doTest() done, duration: 168 seconds
[07:32:34.400] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:32:34.400] <TB1> INFO: separation cut (per ROC): 126 122 97 118 92 91 121 113 138 119 86 112 102 117 114 120
[07:32:34.400] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:34.400] <TB1> INFO: Decoding statistics:
[07:32:34.400] <TB1> INFO: General information:
[07:32:34.400] <TB1> INFO: 16bit words read: 0
[07:32:34.400] <TB1> INFO: valid events total: 0
[07:32:34.400] <TB1> INFO: empty events: 0
[07:32:34.400] <TB1> INFO: valid events with pixels: 0
[07:32:34.400] <TB1> INFO: valid pixel hits: 0
[07:32:34.400] <TB1> INFO: Event errors: 0
[07:32:34.400] <TB1> INFO: start marker: 0
[07:32:34.400] <TB1> INFO: stop marker: 0
[07:32:34.400] <TB1> INFO: overflow: 0
[07:32:34.400] <TB1> INFO: invalid 5bit words: 0
[07:32:34.400] <TB1> INFO: invalid XOR eye diagram: 0
[07:32:34.400] <TB1> INFO: TBM errors: 0
[07:32:34.400] <TB1> INFO: flawed TBM headers: 0
[07:32:34.400] <TB1> INFO: flawed TBM trailers: 0
[07:32:34.400] <TB1> INFO: event ID mismatches: 0
[07:32:34.400] <TB1> INFO: ROC errors: 0
[07:32:34.400] <TB1> INFO: missing ROC header(s): 0
[07:32:34.400] <TB1> INFO: misplaced readback start: 0
[07:32:34.400] <TB1> INFO: Pixel decoding errors: 0
[07:32:34.400] <TB1> INFO: pixel data incomplete: 0
[07:32:34.400] <TB1> INFO: pixel address: 0
[07:32:34.400] <TB1> INFO: pulse height fill bit: 0
[07:32:34.400] <TB1> INFO: buffer corruption: 0
[07:32:34.508] <TB1> INFO: ######################################################################
[07:32:34.508] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:32:34.508] <TB1> INFO: ######################################################################
[07:32:34.508] <TB1> INFO: ----------------------------------------------------------------------
[07:32:34.508] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:32:34.508] <TB1> INFO: ----------------------------------------------------------------------
[07:32:34.508] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:32:34.519] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:34.519] <TB1> INFO: run 1 of 1
[07:32:34.905] <TB1> INFO: Expecting 26208000 events.
[07:33:07.587] <TB1> INFO: 1154550 events read in total (31967ms).
[07:33:39.383] <TB1> INFO: 2280850 events read in total (63763ms).
[07:34:11.621] <TB1> INFO: 3403500 events read in total (96001ms).
[07:34:43.488] <TB1> INFO: 4520200 events read in total (127868ms).
[07:35:15.779] <TB1> INFO: 5635700 events read in total (160159ms).
[07:35:47.709] <TB1> INFO: 6747300 events read in total (192089ms).
[07:36:19.654] <TB1> INFO: 7855650 events read in total (224034ms).
[07:36:51.448] <TB1> INFO: 8964400 events read in total (255828ms).
[07:37:24.139] <TB1> INFO: 10072100 events read in total (288519ms).
[07:37:56.909] <TB1> INFO: 11177150 events read in total (321289ms).
[07:38:30.222] <TB1> INFO: 12279550 events read in total (354602ms).
[07:39:02.334] <TB1> INFO: 13374400 events read in total (386714ms).
[07:39:34.175] <TB1> INFO: 14462500 events read in total (418555ms).
[07:40:06.225] <TB1> INFO: 15547900 events read in total (450605ms).
[07:40:37.943] <TB1> INFO: 16631350 events read in total (482323ms).
[07:41:09.984] <TB1> INFO: 17713950 events read in total (514364ms).
[07:41:41.773] <TB1> INFO: 18794650 events read in total (546153ms).
[07:42:13.293] <TB1> INFO: 19872450 events read in total (577673ms).
[07:42:44.844] <TB1> INFO: 20947000 events read in total (609224ms).
[07:43:16.765] <TB1> INFO: 22022700 events read in total (641145ms).
[07:43:48.566] <TB1> INFO: 23099300 events read in total (672946ms).
[07:44:19.853] <TB1> INFO: 24175950 events read in total (704233ms).
[07:44:51.440] <TB1> INFO: 25254500 events read in total (735820ms).
[07:45:19.015] <TB1> INFO: 26208000 events read in total (763395ms).
[07:45:19.048] <TB1> INFO: Test took 764529ms.
[07:45:19.125] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:45:19.297] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:20.821] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:22.361] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:23.982] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:25.600] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:27.246] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:28.921] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:30.456] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:32.115] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:33.647] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:35.383] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:37.007] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:38.570] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:39.982] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:41.666] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:43.357] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:45.071] <TB1> INFO: PixTestScurves::scurves() done
[07:45:45.071] <TB1> INFO: Vcal mean: 100.54 113.34 86.37 98.53 86.89 82.07 86.70 82.58 106.11 108.37 81.04 96.09 91.12 106.02 96.13 87.02
[07:45:45.071] <TB1> INFO: Vcal RMS: 6.06 5.81 5.49 5.40 5.99 4.82 5.28 5.41 5.53 6.20 5.41 5.82 5.43 6.09 5.97 5.19
[07:45:45.071] <TB1> INFO: PixTestScurves::fullTest() done, duration: 790 seconds
[07:45:45.071] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:45:45.071] <TB1> INFO: Decoding statistics:
[07:45:45.071] <TB1> INFO: General information:
[07:45:45.071] <TB1> INFO: 16bit words read: 0
[07:45:45.071] <TB1> INFO: valid events total: 0
[07:45:45.071] <TB1> INFO: empty events: 0
[07:45:45.071] <TB1> INFO: valid events with pixels: 0
[07:45:45.071] <TB1> INFO: valid pixel hits: 0
[07:45:45.071] <TB1> INFO: Event errors: 0
[07:45:45.071] <TB1> INFO: start marker: 0
[07:45:45.071] <TB1> INFO: stop marker: 0
[07:45:45.071] <TB1> INFO: overflow: 0
[07:45:45.071] <TB1> INFO: invalid 5bit words: 0
[07:45:45.071] <TB1> INFO: invalid XOR eye diagram: 0
[07:45:45.071] <TB1> INFO: TBM errors: 0
[07:45:45.071] <TB1> INFO: flawed TBM headers: 0
[07:45:45.071] <TB1> INFO: flawed TBM trailers: 0
[07:45:45.071] <TB1> INFO: event ID mismatches: 0
[07:45:45.071] <TB1> INFO: ROC errors: 0
[07:45:45.071] <TB1> INFO: missing ROC header(s): 0
[07:45:45.071] <TB1> INFO: misplaced readback start: 0
[07:45:45.071] <TB1> INFO: Pixel decoding errors: 0
[07:45:45.071] <TB1> INFO: pixel data incomplete: 0
[07:45:45.071] <TB1> INFO: pixel address: 0
[07:45:45.071] <TB1> INFO: pulse height fill bit: 0
[07:45:45.071] <TB1> INFO: buffer corruption: 0
[07:45:45.172] <TB1> INFO: ######################################################################
[07:45:45.172] <TB1> INFO: PixTestTrim::doTest()
[07:45:45.172] <TB1> INFO: ######################################################################
[07:45:45.173] <TB1> INFO: ----------------------------------------------------------------------
[07:45:45.173] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:45:45.173] <TB1> INFO: ----------------------------------------------------------------------
[07:45:45.300] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:45:45.300] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:45:45.311] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:45:45.311] <TB1> INFO: run 1 of 1
[07:45:45.699] <TB1> INFO: Expecting 6281600 events.
[07:46:30.002] <TB1> INFO: 1418910 events read in total (43585ms).
[07:47:13.530] <TB1> INFO: 2821280 events read in total (87113ms).
[07:47:58.869] <TB1> INFO: 4221820 events read in total (132452ms).
[07:48:43.339] <TB1> INFO: 5625470 events read in total (176922ms).
[07:49:03.848] <TB1> INFO: 6281600 events read in total (197431ms).
[07:49:03.885] <TB1> INFO: Test took 198574ms.
[07:49:03.952] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:49:24.421] <TB1> INFO: ROC 0 VthrComp = 104
[07:49:24.421] <TB1> INFO: ROC 1 VthrComp = 107
[07:49:24.421] <TB1> INFO: ROC 2 VthrComp = 91
[07:49:24.421] <TB1> INFO: ROC 3 VthrComp = 104
[07:49:24.421] <TB1> INFO: ROC 4 VthrComp = 84
[07:49:24.422] <TB1> INFO: ROC 5 VthrComp = 87
[07:49:24.422] <TB1> INFO: ROC 6 VthrComp = 88
[07:49:24.422] <TB1> INFO: ROC 7 VthrComp = 86
[07:49:24.422] <TB1> INFO: ROC 8 VthrComp = 107
[07:49:24.422] <TB1> INFO: ROC 9 VthrComp = 104
[07:49:24.422] <TB1> INFO: ROC 10 VthrComp = 78
[07:49:24.423] <TB1> INFO: ROC 11 VthrComp = 99
[07:49:24.423] <TB1> INFO: ROC 12 VthrComp = 95
[07:49:24.423] <TB1> INFO: ROC 13 VthrComp = 106
[07:49:24.423] <TB1> INFO: ROC 14 VthrComp = 99
[07:49:24.423] <TB1> INFO: ROC 15 VthrComp = 92
[07:49:24.423] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:49:24.423] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:49:24.434] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:49:24.434] <TB1> INFO: run 1 of 1
[07:49:24.818] <TB1> INFO: Expecting 6281600 events.
[07:50:01.147] <TB1> INFO: 898240 events read in total (35615ms).
[07:50:36.335] <TB1> INFO: 1790900 events read in total (70803ms).
[07:51:12.668] <TB1> INFO: 2683840 events read in total (107136ms).
[07:51:48.978] <TB1> INFO: 3571070 events read in total (143446ms).
[07:52:25.477] <TB1> INFO: 4451920 events read in total (179945ms).
[07:53:01.583] <TB1> INFO: 5328260 events read in total (216051ms).
[07:53:37.810] <TB1> INFO: 6205220 events read in total (252278ms).
[07:53:41.193] <TB1> INFO: 6281600 events read in total (255661ms).
[07:53:41.249] <TB1> INFO: Test took 256815ms.
[07:53:41.388] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:07.064] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 61.4817 for pixel 18/65 mean/min/max = 47.4212/33.3443/61.498
[07:54:07.064] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 69.2457 for pixel 31/6 mean/min/max = 52.3159/35.14/69.4919
[07:54:07.064] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 59.202 for pixel 38/78 mean/min/max = 45.6649/32.119/59.2108
[07:54:07.064] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 60.8145 for pixel 13/79 mean/min/max = 47.2172/33.6142/60.8203
[07:54:07.065] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 63.3288 for pixel 3/10 mean/min/max = 47.5504/31.7046/63.3962
[07:54:07.065] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 56.8924 for pixel 0/71 mean/min/max = 44.3032/31.6806/56.9258
[07:54:07.065] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.7262 for pixel 25/0 mean/min/max = 46.1554/33.5586/58.7522
[07:54:07.065] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.9456 for pixel 0/23 mean/min/max = 45.1662/31.3581/58.9742
[07:54:07.066] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 61.3414 for pixel 18/1 mean/min/max = 47.5437/33.493/61.5943
[07:54:07.066] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 66.9177 for pixel 9/78 mean/min/max = 49.7622/32.5339/66.9905
[07:54:07.066] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 64.5119 for pixel 1/67 mean/min/max = 49.1949/33.6217/64.7682
[07:54:07.066] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 59.5972 for pixel 9/5 mean/min/max = 45.7485/31.6969/59.8001
[07:54:07.067] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 58.8457 for pixel 10/79 mean/min/max = 45.381/31.7707/58.9913
[07:54:07.067] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 63.8571 for pixel 8/76 mean/min/max = 48.8167/33.7117/63.9217
[07:54:07.067] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 59.5266 for pixel 5/77 mean/min/max = 45.599/31.597/59.6009
[07:54:07.067] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 58.4834 for pixel 3/29 mean/min/max = 45.4823/32.4075/58.557
[07:54:07.068] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:54:07.199] <TB1> INFO: Expecting 514560 events.
[07:54:17.317] <TB1> INFO: 514560 events read in total (9403ms).
[07:54:17.322] <TB1> INFO: Expecting 514560 events.
[07:54:27.722] <TB1> INFO: 514560 events read in total (9736ms).
[07:54:27.729] <TB1> INFO: Expecting 514560 events.
[07:54:38.256] <TB1> INFO: 514560 events read in total (9858ms).
[07:54:38.266] <TB1> INFO: Expecting 514560 events.
[07:54:49.011] <TB1> INFO: 514560 events read in total (10083ms).
[07:54:49.022] <TB1> INFO: Expecting 514560 events.
[07:54:59.527] <TB1> INFO: 514560 events read in total (9844ms).
[07:54:59.541] <TB1> INFO: Expecting 514560 events.
[07:55:09.903] <TB1> INFO: 514560 events read in total (9701ms).
[07:55:09.917] <TB1> INFO: Expecting 514560 events.
[07:55:20.227] <TB1> INFO: 514560 events read in total (9643ms).
[07:55:20.245] <TB1> INFO: Expecting 514560 events.
[07:55:31.002] <TB1> INFO: 514560 events read in total (10104ms).
[07:55:31.020] <TB1> INFO: Expecting 514560 events.
[07:55:41.500] <TB1> INFO: 514560 events read in total (9830ms).
[07:55:41.521] <TB1> INFO: Expecting 514560 events.
[07:55:51.665] <TB1> INFO: 514560 events read in total (9492ms).
[07:55:51.691] <TB1> INFO: Expecting 514560 events.
[07:56:01.849] <TB1> INFO: 514560 events read in total (9522ms).
[07:56:01.875] <TB1> INFO: Expecting 514560 events.
[07:56:11.985] <TB1> INFO: 514560 events read in total (9460ms).
[07:56:12.016] <TB1> INFO: Expecting 514560 events.
[07:56:22.108] <TB1> INFO: 514560 events read in total (9450ms).
[07:56:22.136] <TB1> INFO: Expecting 514560 events.
[07:56:32.253] <TB1> INFO: 514560 events read in total (9464ms).
[07:56:32.296] <TB1> INFO: Expecting 514560 events.
[07:56:42.544] <TB1> INFO: 514560 events read in total (9635ms).
[07:56:42.577] <TB1> INFO: Expecting 514560 events.
[07:56:53.306] <TB1> INFO: 514560 events read in total (10092ms).
[07:56:53.341] <TB1> INFO: Test took 166273ms.
[07:56:54.639] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:56:54.649] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:56:54.649] <TB1> INFO: run 1 of 1
[07:56:55.035] <TB1> INFO: Expecting 6281600 events.
[07:57:31.765] <TB1> INFO: 873580 events read in total (36015ms).
[07:58:08.308] <TB1> INFO: 1743970 events read in total (72558ms).
[07:58:44.141] <TB1> INFO: 2613520 events read in total (108392ms).
[07:59:20.359] <TB1> INFO: 3479450 events read in total (144609ms).
[07:59:56.999] <TB1> INFO: 4337190 events read in total (181249ms).
[08:00:33.486] <TB1> INFO: 5192050 events read in total (217736ms).
[08:01:08.876] <TB1> INFO: 6045350 events read in total (253126ms).
[08:01:18.772] <TB1> INFO: 6281600 events read in total (263022ms).
[08:01:18.830] <TB1> INFO: Test took 264181ms.
[08:01:18.982] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:01:44.870] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.058649 .. 255.000000
[08:01:44.971] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[08:01:44.980] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:01:44.980] <TB1> INFO: run 1 of 1
[08:01:45.308] <TB1> INFO: Expecting 10649600 events.
[08:02:19.259] <TB1> INFO: 826090 events read in total (33235ms).
[08:02:54.131] <TB1> INFO: 1652980 events read in total (68107ms).
[08:03:28.799] <TB1> INFO: 2479580 events read in total (102775ms).
[08:04:03.145] <TB1> INFO: 3306480 events read in total (137121ms).
[08:04:37.937] <TB1> INFO: 4133590 events read in total (171913ms).
[08:05:13.660] <TB1> INFO: 4960810 events read in total (207636ms).
[08:05:48.864] <TB1> INFO: 5787850 events read in total (242840ms).
[08:06:24.190] <TB1> INFO: 6614460 events read in total (278166ms).
[08:06:58.766] <TB1> INFO: 7440730 events read in total (312742ms).
[08:07:34.033] <TB1> INFO: 8265930 events read in total (348009ms).
[08:08:09.413] <TB1> INFO: 9090490 events read in total (383389ms).
[08:08:44.011] <TB1> INFO: 9915670 events read in total (417987ms).
[08:09:14.546] <TB1> INFO: 10649600 events read in total (448522ms).
[08:09:14.674] <TB1> INFO: Test took 449694ms.
[08:09:14.981] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:45.401] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.794801 .. 45.643739
[08:09:45.481] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:09:45.490] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:09:45.490] <TB1> INFO: run 1 of 1
[08:09:45.807] <TB1> INFO: Expecting 2121600 events.
[08:10:29.241] <TB1> INFO: 1165430 events read in total (42719ms).
[08:11:02.190] <TB1> INFO: 2121600 events read in total (75668ms).
[08:11:02.204] <TB1> INFO: Test took 76714ms.
[08:11:02.234] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:15.550] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 20.308622 .. 40.934684
[08:11:15.629] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 50 (-1/-1) hits flags = 528 (plus default)
[08:11:15.637] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:11:15.637] <TB1> INFO: run 1 of 1
[08:11:15.943] <TB1> INFO: Expecting 1705600 events.
[08:11:56.506] <TB1> INFO: 1193630 events read in total (39849ms).
[08:12:13.926] <TB1> INFO: 1705600 events read in total (57269ms).
[08:12:13.941] <TB1> INFO: Test took 58304ms.
[08:12:13.967] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:27.994] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 2.790106 .. 40.383356
[08:12:28.074] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 50 (-1/-1) hits flags = 528 (plus default)
[08:12:28.082] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:12:28.082] <TB1> INFO: run 1 of 1
[08:12:28.388] <TB1> INFO: Expecting 2038400 events.
[08:13:10.831] <TB1> INFO: 1262140 events read in total (41729ms).
[08:13:35.950] <TB1> INFO: 2038400 events read in total (66848ms).
[08:13:35.963] <TB1> INFO: Test took 67881ms.
[08:13:35.989] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:49.065] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:13:49.065] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:13:49.073] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:13:49.073] <TB1> INFO: run 1 of 1
[08:13:49.380] <TB1> INFO: Expecting 1705600 events.
[08:14:28.943] <TB1> INFO: 1075760 events read in total (38849ms).
[08:14:52.397] <TB1> INFO: 1705600 events read in total (62303ms).
[08:14:52.412] <TB1> INFO: Test took 63340ms.
[08:14:52.445] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:08.192] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:15:08.192] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:15:08.192] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:15:08.192] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:15:08.192] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:15:08.192] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:15:08.192] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:15:08.193] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:15:08.194] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:15:08.199] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:15:08.205] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:15:08.211] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:15:08.216] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:15:08.222] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:15:08.228] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:15:08.233] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:15:08.239] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:15:08.244] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:15:08.250] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:15:08.256] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:15:08.262] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:15:08.267] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:15:08.273] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:15:08.278] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:15:08.284] <TB1> INFO: PixTestTrim::trimTest() done
[08:15:08.284] <TB1> INFO: vtrim: 124 124 105 100 103 82 98 107 106 126 102 95 90 121 108 90
[08:15:08.284] <TB1> INFO: vthrcomp: 104 107 91 104 84 87 88 86 107 104 78 99 95 106 99 92
[08:15:08.284] <TB1> INFO: vcal mean: 34.96 35.00 34.98 35.03 34.96 34.99 34.97 34.94 35.02 35.01 35.00 34.91 34.98 34.98 34.96 34.95
[08:15:08.284] <TB1> INFO: vcal RMS: 0.90 0.98 0.89 0.81 0.94 0.86 0.87 0.87 0.85 0.98 1.01 0.90 0.89 0.94 0.89 0.83
[08:15:08.284] <TB1> INFO: bits mean: 9.21 7.78 9.64 8.22 9.24 9.99 9.25 9.66 8.88 8.93 8.69 9.64 9.17 9.11 9.73 9.34
[08:15:08.284] <TB1> INFO: bits RMS: 2.49 2.53 2.58 2.85 2.69 2.56 2.58 2.73 2.57 2.55 2.62 2.70 2.87 2.40 2.60 2.71
[08:15:08.588] <TB1> INFO: ----------------------------------------------------------------------
[08:15:08.588] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[08:15:08.588] <TB1> INFO: ----------------------------------------------------------------------
[08:15:08.590] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:15:08.598] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:15:08.598] <TB1> INFO: run 1 of 1
[08:15:08.905] <TB1> INFO: Expecting 8320000 events.
[08:15:48.979] <TB1> INFO: 1179440 events read in total (39359ms).
[08:16:28.130] <TB1> INFO: 2341310 events read in total (78510ms).
[08:17:08.176] <TB1> INFO: 3497590 events read in total (118556ms).
[08:17:48.338] <TB1> INFO: 4644790 events read in total (158718ms).
[08:18:26.915] <TB1> INFO: 5783630 events read in total (197295ms).
[08:19:05.558] <TB1> INFO: 6919180 events read in total (235938ms).
[08:19:44.558] <TB1> INFO: 8053950 events read in total (274938ms).
[08:19:54.081] <TB1> INFO: 8320000 events read in total (284461ms).
[08:19:54.131] <TB1> INFO: Test took 285533ms.
[08:19:54.237] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:23.390] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[08:20:23.399] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:20:23.399] <TB1> INFO: run 1 of 1
[08:20:23.739] <TB1> INFO: Expecting 7696000 events.
[08:21:04.237] <TB1> INFO: 1174630 events read in total (39783ms).
[08:21:43.660] <TB1> INFO: 2332530 events read in total (79206ms).
[08:22:23.019] <TB1> INFO: 3483920 events read in total (118565ms).
[08:23:02.782] <TB1> INFO: 4623770 events read in total (158328ms).
[08:23:41.701] <TB1> INFO: 5755670 events read in total (197247ms).
[08:24:20.402] <TB1> INFO: 6885300 events read in total (235948ms).
[08:24:48.775] <TB1> INFO: 7696000 events read in total (264321ms).
[08:24:48.820] <TB1> INFO: Test took 265421ms.
[08:24:48.922] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:18.259] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[08:25:18.267] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:25:18.267] <TB1> INFO: run 1 of 1
[08:25:18.573] <TB1> INFO: Expecting 7113600 events.
[08:26:00.120] <TB1> INFO: 1227200 events read in total (40833ms).
[08:26:40.264] <TB1> INFO: 2434740 events read in total (80977ms).
[08:27:19.191] <TB1> INFO: 3632430 events read in total (119904ms).
[08:27:57.977] <TB1> INFO: 4814860 events read in total (158690ms).
[08:28:37.154] <TB1> INFO: 5991160 events read in total (197867ms).
[08:29:14.816] <TB1> INFO: 7113600 events read in total (235529ms).
[08:29:14.849] <TB1> INFO: Test took 236582ms.
[08:29:14.928] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:38.970] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[08:29:38.978] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:29:38.978] <TB1> INFO: run 1 of 1
[08:29:39.284] <TB1> INFO: Expecting 7155200 events.
[08:30:20.106] <TB1> INFO: 1221090 events read in total (40107ms).
[08:31:00.720] <TB1> INFO: 2423370 events read in total (80721ms).
[08:31:40.449] <TB1> INFO: 3616590 events read in total (120450ms).
[08:32:21.556] <TB1> INFO: 4793050 events read in total (161557ms).
[08:33:00.168] <TB1> INFO: 5964780 events read in total (200169ms).
[08:33:40.615] <TB1> INFO: 7141590 events read in total (240616ms).
[08:33:41.413] <TB1> INFO: 7155200 events read in total (241414ms).
[08:33:41.448] <TB1> INFO: Test took 242470ms.
[08:33:41.531] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:05.288] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[08:34:05.296] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:34:05.296] <TB1> INFO: run 1 of 1
[08:34:05.605] <TB1> INFO: Expecting 7155200 events.
[08:34:46.934] <TB1> INFO: 1220440 events read in total (40614ms).
[08:35:27.856] <TB1> INFO: 2421470 events read in total (81536ms).
[08:36:07.346] <TB1> INFO: 3613440 events read in total (121027ms).
[08:36:46.805] <TB1> INFO: 4788810 events read in total (160485ms).
[08:37:26.028] <TB1> INFO: 5959690 events read in total (199708ms).
[08:38:08.528] <TB1> INFO: 7135330 events read in total (242208ms).
[08:38:09.512] <TB1> INFO: 7155200 events read in total (243192ms).
[08:38:09.551] <TB1> INFO: Test took 244256ms.
[08:38:09.630] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:34.330] <TB1> INFO: PixTestTrim::trimBitTest() done
[08:38:34.332] <TB1> INFO: PixTestTrim::doTest() done, duration: 3169 seconds
[08:38:34.332] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:34.332] <TB1> INFO: Decoding statistics:
[08:38:34.332] <TB1> INFO: General information:
[08:38:34.332] <TB1> INFO: 16bit words read: 0
[08:38:34.332] <TB1> INFO: valid events total: 0
[08:38:34.332] <TB1> INFO: empty events: 0
[08:38:34.332] <TB1> INFO: valid events with pixels: 0
[08:38:34.332] <TB1> INFO: valid pixel hits: 0
[08:38:34.332] <TB1> INFO: Event errors: 0
[08:38:34.332] <TB1> INFO: start marker: 0
[08:38:34.332] <TB1> INFO: stop marker: 0
[08:38:34.332] <TB1> INFO: overflow: 0
[08:38:34.332] <TB1> INFO: invalid 5bit words: 0
[08:38:34.332] <TB1> INFO: invalid XOR eye diagram: 0
[08:38:34.332] <TB1> INFO: TBM errors: 0
[08:38:34.332] <TB1> INFO: flawed TBM headers: 0
[08:38:34.332] <TB1> INFO: flawed TBM trailers: 0
[08:38:34.332] <TB1> INFO: event ID mismatches: 0
[08:38:34.332] <TB1> INFO: ROC errors: 0
[08:38:34.332] <TB1> INFO: missing ROC header(s): 0
[08:38:34.332] <TB1> INFO: misplaced readback start: 0
[08:38:34.332] <TB1> INFO: Pixel decoding errors: 0
[08:38:34.332] <TB1> INFO: pixel data incomplete: 0
[08:38:34.332] <TB1> INFO: pixel address: 0
[08:38:34.332] <TB1> INFO: pulse height fill bit: 0
[08:38:34.332] <TB1> INFO: buffer corruption: 0
[08:38:35.003] <TB1> INFO: ######################################################################
[08:38:35.003] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:38:35.003] <TB1> INFO: ######################################################################
[08:38:35.309] <TB1> INFO: Expecting 41600 events.
[08:38:39.719] <TB1> INFO: 41600 events read in total (3695ms).
[08:38:39.720] <TB1> INFO: Test took 4715ms.
[08:38:39.726] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:40.390] <TB1> INFO: Expecting 41600 events.
[08:38:44.674] <TB1> INFO: 41600 events read in total (3569ms).
[08:38:44.674] <TB1> INFO: Test took 4592ms.
[08:38:44.680] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:45.124] <TB1> INFO: Expecting 41600 events.
[08:38:49.305] <TB1> INFO: 41600 events read in total (3467ms).
[08:38:49.306] <TB1> INFO: Test took 4506ms.
[08:38:49.312] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:49.758] <TB1> INFO: Expecting 2560 events.
[08:38:50.745] <TB1> INFO: 2560 events read in total (272ms).
[08:38:50.745] <TB1> INFO: Test took 1427ms.
[08:38:51.252] <TB1> INFO: Expecting 2560 events.
[08:38:52.210] <TB1> INFO: 2560 events read in total (243ms).
[08:38:52.210] <TB1> INFO: Test took 1464ms.
[08:38:52.717] <TB1> INFO: Expecting 2560 events.
[08:38:53.688] <TB1> INFO: 2560 events read in total (256ms).
[08:38:53.688] <TB1> INFO: Test took 1477ms.
[08:38:54.196] <TB1> INFO: Expecting 2560 events.
[08:38:55.152] <TB1> INFO: 2560 events read in total (241ms).
[08:38:55.152] <TB1> INFO: Test took 1463ms.
[08:38:55.660] <TB1> INFO: Expecting 2560 events.
[08:38:56.617] <TB1> INFO: 2560 events read in total (242ms).
[08:38:56.617] <TB1> INFO: Test took 1464ms.
[08:38:57.124] <TB1> INFO: Expecting 2560 events.
[08:38:58.096] <TB1> INFO: 2560 events read in total (257ms).
[08:38:58.096] <TB1> INFO: Test took 1479ms.
[08:38:58.604] <TB1> INFO: Expecting 2560 events.
[08:38:59.560] <TB1> INFO: 2560 events read in total (241ms).
[08:38:59.560] <TB1> INFO: Test took 1464ms.
[08:39:00.068] <TB1> INFO: Expecting 2560 events.
[08:39:01.025] <TB1> INFO: 2560 events read in total (242ms).
[08:39:01.025] <TB1> INFO: Test took 1464ms.
[08:39:01.532] <TB1> INFO: Expecting 2560 events.
[08:39:02.503] <TB1> INFO: 2560 events read in total (256ms).
[08:39:02.503] <TB1> INFO: Test took 1478ms.
[08:39:03.012] <TB1> INFO: Expecting 2560 events.
[08:39:03.997] <TB1> INFO: 2560 events read in total (270ms).
[08:39:03.998] <TB1> INFO: Test took 1494ms.
[08:39:04.505] <TB1> INFO: Expecting 2560 events.
[08:39:05.461] <TB1> INFO: 2560 events read in total (241ms).
[08:39:05.461] <TB1> INFO: Test took 1463ms.
[08:39:05.970] <TB1> INFO: Expecting 2560 events.
[08:39:06.928] <TB1> INFO: 2560 events read in total (244ms).
[08:39:06.928] <TB1> INFO: Test took 1466ms.
[08:39:07.435] <TB1> INFO: Expecting 2560 events.
[08:39:08.405] <TB1> INFO: 2560 events read in total (256ms).
[08:39:08.406] <TB1> INFO: Test took 1478ms.
[08:39:08.913] <TB1> INFO: Expecting 2560 events.
[08:39:09.870] <TB1> INFO: 2560 events read in total (242ms).
[08:39:09.870] <TB1> INFO: Test took 1464ms.
[08:39:10.378] <TB1> INFO: Expecting 2560 events.
[08:39:11.356] <TB1> INFO: 2560 events read in total (263ms).
[08:39:11.356] <TB1> INFO: Test took 1485ms.
[08:39:11.864] <TB1> INFO: Expecting 2560 events.
[08:39:12.839] <TB1> INFO: 2560 events read in total (260ms).
[08:39:12.839] <TB1> INFO: Test took 1483ms.
[08:39:12.841] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:13.349] <TB1> INFO: Expecting 655360 events.
[08:39:27.423] <TB1> INFO: 655360 events read in total (13359ms).
[08:39:27.434] <TB1> INFO: Expecting 655360 events.
[08:39:40.366] <TB1> INFO: 655360 events read in total (12344ms).
[08:39:40.379] <TB1> INFO: Expecting 655360 events.
[08:39:53.200] <TB1> INFO: 655360 events read in total (12231ms).
[08:39:53.216] <TB1> INFO: Expecting 655360 events.
[08:40:06.370] <TB1> INFO: 655360 events read in total (12560ms).
[08:40:06.389] <TB1> INFO: Expecting 655360 events.
[08:40:19.436] <TB1> INFO: 655360 events read in total (12467ms).
[08:40:19.462] <TB1> INFO: Expecting 655360 events.
[08:40:32.498] <TB1> INFO: 655360 events read in total (12467ms).
[08:40:32.524] <TB1> INFO: Expecting 655360 events.
[08:40:44.709] <TB1> INFO: 655360 events read in total (11603ms).
[08:40:44.739] <TB1> INFO: Expecting 655360 events.
[08:40:57.673] <TB1> INFO: 655360 events read in total (12354ms).
[08:40:57.705] <TB1> INFO: Expecting 655360 events.
[08:41:10.534] <TB1> INFO: 655360 events read in total (12248ms).
[08:41:10.584] <TB1> INFO: Expecting 655360 events.
[08:41:23.620] <TB1> INFO: 655360 events read in total (12509ms).
[08:41:23.675] <TB1> INFO: Expecting 655360 events.
[08:41:36.841] <TB1> INFO: 655360 events read in total (12629ms).
[08:41:36.891] <TB1> INFO: Expecting 655360 events.
[08:41:49.627] <TB1> INFO: 655360 events read in total (12177ms).
[08:41:49.675] <TB1> INFO: Expecting 655360 events.
[08:42:02.386] <TB1> INFO: 655360 events read in total (12159ms).
[08:42:02.435] <TB1> INFO: Expecting 655360 events.
[08:42:15.054] <TB1> INFO: 655360 events read in total (12054ms).
[08:42:15.108] <TB1> INFO: Expecting 655360 events.
[08:42:27.702] <TB1> INFO: 655360 events read in total (12038ms).
[08:42:27.787] <TB1> INFO: Expecting 655360 events.
[08:42:40.236] <TB1> INFO: 655360 events read in total (11923ms).
[08:42:40.309] <TB1> INFO: Test took 207468ms.
[08:42:40.405] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:40.712] <TB1> INFO: Expecting 655360 events.
[08:42:53.956] <TB1> INFO: 655360 events read in total (12529ms).
[08:42:53.964] <TB1> INFO: Expecting 655360 events.
[08:43:07.286] <TB1> INFO: 655360 events read in total (12714ms).
[08:43:07.300] <TB1> INFO: Expecting 655360 events.
[08:43:20.387] <TB1> INFO: 655360 events read in total (12492ms).
[08:43:20.403] <TB1> INFO: Expecting 655360 events.
[08:43:33.508] <TB1> INFO: 655360 events read in total (12512ms).
[08:43:33.531] <TB1> INFO: Expecting 655360 events.
[08:43:46.821] <TB1> INFO: 655360 events read in total (12720ms).
[08:43:46.844] <TB1> INFO: Expecting 655360 events.
[08:43:59.749] <TB1> INFO: 655360 events read in total (12328ms).
[08:43:59.775] <TB1> INFO: Expecting 655360 events.
[08:44:12.691] <TB1> INFO: 655360 events read in total (12329ms).
[08:44:12.721] <TB1> INFO: Expecting 655360 events.
[08:44:25.968] <TB1> INFO: 655360 events read in total (12660ms).
[08:44:26.008] <TB1> INFO: Expecting 655360 events.
[08:44:38.757] <TB1> INFO: 655360 events read in total (12199ms).
[08:44:38.798] <TB1> INFO: Expecting 655360 events.
[08:44:51.649] <TB1> INFO: 655360 events read in total (12292ms).
[08:44:51.690] <TB1> INFO: Expecting 655360 events.
[08:45:04.659] <TB1> INFO: 655360 events read in total (12395ms).
[08:45:04.702] <TB1> INFO: Expecting 655360 events.
[08:45:17.840] <TB1> INFO: 655360 events read in total (12579ms).
[08:45:17.887] <TB1> INFO: Expecting 655360 events.
[08:45:30.899] <TB1> INFO: 655360 events read in total (12446ms).
[08:45:30.959] <TB1> INFO: Expecting 655360 events.
[08:45:44.192] <TB1> INFO: 655360 events read in total (12688ms).
[08:45:44.250] <TB1> INFO: Expecting 655360 events.
[08:45:57.248] <TB1> INFO: 655360 events read in total (12455ms).
[08:45:57.307] <TB1> INFO: Expecting 655360 events.
[08:46:10.441] <TB1> INFO: 655360 events read in total (12583ms).
[08:46:10.504] <TB1> INFO: Test took 210099ms.
[08:46:10.704] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.711] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.718] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.725] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.732] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.739] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.745] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.752] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.759] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.766] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.772] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.779] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.786] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.793] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.800] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.807] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[08:46:10.814] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:46:10.848] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:46:10.849] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:46:10.849] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:46:10.849] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:46:10.849] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:46:10.849] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:46:10.849] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:46:10.849] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:46:11.163] <TB1> INFO: Expecting 41600 events.
[08:46:15.145] <TB1> INFO: 41600 events read in total (3267ms).
[08:46:15.145] <TB1> INFO: Test took 4294ms.
[08:46:15.803] <TB1> INFO: Expecting 41600 events.
[08:46:19.764] <TB1> INFO: 41600 events read in total (3246ms).
[08:46:19.765] <TB1> INFO: Test took 4292ms.
[08:46:20.422] <TB1> INFO: Expecting 41600 events.
[08:46:24.547] <TB1> INFO: 41600 events read in total (3410ms).
[08:46:24.547] <TB1> INFO: Test took 4455ms.
[08:46:24.884] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:25.015] <TB1> INFO: Expecting 2560 events.
[08:46:25.990] <TB1> INFO: 2560 events read in total (260ms).
[08:46:25.991] <TB1> INFO: Test took 1107ms.
[08:46:25.993] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:26.499] <TB1> INFO: Expecting 2560 events.
[08:46:27.461] <TB1> INFO: 2560 events read in total (247ms).
[08:46:27.462] <TB1> INFO: Test took 1469ms.
[08:46:27.464] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:27.969] <TB1> INFO: Expecting 2560 events.
[08:46:28.944] <TB1> INFO: 2560 events read in total (260ms).
[08:46:28.944] <TB1> INFO: Test took 1480ms.
[08:46:28.945] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:29.454] <TB1> INFO: Expecting 2560 events.
[08:46:30.416] <TB1> INFO: 2560 events read in total (247ms).
[08:46:30.417] <TB1> INFO: Test took 1472ms.
[08:46:30.420] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:30.926] <TB1> INFO: Expecting 2560 events.
[08:46:31.902] <TB1> INFO: 2560 events read in total (261ms).
[08:46:31.902] <TB1> INFO: Test took 1482ms.
[08:46:31.905] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:32.411] <TB1> INFO: Expecting 2560 events.
[08:46:33.369] <TB1> INFO: 2560 events read in total (243ms).
[08:46:33.370] <TB1> INFO: Test took 1465ms.
[08:46:33.372] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:33.877] <TB1> INFO: Expecting 2560 events.
[08:46:34.839] <TB1> INFO: 2560 events read in total (247ms).
[08:46:34.839] <TB1> INFO: Test took 1467ms.
[08:46:34.842] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:35.348] <TB1> INFO: Expecting 2560 events.
[08:46:36.324] <TB1> INFO: 2560 events read in total (261ms).
[08:46:36.325] <TB1> INFO: Test took 1483ms.
[08:46:36.327] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:36.833] <TB1> INFO: Expecting 2560 events.
[08:46:37.797] <TB1> INFO: 2560 events read in total (249ms).
[08:46:37.797] <TB1> INFO: Test took 1470ms.
[08:46:37.800] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:38.306] <TB1> INFO: Expecting 2560 events.
[08:46:39.282] <TB1> INFO: 2560 events read in total (261ms).
[08:46:39.282] <TB1> INFO: Test took 1482ms.
[08:46:39.285] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:39.791] <TB1> INFO: Expecting 2560 events.
[08:46:40.755] <TB1> INFO: 2560 events read in total (249ms).
[08:46:40.755] <TB1> INFO: Test took 1470ms.
[08:46:40.757] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:41.263] <TB1> INFO: Expecting 2560 events.
[08:46:42.238] <TB1> INFO: 2560 events read in total (260ms).
[08:46:42.238] <TB1> INFO: Test took 1481ms.
[08:46:42.240] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:42.747] <TB1> INFO: Expecting 2560 events.
[08:46:43.722] <TB1> INFO: 2560 events read in total (260ms).
[08:46:43.723] <TB1> INFO: Test took 1483ms.
[08:46:43.724] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:44.233] <TB1> INFO: Expecting 2560 events.
[08:46:45.193] <TB1> INFO: 2560 events read in total (245ms).
[08:46:45.194] <TB1> INFO: Test took 1470ms.
[08:46:45.195] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:45.703] <TB1> INFO: Expecting 2560 events.
[08:46:46.666] <TB1> INFO: 2560 events read in total (248ms).
[08:46:46.666] <TB1> INFO: Test took 1471ms.
[08:46:46.668] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:47.175] <TB1> INFO: Expecting 2560 events.
[08:46:48.152] <TB1> INFO: 2560 events read in total (262ms).
[08:46:48.152] <TB1> INFO: Test took 1484ms.
[08:46:48.153] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:48.661] <TB1> INFO: Expecting 2560 events.
[08:46:49.638] <TB1> INFO: 2560 events read in total (262ms).
[08:46:49.639] <TB1> INFO: Test took 1486ms.
[08:46:49.641] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:50.147] <TB1> INFO: Expecting 2560 events.
[08:46:51.136] <TB1> INFO: 2560 events read in total (274ms).
[08:46:51.136] <TB1> INFO: Test took 1495ms.
[08:46:51.138] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:51.645] <TB1> INFO: Expecting 2560 events.
[08:46:52.603] <TB1> INFO: 2560 events read in total (243ms).
[08:46:52.603] <TB1> INFO: Test took 1465ms.
[08:46:52.605] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:53.113] <TB1> INFO: Expecting 2560 events.
[08:46:54.077] <TB1> INFO: 2560 events read in total (249ms).
[08:46:54.078] <TB1> INFO: Test took 1473ms.
[08:46:54.080] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:54.586] <TB1> INFO: Expecting 2560 events.
[08:46:55.563] <TB1> INFO: 2560 events read in total (262ms).
[08:46:55.563] <TB1> INFO: Test took 1484ms.
[08:46:55.565] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:56.071] <TB1> INFO: Expecting 2560 events.
[08:46:57.048] <TB1> INFO: 2560 events read in total (262ms).
[08:46:57.049] <TB1> INFO: Test took 1484ms.
[08:46:57.052] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:57.557] <TB1> INFO: Expecting 2560 events.
[08:46:58.521] <TB1> INFO: 2560 events read in total (249ms).
[08:46:58.521] <TB1> INFO: Test took 1469ms.
[08:46:58.524] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:59.030] <TB1> INFO: Expecting 2560 events.
[08:46:59.993] <TB1> INFO: 2560 events read in total (248ms).
[08:46:59.993] <TB1> INFO: Test took 1470ms.
[08:46:59.996] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:00.502] <TB1> INFO: Expecting 2560 events.
[08:47:01.464] <TB1> INFO: 2560 events read in total (247ms).
[08:47:01.465] <TB1> INFO: Test took 1469ms.
[08:47:01.466] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:01.974] <TB1> INFO: Expecting 2560 events.
[08:47:02.965] <TB1> INFO: 2560 events read in total (276ms).
[08:47:02.965] <TB1> INFO: Test took 1500ms.
[08:47:02.967] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:03.474] <TB1> INFO: Expecting 2560 events.
[08:47:04.434] <TB1> INFO: 2560 events read in total (245ms).
[08:47:04.435] <TB1> INFO: Test took 1468ms.
[08:47:04.437] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:04.944] <TB1> INFO: Expecting 2560 events.
[08:47:05.931] <TB1> INFO: 2560 events read in total (273ms).
[08:47:05.931] <TB1> INFO: Test took 1494ms.
[08:47:05.934] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:06.441] <TB1> INFO: Expecting 2560 events.
[08:47:07.403] <TB1> INFO: 2560 events read in total (247ms).
[08:47:07.403] <TB1> INFO: Test took 1470ms.
[08:47:07.406] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:07.912] <TB1> INFO: Expecting 2560 events.
[08:47:08.875] <TB1> INFO: 2560 events read in total (248ms).
[08:47:08.875] <TB1> INFO: Test took 1470ms.
[08:47:08.878] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:09.385] <TB1> INFO: Expecting 2560 events.
[08:47:10.342] <TB1> INFO: 2560 events read in total (243ms).
[08:47:10.342] <TB1> INFO: Test took 1465ms.
[08:47:10.344] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:10.852] <TB1> INFO: Expecting 2560 events.
[08:47:11.813] <TB1> INFO: 2560 events read in total (246ms).
[08:47:11.814] <TB1> INFO: Test took 1470ms.
[08:47:12.558] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 517 seconds
[08:47:12.558] <TB1> INFO: PH scale (per ROC): 71 75 77 85 85 83 66 86 75 72 79 69 77 75 80 83
[08:47:12.558] <TB1> INFO: PH offset (per ROC): 167 155 157 150 164 156 161 140 161 174 167 163 153 167 159 157
[08:47:12.565] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:47:12.566] <TB1> INFO: Decoding statistics:
[08:47:12.566] <TB1> INFO: General information:
[08:47:12.566] <TB1> INFO: 16bit words read: 66442
[08:47:12.566] <TB1> INFO: valid events total: 5120
[08:47:12.566] <TB1> INFO: empty events: 2619
[08:47:12.566] <TB1> INFO: valid events with pixels: 2501
[08:47:12.566] <TB1> INFO: valid pixel hits: 2501
[08:47:12.566] <TB1> INFO: Event errors: 0
[08:47:12.566] <TB1> INFO: start marker: 0
[08:47:12.566] <TB1> INFO: stop marker: 0
[08:47:12.566] <TB1> INFO: overflow: 0
[08:47:12.566] <TB1> INFO: invalid 5bit words: 0
[08:47:12.566] <TB1> INFO: invalid XOR eye diagram: 0
[08:47:12.566] <TB1> INFO: TBM errors: 0
[08:47:12.566] <TB1> INFO: flawed TBM headers: 0
[08:47:12.566] <TB1> INFO: flawed TBM trailers: 0
[08:47:12.566] <TB1> INFO: event ID mismatches: 0
[08:47:12.566] <TB1> INFO: ROC errors: 0
[08:47:12.566] <TB1> INFO: missing ROC header(s): 0
[08:47:12.566] <TB1> INFO: misplaced readback start: 0
[08:47:12.566] <TB1> INFO: Pixel decoding errors: 0
[08:47:12.566] <TB1> INFO: pixel data incomplete: 0
[08:47:12.566] <TB1> INFO: pixel address: 0
[08:47:12.566] <TB1> INFO: pulse height fill bit: 0
[08:47:12.566] <TB1> INFO: buffer corruption: 0
[08:47:12.733] <TB1> INFO: ######################################################################
[08:47:12.733] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:47:12.734] <TB1> INFO: ######################################################################
[08:47:12.743] <TB1> INFO: scanning low vcal = 10
[08:47:13.055] <TB1> INFO: Expecting 41600 events.
[08:47:16.836] <TB1> INFO: 41600 events read in total (3066ms).
[08:47:16.836] <TB1> INFO: Test took 4093ms.
[08:47:16.838] <TB1> INFO: scanning low vcal = 20
[08:47:17.345] <TB1> INFO: Expecting 41600 events.
[08:47:21.163] <TB1> INFO: 41600 events read in total (3103ms).
[08:47:21.164] <TB1> INFO: Test took 4326ms.
[08:47:21.165] <TB1> INFO: scanning low vcal = 30
[08:47:21.672] <TB1> INFO: Expecting 41600 events.
[08:47:25.477] <TB1> INFO: 41600 events read in total (3091ms).
[08:47:25.478] <TB1> INFO: Test took 4313ms.
[08:47:25.479] <TB1> INFO: scanning low vcal = 40
[08:47:25.979] <TB1> INFO: Expecting 41600 events.
[08:47:30.373] <TB1> INFO: 41600 events read in total (3679ms).
[08:47:30.374] <TB1> INFO: Test took 4895ms.
[08:47:30.377] <TB1> INFO: scanning low vcal = 50
[08:47:30.818] <TB1> INFO: Expecting 41600 events.
[08:47:35.267] <TB1> INFO: 41600 events read in total (3734ms).
[08:47:35.267] <TB1> INFO: Test took 4890ms.
[08:47:35.271] <TB1> INFO: scanning low vcal = 60
[08:47:35.720] <TB1> INFO: Expecting 41600 events.
[08:47:40.006] <TB1> INFO: 41600 events read in total (3572ms).
[08:47:40.007] <TB1> INFO: Test took 4736ms.
[08:47:40.009] <TB1> INFO: scanning low vcal = 70
[08:47:40.458] <TB1> INFO: Expecting 41600 events.
[08:47:44.895] <TB1> INFO: 41600 events read in total (3723ms).
[08:47:44.896] <TB1> INFO: Test took 4886ms.
[08:47:44.898] <TB1> INFO: scanning low vcal = 80
[08:47:45.342] <TB1> INFO: Expecting 41600 events.
[08:47:49.678] <TB1> INFO: 41600 events read in total (3621ms).
[08:47:49.678] <TB1> INFO: Test took 4780ms.
[08:47:49.681] <TB1> INFO: scanning low vcal = 90
[08:47:50.131] <TB1> INFO: Expecting 41600 events.
[08:47:54.528] <TB1> INFO: 41600 events read in total (3682ms).
[08:47:54.529] <TB1> INFO: Test took 4848ms.
[08:47:54.532] <TB1> INFO: scanning low vcal = 100
[08:47:54.971] <TB1> INFO: Expecting 41600 events.
[08:47:59.673] <TB1> INFO: 41600 events read in total (3987ms).
[08:47:59.673] <TB1> INFO: Test took 5141ms.
[08:47:59.676] <TB1> INFO: scanning low vcal = 110
[08:48:00.120] <TB1> INFO: Expecting 41600 events.
[08:48:04.596] <TB1> INFO: 41600 events read in total (3761ms).
[08:48:04.596] <TB1> INFO: Test took 4920ms.
[08:48:04.599] <TB1> INFO: scanning low vcal = 120
[08:48:05.041] <TB1> INFO: Expecting 41600 events.
[08:48:09.419] <TB1> INFO: 41600 events read in total (3663ms).
[08:48:09.419] <TB1> INFO: Test took 4820ms.
[08:48:09.422] <TB1> INFO: scanning low vcal = 130
[08:48:09.864] <TB1> INFO: Expecting 41600 events.
[08:48:14.422] <TB1> INFO: 41600 events read in total (3843ms).
[08:48:14.422] <TB1> INFO: Test took 5000ms.
[08:48:14.425] <TB1> INFO: scanning low vcal = 140
[08:48:14.848] <TB1> INFO: Expecting 41600 events.
[08:48:19.389] <TB1> INFO: 41600 events read in total (3826ms).
[08:48:19.390] <TB1> INFO: Test took 4965ms.
[08:48:19.392] <TB1> INFO: scanning low vcal = 150
[08:48:19.841] <TB1> INFO: Expecting 41600 events.
[08:48:24.378] <TB1> INFO: 41600 events read in total (3822ms).
[08:48:24.379] <TB1> INFO: Test took 4987ms.
[08:48:24.381] <TB1> INFO: scanning low vcal = 160
[08:48:24.827] <TB1> INFO: Expecting 41600 events.
[08:48:29.273] <TB1> INFO: 41600 events read in total (3731ms).
[08:48:29.274] <TB1> INFO: Test took 4893ms.
[08:48:29.277] <TB1> INFO: scanning low vcal = 170
[08:48:29.723] <TB1> INFO: Expecting 41600 events.
[08:48:34.232] <TB1> INFO: 41600 events read in total (3794ms).
[08:48:34.232] <TB1> INFO: Test took 4955ms.
[08:48:34.236] <TB1> INFO: scanning low vcal = 180
[08:48:34.671] <TB1> INFO: Expecting 41600 events.
[08:48:39.159] <TB1> INFO: 41600 events read in total (3773ms).
[08:48:39.160] <TB1> INFO: Test took 4924ms.
[08:48:39.162] <TB1> INFO: scanning low vcal = 190
[08:48:39.609] <TB1> INFO: Expecting 41600 events.
[08:48:44.130] <TB1> INFO: 41600 events read in total (3806ms).
[08:48:44.131] <TB1> INFO: Test took 4969ms.
[08:48:44.133] <TB1> INFO: scanning low vcal = 200
[08:48:44.557] <TB1> INFO: Expecting 41600 events.
[08:48:49.047] <TB1> INFO: 41600 events read in total (3775ms).
[08:48:49.047] <TB1> INFO: Test took 4914ms.
[08:48:49.050] <TB1> INFO: scanning low vcal = 210
[08:48:49.499] <TB1> INFO: Expecting 41600 events.
[08:48:53.973] <TB1> INFO: 41600 events read in total (3759ms).
[08:48:53.973] <TB1> INFO: Test took 4923ms.
[08:48:53.975] <TB1> INFO: scanning low vcal = 220
[08:48:54.424] <TB1> INFO: Expecting 41600 events.
[08:48:58.845] <TB1> INFO: 41600 events read in total (3706ms).
[08:48:58.845] <TB1> INFO: Test took 4869ms.
[08:48:58.848] <TB1> INFO: scanning low vcal = 230
[08:48:59.293] <TB1> INFO: Expecting 41600 events.
[08:49:03.755] <TB1> INFO: 41600 events read in total (3747ms).
[08:49:03.756] <TB1> INFO: Test took 4908ms.
[08:49:03.759] <TB1> INFO: scanning low vcal = 240
[08:49:04.200] <TB1> INFO: Expecting 41600 events.
[08:49:08.641] <TB1> INFO: 41600 events read in total (3726ms).
[08:49:08.642] <TB1> INFO: Test took 4883ms.
[08:49:08.644] <TB1> INFO: scanning low vcal = 250
[08:49:09.093] <TB1> INFO: Expecting 41600 events.
[08:49:13.569] <TB1> INFO: 41600 events read in total (3761ms).
[08:49:13.570] <TB1> INFO: Test took 4926ms.
[08:49:13.573] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[08:49:14.013] <TB1> INFO: Expecting 41600 events.
[08:49:18.538] <TB1> INFO: 41600 events read in total (3810ms).
[08:49:18.539] <TB1> INFO: Test took 4966ms.
[08:49:18.541] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[08:49:18.981] <TB1> INFO: Expecting 41600 events.
[08:49:23.503] <TB1> INFO: 41600 events read in total (3807ms).
[08:49:23.504] <TB1> INFO: Test took 4963ms.
[08:49:23.506] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[08:49:23.931] <TB1> INFO: Expecting 41600 events.
[08:49:28.441] <TB1> INFO: 41600 events read in total (3795ms).
[08:49:28.442] <TB1> INFO: Test took 4936ms.
[08:49:28.444] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[08:49:28.893] <TB1> INFO: Expecting 41600 events.
[08:49:33.333] <TB1> INFO: 41600 events read in total (3725ms).
[08:49:33.333] <TB1> INFO: Test took 4889ms.
[08:49:33.336] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:49:33.779] <TB1> INFO: Expecting 41600 events.
[08:49:38.312] <TB1> INFO: 41600 events read in total (3818ms).
[08:49:38.313] <TB1> INFO: Test took 4977ms.
[08:49:38.772] <TB1> INFO: PixTestGainPedestal::measure() done
[08:50:12.970] <TB1> INFO: PixTestGainPedestal::fit() done
[08:50:12.970] <TB1> INFO: non-linearity mean: 0.954 0.960 0.958 0.954 0.956 0.952 0.954 0.955 0.957 0.958 0.956 0.955 0.960 0.955 0.954 0.958
[08:50:12.970] <TB1> INFO: non-linearity RMS: 0.005 0.006 0.006 0.006 0.007 0.007 0.006 0.005 0.005 0.006 0.007 0.006 0.005 0.007 0.006 0.005
[08:50:12.971] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:50:12.989] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:50:13.007] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:50:13.026] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:50:13.044] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:50:13.062] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:50:13.080] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:50:13.099] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:50:13.117] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:50:13.135] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:50:13.153] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:50:13.172] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:50:13.190] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:50:13.208] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:50:13.226] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:50:13.244] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:50:13.263] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 180 seconds
[08:50:13.263] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:50:13.263] <TB1> INFO: Decoding statistics:
[08:50:13.263] <TB1> INFO: General information:
[08:50:13.263] <TB1> INFO: 16bit words read: 2329554
[08:50:13.263] <TB1> INFO: valid events total: 83200
[08:50:13.263] <TB1> INFO: empty events: 0
[08:50:13.263] <TB1> INFO: valid events with pixels: 83200
[08:50:13.263] <TB1> INFO: valid pixel hits: 665577
[08:50:13.263] <TB1> INFO: Event errors: 0
[08:50:13.263] <TB1> INFO: start marker: 0
[08:50:13.263] <TB1> INFO: stop marker: 0
[08:50:13.263] <TB1> INFO: overflow: 0
[08:50:13.263] <TB1> INFO: invalid 5bit words: 0
[08:50:13.263] <TB1> INFO: invalid XOR eye diagram: 0
[08:50:13.263] <TB1> INFO: TBM errors: 0
[08:50:13.263] <TB1> INFO: flawed TBM headers: 0
[08:50:13.263] <TB1> INFO: flawed TBM trailers: 0
[08:50:13.263] <TB1> INFO: event ID mismatches: 0
[08:50:13.263] <TB1> INFO: ROC errors: 0
[08:50:13.263] <TB1> INFO: missing ROC header(s): 0
[08:50:13.263] <TB1> INFO: misplaced readback start: 0
[08:50:13.263] <TB1> INFO: Pixel decoding errors: 0
[08:50:13.263] <TB1> INFO: pixel data incomplete: 0
[08:50:13.263] <TB1> INFO: pixel address: 0
[08:50:13.263] <TB1> INFO: pulse height fill bit: 0
[08:50:13.263] <TB1> INFO: buffer corruption: 0
[08:50:13.270] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:13.271] <TB1> INFO: ######################################################################
[08:50:13.271] <TB1> INFO: PixTestReadback::doTest()
[08:50:13.271] <TB1> INFO: ######################################################################
[08:50:13.272] <TB1> INFO: PixTestReadback::RES sent once
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:25.220] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:25.221] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:25.221] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:25.221] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:25.221] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:25.221] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:25.245] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:50:25.246] <TB1> INFO: PixTestReadback::RES sent once
[08:50:36.399] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:36.399] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:36.399] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:36.399] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:36.399] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:36.399] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:36.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:36.429] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:50:36.429] <TB1> INFO: PixTestReadback::RES sent once
[08:50:45.004] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:50:45.004] <TB1> INFO: Vbg will be calibrated using Vd calibration
[08:50:45.004] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.5calibrated Vbg = 1.22309 :::*/*/*/*/
[08:50:45.004] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.1calibrated Vbg = 1.22171 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148calibrated Vbg = 1.23205 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.3calibrated Vbg = 1.22744 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.7calibrated Vbg = 1.24036 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.6calibrated Vbg = 1.23842 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.6calibrated Vbg = 1.23708 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151.1calibrated Vbg = 1.24245 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.2calibrated Vbg = 1.23946 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.8calibrated Vbg = 1.24109 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.1calibrated Vbg = 1.24309 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 162calibrated Vbg = 1.23306 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.4calibrated Vbg = 1.2249 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 161.7calibrated Vbg = 1.21477 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.2calibrated Vbg = 1.22084 :::*/*/*/*/
[08:50:45.005] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.5calibrated Vbg = 1.22024 :::*/*/*/*/
[08:50:45.007] <TB1> INFO: PixTestReadback::RES sent once
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat
[08:53:38.950] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat
[08:53:38.951] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat
[08:53:38.951] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4560_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat
[08:53:38.975] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:53:38.976] <TB1> INFO: PixTestReadback::doTest() done
[08:53:38.976] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:38.976] <TB1> INFO: Decoding statistics:
[08:53:38.976] <TB1> INFO: General information:
[08:53:38.976] <TB1> INFO: 16bit words read: 768
[08:53:38.976] <TB1> INFO: valid events total: 64
[08:53:38.976] <TB1> INFO: empty events: 64
[08:53:38.976] <TB1> INFO: valid events with pixels: 0
[08:53:38.976] <TB1> INFO: valid pixel hits: 0
[08:53:38.976] <TB1> INFO: Event errors: 0
[08:53:38.976] <TB1> INFO: start marker: 0
[08:53:38.976] <TB1> INFO: stop marker: 0
[08:53:38.976] <TB1> INFO: overflow: 0
[08:53:38.976] <TB1> INFO: invalid 5bit words: 0
[08:53:38.976] <TB1> INFO: invalid XOR eye diagram: 0
[08:53:38.976] <TB1> INFO: TBM errors: 0
[08:53:38.976] <TB1> INFO: flawed TBM headers: 0
[08:53:38.977] <TB1> INFO: flawed TBM trailers: 0
[08:53:38.977] <TB1> INFO: event ID mismatches: 0
[08:53:38.977] <TB1> INFO: ROC errors: 0
[08:53:38.977] <TB1> INFO: missing ROC header(s): 0
[08:53:38.977] <TB1> INFO: misplaced readback start: 0
[08:53:38.977] <TB1> INFO: Pixel decoding errors: 0
[08:53:38.977] <TB1> INFO: pixel data incomplete: 0
[08:53:38.977] <TB1> INFO: pixel address: 0
[08:53:38.977] <TB1> INFO: pulse height fill bit: 0
[08:53:38.977] <TB1> INFO: buffer corruption: 0
[08:53:38.991] <TB1> INFO: Decoding statistics:
[08:53:38.991] <TB1> INFO: General information:
[08:53:38.991] <TB1> INFO: 16bit words read: 2396764
[08:53:38.991] <TB1> INFO: valid events total: 88384
[08:53:38.991] <TB1> INFO: empty events: 2683
[08:53:38.991] <TB1> INFO: valid events with pixels: 85701
[08:53:38.991] <TB1> INFO: valid pixel hits: 668078
[08:53:38.991] <TB1> INFO: Event errors: 0
[08:53:38.991] <TB1> INFO: start marker: 0
[08:53:38.991] <TB1> INFO: stop marker: 0
[08:53:38.991] <TB1> INFO: overflow: 0
[08:53:38.991] <TB1> INFO: invalid 5bit words: 0
[08:53:38.991] <TB1> INFO: invalid XOR eye diagram: 0
[08:53:38.991] <TB1> INFO: TBM errors: 0
[08:53:38.991] <TB1> INFO: flawed TBM headers: 0
[08:53:38.991] <TB1> INFO: flawed TBM trailers: 0
[08:53:38.991] <TB1> INFO: event ID mismatches: 0
[08:53:38.991] <TB1> INFO: ROC errors: 0
[08:53:38.991] <TB1> INFO: missing ROC header(s): 0
[08:53:38.991] <TB1> INFO: misplaced readback start: 0
[08:53:38.991] <TB1> INFO: Pixel decoding errors: 0
[08:53:38.991] <TB1> INFO: pixel data incomplete: 0
[08:53:38.991] <TB1> INFO: pixel address: 0
[08:53:38.991] <TB1> INFO: pulse height fill bit: 0
[08:53:38.991] <TB1> INFO: buffer corruption: 0
[08:53:38.991] <TB1> INFO: enter test to run
[08:53:38.991] <TB1> INFO: test: exit no parameter change
[08:53:39.223] <TB1> QUIET: Connection to board 154 closed.
[08:53:39.303] <TB1> INFO: pXar: this is the end, my friend