[08:43:45.910] <TB3> INFO: *** Welcome to pxar ***
[08:43:45.910] <TB3> INFO: *** Today: 2016/09/26
[08:43:45.995] <TB3> INFO: *** Version: fe94
[08:43:45.995] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C15.dat
[08:43:45.996] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:43:45.996] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//defaultMaskFile.dat
[08:43:45.997] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters_C15.dat
[08:43:46.068] <TB3> INFO: clk: 4
[08:43:46.068] <TB3> INFO: ctr: 4
[08:43:46.068] <TB3> INFO: sda: 19
[08:43:46.068] <TB3> INFO: tin: 9
[08:43:46.068] <TB3> INFO: level: 15
[08:43:46.068] <TB3> INFO: triggerdelay: 0
[08:43:46.068] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:43:46.068] <TB3> INFO: Log level: INFO
[08:43:46.075] <TB3> INFO: Found DTB DTB_WZ4I6J
[08:43:46.083] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[08:43:46.085] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[08:43:46.087] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[08:43:47.603] <TB3> INFO: DUT info:
[08:43:47.603] <TB3> INFO: The DUT currently contains the following objects:
[08:43:47.603] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:43:47.603] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:43:47.603] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:43:47.603] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:43:47.603] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:47.603] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:48.004] <TB3> INFO: enter 'restricted' command line mode
[08:43:48.004] <TB3> INFO: enter test to run
[08:43:48.004] <TB3> INFO: test: pretest no parameter change
[08:43:48.004] <TB3> INFO: running: pretest
[08:43:48.011] <TB3> INFO: ----------------------------------------------------------------------
[08:43:48.011] <TB3> INFO: PixTestPretest::programROC()
[08:43:48.011] <TB3> INFO: ----------------------------------------------------------------------
[08:44:06.029] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:44:06.029] <TB3> INFO: IA differences per ROC: 20.1 18.5 19.3 19.3 20.9 20.9 19.3 20.9 17.7 18.5 19.3 20.1 18.5 18.5 17.7 21.7
[08:44:06.144] <TB3> INFO: enter test to run
[08:44:06.144] <TB3> INFO: test: pretest no parameter change
[08:44:06.144] <TB3> INFO: running: pretest
[08:44:06.146] <TB3> INFO: ----------------------------------------------------------------------
[08:44:06.146] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:44:06.146] <TB3> INFO: ----------------------------------------------------------------------
[08:44:12.054] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[08:44:12.054] <TB3> INFO: i(loss) [mA/ROC]: 20.1 19.3 20.1 19.3 18.5 19.3 18.5 19.3 19.3 19.3 18.5 19.3 19.3 19.3 19.3 19.3
[08:44:12.099] <TB3> INFO: enter test to run
[08:44:12.099] <TB3> INFO: test: pretest no parameter change
[08:44:12.099] <TB3> INFO: running: pretest
[08:44:12.099] <TB3> INFO: ----------------------------------------------------------------------
[08:44:12.099] <TB3> INFO: PixTestPretest::findTiming()
[08:44:12.099] <TB3> INFO: ----------------------------------------------------------------------
[08:44:12.099] <TB3> INFO: PixTestCmd::init()
[08:44:12.945] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[08:45:41.746] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[08:45:41.746] <TB3> INFO: (success/tries = 100/100), width = 4
[08:45:41.748] <TB3> INFO: enter test to run
[08:45:41.748] <TB3> INFO: test: pretest no parameter change
[08:45:41.748] <TB3> INFO: running: pretest
[08:45:41.751] <TB3> INFO: ----------------------------------------------------------------------
[08:45:41.751] <TB3> INFO: PixTestPretest::findWorkingPixel()
[08:45:41.751] <TB3> INFO: ----------------------------------------------------------------------
[08:45:41.846] <TB3> INFO: Expecting 231680 events.
[08:45:47.451] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[08:45:47.583] <TB3> ERROR: <datapipe.cc/CheckEventID:L485> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[08:45:51.156] <TB3> INFO: 231680 events read in total (8754ms).
[08:45:51.161] <TB3> INFO: Test took 9405ms.
[08:45:51.366] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:45:51.449] <TB3> INFO: enter test to run
[08:45:51.449] <TB3> INFO: test: pretest no parameter change
[08:45:51.449] <TB3> INFO: running: pretest
[08:45:51.451] <TB3> INFO: ----------------------------------------------------------------------
[08:45:51.451] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[08:45:51.451] <TB3> INFO: ----------------------------------------------------------------------
[08:45:51.547] <TB3> INFO: Expecting 231680 events.
[08:46:00.824] <TB3> INFO: 231680 events read in total (8720ms).
[08:46:00.829] <TB3> INFO: Test took 9373ms.
[08:46:01.066] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[08:46:01.066] <TB3> INFO: CalDel: 170 157 160 166 147 141 161 128 156 143 139 153 143 139 143 163
[08:46:01.066] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:46:01.099] <TB3> INFO: enter test to run
[08:46:01.099] <TB3> INFO: test: pretest no parameter change
[08:46:01.099] <TB3> INFO: running: pretest
[08:46:01.099] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C0.dat
[08:46:01.099] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C1.dat
[08:46:01.099] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C2.dat
[08:46:01.099] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C3.dat
[08:46:01.099] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C4.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C5.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C6.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C7.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C8.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C9.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C10.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C11.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C12.dat
[08:46:01.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C13.dat
[08:46:01.101] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C14.dat
[08:46:01.101] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters_C15.dat
[08:46:01.101] <TB3> INFO: enter test to run
[08:46:01.101] <TB3> INFO: test: fulltest no parameter change
[08:46:01.101] <TB3> INFO: running: fulltest
[08:46:01.101] <TB3> INFO: ######################################################################
[08:46:01.101] <TB3> INFO: PixTestFullTest::doTest()
[08:46:01.101] <TB3> INFO: ######################################################################
[08:46:01.104] <TB3> INFO: ######################################################################
[08:46:01.104] <TB3> INFO: PixTestAlive::doTest()
[08:46:01.104] <TB3> INFO: ######################################################################
[08:46:01.105] <TB3> INFO: ----------------------------------------------------------------------
[08:46:01.105] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:46:01.105] <TB3> INFO: ----------------------------------------------------------------------
[08:46:01.392] <TB3> INFO: Expecting 41600 events.
[08:46:04.800] <TB3> INFO: 41600 events read in total (2850ms).
[08:46:04.801] <TB3> INFO: Test took 3693ms.
[08:46:05.042] <TB3> INFO: PixTestAlive::aliveTest() done
[08:46:05.042] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:46:05.044] <TB3> INFO: ----------------------------------------------------------------------
[08:46:05.044] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:46:05.044] <TB3> INFO: ----------------------------------------------------------------------
[08:46:05.339] <TB3> INFO: Expecting 41600 events.
[08:46:08.065] <TB3> INFO: 41600 events read in total (2169ms).
[08:46:08.065] <TB3> INFO: Test took 3018ms.
[08:46:08.065] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:46:08.315] <TB3> INFO: PixTestAlive::maskTest() done
[08:46:08.315] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:46:08.316] <TB3> INFO: ----------------------------------------------------------------------
[08:46:08.316] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:46:08.316] <TB3> INFO: ----------------------------------------------------------------------
[08:46:08.587] <TB3> INFO: Expecting 41600 events.
[08:46:12.052] <TB3> INFO: 41600 events read in total (2908ms).
[08:46:12.053] <TB3> INFO: Test took 3734ms.
[08:46:12.290] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[08:46:12.290] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:46:12.290] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:46:12.291] <TB3> INFO: Decoding statistics:
[08:46:12.291] <TB3> INFO: General information:
[08:46:12.291] <TB3> INFO: 16bit words read: 0
[08:46:12.291] <TB3> INFO: valid events total: 0
[08:46:12.291] <TB3> INFO: empty events: 0
[08:46:12.291] <TB3> INFO: valid events with pixels: 0
[08:46:12.291] <TB3> INFO: valid pixel hits: 0
[08:46:12.291] <TB3> INFO: Event errors: 0
[08:46:12.291] <TB3> INFO: start marker: 0
[08:46:12.291] <TB3> INFO: stop marker: 0
[08:46:12.291] <TB3> INFO: overflow: 0
[08:46:12.291] <TB3> INFO: invalid 5bit words: 0
[08:46:12.291] <TB3> INFO: invalid XOR eye diagram: 0
[08:46:12.291] <TB3> INFO: frame (failed synchr.): 0
[08:46:12.291] <TB3> INFO: idle data (no TBM trl): 0
[08:46:12.291] <TB3> INFO: no data (only TBM hdr): 0
[08:46:12.291] <TB3> INFO: TBM errors: 0
[08:46:12.291] <TB3> INFO: flawed TBM headers: 0
[08:46:12.291] <TB3> INFO: flawed TBM trailers: 0
[08:46:12.291] <TB3> INFO: event ID mismatches: 0
[08:46:12.291] <TB3> INFO: ROC errors: 0
[08:46:12.291] <TB3> INFO: missing ROC header(s): 0
[08:46:12.291] <TB3> INFO: misplaced readback start: 0
[08:46:12.291] <TB3> INFO: Pixel decoding errors: 0
[08:46:12.291] <TB3> INFO: pixel data incomplete: 0
[08:46:12.291] <TB3> INFO: pixel address: 0
[08:46:12.291] <TB3> INFO: pulse height fill bit: 0
[08:46:12.291] <TB3> INFO: buffer corruption: 0
[08:46:12.298] <TB3> INFO: ######################################################################
[08:46:12.298] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:46:12.298] <TB3> INFO: ######################################################################
[08:46:12.302] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:46:12.312] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:46:12.312] <TB3> INFO: run 1 of 1
[08:46:12.615] <TB3> INFO: Expecting 3120000 events.
[08:46:50.319] <TB3> INFO: 872295 events read in total (37147ms).
[08:47:27.481] <TB3> INFO: 1730665 events read in total (74309ms).
[08:48:04.496] <TB3> INFO: 2602975 events read in total (111324ms).
[08:48:26.294] <TB3> INFO: 3120000 events read in total (133122ms).
[08:48:26.370] <TB3> INFO: Test took 134059ms.
[08:48:49.301] <TB3> INFO: PixTestBBMap::doTest() done, duration: 157 seconds
[08:48:49.301] <TB3> INFO: number of dead bumps (per ROC): 0 0 2 0 1 4 9 12 6 2 1 1 0 2 0 0
[08:48:49.301] <TB3> INFO: separation cut (per ROC): 75 73 73 73 87 81 70 74 81 85 85 89 89 80 87 88
[08:48:49.301] <TB3> INFO: Decoding statistics:
[08:48:49.301] <TB3> INFO: General information:
[08:48:49.301] <TB3> INFO: 16bit words read: 0
[08:48:49.301] <TB3> INFO: valid events total: 0
[08:48:49.301] <TB3> INFO: empty events: 0
[08:48:49.301] <TB3> INFO: valid events with pixels: 0
[08:48:49.301] <TB3> INFO: valid pixel hits: 0
[08:48:49.301] <TB3> INFO: Event errors: 0
[08:48:49.301] <TB3> INFO: start marker: 0
[08:48:49.301] <TB3> INFO: stop marker: 0
[08:48:49.301] <TB3> INFO: overflow: 0
[08:48:49.301] <TB3> INFO: invalid 5bit words: 0
[08:48:49.301] <TB3> INFO: invalid XOR eye diagram: 0
[08:48:49.301] <TB3> INFO: frame (failed synchr.): 0
[08:48:49.301] <TB3> INFO: idle data (no TBM trl): 0
[08:48:49.301] <TB3> INFO: no data (only TBM hdr): 0
[08:48:49.301] <TB3> INFO: TBM errors: 0
[08:48:49.301] <TB3> INFO: flawed TBM headers: 0
[08:48:49.301] <TB3> INFO: flawed TBM trailers: 0
[08:48:49.301] <TB3> INFO: event ID mismatches: 0
[08:48:49.301] <TB3> INFO: ROC errors: 0
[08:48:49.301] <TB3> INFO: missing ROC header(s): 0
[08:48:49.301] <TB3> INFO: misplaced readback start: 0
[08:48:49.301] <TB3> INFO: Pixel decoding errors: 0
[08:48:49.301] <TB3> INFO: pixel data incomplete: 0
[08:48:49.302] <TB3> INFO: pixel address: 0
[08:48:49.302] <TB3> INFO: pulse height fill bit: 0
[08:48:49.302] <TB3> INFO: buffer corruption: 0
[08:48:49.377] <TB3> INFO: ######################################################################
[08:48:49.377] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:48:49.377] <TB3> INFO: ######################################################################
[08:48:49.377] <TB3> INFO: ----------------------------------------------------------------------
[08:48:49.377] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:48:49.377] <TB3> INFO: ----------------------------------------------------------------------
[08:48:49.377] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[08:48:49.385] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[08:48:49.385] <TB3> INFO: run 1 of 1
[08:48:49.650] <TB3> INFO: Expecting 36608000 events.
[08:49:18.720] <TB3> INFO: 791450 events read in total (28512ms).
[08:49:47.819] <TB3> INFO: 1572050 events read in total (57611ms).
[08:50:17.025] <TB3> INFO: 2353750 events read in total (86817ms).
[08:50:45.870] <TB3> INFO: 3132800 events read in total (115662ms).
[08:51:13.807] <TB3> INFO: 3912400 events read in total (143599ms).
[08:51:41.364] <TB3> INFO: 4691450 events read in total (171156ms).
[08:52:09.043] <TB3> INFO: 5470550 events read in total (198835ms).
[08:52:36.616] <TB3> INFO: 6251800 events read in total (226408ms).
[08:53:04.536] <TB3> INFO: 7029700 events read in total (254328ms).
[08:53:32.615] <TB3> INFO: 7810450 events read in total (282407ms).
[08:54:00.600] <TB3> INFO: 8590100 events read in total (310392ms).
[08:54:28.673] <TB3> INFO: 9369050 events read in total (338465ms).
[08:54:56.732] <TB3> INFO: 10147300 events read in total (366524ms).
[08:55:24.594] <TB3> INFO: 10925700 events read in total (394386ms).
[08:55:52.428] <TB3> INFO: 11703850 events read in total (422220ms).
[08:56:20.077] <TB3> INFO: 12484350 events read in total (449869ms).
[08:56:47.490] <TB3> INFO: 13264100 events read in total (477282ms).
[08:57:15.587] <TB3> INFO: 14041850 events read in total (505379ms).
[08:57:43.249] <TB3> INFO: 14821750 events read in total (533041ms).
[08:58:11.040] <TB3> INFO: 15598950 events read in total (560832ms).
[08:58:38.854] <TB3> INFO: 16376600 events read in total (588646ms).
[08:59:06.545] <TB3> INFO: 17154350 events read in total (616337ms).
[08:59:34.336] <TB3> INFO: 17930700 events read in total (644128ms).
[09:00:02.100] <TB3> INFO: 18704500 events read in total (671892ms).
[09:00:29.587] <TB3> INFO: 19476550 events read in total (699379ms).
[09:00:57.355] <TB3> INFO: 20248350 events read in total (727147ms).
[09:01:25.137] <TB3> INFO: 21021200 events read in total (754929ms).
[09:01:53.130] <TB3> INFO: 21794550 events read in total (782922ms).
[09:02:20.900] <TB3> INFO: 22564900 events read in total (810692ms).
[09:02:48.560] <TB3> INFO: 23335150 events read in total (838352ms).
[09:03:16.099] <TB3> INFO: 24105800 events read in total (865891ms).
[09:03:43.585] <TB3> INFO: 24875050 events read in total (893377ms).
[09:04:11.133] <TB3> INFO: 25646100 events read in total (920925ms).
[09:04:38.825] <TB3> INFO: 26415050 events read in total (948617ms).
[09:05:06.489] <TB3> INFO: 27184300 events read in total (976281ms).
[09:05:34.377] <TB3> INFO: 27954100 events read in total (1004169ms).
[09:06:02.005] <TB3> INFO: 28722350 events read in total (1031797ms).
[09:06:29.702] <TB3> INFO: 29491600 events read in total (1059494ms).
[09:06:57.597] <TB3> INFO: 30261850 events read in total (1087389ms).
[09:07:25.529] <TB3> INFO: 31031750 events read in total (1115321ms).
[09:07:53.412] <TB3> INFO: 31800650 events read in total (1143204ms).
[09:08:21.044] <TB3> INFO: 32570050 events read in total (1170836ms).
[09:08:48.786] <TB3> INFO: 33340550 events read in total (1198578ms).
[09:09:17.027] <TB3> INFO: 34111000 events read in total (1226819ms).
[09:09:45.005] <TB3> INFO: 34880550 events read in total (1254797ms).
[09:10:12.831] <TB3> INFO: 35651900 events read in total (1282623ms).
[09:10:42.670] <TB3> INFO: 36431800 events read in total (1312462ms).
[09:10:49.347] <TB3> INFO: 36608000 events read in total (1319139ms).
[09:10:49.406] <TB3> INFO: Test took 1320020ms.
[09:10:49.948] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:10:51.886] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:10:53.744] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:10:55.532] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:10:57.292] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:10:59.189] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:00.973] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:02.880] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:04.796] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:06.508] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:08.346] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:10.350] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:12.048] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:13.790] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:15.520] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:17.365] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:11:19.141] <TB3> INFO: PixTestScurves::scurves() done
[09:11:19.141] <TB3> INFO: Vcal mean: 74.11 70.84 69.21 83.67 83.52 76.53 71.76 79.76 84.08 80.35 81.83 82.00 84.16 82.69 87.10 84.33
[09:11:19.141] <TB3> INFO: Vcal RMS: 4.63 4.58 4.25 4.47 4.45 4.35 4.63 3.97 4.52 4.42 4.78 4.58 4.83 4.97 5.35 4.53
[09:11:19.141] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1349 seconds
[09:11:19.141] <TB3> INFO: Decoding statistics:
[09:11:19.141] <TB3> INFO: General information:
[09:11:19.141] <TB3> INFO: 16bit words read: 0
[09:11:19.141] <TB3> INFO: valid events total: 0
[09:11:19.141] <TB3> INFO: empty events: 0
[09:11:19.141] <TB3> INFO: valid events with pixels: 0
[09:11:19.141] <TB3> INFO: valid pixel hits: 0
[09:11:19.141] <TB3> INFO: Event errors: 0
[09:11:19.141] <TB3> INFO: start marker: 0
[09:11:19.141] <TB3> INFO: stop marker: 0
[09:11:19.141] <TB3> INFO: overflow: 0
[09:11:19.141] <TB3> INFO: invalid 5bit words: 0
[09:11:19.141] <TB3> INFO: invalid XOR eye diagram: 0
[09:11:19.141] <TB3> INFO: frame (failed synchr.): 0
[09:11:19.141] <TB3> INFO: idle data (no TBM trl): 0
[09:11:19.141] <TB3> INFO: no data (only TBM hdr): 0
[09:11:19.141] <TB3> INFO: TBM errors: 0
[09:11:19.141] <TB3> INFO: flawed TBM headers: 0
[09:11:19.141] <TB3> INFO: flawed TBM trailers: 0
[09:11:19.141] <TB3> INFO: event ID mismatches: 0
[09:11:19.141] <TB3> INFO: ROC errors: 0
[09:11:19.141] <TB3> INFO: missing ROC header(s): 0
[09:11:19.141] <TB3> INFO: misplaced readback start: 0
[09:11:19.141] <TB3> INFO: Pixel decoding errors: 0
[09:11:19.141] <TB3> INFO: pixel data incomplete: 0
[09:11:19.141] <TB3> INFO: pixel address: 0
[09:11:19.141] <TB3> INFO: pulse height fill bit: 0
[09:11:19.141] <TB3> INFO: buffer corruption: 0
[09:11:19.214] <TB3> INFO: ######################################################################
[09:11:19.214] <TB3> INFO: PixTestTrim::doTest()
[09:11:19.214] <TB3> INFO: ######################################################################
[09:11:19.215] <TB3> INFO: ----------------------------------------------------------------------
[09:11:19.215] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:11:19.215] <TB3> INFO: ----------------------------------------------------------------------
[09:11:19.295] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:11:19.295] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:11:19.303] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:11:19.303] <TB3> INFO: run 1 of 1
[09:11:19.569] <TB3> INFO: Expecting 6281600 events.
[09:11:56.096] <TB3> INFO: 1097860 events read in total (35968ms).
[09:12:31.902] <TB3> INFO: 2188540 events read in total (71774ms).
[09:13:07.604] <TB3> INFO: 3272210 events read in total (107476ms).
[09:13:43.314] <TB3> INFO: 4361950 events read in total (143186ms).
[09:14:19.211] <TB3> INFO: 5455420 events read in total (179083ms).
[09:14:45.826] <TB3> INFO: 6281600 events read in total (205698ms).
[09:14:45.882] <TB3> INFO: Test took 206579ms.
[09:15:05.500] <TB3> INFO: ROC 0 VthrComp = 80
[09:15:05.500] <TB3> INFO: ROC 1 VthrComp = 75
[09:15:05.501] <TB3> INFO: ROC 2 VthrComp = 75
[09:15:05.501] <TB3> INFO: ROC 3 VthrComp = 89
[09:15:05.501] <TB3> INFO: ROC 4 VthrComp = 90
[09:15:05.501] <TB3> INFO: ROC 5 VthrComp = 83
[09:15:05.501] <TB3> INFO: ROC 6 VthrComp = 76
[09:15:05.501] <TB3> INFO: ROC 7 VthrComp = 88
[09:15:05.501] <TB3> INFO: ROC 8 VthrComp = 90
[09:15:05.501] <TB3> INFO: ROC 9 VthrComp = 87
[09:15:05.502] <TB3> INFO: ROC 10 VthrComp = 87
[09:15:05.502] <TB3> INFO: ROC 11 VthrComp = 88
[09:15:05.502] <TB3> INFO: ROC 12 VthrComp = 88
[09:15:05.502] <TB3> INFO: ROC 13 VthrComp = 86
[09:15:05.502] <TB3> INFO: ROC 14 VthrComp = 89
[09:15:05.502] <TB3> INFO: ROC 15 VthrComp = 89
[09:15:05.503] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:15:05.503] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:15:05.511] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:15:05.511] <TB3> INFO: run 1 of 1
[09:15:05.789] <TB3> INFO: Expecting 6281600 events.
[09:15:37.405] <TB3> INFO: 760750 events read in total (31058ms).
[09:16:08.144] <TB3> INFO: 1519790 events read in total (61797ms).
[09:16:39.120] <TB3> INFO: 2278450 events read in total (92773ms).
[09:17:09.857] <TB3> INFO: 3037500 events read in total (123510ms).
[09:17:40.616] <TB3> INFO: 3790470 events read in total (154269ms).
[09:18:11.152] <TB3> INFO: 4539460 events read in total (184805ms).
[09:18:41.621] <TB3> INFO: 5285770 events read in total (215274ms).
[09:19:13.857] <TB3> INFO: 6032140 events read in total (247510ms).
[09:19:24.899] <TB3> INFO: 6281600 events read in total (258552ms).
[09:19:24.951] <TB3> INFO: Test took 259440ms.
[09:19:51.040] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.9871 for pixel 1/13 mean/min/max = 45.088/32.1264/58.0497
[09:19:51.040] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 56.5875 for pixel 14/64 mean/min/max = 45.522/34.3163/56.7277
[09:19:51.041] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.0952 for pixel 24/55 mean/min/max = 46.2323/35.3569/57.1076
[09:19:51.041] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 56.9748 for pixel 14/12 mean/min/max = 44.8436/32.6094/57.0778
[09:19:51.041] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 56.6967 for pixel 15/10 mean/min/max = 44.7051/32.6925/56.7176
[09:19:51.041] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 57.2486 for pixel 36/1 mean/min/max = 44.6769/31.9157/57.4382
[09:19:51.042] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.7893 for pixel 3/8 mean/min/max = 46.9973/35.2004/58.7942
[09:19:51.042] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 56.8135 for pixel 51/24 mean/min/max = 45.0684/33.1676/56.9692
[09:19:51.042] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.5376 for pixel 15/17 mean/min/max = 44.665/32.6678/56.6622
[09:19:51.043] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 58.1641 for pixel 1/13 mean/min/max = 44.8723/31.5423/58.2023
[09:19:51.043] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.3329 for pixel 3/40 mean/min/max = 45.1735/31.9513/58.3958
[09:19:51.043] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 58.781 for pixel 0/4 mean/min/max = 45.6655/32.5315/58.7994
[09:19:51.043] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 58.4101 for pixel 2/79 mean/min/max = 45.6166/32.7231/58.5101
[09:19:51.044] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 59.6823 for pixel 18/2 mean/min/max = 45.9713/32.0319/59.9107
[09:19:51.044] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 60.5688 for pixel 0/20 mean/min/max = 46.5835/32.4728/60.6941
[09:19:51.044] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 57.9579 for pixel 10/2 mean/min/max = 45.8214/33.5521/58.0908
[09:19:51.044] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:51.135] <TB3> INFO: Expecting 514560 events.
[09:20:02.134] <TB3> INFO: 514560 events read in total (10442ms).
[09:20:02.144] <TB3> INFO: Expecting 514560 events.
[09:20:12.990] <TB3> INFO: 514560 events read in total (10444ms).
[09:20:12.999] <TB3> INFO: Expecting 514560 events.
[09:20:23.725] <TB3> INFO: 514560 events read in total (10324ms).
[09:20:23.738] <TB3> INFO: Expecting 514560 events.
[09:20:34.605] <TB3> INFO: 514560 events read in total (10465ms).
[09:20:34.625] <TB3> INFO: Expecting 514560 events.
[09:20:45.403] <TB3> INFO: 514560 events read in total (10376ms).
[09:20:45.421] <TB3> INFO: Expecting 514560 events.
[09:20:56.226] <TB3> INFO: 514560 events read in total (10402ms).
[09:20:56.246] <TB3> INFO: Expecting 514560 events.
[09:21:07.058] <TB3> INFO: 514560 events read in total (10409ms).
[09:21:07.089] <TB3> INFO: Expecting 514560 events.
[09:21:17.853] <TB3> INFO: 514560 events read in total (10362ms).
[09:21:17.880] <TB3> INFO: Expecting 514560 events.
[09:21:28.624] <TB3> INFO: 514560 events read in total (10342ms).
[09:21:28.653] <TB3> INFO: Expecting 514560 events.
[09:21:39.516] <TB3> INFO: 514560 events read in total (10461ms).
[09:21:39.548] <TB3> INFO: Expecting 514560 events.
[09:21:50.390] <TB3> INFO: 514560 events read in total (10440ms).
[09:21:50.426] <TB3> INFO: Expecting 514560 events.
[09:22:01.151] <TB3> INFO: 514560 events read in total (10322ms).
[09:22:01.187] <TB3> INFO: Expecting 514560 events.
[09:22:12.052] <TB3> INFO: 514560 events read in total (10462ms).
[09:22:12.091] <TB3> INFO: Expecting 514560 events.
[09:22:22.991] <TB3> INFO: 514560 events read in total (10497ms).
[09:22:23.049] <TB3> INFO: Expecting 514560 events.
[09:22:33.871] <TB3> INFO: 514560 events read in total (10419ms).
[09:22:33.916] <TB3> INFO: Expecting 514560 events.
[09:22:44.692] <TB3> INFO: 514560 events read in total (10373ms).
[09:22:44.741] <TB3> INFO: Test took 173697ms.
[09:22:45.971] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:22:45.983] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:22:45.983] <TB3> INFO: run 1 of 1
[09:22:46.348] <TB3> INFO: Expecting 6281600 events.
[09:23:17.885] <TB3> INFO: 752320 events read in total (30980ms).
[09:23:48.568] <TB3> INFO: 1504070 events read in total (61663ms).
[09:24:19.350] <TB3> INFO: 2256660 events read in total (92445ms).
[09:24:50.013] <TB3> INFO: 3010030 events read in total (123108ms).
[09:25:20.920] <TB3> INFO: 3756700 events read in total (154015ms).
[09:25:51.659] <TB3> INFO: 4499560 events read in total (184754ms).
[09:26:22.097] <TB3> INFO: 5240310 events read in total (215192ms).
[09:26:53.816] <TB3> INFO: 5981100 events read in total (246911ms).
[09:27:06.829] <TB3> INFO: 6281600 events read in total (259924ms).
[09:27:06.882] <TB3> INFO: Test took 260900ms.
[09:27:30.966] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 12.842175 .. 50.892366
[09:27:31.231] <TB3> INFO: Expecting 208000 events.
[09:27:41.035] <TB3> INFO: 208000 events read in total (9247ms).
[09:27:41.037] <TB3> INFO: Test took 10069ms.
[09:27:41.123] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 2 .. 60 (-1/-1) hits flags = 528 (plus default)
[09:27:41.131] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:27:41.131] <TB3> INFO: run 1 of 1
[09:27:41.421] <TB3> INFO: Expecting 2454400 events.
[09:28:16.588] <TB3> INFO: 973440 events read in total (34610ms).
[09:28:51.106] <TB3> INFO: 1936640 events read in total (69128ms).
[09:29:09.837] <TB3> INFO: 2454400 events read in total (87859ms).
[09:29:09.857] <TB3> INFO: Test took 88726ms.
[09:29:24.715] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 25.188791 .. 47.544199
[09:29:25.020] <TB3> INFO: Expecting 208000 events.
[09:29:34.801] <TB3> INFO: 208000 events read in total (9224ms).
[09:29:34.802] <TB3> INFO: Test took 10085ms.
[09:29:34.888] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[09:29:34.897] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:29:34.897] <TB3> INFO: run 1 of 1
[09:29:35.162] <TB3> INFO: Expecting 1788800 events.
[09:30:09.891] <TB3> INFO: 915340 events read in total (34172ms).
[09:30:42.758] <TB3> INFO: 1788800 events read in total (67039ms).
[09:30:42.782] <TB3> INFO: Test took 67885ms.
[09:30:56.846] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 27.723948 .. 45.829017
[09:30:57.124] <TB3> INFO: Expecting 208000 events.
[09:31:07.125] <TB3> INFO: 208000 events read in total (9444ms).
[09:31:07.125] <TB3> INFO: Test took 10277ms.
[09:31:07.211] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 17 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:31:07.219] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:31:07.219] <TB3> INFO: run 1 of 1
[09:31:07.484] <TB3> INFO: Expecting 1622400 events.
[09:31:42.027] <TB3> INFO: 908700 events read in total (33987ms).
[09:32:08.868] <TB3> INFO: 1622400 events read in total (60828ms).
[09:32:08.888] <TB3> INFO: Test took 61670ms.
[09:32:22.025] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 29.237105 .. 45.686822
[09:32:22.290] <TB3> INFO: Expecting 208000 events.
[09:32:32.002] <TB3> INFO: 208000 events read in total (9155ms).
[09:32:32.002] <TB3> INFO: Test took 9974ms.
[09:32:32.095] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 19 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:32:32.103] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:32:32.104] <TB3> INFO: run 1 of 1
[09:32:32.370] <TB3> INFO: Expecting 1539200 events.
[09:33:06.558] <TB3> INFO: 893790 events read in total (33632ms).
[09:33:31.523] <TB3> INFO: 1539200 events read in total (58597ms).
[09:33:31.540] <TB3> INFO: Test took 59436ms.
[09:33:44.514] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:33:44.514] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:33:44.521] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:33:44.522] <TB3> INFO: run 1 of 1
[09:33:44.784] <TB3> INFO: Expecting 1705600 events.
[09:34:18.943] <TB3> INFO: 918930 events read in total (33603ms).
[09:34:48.258] <TB3> INFO: 1705600 events read in total (62918ms).
[09:34:48.277] <TB3> INFO: Test took 63755ms.
[09:35:01.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:35:01.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:35:01.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:35:01.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:35:01.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:35:01.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:35:01.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:35:01.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:35:01.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:35:01.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:35:01.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:35:01.757] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:35:01.757] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:35:01.757] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:35:01.757] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:35:01.757] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:35:01.757] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:35:01.766] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:35:01.773] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:35:01.779] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:35:01.785] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:35:01.793] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:35:01.802] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:35:01.812] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:35:01.821] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:35:01.830] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:35:01.840] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:35:01.847] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:35:01.853] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:35:01.861] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:35:01.869] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:35:01.878] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:35:01.887] <TB3> INFO: PixTestTrim::trimTest() done
[09:35:01.887] <TB3> INFO: vtrim: 101 96 98 97 105 109 112 97 97 116 110 109 102 116 107 110
[09:35:01.887] <TB3> INFO: vthrcomp: 80 75 75 89 90 83 76 88 90 87 87 88 88 86 89 89
[09:35:01.887] <TB3> INFO: vcal mean: 36.17 37.12 37.40 36.20 36.24 36.00 37.22 36.27 36.72 35.98 35.77 36.21 36.62 36.12 36.91 36.50
[09:35:01.887] <TB3> INFO: vcal RMS: 1.59 2.23 2.41 1.55 1.61 1.46 2.42 1.58 2.01 1.43 1.20 1.62 1.94 1.57 2.23 1.94
[09:35:01.887] <TB3> INFO: bits mean: 6.92 6.04 5.48 6.80 7.26 7.42 5.79 6.59 6.90 7.71 7.77 6.66 6.88 7.60 6.25 6.74
[09:35:01.887] <TB3> INFO: bits RMS: 2.58 2.39 2.41 2.54 2.40 2.52 2.42 2.59 2.42 2.44 2.55 2.75 2.42 2.39 2.62 2.32
[09:35:01.896] <TB3> INFO: ----------------------------------------------------------------------
[09:35:01.896] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:35:01.896] <TB3> INFO: ----------------------------------------------------------------------
[09:35:01.901] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:35:01.912] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:35:01.912] <TB3> INFO: run 1 of 1
[09:35:02.202] <TB3> INFO: Expecting 4160000 events.
[09:35:40.712] <TB3> INFO: 961650 events read in total (37955ms).
[09:36:18.600] <TB3> INFO: 1915610 events read in total (75842ms).
[09:36:56.250] <TB3> INFO: 2857230 events read in total (113492ms).
[09:37:33.910] <TB3> INFO: 3795950 events read in total (151152ms).
[09:37:49.679] <TB3> INFO: 4160000 events read in total (166921ms).
[09:37:49.739] <TB3> INFO: Test took 167826ms.
[09:38:18.195] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[09:38:18.203] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:38:18.203] <TB3> INFO: run 1 of 1
[09:38:18.464] <TB3> INFO: Expecting 3868800 events.
[09:38:56.910] <TB3> INFO: 957850 events read in total (37889ms).
[09:39:34.849] <TB3> INFO: 1908050 events read in total (75828ms).
[09:40:12.558] <TB3> INFO: 2844990 events read in total (113537ms).
[09:40:51.991] <TB3> INFO: 3780415 events read in total (152970ms).
[09:40:56.121] <TB3> INFO: 3868800 events read in total (157101ms).
[09:40:56.174] <TB3> INFO: Test took 157971ms.
[09:41:24.263] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[09:41:24.270] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:41:24.270] <TB3> INFO: run 1 of 1
[09:41:24.534] <TB3> INFO: Expecting 3536000 events.
[09:42:03.858] <TB3> INFO: 1001765 events read in total (38767ms).
[09:42:42.076] <TB3> INFO: 1990690 events read in total (76985ms).
[09:43:21.098] <TB3> INFO: 2965900 events read in total (116007ms).
[09:43:45.753] <TB3> INFO: 3536000 events read in total (140662ms).
[09:43:45.807] <TB3> INFO: Test took 141537ms.
[09:44:11.589] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[09:44:11.597] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:44:11.597] <TB3> INFO: run 1 of 1
[09:44:11.859] <TB3> INFO: Expecting 3598400 events.
[09:44:50.629] <TB3> INFO: 994040 events read in total (38214ms).
[09:45:29.013] <TB3> INFO: 1976520 events read in total (76598ms).
[09:46:07.528] <TB3> INFO: 2945470 events read in total (115113ms).
[09:46:34.630] <TB3> INFO: 3598400 events read in total (142215ms).
[09:46:34.689] <TB3> INFO: Test took 143092ms.
[09:46:59.725] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[09:46:59.733] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:46:59.733] <TB3> INFO: run 1 of 1
[09:46:59.994] <TB3> INFO: Expecting 3577600 events.
[09:47:39.096] <TB3> INFO: 997900 events read in total (38545ms).
[09:48:17.572] <TB3> INFO: 1983605 events read in total (77021ms).
[09:48:56.443] <TB3> INFO: 2955335 events read in total (115892ms).
[09:49:24.789] <TB3> INFO: 3577600 events read in total (144238ms).
[09:49:24.866] <TB3> INFO: Test took 145134ms.
[09:49:54.657] <TB3> INFO: PixTestTrim::trimBitTest() done
[09:49:54.658] <TB3> INFO: PixTestTrim::doTest() done, duration: 2315 seconds
[09:49:54.658] <TB3> INFO: Decoding statistics:
[09:49:54.658] <TB3> INFO: General information:
[09:49:54.658] <TB3> INFO: 16bit words read: 0
[09:49:54.658] <TB3> INFO: valid events total: 0
[09:49:54.658] <TB3> INFO: empty events: 0
[09:49:54.658] <TB3> INFO: valid events with pixels: 0
[09:49:54.658] <TB3> INFO: valid pixel hits: 0
[09:49:54.658] <TB3> INFO: Event errors: 0
[09:49:54.658] <TB3> INFO: start marker: 0
[09:49:54.658] <TB3> INFO: stop marker: 0
[09:49:54.658] <TB3> INFO: overflow: 0
[09:49:54.658] <TB3> INFO: invalid 5bit words: 0
[09:49:54.658] <TB3> INFO: invalid XOR eye diagram: 0
[09:49:54.658] <TB3> INFO: frame (failed synchr.): 0
[09:49:54.658] <TB3> INFO: idle data (no TBM trl): 0
[09:49:54.658] <TB3> INFO: no data (only TBM hdr): 0
[09:49:54.658] <TB3> INFO: TBM errors: 0
[09:49:54.658] <TB3> INFO: flawed TBM headers: 0
[09:49:54.658] <TB3> INFO: flawed TBM trailers: 0
[09:49:54.658] <TB3> INFO: event ID mismatches: 0
[09:49:54.658] <TB3> INFO: ROC errors: 0
[09:49:54.658] <TB3> INFO: missing ROC header(s): 0
[09:49:54.658] <TB3> INFO: misplaced readback start: 0
[09:49:54.658] <TB3> INFO: Pixel decoding errors: 0
[09:49:54.658] <TB3> INFO: pixel data incomplete: 0
[09:49:54.658] <TB3> INFO: pixel address: 0
[09:49:54.658] <TB3> INFO: pulse height fill bit: 0
[09:49:54.658] <TB3> INFO: buffer corruption: 0
[09:49:55.360] <TB3> INFO: ######################################################################
[09:49:55.360] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:49:55.360] <TB3> INFO: ######################################################################
[09:49:55.642] <TB3> INFO: Expecting 41600 events.
[09:49:59.282] <TB3> INFO: 41600 events read in total (3083ms).
[09:49:59.282] <TB3> INFO: Test took 3921ms.
[09:49:59.748] <TB3> INFO: Expecting 41600 events.
[09:50:03.192] <TB3> INFO: 41600 events read in total (2887ms).
[09:50:03.193] <TB3> INFO: Test took 3712ms.
[09:50:03.475] <TB3> INFO: Expecting 41600 events.
[09:50:06.928] <TB3> INFO: 41600 events read in total (2896ms).
[09:50:06.928] <TB3> INFO: Test took 3714ms.
[09:50:07.185] <TB3> INFO: Expecting 2560 events.
[09:50:08.039] <TB3> INFO: 2560 events read in total (297ms).
[09:50:08.039] <TB3> INFO: Test took 1097ms.
[09:50:08.345] <TB3> INFO: Expecting 2560 events.
[09:50:09.198] <TB3> INFO: 2560 events read in total (296ms).
[09:50:09.198] <TB3> INFO: Test took 1159ms.
[09:50:09.505] <TB3> INFO: Expecting 2560 events.
[09:50:10.361] <TB3> INFO: 2560 events read in total (300ms).
[09:50:10.361] <TB3> INFO: Test took 1162ms.
[09:50:10.667] <TB3> INFO: Expecting 2560 events.
[09:50:11.520] <TB3> INFO: 2560 events read in total (296ms).
[09:50:11.520] <TB3> INFO: Test took 1159ms.
[09:50:11.826] <TB3> INFO: Expecting 2560 events.
[09:50:12.680] <TB3> INFO: 2560 events read in total (297ms).
[09:50:12.680] <TB3> INFO: Test took 1159ms.
[09:50:12.986] <TB3> INFO: Expecting 2560 events.
[09:50:13.848] <TB3> INFO: 2560 events read in total (305ms).
[09:50:13.848] <TB3> INFO: Test took 1167ms.
[09:50:14.154] <TB3> INFO: Expecting 2560 events.
[09:50:15.008] <TB3> INFO: 2560 events read in total (298ms).
[09:50:15.009] <TB3> INFO: Test took 1161ms.
[09:50:15.315] <TB3> INFO: Expecting 2560 events.
[09:50:16.170] <TB3> INFO: 2560 events read in total (299ms).
[09:50:16.171] <TB3> INFO: Test took 1162ms.
[09:50:16.476] <TB3> INFO: Expecting 2560 events.
[09:50:17.330] <TB3> INFO: 2560 events read in total (297ms).
[09:50:17.330] <TB3> INFO: Test took 1157ms.
[09:50:17.636] <TB3> INFO: Expecting 2560 events.
[09:50:18.490] <TB3> INFO: 2560 events read in total (297ms).
[09:50:18.490] <TB3> INFO: Test took 1160ms.
[09:50:18.796] <TB3> INFO: Expecting 2560 events.
[09:50:19.652] <TB3> INFO: 2560 events read in total (299ms).
[09:50:19.652] <TB3> INFO: Test took 1161ms.
[09:50:19.958] <TB3> INFO: Expecting 2560 events.
[09:50:20.812] <TB3> INFO: 2560 events read in total (297ms).
[09:50:20.812] <TB3> INFO: Test took 1160ms.
[09:50:21.118] <TB3> INFO: Expecting 2560 events.
[09:50:21.976] <TB3> INFO: 2560 events read in total (301ms).
[09:50:21.976] <TB3> INFO: Test took 1164ms.
[09:50:22.282] <TB3> INFO: Expecting 2560 events.
[09:50:23.139] <TB3> INFO: 2560 events read in total (300ms).
[09:50:23.139] <TB3> INFO: Test took 1162ms.
[09:50:23.445] <TB3> INFO: Expecting 2560 events.
[09:50:24.298] <TB3> INFO: 2560 events read in total (296ms).
[09:50:24.299] <TB3> INFO: Test took 1160ms.
[09:50:24.605] <TB3> INFO: Expecting 2560 events.
[09:50:25.460] <TB3> INFO: 2560 events read in total (298ms).
[09:50:25.460] <TB3> INFO: Test took 1161ms.
[09:50:25.465] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:50:25.766] <TB3> INFO: Expecting 655360 events.
[09:50:39.838] <TB3> INFO: 655360 events read in total (13515ms).
[09:50:39.849] <TB3> INFO: Expecting 655360 events.
[09:50:53.733] <TB3> INFO: 655360 events read in total (13481ms).
[09:50:53.750] <TB3> INFO: Expecting 655360 events.
[09:51:07.534] <TB3> INFO: 655360 events read in total (13383ms).
[09:51:07.555] <TB3> INFO: Expecting 655360 events.
[09:51:21.396] <TB3> INFO: 655360 events read in total (13439ms).
[09:51:21.423] <TB3> INFO: Expecting 655360 events.
[09:51:35.398] <TB3> INFO: 655360 events read in total (13573ms).
[09:51:35.430] <TB3> INFO: Expecting 655360 events.
[09:51:49.327] <TB3> INFO: 655360 events read in total (13494ms).
[09:51:49.360] <TB3> INFO: Expecting 655360 events.
[09:52:03.317] <TB3> INFO: 655360 events read in total (13554ms).
[09:52:03.354] <TB3> INFO: Expecting 655360 events.
[09:52:17.227] <TB3> INFO: 655360 events read in total (13471ms).
[09:52:17.272] <TB3> INFO: Expecting 655360 events.
[09:52:31.098] <TB3> INFO: 655360 events read in total (13424ms).
[09:52:31.146] <TB3> INFO: Expecting 655360 events.
[09:52:45.042] <TB3> INFO: 655360 events read in total (13494ms).
[09:52:45.095] <TB3> INFO: Expecting 655360 events.
[09:52:58.936] <TB3> INFO: 655360 events read in total (13439ms).
[09:52:59.009] <TB3> INFO: Expecting 655360 events.
[09:53:12.913] <TB3> INFO: 655360 events read in total (13503ms).
[09:53:12.975] <TB3> INFO: Expecting 655360 events.
[09:53:26.797] <TB3> INFO: 655360 events read in total (13419ms).
[09:53:26.862] <TB3> INFO: Expecting 655360 events.
[09:53:40.683] <TB3> INFO: 655360 events read in total (13419ms).
[09:53:40.787] <TB3> INFO: Expecting 655360 events.
[09:53:54.625] <TB3> INFO: 655360 events read in total (13436ms).
[09:53:54.696] <TB3> INFO: Expecting 655360 events.
[09:54:08.591] <TB3> INFO: 655360 events read in total (13492ms).
[09:54:08.665] <TB3> INFO: Test took 223200ms.
[09:54:08.747] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:54:08.975] <TB3> INFO: Expecting 655360 events.
[09:54:22.923] <TB3> INFO: 655360 events read in total (13391ms).
[09:54:22.936] <TB3> INFO: Expecting 655360 events.
[09:54:36.839] <TB3> INFO: 655360 events read in total (13501ms).
[09:54:36.853] <TB3> INFO: Expecting 655360 events.
[09:54:50.693] <TB3> INFO: 655360 events read in total (13437ms).
[09:54:50.715] <TB3> INFO: Expecting 655360 events.
[09:55:04.514] <TB3> INFO: 655360 events read in total (13397ms).
[09:55:04.537] <TB3> INFO: Expecting 655360 events.
[09:55:17.785] <TB3> INFO: 655360 events read in total (12845ms).
[09:55:17.814] <TB3> INFO: Expecting 655360 events.
[09:55:31.683] <TB3> INFO: 655360 events read in total (13466ms).
[09:55:31.717] <TB3> INFO: Expecting 655360 events.
[09:55:45.588] <TB3> INFO: 655360 events read in total (13469ms).
[09:55:45.627] <TB3> INFO: Expecting 655360 events.
[09:55:59.584] <TB3> INFO: 655360 events read in total (13555ms).
[09:55:59.627] <TB3> INFO: Expecting 655360 events.
[09:56:13.249] <TB3> INFO: 655360 events read in total (13220ms).
[09:56:13.300] <TB3> INFO: Expecting 655360 events.
[09:56:27.195] <TB3> INFO: 655360 events read in total (13493ms).
[09:56:27.253] <TB3> INFO: Expecting 655360 events.
[09:56:41.095] <TB3> INFO: 655360 events read in total (13439ms).
[09:56:41.157] <TB3> INFO: Expecting 655360 events.
[09:56:54.799] <TB3> INFO: 655360 events read in total (13239ms).
[09:56:54.867] <TB3> INFO: Expecting 655360 events.
[09:57:08.551] <TB3> INFO: 655360 events read in total (13281ms).
[09:57:08.622] <TB3> INFO: Expecting 655360 events.
[09:57:22.522] <TB3> INFO: 655360 events read in total (13498ms).
[09:57:22.590] <TB3> INFO: Expecting 655360 events.
[09:57:36.399] <TB3> INFO: 655360 events read in total (13407ms).
[09:57:36.475] <TB3> INFO: Expecting 655360 events.
[09:57:50.498] <TB3> INFO: 655360 events read in total (13621ms).
[09:57:50.589] <TB3> INFO: Test took 221842ms.
[09:57:50.809] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.818] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.825] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.833] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.842] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.849] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.857] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.865] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.872] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.879] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[09:57:50.886] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[09:57:50.893] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[09:57:50.900] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[09:57:50.906] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.913] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[09:57:50.921] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.929] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.936] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.944] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.951] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:50.957] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:57:51.015] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:57:51.016] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:57:51.016] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:57:51.016] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:57:51.017] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:57:51.017] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:57:51.017] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:57:51.017] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:57:51.017] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:57:51.018] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:57:51.018] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:57:51.018] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:57:51.018] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:57:51.019] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:57:51.041] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:57:51.041] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:57:51.334] <TB3> INFO: Expecting 41600 events.
[09:57:54.479] <TB3> INFO: 41600 events read in total (2589ms).
[09:57:54.480] <TB3> INFO: Test took 3435ms.
[09:57:54.958] <TB3> INFO: Expecting 41600 events.
[09:57:57.986] <TB3> INFO: 41600 events read in total (2471ms).
[09:57:57.987] <TB3> INFO: Test took 3320ms.
[09:57:58.448] <TB3> INFO: Expecting 41600 events.
[09:58:01.574] <TB3> INFO: 41600 events read in total (2569ms).
[09:58:01.574] <TB3> INFO: Test took 3399ms.
[09:58:01.766] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:01.859] <TB3> INFO: Expecting 2560 events.
[09:58:02.725] <TB3> INFO: 2560 events read in total (308ms).
[09:58:02.725] <TB3> INFO: Test took 959ms.
[09:58:02.731] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:03.030] <TB3> INFO: Expecting 2560 events.
[09:58:03.887] <TB3> INFO: 2560 events read in total (300ms).
[09:58:03.888] <TB3> INFO: Test took 1157ms.
[09:58:03.891] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:04.194] <TB3> INFO: Expecting 2560 events.
[09:58:05.047] <TB3> INFO: 2560 events read in total (296ms).
[09:58:05.047] <TB3> INFO: Test took 1156ms.
[09:58:05.049] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:05.355] <TB3> INFO: Expecting 2560 events.
[09:58:06.216] <TB3> INFO: 2560 events read in total (305ms).
[09:58:06.216] <TB3> INFO: Test took 1167ms.
[09:58:06.219] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:06.522] <TB3> INFO: Expecting 2560 events.
[09:58:07.378] <TB3> INFO: 2560 events read in total (299ms).
[09:58:07.379] <TB3> INFO: Test took 1160ms.
[09:58:07.382] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:07.685] <TB3> INFO: Expecting 2560 events.
[09:58:08.546] <TB3> INFO: 2560 events read in total (305ms).
[09:58:08.546] <TB3> INFO: Test took 1164ms.
[09:58:08.549] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:08.852] <TB3> INFO: Expecting 2560 events.
[09:58:09.713] <TB3> INFO: 2560 events read in total (304ms).
[09:58:09.714] <TB3> INFO: Test took 1165ms.
[09:58:09.717] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:10.021] <TB3> INFO: Expecting 2560 events.
[09:58:10.876] <TB3> INFO: 2560 events read in total (299ms).
[09:58:10.877] <TB3> INFO: Test took 1160ms.
[09:58:10.879] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:11.184] <TB3> INFO: Expecting 2560 events.
[09:58:12.040] <TB3> INFO: 2560 events read in total (299ms).
[09:58:12.041] <TB3> INFO: Test took 1162ms.
[09:58:12.044] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:12.346] <TB3> INFO: Expecting 2560 events.
[09:58:13.203] <TB3> INFO: 2560 events read in total (301ms).
[09:58:13.204] <TB3> INFO: Test took 1160ms.
[09:58:13.206] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:13.511] <TB3> INFO: Expecting 2560 events.
[09:58:14.368] <TB3> INFO: 2560 events read in total (300ms).
[09:58:14.369] <TB3> INFO: Test took 1163ms.
[09:58:14.372] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:14.675] <TB3> INFO: Expecting 2560 events.
[09:58:15.534] <TB3> INFO: 2560 events read in total (302ms).
[09:58:15.534] <TB3> INFO: Test took 1162ms.
[09:58:15.537] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:15.841] <TB3> INFO: Expecting 2560 events.
[09:58:16.694] <TB3> INFO: 2560 events read in total (296ms).
[09:58:16.695] <TB3> INFO: Test took 1158ms.
[09:58:16.697] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:17.002] <TB3> INFO: Expecting 2560 events.
[09:58:17.856] <TB3> INFO: 2560 events read in total (298ms).
[09:58:17.856] <TB3> INFO: Test took 1159ms.
[09:58:17.859] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:18.164] <TB3> INFO: Expecting 2560 events.
[09:58:19.025] <TB3> INFO: 2560 events read in total (304ms).
[09:58:19.026] <TB3> INFO: Test took 1167ms.
[09:58:19.029] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:19.332] <TB3> INFO: Expecting 2560 events.
[09:58:20.188] <TB3> INFO: 2560 events read in total (300ms).
[09:58:20.189] <TB3> INFO: Test took 1160ms.
[09:58:20.192] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:20.495] <TB3> INFO: Expecting 2560 events.
[09:58:21.351] <TB3> INFO: 2560 events read in total (299ms).
[09:58:21.351] <TB3> INFO: Test took 1159ms.
[09:58:21.354] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:21.658] <TB3> INFO: Expecting 2560 events.
[09:58:22.519] <TB3> INFO: 2560 events read in total (304ms).
[09:58:22.520] <TB3> INFO: Test took 1167ms.
[09:58:22.522] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:22.827] <TB3> INFO: Expecting 2560 events.
[09:58:23.691] <TB3> INFO: 2560 events read in total (307ms).
[09:58:23.691] <TB3> INFO: Test took 1169ms.
[09:58:23.694] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:23.999] <TB3> INFO: Expecting 2560 events.
[09:58:24.855] <TB3> INFO: 2560 events read in total (299ms).
[09:58:24.856] <TB3> INFO: Test took 1162ms.
[09:58:24.858] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:25.163] <TB3> INFO: Expecting 2560 events.
[09:58:26.018] <TB3> INFO: 2560 events read in total (298ms).
[09:58:26.018] <TB3> INFO: Test took 1160ms.
[09:58:26.021] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:26.326] <TB3> INFO: Expecting 2560 events.
[09:58:27.185] <TB3> INFO: 2560 events read in total (303ms).
[09:58:27.186] <TB3> INFO: Test took 1166ms.
[09:58:27.189] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:27.491] <TB3> INFO: Expecting 2560 events.
[09:58:28.346] <TB3> INFO: 2560 events read in total (298ms).
[09:58:28.346] <TB3> INFO: Test took 1157ms.
[09:58:28.349] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:28.653] <TB3> INFO: Expecting 2560 events.
[09:58:29.508] <TB3> INFO: 2560 events read in total (298ms).
[09:58:29.508] <TB3> INFO: Test took 1159ms.
[09:58:29.511] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:29.815] <TB3> INFO: Expecting 2560 events.
[09:58:30.673] <TB3> INFO: 2560 events read in total (301ms).
[09:58:30.673] <TB3> INFO: Test took 1162ms.
[09:58:30.677] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:30.980] <TB3> INFO: Expecting 2560 events.
[09:58:31.834] <TB3> INFO: 2560 events read in total (297ms).
[09:58:31.834] <TB3> INFO: Test took 1157ms.
[09:58:31.839] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:32.140] <TB3> INFO: Expecting 2560 events.
[09:58:32.997] <TB3> INFO: 2560 events read in total (300ms).
[09:58:32.997] <TB3> INFO: Test took 1158ms.
[09:58:33.000] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:33.304] <TB3> INFO: Expecting 2560 events.
[09:58:34.164] <TB3> INFO: 2560 events read in total (303ms).
[09:58:34.165] <TB3> INFO: Test took 1165ms.
[09:58:34.168] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:34.470] <TB3> INFO: Expecting 2560 events.
[09:58:35.327] <TB3> INFO: 2560 events read in total (300ms).
[09:58:35.327] <TB3> INFO: Test took 1159ms.
[09:58:35.332] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:35.633] <TB3> INFO: Expecting 2560 events.
[09:58:36.495] <TB3> INFO: 2560 events read in total (305ms).
[09:58:36.495] <TB3> INFO: Test took 1163ms.
[09:58:36.499] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:36.801] <TB3> INFO: Expecting 2560 events.
[09:58:37.657] <TB3> INFO: 2560 events read in total (299ms).
[09:58:37.658] <TB3> INFO: Test took 1159ms.
[09:58:37.662] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:58:37.962] <TB3> INFO: Expecting 2560 events.
[09:58:38.816] <TB3> INFO: 2560 events read in total (297ms).
[09:58:38.816] <TB3> INFO: Test took 1155ms.
[09:58:39.310] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 523 seconds
[09:58:39.310] <TB3> INFO: PH scale (per ROC): 68 72 77 69 76 67 78 77 77 79 67 74 69 70 66 73
[09:58:39.310] <TB3> INFO: PH offset (per ROC): 179 157 172 179 182 179 163 167 176 177 186 178 181 175 173 161
[09:58:39.314] <TB3> INFO: Decoding statistics:
[09:58:39.314] <TB3> INFO: General information:
[09:58:39.314] <TB3> INFO: 16bit words read: 86920
[09:58:39.314] <TB3> INFO: valid events total: 10240
[09:58:39.314] <TB3> INFO: empty events: 7740
[09:58:39.314] <TB3> INFO: valid events with pixels: 2500
[09:58:39.314] <TB3> INFO: valid pixel hits: 2500
[09:58:39.314] <TB3> INFO: Event errors: 0
[09:58:39.314] <TB3> INFO: start marker: 0
[09:58:39.314] <TB3> INFO: stop marker: 0
[09:58:39.314] <TB3> INFO: overflow: 0
[09:58:39.314] <TB3> INFO: invalid 5bit words: 0
[09:58:39.314] <TB3> INFO: invalid XOR eye diagram: 0
[09:58:39.314] <TB3> INFO: frame (failed synchr.): 0
[09:58:39.314] <TB3> INFO: idle data (no TBM trl): 0
[09:58:39.314] <TB3> INFO: no data (only TBM hdr): 0
[09:58:39.314] <TB3> INFO: TBM errors: 0
[09:58:39.314] <TB3> INFO: flawed TBM headers: 0
[09:58:39.314] <TB3> INFO: flawed TBM trailers: 0
[09:58:39.314] <TB3> INFO: event ID mismatches: 0
[09:58:39.314] <TB3> INFO: ROC errors: 0
[09:58:39.315] <TB3> INFO: missing ROC header(s): 0
[09:58:39.315] <TB3> INFO: misplaced readback start: 0
[09:58:39.315] <TB3> INFO: Pixel decoding errors: 0
[09:58:39.315] <TB3> INFO: pixel data incomplete: 0
[09:58:39.315] <TB3> INFO: pixel address: 0
[09:58:39.315] <TB3> INFO: pulse height fill bit: 0
[09:58:39.315] <TB3> INFO: buffer corruption: 0
[09:58:39.529] <TB3> INFO: ######################################################################
[09:58:39.529] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:58:39.529] <TB3> INFO: ######################################################################
[09:58:39.539] <TB3> INFO: scanning low vcal = 10
[09:58:39.817] <TB3> INFO: Expecting 41600 events.
[09:58:43.206] <TB3> INFO: 41600 events read in total (2833ms).
[09:58:43.206] <TB3> INFO: Test took 3666ms.
[09:58:43.208] <TB3> INFO: scanning low vcal = 20
[09:58:43.506] <TB3> INFO: Expecting 41600 events.
[09:58:46.933] <TB3> INFO: 41600 events read in total (2870ms).
[09:58:46.933] <TB3> INFO: Test took 3724ms.
[09:58:46.936] <TB3> INFO: scanning low vcal = 30
[09:58:47.225] <TB3> INFO: Expecting 41600 events.
[09:58:50.635] <TB3> INFO: 41600 events read in total (2853ms).
[09:58:50.636] <TB3> INFO: Test took 3699ms.
[09:58:50.638] <TB3> INFO: scanning low vcal = 40
[09:58:50.934] <TB3> INFO: Expecting 41600 events.
[09:58:54.750] <TB3> INFO: 41600 events read in total (3259ms).
[09:58:54.751] <TB3> INFO: Test took 4112ms.
[09:58:54.754] <TB3> INFO: scanning low vcal = 50
[09:58:55.034] <TB3> INFO: Expecting 41600 events.
[09:58:59.036] <TB3> INFO: 41600 events read in total (3446ms).
[09:58:59.036] <TB3> INFO: Test took 4282ms.
[09:58:59.040] <TB3> INFO: scanning low vcal = 60
[09:58:59.325] <TB3> INFO: Expecting 41600 events.
[09:59:03.263] <TB3> INFO: 41600 events read in total (3381ms).
[09:59:03.264] <TB3> INFO: Test took 4224ms.
[09:59:03.268] <TB3> INFO: scanning low vcal = 70
[09:59:03.530] <TB3> INFO: Expecting 41600 events.
[09:59:07.471] <TB3> INFO: 41600 events read in total (3384ms).
[09:59:07.472] <TB3> INFO: Test took 4204ms.
[09:59:07.475] <TB3> INFO: scanning low vcal = 80
[09:59:07.739] <TB3> INFO: Expecting 41600 events.
[09:59:11.702] <TB3> INFO: 41600 events read in total (3406ms).
[09:59:11.702] <TB3> INFO: Test took 4227ms.
[09:59:11.706] <TB3> INFO: scanning low vcal = 90
[09:59:11.983] <TB3> INFO: Expecting 41600 events.
[09:59:15.949] <TB3> INFO: 41600 events read in total (3409ms).
[09:59:15.950] <TB3> INFO: Test took 4244ms.
[09:59:15.954] <TB3> INFO: scanning low vcal = 100
[09:59:16.215] <TB3> INFO: Expecting 41600 events.
[09:59:20.181] <TB3> INFO: 41600 events read in total (3409ms).
[09:59:20.182] <TB3> INFO: Test took 4228ms.
[09:59:20.185] <TB3> INFO: scanning low vcal = 110
[09:59:20.446] <TB3> INFO: Expecting 41600 events.
[09:59:24.411] <TB3> INFO: 41600 events read in total (3408ms).
[09:59:24.412] <TB3> INFO: Test took 4227ms.
[09:59:24.415] <TB3> INFO: scanning low vcal = 120
[09:59:24.692] <TB3> INFO: Expecting 41600 events.
[09:59:28.682] <TB3> INFO: 41600 events read in total (3433ms).
[09:59:28.682] <TB3> INFO: Test took 4267ms.
[09:59:28.686] <TB3> INFO: scanning low vcal = 130
[09:59:28.957] <TB3> INFO: Expecting 41600 events.
[09:59:32.946] <TB3> INFO: 41600 events read in total (3433ms).
[09:59:32.947] <TB3> INFO: Test took 4261ms.
[09:59:32.951] <TB3> INFO: scanning low vcal = 140
[09:59:33.231] <TB3> INFO: Expecting 41600 events.
[09:59:37.180] <TB3> INFO: 41600 events read in total (3392ms).
[09:59:37.180] <TB3> INFO: Test took 4229ms.
[09:59:37.184] <TB3> INFO: scanning low vcal = 150
[09:59:37.445] <TB3> INFO: Expecting 41600 events.
[09:59:41.471] <TB3> INFO: 41600 events read in total (3470ms).
[09:59:41.472] <TB3> INFO: Test took 4288ms.
[09:59:41.476] <TB3> INFO: scanning low vcal = 160
[09:59:41.754] <TB3> INFO: Expecting 41600 events.
[09:59:45.713] <TB3> INFO: 41600 events read in total (3402ms).
[09:59:45.713] <TB3> INFO: Test took 4237ms.
[09:59:45.717] <TB3> INFO: scanning low vcal = 170
[09:59:45.990] <TB3> INFO: Expecting 41600 events.
[09:59:49.921] <TB3> INFO: 41600 events read in total (3374ms).
[09:59:49.923] <TB3> INFO: Test took 4206ms.
[09:59:49.928] <TB3> INFO: scanning low vcal = 180
[09:59:50.193] <TB3> INFO: Expecting 41600 events.
[09:59:54.211] <TB3> INFO: 41600 events read in total (3462ms).
[09:59:54.212] <TB3> INFO: Test took 4283ms.
[09:59:54.215] <TB3> INFO: scanning low vcal = 190
[09:59:54.494] <TB3> INFO: Expecting 41600 events.
[09:59:58.436] <TB3> INFO: 41600 events read in total (3385ms).
[09:59:58.437] <TB3> INFO: Test took 4222ms.
[09:59:58.440] <TB3> INFO: scanning low vcal = 200
[09:59:58.722] <TB3> INFO: Expecting 41600 events.
[10:00:02.726] <TB3> INFO: 41600 events read in total (3447ms).
[10:00:02.727] <TB3> INFO: Test took 4287ms.
[10:00:02.730] <TB3> INFO: scanning low vcal = 210
[10:00:03.066] <TB3> INFO: Expecting 41600 events.
[10:00:06.994] <TB3> INFO: 41600 events read in total (3371ms).
[10:00:06.995] <TB3> INFO: Test took 4265ms.
[10:00:06.998] <TB3> INFO: scanning low vcal = 220
[10:00:07.274] <TB3> INFO: Expecting 41600 events.
[10:00:11.199] <TB3> INFO: 41600 events read in total (3369ms).
[10:00:11.200] <TB3> INFO: Test took 4202ms.
[10:00:11.203] <TB3> INFO: scanning low vcal = 230
[10:00:11.467] <TB3> INFO: Expecting 41600 events.
[10:00:15.395] <TB3> INFO: 41600 events read in total (3371ms).
[10:00:15.396] <TB3> INFO: Test took 4193ms.
[10:00:15.399] <TB3> INFO: scanning low vcal = 240
[10:00:15.665] <TB3> INFO: Expecting 41600 events.
[10:00:19.631] <TB3> INFO: 41600 events read in total (3409ms).
[10:00:19.632] <TB3> INFO: Test took 4232ms.
[10:00:19.636] <TB3> INFO: scanning low vcal = 250
[10:00:19.905] <TB3> INFO: Expecting 41600 events.
[10:00:23.823] <TB3> INFO: 41600 events read in total (3361ms).
[10:00:23.824] <TB3> INFO: Test took 4188ms.
[10:00:23.830] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[10:00:24.097] <TB3> INFO: Expecting 41600 events.
[10:00:28.045] <TB3> INFO: 41600 events read in total (3391ms).
[10:00:28.046] <TB3> INFO: Test took 4216ms.
[10:00:28.049] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[10:00:28.315] <TB3> INFO: Expecting 41600 events.
[10:00:32.357] <TB3> INFO: 41600 events read in total (3485ms).
[10:00:32.358] <TB3> INFO: Test took 4309ms.
[10:00:32.362] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[10:00:32.707] <TB3> INFO: Expecting 41600 events.
[10:00:36.687] <TB3> INFO: 41600 events read in total (3423ms).
[10:00:36.688] <TB3> INFO: Test took 4326ms.
[10:00:36.692] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[10:00:37.038] <TB3> INFO: Expecting 41600 events.
[10:00:40.984] <TB3> INFO: 41600 events read in total (3389ms).
[10:00:40.985] <TB3> INFO: Test took 4293ms.
[10:00:40.988] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:00:41.249] <TB3> INFO: Expecting 41600 events.
[10:00:45.242] <TB3> INFO: 41600 events read in total (3436ms).
[10:00:45.242] <TB3> INFO: Test took 4254ms.
[10:00:45.737] <TB3> INFO: PixTestGainPedestal::measure() done
[10:01:17.837] <TB3> INFO: PixTestGainPedestal::fit() done
[10:01:17.837] <TB3> INFO: non-linearity mean: 0.950 0.950 0.949 0.955 0.952 0.946 0.947 0.953 0.965 0.963 0.948 0.953 0.946 0.953 0.950 0.948
[10:01:17.838] <TB3> INFO: non-linearity RMS: 0.008 0.009 0.007 0.007 0.007 0.007 0.008 0.007 0.004 0.005 0.008 0.007 0.008 0.007 0.007 0.007
[10:01:17.852] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:01:17.871] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:01:17.889] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:01:17.907] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:01:17.925] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:01:17.943] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:01:17.978] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:01:17.998] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:01:18.017] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:01:18.035] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:01:18.053] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:01:18.071] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:01:18.089] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:01:18.108] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:01:18.130] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:01:18.157] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:01:18.182] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[10:01:18.182] <TB3> INFO: Decoding statistics:
[10:01:18.182] <TB3> INFO: General information:
[10:01:18.182] <TB3> INFO: 16bit words read: 2662400
[10:01:18.182] <TB3> INFO: valid events total: 166400
[10:01:18.182] <TB3> INFO: empty events: 0
[10:01:18.182] <TB3> INFO: valid events with pixels: 166400
[10:01:18.182] <TB3> INFO: valid pixel hits: 665600
[10:01:18.182] <TB3> INFO: Event errors: 0
[10:01:18.182] <TB3> INFO: start marker: 0
[10:01:18.182] <TB3> INFO: stop marker: 0
[10:01:18.182] <TB3> INFO: overflow: 0
[10:01:18.182] <TB3> INFO: invalid 5bit words: 0
[10:01:18.182] <TB3> INFO: invalid XOR eye diagram: 0
[10:01:18.182] <TB3> INFO: frame (failed synchr.): 0
[10:01:18.182] <TB3> INFO: idle data (no TBM trl): 0
[10:01:18.182] <TB3> INFO: no data (only TBM hdr): 0
[10:01:18.182] <TB3> INFO: TBM errors: 0
[10:01:18.182] <TB3> INFO: flawed TBM headers: 0
[10:01:18.182] <TB3> INFO: flawed TBM trailers: 0
[10:01:18.182] <TB3> INFO: event ID mismatches: 0
[10:01:18.182] <TB3> INFO: ROC errors: 0
[10:01:18.182] <TB3> INFO: missing ROC header(s): 0
[10:01:18.182] <TB3> INFO: misplaced readback start: 0
[10:01:18.182] <TB3> INFO: Pixel decoding errors: 0
[10:01:18.182] <TB3> INFO: pixel data incomplete: 0
[10:01:18.182] <TB3> INFO: pixel address: 0
[10:01:18.182] <TB3> INFO: pulse height fill bit: 0
[10:01:18.182] <TB3> INFO: buffer corruption: 0
[10:01:18.191] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:01:18.227] <TB3> INFO: ######################################################################
[10:01:18.227] <TB3> INFO: PixTestReadback::doTest()
[10:01:18.227] <TB3> INFO: ######################################################################
[10:01:18.227] <TB3> INFO: ----------------------------------------------------------------------
[10:01:18.227] <TB3> INFO: PixTestReadback::CalibrateVd()
[10:01:18.227] <TB3> INFO: ----------------------------------------------------------------------
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:01:27.804] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:01:27.805] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:01:27.805] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:01:27.805] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:01:27.805] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:01:27.851] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:01:27.851] <TB3> INFO: ----------------------------------------------------------------------
[10:01:27.851] <TB3> INFO: PixTestReadback::CalibrateVa()
[10:01:27.851] <TB3> INFO: ----------------------------------------------------------------------
[10:01:37.395] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:01:37.396] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:01:37.397] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:01:37.397] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:01:37.397] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:01:37.397] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:01:37.444] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:01:37.444] <TB3> INFO: ----------------------------------------------------------------------
[10:01:37.444] <TB3> INFO: PixTestReadback::readbackVbg()
[10:01:37.444] <TB3> INFO: ----------------------------------------------------------------------
[10:01:44.847] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:01:44.847] <TB3> INFO: ----------------------------------------------------------------------
[10:01:44.847] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[10:01:44.848] <TB3> INFO: ----------------------------------------------------------------------
[10:01:44.848] <TB3> INFO: Vbg will be calibrated using Vd calibration
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.6calibrated Vbg = 1.21991 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 140calibrated Vbg = 1.21713 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 140.4calibrated Vbg = 1.22088 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 144.3calibrated Vbg = 1.23193 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156calibrated Vbg = 1.23104 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.8calibrated Vbg = 1.2281 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.4calibrated Vbg = 1.2315 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.4calibrated Vbg = 1.23385 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.7calibrated Vbg = 1.23618 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153calibrated Vbg = 1.22671 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 152.9calibrated Vbg = 1.22417 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 150calibrated Vbg = 1.22016 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.1calibrated Vbg = 1.21146 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151calibrated Vbg = 1.217 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 143calibrated Vbg = 1.21372 :::*/*/*/*/
[10:01:44.848] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 144.2calibrated Vbg = 1.21455 :::*/*/*/*/
[10:01:44.852] <TB3> INFO: ----------------------------------------------------------------------
[10:01:44.852] <TB3> INFO: PixTestReadback::CalibrateIa()
[10:01:44.852] <TB3> INFO: ----------------------------------------------------------------------
[10:02:45.798] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L523> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:04:20.902] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C0.dat
[10:04:20.903] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C1.dat
[10:04:20.903] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C2.dat
[10:04:20.903] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C3.dat
[10:04:20.903] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C4.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C5.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C6.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C7.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C8.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C9.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C10.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C11.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C12.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C13.dat
[10:04:20.904] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C14.dat
[10:04:20.905] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2338_FullQualification_2016-09-26_10h32m_1474878746//000_FulltestPxar_m20//readbackCal_C15.dat
[10:04:20.950] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:04:20.950] <TB3> INFO: PixTestReadback::doTest() done
[10:04:20.950] <TB3> INFO: Decoding statistics:
[10:04:20.950] <TB3> INFO: General information:
[10:04:20.950] <TB3> INFO: 16bit words read: 1024
[10:04:20.950] <TB3> INFO: valid events total: 128
[10:04:20.950] <TB3> INFO: empty events: 128
[10:04:20.950] <TB3> INFO: valid events with pixels: 0
[10:04:20.950] <TB3> INFO: valid pixel hits: 0
[10:04:20.950] <TB3> INFO: Event errors: 0
[10:04:20.950] <TB3> INFO: start marker: 0
[10:04:20.950] <TB3> INFO: stop marker: 0
[10:04:20.950] <TB3> INFO: overflow: 0
[10:04:20.950] <TB3> INFO: invalid 5bit words: 0
[10:04:20.950] <TB3> INFO: invalid XOR eye diagram: 0
[10:04:20.950] <TB3> INFO: frame (failed synchr.): 0
[10:04:20.950] <TB3> INFO: idle data (no TBM trl): 0
[10:04:20.950] <TB3> INFO: no data (only TBM hdr): 0
[10:04:20.951] <TB3> INFO: TBM errors: 0
[10:04:20.951] <TB3> INFO: flawed TBM headers: 0
[10:04:20.951] <TB3> INFO: flawed TBM trailers: 0
[10:04:20.951] <TB3> INFO: event ID mismatches: 0
[10:04:20.951] <TB3> INFO: ROC errors: 0
[10:04:20.951] <TB3> INFO: missing ROC header(s): 0
[10:04:20.951] <TB3> INFO: misplaced readback start: 0
[10:04:20.951] <TB3> INFO: Pixel decoding errors: 0
[10:04:20.951] <TB3> INFO: pixel data incomplete: 0
[10:04:20.951] <TB3> INFO: pixel address: 0
[10:04:20.951] <TB3> INFO: pulse height fill bit: 0
[10:04:20.951] <TB3> INFO: buffer corruption: 0
[10:04:20.968] <TB3> INFO: Decoding statistics:
[10:04:20.968] <TB3> INFO: General information:
[10:04:20.968] <TB3> INFO: 16bit words read: 2750344
[10:04:20.969] <TB3> INFO: valid events total: 176768
[10:04:20.969] <TB3> INFO: empty events: 7868
[10:04:20.969] <TB3> INFO: valid events with pixels: 168900
[10:04:20.969] <TB3> INFO: valid pixel hits: 668100
[10:04:20.969] <TB3> INFO: Event errors: 0
[10:04:20.969] <TB3> INFO: start marker: 0
[10:04:20.969] <TB3> INFO: stop marker: 0
[10:04:20.969] <TB3> INFO: overflow: 0
[10:04:20.969] <TB3> INFO: invalid 5bit words: 0
[10:04:20.969] <TB3> INFO: invalid XOR eye diagram: 0
[10:04:20.969] <TB3> INFO: frame (failed synchr.): 0
[10:04:20.969] <TB3> INFO: idle data (no TBM trl): 0
[10:04:20.969] <TB3> INFO: no data (only TBM hdr): 0
[10:04:20.969] <TB3> INFO: TBM errors: 0
[10:04:20.969] <TB3> INFO: flawed TBM headers: 0
[10:04:20.969] <TB3> INFO: flawed TBM trailers: 0
[10:04:20.969] <TB3> INFO: event ID mismatches: 0
[10:04:20.969] <TB3> INFO: ROC errors: 0
[10:04:20.969] <TB3> INFO: missing ROC header(s): 0
[10:04:20.969] <TB3> INFO: misplaced readback start: 0
[10:04:20.969] <TB3> INFO: Pixel decoding errors: 0
[10:04:20.969] <TB3> INFO: pixel data incomplete: 0
[10:04:20.969] <TB3> INFO: pixel address: 0
[10:04:20.969] <TB3> INFO: pulse height fill bit: 0
[10:04:20.969] <TB3> INFO: buffer corruption: 0
[10:04:20.969] <TB3> INFO: enter test to run
[10:04:20.969] <TB3> INFO: test: exit no parameter change
[10:04:21.204] <TB3> QUIET: Connection to board 170 closed.
[10:04:21.284] <TB3> INFO: pXar: this is the end, my friend