[20:04:10.893] <TB2> INFO: *** Welcome to pxar ***
[20:04:10.893] <TB2> INFO: *** Today: 2016/05/03
[20:04:11.003] <TB2> INFO: *** Version: 9751-dirty
[20:04:11.003] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C15.dat
[20:04:11.005] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//tbmParameters_C0b.dat
[20:04:11.005] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//defaultMaskFile.dat
[20:04:11.006] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters_C15.dat
[20:04:11.088] <TB2> INFO: clk: 4
[20:04:11.088] <TB2> INFO: ctr: 4
[20:04:11.088] <TB2> INFO: sda: 19
[20:04:11.088] <TB2> INFO: tin: 9
[20:04:11.088] <TB2> INFO: level: 15
[20:04:11.088] <TB2> INFO: triggerdelay: 0
[20:04:11.088] <TB2> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[20:04:11.088] <TB2> INFO: Log level: INFO
[20:04:11.095] <TB2> INFO: Found DTB DTB_WXC55Z
[20:04:11.104] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[20:04:11.106] <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:
------------------------------------------------------
[20:04:11.108] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[20:04:12.656] <TB2> INFO: DUT info:
[20:04:12.656] <TB2> INFO: The DUT currently contains the following objects:
[20:04:12.656] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[20:04:12.656] <TB2> INFO: TBM Core alpha (0): 7 registers set
[20:04:12.656] <TB2> INFO: TBM Core beta (1): 7 registers set
[20:04:12.656] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[20:04:12.656] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.656] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.657] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:12.657] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:04:13.057] <TB2> INFO: enter 'restricted' command line mode
[20:04:13.057] <TB2> INFO: enter test to run
[20:04:13.057] <TB2> INFO: test: pretest no parameter change
[20:04:13.057] <TB2> INFO: running: pretest
[20:04:13.062] <TB2> INFO: ----------------------------------------------------------------------
[20:04:13.062] <TB2> INFO: PixTestPretest::programROC()
[20:04:13.062] <TB2> INFO: ----------------------------------------------------------------------
[20:04:31.076] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[20:04:31.076] <TB2> INFO: IA differences per ROC: 19.3 20.9 17.7 20.9 19.3 20.1 17.7 19.3 17.7 17.7 17.7 16.9 22.5 20.9 21.7 17.7
[20:04:31.168] <TB2> INFO: enter test to run
[20:04:31.168] <TB2> INFO: test: pretest no parameter change
[20:04:31.168] <TB2> INFO: running: pretest
[20:04:31.170] <TB2> INFO: ----------------------------------------------------------------------
[20:04:31.170] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[20:04:31.170] <TB2> INFO: ----------------------------------------------------------------------
[20:04:52.438] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[20:04:52.438] <TB2> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 19.3 20.1 20.1 19.3 20.1 18.5 19.3 18.5 18.5 18.5 18.5 19.3 19.3
[20:04:52.475] <TB2> INFO: enter test to run
[20:04:52.475] <TB2> INFO: test: pretest no parameter change
[20:04:52.475] <TB2> INFO: running: pretest
[20:04:52.475] <TB2> INFO: ----------------------------------------------------------------------
[20:04:52.475] <TB2> INFO: PixTestPretest::findTiming()
[20:04:52.475] <TB2> INFO: ----------------------------------------------------------------------
[20:04:52.475] <TB2> INFO: PixTestCmd::init()

[20:06:21.723] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[20:06:21.723] <TB2> INFO: (success/tries = 100/100), width = 4
[20:06:21.724] <TB2> INFO: enter test to run
[20:06:21.724] <TB2> INFO: test: pretest no parameter change
[20:06:21.724] <TB2> INFO: running: pretest
[20:06:21.726] <TB2> INFO: ----------------------------------------------------------------------
[20:06:21.726] <TB2> INFO: PixTestPretest::findWorkingPixel()
[20:06:21.726] <TB2> INFO: ----------------------------------------------------------------------
[20:06:21.819] <TB2> INFO: Expecting 231680 events.


