[09:37:05.489] <TB2> INFO: *** Welcome to pxar ***
[09:37:05.489] <TB2> INFO: *** Today: 2016/04/22
[09:37:05.594] <TB2> INFO: *** Version: 9751-dirty
[09:37:05.594] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C15.dat
[09:37:05.596] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:37:05.596] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//defaultMaskFile.dat
[09:37:05.596] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters_C15.dat
[09:37:05.668] <TB2> INFO: clk: 4
[09:37:05.668] <TB2> INFO: ctr: 4
[09:37:05.668] <TB2> INFO: sda: 19
[09:37:05.668] <TB2> INFO: tin: 9
[09:37:05.668] <TB2> INFO: level: 15
[09:37:05.668] <TB2> INFO: triggerdelay: 0
[09:37:05.668] <TB2> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[09:37:05.668] <TB2> INFO: Log level: INFO
[09:37:05.675] <TB2> INFO: Found DTB DTB_WXC55Z
[09:37:05.684] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[09:37:05.686] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[09:37:05.687] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[09:37:07.231] <TB2> INFO: DUT info:
[09:37:07.231] <TB2> INFO: The DUT currently contains the following objects:
[09:37:07.231] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[09:37:07.231] <TB2> INFO: TBM Core alpha (0): 7 registers set
[09:37:07.231] <TB2> INFO: TBM Core beta (1): 7 registers set
[09:37:07.231] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:37:07.231] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.231] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.231] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.231] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.231] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.231] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.231] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.231] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.231] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.232] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.232] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.232] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.232] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.232] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.232] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.232] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:37:07.633] <TB2> INFO: enter 'restricted' command line mode
[09:37:07.633] <TB2> INFO: enter test to run
[09:37:07.633] <TB2> INFO: test: pretest no parameter change
[09:37:07.633] <TB2> INFO: running: pretest
[09:37:07.639] <TB2> INFO: ----------------------------------------------------------------------
[09:37:07.639] <TB2> INFO: PixTestPretest::programROC()
[09:37:07.639] <TB2> INFO: ----------------------------------------------------------------------
[09:37:25.654] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:37:25.654] <TB2> INFO: IA differences per ROC: 17.7 20.9 19.3 20.9 20.1 19.3 18.5 20.1 19.3 19.3 18.5 19.3 19.3 20.1 20.1 20.9
[09:37:25.752] <TB2> INFO: enter test to run
[09:37:25.752] <TB2> INFO: test: pretest no parameter change
[09:37:25.752] <TB2> INFO: running: pretest
[09:37:25.753] <TB2> INFO: ----------------------------------------------------------------------
[09:37:25.753] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:37:25.753] <TB2> INFO: ----------------------------------------------------------------------
[09:37:47.044] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[09:37:47.044] <TB2> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 18.5 19.3 17.6 19.3 19.3 19.3 17.6 18.5 18.5 19.3 18.5 19.3 18.5
[09:37:47.080] <TB2> INFO: enter test to run
[09:37:47.080] <TB2> INFO: test: pretest no parameter change
[09:37:47.080] <TB2> INFO: running: pretest
[09:37:47.080] <TB2> INFO: ----------------------------------------------------------------------
[09:37:47.080] <TB2> INFO: PixTestPretest::findTiming()
[09:37:47.080] <TB2> INFO: ----------------------------------------------------------------------
[09:37:47.081] <TB2> INFO: PixTestCmd::init()

[09:39:16.133] <TB2> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:39:16.133] <TB2> INFO: (success/tries = 100/100), width = 4
[09:39:16.134] <TB2> INFO: enter test to run
[09:39:16.134] <TB2> INFO: test: pretest no parameter change
[09:39:16.134] <TB2> INFO: running: pretest
[09:39:16.135] <TB2> INFO: ----------------------------------------------------------------------
[09:39:16.135] <TB2> INFO: PixTestPretest::findWorkingPixel()
[09:39:16.135] <TB2> INFO: ----------------------------------------------------------------------
[09:39:16.226] <TB2> INFO: Expecting 231680 events.


