[14:55:03.981] <TB2> INFO: *** Welcome to pxar ***
[14:55:03.981] <TB2> INFO: *** Today: 2015/11/20
[14:55:04.062] <TB2> INFO: *** Version: 9da6-dirty
[14:55:04.062] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C15.dat
[14:55:04.064] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:55:04.064] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//defaultMaskFile.dat
[14:55:04.064] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters_C15.dat
[14:55:04.147] <TB2> INFO: clk: 4
[14:55:04.148] <TB2> INFO: ctr: 4
[14:55:04.148] <TB2> INFO: sda: 19
[14:55:04.148] <TB2> INFO: tin: 9
[14:55:04.148] <TB2> INFO: level: 15
[14:55:04.148] <TB2> INFO: triggerdelay: 0
[14:55:04.148] <TB2> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[14:55:04.148] <TB2> INFO: Log level: INFO
[14:55:04.154] <TB2> INFO: Found DTB DTB_WXC55Z
[14:55:04.165] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[14:55:04.168] <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:55:04.171] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[14:55:05.722] <TB2> INFO: DUT info:
[14:55:05.722] <TB2> INFO: The DUT currently contains the following objects:
[14:55:05.722] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[14:55:05.722] <TB2> INFO: TBM Core alpha (0): 7 registers set
[14:55:05.722] <TB2> INFO: TBM Core beta (1): 7 registers set
[14:55:05.722] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:55:05.722] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.722] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.723] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:05.723] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:55:06.123] <TB2> INFO: enter 'restricted' command line mode
[14:55:06.124] <TB2> INFO: enter test to run
[14:55:06.124] <TB2> INFO: test: pretest no parameter change
[14:55:06.124] <TB2> INFO: running: pretest
[14:55:06.129] <TB2> INFO: ######################################################################
[14:55:06.129] <TB2> INFO: PixTestPretest::doTest()
[14:55:06.129] <TB2> INFO: ######################################################################
[14:55:06.130] <TB2> INFO: ----------------------------------------------------------------------
[14:55:06.130] <TB2> INFO: PixTestPretest::programROC()
[14:55:06.130] <TB2> INFO: ----------------------------------------------------------------------
[14:55:24.147] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:55:24.147] <TB2> INFO: IA differences per ROC: 17.7 18.5 18.5 17.7 17.7 18.5 17.7 18.5 17.7 15.3 16.9 20.1 18.5 18.5 17.7 19.3
[14:55:24.221] <TB2> INFO: ----------------------------------------------------------------------
[14:55:24.221] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:55:24.221] <TB2> INFO: ----------------------------------------------------------------------
[14:55:31.124] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 394.7 mA = 24.6687 mA/ROC
[14:55:31.125] <TB2> INFO: i(loss) [mA/ROC]: 20.9 20.9 20.9 20.1 20.1 20.1 20.1 19.3 20.1 20.1 19.3 20.9 20.1 19.3 19.3 19.3
[14:55:31.159] <TB2> INFO: ----------------------------------------------------------------------
[14:55:31.159] <TB2> INFO: PixTestPretest::findTiming()
[14:55:31.159] <TB2> INFO: ----------------------------------------------------------------------
[14:55:31.159] <TB2> INFO: PixTestCmd::init()