[20:06:31.963] <TB2> INFO: 231680 events read in total (9588ms).
[20:06:31.967] <TB2> INFO: Test took 10237ms.
[20:06:32.196] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[20:06:32.260] <TB2> INFO: enter test to run
[20:06:32.260] <TB2> INFO: test: pretest no parameter change
[20:06:32.260] <TB2> INFO: running: pretest
[20:06:32.261] <TB2> INFO: ----------------------------------------------------------------------
[20:06:32.262] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[20:06:32.262] <TB2> INFO: ----------------------------------------------------------------------
[20:06:32.353] <TB2> INFO: Expecting 231680 events.
[20:06:42.506] <TB2> INFO: 231680 events read in total (9597ms).
[20:06:42.510] <TB2> INFO: Test took 10245ms.
[20:06:42.748] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[20:06:42.748] <TB2> INFO: CalDel: 142 142 142 143 129 141 135 165 142 143 146 143 162 141 139 140
[20:06:42.748] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[20:06:42.788] <TB2> INFO: enter test to run
[20:06:42.788] <TB2> INFO: test: pretest no parameter change
[20:06:42.788] <TB2> INFO: running: pretest
[20:06:42.788] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C0.dat
[20:06:42.789] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C1.dat
[20:06:42.789] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C2.dat
[20:06:42.789] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C3.dat
[20:06:42.789] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C4.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C5.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C6.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C7.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C8.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C9.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C10.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C11.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C12.dat
[20:06:42.790] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C13.dat
[20:06:42.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C14.dat
[20:06:42.791] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters_C15.dat
[20:06:42.791] <TB2> INFO: enter test to run
[20:06:42.791] <TB2> INFO: test: fulltest no parameter change
[20:06:42.791] <TB2> INFO: running: fulltest
[20:06:42.791] <TB2> INFO: ######################################################################
[20:06:42.791] <TB2> INFO: PixTestFullTest::doTest()
[20:06:42.791] <TB2> INFO: ######################################################################
[20:06:42.792] <TB2> INFO: ######################################################################
[20:06:42.792] <TB2> INFO: PixTestAlive::doTest()
[20:06:42.792] <TB2> INFO: ######################################################################
[20:06:42.793] <TB2> INFO: ----------------------------------------------------------------------
[20:06:42.793] <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)
[20:06:42.793] <TB2> INFO: ----------------------------------------------------------------------
[20:06:43.073] <TB2> INFO: Expecting 41600 events.
[20:06:46.859] <TB2> INFO: 41600 events read in total (3229ms).
[20:06:46.859] <TB2> INFO: Test took 4064ms.
[20:06:47.085] <TB2> INFO: PixTestAlive::aliveTest() done
[20:06:47.085] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0
[20:06:47.086] <TB2> INFO: ----------------------------------------------------------------------
[20:06:47.086] <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)
[20:06:47.086] <TB2> INFO: ----------------------------------------------------------------------
[20:06:47.352] <TB2> INFO: Expecting 41600 events.
[20:06:50.349] <TB2> INFO: 41600 events read in total (2441ms).
[20:06:50.349] <TB2> INFO: Test took 3261ms.
[20:06:50.349] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[20:06:50.587] <TB2> INFO: PixTestAlive::maskTest() done
[20:06:50.587] <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
[20:06:50.588] <TB2> INFO: ----------------------------------------------------------------------
[20:06:50.588] <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)
[20:06:50.588] <TB2> INFO: ----------------------------------------------------------------------
[20:06:50.859] <TB2> INFO: Expecting 41600 events.
[20:06:54.638] <TB2> INFO: 41600 events read in total (3223ms).
[20:06:54.639] <TB2> INFO: Test took 4050ms.
[20:06:54.865] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[20:06:54.865] <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
[20:06:54.865] <TB2> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[20:06:54.865] <TB2> INFO: Decoding statistics:
[20:06:54.865] <TB2> INFO: General information:
[20:06:54.865] <TB2> INFO: 16bit words read: 0
[20:06:54.865] <TB2> INFO: valid events total: 0
[20:06:54.865] <TB2> INFO: empty events: 0
[20:06:54.865] <TB2> INFO: valid events with pixels: 0
[20:06:54.865] <TB2> INFO: valid pixel hits: 0
[20:06:54.865] <TB2> INFO: Event errors: 0
[20:06:54.865] <TB2> INFO: start marker: 0
[20:06:54.865] <TB2> INFO: stop marker: 0
[20:06:54.866] <TB2> INFO: overflow: 0
[20:06:54.866] <TB2> INFO: invalid 5bit words: 0
[20:06:54.866] <TB2> INFO: invalid XOR eye diagram: 0
[20:06:54.866] <TB2> INFO: frame (failed synchr.): 0
[20:06:54.866] <TB2> INFO: idle data (no TBM trl): 0
[20:06:54.866] <TB2> INFO: no data (only TBM hdr): 0
[20:06:54.866] <TB2> INFO: TBM errors: 0
[20:06:54.866] <TB2> INFO: flawed TBM headers: 0
[20:06:54.866] <TB2> INFO: flawed TBM trailers: 0
[20:06:54.866] <TB2> INFO: event ID mismatches: 0
[20:06:54.866] <TB2> INFO: ROC errors: 0
[20:06:54.866] <TB2> INFO: missing ROC header(s): 0
[20:06:54.866] <TB2> INFO: misplaced readback start: 0
[20:06:54.866] <TB2> INFO: Pixel decoding errors: 0
[20:06:54.866] <TB2> INFO: pixel data incomplete: 0
[20:06:54.866] <TB2> INFO: pixel address: 0
[20:06:54.866] <TB2> INFO: pulse height fill bit: 0
[20:06:54.866] <TB2> INFO: buffer corruption: 0
[20:06:54.872] <TB2> INFO: ######################################################################
[20:06:54.872] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[20:06:54.872] <TB2> INFO: ######################################################################
[20:06:54.875] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[20:06:54.888] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[20:06:54.888] <TB2> INFO: run 1 of 1
[20:06:55.187] <TB2> INFO: Expecting 3120000 events.
[20:07:36.781] <TB2> INFO: 856600 events read in total (41038ms).
[20:08:17.368] <TB2> INFO: 1699645 events read in total (81626ms).
[20:08:58.141] <TB2> INFO: 2551780 events read in total (122399ms).
[20:09:25.884] <TB2> INFO: 3120000 events read in total (150141ms).
[20:09:25.937] <TB2> INFO: Test took 151049ms.
[20:09:50.217] <TB2> INFO: PixTestBBMap::doTest() done, duration: 175 seconds
[20:09:50.217] <TB2> INFO: number of dead bumps (per ROC): 3 0 0 3 0 1 2 7 0 0 0 1 5 4 4 0
[20:09:50.217] <TB2> INFO: separation cut (per ROC): 100 84 75 102 85 96 99 79 64 65 90 72 76 83 86 90
[20:09:50.217] <TB2> INFO: Decoding statistics:
[20:09:50.217] <TB2> INFO: General information:
[20:09:50.217] <TB2> INFO: 16bit words read: 0
[20:09:50.217] <TB2> INFO: valid events total: 0
[20:09:50.217] <TB2> INFO: empty events: 0
[20:09:50.217] <TB2> INFO: valid events with pixels: 0
[20:09:50.217] <TB2> INFO: valid pixel hits: 0
[20:09:50.217] <TB2> INFO: Event errors: 0
[20:09:50.217] <TB2> INFO: start marker: 0
[20:09:50.217] <TB2> INFO: stop marker: 0
[20:09:50.217] <TB2> INFO: overflow: 0
[20:09:50.217] <TB2> INFO: invalid 5bit words: 0
[20:09:50.217] <TB2> INFO: invalid XOR eye diagram: 0
[20:09:50.217] <TB2> INFO: frame (failed synchr.): 0
[20:09:50.217] <TB2> INFO: idle data (no TBM trl): 0
[20:09:50.217] <TB2> INFO: no data (only TBM hdr): 0
[20:09:50.217] <TB2> INFO: TBM errors: 0
[20:09:50.217] <TB2> INFO: flawed TBM headers: 0
[20:09:50.217] <TB2> INFO: flawed TBM trailers: 0
[20:09:50.217] <TB2> INFO: event ID mismatches: 0
[20:09:50.217] <TB2> INFO: ROC errors: 0
[20:09:50.217] <TB2> INFO: missing ROC header(s): 0
[20:09:50.217] <TB2> INFO: misplaced readback start: 0
[20:09:50.217] <TB2> INFO: Pixel decoding errors: 0
[20:09:50.217] <TB2> INFO: pixel data incomplete: 0
[20:09:50.217] <TB2> INFO: pixel address: 0
[20:09:50.217] <TB2> INFO: pulse height fill bit: 0
[20:09:50.217] <TB2> INFO: buffer corruption: 0
[20:09:50.296] <TB2> INFO: ######################################################################
[20:09:50.296] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:09:50.296] <TB2> INFO: ######################################################################
[20:09:50.296] <TB2> INFO: ----------------------------------------------------------------------
[20:09:50.296] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:09:50.296] <TB2> INFO: ----------------------------------------------------------------------
[20:09:50.296] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[20:09:50.305] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[20:09:50.305] <TB2> INFO: run 1 of 1
[20:09:50.584] <TB2> INFO: Expecting 26208000 events.
[20:10:24.104] <TB2> INFO: 862050 events read in total (32964ms).
[20:10:57.115] <TB2> INFO: 1712900 events read in total (65975ms).
[20:11:30.146] <TB2> INFO: 2562950 events read in total (99006ms).
[20:12:03.004] <TB2> INFO: 3411450 events read in total (131864ms).
[20:12:35.969] <TB2> INFO: 4258450 events read in total (164829ms).
[20:13:08.977] <TB2> INFO: 5106550 events read in total (197837ms).
[20:13:41.662] <TB2> INFO: 5951750 events read in total (230522ms).
[20:14:14.596] <TB2> INFO: 6798700 events read in total (263456ms).
[20:14:47.431] <TB2> INFO: 7644550 events read in total (296292ms).
[20:15:20.062] <TB2> INFO: 8486400 events read in total (328922ms).
[20:15:52.319] <TB2> INFO: 9333750 events read in total (361179ms).
[20:16:24.601] <TB2> INFO: 10178850 events read in total (393461ms).
[20:16:56.572] <TB2> INFO: 11021200 events read in total (425432ms).
[20:17:28.909] <TB2> INFO: 11865050 events read in total (457769ms).
[20:18:01.389] <TB2> INFO: 12708350 events read in total (490249ms).
[20:18:33.407] <TB2> INFO: 13543800 events read in total (522267ms).
[20:19:05.408] <TB2> INFO: 14378550 events read in total (554268ms).
[20:19:37.565] <TB2> INFO: 15212900 events read in total (586425ms).
[20:20:09.617] <TB2> INFO: 16043250 events read in total (618477ms).
[20:20:41.933] <TB2> INFO: 16875050 events read in total (650793ms).
[20:21:13.909] <TB2> INFO: 17707050 events read in total (682769ms).
[20:21:46.039] <TB2> INFO: 18536100 events read in total (714899ms).
[20:22:18.106] <TB2> INFO: 19364100 events read in total (746966ms).
[20:22:49.938] <TB2> INFO: 20192450 events read in total (778798ms).
[20:23:21.995] <TB2> INFO: 21019600 events read in total (810855ms).
[20:23:53.924] <TB2> INFO: 21851050 events read in total (842784ms).
[20:24:26.105] <TB2> INFO: 22678950 events read in total (874965ms).
[20:24:58.228] <TB2> INFO: 23510200 events read in total (907088ms).
[20:25:30.541] <TB2> INFO: 24341800 events read in total (939401ms).
[20:26:02.950] <TB2> INFO: 25173150 events read in total (971810ms).
[20:26:35.876] <TB2> INFO: 26016100 events read in total (1004736ms).
[20:26:43.544] <TB2> INFO: 26208000 events read in total (1012404ms).
[20:26:43.587] <TB2> INFO: Test took 1013282ms.
[20:26:43.866] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:45.472] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:47.023] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:48.550] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:50.065] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:51.495] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:53.137] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:54.694] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:56.224] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:57.841] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:26:59.423] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:27:01.005] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:27:02.594] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:27:04.062] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:27:05.532] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:27:07.003] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[20:27:08.467] <TB2> INFO: PixTestScurves::scurves() done
[20:27:08.467] <TB2> INFO: Vcal mean: 98.40 94.64 88.97 104.88 90.35 98.61 87.13 77.97 71.40 72.50 87.30 76.56 83.49 82.86 88.84 86.51
[20:27:08.467] <TB2> INFO: Vcal RMS: 5.34 5.44 5.54 5.96 5.68 5.66 5.53 4.34 5.13 4.80 6.04 4.49 4.66 5.17 5.29 5.71
[20:27:08.467] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1038 seconds
[20:27:08.467] <TB2> INFO: Decoding statistics:
[20:27:08.467] <TB2> INFO: General information:
[20:27:08.467] <TB2> INFO: 16bit words read: 0
[20:27:08.467] <TB2> INFO: valid events total: 0
[20:27:08.467] <TB2> INFO: empty events: 0
[20:27:08.467] <TB2> INFO: valid events with pixels: 0
[20:27:08.467] <TB2> INFO: valid pixel hits: 0
[20:27:08.467] <TB2> INFO: Event errors: 0
[20:27:08.467] <TB2> INFO: start marker: 0
[20:27:08.467] <TB2> INFO: stop marker: 0
[20:27:08.467] <TB2> INFO: overflow: 0
[20:27:08.467] <TB2> INFO: invalid 5bit words: 0
[20:27:08.467] <TB2> INFO: invalid XOR eye diagram: 0
[20:27:08.468] <TB2> INFO: frame (failed synchr.): 0
[20:27:08.468] <TB2> INFO: idle data (no TBM trl): 0
[20:27:08.468] <TB2> INFO: no data (only TBM hdr): 0
[20:27:08.468] <TB2> INFO: TBM errors: 0
[20:27:08.468] <TB2> INFO: flawed TBM headers: 0
[20:27:08.468] <TB2> INFO: flawed TBM trailers: 0
[20:27:08.468] <TB2> INFO: event ID mismatches: 0
[20:27:08.468] <TB2> INFO: ROC errors: 0
[20:27:08.468] <TB2> INFO: missing ROC header(s): 0
[20:27:08.468] <TB2> INFO: misplaced readback start: 0
[20:27:08.468] <TB2> INFO: Pixel decoding errors: 0
[20:27:08.468] <TB2> INFO: pixel data incomplete: 0
[20:27:08.468] <TB2> INFO: pixel address: 0
[20:27:08.468] <TB2> INFO: pulse height fill bit: 0
[20:27:08.468] <TB2> INFO: buffer corruption: 0
[20:27:08.543] <TB2> INFO: ######################################################################
[20:27:08.543] <TB2> INFO: PixTestTrim::doTest()
[20:27:08.543] <TB2> INFO: ######################################################################
[20:27:08.544] <TB2> INFO: ----------------------------------------------------------------------
[20:27:08.544] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[20:27:08.544] <TB2> INFO: ----------------------------------------------------------------------
[20:27:08.624] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[20:27:08.624] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[20:27:08.632] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[20:27:08.632] <TB2> INFO: run 1 of 1
[20:27:08.903] <TB2> INFO: Expecting 6281600 events.
[20:27:48.951] <TB2> INFO: 1084470 events read in total (39485ms).
[20:28:28.372] <TB2> INFO: 2162130 events read in total (78906ms).
[20:29:08.122] <TB2> INFO: 3233290 events read in total (118656ms).
[20:29:47.868] <TB2> INFO: 4310890 events read in total (158402ms).
[20:30:28.159] <TB2> INFO: 5390330 events read in total (198693ms).
[20:31:01.186] <TB2> INFO: 6281600 events read in total (231720ms).
[20:31:01.212] <TB2> INFO: Test took 232580ms.
[20:31:20.107] <TB2> INFO: ROC 0 VthrComp = 101
[20:31:20.107] <TB2> INFO: ROC 1 VthrComp = 99
[20:31:20.107] <TB2> INFO: ROC 2 VthrComp = 86
[20:31:20.107] <TB2> INFO: ROC 3 VthrComp = 103
[20:31:20.107] <TB2> INFO: ROC 4 VthrComp = 94
[20:31:20.107] <TB2> INFO: ROC 5 VthrComp = 101
[20:31:20.107] <TB2> INFO: ROC 6 VthrComp = 91
[20:31:20.107] <TB2> INFO: ROC 7 VthrComp = 80
[20:31:20.108] <TB2> INFO: ROC 8 VthrComp = 75
[20:31:20.108] <TB2> INFO: ROC 9 VthrComp = 77
[20:31:20.108] <TB2> INFO: ROC 10 VthrComp = 87
[20:31:20.108] <TB2> INFO: ROC 11 VthrComp = 80
[20:31:20.108] <TB2> INFO: ROC 12 VthrComp = 85
[20:31:20.108] <TB2> INFO: ROC 13 VthrComp = 86
[20:31:20.108] <TB2> INFO: ROC 14 VthrComp = 90
[20:31:20.108] <TB2> INFO: ROC 15 VthrComp = 86
[20:31:20.108] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[20:31:20.108] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[20:31:20.117] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[20:31:20.117] <TB2> INFO: run 1 of 1
[20:31:20.378] <TB2> INFO: Expecting 6281600 events.
[20:31:56.094] <TB2> INFO: 756450 events read in total (35160ms).
[20:32:30.912] <TB2> INFO: 1510810 events read in total (69978ms).
[20:33:05.715] <TB2> INFO: 2266200 events read in total (104781ms).
[20:33:40.955] <TB2> INFO: 3022100 events read in total (140021ms).
[20:34:16.362] <TB2> INFO: 3772830 events read in total (175428ms).
[20:34:51.382] <TB2> INFO: 4519920 events read in total (210448ms).
[20:35:26.472] <TB2> INFO: 5264990 events read in total (245538ms).
[20:36:02.651] <TB2> INFO: 6010310 events read in total (281717ms).
[20:36:15.425] <TB2> INFO: 6281600 events read in total (294491ms).
[20:36:15.467] <TB2> INFO: Test took 295350ms.
[20:36:40.708] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 58.1136 for pixel 10/1 mean/min/max = 44.9875/31.8323/58.1426
[20:36:40.709] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 58.1583 for pixel 30/8 mean/min/max = 44.7118/31.1781/58.2456
[20:36:40.709] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 60.3906 for pixel 22/1 mean/min/max = 46.4889/32.573/60.4047
[20:36:40.709] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 61.0155 for pixel 8/78 mean/min/max = 46.523/31.8976/61.1483
[20:36:40.709] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 60.1903 for pixel 10/6 mean/min/max = 46.3438/32.3856/60.302
[20:36:40.710] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 59.757 for pixel 22/49 mean/min/max = 46.0558/32.3021/59.8095
[20:36:40.710] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 58.4931 for pixel 12/7 mean/min/max = 45.4314/32.3248/58.5379
[20:36:40.710] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 58.0891 for pixel 9/0 mean/min/max = 45.4408/32.628/58.2535
[20:36:40.711] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 59.3769 for pixel 25/5 mean/min/max = 47.4688/35.3194/59.6181
[20:36:40.711] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 59.3826 for pixel 23/7 mean/min/max = 47.2779/34.9427/59.6131
[20:36:40.711] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 60.9249 for pixel 22/76 mean/min/max = 46.2906/31.3868/61.1944
[20:36:40.711] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 57.9106 for pixel 47/2 mean/min/max = 45.1751/32.3656/57.9846
[20:36:40.712] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 57.4253 for pixel 51/25 mean/min/max = 45.1162/32.7444/57.4881
[20:36:40.712] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 58.9894 for pixel 0/34 mean/min/max = 45.3621/31.6866/59.0376
[20:36:40.712] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 58.8704 for pixel 3/4 mean/min/max = 46.1483/33.3506/58.946
[20:36:40.713] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 61.0165 for pixel 5/79 mean/min/max = 46.7236/32.1177/61.3294
[20:36:40.713] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:36:40.801] <TB2> INFO: Expecting 514560 events.
[20:36:53.957] <TB2> INFO: 514560 events read in total (12600ms).
[20:36:53.963] <TB2> INFO: Expecting 514560 events.
[20:37:07.028] <TB2> INFO: 514560 events read in total (12646ms).
[20:37:07.035] <TB2> INFO: Expecting 514560 events.
[20:37:19.915] <TB2> INFO: 514560 events read in total (12462ms).
[20:37:19.924] <TB2> INFO: Expecting 514560 events.
[20:37:32.837] <TB2> INFO: 514560 events read in total (12498ms).
[20:37:32.848] <TB2> INFO: Expecting 514560 events.
[20:37:45.671] <TB2> INFO: 514560 events read in total (12405ms).
[20:37:45.684] <TB2> INFO: Expecting 514560 events.
[20:37:58.562] <TB2> INFO: 514560 events read in total (12468ms).
[20:37:58.578] <TB2> INFO: Expecting 514560 events.
[20:38:11.575] <TB2> INFO: 514560 events read in total (12595ms).
[20:38:11.591] <TB2> INFO: Expecting 514560 events.
[20:38:24.382] <TB2> INFO: 514560 events read in total (12388ms).
[20:38:24.400] <TB2> INFO: Expecting 514560 events.
[20:38:37.222] <TB2> INFO: 514560 events read in total (12420ms).
[20:38:37.243] <TB2> INFO: Expecting 514560 events.
[20:38:50.217] <TB2> INFO: 514560 events read in total (12568ms).
[20:38:50.244] <TB2> INFO: Expecting 514560 events.
[20:39:03.090] <TB2> INFO: 514560 events read in total (12444ms).
[20:39:03.113] <TB2> INFO: Expecting 514560 events.
[20:39:16.007] <TB2> INFO: 514560 events read in total (12492ms).
[20:39:16.033] <TB2> INFO: Expecting 514560 events.
[20:39:28.806] <TB2> INFO: 514560 events read in total (12372ms).
[20:39:28.833] <TB2> INFO: Expecting 514560 events.
[20:39:41.496] <TB2> INFO: 514560 events read in total (12261ms).
[20:39:41.527] <TB2> INFO: Expecting 514560 events.
[20:39:54.197] <TB2> INFO: 514560 events read in total (12268ms).
[20:39:54.236] <TB2> INFO: Expecting 514560 events.
[20:40:06.931] <TB2> INFO: 514560 events read in total (12293ms).
[20:40:06.964] <TB2> INFO: Test took 206251ms.
[20:40:07.928] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[20:40:07.937] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[20:40:07.937] <TB2> INFO: run 1 of 1
[20:40:08.197] <TB2> INFO: Expecting 6281600 events.
[20:40:43.380] <TB2> INFO: 740760 events read in total (34627ms).
[20:41:18.029] <TB2> INFO: 1479950 events read in total (69276ms).
[20:41:52.678] <TB2> INFO: 2220440 events read in total (103925ms).
[20:42:27.800] <TB2> INFO: 2960910 events read in total (139047ms).
[20:43:02.262] <TB2> INFO: 3696590 events read in total (173509ms).
[20:43:36.837] <TB2> INFO: 4427720 events read in total (208084ms).
[20:44:11.591] <TB2> INFO: 5156720 events read in total (242838ms).
[20:44:47.288] <TB2> INFO: 5885530 events read in total (278535ms).
[20:45:06.053] <TB2> INFO: 6281600 events read in total (297300ms).
[20:45:06.110] <TB2> INFO: Test took 298173ms.
[20:45:30.205] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.176540 .. 255.000000
[20:45:30.279] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[20:45:30.288] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[20:45:30.288] <TB2> INFO: run 1 of 1
[20:45:30.548] <TB2> INFO: Expecting 10649600 events.
[20:46:05.728] <TB2> INFO: 712260 events read in total (34624ms).
[20:46:40.146] <TB2> INFO: 1424560 events read in total (69042ms).
[20:47:14.532] <TB2> INFO: 2137010 events read in total (103428ms).
[20:47:48.550] <TB2> INFO: 2849500 events read in total (137446ms).
[20:48:23.106] <TB2> INFO: 3561930 events read in total (172002ms).
[20:48:57.268] <TB2> INFO: 4274640 events read in total (206164ms).
[20:49:31.617] <TB2> INFO: 4987320 events read in total (240513ms).
[20:50:06.319] <TB2> INFO: 5699600 events read in total (275215ms).
[20:50:40.944] <TB2> INFO: 6411660 events read in total (309840ms).
[20:51:15.307] <TB2> INFO: 7123010 events read in total (344203ms).
[20:51:49.715] <TB2> INFO: 7834340 events read in total (378611ms).
[20:52:23.950] <TB2> INFO: 8544910 events read in total (412846ms).
[20:52:58.198] <TB2> INFO: 9255940 events read in total (447094ms).
[20:53:33.473] <TB2> INFO: 9966600 events read in total (482369ms).
[20:54:07.390] <TB2> INFO: 10649600 events read in total (516286ms).
[20:54:07.499] <TB2> INFO: Test took 517211ms.
[20:54:38.850] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 13.847848 .. 44.916376
[20:54:38.944] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 3 .. 54 (-1/-1) hits flags = 528 (plus default)
[20:54:38.953] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[20:54:38.953] <TB2> INFO: run 1 of 1
[20:54:39.225] <TB2> INFO: Expecting 2163200 events.
[20:55:17.738] <TB2> INFO: 955950 events read in total (37956ms).
[20:55:56.065] <TB2> INFO: 1909950 events read in total (76283ms).
[20:56:06.463] <TB2> INFO: 2163200 events read in total (86681ms).
[20:56:06.478] <TB2> INFO: Test took 87526ms.
[20:56:20.574] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 19.554333 .. 44.327084
[20:56:20.656] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 54 (-1/-1) hits flags = 528 (plus default)
[20:56:20.665] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[20:56:20.665] <TB2> INFO: run 1 of 1
[20:56:20.944] <TB2> INFO: Expecting 1913600 events.
[20:56:59.864] <TB2> INFO: 924390 events read in total (38364ms).
[20:57:38.135] <TB2> INFO: 1848080 events read in total (76635ms).
[20:57:41.220] <TB2> INFO: 1913600 events read in total (79720ms).
[20:57:41.233] <TB2> INFO: Test took 80568ms.
[20:57:54.371] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 3.500000 .. 44.327084
[20:57:54.446] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 54 (-1/-1) hits flags = 528 (plus default)
[20:57:54.455] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[20:57:54.455] <TB2> INFO: run 1 of 1
[20:57:54.714] <TB2> INFO: Expecting 2163200 events.
[20:58:33.711] <TB2> INFO: 958870 events read in total (38441ms).
[20:59:12.421] <TB2> INFO: 1917640 events read in total (77151ms).
[20:59:22.814] <TB2> INFO: 2163200 events read in total (87544ms).
[20:59:22.825] <TB2> INFO: Test took 88370ms.
[20:59:35.748] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[20:59:35.748] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[20:59:35.756] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[20:59:35.756] <TB2> INFO: run 1 of 1
[20:59:36.021] <TB2> INFO: Expecting 1705600 events.
[21:00:14.625] <TB2> INFO: 878960 events read in total (38047ms).
[21:00:50.107] <TB2> INFO: 1705600 events read in total (73529ms).
[21:00:50.120] <TB2> INFO: Test took 74363ms.
[21:01:03.682] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C0.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C1.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C2.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C3.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C4.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C5.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C6.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C7.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C8.dat
[21:01:03.683] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C9.dat
[21:01:03.684] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C10.dat
[21:01:03.684] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C11.dat
[21:01:03.684] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C12.dat
[21:01:03.684] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C13.dat
[21:01:03.684] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C14.dat
[21:01:03.684] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C15.dat
[21:01:03.684] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C0.dat
[21:01:03.690] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C1.dat
[21:01:03.696] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C2.dat
[21:01:03.702] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C3.dat
[21:01:03.708] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C4.dat
[21:01:03.715] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C5.dat
[21:01:03.721] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C6.dat
[21:01:03.727] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C7.dat
[21:01:03.733] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C8.dat
[21:01:03.739] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C9.dat
[21:01:03.745] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C10.dat
[21:01:03.751] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C11.dat
[21:01:03.757] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C12.dat
[21:01:03.763] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C13.dat
[21:01:03.769] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C14.dat
[21:01:03.775] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//trimParameters35_C15.dat
[21:01:03.781] <TB2> INFO: PixTestTrim::trimTest() done
[21:01:03.781] <TB2> INFO: vtrim: 104 117 97 104 92 108 104 94 103 107 98 87 82 88 95 93
[21:01:03.781] <TB2> INFO: vthrcomp: 101 99 86 103 94 101 91 80 75 77 87 80 85 86 90 86
[21:01:03.781] <TB2> INFO: vcal mean: 34.94 34.97 34.97 34.94 35.02 35.00 35.04 34.99 35.04 35.02 34.99 34.96 34.98 34.99 35.02 34.97
[21:01:03.781] <TB2> INFO: vcal RMS: 0.86 0.93 0.91 0.99 0.85 0.84 0.96 1.00 1.14 0.79 1.08 0.84 0.82 0.84 0.84 0.93
[21:01:03.781] <TB2> INFO: bits mean: 9.45 10.33 9.20 9.09 9.04 9.30 9.75 9.68 8.92 8.45 9.69 9.64 9.35 9.20 9.08 9.02
[21:01:03.781] <TB2> INFO: bits RMS: 2.79 2.47 2.71 2.81 2.79 2.73 2.54 2.53 2.37 2.57 2.64 2.62 2.69 2.94 2.68 2.84
[21:01:03.788] <TB2> INFO: ----------------------------------------------------------------------
[21:01:03.788] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[21:01:03.788] <TB2> INFO: ----------------------------------------------------------------------
[21:01:03.791] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[21:01:03.799] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[21:01:03.799] <TB2> INFO: run 1 of 1
[21:01:04.058] <TB2> INFO: Expecting 4160000 events.
[21:01:45.350] <TB2> INFO: 901885 events read in total (40736ms).
[21:02:26.259] <TB2> INFO: 1798435 events read in total (81645ms).
[21:03:07.168] <TB2> INFO: 2687450 events read in total (122554ms).
[21:03:48.154] <TB2> INFO: 3572470 events read in total (163540ms).
[21:04:15.162] <TB2> INFO: 4160000 events read in total (190549ms).
[21:04:15.209] <TB2> INFO: Test took 191410ms.
[21:04:45.656] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[21:04:45.664] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[21:04:45.664] <TB2> INFO: run 1 of 1
[21:04:45.931] <TB2> INFO: Expecting 3744000 events.
[21:05:27.962] <TB2> INFO: 906020 events read in total (41475ms).
[21:06:08.968] <TB2> INFO: 1805990 events read in total (82481ms).
[21:06:50.440] <TB2> INFO: 2695720 events read in total (123953ms).
[21:07:31.036] <TB2> INFO: 3583920 events read in total (164549ms).
[21:07:38.558] <TB2> INFO: 3744000 events read in total (172071ms).
[21:07:38.595] <TB2> INFO: Test took 172931ms.
[21:08:06.165] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 163 (-1/-1) hits flags = 528 (plus default)
[21:08:06.174] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[21:08:06.174] <TB2> INFO: run 1 of 1
[21:08:06.437] <TB2> INFO: Expecting 3411200 events.
[21:08:48.472] <TB2> INFO: 938605 events read in total (41478ms).
[21:09:30.534] <TB2> INFO: 1867870 events read in total (83540ms).
[21:10:11.677] <TB2> INFO: 2786150 events read in total (124684ms).
[21:10:39.604] <TB2> INFO: 3411200 events read in total (152610ms).
[21:10:39.642] <TB2> INFO: Test took 153468ms.
[21:11:07.089] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[21:11:07.098] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[21:11:07.098] <TB2> INFO: run 1 of 1
[21:11:07.388] <TB2> INFO: Expecting 3390400 events.
[21:11:49.970] <TB2> INFO: 940260 events read in total (42026ms).
[21:12:31.549] <TB2> INFO: 1870990 events read in total (83605ms).
[21:13:12.532] <TB2> INFO: 2790520 events read in total (124588ms).
[21:13:39.326] <TB2> INFO: 3390400 events read in total (151382ms).
[21:13:39.360] <TB2> INFO: Test took 152262ms.
[21:14:06.631] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[21:14:06.642] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[21:14:06.642] <TB2> INFO: run 1 of 1
[21:14:06.933] <TB2> INFO: Expecting 3390400 events.
[21:14:48.878] <TB2> INFO: 939625 events read in total (41389ms).
[21:15:29.627] <TB2> INFO: 1869775 events read in total (82138ms).
[21:16:11.087] <TB2> INFO: 2788845 events read in total (123598ms).
[21:16:37.894] <TB2> INFO: 3390400 events read in total (150405ms).
[21:16:37.931] <TB2> INFO: Test took 151289ms.
[21:17:02.182] <TB2> INFO: PixTestTrim::trimBitTest() done
[21:17:02.183] <TB2> INFO: PixTestTrim::doTest() done, duration: 2993 seconds
[21:17:02.183] <TB2> INFO: Decoding statistics:
[21:17:02.183] <TB2> INFO: General information:
[21:17:02.183] <TB2> INFO: 16bit words read: 0
[21:17:02.183] <TB2> INFO: valid events total: 0
[21:17:02.183] <TB2> INFO: empty events: 0
[21:17:02.183] <TB2> INFO: valid events with pixels: 0
[21:17:02.183] <TB2> INFO: valid pixel hits: 0
[21:17:02.183] <TB2> INFO: Event errors: 0
[21:17:02.183] <TB2> INFO: start marker: 0
[21:17:02.183] <TB2> INFO: stop marker: 0
[21:17:02.183] <TB2> INFO: overflow: 0
[21:17:02.183] <TB2> INFO: invalid 5bit words: 0
[21:17:02.183] <TB2> INFO: invalid XOR eye diagram: 0
[21:17:02.183] <TB2> INFO: frame (failed synchr.): 0
[21:17:02.183] <TB2> INFO: idle data (no TBM trl): 0
[21:17:02.183] <TB2> INFO: no data (only TBM hdr): 0
[21:17:02.183] <TB2> INFO: TBM errors: 0
[21:17:02.183] <TB2> INFO: flawed TBM headers: 0
[21:17:02.183] <TB2> INFO: flawed TBM trailers: 0
[21:17:02.183] <TB2> INFO: event ID mismatches: 0
[21:17:02.183] <TB2> INFO: ROC errors: 0
[21:17:02.183] <TB2> INFO: missing ROC header(s): 0
[21:17:02.183] <TB2> INFO: misplaced readback start: 0
[21:17:02.183] <TB2> INFO: Pixel decoding errors: 0
[21:17:02.183] <TB2> INFO: pixel data incomplete: 0
[21:17:02.183] <TB2> INFO: pixel address: 0
[21:17:02.183] <TB2> INFO: pulse height fill bit: 0
[21:17:02.183] <TB2> INFO: buffer corruption: 0
[21:17:02.877] <TB2> INFO: ######################################################################
[21:17:02.877] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[21:17:02.877] <TB2> INFO: ######################################################################
[21:17:03.140] <TB2> INFO: Expecting 41600 events.
[21:17:06.868] <TB2> INFO: 41600 events read in total (3172ms).
[21:17:06.869] <TB2> INFO: Test took 3991ms.
[21:17:07.418] <TB2> INFO: Expecting 41600 events.
[21:17:11.289] <TB2> INFO: 41600 events read in total (3315ms).
[21:17:11.289] <TB2> INFO: Test took 4215ms.
[21:17:11.597] <TB2> INFO: Expecting 41600 events.
[21:17:15.422] <TB2> INFO: 41600 events read in total (3269ms).
[21:17:15.423] <TB2> INFO: Test took 4113ms.
[21:17:15.679] <TB2> INFO: Expecting 2560 events.
[21:17:16.531] <TB2> INFO: 2560 events read in total (296ms).
[21:17:16.532] <TB2> INFO: Test took 1095ms.
[21:17:16.837] <TB2> INFO: Expecting 2560 events.
[21:17:17.690] <TB2> INFO: 2560 events read in total (297ms).
[21:17:17.690] <TB2> INFO: Test took 1158ms.
[21:17:17.995] <TB2> INFO: Expecting 2560 events.
[21:17:18.849] <TB2> INFO: 2560 events read in total (298ms).
[21:17:18.849] <TB2> INFO: Test took 1158ms.
[21:17:19.154] <TB2> INFO: Expecting 2560 events.
[21:17:20.008] <TB2> INFO: 2560 events read in total (298ms).
[21:17:20.008] <TB2> INFO: Test took 1159ms.
[21:17:20.313] <TB2> INFO: Expecting 2560 events.
[21:17:21.166] <TB2> INFO: 2560 events read in total (296ms).
[21:17:21.166] <TB2> INFO: Test took 1158ms.
[21:17:21.471] <TB2> INFO: Expecting 2560 events.
[21:17:22.324] <TB2> INFO: 2560 events read in total (297ms).
[21:17:22.324] <TB2> INFO: Test took 1158ms.
[21:17:22.629] <TB2> INFO: Expecting 2560 events.
[21:17:23.482] <TB2> INFO: 2560 events read in total (297ms).
[21:17:23.483] <TB2> INFO: Test took 1158ms.
[21:17:23.788] <TB2> INFO: Expecting 2560 events.
[21:17:24.641] <TB2> INFO: 2560 events read in total (297ms).
[21:17:24.641] <TB2> INFO: Test took 1158ms.
[21:17:24.946] <TB2> INFO: Expecting 2560 events.
[21:17:25.799] <TB2> INFO: 2560 events read in total (297ms).
[21:17:25.799] <TB2> INFO: Test took 1158ms.
[21:17:26.104] <TB2> INFO: Expecting 2560 events.
[21:17:26.957] <TB2> INFO: 2560 events read in total (297ms).
[21:17:26.957] <TB2> INFO: Test took 1158ms.
[21:17:27.262] <TB2> INFO: Expecting 2560 events.
[21:17:28.115] <TB2> INFO: 2560 events read in total (297ms).
[21:17:28.115] <TB2> INFO: Test took 1158ms.
[21:17:28.420] <TB2> INFO: Expecting 2560 events.
[21:17:29.278] <TB2> INFO: 2560 events read in total (302ms).
[21:17:29.278] <TB2> INFO: Test took 1163ms.
[21:17:29.583] <TB2> INFO: Expecting 2560 events.
[21:17:30.444] <TB2> INFO: 2560 events read in total (304ms).
[21:17:30.445] <TB2> INFO: Test took 1166ms.
[21:17:30.750] <TB2> INFO: Expecting 2560 events.
[21:17:31.607] <TB2> INFO: 2560 events read in total (301ms).
[21:17:31.608] <TB2> INFO: Test took 1163ms.
[21:17:31.913] <TB2> INFO: Expecting 2560 events.
[21:17:32.765] <TB2> INFO: 2560 events read in total (297ms).
[21:17:32.766] <TB2> INFO: Test took 1158ms.
[21:17:33.071] <TB2> INFO: Expecting 2560 events.
[21:17:33.927] <TB2> INFO: 2560 events read in total (300ms).
[21:17:33.927] <TB2> INFO: Test took 1161ms.
[21:17:33.931] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:17:34.233] <TB2> INFO: Expecting 655360 events.
[21:17:51.152] <TB2> INFO: 655360 events read in total (16363ms).
[21:17:51.160] <TB2> INFO: Expecting 655360 events.
[21:18:07.887] <TB2> INFO: 655360 events read in total (16321ms).
[21:18:07.902] <TB2> INFO: Expecting 655360 events.
[21:18:24.373] <TB2> INFO: 655360 events read in total (16071ms).
[21:18:24.389] <TB2> INFO: Expecting 655360 events.
[21:18:40.946] <TB2> INFO: 655360 events read in total (16155ms).
[21:18:40.966] <TB2> INFO: Expecting 655360 events.
[21:18:57.661] <TB2> INFO: 655360 events read in total (16294ms).
[21:18:57.683] <TB2> INFO: Expecting 655360 events.
[21:19:14.582] <TB2> INFO: 655360 events read in total (16498ms).
[21:19:14.609] <TB2> INFO: Expecting 655360 events.
[21:19:31.351] <TB2> INFO: 655360 events read in total (16340ms).
[21:19:31.383] <TB2> INFO: Expecting 655360 events.
[21:19:48.084] <TB2> INFO: 655360 events read in total (16299ms).
[21:19:48.117] <TB2> INFO: Expecting 655360 events.
[21:20:04.819] <TB2> INFO: 655360 events read in total (16300ms).
[21:20:04.857] <TB2> INFO: Expecting 655360 events.
[21:20:21.626] <TB2> INFO: 655360 events read in total (16368ms).
[21:20:21.668] <TB2> INFO: Expecting 655360 events.
[21:20:38.518] <TB2> INFO: 655360 events read in total (16448ms).
[21:20:38.561] <TB2> INFO: Expecting 655360 events.
[21:20:55.293] <TB2> INFO: 655360 events read in total (16330ms).
[21:20:55.341] <TB2> INFO: Expecting 655360 events.
[21:21:12.095] <TB2> INFO: 655360 events read in total (16352ms).
[21:21:12.148] <TB2> INFO: Expecting 655360 events.
[21:21:28.873] <TB2> INFO: 655360 events read in total (16323ms).
[21:21:28.932] <TB2> INFO: Expecting 655360 events.
[21:21:45.655] <TB2> INFO: 655360 events read in total (16321ms).
[21:21:45.713] <TB2> INFO: Expecting 655360 events.
[21:22:02.472] <TB2> INFO: 655360 events read in total (16357ms).
[21:22:02.537] <TB2> INFO: Test took 268606ms.
[21:22:02.616] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:22:02.846] <TB2> INFO: Expecting 655360 events.
[21:22:19.661] <TB2> INFO: 655360 events read in total (16259ms).
[21:22:19.670] <TB2> INFO: Expecting 655360 events.
[21:22:36.356] <TB2> INFO: 655360 events read in total (16284ms).
[21:22:36.368] <TB2> INFO: Expecting 655360 events.
[21:22:52.990] <TB2> INFO: 655360 events read in total (16220ms).
[21:22:53.006] <TB2> INFO: Expecting 655360 events.
[21:23:09.790] <TB2> INFO: 655360 events read in total (16383ms).
[21:23:09.809] <TB2> INFO: Expecting 655360 events.
[21:23:26.404] <TB2> INFO: 655360 events read in total (16193ms).
[21:23:26.427] <TB2> INFO: Expecting 655360 events.
[21:23:43.131] <TB2> INFO: 655360 events read in total (16302ms).
[21:23:43.159] <TB2> INFO: Expecting 655360 events.
[21:23:59.909] <TB2> INFO: 655360 events read in total (16348ms).
[21:23:59.941] <TB2> INFO: Expecting 655360 events.
[21:24:16.510] <TB2> INFO: 655360 events read in total (16168ms).
[21:24:16.545] <TB2> INFO: Expecting 655360 events.
[21:24:33.226] <TB2> INFO: 655360 events read in total (16279ms).
[21:24:33.263] <TB2> INFO: Expecting 655360 events.
[21:24:49.977] <TB2> INFO: 655360 events read in total (16312ms).
[21:24:50.018] <TB2> INFO: Expecting 655360 events.
[21:25:06.803] <TB2> INFO: 655360 events read in total (16383ms).
[21:25:06.850] <TB2> INFO: Expecting 655360 events.
[21:25:23.678] <TB2> INFO: 655360 events read in total (16426ms).
[21:25:23.725] <TB2> INFO: Expecting 655360 events.
[21:25:40.630] <TB2> INFO: 655360 events read in total (16504ms).
[21:25:40.679] <TB2> INFO: Expecting 655360 events.
[21:25:57.527] <TB2> INFO: 655360 events read in total (16446ms).
[21:25:57.582] <TB2> INFO: Expecting 655360 events.
[21:26:14.320] <TB2> INFO: 655360 events read in total (16336ms).
[21:26:14.376] <TB2> INFO: Expecting 655360 events.
[21:26:31.091] <TB2> INFO: 655360 events read in total (16313ms).
[21:26:31.152] <TB2> INFO: Test took 268536ms.
[21:26:31.350] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.357] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.364] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.372] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.378] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.385] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.392] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[21:26:31.399] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[21:26:31.406] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[21:26:31.413] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[21:26:31.420] <TB2> INFO: safety margin for low PH: adding 5, margin is now 25
[21:26:31.427] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.434] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.441] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.449] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.458] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.466] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.473] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.481] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.489] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.497] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[21:26:31.505] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[21:26:31.513] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[21:26:31.554] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C0.dat
[21:26:31.555] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C1.dat
[21:26:31.555] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C2.dat
[21:26:31.555] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C3.dat
[21:26:31.555] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C4.dat
[21:26:31.555] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C5.dat
[21:26:31.555] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C6.dat
[21:26:31.556] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C7.dat
[21:26:31.556] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C8.dat
[21:26:31.556] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C9.dat
[21:26:31.556] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C10.dat
[21:26:31.556] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C11.dat
[21:26:31.557] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C12.dat
[21:26:31.557] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C13.dat
[21:26:31.557] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C14.dat
[21:26:31.557] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//dacParameters35_C15.dat
[21:26:31.834] <TB2> INFO: Expecting 41600 events.
[21:26:35.297] <TB2> INFO: 41600 events read in total (2907ms).
[21:26:35.298] <TB2> INFO: Test took 3738ms.
[21:26:35.733] <TB2> INFO: Expecting 41600 events.
[21:26:39.222] <TB2> INFO: 41600 events read in total (2932ms).
[21:26:39.222] <TB2> INFO: Test took 3749ms.
[21:26:39.665] <TB2> INFO: Expecting 41600 events.
[21:26:43.155] <TB2> INFO: 41600 events read in total (2933ms).
[21:26:43.155] <TB2> INFO: Test took 3754ms.
[21:26:43.337] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:43.426] <TB2> INFO: Expecting 2560 events.
[21:26:44.279] <TB2> INFO: 2560 events read in total (297ms).
[21:26:44.279] <TB2> INFO: Test took 942ms.
[21:26:44.282] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:44.584] <TB2> INFO: Expecting 2560 events.
[21:26:45.438] <TB2> INFO: 2560 events read in total (297ms).
[21:26:45.438] <TB2> INFO: Test took 1156ms.
[21:26:45.440] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:45.745] <TB2> INFO: Expecting 2560 events.
[21:26:46.598] <TB2> INFO: 2560 events read in total (297ms).
[21:26:46.598] <TB2> INFO: Test took 1158ms.
[21:26:46.600] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:46.904] <TB2> INFO: Expecting 2560 events.
[21:26:47.757] <TB2> INFO: 2560 events read in total (297ms).
[21:26:47.757] <TB2> INFO: Test took 1157ms.
[21:26:47.759] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:48.063] <TB2> INFO: Expecting 2560 events.
[21:26:48.917] <TB2> INFO: 2560 events read in total (298ms).
[21:26:48.917] <TB2> INFO: Test took 1158ms.
[21:26:48.918] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:49.222] <TB2> INFO: Expecting 2560 events.
[21:26:50.082] <TB2> INFO: 2560 events read in total (303ms).
[21:26:50.082] <TB2> INFO: Test took 1164ms.
[21:26:50.084] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:50.388] <TB2> INFO: Expecting 2560 events.
[21:26:51.242] <TB2> INFO: 2560 events read in total (298ms).
[21:26:51.243] <TB2> INFO: Test took 1159ms.
[21:26:51.246] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:51.548] <TB2> INFO: Expecting 2560 events.
[21:26:52.403] <TB2> INFO: 2560 events read in total (299ms).
[21:26:52.403] <TB2> INFO: Test took 1157ms.
[21:26:52.406] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:52.708] <TB2> INFO: Expecting 2560 events.
[21:26:53.563] <TB2> INFO: 2560 events read in total (298ms).
[21:26:53.563] <TB2> INFO: Test took 1157ms.
[21:26:53.566] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:53.870] <TB2> INFO: Expecting 2560 events.
[21:26:54.728] <TB2> INFO: 2560 events read in total (302ms).
[21:26:54.729] <TB2> INFO: Test took 1163ms.
[21:26:54.732] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:55.034] <TB2> INFO: Expecting 2560 events.
[21:26:55.887] <TB2> INFO: 2560 events read in total (297ms).
[21:26:55.887] <TB2> INFO: Test took 1155ms.
[21:26:55.889] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:56.193] <TB2> INFO: Expecting 2560 events.
[21:26:57.047] <TB2> INFO: 2560 events read in total (298ms).
[21:26:57.047] <TB2> INFO: Test took 1158ms.
[21:26:57.048] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:57.352] <TB2> INFO: Expecting 2560 events.
[21:26:58.209] <TB2> INFO: 2560 events read in total (300ms).
[21:26:58.209] <TB2> INFO: Test took 1161ms.
[21:26:58.224] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:58.514] <TB2> INFO: Expecting 2560 events.
[21:26:59.367] <TB2> INFO: 2560 events read in total (296ms).
[21:26:59.368] <TB2> INFO: Test took 1144ms.
[21:26:59.372] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:26:59.673] <TB2> INFO: Expecting 2560 events.
[21:27:00.529] <TB2> INFO: 2560 events read in total (300ms).
[21:27:00.529] <TB2> INFO: Test took 1157ms.
[21:27:00.532] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:00.835] <TB2> INFO: Expecting 2560 events.
[21:27:01.689] <TB2> INFO: 2560 events read in total (298ms).
[21:27:01.689] <TB2> INFO: Test took 1157ms.
[21:27:01.691] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:01.995] <TB2> INFO: Expecting 2560 events.
[21:27:02.854] <TB2> INFO: 2560 events read in total (303ms).
[21:27:02.855] <TB2> INFO: Test took 1164ms.
[21:27:02.857] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:03.161] <TB2> INFO: Expecting 2560 events.
[21:27:04.018] <TB2> INFO: 2560 events read in total (301ms).
[21:27:04.019] <TB2> INFO: Test took 1162ms.
[21:27:04.021] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:04.324] <TB2> INFO: Expecting 2560 events.
[21:27:05.181] <TB2> INFO: 2560 events read in total (301ms).
[21:27:05.181] <TB2> INFO: Test took 1161ms.
[21:27:05.184] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:05.487] <TB2> INFO: Expecting 2560 events.
[21:27:06.345] <TB2> INFO: 2560 events read in total (302ms).
[21:27:06.345] <TB2> INFO: Test took 1161ms.
[21:27:06.347] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:06.651] <TB2> INFO: Expecting 2560 events.
[21:27:07.508] <TB2> INFO: 2560 events read in total (301ms).
[21:27:07.508] <TB2> INFO: Test took 1161ms.
[21:27:07.510] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:07.815] <TB2> INFO: Expecting 2560 events.
[21:27:08.670] <TB2> INFO: 2560 events read in total (299ms).
[21:27:08.670] <TB2> INFO: Test took 1160ms.
[21:27:08.673] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:08.975] <TB2> INFO: Expecting 2560 events.
[21:27:09.828] <TB2> INFO: 2560 events read in total (297ms).
[21:27:09.829] <TB2> INFO: Test took 1156ms.
[21:27:09.831] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:10.135] <TB2> INFO: Expecting 2560 events.
[21:27:10.989] <TB2> INFO: 2560 events read in total (298ms).
[21:27:10.990] <TB2> INFO: Test took 1159ms.
[21:27:10.992] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:11.297] <TB2> INFO: Expecting 2560 events.
[21:27:12.155] <TB2> INFO: 2560 events read in total (301ms).
[21:27:12.155] <TB2> INFO: Test took 1163ms.
[21:27:12.158] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:12.461] <TB2> INFO: Expecting 2560 events.
[21:27:13.317] <TB2> INFO: 2560 events read in total (298ms).
[21:27:13.317] <TB2> INFO: Test took 1159ms.
[21:27:13.332] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:13.622] <TB2> INFO: Expecting 2560 events.
[21:27:14.481] <TB2> INFO: 2560 events read in total (302ms).
[21:27:14.481] <TB2> INFO: Test took 1149ms.
[21:27:14.484] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:14.788] <TB2> INFO: Expecting 2560 events.
[21:27:15.647] <TB2> INFO: 2560 events read in total (303ms).
[21:27:15.647] <TB2> INFO: Test took 1164ms.
[21:27:15.650] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:15.953] <TB2> INFO: Expecting 2560 events.
[21:27:16.810] <TB2> INFO: 2560 events read in total (301ms).
[21:27:16.810] <TB2> INFO: Test took 1161ms.
[21:27:16.815] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:17.116] <TB2> INFO: Expecting 2560 events.
[21:27:17.969] <TB2> INFO: 2560 events read in total (297ms).
[21:27:17.970] <TB2> INFO: Test took 1155ms.
[21:27:17.972] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:18.276] <TB2> INFO: Expecting 2560 events.
[21:27:19.131] <TB2> INFO: 2560 events read in total (299ms).
[21:27:19.131] <TB2> INFO: Test took 1159ms.
[21:27:19.133] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:27:19.438] <TB2> INFO: Expecting 2560 events.
[21:27:20.297] <TB2> INFO: 2560 events read in total (303ms).
[21:27:20.297] <TB2> INFO: Test took 1164ms.
[21:27:20.754] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[21:27:20.754] <TB2> INFO: PH scale (per ROC): 82 80 71 86 74 78 80 87 90 91 82 86 76 83 80 76
[21:27:20.754] <TB2> INFO: PH offset (per ROC): 159 179 163 160 165 176 158 157 149 144 166 164 167 161 158 176
[21:27:20.760] <TB2> INFO: Decoding statistics:
[21:27:20.760] <TB2> INFO: General information:
[21:27:20.760] <TB2> INFO: 16bit words read: 91916
[21:27:20.760] <TB2> INFO: valid events total: 10240
[21:27:20.760] <TB2> INFO: empty events: 7741
[21:27:20.760] <TB2> INFO: valid events with pixels: 2499
[21:27:20.760] <TB2> INFO: valid pixel hits: 2499
[21:27:20.760] <TB2> INFO: Event errors: 0
[21:27:20.760] <TB2> INFO: start marker: 0
[21:27:20.760] <TB2> INFO: stop marker: 0
[21:27:20.760] <TB2> INFO: overflow: 0
[21:27:20.760] <TB2> INFO: invalid 5bit words: 0
[21:27:20.760] <TB2> INFO: invalid XOR eye diagram: 0
[21:27:20.760] <TB2> INFO: frame (failed synchr.): 0
[21:27:20.760] <TB2> INFO: idle data (no TBM trl): 0
[21:27:20.760] <TB2> INFO: no data (only TBM hdr): 0
[21:27:20.760] <TB2> INFO: TBM errors: 0
[21:27:20.760] <TB2> INFO: flawed TBM headers: 0
[21:27:20.760] <TB2> INFO: flawed TBM trailers: 0
[21:27:20.760] <TB2> INFO: event ID mismatches: 0
[21:27:20.760] <TB2> INFO: ROC errors: 0
[21:27:20.760] <TB2> INFO: missing ROC header(s): 0
[21:27:20.760] <TB2> INFO: misplaced readback start: 0
[21:27:20.760] <TB2> INFO: Pixel decoding errors: 0
[21:27:20.760] <TB2> INFO: pixel data incomplete: 0
[21:27:20.760] <TB2> INFO: pixel address: 0
[21:27:20.760] <TB2> INFO: pulse height fill bit: 0
[21:27:20.760] <TB2> INFO: buffer corruption: 0
[21:27:20.943] <TB2> INFO: ######################################################################
[21:27:20.943] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[21:27:20.943] <TB2> INFO: ######################################################################
[21:27:20.954] <TB2> INFO: scanning low vcal = 10
[21:27:21.221] <TB2> INFO: Expecting 41600 events.
[21:27:24.758] <TB2> INFO: 41600 events read in total (2981ms).
[21:27:24.758] <TB2> INFO: Test took 3804ms.
[21:27:24.760] <TB2> INFO: scanning low vcal = 20
[21:27:25.062] <TB2> INFO: Expecting 41600 events.
[21:27:28.616] <TB2> INFO: 41600 events read in total (2997ms).
[21:27:28.617] <TB2> INFO: Test took 3857ms.
[21:27:28.619] <TB2> INFO: scanning low vcal = 30
[21:27:28.922] <TB2> INFO: Expecting 41600 events.
[21:27:32.484] <TB2> INFO: 41600 events read in total (3006ms).
[21:27:32.484] <TB2> INFO: Test took 3865ms.
[21:27:32.486] <TB2> INFO: scanning low vcal = 40
[21:27:32.783] <TB2> INFO: Expecting 41600 events.
[21:27:36.935] <TB2> INFO: 41600 events read in total (3595ms).
[21:27:36.936] <TB2> INFO: Test took 4450ms.
[21:27:36.939] <TB2> INFO: scanning low vcal = 50
[21:27:37.208] <TB2> INFO: Expecting 41600 events.
[21:27:41.432] <TB2> INFO: 41600 events read in total (3668ms).
[21:27:41.432] <TB2> INFO: Test took 4493ms.
[21:27:41.434] <TB2> INFO: scanning low vcal = 60
[21:27:41.694] <TB2> INFO: Expecting 41600 events.
[21:27:45.935] <TB2> INFO: 41600 events read in total (3685ms).
[21:27:45.936] <TB2> INFO: Test took 4501ms.
[21:27:45.938] <TB2> INFO: scanning low vcal = 70
[21:27:46.203] <TB2> INFO: Expecting 41600 events.
[21:27:50.426] <TB2> INFO: 41600 events read in total (3667ms).
[21:27:50.426] <TB2> INFO: Test took 4488ms.
[21:27:50.429] <TB2> INFO: scanning low vcal = 80
[21:27:50.702] <TB2> INFO: Expecting 41600 events.
[21:27:54.911] <TB2> INFO: 41600 events read in total (3653ms).
[21:27:54.912] <TB2> INFO: Test took 4483ms.
[21:27:54.914] <TB2> INFO: scanning low vcal = 90
[21:27:55.188] <TB2> INFO: Expecting 41600 events.
[21:27:59.438] <TB2> INFO: 41600 events read in total (3694ms).
[21:27:59.438] <TB2> INFO: Test took 4524ms.
[21:27:59.441] <TB2> INFO: scanning low vcal = 100
[21:27:59.704] <TB2> INFO: Expecting 41600 events.
[21:28:03.871] <TB2> INFO: 41600 events read in total (3611ms).
[21:28:03.872] <TB2> INFO: Test took 4431ms.
[21:28:03.874] <TB2> INFO: scanning low vcal = 110
[21:28:04.139] <TB2> INFO: Expecting 41600 events.
[21:28:08.340] <TB2> INFO: 41600 events read in total (3645ms).
[21:28:08.340] <TB2> INFO: Test took 4465ms.
[21:28:08.343] <TB2> INFO: scanning low vcal = 120
[21:28:08.614] <TB2> INFO: Expecting 41600 events.
[21:28:12.789] <TB2> INFO: 41600 events read in total (3619ms).
[21:28:12.790] <TB2> INFO: Test took 4447ms.
[21:28:12.792] <TB2> INFO: scanning low vcal = 130
[21:28:13.067] <TB2> INFO: Expecting 41600 events.
[21:28:17.252] <TB2> INFO: 41600 events read in total (3629ms).
[21:28:17.252] <TB2> INFO: Test took 4460ms.
[21:28:17.255] <TB2> INFO: scanning low vcal = 140
[21:28:17.517] <TB2> INFO: Expecting 41600 events.
[21:28:21.696] <TB2> INFO: 41600 events read in total (3623ms).
[21:28:21.697] <TB2> INFO: Test took 4442ms.
[21:28:21.700] <TB2> INFO: scanning low vcal = 150
[21:28:21.962] <TB2> INFO: Expecting 41600 events.
[21:28:26.223] <TB2> INFO: 41600 events read in total (3705ms).
[21:28:26.223] <TB2> INFO: Test took 4523ms.
[21:28:26.226] <TB2> INFO: scanning low vcal = 160
[21:28:26.516] <TB2> INFO: Expecting 41600 events.
[21:28:30.686] <TB2> INFO: 41600 events read in total (3614ms).
[21:28:30.686] <TB2> INFO: Test took 4460ms.
[21:28:30.689] <TB2> INFO: scanning low vcal = 170
[21:28:30.953] <TB2> INFO: Expecting 41600 events.
[21:28:35.164] <TB2> INFO: 41600 events read in total (3655ms).
[21:28:35.165] <TB2> INFO: Test took 4476ms.
[21:28:35.169] <TB2> INFO: scanning low vcal = 180
[21:28:35.434] <TB2> INFO: Expecting 41600 events.
[21:28:39.653] <TB2> INFO: 41600 events read in total (3663ms).
[21:28:39.654] <TB2> INFO: Test took 4485ms.
[21:28:39.657] <TB2> INFO: scanning low vcal = 190
[21:28:39.917] <TB2> INFO: Expecting 41600 events.
[21:28:44.126] <TB2> INFO: 41600 events read in total (3652ms).
[21:28:44.127] <TB2> INFO: Test took 4470ms.
[21:28:44.129] <TB2> INFO: scanning low vcal = 200
[21:28:44.399] <TB2> INFO: Expecting 41600 events.
[21:28:48.603] <TB2> INFO: 41600 events read in total (3648ms).
[21:28:48.603] <TB2> INFO: Test took 4474ms.
[21:28:48.606] <TB2> INFO: scanning low vcal = 210
[21:28:48.895] <TB2> INFO: Expecting 41600 events.
[21:28:53.092] <TB2> INFO: 41600 events read in total (3641ms).
[21:28:53.092] <TB2> INFO: Test took 4486ms.
[21:28:53.095] <TB2> INFO: scanning low vcal = 220
[21:28:53.358] <TB2> INFO: Expecting 41600 events.
[21:28:57.551] <TB2> INFO: 41600 events read in total (3637ms).
[21:28:57.552] <TB2> INFO: Test took 4457ms.
[21:28:57.554] <TB2> INFO: scanning low vcal = 230
[21:28:57.817] <TB2> INFO: Expecting 41600 events.
[21:29:02.021] <TB2> INFO: 41600 events read in total (3648ms).
[21:29:02.021] <TB2> INFO: Test took 4467ms.
[21:29:02.024] <TB2> INFO: scanning low vcal = 240
[21:29:02.288] <TB2> INFO: Expecting 41600 events.
[21:29:06.492] <TB2> INFO: 41600 events read in total (3648ms).
[21:29:06.492] <TB2> INFO: Test took 4468ms.
[21:29:06.495] <TB2> INFO: scanning low vcal = 250
[21:29:06.759] <TB2> INFO: Expecting 41600 events.
[21:29:10.983] <TB2> INFO: 41600 events read in total (3668ms).
[21:29:10.984] <TB2> INFO: Test took 4489ms.
[21:29:10.988] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[21:29:11.280] <TB2> INFO: Expecting 41600 events.
[21:29:15.492] <TB2> INFO: 41600 events read in total (3656ms).
[21:29:15.492] <TB2> INFO: Test took 4504ms.
[21:29:15.495] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[21:29:15.783] <TB2> INFO: Expecting 41600 events.
[21:29:19.985] <TB2> INFO: 41600 events read in total (3646ms).
[21:29:19.985] <TB2> INFO: Test took 4490ms.
[21:29:19.988] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[21:29:20.249] <TB2> INFO: Expecting 41600 events.
[21:29:24.481] <TB2> INFO: 41600 events read in total (3675ms).
[21:29:24.482] <TB2> INFO: Test took 4494ms.
[21:29:24.485] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[21:29:24.763] <TB2> INFO: Expecting 41600 events.
[21:29:28.989] <TB2> INFO: 41600 events read in total (3670ms).
[21:29:28.990] <TB2> INFO: Test took 4505ms.
[21:29:28.993] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[21:29:29.271] <TB2> INFO: Expecting 41600 events.
[21:29:33.502] <TB2> INFO: 41600 events read in total (3675ms).
[21:29:33.503] <TB2> INFO: Test took 4510ms.
[21:29:34.094] <TB2> INFO: PixTestGainPedestal::measure() done
[21:30:07.892] <TB2> INFO: PixTestGainPedestal::fit() done
[21:30:07.892] <TB2> INFO: non-linearity mean: 0.955 0.958 0.958 0.960 0.961 0.962 0.955 0.956 0.949 0.962 0.958 0.957 0.958 0.956 0.958 0.960
[21:30:07.892] <TB2> INFO: non-linearity RMS: 0.005 0.007 0.006 0.005 0.005 0.004 0.007 0.005 0.007 0.005 0.006 0.006 0.005 0.006 0.005 0.005
[21:30:07.892] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[21:30:07.911] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[21:30:07.930] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[21:30:07.951] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[21:30:07.970] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[21:30:07.989] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[21:30:08.007] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[21:30:08.033] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[21:30:08.051] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[21:30:08.071] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[21:30:08.090] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[21:30:08.115] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[21:30:08.137] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[21:30:08.155] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[21:30:08.175] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[21:30:08.194] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[21:30:08.214] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[21:30:08.214] <TB2> INFO: Decoding statistics:
[21:30:08.214] <TB2> INFO: General information:
[21:30:08.214] <TB2> INFO: 16bit words read: 2662400
[21:30:08.214] <TB2> INFO: valid events total: 166400
[21:30:08.214] <TB2> INFO: empty events: 0
[21:30:08.214] <TB2> INFO: valid events with pixels: 166400
[21:30:08.214] <TB2> INFO: valid pixel hits: 665540
[21:30:08.214] <TB2> INFO: Event errors: 0
[21:30:08.214] <TB2> INFO: start marker: 0
[21:30:08.214] <TB2> INFO: stop marker: 0
[21:30:08.214] <TB2> INFO: overflow: 0
[21:30:08.214] <TB2> INFO: invalid 5bit words: 0
[21:30:08.214] <TB2> INFO: invalid XOR eye diagram: 0
[21:30:08.214] <TB2> INFO: frame (failed synchr.): 0
[21:30:08.214] <TB2> INFO: idle data (no TBM trl): 0
[21:30:08.214] <TB2> INFO: no data (only TBM hdr): 0
[21:30:08.214] <TB2> INFO: TBM errors: 0
[21:30:08.214] <TB2> INFO: flawed TBM headers: 0
[21:30:08.214] <TB2> INFO: flawed TBM trailers: 0
[21:30:08.214] <TB2> INFO: event ID mismatches: 0
[21:30:08.214] <TB2> INFO: ROC errors: 0
[21:30:08.214] <TB2> INFO: missing ROC header(s): 0
[21:30:08.214] <TB2> INFO: misplaced readback start: 0
[21:30:08.214] <TB2> INFO: Pixel decoding errors: 0
[21:30:08.214] <TB2> INFO: pixel data incomplete: 0
[21:30:08.214] <TB2> INFO: pixel address: 0
[21:30:08.214] <TB2> INFO: pulse height fill bit: 0
[21:30:08.214] <TB2> INFO: buffer corruption: 0
[21:30:08.222] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C15.dat
[21:30:08.223] <TB2> INFO: ######################################################################
[21:30:08.223] <TB2> INFO: PixTestReadback::doTest()
[21:30:08.223] <TB2> INFO: ######################################################################
[21:30:08.223] <TB2> INFO: ----------------------------------------------------------------------
[21:30:08.223] <TB2> INFO: PixTestReadback::CalibrateVd()
[21:30:08.223] <TB2> INFO: ----------------------------------------------------------------------
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C0.dat
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C1.dat
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C2.dat
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C3.dat
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C4.dat
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C5.dat
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C6.dat
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C7.dat
[21:30:17.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C8.dat
[21:30:17.682] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C9.dat
[21:30:17.682] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C10.dat
[21:30:17.682] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C11.dat
[21:30:17.682] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C12.dat
[21:30:17.682] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C13.dat
[21:30:17.682] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C14.dat
[21:30:17.682] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C15.dat
[21:30:17.711] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[21:30:17.711] <TB2> INFO: ----------------------------------------------------------------------
[21:30:17.711] <TB2> INFO: PixTestReadback::CalibrateVa()
[21:30:17.711] <TB2> INFO: ----------------------------------------------------------------------
[21:30:27.180] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C0.dat
[21:30:27.181] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C1.dat
[21:30:27.181] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C2.dat
[21:30:27.181] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C3.dat
[21:30:27.181] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C4.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C5.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C6.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C7.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C8.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C9.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C10.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C11.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C12.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C13.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C14.dat
[21:30:27.182] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C15.dat
[21:30:27.203] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[21:30:27.203] <TB2> INFO: ----------------------------------------------------------------------
[21:30:27.203] <TB2> INFO: PixTestReadback::readbackVbg()
[21:30:27.203] <TB2> INFO: ----------------------------------------------------------------------
[21:30:34.515] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[21:30:34.515] <TB2> INFO: ----------------------------------------------------------------------
[21:30:34.515] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[21:30:34.515] <TB2> INFO: ----------------------------------------------------------------------
[21:30:34.515] <TB2> INFO: Vbg will be calibrated using Vd calibration
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146.5calibrated Vbg = 1.18016 :::*/*/*/*/
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.9calibrated Vbg = 1.18274 :::*/*/*/*/
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.5calibrated Vbg = 1.19118 :::*/*/*/*/
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 158.5calibrated Vbg = 1.18672 :::*/*/*/*/
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 160.8calibrated Vbg = 1.19762 :::*/*/*/*/
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 160.9calibrated Vbg = 1.1995 :::*/*/*/*/
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 161.9calibrated Vbg = 1.20089 :::*/*/*/*/
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 140.9calibrated Vbg = 1.20339 :::*/*/*/*/
[21:30:34.515] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 141.5calibrated Vbg = 1.20266 :::*/*/*/*/
[21:30:34.516] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 147.9calibrated Vbg = 1.20448 :::*/*/*/*/
[21:30:34.516] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.6calibrated Vbg = 1.20121 :::*/*/*/*/
[21:30:34.516] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 162.2calibrated Vbg = 1.19622 :::*/*/*/*/
[21:30:34.516] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.1calibrated Vbg = 1.19509 :::*/*/*/*/
[21:30:34.516] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151.4calibrated Vbg = 1.1877 :::*/*/*/*/
[21:30:34.516] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.7calibrated Vbg = 1.19023 :::*/*/*/*/
[21:30:34.516] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.6calibrated Vbg = 1.19692 :::*/*/*/*/
[21:30:34.519] <TB2> INFO: ----------------------------------------------------------------------
[21:30:34.519] <TB2> INFO: PixTestReadback::CalibrateIa()
[21:30:34.519] <TB2> INFO: ----------------------------------------------------------------------
[21:33:09.972] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C0.dat
[21:33:09.972] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C1.dat
[21:33:09.972] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C2.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C3.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C4.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C5.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C6.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C7.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C8.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C9.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C10.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C11.dat
[21:33:09.973] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C12.dat
[21:33:09.974] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C13.dat
[21:33:09.974] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C14.dat
[21:33:09.974] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2220_FullQualification_2016-05-03_21h58m_1462305520//000_FulltestPxar_m20//readbackCal_C15.dat
[21:33:10.003] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[21:33:10.005] <TB2> INFO: PixTestReadback::doTest() done
[21:33:10.005] <TB2> INFO: Decoding statistics:
[21:33:10.005] <TB2> INFO: General information:
[21:33:10.005] <TB2> INFO: 16bit words read: 1024
[21:33:10.005] <TB2> INFO: valid events total: 128
[21:33:10.005] <TB2> INFO: empty events: 128
[21:33:10.005] <TB2> INFO: valid events with pixels: 0
[21:33:10.005] <TB2> INFO: valid pixel hits: 0
[21:33:10.005] <TB2> INFO: Event errors: 0
[21:33:10.005] <TB2> INFO: start marker: 0
[21:33:10.005] <TB2> INFO: stop marker: 0
[21:33:10.005] <TB2> INFO: overflow: 0
[21:33:10.005] <TB2> INFO: invalid 5bit words: 0
[21:33:10.005] <TB2> INFO: invalid XOR eye diagram: 0
[21:33:10.005] <TB2> INFO: frame (failed synchr.): 0
[21:33:10.005] <TB2> INFO: idle data (no TBM trl): 0
[21:33:10.005] <TB2> INFO: no data (only TBM hdr): 0
[21:33:10.005] <TB2> INFO: TBM errors: 0
[21:33:10.005] <TB2> INFO: flawed TBM headers: 0
[21:33:10.005] <TB2> INFO: flawed TBM trailers: 0
[21:33:10.005] <TB2> INFO: event ID mismatches: 0
[21:33:10.005] <TB2> INFO: ROC errors: 0
[21:33:10.006] <TB2> INFO: missing ROC header(s): 0
[21:33:10.006] <TB2> INFO: misplaced readback start: 0
[21:33:10.006] <TB2> INFO: Pixel decoding errors: 0
[21:33:10.006] <TB2> INFO: pixel data incomplete: 0
[21:33:10.006] <TB2> INFO: pixel address: 0
[21:33:10.006] <TB2> INFO: pulse height fill bit: 0
[21:33:10.006] <TB2> INFO: buffer corruption: 0
[21:33:10.028] <TB2> INFO: Decoding statistics:
[21:33:10.028] <TB2> INFO: General information:
[21:33:10.028] <TB2> INFO: 16bit words read: 2755340
[21:33:10.028] <TB2> INFO: valid events total: 176768
[21:33:10.028] <TB2> INFO: empty events: 7869
[21:33:10.028] <TB2> INFO: valid events with pixels: 168899
[21:33:10.028] <TB2> INFO: valid pixel hits: 668039
[21:33:10.028] <TB2> INFO: Event errors: 0
[21:33:10.028] <TB2> INFO: start marker: 0
[21:33:10.028] <TB2> INFO: stop marker: 0
[21:33:10.028] <TB2> INFO: overflow: 0
[21:33:10.029] <TB2> INFO: invalid 5bit words: 0
[21:33:10.029] <TB2> INFO: invalid XOR eye diagram: 0
[21:33:10.029] <TB2> INFO: frame (failed synchr.): 0
[21:33:10.029] <TB2> INFO: idle data (no TBM trl): 0
[21:33:10.029] <TB2> INFO: no data (only TBM hdr): 0
[21:33:10.029] <TB2> INFO: TBM errors: 0
[21:33:10.029] <TB2> INFO: flawed TBM headers: 0
[21:33:10.029] <TB2> INFO: flawed TBM trailers: 0
[21:33:10.029] <TB2> INFO: event ID mismatches: 0
[21:33:10.029] <TB2> INFO: ROC errors: 0
[21:33:10.029] <TB2> INFO: missing ROC header(s): 0
[21:33:10.029] <TB2> INFO: misplaced readback start: 0
[21:33:10.029] <TB2> INFO: Pixel decoding errors: 0
[21:33:10.029] <TB2> INFO: pixel data incomplete: 0
[21:33:10.029] <TB2> INFO: pixel address: 0
[21:33:10.029] <TB2> INFO: pulse height fill bit: 0
[21:33:10.029] <TB2> INFO: buffer corruption: 0
[21:33:10.029] <TB2> INFO: enter test to run
[21:33:10.029] <TB2> INFO: test: exit no parameter change
[21:33:10.205] <TB2> QUIET: Connection to board 156 closed.
[21:33:10.287] <TB2> INFO: pXar: this is the end, my friend