[12:07:04.171] <TB2> INFO: *** Welcome to pxar ***
[12:07:04.171] <TB2> INFO: *** Today: 2016/01/11
[12:07:04.665] <TB2> INFO: *** Version: 3197
[12:07:04.665] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C15.dat
[12:07:04.666] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:07:04.666] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//defaultMaskFile.dat
[12:07:04.666] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters_C15.dat
[12:07:04.744] <TB2> INFO: clk: 4
[12:07:04.744] <TB2> INFO: ctr: 4
[12:07:04.744] <TB2> INFO: sda: 19
[12:07:04.744] <TB2> INFO: tin: 9
[12:07:04.744] <TB2> INFO: level: 15
[12:07:04.744] <TB2> INFO: triggerdelay: 0
[12:07:04.744] <TB2> QUIET: Instanciating API for pxar prod-12
[12:07:04.744] <TB2> INFO: Log level: INFO
[12:07:04.751] <TB2> INFO: Found DTB DTB_WXC55Z
[12:07:04.761] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[12:07:04.764] <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:
------------------------------------------------------
[12:07:04.766] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[12:07:06.300] <TB2> INFO: DUT info:
[12:07:06.300] <TB2> INFO: The DUT currently contains the following objects:
[12:07:06.300] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:07:06.300] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:07:06.300] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:07:06.300] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:07:06.300] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.300] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.301] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.301] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:07:06.701] <TB2> INFO: enter 'restricted' command line mode
[12:07:06.701] <TB2> INFO: enter test to run
[12:07:06.702] <TB2> INFO: test: pretest no parameter change
[12:07:06.702] <TB2> INFO: running: pretest
[12:07:06.710] <TB2> INFO: ######################################################################
[12:07:06.710] <TB2> INFO: PixTestPretest::doTest()
[12:07:06.710] <TB2> INFO: ######################################################################
[12:07:06.712] <TB2> INFO: ----------------------------------------------------------------------
[12:07:06.712] <TB2> INFO: PixTestPretest::programROC()
[12:07:06.712] <TB2> INFO: ----------------------------------------------------------------------
[12:07:24.729] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:07:24.729] <TB2> INFO: IA differences per ROC: 20.1 20.9 17.7 21.7 18.5 18.5 20.1 20.1 17.7 20.9 17.7 18.5 18.5 20.9 20.9 16.9
[12:07:24.805] <TB2> INFO: ----------------------------------------------------------------------
[12:07:24.805] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:07:24.805] <TB2> INFO: ----------------------------------------------------------------------
[12:07:31.001] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 385.9 mA = 24.1188 mA/ROC
[12:07:31.001] <TB2> INFO: i(loss) [mA/ROC]: 19.3 20.1 20.1 19.3 19.3 19.3 20.1 19.3 19.3 19.3 19.3 19.3 20.1 19.3 19.3 19.3
[12:07:31.036] <TB2> INFO: ----------------------------------------------------------------------
[12:07:31.036] <TB2> INFO: PixTestPretest::findTiming()
[12:07:31.036] <TB2> INFO: ----------------------------------------------------------------------
[12:07:31.036] <TB2> INFO: PixTestCmd::init()

