[16:25:15.581] <TB1> INFO: *** Welcome to pxar ***
[16:25:15.581] <TB1> INFO: *** Today: 2015/12/08
[16:25:15.668] <TB1> INFO: *** Version: 3197
[16:25:15.668] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C15.dat
[16:25:15.668] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//tbmParameters_C0b.dat
[16:25:15.669] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//defaultMaskFile.dat
[16:25:15.669] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters_C15.dat
[16:25:15.744] <TB1> INFO: clk: 4
[16:25:15.744] <TB1> INFO: ctr: 4
[16:25:15.744] <TB1> INFO: sda: 19
[16:25:15.744] <TB1> INFO: tin: 9
[16:25:15.744] <TB1> INFO: level: 15
[16:25:15.744] <TB1> INFO: triggerdelay: 0
[16:25:15.744] <TB1> QUIET: Instanciating API for pxar prod-12
[16:25:15.744] <TB1> INFO: Log level: INFO
[16:25:15.752] <TB1> INFO: Found DTB DTB_WXBYFL
[16:25:15.766] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[16:25:15.769] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[16:25:15.772] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[16:25:17.298] <TB1> INFO: DUT info:
[16:25:17.298] <TB1> INFO: The DUT currently contains the following objects:
[16:25:17.298] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[16:25:17.298] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:25:17.298] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:25:17.298] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:25:17.298] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.298] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.299] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:17.700] <TB1> INFO: enter 'restricted' command line mode
[16:25:17.700] <TB1> INFO: enter test to run
[16:25:17.700] <TB1> INFO: test: pretest no parameter change
[16:25:17.700] <TB1> INFO: running: pretest
[16:25:17.707] <TB1> INFO: ######################################################################
[16:25:17.707] <TB1> INFO: PixTestPretest::doTest()
[16:25:17.707] <TB1> INFO: ######################################################################
[16:25:17.709] <TB1> INFO: ----------------------------------------------------------------------
[16:25:17.709] <TB1> INFO: PixTestPretest::programROC()
[16:25:17.709] <TB1> INFO: ----------------------------------------------------------------------
[16:25:35.730] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:25:35.730] <TB1> INFO: IA differences per ROC: 17.7 17.7 20.9 19.3 18.5 17.7 20.1 19.3 17.7 18.5 19.3 17.7 21.7 20.1 20.1 19.3
[16:25:35.822] <TB1> INFO: ----------------------------------------------------------------------
[16:25:35.822] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:25:35.822] <TB1> INFO: ----------------------------------------------------------------------
[16:25:41.741] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 393.1 mA = 24.5688 mA/ROC
[16:25:41.742] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3
[16:25:41.788] <TB1> INFO: ----------------------------------------------------------------------
[16:25:41.788] <TB1> INFO: PixTestPretest::findTiming()
[16:25:41.788] <TB1> INFO: ----------------------------------------------------------------------
[16:25:41.789] <TB1> INFO: PixTestCmd::init()
[16:25:42.388] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[16:27:33.445] <TB1> INFO: TBM phases: 160MHz: 3, 400MHz: 2, TBM delays: ROC(0/1):0, header/trailer: 1, token: 0
[16:27:33.445] <TB1> INFO: (success/tries = 100/100), width = 1
[16:27:33.447] <TB1> INFO: ----------------------------------------------------------------------
[16:27:33.447] <TB1> INFO: PixTestPretest::findWorkingPixel()
[16:27:33.447] <TB1> INFO: ----------------------------------------------------------------------
[16:27:33.587] <TB1> INFO: Expecting 231680 events.
[16:27:38.198] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[16:27:38.201] <TB1> ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[16:27:41.145] <TB1> INFO: 231680 events read in total (6843ms).
[16:27:41.149] <TB1> INFO: Test took 7698ms.
[16:27:41.563] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[16:27:41.614] <TB1> INFO: ----------------------------------------------------------------------
[16:27:41.614] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[16:27:41.614] <TB1> INFO: ----------------------------------------------------------------------
[16:27:41.754] <TB1> INFO: Expecting 231680 events.
[16:27:50.301] <TB1> INFO: 231680 events read in total (7831ms).
[16:27:50.306] <TB1> INFO: Test took 8684ms.
[16:27:50.748] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[16:27:50.748] <TB1> INFO: CalDel: 108 105 131 127 138 117 126 109 130 121 111 126 139 143 141 117
[16:27:50.748] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[16:27:50.751] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C0.dat
[16:27:50.751] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C1.dat
[16:27:50.751] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C2.dat
[16:27:50.752] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C3.dat
[16:27:50.752] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C4.dat
[16:27:50.752] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C5.dat
[16:27:50.752] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C6.dat
[16:27:50.752] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C7.dat
[16:27:50.752] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C8.dat
[16:27:50.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C9.dat
[16:27:50.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C10.dat
[16:27:50.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C11.dat
[16:27:50.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C12.dat
[16:27:50.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C13.dat
[16:27:50.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C14.dat
[16:27:50.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters_C15.dat
[16:27:50.754] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//tbmParameters_C0a.dat
[16:27:50.754] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//tbmParameters_C0b.dat
[16:27:50.754] <TB1> INFO: PixTestPretest::doTest() done, duration: 153 seconds
[16:27:50.832] <TB1> INFO: enter test to run
[16:27:50.832] <TB1> INFO: test: fulltest no parameter change
[16:27:50.832] <TB1> INFO: running: fulltest
[16:27:50.832] <TB1> INFO: ######################################################################
[16:27:50.832] <TB1> INFO: PixTestFullTest::doTest()
[16:27:50.832] <TB1> INFO: ######################################################################
[16:27:50.834] <TB1> INFO: ######################################################################
[16:27:50.834] <TB1> INFO: PixTestAlive::doTest()
[16:27:50.834] <TB1> INFO: ######################################################################
[16:27:50.836] <TB1> INFO: ----------------------------------------------------------------------
[16:27:50.836] <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)
[16:27:50.836] <TB1> INFO: ----------------------------------------------------------------------
[16:27:51.155] <TB1> INFO: Expecting 41600 events.
[16:27:55.506] <TB1> INFO: 41600 events read in total (3636ms).
[16:27:55.507] <TB1> INFO: Test took 4669ms.
[16:27:55.514] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:55.907] <TB1> INFO: PixTestAlive::aliveTest() done
[16:27:55.907] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:27:55.911] <TB1> INFO: ----------------------------------------------------------------------
[16:27:55.911] <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)
[16:27:55.911] <TB1> INFO: ----------------------------------------------------------------------
[16:27:56.227] <TB1> INFO: Expecting 41600 events.
[16:27:59.359] <TB1> INFO: 41600 events read in total (2416ms).
[16:27:59.359] <TB1> INFO: Test took 3445ms.
[16:27:59.359] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:59.360] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:27:59.781] <TB1> INFO: PixTestAlive::maskTest() done
[16:27:59.781] <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
[16:27:59.782] <TB1> INFO: ----------------------------------------------------------------------
[16:27:59.782] <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)
[16:27:59.783] <TB1> INFO: ----------------------------------------------------------------------
[16:28:00.091] <TB1> INFO: Expecting 41600 events.
[16:28:04.617] <TB1> INFO: 41600 events read in total (3811ms).
[16:28:04.618] <TB1> INFO: Test took 4833ms.
[16:28:04.624] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:05.022] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[16:28:05.022] <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
[16:28:05.022] <TB1> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:28:05.022] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:05.022] <TB1> INFO: Decoding statistics:
[16:28:05.022] <TB1> INFO: General information:
[16:28:05.022] <TB1> INFO: 16bit words read: 0
[16:28:05.022] <TB1> INFO: valid events total: 0
[16:28:05.022] <TB1> INFO: empty events: 0
[16:28:05.022] <TB1> INFO: valid events with pixels: 0
[16:28:05.022] <TB1> INFO: valid pixel hits: 0
[16:28:05.022] <TB1> INFO: Event errors: 0
[16:28:05.022] <TB1> INFO: start marker: 0
[16:28:05.022] <TB1> INFO: stop marker: 0
[16:28:05.022] <TB1> INFO: overflow: 0
[16:28:05.022] <TB1> INFO: invalid 5bit words: 0
[16:28:05.022] <TB1> INFO: invalid XOR eye diagram: 0
[16:28:05.022] <TB1> INFO: TBM errors: 0
[16:28:05.022] <TB1> INFO: flawed TBM headers: 0
[16:28:05.022] <TB1> INFO: flawed TBM trailers: 0
[16:28:05.022] <TB1> INFO: event ID mismatches: 0
[16:28:05.023] <TB1> INFO: ROC errors: 0
[16:28:05.023] <TB1> INFO: missing ROC header(s): 0
[16:28:05.023] <TB1> INFO: misplaced readback start: 0
[16:28:05.023] <TB1> INFO: Pixel decoding errors: 0
[16:28:05.023] <TB1> INFO: pixel data incomplete: 0
[16:28:05.023] <TB1> INFO: pixel address: 0
[16:28:05.023] <TB1> INFO: pulse height fill bit: 0
[16:28:05.023] <TB1> INFO: buffer corruption: 0
[16:28:05.032] <TB1> INFO: ######################################################################
[16:28:05.032] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:28:05.032] <TB1> INFO: ######################################################################
[16:28:05.038] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[16:28:05.052] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:28:05.052] <TB1> INFO: run 1 of 1
[16:28:05.391] <TB1> INFO: Expecting 3120000 events.
[16:28:56.236] <TB1> INFO: 1201355 events read in total (50129ms).
[16:29:45.356] <TB1> INFO: 2374605 events read in total (99249ms).
[16:30:15.655] <TB1> INFO: 3120000 events read in total (129548ms).
[16:30:15.701] <TB1> INFO: Test took 130650ms.
[16:30:15.784] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:41.882] <TB1> INFO: PixTestBBMap::doTest() done, duration: 156 seconds
[16:30:41.882] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[16:30:41.882] <TB1> INFO: separation cut (per ROC): 135 129 129 127 124 129 117 136 111 108 123 104 124 119 112 102
[16:30:41.882] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:41.882] <TB1> INFO: Decoding statistics:
[16:30:41.882] <TB1> INFO: General information:
[16:30:41.882] <TB1> INFO: 16bit words read: 0
[16:30:41.882] <TB1> INFO: valid events total: 0
[16:30:41.882] <TB1> INFO: empty events: 0
[16:30:41.882] <TB1> INFO: valid events with pixels: 0
[16:30:41.882] <TB1> INFO: valid pixel hits: 0
[16:30:41.882] <TB1> INFO: Event errors: 0
[16:30:41.882] <TB1> INFO: start marker: 0
[16:30:41.882] <TB1> INFO: stop marker: 0
[16:30:41.882] <TB1> INFO: overflow: 0
[16:30:41.882] <TB1> INFO: invalid 5bit words: 0
[16:30:41.882] <TB1> INFO: invalid XOR eye diagram: 0
[16:30:41.882] <TB1> INFO: TBM errors: 0
[16:30:41.882] <TB1> INFO: flawed TBM headers: 0
[16:30:41.882] <TB1> INFO: flawed TBM trailers: 0
[16:30:41.882] <TB1> INFO: event ID mismatches: 0
[16:30:41.882] <TB1> INFO: ROC errors: 0
[16:30:41.882] <TB1> INFO: missing ROC header(s): 0
[16:30:41.882] <TB1> INFO: misplaced readback start: 0
[16:30:41.882] <TB1> INFO: Pixel decoding errors: 0
[16:30:41.882] <TB1> INFO: pixel data incomplete: 0
[16:30:41.882] <TB1> INFO: pixel address: 0
[16:30:41.882] <TB1> INFO: pulse height fill bit: 0
[16:30:41.882] <TB1> INFO: buffer corruption: 0
[16:30:41.968] <TB1> INFO: ######################################################################
[16:30:41.968] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:30:41.968] <TB1> INFO: ######################################################################
[16:30:41.968] <TB1> INFO: ----------------------------------------------------------------------
[16:30:41.968] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:30:41.968] <TB1> INFO: ----------------------------------------------------------------------
[16:30:41.968] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:30:41.977] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:41.977] <TB1> INFO: run 1 of 1
[16:30:42.307] <TB1> INFO: Expecting 26208000 events.
[16:31:14.680] <TB1> INFO: 1189850 events read in total (31658ms).
[16:31:47.142] <TB1> INFO: 2361300 events read in total (64120ms).
[16:32:19.984] <TB1> INFO: 3533200 events read in total (96962ms).
[16:32:52.588] <TB1> INFO: 4696650 events read in total (129566ms).
[16:33:25.405] <TB1> INFO: 5858000 events read in total (162383ms).
[16:33:58.317] <TB1> INFO: 7017550 events read in total (195295ms).
[16:34:31.545] <TB1> INFO: 8175050 events read in total (228523ms).
[16:35:04.782] <TB1> INFO: 9327950 events read in total (261760ms).
[16:35:37.453] <TB1> INFO: 10476000 events read in total (294431ms).
[16:36:10.519] <TB1> INFO: 11626050 events read in total (327497ms).
[16:36:42.690] <TB1> INFO: 12770750 events read in total (359669ms).
[16:37:14.947] <TB1> INFO: 13901200 events read in total (391925ms).
[16:37:45.985] <TB1> INFO: 15024500 events read in total (422963ms).
[16:38:16.756] <TB1> INFO: 16146700 events read in total (453734ms).
[16:38:47.289] <TB1> INFO: 17265200 events read in total (484267ms).
[16:39:18.854] <TB1> INFO: 18380450 events read in total (515832ms).
[16:39:50.107] <TB1> INFO: 19490950 events read in total (547085ms).
[16:40:22.094] <TB1> INFO: 20605100 events read in total (579072ms).
[16:40:54.181] <TB1> INFO: 21719150 events read in total (611159ms).
[16:41:26.167] <TB1> INFO: 22829250 events read in total (643145ms).
[16:41:57.961] <TB1> INFO: 23942800 events read in total (674939ms).
[16:42:30.894] <TB1> INFO: 25057500 events read in total (707872ms).
[16:43:03.377] <TB1> INFO: 26200300 events read in total (740355ms).
[16:43:03.979] <TB1> INFO: 26208000 events read in total (740957ms).
[16:43:04.020] <TB1> INFO: Test took 742043ms.
[16:43:04.099] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:04.270] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:05.776] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:07.265] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:08.689] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:10.279] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:11.881] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:13.284] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:14.740] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:16.150] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:17.814] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:19.334] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:20.751] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:22.215] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:23.657] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:25.049] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:26.447] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:43:27.904] <TB1> INFO: PixTestScurves::scurves() done
[16:43:27.904] <TB1> INFO: Vcal mean: 104.76 105.44 98.49 107.72 97.70 103.27 88.72 112.77 95.18 98.75 103.33 92.98 107.60 97.79 97.42 87.69
[16:43:27.904] <TB1> INFO: Vcal RMS: 5.08 4.97 5.13 4.75 5.17 5.11 5.27 5.18 5.97 5.83 5.23 5.34 5.19 5.44 6.28 5.65
[16:43:27.904] <TB1> INFO: PixTestScurves::fullTest() done, duration: 765 seconds
[16:43:27.905] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:27.905] <TB1> INFO: Decoding statistics:
[16:43:27.905] <TB1> INFO: General information:
[16:43:27.905] <TB1> INFO: 16bit words read: 0
[16:43:27.905] <TB1> INFO: valid events total: 0
[16:43:27.905] <TB1> INFO: empty events: 0
[16:43:27.905] <TB1> INFO: valid events with pixels: 0
[16:43:27.905] <TB1> INFO: valid pixel hits: 0
[16:43:27.905] <TB1> INFO: Event errors: 0
[16:43:27.905] <TB1> INFO: start marker: 0
[16:43:27.905] <TB1> INFO: stop marker: 0
[16:43:27.905] <TB1> INFO: overflow: 0
[16:43:27.905] <TB1> INFO: invalid 5bit words: 0
[16:43:27.905] <TB1> INFO: invalid XOR eye diagram: 0
[16:43:27.905] <TB1> INFO: TBM errors: 0
[16:43:27.905] <TB1> INFO: flawed TBM headers: 0
[16:43:27.905] <TB1> INFO: flawed TBM trailers: 0
[16:43:27.905] <TB1> INFO: event ID mismatches: 0
[16:43:27.905] <TB1> INFO: ROC errors: 0
[16:43:27.905] <TB1> INFO: missing ROC header(s): 0
[16:43:27.905] <TB1> INFO: misplaced readback start: 0
[16:43:27.905] <TB1> INFO: Pixel decoding errors: 0
[16:43:27.905] <TB1> INFO: pixel data incomplete: 0
[16:43:27.905] <TB1> INFO: pixel address: 0
[16:43:27.905] <TB1> INFO: pulse height fill bit: 0
[16:43:27.905] <TB1> INFO: buffer corruption: 0
[16:43:27.975] <TB1> INFO: ######################################################################
[16:43:27.975] <TB1> INFO: PixTestTrim::doTest()
[16:43:27.975] <TB1> INFO: ######################################################################
[16:43:27.977] <TB1> INFO: ----------------------------------------------------------------------
[16:43:27.977] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[16:43:27.977] <TB1> INFO: ----------------------------------------------------------------------
[16:43:28.068] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:43:28.068] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:43:28.077] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:43:28.077] <TB1> INFO: run 1 of 1
[16:43:28.385] <TB1> INFO: Expecting 6281600 events.
[16:44:10.618] <TB1> INFO: 1419700 events read in total (41514ms).
[16:44:54.961] <TB1> INFO: 2826290 events read in total (85857ms).
[16:45:39.143] <TB1> INFO: 4234590 events read in total (130039ms).
[16:46:24.112] <TB1> INFO: 5652020 events read in total (175008ms).
[16:46:43.397] <TB1> INFO: 6281600 events read in total (194293ms).
[16:46:43.426] <TB1> INFO: Test took 195349ms.
[16:46:43.476] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:03.235] <TB1> INFO: ROC 0 VthrComp = 106
[16:47:03.235] <TB1> INFO: ROC 1 VthrComp = 105
[16:47:03.235] <TB1> INFO: ROC 2 VthrComp = 101
[16:47:03.235] <TB1> INFO: ROC 3 VthrComp = 104
[16:47:03.235] <TB1> INFO: ROC 4 VthrComp = 100
[16:47:03.235] <TB1> INFO: ROC 5 VthrComp = 104
[16:47:03.235] <TB1> INFO: ROC 6 VthrComp = 94
[16:47:03.235] <TB1> INFO: ROC 7 VthrComp = 111
[16:47:03.236] <TB1> INFO: ROC 8 VthrComp = 94
[16:47:03.236] <TB1> INFO: ROC 9 VthrComp = 98
[16:47:03.236] <TB1> INFO: ROC 10 VthrComp = 103
[16:47:03.236] <TB1> INFO: ROC 11 VthrComp = 94
[16:47:03.236] <TB1> INFO: ROC 12 VthrComp = 106
[16:47:03.236] <TB1> INFO: ROC 13 VthrComp = 100
[16:47:03.236] <TB1> INFO: ROC 14 VthrComp = 97
[16:47:03.237] <TB1> INFO: ROC 15 VthrComp = 88
[16:47:03.237] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:47:03.237] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:47:03.247] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:47:03.247] <TB1> INFO: run 1 of 1
[16:47:03.560] <TB1> INFO: Expecting 6281600 events.
[16:47:41.408] <TB1> INFO: 890460 events read in total (37132ms).
[16:48:16.815] <TB1> INFO: 1778200 events read in total (72539ms).
[16:48:52.639] <TB1> INFO: 2667110 events read in total (108363ms).
[16:49:27.988] <TB1> INFO: 3551310 events read in total (143712ms).
[16:50:03.419] <TB1> INFO: 4427000 events read in total (179143ms).
[16:50:38.163] <TB1> INFO: 5299150 events read in total (213887ms).
[16:51:13.168] <TB1> INFO: 6170850 events read in total (248892ms).
[16:51:17.584] <TB1> INFO: 6281600 events read in total (253308ms).
[16:51:17.667] <TB1> INFO: Test took 254420ms.
[16:51:17.861] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:51:43.864] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 58.9376 for pixel 23/75 mean/min/max = 46.5117/33.963/59.0603
[16:51:43.864] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.544 for pixel 21/77 mean/min/max = 47.2384/34.8716/59.6051
[16:51:43.865] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.847 for pixel 29/3 mean/min/max = 44.8725/31.8203/57.9248
[16:51:43.865] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.767 for pixel 5/71 mean/min/max = 47.2805/34.7632/59.7977
[16:51:43.866] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.08 for pixel 22/23 mean/min/max = 44.7099/32.133/57.2867
[16:51:43.866] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.1203 for pixel 8/10 mean/min/max = 47.0145/34.8618/59.1672
[16:51:43.867] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.538 for pixel 0/1 mean/min/max = 45.0286/32.4625/57.5948
[16:51:43.867] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 59.7199 for pixel 5/7 mean/min/max = 47.1534/34.5793/59.7275
[16:51:43.867] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.8889 for pixel 8/28 mean/min/max = 46.0039/33.0248/58.9831
[16:51:43.868] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 59.1057 for pixel 26/61 mean/min/max = 45.5395/31.8434/59.2357
[16:51:43.868] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.9925 for pixel 45/1 mean/min/max = 45.2204/32.2574/58.1834
[16:51:43.868] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.3337 for pixel 15/51 mean/min/max = 45.0527/32.7682/57.3371
[16:51:43.869] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 61.0632 for pixel 19/0 mean/min/max = 47.905/34.7255/61.0845
[16:51:43.869] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.6447 for pixel 6/18 mean/min/max = 44.0902/31.2896/56.8907
[16:51:43.870] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 60.0085 for pixel 13/61 mean/min/max = 45.8231/31.6302/60.016
[16:51:43.870] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 59.4497 for pixel 7/63 mean/min/max = 46.6502/33.7829/59.5174
[16:51:43.870] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:51:44.003] <TB1> INFO: Expecting 514560 events.
[16:51:53.689] <TB1> INFO: 514560 events read in total (8970ms).
[16:51:53.694] <TB1> INFO: Expecting 514560 events.
[16:52:03.452] <TB1> INFO: 514560 events read in total (9089ms).
[16:52:03.459] <TB1> INFO: Expecting 514560 events.
[16:52:13.134] <TB1> INFO: 514560 events read in total (9015ms).
[16:52:13.149] <TB1> INFO: Expecting 514560 events.
[16:52:22.858] <TB1> INFO: 514560 events read in total (9050ms).
[16:52:22.870] <TB1> INFO: Expecting 514560 events.
[16:52:32.480] <TB1> INFO: 514560 events read in total (8960ms).
[16:52:32.494] <TB1> INFO: Expecting 514560 events.
[16:52:41.674] <TB1> INFO: 514560 events read in total (8521ms).
[16:52:41.689] <TB1> INFO: Expecting 514560 events.
[16:52:50.827] <TB1> INFO: 514560 events read in total (8476ms).
[16:52:50.845] <TB1> INFO: Expecting 514560 events.
[16:52:59.975] <TB1> INFO: 514560 events read in total (8473ms).
[16:52:59.993] <TB1> INFO: Expecting 514560 events.
[16:53:09.584] <TB1> INFO: 514560 events read in total (8931ms).
[16:53:09.609] <TB1> INFO: Expecting 514560 events.
[16:53:19.390] <TB1> INFO: 514560 events read in total (9140ms).
[16:53:19.414] <TB1> INFO: Expecting 514560 events.
[16:53:29.199] <TB1> INFO: 514560 events read in total (9138ms).
[16:53:29.223] <TB1> INFO: Expecting 514560 events.
[16:53:39.033] <TB1> INFO: 514560 events read in total (9160ms).
[16:53:39.059] <TB1> INFO: Expecting 514560 events.
[16:53:48.868] <TB1> INFO: 514560 events read in total (9158ms).
[16:53:48.904] <TB1> INFO: Expecting 514560 events.
[16:53:58.640] <TB1> INFO: 514560 events read in total (9115ms).
[16:53:58.676] <TB1> INFO: Expecting 514560 events.
[16:54:08.458] <TB1> INFO: 514560 events read in total (9150ms).
[16:54:08.489] <TB1> INFO: Expecting 514560 events.
[16:54:18.193] <TB1> INFO: 514560 events read in total (9056ms).
[16:54:18.232] <TB1> INFO: Test took 154362ms.
[16:54:19.425] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:54:19.434] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:54:19.434] <TB1> INFO: run 1 of 1
[16:54:19.777] <TB1> INFO: Expecting 6281600 events.
[16:54:58.904] <TB1> INFO: 871630 events read in total (38412ms).
[16:55:37.342] <TB1> INFO: 1741470 events read in total (76850ms).
[16:56:14.795] <TB1> INFO: 2611460 events read in total (114303ms).
[16:56:51.971] <TB1> INFO: 3477900 events read in total (151479ms).
[16:57:27.885] <TB1> INFO: 4335120 events read in total (187393ms).
[16:58:04.062] <TB1> INFO: 5189190 events read in total (223570ms).
[16:58:39.642] <TB1> INFO: 6042360 events read in total (259150ms).
[16:58:49.209] <TB1> INFO: 6281600 events read in total (268717ms).
[16:58:49.295] <TB1> INFO: Test took 269861ms.
[16:58:49.479] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:13.280] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.062438 .. 255.000000
[16:59:13.364] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[16:59:13.372] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[16:59:13.372] <TB1> INFO: run 1 of 1
[16:59:13.675] <TB1> INFO: Expecting 10649600 events.
[16:59:49.656] <TB1> INFO: 826840 events read in total (35265ms).
[17:00:23.514] <TB1> INFO: 1654440 events read in total (69123ms).
[17:00:57.240] <TB1> INFO: 2481870 events read in total (102849ms).
[17:01:30.589] <TB1> INFO: 3309350 events read in total (136198ms).
[17:02:03.745] <TB1> INFO: 4136690 events read in total (169354ms).
[17:02:35.945] <TB1> INFO: 4963740 events read in total (201554ms).
[17:03:09.512] <TB1> INFO: 5790700 events read in total (235121ms).
[17:03:44.056] <TB1> INFO: 6617450 events read in total (269665ms).
[17:04:15.270] <TB1> INFO: 7443320 events read in total (300879ms).
[17:04:49.200] <TB1> INFO: 8268320 events read in total (334809ms).
[17:05:23.351] <TB1> INFO: 9092690 events read in total (368960ms).
[17:05:58.455] <TB1> INFO: 9916820 events read in total (404064ms).
[17:06:26.382] <TB1> INFO: 10649600 events read in total (431991ms).
[17:06:26.474] <TB1> INFO: Test took 433101ms.
[17:06:26.765] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:57.927] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 17.577286 .. 44.701492
[17:06:58.002] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 54 (-1/-1) hits flags = 528 (plus default)
[17:06:58.011] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:06:58.011] <TB1> INFO: run 1 of 1
[17:06:58.349] <TB1> INFO: Expecting 1996800 events.
[17:07:39.040] <TB1> INFO: 1164320 events read in total (39970ms).
[17:08:06.393] <TB1> INFO: 1996800 events read in total (67323ms).
[17:08:06.409] <TB1> INFO: Test took 68398ms.
[17:08:06.442] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:20.898] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 15.402065 .. 42.063269
[17:08:20.975] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 5 .. 52 (-1/-1) hits flags = 528 (plus default)
[17:08:20.983] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:08:20.983] <TB1> INFO: run 1 of 1
[17:08:21.290] <TB1> INFO: Expecting 1996800 events.
[17:08:43.911] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (0) != Token Chain Length (8)
[17:08:43.911] <TB1> ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (6) != TBM ID (7)
[17:08:43.911] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:08:43.911] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a10a 8000 40e8 40e8 40e8 40e8 40e8 40e8 40e8 40e8 e022 c000
[17:08:43.911] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a104 80b1 40e8 40e8 40e8 40e8 40e8 40e8 40e8 40e8 e022 c000
[17:08:43.911] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a105 80c0 40e8 40e8 40e8 40e8 40e8 40e8 40e8 40e8 e022 c000
[17:08:43.911] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a106 c008
[17:08:43.911] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a107 8040 40e8 40e8 40e8 40e8 40e8 40e8 40e8 40e8 e022 c000
[17:08:43.911] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a108 80b1 40e8 40e8 40e8 40e8 40e8 40e8 40e8 40e8 e022 c000
[17:08:43.924] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a109 80c0 40e8 40e8 40e8 40e8 40e8 40e8 40e8 40e8 e022 c000
[17:09:01.752] <TB1> INFO: 1208220 events read in total (39747ms).
[17:09:29.298] <TB1> INFO: 1996800 events read in total (67294ms).
[17:09:29.312] <TB1> INFO: Test took 68329ms.
[17:09:29.340] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:42.592] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 15.402065 .. 42.063269
[17:09:42.679] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 5 .. 52 (-1/-1) hits flags = 528 (plus default)
[17:09:42.688] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:09:42.688] <TB1> INFO: run 1 of 1
[17:09:42.992] <TB1> INFO: Expecting 1996800 events.
[17:10:21.430] <TB1> INFO: 1209580 events read in total (37722ms).
[17:10:48.495] <TB1> INFO: 1996800 events read in total (64787ms).
[17:10:48.513] <TB1> INFO: Test took 65825ms.
[17:10:48.542] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:01.612] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:11:01.612] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:11:01.621] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:11:01.621] <TB1> INFO: run 1 of 1
[17:11:01.927] <TB1> INFO: Expecting 1705600 events.
[17:11:38.620] <TB1> INFO: 1076110 events read in total (35977ms).
[17:12:02.525] <TB1> INFO: 1705600 events read in total (59882ms).
[17:12:02.537] <TB1> INFO: Test took 60916ms.
[17:12:02.567] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:16.550] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C0.dat
[17:12:16.552] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C1.dat
[17:12:16.552] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C2.dat
[17:12:16.552] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C3.dat
[17:12:16.552] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C4.dat
[17:12:16.552] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C5.dat
[17:12:16.552] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C6.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C7.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C8.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C9.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C10.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C11.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C12.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C13.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C14.dat
[17:12:16.553] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C15.dat
[17:12:16.554] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C0.dat
[17:12:16.560] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C1.dat
[17:12:16.566] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C2.dat
[17:12:16.573] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C3.dat
[17:12:16.579] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C4.dat
[17:12:16.586] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C5.dat
[17:12:16.593] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C6.dat
[17:12:16.600] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C7.dat
[17:12:16.607] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C8.dat
[17:12:16.614] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C9.dat
[17:12:16.621] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C10.dat
[17:12:16.628] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C11.dat
[17:12:16.636] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C12.dat
[17:12:16.642] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C13.dat
[17:12:16.649] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C14.dat
[17:12:16.655] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//trimParameters35_C15.dat
[17:12:16.661] <TB1> INFO: PixTestTrim::trimTest() done
[17:12:16.661] <TB1> INFO: vtrim: 104 93 99 116 85 100 95 97 94 103 100 100 117 95 99 95
[17:12:16.661] <TB1> INFO: vthrcomp: 106 105 101 104 100 104 94 111 94 98 103 94 106 100 97 88
[17:12:16.661] <TB1> INFO: vcal mean: 34.98 34.94 34.96 34.91 34.93 34.95 34.97 35.01 34.98 34.98 34.98 34.95 35.02 34.92 34.72 34.98
[17:12:16.662] <TB1> INFO: vcal RMS: 0.81 0.85 0.84 0.90 0.85 0.87 0.81 0.87 0.93 0.91 0.90 0.85 0.90 0.89 0.86 0.85
[17:12:16.662] <TB1> INFO: bits mean: 8.75 8.60 9.70 9.10 9.56 8.89 9.42 8.32 9.49 9.66 9.48 9.61 9.08 10.06 9.28 9.10
[17:12:16.662] <TB1> INFO: bits RMS: 2.67 2.56 2.68 2.41 2.77 2.49 2.74 2.69 2.56 2.64 2.70 2.58 2.35 2.66 2.82 2.59
[17:12:16.671] <TB1> INFO: ----------------------------------------------------------------------
[17:12:16.671] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[17:12:16.671] <TB1> INFO: ----------------------------------------------------------------------
[17:12:16.676] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[17:12:16.685] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:12:16.685] <TB1> INFO: run 1 of 1
[17:12:16.990] <TB1> INFO: Expecting 4160000 events.
[17:13:06.006] <TB1> INFO: 1213585 events read in total (48301ms).
[17:13:53.590] <TB1> INFO: 2402155 events read in total (95885ms).
[17:14:40.169] <TB1> INFO: 3569585 events read in total (142465ms).
[17:15:05.132] <TB1> INFO: 4160000 events read in total (167427ms).
[17:15:05.189] <TB1> INFO: Test took 168504ms.
[17:15:05.285] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:34.370] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[17:15:34.390] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:15:34.390] <TB1> INFO: run 1 of 1
[17:15:34.724] <TB1> INFO: Expecting 3868800 events.
[17:16:25.960] <TB1> INFO: 1201915 events read in total (50521ms).
[17:17:14.324] <TB1> INFO: 2378710 events read in total (98886ms).
[17:18:00.994] <TB1> INFO: 3538055 events read in total (145556ms).
[17:18:16.252] <TB1> INFO: 3868800 events read in total (160813ms).
[17:18:16.312] <TB1> INFO: Test took 161922ms.
[17:18:16.417] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:47.443] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[17:18:47.451] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:18:47.451] <TB1> INFO: run 1 of 1
[17:18:47.761] <TB1> INFO: Expecting 3598400 events.
[17:19:38.597] <TB1> INFO: 1254975 events read in total (50120ms).
[17:20:27.668] <TB1> INFO: 2476290 events read in total (99191ms).
[17:21:11.208] <TB1> INFO: 3598400 events read in total (142731ms).
[17:21:11.262] <TB1> INFO: Test took 143812ms.
[17:21:11.354] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:39.151] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[17:21:39.162] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:21:39.162] <TB1> INFO: run 1 of 1
[17:21:39.549] <TB1> INFO: Expecting 3598400 events.
[17:22:32.412] <TB1> INFO: 1253730 events read in total (52148ms).
[17:23:23.353] <TB1> INFO: 2474105 events read in total (103089ms).
[17:24:09.268] <TB1> INFO: 3598400 events read in total (149004ms).
[17:24:09.325] <TB1> INFO: Test took 150164ms.
[17:24:09.417] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:40.435] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[17:24:40.443] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:24:40.443] <TB1> INFO: run 1 of 1
[17:24:40.746] <TB1> INFO: Expecting 3452800 events.
[17:25:34.924] <TB1> INFO: 1287070 events read in total (53462ms).
[17:26:26.514] <TB1> INFO: 2535570 events read in total (105053ms).
[17:27:06.529] <TB1> INFO: 3452800 events read in total (145067ms).
[17:27:06.570] <TB1> INFO: Test took 146127ms.
[17:27:06.651] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:31.435] <TB1> INFO: PixTestTrim::trimBitTest() done
[17:27:31.437] <TB1> INFO: PixTestTrim::doTest() done, duration: 2643 seconds
[17:27:31.437] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:31.437] <TB1> INFO: Decoding statistics:
[17:27:31.437] <TB1> INFO: General information:
[17:27:31.437] <TB1> INFO: 16bit words read: 0
[17:27:31.437] <TB1> INFO: valid events total: 0
[17:27:31.437] <TB1> INFO: empty events: 0
[17:27:31.437] <TB1> INFO: valid events with pixels: 0
[17:27:31.437] <TB1> INFO: valid pixel hits: 0
[17:27:31.437] <TB1> INFO: Event errors: 0
[17:27:31.437] <TB1> INFO: start marker: 0
[17:27:31.437] <TB1> INFO: stop marker: 0
[17:27:31.437] <TB1> INFO: overflow: 0
[17:27:31.437] <TB1> INFO: invalid 5bit words: 0
[17:27:31.437] <TB1> INFO: invalid XOR eye diagram: 0
[17:27:31.437] <TB1> INFO: TBM errors: 0
[17:27:31.437] <TB1> INFO: flawed TBM headers: 0
[17:27:31.437] <TB1> INFO: flawed TBM trailers: 0
[17:27:31.437] <TB1> INFO: event ID mismatches: 0
[17:27:31.437] <TB1> INFO: ROC errors: 0
[17:27:31.437] <TB1> INFO: missing ROC header(s): 0
[17:27:31.437] <TB1> INFO: misplaced readback start: 0
[17:27:31.437] <TB1> INFO: Pixel decoding errors: 0
[17:27:31.437] <TB1> INFO: pixel data incomplete: 0
[17:27:31.437] <TB1> INFO: pixel address: 0
[17:27:31.437] <TB1> INFO: pulse height fill bit: 0
[17:27:31.437] <TB1> INFO: buffer corruption: 0
[17:27:32.141] <TB1> INFO: ######################################################################
[17:27:32.141] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:27:32.141] <TB1> INFO: ######################################################################
[17:27:32.482] <TB1> INFO: Expecting 41600 events.
[17:27:36.460] <TB1> INFO: 41600 events read in total (3261ms).
[17:27:36.460] <TB1> INFO: Test took 4317ms.
[17:27:36.466] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:37.133] <TB1> INFO: Expecting 41600 events.
[17:27:41.210] <TB1> INFO: 41600 events read in total (3362ms).
[17:27:41.211] <TB1> INFO: Test took 4384ms.
[17:27:41.217] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:41.740] <TB1> INFO: Expecting 41600 events.
[17:27:45.790] <TB1> INFO: 41600 events read in total (3335ms).
[17:27:45.790] <TB1> INFO: Test took 4457ms.
[17:27:45.797] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:46.245] <TB1> INFO: Expecting 2560 events.
[17:27:47.203] <TB1> INFO: 2560 events read in total (243ms).
[17:27:47.204] <TB1> INFO: Test took 1399ms.
[17:27:47.713] <TB1> INFO: Expecting 2560 events.
[17:27:48.671] <TB1> INFO: 2560 events read in total (243ms).
[17:27:48.671] <TB1> INFO: Test took 1467ms.
[17:27:49.180] <TB1> INFO: Expecting 2560 events.
[17:27:50.138] <TB1> INFO: 2560 events read in total (243ms).
[17:27:50.138] <TB1> INFO: Test took 1467ms.
[17:27:50.647] <TB1> INFO: Expecting 2560 events.
[17:27:51.604] <TB1> INFO: 2560 events read in total (242ms).
[17:27:51.605] <TB1> INFO: Test took 1467ms.
[17:27:52.114] <TB1> INFO: Expecting 2560 events.
[17:27:53.071] <TB1> INFO: 2560 events read in total (242ms).
[17:27:53.072] <TB1> INFO: Test took 1467ms.
[17:27:53.581] <TB1> INFO: Expecting 2560 events.
[17:27:54.539] <TB1> INFO: 2560 events read in total (243ms).
[17:27:54.539] <TB1> INFO: Test took 1467ms.
[17:27:55.048] <TB1> INFO: Expecting 2560 events.
[17:27:56.007] <TB1> INFO: 2560 events read in total (244ms).
[17:27:56.007] <TB1> INFO: Test took 1468ms.
[17:27:56.517] <TB1> INFO: Expecting 2560 events.
[17:27:57.475] <TB1> INFO: 2560 events read in total (243ms).
[17:27:57.475] <TB1> INFO: Test took 1467ms.
[17:27:57.985] <TB1> INFO: Expecting 2560 events.
[17:27:58.944] <TB1> INFO: 2560 events read in total (244ms).
[17:27:58.944] <TB1> INFO: Test took 1468ms.
[17:27:59.454] <TB1> INFO: Expecting 2560 events.
[17:28:00.414] <TB1> INFO: 2560 events read in total (245ms).
[17:28:00.414] <TB1> INFO: Test took 1469ms.
[17:28:00.923] <TB1> INFO: Expecting 2560 events.
[17:28:01.886] <TB1> INFO: 2560 events read in total (247ms).
[17:28:01.887] <TB1> INFO: Test took 1473ms.
[17:28:02.396] <TB1> INFO: Expecting 2560 events.
[17:28:03.359] <TB1> INFO: 2560 events read in total (248ms).
[17:28:03.360] <TB1> INFO: Test took 1473ms.
[17:28:03.872] <TB1> INFO: Expecting 2560 events.
[17:28:04.834] <TB1> INFO: 2560 events read in total (247ms).
[17:28:04.834] <TB1> INFO: Test took 1474ms.
[17:28:05.343] <TB1> INFO: Expecting 2560 events.
[17:28:06.306] <TB1> INFO: 2560 events read in total (247ms).
[17:28:06.306] <TB1> INFO: Test took 1471ms.
[17:28:06.815] <TB1> INFO: Expecting 2560 events.
[17:28:07.779] <TB1> INFO: 2560 events read in total (249ms).
[17:28:07.779] <TB1> INFO: Test took 1473ms.
[17:28:08.289] <TB1> INFO: Expecting 2560 events.
[17:28:09.252] <TB1> INFO: 2560 events read in total (248ms).
[17:28:09.252] <TB1> INFO: Test took 1472ms.
[17:28:09.257] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:28:09.764] <TB1> INFO: Expecting 655360 events.
[17:28:22.264] <TB1> INFO: 655360 events read in total (11785ms).
[17:28:22.273] <TB1> INFO: Expecting 655360 events.
[17:28:34.345] <TB1> INFO: 655360 events read in total (11469ms).
[17:28:34.358] <TB1> INFO: Expecting 655360 events.
[17:28:46.911] <TB1> INFO: 655360 events read in total (11960ms).
[17:28:46.930] <TB1> INFO: Expecting 655360 events.
[17:28:59.303] <TB1> INFO: 655360 events read in total (11797ms).
[17:28:59.326] <TB1> INFO: Expecting 655360 events.
[17:29:11.554] <TB1> INFO: 655360 events read in total (11654ms).
[17:29:11.577] <TB1> INFO: Expecting 655360 events.
[17:29:23.643] <TB1> INFO: 655360 events read in total (11487ms).
[17:29:23.681] <TB1> INFO: Expecting 655360 events.
[17:29:35.229] <TB1> INFO: 655360 events read in total (11021ms).
[17:29:35.260] <TB1> INFO: Expecting 655360 events.
[17:29:46.574] <TB1> INFO: 655360 events read in total (10758ms).
[17:29:46.611] <TB1> INFO: Expecting 655360 events.
[17:29:59.008] <TB1> INFO: 655360 events read in total (11834ms).
[17:29:59.046] <TB1> INFO: Expecting 655360 events.
[17:30:11.438] <TB1> INFO: 655360 events read in total (11838ms).
[17:30:11.479] <TB1> INFO: Expecting 655360 events.
[17:30:24.054] <TB1> INFO: 655360 events read in total (12007ms).
[17:30:24.101] <TB1> INFO: Expecting 655360 events.
[17:30:36.721] <TB1> INFO: 655360 events read in total (12070ms).
[17:30:36.772] <TB1> INFO: Expecting 655360 events.
[17:30:49.384] <TB1> INFO: 655360 events read in total (12078ms).
[17:30:49.435] <TB1> INFO: Expecting 655360 events.
[17:31:02.067] <TB1> INFO: 655360 events read in total (12083ms).
[17:31:02.127] <TB1> INFO: Expecting 655360 events.
[17:31:14.737] <TB1> INFO: 655360 events read in total (12083ms).
[17:31:14.799] <TB1> INFO: Expecting 655360 events.
[17:31:27.253] <TB1> INFO: 655360 events read in total (11914ms).
[17:31:27.340] <TB1> INFO: Test took 198083ms.
[17:31:27.439] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:27.743] <TB1> INFO: Expecting 655360 events.
[17:31:40.364] <TB1> INFO: 655360 events read in total (11906ms).
[17:31:40.373] <TB1> INFO: Expecting 655360 events.
[17:31:52.907] <TB1> INFO: 655360 events read in total (11936ms).
[17:31:52.924] <TB1> INFO: Expecting 655360 events.
[17:32:05.458] <TB1> INFO: 655360 events read in total (11938ms).
[17:32:05.474] <TB1> INFO: Expecting 655360 events.
[17:32:17.878] <TB1> INFO: 655360 events read in total (11808ms).
[17:32:17.900] <TB1> INFO: Expecting 655360 events.
[17:32:30.353] <TB1> INFO: 655360 events read in total (11853ms).
[17:32:30.375] <TB1> INFO: Expecting 655360 events.
[17:32:42.818] <TB1> INFO: 655360 events read in total (11855ms).
[17:32:42.851] <TB1> INFO: Expecting 655360 events.
[17:32:55.308] <TB1> INFO: 655360 events read in total (11900ms).
[17:32:55.344] <TB1> INFO: Expecting 655360 events.
[17:33:07.678] <TB1> INFO: 655360 events read in total (11785ms).
[17:33:07.711] <TB1> INFO: Expecting 655360 events.
[17:33:20.211] <TB1> INFO: 655360 events read in total (11927ms).
[17:33:20.254] <TB1> INFO: Expecting 655360 events.
[17:33:32.612] <TB1> INFO: 655360 events read in total (11810ms).
[17:33:32.664] <TB1> INFO: Expecting 655360 events.
[17:33:45.102] <TB1> INFO: 655360 events read in total (11903ms).
[17:33:45.147] <TB1> INFO: Expecting 655360 events.
[17:33:57.492] <TB1> INFO: 655360 events read in total (11778ms).
[17:33:57.549] <TB1> INFO: Expecting 655360 events.
[17:34:09.998] <TB1> INFO: 655360 events read in total (11921ms).
[17:34:10.056] <TB1> INFO: Expecting 655360 events.
[17:34:22.418] <TB1> INFO: 655360 events read in total (11800ms).
[17:34:22.474] <TB1> INFO: Expecting 655360 events.
[17:34:34.923] <TB1> INFO: 655360 events read in total (11888ms).
[17:34:35.006] <TB1> INFO: Expecting 655360 events.
[17:34:46.928] <TB1> INFO: 655360 events read in total (11395ms).
[17:34:46.995] <TB1> INFO: Test took 199556ms.
[17:34:47.205] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.214] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.222] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.229] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:34:47.237] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[17:34:47.245] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[17:34:47.252] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.259] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.267] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.274] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.282] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.289] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:34:47.297] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.305] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:34:47.312] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[17:34:47.320] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[17:34:47.327] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[17:34:47.335] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[17:34:47.342] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[17:34:47.350] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[17:34:47.357] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[17:34:47.364] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.371] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.379] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.386] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.393] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.400] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.408] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:34:47.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C0.dat
[17:34:47.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C1.dat
[17:34:47.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C2.dat
[17:34:47.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C3.dat
[17:34:47.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C4.dat
[17:34:47.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C5.dat
[17:34:47.465] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C6.dat
[17:34:47.465] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C7.dat
[17:34:47.465] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C8.dat
[17:34:47.465] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C9.dat
[17:34:47.465] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C10.dat
[17:34:47.465] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C11.dat
[17:34:47.465] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C12.dat
[17:34:47.465] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C13.dat
[17:34:47.466] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C14.dat
[17:34:47.466] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//dacParameters35_C15.dat
[17:34:47.778] <TB1> INFO: Expecting 41600 events.
[17:34:51.571] <TB1> INFO: 41600 events read in total (3078ms).
[17:34:51.571] <TB1> INFO: Test took 4101ms.
[17:34:52.224] <TB1> INFO: Expecting 41600 events.
[17:34:55.991] <TB1> INFO: 41600 events read in total (3051ms).
[17:34:55.992] <TB1> INFO: Test took 4076ms.
[17:34:56.663] <TB1> INFO: Expecting 41600 events.
[17:35:00.436] <TB1> INFO: 41600 events read in total (3058ms).
[17:35:00.437] <TB1> INFO: Test took 4109ms.
[17:35:00.780] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:00.913] <TB1> INFO: Expecting 2560 events.
[17:35:01.871] <TB1> INFO: 2560 events read in total (243ms).
[17:35:01.872] <TB1> INFO: Test took 1092ms.
[17:35:01.878] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:02.382] <TB1> INFO: Expecting 2560 events.
[17:35:03.340] <TB1> INFO: 2560 events read in total (243ms).
[17:35:03.340] <TB1> INFO: Test took 1462ms.
[17:35:03.344] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:03.850] <TB1> INFO: Expecting 2560 events.
[17:35:04.807] <TB1> INFO: 2560 events read in total (242ms).
[17:35:04.808] <TB1> INFO: Test took 1464ms.
[17:35:04.810] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:05.318] <TB1> INFO: Expecting 2560 events.
[17:35:06.276] <TB1> INFO: 2560 events read in total (243ms).
[17:35:06.277] <TB1> INFO: Test took 1467ms.
[17:35:06.279] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:06.787] <TB1> INFO: Expecting 2560 events.
[17:35:07.747] <TB1> INFO: 2560 events read in total (245ms).
[17:35:07.747] <TB1> INFO: Test took 1468ms.
[17:35:07.750] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:08.257] <TB1> INFO: Expecting 2560 events.
[17:35:09.216] <TB1> INFO: 2560 events read in total (244ms).
[17:35:09.217] <TB1> INFO: Test took 1467ms.
[17:35:09.219] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:09.726] <TB1> INFO: Expecting 2560 events.
[17:35:10.685] <TB1> INFO: 2560 events read in total (243ms).
[17:35:10.685] <TB1> INFO: Test took 1466ms.
[17:35:10.688] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:11.195] <TB1> INFO: Expecting 2560 events.
[17:35:12.153] <TB1> INFO: 2560 events read in total (243ms).
[17:35:12.153] <TB1> INFO: Test took 1465ms.
[17:35:12.157] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:12.663] <TB1> INFO: Expecting 2560 events.
[17:35:13.622] <TB1> INFO: 2560 events read in total (244ms).
[17:35:13.622] <TB1> INFO: Test took 1465ms.
[17:35:13.625] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:14.132] <TB1> INFO: Expecting 2560 events.
[17:35:15.091] <TB1> INFO: 2560 events read in total (243ms).
[17:35:15.091] <TB1> INFO: Test took 1466ms.
[17:35:15.093] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:15.601] <TB1> INFO: Expecting 2560 events.
[17:35:16.559] <TB1> INFO: 2560 events read in total (243ms).
[17:35:16.559] <TB1> INFO: Test took 1466ms.
[17:35:16.561] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:17.069] <TB1> INFO: Expecting 2560 events.
[17:35:18.028] <TB1> INFO: 2560 events read in total (244ms).
[17:35:18.028] <TB1> INFO: Test took 1467ms.
[17:35:18.032] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:18.538] <TB1> INFO: Expecting 2560 events.
[17:35:19.503] <TB1> INFO: 2560 events read in total (249ms).
[17:35:19.503] <TB1> INFO: Test took 1471ms.
[17:35:19.507] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:20.013] <TB1> INFO: Expecting 2560 events.
[17:35:20.979] <TB1> INFO: 2560 events read in total (250ms).
[17:35:20.979] <TB1> INFO: Test took 1472ms.
[17:35:20.982] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:21.489] <TB1> INFO: Expecting 2560 events.
[17:35:22.453] <TB1> INFO: 2560 events read in total (248ms).
[17:35:22.453] <TB1> INFO: Test took 1471ms.
[17:35:22.456] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:22.963] <TB1> INFO: Expecting 2560 events.
[17:35:23.927] <TB1> INFO: 2560 events read in total (248ms).
[17:35:23.927] <TB1> INFO: Test took 1471ms.
[17:35:23.931] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:24.437] <TB1> INFO: Expecting 2560 events.
[17:35:25.403] <TB1> INFO: 2560 events read in total (250ms).
[17:35:25.403] <TB1> INFO: Test took 1473ms.
[17:35:25.407] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:25.913] <TB1> INFO: Expecting 2560 events.
[17:35:26.875] <TB1> INFO: 2560 events read in total (247ms).
[17:35:26.876] <TB1> INFO: Test took 1469ms.
[17:35:26.879] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:27.386] <TB1> INFO: Expecting 2560 events.
[17:35:28.350] <TB1> INFO: 2560 events read in total (249ms).
[17:35:28.351] <TB1> INFO: Test took 1472ms.
[17:35:28.354] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:28.861] <TB1> INFO: Expecting 2560 events.
[17:35:29.825] <TB1> INFO: 2560 events read in total (249ms).
[17:35:29.826] <TB1> INFO: Test took 1472ms.
[17:35:29.829] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:30.335] <TB1> INFO: Expecting 2560 events.
[17:35:31.298] <TB1> INFO: 2560 events read in total (247ms).
[17:35:31.298] <TB1> INFO: Test took 1470ms.
[17:35:31.302] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:31.809] <TB1> INFO: Expecting 2560 events.
[17:35:32.772] <TB1> INFO: 2560 events read in total (248ms).
[17:35:32.773] <TB1> INFO: Test took 1472ms.
[17:35:32.775] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:33.283] <TB1> INFO: Expecting 2560 events.
[17:35:34.246] <TB1> INFO: 2560 events read in total (247ms).
[17:35:34.247] <TB1> INFO: Test took 1472ms.
[17:35:34.250] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:34.756] <TB1> INFO: Expecting 2560 events.
[17:35:35.722] <TB1> INFO: 2560 events read in total (250ms).
[17:35:35.722] <TB1> INFO: Test took 1472ms.
[17:35:35.727] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:36.232] <TB1> INFO: Expecting 2560 events.
[17:35:37.196] <TB1> INFO: 2560 events read in total (248ms).
[17:35:37.197] <TB1> INFO: Test took 1471ms.
[17:35:37.200] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:37.707] <TB1> INFO: Expecting 2560 events.
[17:35:38.667] <TB1> INFO: 2560 events read in total (244ms).
[17:35:38.667] <TB1> INFO: Test took 1467ms.
[17:35:38.670] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:39.178] <TB1> INFO: Expecting 2560 events.
[17:35:40.143] <TB1> INFO: 2560 events read in total (250ms).
[17:35:40.143] <TB1> INFO: Test took 1473ms.
[17:35:40.146] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:40.653] <TB1> INFO: Expecting 2560 events.
[17:35:41.617] <TB1> INFO: 2560 events read in total (248ms).
[17:35:41.618] <TB1> INFO: Test took 1472ms.
[17:35:41.621] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:42.128] <TB1> INFO: Expecting 2560 events.
[17:35:43.093] <TB1> INFO: 2560 events read in total (250ms).
[17:35:43.093] <TB1> INFO: Test took 1472ms.
[17:35:43.097] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:43.604] <TB1> INFO: Expecting 2560 events.
[17:35:44.568] <TB1> INFO: 2560 events read in total (249ms).
[17:35:44.569] <TB1> INFO: Test took 1473ms.
[17:35:44.574] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:45.078] <TB1> INFO: Expecting 2560 events.
[17:35:46.042] <TB1> INFO: 2560 events read in total (248ms).
[17:35:46.043] <TB1> INFO: Test took 1470ms.
[17:35:46.048] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:46.553] <TB1> INFO: Expecting 2560 events.
[17:35:47.516] <TB1> INFO: 2560 events read in total (248ms).
[17:35:47.516] <TB1> INFO: Test took 1468ms.
[17:35:48.289] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 496 seconds
[17:35:48.289] <TB1> INFO: PH scale (per ROC): 81 65 80 70 70 75 80 70 78 62 70 80 74 69 76 68
[17:35:48.289] <TB1> INFO: PH offset (per ROC): 167 162 171 182 173 188 164 176 176 169 160 170 187 173 173 175
[17:35:48.297] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:48.298] <TB1> INFO: Decoding statistics:
[17:35:48.298] <TB1> INFO: General information:
[17:35:48.298] <TB1> INFO: 16bit words read: 66444
[17:35:48.298] <TB1> INFO: valid events total: 5120
[17:35:48.298] <TB1> INFO: empty events: 2618
[17:35:48.298] <TB1> INFO: valid events with pixels: 2502
[17:35:48.298] <TB1> INFO: valid pixel hits: 2502
[17:35:48.298] <TB1> INFO: Event errors: 0
[17:35:48.298] <TB1> INFO: start marker: 0
[17:35:48.298] <TB1> INFO: stop marker: 0
[17:35:48.298] <TB1> INFO: overflow: 0
[17:35:48.298] <TB1> INFO: invalid 5bit words: 0
[17:35:48.298] <TB1> INFO: invalid XOR eye diagram: 0
[17:35:48.298] <TB1> INFO: TBM errors: 0
[17:35:48.298] <TB1> INFO: flawed TBM headers: 0
[17:35:48.298] <TB1> INFO: flawed TBM trailers: 0
[17:35:48.298] <TB1> INFO: event ID mismatches: 0
[17:35:48.298] <TB1> INFO: ROC errors: 0
[17:35:48.298] <TB1> INFO: missing ROC header(s): 0
[17:35:48.298] <TB1> INFO: misplaced readback start: 0
[17:35:48.298] <TB1> INFO: Pixel decoding errors: 0
[17:35:48.298] <TB1> INFO: pixel data incomplete: 0
[17:35:48.298] <TB1> INFO: pixel address: 0
[17:35:48.298] <TB1> INFO: pulse height fill bit: 0
[17:35:48.298] <TB1> INFO: buffer corruption: 0
[17:35:48.486] <TB1> INFO: ######################################################################
[17:35:48.486] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:35:48.486] <TB1> INFO: ######################################################################
[17:35:48.497] <TB1> INFO: scanning low vcal = 10
[17:35:48.803] <TB1> INFO: Expecting 41600 events.
[17:35:52.579] <TB1> INFO: 41600 events read in total (3060ms).
[17:35:52.579] <TB1> INFO: Test took 4082ms.
[17:35:52.582] <TB1> INFO: scanning low vcal = 20
[17:35:53.089] <TB1> INFO: Expecting 41600 events.
[17:35:56.875] <TB1> INFO: 41600 events read in total (3071ms).
[17:35:56.876] <TB1> INFO: Test took 4294ms.
[17:35:56.878] <TB1> INFO: scanning low vcal = 30
[17:35:57.385] <TB1> INFO: Expecting 41600 events.
[17:36:01.156] <TB1> INFO: 41600 events read in total (3055ms).
[17:36:01.158] <TB1> INFO: Test took 4280ms.
[17:36:01.161] <TB1> INFO: scanning low vcal = 40
[17:36:01.658] <TB1> INFO: Expecting 41600 events.
[17:36:05.982] <TB1> INFO: 41600 events read in total (3609ms).
[17:36:05.983] <TB1> INFO: Test took 4822ms.
[17:36:05.986] <TB1> INFO: scanning low vcal = 50
[17:36:06.430] <TB1> INFO: Expecting 41600 events.
[17:36:10.799] <TB1> INFO: 41600 events read in total (3654ms).
[17:36:10.800] <TB1> INFO: Test took 4814ms.
[17:36:10.804] <TB1> INFO: scanning low vcal = 60
[17:36:11.244] <TB1> INFO: Expecting 41600 events.
[17:36:15.577] <TB1> INFO: 41600 events read in total (3618ms).
[17:36:15.578] <TB1> INFO: Test took 4774ms.
[17:36:15.582] <TB1> INFO: scanning low vcal = 70
[17:36:16.023] <TB1> INFO: Expecting 41600 events.
[17:36:20.393] <TB1> INFO: 41600 events read in total (3654ms).
[17:36:20.394] <TB1> INFO: Test took 4812ms.
[17:36:20.398] <TB1> INFO: scanning low vcal = 80
[17:36:20.821] <TB1> INFO: Expecting 41600 events.
[17:36:25.191] <TB1> INFO: 41600 events read in total (3654ms).
[17:36:25.191] <TB1> INFO: Test took 4793ms.
[17:36:25.195] <TB1> INFO: scanning low vcal = 90
[17:36:25.644] <TB1> INFO: Expecting 41600 events.
[17:36:29.945] <TB1> INFO: 41600 events read in total (3586ms).
[17:36:29.946] <TB1> INFO: Test took 4751ms.
[17:36:29.950] <TB1> INFO: scanning low vcal = 100
[17:36:30.391] <TB1> INFO: Expecting 41600 events.
[17:36:34.858] <TB1> INFO: 41600 events read in total (3752ms).
[17:36:34.858] <TB1> INFO: Test took 4908ms.
[17:36:34.863] <TB1> INFO: scanning low vcal = 110
[17:36:35.300] <TB1> INFO: Expecting 41600 events.
[17:36:39.652] <TB1> INFO: 41600 events read in total (3636ms).
[17:36:39.653] <TB1> INFO: Test took 4790ms.
[17:36:39.656] <TB1> INFO: scanning low vcal = 120
[17:36:40.100] <TB1> INFO: Expecting 41600 events.
[17:36:44.458] <TB1> INFO: 41600 events read in total (3642ms).
[17:36:44.458] <TB1> INFO: Test took 4802ms.
[17:36:44.462] <TB1> INFO: scanning low vcal = 130
[17:36:44.902] <TB1> INFO: Expecting 41600 events.
[17:36:49.259] <TB1> INFO: 41600 events read in total (3642ms).
[17:36:49.260] <TB1> INFO: Test took 4798ms.
[17:36:49.263] <TB1> INFO: scanning low vcal = 140
[17:36:49.709] <TB1> INFO: Expecting 41600 events.
[17:36:54.108] <TB1> INFO: 41600 events read in total (3684ms).
[17:36:54.108] <TB1> INFO: Test took 4845ms.
[17:36:54.112] <TB1> INFO: scanning low vcal = 150
[17:36:54.532] <TB1> INFO: Expecting 41600 events.
[17:36:58.916] <TB1> INFO: 41600 events read in total (3668ms).
[17:36:58.917] <TB1> INFO: Test took 4805ms.
[17:36:58.921] <TB1> INFO: scanning low vcal = 160
[17:36:59.365] <TB1> INFO: Expecting 41600 events.
[17:37:03.683] <TB1> INFO: 41600 events read in total (3602ms).
[17:37:03.683] <TB1> INFO: Test took 4762ms.
[17:37:03.686] <TB1> INFO: scanning low vcal = 170
[17:37:04.126] <TB1> INFO: Expecting 41600 events.
[17:37:08.500] <TB1> INFO: 41600 events read in total (3659ms).
[17:37:08.501] <TB1> INFO: Test took 4815ms.
[17:37:08.505] <TB1> INFO: scanning low vcal = 180
[17:37:08.929] <TB1> INFO: Expecting 41600 events.
[17:37:13.279] <TB1> INFO: 41600 events read in total (3635ms).
[17:37:13.279] <TB1> INFO: Test took 4774ms.
[17:37:13.283] <TB1> INFO: scanning low vcal = 190
[17:37:13.728] <TB1> INFO: Expecting 41600 events.
[17:37:18.076] <TB1> INFO: 41600 events read in total (3633ms).
[17:37:18.077] <TB1> INFO: Test took 4794ms.
[17:37:18.080] <TB1> INFO: scanning low vcal = 200
[17:37:18.528] <TB1> INFO: Expecting 41600 events.
[17:37:22.923] <TB1> INFO: 41600 events read in total (3679ms).
[17:37:22.924] <TB1> INFO: Test took 4844ms.
[17:37:22.927] <TB1> INFO: scanning low vcal = 210
[17:37:23.342] <TB1> INFO: Expecting 41600 events.
[17:37:27.717] <TB1> INFO: 41600 events read in total (3660ms).
[17:37:27.717] <TB1> INFO: Test took 4790ms.
[17:37:27.721] <TB1> INFO: scanning low vcal = 220
[17:37:28.158] <TB1> INFO: Expecting 41600 events.
[17:37:32.515] <TB1> INFO: 41600 events read in total (3641ms).
[17:37:32.516] <TB1> INFO: Test took 4795ms.
[17:37:32.519] <TB1> INFO: scanning low vcal = 230
[17:37:32.959] <TB1> INFO: Expecting 41600 events.
[17:37:37.323] <TB1> INFO: 41600 events read in total (3648ms).
[17:37:37.324] <TB1> INFO: Test took 4805ms.
[17:37:37.328] <TB1> INFO: scanning low vcal = 240
[17:37:37.769] <TB1> INFO: Expecting 41600 events.
[17:37:42.055] <TB1> INFO: 41600 events read in total (3571ms).
[17:37:42.056] <TB1> INFO: Test took 4728ms.
[17:37:42.059] <TB1> INFO: scanning low vcal = 250
[17:37:42.479] <TB1> INFO: Expecting 41600 events.
[17:37:46.856] <TB1> INFO: 41600 events read in total (3662ms).
[17:37:46.857] <TB1> INFO: Test took 4798ms.
[17:37:46.863] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[17:37:47.291] <TB1> INFO: Expecting 41600 events.
[17:37:51.669] <TB1> INFO: 41600 events read in total (3663ms).
[17:37:51.671] <TB1> INFO: Test took 4808ms.
[17:37:51.677] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[17:37:52.097] <TB1> INFO: Expecting 41600 events.
[17:37:56.485] <TB1> INFO: 41600 events read in total (3673ms).
[17:37:56.486] <TB1> INFO: Test took 4809ms.
[17:37:56.490] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[17:37:56.927] <TB1> INFO: Expecting 41600 events.
[17:38:01.296] <TB1> INFO: 41600 events read in total (3653ms).
[17:38:01.297] <TB1> INFO: Test took 4807ms.
[17:38:01.301] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[17:38:01.740] <TB1> INFO: Expecting 41600 events.
[17:38:06.080] <TB1> INFO: 41600 events read in total (3624ms).
[17:38:06.081] <TB1> INFO: Test took 4780ms.
[17:38:06.084] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:38:06.534] <TB1> INFO: Expecting 41600 events.
[17:38:10.863] <TB1> INFO: 41600 events read in total (3613ms).
[17:38:10.863] <TB1> INFO: Test took 4779ms.
[17:38:11.354] <TB1> INFO: PixTestGainPedestal::measure() done
[17:38:44.442] <TB1> INFO: PixTestGainPedestal::fit() done
[17:38:44.442] <TB1> INFO: non-linearity mean: 0.953 0.949 0.959 0.954 0.954 0.959 0.954 0.955 0.966 0.958 0.951 0.954 0.961 0.949 0.950 0.954
[17:38:44.442] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.007 0.006 0.006 0.007 0.003 0.006 0.008 0.008 0.006 0.006 0.009 0.007
[17:38:44.442] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[17:38:44.463] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[17:38:44.484] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[17:38:44.504] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[17:38:44.525] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[17:38:44.545] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[17:38:44.566] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[17:38:44.587] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[17:38:44.607] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[17:38:44.627] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[17:38:44.648] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[17:38:44.668] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[17:38:44.688] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[17:38:44.709] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[17:38:44.729] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[17:38:44.750] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[17:38:44.770] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 176 seconds
[17:38:44.770] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:44.770] <TB1> INFO: Decoding statistics:
[17:38:44.770] <TB1> INFO: General information:
[17:38:44.770] <TB1> INFO: 16bit words read: 2329568
[17:38:44.770] <TB1> INFO: valid events total: 83200
[17:38:44.770] <TB1> INFO: empty events: 0
[17:38:44.770] <TB1> INFO: valid events with pixels: 83200
[17:38:44.770] <TB1> INFO: valid pixel hits: 665584
[17:38:44.770] <TB1> INFO: Event errors: 0
[17:38:44.770] <TB1> INFO: start marker: 0
[17:38:44.770] <TB1> INFO: stop marker: 0
[17:38:44.770] <TB1> INFO: overflow: 0
[17:38:44.770] <TB1> INFO: invalid 5bit words: 0
[17:38:44.770] <TB1> INFO: invalid XOR eye diagram: 0
[17:38:44.770] <TB1> INFO: TBM errors: 0
[17:38:44.770] <TB1> INFO: flawed TBM headers: 0
[17:38:44.770] <TB1> INFO: flawed TBM trailers: 0
[17:38:44.770] <TB1> INFO: event ID mismatches: 0
[17:38:44.770] <TB1> INFO: ROC errors: 0
[17:38:44.770] <TB1> INFO: missing ROC header(s): 0
[17:38:44.770] <TB1> INFO: misplaced readback start: 0
[17:38:44.770] <TB1> INFO: Pixel decoding errors: 0
[17:38:44.770] <TB1> INFO: pixel data incomplete: 0
[17:38:44.770] <TB1> INFO: pixel address: 0
[17:38:44.770] <TB1> INFO: pulse height fill bit: 0
[17:38:44.770] <TB1> INFO: buffer corruption: 0
[17:38:44.777] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C15.dat
[17:38:44.858] <TB1> INFO: ######################################################################
[17:38:44.858] <TB1> INFO: PixTestReadback::doTest()
[17:38:44.859] <TB1> INFO: ######################################################################
[17:38:44.859] <TB1> INFO: PixTestReadback::RES sent once
[17:38:56.107] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C0.dat
[17:38:56.108] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C1.dat
[17:38:56.108] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C2.dat
[17:38:56.108] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C3.dat
[17:38:56.108] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C4.dat
[17:38:56.108] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C5.dat
[17:38:56.108] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C6.dat
[17:38:56.109] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C7.dat
[17:38:56.109] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C8.dat
[17:38:56.109] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C9.dat
[17:38:56.109] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C10.dat
[17:38:56.109] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C11.dat
[17:38:56.109] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C12.dat
[17:38:56.109] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C13.dat
[17:38:56.109] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C14.dat
[17:38:56.110] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C15.dat
[17:38:56.162] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[17:38:56.163] <TB1> INFO: PixTestReadback::RES sent once
[17:39:07.387] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C0.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C1.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C2.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C3.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C4.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C5.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C6.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C7.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C8.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C9.dat
[17:39:07.388] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C10.dat
[17:39:07.389] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C11.dat
[17:39:07.389] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C12.dat
[17:39:07.389] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C13.dat
[17:39:07.389] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C14.dat
[17:39:07.389] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C15.dat
[17:39:07.438] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[17:39:07.439] <TB1> INFO: PixTestReadback::RES sent once
[17:39:16.079] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[17:39:16.079] <TB1> INFO: Vbg will be calibrated using Vd calibration
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.3calibrated Vbg = 1.19825 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150.5calibrated Vbg = 1.19248 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.8calibrated Vbg = 1.19568 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154calibrated Vbg = 1.20161 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.6calibrated Vbg = 1.21003 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.2calibrated Vbg = 1.20899 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148.6calibrated Vbg = 1.21026 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 154.9calibrated Vbg = 1.20609 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.8calibrated Vbg = 1.21168 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.4calibrated Vbg = 1.20908 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 150.5calibrated Vbg = 1.20143 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145.4calibrated Vbg = 1.20217 :::*/*/*/*/
[17:39:16.079] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 144.5calibrated Vbg = 1.20304 :::*/*/*/*/
[17:39:16.080] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.3calibrated Vbg = 1.20177 :::*/*/*/*/
[17:39:16.080] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.2calibrated Vbg = 1.19717 :::*/*/*/*/
[17:39:16.080] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.3calibrated Vbg = 1.19994 :::*/*/*/*/
[17:39:16.084] <TB1> INFO: PixTestReadback::RES sent once
[17:42:10.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C0.dat
[17:42:10.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C1.dat
[17:42:10.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C2.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C3.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C4.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C5.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C6.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C7.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C8.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C9.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C10.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C11.dat
[17:42:10.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C12.dat
[17:42:10.974] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C13.dat
[17:42:10.974] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C14.dat
[17:42:10.974] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//004_FulltestPxar_p17//readbackCal_C15.dat
[17:42:11.020] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[17:42:11.022] <TB1> INFO: PixTestReadback::doTest() done
[17:42:11.022] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:42:11.022] <TB1> INFO: Decoding statistics:
[17:42:11.022] <TB1> INFO: General information:
[17:42:11.022] <TB1> INFO: 16bit words read: 768
[17:42:11.022] <TB1> INFO: valid events total: 64
[17:42:11.022] <TB1> INFO: empty events: 64
[17:42:11.022] <TB1> INFO: valid events with pixels: 0
[17:42:11.022] <TB1> INFO: valid pixel hits: 0
[17:42:11.022] <TB1> INFO: Event errors: 0
[17:42:11.022] <TB1> INFO: start marker: 0
[17:42:11.022] <TB1> INFO: stop marker: 0
[17:42:11.022] <TB1> INFO: overflow: 0
[17:42:11.022] <TB1> INFO: invalid 5bit words: 0
[17:42:11.022] <TB1> INFO: invalid XOR eye diagram: 0
[17:42:11.022] <TB1> INFO: TBM errors: 0
[17:42:11.022] <TB1> INFO: flawed TBM headers: 0
[17:42:11.022] <TB1> INFO: flawed TBM trailers: 0
[17:42:11.022] <TB1> INFO: event ID mismatches: 0
[17:42:11.022] <TB1> INFO: ROC errors: 0
[17:42:11.022] <TB1> INFO: missing ROC header(s): 0
[17:42:11.022] <TB1> INFO: misplaced readback start: 0
[17:42:11.022] <TB1> INFO: Pixel decoding errors: 0
[17:42:11.022] <TB1> INFO: pixel data incomplete: 0
[17:42:11.022] <TB1> INFO: pixel address: 0
[17:42:11.022] <TB1> INFO: pulse height fill bit: 0
[17:42:11.022] <TB1> INFO: buffer corruption: 0
[17:42:11.037] <TB1> INFO: Decoding statistics:
[17:42:11.037] <TB1> INFO: General information:
[17:42:11.037] <TB1> INFO: 16bit words read: 2396780
[17:42:11.037] <TB1> INFO: valid events total: 88384
[17:42:11.037] <TB1> INFO: empty events: 2682
[17:42:11.037] <TB1> INFO: valid events with pixels: 85702
[17:42:11.037] <TB1> INFO: valid pixel hits: 668086
[17:42:11.037] <TB1> INFO: Event errors: 0
[17:42:11.037] <TB1> INFO: start marker: 0
[17:42:11.037] <TB1> INFO: stop marker: 0
[17:42:11.037] <TB1> INFO: overflow: 0
[17:42:11.037] <TB1> INFO: invalid 5bit words: 0
[17:42:11.037] <TB1> INFO: invalid XOR eye diagram: 0
[17:42:11.037] <TB1> INFO: TBM errors: 0
[17:42:11.037] <TB1> INFO: flawed TBM headers: 0
[17:42:11.037] <TB1> INFO: flawed TBM trailers: 0
[17:42:11.037] <TB1> INFO: event ID mismatches: 0
[17:42:11.037] <TB1> INFO: ROC errors: 0
[17:42:11.037] <TB1> INFO: missing ROC header(s): 0
[17:42:11.037] <TB1> INFO: misplaced readback start: 0
[17:42:11.037] <TB1> INFO: Pixel decoding errors: 0
[17:42:11.037] <TB1> INFO: pixel data incomplete: 0
[17:42:11.037] <TB1> INFO: pixel address: 0
[17:42:11.037] <TB1> INFO: pulse height fill bit: 0
[17:42:11.037] <TB1> INFO: buffer corruption: 0
[17:42:11.037] <TB1> INFO: enter test to run
[17:42:11.037] <TB1> INFO: test: exit no parameter change
[17:42:11.259] <TB1> QUIET: Connection to board 153 closed.
[17:42:11.338] <TB1> INFO: pXar: this is the end, my friend