[09:03:37.931] <TB2> INFO: *** Welcome to pxar ***
[09:03:37.931] <TB2> INFO: *** Today: 2015/11/20
[09:03:38.034] <TB2> INFO: *** Version: 9da6-dirty
[09:03:38.034] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C15.dat
[09:03:38.035] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:03:38.035] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//defaultMaskFile.dat
[09:03:38.036] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters_C15.dat
[09:03:38.141] <TB2> INFO: clk: 4
[09:03:38.141] <TB2> INFO: ctr: 4
[09:03:38.141] <TB2> INFO: sda: 19
[09:03:38.141] <TB2> INFO: tin: 9
[09:03:38.141] <TB2> INFO: level: 15
[09:03:38.141] <TB2> INFO: triggerdelay: 0
[09:03:38.142] <TB2> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[09:03:38.142] <TB2> INFO: Log level: INFO
[09:03:38.149] <TB2> INFO: Found DTB DTB_WXC55Z
[09:03:38.160] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[09:03:38.163] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[09:03:38.167] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[09:03:39.715] <TB2> INFO: DUT info:
[09:03:39.715] <TB2> INFO: The DUT currently contains the following objects:
[09:03:39.715] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[09:03:39.715] <TB2> INFO: TBM Core alpha (0): 7 registers set
[09:03:39.715] <TB2> INFO: TBM Core beta (1): 7 registers set
[09:03:39.715] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:03:39.716] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:39.716] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:40.117] <TB2> INFO: enter 'restricted' command line mode
[09:03:40.117] <TB2> INFO: enter test to run
[09:03:40.117] <TB2> INFO: test: pretest no parameter change
[09:03:40.117] <TB2> INFO: running: pretest
[09:03:40.125] <TB2> INFO: ######################################################################
[09:03:40.125] <TB2> INFO: PixTestPretest::doTest()
[09:03:40.125] <TB2> INFO: ######################################################################
[09:03:40.126] <TB2> INFO: ----------------------------------------------------------------------
[09:03:40.126] <TB2> INFO: PixTestPretest::programROC()
[09:03:40.126] <TB2> INFO: ----------------------------------------------------------------------
[09:03:58.143] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:03:58.143] <TB2> INFO: IA differences per ROC: 16.1 16.9 17.7 16.9 16.9 17.7 16.9 16.9 16.1 14.5 15.3 19.3 17.7 17.7 16.9 18.5
[09:03:58.225] <TB2> INFO: ----------------------------------------------------------------------
[09:03:58.225] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:03:58.225] <TB2> INFO: ----------------------------------------------------------------------
[09:04:04.518] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 397.1 mA = 24.8188 mA/ROC
[09:04:04.518] <TB2> INFO: i(loss) [mA/ROC]: 20.9 20.9 20.9 20.1 20.1 20.1 20.9 20.1 20.1 20.1 20.1 20.1 20.1 20.9 20.9 20.1
[09:04:04.553] <TB2> INFO: ----------------------------------------------------------------------
[09:04:04.553] <TB2> INFO: PixTestPretest::findTiming()
[09:04:04.553] <TB2> INFO: ----------------------------------------------------------------------
[09:04:04.554] <TB2> INFO: PixTestCmd::init()
[09:04:05.152] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[09:06:24.432] <TB2> INFO: TBM phases: 160MHz: 4, 400MHz: 2, TBM delays: ROC(0/1):3, header/trailer: 1, token: 0
[09:06:24.432] <TB2> INFO: (success/tries = 100/100), width = 4
[09:06:24.433] <TB2> INFO: ----------------------------------------------------------------------
[09:06:24.433] <TB2> INFO: PixTestPretest::findWorkingPixel()
[09:06:24.433] <TB2> INFO: ----------------------------------------------------------------------
[09:06:24.572] <TB2> INFO: Expecting 231680 events.
[09:06:33.078] <TB2> INFO: 231680 events read in total (7790ms).
[09:06:33.082] <TB2> INFO: Test took 8646ms.
[09:06:33.372] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:06:33.402] <TB2> INFO: ----------------------------------------------------------------------
[09:06:33.402] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[09:06:33.402] <TB2> INFO: ----------------------------------------------------------------------
[09:06:33.537] <TB2> INFO: Expecting 231680 events.
[09:06:42.078] <TB2> INFO: 231680 events read in total (7826ms).
[09:06:42.083] <TB2> INFO: Test took 8678ms.
[09:06:42.382] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[09:06:42.382] <TB2> INFO: CalDel: 147 156 130 135 145 113 142 122 143 133 127 139 123 129 141 136
[09:06:42.382] <TB2> INFO: VthrComp: 51 51 51 51 51 52 51 51 52 51 51 51 60 51 51 54
[09:06:42.384] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C0.dat
[09:06:42.384] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C1.dat
[09:06:42.384] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C2.dat
[09:06:42.384] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C3.dat
[09:06:42.385] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C4.dat
[09:06:42.385] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C5.dat
[09:06:42.385] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C6.dat
[09:06:42.385] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C7.dat
[09:06:42.385] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C8.dat
[09:06:42.385] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C9.dat
[09:06:42.385] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C10.dat
[09:06:42.385] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C11.dat
[09:06:42.386] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C12.dat
[09:06:42.386] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C13.dat
[09:06:42.386] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C14.dat
[09:06:42.386] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters_C15.dat
[09:06:42.386] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//tbmParameters_C0a.dat
[09:06:42.386] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:06:42.386] <TB2> INFO: PixTestPretest::doTest() done, duration: 182 seconds
[09:06:42.451] <TB2> INFO: enter test to run
[09:06:42.451] <TB2> INFO: test: fulltest no parameter change
[09:06:42.451] <TB2> INFO: running: fulltest
[09:06:42.451] <TB2> INFO: ######################################################################
[09:06:42.451] <TB2> INFO: PixTestFullTest::doTest()
[09:06:42.451] <TB2> INFO: ######################################################################
[09:06:42.452] <TB2> INFO: ######################################################################
[09:06:42.452] <TB2> INFO: PixTestAlive::doTest()
[09:06:42.452] <TB2> INFO: ######################################################################
[09:06:42.454] <TB2> INFO: ----------------------------------------------------------------------
[09:06:42.454] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:06:42.454] <TB2> INFO: ----------------------------------------------------------------------
[09:06:42.765] <TB2> INFO: Expecting 41600 events.
[09:06:47.387] <TB2> INFO: 41600 events read in total (3906ms).
[09:06:47.388] <TB2> INFO: Test took 4933ms.
[09:06:47.393] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:47.679] <TB2> INFO: PixTestAlive::aliveTest() done
[09:06:47.679] <TB2> INFO: number of dead pixels (per ROC): 1 1 2 0 2 0 1 2 3 0 2 9 1 0 4 4
[09:06:47.681] <TB2> INFO: ----------------------------------------------------------------------
[09:06:47.681] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:06:47.681] <TB2> INFO: ----------------------------------------------------------------------
[09:06:47.996] <TB2> INFO: Expecting 41600 events.
[09:06:51.319] <TB2> INFO: 41600 events read in total (2607ms).
[09:06:51.319] <TB2> INFO: Test took 3637ms.
[09:06:51.319] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:51.320] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:06:51.627] <TB2> INFO: PixTestAlive::maskTest() done
[09:06:51.627] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:06:51.629] <TB2> INFO: ----------------------------------------------------------------------
[09:06:51.629] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:06:51.629] <TB2> INFO: ----------------------------------------------------------------------
[09:06:51.958] <TB2> INFO: Expecting 41600 events.
[09:06:56.635] <TB2> INFO: 41600 events read in total (3961ms).
[09:06:56.635] <TB2> INFO: Test took 5005ms.
[09:06:56.641] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:56.932] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[09:06:56.932] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:06:56.932] <TB2> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[09:06:56.932] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:56.932] <TB2> INFO: Decoding statistics:
[09:06:56.932] <TB2> INFO: General information:
[09:06:56.932] <TB2> INFO: 16bit words read: 0
[09:06:56.932] <TB2> INFO: valid events total: 0
[09:06:56.932] <TB2> INFO: empty events: 0
[09:06:56.932] <TB2> INFO: valid events with pixels: 0
[09:06:56.932] <TB2> INFO: valid pixel hits: 0
[09:06:56.932] <TB2> INFO: Event errors: 0
[09:06:56.932] <TB2> INFO: start marker: 0
[09:06:56.932] <TB2> INFO: stop marker: 0
[09:06:56.932] <TB2> INFO: overflow: 0
[09:06:56.932] <TB2> INFO: invalid 5bit words: 0
[09:06:56.932] <TB2> INFO: invalid XOR eye diagram: 0
[09:06:56.932] <TB2> INFO: TBM errors: 0
[09:06:56.932] <TB2> INFO: flawed TBM headers: 0
[09:06:56.932] <TB2> INFO: flawed TBM trailers: 0
[09:06:56.932] <TB2> INFO: event ID mismatches: 0
[09:06:56.932] <TB2> INFO: ROC errors: 0
[09:06:56.932] <TB2> INFO: missing ROC header(s): 0
[09:06:56.932] <TB2> INFO: misplaced readback start: 0
[09:06:56.932] <TB2> INFO: Pixel decoding errors: 0
[09:06:56.932] <TB2> INFO: pixel data incomplete: 0
[09:06:56.932] <TB2> INFO: pixel address: 0
[09:06:56.932] <TB2> INFO: pulse height fill bit: 0
[09:06:56.932] <TB2> INFO: buffer corruption: 0
[09:06:56.940] <TB2> INFO: ######################################################################
[09:06:56.940] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:06:56.940] <TB2> INFO: ######################################################################
[09:06:56.943] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:06:56.995] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:06:56.995] <TB2> INFO: run 1 of 1
[09:06:57.321] <TB2> INFO: Expecting 3120000 events.
[09:07:45.762] <TB2> INFO: 1006745 events read in total (47725ms).
[09:08:33.296] <TB2> INFO: 2000880 events read in total (95259ms).
[09:09:18.065] <TB2> INFO: 2996095 events read in total (140029ms).
[09:09:23.716] <TB2> INFO: 3120000 events read in total (145679ms).
[09:09:23.752] <TB2> INFO: Test took 146757ms.
[09:09:23.825] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:46.944] <TB2> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[09:09:46.944] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:09:46.944] <TB2> INFO: separation cut (per ROC): 146 152 150 147 142 149 142 150 133 141 139 146 154 137 130 165
[09:09:46.944] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:46.944] <TB2> INFO: Decoding statistics:
[09:09:46.944] <TB2> INFO: General information:
[09:09:46.944] <TB2> INFO: 16bit words read: 0
[09:09:46.944] <TB2> INFO: valid events total: 0
[09:09:46.944] <TB2> INFO: empty events: 0
[09:09:46.944] <TB2> INFO: valid events with pixels: 0
[09:09:46.944] <TB2> INFO: valid pixel hits: 0
[09:09:46.944] <TB2> INFO: Event errors: 0
[09:09:46.944] <TB2> INFO: start marker: 0
[09:09:46.944] <TB2> INFO: stop marker: 0
[09:09:46.944] <TB2> INFO: overflow: 0
[09:09:46.944] <TB2> INFO: invalid 5bit words: 0
[09:09:46.944] <TB2> INFO: invalid XOR eye diagram: 0
[09:09:46.944] <TB2> INFO: TBM errors: 0
[09:09:46.944] <TB2> INFO: flawed TBM headers: 0
[09:09:46.944] <TB2> INFO: flawed TBM trailers: 0
[09:09:46.944] <TB2> INFO: event ID mismatches: 0
[09:09:46.944] <TB2> INFO: ROC errors: 0
[09:09:46.944] <TB2> INFO: missing ROC header(s): 0
[09:09:46.944] <TB2> INFO: misplaced readback start: 0
[09:09:46.944] <TB2> INFO: Pixel decoding errors: 0
[09:09:46.944] <TB2> INFO: pixel data incomplete: 0
[09:09:46.944] <TB2> INFO: pixel address: 0
[09:09:46.944] <TB2> INFO: pulse height fill bit: 0
[09:09:46.944] <TB2> INFO: buffer corruption: 0
[09:09:47.015] <TB2> INFO: ######################################################################
[09:09:47.015] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:09:47.015] <TB2> INFO: ######################################################################
[09:09:47.015] <TB2> INFO: ----------------------------------------------------------------------
[09:09:47.016] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:09:47.016] <TB2> INFO: ----------------------------------------------------------------------
[09:09:47.016] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:09:47.024] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:47.024] <TB2> INFO: run 1 of 1
[09:09:47.351] <TB2> INFO: Expecting 26208000 events.
[09:10:23.105] <TB2> INFO: 993550 events read in total (35039ms).
[09:10:57.463] <TB2> INFO: 1966750 events read in total (69397ms).
[09:11:32.481] <TB2> INFO: 2939650 events read in total (104415ms).
[09:12:07.426] <TB2> INFO: 3910650 events read in total (139360ms).
[09:12:42.520] <TB2> INFO: 4881900 events read in total (174454ms).
[09:13:16.993] <TB2> INFO: 5852150 events read in total (208927ms).
[09:13:52.159] <TB2> INFO: 6821750 events read in total (244093ms).
[09:14:27.327] <TB2> INFO: 7788800 events read in total (279261ms).
[09:15:01.450] <TB2> INFO: 8755900 events read in total (313384ms).
[09:15:35.528] <TB2> INFO: 9724700 events read in total (347462ms).
[09:16:10.048] <TB2> INFO: 10691350 events read in total (381982ms).
[09:16:43.584] <TB2> INFO: 11657200 events read in total (415518ms).
[09:17:19.031] <TB2> INFO: 12624000 events read in total (450965ms).
[09:17:53.438] <TB2> INFO: 13581500 events read in total (485372ms).
[09:18:28.216] <TB2> INFO: 14535750 events read in total (520150ms).
[09:19:02.777] <TB2> INFO: 15489900 events read in total (554711ms).
[09:19:38.022] <TB2> INFO: 16439500 events read in total (589956ms).
[09:20:13.318] <TB2> INFO: 17389500 events read in total (625252ms).
[09:20:48.740] <TB2> INFO: 18339650 events read in total (660674ms).
[09:21:23.992] <TB2> INFO: 19287300 events read in total (695926ms).
[09:21:54.323] <TB2> INFO: 20235300 events read in total (726257ms).
[09:22:28.985] <TB2> INFO: 21182950 events read in total (760919ms).
[09:23:03.651] <TB2> INFO: 22129800 events read in total (795585ms).
[09:23:35.351] <TB2> INFO: 23077400 events read in total (827285ms).
[09:24:09.374] <TB2> INFO: 24026500 events read in total (861308ms).
[09:24:45.725] <TB2> INFO: 24974500 events read in total (897659ms).
[09:25:16.421] <TB2> INFO: 25933250 events read in total (928355ms).
[09:25:26.549] <TB2> INFO: 26208000 events read in total (938483ms).
[09:25:26.575] <TB2> INFO: Test took 939551ms.
[09:25:26.642] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:26.770] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:28.223] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:29.646] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:31.158] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:32.690] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:34.104] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:35.478] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:36.935] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:38.378] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:39.750] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:41.102] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:42.460] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:43.815] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:45.445] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:47.035] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:48.505] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:25:50.034] <TB2> INFO: PixTestScurves::scurves() done
[09:25:50.034] <TB2> INFO: Vcal mean: 111.97 119.24 107.40 107.33 109.95 113.45 101.01 111.99 103.89 107.53 111.22 107.88 111.61 95.14 90.68 124.73
[09:25:50.034] <TB2> INFO: Vcal RMS: 6.10 7.13 5.41 5.77 5.63 5.66 5.79 6.02 6.07 5.19 5.40 7.25 6.47 5.13 5.99 8.12
[09:25:50.034] <TB2> INFO: PixTestScurves::fullTest() done, duration: 963 seconds
[09:25:50.034] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:50.034] <TB2> INFO: Decoding statistics:
[09:25:50.034] <TB2> INFO: General information:
[09:25:50.034] <TB2> INFO: 16bit words read: 0
[09:25:50.034] <TB2> INFO: valid events total: 0
[09:25:50.034] <TB2> INFO: empty events: 0
[09:25:50.034] <TB2> INFO: valid events with pixels: 0
[09:25:50.034] <TB2> INFO: valid pixel hits: 0
[09:25:50.034] <TB2> INFO: Event errors: 0
[09:25:50.034] <TB2> INFO: start marker: 0
[09:25:50.034] <TB2> INFO: stop marker: 0
[09:25:50.034] <TB2> INFO: overflow: 0
[09:25:50.034] <TB2> INFO: invalid 5bit words: 0
[09:25:50.034] <TB2> INFO: invalid XOR eye diagram: 0
[09:25:50.034] <TB2> INFO: TBM errors: 0
[09:25:50.034] <TB2> INFO: flawed TBM headers: 0
[09:25:50.034] <TB2> INFO: flawed TBM trailers: 0
[09:25:50.034] <TB2> INFO: event ID mismatches: 0
[09:25:50.034] <TB2> INFO: ROC errors: 0
[09:25:50.034] <TB2> INFO: missing ROC header(s): 0
[09:25:50.034] <TB2> INFO: misplaced readback start: 0
[09:25:50.034] <TB2> INFO: Pixel decoding errors: 0
[09:25:50.034] <TB2> INFO: pixel data incomplete: 0
[09:25:50.035] <TB2> INFO: pixel address: 0
[09:25:50.035] <TB2> INFO: pulse height fill bit: 0
[09:25:50.035] <TB2> INFO: buffer corruption: 0
[09:25:50.132] <TB2> INFO: ######################################################################
[09:25:50.132] <TB2> INFO: PixTestTrim::doTest()
[09:25:50.132] <TB2> INFO: ######################################################################
[09:25:50.133] <TB2> INFO: ----------------------------------------------------------------------
[09:25:50.133] <TB2> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[09:25:50.133] <TB2> INFO: ----------------------------------------------------------------------
[09:25:50.223] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:25:50.223] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:25:50.233] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:25:50.233] <TB2> INFO: run 1 of 1
[09:25:50.554] <TB2> INFO: Expecting 5025280 events.
[09:26:33.660] <TB2> INFO: 1092184 events read in total (42384ms).
[09:27:12.991] <TB2> INFO: 2180752 events read in total (81715ms).
[09:27:55.391] <TB2> INFO: 3264592 events read in total (124115ms).
[09:28:34.743] <TB2> INFO: 4347536 events read in total (163467ms).
[09:29:03.203] <TB2> INFO: 5025280 events read in total (191927ms).
[09:29:03.232] <TB2> INFO: Test took 193000ms.
[09:29:03.290] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:25.075] <TB2> INFO: ROC 0 VthrComp = 110
[09:29:25.075] <TB2> INFO: ROC 1 VthrComp = 105
[09:29:25.075] <TB2> INFO: ROC 2 VthrComp = 111
[09:29:25.075] <TB2> INFO: ROC 3 VthrComp = 107
[09:29:25.075] <TB2> INFO: ROC 4 VthrComp = 108
[09:29:25.075] <TB2> INFO: ROC 5 VthrComp = 111
[09:29:25.075] <TB2> INFO: ROC 6 VthrComp = 105
[09:29:25.075] <TB2> INFO: ROC 7 VthrComp = 111
[09:29:25.075] <TB2> INFO: ROC 8 VthrComp = 102
[09:29:25.076] <TB2> INFO: ROC 9 VthrComp = 107
[09:29:25.076] <TB2> INFO: ROC 10 VthrComp = 108
[09:29:25.076] <TB2> INFO: ROC 11 VthrComp = 110
[09:29:25.076] <TB2> INFO: ROC 12 VthrComp = 109
[09:29:25.076] <TB2> INFO: ROC 13 VthrComp = 101
[09:29:25.076] <TB2> INFO: ROC 14 VthrComp = 97
[09:29:25.076] <TB2> INFO: ROC 15 VthrComp = 116
[09:29:25.076] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:29:25.076] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:29:25.086] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:29:25.086] <TB2> INFO: run 1 of 1
[09:29:25.407] <TB2> INFO: Expecting 5025280 events.
[09:30:05.540] <TB2> INFO: 763912 events read in total (39417ms).
[09:30:43.905] <TB2> INFO: 1526688 events read in total (77782ms).
[09:31:19.840] <TB2> INFO: 2290224 events read in total (113717ms).
[09:31:58.704] <TB2> INFO: 3049072 events read in total (152581ms).
[09:32:37.882] <TB2> INFO: 3802416 events read in total (191759ms).
[09:33:16.964] <TB2> INFO: 4554376 events read in total (230841ms).
[09:33:41.689] <TB2> INFO: 5025280 events read in total (255566ms).
[09:33:41.733] <TB2> INFO: Test took 256647ms.
[09:33:41.882] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:07.635] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 63.6329 for pixel 34/73 mean/min/max = 48.748/33.6055/63.8905
[09:34:07.635] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 77.3217 for pixel 13/79 mean/min/max = 58.195/38.913/77.477
[09:34:07.635] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 60.7387 for pixel 0/28 mean/min/max = 47.7711/34.7874/60.7548
[09:34:07.636] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 61.7919 for pixel 48/74 mean/min/max = 47.8585/33.8247/61.8924
[09:34:07.636] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 62.3012 for pixel 24/13 mean/min/max = 48.1399/33.5869/62.6928
[09:34:07.636] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 67.2144 for pixel 20/78 mean/min/max = 51.4026/35.5604/67.2447
[09:34:07.636] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 60.4473 for pixel 0/19 mean/min/max = 47.192/33.8846/60.4994
[09:34:07.637] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 62.2493 for pixel 7/4 mean/min/max = 47.986/33.473/62.499
[09:34:07.637] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 60.7276 for pixel 0/3 mean/min/max = 46.6038/32.4675/60.74
[09:34:07.638] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 62.0288 for pixel 2/2 mean/min/max = 47.613/33.1201/62.1059
[09:34:07.638] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 61.9114 for pixel 38/29 mean/min/max = 48.2596/34.5122/62.007
[09:34:07.638] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 62.0695 for pixel 2/71 mean/min/max = 48.0827/34.0206/62.1449
[09:34:07.639] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 74.3496 for pixel 0/58 mean/min/max = 54.5745/34.6532/74.4958
[09:34:07.639] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 59.0926 for pixel 6/0 mean/min/max = 45.5275/31.9069/59.1481
[09:34:07.640] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 58.6092 for pixel 15/15 mean/min/max = 45.3116/31.9939/58.6294
[09:34:07.640] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 74.1016 for pixel 18/3 mean/min/max = 55.9524/37.7869/74.118
[09:34:07.640] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:07.771] <TB2> INFO: Expecting 411648 events.
[09:34:17.045] <TB2> INFO: 411648 events read in total (8558ms).
[09:34:17.052] <TB2> INFO: Expecting 411648 events.
[09:34:26.375] <TB2> INFO: 411648 events read in total (8756ms).
[09:34:26.383] <TB2> INFO: Expecting 411648 events.
[09:34:37.030] <TB2> INFO: 411648 events read in total (10067ms).
[09:34:37.039] <TB2> INFO: Expecting 411648 events.
[09:34:47.353] <TB2> INFO: 411648 events read in total (9739ms).
[09:34:47.367] <TB2> INFO: Expecting 411648 events.
[09:34:57.264] <TB2> INFO: 411648 events read in total (9332ms).
[09:34:57.279] <TB2> INFO: Expecting 411648 events.
[09:35:06.991] <TB2> INFO: 411648 events read in total (9153ms).
[09:35:07.007] <TB2> INFO: Expecting 411648 events.
[09:35:17.711] <TB2> INFO: 411648 events read in total (10148ms).
[09:35:17.728] <TB2> INFO: Expecting 411648 events.
[09:35:28.222] <TB2> INFO: 411648 events read in total (9919ms).
[09:35:28.246] <TB2> INFO: Expecting 411648 events.
[09:35:38.222] <TB2> INFO: 411648 events read in total (9419ms).
[09:35:38.246] <TB2> INFO: Expecting 411648 events.
[09:35:47.937] <TB2> INFO: 411648 events read in total (9130ms).
[09:35:47.960] <TB2> INFO: Expecting 411648 events.
[09:35:58.276] <TB2> INFO: 411648 events read in total (9753ms).
[09:35:58.306] <TB2> INFO: Expecting 411648 events.
[09:36:08.538] <TB2> INFO: 411648 events read in total (9682ms).
[09:36:08.569] <TB2> INFO: Expecting 411648 events.
[09:36:19.181] <TB2> INFO: 411648 events read in total (10062ms).
[09:36:19.211] <TB2> INFO: Expecting 411648 events.
[09:36:28.685] <TB2> INFO: 411648 events read in total (8922ms).
[09:36:28.716] <TB2> INFO: Expecting 411648 events.
[09:36:39.237] <TB2> INFO: 411648 events read in total (9967ms).
[09:36:39.272] <TB2> INFO: Expecting 411648 events.
[09:36:49.884] <TB2> INFO: 411648 events read in total (10061ms).
[09:36:49.936] <TB2> INFO: Test took 162296ms.
[09:36:51.135] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:36:51.144] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:36:51.144] <TB2> INFO: run 1 of 1
[09:36:51.446] <TB2> INFO: Expecting 5025280 events.
[09:37:29.398] <TB2> INFO: 744816 events read in total (37236ms).
[09:38:04.440] <TB2> INFO: 1489408 events read in total (72278ms).
[09:38:41.063] <TB2> INFO: 2234632 events read in total (108901ms).
[09:39:17.979] <TB2> INFO: 2975632 events read in total (145817ms).
[09:39:54.830] <TB2> INFO: 3710512 events read in total (182668ms).
[09:40:29.329] <TB2> INFO: 4442888 events read in total (217167ms).
[09:40:58.861] <TB2> INFO: 5025280 events read in total (246699ms).
[09:40:58.907] <TB2> INFO: Test took 247763ms.
[09:40:59.047] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:41:24.618] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.076856 .. 255.000000
[09:41:24.697] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[09:41:24.706] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:41:24.706] <TB2> INFO: run 1 of 1
[09:41:25.009] <TB2> INFO: Expecting 8519680 events.
[09:42:03.411] <TB2> INFO: 711736 events read in total (37681ms).
[09:42:40.440] <TB2> INFO: 1423696 events read in total (74710ms).
[09:43:18.387] <TB2> INFO: 2135048 events read in total (112657ms).
[09:43:52.436] <TB2> INFO: 2846840 events read in total (146707ms).
[09:44:29.240] <TB2> INFO: 3558704 events read in total (183510ms).
[09:45:04.359] <TB2> INFO: 4270616 events read in total (218629ms).
[09:45:39.035] <TB2> INFO: 4983152 events read in total (253305ms).
[09:46:15.366] <TB2> INFO: 5695296 events read in total (289636ms).
[09:46:49.564] <TB2> INFO: 6407504 events read in total (323834ms).
[09:47:26.381] <TB2> INFO: 7119440 events read in total (360651ms).
[09:48:00.982] <TB2> INFO: 7831152 events read in total (395252ms).
[09:48:36.782] <TB2> INFO: 8519680 events read in total (431052ms).
[09:48:36.865] <TB2> INFO: Test took 432159ms.
[09:48:37.128] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:07.918] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 16.219009 .. 101.436104
[09:49:07.997] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 6 .. 111 (-1/-1) hits flags = 528 (plus default)
[09:49:08.006] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:49:08.006] <TB2> INFO: run 1 of 1
[09:49:08.308] <TB2> INFO: Expecting 3527680 events.
[09:49:43.245] <TB2> INFO: 768000 events read in total (34221ms).
[09:50:20.388] <TB2> INFO: 1535920 events read in total (71364ms).
[09:50:56.483] <TB2> INFO: 2303824 events read in total (107459ms).
[09:51:30.234] <TB2> INFO: 3071368 events read in total (141210ms).
[09:51:53.409] <TB2> INFO: 3527680 events read in total (164385ms).
[09:51:53.439] <TB2> INFO: Test took 165433ms.
[09:51:53.536] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:52:14.420] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 2.014513 .. 87.803065
[09:52:14.499] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 2 .. 97 (-1/-1) hits flags = 528 (plus default)
[09:52:14.507] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:52:14.507] <TB2> INFO: run 1 of 1
[09:52:14.810] <TB2> INFO: Expecting 3194880 events.
[09:52:54.769] <TB2> INFO: 801664 events read in total (39243ms).
[09:53:34.226] <TB2> INFO: 1603344 events read in total (78701ms).
[09:54:12.958] <TB2> INFO: 2404712 events read in total (117432ms).
[09:54:51.194] <TB2> INFO: 3194880 events read in total (155668ms).
[09:54:51.236] <TB2> INFO: Test took 156730ms.
[09:54:51.334] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:10.450] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 2.014513 .. 84.627354
[09:55:10.529] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 2 .. 94 (-1/-1) hits flags = 528 (plus default)
[09:55:10.537] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:55:10.537] <TB2> INFO: run 1 of 1
[09:55:10.840] <TB2> INFO: Expecting 3095040 events.
[09:55:49.164] <TB2> INFO: 806984 events read in total (37609ms).
[09:56:25.143] <TB2> INFO: 1613816 events read in total (73588ms).
[09:57:03.105] <TB2> INFO: 2420584 events read in total (111550ms).
[09:57:33.798] <TB2> INFO: 3095040 events read in total (142243ms).
[09:57:33.828] <TB2> INFO: Test took 143291ms.
[09:57:33.900] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:52.362] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:57:52.362] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:57:52.371] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:57:52.371] <TB2> INFO: run 1 of 1
[09:57:52.677] <TB2> INFO: Expecting 1364480 events.
[09:58:33.704] <TB2> INFO: 878632 events read in total (40311ms).
[09:58:56.963] <TB2> INFO: 1364480 events read in total (63570ms).
[09:58:56.985] <TB2> INFO: Test took 64614ms.
[09:58:57.019] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:11.113] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:59:11.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:59:11.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:59:11.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:59:11.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:59:11.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:59:11.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:59:11.114] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:59:11.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:59:11.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:59:11.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:59:11.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:59:11.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:59:11.115] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:59:11.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:59:11.116] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:59:11.116] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:59:11.128] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:59:11.136] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:59:11.146] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:59:11.155] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:59:11.165] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:59:11.172] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:59:11.178] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:59:11.184] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:59:11.191] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:59:11.197] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:59:11.203] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:59:11.211] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:59:11.217] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:59:11.223] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:59:11.230] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:59:11.235] <TB2> INFO: PixTestTrim::trimTest() done
[09:59:11.235] <TB2> INFO: vtrim: 115 155 115 129 125 139 101 114 102 110 112 128 135 98 109 149
[09:59:11.235] <TB2> INFO: vthrcomp: 110 105 111 107 108 111 105 111 102 107 108 110 109 101 97 116
[09:59:11.235] <TB2> INFO: vcal mean: 35.03 35.03 35.01 35.02 34.99 35.04 35.00 34.97 34.94 35.07 34.96 34.97 34.97 34.98 34.94 34.95
[09:59:11.235] <TB2> INFO: vcal RMS: 0.97 1.29 1.17 0.93 1.37 1.02 1.03 1.21 1.33 0.98 1.23 1.88 1.22 0.90 1.37 1.47
[09:59:11.235] <TB2> INFO: bits mean: 8.64 7.05 8.10 8.75 9.00 8.19 8.11 8.75 8.78 8.51 8.85 8.83 7.38 9.44 9.82 7.40
[09:59:11.235] <TB2> INFO: bits RMS: 2.53 2.27 2.72 2.54 2.50 2.33 2.86 2.61 2.86 2.76 2.41 2.50 2.60 2.69 2.50 2.17
[09:59:11.243] <TB2> INFO: ----------------------------------------------------------------------
[09:59:11.243] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[09:59:11.243] <TB2> INFO: ----------------------------------------------------------------------
[09:59:11.246] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:59:11.258] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:59:11.258] <TB2> INFO: run 1 of 1
[09:59:11.580] <TB2> INFO: Expecting 8320000 events.
[09:59:51.494] <TB2> INFO: 996450 events read in total (39198ms).
[10:00:30.251] <TB2> INFO: 1985290 events read in total (77955ms).
[10:01:08.600] <TB2> INFO: 2971160 events read in total (116305ms).
[10:01:43.306] <TB2> INFO: 3955300 events read in total (151010ms).
[10:02:21.748] <TB2> INFO: 4932750 events read in total (189452ms).
[10:03:00.108] <TB2> INFO: 5906480 events read in total (227812ms).
[10:03:38.628] <TB2> INFO: 6880070 events read in total (266332ms).
[10:04:18.145] <TB2> INFO: 7855350 events read in total (305849ms).
[10:04:37.261] <TB2> INFO: 8320000 events read in total (324965ms).
[10:04:37.292] <TB2> INFO: Test took 326035ms.
[10:04:37.384] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:05:04.514] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 198 (-1/-1) hits flags = 528 (plus default)
[10:05:04.523] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:05:04.523] <TB2> INFO: run 1 of 1
[10:05:04.859] <TB2> INFO: Expecting 8278400 events.
[10:05:44.625] <TB2> INFO: 962200 events read in total (39051ms).
[10:06:23.610] <TB2> INFO: 1917750 events read in total (78036ms).
[10:07:00.318] <TB2> INFO: 2871170 events read in total (114744ms).
[10:07:38.114] <TB2> INFO: 3823270 events read in total (152540ms).
[10:08:16.715] <TB2> INFO: 4769580 events read in total (191141ms).
[10:08:55.616] <TB2> INFO: 5711920 events read in total (230042ms).
[10:09:34.689] <TB2> INFO: 6653830 events read in total (269115ms).
[10:10:13.326] <TB2> INFO: 7596820 events read in total (307752ms).
[10:10:42.563] <TB2> INFO: 8278400 events read in total (336989ms).
[10:10:42.599] <TB2> INFO: Test took 338076ms.
[10:10:42.690] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:08.164] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 191 (-1/-1) hits flags = 528 (plus default)
[10:11:08.175] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:11:08.175] <TB2> INFO: run 1 of 1
[10:11:08.482] <TB2> INFO: Expecting 7987200 events.
[10:11:45.220] <TB2> INFO: 977010 events read in total (36022ms).
[10:12:24.974] <TB2> INFO: 1947580 events read in total (75776ms).
[10:13:05.225] <TB2> INFO: 2916130 events read in total (116027ms).
[10:13:44.754] <TB2> INFO: 3882680 events read in total (155556ms).
[10:14:23.955] <TB2> INFO: 4841680 events read in total (194757ms).
[10:15:03.380] <TB2> INFO: 5797870 events read in total (234182ms).
[10:15:44.116] <TB2> INFO: 6754240 events read in total (274918ms).
[10:16:23.312] <TB2> INFO: 7712500 events read in total (314114ms).
[10:16:34.638] <TB2> INFO: 7987200 events read in total (325440ms).
[10:16:34.671] <TB2> INFO: Test took 326496ms.
[10:16:34.765] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:16:59.960] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 187 (-1/-1) hits flags = 528 (plus default)
[10:16:59.968] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:16:59.968] <TB2> INFO: run 1 of 1
[10:17:00.272] <TB2> INFO: Expecting 7820800 events.
[10:17:41.859] <TB2> INFO: 986160 events read in total (40871ms).
[10:18:22.758] <TB2> INFO: 1966070 events read in total (81770ms).
[10:18:59.338] <TB2> INFO: 2942810 events read in total (118350ms).
[10:19:39.868] <TB2> INFO: 3917920 events read in total (158880ms).
[10:20:21.138] <TB2> INFO: 4884730 events read in total (200150ms).
[10:21:01.009] <TB2> INFO: 5849400 events read in total (240021ms).
[10:21:41.090] <TB2> INFO: 6815050 events read in total (280102ms).
[10:22:21.057] <TB2> INFO: 7786290 events read in total (320069ms).
[10:22:22.817] <TB2> INFO: 7820800 events read in total (321829ms).
[10:22:22.848] <TB2> INFO: Test took 322880ms.
[10:22:22.926] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:49.034] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 186 (-1/-1) hits flags = 528 (plus default)
[10:22:49.043] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:22:49.043] <TB2> INFO: run 1 of 1
[10:22:49.373] <TB2> INFO: Expecting 7779200 events.
[10:23:29.712] <TB2> INFO: 987880 events read in total (39623ms).
[10:24:10.019] <TB2> INFO: 1969620 events read in total (79930ms).
[10:24:49.998] <TB2> INFO: 2948150 events read in total (119911ms).
[10:25:29.126] <TB2> INFO: 3924870 events read in total (159037ms).
[10:26:05.027] <TB2> INFO: 4893060 events read in total (194938ms).
[10:26:43.839] <TB2> INFO: 5859810 events read in total (233750ms).
[10:27:25.604] <TB2> INFO: 6826940 events read in total (275515ms).
[10:28:06.917] <TB2> INFO: 7779200 events read in total (316828ms).
[10:28:06.958] <TB2> INFO: Test took 317915ms.
[10:28:07.050] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:33.094] <TB2> INFO: PixTestTrim::trimBitTest() done
[10:28:33.095] <TB2> INFO: PixTestTrim::doTest() done, duration: 3762 seconds
[10:28:33.095] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:33.095] <TB2> INFO: Decoding statistics:
[10:28:33.095] <TB2> INFO: General information:
[10:28:33.095] <TB2> INFO: 16bit words read: 0
[10:28:33.095] <TB2> INFO: valid events total: 0
[10:28:33.095] <TB2> INFO: empty events: 0
[10:28:33.095] <TB2> INFO: valid events with pixels: 0
[10:28:33.095] <TB2> INFO: valid pixel hits: 0
[10:28:33.095] <TB2> INFO: Event errors: 0
[10:28:33.095] <TB2> INFO: start marker: 0
[10:28:33.095] <TB2> INFO: stop marker: 0
[10:28:33.095] <TB2> INFO: overflow: 0
[10:28:33.095] <TB2> INFO: invalid 5bit words: 0
[10:28:33.095] <TB2> INFO: invalid XOR eye diagram: 0
[10:28:33.095] <TB2> INFO: TBM errors: 0
[10:28:33.095] <TB2> INFO: flawed TBM headers: 0
[10:28:33.095] <TB2> INFO: flawed TBM trailers: 0
[10:28:33.095] <TB2> INFO: event ID mismatches: 0
[10:28:33.095] <TB2> INFO: ROC errors: 0
[10:28:33.095] <TB2> INFO: missing ROC header(s): 0
[10:28:33.095] <TB2> INFO: misplaced readback start: 0
[10:28:33.095] <TB2> INFO: Pixel decoding errors: 0
[10:28:33.095] <TB2> INFO: pixel data incomplete: 0
[10:28:33.095] <TB2> INFO: pixel address: 0
[10:28:33.095] <TB2> INFO: pulse height fill bit: 0
[10:28:33.095] <TB2> INFO: buffer corruption: 0
[10:28:33.915] <TB2> INFO: ######################################################################
[10:28:33.915] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:28:33.915] <TB2> INFO: ######################################################################
[10:28:34.257] <TB2> INFO: Expecting 41600 events.
[10:28:38.840] <TB2> INFO: 41600 events read in total (3867ms).
[10:28:38.841] <TB2> INFO: Test took 4924ms.
[10:28:38.847] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:39.418] <TB2> INFO: Expecting 41600 events.
[10:28:43.916] <TB2> INFO: 41600 events read in total (3783ms).
[10:28:43.917] <TB2> INFO: Test took 4806ms.
[10:28:43.925] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:44.277] <TB2> INFO: Expecting 41600 events.
[10:28:48.503] <TB2> INFO: 41600 events read in total (3510ms).
[10:28:48.504] <TB2> INFO: Test took 4550ms.
[10:28:48.510] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:48.859] <TB2> INFO: Expecting 41600 events.
[10:28:53.622] <TB2> INFO: 41600 events read in total (4047ms).
[10:28:53.622] <TB2> INFO: Test took 5086ms.
[10:28:53.628] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:53.976] <TB2> INFO: Expecting 41600 events.
[10:28:58.506] <TB2> INFO: 41600 events read in total (3815ms).
[10:28:58.507] <TB2> INFO: Test took 4854ms.
[10:28:58.513] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:58.869] <TB2> INFO: Expecting 41600 events.
[10:29:03.331] <TB2> INFO: 41600 events read in total (3747ms).
[10:29:03.331] <TB2> INFO: Test took 4790ms.
[10:29:03.337] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:03.698] <TB2> INFO: Expecting 41600 events.
[10:29:08.350] <TB2> INFO: 41600 events read in total (3937ms).
[10:29:08.350] <TB2> INFO: Test took 4982ms.
[10:29:08.357] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:08.710] <TB2> INFO: Expecting 41600 events.
[10:29:13.230] <TB2> INFO: 41600 events read in total (3804ms).
[10:29:13.230] <TB2> INFO: Test took 4860ms.
[10:29:13.236] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:13.592] <TB2> INFO: Expecting 41600 events.
[10:29:18.323] <TB2> INFO: 41600 events read in total (4015ms).
[10:29:18.323] <TB2> INFO: Test took 5054ms.
[10:29:18.329] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:18.704] <TB2> INFO: Expecting 41600 events.
[10:29:23.441] <TB2> INFO: 41600 events read in total (4021ms).
[10:29:23.441] <TB2> INFO: Test took 5086ms.
[10:29:23.447] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:23.799] <TB2> INFO: Expecting 41600 events.
[10:29:28.448] <TB2> INFO: 41600 events read in total (3933ms).
[10:29:28.449] <TB2> INFO: Test took 4973ms.
[10:29:28.455] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:28.814] <TB2> INFO: Expecting 41600 events.
[10:29:33.656] <TB2> INFO: 41600 events read in total (4126ms).
[10:29:33.657] <TB2> INFO: Test took 5177ms.
[10:29:33.664] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:33.995] <TB2> INFO: Expecting 41600 events.
[10:29:38.888] <TB2> INFO: 41600 events read in total (4177ms).
[10:29:38.889] <TB2> INFO: Test took 5217ms.
[10:29:38.896] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:39.260] <TB2> INFO: Expecting 41600 events.
[10:29:44.105] <TB2> INFO: 41600 events read in total (4129ms).
[10:29:44.106] <TB2> INFO: Test took 5198ms.
[10:29:44.113] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:44.448] <TB2> INFO: Expecting 41600 events.
[10:29:49.234] <TB2> INFO: 41600 events read in total (4070ms).
[10:29:49.234] <TB2> INFO: Test took 5109ms.
[10:29:49.242] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:49.607] <TB2> INFO: Expecting 41600 events.
[10:29:54.617] <TB2> INFO: 41600 events read in total (4294ms).
[10:29:54.617] <TB2> INFO: Test took 5363ms.
[10:29:54.623] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:54.981] <TB2> INFO: Expecting 41600 events.
[10:29:59.507] <TB2> INFO: 41600 events read in total (3810ms).
[10:29:59.507] <TB2> INFO: Test took 4861ms.
[10:29:59.513] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:59.867] <TB2> INFO: Expecting 41600 events.
[10:30:04.889] <TB2> INFO: 41600 events read in total (4307ms).
[10:30:04.889] <TB2> INFO: Test took 5346ms.
[10:30:04.895] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:05.250] <TB2> INFO: Expecting 41600 events.
[10:30:10.010] <TB2> INFO: 41600 events read in total (4044ms).
[10:30:10.010] <TB2> INFO: Test took 5087ms.
[10:30:10.018] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:10.367] <TB2> INFO: Expecting 41600 events.
[10:30:15.071] <TB2> INFO: 41600 events read in total (3988ms).
[10:30:15.072] <TB2> INFO: Test took 5033ms.
[10:30:15.079] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:15.437] <TB2> INFO: Expecting 41600 events.
[10:30:20.300] <TB2> INFO: 41600 events read in total (4148ms).
[10:30:20.301] <TB2> INFO: Test took 5211ms.
[10:30:20.307] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:20.660] <TB2> INFO: Expecting 41600 events.
[10:30:25.188] <TB2> INFO: 41600 events read in total (3813ms).
[10:30:25.189] <TB2> INFO: Test took 4853ms.
[10:30:25.195] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:25.543] <TB2> INFO: Expecting 41600 events.
[10:30:30.442] <TB2> INFO: 41600 events read in total (4183ms).
[10:30:30.442] <TB2> INFO: Test took 5223ms.
[10:30:30.448] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:30.800] <TB2> INFO: Expecting 41600 events.
[10:30:35.495] <TB2> INFO: 41600 events read in total (3980ms).
[10:30:35.495] <TB2> INFO: Test took 5018ms.
[10:30:35.501] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:35.853] <TB2> INFO: Expecting 41600 events.
[10:30:40.512] <TB2> INFO: 41600 events read in total (3943ms).
[10:30:40.513] <TB2> INFO: Test took 4983ms.
[10:30:40.519] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:40.871] <TB2> INFO: Expecting 41600 events.
[10:30:45.712] <TB2> INFO: 41600 events read in total (4125ms).
[10:30:45.713] <TB2> INFO: Test took 5165ms.
[10:30:45.719] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:46.071] <TB2> INFO: Expecting 41600 events.
[10:30:50.849] <TB2> INFO: 41600 events read in total (4062ms).
[10:30:50.850] <TB2> INFO: Test took 5103ms.
[10:30:50.856] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:51.203] <TB2> INFO: Expecting 41600 events.
[10:30:55.809] <TB2> INFO: 41600 events read in total (3890ms).
[10:30:55.810] <TB2> INFO: Test took 4930ms.
[10:30:55.817] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:56.164] <TB2> INFO: Expecting 41600 events.
[10:31:01.027] <TB2> INFO: 41600 events read in total (4148ms).
[10:31:01.027] <TB2> INFO: Test took 5187ms.
[10:31:01.033] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:01.380] <TB2> INFO: Expecting 41600 events.
[10:31:06.369] <TB2> INFO: 41600 events read in total (4273ms).
[10:31:06.370] <TB2> INFO: Test took 5314ms.
[10:31:06.377] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:06.734] <TB2> INFO: Expecting 41600 events.
[10:31:11.380] <TB2> INFO: 41600 events read in total (3930ms).
[10:31:11.381] <TB2> INFO: Test took 4980ms.
[10:31:11.387] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:11.758] <TB2> INFO: Expecting 41600 events.
[10:31:16.665] <TB2> INFO: 41600 events read in total (4191ms).
[10:31:16.665] <TB2> INFO: Test took 5253ms.
[10:31:16.672] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:17.026] <TB2> INFO: Expecting 41600 events.
[10:31:22.224] <TB2> INFO: 41600 events read in total (4482ms).
[10:31:22.225] <TB2> INFO: Test took 5543ms.
[10:31:22.233] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:22.585] <TB2> INFO: Expecting 41600 events.
[10:31:27.255] <TB2> INFO: 41600 events read in total (3955ms).
[10:31:27.256] <TB2> INFO: Test took 5012ms.
[10:31:27.274] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:27.628] <TB2> INFO: Expecting 41600 events.
[10:31:32.416] <TB2> INFO: 41600 events read in total (4072ms).
[10:31:32.417] <TB2> INFO: Test took 5133ms.
[10:31:32.423] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:32.771] <TB2> INFO: Expecting 41600 events.
[10:31:37.624] <TB2> INFO: 41600 events read in total (4138ms).
[10:31:37.624] <TB2> INFO: Test took 5177ms.
[10:31:37.632] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:37.961] <TB2> INFO: Expecting 41600 events.
[10:31:42.630] <TB2> INFO: 41600 events read in total (3954ms).
[10:31:42.630] <TB2> INFO: Test took 4987ms.
[10:31:42.645] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:43.008] <TB2> INFO: Expecting 41600 events.
[10:31:47.899] <TB2> INFO: 41600 events read in total (4175ms).
[10:31:47.900] <TB2> INFO: Test took 5238ms.
[10:31:47.906] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:48.279] <TB2> INFO: Expecting 41600 events.
[10:31:53.151] <TB2> INFO: 41600 events read in total (4156ms).
[10:31:53.152] <TB2> INFO: Test took 5219ms.
[10:31:53.159] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:53.490] <TB2> INFO: Expecting 41600 events.
[10:31:57.951] <TB2> INFO: 41600 events read in total (3746ms).
[10:31:57.952] <TB2> INFO: Test took 4782ms.
[10:31:57.958] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:58.329] <TB2> INFO: Expecting 41600 events.
[10:32:03.068] <TB2> INFO: 41600 events read in total (4024ms).
[10:32:03.068] <TB2> INFO: Test took 5081ms.
[10:32:03.074] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:03.424] <TB2> INFO: Expecting 41600 events.
[10:32:08.038] <TB2> INFO: 41600 events read in total (3899ms).
[10:32:08.039] <TB2> INFO: Test took 4939ms.
[10:32:08.045] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:08.392] <TB2> INFO: Expecting 41600 events.
[10:32:13.205] <TB2> INFO: 41600 events read in total (4097ms).
[10:32:13.205] <TB2> INFO: Test took 5136ms.
[10:32:13.212] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:13.540] <TB2> INFO: Expecting 41600 events.
[10:32:18.557] <TB2> INFO: 41600 events read in total (4302ms).
[10:32:18.558] <TB2> INFO: Test took 5337ms.
[10:32:18.565] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:18.894] <TB2> INFO: Expecting 41600 events.
[10:32:23.430] <TB2> INFO: 41600 events read in total (3820ms).
[10:32:23.430] <TB2> INFO: Test took 4854ms.
[10:32:23.437] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:23.786] <TB2> INFO: Expecting 41600 events.
[10:32:28.397] <TB2> INFO: 41600 events read in total (3895ms).
[10:32:28.397] <TB2> INFO: Test took 4935ms.
[10:32:28.404] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:28.758] <TB2> INFO: Expecting 41600 events.
[10:32:33.797] <TB2> INFO: 41600 events read in total (4324ms).
[10:32:33.798] <TB2> INFO: Test took 5369ms.
[10:32:33.804] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:34.152] <TB2> INFO: Expecting 41600 events.
[10:32:38.609] <TB2> INFO: 41600 events read in total (3741ms).
[10:32:38.610] <TB2> INFO: Test took 4781ms.
[10:32:38.618] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:38.952] <TB2> INFO: Expecting 41600 events.
[10:32:43.394] <TB2> INFO: 41600 events read in total (3726ms).
[10:32:43.395] <TB2> INFO: Test took 4765ms.
[10:32:43.401] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:43.743] <TB2> INFO: Expecting 41600 events.
[10:32:48.620] <TB2> INFO: 41600 events read in total (4161ms).
[10:32:48.621] <TB2> INFO: Test took 5201ms.
[10:32:48.639] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:48.991] <TB2> INFO: Expecting 41600 events.
[10:32:53.806] <TB2> INFO: 41600 events read in total (4100ms).
[10:32:53.807] <TB2> INFO: Test took 5156ms.
[10:32:53.821] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:54.166] <TB2> INFO: Expecting 41600 events.
[10:32:58.611] <TB2> INFO: 41600 events read in total (3730ms).
[10:32:58.611] <TB2> INFO: Test took 4768ms.
[10:32:58.617] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:58.969] <TB2> INFO: Expecting 41600 events.
[10:33:03.911] <TB2> INFO: 41600 events read in total (4226ms).
[10:33:03.911] <TB2> INFO: Test took 5265ms.
[10:33:03.918] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:04.281] <TB2> INFO: Expecting 41600 events.
[10:33:08.924] <TB2> INFO: 41600 events read in total (3929ms).
[10:33:08.925] <TB2> INFO: Test took 4986ms.
[10:33:08.931] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:09.281] <TB2> INFO: Expecting 2560 events.
[10:33:10.349] <TB2> INFO: 2560 events read in total (352ms).
[10:33:10.349] <TB2> INFO: Test took 1411ms.
[10:33:10.763] <TB2> INFO: Expecting 2560 events.
[10:33:11.833] <TB2> INFO: 2560 events read in total (354ms).
[10:33:11.833] <TB2> INFO: Test took 1484ms.
[10:33:12.247] <TB2> INFO: Expecting 2560 events.
[10:33:13.313] <TB2> INFO: 2560 events read in total (350ms).
[10:33:13.314] <TB2> INFO: Test took 1481ms.
[10:33:13.728] <TB2> INFO: Expecting 2560 events.
[10:33:14.810] <TB2> INFO: 2560 events read in total (366ms).
[10:33:14.811] <TB2> INFO: Test took 1497ms.
[10:33:15.224] <TB2> INFO: Expecting 2560 events.
[10:33:16.293] <TB2> INFO: 2560 events read in total (353ms).
[10:33:16.294] <TB2> INFO: Test took 1476ms.
[10:33:16.707] <TB2> INFO: Expecting 2560 events.
[10:33:17.804] <TB2> INFO: 2560 events read in total (381ms).
[10:33:17.804] <TB2> INFO: Test took 1510ms.
[10:33:18.218] <TB2> INFO: Expecting 2560 events.
[10:33:19.315] <TB2> INFO: 2560 events read in total (381ms).
[10:33:19.315] <TB2> INFO: Test took 1511ms.
[10:33:19.729] <TB2> INFO: Expecting 2560 events.
[10:33:20.796] <TB2> INFO: 2560 events read in total (351ms).
[10:33:20.796] <TB2> INFO: Test took 1480ms.
[10:33:21.210] <TB2> INFO: Expecting 2560 events.
[10:33:22.278] <TB2> INFO: 2560 events read in total (352ms).
[10:33:22.278] <TB2> INFO: Test took 1482ms.
[10:33:22.692] <TB2> INFO: Expecting 2560 events.
[10:33:23.766] <TB2> INFO: 2560 events read in total (358ms).
[10:33:23.766] <TB2> INFO: Test took 1487ms.
[10:33:24.179] <TB2> INFO: Expecting 2560 events.
[10:33:25.250] <TB2> INFO: 2560 events read in total (355ms).
[10:33:25.250] <TB2> INFO: Test took 1483ms.
[10:33:25.663] <TB2> INFO: Expecting 2560 events.
[10:33:26.732] <TB2> INFO: 2560 events read in total (353ms).
[10:33:26.732] <TB2> INFO: Test took 1481ms.
[10:33:27.146] <TB2> INFO: Expecting 2560 events.
[10:33:28.218] <TB2> INFO: 2560 events read in total (356ms).
[10:33:28.218] <TB2> INFO: Test took 1485ms.
[10:33:28.632] <TB2> INFO: Expecting 2560 events.
[10:33:29.705] <TB2> INFO: 2560 events read in total (357ms).
[10:33:29.705] <TB2> INFO: Test took 1486ms.
[10:33:30.119] <TB2> INFO: Expecting 2560 events.
[10:33:31.203] <TB2> INFO: 2560 events read in total (368ms).
[10:33:31.204] <TB2> INFO: Test took 1498ms.
[10:33:31.617] <TB2> INFO: Expecting 2560 events.
[10:33:32.687] <TB2> INFO: 2560 events read in total (354ms).
[10:33:32.688] <TB2> INFO: Test took 1484ms.
[10:33:32.692] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:33:33.103] <TB2> INFO: Expecting 655360 events.
[10:33:49.431] <TB2> INFO: 655360 events read in total (15612ms).
[10:33:49.440] <TB2> INFO: Expecting 655360 events.
[10:34:05.504] <TB2> INFO: 655360 events read in total (15536ms).
[10:34:05.521] <TB2> INFO: Expecting 655360 events.
[10:34:21.492] <TB2> INFO: 655360 events read in total (15443ms).
[10:34:21.508] <TB2> INFO: Expecting 655360 events.
[10:34:37.694] <TB2> INFO: 655360 events read in total (15659ms).
[10:34:37.713] <TB2> INFO: Expecting 655360 events.
[10:34:51.808] <TB2> INFO: 655360 events read in total (13567ms).
[10:34:51.830] <TB2> INFO: Expecting 655360 events.
[10:35:05.718] <TB2> INFO: 655360 events read in total (13360ms).
[10:35:05.743] <TB2> INFO: Expecting 655360 events.
[10:35:19.956] <TB2> INFO: 655360 events read in total (13686ms).
[10:35:20.133] <TB2> INFO: Expecting 655360 events.
[10:36:29.831] <TB2> INFO: 655360 events read in total (69170ms).
[10:36:29.911] <TB2> INFO: Expecting 655360 events.
[10:36:46.260] <TB2> INFO: 655360 events read in total (15823ms).
[10:36:46.321] <TB2> INFO: Expecting 655360 events.
[10:36:59.935] <TB2> INFO: 655360 events read in total (13087ms).
[10:36:59.973] <TB2> INFO: Expecting 655360 events.
[10:37:13.439] <TB2> INFO: 655360 events read in total (12939ms).
[10:37:13.487] <TB2> INFO: Expecting 655360 events.
[10:37:26.965] <TB2> INFO: 655360 events read in total (12951ms).
[10:37:27.009] <TB2> INFO: Expecting 655360 events.
[10:37:40.492] <TB2> INFO: 655360 events read in total (12956ms).
[10:37:40.543] <TB2> INFO: Expecting 655360 events.
[10:37:54.038] <TB2> INFO: 655360 events read in total (12968ms).
[10:37:54.154] <TB2> INFO: Expecting 655360 events.
[10:38:07.544] <TB2> INFO: 655360 events read in total (12862ms).
[10:38:07.598] <TB2> INFO: Expecting 655360 events.
[10:38:22.546] <TB2> INFO: 655360 events read in total (14421ms).
[10:38:22.636] <TB2> INFO: Test took 289944ms.
[10:38:23.587] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:38:23.718] <TB2> INFO: Expecting 655360 events.
[10:38:37.343] <TB2> INFO: 655360 events read in total (12910ms).
[10:38:37.351] <TB2> INFO: Expecting 655360 events.
[10:38:50.768] <TB2> INFO: 655360 events read in total (12889ms).
[10:38:50.779] <TB2> INFO: Expecting 655360 events.
[10:39:04.205] <TB2> INFO: 655360 events read in total (12898ms).
[10:39:04.219] <TB2> INFO: Expecting 655360 events.
[10:39:17.586] <TB2> INFO: 655360 events read in total (12840ms).
[10:39:17.604] <TB2> INFO: Expecting 655360 events.
[10:39:31.015] <TB2> INFO: 655360 events read in total (12884ms).
[10:39:31.037] <TB2> INFO: Expecting 655360 events.
[10:39:44.355] <TB2> INFO: 655360 events read in total (12791ms).
[10:39:44.379] <TB2> INFO: Expecting 655360 events.
[10:39:57.779] <TB2> INFO: 655360 events read in total (12872ms).
[10:39:57.807] <TB2> INFO: Expecting 655360 events.
[10:40:11.232] <TB2> INFO: 655360 events read in total (12898ms).
[10:40:11.266] <TB2> INFO: Expecting 655360 events.
[10:40:24.672] <TB2> INFO: 655360 events read in total (12878ms).
[10:40:24.708] <TB2> INFO: Expecting 655360 events.
[10:40:37.980] <TB2> INFO: 655360 events read in total (12745ms).
[10:40:38.022] <TB2> INFO: Expecting 655360 events.
[10:40:51.447] <TB2> INFO: 655360 events read in total (12898ms).
[10:40:51.488] <TB2> INFO: Expecting 655360 events.
[10:41:04.910] <TB2> INFO: 655360 events read in total (12894ms).
[10:41:04.954] <TB2> INFO: Expecting 655360 events.
[10:41:18.206] <TB2> INFO: 655360 events read in total (12725ms).
[10:41:18.254] <TB2> INFO: Expecting 655360 events.
[10:41:31.755] <TB2> INFO: 655360 events read in total (12973ms).
[10:41:31.805] <TB2> INFO: Expecting 655360 events.
[10:41:45.280] <TB2> INFO: 655360 events read in total (12947ms).
[10:41:45.333] <TB2> INFO: Expecting 655360 events.
[10:41:58.676] <TB2> INFO: 655360 events read in total (12815ms).
[10:41:58.733] <TB2> INFO: Test took 215146ms.
[10:41:59.796] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.802] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.809] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.816] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.822] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[10:41:59.829] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[10:41:59.842] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[10:41:59.849] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[10:41:59.855] <TB2> INFO: safety margin for low PH: adding 5, margin is now 25
[10:41:59.862] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.869] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.875] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.881] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.888] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.895] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.901] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.908] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.915] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.921] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.928] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[10:41:59.935] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:41:59.941] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:42:01.502] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:42:01.514] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:42:01.514] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:42:01.514] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:42:01.515] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:42:01.519] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:42:01.519] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:42:01.520] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:42:01.520] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:42:01.520] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:42:01.520] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:42:01.520] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:42:01.521] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:42:01.521] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:42:01.521] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:42:01.521] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:42:01.823] <TB2> INFO: Expecting 41600 events.
[10:42:05.666] <TB2> INFO: 41600 events read in total (3127ms).
[10:42:05.666] <TB2> INFO: Test took 4142ms.
[10:42:06.209] <TB2> INFO: Expecting 41600 events.
[10:42:10.046] <TB2> INFO: 41600 events read in total (3122ms).
[10:42:10.046] <TB2> INFO: Test took 4137ms.
[10:42:10.585] <TB2> INFO: Expecting 41600 events.
[10:42:14.427] <TB2> INFO: 41600 events read in total (3127ms).
[10:42:14.428] <TB2> INFO: Test took 4141ms.
[10:42:14.670] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:14.801] <TB2> INFO: Expecting 2560 events.
[10:42:15.861] <TB2> INFO: 2560 events read in total (345ms).
[10:42:15.861] <TB2> INFO: Test took 1191ms.
[10:42:15.863] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:16.276] <TB2> INFO: Expecting 2560 events.
[10:42:17.337] <TB2> INFO: 2560 events read in total (345ms).
[10:42:17.337] <TB2> INFO: Test took 1474ms.
[10:42:17.339] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:17.752] <TB2> INFO: Expecting 2560 events.
[10:42:18.813] <TB2> INFO: 2560 events read in total (345ms).
[10:42:18.813] <TB2> INFO: Test took 1474ms.
[10:42:18.815] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:19.228] <TB2> INFO: Expecting 2560 events.
[10:42:20.289] <TB2> INFO: 2560 events read in total (345ms).
[10:42:20.289] <TB2> INFO: Test took 1474ms.
[10:42:20.291] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:20.704] <TB2> INFO: Expecting 2560 events.
[10:42:21.765] <TB2> INFO: 2560 events read in total (346ms).
[10:42:21.765] <TB2> INFO: Test took 1474ms.
[10:42:21.766] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:22.180] <TB2> INFO: Expecting 2560 events.
[10:42:23.241] <TB2> INFO: 2560 events read in total (346ms).
[10:42:23.241] <TB2> INFO: Test took 1475ms.
[10:42:23.242] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:23.655] <TB2> INFO: Expecting 2560 events.
[10:42:24.716] <TB2> INFO: 2560 events read in total (345ms).
[10:42:24.717] <TB2> INFO: Test took 1475ms.
[10:42:24.718] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:25.131] <TB2> INFO: Expecting 2560 events.
[10:42:26.192] <TB2> INFO: 2560 events read in total (345ms).
[10:42:26.193] <TB2> INFO: Test took 1475ms.
[10:42:26.194] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:26.607] <TB2> INFO: Expecting 2560 events.
[10:42:27.668] <TB2> INFO: 2560 events read in total (345ms).
[10:42:27.668] <TB2> INFO: Test took 1474ms.
[10:42:27.670] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:28.083] <TB2> INFO: Expecting 2560 events.
[10:42:29.144] <TB2> INFO: 2560 events read in total (345ms).
[10:42:29.144] <TB2> INFO: Test took 1474ms.
[10:42:29.146] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:29.559] <TB2> INFO: Expecting 2560 events.
[10:42:30.620] <TB2> INFO: 2560 events read in total (345ms).
[10:42:30.620] <TB2> INFO: Test took 1474ms.
[10:42:30.622] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:31.035] <TB2> INFO: Expecting 2560 events.
[10:42:32.096] <TB2> INFO: 2560 events read in total (345ms).
[10:42:32.096] <TB2> INFO: Test took 1474ms.
[10:42:32.098] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:32.511] <TB2> INFO: Expecting 2560 events.
[10:42:33.572] <TB2> INFO: 2560 events read in total (345ms).
[10:42:33.572] <TB2> INFO: Test took 1474ms.
[10:42:33.574] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:33.987] <TB2> INFO: Expecting 2560 events.
[10:42:35.048] <TB2> INFO: 2560 events read in total (345ms).
[10:42:35.049] <TB2> INFO: Test took 1476ms.
[10:42:35.050] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:35.464] <TB2> INFO: Expecting 2560 events.
[10:42:36.524] <TB2> INFO: 2560 events read in total (345ms).
[10:42:36.525] <TB2> INFO: Test took 1475ms.
[10:42:36.528] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:36.940] <TB2> INFO: Expecting 2560 events.
[10:42:38.001] <TB2> INFO: 2560 events read in total (345ms).
[10:42:38.002] <TB2> INFO: Test took 1474ms.
[10:42:38.004] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:38.417] <TB2> INFO: Expecting 2560 events.
[10:42:39.478] <TB2> INFO: 2560 events read in total (345ms).
[10:42:39.479] <TB2> INFO: Test took 1475ms.
[10:42:39.482] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:39.894] <TB2> INFO: Expecting 2560 events.
[10:42:40.956] <TB2> INFO: 2560 events read in total (346ms).
[10:42:40.957] <TB2> INFO: Test took 1476ms.
[10:42:40.959] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:41.371] <TB2> INFO: Expecting 2560 events.
[10:42:42.433] <TB2> INFO: 2560 events read in total (346ms).
[10:42:42.433] <TB2> INFO: Test took 1474ms.
[10:42:42.435] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:42.848] <TB2> INFO: Expecting 2560 events.
[10:42:43.909] <TB2> INFO: 2560 events read in total (345ms).
[10:42:43.910] <TB2> INFO: Test took 1476ms.
[10:42:43.911] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:44.325] <TB2> INFO: Expecting 2560 events.
[10:42:45.387] <TB2> INFO: 2560 events read in total (346ms).
[10:42:45.387] <TB2> INFO: Test took 1476ms.
[10:42:45.389] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:45.802] <TB2> INFO: Expecting 2560 events.
[10:42:46.864] <TB2> INFO: 2560 events read in total (346ms).
[10:42:46.864] <TB2> INFO: Test took 1475ms.
[10:42:46.871] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:47.279] <TB2> INFO: Expecting 2560 events.
[10:42:48.341] <TB2> INFO: 2560 events read in total (346ms).
[10:42:48.341] <TB2> INFO: Test took 1470ms.
[10:42:48.343] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:48.757] <TB2> INFO: Expecting 2560 events.
[10:42:49.818] <TB2> INFO: 2560 events read in total (346ms).
[10:42:49.819] <TB2> INFO: Test took 1476ms.
[10:42:49.821] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:50.234] <TB2> INFO: Expecting 2560 events.
[10:42:51.296] <TB2> INFO: 2560 events read in total (346ms).
[10:42:51.296] <TB2> INFO: Test took 1475ms.
[10:42:51.298] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:51.712] <TB2> INFO: Expecting 2560 events.
[10:42:52.773] <TB2> INFO: 2560 events read in total (346ms).
[10:42:52.773] <TB2> INFO: Test took 1475ms.
[10:42:52.776] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:53.189] <TB2> INFO: Expecting 2560 events.
[10:42:54.250] <TB2> INFO: 2560 events read in total (346ms).
[10:42:54.251] <TB2> INFO: Test took 1475ms.
[10:42:54.253] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:54.665] <TB2> INFO: Expecting 2560 events.
[10:42:55.727] <TB2> INFO: 2560 events read in total (346ms).
[10:42:55.727] <TB2> INFO: Test took 1475ms.
[10:42:55.729] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:56.142] <TB2> INFO: Expecting 2560 events.
[10:42:57.204] <TB2> INFO: 2560 events read in total (346ms).
[10:42:57.204] <TB2> INFO: Test took 1475ms.
[10:42:57.206] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:57.619] <TB2> INFO: Expecting 2560 events.
[10:42:58.681] <TB2> INFO: 2560 events read in total (346ms).
[10:42:58.681] <TB2> INFO: Test took 1475ms.
[10:42:58.683] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:42:59.097] <TB2> INFO: Expecting 2560 events.
[10:43:00.158] <TB2> INFO: 2560 events read in total (346ms).
[10:43:00.158] <TB2> INFO: Test took 1475ms.
[10:43:00.160] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:43:00.573] <TB2> INFO: Expecting 2560 events.
[10:43:01.634] <TB2> INFO: 2560 events read in total (346ms).
[10:43:01.634] <TB2> INFO: Test took 1474ms.
[10:43:02.310] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 868 seconds
[10:43:02.310] <TB2> INFO: PH scale (per ROC): 80 76 79 80 77 74 80 79 71 84 69 80 68 75 74 77
[10:43:02.310] <TB2> INFO: PH offset (per ROC): 171 171 181 170 160 159 153 171 170 146 167 159 173 176 148 163
[10:43:02.344] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:02.344] <TB2> INFO: Decoding statistics:
[10:43:02.344] <TB2> INFO: General information:
[10:43:02.344] <TB2> INFO: 16bit words read: 91932
[10:43:02.344] <TB2> INFO: valid events total: 10240
[10:43:02.344] <TB2> INFO: empty events: 7737
[10:43:02.344] <TB2> INFO: valid events with pixels: 2503
[10:43:02.344] <TB2> INFO: valid pixel hits: 2503
[10:43:02.344] <TB2> INFO: Event errors: 0
[10:43:02.344] <TB2> INFO: start marker: 0
[10:43:02.344] <TB2> INFO: stop marker: 0
[10:43:02.344] <TB2> INFO: overflow: 0
[10:43:02.344] <TB2> INFO: invalid 5bit words: 0
[10:43:02.344] <TB2> INFO: invalid XOR eye diagram: 0
[10:43:02.344] <TB2> INFO: TBM errors: 0
[10:43:02.344] <TB2> INFO: flawed TBM headers: 0
[10:43:02.344] <TB2> INFO: flawed TBM trailers: 0
[10:43:02.344] <TB2> INFO: event ID mismatches: 0
[10:43:02.344] <TB2> INFO: ROC errors: 0
[10:43:02.344] <TB2> INFO: missing ROC header(s): 0
[10:43:02.344] <TB2> INFO: misplaced readback start: 0
[10:43:02.344] <TB2> INFO: Pixel decoding errors: 0
[10:43:02.344] <TB2> INFO: pixel data incomplete: 0
[10:43:02.344] <TB2> INFO: pixel address: 0
[10:43:02.344] <TB2> INFO: pulse height fill bit: 0
[10:43:02.344] <TB2> INFO: buffer corruption: 0
[10:43:03.492] <TB2> INFO: ######################################################################
[10:43:03.492] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:43:03.492] <TB2> INFO: ######################################################################
[10:43:08.540] <TB2> INFO: scanning low vcal = 10
[10:43:08.838] <TB2> INFO: Expecting 41600 events.
[10:43:12.575] <TB2> INFO: 41600 events read in total (3021ms).
[10:43:12.575] <TB2> INFO: Test took 4035ms.
[10:43:12.577] <TB2> INFO: scanning low vcal = 20
[10:43:12.989] <TB2> INFO: Expecting 41600 events.
[10:43:16.724] <TB2> INFO: 41600 events read in total (3019ms).
[10:43:16.724] <TB2> INFO: Test took 4147ms.
[10:43:16.726] <TB2> INFO: scanning low vcal = 30
[10:43:17.138] <TB2> INFO: Expecting 41600 events.
[10:43:20.905] <TB2> INFO: 41600 events read in total (3051ms).
[10:43:20.906] <TB2> INFO: Test took 4180ms.
[10:43:20.908] <TB2> INFO: scanning low vcal = 40
[10:43:21.313] <TB2> INFO: Expecting 41600 events.
[10:43:25.515] <TB2> INFO: 41600 events read in total (3486ms).
[10:43:25.516] <TB2> INFO: Test took 4608ms.
[10:43:25.518] <TB2> INFO: scanning low vcal = 50
[10:43:25.875] <TB2> INFO: Expecting 41600 events.
[10:43:30.079] <TB2> INFO: 41600 events read in total (3488ms).
[10:43:30.079] <TB2> INFO: Test took 4561ms.
[10:43:30.082] <TB2> INFO: scanning low vcal = 60
[10:43:30.439] <TB2> INFO: Expecting 41600 events.
[10:43:34.642] <TB2> INFO: 41600 events read in total (3487ms).
[10:43:34.643] <TB2> INFO: Test took 4561ms.
[10:43:34.645] <TB2> INFO: scanning low vcal = 70
[10:43:35.004] <TB2> INFO: Expecting 41600 events.
[10:43:39.208] <TB2> INFO: 41600 events read in total (3489ms).
[10:43:39.209] <TB2> INFO: Test took 4564ms.
[10:43:39.211] <TB2> INFO: scanning low vcal = 80
[10:43:39.570] <TB2> INFO: Expecting 41600 events.
[10:43:43.784] <TB2> INFO: 41600 events read in total (3499ms).
[10:43:43.784] <TB2> INFO: Test took 4573ms.
[10:43:43.787] <TB2> INFO: scanning low vcal = 90
[10:43:44.143] <TB2> INFO: Expecting 41600 events.
[10:43:48.357] <TB2> INFO: 41600 events read in total (3498ms).
[10:43:48.357] <TB2> INFO: Test took 4570ms.
[10:43:48.360] <TB2> INFO: scanning low vcal = 100
[10:43:48.716] <TB2> INFO: Expecting 41600 events.
[10:43:53.042] <TB2> INFO: 41600 events read in total (3611ms).
[10:43:53.043] <TB2> INFO: Test took 4683ms.
[10:43:53.045] <TB2> INFO: scanning low vcal = 110
[10:43:53.405] <TB2> INFO: Expecting 41600 events.
[10:43:57.624] <TB2> INFO: 41600 events read in total (3503ms).
[10:43:57.625] <TB2> INFO: Test took 4580ms.
[10:43:57.627] <TB2> INFO: scanning low vcal = 120
[10:43:57.987] <TB2> INFO: Expecting 41600 events.
[10:44:02.209] <TB2> INFO: 41600 events read in total (3506ms).
[10:44:02.209] <TB2> INFO: Test took 4582ms.
[10:44:02.212] <TB2> INFO: scanning low vcal = 130
[10:44:02.571] <TB2> INFO: Expecting 41600 events.
[10:44:06.792] <TB2> INFO: 41600 events read in total (3505ms).
[10:44:06.792] <TB2> INFO: Test took 4580ms.
[10:44:06.795] <TB2> INFO: scanning low vcal = 140
[10:44:07.154] <TB2> INFO: Expecting 41600 events.
[10:44:11.376] <TB2> INFO: 41600 events read in total (3506ms).
[10:44:11.377] <TB2> INFO: Test took 4582ms.
[10:44:11.379] <TB2> INFO: scanning low vcal = 150
[10:44:11.739] <TB2> INFO: Expecting 41600 events.
[10:44:15.964] <TB2> INFO: 41600 events read in total (3510ms).
[10:44:15.964] <TB2> INFO: Test took 4585ms.
[10:44:15.967] <TB2> INFO: scanning low vcal = 160
[10:44:16.324] <TB2> INFO: Expecting 41600 events.
[10:44:20.546] <TB2> INFO: 41600 events read in total (3507ms).
[10:44:20.546] <TB2> INFO: Test took 4579ms.
[10:44:20.549] <TB2> INFO: scanning low vcal = 170
[10:44:20.909] <TB2> INFO: Expecting 41600 events.
[10:44:25.130] <TB2> INFO: 41600 events read in total (3505ms).
[10:44:25.131] <TB2> INFO: Test took 4582ms.
[10:44:25.134] <TB2> INFO: scanning low vcal = 180
[10:44:25.493] <TB2> INFO: Expecting 41600 events.
[10:44:29.715] <TB2> INFO: 41600 events read in total (3506ms).
[10:44:29.716] <TB2> INFO: Test took 4582ms.
[10:44:29.719] <TB2> INFO: scanning low vcal = 190
[10:44:30.078] <TB2> INFO: Expecting 41600 events.
[10:44:34.323] <TB2> INFO: 41600 events read in total (3529ms).
[10:44:34.324] <TB2> INFO: Test took 4605ms.
[10:44:34.329] <TB2> INFO: scanning low vcal = 200
[10:44:34.684] <TB2> INFO: Expecting 41600 events.
[10:44:38.908] <TB2> INFO: 41600 events read in total (3508ms).
[10:44:38.908] <TB2> INFO: Test took 4579ms.
[10:44:38.911] <TB2> INFO: scanning low vcal = 210
[10:44:39.270] <TB2> INFO: Expecting 41600 events.
[10:44:43.489] <TB2> INFO: 41600 events read in total (3503ms).
[10:44:43.489] <TB2> INFO: Test took 4578ms.
[10:44:43.492] <TB2> INFO: scanning low vcal = 220
[10:44:43.850] <TB2> INFO: Expecting 41600 events.
[10:44:48.074] <TB2> INFO: 41600 events read in total (3508ms).
[10:44:48.074] <TB2> INFO: Test took 4582ms.
[10:44:48.077] <TB2> INFO: scanning low vcal = 230
[10:44:48.433] <TB2> INFO: Expecting 41600 events.
[10:44:52.658] <TB2> INFO: 41600 events read in total (3509ms).
[10:44:52.658] <TB2> INFO: Test took 4581ms.
[10:44:52.662] <TB2> INFO: scanning low vcal = 240
[10:44:53.018] <TB2> INFO: Expecting 41600 events.
[10:44:57.238] <TB2> INFO: 41600 events read in total (3504ms).
[10:44:57.238] <TB2> INFO: Test took 4576ms.
[10:44:57.241] <TB2> INFO: scanning low vcal = 250
[10:44:57.600] <TB2> INFO: Expecting 41600 events.
[10:45:01.822] <TB2> INFO: 41600 events read in total (3506ms).
[10:45:01.823] <TB2> INFO: Test took 4582ms.
[10:45:01.827] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[10:45:02.186] <TB2> INFO: Expecting 41600 events.
[10:45:06.409] <TB2> INFO: 41600 events read in total (3507ms).
[10:45:06.410] <TB2> INFO: Test took 4583ms.
[10:45:06.413] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[10:45:06.771] <TB2> INFO: Expecting 41600 events.
[10:45:10.993] <TB2> INFO: 41600 events read in total (3506ms).
[10:45:10.994] <TB2> INFO: Test took 4581ms.
[10:45:10.996] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[10:45:11.356] <TB2> INFO: Expecting 41600 events.
[10:45:15.576] <TB2> INFO: 41600 events read in total (3504ms).
[10:45:15.576] <TB2> INFO: Test took 4580ms.
[10:45:15.579] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[10:45:15.938] <TB2> INFO: Expecting 41600 events.
[10:45:20.167] <TB2> INFO: 41600 events read in total (3513ms).
[10:45:20.167] <TB2> INFO: Test took 4588ms.
[10:45:20.171] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:45:20.528] <TB2> INFO: Expecting 41600 events.
[10:45:24.747] <TB2> INFO: 41600 events read in total (3503ms).
[10:45:24.748] <TB2> INFO: Test took 4577ms.
[10:45:25.184] <TB2> INFO: PixTestGainPedestal::measure() done
[10:45:56.665] <TB2> INFO: PixTestGainPedestal::fit() done
[10:45:56.665] <TB2> INFO: non-linearity mean: 0.958 0.961 0.948 0.958 0.956 0.957 0.956 0.959 0.957 0.950 0.954 0.962 0.963 0.947 0.951 0.954
[10:45:56.665] <TB2> INFO: non-linearity RMS: 0.006 0.006 0.008 0.005 0.005 0.006 0.005 0.005 0.007 0.007 0.006 0.005 0.005 0.006 0.007 0.006
[10:45:56.666] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:45:56.683] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:45:56.701] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:45:56.719] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:45:56.736] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:45:56.754] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:45:56.772] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:45:56.790] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:45:56.808] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:45:56.825] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:45:56.843] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:45:56.861] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:45:56.879] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:45:56.896] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:45:56.914] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:45:56.932] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:45:56.949] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 173 seconds
[10:45:56.949] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:56.949] <TB2> INFO: Decoding statistics:
[10:45:56.949] <TB2> INFO: General information:
[10:45:56.949] <TB2> INFO: 16bit words read: 2662280
[10:45:56.949] <TB2> INFO: valid events total: 166400
[10:45:56.949] <TB2> INFO: empty events: 0
[10:45:56.949] <TB2> INFO: valid events with pixels: 166400
[10:45:56.949] <TB2> INFO: valid pixel hits: 665357
[10:45:56.949] <TB2> INFO: Event errors: 0
[10:45:56.949] <TB2> INFO: start marker: 0
[10:45:56.949] <TB2> INFO: stop marker: 0
[10:45:56.949] <TB2> INFO: overflow: 0
[10:45:56.949] <TB2> INFO: invalid 5bit words: 0
[10:45:56.949] <TB2> INFO: invalid XOR eye diagram: 0
[10:45:56.949] <TB2> INFO: TBM errors: 0
[10:45:56.949] <TB2> INFO: flawed TBM headers: 0
[10:45:56.949] <TB2> INFO: flawed TBM trailers: 0
[10:45:56.949] <TB2> INFO: event ID mismatches: 0
[10:45:56.949] <TB2> INFO: ROC errors: 0
[10:45:56.949] <TB2> INFO: missing ROC header(s): 0
[10:45:56.949] <TB2> INFO: misplaced readback start: 0
[10:45:56.949] <TB2> INFO: Pixel decoding errors: 0
[10:45:56.949] <TB2> INFO: pixel data incomplete: 0
[10:45:56.949] <TB2> INFO: pixel address: 0
[10:45:56.949] <TB2> INFO: pulse height fill bit: 0
[10:45:56.949] <TB2> INFO: buffer corruption: 0
[10:45:56.956] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C15.dat
[10:45:56.972] <TB2> INFO: ######################################################################
[10:45:56.972] <TB2> INFO: PixTestReadback::doTest()
[10:45:56.972] <TB2> INFO: ######################################################################
[10:45:56.973] <TB2> INFO: PixTestReadback::RES sent once
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C0.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C1.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C2.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C3.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C4.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C5.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C6.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C7.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C8.dat
[10:46:09.592] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C9.dat
[10:46:09.593] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C10.dat
[10:46:09.593] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C11.dat
[10:46:09.593] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C12.dat
[10:46:09.593] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C13.dat
[10:46:09.593] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C14.dat
[10:46:09.593] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C15.dat
[10:46:09.627] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:46:09.627] <TB2> INFO: PixTestReadback::RES sent once
[10:46:20.791] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C0.dat
[10:46:20.792] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C1.dat
[10:46:20.792] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C2.dat
[10:46:20.792] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C3.dat
[10:46:20.792] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C4.dat
[10:46:20.792] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C5.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C6.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C7.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C8.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C9.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C10.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C11.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C12.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C13.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C14.dat
[10:46:20.793] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C15.dat
[10:46:20.821] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:46:20.822] <TB2> INFO: PixTestReadback::RES sent once
[10:46:29.414] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:46:29.414] <TB2> INFO: Vbg will be calibrated using Vd calibration
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.1calibrated Vbg = 1.18877 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.7calibrated Vbg = 1.19068 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159calibrated Vbg = 1.19521 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.6calibrated Vbg = 1.19821 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.2calibrated Vbg = 1.20446 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.7calibrated Vbg = 1.20669 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 143.4calibrated Vbg = 1.20502 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 159.9calibrated Vbg = 1.20269 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 163.6calibrated Vbg = 1.21289 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.2calibrated Vbg = 1.20966 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156calibrated Vbg = 1.2117 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 148.4calibrated Vbg = 1.2082 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.3calibrated Vbg = 1.20954 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 150calibrated Vbg = 1.20561 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.4calibrated Vbg = 1.20325 :::*/*/*/*/
[10:46:29.414] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.6calibrated Vbg = 1.19192 :::*/*/*/*/
[10:46:29.418] <TB2> INFO: PixTestReadback::RES sent once
[10:49:23.854] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C0.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C1.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C2.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C3.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C4.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C5.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C6.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C7.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C8.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C9.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C10.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C11.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C12.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C13.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C14.dat
[10:49:23.856] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2119_FullQualification_2015-11-20_09h55m_1448009726//000_FulltestPxar_m20//readbackCal_C15.dat
[10:49:23.883] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:49:23.913] <TB2> INFO: PixTestReadback::doTest() done
[10:49:23.914] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:23.914] <TB2> INFO: Decoding statistics:
[10:49:23.914] <TB2> INFO: General information:
[10:49:23.914] <TB2> INFO: 16bit words read: 1024
[10:49:23.914] <TB2> INFO: valid events total: 128
[10:49:23.914] <TB2> INFO: empty events: 128
[10:49:23.914] <TB2> INFO: valid events with pixels: 0
[10:49:23.914] <TB2> INFO: valid pixel hits: 0
[10:49:23.914] <TB2> INFO: Event errors: 0
[10:49:23.914] <TB2> INFO: start marker: 0
[10:49:23.914] <TB2> INFO: stop marker: 0
[10:49:23.914] <TB2> INFO: overflow: 0
[10:49:23.914] <TB2> INFO: invalid 5bit words: 0
[10:49:23.914] <TB2> INFO: invalid XOR eye diagram: 0
[10:49:23.914] <TB2> INFO: TBM errors: 0
[10:49:23.914] <TB2> INFO: flawed TBM headers: 0
[10:49:23.914] <TB2> INFO: flawed TBM trailers: 0
[10:49:23.914] <TB2> INFO: event ID mismatches: 0
[10:49:23.914] <TB2> INFO: ROC errors: 0
[10:49:23.914] <TB2> INFO: missing ROC header(s): 0
[10:49:23.914] <TB2> INFO: misplaced readback start: 0
[10:49:23.914] <TB2> INFO: Pixel decoding errors: 0
[10:49:23.914] <TB2> INFO: pixel data incomplete: 0
[10:49:23.914] <TB2> INFO: pixel address: 0
[10:49:23.914] <TB2> INFO: pulse height fill bit: 0
[10:49:23.914] <TB2> INFO: buffer corruption: 0
[10:49:24.090] <TB2> INFO: Decoding statistics:
[10:49:24.090] <TB2> INFO: General information:
[10:49:24.090] <TB2> INFO: 16bit words read: 2755236
[10:49:24.090] <TB2> INFO: valid events total: 176768
[10:49:24.090] <TB2> INFO: empty events: 7865
[10:49:24.090] <TB2> INFO: valid events with pixels: 168903
[10:49:24.090] <TB2> INFO: valid pixel hits: 667860
[10:49:24.090] <TB2> INFO: Event errors: 0
[10:49:24.090] <TB2> INFO: start marker: 0
[10:49:24.090] <TB2> INFO: stop marker: 0
[10:49:24.090] <TB2> INFO: overflow: 0
[10:49:24.090] <TB2> INFO: invalid 5bit words: 0
[10:49:24.090] <TB2> INFO: invalid XOR eye diagram: 0
[10:49:24.090] <TB2> INFO: TBM errors: 0
[10:49:24.090] <TB2> INFO: flawed TBM headers: 0
[10:49:24.090] <TB2> INFO: flawed TBM trailers: 0
[10:49:24.090] <TB2> INFO: event ID mismatches: 0
[10:49:24.090] <TB2> INFO: ROC errors: 0
[10:49:24.090] <TB2> INFO: missing ROC header(s): 0
[10:49:24.090] <TB2> INFO: misplaced readback start: 0
[10:49:24.091] <TB2> INFO: Pixel decoding errors: 0
[10:49:24.091] <TB2> INFO: pixel data incomplete: 0
[10:49:24.091] <TB2> INFO: pixel address: 0
[10:49:24.091] <TB2> INFO: pulse height fill bit: 0
[10:49:24.091] <TB2> INFO: buffer corruption: 0
[10:49:24.104] <TB2> INFO: enter test to run
[10:49:24.104] <TB2> INFO: test: exit no parameter change
[10:49:26.764] <TB2> QUIET: Connection to board 156 closed.
[10:49:26.852] <TB2> INFO: pXar: this is the end, my friend