[17:22:36.331] <TB0> INFO: *** Welcome to pxar ***
[17:22:36.331] <TB0> INFO: *** Today: 2015/10/29
[17:22:37.012] <TB0> INFO: *** Version: 9da6-dirty
[17:22:37.012] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C15.dat
[17:22:37.013] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//tbmParameters_C0b.dat
[17:22:37.013] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//defaultMaskFile.dat
[17:22:37.013] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters_C15.dat
[17:22:37.080] <TB0> INFO: clk: 4
[17:22:37.080] <TB0> INFO: ctr: 4
[17:22:37.080] <TB0> INFO: sda: 19
[17:22:37.080] <TB0> INFO: tin: 9
[17:22:37.080] <TB0> INFO: level: 15
[17:22:37.080] <TB0> INFO: triggerdelay: 0
[17:22:37.080] <TB0> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[17:22:37.080] <TB0> INFO: Log level: INFO
[17:22:37.091] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[17:22:37.094] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[17:22:37.098] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[17:22:38.635] <TB0> INFO: DUT info:
[17:22:38.635] <TB0> INFO: The DUT currently contains the following objects:
[17:22:38.635] <TB0> INFO: 2 TBM Cores tbm08c (2 ON)
[17:22:38.635] <TB0> INFO: TBM Core alpha (0): 7 registers set
[17:22:38.635] <TB0> INFO: TBM Core beta (1): 7 registers set
[17:22:38.635] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:22:38.635] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:38.635] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:22:39.036] <TB0> INFO: enter 'restricted' command line mode
[17:22:39.036] <TB0> INFO: enter test to run
[17:22:39.036] <TB0> INFO: test: pretest no parameter change
[17:22:39.036] <TB0> INFO: running: pretest
[17:22:39.041] <TB0> INFO: ######################################################################
[17:22:39.041] <TB0> INFO: PixTestPretest::doTest()
[17:22:39.041] <TB0> INFO: ######################################################################
[17:22:39.042] <TB0> INFO: ----------------------------------------------------------------------
[17:22:39.042] <TB0> INFO: PixTestPretest::programROC()
[17:22:39.042] <TB0> INFO: ----------------------------------------------------------------------
[17:22:57.059] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[17:22:57.059] <TB0> INFO: IA differences per ROC: 18.5 16.1 18.5 16.9 15.3 20.1 19.3 18.5 17.7 19.3 17.7 20.1 16.9 18.5 19.3 19.3
[17:22:57.118] <TB0> INFO: ----------------------------------------------------------------------
[17:22:57.119] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[17:22:57.119] <TB0> INFO: ----------------------------------------------------------------------
[17:23:02.901] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 388.3 mA = 24.2687 mA/ROC
[17:23:02.901] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 19.3 19.3 19.3 19.3
[17:23:02.929] <TB0> INFO: ----------------------------------------------------------------------
[17:23:02.929] <TB0> INFO: PixTestPretest::findTiming()
[17:23:02.929] <TB0> INFO: ----------------------------------------------------------------------
[17:23:02.929] <TB0> INFO: PixTestCmd::init()
[17:23:03.527] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[17:24:37.285] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[17:24:37.285] <TB0> INFO: (success/tries = 100/100), width = 3
[17:24:37.286] <TB0> INFO: ----------------------------------------------------------------------
[17:24:37.287] <TB0> INFO: PixTestPretest::findWorkingPixel()
[17:24:37.287] <TB0> INFO: ----------------------------------------------------------------------
[17:24:37.422] <TB0> INFO: Expecting 231680 events.
[17:24:42.032] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[17:24:42.035] <TB0> ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[17:24:44.470] <TB0> INFO: 231680 events read in total (6333ms).
[17:24:44.474] <TB0> INFO: Test took 7185ms.
[17:24:44.873] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[17:24:44.902] <TB0> INFO: ----------------------------------------------------------------------
[17:24:44.902] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[17:24:44.902] <TB0> INFO: ----------------------------------------------------------------------
[17:24:45.036] <TB0> INFO: Expecting 231680 events.
[17:24:53.021] <TB0> INFO: 231680 events read in total (7270ms).
[17:24:53.025] <TB0> INFO: Test took 8120ms.
[17:24:53.432] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[17:24:53.432] <TB0> INFO: CalDel: 125 129 131 126 95 130 132 122 139 110 111 113 105 104 118 122
[17:24:53.432] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 53 51 51 51 51 51
[17:24:53.435] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C0.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C1.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C2.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C3.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C4.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C5.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C6.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C7.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C8.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C9.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C10.dat
[17:24:53.436] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C11.dat
[17:24:53.437] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C12.dat
[17:24:53.437] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C13.dat
[17:24:53.437] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C14.dat
[17:24:53.437] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters_C15.dat
[17:24:53.437] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//tbmParameters_C0a.dat
[17:24:53.437] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//tbmParameters_C0b.dat
[17:24:53.437] <TB0> INFO: PixTestPretest::doTest() done, duration: 134 seconds
[17:24:53.499] <TB0> INFO: enter test to run
[17:24:53.499] <TB0> INFO: test: fulltest no parameter change
[17:24:53.499] <TB0> INFO: running: fulltest
[17:24:53.499] <TB0> INFO: ######################################################################
[17:24:53.499] <TB0> INFO: PixTestFullTest::doTest()
[17:24:53.499] <TB0> INFO: ######################################################################
[17:24:53.500] <TB0> INFO: ######################################################################
[17:24:53.500] <TB0> INFO: PixTestAlive::doTest()
[17:24:53.500] <TB0> INFO: ######################################################################
[17:24:53.502] <TB0> INFO: ----------------------------------------------------------------------
[17:24:53.502] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:24:53.502] <TB0> INFO: ----------------------------------------------------------------------
[17:24:53.800] <TB0> INFO: Expecting 41600 events.
[17:24:57.719] <TB0> INFO: 41600 events read in total (3204ms).
[17:24:57.720] <TB0> INFO: Test took 4217ms.
[17:24:57.726] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:58.110] <TB0> INFO: PixTestAlive::aliveTest() done
[17:24:58.110] <TB0> INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 4 4 3 1 1 0 0 0 0 0
[17:24:58.112] <TB0> INFO: ----------------------------------------------------------------------
[17:24:58.112] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:24:58.112] <TB0> INFO: ----------------------------------------------------------------------
[17:24:58.411] <TB0> INFO: Expecting 41600 events.
[17:25:01.303] <TB0> INFO: 41600 events read in total (2177ms).
[17:25:01.303] <TB0> INFO: Test took 3190ms.
[17:25:01.303] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:01.304] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:25:01.708] <TB0> INFO: PixTestAlive::maskTest() done
[17:25:01.708] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:25:01.709] <TB0> INFO: ----------------------------------------------------------------------
[17:25:01.709] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:25:01.709] <TB0> INFO: ----------------------------------------------------------------------
[17:25:02.009] <TB0> INFO: Expecting 41600 events.
[17:25:05.937] <TB0> INFO: 41600 events read in total (3213ms).
[17:25:05.938] <TB0> INFO: Test took 4228ms.
[17:25:05.944] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:06.328] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[17:25:06.328] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:25:06.328] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[17:25:06.328] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:06.328] <TB0> INFO: Decoding statistics:
[17:25:06.328] <TB0> INFO: General information:
[17:25:06.328] <TB0> INFO: 16bit words read: 0
[17:25:06.328] <TB0> INFO: valid events total: 0
[17:25:06.328] <TB0> INFO: empty events: 0
[17:25:06.328] <TB0> INFO: valid events with pixels: 0
[17:25:06.328] <TB0> INFO: valid pixel hits: 0
[17:25:06.328] <TB0> INFO: Event errors: 0
[17:25:06.328] <TB0> INFO: start marker: 0
[17:25:06.328] <TB0> INFO: stop marker: 0
[17:25:06.328] <TB0> INFO: overflow: 0
[17:25:06.328] <TB0> INFO: invalid 5bit words: 0
[17:25:06.328] <TB0> INFO: invalid XOR eye diagram: 0
[17:25:06.328] <TB0> INFO: TBM errors: 0
[17:25:06.328] <TB0> INFO: flawed TBM headers: 0
[17:25:06.328] <TB0> INFO: flawed TBM trailers: 0
[17:25:06.328] <TB0> INFO: event ID mismatches: 0
[17:25:06.328] <TB0> INFO: ROC errors: 0
[17:25:06.328] <TB0> INFO: missing ROC header(s): 0
[17:25:06.328] <TB0> INFO: misplaced readback start: 0
[17:25:06.328] <TB0> INFO: Pixel decoding errors: 0
[17:25:06.328] <TB0> INFO: pixel data incomplete: 0
[17:25:06.328] <TB0> INFO: pixel address: 0
[17:25:06.328] <TB0> INFO: pulse height fill bit: 0
[17:25:06.328] <TB0> INFO: buffer corruption: 0
[17:25:06.335] <TB0> INFO: ######################################################################
[17:25:06.335] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[17:25:06.335] <TB0> INFO: ######################################################################
[17:25:06.337] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[17:25:06.348] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:25:06.348] <TB0> INFO: run 1 of 1
[17:25:06.647] <TB0> INFO: Expecting 3120000 events.
[17:25:47.595] <TB0> INFO: 1002700 events read in total (40233ms).
[17:26:27.429] <TB0> INFO: 1985205 events read in total (80068ms).
[17:27:07.823] <TB0> INFO: 2993630 events read in total (120462ms).
[17:27:13.158] <TB0> INFO: 3120000 events read in total (125796ms).
[17:27:13.207] <TB0> INFO: Test took 126859ms.
[17:27:13.315] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:34.336] <TB0> INFO: PixTestBBMap::doTest() done, duration: 148 seconds
[17:27:34.336] <TB0> INFO: number of dead bumps (per ROC): 1 0 0 0 0 3 1 3 2 1 0 0 0 2 0 2
[17:27:34.336] <TB0> INFO: separation cut (per ROC): 76 82 91 77 76 71 76 76 76 92 102 92 81 78 87 86
[17:27:34.336] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:34.336] <TB0> INFO: Decoding statistics:
[17:27:34.336] <TB0> INFO: General information:
[17:27:34.336] <TB0> INFO: 16bit words read: 0
[17:27:34.336] <TB0> INFO: valid events total: 0
[17:27:34.336] <TB0> INFO: empty events: 0
[17:27:34.336] <TB0> INFO: valid events with pixels: 0
[17:27:34.336] <TB0> INFO: valid pixel hits: 0
[17:27:34.336] <TB0> INFO: Event errors: 0
[17:27:34.336] <TB0> INFO: start marker: 0
[17:27:34.336] <TB0> INFO: stop marker: 0
[17:27:34.336] <TB0> INFO: overflow: 0
[17:27:34.336] <TB0> INFO: invalid 5bit words: 0
[17:27:34.336] <TB0> INFO: invalid XOR eye diagram: 0
[17:27:34.336] <TB0> INFO: TBM errors: 0
[17:27:34.336] <TB0> INFO: flawed TBM headers: 0
[17:27:34.336] <TB0> INFO: flawed TBM trailers: 0
[17:27:34.336] <TB0> INFO: event ID mismatches: 0
[17:27:34.336] <TB0> INFO: ROC errors: 0
[17:27:34.336] <TB0> INFO: missing ROC header(s): 0
[17:27:34.336] <TB0> INFO: misplaced readback start: 0
[17:27:34.336] <TB0> INFO: Pixel decoding errors: 0
[17:27:34.336] <TB0> INFO: pixel data incomplete: 0
[17:27:34.336] <TB0> INFO: pixel address: 0
[17:27:34.336] <TB0> INFO: pulse height fill bit: 0
[17:27:34.336] <TB0> INFO: buffer corruption: 0
[17:27:34.408] <TB0> INFO: ######################################################################
[17:27:34.408] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:27:34.408] <TB0> INFO: ######################################################################
[17:27:34.408] <TB0> INFO: ----------------------------------------------------------------------
[17:27:34.408] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:27:34.408] <TB0> INFO: ----------------------------------------------------------------------
[17:27:34.408] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:27:34.416] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[17:27:34.416] <TB0> INFO: run 1 of 1
[17:27:34.713] <TB0> INFO: Expecting 26208000 events.
[17:28:02.738] <TB0> INFO: 1196200 events read in total (27310ms).
[17:28:29.937] <TB0> INFO: 2370750 events read in total (54509ms).
[17:28:57.051] <TB0> INFO: 3545100 events read in total (81623ms).
[17:29:24.235] <TB0> INFO: 4710350 events read in total (108807ms).
[17:29:51.390] <TB0> INFO: 5876500 events read in total (135962ms).
[17:30:18.544] <TB0> INFO: 7042850 events read in total (163116ms).
[17:30:45.636] <TB0> INFO: 8206800 events read in total (190208ms).
[17:31:12.794] <TB0> INFO: 9369550 events read in total (217366ms).
[17:31:40.065] <TB0> INFO: 10529400 events read in total (244637ms).
[17:32:07.156] <TB0> INFO: 11690400 events read in total (271728ms).
[17:32:34.273] <TB0> INFO: 12845050 events read in total (298845ms).
[17:33:01.238] <TB0> INFO: 13985700 events read in total (325810ms).
[17:33:28.257] <TB0> INFO: 15124450 events read in total (352829ms).
[17:33:55.161] <TB0> INFO: 16258100 events read in total (379733ms).
[17:34:22.072] <TB0> INFO: 17387100 events read in total (406644ms).
[17:34:49.148] <TB0> INFO: 18517150 events read in total (433720ms).
[17:35:16.011] <TB0> INFO: 19641550 events read in total (460583ms).
[17:35:42.856] <TB0> INFO: 20769300 events read in total (487428ms).
[17:36:09.632] <TB0> INFO: 21891500 events read in total (514204ms).
[17:36:36.403] <TB0> INFO: 23015100 events read in total (540975ms).
[17:37:03.202] <TB0> INFO: 24142050 events read in total (567774ms).
[17:37:30.224] <TB0> INFO: 25273900 events read in total (594796ms).
[17:37:52.186] <TB0> INFO: 26208000 events read in total (616758ms).
[17:37:52.222] <TB0> INFO: Test took 617805ms.
[17:37:52.286] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:52.406] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:37:53.747] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:37:55.060] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:37:56.342] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:37:57.665] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:37:58.990] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:00.316] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:01.633] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:02.975] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:04.311] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:05.614] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:07.091] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:08.380] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:09.672] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:10.974] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:12.247] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:38:13.568] <TB0> INFO: PixTestScurves::scurves() done
[17:38:13.568] <TB0> INFO: Vcal mean: 88.65 104.42 110.89 98.46 96.10 98.26 102.42 91.28 96.72 104.43 114.53 102.84 103.75 102.46 103.55 102.55
[17:38:13.568] <TB0> INFO: Vcal RMS: 5.13 5.11 5.33 6.10 5.22 5.71 5.96 6.50 6.45 6.01 5.78 5.77 5.62 5.32 5.55 6.58
[17:38:13.569] <TB0> INFO: PixTestScurves::fullTest() done, duration: 639 seconds
[17:38:13.569] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:13.569] <TB0> INFO: Decoding statistics:
[17:38:13.569] <TB0> INFO: General information:
[17:38:13.569] <TB0> INFO: 16bit words read: 0
[17:38:13.569] <TB0> INFO: valid events total: 0
[17:38:13.569] <TB0> INFO: empty events: 0
[17:38:13.569] <TB0> INFO: valid events with pixels: 0
[17:38:13.569] <TB0> INFO: valid pixel hits: 0
[17:38:13.569] <TB0> INFO: Event errors: 0
[17:38:13.569] <TB0> INFO: start marker: 0
[17:38:13.569] <TB0> INFO: stop marker: 0
[17:38:13.569] <TB0> INFO: overflow: 0
[17:38:13.569] <TB0> INFO: invalid 5bit words: 0
[17:38:13.569] <TB0> INFO: invalid XOR eye diagram: 0
[17:38:13.569] <TB0> INFO: TBM errors: 0
[17:38:13.569] <TB0> INFO: flawed TBM headers: 0
[17:38:13.569] <TB0> INFO: flawed TBM trailers: 0
[17:38:13.569] <TB0> INFO: event ID mismatches: 0
[17:38:13.569] <TB0> INFO: ROC errors: 0
[17:38:13.569] <TB0> INFO: missing ROC header(s): 0
[17:38:13.569] <TB0> INFO: misplaced readback start: 0
[17:38:13.569] <TB0> INFO: Pixel decoding errors: 0
[17:38:13.569] <TB0> INFO: pixel data incomplete: 0
[17:38:13.569] <TB0> INFO: pixel address: 0
[17:38:13.569] <TB0> INFO: pulse height fill bit: 0
[17:38:13.569] <TB0> INFO: buffer corruption: 0
[17:38:13.638] <TB0> INFO: ######################################################################
[17:38:13.638] <TB0> INFO: PixTestTrim::doTest()
[17:38:13.638] <TB0> INFO: ######################################################################
[17:38:13.639] <TB0> INFO: ----------------------------------------------------------------------
[17:38:13.639] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[17:38:13.639] <TB0> INFO: ----------------------------------------------------------------------
[17:38:13.718] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[17:38:13.718] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:38:13.726] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:38:13.726] <TB0> INFO: run 1 of 1
[17:38:14.022] <TB0> INFO: Expecting 6281600 events.
[17:38:53.508] <TB0> INFO: 1440200 events read in total (38768ms).
[17:39:32.162] <TB0> INFO: 2867290 events read in total (77422ms).
[17:40:10.847] <TB0> INFO: 4297310 events read in total (116108ms).
[17:40:49.767] <TB0> INFO: 5737410 events read in total (155027ms).
[17:41:04.668] <TB0> INFO: 6281600 events read in total (169928ms).
[17:41:04.698] <TB0> INFO: Test took 170972ms.
[17:41:04.745] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:22.220] <TB0> INFO: ROC 0 VthrComp = 89
[17:41:22.221] <TB0> INFO: ROC 1 VthrComp = 100
[17:41:22.221] <TB0> INFO: ROC 2 VthrComp = 105
[17:41:22.221] <TB0> INFO: ROC 3 VthrComp = 94
[17:41:22.221] <TB0> INFO: ROC 4 VthrComp = 92
[17:41:22.221] <TB0> INFO: ROC 5 VthrComp = 94
[17:41:22.221] <TB0> INFO: ROC 6 VthrComp = 101
[17:41:22.221] <TB0> INFO: ROC 7 VthrComp = 92
[17:41:22.221] <TB0> INFO: ROC 8 VthrComp = 93
[17:41:22.221] <TB0> INFO: ROC 9 VthrComp = 102
[17:41:22.221] <TB0> INFO: ROC 10 VthrComp = 107
[17:41:22.221] <TB0> INFO: ROC 11 VthrComp = 101
[17:41:22.221] <TB0> INFO: ROC 12 VthrComp = 102
[17:41:22.222] <TB0> INFO: ROC 13 VthrComp = 101
[17:41:22.222] <TB0> INFO: ROC 14 VthrComp = 102
[17:41:22.222] <TB0> INFO: ROC 15 VthrComp = 100
[17:41:22.222] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[17:41:22.222] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:41:22.230] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:41:22.230] <TB0> INFO: run 1 of 1
[17:41:22.526] <TB0> INFO: Expecting 6281600 events.
[17:41:53.902] <TB0> INFO: 896410 events read in total (30661ms).
[17:42:24.657] <TB0> INFO: 1788940 events read in total (61416ms).
[17:42:55.465] <TB0> INFO: 2682650 events read in total (92224ms).
[17:43:26.180] <TB0> INFO: 3570590 events read in total (122939ms).
[17:43:56.724] <TB0> INFO: 4449690 events read in total (153483ms).
[17:44:27.181] <TB0> INFO: 5324760 events read in total (183940ms).
[17:44:57.609] <TB0> INFO: 6199310 events read in total (214368ms).
[17:45:00.812] <TB0> INFO: 6281600 events read in total (217571ms).
[17:45:00.869] <TB0> INFO: Test took 218639ms.
[17:45:01.019] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:45:23.468] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 58.8179 for pixel 14/58 mean/min/max = 46.5043/34.1316/58.8769
[17:45:23.468] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 58.9967 for pixel 4/9 mean/min/max = 46.1201/33.2183/59.0218
[17:45:23.468] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 63.0596 for pixel 15/20 mean/min/max = 48.9406/34.8215/63.0597
[17:45:23.469] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 62.644 for pixel 9/72 mean/min/max = 47.9656/33.1661/62.7651
[17:45:23.469] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 60.0498 for pixel 6/12 mean/min/max = 46.919/33.7712/60.0667
[17:45:23.469] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 62.3759 for pixel 13/61 mean/min/max = 48.0837/33.7606/62.4069
[17:45:23.499] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 58.2356 for pixel 29/29 mean/min/max = 46.3507/34.2425/58.4589
[17:45:23.500] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 59.961 for pixel 3/0 mean/min/max = 46.8382/33.6254/60.051
[17:45:23.500] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 61.975 for pixel 5/73 mean/min/max = 47.8381/33.6713/62.0049
[17:45:23.500] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 60.362 for pixel 3/12 mean/min/max = 46.3104/32.205/60.4158
[17:45:23.500] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 66.308 for pixel 23/7 mean/min/max = 50.7171/35.0531/66.3811
[17:45:23.500] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 60.3399 for pixel 6/0 mean/min/max = 46.625/32.5428/60.7072
[17:45:23.501] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.7277 for pixel 18/67 mean/min/max = 45.9257/32.0116/59.8398
[17:45:23.501] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 58.6038 for pixel 21/5 mean/min/max = 45.2038/31.7602/58.6474
[17:45:23.501] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.553 for pixel 2/78 mean/min/max = 45.9882/32.3928/59.5835
[17:45:23.502] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.261 for pixel 22/2 mean/min/max = 45.1801/31.0245/59.3358
[17:45:23.502] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:45:23.633] <TB0> INFO: Expecting 514560 events.
[17:45:32.481] <TB0> INFO: 514560 events read in total (8133ms).
[17:45:32.485] <TB0> INFO: Expecting 514560 events.
[17:45:41.301] <TB0> INFO: 514560 events read in total (8138ms).
[17:45:41.308] <TB0> INFO: Expecting 514560 events.
[17:45:50.118] <TB0> INFO: 514560 events read in total (8133ms).
[17:45:50.126] <TB0> INFO: Expecting 514560 events.
[17:45:58.920] <TB0> INFO: 514560 events read in total (8121ms).
[17:45:58.930] <TB0> INFO: Expecting 514560 events.
[17:46:07.723] <TB0> INFO: 514560 events read in total (8120ms).
[17:46:07.734] <TB0> INFO: Expecting 514560 events.
[17:46:16.554] <TB0> INFO: 514560 events read in total (8149ms).
[17:46:16.567] <TB0> INFO: Expecting 514560 events.
[17:46:25.346] <TB0> INFO: 514560 events read in total (8106ms).
[17:46:25.361] <TB0> INFO: Expecting 514560 events.
[17:46:34.106] <TB0> INFO: 514560 events read in total (8075ms).
[17:46:34.123] <TB0> INFO: Expecting 514560 events.
[17:46:42.921] <TB0> INFO: 514560 events read in total (8132ms).
[17:46:42.939] <TB0> INFO: Expecting 514560 events.
[17:46:51.738] <TB0> INFO: 514560 events read in total (8133ms).
[17:46:51.757] <TB0> INFO: Expecting 514560 events.
[17:47:00.569] <TB0> INFO: 514560 events read in total (8150ms).
[17:47:00.590] <TB0> INFO: Expecting 514560 events.
[17:47:09.373] <TB0> INFO: 514560 events read in total (8124ms).
[17:47:09.396] <TB0> INFO: Expecting 514560 events.
[17:47:18.206] <TB0> INFO: 514560 events read in total (8150ms).
[17:47:18.235] <TB0> INFO: Expecting 514560 events.
[17:47:26.997] <TB0> INFO: 514560 events read in total (8111ms).
[17:47:27.025] <TB0> INFO: Expecting 514560 events.
[17:47:35.799] <TB0> INFO: 514560 events read in total (8119ms).
[17:47:35.827] <TB0> INFO: Expecting 514560 events.
[17:47:44.616] <TB0> INFO: 514560 events read in total (8134ms).
[17:47:44.649] <TB0> INFO: Test took 141147ms.
[17:47:45.586] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:47:45.594] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:47:45.596] <TB0> INFO: run 1 of 1
[17:47:45.893] <TB0> INFO: Expecting 6281600 events.
[17:48:16.909] <TB0> INFO: 873640 events read in total (30301ms).
[17:48:47.366] <TB0> INFO: 1743640 events read in total (60759ms).
[17:49:17.744] <TB0> INFO: 2614620 events read in total (91136ms).
[17:49:48.022] <TB0> INFO: 3481050 events read in total (121414ms).
[17:50:18.496] <TB0> INFO: 4338110 events read in total (151888ms).
[17:50:48.671] <TB0> INFO: 5192330 events read in total (182063ms).
[17:51:18.955] <TB0> INFO: 6044370 events read in total (212347ms).
[17:51:27.680] <TB0> INFO: 6281600 events read in total (221072ms).
[17:51:27.741] <TB0> INFO: Test took 222144ms.
[17:51:27.890] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:51:49.583] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.020177 .. 255.000000
[17:51:49.659] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[17:51:49.667] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:51:49.667] <TB0> INFO: run 1 of 1
[17:51:49.963] <TB0> INFO: Expecting 10649600 events.
[17:52:20.224] <TB0> INFO: 826860 events read in total (29542ms).
[17:52:49.781] <TB0> INFO: 1654580 events read in total (59099ms).
[17:53:19.306] <TB0> INFO: 2481920 events read in total (88624ms).
[17:53:48.786] <TB0> INFO: 3309400 events read in total (118104ms).
[17:54:18.486] <TB0> INFO: 4136730 events read in total (147804ms).
[17:54:48.041] <TB0> INFO: 4964390 events read in total (177359ms).
[17:55:17.670] <TB0> INFO: 5792030 events read in total (206988ms).
[17:55:47.243] <TB0> INFO: 6618760 events read in total (236561ms).
[17:56:16.872] <TB0> INFO: 7445170 events read in total (266190ms).
[17:56:46.567] <TB0> INFO: 8270410 events read in total (295885ms).
[17:57:16.213] <TB0> INFO: 9094480 events read in total (325532ms).
[17:57:45.700] <TB0> INFO: 9918660 events read in total (355018ms).
[17:58:11.990] <TB0> INFO: 10649600 events read in total (381308ms).
[17:58:12.085] <TB0> INFO: Test took 382418ms.
[17:58:12.351] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:39.170] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 13.569471 .. 54.838652
[17:58:39.248] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 3 .. 64 (-1/-1) hits flags = 528 (plus default)
[17:58:39.255] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:58:39.255] <TB0> INFO: run 1 of 1
[17:58:39.550] <TB0> INFO: Expecting 2579200 events.
[17:59:14.256] <TB0> INFO: 1094130 events read in total (33987ms).
[17:59:48.126] <TB0> INFO: 2186340 events read in total (67857ms).
[18:00:00.529] <TB0> INFO: 2579200 events read in total (80260ms).
[18:00:00.544] <TB0> INFO: Test took 81289ms.
[18:00:00.583] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:00:14.084] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 18.594571 .. 46.720659
[18:00:14.161] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 8 .. 56 (-1/-1) hits flags = 528 (plus default)
[18:00:14.169] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[18:00:14.169] <TB0> INFO: run 1 of 1
[18:00:14.469] <TB0> INFO: Expecting 2038400 events.
[18:00:49.957] <TB0> INFO: 1131330 events read in total (34773ms).
[18:01:17.915] <TB0> INFO: 2038400 events read in total (62731ms).
[18:01:17.935] <TB0> INFO: Test took 63766ms.
[18:01:17.967] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:01:30.463] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 21.962319 .. 46.720659
[18:01:30.540] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 11 .. 56 (-1/-1) hits flags = 528 (plus default)
[18:01:30.548] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[18:01:30.548] <TB0> INFO: run 1 of 1
[18:01:30.845] <TB0> INFO: Expecting 1913600 events.
[18:02:05.892] <TB0> INFO: 1106710 events read in total (34332ms).
[18:02:31.024] <TB0> INFO: 1913600 events read in total (59464ms).
[18:02:31.043] <TB0> INFO: Test took 60495ms.
[18:02:31.075] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:02:43.545] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[18:02:43.545] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[18:02:43.553] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[18:02:43.553] <TB0> INFO: run 1 of 1
[18:02:43.852] <TB0> INFO: Expecting 1705600 events.
[18:03:18.606] <TB0> INFO: 1076480 events read in total (34039ms).
[18:03:38.643] <TB0> INFO: 1705600 events read in total (54077ms).
[18:03:38.657] <TB0> INFO: Test took 55104ms.
[18:03:38.686] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:50.958] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C0.dat
[18:03:50.958] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C1.dat
[18:03:50.958] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C2.dat
[18:03:50.958] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C3.dat
[18:03:50.958] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C4.dat
[18:03:50.958] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C5.dat
[18:03:50.958] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C6.dat
[18:03:50.958] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C7.dat
[18:03:50.959] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C8.dat
[18:03:50.959] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C9.dat
[18:03:50.959] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C10.dat
[18:03:50.959] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C11.dat
[18:03:50.959] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C12.dat
[18:03:50.959] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C13.dat
[18:03:50.959] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C14.dat
[18:03:50.959] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C15.dat
[18:03:50.959] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C0.dat
[18:03:50.965] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C1.dat
[18:03:50.971] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C2.dat
[18:03:50.977] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C3.dat
[18:03:50.982] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C4.dat
[18:03:50.988] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C5.dat
[18:03:50.994] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C6.dat
[18:03:51.000] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C7.dat
[18:03:51.006] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C8.dat
[18:03:51.012] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C9.dat
[18:03:51.018] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C10.dat
[18:03:51.024] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C11.dat
[18:03:51.029] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C12.dat
[18:03:51.035] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C13.dat
[18:03:51.041] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C14.dat
[18:03:51.047] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//trimParameters35_C15.dat
[18:03:51.053] <TB0> INFO: PixTestTrim::trimTest() done
[18:03:51.053] <TB0> INFO: vtrim: 99 99 110 103 102 94 92 98 100 105 117 94 111 93 93 103
[18:03:51.053] <TB0> INFO: vthrcomp: 89 100 105 94 92 94 101 92 93 102 107 101 102 101 102 100
[18:03:51.053] <TB0> INFO: vcal mean: 34.93 34.96 34.95 34.96 34.95 34.98 35.00 34.95 34.96 34.99 34.94 34.97 34.95 34.96 35.00 34.95
[18:03:51.053] <TB0> INFO: vcal RMS: 0.83 0.93 1.08 1.11 0.86 0.92 1.41 1.43 1.31 0.94 1.09 0.91 0.95 0.92 0.87 1.02
[18:03:51.053] <TB0> INFO: bits mean: 8.93 9.48 8.79 8.72 9.11 8.58 9.01 9.37 8.95 9.49 8.19 9.24 9.62 9.98 9.11 10.09
[18:03:51.053] <TB0> INFO: bits RMS: 2.62 2.53 2.40 2.75 2.53 2.70 2.59 2.46 2.60 2.60 2.45 2.74 2.61 2.54 2.84 2.60
[18:03:51.059] <TB0> INFO: ----------------------------------------------------------------------
[18:03:51.059] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[18:03:51.059] <TB0> INFO: ----------------------------------------------------------------------
[18:03:51.062] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[18:03:51.070] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[18:03:51.070] <TB0> INFO: run 1 of 1
[18:03:51.366] <TB0> INFO: Expecting 8320000 events.
[18:04:27.104] <TB0> INFO: 1223620 events read in total (35023ms).
[18:05:02.084] <TB0> INFO: 2435750 events read in total (70003ms).
[18:05:36.920] <TB0> INFO: 3642110 events read in total (104839ms).
[18:06:11.742] <TB0> INFO: 4836700 events read in total (139661ms).
[18:06:46.362] <TB0> INFO: 6021700 events read in total (174281ms).
[18:07:20.929] <TB0> INFO: 7203830 events read in total (208848ms).
[18:07:53.607] <TB0> INFO: 8320000 events read in total (241526ms).
[18:07:53.648] <TB0> INFO: Test took 242578ms.
[18:07:53.733] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:08:17.196] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 187 (-1/-1) hits flags = 528 (plus default)
[18:08:17.204] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[18:08:17.204] <TB0> INFO: run 1 of 1
[18:08:17.498] <TB0> INFO: Expecting 7820800 events.
[18:08:53.079] <TB0> INFO: 1203840 events read in total (34866ms).
[18:09:27.967] <TB0> INFO: 2396570 events read in total (69754ms).
[18:10:02.653] <TB0> INFO: 3584270 events read in total (104440ms).
[18:10:37.170] <TB0> INFO: 4758190 events read in total (138957ms).
[18:11:11.641] <TB0> INFO: 5925050 events read in total (173428ms).
[18:11:46.167] <TB0> INFO: 7090640 events read in total (207954ms).
[18:12:07.686] <TB0> INFO: 7820800 events read in total (229473ms).
[18:12:07.721] <TB0> INFO: Test took 230517ms.
[18:12:07.807] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:12:30.766] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 174 (-1/-1) hits flags = 528 (plus default)
[18:12:30.774] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[18:12:30.774] <TB0> INFO: run 1 of 1
[18:12:31.069] <TB0> INFO: Expecting 7280000 events.
[18:13:07.549] <TB0> INFO: 1257780 events read in total (35765ms).
[18:13:43.080] <TB0> INFO: 2501750 events read in total (71297ms).
[18:14:18.531] <TB0> INFO: 3737020 events read in total (106748ms).
[18:14:53.860] <TB0> INFO: 4955330 events read in total (142076ms).
[18:15:29.169] <TB0> INFO: 6168190 events read in total (177385ms).
[18:16:01.424] <TB0> INFO: 7280000 events read in total (209640ms).
[18:16:01.472] <TB0> INFO: Test took 210698ms.
[18:16:01.545] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:16:23.458] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[18:16:23.466] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[18:16:23.467] <TB0> INFO: run 1 of 1
[18:16:23.764] <TB0> INFO: Expecting 7155200 events.
[18:17:00.413] <TB0> INFO: 1270390 events read in total (35933ms).
[18:17:36.167] <TB0> INFO: 2526580 events read in total (71687ms).
[18:18:11.631] <TB0> INFO: 3772760 events read in total (107151ms).
[18:18:46.928] <TB0> INFO: 5001940 events read in total (142448ms).
[18:19:22.108] <TB0> INFO: 6226240 events read in total (177628ms).
[18:19:48.735] <TB0> INFO: 7155200 events read in total (204255ms).
[18:19:48.766] <TB0> INFO: Test took 205300ms.
[18:19:48.833] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:20:10.304] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[18:20:10.312] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[18:20:10.312] <TB0> INFO: run 1 of 1
[18:20:10.610] <TB0> INFO: Expecting 7113600 events.
[18:20:47.155] <TB0> INFO: 1273540 events read in total (35830ms).
[18:21:23.009] <TB0> INFO: 2533780 events read in total (71684ms).
[18:21:58.730] <TB0> INFO: 3782520 events read in total (107405ms).
[18:22:34.197] <TB0> INFO: 5015270 events read in total (142872ms).
[18:23:09.646] <TB0> INFO: 6243030 events read in total (178321ms).
[18:23:34.683] <TB0> INFO: 7113600 events read in total (203358ms).
[18:23:34.712] <TB0> INFO: Test took 204400ms.
[18:23:34.778] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:23:56.214] <TB0> INFO: PixTestTrim::trimBitTest() done
[18:23:56.216] <TB0> INFO: PixTestTrim::doTest() done, duration: 2742 seconds
[18:23:56.225] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:23:56.225] <TB0> INFO: Decoding statistics:
[18:23:56.225] <TB0> INFO: General information:
[18:23:56.225] <TB0> INFO: 16bit words read: 0
[18:23:56.225] <TB0> INFO: valid events total: 0
[18:23:56.225] <TB0> INFO: empty events: 0
[18:23:56.225] <TB0> INFO: valid events with pixels: 0
[18:23:56.225] <TB0> INFO: valid pixel hits: 0
[18:23:56.225] <TB0> INFO: Event errors: 0
[18:23:56.225] <TB0> INFO: start marker: 0
[18:23:56.225] <TB0> INFO: stop marker: 0
[18:23:56.225] <TB0> INFO: overflow: 0
[18:23:56.225] <TB0> INFO: invalid 5bit words: 0
[18:23:56.225] <TB0> INFO: invalid XOR eye diagram: 0
[18:23:56.225] <TB0> INFO: TBM errors: 0
[18:23:56.225] <TB0> INFO: flawed TBM headers: 0
[18:23:56.225] <TB0> INFO: flawed TBM trailers: 0
[18:23:56.225] <TB0> INFO: event ID mismatches: 0
[18:23:56.225] <TB0> INFO: ROC errors: 0
[18:23:56.225] <TB0> INFO: missing ROC header(s): 0
[18:23:56.225] <TB0> INFO: misplaced readback start: 0
[18:23:56.225] <TB0> INFO: Pixel decoding errors: 0
[18:23:56.225] <TB0> INFO: pixel data incomplete: 0
[18:23:56.225] <TB0> INFO: pixel address: 0
[18:23:56.225] <TB0> INFO: pulse height fill bit: 0
[18:23:56.225] <TB0> INFO: buffer corruption: 0
[18:23:56.869] <TB0> INFO: ######################################################################
[18:23:56.869] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[18:23:56.869] <TB0> INFO: ######################################################################
[18:23:57.171] <TB0> INFO: Expecting 41600 events.
[18:24:01.104] <TB0> INFO: 41600 events read in total (3218ms).
[18:24:01.104] <TB0> INFO: Test took 4232ms.
[18:24:01.110] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:24:01.768] <TB0> INFO: Expecting 41600 events.
[18:24:05.736] <TB0> INFO: 41600 events read in total (3254ms).
[18:24:05.737] <TB0> INFO: Test took 4268ms.
[18:24:05.743] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:24:06.195] <TB0> INFO: Expecting 41600 events.
[18:24:10.165] <TB0> INFO: 41600 events read in total (3255ms).
[18:24:10.166] <TB0> INFO: Test took 4295ms.
[18:24:10.172] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:24:10.621] <TB0> INFO: Expecting 2560 events.
[18:24:11.578] <TB0> INFO: 2560 events read in total (242ms).
[18:24:11.578] <TB0> INFO: Test took 1400ms.
[18:24:12.086] <TB0> INFO: Expecting 2560 events.
[18:24:13.043] <TB0> INFO: 2560 events read in total (241ms).
[18:24:13.043] <TB0> INFO: Test took 1465ms.
[18:24:13.552] <TB0> INFO: Expecting 2560 events.
[18:24:14.508] <TB0> INFO: 2560 events read in total (241ms).
[18:24:14.508] <TB0> INFO: Test took 1464ms.
[18:24:15.016] <TB0> INFO: Expecting 2560 events.
[18:24:15.972] <TB0> INFO: 2560 events read in total (241ms).
[18:24:15.973] <TB0> INFO: Test took 1465ms.
[18:24:16.481] <TB0> INFO: Expecting 2560 events.
[18:24:17.438] <TB0> INFO: 2560 events read in total (242ms).
[18:24:17.438] <TB0> INFO: Test took 1464ms.
[18:24:17.946] <TB0> INFO: Expecting 2560 events.
[18:24:18.903] <TB0> INFO: 2560 events read in total (242ms).
[18:24:18.903] <TB0> INFO: Test took 1464ms.
[18:24:19.411] <TB0> INFO: Expecting 2560 events.
[18:24:20.368] <TB0> INFO: 2560 events read in total (242ms).
[18:24:20.368] <TB0> INFO: Test took 1465ms.
[18:24:20.876] <TB0> INFO: Expecting 2560 events.
[18:24:21.832] <TB0> INFO: 2560 events read in total (241ms).
[18:24:21.832] <TB0> INFO: Test took 1464ms.
[18:24:22.339] <TB0> INFO: Expecting 2560 events.
[18:24:23.295] <TB0> INFO: 2560 events read in total (241ms).
[18:24:23.296] <TB0> INFO: Test took 1464ms.
[18:24:23.803] <TB0> INFO: Expecting 2560 events.
[18:24:24.759] <TB0> INFO: 2560 events read in total (241ms).
[18:24:24.759] <TB0> INFO: Test took 1463ms.
[18:24:25.267] <TB0> INFO: Expecting 2560 events.
[18:24:26.223] <TB0> INFO: 2560 events read in total (241ms).
[18:24:26.223] <TB0> INFO: Test took 1463ms.
[18:24:26.730] <TB0> INFO: Expecting 2560 events.
[18:24:27.686] <TB0> INFO: 2560 events read in total (241ms).
[18:24:27.687] <TB0> INFO: Test took 1464ms.
[18:24:28.194] <TB0> INFO: Expecting 2560 events.
[18:24:29.150] <TB0> INFO: 2560 events read in total (241ms).
[18:24:29.150] <TB0> INFO: Test took 1463ms.
[18:24:29.658] <TB0> INFO: Expecting 2560 events.
[18:24:30.614] <TB0> INFO: 2560 events read in total (241ms).
[18:24:30.614] <TB0> INFO: Test took 1463ms.
[18:24:31.122] <TB0> INFO: Expecting 2560 events.
[18:24:32.077] <TB0> INFO: 2560 events read in total (241ms).
[18:24:32.078] <TB0> INFO: Test took 1464ms.
[18:24:32.585] <TB0> INFO: Expecting 2560 events.
[18:24:33.541] <TB0> INFO: 2560 events read in total (241ms).
[18:24:33.541] <TB0> INFO: Test took 1463ms.
[18:24:33.544] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:24:34.051] <TB0> INFO: Expecting 655360 events.
[18:24:45.303] <TB0> INFO: 655360 events read in total (10538ms).
[18:24:45.311] <TB0> INFO: Expecting 655360 events.
[18:24:56.476] <TB0> INFO: 655360 events read in total (10553ms).
[18:24:56.487] <TB0> INFO: Expecting 655360 events.
[18:25:07.660] <TB0> INFO: 655360 events read in total (10564ms).
[18:25:07.675] <TB0> INFO: Expecting 655360 events.
[18:25:18.831] <TB0> INFO: 655360 events read in total (10550ms).
[18:25:18.850] <TB0> INFO: Expecting 655360 events.
[18:25:30.026] <TB0> INFO: 655360 events read in total (10574ms).
[18:25:30.047] <TB0> INFO: Expecting 655360 events.
[18:25:41.211] <TB0> INFO: 655360 events read in total (10562ms).
[18:25:41.235] <TB0> INFO: Expecting 655360 events.
[18:25:52.383] <TB0> INFO: 655360 events read in total (10550ms).
[18:25:52.411] <TB0> INFO: Expecting 655360 events.
[18:26:03.581] <TB0> INFO: 655360 events read in total (10578ms).
[18:26:03.613] <TB0> INFO: Expecting 655360 events.
[18:26:14.823] <TB0> INFO: 655360 events read in total (10623ms).
[18:26:14.858] <TB0> INFO: Expecting 655360 events.
[18:26:26.064] <TB0> INFO: 655360 events read in total (10620ms).
[18:26:26.102] <TB0> INFO: Expecting 655360 events.
[18:26:37.300] <TB0> INFO: 655360 events read in total (10617ms).
[18:26:37.341] <TB0> INFO: Expecting 655360 events.
[18:26:48.532] <TB0> INFO: 655360 events read in total (10613ms).
[18:26:48.576] <TB0> INFO: Expecting 655360 events.
[18:26:59.760] <TB0> INFO: 655360 events read in total (10608ms).
[18:26:59.809] <TB0> INFO: Expecting 655360 events.
[18:27:10.994] <TB0> INFO: 655360 events read in total (10614ms).
[18:27:11.045] <TB0> INFO: Expecting 655360 events.
[18:27:22.260] <TB0> INFO: 655360 events read in total (10646ms).
[18:27:22.315] <TB0> INFO: Expecting 655360 events.
[18:27:33.489] <TB0> INFO: 655360 events read in total (10611ms).
[18:27:33.549] <TB0> INFO: Test took 180005ms.
[18:27:33.623] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:27:33.934] <TB0> INFO: Expecting 655360 events.
[18:27:45.167] <TB0> INFO: 655360 events read in total (10518ms).
[18:27:45.175] <TB0> INFO: Expecting 655360 events.
[18:27:56.319] <TB0> INFO: 655360 events read in total (10527ms).
[18:27:56.331] <TB0> INFO: Expecting 655360 events.
[18:28:07.480] <TB0> INFO: 655360 events read in total (10537ms).
[18:28:07.495] <TB0> INFO: Expecting 655360 events.
[18:28:18.646] <TB0> INFO: 655360 events read in total (10542ms).
[18:28:18.664] <TB0> INFO: Expecting 655360 events.
[18:28:29.756] <TB0> INFO: 655360 events read in total (10485ms).
[18:28:29.777] <TB0> INFO: Expecting 655360 events.
[18:28:40.893] <TB0> INFO: 655360 events read in total (10507ms).
[18:28:40.918] <TB0> INFO: Expecting 655360 events.
[18:28:52.060] <TB0> INFO: 655360 events read in total (10541ms).
[18:28:52.088] <TB0> INFO: Expecting 655360 events.
[18:29:03.157] <TB0> INFO: 655360 events read in total (10479ms).
[18:29:03.188] <TB0> INFO: Expecting 655360 events.
[18:29:14.326] <TB0> INFO: 655360 events read in total (10540ms).
[18:29:14.360] <TB0> INFO: Expecting 655360 events.
[18:29:25.486] <TB0> INFO: 655360 events read in total (10533ms).
[18:29:25.524] <TB0> INFO: Expecting 655360 events.
[18:29:36.638] <TB0> INFO: 655360 events read in total (10525ms).
[18:29:36.679] <TB0> INFO: Expecting 655360 events.
[18:29:47.834] <TB0> INFO: 655360 events read in total (10570ms).
[18:29:47.879] <TB0> INFO: Expecting 655360 events.
[18:29:59.043] <TB0> INFO: 655360 events read in total (10587ms).
[18:29:59.089] <TB0> INFO: Expecting 655360 events.
[18:30:10.297] <TB0> INFO: 655360 events read in total (10634ms).
[18:30:10.347] <TB0> INFO: Expecting 655360 events.
[18:30:21.490] <TB0> INFO: 655360 events read in total (10572ms).
[18:30:21.544] <TB0> INFO: Expecting 655360 events.
[18:30:32.678] <TB0> INFO: 655360 events read in total (10563ms).
[18:30:32.766] <TB0> INFO: Test took 179143ms.
[18:30:32.943] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:32.950] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:32.956] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[18:30:32.963] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:32.969] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[18:30:32.976] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[18:30:32.982] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[18:30:32.989] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:32.995] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.002] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.009] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.015] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.022] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.028] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[18:30:33.035] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[18:30:33.041] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.048] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.054] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.061] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.067] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.074] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.080] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:30:33.117] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C0.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C1.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C2.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C3.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C4.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C5.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C6.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C7.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C8.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C9.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C10.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C11.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C12.dat
[18:30:33.127] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C13.dat
[18:30:33.128] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C14.dat
[18:30:33.128] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//dacParameters35_C15.dat
[18:30:33.428] <TB0> INFO: Expecting 41600 events.
[18:30:37.182] <TB0> INFO: 41600 events read in total (3039ms).
[18:30:37.182] <TB0> INFO: Test took 4052ms.
[18:30:37.815] <TB0> INFO: Expecting 41600 events.
[18:30:41.533] <TB0> INFO: 41600 events read in total (3003ms).
[18:30:41.534] <TB0> INFO: Test took 4015ms.
[18:30:42.165] <TB0> INFO: Expecting 41600 events.
[18:30:45.911] <TB0> INFO: 41600 events read in total (3031ms).
[18:30:45.912] <TB0> INFO: Test took 4042ms.
[18:30:46.247] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:46.378] <TB0> INFO: Expecting 2560 events.
[18:30:47.334] <TB0> INFO: 2560 events read in total (241ms).
[18:30:47.335] <TB0> INFO: Test took 1088ms.
[18:30:47.336] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:47.843] <TB0> INFO: Expecting 2560 events.
[18:30:48.799] <TB0> INFO: 2560 events read in total (241ms).
[18:30:48.799] <TB0> INFO: Test took 1463ms.
[18:30:48.801] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:49.308] <TB0> INFO: Expecting 2560 events.
[18:30:50.264] <TB0> INFO: 2560 events read in total (241ms).
[18:30:50.264] <TB0> INFO: Test took 1463ms.
[18:30:50.266] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:50.773] <TB0> INFO: Expecting 2560 events.
[18:30:51.729] <TB0> INFO: 2560 events read in total (241ms).
[18:30:51.729] <TB0> INFO: Test took 1463ms.
[18:30:51.730] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:52.238] <TB0> INFO: Expecting 2560 events.
[18:30:53.194] <TB0> INFO: 2560 events read in total (241ms).
[18:30:53.194] <TB0> INFO: Test took 1464ms.
[18:30:53.196] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:53.703] <TB0> INFO: Expecting 2560 events.
[18:30:54.659] <TB0> INFO: 2560 events read in total (241ms).
[18:30:54.659] <TB0> INFO: Test took 1463ms.
[18:30:54.661] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:55.168] <TB0> INFO: Expecting 2560 events.
[18:30:56.124] <TB0> INFO: 2560 events read in total (241ms).
[18:30:56.125] <TB0> INFO: Test took 1464ms.
[18:30:56.126] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:56.634] <TB0> INFO: Expecting 2560 events.
[18:30:57.591] <TB0> INFO: 2560 events read in total (242ms).
[18:30:57.591] <TB0> INFO: Test took 1465ms.
[18:30:57.593] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:58.101] <TB0> INFO: Expecting 2560 events.
[18:30:59.057] <TB0> INFO: 2560 events read in total (241ms).
[18:30:59.058] <TB0> INFO: Test took 1465ms.
[18:30:59.059] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:30:59.566] <TB0> INFO: Expecting 2560 events.
[18:31:00.523] <TB0> INFO: 2560 events read in total (242ms).
[18:31:00.523] <TB0> INFO: Test took 1464ms.
[18:31:00.525] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:01.031] <TB0> INFO: Expecting 2560 events.
[18:31:01.987] <TB0> INFO: 2560 events read in total (241ms).
[18:31:01.988] <TB0> INFO: Test took 1464ms.
[18:31:01.989] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:02.496] <TB0> INFO: Expecting 2560 events.
[18:31:03.453] <TB0> INFO: 2560 events read in total (242ms).
[18:31:03.453] <TB0> INFO: Test took 1464ms.
[18:31:03.455] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:03.962] <TB0> INFO: Expecting 2560 events.
[18:31:04.918] <TB0> INFO: 2560 events read in total (241ms).
[18:31:04.918] <TB0> INFO: Test took 1463ms.
[18:31:04.920] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:05.428] <TB0> INFO: Expecting 2560 events.
[18:31:06.384] <TB0> INFO: 2560 events read in total (241ms).
[18:31:06.385] <TB0> INFO: Test took 1465ms.
[18:31:06.386] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:06.894] <TB0> INFO: Expecting 2560 events.
[18:31:07.851] <TB0> INFO: 2560 events read in total (242ms).
[18:31:07.851] <TB0> INFO: Test took 1465ms.
[18:31:07.853] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:08.360] <TB0> INFO: Expecting 2560 events.
[18:31:09.317] <TB0> INFO: 2560 events read in total (242ms).
[18:31:09.317] <TB0> INFO: Test took 1464ms.
[18:31:09.319] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:09.827] <TB0> INFO: Expecting 2560 events.
[18:31:10.784] <TB0> INFO: 2560 events read in total (242ms).
[18:31:10.784] <TB0> INFO: Test took 1465ms.
[18:31:10.786] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:11.293] <TB0> INFO: Expecting 2560 events.
[18:31:12.250] <TB0> INFO: 2560 events read in total (242ms).
[18:31:12.250] <TB0> INFO: Test took 1464ms.
[18:31:12.252] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:12.760] <TB0> INFO: Expecting 2560 events.
[18:31:13.717] <TB0> INFO: 2560 events read in total (242ms).
[18:31:13.717] <TB0> INFO: Test took 1465ms.
[18:31:13.719] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:14.227] <TB0> INFO: Expecting 2560 events.
[18:31:15.184] <TB0> INFO: 2560 events read in total (242ms).
[18:31:15.184] <TB0> INFO: Test took 1465ms.
[18:31:15.186] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:15.694] <TB0> INFO: Expecting 2560 events.
[18:31:16.651] <TB0> INFO: 2560 events read in total (242ms).
[18:31:16.651] <TB0> INFO: Test took 1465ms.
[18:31:16.652] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:17.160] <TB0> INFO: Expecting 2560 events.
[18:31:18.117] <TB0> INFO: 2560 events read in total (242ms).
[18:31:18.117] <TB0> INFO: Test took 1465ms.
[18:31:18.119] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:18.627] <TB0> INFO: Expecting 2560 events.
[18:31:19.583] <TB0> INFO: 2560 events read in total (241ms).
[18:31:19.584] <TB0> INFO: Test took 1465ms.
[18:31:19.585] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:20.093] <TB0> INFO: Expecting 2560 events.
[18:31:21.050] <TB0> INFO: 2560 events read in total (242ms).
[18:31:21.050] <TB0> INFO: Test took 1466ms.
[18:31:21.053] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:21.560] <TB0> INFO: Expecting 2560 events.
[18:31:22.517] <TB0> INFO: 2560 events read in total (242ms).
[18:31:22.517] <TB0> INFO: Test took 1465ms.
[18:31:22.519] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:23.026] <TB0> INFO: Expecting 2560 events.
[18:31:23.983] <TB0> INFO: 2560 events read in total (242ms).
[18:31:23.983] <TB0> INFO: Test took 1464ms.
[18:31:23.985] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:24.493] <TB0> INFO: Expecting 2560 events.
[18:31:25.449] <TB0> INFO: 2560 events read in total (241ms).
[18:31:25.450] <TB0> INFO: Test took 1465ms.
[18:31:25.451] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:25.959] <TB0> INFO: Expecting 2560 events.
[18:31:26.916] <TB0> INFO: 2560 events read in total (242ms).
[18:31:26.916] <TB0> INFO: Test took 1465ms.
[18:31:26.918] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:27.426] <TB0> INFO: Expecting 2560 events.
[18:31:28.382] <TB0> INFO: 2560 events read in total (241ms).
[18:31:28.383] <TB0> INFO: Test took 1465ms.
[18:31:28.384] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:28.892] <TB0> INFO: Expecting 2560 events.
[18:31:29.849] <TB0> INFO: 2560 events read in total (242ms).
[18:31:29.849] <TB0> INFO: Test took 1465ms.
[18:31:29.851] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:30.357] <TB0> INFO: Expecting 2560 events.
[18:31:31.314] <TB0> INFO: 2560 events read in total (242ms).
[18:31:31.314] <TB0> INFO: Test took 1464ms.
[18:31:31.316] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:31:31.823] <TB0> INFO: Expecting 2560 events.
[18:31:32.779] <TB0> INFO: 2560 events read in total (242ms).
[18:31:32.779] <TB0> INFO: Test took 1463ms.
[18:31:33.478] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 456 seconds
[18:31:33.478] <TB0> INFO: PH scale (per ROC): 67 79 68 65 71 70 72 73 66 70 68 63 71 65 71 72
[18:31:33.478] <TB0> INFO: PH offset (per ROC): 187 181 176 174 190 175 162 174 176 176 183 179 180 176 166 186
[18:31:33.482] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:31:33.482] <TB0> INFO: Decoding statistics:
[18:31:33.482] <TB0> INFO: General information:
[18:31:33.482] <TB0> INFO: 16bit words read: 66450
[18:31:33.482] <TB0> INFO: valid events total: 5120
[18:31:33.482] <TB0> INFO: empty events: 2615
[18:31:33.482] <TB0> INFO: valid events with pixels: 2505
[18:31:33.482] <TB0> INFO: valid pixel hits: 2505
[18:31:33.482] <TB0> INFO: Event errors: 0
[18:31:33.482] <TB0> INFO: start marker: 0
[18:31:33.482] <TB0> INFO: stop marker: 0
[18:31:33.482] <TB0> INFO: overflow: 0
[18:31:33.482] <TB0> INFO: invalid 5bit words: 0
[18:31:33.482] <TB0> INFO: invalid XOR eye diagram: 0
[18:31:33.482] <TB0> INFO: TBM errors: 0
[18:31:33.482] <TB0> INFO: flawed TBM headers: 0
[18:31:33.482] <TB0> INFO: flawed TBM trailers: 0
[18:31:33.482] <TB0> INFO: event ID mismatches: 0
[18:31:33.482] <TB0> INFO: ROC errors: 0
[18:31:33.482] <TB0> INFO: missing ROC header(s): 0
[18:31:33.482] <TB0> INFO: misplaced readback start: 0
[18:31:33.482] <TB0> INFO: Pixel decoding errors: 0
[18:31:33.482] <TB0> INFO: pixel data incomplete: 0
[18:31:33.482] <TB0> INFO: pixel address: 0
[18:31:33.482] <TB0> INFO: pulse height fill bit: 0
[18:31:33.482] <TB0> INFO: buffer corruption: 0
[18:31:33.671] <TB0> INFO: ######################################################################
[18:31:33.671] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[18:31:33.671] <TB0> INFO: ######################################################################
[18:31:33.681] <TB0> INFO: scanning low vcal = 10
[18:31:33.983] <TB0> INFO: Expecting 41600 events.
[18:31:37.680] <TB0> INFO: 41600 events read in total (2982ms).
[18:31:37.681] <TB0> INFO: Test took 4000ms.
[18:31:37.682] <TB0> INFO: scanning low vcal = 20
[18:31:38.189] <TB0> INFO: Expecting 41600 events.
[18:31:41.888] <TB0> INFO: 41600 events read in total (2984ms).
[18:31:41.888] <TB0> INFO: Test took 4206ms.
[18:31:41.891] <TB0> INFO: scanning low vcal = 30
[18:31:42.397] <TB0> INFO: Expecting 41600 events.
[18:31:46.118] <TB0> INFO: 41600 events read in total (3006ms).
[18:31:46.119] <TB0> INFO: Test took 4228ms.
[18:31:46.120] <TB0> INFO: scanning low vcal = 40
[18:31:46.621] <TB0> INFO: Expecting 41600 events.
[18:31:50.755] <TB0> INFO: 41600 events read in total (3419ms).
[18:31:50.756] <TB0> INFO: Test took 4636ms.
[18:31:50.759] <TB0> INFO: scanning low vcal = 50
[18:31:51.210] <TB0> INFO: Expecting 41600 events.
[18:31:55.387] <TB0> INFO: 41600 events read in total (3461ms).
[18:31:55.388] <TB0> INFO: Test took 4629ms.
[18:31:55.390] <TB0> INFO: scanning low vcal = 60
[18:31:55.843] <TB0> INFO: Expecting 41600 events.
[18:32:00.036] <TB0> INFO: 41600 events read in total (3478ms).
[18:32:00.037] <TB0> INFO: Test took 4647ms.
[18:32:00.039] <TB0> INFO: scanning low vcal = 70
[18:32:00.492] <TB0> INFO: Expecting 41600 events.
[18:32:04.668] <TB0> INFO: 41600 events read in total (3461ms).
[18:32:04.669] <TB0> INFO: Test took 4630ms.
[18:32:04.671] <TB0> INFO: scanning low vcal = 80
[18:32:05.124] <TB0> INFO: Expecting 41600 events.
[18:32:09.302] <TB0> INFO: 41600 events read in total (3463ms).
[18:32:09.302] <TB0> INFO: Test took 4631ms.
[18:32:09.305] <TB0> INFO: scanning low vcal = 90
[18:32:09.757] <TB0> INFO: Expecting 41600 events.
[18:32:13.918] <TB0> INFO: 41600 events read in total (3446ms).
[18:32:13.919] <TB0> INFO: Test took 4614ms.
[18:32:13.922] <TB0> INFO: scanning low vcal = 100
[18:32:14.373] <TB0> INFO: Expecting 41600 events.
[18:32:18.676] <TB0> INFO: 41600 events read in total (3588ms).
[18:32:18.676] <TB0> INFO: Test took 4754ms.
[18:32:18.679] <TB0> INFO: scanning low vcal = 110
[18:32:19.132] <TB0> INFO: Expecting 41600 events.
[18:32:23.311] <TB0> INFO: 41600 events read in total (3463ms).
[18:32:23.312] <TB0> INFO: Test took 4633ms.
[18:32:23.315] <TB0> INFO: scanning low vcal = 120
[18:32:23.768] <TB0> INFO: Expecting 41600 events.
[18:32:27.948] <TB0> INFO: 41600 events read in total (3465ms).
[18:32:27.949] <TB0> INFO: Test took 4634ms.
[18:32:27.952] <TB0> INFO: scanning low vcal = 130
[18:32:28.404] <TB0> INFO: Expecting 41600 events.
[18:32:32.581] <TB0> INFO: 41600 events read in total (3462ms).
[18:32:32.582] <TB0> INFO: Test took 4630ms.
[18:32:32.585] <TB0> INFO: scanning low vcal = 140
[18:32:33.037] <TB0> INFO: Expecting 41600 events.
[18:32:37.215] <TB0> INFO: 41600 events read in total (3463ms).
[18:32:37.216] <TB0> INFO: Test took 4631ms.
[18:32:37.219] <TB0> INFO: scanning low vcal = 150
[18:32:37.670] <TB0> INFO: Expecting 41600 events.
[18:32:41.848] <TB0> INFO: 41600 events read in total (3463ms).
[18:32:41.848] <TB0> INFO: Test took 4629ms.
[18:32:41.851] <TB0> INFO: scanning low vcal = 160
[18:32:42.303] <TB0> INFO: Expecting 41600 events.
[18:32:46.484] <TB0> INFO: 41600 events read in total (3466ms).
[18:32:46.484] <TB0> INFO: Test took 4633ms.
[18:32:46.487] <TB0> INFO: scanning low vcal = 170
[18:32:46.940] <TB0> INFO: Expecting 41600 events.
[18:32:51.117] <TB0> INFO: 41600 events read in total (3462ms).
[18:32:51.118] <TB0> INFO: Test took 4631ms.
[18:32:51.121] <TB0> INFO: scanning low vcal = 180
[18:32:51.573] <TB0> INFO: Expecting 41600 events.
[18:32:55.752] <TB0> INFO: 41600 events read in total (3463ms).
[18:32:55.752] <TB0> INFO: Test took 4631ms.
[18:32:55.755] <TB0> INFO: scanning low vcal = 190
[18:32:56.208] <TB0> INFO: Expecting 41600 events.
[18:33:00.387] <TB0> INFO: 41600 events read in total (3464ms).
[18:33:00.387] <TB0> INFO: Test took 4632ms.
[18:33:00.390] <TB0> INFO: scanning low vcal = 200
[18:33:00.843] <TB0> INFO: Expecting 41600 events.
[18:33:05.022] <TB0> INFO: 41600 events read in total (3464ms).
[18:33:05.023] <TB0> INFO: Test took 4633ms.
[18:33:05.025] <TB0> INFO: scanning low vcal = 210
[18:33:05.477] <TB0> INFO: Expecting 41600 events.
[18:33:09.656] <TB0> INFO: 41600 events read in total (3463ms).
[18:33:09.657] <TB0> INFO: Test took 4632ms.
[18:33:09.666] <TB0> INFO: scanning low vcal = 220
[18:33:10.112] <TB0> INFO: Expecting 41600 events.
[18:33:14.290] <TB0> INFO: 41600 events read in total (3463ms).
[18:33:14.291] <TB0> INFO: Test took 4625ms.
[18:33:14.293] <TB0> INFO: scanning low vcal = 230
[18:33:14.746] <TB0> INFO: Expecting 41600 events.
[18:33:18.922] <TB0> INFO: 41600 events read in total (3461ms).
[18:33:18.923] <TB0> INFO: Test took 4630ms.
[18:33:18.925] <TB0> INFO: scanning low vcal = 240
[18:33:19.379] <TB0> INFO: Expecting 41600 events.
[18:33:23.558] <TB0> INFO: 41600 events read in total (3464ms).
[18:33:23.559] <TB0> INFO: Test took 4634ms.
[18:33:23.561] <TB0> INFO: scanning low vcal = 250
[18:33:24.015] <TB0> INFO: Expecting 41600 events.
[18:33:28.190] <TB0> INFO: 41600 events read in total (3460ms).
[18:33:28.191] <TB0> INFO: Test took 4630ms.
[18:33:28.194] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[18:33:28.647] <TB0> INFO: Expecting 41600 events.
[18:33:32.841] <TB0> INFO: 41600 events read in total (3479ms).
[18:33:32.842] <TB0> INFO: Test took 4648ms.
[18:33:32.844] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[18:33:33.297] <TB0> INFO: Expecting 41600 events.
[18:33:37.494] <TB0> INFO: 41600 events read in total (3482ms).
[18:33:37.495] <TB0> INFO: Test took 4651ms.
[18:33:37.498] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[18:33:37.950] <TB0> INFO: Expecting 41600 events.
[18:33:42.148] <TB0> INFO: 41600 events read in total (3483ms).
[18:33:42.149] <TB0> INFO: Test took 4651ms.
[18:33:42.151] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[18:33:42.604] <TB0> INFO: Expecting 41600 events.
[18:33:46.801] <TB0> INFO: 41600 events read in total (3483ms).
[18:33:46.802] <TB0> INFO: Test took 4651ms.
[18:33:46.805] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[18:33:47.258] <TB0> INFO: Expecting 41600 events.
[18:33:51.458] <TB0> INFO: 41600 events read in total (3485ms).
[18:33:51.458] <TB0> INFO: Test took 4653ms.
[18:33:51.877] <TB0> INFO: PixTestGainPedestal::measure() done
[18:34:24.072] <TB0> INFO: PixTestGainPedestal::fit() done
[18:34:24.072] <TB0> INFO: non-linearity mean: 0.957 0.957 0.960 0.960 0.959 0.965 0.964 0.958 0.957 0.961 0.962 0.953 0.957 0.965 0.957 0.951
[18:34:24.072] <TB0> INFO: non-linearity RMS: 0.007 0.008 0.006 0.007 0.006 0.005 0.005 0.006 0.006 0.006 0.006 0.007 0.006 0.005 0.005 0.007
[18:34:24.072] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[18:34:24.090] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[18:34:24.108] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[18:34:24.125] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[18:34:24.143] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[18:34:24.161] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[18:34:24.178] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[18:34:24.196] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[18:34:24.213] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[18:34:24.231] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[18:34:24.248] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[18:34:24.266] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[18:34:24.283] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[18:34:24.301] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[18:34:24.318] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[18:34:24.336] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[18:34:24.353] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 170 seconds
[18:34:24.353] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:34:24.353] <TB0> INFO: Decoding statistics:
[18:34:24.353] <TB0> INFO: General information:
[18:34:24.353] <TB0> INFO: 16bit words read: 2329300
[18:34:24.353] <TB0> INFO: valid events total: 83200
[18:34:24.353] <TB0> INFO: empty events: 0
[18:34:24.353] <TB0> INFO: valid events with pixels: 83200
[18:34:24.353] <TB0> INFO: valid pixel hits: 665450
[18:34:24.353] <TB0> INFO: Event errors: 0
[18:34:24.353] <TB0> INFO: start marker: 0
[18:34:24.353] <TB0> INFO: stop marker: 0
[18:34:24.353] <TB0> INFO: overflow: 0
[18:34:24.353] <TB0> INFO: invalid 5bit words: 0
[18:34:24.353] <TB0> INFO: invalid XOR eye diagram: 0
[18:34:24.353] <TB0> INFO: TBM errors: 0
[18:34:24.353] <TB0> INFO: flawed TBM headers: 0
[18:34:24.353] <TB0> INFO: flawed TBM trailers: 0
[18:34:24.353] <TB0> INFO: event ID mismatches: 0
[18:34:24.353] <TB0> INFO: ROC errors: 0
[18:34:24.353] <TB0> INFO: missing ROC header(s): 0
[18:34:24.353] <TB0> INFO: misplaced readback start: 0
[18:34:24.353] <TB0> INFO: Pixel decoding errors: 0
[18:34:24.353] <TB0> INFO: pixel data incomplete: 0
[18:34:24.353] <TB0> INFO: pixel address: 0
[18:34:24.353] <TB0> INFO: pulse height fill bit: 0
[18:34:24.353] <TB0> INFO: buffer corruption: 0
[18:34:24.359] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C15.dat
[18:34:24.361] <TB0> INFO: ######################################################################
[18:34:24.361] <TB0> INFO: PixTestReadback::doTest()
[18:34:24.361] <TB0> INFO: ######################################################################
[18:34:24.361] <TB0> INFO: PixTestReadback::RES sent once
[18:34:35.533] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C0.dat
[18:34:35.533] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C1.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C2.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C3.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C4.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C5.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C6.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C7.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C8.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C9.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C10.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C11.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C12.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C13.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C14.dat
[18:34:35.534] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C15.dat
[18:34:35.563] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:34:35.563] <TB0> INFO: PixTestReadback::RES sent once
[18:34:46.700] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C0.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C1.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C2.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C3.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C4.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C5.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C6.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C7.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C8.dat
[18:34:46.701] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C9.dat
[18:34:46.702] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C10.dat
[18:34:46.702] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C11.dat
[18:34:46.702] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C12.dat
[18:34:46.702] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C13.dat
[18:34:46.702] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C14.dat
[18:34:46.702] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C15.dat
[18:34:46.733] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:34:46.733] <TB0> INFO: PixTestReadback::RES sent once
[18:34:55.298] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:34:55.298] <TB0> INFO: Vbg will be calibrated using Vd calibration
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146.5calibrated Vbg = 1.2282 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.2calibrated Vbg = 1.22835 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.1calibrated Vbg = 1.23168 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 161calibrated Vbg = 1.2455 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 161.3calibrated Vbg = 1.24493 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.3calibrated Vbg = 1.24693 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 146.1calibrated Vbg = 1.25061 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 158.8calibrated Vbg = 1.25388 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.3calibrated Vbg = 1.24707 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 158.8calibrated Vbg = 1.24444 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.6calibrated Vbg = 1.23836 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159.8calibrated Vbg = 1.23896 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 162.4calibrated Vbg = 1.23107 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 160.6calibrated Vbg = 1.22544 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.6calibrated Vbg = 1.23196 :::*/*/*/*/
[18:34:55.298] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 160.6calibrated Vbg = 1.23237 :::*/*/*/*/
[18:34:55.301] <TB0> INFO: PixTestReadback::RES sent once
[18:37:49.021] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C0.dat
[18:37:49.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C1.dat
[18:37:49.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C2.dat
[18:37:49.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C3.dat
[18:37:49.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C4.dat
[18:37:49.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C5.dat
[18:37:49.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C6.dat
[18:37:49.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C7.dat
[18:37:49.022] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C8.dat
[18:37:49.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C9.dat
[18:37:49.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C10.dat
[18:37:49.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C11.dat
[18:37:49.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C12.dat
[18:37:49.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C13.dat
[18:37:49.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C14.dat
[18:37:49.023] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M3022_FullQualification_2015-10-29_13h58m_1446123484//004_FulltestPxar_p17//readbackCal_C15.dat
[18:37:49.050] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:37:49.051] <TB0> INFO: PixTestReadback::doTest() done
[18:37:49.051] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:37:49.051] <TB0> INFO: Decoding statistics:
[18:37:49.051] <TB0> INFO: General information:
[18:37:49.051] <TB0> INFO: 16bit words read: 768
[18:37:49.051] <TB0> INFO: valid events total: 64
[18:37:49.051] <TB0> INFO: empty events: 64
[18:37:49.051] <TB0> INFO: valid events with pixels: 0
[18:37:49.051] <TB0> INFO: valid pixel hits: 0
[18:37:49.051] <TB0> INFO: Event errors: 0
[18:37:49.051] <TB0> INFO: start marker: 0
[18:37:49.051] <TB0> INFO: stop marker: 0
[18:37:49.051] <TB0> INFO: overflow: 0
[18:37:49.051] <TB0> INFO: invalid 5bit words: 0
[18:37:49.051] <TB0> INFO: invalid XOR eye diagram: 0
[18:37:49.051] <TB0> INFO: TBM errors: 0
[18:37:49.051] <TB0> INFO: flawed TBM headers: 0
[18:37:49.051] <TB0> INFO: flawed TBM trailers: 0
[18:37:49.051] <TB0> INFO: event ID mismatches: 0
[18:37:49.051] <TB0> INFO: ROC errors: 0
[18:37:49.051] <TB0> INFO: missing ROC header(s): 0
[18:37:49.051] <TB0> INFO: misplaced readback start: 0
[18:37:49.051] <TB0> INFO: Pixel decoding errors: 0
[18:37:49.051] <TB0> INFO: pixel data incomplete: 0
[18:37:49.051] <TB0> INFO: pixel address: 0
[18:37:49.051] <TB0> INFO: pulse height fill bit: 0
[18:37:49.051] <TB0> INFO: buffer corruption: 0
[18:37:49.063] <TB0> INFO: Decoding statistics:
[18:37:49.063] <TB0> INFO: General information:
[18:37:49.063] <TB0> INFO: 16bit words read: 2396518
[18:37:49.063] <TB0> INFO: valid events total: 88384
[18:37:49.063] <TB0> INFO: empty events: 2679
[18:37:49.063] <TB0> INFO: valid events with pixels: 85705
[18:37:49.063] <TB0> INFO: valid pixel hits: 667955
[18:37:49.063] <TB0> INFO: Event errors: 0
[18:37:49.063] <TB0> INFO: start marker: 0
[18:37:49.063] <TB0> INFO: stop marker: 0
[18:37:49.064] <TB0> INFO: overflow: 0
[18:37:49.064] <TB0> INFO: invalid 5bit words: 0
[18:37:49.064] <TB0> INFO: invalid XOR eye diagram: 0
[18:37:49.064] <TB0> INFO: TBM errors: 0
[18:37:49.064] <TB0> INFO: flawed TBM headers: 0
[18:37:49.064] <TB0> INFO: flawed TBM trailers: 0
[18:37:49.064] <TB0> INFO: event ID mismatches: 0
[18:37:49.064] <TB0> INFO: ROC errors: 0
[18:37:49.064] <TB0> INFO: missing ROC header(s): 0
[18:37:49.064] <TB0> INFO: misplaced readback start: 0
[18:37:49.064] <TB0> INFO: Pixel decoding errors: 0
[18:37:49.064] <TB0> INFO: pixel data incomplete: 0
[18:37:49.064] <TB0> INFO: pixel address: 0
[18:37:49.064] <TB0> INFO: pulse height fill bit: 0
[18:37:49.064] <TB0> INFO: buffer corruption: 0
[18:37:49.064] <TB0> INFO: enter test to run
[18:37:49.064] <TB0> INFO: test: exit no parameter change
[18:37:49.265] <TB0> QUIET: Connection to board 73 closed.
[18:37:49.344] <TB0> INFO: pXar: this is the end, my friend