[14:19:40.503] <TB2> INFO: *** Welcome to pxar ***
[14:19:40.503] <TB2> INFO: *** Today: 2015/09/11
[14:19:40.503] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C15.dat
[14:19:40.505] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:19:40.505] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//defaultMaskFile.dat
[14:19:40.505] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters_C15.dat
[14:19:40.596] <TB2> INFO: clk: 4
[14:19:40.596] <TB2> INFO: ctr: 4
[14:19:40.596] <TB2> INFO: sda: 19
[14:19:40.596] <TB2> INFO: tin: 9
[14:19:40.596] <TB2> INFO: level: 15
[14:19:40.596] <TB2> INFO: triggerdelay: 0
[14:19:40.596] <TB2> QUIET: Instanciating API for pxar prod-10+24~g09f6d2c
[14:19:40.596] <TB2> INFO: Log level: INFO
[14:19:40.604] <TB2> INFO: Found DTB DTB_WXC55Z
[14:19:40.611] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[14:19:40.615] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[14:19:40.617] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[14:19:42.146] <TB2> INFO: DUT info:
[14:19:42.146] <TB2> INFO: The DUT currently contains the following objects:
[14:19:42.146] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[14:19:42.146] <TB2> INFO: TBM Core alpha (0): 7 registers set
[14:19:42.146] <TB2> INFO: TBM Core beta (1): 7 registers set
[14:19:42.146] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:19:42.146] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.146] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.146] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.146] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.146] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.147] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:19:42.548] <TB2> INFO: enter 'restricted' command line mode
[14:19:42.548] <TB2> INFO: enter test to run
[14:19:42.548] <TB2> INFO: test: pretest no parameter change
[14:19:42.548] <TB2> INFO: running: pretest
[14:19:42.556] <TB2> INFO: ######################################################################
[14:19:42.556] <TB2> INFO: PixTestPretest::doTest()
[14:19:42.557] <TB2> INFO: ######################################################################
[14:19:42.558] <TB2> INFO: ----------------------------------------------------------------------
[14:19:42.558] <TB2> INFO: PixTestPretest::programROC()
[14:19:42.558] <TB2> INFO: ----------------------------------------------------------------------
[14:20:00.576] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:20:00.577] <TB2> INFO: IA differences per ROC: 18.5 20.1 19.3 20.9 18.5 20.1 19.3 20.9 17.7 18.5 20.9 16.9 17.7 19.3 16.9 20.1
[14:20:00.646] <TB2> INFO: ----------------------------------------------------------------------
[14:20:00.646] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:20:00.647] <TB2> INFO: ----------------------------------------------------------------------
[14:20:20.228] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[14:20:20.229] <TB2> INFO: ----------------------------------------------------------------------
[14:20:20.229] <TB2> INFO: PixTestPretest::findTiming()
[14:20:20.229] <TB2> INFO: ----------------------------------------------------------------------
[14:20:20.229] <TB2> INFO: PixTestCmd::init()

