[12:34:57.825] <TB1> INFO: *** Welcome to pxar ***
[12:34:57.825] <TB1> INFO: *** Today: 2015/12/08
[12:34:58.162] <TB1> INFO: *** Version: 3197
[12:34:58.162] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C15.dat
[12:34:58.164] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//tbmParameters_C0b.dat
[12:34:58.164] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//defaultMaskFile.dat
[12:34:58.164] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters_C15.dat
[12:34:58.230] <TB1> INFO: clk: 4
[12:34:58.230] <TB1> INFO: ctr: 4
[12:34:58.230] <TB1> INFO: sda: 19
[12:34:58.230] <TB1> INFO: tin: 9
[12:34:58.230] <TB1> INFO: level: 15
[12:34:58.230] <TB1> INFO: triggerdelay: 0
[12:34:58.230] <TB1> QUIET: Instanciating API for pxar prod-12
[12:34:58.230] <TB1> INFO: Log level: INFO
[12:34:58.237] <TB1> INFO: Found DTB DTB_WXBYFL
[12:34:58.251] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[12:34:58.254] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[12:34:58.257] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[12:34:59.813] <TB1> INFO: DUT info:
[12:34:59.813] <TB1> INFO: The DUT currently contains the following objects:
[12:34:59.813] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[12:34:59.813] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:34:59.813] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:34:59.813] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:34:59.813] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.813] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:59.814] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:00.215] <TB1> INFO: enter 'restricted' command line mode
[12:35:00.215] <TB1> INFO: enter test to run
[12:35:00.215] <TB1> INFO: test: pretest no parameter change
[12:35:00.215] <TB1> INFO: running: pretest
[12:35:00.226] <TB1> INFO: ######################################################################
[12:35:00.226] <TB1> INFO: PixTestPretest::doTest()
[12:35:00.226] <TB1> INFO: ######################################################################
[12:35:00.228] <TB1> INFO: ----------------------------------------------------------------------
[12:35:00.228] <TB1> INFO: PixTestPretest::programROC()
[12:35:00.228] <TB1> INFO: ----------------------------------------------------------------------
[12:35:18.251] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:35:18.251] <TB1> INFO: IA differences per ROC: 17.7 18.5 20.9 18.5 19.3 17.7 20.1 19.3 17.7 18.5 19.3 17.7 22.5 20.9 20.9 19.3
[12:35:18.350] <TB1> INFO: ----------------------------------------------------------------------
[12:35:18.350] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:35:18.350] <TB1> INFO: ----------------------------------------------------------------------
[12:35:24.570] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 381.9 mA = 23.8688 mA/ROC
[12:35:24.570] <TB1> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5
[12:35:24.618] <TB1> INFO: ----------------------------------------------------------------------
[12:35:24.618] <TB1> INFO: PixTestPretest::findTiming()
[12:35:24.618] <TB1> INFO: ----------------------------------------------------------------------
[12:35:24.618] <TB1> INFO: PixTestCmd::init()

[12:37:00.537] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[12:37:00.537] <TB1> INFO: (success/tries = 100/100), width = 5
[12:37:00.539] <TB1> INFO: ----------------------------------------------------------------------
[12:37:00.539] <TB1> INFO: PixTestPretest::findWorkingPixel()
[12:37:00.539] <TB1> INFO: ----------------------------------------------------------------------
[12:37:00.677] <TB1> INFO: Expecting 231680 events.