[12:09:57.544] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[12:09:57.544] <TB2> INFO: (success/tries = 100/100), width = 5
[12:09:57.545] <TB2> INFO: ----------------------------------------------------------------------
[12:09:57.545] <TB2> INFO: PixTestPretest::findWorkingPixel()
[12:09:57.545] <TB2> INFO: ----------------------------------------------------------------------
[12:09:57.685] <TB2> INFO: Expecting 231680 events.
[12:10:06.418] <TB2> INFO: 231680 events read in total (8018ms).
[12:10:06.422] <TB2> INFO: Test took 8873ms.
[12:10:06.727] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:10:06.758] <TB2> INFO: ----------------------------------------------------------------------
[12:10:06.758] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[12:10:06.758] <TB2> INFO: ----------------------------------------------------------------------
[12:10:06.892] <TB2> INFO: Expecting 231680 events.
[12:10:15.834] <TB2> INFO: 231680 events read in total (8227ms).
[12:10:15.837] <TB2> INFO: Test took 9076ms.
[12:10:16.161] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[12:10:16.161] <TB2> INFO: CalDel: 123 126 160 133 142 141 132 131 130 150 140 144 138 143 135 124
[12:10:16.161] <TB2> INFO: VthrComp: 51 51 51 51 51 52 51 51 51 51 51 51 51 51 51 53
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C0.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C1.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C2.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C3.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C4.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C5.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C6.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C7.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C8.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C9.dat
[12:10:16.163] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C10.dat
[12:10:16.164] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C11.dat
[12:10:16.164] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C12.dat
[12:10:16.164] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C13.dat
[12:10:16.164] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C14.dat
[12:10:16.164] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters_C15.dat
[12:10:16.164] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//tbmParameters_C0a.dat
[12:10:16.164] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:10:16.164] <TB2> INFO: PixTestPretest::doTest() done, duration: 189 seconds
[12:10:16.227] <TB2> INFO: enter test to run
[12:10:16.227] <TB2> INFO: test: fulltest no parameter change
[12:10:16.227] <TB2> INFO: running: fulltest
[12:10:16.228] <TB2> INFO: ######################################################################
[12:10:16.228] <TB2> INFO: PixTestFullTest::doTest()
[12:10:16.228] <TB2> INFO: ######################################################################
[12:10:16.229] <TB2> INFO: ######################################################################
[12:10:16.229] <TB2> INFO: PixTestAlive::doTest()
[12:10:16.229] <TB2> INFO: ######################################################################
[12:10:16.230] <TB2> INFO: ----------------------------------------------------------------------
[12:10:16.230] <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)
[12:10:16.230] <TB2> INFO: ----------------------------------------------------------------------
[12:10:16.536] <TB2> INFO: Expecting 41600 events.
[12:10:20.920] <TB2> INFO: 41600 events read in total (3668ms).
[12:10:20.921] <TB2> INFO: Test took 4689ms.
[12:10:20.927] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:21.210] <TB2> INFO: PixTestAlive::aliveTest() done
[12:10:21.210] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:10:21.211] <TB2> INFO: ----------------------------------------------------------------------
[12:10:21.211] <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)
[12:10:21.211] <TB2> INFO: ----------------------------------------------------------------------
[12:10:21.518] <TB2> INFO: Expecting 41600 events.
[12:10:24.620] <TB2> INFO: 41600 events read in total (2387ms).
[12:10:24.621] <TB2> INFO: Test took 3409ms.
[12:10:24.621] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:24.621] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:10:24.924] <TB2> INFO: PixTestAlive::maskTest() done
[12:10:24.924] <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
[12:10:24.925] <TB2> INFO: ----------------------------------------------------------------------
[12:10:24.925] <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)
[12:10:24.925] <TB2> INFO: ----------------------------------------------------------------------
[12:10:25.251] <TB2> INFO: Expecting 41600 events.
[12:10:29.441] <TB2> INFO: 41600 events read in total (3475ms).
[12:10:29.442] <TB2> INFO: Test took 4516ms.
[12:10:29.448] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:29.731] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[12:10:29.731] <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
[12:10:29.731] <TB2> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[12:10:29.731] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:29.732] <TB2> INFO: Decoding statistics:
[12:10:29.732] <TB2> INFO: General information:
[12:10:29.732] <TB2> INFO: 16bit words read: 0
[12:10:29.732] <TB2> INFO: valid events total: 0
[12:10:29.732] <TB2> INFO: empty events: 0
[12:10:29.732] <TB2> INFO: valid events with pixels: 0
[12:10:29.732] <TB2> INFO: valid pixel hits: 0
[12:10:29.732] <TB2> INFO: Event errors: 0
[12:10:29.732] <TB2> INFO: start marker: 0
[12:10:29.732] <TB2> INFO: stop marker: 0
[12:10:29.732] <TB2> INFO: overflow: 0
[12:10:29.732] <TB2> INFO: invalid 5bit words: 0
[12:10:29.732] <TB2> INFO: invalid XOR eye diagram: 0
[12:10:29.732] <TB2> INFO: TBM errors: 0
[12:10:29.732] <TB2> INFO: flawed TBM headers: 0
[12:10:29.732] <TB2> INFO: flawed TBM trailers: 0
[12:10:29.732] <TB2> INFO: event ID mismatches: 0
[12:10:29.732] <TB2> INFO: ROC errors: 0
[12:10:29.732] <TB2> INFO: missing ROC header(s): 0
[12:10:29.732] <TB2> INFO: misplaced readback start: 0
[12:10:29.732] <TB2> INFO: Pixel decoding errors: 0
[12:10:29.732] <TB2> INFO: pixel data incomplete: 0
[12:10:29.732] <TB2> INFO: pixel address: 0
[12:10:29.732] <TB2> INFO: pulse height fill bit: 0
[12:10:29.732] <TB2> INFO: buffer corruption: 0
[12:10:29.743] <TB2> INFO: ######################################################################
[12:10:29.743] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:10:29.743] <TB2> INFO: ######################################################################
[12:10:29.745] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:10:29.756] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[12:10:29.756] <TB2> INFO: run 1 of 1
[12:10:30.072] <TB2> INFO: Expecting 3120000 events.
[12:11:11.417] <TB2> INFO: 871855 events read in total (40630ms).
[12:11:51.505] <TB2> INFO: 1734775 events read in total (80718ms).
[12:12:33.736] <TB2> INFO: 2607805 events read in total (122949ms).
[12:12:58.752] <TB2> INFO: 3120000 events read in total (147965ms).
[12:12:58.807] <TB2> INFO: Test took 149051ms.
[12:12:58.909] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:23.328] <TB2> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[12:13:23.328] <TB2> INFO: number of dead bumps (per ROC): 11 7 6 8 4 1 5 14 14 17 1 3 2 0 3 11
[12:13:23.328] <TB2> INFO: separation cut (per ROC): 83 89 66 102 103 93 106 101 75 79 90 85 84 104 96 89
[12:13:23.328] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:23.328] <TB2> INFO: Decoding statistics:
[12:13:23.328] <TB2> INFO: General information:
[12:13:23.328] <TB2> INFO: 16bit words read: 0
[12:13:23.328] <TB2> INFO: valid events total: 0
[12:13:23.328] <TB2> INFO: empty events: 0
[12:13:23.328] <TB2> INFO: valid events with pixels: 0
[12:13:23.328] <TB2> INFO: valid pixel hits: 0
[12:13:23.328] <TB2> INFO: Event errors: 0
[12:13:23.328] <TB2> INFO: start marker: 0
[12:13:23.328] <TB2> INFO: stop marker: 0
[12:13:23.328] <TB2> INFO: overflow: 0
[12:13:23.328] <TB2> INFO: invalid 5bit words: 0
[12:13:23.328] <TB2> INFO: invalid XOR eye diagram: 0
[12:13:23.328] <TB2> INFO: TBM errors: 0
[12:13:23.328] <TB2> INFO: flawed TBM headers: 0
[12:13:23.328] <TB2> INFO: flawed TBM trailers: 0
[12:13:23.328] <TB2> INFO: event ID mismatches: 0
[12:13:23.328] <TB2> INFO: ROC errors: 0
[12:13:23.328] <TB2> INFO: missing ROC header(s): 0
[12:13:23.328] <TB2> INFO: misplaced readback start: 0
[12:13:23.328] <TB2> INFO: Pixel decoding errors: 0
[12:13:23.328] <TB2> INFO: pixel data incomplete: 0
[12:13:23.328] <TB2> INFO: pixel address: 0
[12:13:23.328] <TB2> INFO: pulse height fill bit: 0
[12:13:23.328] <TB2> INFO: buffer corruption: 0
[12:13:23.418] <TB2> INFO: ######################################################################
[12:13:23.418] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:13:23.418] <TB2> INFO: ######################################################################
[12:13:23.418] <TB2> INFO: ----------------------------------------------------------------------
[12:13:23.418] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:13:23.418] <TB2> INFO: ----------------------------------------------------------------------
[12:13:23.419] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:13:23.428] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[12:13:23.428] <TB2> INFO: run 1 of 1
[12:13:23.769] <TB2> INFO: Expecting 26208000 events.
[12:13:53.964] <TB2> INFO: 891750 events read in total (29479ms).
[12:14:22.702] <TB2> INFO: 1769100 events read in total (58217ms).
[12:14:52.899] <TB2> INFO: 2647150 events read in total (88414ms).
[12:15:23.967] <TB2> INFO: 3524750 events read in total (119482ms).
[12:15:54.840] <TB2> INFO: 4399500 events read in total (150355ms).
[12:16:25.313] <TB2> INFO: 5275550 events read in total (180828ms).
[12:16:54.928] <TB2> INFO: 6150550 events read in total (210443ms).
[12:17:24.905] <TB2> INFO: 7021800 events read in total (240420ms).
[12:17:55.329] <TB2> INFO: 7896000 events read in total (270844ms).
[12:18:24.941] <TB2> INFO: 8768950 events read in total (300456ms).
[12:18:54.622] <TB2> INFO: 9643500 events read in total (330137ms).
[12:19:24.786] <TB2> INFO: 10513450 events read in total (360301ms).
[12:19:55.672] <TB2> INFO: 11383200 events read in total (391187ms).
[12:20:26.937] <TB2> INFO: 12257350 events read in total (422452ms).
[12:20:57.825] <TB2> INFO: 13126950 events read in total (453340ms).
[12:21:28.186] <TB2> INFO: 13986350 events read in total (483701ms).
[12:21:56.417] <TB2> INFO: 14847700 events read in total (511932ms).
[12:22:27.431] <TB2> INFO: 15709300 events read in total (542946ms).
[12:22:56.288] <TB2> INFO: 16563800 events read in total (571803ms).
[12:23:24.946] <TB2> INFO: 17422700 events read in total (600461ms).
[12:23:55.729] <TB2> INFO: 18281300 events read in total (631244ms).
[12:24:25.470] <TB2> INFO: 19137850 events read in total (660985ms).
[12:24:52.815] <TB2> INFO: 19994100 events read in total (688330ms).
[12:25:23.343] <TB2> INFO: 20850950 events read in total (718858ms).
[12:25:51.496] <TB2> INFO: 21707250 events read in total (747011ms).
[12:26:20.263] <TB2> INFO: 22559500 events read in total (775778ms).
[12:26:50.866] <TB2> INFO: 23419350 events read in total (806381ms).
[12:27:21.602] <TB2> INFO: 24278350 events read in total (837117ms).
[12:27:50.930] <TB2> INFO: 25135750 events read in total (866445ms).
[12:28:20.328] <TB2> INFO: 26006150 events read in total (895843ms).
[12:28:28.080] <TB2> INFO: 26208000 events read in total (903596ms).
[12:28:28.120] <TB2> INFO: Test took 904692ms.
[12:28:28.201] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:28.391] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:30.016] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:31.657] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:33.172] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:34.760] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:36.292] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:37.894] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:39.463] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:41.179] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:42.968] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:44.603] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:46.180] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:47.789] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:49.395] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:50.912] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:52.642] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[12:28:54.353] <TB2> INFO: PixTestScurves::scurves() done
[12:28:54.353] <TB2> INFO: Vcal mean: 97.26 79.98 72.52 95.94 97.19 94.32 102.32 88.45 78.50 87.63 87.53 81.69 75.22 97.71 94.03 102.17
[12:28:54.353] <TB2> INFO: Vcal RMS: 5.36 4.48 4.34 6.02 5.96 5.92 5.46 5.52 4.58 5.80 5.25 4.62 4.51 6.57 5.29 5.89
[12:28:54.353] <TB2> INFO: PixTestScurves::fullTest() done, duration: 930 seconds
[12:28:54.353] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:54.353] <TB2> INFO: Decoding statistics:
[12:28:54.353] <TB2> INFO: General information:
[12:28:54.353] <TB2> INFO: 16bit words read: 0
[12:28:54.353] <TB2> INFO: valid events total: 0
[12:28:54.353] <TB2> INFO: empty events: 0
[12:28:54.353] <TB2> INFO: valid events with pixels: 0
[12:28:54.353] <TB2> INFO: valid pixel hits: 0
[12:28:54.353] <TB2> INFO: Event errors: 0
[12:28:54.353] <TB2> INFO: start marker: 0
[12:28:54.353] <TB2> INFO: stop marker: 0
[12:28:54.353] <TB2> INFO: overflow: 0
[12:28:54.353] <TB2> INFO: invalid 5bit words: 0
[12:28:54.353] <TB2> INFO: invalid XOR eye diagram: 0
[12:28:54.353] <TB2> INFO: TBM errors: 0
[12:28:54.353] <TB2> INFO: flawed TBM headers: 0
[12:28:54.353] <TB2> INFO: flawed TBM trailers: 0
[12:28:54.353] <TB2> INFO: event ID mismatches: 0
[12:28:54.353] <TB2> INFO: ROC errors: 0
[12:28:54.353] <TB2> INFO: missing ROC header(s): 0
[12:28:54.353] <TB2> INFO: misplaced readback start: 0
[12:28:54.353] <TB2> INFO: Pixel decoding errors: 0
[12:28:54.353] <TB2> INFO: pixel data incomplete: 0
[12:28:54.353] <TB2> INFO: pixel address: 0
[12:28:54.353] <TB2> INFO: pulse height fill bit: 0
[12:28:54.353] <TB2> INFO: buffer corruption: 0
[12:28:54.439] <TB2> INFO: ######################################################################
[12:28:54.439] <TB2> INFO: PixTestTrim::doTest()
[12:28:54.439] <TB2> INFO: ######################################################################
[12:28:54.440] <TB2> INFO: ----------------------------------------------------------------------
[12:28:54.440] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:28:54.440] <TB2> INFO: ----------------------------------------------------------------------
[12:28:54.518] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:28:54.518] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:28:54.527] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:28:54.527] <TB2> INFO: run 1 of 1
[12:28:54.831] <TB2> INFO: Expecting 6281600 events.
[12:29:30.892] <TB2> INFO: 1074250 events read in total (35346ms).
[12:30:08.824] <TB2> INFO: 2142080 events read in total (73278ms).
[12:30:46.900] <TB2> INFO: 3205140 events read in total (111354ms).
[12:31:25.140] <TB2> INFO: 4271390 events read in total (149594ms).
[12:32:03.483] <TB2> INFO: 5339850 events read in total (187937ms).
[12:32:36.023] <TB2> INFO: 6281600 events read in total (220477ms).
[12:32:36.057] <TB2> INFO: Test took 221530ms.
[12:32:36.106] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:32:56.010] <TB2> INFO: ROC 0 VthrComp = 98
[12:32:56.010] <TB2> INFO: ROC 1 VthrComp = 85
[12:32:56.010] <TB2> INFO: ROC 2 VthrComp = 76
[12:32:56.010] <TB2> INFO: ROC 3 VthrComp = 97
[12:32:56.011] <TB2> INFO: ROC 4 VthrComp = 96
[12:32:56.011] <TB2> INFO: ROC 5 VthrComp = 93
[12:32:56.011] <TB2> INFO: ROC 6 VthrComp = 101
[12:32:56.011] <TB2> INFO: ROC 7 VthrComp = 93
[12:32:56.011] <TB2> INFO: ROC 8 VthrComp = 80
[12:32:56.011] <TB2> INFO: ROC 9 VthrComp = 87
[12:32:56.011] <TB2> INFO: ROC 10 VthrComp = 87
[12:32:56.011] <TB2> INFO: ROC 11 VthrComp = 84
[12:32:56.011] <TB2> INFO: ROC 12 VthrComp = 80
[12:32:56.012] <TB2> INFO: ROC 13 VthrComp = 96
[12:32:56.012] <TB2> INFO: ROC 14 VthrComp = 97
[12:32:56.012] <TB2> INFO: ROC 15 VthrComp = 102
[12:32:56.012] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:32:56.012] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:32:56.022] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:32:56.022] <TB2> INFO: run 1 of 1
[12:32:56.330] <TB2> INFO: Expecting 6281600 events.
[12:33:30.225] <TB2> INFO: 755650 events read in total (33180ms).
[12:34:02.694] <TB2> INFO: 1509290 events read in total (65649ms).
[12:34:35.082] <TB2> INFO: 2263870 events read in total (98037ms).
[12:35:07.055] <TB2> INFO: 3019360 events read in total (130010ms).
[12:35:40.327] <TB2> INFO: 3769770 events read in total (163282ms).
[12:36:12.324] <TB2> INFO: 4516720 events read in total (195279ms).
[12:36:45.252] <TB2> INFO: 5261900 events read in total (228207ms).
[12:37:17.546] <TB2> INFO: 6007230 events read in total (260501ms).
[12:37:30.446] <TB2> INFO: 6281600 events read in total (273401ms).
[12:37:30.488] <TB2> INFO: Test took 274467ms.
[12:37:30.626] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:53.700] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 57.7017 for pixel 28/78 mean/min/max = 44.9925/32.2615/57.7235
[12:37:53.700] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 57.7156 for pixel 20/6 mean/min/max = 45.0073/32.2867/57.728
[12:37:53.701] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 57.1575 for pixel 0/3 mean/min/max = 46.7503/36.1742/57.3264
[12:37:53.701] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 60.1672 for pixel 1/5 mean/min/max = 45.8252/31.4742/60.1763
[12:37:53.701] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 60.0186 for pixel 18/79 mean/min/max = 45.961/31.8015/60.1206
[12:37:53.701] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 59.4132 for pixel 16/79 mean/min/max = 46.0277/32.4584/59.597
[12:37:53.702] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 58.5633 for pixel 25/79 mean/min/max = 44.962/31.3317/58.5923
[12:37:53.702] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 59.151 for pixel 8/7 mean/min/max = 45.9388/32.6348/59.2428
[12:37:53.702] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 58.4131 for pixel 25/3 mean/min/max = 45.653/32.64/58.666
[12:37:53.702] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 61.7269 for pixel 18/14 mean/min/max = 46.591/31.4031/61.7789
[12:37:53.703] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 58.889 for pixel 8/8 mean/min/max = 45.4225/31.915/58.93
[12:37:53.703] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 58.4984 for pixel 43/1 mean/min/max = 45.6862/32.7725/58.6
[12:37:53.703] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 57.2083 for pixel 6/14 mean/min/max = 45.0123/32.6774/57.3473
[12:37:53.703] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 61.8107 for pixel 3/25 mean/min/max = 46.6159/31.3322/61.8997
[12:37:53.704] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 57.3838 for pixel 1/16 mean/min/max = 44.5261/31.4766/57.5755
[12:37:53.704] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 61.8137 for pixel 2/79 mean/min/max = 47.0335/32.1279/61.9392
[12:37:53.704] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:37:53.835] <TB2> INFO: Expecting 514560 events.
[12:38:06.041] <TB2> INFO: 514560 events read in total (11491ms).
[12:38:06.047] <TB2> INFO: Expecting 514560 events.
[12:38:17.214] <TB2> INFO: 514560 events read in total (10587ms).
[12:38:17.221] <TB2> INFO: Expecting 514560 events.
[12:38:28.882] <TB2> INFO: 514560 events read in total (11092ms).
[12:38:28.892] <TB2> INFO: Expecting 514560 events.
[12:38:40.899] <TB2> INFO: 514560 events read in total (11432ms).
[12:38:40.909] <TB2> INFO: Expecting 514560 events.
[12:38:52.183] <TB2> INFO: 514560 events read in total (10698ms).
[12:38:52.196] <TB2> INFO: Expecting 514560 events.
[12:39:02.942] <TB2> INFO: 514560 events read in total (10172ms).
[12:39:02.957] <TB2> INFO: Expecting 514560 events.
[12:39:15.047] <TB2> INFO: 514560 events read in total (11521ms).
[12:39:15.065] <TB2> INFO: Expecting 514560 events.
[12:39:26.978] <TB2> INFO: 514560 events read in total (11351ms).
[12:39:26.998] <TB2> INFO: Expecting 514560 events.
[12:39:37.562] <TB2> INFO: 514560 events read in total (10008ms).
[12:39:37.584] <TB2> INFO: Expecting 514560 events.
[12:39:49.994] <TB2> INFO: 514560 events read in total (11857ms).
[12:39:50.015] <TB2> INFO: Expecting 514560 events.
[12:40:02.077] <TB2> INFO: 514560 events read in total (11500ms).
[12:40:02.104] <TB2> INFO: Expecting 514560 events.
[12:40:12.822] <TB2> INFO: 514560 events read in total (10168ms).
[12:40:12.845] <TB2> INFO: Expecting 514560 events.
[12:40:24.720] <TB2> INFO: 514560 events read in total (11316ms).
[12:40:24.747] <TB2> INFO: Expecting 514560 events.
[12:40:37.332] <TB2> INFO: 514560 events read in total (12025ms).
[12:40:37.366] <TB2> INFO: Expecting 514560 events.
[12:40:48.707] <TB2> INFO: 514560 events read in total (10799ms).
[12:40:48.738] <TB2> INFO: Expecting 514560 events.
[12:40:59.341] <TB2> INFO: 514560 events read in total (10048ms).
[12:40:59.378] <TB2> INFO: Test took 185674ms.
[12:41:00.367] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:41:00.375] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:41:00.375] <TB2> INFO: run 1 of 1
[12:41:00.679] <TB2> INFO: Expecting 6281600 events.
[12:41:33.627] <TB2> INFO: 740370 events read in total (32232ms).
[12:42:07.216] <TB2> INFO: 1479030 events read in total (65821ms).
[12:42:37.432] <TB2> INFO: 2218680 events read in total (96037ms).
[12:43:11.597] <TB2> INFO: 2958960 events read in total (130202ms).
[12:43:45.793] <TB2> INFO: 3694010 events read in total (164398ms).
[12:44:18.398] <TB2> INFO: 4425020 events read in total (197003ms).
[12:44:50.947] <TB2> INFO: 5154330 events read in total (229552ms).
[12:45:21.908] <TB2> INFO: 5883170 events read in total (260513ms).
[12:45:40.748] <TB2> INFO: 6281600 events read in total (279353ms).
[12:45:40.801] <TB2> INFO: Test took 280426ms.
[12:45:40.955] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:04.515] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.176816 .. 255.000000
[12:46:04.591] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:46:04.599] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:46:04.599] <TB2> INFO: run 1 of 1
[12:46:04.903] <TB2> INFO: Expecting 10649600 events.
[12:46:37.642] <TB2> INFO: 712330 events read in total (32024ms).
[12:47:09.751] <TB2> INFO: 1424600 events read in total (64133ms).
[12:47:42.992] <TB2> INFO: 2136890 events read in total (97374ms).
[12:48:16.503] <TB2> INFO: 2849740 events read in total (130885ms).
[12:48:46.783] <TB2> INFO: 3562340 events read in total (161165ms).
[12:49:19.547] <TB2> INFO: 4275180 events read in total (193929ms).
[12:49:52.338] <TB2> INFO: 4987720 events read in total (226720ms).
[12:50:24.010] <TB2> INFO: 5700040 events read in total (258392ms).
[12:50:55.283] <TB2> INFO: 6411990 events read in total (289665ms).
[12:51:26.613] <TB2> INFO: 7123360 events read in total (320995ms).
[12:51:59.615] <TB2> INFO: 7834990 events read in total (353997ms).
[12:52:34.143] <TB2> INFO: 8545920 events read in total (388525ms).
[12:53:03.650] <TB2> INFO: 9256460 events read in total (418032ms).
[12:53:36.173] <TB2> INFO: 9967370 events read in total (450555ms).
[12:54:08.289] <TB2> INFO: 10649600 events read in total (482671ms).
[12:54:08.392] <TB2> INFO: Test took 483794ms.
[12:54:08.677] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:36.856] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 14.789771 .. 46.525211
[12:54:36.938] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 56 (-1/-1) hits flags = 528 (plus default)
[12:54:36.948] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:54:36.948] <TB2> INFO: run 1 of 1
[12:54:37.287] <TB2> INFO: Expecting 2204800 events.
[12:55:13.790] <TB2> INFO: 935430 events read in total (35788ms).
[12:55:49.228] <TB2> INFO: 1868730 events read in total (71226ms).
[12:56:02.710] <TB2> INFO: 2204800 events read in total (84708ms).
[12:56:02.730] <TB2> INFO: Test took 85782ms.
[12:56:02.768] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:17.313] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 19.593340 .. 43.933124
[12:56:17.389] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 53 (-1/-1) hits flags = 528 (plus default)
[12:56:17.398] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:56:17.398] <TB2> INFO: run 1 of 1
[12:56:17.703] <TB2> INFO: Expecting 1872000 events.
[12:56:53.325] <TB2> INFO: 933450 events read in total (34907ms).
[12:57:30.304] <TB2> INFO: 1865020 events read in total (71886ms).
[12:57:31.072] <TB2> INFO: 1872000 events read in total (72655ms).
[12:57:31.088] <TB2> INFO: Test took 73691ms.
[12:57:31.122] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:45.892] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 3.500000 .. 43.686240
[12:57:45.987] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 53 (-1/-1) hits flags = 528 (plus default)
[12:57:45.996] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:57:45.996] <TB2> INFO: run 1 of 1
[12:57:46.327] <TB2> INFO: Expecting 2121600 events.
[12:58:20.906] <TB2> INFO: 967430 events read in total (33864ms).
[12:58:56.214] <TB2> INFO: 1933950 events read in total (69172ms).
[12:59:03.429] <TB2> INFO: 2121600 events read in total (76387ms).
[12:59:03.445] <TB2> INFO: Test took 77450ms.
[12:59:03.477] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:16.940] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[12:59:16.940] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[12:59:16.948] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[12:59:16.948] <TB2> INFO: run 1 of 1
[12:59:17.250] <TB2> INFO: Expecting 1705600 events.
[12:59:50.741] <TB2> INFO: 878890 events read in total (32775ms).
[13:00:22.750] <TB2> INFO: 1705600 events read in total (64784ms).
[13:00:22.764] <TB2> INFO: Test took 65817ms.
[13:00:22.794] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:37.572] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:00:37.572] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:00:37.572] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:00:37.572] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:00:37.572] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:00:37.572] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:00:37.572] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:00:37.573] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:00:37.573] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:00:37.573] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:00:37.573] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:00:37.573] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:00:37.573] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:00:37.573] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:00:37.573] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:00:37.574] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:00:37.574] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:00:37.580] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:00:37.587] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:00:37.597] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:00:37.603] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:00:37.610] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:00:37.618] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:00:37.625] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:00:37.632] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:00:37.641] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:00:37.650] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:00:37.659] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:00:37.668] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:00:37.676] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:00:37.685] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:00:37.693] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:00:37.700] <TB2> INFO: PixTestTrim::trimTest() done
[13:00:37.700] <TB2> INFO: vtrim: 92 100 81 99 85 83 101 102 93 104 107 97 90 113 81 94
[13:00:37.700] <TB2> INFO: vthrcomp: 98 85 76 97 96 93 101 93 80 87 87 84 80 96 97 102
[13:00:37.700] <TB2> INFO: vcal mean: 34.97 34.99 34.97 34.94 34.97 34.95 34.96 35.00 34.96 34.93 34.93 35.01 34.97 34.91 34.93 34.96
[13:00:37.700] <TB2> INFO: vcal RMS: 1.02 0.87 0.80 1.09 0.91 0.91 0.85 0.86 0.84 0.88 0.88 0.86 0.82 0.90 0.86 0.88
[13:00:37.700] <TB2> INFO: bits mean: 9.81 9.98 8.64 9.82 9.17 9.10 9.60 9.47 9.50 9.49 9.61 9.72 9.67 9.63 9.64 8.85
[13:00:37.700] <TB2> INFO: bits RMS: 2.55 2.47 2.38 2.61 2.85 2.83 2.82 2.62 2.60 2.69 2.66 2.52 2.59 2.67 2.80 2.85
[13:00:37.707] <TB2> INFO: ----------------------------------------------------------------------
[13:00:37.707] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:00:37.707] <TB2> INFO: ----------------------------------------------------------------------
[13:00:37.710] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:00:37.718] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[13:00:37.718] <TB2> INFO: run 1 of 1
[13:00:38.029] <TB2> INFO: Expecting 4160000 events.
[13:01:17.986] <TB2> INFO: 925545 events read in total (39242ms).
[13:01:57.645] <TB2> INFO: 1844720 events read in total (78902ms).
[13:02:37.491] <TB2> INFO: 2756300 events read in total (118747ms).
[13:03:19.468] <TB2> INFO: 3664850 events read in total (160724ms).
[13:03:40.999] <TB2> INFO: 4160000 events read in total (182255ms).
[13:03:41.044] <TB2> INFO: Test took 183325ms.
[13:03:41.158] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:10.605] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 174 (-1/-1) hits flags = 528 (plus default)
[13:04:10.614] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[13:04:10.618] <TB2> INFO: run 1 of 1
[13:04:10.920] <TB2> INFO: Expecting 3640000 events.
[13:04:51.257] <TB2> INFO: 942630 events read in total (39622ms).
[13:05:31.634] <TB2> INFO: 1877690 events read in total (79999ms).
[13:06:13.195] <TB2> INFO: 2802510 events read in total (121560ms).
[13:06:51.812] <TB2> INFO: 3640000 events read in total (160177ms).
[13:06:51.852] <TB2> INFO: Test took 161234ms.
[13:06:51.939] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:18.915] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[13:07:18.924] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[13:07:18.924] <TB2> INFO: run 1 of 1
[13:07:19.227] <TB2> INFO: Expecting 3390400 events.
[13:08:01.583] <TB2> INFO: 971605 events read in total (41641ms).
[13:08:42.889] <TB2> INFO: 1933280 events read in total (82948ms).
[13:09:22.104] <TB2> INFO: 2884300 events read in total (122162ms).
[13:09:45.658] <TB2> INFO: 3390400 events read in total (145716ms).
[13:09:45.694] <TB2> INFO: Test took 146770ms.
[13:09:45.769] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:11.191] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:10:11.200] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[13:10:11.200] <TB2> INFO: run 1 of 1
[13:10:11.504] <TB2> INFO: Expecting 3348800 events.
[13:10:52.489] <TB2> INFO: 976390 events read in total (40269ms).
[13:11:35.778] <TB2> INFO: 1942180 events read in total (83558ms).
[13:12:15.000] <TB2> INFO: 2897280 events read in total (122781ms).
[13:12:35.778] <TB2> INFO: 3348800 events read in total (143558ms).
[13:12:35.815] <TB2> INFO: Test took 144615ms.
[13:12:35.883] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:03.050] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[13:13:03.060] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[13:13:03.060] <TB2> INFO: run 1 of 1
[13:13:03.403] <TB2> INFO: Expecting 3369600 events.
[13:13:45.369] <TB2> INFO: 972760 events read in total (41251ms).
[13:14:26.890] <TB2> INFO: 1935190 events read in total (82772ms).
[13:15:09.375] <TB2> INFO: 2887525 events read in total (125257ms).
[13:15:29.853] <TB2> INFO: 3369600 events read in total (145735ms).
[13:15:29.890] <TB2> INFO: Test took 146830ms.
[13:15:29.965] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:53.682] <TB2> INFO: PixTestTrim::trimBitTest() done
[13:15:53.684] <TB2> INFO: PixTestTrim::doTest() done, duration: 2819 seconds
[13:15:53.684] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:53.684] <TB2> INFO: Decoding statistics:
[13:15:53.684] <TB2> INFO: General information:
[13:15:53.684] <TB2> INFO: 16bit words read: 0
[13:15:53.684] <TB2> INFO: valid events total: 0
[13:15:53.684] <TB2> INFO: empty events: 0
[13:15:53.684] <TB2> INFO: valid events with pixels: 0
[13:15:53.684] <TB2> INFO: valid pixel hits: 0
[13:15:53.684] <TB2> INFO: Event errors: 0
[13:15:53.684] <TB2> INFO: start marker: 0
[13:15:53.684] <TB2> INFO: stop marker: 0
[13:15:53.684] <TB2> INFO: overflow: 0
[13:15:53.684] <TB2> INFO: invalid 5bit words: 0
[13:15:53.684] <TB2> INFO: invalid XOR eye diagram: 0
[13:15:53.684] <TB2> INFO: TBM errors: 0
[13:15:53.684] <TB2> INFO: flawed TBM headers: 0
[13:15:53.684] <TB2> INFO: flawed TBM trailers: 0
[13:15:53.684] <TB2> INFO: event ID mismatches: 0
[13:15:53.684] <TB2> INFO: ROC errors: 0
[13:15:53.684] <TB2> INFO: missing ROC header(s): 0
[13:15:53.684] <TB2> INFO: misplaced readback start: 0
[13:15:53.684] <TB2> INFO: Pixel decoding errors: 0
[13:15:53.684] <TB2> INFO: pixel data incomplete: 0
[13:15:53.684] <TB2> INFO: pixel address: 0
[13:15:53.684] <TB2> INFO: pulse height fill bit: 0
[13:15:53.684] <TB2> INFO: buffer corruption: 0
[13:15:54.412] <TB2> INFO: ######################################################################
[13:15:54.412] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:15:54.412] <TB2> INFO: ######################################################################
[13:15:54.813] <TB2> INFO: Expecting 41600 events.
[13:15:59.192] <TB2> INFO: 41600 events read in total (3663ms).
[13:15:59.192] <TB2> INFO: Test took 4779ms.
[13:15:59.198] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:59.772] <TB2> INFO: Expecting 41600 events.
[13:16:04.084] <TB2> INFO: 41600 events read in total (3597ms).
[13:16:04.084] <TB2> INFO: Test took 4625ms.
[13:16:04.091] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:04.439] <TB2> INFO: Expecting 41600 events.
[13:16:08.706] <TB2> INFO: 41600 events read in total (3552ms).
[13:16:08.707] <TB2> INFO: Test took 4592ms.
[13:16:08.713] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:09.063] <TB2> INFO: Expecting 2560 events.
[13:16:10.153] <TB2> INFO: 2560 events read in total (375ms).
[13:16:10.153] <TB2> INFO: Test took 1434ms.
[13:16:10.567] <TB2> INFO: Expecting 2560 events.
[13:16:11.634] <TB2> INFO: 2560 events read in total (351ms).
[13:16:11.635] <TB2> INFO: Test took 1481ms.
[13:16:12.048] <TB2> INFO: Expecting 2560 events.
[13:16:13.112] <TB2> INFO: 2560 events read in total (348ms).
[13:16:13.112] <TB2> INFO: Test took 1477ms.
[13:16:13.526] <TB2> INFO: Expecting 2560 events.
[13:16:14.587] <TB2> INFO: 2560 events read in total (346ms).
[13:16:14.587] <TB2> INFO: Test took 1475ms.
[13:16:15.001] <TB2> INFO: Expecting 2560 events.
[13:16:16.063] <TB2> INFO: 2560 events read in total (346ms).
[13:16:16.064] <TB2> INFO: Test took 1476ms.
[13:16:16.478] <TB2> INFO: Expecting 2560 events.
[13:16:17.539] <TB2> INFO: 2560 events read in total (346ms).
[13:16:17.539] <TB2> INFO: Test took 1475ms.
[13:16:17.953] <TB2> INFO: Expecting 2560 events.
[13:16:19.023] <TB2> INFO: 2560 events read in total (354ms).
[13:16:19.023] <TB2> INFO: Test took 1484ms.
[13:16:19.437] <TB2> INFO: Expecting 2560 events.
[13:16:20.503] <TB2> INFO: 2560 events read in total (351ms).
[13:16:20.503] <TB2> INFO: Test took 1480ms.
[13:16:20.917] <TB2> INFO: Expecting 2560 events.
[13:16:21.982] <TB2> INFO: 2560 events read in total (349ms).
[13:16:21.983] <TB2> INFO: Test took 1479ms.
[13:16:22.396] <TB2> INFO: Expecting 2560 events.
[13:16:23.458] <TB2> INFO: 2560 events read in total (347ms).
[13:16:23.459] <TB2> INFO: Test took 1476ms.
[13:16:23.873] <TB2> INFO: Expecting 2560 events.
[13:16:24.939] <TB2> INFO: 2560 events read in total (350ms).
[13:16:24.939] <TB2> INFO: Test took 1480ms.
[13:16:25.353] <TB2> INFO: Expecting 2560 events.
[13:16:26.414] <TB2> INFO: 2560 events read in total (345ms).
[13:16:26.414] <TB2> INFO: Test took 1475ms.
[13:16:26.829] <TB2> INFO: Expecting 2560 events.
[13:16:27.891] <TB2> INFO: 2560 events read in total (348ms).
[13:16:27.892] <TB2> INFO: Test took 1477ms.
[13:16:28.306] <TB2> INFO: Expecting 2560 events.
[13:16:29.376] <TB2> INFO: 2560 events read in total (355ms).
[13:16:29.376] <TB2> INFO: Test took 1484ms.
[13:16:29.789] <TB2> INFO: Expecting 2560 events.
[13:16:30.868] <TB2> INFO: 2560 events read in total (363ms).
[13:16:30.869] <TB2> INFO: Test took 1492ms.
[13:16:31.282] <TB2> INFO: Expecting 2560 events.
[13:16:32.347] <TB2> INFO: 2560 events read in total (350ms).
[13:16:32.347] <TB2> INFO: Test took 1478ms.
[13:16:32.351] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:16:32.762] <TB2> INFO: Expecting 655360 events.
[13:16:47.411] <TB2> INFO: 655360 events read in total (13934ms).
[13:16:47.419] <TB2> INFO: Expecting 655360 events.
[13:17:02.023] <TB2> INFO: 655360 events read in total (14063ms).
[13:17:02.035] <TB2> INFO: Expecting 655360 events.
[13:17:16.303] <TB2> INFO: 655360 events read in total (13740ms).
[13:17:16.322] <TB2> INFO: Expecting 655360 events.
[13:17:30.668] <TB2> INFO: 655360 events read in total (13818ms).
[13:17:30.691] <TB2> INFO: Expecting 655360 events.
[13:17:45.329] <TB2> INFO: 655360 events read in total (14110ms).
[13:17:45.353] <TB2> INFO: Expecting 655360 events.
[13:17:59.937] <TB2> INFO: 655360 events read in total (14056ms).
[13:17:59.963] <TB2> INFO: Expecting 655360 events.
[13:18:14.598] <TB2> INFO: 655360 events read in total (14108ms).
[13:18:14.627] <TB2> INFO: Expecting 655360 events.
[13:18:29.275] <TB2> INFO: 655360 events read in total (14121ms).
[13:18:29.310] <TB2> INFO: Expecting 655360 events.
[13:18:43.607] <TB2> INFO: 655360 events read in total (13770ms).
[13:18:43.643] <TB2> INFO: Expecting 655360 events.
[13:18:57.933] <TB2> INFO: 655360 events read in total (13763ms).
[13:18:57.971] <TB2> INFO: Expecting 655360 events.
[13:19:12.230] <TB2> INFO: 655360 events read in total (13731ms).
[13:19:12.279] <TB2> INFO: Expecting 655360 events.
[13:19:26.949] <TB2> INFO: 655360 events read in total (14142ms).
[13:19:27.002] <TB2> INFO: Expecting 655360 events.
[13:19:41.564] <TB2> INFO: 655360 events read in total (14035ms).
[13:19:41.612] <TB2> INFO: Expecting 655360 events.
[13:19:57.619] <TB2> INFO: 655360 events read in total (15480ms).
[13:19:57.674] <TB2> INFO: Expecting 655360 events.
[13:20:12.987] <TB2> INFO: 655360 events read in total (14785ms).
[13:20:13.042] <TB2> INFO: Expecting 655360 events.
[13:20:28.192] <TB2> INFO: 655360 events read in total (14622ms).
[13:20:28.254] <TB2> INFO: Test took 235904ms.
[13:20:28.336] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:20:28.646] <TB2> INFO: Expecting 655360 events.
[13:20:43.730] <TB2> INFO: 655360 events read in total (14369ms).
[13:20:43.739] <TB2> INFO: Expecting 655360 events.
[13:20:59.107] <TB2> INFO: 655360 events read in total (14841ms).
[13:20:59.119] <TB2> INFO: Expecting 655360 events.
[13:21:14.332] <TB2> INFO: 655360 events read in total (14686ms).
[13:21:14.347] <TB2> INFO: Expecting 655360 events.
[13:21:30.499] <TB2> INFO: 655360 events read in total (15624ms).
[13:21:30.518] <TB2> INFO: Expecting 655360 events.
[13:21:46.794] <TB2> INFO: 655360 events read in total (15748ms).
[13:21:46.820] <TB2> INFO: Expecting 655360 events.
[13:22:03.178] <TB2> INFO: 655360 events read in total (15830ms).
[13:22:03.204] <TB2> INFO: Expecting 655360 events.
[13:22:19.101] <TB2> INFO: 655360 events read in total (15370ms).
[13:22:19.130] <TB2> INFO: Expecting 655360 events.
[13:22:34.287] <TB2> INFO: 655360 events read in total (14629ms).
[13:22:34.336] <TB2> INFO: Expecting 655360 events.
[13:22:48.767] <TB2> INFO: 655360 events read in total (13904ms).
[13:22:48.803] <TB2> INFO: Expecting 655360 events.
[13:23:03.991] <TB2> INFO: 655360 events read in total (14661ms).
[13:23:04.031] <TB2> INFO: Expecting 655360 events.
[13:23:19.166] <TB2> INFO: 655360 events read in total (14607ms).
[13:23:19.215] <TB2> INFO: Expecting 655360 events.
[13:23:34.807] <TB2> INFO: 655360 events read in total (15065ms).
[13:23:34.853] <TB2> INFO: Expecting 655360 events.
[13:23:50.780] <TB2> INFO: 655360 events read in total (15400ms).
[13:23:50.854] <TB2> INFO: Expecting 655360 events.
[13:24:06.114] <TB2> INFO: 655360 events read in total (14732ms).
[13:24:06.168] <TB2> INFO: Expecting 655360 events.
[13:24:21.954] <TB2> INFO: 655360 events read in total (15260ms).
[13:24:22.019] <TB2> INFO: Expecting 655360 events.
[13:24:38.105] <TB2> INFO: 655360 events read in total (15558ms).
[13:24:38.165] <TB2> INFO: Test took 249829ms.
[13:24:38.348] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.355] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.362] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.369] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.376] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.383] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.390] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.397] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.404] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[13:24:38.411] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[13:24:38.418] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[13:24:38.425] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.432] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.439] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[13:24:38.446] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[13:24:38.453] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.460] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.467] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.474] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.480] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.487] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[13:24:38.523] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:24:38.523] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:24:38.524] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:24:38.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:24:38.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:24:38.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:24:38.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:24:38.525] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:24:38.830] <TB2> INFO: Expecting 41600 events.
[13:24:42.772] <TB2> INFO: 41600 events read in total (3227ms).
[13:24:42.773] <TB2> INFO: Test took 4245ms.
[13:24:43.330] <TB2> INFO: Expecting 41600 events.
[13:24:47.457] <TB2> INFO: 41600 events read in total (3412ms).
[13:24:47.458] <TB2> INFO: Test took 4447ms.
[13:24:48.011] <TB2> INFO: Expecting 41600 events.
[13:24:52.235] <TB2> INFO: 41600 events read in total (3508ms).
[13:24:52.235] <TB2> INFO: Test took 4540ms.
[13:24:52.454] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:52.586] <TB2> INFO: Expecting 2560 events.
[13:24:53.664] <TB2> INFO: 2560 events read in total (362ms).
[13:24:53.665] <TB2> INFO: Test took 1211ms.
[13:24:53.667] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:54.079] <TB2> INFO: Expecting 2560 events.
[13:24:55.147] <TB2> INFO: 2560 events read in total (352ms).
[13:24:55.148] <TB2> INFO: Test took 1481ms.
[13:24:55.150] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:55.562] <TB2> INFO: Expecting 2560 events.
[13:24:56.631] <TB2> INFO: 2560 events read in total (353ms).
[13:24:56.632] <TB2> INFO: Test took 1482ms.
[13:24:56.634] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:57.048] <TB2> INFO: Expecting 2560 events.
[13:24:58.117] <TB2> INFO: 2560 events read in total (353ms).
[13:24:58.118] <TB2> INFO: Test took 1484ms.
[13:24:58.120] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:58.532] <TB2> INFO: Expecting 2560 events.
[13:24:59.608] <TB2> INFO: 2560 events read in total (360ms).
[13:24:59.609] <TB2> INFO: Test took 1489ms.
[13:24:59.611] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:00.022] <TB2> INFO: Expecting 2560 events.
[13:25:01.092] <TB2> INFO: 2560 events read in total (354ms).
[13:25:01.094] <TB2> INFO: Test took 1483ms.
[13:25:01.097] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:01.506] <TB2> INFO: Expecting 2560 events.
[13:25:02.604] <TB2> INFO: 2560 events read in total (382ms).
[13:25:02.604] <TB2> INFO: Test took 1507ms.
[13:25:02.607] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:03.019] <TB2> INFO: Expecting 2560 events.
[13:25:04.088] <TB2> INFO: 2560 events read in total (353ms).
[13:25:04.089] <TB2> INFO: Test took 1482ms.
[13:25:04.092] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:04.503] <TB2> INFO: Expecting 2560 events.
[13:25:05.570] <TB2> INFO: 2560 events read in total (351ms).
[13:25:05.571] <TB2> INFO: Test took 1480ms.
[13:25:05.574] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:05.986] <TB2> INFO: Expecting 2560 events.
[13:25:07.054] <TB2> INFO: 2560 events read in total (353ms).
[13:25:07.054] <TB2> INFO: Test took 1481ms.
[13:25:07.057] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:07.468] <TB2> INFO: Expecting 2560 events.
[13:25:08.532] <TB2> INFO: 2560 events read in total (348ms).
[13:25:08.533] <TB2> INFO: Test took 1476ms.
[13:25:08.534] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:08.948] <TB2> INFO: Expecting 2560 events.
[13:25:10.014] <TB2> INFO: 2560 events read in total (350ms).
[13:25:10.014] <TB2> INFO: Test took 1480ms.
[13:25:10.017] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:10.428] <TB2> INFO: Expecting 2560 events.
[13:25:11.497] <TB2> INFO: 2560 events read in total (353ms).
[13:25:11.497] <TB2> INFO: Test took 1480ms.
[13:25:11.500] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:11.913] <TB2> INFO: Expecting 2560 events.
[13:25:12.991] <TB2> INFO: 2560 events read in total (363ms).
[13:25:12.991] <TB2> INFO: Test took 1491ms.
[13:25:12.995] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:13.406] <TB2> INFO: Expecting 2560 events.
[13:25:14.489] <TB2> INFO: 2560 events read in total (367ms).
[13:25:14.489] <TB2> INFO: Test took 1494ms.
[13:25:14.492] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:14.904] <TB2> INFO: Expecting 2560 events.
[13:25:15.986] <TB2> INFO: 2560 events read in total (366ms).
[13:25:15.987] <TB2> INFO: Test took 1495ms.
[13:25:15.990] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:16.401] <TB2> INFO: Expecting 2560 events.
[13:25:17.501] <TB2> INFO: 2560 events read in total (384ms).
[13:25:17.501] <TB2> INFO: Test took 1511ms.
[13:25:17.504] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:17.915] <TB2> INFO: Expecting 2560 events.
[13:25:19.019] <TB2> INFO: 2560 events read in total (389ms).
[13:25:19.019] <TB2> INFO: Test took 1516ms.
[13:25:19.021] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:19.434] <TB2> INFO: Expecting 2560 events.
[13:25:20.532] <TB2> INFO: 2560 events read in total (382ms).
[13:25:20.533] <TB2> INFO: Test took 1512ms.
[13:25:20.535] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:20.948] <TB2> INFO: Expecting 2560 events.
[13:25:22.009] <TB2> INFO: 2560 events read in total (346ms).
[13:25:22.010] <TB2> INFO: Test took 1475ms.
[13:25:22.012] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:22.425] <TB2> INFO: Expecting 2560 events.
[13:25:23.492] <TB2> INFO: 2560 events read in total (352ms).
[13:25:23.492] <TB2> INFO: Test took 1480ms.
[13:25:23.495] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:23.907] <TB2> INFO: Expecting 2560 events.
[13:25:24.974] <TB2> INFO: 2560 events read in total (351ms).
[13:25:24.974] <TB2> INFO: Test took 1480ms.
[13:25:24.977] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:25.389] <TB2> INFO: Expecting 2560 events.
[13:25:26.456] <TB2> INFO: 2560 events read in total (351ms).
[13:25:26.457] <TB2> INFO: Test took 1481ms.
[13:25:26.459] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:26.871] <TB2> INFO: Expecting 2560 events.
[13:25:27.969] <TB2> INFO: 2560 events read in total (382ms).
[13:25:27.969] <TB2> INFO: Test took 1510ms.
[13:25:27.972] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:28.384] <TB2> INFO: Expecting 2560 events.
[13:25:29.453] <TB2> INFO: 2560 events read in total (354ms).
[13:25:29.454] <TB2> INFO: Test took 1482ms.
[13:25:29.455] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:29.868] <TB2> INFO: Expecting 2560 events.
[13:25:30.966] <TB2> INFO: 2560 events read in total (382ms).
[13:25:30.966] <TB2> INFO: Test took 1511ms.
[13:25:30.968] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:31.381] <TB2> INFO: Expecting 2560 events.
[13:25:32.471] <TB2> INFO: 2560 events read in total (374ms).
[13:25:32.472] <TB2> INFO: Test took 1504ms.
[13:25:32.473] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:32.887] <TB2> INFO: Expecting 2560 events.
[13:25:33.956] <TB2> INFO: 2560 events read in total (353ms).
[13:25:33.956] <TB2> INFO: Test took 1483ms.
[13:25:33.959] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:34.372] <TB2> INFO: Expecting 2560 events.
[13:25:35.452] <TB2> INFO: 2560 events read in total (364ms).
[13:25:35.453] <TB2> INFO: Test took 1494ms.
[13:25:35.454] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:35.868] <TB2> INFO: Expecting 2560 events.
[13:25:36.935] <TB2> INFO: 2560 events read in total (351ms).
[13:25:36.935] <TB2> INFO: Test took 1481ms.
[13:25:36.946] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:37.350] <TB2> INFO: Expecting 2560 events.
[13:25:38.414] <TB2> INFO: 2560 events read in total (348ms).
[13:25:38.414] <TB2> INFO: Test took 1468ms.
[13:25:38.417] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:38.829] <TB2> INFO: Expecting 2560 events.
[13:25:39.897] <TB2> INFO: 2560 events read in total (352ms).
[13:25:39.897] <TB2> INFO: Test took 1480ms.
[13:25:40.516] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 586 seconds
[13:25:40.516] <TB2> INFO: PH scale (per ROC): 84 84 81 82 77 76 79 80 86 73 80 85 87 77 80 75
[13:25:40.516] <TB2> INFO: PH offset (per ROC): 157 154 163 167 172 171 174 162 155 174 165 153 151 164 155 167
[13:25:40.520] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:40.520] <TB2> INFO: Decoding statistics:
[13:25:40.520] <TB2> INFO: General information:
[13:25:40.520] <TB2> INFO: 16bit words read: 91920
[13:25:40.520] <TB2> INFO: valid events total: 10240
[13:25:40.520] <TB2> INFO: empty events: 7740
[13:25:40.520] <TB2> INFO: valid events with pixels: 2500
[13:25:40.520] <TB2> INFO: valid pixel hits: 2500
[13:25:40.520] <TB2> INFO: Event errors: 0
[13:25:40.520] <TB2> INFO: start marker: 0
[13:25:40.520] <TB2> INFO: stop marker: 0
[13:25:40.520] <TB2> INFO: overflow: 0
[13:25:40.520] <TB2> INFO: invalid 5bit words: 0
[13:25:40.520] <TB2> INFO: invalid XOR eye diagram: 0
[13:25:40.520] <TB2> INFO: TBM errors: 0
[13:25:40.520] <TB2> INFO: flawed TBM headers: 0
[13:25:40.520] <TB2> INFO: flawed TBM trailers: 0
[13:25:40.520] <TB2> INFO: event ID mismatches: 0
[13:25:40.520] <TB2> INFO: ROC errors: 0
[13:25:40.520] <TB2> INFO: missing ROC header(s): 0
[13:25:40.520] <TB2> INFO: misplaced readback start: 0
[13:25:40.520] <TB2> INFO: Pixel decoding errors: 0
[13:25:40.520] <TB2> INFO: pixel data incomplete: 0
[13:25:40.520] <TB2> INFO: pixel address: 0
[13:25:40.520] <TB2> INFO: pulse height fill bit: 0
[13:25:40.520] <TB2> INFO: buffer corruption: 0
[13:25:40.686] <TB2> INFO: ######################################################################
[13:25:40.686] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:25:40.686] <TB2> INFO: ######################################################################
[13:25:40.695] <TB2> INFO: scanning low vcal = 10
[13:25:41.024] <TB2> INFO: Expecting 41600 events.
[13:25:44.879] <TB2> INFO: 41600 events read in total (3140ms).
[13:25:44.879] <TB2> INFO: Test took 4184ms.
[13:25:44.881] <TB2> INFO: scanning low vcal = 20
[13:25:45.293] <TB2> INFO: Expecting 41600 events.
[13:25:49.183] <TB2> INFO: 41600 events read in total (3174ms).
[13:25:49.183] <TB2> INFO: Test took 4302ms.
[13:25:49.185] <TB2> INFO: scanning low vcal = 30
[13:25:49.597] <TB2> INFO: Expecting 41600 events.
[13:25:53.432] <TB2> INFO: 41600 events read in total (3119ms).
[13:25:53.433] <TB2> INFO: Test took 4248ms.
[13:25:53.434] <TB2> INFO: scanning low vcal = 40
[13:25:53.837] <TB2> INFO: Expecting 41600 events.
[13:25:58.303] <TB2> INFO: 41600 events read in total (3750ms).
[13:25:58.303] <TB2> INFO: Test took 4868ms.
[13:25:58.306] <TB2> INFO: scanning low vcal = 50
[13:25:58.652] <TB2> INFO: Expecting 41600 events.
[13:26:03.263] <TB2> INFO: 41600 events read in total (3895ms).
[13:26:03.264] <TB2> INFO: Test took 4958ms.
[13:26:03.267] <TB2> INFO: scanning low vcal = 60
[13:26:03.616] <TB2> INFO: Expecting 41600 events.
[13:26:08.011] <TB2> INFO: 41600 events read in total (3679ms).
[13:26:08.011] <TB2> INFO: Test took 4744ms.
[13:26:08.014] <TB2> INFO: scanning low vcal = 70
[13:26:08.363] <TB2> INFO: Expecting 41600 events.
[13:26:12.780] <TB2> INFO: 41600 events read in total (3701ms).
[13:26:12.780] <TB2> INFO: Test took 4766ms.
[13:26:12.783] <TB2> INFO: scanning low vcal = 80
[13:26:13.135] <TB2> INFO: Expecting 41600 events.
[13:26:17.567] <TB2> INFO: 41600 events read in total (3716ms).
[13:26:17.567] <TB2> INFO: Test took 4785ms.
[13:26:17.570] <TB2> INFO: scanning low vcal = 90
[13:26:17.922] <TB2> INFO: Expecting 41600 events.
[13:26:22.236] <TB2> INFO: 41600 events read in total (3599ms).
[13:26:22.236] <TB2> INFO: Test took 4666ms.
[13:26:22.239] <TB2> INFO: scanning low vcal = 100
[13:26:22.591] <TB2> INFO: Expecting 41600 events.
[13:26:27.287] <TB2> INFO: 41600 events read in total (3980ms).
[13:26:27.287] <TB2> INFO: Test took 5048ms.
[13:26:27.290] <TB2> INFO: scanning low vcal = 110
[13:26:27.641] <TB2> INFO: Expecting 41600 events.
[13:26:32.171] <TB2> INFO: 41600 events read in total (3814ms).
[13:26:32.172] <TB2> INFO: Test took 4882ms.
[13:26:32.174] <TB2> INFO: scanning low vcal = 120
[13:26:32.528] <TB2> INFO: Expecting 41600 events.
[13:26:36.872] <TB2> INFO: 41600 events read in total (3628ms).
[13:26:36.873] <TB2> INFO: Test took 4699ms.
[13:26:36.875] <TB2> INFO: scanning low vcal = 130
[13:26:37.224] <TB2> INFO: Expecting 41600 events.
[13:26:41.753] <TB2> INFO: 41600 events read in total (3814ms).
[13:26:41.753] <TB2> INFO: Test took 4878ms.
[13:26:41.756] <TB2> INFO: scanning low vcal = 140
[13:26:42.110] <TB2> INFO: Expecting 41600 events.
[13:26:46.663] <TB2> INFO: 41600 events read in total (3838ms).
[13:26:46.664] <TB2> INFO: Test took 4908ms.
[13:26:46.666] <TB2> INFO: scanning low vcal = 150
[13:26:46.998] <TB2> INFO: Expecting 41600 events.
[13:26:51.402] <TB2> INFO: 41600 events read in total (3689ms).
[13:26:51.402] <TB2> INFO: Test took 4736ms.
[13:26:51.404] <TB2> INFO: scanning low vcal = 160
[13:26:51.755] <TB2> INFO: Expecting 41600 events.
[13:26:56.287] <TB2> INFO: 41600 events read in total (3817ms).
[13:26:56.287] <TB2> INFO: Test took 4882ms.
[13:26:56.290] <TB2> INFO: scanning low vcal = 170
[13:26:56.638] <TB2> INFO: Expecting 41600 events.
[13:27:01.382] <TB2> INFO: 41600 events read in total (4028ms).
[13:27:01.382] <TB2> INFO: Test took 5092ms.
[13:27:01.386] <TB2> INFO: scanning low vcal = 180
[13:27:01.730] <TB2> INFO: Expecting 41600 events.
[13:27:06.156] <TB2> INFO: 41600 events read in total (3710ms).
[13:27:06.157] <TB2> INFO: Test took 4771ms.
[13:27:06.160] <TB2> INFO: scanning low vcal = 190
[13:27:06.491] <TB2> INFO: Expecting 41600 events.
[13:27:11.082] <TB2> INFO: 41600 events read in total (3876ms).
[13:27:11.082] <TB2> INFO: Test took 4922ms.
[13:27:11.085] <TB2> INFO: scanning low vcal = 200
[13:27:11.438] <TB2> INFO: Expecting 41600 events.
[13:27:15.873] <TB2> INFO: 41600 events read in total (3720ms).
[13:27:15.874] <TB2> INFO: Test took 4789ms.
[13:27:15.876] <TB2> INFO: scanning low vcal = 210
[13:27:16.230] <TB2> INFO: Expecting 41600 events.
[13:27:20.481] <TB2> INFO: 41600 events read in total (3536ms).
[13:27:20.481] <TB2> INFO: Test took 4605ms.
[13:27:20.484] <TB2> INFO: scanning low vcal = 220
[13:27:20.831] <TB2> INFO: Expecting 41600 events.
[13:27:25.044] <TB2> INFO: 41600 events read in total (3497ms).
[13:27:25.044] <TB2> INFO: Test took 4560ms.
[13:27:25.047] <TB2> INFO: scanning low vcal = 230
[13:27:25.403] <TB2> INFO: Expecting 41600 events.
[13:27:29.657] <TB2> INFO: 41600 events read in total (3539ms).
[13:27:29.657] <TB2> INFO: Test took 4610ms.
[13:27:29.660] <TB2> INFO: scanning low vcal = 240
[13:27:30.016] <TB2> INFO: Expecting 41600 events.
[13:27:34.288] <TB2> INFO: 41600 events read in total (3556ms).
[13:27:34.289] <TB2> INFO: Test took 4629ms.
[13:27:34.292] <TB2> INFO: scanning low vcal = 250
[13:27:34.646] <TB2> INFO: Expecting 41600 events.
[13:27:38.901] <TB2> INFO: 41600 events read in total (3539ms).
[13:27:38.902] <TB2> INFO: Test took 4610ms.
[13:27:38.906] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[13:27:39.260] <TB2> INFO: Expecting 41600 events.
[13:27:43.529] <TB2> INFO: 41600 events read in total (3553ms).
[13:27:43.529] <TB2> INFO: Test took 4623ms.
[13:27:43.532] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[13:27:43.868] <TB2> INFO: Expecting 41600 events.
[13:27:48.124] <TB2> INFO: 41600 events read in total (3541ms).
[13:27:48.124] <TB2> INFO: Test took 4592ms.
[13:27:48.126] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[13:27:48.478] <TB2> INFO: Expecting 41600 events.
[13:27:52.728] <TB2> INFO: 41600 events read in total (3534ms).
[13:27:52.728] <TB2> INFO: Test took 4602ms.
[13:27:52.731] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[13:27:53.087] <TB2> INFO: Expecting 41600 events.
[13:27:57.373] <TB2> INFO: 41600 events read in total (3570ms).
[13:27:57.374] <TB2> INFO: Test took 4643ms.
[13:27:57.376] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:27:57.730] <TB2> INFO: Expecting 41600 events.
[13:28:02.335] <TB2> INFO: 41600 events read in total (3890ms).
[13:28:02.878] <TB2> INFO: Test took 5502ms.
[13:28:11.363] <TB2> INFO: PixTestGainPedestal::measure() done
[13:28:57.623] <TB2> INFO: PixTestGainPedestal::fit() done
[13:28:57.632] <TB2> INFO: non-linearity mean: 0.960 0.958 0.955 0.957 0.959 0.950 0.961 0.955 0.955 0.950 0.954 0.950 0.962 0.953 0.960 0.961
[13:28:57.633] <TB2> INFO: non-linearity RMS: 0.005 0.005 0.005 0.006 0.007 0.006 0.005 0.005 0.005 0.005 0.006 0.005 0.005 0.006 0.005 0.006
[13:28:57.772] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:28:57.819] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:28:57.839] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:28:57.859] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:28:57.878] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:28:57.898] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:28:57.937] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:28:57.956] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:28:57.976] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:28:57.995] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:28:58.015] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:28:58.034] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:28:58.054] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:28:58.073] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:28:58.092] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:28:58.112] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:28:58.144] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 197 seconds
[13:28:58.144] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:58.144] <TB2> INFO: Decoding statistics:
[13:28:58.144] <TB2> INFO: General information:
[13:28:58.144] <TB2> INFO: 16bit words read: 2662400
[13:28:58.144] <TB2> INFO: valid events total: 166400
[13:28:58.144] <TB2> INFO: empty events: 0
[13:28:58.144] <TB2> INFO: valid events with pixels: 166400
[13:28:58.145] <TB2> INFO: valid pixel hits: 665570
[13:28:58.145] <TB2> INFO: Event errors: 0
[13:28:58.145] <TB2> INFO: start marker: 0
[13:28:58.145] <TB2> INFO: stop marker: 0
[13:28:58.145] <TB2> INFO: overflow: 0
[13:28:58.145] <TB2> INFO: invalid 5bit words: 0
[13:28:58.145] <TB2> INFO: invalid XOR eye diagram: 0
[13:28:58.145] <TB2> INFO: TBM errors: 0
[13:28:58.145] <TB2> INFO: flawed TBM headers: 0
[13:28:58.145] <TB2> INFO: flawed TBM trailers: 0
[13:28:58.145] <TB2> INFO: event ID mismatches: 0
[13:28:58.145] <TB2> INFO: ROC errors: 0
[13:28:58.145] <TB2> INFO: missing ROC header(s): 0
[13:28:58.145] <TB2> INFO: misplaced readback start: 0
[13:28:58.145] <TB2> INFO: Pixel decoding errors: 0
[13:28:58.145] <TB2> INFO: pixel data incomplete: 0
[13:28:58.145] <TB2> INFO: pixel address: 0
[13:28:58.145] <TB2> INFO: pulse height fill bit: 0
[13:28:58.145] <TB2> INFO: buffer corruption: 0
[13:28:58.322] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C15.dat
[13:28:58.374] <TB2> INFO: ######################################################################
[13:28:58.374] <TB2> INFO: PixTestReadback::doTest()
[13:28:58.374] <TB2> INFO: ######################################################################
[13:28:58.375] <TB2> INFO: PixTestReadback::RES sent once
[13:29:12.204] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C0.dat
[13:29:12.204] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C1.dat
[13:29:12.204] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C2.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C3.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C4.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C5.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C6.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C7.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C8.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C9.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C10.dat
[13:29:12.205] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C11.dat
[13:29:12.206] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C12.dat
[13:29:12.206] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C13.dat
[13:29:12.206] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C14.dat
[13:29:12.206] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C15.dat
[13:29:12.236] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:29:12.237] <TB2> INFO: PixTestReadback::RES sent once
[13:29:23.430] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C0.dat
[13:29:23.430] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C1.dat
[13:29:23.430] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C2.dat
[13:29:23.430] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C3.dat
[13:29:23.430] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C4.dat
[13:29:23.430] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C5.dat
[13:29:23.430] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C6.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C7.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C8.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C9.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C10.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C11.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C12.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C13.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C14.dat
[13:29:23.431] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C15.dat
[13:29:23.459] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:29:23.460] <TB2> INFO: PixTestReadback::RES sent once
[13:29:32.077] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:29:32.077] <TB2> INFO: Vbg will be calibrated using Vd calibration
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148.6calibrated Vbg = 1.18057 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159calibrated Vbg = 1.17986 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 141.2calibrated Vbg = 1.18097 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.4calibrated Vbg = 1.18785 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 165.4calibrated Vbg = 1.19174 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.3calibrated Vbg = 1.19669 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 152.8calibrated Vbg = 1.19504 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.1calibrated Vbg = 1.19704 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.7calibrated Vbg = 1.19315 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.9calibrated Vbg = 1.19448 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.8calibrated Vbg = 1.18389 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.3calibrated Vbg = 1.18916 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152.7calibrated Vbg = 1.1834 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 161.9calibrated Vbg = 1.18924 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.2calibrated Vbg = 1.18508 :::*/*/*/*/
[13:29:32.077] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 149.3calibrated Vbg = 1.17689 :::*/*/*/*/
[13:29:32.080] <TB2> INFO: PixTestReadback::RES sent once
[13:32:26.387] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C0.dat
[13:32:26.387] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C1.dat
[13:32:26.387] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C2.dat
[13:32:26.387] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C3.dat
[13:32:26.387] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C4.dat
[13:32:26.387] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C5.dat
[13:32:26.387] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C6.dat
[13:32:26.387] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C7.dat
[13:32:26.388] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C8.dat
[13:32:26.388] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C9.dat
[13:32:26.388] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C10.dat
[13:32:26.388] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C11.dat
[13:32:26.388] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C12.dat
[13:32:26.388] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C13.dat
[13:32:26.388] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C14.dat
[13:32:26.388] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2139_FullQualification_2016-01-11_09h41m_1452501661//002_FulltestPxar_m20//readbackCal_C15.dat
[13:32:26.416] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:32:26.425] <TB2> INFO: PixTestReadback::doTest() done
[13:32:26.425] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:26.425] <TB2> INFO: Decoding statistics:
[13:32:26.425] <TB2> INFO: General information:
[13:32:26.425] <TB2> INFO: 16bit words read: 1024
[13:32:26.425] <TB2> INFO: valid events total: 128
[13:32:26.425] <TB2> INFO: empty events: 128
[13:32:26.425] <TB2> INFO: valid events with pixels: 0
[13:32:26.425] <TB2> INFO: valid pixel hits: 0
[13:32:26.425] <TB2> INFO: Event errors: 0
[13:32:26.425] <TB2> INFO: start marker: 0
[13:32:26.425] <TB2> INFO: stop marker: 0
[13:32:26.425] <TB2> INFO: overflow: 0
[13:32:26.425] <TB2> INFO: invalid 5bit words: 0
[13:32:26.425] <TB2> INFO: invalid XOR eye diagram: 0
[13:32:26.425] <TB2> INFO: TBM errors: 0
[13:32:26.425] <TB2> INFO: flawed TBM headers: 0
[13:32:26.425] <TB2> INFO: flawed TBM trailers: 0
[13:32:26.425] <TB2> INFO: event ID mismatches: 0
[13:32:26.425] <TB2> INFO: ROC errors: 0
[13:32:26.425] <TB2> INFO: missing ROC header(s): 0
[13:32:26.425] <TB2> INFO: misplaced readback start: 0
[13:32:26.425] <TB2> INFO: Pixel decoding errors: 0
[13:32:26.425] <TB2> INFO: pixel data incomplete: 0
[13:32:26.425] <TB2> INFO: pixel address: 0
[13:32:26.425] <TB2> INFO: pulse height fill bit: 0
[13:32:26.425] <TB2> INFO: buffer corruption: 0
[13:32:26.548] <TB2> INFO: Decoding statistics:
[13:32:26.548] <TB2> INFO: General information:
[13:32:26.548] <TB2> INFO: 16bit words read: 2755344
[13:32:26.548] <TB2> INFO: valid events total: 176768
[13:32:26.548] <TB2> INFO: empty events: 7868
[13:32:26.548] <TB2> INFO: valid events with pixels: 168900
[13:32:26.548] <TB2> INFO: valid pixel hits: 668070
[13:32:26.548] <TB2> INFO: Event errors: 0
[13:32:26.548] <TB2> INFO: start marker: 0
[13:32:26.548] <TB2> INFO: stop marker: 0
[13:32:26.548] <TB2> INFO: overflow: 0
[13:32:26.548] <TB2> INFO: invalid 5bit words: 0
[13:32:26.548] <TB2> INFO: invalid XOR eye diagram: 0
[13:32:26.548] <TB2> INFO: TBM errors: 0
[13:32:26.548] <TB2> INFO: flawed TBM headers: 0
[13:32:26.548] <TB2> INFO: flawed TBM trailers: 0
[13:32:26.548] <TB2> INFO: event ID mismatches: 0
[13:32:26.548] <TB2> INFO: ROC errors: 0
[13:32:26.548] <TB2> INFO: missing ROC header(s): 0
[13:32:26.548] <TB2> INFO: misplaced readback start: 0
[13:32:26.548] <TB2> INFO: Pixel decoding errors: 0
[13:32:26.548] <TB2> INFO: pixel data incomplete: 0
[13:32:26.548] <TB2> INFO: pixel address: 0
[13:32:26.548] <TB2> INFO: pulse height fill bit: 0
[13:32:26.548] <TB2> INFO: buffer corruption: 0
[13:32:26.562] <TB2> INFO: enter test to run
[13:32:26.562] <TB2> INFO: test: exit no parameter change
[13:32:27.847] <TB2> QUIET: Connection to board 156 closed.
[13:32:27.926] <TB2> INFO: pXar: this is the end, my friend