[14:22:07.161] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[14:22:07.161] <TB2> INFO: (success/tries = 100/100), width = 4
[14:22:07.163] <TB2> INFO: ----------------------------------------------------------------------
[14:22:07.163] <TB2> INFO: PixTestPretest::findWorkingPixel()
[14:22:07.163] <TB2> INFO: ----------------------------------------------------------------------
[14:22:07.301] <TB2> INFO: Expecting 231680 events.
[14:22:15.829] <TB2> INFO: 231680 events read in total (7812ms).
[14:22:15.833] <TB2> INFO: Test took 8668ms.
[14:22:16.150] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:22:16.187] <TB2> INFO: ----------------------------------------------------------------------
[14:22:16.187] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[14:22:16.187] <TB2> INFO: ----------------------------------------------------------------------
[14:22:16.324] <TB2> INFO: Expecting 231680 events.
[14:22:25.833] <TB2> INFO: 231680 events read in total (8793ms).
[14:22:25.836] <TB2> INFO: Test took 9645ms.
[14:22:26.143] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[14:22:26.143] <TB2> INFO: CalDel: 118 145 129 143 135 128 137 146 128 147 125 160 122 139 131 137
[14:22:26.143] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:22:26.145] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C0.dat
[14:22:26.145] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C1.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C2.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C3.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C4.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C5.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C6.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C7.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C8.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C9.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C10.dat
[14:22:26.146] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C11.dat
[14:22:26.147] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C12.dat
[14:22:26.147] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C13.dat
[14:22:26.147] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C14.dat
[14:22:26.147] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters_C15.dat
[14:22:26.147] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//tbmParameters_C0a.dat
[14:22:26.148] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:22:26.148] <TB2> INFO: PixTestPretest::doTest() done, duration: 163 seconds
[14:22:26.214] <TB2> INFO: enter test to run
[14:22:26.214] <TB2> INFO: test: fulltest no parameter change
[14:22:26.214] <TB2> INFO: running: fulltest
[14:22:26.214] <TB2> INFO: ######################################################################
[14:22:26.214] <TB2> INFO: PixTestFullTest::doTest()
[14:22:26.214] <TB2> INFO: ######################################################################
[14:22:26.215] <TB2> INFO: ######################################################################
[14:22:26.215] <TB2> INFO: PixTestAlive::doTest()
[14:22:26.215] <TB2> INFO: ######################################################################
[14:22:26.217] <TB2> INFO: ----------------------------------------------------------------------
[14:22:26.217] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:22:26.217] <TB2> INFO: ----------------------------------------------------------------------
[14:22:26.527] <TB2> INFO: Expecting 41600 events.
[14:22:31.186] <TB2> INFO: 41600 events read in total (3943ms).
[14:22:31.187] <TB2> INFO: Test took 4969ms.
[14:22:31.193] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:31.483] <TB2> INFO: PixTestAlive::aliveTest() done
[14:22:31.483] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:22:31.485] <TB2> INFO: ----------------------------------------------------------------------
[14:22:31.485] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:22:31.485] <TB2> INFO: ----------------------------------------------------------------------
[14:22:31.814] <TB2> INFO: Expecting 41600 events.
[14:22:35.296] <TB2> INFO: 41600 events read in total (2767ms).
[14:22:35.296] <TB2> INFO: Test took 3809ms.
[14:22:35.296] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:35.297] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:22:35.611] <TB2> INFO: PixTestAlive::maskTest() done
[14:22:35.611] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:22:35.612] <TB2> INFO: ----------------------------------------------------------------------
[14:22:35.612] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:22:35.612] <TB2> INFO: ----------------------------------------------------------------------
[14:22:35.924] <TB2> INFO: Expecting 41600 events.
[14:22:40.580] <TB2> INFO: 41600 events read in total (3941ms).
[14:22:40.581] <TB2> INFO: Test took 4967ms.
[14:22:40.587] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:40.872] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[14:22:40.873] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:22:40.873] <TB2> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:22:40.873] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:40.873] <TB2> INFO: Decoding statistics:
[14:22:40.873] <TB2> INFO: General information:
[14:22:40.873] <TB2> INFO: 16bit words read: 0
[14:22:40.873] <TB2> INFO: valid events total: 0
[14:22:40.873] <TB2> INFO: empty events: 0
[14:22:40.873] <TB2> INFO: valid events with pixels: 0
[14:22:40.873] <TB2> INFO: valid pixel hits: 0
[14:22:40.873] <TB2> INFO: Event errors: 0
[14:22:40.873] <TB2> INFO: start marker: 0
[14:22:40.873] <TB2> INFO: stop marker: 0
[14:22:40.873] <TB2> INFO: overflow: 0
[14:22:40.873] <TB2> INFO: invalid 5bit words: 0
[14:22:40.873] <TB2> INFO: invalid XOR eye diagram: 0
[14:22:40.873] <TB2> INFO: TBM errors: 0
[14:22:40.873] <TB2> INFO: flawed TBM headers: 0
[14:22:40.873] <TB2> INFO: flawed TBM trailers: 0
[14:22:40.873] <TB2> INFO: event ID mismatches: 0
[14:22:40.873] <TB2> INFO: ROC errors: 0
[14:22:40.873] <TB2> INFO: missing ROC header(s): 0
[14:22:40.873] <TB2> INFO: misplaced readback start: 0
[14:22:40.873] <TB2> INFO: Pixel decoding errors: 0
[14:22:40.873] <TB2> INFO: pixel data incomplete: 0
[14:22:40.873] <TB2> INFO: pixel address: 0
[14:22:40.873] <TB2> INFO: pulse height fill bit: 0
[14:22:40.873] <TB2> INFO: buffer corruption: 0
[14:22:40.887] <TB2> INFO: ######################################################################
[14:22:40.887] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:22:40.887] <TB2> INFO: ######################################################################
[14:22:40.889] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:22:40.901] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[14:22:40.901] <TB2> INFO: run 1 of 1
[14:22:41.227] <TB2> INFO: Expecting 3120000 events.
[14:23:26.002] <TB2> INFO: 857095 events read in total (44059ms).
[14:24:09.467] <TB2> INFO: 1706170 events read in total (87525ms).
[14:24:51.468] <TB2> INFO: 2571540 events read in total (129525ms).
[14:25:16.021] <TB2> INFO: 3120000 events read in total (154078ms).
[14:25:16.070] <TB2> INFO: Test took 155169ms.
[14:25:16.167] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:40.424] <TB2> INFO: PixTestBBMap::doTest() done, duration: 179 seconds
[14:25:40.424] <TB2> INFO: number of dead bumps (per ROC): 2 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[14:25:40.424] <TB2> INFO: separation cut (per ROC): 94 89 95 74 82 96 87 97 102 81 90 92 100 91 79 88
[14:25:40.424] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:40.424] <TB2> INFO: Decoding statistics:
[14:25:40.424] <TB2> INFO: General information:
[14:25:40.424] <TB2> INFO: 16bit words read: 0
[14:25:40.424] <TB2> INFO: valid events total: 0
[14:25:40.424] <TB2> INFO: empty events: 0
[14:25:40.424] <TB2> INFO: valid events with pixels: 0
[14:25:40.424] <TB2> INFO: valid pixel hits: 0
[14:25:40.424] <TB2> INFO: Event errors: 0
[14:25:40.424] <TB2> INFO: start marker: 0
[14:25:40.424] <TB2> INFO: stop marker: 0
[14:25:40.424] <TB2> INFO: overflow: 0
[14:25:40.424] <TB2> INFO: invalid 5bit words: 0
[14:25:40.424] <TB2> INFO: invalid XOR eye diagram: 0
[14:25:40.424] <TB2> INFO: TBM errors: 0
[14:25:40.424] <TB2> INFO: flawed TBM headers: 0
[14:25:40.424] <TB2> INFO: flawed TBM trailers: 0
[14:25:40.424] <TB2> INFO: event ID mismatches: 0
[14:25:40.424] <TB2> INFO: ROC errors: 0
[14:25:40.424] <TB2> INFO: missing ROC header(s): 0
[14:25:40.424] <TB2> INFO: misplaced readback start: 0
[14:25:40.424] <TB2> INFO: Pixel decoding errors: 0
[14:25:40.424] <TB2> INFO: pixel data incomplete: 0
[14:25:40.424] <TB2> INFO: pixel address: 0
[14:25:40.424] <TB2> INFO: pulse height fill bit: 0
[14:25:40.424] <TB2> INFO: buffer corruption: 0
[14:25:40.493] <TB2> INFO: ######################################################################
[14:25:40.493] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:25:40.493] <TB2> INFO: ######################################################################
[14:25:40.493] <TB2> INFO: ----------------------------------------------------------------------
[14:25:40.494] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:25:40.494] <TB2> INFO: ----------------------------------------------------------------------
[14:25:40.494] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:25:40.501] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[14:25:40.501] <TB2> INFO: run 1 of 1
[14:25:40.805] <TB2> INFO: Expecting 26208000 events.
[14:26:14.837] <TB2> INFO: 928000 events read in total (33317ms).
[14:26:48.473] <TB2> INFO: 1838950 events read in total (66953ms).
[14:27:22.177] <TB2> INFO: 2747400 events read in total (100657ms).
[14:27:55.452] <TB2> INFO: 3657050 events read in total (133932ms).
[14:28:29.094] <TB2> INFO: 4560550 events read in total (167574ms).
[14:29:03.071] <TB2> INFO: 5461200 events read in total (201551ms).
[14:29:37.529] <TB2> INFO: 6362750 events read in total (236009ms).
[14:30:11.848] <TB2> INFO: 7261900 events read in total (270328ms).
[14:30:45.523] <TB2> INFO: 8161050 events read in total (304003ms).
[14:31:19.193] <TB2> INFO: 9058350 events read in total (337673ms).
[14:31:52.796] <TB2> INFO: 9951650 events read in total (371276ms).
[14:32:26.941] <TB2> INFO: 10846850 events read in total (405421ms).
[14:33:00.973] <TB2> INFO: 11742250 events read in total (439453ms).
[14:33:35.193] <TB2> INFO: 12636900 events read in total (473673ms).
[14:34:08.572] <TB2> INFO: 13521600 events read in total (507052ms).
[14:34:42.159] <TB2> INFO: 14405300 events read in total (540639ms).
[14:35:15.819] <TB2> INFO: 15288050 events read in total (574299ms).
[14:35:49.798] <TB2> INFO: 16169250 events read in total (608278ms).
[14:36:23.898] <TB2> INFO: 17046550 events read in total (642378ms).
[14:36:58.104] <TB2> INFO: 17926350 events read in total (676584ms).
[14:37:31.499] <TB2> INFO: 18806850 events read in total (709979ms).
[14:38:05.138] <TB2> INFO: 19683500 events read in total (743618ms).
[14:38:39.231] <TB2> INFO: 20560000 events read in total (777711ms).
[14:39:12.732] <TB2> INFO: 21437900 events read in total (811212ms).
[14:39:46.035] <TB2> INFO: 22313850 events read in total (844515ms).
[14:40:18.023] <TB2> INFO: 23192400 events read in total (876503ms).
[14:40:49.406] <TB2> INFO: 24067700 events read in total (907886ms).
[14:41:22.559] <TB2> INFO: 24947850 events read in total (941039ms).
[14:41:51.497] <TB2> INFO: 25834000 events read in total (969977ms).
[14:42:04.173] <TB2> INFO: 26208000 events read in total (982653ms).
[14:42:04.200] <TB2> INFO: Test took 983699ms.
[14:42:04.279] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:04.432] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:05.921] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:07.462] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:09.157] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:10.863] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:12.387] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:13.819] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:15.374] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:16.798] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:18.204] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:19.607] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:20.989] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:22.606] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:24.280] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:25.777] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:27.311] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[14:42:28.767] <TB2> INFO: PixTestScurves::scurves() done
[14:42:28.768] <TB2> INFO: Vcal mean: 96.26 90.00 91.59 79.96 83.72 92.31 90.43 99.94 103.06 86.45 98.65 95.06 95.21 95.53 80.66 92.41
[14:42:28.768] <TB2> INFO: Vcal RMS: 6.28 5.81 4.53 4.45 5.21 5.78 4.87 6.37 6.13 5.65 7.31 5.45 6.19 5.72 4.33 5.56
[14:42:28.768] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1008 seconds
[14:42:28.768] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:28.768] <TB2> INFO: Decoding statistics:
[14:42:28.768] <TB2> INFO: General information:
[14:42:28.768] <TB2> INFO: 16bit words read: 0
[14:42:28.768] <TB2> INFO: valid events total: 0
[14:42:28.768] <TB2> INFO: empty events: 0
[14:42:28.768] <TB2> INFO: valid events with pixels: 0
[14:42:28.768] <TB2> INFO: valid pixel hits: 0
[14:42:28.768] <TB2> INFO: Event errors: 0
[14:42:28.768] <TB2> INFO: start marker: 0
[14:42:28.768] <TB2> INFO: stop marker: 0
[14:42:28.768] <TB2> INFO: overflow: 0
[14:42:28.768] <TB2> INFO: invalid 5bit words: 0
[14:42:28.768] <TB2> INFO: invalid XOR eye diagram: 0
[14:42:28.768] <TB2> INFO: TBM errors: 0
[14:42:28.768] <TB2> INFO: flawed TBM headers: 0
[14:42:28.768] <TB2> INFO: flawed TBM trailers: 0
[14:42:28.768] <TB2> INFO: event ID mismatches: 0
[14:42:28.768] <TB2> INFO: ROC errors: 0
[14:42:28.768] <TB2> INFO: missing ROC header(s): 0
[14:42:28.768] <TB2> INFO: misplaced readback start: 0
[14:42:28.768] <TB2> INFO: Pixel decoding errors: 0
[14:42:28.768] <TB2> INFO: pixel data incomplete: 0
[14:42:28.768] <TB2> INFO: pixel address: 0
[14:42:28.768] <TB2> INFO: pulse height fill bit: 0
[14:42:28.768] <TB2> INFO: buffer corruption: 0
[14:42:28.854] <TB2> INFO: ######################################################################
[14:42:28.854] <TB2> INFO: PixTestTrim::doTest()
[14:42:28.854] <TB2> INFO: ######################################################################
[14:42:28.856] <TB2> INFO: ----------------------------------------------------------------------
[14:42:28.856] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[14:42:28.856] <TB2> INFO: ----------------------------------------------------------------------
[14:42:28.939] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[14:42:28.939] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:42:28.946] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[14:42:28.946] <TB2> INFO: run 1 of 1
[14:42:29.248] <TB2> INFO: Expecting 6281600 events.
[14:43:10.833] <TB2> INFO: 1089820 events read in total (40865ms).
[14:43:51.536] <TB2> INFO: 2171230 events read in total (81568ms).
[14:44:30.977] <TB2> INFO: 3246380 events read in total (121009ms).
[14:45:11.174] <TB2> INFO: 4327900 events read in total (161206ms).
[14:45:51.756] <TB2> INFO: 5411860 events read in total (201788ms).
[14:46:21.287] <TB2> INFO: 6281600 events read in total (231319ms).
[14:46:21.317] <TB2> INFO: Test took 232371ms.
[14:46:21.367] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:42.190] <TB2> INFO: ROC 0 VthrComp = 99
[14:46:42.190] <TB2> INFO: ROC 1 VthrComp = 92
[14:46:42.190] <TB2> INFO: ROC 2 VthrComp = 98
[14:46:42.190] <TB2> INFO: ROC 3 VthrComp = 83
[14:46:42.190] <TB2> INFO: ROC 4 VthrComp = 85
[14:46:42.191] <TB2> INFO: ROC 5 VthrComp = 96
[14:46:42.191] <TB2> INFO: ROC 6 VthrComp = 93
[14:46:42.191] <TB2> INFO: ROC 7 VthrComp = 97
[14:46:42.191] <TB2> INFO: ROC 8 VthrComp = 101
[14:46:42.191] <TB2> INFO: ROC 9 VthrComp = 87
[14:46:42.191] <TB2> INFO: ROC 10 VthrComp = 95
[14:46:42.191] <TB2> INFO: ROC 11 VthrComp = 95
[14:46:42.191] <TB2> INFO: ROC 12 VthrComp = 95
[14:46:42.191] <TB2> INFO: ROC 13 VthrComp = 96
[14:46:42.192] <TB2> INFO: ROC 14 VthrComp = 84
[14:46:42.192] <TB2> INFO: ROC 15 VthrComp = 92
[14:46:42.192] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[14:46:42.192] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:46:42.200] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[14:46:42.200] <TB2> INFO: run 1 of 1
[14:46:42.502] <TB2> INFO: Expecting 6281600 events.
[14:47:18.883] <TB2> INFO: 758520 events read in total (35666ms).
[14:47:53.713] <TB2> INFO: 1513990 events read in total (70496ms).
[14:48:28.662] <TB2> INFO: 2268660 events read in total (105445ms).
[14:49:05.381] <TB2> INFO: 3023270 events read in total (142164ms).
[14:49:39.523] <TB2> INFO: 3772150 events read in total (176306ms).
[14:50:15.155] <TB2> INFO: 4517530 events read in total (211938ms).
[14:50:51.835] <TB2> INFO: 5260490 events read in total (248618ms).
[14:51:23.953] <TB2> INFO: 6003240 events read in total (280736ms).
[14:51:35.607] <TB2> INFO: 6281600 events read in total (292390ms).
[14:51:35.643] <TB2> INFO: Test took 293444ms.
[14:51:35.783] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:00.371] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 58.8202 for pixel 4/1 mean/min/max = 45.2671/31.1981/59.3361
[14:52:00.372] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 59.782 for pixel 1/75 mean/min/max = 46.0546/32.2376/59.8716
[14:52:00.372] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 55.3137 for pixel 0/14 mean/min/max = 43.887/32.2847/55.4892
[14:52:00.373] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 57.3613 for pixel 2/13 mean/min/max = 45.0846/32.7818/57.3874
[14:52:00.373] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 57.0923 for pixel 16/78 mean/min/max = 44.7659/32.1151/57.4168
[14:52:00.374] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 58.294 for pixel 2/3 mean/min/max = 45.1908/31.985/58.3966
[14:52:00.374] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 56.7864 for pixel 1/15 mean/min/max = 45.1356/33.2698/57.0014
[14:52:00.374] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 61.0677 for pixel 24/0 mean/min/max = 46.4781/31.8478/61.1084
[14:52:00.375] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 63.1668 for pixel 32/70 mean/min/max = 47.9895/32.806/63.173
[14:52:00.375] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 58.0161 for pixel 0/64 mean/min/max = 45.1826/32.3091/58.0561
[14:52:00.375] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 65.2827 for pixel 19/77 mean/min/max = 48.379/31.4456/65.3124
[14:52:00.375] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 58.8702 for pixel 10/6 mean/min/max = 45.5402/32.0752/59.0052
[14:52:00.376] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 60.5419 for pixel 0/76 mean/min/max = 46.5582/32.2816/60.8348
[14:52:00.376] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 58.9996 for pixel 0/62 mean/min/max = 45.9001/32.7834/59.0167
[14:52:00.376] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 56.3985 for pixel 10/0 mean/min/max = 44.4678/32.4821/56.4535
[14:52:00.377] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 60.3044 for pixel 4/1 mean/min/max = 46.7014/32.9061/60.4966
[14:52:00.377] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:52:00.508] <TB2> INFO: Expecting 514560 events.
[14:52:13.055] <TB2> INFO: 514560 events read in total (11831ms).
[14:52:13.060] <TB2> INFO: Expecting 514560 events.
[14:52:24.807] <TB2> INFO: 514560 events read in total (11171ms).
[14:52:24.813] <TB2> INFO: Expecting 514560 events.
[14:52:36.576] <TB2> INFO: 514560 events read in total (11181ms).
[14:52:36.585] <TB2> INFO: Expecting 514560 events.
[14:52:48.430] <TB2> INFO: 514560 events read in total (11274ms).
[14:52:48.440] <TB2> INFO: Expecting 514560 events.
[14:53:00.408] <TB2> INFO: 514560 events read in total (11390ms).
[14:53:00.420] <TB2> INFO: Expecting 514560 events.
[14:53:12.174] <TB2> INFO: 514560 events read in total (11185ms).
[14:53:12.189] <TB2> INFO: Expecting 514560 events.
[14:53:23.791] <TB2> INFO: 514560 events read in total (11040ms).
[14:53:23.809] <TB2> INFO: Expecting 514560 events.
[14:53:35.810] <TB2> INFO: 514560 events read in total (11441ms).
[14:53:35.828] <TB2> INFO: Expecting 514560 events.
[14:53:47.670] <TB2> INFO: 514560 events read in total (11282ms).
[14:53:47.694] <TB2> INFO: Expecting 514560 events.
[14:53:59.681] <TB2> INFO: 514560 events read in total (11443ms).
[14:53:59.705] <TB2> INFO: Expecting 514560 events.
[14:54:11.663] <TB2> INFO: 514560 events read in total (11405ms).
[14:54:11.687] <TB2> INFO: Expecting 514560 events.
[14:54:23.509] <TB2> INFO: 514560 events read in total (11274ms).
[14:54:23.538] <TB2> INFO: Expecting 514560 events.
[14:54:34.922] <TB2> INFO: 514560 events read in total (10831ms).
[14:54:34.959] <TB2> INFO: Expecting 514560 events.
[14:54:46.913] <TB2> INFO: 514560 events read in total (11411ms).
[14:54:46.942] <TB2> INFO: Expecting 514560 events.
[14:54:58.357] <TB2> INFO: 514560 events read in total (10856ms).
[14:54:58.390] <TB2> INFO: Expecting 514560 events.
[14:55:11.198] <TB2> INFO: 514560 events read in total (12258ms).
[14:55:11.230] <TB2> INFO: Test took 190853ms.
[14:55:12.187] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:55:12.195] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[14:55:12.195] <TB2> INFO: run 1 of 1
[14:55:12.497] <TB2> INFO: Expecting 6281600 events.
[14:55:47.979] <TB2> INFO: 745230 events read in total (34766ms).
[14:56:23.917] <TB2> INFO: 1488120 events read in total (70704ms).
[14:56:59.406] <TB2> INFO: 2230160 events read in total (106193ms).
[14:57:32.238] <TB2> INFO: 2971890 events read in total (139025ms).
[14:58:05.501] <TB2> INFO: 3708050 events read in total (172288ms).
[14:58:40.789] <TB2> INFO: 4440020 events read in total (207576ms).
[14:59:16.478] <TB2> INFO: 5169240 events read in total (243265ms).
[14:59:51.847] <TB2> INFO: 5896910 events read in total (278634ms).
[15:00:08.468] <TB2> INFO: 6281600 events read in total (295255ms).
[15:00:08.518] <TB2> INFO: Test took 296323ms.
[15:00:08.677] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:32.847] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.059045 .. 255.000000
[15:00:32.926] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:00:32.933] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:00:32.933] <TB2> INFO: run 1 of 1
[15:00:33.236] <TB2> INFO: Expecting 10649600 events.
[15:01:09.843] <TB2> INFO: 712420 events read in total (35891ms).
[15:01:45.154] <TB2> INFO: 1425210 events read in total (71202ms).
[15:02:19.901] <TB2> INFO: 2138410 events read in total (105949ms).
[15:02:55.350] <TB2> INFO: 2851310 events read in total (141398ms).
[15:03:31.597] <TB2> INFO: 3564490 events read in total (177645ms).
[15:04:08.133] <TB2> INFO: 4277820 events read in total (214181ms).
[15:04:42.893] <TB2> INFO: 4991060 events read in total (248941ms).
[15:05:17.382] <TB2> INFO: 5704120 events read in total (283430ms).
[15:05:50.201] <TB2> INFO: 6416530 events read in total (316249ms).
[15:06:23.376] <TB2> INFO: 7128110 events read in total (349424ms).
[15:06:55.410] <TB2> INFO: 7839700 events read in total (381458ms).
[15:07:29.523] <TB2> INFO: 8550180 events read in total (415571ms).
[15:08:04.096] <TB2> INFO: 9260990 events read in total (450144ms).
[15:08:37.147] <TB2> INFO: 9971680 events read in total (483195ms).
[15:09:06.536] <TB2> INFO: 10649600 events read in total (512584ms).
[15:09:06.654] <TB2> INFO: Test took 513721ms.
[15:09:06.955] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:36.717] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 15.512989 .. 43.873231
[15:09:36.803] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 53 (-1/-1) hits flags = 528 (plus default)
[15:09:36.811] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:09:36.811] <TB2> INFO: run 1 of 1
[15:09:37.116] <TB2> INFO: Expecting 2038400 events.
[15:10:14.712] <TB2> INFO: 955040 events read in total (36881ms).
[15:10:53.811] <TB2> INFO: 1907280 events read in total (75980ms).
[15:10:59.608] <TB2> INFO: 2038400 events read in total (81778ms).
[15:10:59.624] <TB2> INFO: Test took 82814ms.
[15:10:59.655] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:13.077] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 1.012046 .. 40.217710
[15:11:13.157] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 50 (-1/-1) hits flags = 528 (plus default)
[15:11:13.165] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:11:13.165] <TB2> INFO: run 1 of 1
[15:11:13.482] <TB2> INFO: Expecting 2080000 events.
[15:11:54.165] <TB2> INFO: 1006460 events read in total (39968ms).
[15:12:33.307] <TB2> INFO: 2011320 events read in total (79110ms).
[15:12:36.405] <TB2> INFO: 2080000 events read in total (82208ms).
[15:12:36.418] <TB2> INFO: Test took 83254ms.
[15:12:36.444] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:49.649] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 1.012046 .. 39.998311
[15:12:49.748] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 49 (-1/-1) hits flags = 528 (plus default)
[15:12:49.758] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:12:49.758] <TB2> INFO: run 1 of 1
[15:12:50.096] <TB2> INFO: Expecting 2038400 events.
[15:13:28.239] <TB2> INFO: 1017490 events read in total (37427ms).
[15:14:08.912] <TB2> INFO: 2034390 events read in total (78100ms).
[15:14:09.563] <TB2> INFO: 2038400 events read in total (78752ms).
[15:14:09.576] <TB2> INFO: Test took 79818ms.
[15:14:09.600] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:22.578] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:14:22.578] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:14:22.588] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:14:22.588] <TB2> INFO: run 1 of 1
[15:14:22.894] <TB2> INFO: Expecting 1705600 events.
[15:15:02.343] <TB2> INFO: 879230 events read in total (38733ms).
[15:15:34.216] <TB2> INFO: 1705600 events read in total (70606ms).
[15:15:34.236] <TB2> INFO: Test took 71649ms.
[15:15:34.275] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:47.522] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:15:47.523] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:15:47.523] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:15:47.523] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:15:47.523] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:15:47.523] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:15:47.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:15:47.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:15:47.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:15:47.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:15:47.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:15:47.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:15:47.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:15:47.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:15:47.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:15:47.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:15:47.526] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:15:47.537] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:15:47.543] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:15:47.549] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:15:47.555] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:15:47.561] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:15:47.567] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:15:47.573] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:15:47.579] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:15:47.585] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:15:47.591] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:15:47.598] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:15:47.604] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:15:47.610] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:15:47.616] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:15:47.622] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:15:47.628] <TB2> INFO: PixTestTrim::trimTest() done
[15:15:47.628] <TB2> INFO: vtrim: 109 97 99 87 88 112 94 113 122 92 132 92 106 89 81 112
[15:15:47.628] <TB2> INFO: vthrcomp: 99 92 98 83 85 96 93 97 101 87 95 95 95 96 84 92
[15:15:47.628] <TB2> INFO: vcal mean: 34.97 35.01 34.97 34.97 34.93 35.01 34.99 34.99 34.98 34.94 34.96 34.95 35.01 35.00 35.00 34.99
[15:15:47.628] <TB2> INFO: vcal RMS: 0.87 0.85 0.77 0.81 0.83 0.84 0.78 1.00 1.04 0.80 0.95 0.98 0.82 0.86 0.81 0.84
[15:15:47.628] <TB2> INFO: bits mean: 9.83 9.22 9.86 9.31 9.33 9.81 9.27 9.73 9.03 9.24 9.53 9.49 8.85 9.19 9.89 9.27
[15:15:47.628] <TB2> INFO: bits RMS: 2.69 2.78 2.57 2.70 2.84 2.57 2.68 2.55 2.69 2.83 2.53 2.74 2.91 2.77 2.56 2.64
[15:15:47.635] <TB2> INFO: ----------------------------------------------------------------------
[15:15:47.635] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[15:15:47.635] <TB2> INFO: ----------------------------------------------------------------------
[15:15:47.637] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:15:47.646] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:15:47.646] <TB2> INFO: run 1 of 1
[15:15:47.965] <TB2> INFO: Expecting 8320000 events.
[15:16:25.908] <TB2> INFO: 953900 events read in total (37228ms).
[15:17:01.819] <TB2> INFO: 1896660 events read in total (73139ms).
[15:17:38.719] <TB2> INFO: 2834430 events read in total (110039ms).
[15:18:15.724] <TB2> INFO: 3769230 events read in total (147044ms).
[15:18:53.458] <TB2> INFO: 4697280 events read in total (184778ms).
[15:19:31.361] <TB2> INFO: 5621340 events read in total (222681ms).
[15:20:09.704] <TB2> INFO: 6543970 events read in total (261024ms).
[15:20:48.814] <TB2> INFO: 7465960 events read in total (300134ms).
[15:21:24.142] <TB2> INFO: 8320000 events read in total (335462ms).
[15:21:24.191] <TB2> INFO: Test took 336545ms.
[15:21:24.305] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:50.880] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 177 (-1/-1) hits flags = 528 (plus default)
[15:21:50.888] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:21:50.888] <TB2> INFO: run 1 of 1
[15:21:51.190] <TB2> INFO: Expecting 7404800 events.
[15:22:30.034] <TB2> INFO: 965690 events read in total (38128ms).
[15:23:02.749] <TB2> INFO: 1920080 events read in total (70843ms).
[15:23:41.087] <TB2> INFO: 2869380 events read in total (109181ms).
[15:24:17.382] <TB2> INFO: 3814030 events read in total (145476ms).
[15:24:55.631] <TB2> INFO: 4750250 events read in total (183725ms).
[15:25:33.808] <TB2> INFO: 5685140 events read in total (221902ms).
[15:26:12.295] <TB2> INFO: 6618520 events read in total (260389ms).
[15:26:43.732] <TB2> INFO: 7404800 events read in total (291826ms).
[15:26:43.765] <TB2> INFO: Test took 292877ms.
[15:26:43.851] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:09.363] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 163 (-1/-1) hits flags = 528 (plus default)
[15:27:09.371] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:27:09.371] <TB2> INFO: run 1 of 1
[15:27:09.690] <TB2> INFO: Expecting 6822400 events.
[15:27:49.775] <TB2> INFO: 1003260 events read in total (39369ms).
[15:28:24.935] <TB2> INFO: 1993620 events read in total (74529ms).
[15:29:04.277] <TB2> INFO: 2977270 events read in total (113871ms).
[15:29:43.274] <TB2> INFO: 3952920 events read in total (152868ms).
[15:30:22.237] <TB2> INFO: 4921440 events read in total (191831ms).
[15:31:01.384] <TB2> INFO: 5888530 events read in total (230978ms).
[15:31:38.173] <TB2> INFO: 6822400 events read in total (267767ms).
[15:31:38.208] <TB2> INFO: Test took 268837ms.
[15:31:38.281] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:03.282] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[15:32:03.293] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:32:03.293] <TB2> INFO: run 1 of 1
[15:32:03.636] <TB2> INFO: Expecting 6905600 events.
[15:32:42.291] <TB2> INFO: 997030 events read in total (37940ms).
[15:33:19.972] <TB2> INFO: 1980640 events read in total (75621ms).
[15:33:53.120] <TB2> INFO: 2959070 events read in total (108770ms).
[15:34:32.689] <TB2> INFO: 3928640 events read in total (148338ms).
[15:35:11.669] <TB2> INFO: 4892460 events read in total (187318ms).
[15:35:51.008] <TB2> INFO: 5854170 events read in total (226657ms).
[15:36:30.458] <TB2> INFO: 6818870 events read in total (266107ms).
[15:36:34.136] <TB2> INFO: 6905600 events read in total (269785ms).
[15:36:34.168] <TB2> INFO: Test took 270875ms.
[15:36:34.250] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:57.766] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 164 (-1/-1) hits flags = 528 (plus default)
[15:36:57.773] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:36:57.773] <TB2> INFO: run 1 of 1
[15:36:58.102] <TB2> INFO: Expecting 6864000 events.
[15:37:36.038] <TB2> INFO: 999340 events read in total (37220ms).
[15:38:15.770] <TB2> INFO: 1985670 events read in total (76952ms).
[15:38:55.401] <TB2> INFO: 2965340 events read in total (116584ms).
[15:39:30.024] <TB2> INFO: 3936830 events read in total (151206ms).
[15:40:09.542] <TB2> INFO: 4902750 events read in total (190724ms).
[15:40:49.066] <TB2> INFO: 5865380 events read in total (230248ms).
[15:41:29.371] <TB2> INFO: 6834390 events read in total (270553ms).
[15:41:31.026] <TB2> INFO: 6864000 events read in total (272208ms).
[15:41:31.060] <TB2> INFO: Test took 273286ms.
[15:41:31.141] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:54.729] <TB2> INFO: PixTestTrim::trimBitTest() done
[15:41:54.730] <TB2> INFO: PixTestTrim::doTest() done, duration: 3565 seconds
[15:41:54.730] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:54.730] <TB2> INFO: Decoding statistics:
[15:41:54.730] <TB2> INFO: General information:
[15:41:54.730] <TB2> INFO: 16bit words read: 0
[15:41:54.730] <TB2> INFO: valid events total: 0
[15:41:54.730] <TB2> INFO: empty events: 0
[15:41:54.730] <TB2> INFO: valid events with pixels: 0
[15:41:54.730] <TB2> INFO: valid pixel hits: 0
[15:41:54.730] <TB2> INFO: Event errors: 0
[15:41:54.730] <TB2> INFO: start marker: 0
[15:41:54.730] <TB2> INFO: stop marker: 0
[15:41:54.730] <TB2> INFO: overflow: 0
[15:41:54.730] <TB2> INFO: invalid 5bit words: 0
[15:41:54.730] <TB2> INFO: invalid XOR eye diagram: 0
[15:41:54.730] <TB2> INFO: TBM errors: 0
[15:41:54.730] <TB2> INFO: flawed TBM headers: 0
[15:41:54.730] <TB2> INFO: flawed TBM trailers: 0
[15:41:54.730] <TB2> INFO: event ID mismatches: 0
[15:41:54.730] <TB2> INFO: ROC errors: 0
[15:41:54.730] <TB2> INFO: missing ROC header(s): 0
[15:41:54.730] <TB2> INFO: misplaced readback start: 0
[15:41:54.730] <TB2> INFO: Pixel decoding errors: 0
[15:41:54.730] <TB2> INFO: pixel data incomplete: 0
[15:41:54.730] <TB2> INFO: pixel address: 0
[15:41:54.730] <TB2> INFO: pulse height fill bit: 0
[15:41:54.730] <TB2> INFO: buffer corruption: 0
[15:41:55.404] <TB2> INFO: ######################################################################
[15:41:55.404] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:41:55.404] <TB2> INFO: ######################################################################
[15:41:55.709] <TB2> INFO: Expecting 41600 events.
[15:42:00.419] <TB2> INFO: 41600 events read in total (3995ms).
[15:42:00.419] <TB2> INFO: Test took 5014ms.
[15:42:00.426] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:01.008] <TB2> INFO: Expecting 41600 events.
[15:42:05.623] <TB2> INFO: 41600 events read in total (3899ms).
[15:42:05.623] <TB2> INFO: Test took 4941ms.
[15:42:05.629] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:05.980] <TB2> INFO: Expecting 41600 events.
[15:42:10.504] <TB2> INFO: 41600 events read in total (3808ms).
[15:42:10.504] <TB2> INFO: Test took 4862ms.
[15:42:10.510] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:10.862] <TB2> INFO: Expecting 2560 events.
[15:42:11.940] <TB2> INFO: 2560 events read in total (363ms).
[15:42:11.940] <TB2> INFO: Test took 1423ms.
[15:42:12.354] <TB2> INFO: Expecting 2560 events.
[15:42:13.434] <TB2> INFO: 2560 events read in total (365ms).
[15:42:13.434] <TB2> INFO: Test took 1494ms.
[15:42:13.848] <TB2> INFO: Expecting 2560 events.
[15:42:14.917] <TB2> INFO: 2560 events read in total (353ms).
[15:42:14.917] <TB2> INFO: Test took 1482ms.
[15:42:15.331] <TB2> INFO: Expecting 2560 events.
[15:42:16.396] <TB2> INFO: 2560 events read in total (349ms).
[15:42:16.397] <TB2> INFO: Test took 1479ms.
[15:42:16.810] <TB2> INFO: Expecting 2560 events.
[15:42:17.879] <TB2> INFO: 2560 events read in total (353ms).
[15:42:17.879] <TB2> INFO: Test took 1475ms.
[15:42:18.293] <TB2> INFO: Expecting 2560 events.
[15:42:19.363] <TB2> INFO: 2560 events read in total (354ms).
[15:42:19.363] <TB2> INFO: Test took 1483ms.
[15:42:19.777] <TB2> INFO: Expecting 2560 events.
[15:42:20.845] <TB2> INFO: 2560 events read in total (352ms).
[15:42:20.846] <TB2> INFO: Test took 1482ms.
[15:42:21.260] <TB2> INFO: Expecting 2560 events.
[15:42:22.329] <TB2> INFO: 2560 events read in total (353ms).
[15:42:22.329] <TB2> INFO: Test took 1483ms.
[15:42:22.743] <TB2> INFO: Expecting 2560 events.
[15:42:23.813] <TB2> INFO: 2560 events read in total (354ms).
[15:42:23.813] <TB2> INFO: Test took 1483ms.
[15:42:24.227] <TB2> INFO: Expecting 2560 events.
[15:42:25.296] <TB2> INFO: 2560 events read in total (353ms).
[15:42:25.296] <TB2> INFO: Test took 1482ms.
[15:42:25.710] <TB2> INFO: Expecting 2560 events.
[15:42:26.777] <TB2> INFO: 2560 events read in total (352ms).
[15:42:26.777] <TB2> INFO: Test took 1481ms.
[15:42:27.191] <TB2> INFO: Expecting 2560 events.
[15:42:28.300] <TB2> INFO: 2560 events read in total (393ms).
[15:42:28.300] <TB2> INFO: Test took 1523ms.
[15:42:28.714] <TB2> INFO: Expecting 2560 events.
[15:42:29.780] <TB2> INFO: 2560 events read in total (351ms).
[15:42:29.781] <TB2> INFO: Test took 1480ms.
[15:42:30.195] <TB2> INFO: Expecting 2560 events.
[15:42:31.258] <TB2> INFO: 2560 events read in total (347ms).
[15:42:31.258] <TB2> INFO: Test took 1477ms.
[15:42:31.672] <TB2> INFO: Expecting 2560 events.
[15:42:32.767] <TB2> INFO: 2560 events read in total (379ms).
[15:42:32.767] <TB2> INFO: Test took 1508ms.
[15:42:33.182] <TB2> INFO: Expecting 2560 events.
[15:42:34.261] <TB2> INFO: 2560 events read in total (364ms).
[15:42:34.261] <TB2> INFO: Test took 1494ms.
[15:42:34.264] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:42:34.676] <TB2> INFO: Expecting 655360 events.
[15:42:51.282] <TB2> INFO: 655360 events read in total (15890ms).
[15:42:51.292] <TB2> INFO: Expecting 655360 events.
[15:43:06.373] <TB2> INFO: 655360 events read in total (14554ms).
[15:43:06.384] <TB2> INFO: Expecting 655360 events.
[15:43:22.743] <TB2> INFO: 655360 events read in total (15831ms).
[15:43:22.758] <TB2> INFO: Expecting 655360 events.
[15:43:38.562] <TB2> INFO: 655360 events read in total (15277ms).
[15:43:38.581] <TB2> INFO: Expecting 655360 events.
[15:43:54.904] <TB2> INFO: 655360 events read in total (15796ms).
[15:43:54.928] <TB2> INFO: Expecting 655360 events.
[15:44:08.443] <TB2> INFO: 655360 events read in total (12988ms).
[15:44:08.469] <TB2> INFO: Expecting 655360 events.
[15:44:22.098] <TB2> INFO: 655360 events read in total (13102ms).
[15:44:22.129] <TB2> INFO: Expecting 655360 events.
[15:44:37.412] <TB2> INFO: 655360 events read in total (14756ms).
[15:44:37.446] <TB2> INFO: Expecting 655360 events.
[15:44:52.652] <TB2> INFO: 655360 events read in total (14679ms).
[15:44:52.687] <TB2> INFO: Expecting 655360 events.
[15:45:07.859] <TB2> INFO: 655360 events read in total (14644ms).
[15:45:07.904] <TB2> INFO: Expecting 655360 events.
[15:45:24.485] <TB2> INFO: 655360 events read in total (16053ms).
[15:45:24.535] <TB2> INFO: Expecting 655360 events.
[15:45:40.978] <TB2> INFO: 655360 events read in total (15916ms).
[15:45:41.030] <TB2> INFO: Expecting 655360 events.
[15:45:57.343] <TB2> INFO: 655360 events read in total (15785ms).
[15:45:57.397] <TB2> INFO: Expecting 655360 events.
[15:46:13.674] <TB2> INFO: 655360 events read in total (15750ms).
[15:46:13.733] <TB2> INFO: Expecting 655360 events.
[15:46:30.359] <TB2> INFO: 655360 events read in total (16099ms).
[15:46:30.415] <TB2> INFO: Expecting 655360 events.
[15:46:47.265] <TB2> INFO: 655360 events read in total (16323ms).
[15:46:47.333] <TB2> INFO: Test took 253069ms.
[15:46:47.418] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:47.723] <TB2> INFO: Expecting 655360 events.
[15:47:04.021] <TB2> INFO: 655360 events read in total (15583ms).
[15:47:04.030] <TB2> INFO: Expecting 655360 events.
[15:47:19.119] <TB2> INFO: 655360 events read in total (14561ms).
[15:47:19.131] <TB2> INFO: Expecting 655360 events.
[15:47:33.781] <TB2> INFO: 655360 events read in total (14123ms).
[15:47:33.796] <TB2> INFO: Expecting 655360 events.
[15:47:48.172] <TB2> INFO: 655360 events read in total (13849ms).
[15:47:48.191] <TB2> INFO: Expecting 655360 events.
[15:48:02.970] <TB2> INFO: 655360 events read in total (14252ms).
[15:48:02.994] <TB2> INFO: Expecting 655360 events.
[15:48:17.821] <TB2> INFO: 655360 events read in total (14300ms).
[15:48:17.847] <TB2> INFO: Expecting 655360 events.
[15:48:32.172] <TB2> INFO: 655360 events read in total (13797ms).
[15:48:32.204] <TB2> INFO: Expecting 655360 events.
[15:48:47.752] <TB2> INFO: 655360 events read in total (15021ms).
[15:48:47.785] <TB2> INFO: Expecting 655360 events.
[15:49:03.788] <TB2> INFO: 655360 events read in total (15476ms).
[15:49:03.830] <TB2> INFO: Expecting 655360 events.
[15:49:18.237] <TB2> INFO: 655360 events read in total (13880ms).
[15:49:18.282] <TB2> INFO: Expecting 655360 events.
[15:49:33.210] <TB2> INFO: 655360 events read in total (14401ms).
[15:49:33.273] <TB2> INFO: Expecting 655360 events.
[15:49:47.999] <TB2> INFO: 655360 events read in total (14198ms).
[15:49:48.051] <TB2> INFO: Expecting 655360 events.
[15:50:02.929] <TB2> INFO: 655360 events read in total (14351ms).
[15:50:02.980] <TB2> INFO: Expecting 655360 events.
[15:50:19.089] <TB2> INFO: 655360 events read in total (15581ms).
[15:50:19.149] <TB2> INFO: Expecting 655360 events.
[15:50:34.961] <TB2> INFO: 655360 events read in total (15285ms).
[15:50:35.024] <TB2> INFO: Expecting 655360 events.
[15:50:51.160] <TB2> INFO: 655360 events read in total (15608ms).
[15:50:51.226] <TB2> INFO: Test took 243808ms.
[15:50:51.422] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.429] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.436] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[15:50:51.442] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[15:50:51.449] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.456] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.462] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[15:50:51.469] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.476] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.483] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.489] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.496] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.503] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.510] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.517] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.524] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.530] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.537] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.544] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:50:51.596] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:50:51.596] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:50:51.596] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:50:51.597] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:50:51.597] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:50:51.597] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:50:51.597] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:50:51.597] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:50:51.598] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:50:51.598] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:50:51.598] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:50:51.598] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:50:51.599] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:50:51.599] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:50:51.599] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:50:51.600] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:50:51.931] <TB2> INFO: Expecting 41600 events.
[15:50:56.206] <TB2> INFO: 41600 events read in total (3559ms).
[15:50:56.207] <TB2> INFO: Test took 4604ms.
[15:50:56.756] <TB2> INFO: Expecting 41600 events.
[15:51:00.952] <TB2> INFO: 41600 events read in total (3480ms).
[15:51:00.953] <TB2> INFO: Test took 4507ms.
[15:51:01.501] <TB2> INFO: Expecting 41600 events.
[15:51:05.512] <TB2> INFO: 41600 events read in total (3295ms).
[15:51:05.513] <TB2> INFO: Test took 4324ms.
[15:51:05.745] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:05.877] <TB2> INFO: Expecting 2560 events.
[15:51:06.943] <TB2> INFO: 2560 events read in total (350ms).
[15:51:06.944] <TB2> INFO: Test took 1199ms.
[15:51:06.946] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:07.359] <TB2> INFO: Expecting 2560 events.
[15:51:08.426] <TB2> INFO: 2560 events read in total (352ms).
[15:51:08.427] <TB2> INFO: Test took 1481ms.
[15:51:08.429] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:08.841] <TB2> INFO: Expecting 2560 events.
[15:51:09.922] <TB2> INFO: 2560 events read in total (365ms).
[15:51:09.922] <TB2> INFO: Test took 1493ms.
[15:51:09.924] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:10.337] <TB2> INFO: Expecting 2560 events.
[15:51:11.418] <TB2> INFO: 2560 events read in total (365ms).
[15:51:11.419] <TB2> INFO: Test took 1495ms.
[15:51:11.427] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:11.833] <TB2> INFO: Expecting 2560 events.
[15:51:12.916] <TB2> INFO: 2560 events read in total (367ms).
[15:51:12.916] <TB2> INFO: Test took 1489ms.
[15:51:12.919] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:13.331] <TB2> INFO: Expecting 2560 events.
[15:51:14.414] <TB2> INFO: 2560 events read in total (367ms).
[15:51:14.415] <TB2> INFO: Test took 1497ms.
[15:51:14.417] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:14.829] <TB2> INFO: Expecting 2560 events.
[15:51:15.899] <TB2> INFO: 2560 events read in total (354ms).
[15:51:15.899] <TB2> INFO: Test took 1482ms.
[15:51:15.902] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:16.314] <TB2> INFO: Expecting 2560 events.
[15:51:17.383] <TB2> INFO: 2560 events read in total (353ms).
[15:51:17.384] <TB2> INFO: Test took 1482ms.
[15:51:17.386] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:17.798] <TB2> INFO: Expecting 2560 events.
[15:51:18.865] <TB2> INFO: 2560 events read in total (351ms).
[15:51:18.865] <TB2> INFO: Test took 1479ms.
[15:51:18.867] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:19.280] <TB2> INFO: Expecting 2560 events.
[15:51:20.349] <TB2> INFO: 2560 events read in total (353ms).
[15:51:20.350] <TB2> INFO: Test took 1483ms.
[15:51:20.352] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:20.764] <TB2> INFO: Expecting 2560 events.
[15:51:21.831] <TB2> INFO: 2560 events read in total (352ms).
[15:51:21.832] <TB2> INFO: Test took 1480ms.
[15:51:21.834] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:22.247] <TB2> INFO: Expecting 2560 events.
[15:51:23.328] <TB2> INFO: 2560 events read in total (365ms).
[15:51:23.328] <TB2> INFO: Test took 1494ms.
[15:51:23.331] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:23.744] <TB2> INFO: Expecting 2560 events.
[15:51:24.839] <TB2> INFO: 2560 events read in total (380ms).
[15:51:24.840] <TB2> INFO: Test took 1509ms.
[15:51:24.842] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:25.254] <TB2> INFO: Expecting 2560 events.
[15:51:26.337] <TB2> INFO: 2560 events read in total (367ms).
[15:51:26.338] <TB2> INFO: Test took 1496ms.
[15:51:26.340] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:26.752] <TB2> INFO: Expecting 2560 events.
[15:51:27.837] <TB2> INFO: 2560 events read in total (369ms).
[15:51:27.837] <TB2> INFO: Test took 1497ms.
[15:51:27.840] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:28.252] <TB2> INFO: Expecting 2560 events.
[15:51:29.335] <TB2> INFO: 2560 events read in total (367ms).
[15:51:29.335] <TB2> INFO: Test took 1495ms.
[15:51:29.337] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:29.751] <TB2> INFO: Expecting 2560 events.
[15:51:30.847] <TB2> INFO: 2560 events read in total (381ms).
[15:51:30.848] <TB2> INFO: Test took 1511ms.
[15:51:30.850] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:31.263] <TB2> INFO: Expecting 2560 events.
[15:51:32.331] <TB2> INFO: 2560 events read in total (353ms).
[15:51:32.331] <TB2> INFO: Test took 1481ms.
[15:51:32.337] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:32.746] <TB2> INFO: Expecting 2560 events.
[15:51:33.814] <TB2> INFO: 2560 events read in total (353ms).
[15:51:33.815] <TB2> INFO: Test took 1478ms.
[15:51:33.817] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:34.229] <TB2> INFO: Expecting 2560 events.
[15:51:35.297] <TB2> INFO: 2560 events read in total (352ms).
[15:51:35.297] <TB2> INFO: Test took 1480ms.
[15:51:35.300] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:35.712] <TB2> INFO: Expecting 2560 events.
[15:51:36.780] <TB2> INFO: 2560 events read in total (352ms).
[15:51:36.780] <TB2> INFO: Test took 1480ms.
[15:51:36.783] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:37.195] <TB2> INFO: Expecting 2560 events.
[15:51:38.276] <TB2> INFO: 2560 events read in total (365ms).
[15:51:38.276] <TB2> INFO: Test took 1493ms.
[15:51:38.279] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:38.691] <TB2> INFO: Expecting 2560 events.
[15:51:39.759] <TB2> INFO: 2560 events read in total (353ms).
[15:51:39.759] <TB2> INFO: Test took 1481ms.
[15:51:39.762] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:40.174] <TB2> INFO: Expecting 2560 events.
[15:51:41.243] <TB2> INFO: 2560 events read in total (353ms).
[15:51:41.244] <TB2> INFO: Test took 1482ms.
[15:51:41.246] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:41.659] <TB2> INFO: Expecting 2560 events.
[15:51:42.727] <TB2> INFO: 2560 events read in total (353ms).
[15:51:42.728] <TB2> INFO: Test took 1482ms.
[15:51:42.731] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:43.143] <TB2> INFO: Expecting 2560 events.
[15:51:44.225] <TB2> INFO: 2560 events read in total (366ms).
[15:51:44.226] <TB2> INFO: Test took 1496ms.
[15:51:44.229] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:44.640] <TB2> INFO: Expecting 2560 events.
[15:51:45.707] <TB2> INFO: 2560 events read in total (351ms).
[15:51:45.707] <TB2> INFO: Test took 1479ms.
[15:51:45.710] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:46.122] <TB2> INFO: Expecting 2560 events.
[15:51:47.186] <TB2> INFO: 2560 events read in total (348ms).
[15:51:47.186] <TB2> INFO: Test took 1476ms.
[15:51:47.189] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:47.601] <TB2> INFO: Expecting 2560 events.
[15:51:48.669] <TB2> INFO: 2560 events read in total (352ms).
[15:51:48.670] <TB2> INFO: Test took 1481ms.
[15:51:48.672] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:49.084] <TB2> INFO: Expecting 2560 events.
[15:51:50.153] <TB2> INFO: 2560 events read in total (353ms).
[15:51:50.153] <TB2> INFO: Test took 1481ms.
[15:51:50.155] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:50.568] <TB2> INFO: Expecting 2560 events.
[15:51:51.636] <TB2> INFO: 2560 events read in total (353ms).
[15:51:51.636] <TB2> INFO: Test took 1481ms.
[15:51:51.639] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:51:52.054] <TB2> INFO: Expecting 2560 events.
[15:51:53.121] <TB2> INFO: 2560 events read in total (352ms).
[15:51:53.122] <TB2> INFO: Test took 1483ms.
[15:51:53.741] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 598 seconds
[15:51:53.741] <TB2> INFO: PH scale (per ROC): 75 74 72 67 72 79 67 66 70 73 74 76 68 67 75 76
[15:51:53.741] <TB2> INFO: PH offset (per ROC): 174 182 178 170 165 163 170 186 179 174 181 179 155 179 177 171
[15:51:53.750] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:53.750] <TB2> INFO: Decoding statistics:
[15:51:53.750] <TB2> INFO: General information:
[15:51:53.750] <TB2> INFO: 16bit words read: 91936
[15:51:53.750] <TB2> INFO: valid events total: 10240
[15:51:53.750] <TB2> INFO: empty events: 7736
[15:51:53.750] <TB2> INFO: valid events with pixels: 2504
[15:51:53.750] <TB2> INFO: valid pixel hits: 2504
[15:51:53.750] <TB2> INFO: Event errors: 0
[15:51:53.750] <TB2> INFO: start marker: 0
[15:51:53.750] <TB2> INFO: stop marker: 0
[15:51:53.750] <TB2> INFO: overflow: 0
[15:51:53.750] <TB2> INFO: invalid 5bit words: 0
[15:51:53.750] <TB2> INFO: invalid XOR eye diagram: 0
[15:51:53.750] <TB2> INFO: TBM errors: 0
[15:51:53.750] <TB2> INFO: flawed TBM headers: 0
[15:51:53.750] <TB2> INFO: flawed TBM trailers: 0
[15:51:53.750] <TB2> INFO: event ID mismatches: 0
[15:51:53.750] <TB2> INFO: ROC errors: 0
[15:51:53.750] <TB2> INFO: missing ROC header(s): 0
[15:51:53.750] <TB2> INFO: misplaced readback start: 0
[15:51:53.750] <TB2> INFO: Pixel decoding errors: 0
[15:51:53.750] <TB2> INFO: pixel data incomplete: 0
[15:51:53.750] <TB2> INFO: pixel address: 0
[15:51:53.750] <TB2> INFO: pulse height fill bit: 0
[15:51:53.750] <TB2> INFO: buffer corruption: 0
[15:51:53.926] <TB2> INFO: ######################################################################
[15:51:53.926] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[15:51:53.926] <TB2> INFO: ######################################################################
[15:51:53.936] <TB2> INFO: scanning low vcal = 10
[15:51:54.247] <TB2> INFO: Expecting 41600 events.
[15:51:58.160] <TB2> INFO: 41600 events read in total (3197ms).
[15:51:58.160] <TB2> INFO: Test took 4224ms.
[15:51:58.162] <TB2> INFO: scanning low vcal = 20
[15:51:58.574] <TB2> INFO: Expecting 41600 events.
[15:52:02.379] <TB2> INFO: 41600 events read in total (3089ms).
[15:52:02.379] <TB2> INFO: Test took 4217ms.
[15:52:02.381] <TB2> INFO: scanning low vcal = 30
[15:52:02.793] <TB2> INFO: Expecting 41600 events.
[15:52:06.649] <TB2> INFO: 41600 events read in total (3140ms).
[15:52:06.649] <TB2> INFO: Test took 4268ms.
[15:52:06.652] <TB2> INFO: scanning low vcal = 40
[15:52:07.058] <TB2> INFO: Expecting 41600 events.
[15:52:11.699] <TB2> INFO: 41600 events read in total (3925ms).
[15:52:11.700] <TB2> INFO: Test took 5048ms.
[15:52:11.702] <TB2> INFO: scanning low vcal = 50
[15:52:12.050] <TB2> INFO: Expecting 41600 events.
[15:52:16.673] <TB2> INFO: 41600 events read in total (3907ms).
[15:52:16.674] <TB2> INFO: Test took 4972ms.
[15:52:16.676] <TB2> INFO: scanning low vcal = 60
[15:52:17.021] <TB2> INFO: Expecting 41600 events.
[15:52:21.418] <TB2> INFO: 41600 events read in total (3681ms).
[15:52:21.419] <TB2> INFO: Test took 4743ms.
[15:52:21.427] <TB2> INFO: scanning low vcal = 70
[15:52:21.771] <TB2> INFO: Expecting 41600 events.
[15:52:26.389] <TB2> INFO: 41600 events read in total (3902ms).
[15:52:26.389] <TB2> INFO: Test took 4962ms.
[15:52:26.391] <TB2> INFO: scanning low vcal = 80
[15:52:26.745] <TB2> INFO: Expecting 41600 events.
[15:52:31.377] <TB2> INFO: 41600 events read in total (3917ms).
[15:52:31.378] <TB2> INFO: Test took 4987ms.
[15:52:31.381] <TB2> INFO: scanning low vcal = 90
[15:52:31.733] <TB2> INFO: Expecting 41600 events.
[15:52:36.149] <TB2> INFO: 41600 events read in total (3700ms).
[15:52:36.149] <TB2> INFO: Test took 4768ms.
[15:52:36.153] <TB2> INFO: scanning low vcal = 100
[15:52:36.493] <TB2> INFO: Expecting 41600 events.
[15:52:41.209] <TB2> INFO: 41600 events read in total (4000ms).
[15:52:41.210] <TB2> INFO: Test took 5057ms.
[15:52:41.212] <TB2> INFO: scanning low vcal = 110
[15:52:41.567] <TB2> INFO: Expecting 41600 events.
[15:52:46.120] <TB2> INFO: 41600 events read in total (3837ms).
[15:52:46.121] <TB2> INFO: Test took 4909ms.
[15:52:46.123] <TB2> INFO: scanning low vcal = 120
[15:52:46.476] <TB2> INFO: Expecting 41600 events.
[15:52:50.871] <TB2> INFO: 41600 events read in total (3680ms).
[15:52:50.871] <TB2> INFO: Test took 4748ms.
[15:52:50.874] <TB2> INFO: scanning low vcal = 130
[15:52:51.228] <TB2> INFO: Expecting 41600 events.
[15:52:55.889] <TB2> INFO: 41600 events read in total (3945ms).
[15:52:55.889] <TB2> INFO: Test took 5015ms.
[15:52:55.892] <TB2> INFO: scanning low vcal = 140
[15:52:56.239] <TB2> INFO: Expecting 41600 events.
[15:53:00.932] <TB2> INFO: 41600 events read in total (3977ms).
[15:53:00.933] <TB2> INFO: Test took 5041ms.
[15:53:00.936] <TB2> INFO: scanning low vcal = 150
[15:53:01.272] <TB2> INFO: Expecting 41600 events.
[15:53:05.640] <TB2> INFO: 41600 events read in total (3652ms).
[15:53:05.641] <TB2> INFO: Test took 4705ms.
[15:53:05.644] <TB2> INFO: scanning low vcal = 160
[15:53:05.995] <TB2> INFO: Expecting 41600 events.
[15:53:10.448] <TB2> INFO: 41600 events read in total (3737ms).
[15:53:10.448] <TB2> INFO: Test took 4804ms.
[15:53:10.451] <TB2> INFO: scanning low vcal = 170
[15:53:10.798] <TB2> INFO: Expecting 41600 events.
[15:53:15.495] <TB2> INFO: 41600 events read in total (3981ms).
[15:53:15.496] <TB2> INFO: Test took 5045ms.
[15:53:15.500] <TB2> INFO: scanning low vcal = 180
[15:53:15.838] <TB2> INFO: Expecting 41600 events.
[15:53:20.249] <TB2> INFO: 41600 events read in total (3695ms).
[15:53:20.250] <TB2> INFO: Test took 4750ms.
[15:53:20.265] <TB2> INFO: scanning low vcal = 190
[15:53:20.610] <TB2> INFO: Expecting 41600 events.
[15:53:25.053] <TB2> INFO: 41600 events read in total (3727ms).
[15:53:25.054] <TB2> INFO: Test took 4789ms.
[15:53:25.056] <TB2> INFO: scanning low vcal = 200
[15:53:25.411] <TB2> INFO: Expecting 41600 events.
[15:53:29.970] <TB2> INFO: 41600 events read in total (3844ms).
[15:53:29.970] <TB2> INFO: Test took 4913ms.
[15:53:29.973] <TB2> INFO: scanning low vcal = 210
[15:53:30.329] <TB2> INFO: Expecting 41600 events.
[15:53:34.871] <TB2> INFO: 41600 events read in total (3826ms).
[15:53:34.872] <TB2> INFO: Test took 4899ms.
[15:53:34.875] <TB2> INFO: scanning low vcal = 220
[15:53:35.220] <TB2> INFO: Expecting 41600 events.
[15:53:39.774] <TB2> INFO: 41600 events read in total (3838ms).
[15:53:39.775] <TB2> INFO: Test took 4900ms.
[15:53:39.778] <TB2> INFO: scanning low vcal = 230
[15:53:40.111] <TB2> INFO: Expecting 41600 events.
[15:53:44.652] <TB2> INFO: 41600 events read in total (3825ms).
[15:53:44.653] <TB2> INFO: Test took 4875ms.
[15:53:44.656] <TB2> INFO: scanning low vcal = 240
[15:53:44.976] <TB2> INFO: Expecting 41600 events.
[15:53:49.467] <TB2> INFO: 41600 events read in total (3775ms).
[15:53:49.467] <TB2> INFO: Test took 4811ms.
[15:53:49.470] <TB2> INFO: scanning low vcal = 250
[15:53:49.827] <TB2> INFO: Expecting 41600 events.
[15:53:54.300] <TB2> INFO: 41600 events read in total (3757ms).
[15:53:54.300] <TB2> INFO: Test took 4830ms.
[15:53:54.305] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[15:53:54.626] <TB2> INFO: Expecting 41600 events.
[15:53:59.155] <TB2> INFO: 41600 events read in total (3813ms).
[15:53:59.156] <TB2> INFO: Test took 4851ms.
[15:53:59.159] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[15:53:59.498] <TB2> INFO: Expecting 41600 events.
[15:54:03.978] <TB2> INFO: 41600 events read in total (3764ms).
[15:54:03.978] <TB2> INFO: Test took 4819ms.
[15:54:03.981] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[15:54:04.321] <TB2> INFO: Expecting 41600 events.
[15:54:08.735] <TB2> INFO: 41600 events read in total (3698ms).
[15:54:08.736] <TB2> INFO: Test took 4755ms.
[15:54:08.739] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[15:54:09.094] <TB2> INFO: Expecting 41600 events.
[15:54:13.573] <TB2> INFO: 41600 events read in total (3763ms).
[15:54:13.574] <TB2> INFO: Test took 4835ms.
[15:54:13.576] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[15:54:13.926] <TB2> INFO: Expecting 41600 events.
[15:54:18.296] <TB2> INFO: 41600 events read in total (3654ms).
[15:54:18.296] <TB2> INFO: Test took 4720ms.
[15:54:18.728] <TB2> INFO: PixTestGainPedestal::measure() done
[15:54:50.279] <TB2> INFO: PixTestGainPedestal::fit() done
[15:54:50.279] <TB2> INFO: non-linearity mean: 0.961 0.963 0.957 0.960 0.955 0.960 0.961 0.956 0.959 0.959 0.966 0.960 0.958 0.954 0.950 0.957
[15:54:50.279] <TB2> INFO: non-linearity RMS: 0.006 0.005 0.007 0.006 0.007 0.006 0.006 0.008 0.006 0.005 0.006 0.006 0.006 0.007 0.007 0.006
[15:54:50.279] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[15:54:50.298] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[15:54:50.317] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[15:54:50.335] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[15:54:50.354] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[15:54:50.373] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[15:54:50.391] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[15:54:50.410] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[15:54:50.428] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[15:54:50.447] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[15:54:50.466] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[15:54:50.484] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[15:54:50.502] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[15:54:50.521] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[15:54:50.540] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[15:54:50.558] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[15:54:50.577] <TB2> INFO: PixTestGainPedestal::doTest() done, duration: 176 seconds
[15:54:50.577] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:50.577] <TB2> INFO: Decoding statistics:
[15:54:50.577] <TB2> INFO: General information:
[15:54:50.577] <TB2> INFO: 16bit words read: 2662400
[15:54:50.577] <TB2> INFO: valid events total: 166400
[15:54:50.577] <TB2> INFO: empty events: 0
[15:54:50.577] <TB2> INFO: valid events with pixels: 166400
[15:54:50.577] <TB2> INFO: valid pixel hits: 665571
[15:54:50.577] <TB2> INFO: Event errors: 0
[15:54:50.577] <TB2> INFO: start marker: 0
[15:54:50.577] <TB2> INFO: stop marker: 0
[15:54:50.577] <TB2> INFO: overflow: 0
[15:54:50.577] <TB2> INFO: invalid 5bit words: 0
[15:54:50.577] <TB2> INFO: invalid XOR eye diagram: 0
[15:54:50.577] <TB2> INFO: TBM errors: 0
[15:54:50.577] <TB2> INFO: flawed TBM headers: 0
[15:54:50.577] <TB2> INFO: flawed TBM trailers: 0
[15:54:50.577] <TB2> INFO: event ID mismatches: 0
[15:54:50.577] <TB2> INFO: ROC errors: 0
[15:54:50.577] <TB2> INFO: missing ROC header(s): 0
[15:54:50.577] <TB2> INFO: misplaced readback start: 0
[15:54:50.577] <TB2> INFO: Pixel decoding errors: 0
[15:54:50.577] <TB2> INFO: pixel data incomplete: 0
[15:54:50.577] <TB2> INFO: pixel address: 0
[15:54:50.577] <TB2> INFO: pulse height fill bit: 0
[15:54:50.577] <TB2> INFO: buffer corruption: 0
[15:54:50.583] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C15.dat
[15:54:50.584] <TB2> INFO: ######################################################################
[15:54:50.584] <TB2> INFO: PixTestTrim::doTest()
[15:54:50.584] <TB2> INFO: ######################################################################
[15:54:50.585] <TB2> INFO: PixTestReadback::RES sent once
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C0.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C1.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C2.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C3.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C4.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C5.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C6.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C7.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C8.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C9.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C10.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C11.dat
[15:55:01.839] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C12.dat
[15:55:01.840] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C13.dat
[15:55:01.840] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C14.dat
[15:55:01.840] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C15.dat
[15:55:01.870] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[15:55:01.870] <TB2> INFO: PixTestReadback::RES sent once
[15:55:13.052] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C0.dat
[15:55:13.053] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C1.dat
[15:55:13.054] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C2.dat
[15:55:13.054] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C3.dat
[15:55:13.054] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C4.dat
[15:55:13.054] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C5.dat
[15:55:13.055] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C6.dat
[15:55:13.055] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C7.dat
[15:55:13.055] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C8.dat
[15:55:13.055] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C9.dat
[15:55:13.055] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C10.dat
[15:55:13.056] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C11.dat
[15:55:13.056] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C12.dat
[15:55:13.056] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C13.dat
[15:55:13.056] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C14.dat
[15:55:13.056] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C15.dat
[15:55:13.087] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[15:55:13.087] <TB2> INFO: PixTestReadback::RES sent once
[15:55:21.696] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[15:55:21.696] <TB2> INFO: Vbg will be calibrated using Vd calibration
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 160.3calibrated Vbg = 1.21019 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.8calibrated Vbg = 1.21532 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.3calibrated Vbg = 1.20717 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149.4calibrated Vbg = 1.21524 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.3calibrated Vbg = 1.2233 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.3calibrated Vbg = 1.22223 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.4calibrated Vbg = 1.22193 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 160.9calibrated Vbg = 1.21865 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 165.4calibrated Vbg = 1.22421 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.6calibrated Vbg = 1.22052 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 164.9calibrated Vbg = 1.22195 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 161.8calibrated Vbg = 1.21651 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 159.9calibrated Vbg = 1.20751 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151.7calibrated Vbg = 1.20737 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148.8calibrated Vbg = 1.21292 :::*/*/*/*/
[15:55:21.696] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.9calibrated Vbg = 1.20833 :::*/*/*/*/
[15:55:21.699] <TB2> INFO: PixTestReadback::RES sent once
[15:58:16.140] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C0.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C1.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C2.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C3.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C4.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C5.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C6.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C7.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C8.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C9.dat
[15:58:16.141] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C10.dat
[15:58:16.142] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C11.dat
[15:58:16.142] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C12.dat
[15:58:16.142] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C13.dat
[15:58:16.142] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C14.dat
[15:58:16.142] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2113_FullQualification_2015-09-11_10h30m_1441960257//004_FulltestPxar_p17//readbackCal_C15.dat
[15:58:16.170] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[15:58:16.171] <TB2> INFO: PixTestReadback::doTest() done
[15:58:16.171] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:16.171] <TB2> INFO: Decoding statistics:
[15:58:16.171] <TB2> INFO: General information:
[15:58:16.171] <TB2> INFO: 16bit words read: 1024
[15:58:16.171] <TB2> INFO: valid events total: 128
[15:58:16.171] <TB2> INFO: empty events: 128
[15:58:16.171] <TB2> INFO: valid events with pixels: 0
[15:58:16.171] <TB2> INFO: valid pixel hits: 0
[15:58:16.171] <TB2> INFO: Event errors: 0
[15:58:16.171] <TB2> INFO: start marker: 0
[15:58:16.171] <TB2> INFO: stop marker: 0
[15:58:16.171] <TB2> INFO: overflow: 0
[15:58:16.171] <TB2> INFO: invalid 5bit words: 0
[15:58:16.171] <TB2> INFO: invalid XOR eye diagram: 0
[15:58:16.171] <TB2> INFO: TBM errors: 0
[15:58:16.171] <TB2> INFO: flawed TBM headers: 0
[15:58:16.171] <TB2> INFO: flawed TBM trailers: 0
[15:58:16.171] <TB2> INFO: event ID mismatches: 0
[15:58:16.171] <TB2> INFO: ROC errors: 0
[15:58:16.171] <TB2> INFO: missing ROC header(s): 0
[15:58:16.171] <TB2> INFO: misplaced readback start: 0
[15:58:16.171] <TB2> INFO: Pixel decoding errors: 0
[15:58:16.171] <TB2> INFO: pixel data incomplete: 0
[15:58:16.171] <TB2> INFO: pixel address: 0
[15:58:16.171] <TB2> INFO: pulse height fill bit: 0
[15:58:16.171] <TB2> INFO: buffer corruption: 0
[15:58:16.185] <TB2> INFO: Decoding statistics:
[15:58:16.185] <TB2> INFO: General information:
[15:58:16.185] <TB2> INFO: 16bit words read: 2755360
[15:58:16.185] <TB2> INFO: valid events total: 176768
[15:58:16.185] <TB2> INFO: empty events: 7864
[15:58:16.185] <TB2> INFO: valid events with pixels: 168904
[15:58:16.185] <TB2> INFO: valid pixel hits: 668075
[15:58:16.185] <TB2> INFO: Event errors: 0
[15:58:16.185] <TB2> INFO: start marker: 0
[15:58:16.185] <TB2> INFO: stop marker: 0
[15:58:16.185] <TB2> INFO: overflow: 0
[15:58:16.185] <TB2> INFO: invalid 5bit words: 0
[15:58:16.185] <TB2> INFO: invalid XOR eye diagram: 0
[15:58:16.185] <TB2> INFO: TBM errors: 0
[15:58:16.185] <TB2> INFO: flawed TBM headers: 0
[15:58:16.185] <TB2> INFO: flawed TBM trailers: 0
[15:58:16.185] <TB2> INFO: event ID mismatches: 0
[15:58:16.185] <TB2> INFO: ROC errors: 0
[15:58:16.185] <TB2> INFO: missing ROC header(s): 0
[15:58:16.185] <TB2> INFO: misplaced readback start: 0
[15:58:16.185] <TB2> INFO: Pixel decoding errors: 0
[15:58:16.185] <TB2> INFO: pixel data incomplete: 0
[15:58:16.185] <TB2> INFO: pixel address: 0
[15:58:16.185] <TB2> INFO: pulse height fill bit: 0
[15:58:16.185] <TB2> INFO: buffer corruption: 0
[15:58:16.185] <TB2> INFO: enter test to run
[15:58:16.185] <TB2> INFO: test: exit no parameter change
[15:58:16.765] <TB2> QUIET: Connection to board 156 closed.
[15:58:16.844] <TB2> INFO: pXar: this is the end, my friend