[09:39:26.461] <TB2> INFO: 231680 events read in total (9678ms).
[09:39:26.466] <TB2> INFO: Test took 10328ms.
[09:39:26.700] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:39:26.778] <TB2> INFO: enter test to run
[09:39:26.778] <TB2> INFO: test: pretest no parameter change
[09:39:26.778] <TB2> INFO: running: pretest
[09:39:26.780] <TB2> INFO: ----------------------------------------------------------------------
[09:39:26.780] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[09:39:26.780] <TB2> INFO: ----------------------------------------------------------------------
[09:39:26.873] <TB2> INFO: Expecting 231680 events.
[09:39:37.093] <TB2> INFO: 231680 events read in total (9663ms).
[09:39:37.097] <TB2> INFO: Test took 10313ms.
[09:39:37.339] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[09:39:37.339] <TB2> INFO: CalDel: 139 149 139 184 155 161 156 145 156 153 142 148 137 154 125 121
[09:39:37.339] <TB2> INFO: VthrComp: 51 52 51 51 51 53 51 54 52 51 51 51 51 51 51 55
[09:39:37.372] <TB2> INFO: enter test to run
[09:39:37.372] <TB2> INFO: test: pretest no parameter change
[09:39:37.372] <TB2> INFO: running: pretest
[09:39:37.372] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C0.dat
[09:39:37.372] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C1.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C2.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C3.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C4.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C5.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C6.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C7.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C8.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C9.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C10.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C11.dat
[09:39:37.373] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C12.dat
[09:39:37.374] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C13.dat
[09:39:37.374] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C14.dat
[09:39:37.374] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters_C15.dat
[09:39:37.374] <TB2> INFO: enter test to run
[09:39:37.374] <TB2> INFO: test: fulltest no parameter change
[09:39:37.374] <TB2> INFO: running: fulltest
[09:39:37.374] <TB2> INFO: ######################################################################
[09:39:37.374] <TB2> INFO: PixTestFullTest::doTest()
[09:39:37.374] <TB2> INFO: ######################################################################
[09:39:37.377] <TB2> INFO: ######################################################################
[09:39:37.377] <TB2> INFO: PixTestAlive::doTest()
[09:39:37.377] <TB2> INFO: ######################################################################
[09:39:37.378] <TB2> INFO: ----------------------------------------------------------------------
[09:39:37.378] <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:39:37.378] <TB2> INFO: ----------------------------------------------------------------------
[09:39:37.641] <TB2> INFO: Expecting 41600 events.
[09:39:41.494] <TB2> INFO: 41600 events read in total (3297ms).
[09:39:41.495] <TB2> INFO: Test took 4116ms.
[09:39:41.713] <TB2> INFO: PixTestAlive::aliveTest() done
[09:39:41.713] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:39:41.716] <TB2> INFO: ----------------------------------------------------------------------
[09:39:41.716] <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:39:41.716] <TB2> INFO: ----------------------------------------------------------------------
[09:39:42.009] <TB2> INFO: Expecting 41600 events.
[09:39:45.038] <TB2> INFO: 41600 events read in total (2473ms).
[09:39:45.038] <TB2> INFO: Test took 3320ms.
[09:39:45.039] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:39:45.278] <TB2> INFO: PixTestAlive::maskTest() done
[09:39:45.278] <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:39:45.280] <TB2> INFO: ----------------------------------------------------------------------
[09:39:45.280] <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:39:45.280] <TB2> INFO: ----------------------------------------------------------------------
[09:39:45.555] <TB2> INFO: Expecting 41600 events.
[09:39:49.362] <TB2> INFO: 41600 events read in total (3251ms).
[09:39:49.363] <TB2> INFO: Test took 4082ms.
[09:39:49.585] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[09:39:49.585] <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:39:49.585] <TB2> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[09:39:49.585] <TB2> INFO: Decoding statistics:
[09:39:49.585] <TB2> INFO: General information:
[09:39:49.585] <TB2> INFO: 16bit words read: 0
[09:39:49.585] <TB2> INFO: valid events total: 0
[09:39:49.585] <TB2> INFO: empty events: 0
[09:39:49.585] <TB2> INFO: valid events with pixels: 0
[09:39:49.585] <TB2> INFO: valid pixel hits: 0
[09:39:49.585] <TB2> INFO: Event errors: 0
[09:39:49.585] <TB2> INFO: start marker: 0
[09:39:49.585] <TB2> INFO: stop marker: 0
[09:39:49.585] <TB2> INFO: overflow: 0
[09:39:49.585] <TB2> INFO: invalid 5bit words: 0
[09:39:49.585] <TB2> INFO: invalid XOR eye diagram: 0
[09:39:49.585] <TB2> INFO: frame (failed synchr.): 0
[09:39:49.585] <TB2> INFO: idle data (no TBM trl): 0
[09:39:49.585] <TB2> INFO: no data (only TBM hdr): 0
[09:39:49.585] <TB2> INFO: TBM errors: 0
[09:39:49.585] <TB2> INFO: flawed TBM headers: 0
[09:39:49.585] <TB2> INFO: flawed TBM trailers: 0
[09:39:49.585] <TB2> INFO: event ID mismatches: 0
[09:39:49.585] <TB2> INFO: ROC errors: 0
[09:39:49.585] <TB2> INFO: missing ROC header(s): 0
[09:39:49.585] <TB2> INFO: misplaced readback start: 0
[09:39:49.585] <TB2> INFO: Pixel decoding errors: 0
[09:39:49.585] <TB2> INFO: pixel data incomplete: 0
[09:39:49.586] <TB2> INFO: pixel address: 0
[09:39:49.586] <TB2> INFO: pulse height fill bit: 0
[09:39:49.586] <TB2> INFO: buffer corruption: 0
[09:39:49.593] <TB2> INFO: ######################################################################
[09:39:49.593] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:39:49.593] <TB2> INFO: ######################################################################
[09:39:49.596] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:39:49.609] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:39:49.609] <TB2> INFO: run 1 of 1
[09:39:49.902] <TB2> INFO: Expecting 3120000 events.
[09:40:31.480] <TB2> INFO: 866305 events read in total (41022ms).
[09:41:12.073] <TB2> INFO: 1724660 events read in total (81616ms).
[09:41:52.877] <TB2> INFO: 2593840 events read in total (122419ms).
[09:42:17.404] <TB2> INFO: 3120000 events read in total (146946ms).
[09:42:17.458] <TB2> INFO: Test took 147848ms.
[09:42:40.792] <TB2> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[09:42:40.792] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 1 0 1 0 0 0 0 7 0 0 2
[09:42:40.792] <TB2> INFO: separation cut (per ROC): 76 104 83 69 86 102 77 97 92 78 88 68 96 71 102 107
[09:42:40.792] <TB2> INFO: Decoding statistics:
[09:42:40.792] <TB2> INFO: General information:
[09:42:40.792] <TB2> INFO: 16bit words read: 0
[09:42:40.792] <TB2> INFO: valid events total: 0
[09:42:40.792] <TB2> INFO: empty events: 0
[09:42:40.792] <TB2> INFO: valid events with pixels: 0
[09:42:40.792] <TB2> INFO: valid pixel hits: 0
[09:42:40.792] <TB2> INFO: Event errors: 0
[09:42:40.792] <TB2> INFO: start marker: 0
[09:42:40.792] <TB2> INFO: stop marker: 0
[09:42:40.792] <TB2> INFO: overflow: 0
[09:42:40.792] <TB2> INFO: invalid 5bit words: 0
[09:42:40.793] <TB2> INFO: invalid XOR eye diagram: 0
[09:42:40.793] <TB2> INFO: frame (failed synchr.): 0
[09:42:40.793] <TB2> INFO: idle data (no TBM trl): 0
[09:42:40.793] <TB2> INFO: no data (only TBM hdr): 0
[09:42:40.793] <TB2> INFO: TBM errors: 0
[09:42:40.793] <TB2> INFO: flawed TBM headers: 0
[09:42:40.793] <TB2> INFO: flawed TBM trailers: 0
[09:42:40.793] <TB2> INFO: event ID mismatches: 0
[09:42:40.793] <TB2> INFO: ROC errors: 0
[09:42:40.793] <TB2> INFO: missing ROC header(s): 0
[09:42:40.793] <TB2> INFO: misplaced readback start: 0
[09:42:40.793] <TB2> INFO: Pixel decoding errors: 0
[09:42:40.793] <TB2> INFO: pixel data incomplete: 0
[09:42:40.793] <TB2> INFO: pixel address: 0
[09:42:40.793] <TB2> INFO: pulse height fill bit: 0
[09:42:40.793] <TB2> INFO: buffer corruption: 0
[09:42:40.870] <TB2> INFO: ######################################################################
[09:42:40.870] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:42:40.870] <TB2> INFO: ######################################################################
[09:42:40.870] <TB2> INFO: ----------------------------------------------------------------------
[09:42:40.870] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:42:40.870] <TB2> INFO: ----------------------------------------------------------------------
[09:42:40.871] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:42:40.879] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:42:40.879] <TB2> INFO: run 1 of 1
[09:42:41.140] <TB2> INFO: Expecting 26208000 events.
[09:43:15.174] <TB2> INFO: 892350 events read in total (33477ms).
[09:43:48.497] <TB2> INFO: 1771050 events read in total (66800ms).
[09:44:22.275] <TB2> INFO: 2649100 events read in total (100578ms).
[09:44:55.589] <TB2> INFO: 3524600 events read in total (133892ms).
[09:45:29.214] <TB2> INFO: 4400800 events read in total (167517ms).
[09:46:02.418] <TB2> INFO: 5274800 events read in total (200721ms).
[09:46:35.745] <TB2> INFO: 6152500 events read in total (234048ms).
[09:47:08.922] <TB2> INFO: 7024950 events read in total (267225ms).
[09:47:42.352] <TB2> INFO: 7899350 events read in total (300655ms).
[09:48:15.940] <TB2> INFO: 8774400 events read in total (334243ms).
[09:48:49.357] <TB2> INFO: 9650500 events read in total (367660ms).
[09:49:22.894] <TB2> INFO: 10524250 events read in total (401197ms).
[09:49:56.364] <TB2> INFO: 11396150 events read in total (434667ms).
[09:50:29.880] <TB2> INFO: 12270500 events read in total (468183ms).
[09:51:03.075] <TB2> INFO: 13141400 events read in total (501378ms).
[09:51:36.346] <TB2> INFO: 14003500 events read in total (534649ms).
[09:52:10.071] <TB2> INFO: 14865750 events read in total (568374ms).
[09:52:43.321] <TB2> INFO: 15729050 events read in total (601624ms).
[09:53:16.211] <TB2> INFO: 16587750 events read in total (634514ms).
[09:53:49.766] <TB2> INFO: 17449000 events read in total (668069ms).
[09:54:23.030] <TB2> INFO: 18309400 events read in total (701333ms).
[09:54:56.009] <TB2> INFO: 19169050 events read in total (734312ms).
[09:55:29.305] <TB2> INFO: 20025550 events read in total (767608ms).
[09:56:02.663] <TB2> INFO: 20883500 events read in total (800966ms).
[09:56:35.961] <TB2> INFO: 21744950 events read in total (834264ms).
[09:57:09.539] <TB2> INFO: 22603300 events read in total (867842ms).
[09:57:43.077] <TB2> INFO: 23464800 events read in total (901380ms).
[09:58:16.091] <TB2> INFO: 24328050 events read in total (934394ms).
[09:58:49.873] <TB2> INFO: 25191300 events read in total (968176ms).
[09:59:23.400] <TB2> INFO: 26068150 events read in total (1001703ms).
[09:59:28.905] <TB2> INFO: 26208000 events read in total (1007208ms).
[09:59:28.936] <TB2> INFO: Test took 1008057ms.
[09:59:29.217] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:30.674] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:32.003] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:33.416] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:34.865] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:36.342] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:37.805] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:39.214] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:40.572] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:41.926] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:43.325] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:44.703] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:46.130] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:47.496] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:48.922] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:50.292] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:59:51.685] <TB2> INFO: PixTestScurves::scurves() done
[09:59:51.685] <TB2> INFO: Vcal mean: 87.53 106.47 77.50 75.82 82.05 99.02 80.08 95.70 99.85 81.08 85.12 73.57 94.92 70.35 93.83 104.03
[09:59:51.685] <TB2> INFO: Vcal RMS: 5.61 5.61 4.02 4.70 4.72 6.68 4.58 6.25 5.79 4.37 5.06 4.49 5.77 4.75 5.95 6.10
[09:59:51.685] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1030 seconds
[09:59:51.685] <TB2> INFO: Decoding statistics:
[09:59:51.685] <TB2> INFO: General information:
[09:59:51.685] <TB2> INFO: 16bit words read: 0
[09:59:51.685] <TB2> INFO: valid events total: 0
[09:59:51.685] <TB2> INFO: empty events: 0
[09:59:51.685] <TB2> INFO: valid events with pixels: 0
[09:59:51.685] <TB2> INFO: valid pixel hits: 0
[09:59:51.685] <TB2> INFO: Event errors: 0
[09:59:51.685] <TB2> INFO: start marker: 0
[09:59:51.685] <TB2> INFO: stop marker: 0
[09:59:51.685] <TB2> INFO: overflow: 0
[09:59:51.685] <TB2> INFO: invalid 5bit words: 0
[09:59:51.685] <TB2> INFO: invalid XOR eye diagram: 0
[09:59:51.685] <TB2> INFO: frame (failed synchr.): 0
[09:59:51.685] <TB2> INFO: idle data (no TBM trl): 0
[09:59:51.685] <TB2> INFO: no data (only TBM hdr): 0
[09:59:51.685] <TB2> INFO: TBM errors: 0
[09:59:51.685] <TB2> INFO: flawed TBM headers: 0
[09:59:51.685] <TB2> INFO: flawed TBM trailers: 0
[09:59:51.685] <TB2> INFO: event ID mismatches: 0
[09:59:51.685] <TB2> INFO: ROC errors: 0
[09:59:51.685] <TB2> INFO: missing ROC header(s): 0
[09:59:51.685] <TB2> INFO: misplaced readback start: 0
[09:59:51.685] <TB2> INFO: Pixel decoding errors: 0
[09:59:51.685] <TB2> INFO: pixel data incomplete: 0
[09:59:51.685] <TB2> INFO: pixel address: 0
[09:59:51.685] <TB2> INFO: pulse height fill bit: 0
[09:59:51.685] <TB2> INFO: buffer corruption: 0
[09:59:51.760] <TB2> INFO: ######################################################################
[09:59:51.760] <TB2> INFO: PixTestTrim::doTest()
[09:59:51.760] <TB2> INFO: ######################################################################
[09:59:51.761] <TB2> INFO: ----------------------------------------------------------------------
[09:59:51.761] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:59:51.761] <TB2> INFO: ----------------------------------------------------------------------
[09:59:51.841] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:59:51.841] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:59:51.850] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:59:51.850] <TB2> INFO: run 1 of 1
[09:59:52.124] <TB2> INFO: Expecting 6281600 events.
[10:00:31.355] <TB2> INFO: 1063840 events read in total (38674ms).
[10:01:10.663] <TB2> INFO: 2121880 events read in total (77982ms).
[10:01:49.637] <TB2> INFO: 3173800 events read in total (116956ms).
[10:02:28.892] <TB2> INFO: 4232340 events read in total (156211ms).
[10:03:07.829] <TB2> INFO: 5296050 events read in total (195148ms).
[10:03:44.207] <TB2> INFO: 6281600 events read in total (231526ms).
[10:03:44.249] <TB2> INFO: Test took 232399ms.
[10:04:03.691] <TB2> INFO: ROC 0 VthrComp = 88
[10:04:03.692] <TB2> INFO: ROC 1 VthrComp = 102
[10:04:03.692] <TB2> INFO: ROC 2 VthrComp = 79
[10:04:03.692] <TB2> INFO: ROC 3 VthrComp = 75
[10:04:03.692] <TB2> INFO: ROC 4 VthrComp = 86
[10:04:03.692] <TB2> INFO: ROC 5 VthrComp = 92
[10:04:03.692] <TB2> INFO: ROC 6 VthrComp = 81
[10:04:03.692] <TB2> INFO: ROC 7 VthrComp = 92
[10:04:03.692] <TB2> INFO: ROC 8 VthrComp = 98
[10:04:03.692] <TB2> INFO: ROC 9 VthrComp = 84
[10:04:03.692] <TB2> INFO: ROC 10 VthrComp = 88
[10:04:03.693] <TB2> INFO: ROC 11 VthrComp = 77
[10:04:03.693] <TB2> INFO: ROC 12 VthrComp = 97
[10:04:03.693] <TB2> INFO: ROC 13 VthrComp = 75
[10:04:03.693] <TB2> INFO: ROC 14 VthrComp = 100
[10:04:03.693] <TB2> INFO: ROC 15 VthrComp = 104
[10:04:03.693] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:04:03.693] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:04:03.704] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:04:03.705] <TB2> INFO: run 1 of 1
[10:04:04.032] <TB2> INFO: Expecting 6281600 events.
[10:04:40.172] <TB2> INFO: 758580 events read in total (35584ms).
[10:05:15.291] <TB2> INFO: 1515120 events read in total (70703ms).
[10:05:50.771] <TB2> INFO: 2271010 events read in total (106183ms).
[10:06:25.979] <TB2> INFO: 3028150 events read in total (141391ms).
[10:07:01.783] <TB2> INFO: 3779310 events read in total (177195ms).
[10:07:37.477] <TB2> INFO: 4527460 events read in total (212889ms).
[10:08:12.729] <TB2> INFO: 5273020 events read in total (248141ms).
[10:08:47.936] <TB2> INFO: 6019360 events read in total (283348ms).
[10:09:00.371] <TB2> INFO: 6281600 events read in total (295783ms).
[10:09:00.424] <TB2> INFO: Test took 296719ms.
[10:09:26.486] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 61.1137 for pixel 0/23 mean/min/max = 47.1593/32.8525/61.4662
[10:09:26.487] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 60.3768 for pixel 26/1 mean/min/max = 46.0072/31.566/60.4484
[10:09:26.487] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 57.9256 for pixel 33/49 mean/min/max = 46.8166/35.6705/57.9627
[10:09:26.487] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 61.9654 for pixel 0/22 mean/min/max = 48.4995/34.9749/62.0241
[10:09:26.487] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 57.7663 for pixel 4/5 mean/min/max = 44.7865/31.704/57.8691
[10:09:26.488] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 67.0766 for pixel 37/4 mean/min/max = 49.6985/32.1617/67.2354
[10:09:26.488] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 58.49 for pixel 15/74 mean/min/max = 45.9551/33.292/58.6182
[10:09:26.488] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 64.8289 for pixel 0/79 mean/min/max = 48.291/31.3752/65.2068
[10:09:26.488] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 59.9394 for pixel 8/79 mean/min/max = 46.2482/32.402/60.0943
[10:09:26.488] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 56.6398 for pixel 21/2 mean/min/max = 44.427/32.1561/56.6979
[10:09:26.489] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 58.7177 for pixel 29/79 mean/min/max = 45.6605/32.5055/58.8156
[10:09:26.489] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 57.9158 for pixel 17/43 mean/min/max = 46.3544/34.7467/57.9621
[10:09:26.489] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 59.2801 for pixel 22/1 mean/min/max = 45.7004/32.1057/59.295
[10:09:26.489] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 57.967 for pixel 6/79 mean/min/max = 46.1762/34.3639/57.9886
[10:09:26.490] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 58.8227 for pixel 26/7 mean/min/max = 45.6214/32.3057/58.9371
[10:09:26.490] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 64.6118 for pixel 0/22 mean/min/max = 49.0938/33.1988/64.9887
[10:09:26.490] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:09:26.579] <TB2> INFO: Expecting 514560 events.
[10:09:39.492] <TB2> INFO: 514560 events read in total (12357ms).
[10:09:39.496] <TB2> INFO: Expecting 514560 events.
[10:09:52.306] <TB2> INFO: 514560 events read in total (12386ms).
[10:09:52.313] <TB2> INFO: Expecting 514560 events.
[10:10:05.060] <TB2> INFO: 514560 events read in total (12332ms).
[10:10:05.068] <TB2> INFO: Expecting 514560 events.
[10:10:17.877] <TB2> INFO: 514560 events read in total (12388ms).
[10:10:17.887] <TB2> INFO: Expecting 514560 events.
[10:10:30.773] <TB2> INFO: 514560 events read in total (12463ms).
[10:10:30.786] <TB2> INFO: Expecting 514560 events.
[10:10:43.610] <TB2> INFO: 514560 events read in total (12413ms).
[10:10:43.625] <TB2> INFO: Expecting 514560 events.
[10:10:56.541] <TB2> INFO: 514560 events read in total (12509ms).
[10:10:56.559] <TB2> INFO: Expecting 514560 events.
[10:11:09.275] <TB2> INFO: 514560 events read in total (12312ms).
[10:11:09.293] <TB2> INFO: Expecting 514560 events.
[10:11:22.001] <TB2> INFO: 514560 events read in total (12297ms).
[10:11:22.019] <TB2> INFO: Expecting 514560 events.
[10:11:34.935] <TB2> INFO: 514560 events read in total (12511ms).
[10:11:34.959] <TB2> INFO: Expecting 514560 events.
[10:11:47.754] <TB2> INFO: 514560 events read in total (12393ms).
[10:11:47.778] <TB2> INFO: Expecting 514560 events.
[10:12:00.475] <TB2> INFO: 514560 events read in total (12295ms).
[10:12:00.501] <TB2> INFO: Expecting 514560 events.
[10:12:13.280] <TB2> INFO: 514560 events read in total (12376ms).
[10:12:13.309] <TB2> INFO: Expecting 514560 events.
[10:12:25.987] <TB2> INFO: 514560 events read in total (12276ms).
[10:12:26.019] <TB2> INFO: Expecting 514560 events.
[10:12:38.843] <TB2> INFO: 514560 events read in total (12422ms).
[10:12:38.879] <TB2> INFO: Expecting 514560 events.
[10:12:51.652] <TB2> INFO: 514560 events read in total (12371ms).
[10:12:51.686] <TB2> INFO: Test took 205196ms.
[10:12:52.656] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:12:52.665] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:12:52.665] <TB2> INFO: run 1 of 1
[10:12:52.929] <TB2> INFO: Expecting 6281600 events.
[10:13:28.628] <TB2> INFO: 741680 events read in total (35143ms).
[10:14:03.934] <TB2> INFO: 1482210 events read in total (70449ms).
[10:14:39.203] <TB2> INFO: 2222890 events read in total (105718ms).
[10:15:14.524] <TB2> INFO: 2964440 events read in total (141039ms).
[10:15:49.721] <TB2> INFO: 3700090 events read in total (176236ms).
[10:16:24.763] <TB2> INFO: 4432610 events read in total (211278ms).
[10:17:00.660] <TB2> INFO: 5163250 events read in total (247176ms).
[10:17:35.725] <TB2> INFO: 5892910 events read in total (282240ms).
[10:17:54.610] <TB2> INFO: 6281600 events read in total (301125ms).
[10:17:54.663] <TB2> INFO: Test took 301997ms.
[10:18:21.687] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.015846 .. 255.000000
[10:18:21.762] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:18:21.770] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:18:21.770] <TB2> INFO: run 1 of 1
[10:18:22.050] <TB2> INFO: Expecting 10649600 events.
[10:18:57.490] <TB2> INFO: 712160 events read in total (34884ms).
[10:19:32.284] <TB2> INFO: 1424470 events read in total (69678ms).
[10:20:07.442] <TB2> INFO: 2136780 events read in total (104836ms).
[10:20:42.693] <TB2> INFO: 2849120 events read in total (140087ms).
[10:21:17.360] <TB2> INFO: 3561780 events read in total (174754ms).
[10:21:52.424] <TB2> INFO: 4274210 events read in total (209818ms).
[10:22:26.700] <TB2> INFO: 4986410 events read in total (244094ms).
[10:23:01.497] <TB2> INFO: 5699030 events read in total (278891ms).
[10:23:36.093] <TB2> INFO: 6411380 events read in total (313487ms).
[10:24:10.921] <TB2> INFO: 7123220 events read in total (348315ms).
[10:24:45.794] <TB2> INFO: 7834860 events read in total (383189ms).
[10:25:20.916] <TB2> INFO: 8545790 events read in total (418310ms).
[10:25:57.091] <TB2> INFO: 9257130 events read in total (454485ms).
[10:26:31.728] <TB2> INFO: 9968350 events read in total (489122ms).
[10:27:05.235] <TB2> INFO: 10649600 events read in total (522629ms).
[10:27:05.352] <TB2> INFO: Test took 523582ms.
[10:27:37.475] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 16.447431 .. 46.659302
[10:27:37.552] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 56 (-1/-1) hits flags = 528 (plus default)
[10:27:37.560] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:27:37.560] <TB2> INFO: run 1 of 1
[10:27:37.831] <TB2> INFO: Expecting 2121600 events.
[10:28:16.684] <TB2> INFO: 924170 events read in total (38296ms).
[10:28:54.772] <TB2> INFO: 1847080 events read in total (76384ms).
[10:29:06.532] <TB2> INFO: 2121600 events read in total (88145ms).
[10:29:06.551] <TB2> INFO: Test took 88990ms.
[10:29:21.025] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 21.683856 .. 44.395908
[10:29:21.131] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 54 (-1/-1) hits flags = 528 (plus default)
[10:29:21.139] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:29:21.139] <TB2> INFO: run 1 of 1
[10:29:21.415] <TB2> INFO: Expecting 1830400 events.
[10:30:00.574] <TB2> INFO: 912100 events read in total (38603ms).
[10:30:38.061] <TB2> INFO: 1823160 events read in total (76090ms).
[10:30:38.753] <TB2> INFO: 1830400 events read in total (76782ms).
[10:30:38.773] <TB2> INFO: Test took 77635ms.
[10:30:52.309] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 4.318436 .. 43.800373
[10:30:52.385] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 4 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:30:52.393] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:30:52.393] <TB2> INFO: run 1 of 1
[10:30:52.652] <TB2> INFO: Expecting 2080000 events.
[10:31:31.776] <TB2> INFO: 961600 events read in total (38567ms).
[10:32:10.728] <TB2> INFO: 1922700 events read in total (77519ms).
[10:32:17.439] <TB2> INFO: 2080000 events read in total (84230ms).
[10:32:17.456] <TB2> INFO: Test took 85065ms.
[10:32:31.310] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:32:31.310] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:32:31.318] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:32:31.318] <TB2> INFO: run 1 of 1
[10:32:31.578] <TB2> INFO: Expecting 1705600 events.
[10:33:10.102] <TB2> INFO: 878560 events read in total (37968ms).
[10:33:45.804] <TB2> INFO: 1705600 events read in total (73670ms).
[10:33:45.829] <TB2> INFO: Test took 74511ms.
[10:34:00.685] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:34:00.686] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:34:00.686] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:34:00.686] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:34:00.686] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:34:00.686] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:34:00.686] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:34:00.686] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:34:00.686] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:34:00.687] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:34:00.687] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:34:00.687] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:34:00.687] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:34:00.687] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:34:00.687] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:34:00.687] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:34:00.688] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:34:00.694] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:34:00.701] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:34:00.707] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:34:00.713] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:34:00.723] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:34:00.733] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:34:00.739] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:34:00.745] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:34:00.751] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:34:00.757] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:34:00.764] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:34:00.770] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:34:00.776] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:34:00.783] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:34:00.792] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:34:00.803] <TB2> INFO: PixTestTrim::trimTest() done
[10:34:00.803] <TB2> INFO: vtrim: 87 103 87 93 101 114 90 93 95 97 94 85 108 82 104 105
[10:34:00.803] <TB2> INFO: vthrcomp: 88 102 79 75 86 92 81 92 98 84 88 77 97 75 100 104
[10:34:00.803] <TB2> INFO: vcal mean: 35.00 34.92 35.01 35.00 34.94 34.97 35.01 34.97 34.96 34.99 35.00 34.98 35.02 35.04 34.89 34.99
[10:34:00.803] <TB2> INFO: vcal RMS: 0.87 0.91 0.80 0.84 0.85 1.26 0.86 0.92 0.90 0.88 0.84 0.82 0.93 0.76 0.84 0.95
[10:34:00.803] <TB2> INFO: bits mean: 8.62 9.80 8.55 8.44 9.82 9.21 9.61 8.47 9.29 10.10 8.94 9.21 9.09 8.39 9.43 8.11
[10:34:00.803] <TB2> INFO: bits RMS: 2.87 2.56 2.50 2.53 2.66 2.49 2.47 3.02 2.74 2.47 2.87 2.42 2.87 2.75 2.69 2.80
[10:34:00.810] <TB2> INFO: ----------------------------------------------------------------------
[10:34:00.810] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:34:00.810] <TB2> INFO: ----------------------------------------------------------------------
[10:34:00.813] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:34:00.822] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:34:00.822] <TB2> INFO: run 1 of 1
[10:34:01.085] <TB2> INFO: Expecting 4160000 events.
[10:34:43.570] <TB2> INFO: 926820 events read in total (41929ms).
[10:35:25.481] <TB2> INFO: 1849490 events read in total (83840ms).
[10:36:06.982] <TB2> INFO: 2764195 events read in total (125341ms).
[10:36:48.523] <TB2> INFO: 3676855 events read in total (166882ms).
[10:37:10.827] <TB2> INFO: 4160000 events read in total (189186ms).
[10:37:10.872] <TB2> INFO: Test took 190050ms.
[10:37:41.235] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[10:37:41.244] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:37:41.244] <TB2> INFO: run 1 of 1
[10:37:41.526] <TB2> INFO: Expecting 3868800 events.
[10:38:23.678] <TB2> INFO: 919140 events read in total (41596ms).
[10:39:05.557] <TB2> INFO: 1833545 events read in total (83475ms).
[10:39:47.044] <TB2> INFO: 2739355 events read in total (124963ms).
[10:40:28.206] <TB2> INFO: 3644575 events read in total (166124ms).
[10:40:38.493] <TB2> INFO: 3868800 events read in total (176411ms).
[10:40:38.531] <TB2> INFO: Test took 177287ms.
[10:41:07.333] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[10:41:07.341] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:07.341] <TB2> INFO: run 1 of 1
[10:41:07.631] <TB2> INFO: Expecting 3515200 events.
[10:41:50.798] <TB2> INFO: 955385 events read in total (42611ms).
[10:42:33.459] <TB2> INFO: 1903580 events read in total (85272ms).
[10:43:15.568] <TB2> INFO: 2841655 events read in total (127382ms).
[10:43:46.036] <TB2> INFO: 3515200 events read in total (157849ms).
[10:43:46.078] <TB2> INFO: Test took 158738ms.
[10:44:13.159] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[10:44:13.167] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:44:13.167] <TB2> INFO: run 1 of 1
[10:44:13.434] <TB2> INFO: Expecting 3536000 events.
[10:44:55.802] <TB2> INFO: 952600 events read in total (41810ms).
[10:45:38.277] <TB2> INFO: 1897995 events read in total (84285ms).
[10:46:20.525] <TB2> INFO: 2833525 events read in total (126533ms).
[10:46:52.162] <TB2> INFO: 3536000 events read in total (158170ms).
[10:46:52.213] <TB2> INFO: Test took 159047ms.
[10:47:19.097] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[10:47:19.107] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:47:19.107] <TB2> INFO: run 1 of 1
[10:47:19.388] <TB2> INFO: Expecting 3536000 events.
[10:48:02.282] <TB2> INFO: 952225 events read in total (42337ms).
[10:48:44.618] <TB2> INFO: 1897330 events read in total (84673ms).
[10:49:26.623] <TB2> INFO: 2832510 events read in total (126678ms).
[10:49:57.943] <TB2> INFO: 3536000 events read in total (157998ms).
[10:49:57.985] <TB2> INFO: Test took 158877ms.
[10:50:25.694] <TB2> INFO: PixTestTrim::trimBitTest() done
[10:50:25.696] <TB2> INFO: PixTestTrim::doTest() done, duration: 3033 seconds
[10:50:25.696] <TB2> INFO: Decoding statistics:
[10:50:25.696] <TB2> INFO: General information:
[10:50:25.696] <TB2> INFO: 16bit words read: 0
[10:50:25.696] <TB2> INFO: valid events total: 0
[10:50:25.696] <TB2> INFO: empty events: 0
[10:50:25.696] <TB2> INFO: valid events with pixels: 0
[10:50:25.696] <TB2> INFO: valid pixel hits: 0
[10:50:25.696] <TB2> INFO: Event errors: 0
[10:50:25.696] <TB2> INFO: start marker: 0
[10:50:25.696] <TB2> INFO: stop marker: 0
[10:50:25.696] <TB2> INFO: overflow: 0
[10:50:25.696] <TB2> INFO: invalid 5bit words: 0
[10:50:25.696] <TB2> INFO: invalid XOR eye diagram: 0
[10:50:25.696] <TB2> INFO: frame (failed synchr.): 0
[10:50:25.696] <TB2> INFO: idle data (no TBM trl): 0
[10:50:25.696] <TB2> INFO: no data (only TBM hdr): 0
[10:50:25.696] <TB2> INFO: TBM errors: 0
[10:50:25.696] <TB2> INFO: flawed TBM headers: 0
[10:50:25.696] <TB2> INFO: flawed TBM trailers: 0
[10:50:25.696] <TB2> INFO: event ID mismatches: 0
[10:50:25.696] <TB2> INFO: ROC errors: 0
[10:50:25.696] <TB2> INFO: missing ROC header(s): 0
[10:50:25.696] <TB2> INFO: misplaced readback start: 0
[10:50:25.696] <TB2> INFO: Pixel decoding errors: 0
[10:50:25.696] <TB2> INFO: pixel data incomplete: 0
[10:50:25.696] <TB2> INFO: pixel address: 0
[10:50:25.696] <TB2> INFO: pulse height fill bit: 0
[10:50:25.696] <TB2> INFO: buffer corruption: 0
[10:50:26.390] <TB2> INFO: ######################################################################
[10:50:26.390] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:50:26.390] <TB2> INFO: ######################################################################
[10:50:26.653] <TB2> INFO: Expecting 41600 events.
[10:50:30.399] <TB2> INFO: 41600 events read in total (3190ms).
[10:50:30.400] <TB2> INFO: Test took 4009ms.
[10:50:30.855] <TB2> INFO: Expecting 41600 events.
[10:50:34.669] <TB2> INFO: 41600 events read in total (3258ms).
[10:50:34.670] <TB2> INFO: Test took 4077ms.
[10:50:35.036] <TB2> INFO: Expecting 41600 events.
[10:50:38.889] <TB2> INFO: 41600 events read in total (3297ms).
[10:50:38.889] <TB2> INFO: Test took 4197ms.
[10:50:39.153] <TB2> INFO: Expecting 2560 events.
[10:50:40.006] <TB2> INFO: 2560 events read in total (297ms).
[10:50:40.007] <TB2> INFO: Test took 1096ms.
[10:50:40.312] <TB2> INFO: Expecting 2560 events.
[10:50:41.165] <TB2> INFO: 2560 events read in total (297ms).
[10:50:41.165] <TB2> INFO: Test took 1158ms.
[10:50:41.471] <TB2> INFO: Expecting 2560 events.
[10:50:42.324] <TB2> INFO: 2560 events read in total (297ms).
[10:50:42.324] <TB2> INFO: Test took 1158ms.
[10:50:42.629] <TB2> INFO: Expecting 2560 events.
[10:50:43.486] <TB2> INFO: 2560 events read in total (301ms).
[10:50:43.486] <TB2> INFO: Test took 1161ms.
[10:50:43.792] <TB2> INFO: Expecting 2560 events.
[10:50:44.646] <TB2> INFO: 2560 events read in total (297ms).
[10:50:44.646] <TB2> INFO: Test took 1160ms.
[10:50:44.951] <TB2> INFO: Expecting 2560 events.
[10:50:45.806] <TB2> INFO: 2560 events read in total (299ms).
[10:50:45.806] <TB2> INFO: Test took 1160ms.
[10:50:46.111] <TB2> INFO: Expecting 2560 events.
[10:50:46.964] <TB2> INFO: 2560 events read in total (297ms).
[10:50:46.964] <TB2> INFO: Test took 1158ms.
[10:50:47.269] <TB2> INFO: Expecting 2560 events.
[10:50:48.122] <TB2> INFO: 2560 events read in total (297ms).
[10:50:48.122] <TB2> INFO: Test took 1158ms.
[10:50:48.427] <TB2> INFO: Expecting 2560 events.
[10:50:49.280] <TB2> INFO: 2560 events read in total (297ms).
[10:50:49.280] <TB2> INFO: Test took 1158ms.
[10:50:49.585] <TB2> INFO: Expecting 2560 events.
[10:50:50.438] <TB2> INFO: 2560 events read in total (297ms).
[10:50:50.439] <TB2> INFO: Test took 1159ms.
[10:50:50.744] <TB2> INFO: Expecting 2560 events.
[10:50:51.598] <TB2> INFO: 2560 events read in total (298ms).
[10:50:51.598] <TB2> INFO: Test took 1159ms.
[10:50:51.904] <TB2> INFO: Expecting 2560 events.
[10:50:52.758] <TB2> INFO: 2560 events read in total (298ms).
[10:50:52.758] <TB2> INFO: Test took 1159ms.
[10:50:53.063] <TB2> INFO: Expecting 2560 events.
[10:50:53.917] <TB2> INFO: 2560 events read in total (298ms).
[10:50:53.917] <TB2> INFO: Test took 1159ms.
[10:50:54.223] <TB2> INFO: Expecting 2560 events.
[10:50:55.080] <TB2> INFO: 2560 events read in total (301ms).
[10:50:55.081] <TB2> INFO: Test took 1164ms.
[10:50:55.386] <TB2> INFO: Expecting 2560 events.
[10:50:56.241] <TB2> INFO: 2560 events read in total (298ms).
[10:50:56.241] <TB2> INFO: Test took 1160ms.
[10:50:56.546] <TB2> INFO: Expecting 2560 events.
[10:50:57.402] <TB2> INFO: 2560 events read in total (300ms).
[10:50:57.402] <TB2> INFO: Test took 1161ms.
[10:50:57.405] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:50:57.708] <TB2> INFO: Expecting 655360 events.
[10:51:14.778] <TB2> INFO: 655360 events read in total (16514ms).
[10:51:14.788] <TB2> INFO: Expecting 655360 events.
[10:51:31.545] <TB2> INFO: 655360 events read in total (16355ms).
[10:51:31.561] <TB2> INFO: Expecting 655360 events.
[10:51:48.398] <TB2> INFO: 655360 events read in total (16435ms).
[10:51:48.415] <TB2> INFO: Expecting 655360 events.
[10:52:05.258] <TB2> INFO: 655360 events read in total (16442ms).
[10:52:05.282] <TB2> INFO: Expecting 655360 events.
[10:52:22.257] <TB2> INFO: 655360 events read in total (16573ms).
[10:52:22.281] <TB2> INFO: Expecting 655360 events.
[10:52:39.191] <TB2> INFO: 655360 events read in total (16508ms).
[10:52:39.223] <TB2> INFO: Expecting 655360 events.
[10:52:56.056] <TB2> INFO: 655360 events read in total (16431ms).
[10:52:56.090] <TB2> INFO: Expecting 655360 events.
[10:53:12.936] <TB2> INFO: 655360 events read in total (16444ms).
[10:53:12.982] <TB2> INFO: Expecting 655360 events.
[10:53:29.803] <TB2> INFO: 655360 events read in total (16419ms).
[10:53:29.840] <TB2> INFO: Expecting 655360 events.
[10:53:46.746] <TB2> INFO: 655360 events read in total (16504ms).
[10:53:46.790] <TB2> INFO: Expecting 655360 events.
[10:54:03.654] <TB2> INFO: 655360 events read in total (16463ms).
[10:54:03.707] <TB2> INFO: Expecting 655360 events.
[10:54:20.517] <TB2> INFO: 655360 events read in total (16408ms).
[10:54:20.579] <TB2> INFO: Expecting 655360 events.
[10:54:37.233] <TB2> INFO: 655360 events read in total (16252ms).
[10:54:37.285] <TB2> INFO: Expecting 655360 events.
[10:54:53.862] <TB2> INFO: 655360 events read in total (16176ms).
[10:54:53.931] <TB2> INFO: Expecting 655360 events.
[10:55:10.648] <TB2> INFO: 655360 events read in total (16315ms).
[10:55:10.711] <TB2> INFO: Expecting 655360 events.
[10:55:27.532] <TB2> INFO: 655360 events read in total (16419ms).
[10:55:27.600] <TB2> INFO: Test took 270195ms.
[10:55:27.688] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:55:27.918] <TB2> INFO: Expecting 655360 events.
[10:55:44.787] <TB2> INFO: 655360 events read in total (16313ms).
[10:55:44.797] <TB2> INFO: Expecting 655360 events.
[10:56:01.421] <TB2> INFO: 655360 events read in total (16222ms).
[10:56:01.433] <TB2> INFO: Expecting 655360 events.
[10:56:18.240] <TB2> INFO: 655360 events read in total (16405ms).
[10:56:18.257] <TB2> INFO: Expecting 655360 events.
[10:56:35.070] <TB2> INFO: 655360 events read in total (16411ms).
[10:56:35.091] <TB2> INFO: Expecting 655360 events.
[10:56:52.015] <TB2> INFO: 655360 events read in total (16522ms).
[10:56:52.039] <TB2> INFO: Expecting 655360 events.
[10:57:08.840] <TB2> INFO: 655360 events read in total (16399ms).
[10:57:08.868] <TB2> INFO: Expecting 655360 events.
[10:57:25.786] <TB2> INFO: 655360 events read in total (16517ms).
[10:57:25.821] <TB2> INFO: Expecting 655360 events.
[10:57:42.574] <TB2> INFO: 655360 events read in total (16350ms).
[10:57:42.607] <TB2> INFO: Expecting 655360 events.
[10:57:59.487] <TB2> INFO: 655360 events read in total (16478ms).
[10:57:59.523] <TB2> INFO: Expecting 655360 events.
[10:58:16.301] <TB2> INFO: 655360 events read in total (16376ms).
[10:58:16.350] <TB2> INFO: Expecting 655360 events.
[10:58:33.061] <TB2> INFO: 655360 events read in total (16309ms).
[10:58:33.118] <TB2> INFO: Expecting 655360 events.
[10:58:49.842] <TB2> INFO: 655360 events read in total (16322ms).
[10:58:49.911] <TB2> INFO: Expecting 655360 events.
[10:59:06.546] <TB2> INFO: 655360 events read in total (16233ms).
[10:59:06.608] <TB2> INFO: Expecting 655360 events.
[10:59:23.153] <TB2> INFO: 655360 events read in total (16143ms).
[10:59:23.214] <TB2> INFO: Expecting 655360 events.
[10:59:39.938] <TB2> INFO: 655360 events read in total (16322ms).
[10:59:40.019] <TB2> INFO: Expecting 655360 events.
[10:59:56.660] <TB2> INFO: 655360 events read in total (16240ms).
[10:59:56.720] <TB2> INFO: Test took 269032ms.
[10:59:56.907] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:56.914] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[10:59:56.921] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:56.927] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[10:59:56.934] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[10:59:56.940] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[10:59:56.947] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[10:59:56.953] <TB2> INFO: safety margin for low PH: adding 5, margin is now 25
[10:59:56.960] <TB2> INFO: safety margin for low PH: adding 6, margin is now 26
[10:59:56.967] <TB2> INFO: safety margin for low PH: adding 7, margin is now 27
[10:59:56.973] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:56.980] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:56.986] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:56.993] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.000] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.006] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.013] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.019] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.026] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.033] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.039] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.046] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.052] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.059] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:59:57.098] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:59:57.098] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:59:57.098] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:59:57.098] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:59:57.110] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:59:57.110] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:59:57.110] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:59:57.110] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:59:57.111] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:59:57.111] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:59:57.111] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:59:57.111] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:59:57.111] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:59:57.111] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:59:57.111] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:59:57.111] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:59:57.375] <TB2> INFO: Expecting 41600 events.
[11:00:00.866] <TB2> INFO: 41600 events read in total (2935ms).
[11:00:00.866] <TB2> INFO: Test took 3752ms.
[11:00:01.339] <TB2> INFO: Expecting 41600 events.
[11:00:04.814] <TB2> INFO: 41600 events read in total (2919ms).
[11:00:04.815] <TB2> INFO: Test took 3771ms.
[11:00:05.270] <TB2> INFO: Expecting 41600 events.
[11:00:08.755] <TB2> INFO: 41600 events read in total (2929ms).
[11:00:08.756] <TB2> INFO: Test took 3761ms.
[11:00:08.940] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:09.028] <TB2> INFO: Expecting 2560 events.
[11:00:09.886] <TB2> INFO: 2560 events read in total (302ms).
[11:00:09.886] <TB2> INFO: Test took 946ms.
[11:00:09.889] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:10.191] <TB2> INFO: Expecting 2560 events.
[11:00:11.044] <TB2> INFO: 2560 events read in total (297ms).
[11:00:11.044] <TB2> INFO: Test took 1155ms.
[11:00:11.047] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:11.350] <TB2> INFO: Expecting 2560 events.
[11:00:12.207] <TB2> INFO: 2560 events read in total (300ms).
[11:00:12.207] <TB2> INFO: Test took 1160ms.
[11:00:12.212] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:12.512] <TB2> INFO: Expecting 2560 events.
[11:00:13.368] <TB2> INFO: 2560 events read in total (299ms).
[11:00:13.368] <TB2> INFO: Test took 1156ms.
[11:00:13.370] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:13.673] <TB2> INFO: Expecting 2560 events.
[11:00:14.526] <TB2> INFO: 2560 events read in total (297ms).
[11:00:14.527] <TB2> INFO: Test took 1157ms.
[11:00:14.529] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:14.833] <TB2> INFO: Expecting 2560 events.
[11:00:15.689] <TB2> INFO: 2560 events read in total (300ms).
[11:00:15.690] <TB2> INFO: Test took 1161ms.
[11:00:15.692] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:15.995] <TB2> INFO: Expecting 2560 events.
[11:00:16.848] <TB2> INFO: 2560 events read in total (296ms).
[11:00:16.848] <TB2> INFO: Test took 1156ms.
[11:00:16.850] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:17.154] <TB2> INFO: Expecting 2560 events.
[11:00:18.008] <TB2> INFO: 2560 events read in total (298ms).
[11:00:18.008] <TB2> INFO: Test took 1158ms.
[11:00:18.010] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:18.314] <TB2> INFO: Expecting 2560 events.
[11:00:19.167] <TB2> INFO: 2560 events read in total (297ms).
[11:00:19.167] <TB2> INFO: Test took 1157ms.
[11:00:19.168] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:19.473] <TB2> INFO: Expecting 2560 events.
[11:00:20.327] <TB2> INFO: 2560 events read in total (298ms).
[11:00:20.328] <TB2> INFO: Test took 1160ms.
[11:00:20.331] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:20.633] <TB2> INFO: Expecting 2560 events.
[11:00:21.491] <TB2> INFO: 2560 events read in total (302ms).
[11:00:21.491] <TB2> INFO: Test took 1161ms.
[11:00:21.493] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:21.796] <TB2> INFO: Expecting 2560 events.
[11:00:22.655] <TB2> INFO: 2560 events read in total (303ms).
[11:00:22.655] <TB2> INFO: Test took 1162ms.
[11:00:22.658] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:22.962] <TB2> INFO: Expecting 2560 events.
[11:00:23.818] <TB2> INFO: 2560 events read in total (300ms).
[11:00:23.818] <TB2> INFO: Test took 1161ms.
[11:00:23.821] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:24.123] <TB2> INFO: Expecting 2560 events.
[11:00:24.975] <TB2> INFO: 2560 events read in total (296ms).
[11:00:24.976] <TB2> INFO: Test took 1155ms.
[11:00:24.978] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:25.282] <TB2> INFO: Expecting 2560 events.
[11:00:26.144] <TB2> INFO: 2560 events read in total (305ms).
[11:00:26.144] <TB2> INFO: Test took 1166ms.
[11:00:26.147] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:26.449] <TB2> INFO: Expecting 2560 events.
[11:00:27.306] <TB2> INFO: 2560 events read in total (300ms).
[11:00:27.307] <TB2> INFO: Test took 1160ms.
[11:00:27.309] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:27.612] <TB2> INFO: Expecting 2560 events.
[11:00:28.471] <TB2> INFO: 2560 events read in total (302ms).
[11:00:28.472] <TB2> INFO: Test took 1164ms.
[11:00:28.474] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:28.777] <TB2> INFO: Expecting 2560 events.
[11:00:29.632] <TB2> INFO: 2560 events read in total (299ms).
[11:00:29.632] <TB2> INFO: Test took 1158ms.
[11:00:29.635] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:29.939] <TB2> INFO: Expecting 2560 events.
[11:00:30.794] <TB2> INFO: 2560 events read in total (299ms).
[11:00:30.794] <TB2> INFO: Test took 1159ms.
[11:00:30.798] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:31.100] <TB2> INFO: Expecting 2560 events.
[11:00:31.957] <TB2> INFO: 2560 events read in total (301ms).
[11:00:31.958] <TB2> INFO: Test took 1161ms.
[11:00:31.960] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:32.263] <TB2> INFO: Expecting 2560 events.
[11:00:33.116] <TB2> INFO: 2560 events read in total (298ms).
[11:00:33.117] <TB2> INFO: Test took 1157ms.
[11:00:33.120] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:33.423] <TB2> INFO: Expecting 2560 events.
[11:00:34.279] <TB2> INFO: 2560 events read in total (300ms).
[11:00:34.279] <TB2> INFO: Test took 1159ms.
[11:00:34.282] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:34.584] <TB2> INFO: Expecting 2560 events.
[11:00:35.442] <TB2> INFO: 2560 events read in total (302ms).
[11:00:35.442] <TB2> INFO: Test took 1160ms.
[11:00:35.445] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:35.748] <TB2> INFO: Expecting 2560 events.
[11:00:36.601] <TB2> INFO: 2560 events read in total (297ms).
[11:00:36.602] <TB2> INFO: Test took 1157ms.
[11:00:36.605] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:36.907] <TB2> INFO: Expecting 2560 events.
[11:00:37.767] <TB2> INFO: 2560 events read in total (304ms).
[11:00:37.767] <TB2> INFO: Test took 1162ms.
[11:00:37.769] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:38.073] <TB2> INFO: Expecting 2560 events.
[11:00:38.933] <TB2> INFO: 2560 events read in total (303ms).
[11:00:38.933] <TB2> INFO: Test took 1164ms.
[11:00:38.935] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:39.240] <TB2> INFO: Expecting 2560 events.
[11:00:40.099] <TB2> INFO: 2560 events read in total (303ms).
[11:00:40.100] <TB2> INFO: Test took 1165ms.
[11:00:40.103] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:40.406] <TB2> INFO: Expecting 2560 events.
[11:00:41.263] <TB2> INFO: 2560 events read in total (302ms).
[11:00:41.264] <TB2> INFO: Test took 1161ms.
[11:00:41.267] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:41.569] <TB2> INFO: Expecting 2560 events.
[11:00:42.427] <TB2> INFO: 2560 events read in total (302ms).
[11:00:42.427] <TB2> INFO: Test took 1161ms.
[11:00:42.429] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:42.734] <TB2> INFO: Expecting 2560 events.
[11:00:43.591] <TB2> INFO: 2560 events read in total (301ms).
[11:00:43.591] <TB2> INFO: Test took 1162ms.
[11:00:43.594] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:43.897] <TB2> INFO: Expecting 2560 events.
[11:00:44.758] <TB2> INFO: 2560 events read in total (305ms).
[11:00:44.758] <TB2> INFO: Test took 1165ms.
[11:00:44.761] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:45.065] <TB2> INFO: Expecting 2560 events.
[11:00:45.920] <TB2> INFO: 2560 events read in total (299ms).
[11:00:45.920] <TB2> INFO: Test took 1159ms.
[11:00:46.401] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 620 seconds
[11:00:46.401] <TB2> INFO: PH scale (per ROC): 80 73 79 66 81 66 70 72 73 84 83 80 81 97 81 67
[11:00:46.401] <TB2> INFO: PH offset (per ROC): 172 176 153 160 162 176 156 172 182 135 167 156 165 135 175 171
[11:00:46.405] <TB2> INFO: Decoding statistics:
[11:00:46.405] <TB2> INFO: General information:
[11:00:46.405] <TB2> INFO: 16bit words read: 91900
[11:00:46.405] <TB2> INFO: valid events total: 10240
[11:00:46.405] <TB2> INFO: empty events: 7745
[11:00:46.405] <TB2> INFO: valid events with pixels: 2495
[11:00:46.405] <TB2> INFO: valid pixel hits: 2495
[11:00:46.405] <TB2> INFO: Event errors: 0
[11:00:46.405] <TB2> INFO: start marker: 0
[11:00:46.405] <TB2> INFO: stop marker: 0
[11:00:46.405] <TB2> INFO: overflow: 0
[11:00:46.405] <TB2> INFO: invalid 5bit words: 0
[11:00:46.405] <TB2> INFO: invalid XOR eye diagram: 0
[11:00:46.405] <TB2> INFO: frame (failed synchr.): 0
[11:00:46.405] <TB2> INFO: idle data (no TBM trl): 0
[11:00:46.405] <TB2> INFO: no data (only TBM hdr): 0
[11:00:46.405] <TB2> INFO: TBM errors: 0
[11:00:46.405] <TB2> INFO: flawed TBM headers: 0
[11:00:46.405] <TB2> INFO: flawed TBM trailers: 0
[11:00:46.405] <TB2> INFO: event ID mismatches: 0
[11:00:46.405] <TB2> INFO: ROC errors: 0
[11:00:46.405] <TB2> INFO: missing ROC header(s): 0
[11:00:46.405] <TB2> INFO: misplaced readback start: 0
[11:00:46.405] <TB2> INFO: Pixel decoding errors: 0
[11:00:46.405] <TB2> INFO: pixel data incomplete: 0
[11:00:46.405] <TB2> INFO: pixel address: 0
[11:00:46.405] <TB2> INFO: pulse height fill bit: 0
[11:00:46.405] <TB2> INFO: buffer corruption: 0
[11:00:46.593] <TB2> INFO: ######################################################################
[11:00:46.593] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:00:46.593] <TB2> INFO: ######################################################################
[11:00:46.602] <TB2> INFO: scanning low vcal = 10
[11:00:46.865] <TB2> INFO: Expecting 41600 events.
[11:00:50.412] <TB2> INFO: 41600 events read in total (2991ms).
[11:00:50.413] <TB2> INFO: Test took 3810ms.
[11:00:50.415] <TB2> INFO: scanning low vcal = 20
[11:00:50.717] <TB2> INFO: Expecting 41600 events.
[11:00:54.259] <TB2> INFO: 41600 events read in total (2986ms).
[11:00:54.259] <TB2> INFO: Test took 3844ms.
[11:00:54.261] <TB2> INFO: scanning low vcal = 30
[11:00:54.565] <TB2> INFO: Expecting 41600 events.
[11:00:58.136] <TB2> INFO: 41600 events read in total (3015ms).
[11:00:58.137] <TB2> INFO: Test took 3876ms.
[11:00:58.139] <TB2> INFO: scanning low vcal = 40
[11:00:58.433] <TB2> INFO: Expecting 41600 events.
[11:01:02.594] <TB2> INFO: 41600 events read in total (3605ms).
[11:01:02.596] <TB2> INFO: Test took 4457ms.
[11:01:02.599] <TB2> INFO: scanning low vcal = 50
[11:01:02.862] <TB2> INFO: Expecting 41600 events.
[11:01:07.076] <TB2> INFO: 41600 events read in total (3658ms).
[11:01:07.077] <TB2> INFO: Test took 4478ms.
[11:01:07.080] <TB2> INFO: scanning low vcal = 60
[11:01:07.337] <TB2> INFO: Expecting 41600 events.
[11:01:11.524] <TB2> INFO: 41600 events read in total (3631ms).
[11:01:11.524] <TB2> INFO: Test took 4444ms.
[11:01:11.527] <TB2> INFO: scanning low vcal = 70
[11:01:11.788] <TB2> INFO: Expecting 41600 events.
[11:01:15.988] <TB2> INFO: 41600 events read in total (3644ms).
[11:01:15.989] <TB2> INFO: Test took 4462ms.
[11:01:15.991] <TB2> INFO: scanning low vcal = 80
[11:01:16.267] <TB2> INFO: Expecting 41600 events.
[11:01:20.463] <TB2> INFO: 41600 events read in total (3640ms).
[11:01:20.463] <TB2> INFO: Test took 4472ms.
[11:01:20.466] <TB2> INFO: scanning low vcal = 90
[11:01:20.745] <TB2> INFO: Expecting 41600 events.
[11:01:24.992] <TB2> INFO: 41600 events read in total (3691ms).
[11:01:24.993] <TB2> INFO: Test took 4527ms.
[11:01:24.996] <TB2> INFO: scanning low vcal = 100
[11:01:25.283] <TB2> INFO: Expecting 41600 events.
[11:01:29.478] <TB2> INFO: 41600 events read in total (3638ms).
[11:01:29.479] <TB2> INFO: Test took 4483ms.
[11:01:29.482] <TB2> INFO: scanning low vcal = 110
[11:01:29.747] <TB2> INFO: Expecting 41600 events.
[11:01:33.972] <TB2> INFO: 41600 events read in total (3668ms).
[11:01:33.972] <TB2> INFO: Test took 4490ms.
[11:01:33.975] <TB2> INFO: scanning low vcal = 120
[11:01:34.255] <TB2> INFO: Expecting 41600 events.
[11:01:38.454] <TB2> INFO: 41600 events read in total (3643ms).
[11:01:38.454] <TB2> INFO: Test took 4479ms.
[11:01:38.457] <TB2> INFO: scanning low vcal = 130
[11:01:38.721] <TB2> INFO: Expecting 41600 events.
[11:01:42.952] <TB2> INFO: 41600 events read in total (3675ms).
[11:01:42.952] <TB2> INFO: Test took 4495ms.
[11:01:42.955] <TB2> INFO: scanning low vcal = 140
[11:01:43.223] <TB2> INFO: Expecting 41600 events.
[11:01:47.471] <TB2> INFO: 41600 events read in total (3691ms).
[11:01:47.472] <TB2> INFO: Test took 4517ms.
[11:01:47.474] <TB2> INFO: scanning low vcal = 150
[11:01:47.737] <TB2> INFO: Expecting 41600 events.
[11:01:51.964] <TB2> INFO: 41600 events read in total (3671ms).
[11:01:51.965] <TB2> INFO: Test took 4490ms.
[11:01:51.968] <TB2> INFO: scanning low vcal = 160
[11:01:52.253] <TB2> INFO: Expecting 41600 events.
[11:01:56.448] <TB2> INFO: 41600 events read in total (3638ms).
[11:01:56.448] <TB2> INFO: Test took 4480ms.
[11:01:56.451] <TB2> INFO: scanning low vcal = 170
[11:01:56.714] <TB2> INFO: Expecting 41600 events.
[11:02:00.901] <TB2> INFO: 41600 events read in total (3631ms).
[11:02:00.901] <TB2> INFO: Test took 4450ms.
[11:02:00.905] <TB2> INFO: scanning low vcal = 180
[11:02:01.183] <TB2> INFO: Expecting 41600 events.
[11:02:05.380] <TB2> INFO: 41600 events read in total (3641ms).
[11:02:05.381] <TB2> INFO: Test took 4476ms.
[11:02:05.383] <TB2> INFO: scanning low vcal = 190
[11:02:05.645] <TB2> INFO: Expecting 41600 events.
[11:02:09.845] <TB2> INFO: 41600 events read in total (3644ms).
[11:02:09.846] <TB2> INFO: Test took 4463ms.
[11:02:09.848] <TB2> INFO: scanning low vcal = 200
[11:02:10.108] <TB2> INFO: Expecting 41600 events.
[11:02:14.319] <TB2> INFO: 41600 events read in total (3655ms).
[11:02:14.319] <TB2> INFO: Test took 4471ms.
[11:02:14.322] <TB2> INFO: scanning low vcal = 210
[11:02:14.597] <TB2> INFO: Expecting 41600 events.
[11:02:18.820] <TB2> INFO: 41600 events read in total (3667ms).
[11:02:18.821] <TB2> INFO: Test took 4499ms.
[11:02:18.824] <TB2> INFO: scanning low vcal = 220
[11:02:19.087] <TB2> INFO: Expecting 41600 events.
[11:02:23.308] <TB2> INFO: 41600 events read in total (3664ms).
[11:02:23.308] <TB2> INFO: Test took 4484ms.
[11:02:23.311] <TB2> INFO: scanning low vcal = 230
[11:02:23.598] <TB2> INFO: Expecting 41600 events.
[11:02:27.799] <TB2> INFO: 41600 events read in total (3645ms).
[11:02:27.799] <TB2> INFO: Test took 4488ms.
[11:02:27.807] <TB2> INFO: scanning low vcal = 240
[11:02:28.101] <TB2> INFO: Expecting 41600 events.
[11:02:32.320] <TB2> INFO: 41600 events read in total (3663ms).
[11:02:32.321] <TB2> INFO: Test took 4514ms.
[11:02:32.323] <TB2> INFO: scanning low vcal = 250
[11:02:32.579] <TB2> INFO: Expecting 41600 events.
[11:02:36.801] <TB2> INFO: 41600 events read in total (3666ms).
[11:02:36.801] <TB2> INFO: Test took 4477ms.
[11:02:36.806] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[11:02:37.083] <TB2> INFO: Expecting 41600 events.
[11:02:41.314] <TB2> INFO: 41600 events read in total (3675ms).
[11:02:41.314] <TB2> INFO: Test took 4508ms.
[11:02:41.317] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[11:02:41.575] <TB2> INFO: Expecting 41600 events.
[11:02:45.819] <TB2> INFO: 41600 events read in total (3688ms).
[11:02:45.820] <TB2> INFO: Test took 4503ms.
[11:02:45.823] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[11:02:46.112] <TB2> INFO: Expecting 41600 events.
[11:02:50.318] <TB2> INFO: 41600 events read in total (3650ms).
[11:02:50.319] <TB2> INFO: Test took 4496ms.
[11:02:50.322] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[11:02:50.582] <TB2> INFO: Expecting 41600 events.
[11:02:54.803] <TB2> INFO: 41600 events read in total (3665ms).
[11:02:54.804] <TB2> INFO: Test took 4482ms.
[11:02:54.807] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:02:55.097] <TB2> INFO: Expecting 41600 events.
[11:02:59.311] <TB2> INFO: 41600 events read in total (3658ms).
[11:02:59.312] <TB2> INFO: Test took 4505ms.
[11:02:59.826] <TB2> INFO: PixTestGainPedestal::measure() done
[11:03:31.569] <TB2> INFO: PixTestGainPedestal::fit() done
[11:03:31.569] <TB2> INFO: non-linearity mean: 0.962 0.963 0.953 0.956 0.953 0.958 0.953 0.960 0.957 0.960 0.953 0.949 0.952 0.950 0.953 0.960
[11:03:31.569] <TB2> INFO: non-linearity RMS: 0.005 0.004 0.006 0.006 0.006 0.006 0.005 0.005 0.007 0.004 0.007 0.007 0.006 0.005 0.006 0.006
[11:03:31.570] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:03:31.588] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:03:31.605] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:03:31.623] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:03:31.640] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:03:31.658] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:03:31.678] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:03:31.708] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:03:31.736] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:03:31.754] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:03:31.771] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:03:31.789] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:03:31.806] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:03:31.824] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:03:31.842] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:03:31.859] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:03:31.877] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[11:03:31.877] <TB2> INFO: Decoding statistics:
[11:03:31.877] <TB2> INFO: General information:
[11:03:31.877] <TB2> INFO: 16bit words read: 2662400
[11:03:31.877] <TB2> INFO: valid events total: 166400
[11:03:31.877] <TB2> INFO: empty events: 0
[11:03:31.877] <TB2> INFO: valid events with pixels: 166400
[11:03:31.877] <TB2> INFO: valid pixel hits: 665562
[11:03:31.877] <TB2> INFO: Event errors: 0
[11:03:31.877] <TB2> INFO: start marker: 0
[11:03:31.877] <TB2> INFO: stop marker: 0
[11:03:31.877] <TB2> INFO: overflow: 0
[11:03:31.877] <TB2> INFO: invalid 5bit words: 0
[11:03:31.877] <TB2> INFO: invalid XOR eye diagram: 0
[11:03:31.877] <TB2> INFO: frame (failed synchr.): 0
[11:03:31.877] <TB2> INFO: idle data (no TBM trl): 0
[11:03:31.877] <TB2> INFO: no data (only TBM hdr): 0
[11:03:31.877] <TB2> INFO: TBM errors: 0
[11:03:31.877] <TB2> INFO: flawed TBM headers: 0
[11:03:31.877] <TB2> INFO: flawed TBM trailers: 0
[11:03:31.877] <TB2> INFO: event ID mismatches: 0
[11:03:31.877] <TB2> INFO: ROC errors: 0
[11:03:31.877] <TB2> INFO: missing ROC header(s): 0
[11:03:31.877] <TB2> INFO: misplaced readback start: 0
[11:03:31.877] <TB2> INFO: Pixel decoding errors: 0
[11:03:31.877] <TB2> INFO: pixel data incomplete: 0
[11:03:31.877] <TB2> INFO: pixel address: 0
[11:03:31.877] <TB2> INFO: pulse height fill bit: 0
[11:03:31.877] <TB2> INFO: buffer corruption: 0
[11:03:31.884] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C15.dat
[11:03:31.886] <TB2> INFO: ######################################################################
[11:03:31.886] <TB2> INFO: PixTestReadback::doTest()
[11:03:31.886] <TB2> INFO: ######################################################################
[11:03:31.886] <TB2> INFO: ----------------------------------------------------------------------
[11:03:31.886] <TB2> INFO: PixTestReadback::CalibrateVd()
[11:03:31.886] <TB2> INFO: ----------------------------------------------------------------------
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C0.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C1.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C2.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C3.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C4.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C5.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C6.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C7.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C8.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C9.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C10.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C11.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C12.dat
[11:03:41.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C13.dat
[11:03:41.357] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C14.dat
[11:03:41.357] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C15.dat
[11:03:41.387] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:03:41.388] <TB2> INFO: ----------------------------------------------------------------------
[11:03:41.388] <TB2> INFO: PixTestReadback::CalibrateVa()
[11:03:41.388] <TB2> INFO: ----------------------------------------------------------------------
[11:03:50.866] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C0.dat
[11:03:50.867] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C1.dat
[11:03:50.867] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C2.dat
[11:03:50.867] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C3.dat
[11:03:50.867] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C4.dat
[11:03:50.867] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C5.dat
[11:03:50.867] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C6.dat
[11:03:50.867] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C7.dat
[11:03:50.867] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C8.dat
[11:03:50.868] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C9.dat
[11:03:50.868] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C10.dat
[11:03:50.868] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C11.dat
[11:03:50.868] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C12.dat
[11:03:50.868] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C13.dat
[11:03:50.868] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C14.dat
[11:03:50.868] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C15.dat
[11:03:50.899] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:03:50.899] <TB2> INFO: ----------------------------------------------------------------------
[11:03:50.899] <TB2> INFO: PixTestReadback::readbackVbg()
[11:03:50.899] <TB2> INFO: ----------------------------------------------------------------------
[11:03:58.155] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:03:58.155] <TB2> INFO: ----------------------------------------------------------------------
[11:03:58.155] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[11:03:58.155] <TB2> INFO: ----------------------------------------------------------------------
[11:03:58.155] <TB2> INFO: Vbg will be calibrated using Vd calibration
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153calibrated Vbg = 1.18409 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.5calibrated Vbg = 1.18304 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.4calibrated Vbg = 1.18923 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149.2calibrated Vbg = 1.19438 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 147.1calibrated Vbg = 1.19569 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.6calibrated Vbg = 1.19789 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.3calibrated Vbg = 1.20207 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.4calibrated Vbg = 1.20328 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.4calibrated Vbg = 1.20442 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.6calibrated Vbg = 1.20225 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 148calibrated Vbg = 1.19646 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.5calibrated Vbg = 1.19962 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.1calibrated Vbg = 1.19464 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.8calibrated Vbg = 1.1951 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.1calibrated Vbg = 1.19978 :::*/*/*/*/
[11:03:58.155] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 165.7calibrated Vbg = 1.1987 :::*/*/*/*/
[11:03:58.157] <TB2> INFO: ----------------------------------------------------------------------
[11:03:58.157] <TB2> INFO: PixTestReadback::CalibrateIa()
[11:03:58.157] <TB2> INFO: ----------------------------------------------------------------------
[11:06:33.656] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C0.dat
[11:06:33.656] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C1.dat
[11:06:33.656] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C2.dat
[11:06:33.656] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C3.dat
[11:06:33.656] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C4.dat
[11:06:33.656] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C5.dat
[11:06:33.656] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C6.dat
[11:06:33.656] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C7.dat
[11:06:33.657] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C8.dat
[11:06:33.657] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C9.dat
[11:06:33.659] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C10.dat
[11:06:33.659] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C11.dat
[11:06:33.659] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C12.dat
[11:06:33.659] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C13.dat
[11:06:33.659] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C14.dat
[11:06:33.659] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2214_FullQualification_2016-04-22_11h26m_1461317188//000_FulltestPxar_m20//readbackCal_C15.dat
[11:06:33.690] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:06:33.692] <TB2> INFO: PixTestReadback::doTest() done
[11:06:33.692] <TB2> INFO: Decoding statistics:
[11:06:33.692] <TB2> INFO: General information:
[11:06:33.692] <TB2> INFO: 16bit words read: 1024
[11:06:33.692] <TB2> INFO: valid events total: 128
[11:06:33.692] <TB2> INFO: empty events: 128
[11:06:33.692] <TB2> INFO: valid events with pixels: 0
[11:06:33.692] <TB2> INFO: valid pixel hits: 0
[11:06:33.692] <TB2> INFO: Event errors: 0
[11:06:33.692] <TB2> INFO: start marker: 0
[11:06:33.692] <TB2> INFO: stop marker: 0
[11:06:33.692] <TB2> INFO: overflow: 0
[11:06:33.692] <TB2> INFO: invalid 5bit words: 0
[11:06:33.692] <TB2> INFO: invalid XOR eye diagram: 0
[11:06:33.692] <TB2> INFO: frame (failed synchr.): 0
[11:06:33.692] <TB2> INFO: idle data (no TBM trl): 0
[11:06:33.692] <TB2> INFO: no data (only TBM hdr): 0
[11:06:33.692] <TB2> INFO: TBM errors: 0
[11:06:33.692] <TB2> INFO: flawed TBM headers: 0
[11:06:33.692] <TB2> INFO: flawed TBM trailers: 0
[11:06:33.692] <TB2> INFO: event ID mismatches: 0
[11:06:33.692] <TB2> INFO: ROC errors: 0
[11:06:33.692] <TB2> INFO: missing ROC header(s): 0
[11:06:33.692] <TB2> INFO: misplaced readback start: 0
[11:06:33.692] <TB2> INFO: Pixel decoding errors: 0
[11:06:33.692] <TB2> INFO: pixel data incomplete: 0
[11:06:33.692] <TB2> INFO: pixel address: 0
[11:06:33.692] <TB2> INFO: pulse height fill bit: 0
[11:06:33.692] <TB2> INFO: buffer corruption: 0
[11:06:33.716] <TB2> INFO: Decoding statistics:
[11:06:33.716] <TB2> INFO: General information:
[11:06:33.716] <TB2> INFO: 16bit words read: 2755324
[11:06:33.716] <TB2> INFO: valid events total: 176768
[11:06:33.716] <TB2> INFO: empty events: 7873
[11:06:33.716] <TB2> INFO: valid events with pixels: 168895
[11:06:33.716] <TB2> INFO: valid pixel hits: 668057
[11:06:33.716] <TB2> INFO: Event errors: 0
[11:06:33.716] <TB2> INFO: start marker: 0
[11:06:33.716] <TB2> INFO: stop marker: 0
[11:06:33.716] <TB2> INFO: overflow: 0
[11:06:33.716] <TB2> INFO: invalid 5bit words: 0
[11:06:33.716] <TB2> INFO: invalid XOR eye diagram: 0
[11:06:33.716] <TB2> INFO: frame (failed synchr.): 0
[11:06:33.716] <TB2> INFO: idle data (no TBM trl): 0
[11:06:33.716] <TB2> INFO: no data (only TBM hdr): 0
[11:06:33.716] <TB2> INFO: TBM errors: 0
[11:06:33.716] <TB2> INFO: flawed TBM headers: 0
[11:06:33.716] <TB2> INFO: flawed TBM trailers: 0
[11:06:33.716] <TB2> INFO: event ID mismatches: 0
[11:06:33.716] <TB2> INFO: ROC errors: 0
[11:06:33.716] <TB2> INFO: missing ROC header(s): 0
[11:06:33.716] <TB2> INFO: misplaced readback start: 0
[11:06:33.716] <TB2> INFO: Pixel decoding errors: 0
[11:06:33.716] <TB2> INFO: pixel data incomplete: 0
[11:06:33.716] <TB2> INFO: pixel address: 0
[11:06:33.716] <TB2> INFO: pulse height fill bit: 0
[11:06:33.716] <TB2> INFO: buffer corruption: 0
[11:06:33.716] <TB2> INFO: enter test to run
[11:06:33.716] <TB2> INFO: test: exit no parameter change
[11:06:33.857] <TB2> QUIET: Connection to board 156 closed.
[11:06:33.936] <TB2> INFO: pXar: this is the end, my friend