[14:57:09.148] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[14:57:09.148] <TB2> INFO: (success/tries = 100/100), width = 5
[14:57:09.150] <TB2> INFO: ----------------------------------------------------------------------
[14:57:09.150] <TB2> INFO: PixTestPretest::findWorkingPixel()
[14:57:09.150] <TB2> INFO: ----------------------------------------------------------------------
[14:57:09.290] <TB2> INFO: Expecting 231680 events.
[14:57:17.901] <TB2> INFO: 231680 events read in total (7895ms).
[14:57:17.908] <TB2> INFO: Test took 8754ms.
[14:57:18.216] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:57:18.254] <TB2> INFO: ----------------------------------------------------------------------
[14:57:18.254] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[14:57:18.254] <TB2> INFO: ----------------------------------------------------------------------
[14:57:18.394] <TB2> INFO: Expecting 231680 events.
[14:57:27.332] <TB2> INFO: 231680 events read in total (8223ms).
[14:57:27.336] <TB2> INFO: Test took 9074ms.
[14:57:27.666] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[14:57:27.666] <TB2> INFO: CalDel: 141 146 123 126 139 111 134 115 137 127 122 130 116 123 131 129
[14:57:27.666] <TB2> INFO: VthrComp: 52 52 51 51 51 53 51 51 53 51 52 51 61 51 51 54
[14:57:27.670] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C0.dat
[14:57:27.675] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C1.dat
[14:57:27.675] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C2.dat
[14:57:27.675] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C3.dat
[14:57:27.675] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C4.dat
[14:57:27.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C5.dat
[14:57:27.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C6.dat
[14:57:27.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C7.dat
[14:57:27.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C8.dat
[14:57:27.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C9.dat
[14:57:27.676] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C10.dat
[14:57:27.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C11.dat
[14:57:27.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C12.dat
[14:57:27.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C13.dat
[14:57:27.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C14.dat
[14:57:27.677] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters_C15.dat
[14:57:27.678] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//tbmParameters_C0a.dat
[14:57:27.678] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:57:27.678] <TB2> INFO: PixTestPretest::doTest() done, duration: 141 seconds
[14:57:27.745] <TB2> INFO: enter test to run
[14:57:27.745] <TB2> INFO: test: fulltest no parameter change
[14:57:27.745] <TB2> INFO: running: fulltest
[14:57:27.745] <TB2> INFO: ######################################################################
[14:57:27.745] <TB2> INFO: PixTestFullTest::doTest()
[14:57:27.745] <TB2> INFO: ######################################################################
[14:57:27.746] <TB2> INFO: ######################################################################
[14:57:27.746] <TB2> INFO: PixTestAlive::doTest()
[14:57:27.746] <TB2> INFO: ######################################################################
[14:57:27.748] <TB2> INFO: ----------------------------------------------------------------------
[14:57:27.748] <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:57:27.748] <TB2> INFO: ----------------------------------------------------------------------
[14:57:28.051] <TB2> INFO: Expecting 41600 events.
[14:57:32.527] <TB2> INFO: 41600 events read in total (3760ms).
[14:57:32.527] <TB2> INFO: Test took 4777ms.
[14:57:32.533] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:32.827] <TB2> INFO: PixTestAlive::aliveTest() done
[14:57:32.827] <TB2> INFO: number of dead pixels (per ROC): 2 1 2 0 2 0 1 2 3 0 2 10 1 0 3 4
[14:57:32.828] <TB2> INFO: ----------------------------------------------------------------------
[14:57:32.828] <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:57:32.828] <TB2> INFO: ----------------------------------------------------------------------
[14:57:33.145] <TB2> INFO: Expecting 41600 events.
[14:57:36.387] <TB2> INFO: 41600 events read in total (2527ms).
[14:57:36.387] <TB2> INFO: Test took 3557ms.
[14:57:36.387] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:36.388] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:57:36.703] <TB2> INFO: PixTestAlive::maskTest() done
[14:57:36.703] <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:57:36.705] <TB2> INFO: ----------------------------------------------------------------------
[14:57:36.705] <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:57:36.705] <TB2> INFO: ----------------------------------------------------------------------
[14:57:37.015] <TB2> INFO: Expecting 41600 events.
[14:57:41.535] <TB2> INFO: 41600 events read in total (3805ms).
[14:57:41.536] <TB2> INFO: Test took 4830ms.
[14:57:41.542] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:41.828] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[14:57:41.828] <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:57:41.828] <TB2> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:57:41.828] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:41.828] <TB2> INFO: Decoding statistics:
[14:57:41.828] <TB2> INFO: General information:
[14:57:41.828] <TB2> INFO: 16bit words read: 0
[14:57:41.828] <TB2> INFO: valid events total: 0
[14:57:41.828] <TB2> INFO: empty events: 0
[14:57:41.828] <TB2> INFO: valid events with pixels: 0
[14:57:41.828] <TB2> INFO: valid pixel hits: 0
[14:57:41.828] <TB2> INFO: Event errors: 0
[14:57:41.828] <TB2> INFO: start marker: 0
[14:57:41.828] <TB2> INFO: stop marker: 0
[14:57:41.828] <TB2> INFO: overflow: 0
[14:57:41.828] <TB2> INFO: invalid 5bit words: 0
[14:57:41.828] <TB2> INFO: invalid XOR eye diagram: 0
[14:57:41.828] <TB2> INFO: TBM errors: 0
[14:57:41.828] <TB2> INFO: flawed TBM headers: 0
[14:57:41.828] <TB2> INFO: flawed TBM trailers: 0
[14:57:41.828] <TB2> INFO: event ID mismatches: 0
[14:57:41.828] <TB2> INFO: ROC errors: 0
[14:57:41.828] <TB2> INFO: missing ROC header(s): 0
[14:57:41.828] <TB2> INFO: misplaced readback start: 0
[14:57:41.828] <TB2> INFO: Pixel decoding errors: 0
[14:57:41.829] <TB2> INFO: pixel data incomplete: 0
[14:57:41.829] <TB2> INFO: pixel address: 0
[14:57:41.829] <TB2> INFO: pulse height fill bit: 0
[14:57:41.829] <TB2> INFO: buffer corruption: 0
[14:57:41.836] <TB2> INFO: ######################################################################
[14:57:41.836] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:57:41.836] <TB2> INFO: ######################################################################
[14:57:41.839] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:57:41.851] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[14:57:41.851] <TB2> INFO: run 1 of 1
[14:57:42.154] <TB2> INFO: Expecting 3120000 events.
[14:58:29.145] <TB2> INFO: 1019860 events read in total (46275ms).
[14:59:14.288] <TB2> INFO: 2025565 events read in total (91419ms).
[15:00:01.105] <TB2> INFO: 3032620 events read in total (138236ms).
[15:00:05.857] <TB2> INFO: 3120000 events read in total (142987ms).
[15:00:05.906] <TB2> INFO: Test took 144055ms.
[15:00:05.984] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:28.421] <TB2> INFO: PixTestBBMap::doTest() done, duration: 166 seconds
[15:00:28.421] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:00:28.421] <TB2> INFO: separation cut (per ROC): 144 150 148 144 146 147 142 147 135 141 139 145 150 138 129 160
[15:00:28.421] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:28.421] <TB2> INFO: Decoding statistics:
[15:00:28.421] <TB2> INFO: General information:
[15:00:28.421] <TB2> INFO: 16bit words read: 0
[15:00:28.421] <TB2> INFO: valid events total: 0
[15:00:28.421] <TB2> INFO: empty events: 0
[15:00:28.421] <TB2> INFO: valid events with pixels: 0
[15:00:28.421] <TB2> INFO: valid pixel hits: 0
[15:00:28.421] <TB2> INFO: Event errors: 0
[15:00:28.421] <TB2> INFO: start marker: 0
[15:00:28.421] <TB2> INFO: stop marker: 0
[15:00:28.421] <TB2> INFO: overflow: 0
[15:00:28.421] <TB2> INFO: invalid 5bit words: 0
[15:00:28.421] <TB2> INFO: invalid XOR eye diagram: 0
[15:00:28.421] <TB2> INFO: TBM errors: 0
[15:00:28.421] <TB2> INFO: flawed TBM headers: 0
[15:00:28.421] <TB2> INFO: flawed TBM trailers: 0
[15:00:28.421] <TB2> INFO: event ID mismatches: 0
[15:00:28.421] <TB2> INFO: ROC errors: 0
[15:00:28.421] <TB2> INFO: missing ROC header(s): 0
[15:00:28.421] <TB2> INFO: misplaced readback start: 0
[15:00:28.421] <TB2> INFO: Pixel decoding errors: 0
[15:00:28.421] <TB2> INFO: pixel data incomplete: 0
[15:00:28.421] <TB2> INFO: pixel address: 0
[15:00:28.421] <TB2> INFO: pulse height fill bit: 0
[15:00:28.421] <TB2> INFO: buffer corruption: 0
[15:00:28.515] <TB2> INFO: ######################################################################
[15:00:28.515] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:00:28.515] <TB2> INFO: ######################################################################
[15:00:28.515] <TB2> INFO: ----------------------------------------------------------------------
[15:00:28.515] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:00:28.515] <TB2> INFO: ----------------------------------------------------------------------
[15:00:28.515] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:00:28.524] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[15:00:28.524] <TB2> INFO: run 1 of 1
[15:00:28.863] <TB2> INFO: Expecting 26208000 events.
[15:00:58.572] <TB2> INFO: 1037750 events read in total (28993ms).
[15:01:32.205] <TB2> INFO: 2054350 events read in total (62626ms).
[15:02:04.937] <TB2> INFO: 3071150 events read in total (95358ms).
[15:02:37.824] <TB2> INFO: 4083200 events read in total (128245ms).
[15:03:11.011] <TB2> INFO: 5096600 events read in total (161432ms).
[15:03:43.491] <TB2> INFO: 6109900 events read in total (193912ms).
[15:04:15.190] <TB2> INFO: 7118850 events read in total (225611ms).
[15:04:47.683] <TB2> INFO: 8128150 events read in total (258104ms).
[15:05:20.457] <TB2> INFO: 9136950 events read in total (290878ms).
[15:05:52.433] <TB2> INFO: 10143000 events read in total (322854ms).
[15:06:24.498] <TB2> INFO: 11150500 events read in total (354919ms).
[15:06:56.691] <TB2> INFO: 12158150 events read in total (387112ms).
[15:07:29.184] <TB2> INFO: 13159450 events read in total (419605ms).
[15:08:01.836] <TB2> INFO: 14152450 events read in total (452257ms).
[15:08:34.161] <TB2> INFO: 15140350 events read in total (484582ms).
[15:09:06.133] <TB2> INFO: 16127400 events read in total (516554ms).
[15:09:38.959] <TB2> INFO: 17111250 events read in total (549380ms).
[15:10:11.729] <TB2> INFO: 18097200 events read in total (582150ms).
[15:10:44.839] <TB2> INFO: 19079700 events read in total (615260ms).
[15:11:19.005] <TB2> INFO: 20062550 events read in total (649426ms).
[15:11:52.677] <TB2> INFO: 21044400 events read in total (683098ms).
[15:12:25.695] <TB2> INFO: 22024050 events read in total (716116ms).
[15:12:58.962] <TB2> INFO: 23005700 events read in total (749383ms).
[15:13:31.722] <TB2> INFO: 23988050 events read in total (782143ms).
[15:14:05.377] <TB2> INFO: 24968250 events read in total (815798ms).
[15:14:39.000] <TB2> INFO: 25961600 events read in total (849421ms).
[15:14:47.397] <TB2> INFO: 26208000 events read in total (857818ms).
[15:14:47.419] <TB2> INFO: Test took 858895ms.
[15:14:47.470] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:47.568] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:14:49.152] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:14:50.543] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:14:51.982] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:14:53.453] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:14:54.860] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:14:56.209] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:14:57.578] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:14:58.900] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:15:00.256] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:15:01.560] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:15:02.858] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:15:04.153] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:15:05.478] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:15:06.861] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:15:08.230] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:15:09.561] <TB2> INFO: PixTestScurves::scurves() done
[15:15:09.561] <TB2> INFO: Vcal mean: 118.57 124.96 114.55 113.95 116.38 117.61 110.46 116.60 109.51 116.33 116.37 115.96 113.46 103.44 98.00 127.38
[15:15:09.561] <TB2> INFO: Vcal RMS: 6.48 7.31 5.51 5.84 6.04 5.93 5.66 6.21 5.41 5.54 5.54 7.80 6.05 5.15 6.13 8.16
[15:15:09.561] <TB2> INFO: PixTestScurves::fullTest() done, duration: 881 seconds
[15:15:09.561] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:09.561] <TB2> INFO: Decoding statistics:
[15:15:09.561] <TB2> INFO: General information:
[15:15:09.561] <TB2> INFO: 16bit words read: 0
[15:15:09.561] <TB2> INFO: valid events total: 0
[15:15:09.561] <TB2> INFO: empty events: 0
[15:15:09.561] <TB2> INFO: valid events with pixels: 0
[15:15:09.561] <TB2> INFO: valid pixel hits: 0
[15:15:09.561] <TB2> INFO: Event errors: 0
[15:15:09.561] <TB2> INFO: start marker: 0
[15:15:09.561] <TB2> INFO: stop marker: 0
[15:15:09.561] <TB2> INFO: overflow: 0
[15:15:09.561] <TB2> INFO: invalid 5bit words: 0
[15:15:09.561] <TB2> INFO: invalid XOR eye diagram: 0
[15:15:09.561] <TB2> INFO: TBM errors: 0
[15:15:09.561] <TB2> INFO: flawed TBM headers: 0
[15:15:09.561] <TB2> INFO: flawed TBM trailers: 0
[15:15:09.561] <TB2> INFO: event ID mismatches: 0
[15:15:09.561] <TB2> INFO: ROC errors: 0
[15:15:09.561] <TB2> INFO: missing ROC header(s): 0
[15:15:09.561] <TB2> INFO: misplaced readback start: 0
[15:15:09.561] <TB2> INFO: Pixel decoding errors: 0
[15:15:09.561] <TB2> INFO: pixel data incomplete: 0
[15:15:09.561] <TB2> INFO: pixel address: 0
[15:15:09.561] <TB2> INFO: pulse height fill bit: 0
[15:15:09.561] <TB2> INFO: buffer corruption: 0
[15:15:09.637] <TB2> INFO: ######################################################################
[15:15:09.637] <TB2> INFO: PixTestTrim::doTest()
[15:15:09.637] <TB2> INFO: ######################################################################
[15:15:09.639] <TB2> INFO: ----------------------------------------------------------------------
[15:15:09.639] <TB2> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[15:15:09.639] <TB2> INFO: ----------------------------------------------------------------------
[15:15:09.722] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:15:09.722] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:15:09.730] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[15:15:09.730] <TB2> INFO: run 1 of 1
[15:15:10.038] <TB2> INFO: Expecting 5025280 events.
[15:15:53.928] <TB2> INFO: 1105224 events read in total (43173ms).
[15:16:36.421] <TB2> INFO: 2206048 events read in total (85666ms).
[15:17:18.554] <TB2> INFO: 3301280 events read in total (127799ms).
[15:18:00.461] <TB2> INFO: 4395464 events read in total (169706ms).
[15:18:25.289] <TB2> INFO: 5025280 events read in total (194534ms).
[15:18:25.318] <TB2> INFO: Test took 195588ms.
[15:18:25.363] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:46.052] <TB2> INFO: ROC 0 VthrComp = 113
[15:18:46.052] <TB2> INFO: ROC 1 VthrComp = 109
[15:18:46.052] <TB2> INFO: ROC 2 VthrComp = 114
[15:18:46.052] <TB2> INFO: ROC 3 VthrComp = 109
[15:18:46.052] <TB2> INFO: ROC 4 VthrComp = 109
[15:18:46.052] <TB2> INFO: ROC 5 VthrComp = 111
[15:18:46.052] <TB2> INFO: ROC 6 VthrComp = 108
[15:18:46.052] <TB2> INFO: ROC 7 VthrComp = 112
[15:18:46.053] <TB2> INFO: ROC 8 VthrComp = 106
[15:18:46.053] <TB2> INFO: ROC 9 VthrComp = 111
[15:18:46.053] <TB2> INFO: ROC 10 VthrComp = 112
[15:18:46.053] <TB2> INFO: ROC 11 VthrComp = 113
[15:18:46.053] <TB2> INFO: ROC 12 VthrComp = 110
[15:18:46.053] <TB2> INFO: ROC 13 VthrComp = 104
[15:18:46.053] <TB2> INFO: ROC 14 VthrComp = 99
[15:18:46.053] <TB2> INFO: ROC 15 VthrComp = 119
[15:18:46.053] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:18:46.053] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:18:46.063] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[15:18:46.063] <TB2> INFO: run 1 of 1
[15:18:46.365] <TB2> INFO: Expecting 5025280 events.
[15:19:23.650] <TB2> INFO: 763832 events read in total (36569ms).
[15:20:00.251] <TB2> INFO: 1526896 events read in total (73170ms).
[15:20:36.364] <TB2> INFO: 2291072 events read in total (109283ms).
[15:21:12.594] <TB2> INFO: 3050136 events read in total (145513ms).
[15:21:48.805] <TB2> INFO: 3803464 events read in total (181724ms).
[15:22:25.258] <TB2> INFO: 4554960 events read in total (218177ms).
[15:22:48.682] <TB2> INFO: 5025280 events read in total (241601ms).
[15:22:48.726] <TB2> INFO: Test took 242663ms.
[15:22:48.860] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:16.514] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 61.7889 for pixel 7/0 mean/min/max = 48.0115/34.2159/61.807
[15:23:16.515] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 75.6055 for pixel 13/79 mean/min/max = 57.1593/38.6555/75.6632
[15:23:16.515] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 59.2997 for pixel 5/29 mean/min/max = 46.4863/33.6416/59.3311
[15:23:16.515] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 62.3154 for pixel 2/78 mean/min/max = 48.7335/34.8252/62.6418
[15:23:16.516] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 64.06 for pixel 0/14 mean/min/max = 49.4085/34.5806/64.2364
[15:23:16.516] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 69.4735 for pixel 10/75 mean/min/max = 53.3063/37.1167/69.4959
[15:23:16.516] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 62.7511 for pixel 7/79 mean/min/max = 48.8075/34.7882/62.8268
[15:23:16.517] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 61.3088 for pixel 6/16 mean/min/max = 47.3514/33.3684/61.3344
[15:23:16.517] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 61.9307 for pixel 7/1 mean/min/max = 48.0804/33.7549/62.4059
[15:23:16.518] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 62.2936 for pixel 24/6 mean/min/max = 48.5067/34.6384/62.375
[15:23:16.518] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 58.379 for pixel 41/43 mean/min/max = 45.7722/33.026/58.5184
[15:23:16.518] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 61.1174 for pixel 23/5 mean/min/max = 47.7277/34.2847/61.1706
[15:23:16.519] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 73.6532 for pixel 1/78 mean/min/max = 55.0318/36.3475/73.716
[15:23:16.519] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 60.5299 for pixel 3/73 mean/min/max = 47.0903/33.5728/60.6078
[15:23:16.519] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 58.1199 for pixel 5/51 mean/min/max = 45.4531/32.413/58.4932
[15:23:16.520] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 69.2612 for pixel 4/11 mean/min/max = 52.958/36.432/69.4839
[15:23:16.520] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:23:16.652] <TB2> INFO: Expecting 411648 events.
[15:23:26.393] <TB2> INFO: 411648 events read in total (9025ms).
[15:23:26.398] <TB2> INFO: Expecting 411648 events.
[15:23:35.896] <TB2> INFO: 411648 events read in total (8913ms).
[15:23:35.904] <TB2> INFO: Expecting 411648 events.
[15:23:45.668] <TB2> INFO: 411648 events read in total (9186ms).
[15:23:45.681] <TB2> INFO: Expecting 411648 events.
[15:23:55.340] <TB2> INFO: 411648 events read in total (9100ms).
[15:23:55.351] <TB2> INFO: Expecting 411648 events.
[15:24:05.018] <TB2> INFO: 411648 events read in total (9093ms).
[15:24:05.037] <TB2> INFO: Expecting 411648 events.
[15:24:14.881] <TB2> INFO: 411648 events read in total (9285ms).
[15:24:14.898] <TB2> INFO: Expecting 411648 events.
[15:24:24.634] <TB2> INFO: 411648 events read in total (9173ms).
[15:24:24.656] <TB2> INFO: Expecting 411648 events.
[15:24:34.174] <TB2> INFO: 411648 events read in total (8967ms).
[15:24:34.195] <TB2> INFO: Expecting 411648 events.
[15:24:43.861] <TB2> INFO: 411648 events read in total (9099ms).
[15:24:43.890] <TB2> INFO: Expecting 411648 events.
[15:24:53.645] <TB2> INFO: 411648 events read in total (9205ms).
[15:24:53.671] <TB2> INFO: Expecting 411648 events.
[15:25:03.512] <TB2> INFO: 411648 events read in total (9281ms).
[15:25:03.536] <TB2> INFO: Expecting 411648 events.
[15:25:13.375] <TB2> INFO: 411648 events read in total (9270ms).
[15:25:13.412] <TB2> INFO: Expecting 411648 events.
[15:25:23.182] <TB2> INFO: 411648 events read in total (9237ms).
[15:25:23.223] <TB2> INFO: Expecting 411648 events.
[15:25:32.852] <TB2> INFO: 411648 events read in total (9087ms).
[15:25:32.883] <TB2> INFO: Expecting 411648 events.
[15:25:42.562] <TB2> INFO: 411648 events read in total (9121ms).
[15:25:42.601] <TB2> INFO: Expecting 411648 events.
[15:25:52.244] <TB2> INFO: 411648 events read in total (9101ms).
[15:25:52.289] <TB2> INFO: Test took 155769ms.
[15:25:53.401] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:25:53.409] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[15:25:53.409] <TB2> INFO: run 1 of 1
[15:25:53.742] <TB2> INFO: Expecting 5025280 events.
[15:26:30.835] <TB2> INFO: 744392 events read in total (36377ms).
[15:27:07.692] <TB2> INFO: 1488800 events read in total (73234ms).
[15:27:44.225] <TB2> INFO: 2234072 events read in total (109767ms).
[15:28:21.204] <TB2> INFO: 2974760 events read in total (146746ms).
[15:28:57.106] <TB2> INFO: 3708984 events read in total (182648ms).
[15:29:33.523] <TB2> INFO: 4440272 events read in total (219065ms).
[15:30:01.632] <TB2> INFO: 5025280 events read in total (247174ms).
[15:30:01.685] <TB2> INFO: Test took 248276ms.
[15:30:01.830] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:25.885] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.166008 .. 255.000000
[15:30:25.967] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:30:25.976] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[15:30:25.976] <TB2> INFO: run 1 of 1
[15:30:26.282] <TB2> INFO: Expecting 8519680 events.
[15:31:03.380] <TB2> INFO: 711704 events read in total (36382ms).
[15:31:40.619] <TB2> INFO: 1423472 events read in total (73621ms).
[15:32:18.113] <TB2> INFO: 2135336 events read in total (111115ms).
[15:32:54.024] <TB2> INFO: 2847472 events read in total (147026ms).
[15:33:30.029] <TB2> INFO: 3559360 events read in total (183031ms).
[15:34:05.771] <TB2> INFO: 4271336 events read in total (218773ms).
[15:34:41.717] <TB2> INFO: 4984648 events read in total (254719ms).
[15:35:19.040] <TB2> INFO: 5697920 events read in total (292042ms).
[15:35:54.674] <TB2> INFO: 6410736 events read in total (327676ms).
[15:36:30.241] <TB2> INFO: 7123288 events read in total (363243ms).
[15:37:05.468] <TB2> INFO: 7835904 events read in total (398470ms).
[15:37:37.798] <TB2> INFO: 8519680 events read in total (430800ms).
[15:37:37.875] <TB2> INFO: Test took 431899ms.
[15:37:38.143] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:07.839] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 17.786414 .. 83.823588
[15:38:07.918] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 7 .. 93 (-1/-1) hits flags = 528 (plus default)
[15:38:07.926] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[15:38:07.926] <TB2> INFO: run 1 of 1
[15:38:08.228] <TB2> INFO: Expecting 2895360 events.
[15:38:45.356] <TB2> INFO: 790136 events read in total (36412ms).
[15:39:21.047] <TB2> INFO: 1580288 events read in total (72103ms).
[15:39:57.878] <TB2> INFO: 2370168 events read in total (108934ms).
[15:40:22.308] <TB2> INFO: 2895360 events read in total (133364ms).
[15:40:22.344] <TB2> INFO: Test took 134418ms.
[15:40:22.428] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:41.823] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 1.933432 .. 68.176313
[15:40:41.904] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 1 .. 78 (-1/-1) hits flags = 528 (plus default)
[15:40:41.913] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[15:40:41.913] <TB2> INFO: run 1 of 1
[15:40:42.218] <TB2> INFO: Expecting 2595840 events.
[15:41:21.188] <TB2> INFO: 848904 events read in total (38254ms).
[15:42:00.342] <TB2> INFO: 1697592 events read in total (77409ms).
[15:42:36.348] <TB2> INFO: 2546160 events read in total (113414ms).
[15:42:39.191] <TB2> INFO: 2595840 events read in total (116257ms).
[15:42:39.214] <TB2> INFO: Test took 117302ms.
[15:42:39.272] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:57.041] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 2.002371 .. 68.176313
[15:42:57.122] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 2 .. 78 (-1/-1) hits flags = 528 (plus default)
[15:42:57.131] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[15:42:57.131] <TB2> INFO: run 1 of 1
[15:42:57.436] <TB2> INFO: Expecting 2562560 events.
[15:43:35.012] <TB2> INFO: 845008 events read in total (36860ms).
[15:44:14.367] <TB2> INFO: 1689984 events read in total (76215ms).
[15:44:52.551] <TB2> INFO: 2534792 events read in total (114400ms).
[15:44:54.221] <TB2> INFO: 2562560 events read in total (116069ms).
[15:44:54.247] <TB2> INFO: Test took 117117ms.
[15:44:54.309] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:13.504] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:45:13.504] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:45:13.513] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[15:45:13.513] <TB2> INFO: run 1 of 1
[15:45:13.825] <TB2> INFO: Expecting 1364480 events.
[15:45:54.354] <TB2> INFO: 878080 events read in total (39814ms).
[15:46:16.660] <TB2> INFO: 1364480 events read in total (62120ms).
[15:46:16.681] <TB2> INFO: Test took 63168ms.
[15:46:16.712] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:31.662] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:46:31.662] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:46:31.662] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:46:31.663] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:46:31.663] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:46:31.663] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:46:31.663] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:46:31.663] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:46:31.663] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:46:31.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:46:31.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:46:31.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:46:31.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:46:31.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:46:31.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:46:31.664] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:46:31.664] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:46:31.671] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:46:31.679] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:46:31.689] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:46:31.699] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:46:31.709] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:46:31.719] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:46:31.729] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:46:31.739] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:46:31.747] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:46:31.756] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:46:31.766] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:46:31.775] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:46:31.782] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:46:31.789] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:46:31.796] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:46:31.802] <TB2> INFO: PixTestTrim::trimTest() done
[15:46:31.802] <TB2> INFO: vtrim: 110 150 117 130 117 149 118 113 113 118 101 118 151 104 104 141
[15:46:31.802] <TB2> INFO: vthrcomp: 113 109 114 109 109 111 108 112 106 111 112 113 110 104 99 119
[15:46:31.802] <TB2> INFO: vcal mean: 34.97 35.01 34.99 35.00 34.93 35.01 34.95 34.93 34.96 34.99 34.85 34.93 34.96 35.02 34.92 34.92
[15:46:31.802] <TB2> INFO: vcal RMS: 0.96 1.27 1.19 0.96 1.24 1.07 1.11 1.23 1.24 0.98 1.22 1.94 1.27 0.94 1.31 1.52
[15:46:31.802] <TB2> INFO: bits mean: 8.53 7.26 9.04 8.56 8.14 8.10 8.49 9.00 8.72 8.65 9.51 8.61 7.99 9.00 9.64 7.91
[15:46:31.802] <TB2> INFO: bits RMS: 2.60 2.27 2.63 2.43 2.60 2.12 2.46 2.58 2.49 2.47 2.54 2.56 2.24 2.57 2.57 2.22
[15:46:31.810] <TB2> INFO: ----------------------------------------------------------------------
[15:46:31.810] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[15:46:31.810] <TB2> INFO: ----------------------------------------------------------------------
[15:46:31.813] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:46:31.823] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:46:31.823] <TB2> INFO: run 1 of 1
[15:46:32.143] <TB2> INFO: Expecting 8320000 events.
[15:47:12.636] <TB2> INFO: 1037440 events read in total (39777ms).
[15:47:52.371] <TB2> INFO: 2067310 events read in total (79512ms).
[15:48:30.901] <TB2> INFO: 3092740 events read in total (118043ms).
[15:49:07.228] <TB2> INFO: 4114850 events read in total (154369ms).
[15:49:45.589] <TB2> INFO: 5127400 events read in total (192730ms).
[15:50:25.096] <TB2> INFO: 6136070 events read in total (232237ms).
[15:51:04.532] <TB2> INFO: 7145420 events read in total (271673ms).
[15:51:44.878] <TB2> INFO: 8155890 events read in total (312019ms).
[15:51:51.480] <TB2> INFO: 8320000 events read in total (318621ms).
[15:51:51.517] <TB2> INFO: Test took 319694ms.
[15:51:51.601] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:17.525] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 204 (-1/-1) hits flags = 528 (plus default)
[15:52:17.535] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:52:17.535] <TB2> INFO: run 1 of 1
[15:52:17.880] <TB2> INFO: Expecting 8528000 events.
[15:52:57.197] <TB2> INFO: 983640 events read in total (38601ms).
[15:53:36.642] <TB2> INFO: 1960580 events read in total (78046ms).
[15:54:16.495] <TB2> INFO: 2934830 events read in total (117899ms).
[15:54:55.878] <TB2> INFO: 3907020 events read in total (157282ms).
[15:55:34.452] <TB2> INFO: 4872580 events read in total (195856ms).
[15:56:09.405] <TB2> INFO: 5833930 events read in total (230809ms).
[15:56:47.682] <TB2> INFO: 6794290 events read in total (269086ms).
[15:57:25.378] <TB2> INFO: 7755100 events read in total (306782ms).
[15:57:56.347] <TB2> INFO: 8528000 events read in total (337751ms).
[15:57:56.380] <TB2> INFO: Test took 338845ms.
[15:57:56.472] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:25.620] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 197 (-1/-1) hits flags = 528 (plus default)
[15:58:25.628] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:58:25.628] <TB2> INFO: run 1 of 1
[15:58:25.931] <TB2> INFO: Expecting 8236800 events.
[15:59:05.838] <TB2> INFO: 999880 events read in total (39191ms).
[15:59:44.589] <TB2> INFO: 1993380 events read in total (77942ms).
[16:00:23.756] <TB2> INFO: 2983640 events read in total (117109ms).
[16:01:02.458] <TB2> INFO: 3971690 events read in total (155811ms).
[16:01:36.128] <TB2> INFO: 4950980 events read in total (189481ms).
[16:02:14.542] <TB2> INFO: 5926800 events read in total (227895ms).
[16:02:51.734] <TB2> INFO: 6902280 events read in total (265087ms).
[16:03:28.861] <TB2> INFO: 7879520 events read in total (302214ms).
[16:03:43.835] <TB2> INFO: 8236800 events read in total (317188ms).
[16:03:43.869] <TB2> INFO: Test took 318241ms.
[16:03:43.967] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:09.921] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 213 (-1/-1) hits flags = 528 (plus default)
[16:04:09.931] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[16:04:09.931] <TB2> INFO: run 1 of 1
[16:04:10.276] <TB2> INFO: Expecting 8902400 events.
[16:04:48.534] <TB2> INFO: 963510 events read in total (37543ms).
[16:05:26.329] <TB2> INFO: 1921370 events read in total (75338ms).
[16:06:03.987] <TB2> INFO: 2876210 events read in total (112996ms).
[16:06:40.566] <TB2> INFO: 3830070 events read in total (149575ms).
[16:07:16.030] <TB2> INFO: 4779480 events read in total (185039ms).
[16:07:53.300] <TB2> INFO: 5722520 events read in total (222309ms).
[16:08:30.854] <TB2> INFO: 6665030 events read in total (259863ms).
[16:09:08.204] <TB2> INFO: 7607310 events read in total (297213ms).
[16:09:46.200] <TB2> INFO: 8550930 events read in total (335209ms).
[16:10:00.113] <TB2> INFO: 8902400 events read in total (349122ms).
[16:10:00.152] <TB2> INFO: Test took 350221ms.
[16:10:00.254] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:31.105] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 208 (-1/-1) hits flags = 528 (plus default)
[16:10:31.115] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[16:10:31.115] <TB2> INFO: run 1 of 1
[16:10:31.456] <TB2> INFO: Expecting 8694400 events.
[16:11:10.124] <TB2> INFO: 973340 events read in total (37952ms).
[16:11:48.065] <TB2> INFO: 1940770 events read in total (75893ms).
[16:12:23.691] <TB2> INFO: 2905170 events read in total (111519ms).
[16:13:03.239] <TB2> INFO: 3868180 events read in total (151067ms).
[16:13:42.551] <TB2> INFO: 4825180 events read in total (190379ms).
[16:14:22.468] <TB2> INFO: 5777240 events read in total (230296ms).
[16:15:03.097] <TB2> INFO: 6728830 events read in total (270925ms).
[16:15:44.273] <TB2> INFO: 7680410 events read in total (312101ms).
[16:16:23.648] <TB2> INFO: 8636380 events read in total (351476ms).
[16:16:26.323] <TB2> INFO: 8694400 events read in total (354151ms).
[16:16:26.366] <TB2> INFO: Test took 355251ms.
[16:16:26.477] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:16:55.060] <TB2> INFO: PixTestTrim::trimBitTest() done
[16:16:55.061] <TB2> INFO: PixTestTrim::doTest() done, duration: 3705 seconds
[16:16:55.061] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:16:55.061] <TB2> INFO: Decoding statistics:
[16:16:55.061] <TB2> INFO: General information:
[16:16:55.062] <TB2> INFO: 16bit words read: 0
[16:16:55.062] <TB2> INFO: valid events total: 0
[16:16:55.062] <TB2> INFO: empty events: 0
[16:16:55.062] <TB2> INFO: valid events with pixels: 0
[16:16:55.062] <TB2> INFO: valid pixel hits: 0
[16:16:55.062] <TB2> INFO: Event errors: 0
[16:16:55.062] <TB2> INFO: start marker: 0
[16:16:55.062] <TB2> INFO: stop marker: 0
[16:16:55.062] <TB2> INFO: overflow: 0
[16:16:55.062] <TB2> INFO: invalid 5bit words: 0
[16:16:55.062] <TB2> INFO: invalid XOR eye diagram: 0
[16:16:55.062] <TB2> INFO: TBM errors: 0
[16:16:55.062] <TB2> INFO: flawed TBM headers: 0
[16:16:55.062] <TB2> INFO: flawed TBM trailers: 0
[16:16:55.062] <TB2> INFO: event ID mismatches: 0
[16:16:55.062] <TB2> INFO: ROC errors: 0
[16:16:55.062] <TB2> INFO: missing ROC header(s): 0
[16:16:55.062] <TB2> INFO: misplaced readback start: 0
[16:16:55.062] <TB2> INFO: Pixel decoding errors: 0
[16:16:55.062] <TB2> INFO: pixel data incomplete: 0
[16:16:55.062] <TB2> INFO: pixel address: 0
[16:16:55.062] <TB2> INFO: pulse height fill bit: 0
[16:16:55.062] <TB2> INFO: buffer corruption: 0
[16:16:55.730] <TB2> INFO: ######################################################################
[16:16:55.730] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:16:55.730] <TB2> INFO: ######################################################################
[16:16:56.034] <TB2> INFO: Expecting 41600 events.
[16:17:00.618] <TB2> INFO: 41600 events read in total (3869ms).
[16:17:00.619] <TB2> INFO: Test took 4888ms.
[16:17:00.625] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:01.199] <TB2> INFO: Expecting 41600 events.
[16:17:05.720] <TB2> INFO: 41600 events read in total (3805ms).
[16:17:05.720] <TB2> INFO: Test took 4835ms.
[16:17:05.727] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:06.078] <TB2> INFO: Expecting 41600 events.
[16:17:10.549] <TB2> INFO: 41600 events read in total (3755ms).
[16:17:10.550] <TB2> INFO: Test took 4795ms.
[16:17:10.557] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:10.891] <TB2> INFO: Expecting 41600 events.
[16:17:15.441] <TB2> INFO: 41600 events read in total (3834ms).
[16:17:15.441] <TB2> INFO: Test took 4872ms.
[16:17:15.447] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:15.800] <TB2> INFO: Expecting 2560 events.
[16:17:16.870] <TB2> INFO: 2560 events read in total (354ms).
[16:17:16.870] <TB2> INFO: Test took 1414ms.
[16:17:17.284] <TB2> INFO: Expecting 2560 events.
[16:17:18.351] <TB2> INFO: 2560 events read in total (351ms).
[16:17:18.351] <TB2> INFO: Test took 1480ms.
[16:17:18.765] <TB2> INFO: Expecting 2560 events.
[16:17:19.848] <TB2> INFO: 2560 events read in total (367ms).
[16:17:19.848] <TB2> INFO: Test took 1496ms.
[16:17:20.262] <TB2> INFO: Expecting 2560 events.
[16:17:21.330] <TB2> INFO: 2560 events read in total (352ms).
[16:17:21.330] <TB2> INFO: Test took 1481ms.
[16:17:21.745] <TB2> INFO: Expecting 2560 events.
[16:17:22.815] <TB2> INFO: 2560 events read in total (355ms).
[16:17:22.815] <TB2> INFO: Test took 1484ms.
[16:17:23.229] <TB2> INFO: Expecting 2560 events.
[16:17:24.296] <TB2> INFO: 2560 events read in total (352ms).
[16:17:24.297] <TB2> INFO: Test took 1482ms.
[16:17:24.710] <TB2> INFO: Expecting 2560 events.
[16:17:25.773] <TB2> INFO: 2560 events read in total (347ms).
[16:17:25.773] <TB2> INFO: Test took 1476ms.
[16:17:26.187] <TB2> INFO: Expecting 2560 events.
[16:17:27.254] <TB2> INFO: 2560 events read in total (351ms).
[16:17:27.255] <TB2> INFO: Test took 1481ms.
[16:17:27.669] <TB2> INFO: Expecting 2560 events.
[16:17:28.738] <TB2> INFO: 2560 events read in total (354ms).
[16:17:28.738] <TB2> INFO: Test took 1483ms.
[16:17:29.152] <TB2> INFO: Expecting 2560 events.
[16:17:30.221] <TB2> INFO: 2560 events read in total (353ms).
[16:17:30.221] <TB2> INFO: Test took 1482ms.
[16:17:30.635] <TB2> INFO: Expecting 2560 events.
[16:17:31.703] <TB2> INFO: 2560 events read in total (352ms).
[16:17:31.703] <TB2> INFO: Test took 1481ms.
[16:17:32.117] <TB2> INFO: Expecting 2560 events.
[16:17:33.188] <TB2> INFO: 2560 events read in total (355ms).
[16:17:33.188] <TB2> INFO: Test took 1484ms.
[16:17:33.601] <TB2> INFO: Expecting 2560 events.
[16:17:34.670] <TB2> INFO: 2560 events read in total (353ms).
[16:17:34.670] <TB2> INFO: Test took 1482ms.
[16:17:35.083] <TB2> INFO: Expecting 2560 events.
[16:17:36.154] <TB2> INFO: 2560 events read in total (355ms).
[16:17:36.154] <TB2> INFO: Test took 1484ms.
[16:17:36.567] <TB2> INFO: Expecting 2560 events.
[16:17:37.637] <TB2> INFO: 2560 events read in total (354ms).
[16:17:37.638] <TB2> INFO: Test took 1484ms.
[16:17:38.051] <TB2> INFO: Expecting 2560 events.
[16:17:39.120] <TB2> INFO: 2560 events read in total (353ms).
[16:17:39.120] <TB2> INFO: Test took 1481ms.
[16:17:39.124] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:17:39.536] <TB2> INFO: Expecting 655360 events.
[16:17:54.781] <TB2> INFO: 655360 events read in total (14527ms).
[16:17:54.790] <TB2> INFO: Expecting 655360 events.
[16:18:09.935] <TB2> INFO: 655360 events read in total (14618ms).
[16:18:09.949] <TB2> INFO: Expecting 655360 events.
[16:18:24.860] <TB2> INFO: 655360 events read in total (14384ms).
[16:18:24.877] <TB2> INFO: Expecting 655360 events.
[16:18:39.943] <TB2> INFO: 655360 events read in total (14538ms).
[16:18:39.962] <TB2> INFO: Expecting 655360 events.
[16:18:55.116] <TB2> INFO: 655360 events read in total (14627ms).
[16:18:55.139] <TB2> INFO: Expecting 655360 events.
[16:19:10.231] <TB2> INFO: 655360 events read in total (14564ms).
[16:19:10.258] <TB2> INFO: Expecting 655360 events.
[16:19:25.248] <TB2> INFO: 655360 events read in total (14462ms).
[16:19:25.277] <TB2> INFO: Expecting 655360 events.
[16:19:40.205] <TB2> INFO: 655360 events read in total (14400ms).
[16:19:40.255] <TB2> INFO: Expecting 655360 events.
[16:19:55.541] <TB2> INFO: 655360 events read in total (14758ms).
[16:19:55.584] <TB2> INFO: Expecting 655360 events.
[16:20:10.775] <TB2> INFO: 655360 events read in total (14663ms).
[16:20:10.814] <TB2> INFO: Expecting 655360 events.
[16:20:25.883] <TB2> INFO: 655360 events read in total (14542ms).
[16:20:25.933] <TB2> INFO: Expecting 655360 events.
[16:20:40.935] <TB2> INFO: 655360 events read in total (14474ms).
[16:20:40.995] <TB2> INFO: Expecting 655360 events.
[16:20:56.094] <TB2> INFO: 655360 events read in total (14572ms).
[16:20:56.143] <TB2> INFO: Expecting 655360 events.
[16:21:11.167] <TB2> INFO: 655360 events read in total (14496ms).
[16:21:11.235] <TB2> INFO: Expecting 655360 events.
[16:21:26.359] <TB2> INFO: 655360 events read in total (14596ms).
[16:21:26.415] <TB2> INFO: Expecting 655360 events.
[16:21:41.467] <TB2> INFO: 655360 events read in total (14525ms).
[16:21:41.527] <TB2> INFO: Test took 242403ms.
[16:21:41.603] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:21:41.913] <TB2> INFO: Expecting 655360 events.
[16:21:57.261] <TB2> INFO: 655360 events read in total (14632ms).
[16:21:57.270] <TB2> INFO: Expecting 655360 events.
[16:22:12.407] <TB2> INFO: 655360 events read in total (14609ms).
[16:22:12.419] <TB2> INFO: Expecting 655360 events.
[16:22:27.558] <TB2> INFO: 655360 events read in total (14612ms).
[16:22:27.575] <TB2> INFO: Expecting 655360 events.
[16:22:42.682] <TB2> INFO: 655360 events read in total (14580ms).
[16:22:42.706] <TB2> INFO: Expecting 655360 events.
[16:22:57.561] <TB2> INFO: 655360 events read in total (14328ms).
[16:22:57.589] <TB2> INFO: Expecting 655360 events.
[16:23:12.513] <TB2> INFO: 655360 events read in total (14397ms).
[16:23:12.539] <TB2> INFO: Expecting 655360 events.
[16:23:27.491] <TB2> INFO: 655360 events read in total (14424ms).
[16:23:27.531] <TB2> INFO: Expecting 655360 events.
[16:23:42.727] <TB2> INFO: 655360 events read in total (14668ms).
[16:23:42.772] <TB2> INFO: Expecting 655360 events.
[16:23:57.864] <TB2> INFO: 655360 events read in total (14564ms).
[16:23:57.903] <TB2> INFO: Expecting 655360 events.
[16:24:12.788] <TB2> INFO: 655360 events read in total (14358ms).
[16:24:12.836] <TB2> INFO: Expecting 655360 events.
[16:24:27.886] <TB2> INFO: 655360 events read in total (14522ms).
[16:24:27.939] <TB2> INFO: Expecting 655360 events.
[16:24:41.849] <TB2> INFO: 655360 events read in total (13383ms).
[16:24:41.916] <TB2> INFO: Expecting 655360 events.
[16:24:55.865] <TB2> INFO: 655360 events read in total (13422ms).
[16:24:55.914] <TB2> INFO: Expecting 655360 events.
[16:25:09.510] <TB2> INFO: 655360 events read in total (13068ms).
[16:25:09.564] <TB2> INFO: Expecting 655360 events.
[16:25:24.550] <TB2> INFO: 655360 events read in total (14459ms).
[16:25:24.620] <TB2> INFO: Expecting 655360 events.
[16:25:39.652] <TB2> INFO: 655360 events read in total (14505ms).
[16:25:39.714] <TB2> INFO: Test took 238111ms.
[16:25:39.897] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.904] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.911] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[16:25:39.917] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.924] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.931] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.937] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.944] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.951] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[16:25:39.958] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.964] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.971] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.978] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.984] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.991] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:39.997] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:40.004] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:40.011] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[16:25:40.053] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:25:40.054] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:25:40.054] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:25:40.054] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:25:40.054] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:25:40.055] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:25:40.055] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:25:40.055] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:25:40.055] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:25:40.056] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:25:40.056] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:25:40.056] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:25:40.056] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:25:40.056] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:25:40.057] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:25:40.057] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:25:40.392] <TB2> INFO: Expecting 41600 events.
[16:25:44.443] <TB2> INFO: 41600 events read in total (3335ms).
[16:25:44.444] <TB2> INFO: Test took 4384ms.
[16:25:44.996] <TB2> INFO: Expecting 41600 events.
[16:25:48.999] <TB2> INFO: 41600 events read in total (3287ms).
[16:25:49.000] <TB2> INFO: Test took 4319ms.
[16:25:49.575] <TB2> INFO: Expecting 41600 events.
[16:25:53.591] <TB2> INFO: 41600 events read in total (3300ms).
[16:25:53.592] <TB2> INFO: Test took 4356ms.
[16:25:53.834] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:25:53.966] <TB2> INFO: Expecting 2560 events.
[16:25:55.037] <TB2> INFO: 2560 events read in total (356ms).
[16:25:55.037] <TB2> INFO: Test took 1203ms.
[16:25:55.040] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:25:55.452] <TB2> INFO: Expecting 2560 events.
[16:25:56.523] <TB2> INFO: 2560 events read in total (355ms).
[16:25:56.523] <TB2> INFO: Test took 1483ms.
[16:25:56.527] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:25:56.938] <TB2> INFO: Expecting 2560 events.
[16:25:58.003] <TB2> INFO: 2560 events read in total (349ms).
[16:25:58.004] <TB2> INFO: Test took 1477ms.
[16:25:58.006] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:25:58.418] <TB2> INFO: Expecting 2560 events.
[16:25:59.490] <TB2> INFO: 2560 events read in total (356ms).
[16:25:59.490] <TB2> INFO: Test took 1484ms.
[16:25:59.493] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:25:59.905] <TB2> INFO: Expecting 2560 events.
[16:26:00.971] <TB2> INFO: 2560 events read in total (350ms).
[16:26:00.972] <TB2> INFO: Test took 1479ms.
[16:26:00.974] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:01.386] <TB2> INFO: Expecting 2560 events.
[16:26:02.453] <TB2> INFO: 2560 events read in total (352ms).
[16:26:02.454] <TB2> INFO: Test took 1480ms.
[16:26:02.456] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:02.868] <TB2> INFO: Expecting 2560 events.
[16:26:03.937] <TB2> INFO: 2560 events read in total (353ms).
[16:26:03.937] <TB2> INFO: Test took 1481ms.
[16:26:03.940] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:04.352] <TB2> INFO: Expecting 2560 events.
[16:26:05.423] <TB2> INFO: 2560 events read in total (355ms).
[16:26:05.423] <TB2> INFO: Test took 1483ms.
[16:26:05.426] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:05.838] <TB2> INFO: Expecting 2560 events.
[16:26:06.906] <TB2> INFO: 2560 events read in total (353ms).
[16:26:06.906] <TB2> INFO: Test took 1480ms.
[16:26:06.909] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:07.321] <TB2> INFO: Expecting 2560 events.
[16:26:08.391] <TB2> INFO: 2560 events read in total (354ms).
[16:26:08.392] <TB2> INFO: Test took 1483ms.
[16:26:08.394] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:08.806] <TB2> INFO: Expecting 2560 events.
[16:26:09.890] <TB2> INFO: 2560 events read in total (368ms).
[16:26:09.890] <TB2> INFO: Test took 1496ms.
[16:26:09.897] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:10.305] <TB2> INFO: Expecting 2560 events.
[16:26:11.369] <TB2> INFO: 2560 events read in total (348ms).
[16:26:11.370] <TB2> INFO: Test took 1473ms.
[16:26:11.372] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:11.785] <TB2> INFO: Expecting 2560 events.
[16:26:12.850] <TB2> INFO: 2560 events read in total (349ms).
[16:26:12.850] <TB2> INFO: Test took 1478ms.
[16:26:12.853] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:13.264] <TB2> INFO: Expecting 2560 events.
[16:26:14.333] <TB2> INFO: 2560 events read in total (353ms).
[16:26:14.333] <TB2> INFO: Test took 1481ms.
[16:26:14.335] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:14.748] <TB2> INFO: Expecting 2560 events.
[16:26:15.818] <TB2> INFO: 2560 events read in total (355ms).
[16:26:15.818] <TB2> INFO: Test took 1483ms.
[16:26:15.821] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:16.233] <TB2> INFO: Expecting 2560 events.
[16:26:17.303] <TB2> INFO: 2560 events read in total (354ms).
[16:26:17.303] <TB2> INFO: Test took 1483ms.
[16:26:17.305] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:17.718] <TB2> INFO: Expecting 2560 events.
[16:26:18.787] <TB2> INFO: 2560 events read in total (353ms).
[16:26:18.787] <TB2> INFO: Test took 1482ms.
[16:26:18.790] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:19.202] <TB2> INFO: Expecting 2560 events.
[16:26:20.270] <TB2> INFO: 2560 events read in total (352ms).
[16:26:20.270] <TB2> INFO: Test took 1480ms.
[16:26:20.273] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:20.685] <TB2> INFO: Expecting 2560 events.
[16:26:21.754] <TB2> INFO: 2560 events read in total (353ms).
[16:26:21.754] <TB2> INFO: Test took 1481ms.
[16:26:21.760] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:22.169] <TB2> INFO: Expecting 2560 events.
[16:26:23.238] <TB2> INFO: 2560 events read in total (353ms).
[16:26:23.238] <TB2> INFO: Test took 1478ms.
[16:26:23.240] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:23.653] <TB2> INFO: Expecting 2560 events.
[16:26:24.722] <TB2> INFO: 2560 events read in total (353ms).
[16:26:24.723] <TB2> INFO: Test took 1483ms.
[16:26:24.725] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:25.137] <TB2> INFO: Expecting 2560 events.
[16:26:26.219] <TB2> INFO: 2560 events read in total (366ms).
[16:26:26.219] <TB2> INFO: Test took 1494ms.
[16:26:26.221] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:26.634] <TB2> INFO: Expecting 2560 events.
[16:26:27.701] <TB2> INFO: 2560 events read in total (352ms).
[16:26:27.701] <TB2> INFO: Test took 1480ms.
[16:26:27.703] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:28.116] <TB2> INFO: Expecting 2560 events.
[16:26:29.182] <TB2> INFO: 2560 events read in total (350ms).
[16:26:29.183] <TB2> INFO: Test took 1480ms.
[16:26:29.185] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:29.598] <TB2> INFO: Expecting 2560 events.
[16:26:30.665] <TB2> INFO: 2560 events read in total (352ms).
[16:26:30.665] <TB2> INFO: Test took 1480ms.
[16:26:30.669] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:31.080] <TB2> INFO: Expecting 2560 events.
[16:26:32.148] <TB2> INFO: 2560 events read in total (353ms).
[16:26:32.149] <TB2> INFO: Test took 1480ms.
[16:26:32.153] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:32.563] <TB2> INFO: Expecting 2560 events.
[16:26:33.631] <TB2> INFO: 2560 events read in total (352ms).
[16:26:33.631] <TB2> INFO: Test took 1479ms.
[16:26:33.634] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:34.046] <TB2> INFO: Expecting 2560 events.
[16:26:35.115] <TB2> INFO: 2560 events read in total (353ms).
[16:26:35.116] <TB2> INFO: Test took 1482ms.
[16:26:35.118] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:35.531] <TB2> INFO: Expecting 2560 events.
[16:26:36.599] <TB2> INFO: 2560 events read in total (353ms).
[16:26:36.599] <TB2> INFO: Test took 1481ms.
[16:26:36.602] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:37.014] <TB2> INFO: Expecting 2560 events.
[16:26:38.079] <TB2> INFO: 2560 events read in total (349ms).
[16:26:38.079] <TB2> INFO: Test took 1477ms.
[16:26:38.081] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:38.495] <TB2> INFO: Expecting 2560 events.
[16:26:39.565] <TB2> INFO: 2560 events read in total (354ms).
[16:26:39.565] <TB2> INFO: Test took 1484ms.
[16:26:39.568] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:26:39.980] <TB2> INFO: Expecting 2560 events.
[16:26:41.048] <TB2> INFO: 2560 events read in total (352ms).
[16:26:41.048] <TB2> INFO: Test took 1481ms.
[16:26:41.664] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 585 seconds
[16:26:41.664] <TB2> INFO: PH scale (per ROC): 69 64 67 67 65 66 70 69 64 75 61 73 61 64 66 66
[16:26:41.664] <TB2> INFO: PH offset (per ROC): 190 190 203 189 178 177 174 188 188 167 186 178 190 198 172 181
[16:26:41.673] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:41.673] <TB2> INFO: Decoding statistics:
[16:26:41.673] <TB2> INFO: General information:
[16:26:41.673] <TB2> INFO: 16bit words read: 91936
[16:26:41.673] <TB2> INFO: valid events total: 10240
[16:26:41.673] <TB2> INFO: empty events: 7736
[16:26:41.673] <TB2> INFO: valid events with pixels: 2504
[16:26:41.673] <TB2> INFO: valid pixel hits: 2504
[16:26:41.673] <TB2> INFO: Event errors: 0
[16:26:41.673] <TB2> INFO: start marker: 0
[16:26:41.673] <TB2> INFO: stop marker: 0
[16:26:41.673] <TB2> INFO: overflow: 0
[16:26:41.673] <TB2> INFO: invalid 5bit words: 0
[16:26:41.673] <TB2> INFO: invalid XOR eye diagram: 0
[16:26:41.673] <TB2> INFO: TBM errors: 0
[16:26:41.673] <TB2> INFO: flawed TBM headers: 0
[16:26:41.673] <TB2> INFO: flawed TBM trailers: 0
[16:26:41.674] <TB2> INFO: event ID mismatches: 0
[16:26:41.674] <TB2> INFO: ROC errors: 0
[16:26:41.674] <TB2> INFO: missing ROC header(s): 0
[16:26:41.674] <TB2> INFO: misplaced readback start: 0
[16:26:41.674] <TB2> INFO: Pixel decoding errors: 0
[16:26:41.674] <TB2> INFO: pixel data incomplete: 0
[16:26:41.674] <TB2> INFO: pixel address: 0
[16:26:41.674] <TB2> INFO: pulse height fill bit: 0
[16:26:41.674] <TB2> INFO: buffer corruption: 0
[16:26:41.850] <TB2> INFO: ######################################################################
[16:26:41.850] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:26:41.850] <TB2> INFO: ######################################################################
[16:26:41.861] <TB2> INFO: scanning low vcal = 10
[16:26:42.175] <TB2> INFO: Expecting 41600 events.
[16:26:45.990] <TB2> INFO: 41600 events read in total (3099ms).
[16:26:45.990] <TB2> INFO: Test took 4129ms.
[16:26:45.992] <TB2> INFO: scanning low vcal = 20
[16:26:46.404] <TB2> INFO: Expecting 41600 events.
[16:26:50.261] <TB2> INFO: 41600 events read in total (3141ms).
[16:26:50.262] <TB2> INFO: Test took 4270ms.
[16:26:50.263] <TB2> INFO: scanning low vcal = 30
[16:26:50.675] <TB2> INFO: Expecting 41600 events.
[16:26:54.561] <TB2> INFO: 41600 events read in total (3170ms).
[16:26:54.562] <TB2> INFO: Test took 4299ms.
[16:26:54.564] <TB2> INFO: scanning low vcal = 40
[16:26:54.966] <TB2> INFO: Expecting 41600 events.
[16:26:59.350] <TB2> INFO: 41600 events read in total (3668ms).
[16:26:59.351] <TB2> INFO: Test took 4787ms.
[16:26:59.355] <TB2> INFO: scanning low vcal = 50
[16:26:59.705] <TB2> INFO: Expecting 41600 events.
[16:27:04.092] <TB2> INFO: 41600 events read in total (3671ms).
[16:27:04.093] <TB2> INFO: Test took 4738ms.
[16:27:04.096] <TB2> INFO: scanning low vcal = 60
[16:27:04.447] <TB2> INFO: Expecting 41600 events.
[16:27:08.879] <TB2> INFO: 41600 events read in total (3717ms).
[16:27:08.881] <TB2> INFO: Test took 4785ms.
[16:27:08.884] <TB2> INFO: scanning low vcal = 70
[16:27:09.220] <TB2> INFO: Expecting 41600 events.
[16:27:13.610] <TB2> INFO: 41600 events read in total (3675ms).
[16:27:13.611] <TB2> INFO: Test took 4727ms.
[16:27:13.614] <TB2> INFO: scanning low vcal = 80
[16:27:13.962] <TB2> INFO: Expecting 41600 events.
[16:27:18.415] <TB2> INFO: 41600 events read in total (3737ms).
[16:27:18.416] <TB2> INFO: Test took 4802ms.
[16:27:18.419] <TB2> INFO: scanning low vcal = 90
[16:27:18.771] <TB2> INFO: Expecting 41600 events.
[16:27:23.199] <TB2> INFO: 41600 events read in total (3712ms).
[16:27:23.200] <TB2> INFO: Test took 4781ms.
[16:27:23.203] <TB2> INFO: scanning low vcal = 100
[16:27:23.555] <TB2> INFO: Expecting 41600 events.
[16:27:28.103] <TB2> INFO: 41600 events read in total (3832ms).
[16:27:28.104] <TB2> INFO: Test took 4901ms.
[16:27:28.106] <TB2> INFO: scanning low vcal = 110
[16:27:28.455] <TB2> INFO: Expecting 41600 events.
[16:27:32.864] <TB2> INFO: 41600 events read in total (3693ms).
[16:27:32.865] <TB2> INFO: Test took 4759ms.
[16:27:32.867] <TB2> INFO: scanning low vcal = 120
[16:27:33.188] <TB2> INFO: Expecting 41600 events.
[16:27:37.580] <TB2> INFO: 41600 events read in total (3676ms).
[16:27:37.581] <TB2> INFO: Test took 4713ms.
[16:27:37.583] <TB2> INFO: scanning low vcal = 130
[16:27:37.936] <TB2> INFO: Expecting 41600 events.
[16:27:42.336] <TB2> INFO: 41600 events read in total (3685ms).
[16:27:42.337] <TB2> INFO: Test took 4754ms.
[16:27:42.339] <TB2> INFO: scanning low vcal = 140
[16:27:42.695] <TB2> INFO: Expecting 41600 events.
[16:27:47.132] <TB2> INFO: 41600 events read in total (3721ms).
[16:27:47.133] <TB2> INFO: Test took 4794ms.
[16:27:47.136] <TB2> INFO: scanning low vcal = 150
[16:27:47.459] <TB2> INFO: Expecting 41600 events.
[16:27:51.868] <TB2> INFO: 41600 events read in total (3693ms).
[16:27:51.869] <TB2> INFO: Test took 4733ms.
[16:27:51.872] <TB2> INFO: scanning low vcal = 160
[16:27:52.227] <TB2> INFO: Expecting 41600 events.
[16:27:56.639] <TB2> INFO: 41600 events read in total (3696ms).
[16:27:56.640] <TB2> INFO: Test took 4768ms.
[16:27:56.642] <TB2> INFO: scanning low vcal = 170
[16:27:56.999] <TB2> INFO: Expecting 41600 events.
[16:28:01.400] <TB2> INFO: 41600 events read in total (3686ms).
[16:28:01.401] <TB2> INFO: Test took 4758ms.
[16:28:01.404] <TB2> INFO: scanning low vcal = 180
[16:28:01.759] <TB2> INFO: Expecting 41600 events.
[16:28:06.197] <TB2> INFO: 41600 events read in total (3722ms).
[16:28:06.198] <TB2> INFO: Test took 4793ms.
[16:28:06.201] <TB2> INFO: scanning low vcal = 190
[16:28:06.526] <TB2> INFO: Expecting 41600 events.
[16:28:10.899] <TB2> INFO: 41600 events read in total (3657ms).
[16:28:10.899] <TB2> INFO: Test took 4698ms.
[16:28:10.902] <TB2> INFO: scanning low vcal = 200
[16:28:11.258] <TB2> INFO: Expecting 41600 events.
[16:28:15.687] <TB2> INFO: 41600 events read in total (3713ms).
[16:28:15.688] <TB2> INFO: Test took 4786ms.
[16:28:15.690] <TB2> INFO: scanning low vcal = 210
[16:28:16.042] <TB2> INFO: Expecting 41600 events.
[16:28:20.481] <TB2> INFO: 41600 events read in total (3723ms).
[16:28:20.482] <TB2> INFO: Test took 4792ms.
[16:28:20.485] <TB2> INFO: scanning low vcal = 220
[16:28:20.833] <TB2> INFO: Expecting 41600 events.
[16:28:25.251] <TB2> INFO: 41600 events read in total (3702ms).
[16:28:25.252] <TB2> INFO: Test took 4767ms.
[16:28:25.254] <TB2> INFO: scanning low vcal = 230
[16:28:25.609] <TB2> INFO: Expecting 41600 events.
[16:28:29.985] <TB2> INFO: 41600 events read in total (3660ms).
[16:28:29.986] <TB2> INFO: Test took 4732ms.
[16:28:29.989] <TB2> INFO: scanning low vcal = 240
[16:28:30.338] <TB2> INFO: Expecting 41600 events.
[16:28:34.741] <TB2> INFO: 41600 events read in total (3688ms).
[16:28:34.741] <TB2> INFO: Test took 4752ms.
[16:28:34.744] <TB2> INFO: scanning low vcal = 250
[16:28:35.092] <TB2> INFO: Expecting 41600 events.
[16:28:39.464] <TB2> INFO: 41600 events read in total (3657ms).
[16:28:39.465] <TB2> INFO: Test took 4721ms.
[16:28:39.469] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[16:28:39.822] <TB2> INFO: Expecting 41600 events.
[16:28:44.238] <TB2> INFO: 41600 events read in total (3701ms).
[16:28:44.238] <TB2> INFO: Test took 4769ms.
[16:28:44.241] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[16:28:44.574] <TB2> INFO: Expecting 41600 events.
[16:28:48.969] <TB2> INFO: 41600 events read in total (3679ms).
[16:28:48.970] <TB2> INFO: Test took 4729ms.
[16:28:48.973] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[16:28:49.325] <TB2> INFO: Expecting 41600 events.
[16:28:53.703] <TB2> INFO: 41600 events read in total (3662ms).
[16:28:53.704] <TB2> INFO: Test took 4731ms.
[16:28:53.707] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[16:28:54.052] <TB2> INFO: Expecting 41600 events.
[16:28:58.444] <TB2> INFO: 41600 events read in total (3676ms).
[16:28:58.445] <TB2> INFO: Test took 4738ms.
[16:28:58.447] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:28:58.803] <TB2> INFO: Expecting 41600 events.
[16:29:03.147] <TB2> INFO: 41600 events read in total (3628ms).
[16:29:03.148] <TB2> INFO: Test took 4701ms.
[16:29:03.588] <TB2> INFO: PixTestGainPedestal::measure() done
[16:29:36.017] <TB2> INFO: PixTestGainPedestal::fit() done
[16:29:36.017] <TB2> INFO: non-linearity mean: 0.959 0.959 0.951 0.952 0.954 0.962 0.958 0.957 0.961 0.953 0.956 0.966 0.959 0.947 0.957 0.950
[16:29:36.017] <TB2> INFO: non-linearity RMS: 0.007 0.007 0.009 0.007 0.007 0.006 0.006 0.007 0.006 0.007 0.007 0.006 0.007 0.007 0.006 0.008
[16:29:36.017] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:29:36.035] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:29:36.053] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:29:36.071] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:29:36.089] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:29:36.107] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:29:36.124] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:29:36.142] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:29:36.160] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:29:36.178] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:29:36.196] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:29:36.213] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:29:36.231] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:29:36.248] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:29:36.266] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:29:36.291] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:29:36.309] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 174 seconds
[16:29:36.309] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:36.309] <TB2> INFO: Decoding statistics:
[16:29:36.309] <TB2> INFO: General information:
[16:29:36.309] <TB2> INFO: 16bit words read: 2662280
[16:29:36.309] <TB2> INFO: valid events total: 166400
[16:29:36.309] <TB2> INFO: empty events: 0
[16:29:36.309] <TB2> INFO: valid events with pixels: 166400
[16:29:36.309] <TB2> INFO: valid pixel hits: 665288
[16:29:36.309] <TB2> INFO: Event errors: 0
[16:29:36.309] <TB2> INFO: start marker: 0
[16:29:36.309] <TB2> INFO: stop marker: 0
[16:29:36.309] <TB2> INFO: overflow: 0
[16:29:36.309] <TB2> INFO: invalid 5bit words: 0
[16:29:36.309] <TB2> INFO: invalid XOR eye diagram: 0
[16:29:36.309] <TB2> INFO: TBM errors: 0
[16:29:36.309] <TB2> INFO: flawed TBM headers: 0
[16:29:36.309] <TB2> INFO: flawed TBM trailers: 0
[16:29:36.309] <TB2> INFO: event ID mismatches: 0
[16:29:36.309] <TB2> INFO: ROC errors: 0
[16:29:36.309] <TB2> INFO: missing ROC header(s): 0
[16:29:36.309] <TB2> INFO: misplaced readback start: 0
[16:29:36.309] <TB2> INFO: Pixel decoding errors: 0
[16:29:36.309] <TB2> INFO: pixel data incomplete: 0
[16:29:36.309] <TB2> INFO: pixel address: 0
[16:29:36.309] <TB2> INFO: pulse height fill bit: 0
[16:29:36.309] <TB2> INFO: buffer corruption: 0
[16:29:36.315] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C15.dat
[16:29:36.316] <TB2> INFO: ######################################################################
[16:29:36.316] <TB2> INFO: PixTestReadback::doTest()
[16:29:36.316] <TB2> INFO: ######################################################################
[16:29:36.317] <TB2> INFO: PixTestReadback::RES sent once
[16:29:47.585] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C0.dat
[16:29:47.585] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C1.dat
[16:29:47.585] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C2.dat
[16:29:47.585] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C3.dat
[16:29:47.585] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C4.dat
[16:29:47.585] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C5.dat
[16:29:47.585] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C6.dat
[16:29:47.585] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C7.dat
[16:29:47.586] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C8.dat
[16:29:47.586] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C9.dat
[16:29:47.586] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C10.dat
[16:29:47.586] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C11.dat
[16:29:47.586] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C12.dat
[16:29:47.586] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C13.dat
[16:29:47.586] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C14.dat
[16:29:47.586] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C15.dat
[16:29:47.616] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[16:29:47.616] <TB2> INFO: PixTestReadback::RES sent once
[16:29:58.816] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C0.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C1.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C2.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C3.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C4.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C5.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C6.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C7.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C8.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C9.dat
[16:29:58.817] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C10.dat
[16:29:58.818] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C11.dat
[16:29:58.818] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C12.dat
[16:29:58.818] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C13.dat
[16:29:58.818] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C14.dat
[16:29:58.818] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C15.dat
[16:29:58.839] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[16:29:58.840] <TB2> INFO: PixTestReadback::RES sent once
[16:30:07.450] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[16:30:07.450] <TB2> INFO: Vbg will be calibrated using Vd calibration
[16:30:07.450] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.1calibrated Vbg = 1.21041 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.2calibrated Vbg = 1.20871 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159calibrated Vbg = 1.21312 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.5calibrated Vbg = 1.21468 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.9calibrated Vbg = 1.22365 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.5calibrated Vbg = 1.22533 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 145calibrated Vbg = 1.22421 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 160calibrated Vbg = 1.22553 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 163.2calibrated Vbg = 1.23219 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.8calibrated Vbg = 1.22925 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.2calibrated Vbg = 1.22768 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.8calibrated Vbg = 1.2258 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.8calibrated Vbg = 1.21846 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 150.4calibrated Vbg = 1.21881 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.8calibrated Vbg = 1.22161 :::*/*/*/*/
[16:30:07.451] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.9calibrated Vbg = 1.21792 :::*/*/*/*/
[16:30:07.455] <TB2> INFO: PixTestReadback::RES sent once
[16:33:01.890] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C0.dat
[16:33:01.890] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C1.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C2.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C3.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C4.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C5.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C6.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C7.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C8.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C9.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C10.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C11.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C12.dat
[16:33:01.891] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C13.dat
[16:33:01.892] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C14.dat
[16:33:01.892] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//004_FulltestPxar_p17//readbackCal_C15.dat
[16:33:01.921] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[16:33:01.922] <TB2> INFO: PixTestReadback::doTest() done
[16:33:01.922] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:01.922] <TB2> INFO: Decoding statistics:
[16:33:01.922] <TB2> INFO: General information:
[16:33:01.922] <TB2> INFO: 16bit words read: 1024
[16:33:01.922] <TB2> INFO: valid events total: 128
[16:33:01.922] <TB2> INFO: empty events: 128
[16:33:01.922] <TB2> INFO: valid events with pixels: 0
[16:33:01.922] <TB2> INFO: valid pixel hits: 0
[16:33:01.922] <TB2> INFO: Event errors: 0
[16:33:01.922] <TB2> INFO: start marker: 0
[16:33:01.922] <TB2> INFO: stop marker: 0
[16:33:01.922] <TB2> INFO: overflow: 0
[16:33:01.922] <TB2> INFO: invalid 5bit words: 0
[16:33:01.922] <TB2> INFO: invalid XOR eye diagram: 0
[16:33:01.922] <TB2> INFO: TBM errors: 0
[16:33:01.922] <TB2> INFO: flawed TBM headers: 0
[16:33:01.922] <TB2> INFO: flawed TBM trailers: 0
[16:33:01.922] <TB2> INFO: event ID mismatches: 0
[16:33:01.922] <TB2> INFO: ROC errors: 0
[16:33:01.922] <TB2> INFO: missing ROC header(s): 0
[16:33:01.922] <TB2> INFO: misplaced readback start: 0
[16:33:01.922] <TB2> INFO: Pixel decoding errors: 0
[16:33:01.922] <TB2> INFO: pixel data incomplete: 0
[16:33:01.922] <TB2> INFO: pixel address: 0
[16:33:01.922] <TB2> INFO: pulse height fill bit: 0
[16:33:01.922] <TB2> INFO: buffer corruption: 0
[16:33:01.938] <TB2> INFO: Decoding statistics:
[16:33:01.938] <TB2> INFO: General information:
[16:33:01.938] <TB2> INFO: 16bit words read: 2755240
[16:33:01.938] <TB2> INFO: valid events total: 176768
[16:33:01.938] <TB2> INFO: empty events: 7864
[16:33:01.938] <TB2> INFO: valid events with pixels: 168904
[16:33:01.938] <TB2> INFO: valid pixel hits: 667792
[16:33:01.938] <TB2> INFO: Event errors: 0
[16:33:01.938] <TB2> INFO: start marker: 0
[16:33:01.938] <TB2> INFO: stop marker: 0
[16:33:01.938] <TB2> INFO: overflow: 0
[16:33:01.938] <TB2> INFO: invalid 5bit words: 0
[16:33:01.938] <TB2> INFO: invalid XOR eye diagram: 0
[16:33:01.938] <TB2> INFO: TBM errors: 0
[16:33:01.938] <TB2> INFO: flawed TBM headers: 0
[16:33:01.938] <TB2> INFO: flawed TBM trailers: 0
[16:33:01.938] <TB2> INFO: event ID mismatches: 0
[16:33:01.938] <TB2> INFO: ROC errors: 0
[16:33:01.938] <TB2> INFO: missing ROC header(s): 0
[16:33:01.938] <TB2> INFO: misplaced readback start: 0
[16:33:01.938] <TB2> INFO: Pixel decoding errors: 0
[16:33:01.938] <TB2> INFO: pixel data incomplete: 0
[16:33:01.938] <TB2> INFO: pixel address: 0
[16:33:01.938] <TB2> INFO: pulse height fill bit: 0
[16:33:01.938] <TB2> INFO: buffer corruption: 0
[16:33:01.938] <TB2> INFO: enter test to run
[16:33:01.938] <TB2> INFO: test: exit no parameter change
[16:33:02.197] <TB2> QUIET: Connection to board 156 closed.
[16:33:02.276] <TB2> INFO: pXar: this is the end, my friend