[12:37:08.281] <TB1> INFO: 231680 events read in total (6889ms).
[12:37:08.284] <TB1> INFO: Test took 7743ms.
[12:37:08.703] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:37:08.752] <TB1> INFO: ----------------------------------------------------------------------
[12:37:08.752] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[12:37:08.752] <TB1> INFO: ----------------------------------------------------------------------
[12:37:08.893] <TB1> INFO: Expecting 231680 events.
[12:37:17.423] <TB1> INFO: 231680 events read in total (7815ms).
[12:37:17.427] <TB1> INFO: Test took 8668ms.
[12:37:17.870] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[12:37:17.870] <TB1> INFO: CalDel: 113 110 141 135 144 124 134 114 139 125 117 135 144 149 149 125
[12:37:17.870] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:37:17.874] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C0.dat
[12:37:17.874] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C1.dat
[12:37:17.874] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C2.dat
[12:37:17.874] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C3.dat
[12:37:17.875] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C4.dat
[12:37:17.875] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C5.dat
[12:37:17.875] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C6.dat
[12:37:17.875] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C7.dat
[12:37:17.876] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C8.dat
[12:37:17.876] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C9.dat
[12:37:17.876] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C10.dat
[12:37:17.876] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C11.dat
[12:37:17.876] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C12.dat
[12:37:17.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C13.dat
[12:37:17.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C14.dat
[12:37:17.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C15.dat
[12:37:17.877] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//tbmParameters_C0a.dat
[12:37:17.878] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//tbmParameters_C0b.dat
[12:37:17.878] <TB1> INFO: PixTestPretest::doTest() done, duration: 137 seconds
[12:37:17.947] <TB1> INFO: enter test to run
[12:37:17.947] <TB1> INFO: test: fulltest no parameter change
[12:37:17.947] <TB1> INFO: running: fulltest
[12:37:17.948] <TB1> INFO: ######################################################################
[12:37:17.948] <TB1> INFO: PixTestFullTest::doTest()
[12:37:17.948] <TB1> INFO: ######################################################################
[12:37:17.949] <TB1> INFO: ######################################################################
[12:37:17.949] <TB1> INFO: PixTestAlive::doTest()
[12:37:17.949] <TB1> INFO: ######################################################################
[12:37:17.951] <TB1> INFO: ----------------------------------------------------------------------
[12:37:17.951] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:37:17.951] <TB1> INFO: ----------------------------------------------------------------------
[12:37:18.276] <TB1> INFO: Expecting 41600 events.
[12:37:22.707] <TB1> INFO: 41600 events read in total (3715ms).
[12:37:22.708] <TB1> INFO: Test took 4754ms.
[12:37:22.716] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:23.107] <TB1> INFO: PixTestAlive::aliveTest() done
[12:37:23.108] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:37:23.110] <TB1> INFO: ----------------------------------------------------------------------
[12:37:23.110] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:37:23.110] <TB1> INFO: ----------------------------------------------------------------------
[12:37:23.429] <TB1> INFO: Expecting 41600 events.
[12:37:26.465] <TB1> INFO: 41600 events read in total (2321ms).
[12:37:26.465] <TB1> INFO: Test took 3353ms.
[12:37:26.465] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:26.466] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:37:26.887] <TB1> INFO: PixTestAlive::maskTest() done
[12:37:26.887] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:37:26.888] <TB1> INFO: ----------------------------------------------------------------------
[12:37:26.888] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:37:26.888] <TB1> INFO: ----------------------------------------------------------------------
[12:37:27.215] <TB1> INFO: Expecting 41600 events.
[12:37:31.633] <TB1> INFO: 41600 events read in total (3703ms).
[12:37:31.633] <TB1> INFO: Test took 4743ms.
[12:37:31.641] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:32.036] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:37:32.036] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:37:32.037] <TB1> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:37:32.037] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:32.037] <TB1> INFO: Decoding statistics:
[12:37:32.037] <TB1> INFO: General information:
[12:37:32.037] <TB1> INFO: 16bit words read: 0
[12:37:32.037] <TB1> INFO: valid events total: 0
[12:37:32.037] <TB1> INFO: empty events: 0
[12:37:32.037] <TB1> INFO: valid events with pixels: 0
[12:37:32.037] <TB1> INFO: valid pixel hits: 0
[12:37:32.037] <TB1> INFO: Event errors: 0
[12:37:32.037] <TB1> INFO: start marker: 0
[12:37:32.037] <TB1> INFO: stop marker: 0
[12:37:32.037] <TB1> INFO: overflow: 0
[12:37:32.037] <TB1> INFO: invalid 5bit words: 0
[12:37:32.037] <TB1> INFO: invalid XOR eye diagram: 0
[12:37:32.037] <TB1> INFO: TBM errors: 0
[12:37:32.037] <TB1> INFO: flawed TBM headers: 0
[12:37:32.038] <TB1> INFO: flawed TBM trailers: 0
[12:37:32.038] <TB1> INFO: event ID mismatches: 0
[12:37:32.038] <TB1> INFO: ROC errors: 0
[12:37:32.038] <TB1> INFO: missing ROC header(s): 0
[12:37:32.038] <TB1> INFO: misplaced readback start: 0
[12:37:32.038] <TB1> INFO: Pixel decoding errors: 0
[12:37:32.038] <TB1> INFO: pixel data incomplete: 0
[12:37:32.038] <TB1> INFO: pixel address: 0
[12:37:32.039] <TB1> INFO: pulse height fill bit: 0
[12:37:32.039] <TB1> INFO: buffer corruption: 0
[12:37:32.050] <TB1> INFO: ######################################################################
[12:37:32.051] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:37:32.051] <TB1> INFO: ######################################################################
[12:37:32.055] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:37:32.066] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:37:32.066] <TB1> INFO: run 1 of 1
[12:37:32.401] <TB1> INFO: Expecting 3120000 events.
[12:38:22.743] <TB1> INFO: 1190405 events read in total (49627ms).
[12:39:14.538] <TB1> INFO: 2355010 events read in total (101422ms).
[12:39:47.804] <TB1> INFO: 3120000 events read in total (134688ms).
[12:39:47.857] <TB1> INFO: Test took 135792ms.
[12:39:47.945] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:13.486] <TB1> INFO: PixTestBBMap::doTest() done, duration: 161 seconds
[12:40:13.486] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 2 0 0 1 0 0 0 0 0 0 0 0
[12:40:13.486] <TB1> INFO: separation cut (per ROC): 128 116 123 116 113 118 112 119 108 107 112 103 118 108 104 102
[12:40:13.486] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:13.486] <TB1> INFO: Decoding statistics:
[12:40:13.486] <TB1> INFO: General information:
[12:40:13.486] <TB1> INFO: 16bit words read: 0
[12:40:13.486] <TB1> INFO: valid events total: 0
[12:40:13.486] <TB1> INFO: empty events: 0
[12:40:13.486] <TB1> INFO: valid events with pixels: 0
[12:40:13.486] <TB1> INFO: valid pixel hits: 0
[12:40:13.486] <TB1> INFO: Event errors: 0
[12:40:13.486] <TB1> INFO: start marker: 0
[12:40:13.486] <TB1> INFO: stop marker: 0
[12:40:13.486] <TB1> INFO: overflow: 0
[12:40:13.486] <TB1> INFO: invalid 5bit words: 0
[12:40:13.486] <TB1> INFO: invalid XOR eye diagram: 0
[12:40:13.486] <TB1> INFO: TBM errors: 0
[12:40:13.486] <TB1> INFO: flawed TBM headers: 0
[12:40:13.486] <TB1> INFO: flawed TBM trailers: 0
[12:40:13.486] <TB1> INFO: event ID mismatches: 0
[12:40:13.486] <TB1> INFO: ROC errors: 0
[12:40:13.486] <TB1> INFO: missing ROC header(s): 0
[12:40:13.486] <TB1> INFO: misplaced readback start: 0
[12:40:13.486] <TB1> INFO: Pixel decoding errors: 0
[12:40:13.486] <TB1> INFO: pixel data incomplete: 0
[12:40:13.486] <TB1> INFO: pixel address: 0
[12:40:13.486] <TB1> INFO: pulse height fill bit: 0
[12:40:13.486] <TB1> INFO: buffer corruption: 0
[12:40:13.588] <TB1> INFO: ######################################################################
[12:40:13.588] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:40:13.588] <TB1> INFO: ######################################################################
[12:40:13.589] <TB1> INFO: ----------------------------------------------------------------------
[12:40:13.589] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:40:13.589] <TB1> INFO: ----------------------------------------------------------------------
[12:40:13.589] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:40:13.600] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:40:13.600] <TB1> INFO: run 1 of 1
[12:40:13.982] <TB1> INFO: Expecting 26208000 events.
[12:40:44.567] <TB1> INFO: 1088800 events read in total (29868ms).
[12:41:16.476] <TB1> INFO: 2160150 events read in total (61777ms).
[12:41:48.516] <TB1> INFO: 3230350 events read in total (93817ms).
[12:42:20.132] <TB1> INFO: 4296950 events read in total (125433ms).
[12:42:52.435] <TB1> INFO: 5362450 events read in total (157736ms).
[12:43:24.989] <TB1> INFO: 6429650 events read in total (190290ms).
[12:43:56.815] <TB1> INFO: 7490550 events read in total (222116ms).
[12:44:28.880] <TB1> INFO: 8553750 events read in total (254181ms).
[12:45:00.364] <TB1> INFO: 9617050 events read in total (285665ms).
[12:45:32.517] <TB1> INFO: 10675250 events read in total (317818ms).
[12:46:04.403] <TB1> INFO: 11732300 events read in total (349704ms).
[12:46:36.355] <TB1> INFO: 12788800 events read in total (381656ms).
[12:47:07.835] <TB1> INFO: 13834950 events read in total (413136ms).
[12:47:39.894] <TB1> INFO: 14877400 events read in total (445195ms).
[12:48:11.103] <TB1> INFO: 15917650 events read in total (476404ms).
[12:48:42.942] <TB1> INFO: 16954450 events read in total (508243ms).
[12:49:14.105] <TB1> INFO: 17991450 events read in total (539406ms).
[12:49:45.807] <TB1> INFO: 19025350 events read in total (571108ms).
[12:50:17.266] <TB1> INFO: 20059700 events read in total (602567ms).
[12:50:48.889] <TB1> INFO: 21094550 events read in total (634190ms).
[12:51:20.302] <TB1> INFO: 22129700 events read in total (665603ms).
[12:51:51.626] <TB1> INFO: 23163800 events read in total (696927ms).
[12:52:23.284] <TB1> INFO: 24201200 events read in total (728585ms).
[12:52:54.908] <TB1> INFO: 25238800 events read in total (760209ms).
[12:53:21.544] <TB1> INFO: 26208000 events read in total (786845ms).
[12:53:21.580] <TB1> INFO: Test took 787980ms.
[12:53:21.664] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:21.856] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:23.481] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:25.125] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:26.730] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:28.118] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:29.552] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:30.953] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:32.400] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:33.942] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:35.346] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:36.803] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:38.254] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:39.703] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:41.092] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:42.745] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:44.271] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:53:45.950] <TB1> INFO: PixTestScurves::scurves() done
[12:53:45.950] <TB1> INFO: Vcal mean: 93.04 91.02 88.72 92.29 81.08 90.35 74.65 98.31 82.03 86.75 89.22 80.70 98.15 86.29 83.85 76.33
[12:53:45.950] <TB1> INFO: Vcal RMS: 5.38 5.53 5.18 5.48 4.37 5.30 4.53 5.55 5.00 5.63 5.45 4.44 5.38 5.07 5.44 4.60
[12:53:45.950] <TB1> INFO: PixTestScurves::fullTest() done, duration: 812 seconds
[12:53:45.950] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:45.950] <TB1> INFO: Decoding statistics:
[12:53:45.950] <TB1> INFO: General information:
[12:53:45.950] <TB1> INFO: 16bit words read: 0
[12:53:45.950] <TB1> INFO: valid events total: 0
[12:53:45.950] <TB1> INFO: empty events: 0
[12:53:45.950] <TB1> INFO: valid events with pixels: 0
[12:53:45.950] <TB1> INFO: valid pixel hits: 0
[12:53:45.950] <TB1> INFO: Event errors: 0
[12:53:45.950] <TB1> INFO: start marker: 0
[12:53:45.951] <TB1> INFO: stop marker: 0
[12:53:45.951] <TB1> INFO: overflow: 0
[12:53:45.951] <TB1> INFO: invalid 5bit words: 0
[12:53:45.951] <TB1> INFO: invalid XOR eye diagram: 0
[12:53:45.951] <TB1> INFO: TBM errors: 0
[12:53:45.951] <TB1> INFO: flawed TBM headers: 0
[12:53:45.951] <TB1> INFO: flawed TBM trailers: 0
[12:53:45.951] <TB1> INFO: event ID mismatches: 0
[12:53:45.951] <TB1> INFO: ROC errors: 0
[12:53:45.951] <TB1> INFO: missing ROC header(s): 0
[12:53:45.951] <TB1> INFO: misplaced readback start: 0
[12:53:45.951] <TB1> INFO: Pixel decoding errors: 0
[12:53:45.951] <TB1> INFO: pixel data incomplete: 0
[12:53:45.951] <TB1> INFO: pixel address: 0
[12:53:45.951] <TB1> INFO: pulse height fill bit: 0
[12:53:45.951] <TB1> INFO: buffer corruption: 0
[12:53:46.035] <TB1> INFO: ######################################################################
[12:53:46.035] <TB1> INFO: PixTestTrim::doTest()
[12:53:46.035] <TB1> INFO: ######################################################################
[12:53:46.036] <TB1> INFO: ----------------------------------------------------------------------
[12:53:46.036] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:53:46.036] <TB1> INFO: ----------------------------------------------------------------------
[12:53:46.131] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:53:46.131] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:53:46.140] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:53:46.140] <TB1> INFO: run 1 of 1
[12:53:46.457] <TB1> INFO: Expecting 6281600 events.
[12:54:27.589] <TB1> INFO: 1409710 events read in total (40408ms).
[12:55:13.940] <TB1> INFO: 2805160 events read in total (86760ms).
[12:56:00.447] <TB1> INFO: 4204740 events read in total (133267ms).
[12:56:45.345] <TB1> INFO: 5612810 events read in total (178164ms).
[12:57:05.844] <TB1> INFO: 6281600 events read in total (198663ms).
[12:57:05.887] <TB1> INFO: Test took 199747ms.
[12:57:05.946] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:25.714] <TB1> INFO: ROC 0 VthrComp = 98
[12:57:25.714] <TB1> INFO: ROC 1 VthrComp = 95
[12:57:25.714] <TB1> INFO: ROC 2 VthrComp = 95
[12:57:25.714] <TB1> INFO: ROC 3 VthrComp = 93
[12:57:25.715] <TB1> INFO: ROC 4 VthrComp = 86
[12:57:25.715] <TB1> INFO: ROC 5 VthrComp = 97
[12:57:25.715] <TB1> INFO: ROC 6 VthrComp = 81
[12:57:25.715] <TB1> INFO: ROC 7 VthrComp = 101
[12:57:25.715] <TB1> INFO: ROC 8 VthrComp = 85
[12:57:25.715] <TB1> INFO: ROC 9 VthrComp = 88
[12:57:25.716] <TB1> INFO: ROC 10 VthrComp = 91
[12:57:25.716] <TB1> INFO: ROC 11 VthrComp = 85
[12:57:25.716] <TB1> INFO: ROC 12 VthrComp = 101
[12:57:25.716] <TB1> INFO: ROC 13 VthrComp = 91
[12:57:25.716] <TB1> INFO: ROC 14 VthrComp = 87
[12:57:25.716] <TB1> INFO: ROC 15 VthrComp = 80
[12:57:25.716] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:57:25.716] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:57:25.727] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:57:25.727] <TB1> INFO: run 1 of 1
[12:57:26.061] <TB1> INFO: Expecting 6281600 events.
[12:57:59.552] <TB1> INFO: 884410 events read in total (32776ms).
[12:58:35.151] <TB1> INFO: 1766320 events read in total (68375ms).
[12:59:11.540] <TB1> INFO: 2649640 events read in total (104764ms).
[12:59:48.567] <TB1> INFO: 3530810 events read in total (141791ms).
[13:00:25.609] <TB1> INFO: 4405150 events read in total (178833ms).
[13:01:01.927] <TB1> INFO: 5277090 events read in total (215151ms).
[13:01:34.443] <TB1> INFO: 6149090 events read in total (247667ms).
[13:01:39.608] <TB1> INFO: 6281600 events read in total (252832ms).
[13:01:39.679] <TB1> INFO: Test took 253952ms.
[13:01:39.845] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:04.077] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 58.4054 for pixel 23/7 mean/min/max = 44.9369/31.4643/58.4096
[13:02:04.077] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.4666 for pixel 20/79 mean/min/max = 45.2469/31.9968/58.4969
[13:02:04.078] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.9086 for pixel 20/0 mean/min/max = 44.7765/31.5912/57.9618
[13:02:04.078] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 58.4287 for pixel 13/79 mean/min/max = 45.6397/32.5797/58.6998
[13:02:04.079] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.2092 for pixel 49/76 mean/min/max = 44.8782/32.5347/57.2218
[13:02:04.079] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.5673 for pixel 17/66 mean/min/max = 44.7501/31.9027/57.5975
[13:02:04.079] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.1227 for pixel 0/1 mean/min/max = 44.8949/32.6143/57.1754
[13:02:04.080] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.7448 for pixel 23/28 mean/min/max = 45.018/32.0006/58.0354
[13:02:04.080] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.9118 for pixel 3/67 mean/min/max = 44.999/31.8454/58.1526
[13:02:04.081] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 60.7356 for pixel 26/61 mean/min/max = 46.6349/32.4654/60.8044
[13:02:04.081] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 59.5465 for pixel 51/28 mean/min/max = 46.2332/32.7787/59.6877
[13:02:04.081] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.0563 for pixel 9/8 mean/min/max = 44.6408/32.2229/57.0588
[13:02:04.082] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 59.0078 for pixel 22/79 mean/min/max = 45.4055/31.5993/59.2116
[13:02:04.082] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.4481 for pixel 0/5 mean/min/max = 45.0365/32.4847/57.5882
[13:02:04.083] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 58.8705 for pixel 0/0 mean/min/max = 45.3101/31.5181/59.102
[13:02:04.083] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.6739 for pixel 24/75 mean/min/max = 45.4175/32.9815/57.8535
[13:02:04.083] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:02:04.217] <TB1> INFO: Expecting 514560 events.
[13:02:14.166] <TB1> INFO: 514560 events read in total (9233ms).
[13:02:14.173] <TB1> INFO: Expecting 514560 events.
[13:02:23.703] <TB1> INFO: 514560 events read in total (8875ms).
[13:02:23.709] <TB1> INFO: Expecting 514560 events.
[13:02:32.671] <TB1> INFO: 514560 events read in total (8288ms).
[13:02:32.682] <TB1> INFO: Expecting 514560 events.
[13:02:41.696] <TB1> INFO: 514560 events read in total (8357ms).
[13:02:41.708] <TB1> INFO: Expecting 514560 events.
[13:02:50.761] <TB1> INFO: 514560 events read in total (8390ms).
[13:02:50.774] <TB1> INFO: Expecting 514560 events.
[13:02:59.820] <TB1> INFO: 514560 events read in total (8383ms).
[13:02:59.840] <TB1> INFO: Expecting 514560 events.
[13:03:08.783] <TB1> INFO: 514560 events read in total (8299ms).
[13:03:08.803] <TB1> INFO: Expecting 514560 events.
[13:03:18.580] <TB1> INFO: 514560 events read in total (9129ms).
[13:03:18.604] <TB1> INFO: Expecting 514560 events.
[13:03:28.335] <TB1> INFO: 514560 events read in total (9080ms).
[13:03:28.356] <TB1> INFO: Expecting 514560 events.
[13:03:38.199] <TB1> INFO: 514560 events read in total (9184ms).
[13:03:38.222] <TB1> INFO: Expecting 514560 events.
[13:03:48.056] <TB1> INFO: 514560 events read in total (9181ms).
[13:03:48.081] <TB1> INFO: Expecting 514560 events.
[13:03:57.963] <TB1> INFO: 514560 events read in total (9233ms).
[13:03:57.996] <TB1> INFO: Expecting 514560 events.
[13:04:07.844] <TB1> INFO: 514560 events read in total (9217ms).
[13:04:07.879] <TB1> INFO: Expecting 514560 events.
[13:04:17.703] <TB1> INFO: 514560 events read in total (9193ms).
[13:04:17.740] <TB1> INFO: Expecting 514560 events.
[13:04:27.557] <TB1> INFO: 514560 events read in total (9189ms).
[13:04:27.587] <TB1> INFO: Expecting 514560 events.
[13:04:37.341] <TB1> INFO: 514560 events read in total (9106ms).
[13:04:37.376] <TB1> INFO: Test took 153293ms.
[13:04:38.487] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:04:38.495] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:04:38.495] <TB1> INFO: run 1 of 1
[13:04:38.815] <TB1> INFO: Expecting 6281600 events.
[13:05:19.185] <TB1> INFO: 866560 events read in total (39655ms).
[13:05:58.000] <TB1> INFO: 1731500 events read in total (78470ms).
[13:06:35.066] <TB1> INFO: 2597600 events read in total (115536ms).
[13:07:12.456] <TB1> INFO: 3461120 events read in total (152926ms).
[13:07:50.300] <TB1> INFO: 4317670 events read in total (190770ms).
[13:08:27.859] <TB1> INFO: 5171890 events read in total (228329ms).
[13:09:06.414] <TB1> INFO: 6025660 events read in total (266884ms).
[13:09:17.936] <TB1> INFO: 6281600 events read in total (278406ms).
[13:09:17.993] <TB1> INFO: Test took 279497ms.
[13:09:18.136] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:47.437] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.337715 .. 255.000000
[13:09:47.534] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:09:47.543] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:09:47.543] <TB1> INFO: run 1 of 1
[13:09:47.882] <TB1> INFO: Expecting 10649600 events.
[13:10:23.061] <TB1> INFO: 826430 events read in total (34449ms).
[13:10:55.341] <TB1> INFO: 1653080 events read in total (66729ms).
[13:11:32.040] <TB1> INFO: 2479820 events read in total (103428ms).
[13:12:07.485] <TB1> INFO: 3306440 events read in total (138873ms).
[13:12:42.668] <TB1> INFO: 4133220 events read in total (174056ms).
[13:13:13.785] <TB1> INFO: 4960000 events read in total (205173ms).
[13:13:48.846] <TB1> INFO: 5786370 events read in total (240234ms).
[13:14:22.827] <TB1> INFO: 6611670 events read in total (274215ms).
[13:14:58.294] <TB1> INFO: 7436600 events read in total (309682ms).
[13:15:32.671] <TB1> INFO: 8261030 events read in total (344059ms).
[13:16:05.536] <TB1> INFO: 9085240 events read in total (376924ms).
[13:16:39.366] <TB1> INFO: 9909260 events read in total (410754ms).
[13:17:10.583] <TB1> INFO: 10649600 events read in total (441971ms).
[13:17:10.682] <TB1> INFO: Test took 443139ms.
[13:17:10.962] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:39.729] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.374215 .. 44.651780
[13:17:39.810] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 54 (-1/-1) hits flags = 528 (plus default)
[13:17:39.818] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:17:39.818] <TB1> INFO: run 1 of 1
[13:17:40.127] <TB1> INFO: Expecting 2080000 events.
[13:18:21.858] <TB1> INFO: 1177040 events read in total (41014ms).
[13:18:50.677] <TB1> INFO: 2080000 events read in total (69833ms).
[13:18:50.701] <TB1> INFO: Test took 70884ms.
[13:18:50.738] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:04.368] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 18.932308 .. 41.057041
[13:19:04.452] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 8 .. 51 (-1/-1) hits flags = 528 (plus default)
[13:19:04.461] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:19:04.461] <TB1> INFO: run 1 of 1
[13:19:04.785] <TB1> INFO: Expecting 1830400 events.
[13:19:48.346] <TB1> INFO: 1195120 events read in total (42846ms).
[13:20:10.999] <TB1> INFO: 1830400 events read in total (65499ms).
[13:20:11.018] <TB1> INFO: Test took 66557ms.
[13:20:11.047] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:25.365] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 22.255950 .. 41.057041
[13:20:25.442] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 12 .. 51 (-1/-1) hits flags = 528 (plus default)
[13:20:25.450] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:20:25.451] <TB1> INFO: run 1 of 1
[13:20:25.757] <TB1> INFO: Expecting 1664000 events.
[13:21:04.922] <TB1> INFO: 1159550 events read in total (38450ms).
[13:21:24.055] <TB1> INFO: 1664000 events read in total (57583ms).
[13:21:24.074] <TB1> INFO: Test took 58623ms.
[13:21:24.102] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:37.284] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:21:37.284] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:21:37.292] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:21:37.293] <TB1> INFO: run 1 of 1
[13:21:37.601] <TB1> INFO: Expecting 1705600 events.
[13:22:14.076] <TB1> INFO: 1075590 events read in total (35760ms).
[13:22:36.090] <TB1> INFO: 1705600 events read in total (57774ms).
[13:22:36.103] <TB1> INFO: Test took 58810ms.
[13:22:36.137] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:52.118] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C0.dat
[13:22:52.118] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C1.dat
[13:22:52.119] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C2.dat
[13:22:52.119] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C3.dat
[13:22:52.119] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C4.dat
[13:22:52.119] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C5.dat
[13:22:52.119] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C6.dat
[13:22:52.119] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C7.dat
[13:22:52.120] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C8.dat
[13:22:52.120] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C9.dat
[13:22:52.120] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C10.dat
[13:22:52.120] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C11.dat
[13:22:52.120] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C12.dat
[13:22:52.120] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C13.dat
[13:22:52.120] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C14.dat
[13:22:52.120] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C15.dat
[13:22:52.120] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C0.dat
[13:22:52.129] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C1.dat
[13:22:52.136] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C2.dat
[13:22:52.143] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C3.dat
[13:22:52.149] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C4.dat
[13:22:52.156] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C5.dat
[13:22:52.162] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C6.dat
[13:22:52.168] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C7.dat
[13:22:52.175] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C8.dat
[13:22:52.181] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C9.dat
[13:22:52.188] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C10.dat
[13:22:52.194] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C11.dat
[13:22:52.201] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C12.dat
[13:22:52.207] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C13.dat
[13:22:52.214] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C14.dat
[13:22:52.221] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C15.dat
[13:22:52.228] <TB1> INFO: PixTestTrim::trimTest() done
[13:22:52.228] <TB1> INFO: vtrim: 104 85 100 107 90 91 89 94 85 107 92 101 103 93 98 95
[13:22:52.228] <TB1> INFO: vthrcomp: 98 95 95 93 86 97 81 101 85 88 91 85 101 91 87 80
[13:22:52.228] <TB1> INFO: vcal mean: 34.99 34.94 34.96 35.01 34.99 34.97 35.00 34.95 34.98 35.03 34.98 34.95 34.95 34.96 34.97 34.97
[13:22:52.228] <TB1> INFO: vcal RMS: 0.84 1.03 0.84 0.89 0.83 0.86 0.79 0.92 0.86 0.90 0.87 0.86 0.97 0.82 0.87 0.82
[13:22:52.228] <TB1> INFO: bits mean: 9.78 9.64 9.76 9.69 9.91 9.75 9.25 9.66 9.74 9.40 8.79 9.87 9.69 9.33 9.62 9.68
[13:22:52.228] <TB1> INFO: bits RMS: 2.65 2.64 2.66 2.54 2.51 2.67 2.76 2.66 2.67 2.56 2.83 2.54 2.65 2.71 2.73 2.51
[13:22:52.239] <TB1> INFO: ----------------------------------------------------------------------
[13:22:52.239] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:22:52.239] <TB1> INFO: ----------------------------------------------------------------------
[13:22:52.244] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:22:52.253] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:22:52.253] <TB1> INFO: run 1 of 1
[13:22:52.589] <TB1> INFO: Expecting 4160000 events.
[13:23:41.779] <TB1> INFO: 1130710 events read in total (48474ms).
[13:24:28.959] <TB1> INFO: 2247760 events read in total (95655ms).
[13:25:15.115] <TB1> INFO: 3349715 events read in total (141810ms).
[13:25:52.585] <TB1> INFO: 4160000 events read in total (179280ms).
[13:25:52.651] <TB1> INFO: Test took 180397ms.
[13:25:52.770] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:26.458] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 164 (-1/-1) hits flags = 528 (plus default)
[13:26:26.466] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:26:26.466] <TB1> INFO: run 1 of 1
[13:26:26.774] <TB1> INFO: Expecting 3432000 events.
[13:27:19.341] <TB1> INFO: 1194340 events read in total (51852ms).
[13:28:05.362] <TB1> INFO: 2363960 events read in total (97873ms).
[13:28:52.354] <TB1> INFO: 3432000 events read in total (144866ms).
[13:28:52.411] <TB1> INFO: Test took 145946ms.
[13:28:52.507] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:20.621] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[13:29:20.630] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:29:20.630] <TB1> INFO: run 1 of 1
[13:29:20.936] <TB1> INFO: Expecting 3182400 events.
[13:30:14.739] <TB1> INFO: 1248090 events read in total (53087ms).
[13:31:02.258] <TB1> INFO: 2463840 events read in total (100606ms).
[13:31:35.895] <TB1> INFO: 3182400 events read in total (134243ms).
[13:31:35.931] <TB1> INFO: Test took 135302ms.
[13:31:36.002] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:02.890] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[13:32:02.900] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:32:02.900] <TB1> INFO: run 1 of 1
[13:32:03.239] <TB1> INFO: Expecting 3182400 events.
[13:32:52.862] <TB1> INFO: 1246400 events read in total (48907ms).
[13:33:42.638] <TB1> INFO: 2460745 events read in total (98683ms).
[13:34:12.042] <TB1> INFO: 3182400 events read in total (128087ms).
[13:34:12.079] <TB1> INFO: Test took 129180ms.
[13:34:12.152] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:37.060] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[13:34:37.068] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:34:37.068] <TB1> INFO: run 1 of 1
[13:34:37.380] <TB1> INFO: Expecting 3182400 events.
[13:35:31.979] <TB1> INFO: 1244960 events read in total (53884ms).
[13:36:25.189] <TB1> INFO: 2457915 events read in total (107094ms).
[13:36:53.407] <TB1> INFO: 3182400 events read in total (135313ms).
[13:36:53.440] <TB1> INFO: Test took 136372ms.
[13:36:53.508] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:17.105] <TB1> INFO: PixTestTrim::trimBitTest() done
[13:37:17.106] <TB1> INFO: PixTestTrim::doTest() done, duration: 2611 seconds
[13:37:17.106] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:17.106] <TB1> INFO: Decoding statistics:
[13:37:17.106] <TB1> INFO: General information:
[13:37:17.106] <TB1> INFO: 16bit words read: 0
[13:37:17.106] <TB1> INFO: valid events total: 0
[13:37:17.106] <TB1> INFO: empty events: 0
[13:37:17.106] <TB1> INFO: valid events with pixels: 0
[13:37:17.106] <TB1> INFO: valid pixel hits: 0
[13:37:17.106] <TB1> INFO: Event errors: 0
[13:37:17.106] <TB1> INFO: start marker: 0
[13:37:17.106] <TB1> INFO: stop marker: 0
[13:37:17.106] <TB1> INFO: overflow: 0
[13:37:17.106] <TB1> INFO: invalid 5bit words: 0
[13:37:17.106] <TB1> INFO: invalid XOR eye diagram: 0
[13:37:17.106] <TB1> INFO: TBM errors: 0
[13:37:17.106] <TB1> INFO: flawed TBM headers: 0
[13:37:17.106] <TB1> INFO: flawed TBM trailers: 0
[13:37:17.106] <TB1> INFO: event ID mismatches: 0
[13:37:17.106] <TB1> INFO: ROC errors: 0
[13:37:17.106] <TB1> INFO: missing ROC header(s): 0
[13:37:17.106] <TB1> INFO: misplaced readback start: 0
[13:37:17.106] <TB1> INFO: Pixel decoding errors: 0
[13:37:17.106] <TB1> INFO: pixel data incomplete: 0
[13:37:17.106] <TB1> INFO: pixel address: 0
[13:37:17.107] <TB1> INFO: pulse height fill bit: 0
[13:37:17.107] <TB1> INFO: buffer corruption: 0
[13:37:17.797] <TB1> INFO: ######################################################################
[13:37:17.797] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:37:17.797] <TB1> INFO: ######################################################################
[13:37:18.107] <TB1> INFO: Expecting 41600 events.
[13:37:22.420] <TB1> INFO: 41600 events read in total (3597ms).
[13:37:22.420] <TB1> INFO: Test took 4621ms.
[13:37:22.428] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:23.105] <TB1> INFO: Expecting 41600 events.
[13:37:27.435] <TB1> INFO: 41600 events read in total (3614ms).
[13:37:27.435] <TB1> INFO: Test took 4659ms.
[13:37:27.442] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:27.867] <TB1> INFO: Expecting 41600 events.
[13:37:32.459] <TB1> INFO: 41600 events read in total (3877ms).
[13:37:32.460] <TB1> INFO: Test took 4918ms.
[13:37:32.468] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:32.885] <TB1> INFO: Expecting 2560 events.
[13:37:33.848] <TB1> INFO: 2560 events read in total (247ms).
[13:37:33.849] <TB1> INFO: Test took 1370ms.
[13:37:34.358] <TB1> INFO: Expecting 2560 events.
[13:37:35.325] <TB1> INFO: 2560 events read in total (252ms).
[13:37:35.325] <TB1> INFO: Test took 1476ms.
[13:37:35.834] <TB1> INFO: Expecting 2560 events.
[13:37:36.793] <TB1> INFO: 2560 events read in total (244ms).
[13:37:36.794] <TB1> INFO: Test took 1468ms.
[13:37:37.303] <TB1> INFO: Expecting 2560 events.
[13:37:38.263] <TB1> INFO: 2560 events read in total (244ms).
[13:37:38.263] <TB1> INFO: Test took 1469ms.
[13:37:38.772] <TB1> INFO: Expecting 2560 events.
[13:37:39.734] <TB1> INFO: 2560 events read in total (247ms).
[13:37:39.735] <TB1> INFO: Test took 1471ms.
[13:37:40.244] <TB1> INFO: Expecting 2560 events.
[13:37:41.208] <TB1> INFO: 2560 events read in total (249ms).
[13:37:41.208] <TB1> INFO: Test took 1473ms.
[13:37:41.717] <TB1> INFO: Expecting 2560 events.
[13:37:42.682] <TB1> INFO: 2560 events read in total (249ms).
[13:37:42.683] <TB1> INFO: Test took 1474ms.
[13:37:43.191] <TB1> INFO: Expecting 2560 events.
[13:37:44.154] <TB1> INFO: 2560 events read in total (247ms).
[13:37:44.155] <TB1> INFO: Test took 1472ms.
[13:37:44.664] <TB1> INFO: Expecting 2560 events.
[13:37:45.630] <TB1> INFO: 2560 events read in total (250ms).
[13:37:45.630] <TB1> INFO: Test took 1475ms.
[13:37:46.139] <TB1> INFO: Expecting 2560 events.
[13:37:47.104] <TB1> INFO: 2560 events read in total (250ms).
[13:37:47.104] <TB1> INFO: Test took 1473ms.
[13:37:47.613] <TB1> INFO: Expecting 2560 events.
[13:37:48.577] <TB1> INFO: 2560 events read in total (248ms).
[13:37:48.577] <TB1> INFO: Test took 1472ms.
[13:37:49.087] <TB1> INFO: Expecting 2560 events.
[13:37:50.051] <TB1> INFO: 2560 events read in total (249ms).
[13:37:50.052] <TB1> INFO: Test took 1474ms.
[13:37:50.560] <TB1> INFO: Expecting 2560 events.
[13:37:51.525] <TB1> INFO: 2560 events read in total (249ms).
[13:37:51.525] <TB1> INFO: Test took 1471ms.
[13:37:52.034] <TB1> INFO: Expecting 2560 events.
[13:37:52.999] <TB1> INFO: 2560 events read in total (249ms).
[13:37:52.999] <TB1> INFO: Test took 1473ms.
[13:37:53.508] <TB1> INFO: Expecting 2560 events.
[13:37:54.472] <TB1> INFO: 2560 events read in total (248ms).
[13:37:54.472] <TB1> INFO: Test took 1473ms.
[13:37:54.982] <TB1> INFO: Expecting 2560 events.
[13:37:55.945] <TB1> INFO: 2560 events read in total (248ms).
[13:37:55.945] <TB1> INFO: Test took 1472ms.
[13:37:55.951] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:37:56.456] <TB1> INFO: Expecting 655360 events.
[13:38:09.096] <TB1> INFO: 655360 events read in total (11924ms).
[13:38:09.107] <TB1> INFO: Expecting 655360 events.
[13:38:21.657] <TB1> INFO: 655360 events read in total (11976ms).
[13:38:21.670] <TB1> INFO: Expecting 655360 events.
[13:38:33.350] <TB1> INFO: 655360 events read in total (11084ms).
[13:38:33.365] <TB1> INFO: Expecting 655360 events.
[13:38:44.856] <TB1> INFO: 655360 events read in total (10909ms).
[13:38:44.877] <TB1> INFO: Expecting 655360 events.
[13:38:56.322] <TB1> INFO: 655360 events read in total (10868ms).
[13:38:56.348] <TB1> INFO: Expecting 655360 events.
[13:39:08.934] <TB1> INFO: 655360 events read in total (12024ms).
[13:39:08.960] <TB1> INFO: Expecting 655360 events.
[13:39:21.414] <TB1> INFO: 655360 events read in total (11876ms).
[13:39:21.444] <TB1> INFO: Expecting 655360 events.
[13:39:33.704] <TB1> INFO: 655360 events read in total (11684ms).
[13:39:33.742] <TB1> INFO: Expecting 655360 events.
[13:39:45.436] <TB1> INFO: 655360 events read in total (11136ms).
[13:39:45.472] <TB1> INFO: Expecting 655360 events.
[13:39:56.854] <TB1> INFO: 655360 events read in total (10821ms).
[13:39:56.895] <TB1> INFO: Expecting 655360 events.
[13:40:09.079] <TB1> INFO: 655360 events read in total (11620ms).
[13:40:09.128] <TB1> INFO: Expecting 655360 events.
[13:40:21.447] <TB1> INFO: 655360 events read in total (11778ms).
[13:40:21.495] <TB1> INFO: Expecting 655360 events.
[13:40:34.104] <TB1> INFO: 655360 events read in total (12047ms).
[13:40:34.172] <TB1> INFO: Expecting 655360 events.
[13:40:46.707] <TB1> INFO: 655360 events read in total (12007ms).
[13:40:46.760] <TB1> INFO: Expecting 655360 events.
[13:40:59.324] <TB1> INFO: 655360 events read in total (12009ms).
[13:40:59.388] <TB1> INFO: Expecting 655360 events.
[13:41:11.846] <TB1> INFO: 655360 events read in total (11918ms).
[13:41:11.918] <TB1> INFO: Test took 195967ms.
[13:41:12.003] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:41:12.310] <TB1> INFO: Expecting 655360 events.
[13:41:24.829] <TB1> INFO: 655360 events read in total (11804ms).
[13:41:24.841] <TB1> INFO: Expecting 655360 events.
[13:41:36.669] <TB1> INFO: 655360 events read in total (11226ms).
[13:41:36.687] <TB1> INFO: Expecting 655360 events.
[13:41:48.219] <TB1> INFO: 655360 events read in total (10988ms).
[13:41:48.238] <TB1> INFO: Expecting 655360 events.
[13:42:00.401] <TB1> INFO: 655360 events read in total (11591ms).
[13:42:00.420] <TB1> INFO: Expecting 655360 events.
[13:42:13.004] <TB1> INFO: 655360 events read in total (11998ms).
[13:42:13.027] <TB1> INFO: Expecting 655360 events.
[13:42:25.440] <TB1> INFO: 655360 events read in total (11838ms).
[13:42:25.467] <TB1> INFO: Expecting 655360 events.
[13:42:37.866] <TB1> INFO: 655360 events read in total (11808ms).
[13:42:37.896] <TB1> INFO: Expecting 655360 events.
[13:42:50.360] <TB1> INFO: 655360 events read in total (11885ms).
[13:42:50.398] <TB1> INFO: Expecting 655360 events.
[13:43:02.684] <TB1> INFO: 655360 events read in total (11723ms).
[13:43:02.724] <TB1> INFO: Expecting 655360 events.
[13:43:15.142] <TB1> INFO: 655360 events read in total (11849ms).
[13:43:15.183] <TB1> INFO: Expecting 655360 events.
[13:43:27.586] <TB1> INFO: 655360 events read in total (11830ms).
[13:43:27.640] <TB1> INFO: Expecting 655360 events.
[13:43:40.171] <TB1> INFO: 655360 events read in total (11975ms).
[13:43:40.222] <TB1> INFO: Expecting 655360 events.
[13:43:52.552] <TB1> INFO: 655360 events read in total (11787ms).
[13:43:52.610] <TB1> INFO: Expecting 655360 events.
[13:44:04.973] <TB1> INFO: 655360 events read in total (11827ms).
[13:44:05.039] <TB1> INFO: Expecting 655360 events.
[13:44:17.395] <TB1> INFO: 655360 events read in total (11822ms).
[13:44:17.479] <TB1> INFO: Expecting 655360 events.
[13:44:29.858] <TB1> INFO: 655360 events read in total (11851ms).
[13:44:29.954] <TB1> INFO: Test took 197951ms.
[13:44:30.205] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.214] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.223] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.232] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.240] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.249] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.257] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.266] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:44:30.275] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:44:30.283] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[13:44:30.292] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[13:44:30.301] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.309] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:44:30.318] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:44:30.327] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[13:44:30.336] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[13:44:30.345] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.354] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.361] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.368] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.375] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.382] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.389] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:44:30.396] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.403] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:30.460] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C0.dat
[13:44:30.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C1.dat
[13:44:30.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C2.dat
[13:44:30.461] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C3.dat
[13:44:30.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C4.dat
[13:44:30.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C5.dat
[13:44:30.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C6.dat
[13:44:30.462] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C7.dat
[13:44:30.463] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C8.dat
[13:44:30.463] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C9.dat
[13:44:30.463] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C10.dat
[13:44:30.463] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C11.dat
[13:44:30.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C12.dat
[13:44:30.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C13.dat
[13:44:30.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C14.dat
[13:44:30.464] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C15.dat
[13:44:30.793] <TB1> INFO: Expecting 41600 events.
[13:44:34.698] <TB1> INFO: 41600 events read in total (3189ms).
[13:44:34.699] <TB1> INFO: Test took 4230ms.
[13:44:35.369] <TB1> INFO: Expecting 41600 events.
[13:44:39.265] <TB1> INFO: 41600 events read in total (3180ms).
[13:44:39.266] <TB1> INFO: Test took 4231ms.
[13:44:39.915] <TB1> INFO: Expecting 41600 events.
[13:44:43.842] <TB1> INFO: 41600 events read in total (3212ms).
[13:44:43.842] <TB1> INFO: Test took 4251ms.
[13:44:44.171] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:44.304] <TB1> INFO: Expecting 2560 events.
[13:44:45.270] <TB1> INFO: 2560 events read in total (251ms).
[13:44:45.270] <TB1> INFO: Test took 1099ms.
[13:44:45.274] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:45.780] <TB1> INFO: Expecting 2560 events.
[13:44:46.742] <TB1> INFO: 2560 events read in total (247ms).
[13:44:46.743] <TB1> INFO: Test took 1469ms.
[13:44:46.746] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:47.252] <TB1> INFO: Expecting 2560 events.
[13:44:48.217] <TB1> INFO: 2560 events read in total (249ms).
[13:44:48.218] <TB1> INFO: Test took 1472ms.
[13:44:48.220] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:48.728] <TB1> INFO: Expecting 2560 events.
[13:44:49.692] <TB1> INFO: 2560 events read in total (248ms).
[13:44:49.692] <TB1> INFO: Test took 1472ms.
[13:44:49.695] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:50.202] <TB1> INFO: Expecting 2560 events.
[13:44:51.166] <TB1> INFO: 2560 events read in total (249ms).
[13:44:51.167] <TB1> INFO: Test took 1472ms.
[13:44:51.170] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:51.677] <TB1> INFO: Expecting 2560 events.
[13:44:52.640] <TB1> INFO: 2560 events read in total (248ms).
[13:44:52.641] <TB1> INFO: Test took 1472ms.
[13:44:52.644] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:53.150] <TB1> INFO: Expecting 2560 events.
[13:44:54.115] <TB1> INFO: 2560 events read in total (249ms).
[13:44:54.115] <TB1> INFO: Test took 1471ms.
[13:44:54.119] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:54.625] <TB1> INFO: Expecting 2560 events.
[13:44:55.584] <TB1> INFO: 2560 events read in total (244ms).
[13:44:55.585] <TB1> INFO: Test took 1466ms.
[13:44:55.587] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:56.094] <TB1> INFO: Expecting 2560 events.
[13:44:57.053] <TB1> INFO: 2560 events read in total (243ms).
[13:44:57.053] <TB1> INFO: Test took 1466ms.
[13:44:57.056] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:57.563] <TB1> INFO: Expecting 2560 events.
[13:44:58.521] <TB1> INFO: 2560 events read in total (243ms).
[13:44:58.521] <TB1> INFO: Test took 1465ms.
[13:44:58.525] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:59.031] <TB1> INFO: Expecting 2560 events.
[13:44:59.991] <TB1> INFO: 2560 events read in total (245ms).
[13:44:59.992] <TB1> INFO: Test took 1467ms.
[13:44:59.995] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:00.501] <TB1> INFO: Expecting 2560 events.
[13:45:01.460] <TB1> INFO: 2560 events read in total (244ms).
[13:45:01.461] <TB1> INFO: Test took 1467ms.
[13:45:01.463] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:01.970] <TB1> INFO: Expecting 2560 events.
[13:45:02.928] <TB1> INFO: 2560 events read in total (242ms).
[13:45:02.928] <TB1> INFO: Test took 1465ms.
[13:45:02.931] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:03.438] <TB1> INFO: Expecting 2560 events.
[13:45:04.395] <TB1> INFO: 2560 events read in total (242ms).
[13:45:04.396] <TB1> INFO: Test took 1465ms.
[13:45:04.398] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:04.905] <TB1> INFO: Expecting 2560 events.
[13:45:05.865] <TB1> INFO: 2560 events read in total (244ms).
[13:45:05.865] <TB1> INFO: Test took 1467ms.
[13:45:05.868] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:06.375] <TB1> INFO: Expecting 2560 events.
[13:45:07.333] <TB1> INFO: 2560 events read in total (242ms).
[13:45:07.334] <TB1> INFO: Test took 1466ms.
[13:45:07.336] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:07.843] <TB1> INFO: Expecting 2560 events.
[13:45:08.801] <TB1> INFO: 2560 events read in total (242ms).
[13:45:08.802] <TB1> INFO: Test took 1466ms.
[13:45:08.804] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:09.312] <TB1> INFO: Expecting 2560 events.
[13:45:10.269] <TB1> INFO: 2560 events read in total (242ms).
[13:45:10.270] <TB1> INFO: Test took 1466ms.
[13:45:10.272] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:10.780] <TB1> INFO: Expecting 2560 events.
[13:45:11.739] <TB1> INFO: 2560 events read in total (244ms).
[13:45:11.739] <TB1> INFO: Test took 1467ms.
[13:45:11.743] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:12.249] <TB1> INFO: Expecting 2560 events.
[13:45:13.207] <TB1> INFO: 2560 events read in total (243ms).
[13:45:13.207] <TB1> INFO: Test took 1464ms.
[13:45:13.210] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:13.717] <TB1> INFO: Expecting 2560 events.
[13:45:14.676] <TB1> INFO: 2560 events read in total (244ms).
[13:45:14.676] <TB1> INFO: Test took 1466ms.
[13:45:14.678] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:15.186] <TB1> INFO: Expecting 2560 events.
[13:45:16.143] <TB1> INFO: 2560 events read in total (242ms).
[13:45:16.143] <TB1> INFO: Test took 1465ms.
[13:45:16.146] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:16.653] <TB1> INFO: Expecting 2560 events.
[13:45:17.612] <TB1> INFO: 2560 events read in total (243ms).
[13:45:17.612] <TB1> INFO: Test took 1466ms.
[13:45:17.615] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:18.122] <TB1> INFO: Expecting 2560 events.
[13:45:19.082] <TB1> INFO: 2560 events read in total (244ms).
[13:45:19.082] <TB1> INFO: Test took 1467ms.
[13:45:19.084] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:19.592] <TB1> INFO: Expecting 2560 events.
[13:45:20.551] <TB1> INFO: 2560 events read in total (244ms).
[13:45:20.551] <TB1> INFO: Test took 1467ms.
[13:45:20.554] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:21.061] <TB1> INFO: Expecting 2560 events.
[13:45:22.019] <TB1> INFO: 2560 events read in total (243ms).
[13:45:22.019] <TB1> INFO: Test took 1466ms.
[13:45:22.023] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:22.529] <TB1> INFO: Expecting 2560 events.
[13:45:23.487] <TB1> INFO: 2560 events read in total (242ms).
[13:45:23.487] <TB1> INFO: Test took 1464ms.
[13:45:23.490] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:23.997] <TB1> INFO: Expecting 2560 events.
[13:45:24.955] <TB1> INFO: 2560 events read in total (243ms).
[13:45:24.956] <TB1> INFO: Test took 1466ms.
[13:45:24.958] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:25.465] <TB1> INFO: Expecting 2560 events.
[13:45:26.423] <TB1> INFO: 2560 events read in total (242ms).
[13:45:26.424] <TB1> INFO: Test took 1466ms.
[13:45:26.426] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:26.933] <TB1> INFO: Expecting 2560 events.
[13:45:27.891] <TB1> INFO: 2560 events read in total (243ms).
[13:45:27.891] <TB1> INFO: Test took 1465ms.
[13:45:27.894] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:28.402] <TB1> INFO: Expecting 2560 events.
[13:45:29.360] <TB1> INFO: 2560 events read in total (243ms).
[13:45:29.361] <TB1> INFO: Test took 1467ms.
[13:45:29.363] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:29.871] <TB1> INFO: Expecting 2560 events.
[13:45:30.829] <TB1> INFO: 2560 events read in total (243ms).
[13:45:30.829] <TB1> INFO: Test took 1466ms.
[13:45:31.584] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 493 seconds
[13:45:31.584] <TB1> INFO: PH scale (per ROC): 94 74 87 80 80 83 92 80 84 67 81 96 81 80 87 79
[13:45:31.584] <TB1> INFO: PH offset (per ROC): 146 143 149 162 149 166 144 158 156 146 141 146 169 152 151 154
[13:45:31.588] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:31.588] <TB1> INFO: Decoding statistics:
[13:45:31.588] <TB1> INFO: General information:
[13:45:31.588] <TB1> INFO: 16bit words read: 66440
[13:45:31.588] <TB1> INFO: valid events total: 5120
[13:45:31.588] <TB1> INFO: empty events: 2620
[13:45:31.588] <TB1> INFO: valid events with pixels: 2500
[13:45:31.588] <TB1> INFO: valid pixel hits: 2500
[13:45:31.588] <TB1> INFO: Event errors: 0
[13:45:31.588] <TB1> INFO: start marker: 0
[13:45:31.588] <TB1> INFO: stop marker: 0
[13:45:31.588] <TB1> INFO: overflow: 0
[13:45:31.588] <TB1> INFO: invalid 5bit words: 0
[13:45:31.588] <TB1> INFO: invalid XOR eye diagram: 0
[13:45:31.588] <TB1> INFO: TBM errors: 0
[13:45:31.588] <TB1> INFO: flawed TBM headers: 0
[13:45:31.588] <TB1> INFO: flawed TBM trailers: 0
[13:45:31.588] <TB1> INFO: event ID mismatches: 0
[13:45:31.588] <TB1> INFO: ROC errors: 0
[13:45:31.588] <TB1> INFO: missing ROC header(s): 0
[13:45:31.588] <TB1> INFO: misplaced readback start: 0
[13:45:31.588] <TB1> INFO: Pixel decoding errors: 0
[13:45:31.588] <TB1> INFO: pixel data incomplete: 0
[13:45:31.588] <TB1> INFO: pixel address: 0
[13:45:31.588] <TB1> INFO: pulse height fill bit: 0
[13:45:31.588] <TB1> INFO: buffer corruption: 0
[13:45:31.757] <TB1> INFO: ######################################################################
[13:45:31.757] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:45:31.757] <TB1> INFO: ######################################################################
[13:45:31.768] <TB1> INFO: scanning low vcal = 10
[13:45:32.078] <TB1> INFO: Expecting 41600 events.
[13:45:35.836] <TB1> INFO: 41600 events read in total (3042ms).
[13:45:35.836] <TB1> INFO: Test took 4068ms.
[13:45:35.839] <TB1> INFO: scanning low vcal = 20
[13:45:36.346] <TB1> INFO: Expecting 41600 events.
[13:45:40.121] <TB1> INFO: 41600 events read in total (3060ms).
[13:45:40.121] <TB1> INFO: Test took 4282ms.
[13:45:40.123] <TB1> INFO: scanning low vcal = 30
[13:45:40.630] <TB1> INFO: Expecting 41600 events.
[13:45:44.429] <TB1> INFO: 41600 events read in total (3083ms).
[13:45:44.430] <TB1> INFO: Test took 4307ms.
[13:45:44.432] <TB1> INFO: scanning low vcal = 40
[13:45:44.934] <TB1> INFO: Expecting 41600 events.
[13:45:49.250] <TB1> INFO: 41600 events read in total (3601ms).
[13:45:49.251] <TB1> INFO: Test took 4819ms.
[13:45:49.254] <TB1> INFO: scanning low vcal = 50
[13:45:49.691] <TB1> INFO: Expecting 41600 events.
[13:45:54.065] <TB1> INFO: 41600 events read in total (3659ms).
[13:45:54.066] <TB1> INFO: Test took 4811ms.
[13:45:54.069] <TB1> INFO: scanning low vcal = 60
[13:45:54.505] <TB1> INFO: Expecting 41600 events.
[13:45:58.841] <TB1> INFO: 41600 events read in total (3620ms).
[13:45:58.841] <TB1> INFO: Test took 4772ms.
[13:45:58.845] <TB1> INFO: scanning low vcal = 70
[13:45:59.292] <TB1> INFO: Expecting 41600 events.
[13:46:03.643] <TB1> INFO: 41600 events read in total (3636ms).
[13:46:03.643] <TB1> INFO: Test took 4798ms.
[13:46:03.647] <TB1> INFO: scanning low vcal = 80
[13:46:04.089] <TB1> INFO: Expecting 41600 events.
[13:46:08.465] <TB1> INFO: 41600 events read in total (3660ms).
[13:46:08.466] <TB1> INFO: Test took 4819ms.
[13:46:08.469] <TB1> INFO: scanning low vcal = 90
[13:46:08.915] <TB1> INFO: Expecting 41600 events.
[13:46:13.251] <TB1> INFO: 41600 events read in total (3620ms).
[13:46:13.252] <TB1> INFO: Test took 4783ms.
[13:46:13.256] <TB1> INFO: scanning low vcal = 100
[13:46:13.703] <TB1> INFO: Expecting 41600 events.
[13:46:18.200] <TB1> INFO: 41600 events read in total (3781ms).
[13:46:18.200] <TB1> INFO: Test took 4944ms.
[13:46:18.205] <TB1> INFO: scanning low vcal = 110
[13:46:18.637] <TB1> INFO: Expecting 41600 events.
[13:46:23.008] <TB1> INFO: 41600 events read in total (3656ms).
[13:46:23.009] <TB1> INFO: Test took 4804ms.
[13:46:23.012] <TB1> INFO: scanning low vcal = 120
[13:46:23.458] <TB1> INFO: Expecting 41600 events.
[13:46:27.821] <TB1> INFO: 41600 events read in total (3647ms).
[13:46:27.821] <TB1> INFO: Test took 4809ms.
[13:46:27.825] <TB1> INFO: scanning low vcal = 130
[13:46:28.254] <TB1> INFO: Expecting 41600 events.
[13:46:32.604] <TB1> INFO: 41600 events read in total (3634ms).
[13:46:32.605] <TB1> INFO: Test took 4780ms.
[13:46:32.608] <TB1> INFO: scanning low vcal = 140
[13:46:33.049] <TB1> INFO: Expecting 41600 events.
[13:46:37.385] <TB1> INFO: 41600 events read in total (3621ms).
[13:46:37.386] <TB1> INFO: Test took 4778ms.
[13:46:37.389] <TB1> INFO: scanning low vcal = 150
[13:46:37.838] <TB1> INFO: Expecting 41600 events.
[13:46:42.215] <TB1> INFO: 41600 events read in total (3662ms).
[13:46:42.216] <TB1> INFO: Test took 4827ms.
[13:46:42.220] <TB1> INFO: scanning low vcal = 160
[13:46:42.642] <TB1> INFO: Expecting 41600 events.
[13:46:47.028] <TB1> INFO: 41600 events read in total (3670ms).
[13:46:47.028] <TB1> INFO: Test took 4809ms.
[13:46:47.032] <TB1> INFO: scanning low vcal = 170
[13:46:47.459] <TB1> INFO: Expecting 41600 events.
[13:46:51.862] <TB1> INFO: 41600 events read in total (3687ms).
[13:46:51.863] <TB1> INFO: Test took 4831ms.
[13:46:51.868] <TB1> INFO: scanning low vcal = 180
[13:46:52.312] <TB1> INFO: Expecting 41600 events.
[13:46:56.687] <TB1> INFO: 41600 events read in total (3660ms).
[13:46:56.687] <TB1> INFO: Test took 4819ms.
[13:46:56.691] <TB1> INFO: scanning low vcal = 190
[13:46:57.110] <TB1> INFO: Expecting 41600 events.
[13:47:01.460] <TB1> INFO: 41600 events read in total (3634ms).
[13:47:01.461] <TB1> INFO: Test took 4770ms.
[13:47:01.464] <TB1> INFO: scanning low vcal = 200
[13:47:01.914] <TB1> INFO: Expecting 41600 events.
[13:47:06.300] <TB1> INFO: 41600 events read in total (3671ms).
[13:47:06.301] <TB1> INFO: Test took 4837ms.
[13:47:06.304] <TB1> INFO: scanning low vcal = 210
[13:47:06.735] <TB1> INFO: Expecting 41600 events.
[13:47:11.052] <TB1> INFO: 41600 events read in total (3602ms).
[13:47:11.052] <TB1> INFO: Test took 4747ms.
[13:47:11.057] <TB1> INFO: scanning low vcal = 220
[13:47:11.502] <TB1> INFO: Expecting 41600 events.
[13:47:15.763] <TB1> INFO: 41600 events read in total (3546ms).
[13:47:15.764] <TB1> INFO: Test took 4699ms.
[13:47:15.768] <TB1> INFO: scanning low vcal = 230
[13:47:16.210] <TB1> INFO: Expecting 41600 events.
[13:47:20.536] <TB1> INFO: 41600 events read in total (3611ms).
[13:47:20.536] <TB1> INFO: Test took 4768ms.
[13:47:20.540] <TB1> INFO: scanning low vcal = 240
[13:47:20.981] <TB1> INFO: Expecting 41600 events.
[13:47:25.322] <TB1> INFO: 41600 events read in total (3626ms).
[13:47:25.323] <TB1> INFO: Test took 4783ms.
[13:47:25.326] <TB1> INFO: scanning low vcal = 250
[13:47:25.749] <TB1> INFO: Expecting 41600 events.
[13:47:30.109] <TB1> INFO: 41600 events read in total (3644ms).
[13:47:30.110] <TB1> INFO: Test took 4784ms.
[13:47:30.115] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[13:47:30.558] <TB1> INFO: Expecting 41600 events.
[13:47:34.906] <TB1> INFO: 41600 events read in total (3632ms).
[13:47:34.906] <TB1> INFO: Test took 4791ms.
[13:47:34.910] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[13:47:35.354] <TB1> INFO: Expecting 41600 events.
[13:47:39.733] <TB1> INFO: 41600 events read in total (3663ms).
[13:47:39.734] <TB1> INFO: Test took 4824ms.
[13:47:39.737] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[13:47:40.159] <TB1> INFO: Expecting 41600 events.
[13:47:44.518] <TB1> INFO: 41600 events read in total (3643ms).
[13:47:44.518] <TB1> INFO: Test took 4781ms.
[13:47:44.522] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[13:47:44.965] <TB1> INFO: Expecting 41600 events.
[13:47:49.303] <TB1> INFO: 41600 events read in total (3622ms).
[13:47:49.304] <TB1> INFO: Test took 4782ms.
[13:47:49.307] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:47:49.732] <TB1> INFO: Expecting 41600 events.
[13:47:54.087] <TB1> INFO: 41600 events read in total (3639ms).
[13:47:54.087] <TB1> INFO: Test took 4780ms.
[13:47:54.683] <TB1> INFO: PixTestGainPedestal::measure() done
[13:48:35.021] <TB1> INFO: PixTestGainPedestal::fit() done
[13:48:35.021] <TB1> INFO: non-linearity mean: 0.951 0.949 0.952 0.954 0.952 0.955 0.957 0.961 0.957 0.953 0.958 0.953 0.959 0.954 0.956 0.952
[13:48:35.021] <TB1> INFO: non-linearity RMS: 0.005 0.005 0.006 0.005 0.006 0.005 0.005 0.005 0.004 0.007 0.005 0.007 0.007 0.005 0.006 0.006
[13:48:35.021] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:48:35.049] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:48:35.076] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:48:35.099] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:48:35.121] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:48:35.148] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:48:35.174] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:48:35.201] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:48:35.219] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:48:35.238] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:48:35.256] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:48:35.275] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:48:35.295] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:48:35.313] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:48:35.332] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:48:35.351] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:48:35.369] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 183 seconds
[13:48:35.369] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:35.369] <TB1> INFO: Decoding statistics:
[13:48:35.369] <TB1> INFO: General information:
[13:48:35.369] <TB1> INFO: 16bit words read: 2329560
[13:48:35.369] <TB1> INFO: valid events total: 83200
[13:48:35.369] <TB1> INFO: empty events: 0
[13:48:35.369] <TB1> INFO: valid events with pixels: 83200
[13:48:35.369] <TB1> INFO: valid pixel hits: 665580
[13:48:35.369] <TB1> INFO: Event errors: 0
[13:48:35.369] <TB1> INFO: start marker: 0
[13:48:35.369] <TB1> INFO: stop marker: 0
[13:48:35.369] <TB1> INFO: overflow: 0
[13:48:35.369] <TB1> INFO: invalid 5bit words: 0
[13:48:35.369] <TB1> INFO: invalid XOR eye diagram: 0
[13:48:35.369] <TB1> INFO: TBM errors: 0
[13:48:35.369] <TB1> INFO: flawed TBM headers: 0
[13:48:35.369] <TB1> INFO: flawed TBM trailers: 0
[13:48:35.369] <TB1> INFO: event ID mismatches: 0
[13:48:35.369] <TB1> INFO: ROC errors: 0
[13:48:35.369] <TB1> INFO: missing ROC header(s): 0
[13:48:35.369] <TB1> INFO: misplaced readback start: 0
[13:48:35.369] <TB1> INFO: Pixel decoding errors: 0
[13:48:35.369] <TB1> INFO: pixel data incomplete: 0
[13:48:35.369] <TB1> INFO: pixel address: 0
[13:48:35.369] <TB1> INFO: pulse height fill bit: 0
[13:48:35.369] <TB1> INFO: buffer corruption: 0
[13:48:35.376] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C15.dat
[13:48:35.377] <TB1> INFO: ######################################################################
[13:48:35.377] <TB1> INFO: PixTestReadback::doTest()
[13:48:35.377] <TB1> INFO: ######################################################################
[13:48:35.378] <TB1> INFO: PixTestReadback::RES sent once
[13:48:46.700] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C0.dat
[13:48:46.700] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C1.dat
[13:48:46.700] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C2.dat
[13:48:46.700] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C3.dat
[13:48:46.700] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C4.dat
[13:48:46.700] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C5.dat
[13:48:46.700] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C6.dat
[13:48:46.700] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C7.dat
[13:48:46.701] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C8.dat
[13:48:46.701] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C9.dat
[13:48:46.701] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C10.dat
[13:48:46.701] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C11.dat
[13:48:46.701] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C12.dat
[13:48:46.701] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C13.dat
[13:48:46.701] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C14.dat
[13:48:46.701] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C15.dat
[13:48:46.752] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:48:46.753] <TB1> INFO: PixTestReadback::RES sent once
[13:48:58.011] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C0.dat
[13:48:58.012] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C1.dat
[13:48:58.012] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C2.dat
[13:48:58.012] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C3.dat
[13:48:58.012] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C4.dat
[13:48:58.012] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C5.dat
[13:48:58.013] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C6.dat
[13:48:58.013] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C7.dat
[13:48:58.013] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C8.dat
[13:48:58.013] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C9.dat
[13:48:58.013] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C10.dat
[13:48:58.013] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C11.dat
[13:48:58.014] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C12.dat
[13:48:58.014] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C13.dat
[13:48:58.014] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C14.dat
[13:48:58.014] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C15.dat
[13:48:58.063] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:48:58.063] <TB1> INFO: PixTestReadback::RES sent once
[13:49:06.699] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:49:06.699] <TB1> INFO: Vbg will be calibrated using Vd calibration
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157calibrated Vbg = 1.18482 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.1calibrated Vbg = 1.1772 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.5calibrated Vbg = 1.18093 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.7calibrated Vbg = 1.18918 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 148calibrated Vbg = 1.19188 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.1calibrated Vbg = 1.19586 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148calibrated Vbg = 1.19438 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155calibrated Vbg = 1.19447 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.3calibrated Vbg = 1.20052 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.2calibrated Vbg = 1.197 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 150.3calibrated Vbg = 1.1922 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145.5calibrated Vbg = 1.19443 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 144.9calibrated Vbg = 1.19713 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.8calibrated Vbg = 1.18158 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152calibrated Vbg = 1.18273 :::*/*/*/*/
[13:49:06.699] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 148.7calibrated Vbg = 1.18404 :::*/*/*/*/
[13:49:06.703] <TB1> INFO: PixTestReadback::RES sent once
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C0.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C1.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C2.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C3.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C4.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C5.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C6.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C7.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C8.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C9.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C10.dat
[13:52:01.522] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C11.dat
[13:52:01.523] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C12.dat
[13:52:01.523] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C13.dat
[13:52:01.523] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C14.dat
[13:52:01.523] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4077_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C15.dat
[13:52:01.568] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:52:01.570] <TB1> INFO: PixTestReadback::doTest() done
[13:52:01.570] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:01.570] <TB1> INFO: Decoding statistics:
[13:52:01.570] <TB1> INFO: General information:
[13:52:01.570] <TB1> INFO: 16bit words read: 768
[13:52:01.570] <TB1> INFO: valid events total: 64
[13:52:01.570] <TB1> INFO: empty events: 64
[13:52:01.570] <TB1> INFO: valid events with pixels: 0
[13:52:01.570] <TB1> INFO: valid pixel hits: 0
[13:52:01.570] <TB1> INFO: Event errors: 0
[13:52:01.570] <TB1> INFO: start marker: 0
[13:52:01.570] <TB1> INFO: stop marker: 0
[13:52:01.570] <TB1> INFO: overflow: 0
[13:52:01.570] <TB1> INFO: invalid 5bit words: 0
[13:52:01.570] <TB1> INFO: invalid XOR eye diagram: 0
[13:52:01.570] <TB1> INFO: TBM errors: 0
[13:52:01.570] <TB1> INFO: flawed TBM headers: 0
[13:52:01.570] <TB1> INFO: flawed TBM trailers: 0
[13:52:01.570] <TB1> INFO: event ID mismatches: 0
[13:52:01.570] <TB1> INFO: ROC errors: 0
[13:52:01.570] <TB1> INFO: missing ROC header(s): 0
[13:52:01.570] <TB1> INFO: misplaced readback start: 0
[13:52:01.570] <TB1> INFO: Pixel decoding errors: 0
[13:52:01.570] <TB1> INFO: pixel data incomplete: 0
[13:52:01.570] <TB1> INFO: pixel address: 0
[13:52:01.570] <TB1> INFO: pulse height fill bit: 0
[13:52:01.570] <TB1> INFO: buffer corruption: 0
[13:52:01.585] <TB1> INFO: Decoding statistics:
[13:52:01.585] <TB1> INFO: General information:
[13:52:01.585] <TB1> INFO: 16bit words read: 2396768
[13:52:01.585] <TB1> INFO: valid events total: 88384
[13:52:01.585] <TB1> INFO: empty events: 2684
[13:52:01.585] <TB1> INFO: valid events with pixels: 85700
[13:52:01.585] <TB1> INFO: valid pixel hits: 668080
[13:52:01.585] <TB1> INFO: Event errors: 0
[13:52:01.585] <TB1> INFO: start marker: 0
[13:52:01.585] <TB1> INFO: stop marker: 0
[13:52:01.585] <TB1> INFO: overflow: 0
[13:52:01.585] <TB1> INFO: invalid 5bit words: 0
[13:52:01.585] <TB1> INFO: invalid XOR eye diagram: 0
[13:52:01.585] <TB1> INFO: TBM errors: 0
[13:52:01.585] <TB1> INFO: flawed TBM headers: 0
[13:52:01.585] <TB1> INFO: flawed TBM trailers: 0
[13:52:01.585] <TB1> INFO: event ID mismatches: 0
[13:52:01.585] <TB1> INFO: ROC errors: 0
[13:52:01.585] <TB1> INFO: missing ROC header(s): 0
[13:52:01.585] <TB1> INFO: misplaced readback start: 0
[13:52:01.585] <TB1> INFO: Pixel decoding errors: 0
[13:52:01.585] <TB1> INFO: pixel data incomplete: 0
[13:52:01.585] <TB1> INFO: pixel address: 0
[13:52:01.585] <TB1> INFO: pulse height fill bit: 0
[13:52:01.585] <TB1> INFO: buffer corruption: 0
[13:52:01.585] <TB1> INFO: enter test to run
[13:52:01.586] <TB1> INFO: test: exit no parameter change
[13:52:01.756] <TB1> QUIET: Connection to board 153 closed.
[13:52:01.836] <TB1> INFO: pXar: this is the end, my friend