[14:32:58.037] <TB0> INFO: *** Welcome to pxar ***
[14:32:58.037] <TB0> INFO: *** Today: 2015/12/08
[14:32:58.600] <TB0> INFO: *** Version: 3197
[14:32:58.600] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C15.dat
[14:32:58.600] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//tbmParameters_C0b.dat
[14:32:58.601] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//defaultMaskFile.dat
[14:32:58.601] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters_C15.dat
[14:32:58.665] <TB0> INFO: clk: 4
[14:32:58.665] <TB0> INFO: ctr: 4
[14:32:58.665] <TB0> INFO: sda: 19
[14:32:58.665] <TB0> INFO: tin: 9
[14:32:58.665] <TB0> INFO: level: 15
[14:32:58.665] <TB0> INFO: triggerdelay: 0
[14:32:58.665] <TB0> QUIET: Instanciating API for pxar prod-12
[14:32:58.665] <TB0> INFO: Log level: INFO
[14:32:58.672] <TB0> INFO: Found DTB DTB_WS6AYH
[14:32:58.682] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[14:32:58.686] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[14:32:58.688] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[14:33:00.197] <TB0> INFO: DUT info:
[14:33:00.197] <TB0> INFO: The DUT currently contains the following objects:
[14:33:00.197] <TB0> INFO: 2 TBM Cores tbm08c (2 ON)
[14:33:00.197] <TB0> INFO: TBM Core alpha (0): 7 registers set
[14:33:00.197] <TB0> INFO: TBM Core beta (1): 7 registers set
[14:33:00.197] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:33:00.197] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.197] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:00.598] <TB0> INFO: enter 'restricted' command line mode
[14:33:00.598] <TB0> INFO: enter test to run
[14:33:00.598] <TB0> INFO: test: pretest no parameter change
[14:33:00.598] <TB0> INFO: running: pretest
[14:33:00.603] <TB0> INFO: ######################################################################
[14:33:00.603] <TB0> INFO: PixTestPretest::doTest()
[14:33:00.603] <TB0> INFO: ######################################################################
[14:33:00.605] <TB0> INFO: ----------------------------------------------------------------------
[14:33:00.605] <TB0> INFO: PixTestPretest::programROC()
[14:33:00.605] <TB0> INFO: ----------------------------------------------------------------------
[14:33:18.622] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:33:18.622] <TB0> INFO: IA differences per ROC: 16.9 16.1 16.9 16.9 18.5 17.7 20.1 17.7 20.1 17.7 18.5 20.1 19.3 17.7 16.9 18.5
[14:33:18.709] <TB0> INFO: ----------------------------------------------------------------------
[14:33:18.709] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:33:18.709] <TB0> INFO: ----------------------------------------------------------------------
[14:33:39.991] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[14:33:39.991] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 20.1 19.3 19.3 18.5 18.5 19.3 18.5 19.3 19.3 19.3 20.1
[14:33:40.028] <TB0> INFO: ----------------------------------------------------------------------
[14:33:40.028] <TB0> INFO: PixTestPretest::findTiming()
[14:33:40.028] <TB0> INFO: ----------------------------------------------------------------------
[14:33:40.029] <TB0> INFO: PixTestCmd::init()

[14:35:17.898] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[14:35:17.898] <TB0> INFO: (success/tries = 100/100), width = 5
[14:35:17.900] <TB0> INFO: ----------------------------------------------------------------------
[14:35:17.900] <TB0> INFO: PixTestPretest::findWorkingPixel()
[14:35:17.900] <TB0> INFO: ----------------------------------------------------------------------
[14:35:18.036] <TB0> INFO: Expecting 231680 events.


[14:35:25.725] <TB0> INFO: 231680 events read in total (6974ms).
[14:35:25.730] <TB0> INFO: Test took 7828ms.
[14:35:26.133] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:35:26.171] <TB0> INFO: ----------------------------------------------------------------------
[14:35:26.171] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[14:35:26.171] <TB0> INFO: ----------------------------------------------------------------------
[14:35:26.310] <TB0> INFO: Expecting 231680 events.
[14:35:34.744] <TB0> INFO: 231680 events read in total (7719ms).
[14:35:34.749] <TB0> INFO: Test took 8572ms.
[14:35:35.161] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[14:35:35.161] <TB0> INFO: CalDel: 123 140 132 124 129 131 123 131 147 126 133 172 125 144 135 111
[14:35:35.161] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 53 51 51 51 51
[14:35:35.165] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C0.dat
[14:35:35.165] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C1.dat
[14:35:35.165] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C2.dat
[14:35:35.166] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C3.dat
[14:35:35.166] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C4.dat
[14:35:35.166] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C5.dat
[14:35:35.166] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C6.dat
[14:35:35.167] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C7.dat
[14:35:35.167] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C8.dat
[14:35:35.167] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C9.dat
[14:35:35.167] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C10.dat
[14:35:35.167] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C11.dat
[14:35:35.168] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C12.dat
[14:35:35.168] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C13.dat
[14:35:35.168] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C14.dat
[14:35:35.168] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters_C15.dat
[14:35:35.169] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//tbmParameters_C0a.dat
[14:35:35.169] <TB0> INFO: write tbm parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//tbmParameters_C0b.dat
[14:35:35.169] <TB0> INFO: PixTestPretest::doTest() done, duration: 154 seconds
[14:35:35.236] <TB0> INFO: enter test to run
[14:35:35.236] <TB0> INFO: test: fulltest no parameter change
[14:35:35.236] <TB0> INFO: running: fulltest
[14:35:35.236] <TB0> INFO: ######################################################################
[14:35:35.236] <TB0> INFO: PixTestFullTest::doTest()
[14:35:35.236] <TB0> INFO: ######################################################################
[14:35:35.238] <TB0> INFO: ######################################################################
[14:35:35.238] <TB0> INFO: PixTestAlive::doTest()
[14:35:35.238] <TB0> INFO: ######################################################################
[14:35:35.239] <TB0> INFO: ----------------------------------------------------------------------
[14:35:35.239] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:35:35.239] <TB0> INFO: ----------------------------------------------------------------------
[14:35:35.548] <TB0> INFO: Expecting 41600 events.
[14:35:40.005] <TB0> INFO: 41600 events read in total (3741ms).
[14:35:40.006] <TB0> INFO: Test took 4766ms.
[14:35:40.012] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:40.394] <TB0> INFO: PixTestAlive::aliveTest() done
[14:35:40.394] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[14:35:40.395] <TB0> INFO: ----------------------------------------------------------------------
[14:35:40.395] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:35:40.395] <TB0> INFO: ----------------------------------------------------------------------
[14:35:40.705] <TB0> INFO: Expecting 41600 events.
[14:35:43.913] <TB0> INFO: 41600 events read in total (2493ms).
[14:35:43.913] <TB0> INFO: Test took 3516ms.
[14:35:43.913] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:43.914] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:35:44.325] <TB0> INFO: PixTestAlive::maskTest() done
[14:35:44.325] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:35:44.327] <TB0> INFO: ----------------------------------------------------------------------
[14:35:44.327] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:35:44.327] <TB0> INFO: ----------------------------------------------------------------------
[14:35:44.647] <TB0> INFO: Expecting 41600 events.
[14:35:49.110] <TB0> INFO: 41600 events read in total (3748ms).
[14:35:49.111] <TB0> INFO: Test took 4782ms.
[14:35:49.118] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:49.498] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[14:35:49.499] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:35:49.499] <TB0> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:35:49.499] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:49.499] <TB0> INFO: Decoding statistics:
[14:35:49.499] <TB0> INFO: General information:
[14:35:49.499] <TB0> INFO: 16bit words read: 0
[14:35:49.499] <TB0> INFO: valid events total: 0
[14:35:49.499] <TB0> INFO: empty events: 0
[14:35:49.499] <TB0> INFO: valid events with pixels: 0
[14:35:49.499] <TB0> INFO: valid pixel hits: 0
[14:35:49.499] <TB0> INFO: Event errors: 0
[14:35:49.499] <TB0> INFO: start marker: 0
[14:35:49.499] <TB0> INFO: stop marker: 0
[14:35:49.499] <TB0> INFO: overflow: 0
[14:35:49.499] <TB0> INFO: invalid 5bit words: 0
[14:35:49.499] <TB0> INFO: invalid XOR eye diagram: 0
[14:35:49.499] <TB0> INFO: TBM errors: 0
[14:35:49.499] <TB0> INFO: flawed TBM headers: 0
[14:35:49.499] <TB0> INFO: flawed TBM trailers: 0
[14:35:49.499] <TB0> INFO: event ID mismatches: 0
[14:35:49.499] <TB0> INFO: ROC errors: 0
[14:35:49.499] <TB0> INFO: missing ROC header(s): 0
[14:35:49.499] <TB0> INFO: misplaced readback start: 0
[14:35:49.499] <TB0> INFO: Pixel decoding errors: 0
[14:35:49.499] <TB0> INFO: pixel data incomplete: 0
[14:35:49.499] <TB0> INFO: pixel address: 0
[14:35:49.499] <TB0> INFO: pulse height fill bit: 0
[14:35:49.499] <TB0> INFO: buffer corruption: 0
[14:35:49.514] <TB0> INFO: ######################################################################
[14:35:49.514] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:35:49.514] <TB0> INFO: ######################################################################
[14:35:49.517] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:35:49.529] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:35:49.529] <TB0> INFO: run 1 of 1
[14:35:49.833] <TB0> INFO: Expecting 3120000 events.
[14:36:40.949] <TB0> INFO: 1177160 events read in total (50402ms).
[14:37:32.813] <TB0> INFO: 2342075 events read in total (102266ms).
[14:38:05.747] <TB0> INFO: 3120000 events read in total (135201ms).
[14:38:05.788] <TB0> INFO: Test took 136260ms.
[14:38:05.873] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:31.209] <TB0> INFO: PixTestBBMap::doTest() done, duration: 161 seconds
[14:38:31.209] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 1 0 0 0
[14:38:31.209] <TB0> INFO: separation cut (per ROC): 144 121 111 112 117 122 112 134 108 106 124 117 107 106 109 145
[14:38:31.209] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:31.209] <TB0> INFO: Decoding statistics:
[14:38:31.209] <TB0> INFO: General information:
[14:38:31.209] <TB0> INFO: 16bit words read: 0
[14:38:31.209] <TB0> INFO: valid events total: 0
[14:38:31.209] <TB0> INFO: empty events: 0
[14:38:31.209] <TB0> INFO: valid events with pixels: 0
[14:38:31.209] <TB0> INFO: valid pixel hits: 0
[14:38:31.209] <TB0> INFO: Event errors: 0
[14:38:31.209] <TB0> INFO: start marker: 0
[14:38:31.209] <TB0> INFO: stop marker: 0
[14:38:31.209] <TB0> INFO: overflow: 0
[14:38:31.209] <TB0> INFO: invalid 5bit words: 0
[14:38:31.209] <TB0> INFO: invalid XOR eye diagram: 0
[14:38:31.209] <TB0> INFO: TBM errors: 0
[14:38:31.209] <TB0> INFO: flawed TBM headers: 0
[14:38:31.209] <TB0> INFO: flawed TBM trailers: 0
[14:38:31.209] <TB0> INFO: event ID mismatches: 0
[14:38:31.209] <TB0> INFO: ROC errors: 0
[14:38:31.209] <TB0> INFO: missing ROC header(s): 0
[14:38:31.209] <TB0> INFO: misplaced readback start: 0
[14:38:31.209] <TB0> INFO: Pixel decoding errors: 0
[14:38:31.209] <TB0> INFO: pixel data incomplete: 0
[14:38:31.209] <TB0> INFO: pixel address: 0
[14:38:31.209] <TB0> INFO: pulse height fill bit: 0
[14:38:31.209] <TB0> INFO: buffer corruption: 0
[14:38:31.279] <TB0> INFO: ######################################################################
[14:38:31.279] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:38:31.279] <TB0> INFO: ######################################################################
[14:38:31.279] <TB0> INFO: ----------------------------------------------------------------------
[14:38:31.279] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:38:31.279] <TB0> INFO: ----------------------------------------------------------------------
[14:38:31.280] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:38:31.288] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[14:38:31.288] <TB0> INFO: run 1 of 1
[14:38:31.595] <TB0> INFO: Expecting 26208000 events.
[14:39:03.307] <TB0> INFO: 1132150 events read in total (30997ms).
[14:39:37.702] <TB0> INFO: 2238750 events read in total (65392ms).
[14:40:12.385] <TB0> INFO: 3347150 events read in total (100075ms).
[14:40:46.499] <TB0> INFO: 4453500 events read in total (134189ms).
[14:41:20.919] <TB0> INFO: 5557950 events read in total (168609ms).
[14:41:55.208] <TB0> INFO: 6662600 events read in total (202898ms).
[14:42:30.131] <TB0> INFO: 7761850 events read in total (237821ms).
[14:43:04.588] <TB0> INFO: 8861550 events read in total (272278ms).
[14:43:39.849] <TB0> INFO: 9959150 events read in total (307539ms).
[14:44:15.025] <TB0> INFO: 11054850 events read in total (342715ms).
[14:44:48.374] <TB0> INFO: 12151500 events read in total (376064ms).
[14:45:22.270] <TB0> INFO: 13241200 events read in total (409960ms).
[14:45:56.021] <TB0> INFO: 14318500 events read in total (443711ms).
[14:46:31.262] <TB0> INFO: 15393800 events read in total (478952ms).
[14:47:06.164] <TB0> INFO: 16467300 events read in total (513854ms).
[14:47:40.927] <TB0> INFO: 17539450 events read in total (548617ms).
[14:48:15.101] <TB0> INFO: 18613350 events read in total (582791ms).
[14:48:49.416] <TB0> INFO: 19681700 events read in total (617106ms).
[14:49:24.181] <TB0> INFO: 20751350 events read in total (651871ms).
[14:49:58.225] <TB0> INFO: 21821000 events read in total (685915ms).
[14:50:32.562] <TB0> INFO: 22890250 events read in total (720252ms).
[14:51:05.926] <TB0> INFO: 23958900 events read in total (753616ms).
[14:51:37.517] <TB0> INFO: 25032200 events read in total (785207ms).
[14:52:08.614] <TB0> INFO: 26123900 events read in total (816304ms).
[14:52:11.556] <TB0> INFO: 26208000 events read in total (819246ms).
[14:52:11.591] <TB0> INFO: Test took 820303ms.
[14:52:11.677] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:11.830] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:13.205] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:14.614] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:16.029] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:17.635] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:19.156] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:20.612] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:22.062] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:23.507] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:24.911] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:26.429] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:27.907] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:29.594] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:31.370] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:33.114] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:34.840] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[14:52:36.494] <TB0> INFO: PixTestScurves::scurves() done
[14:52:36.494] <TB0> INFO: Vcal mean: 110.36 97.60 92.91 96.66 93.42 92.12 78.33 91.30 89.25 84.69 99.55 104.68 81.66 81.92 87.32 97.13
[14:52:36.494] <TB0> INFO: Vcal RMS: 5.54 5.77 5.74 5.31 4.89 5.14 3.67 5.19 5.32 5.16 6.42 6.36 5.15 5.56 5.30 5.72
[14:52:36.494] <TB0> INFO: PixTestScurves::fullTest() done, duration: 845 seconds
[14:52:36.494] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:36.494] <TB0> INFO: Decoding statistics:
[14:52:36.494] <TB0> INFO: General information:
[14:52:36.494] <TB0> INFO: 16bit words read: 0
[14:52:36.494] <TB0> INFO: valid events total: 0
[14:52:36.494] <TB0> INFO: empty events: 0
[14:52:36.494] <TB0> INFO: valid events with pixels: 0
[14:52:36.494] <TB0> INFO: valid pixel hits: 0
[14:52:36.494] <TB0> INFO: Event errors: 0
[14:52:36.494] <TB0> INFO: start marker: 0
[14:52:36.494] <TB0> INFO: stop marker: 0
[14:52:36.494] <TB0> INFO: overflow: 0
[14:52:36.494] <TB0> INFO: invalid 5bit words: 0
[14:52:36.494] <TB0> INFO: invalid XOR eye diagram: 0
[14:52:36.494] <TB0> INFO: TBM errors: 0
[14:52:36.494] <TB0> INFO: flawed TBM headers: 0
[14:52:36.494] <TB0> INFO: flawed TBM trailers: 0
[14:52:36.494] <TB0> INFO: event ID mismatches: 0
[14:52:36.494] <TB0> INFO: ROC errors: 0
[14:52:36.494] <TB0> INFO: missing ROC header(s): 0
[14:52:36.494] <TB0> INFO: misplaced readback start: 0
[14:52:36.494] <TB0> INFO: Pixel decoding errors: 0
[14:52:36.494] <TB0> INFO: pixel data incomplete: 0
[14:52:36.494] <TB0> INFO: pixel address: 0
[14:52:36.494] <TB0> INFO: pulse height fill bit: 0
[14:52:36.494] <TB0> INFO: buffer corruption: 0
[14:52:36.577] <TB0> INFO: ######################################################################
[14:52:36.577] <TB0> INFO: PixTestTrim::doTest()
[14:52:36.577] <TB0> INFO: ######################################################################
[14:52:36.578] <TB0> INFO: ----------------------------------------------------------------------
[14:52:36.578] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[14:52:36.578] <TB0> INFO: ----------------------------------------------------------------------
[14:52:36.672] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[14:52:36.672] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:52:36.680] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:52:36.680] <TB0> INFO: run 1 of 1
[14:52:37.005] <TB0> INFO: Expecting 6281600 events.
[14:53:20.959] <TB0> INFO: 1409920 events read in total (43239ms).
[14:54:05.726] <TB0> INFO: 2803460 events read in total (88006ms).
[14:54:50.209] <TB0> INFO: 4194970 events read in total (132490ms).
[14:55:33.779] <TB0> INFO: 5592190 events read in total (176059ms).
[14:55:56.227] <TB0> INFO: 6281600 events read in total (198507ms).
[14:55:56.258] <TB0> INFO: Test took 199578ms.
[14:55:56.312] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:17.529] <TB0> INFO: ROC 0 VthrComp = 107
[14:56:17.530] <TB0> INFO: ROC 1 VthrComp = 97
[14:56:17.530] <TB0> INFO: ROC 2 VthrComp = 92
[14:56:17.530] <TB0> INFO: ROC 3 VthrComp = 97
[14:56:17.530] <TB0> INFO: ROC 4 VthrComp = 101
[14:56:17.530] <TB0> INFO: ROC 5 VthrComp = 100
[14:56:17.530] <TB0> INFO: ROC 6 VthrComp = 86
[14:56:17.530] <TB0> INFO: ROC 7 VthrComp = 96
[14:56:17.530] <TB0> INFO: ROC 8 VthrComp = 92
[14:56:17.531] <TB0> INFO: ROC 9 VthrComp = 91
[14:56:17.531] <TB0> INFO: ROC 10 VthrComp = 101
[14:56:17.531] <TB0> INFO: ROC 11 VthrComp = 101
[14:56:17.531] <TB0> INFO: ROC 12 VthrComp = 89
[14:56:17.531] <TB0> INFO: ROC 13 VthrComp = 85
[14:56:17.531] <TB0> INFO: ROC 14 VthrComp = 92
[14:56:17.531] <TB0> INFO: ROC 15 VthrComp = 104
[14:56:17.531] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[14:56:17.531] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:56:17.542] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:56:17.542] <TB0> INFO: run 1 of 1
[14:56:17.859] <TB0> INFO: Expecting 6281600 events.
[14:56:51.956] <TB0> INFO: 887360 events read in total (33382ms).
[14:57:30.194] <TB0> INFO: 1771970 events read in total (71620ms).
[14:58:08.127] <TB0> INFO: 2658090 events read in total (109553ms).
[14:58:44.502] <TB0> INFO: 3541490 events read in total (145928ms).
[14:59:23.049] <TB0> INFO: 4418200 events read in total (184475ms).
[14:59:59.893] <TB0> INFO: 5292090 events read in total (221319ms).
[15:00:38.694] <TB0> INFO: 6165370 events read in total (260120ms).
[15:00:44.453] <TB0> INFO: 6281600 events read in total (265879ms).
[15:00:44.506] <TB0> INFO: Test took 266964ms.
[15:00:44.646] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:10.430] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 62.8669 for pixel 34/0 mean/min/max = 48.7284/34.4207/63.0361
[15:01:10.430] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 61.5551 for pixel 2/71 mean/min/max = 46.5915/31.5252/61.6578
[15:01:10.430] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 61.9719 for pixel 0/8 mean/min/max = 46.7841/31.5438/62.0244
[15:01:10.430] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 58.8127 for pixel 22/6 mean/min/max = 45.4265/31.5195/59.3335
[15:01:10.431] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 55.7978 for pixel 23/70 mean/min/max = 43.7277/31.4482/56.0072
[15:01:10.431] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 56.5946 for pixel 2/79 mean/min/max = 44.8232/32.6099/57.0366
[15:01:10.431] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 54.5387 for pixel 51/60 mean/min/max = 43.4328/32.2786/54.5869
[15:01:10.432] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.575 for pixel 3/28 mean/min/max = 44.8487/31.8884/57.8091
[15:01:10.432] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 57.7617 for pixel 0/44 mean/min/max = 45.0185/32.2567/57.7803
[15:01:10.432] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 56.8469 for pixel 7/58 mean/min/max = 44.7632/32.4069/57.1194
[15:01:10.432] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 61.9 for pixel 0/2 mean/min/max = 46.9539/31.999/61.9089
[15:01:10.433] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 64.089 for pixel 45/10 mean/min/max = 48.0948/32.0614/64.1283
[15:01:10.433] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.0243 for pixel 14/68 mean/min/max = 45.6887/32.3259/59.0515
[15:01:10.433] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 60.5182 for pixel 0/73 mean/min/max = 45.9818/31.4296/60.534
[15:01:10.433] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 57.1399 for pixel 4/77 mean/min/max = 45.0116/32.5486/57.4747
[15:01:10.434] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 58.9883 for pixel 7/61 mean/min/max = 46.0151/32.9405/59.0897
[15:01:10.434] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:01:10.564] <TB0> INFO: Expecting 514560 events.
[15:01:19.583] <TB0> INFO: 514560 events read in total (8305ms).
[15:01:19.589] <TB0> INFO: Expecting 514560 events.
[15:01:28.648] <TB0> INFO: 514560 events read in total (8388ms).
[15:01:28.655] <TB0> INFO: Expecting 514560 events.
[15:01:38.846] <TB0> INFO: 514560 events read in total (9518ms).
[15:01:38.855] <TB0> INFO: Expecting 514560 events.
[15:01:49.056] <TB0> INFO: 514560 events read in total (9537ms).
[15:01:49.067] <TB0> INFO: Expecting 514560 events.
[15:01:59.299] <TB0> INFO: 514560 events read in total (9569ms).
[15:01:59.312] <TB0> INFO: Expecting 514560 events.
[15:02:09.595] <TB0> INFO: 514560 events read in total (9620ms).
[15:02:09.609] <TB0> INFO: Expecting 514560 events.
[15:02:19.750] <TB0> INFO: 514560 events read in total (9482ms).
[15:02:19.770] <TB0> INFO: Expecting 514560 events.
[15:02:30.479] <TB0> INFO: 514560 events read in total (10062ms).
[15:02:30.500] <TB0> INFO: Expecting 514560 events.
[15:02:40.947] <TB0> INFO: 514560 events read in total (9803ms).
[15:02:40.967] <TB0> INFO: Expecting 514560 events.
[15:02:50.662] <TB0> INFO: 514560 events read in total (9035ms).
[15:02:50.686] <TB0> INFO: Expecting 514560 events.
[15:02:59.903] <TB0> INFO: 514560 events read in total (8567ms).
[15:02:59.928] <TB0> INFO: Expecting 514560 events.
[15:03:09.376] <TB0> INFO: 514560 events read in total (8793ms).
[15:03:09.405] <TB0> INFO: Expecting 514560 events.
[15:03:20.075] <TB0> INFO: 514560 events read in total (10024ms).
[15:03:20.106] <TB0> INFO: Expecting 514560 events.
[15:03:30.535] <TB0> INFO: 514560 events read in total (9808ms).
[15:03:30.567] <TB0> INFO: Expecting 514560 events.
[15:03:40.782] <TB0> INFO: 514560 events read in total (9569ms).
[15:03:40.813] <TB0> INFO: Expecting 514560 events.
[15:03:51.332] <TB0> INFO: 514560 events read in total (9880ms).
[15:03:51.371] <TB0> INFO: Test took 160937ms.
[15:03:52.417] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:03:52.425] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:03:52.425] <TB0> INFO: run 1 of 1
[15:03:52.727] <TB0> INFO: Expecting 6281600 events.
[15:04:33.187] <TB0> INFO: 869930 events read in total (39745ms).
[15:05:13.081] <TB0> INFO: 1737520 events read in total (79639ms).
[15:05:51.978] <TB0> INFO: 2606080 events read in total (118536ms).
[15:06:30.445] <TB0> INFO: 3472030 events read in total (157004ms).
[15:07:09.259] <TB0> INFO: 4330420 events read in total (195817ms).
[15:07:45.070] <TB0> INFO: 5186020 events read in total (231628ms).
[15:08:23.885] <TB0> INFO: 6040090 events read in total (270443ms).
[15:08:34.990] <TB0> INFO: 6281600 events read in total (281548ms).
[15:08:35.060] <TB0> INFO: Test took 282635ms.
[15:08:35.221] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:00.890] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.201017 .. 255.000000
[15:09:00.982] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:09:00.992] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:09:00.992] <TB0> INFO: run 1 of 1
[15:09:01.321] <TB0> INFO: Expecting 10649600 events.
[15:09:39.552] <TB0> INFO: 826730 events read in total (37510ms).
[15:10:14.871] <TB0> INFO: 1653570 events read in total (72829ms).
[15:10:52.490] <TB0> INFO: 2480210 events read in total (110448ms).
[15:11:30.525] <TB0> INFO: 3307130 events read in total (148483ms).
[15:12:09.314] <TB0> INFO: 4133830 events read in total (187272ms).
[15:12:46.588] <TB0> INFO: 4960720 events read in total (224546ms).
[15:13:24.734] <TB0> INFO: 5787470 events read in total (262692ms).
[15:14:01.743] <TB0> INFO: 6613710 events read in total (299701ms).
[15:14:38.117] <TB0> INFO: 7439290 events read in total (336075ms).
[15:15:14.539] <TB0> INFO: 8264470 events read in total (372497ms).
[15:15:48.986] <TB0> INFO: 9089080 events read in total (406944ms).
[15:16:24.221] <TB0> INFO: 9913560 events read in total (442179ms).
[15:16:58.635] <TB0> INFO: 10649600 events read in total (476593ms).
[15:16:58.761] <TB0> INFO: Test took 477770ms.
[15:16:59.059] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:29.750] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 16.653968 .. 47.062168
[15:17:29.827] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 57 (-1/-1) hits flags = 528 (plus default)
[15:17:29.836] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:17:29.836] <TB0> INFO: run 1 of 1
[15:17:30.141] <TB0> INFO: Expecting 2163200 events.
[15:18:10.421] <TB0> INFO: 1134700 events read in total (39563ms).
[15:18:48.245] <TB0> INFO: 2163200 events read in total (77388ms).
[15:18:48.270] <TB0> INFO: Test took 78435ms.
[15:18:48.305] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:03.090] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 20.733100 .. 44.591477
[15:19:03.169] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 54 (-1/-1) hits flags = 528 (plus default)
[15:19:03.178] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:19:03.178] <TB0> INFO: run 1 of 1
[15:19:03.486] <TB0> INFO: Expecting 1872000 events.
[15:19:47.272] <TB0> INFO: 1136240 events read in total (43071ms).
[15:20:12.171] <TB0> INFO: 1872000 events read in total (67971ms).
[15:20:12.189] <TB0> INFO: Test took 69012ms.
[15:20:12.223] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:26.207] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 22.046846 .. 44.181457
[15:20:26.282] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 12 .. 54 (-1/-1) hits flags = 528 (plus default)
[15:20:26.291] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:20:26.291] <TB0> INFO: run 1 of 1
[15:20:26.627] <TB0> INFO: Expecting 1788800 events.
[15:21:10.479] <TB0> INFO: 1118720 events read in total (43138ms).
[15:21:32.847] <TB0> INFO: 1788800 events read in total (65506ms).
[15:21:32.860] <TB0> INFO: Test took 66569ms.
[15:21:32.888] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:47.418] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:21:47.418] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:21:47.426] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:21:47.426] <TB0> INFO: run 1 of 1
[15:21:47.738] <TB0> INFO: Expecting 1705600 events.
[15:22:29.057] <TB0> INFO: 1075680 events read in total (40604ms).
[15:22:52.648] <TB0> INFO: 1705600 events read in total (64195ms).
[15:22:52.667] <TB0> INFO: Test took 65241ms.
[15:22:52.699] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:06.656] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C0.dat
[15:23:06.656] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C1.dat
[15:23:06.657] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C2.dat
[15:23:06.657] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C3.dat
[15:23:06.657] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C4.dat
[15:23:06.657] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C5.dat
[15:23:06.657] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C6.dat
[15:23:06.658] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C7.dat
[15:23:06.658] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C8.dat
[15:23:06.658] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C9.dat
[15:23:06.658] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C10.dat
[15:23:06.658] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C11.dat
[15:23:06.658] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C12.dat
[15:23:06.659] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C13.dat
[15:23:06.659] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C14.dat
[15:23:06.659] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C15.dat
[15:23:06.659] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C0.dat
[15:23:06.669] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C1.dat
[15:23:06.675] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C2.dat
[15:23:06.681] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C3.dat
[15:23:06.687] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C4.dat
[15:23:06.693] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C5.dat
[15:23:06.699] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C6.dat
[15:23:06.706] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C7.dat
[15:23:06.712] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C8.dat
[15:23:06.718] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C9.dat
[15:23:06.724] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C10.dat
[15:23:06.730] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C11.dat
[15:23:06.736] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C12.dat
[15:23:06.742] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C13.dat
[15:23:06.748] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C14.dat
[15:23:06.755] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//trimParameters35_C15.dat
[15:23:06.761] <TB0> INFO: PixTestTrim::trimTest() done
[15:23:06.761] <TB0> INFO: vtrim: 114 94 99 95 93 92 81 98 92 97 104 98 130 95 91 115
[15:23:06.761] <TB0> INFO: vthrcomp: 107 97 92 97 101 100 86 96 92 91 101 101 89 85 92 104
[15:23:06.761] <TB0> INFO: vcal mean: 34.98 34.96 34.98 34.97 34.98 35.00 34.97 34.98 34.99 34.90 35.02 34.99 34.97 35.00 34.98 34.98
[15:23:06.761] <TB0> INFO: vcal RMS: 0.91 1.03 0.90 0.87 1.00 0.83 0.74 0.83 0.87 0.83 0.89 0.93 0.83 0.82 0.82 0.81
[15:23:06.761] <TB0> INFO: bits mean: 8.61 9.47 9.20 9.52 9.97 9.55 9.70 9.51 9.38 9.55 8.69 9.00 9.69 9.12 9.79 9.50
[15:23:06.761] <TB0> INFO: bits RMS: 2.48 2.71 2.80 2.74 2.64 2.67 2.66 2.75 2.77 2.66 2.98 2.63 2.57 2.89 2.49 2.51
[15:23:06.768] <TB0> INFO: ----------------------------------------------------------------------
[15:23:06.768] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:23:06.768] <TB0> INFO: ----------------------------------------------------------------------
[15:23:06.770] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:23:06.779] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:23:06.779] <TB0> INFO: run 1 of 1
[15:23:07.082] <TB0> INFO: Expecting 4160000 events.
[15:23:58.433] <TB0> INFO: 1161910 events read in total (50636ms).
[15:24:46.699] <TB0> INFO: 2307920 events read in total (98902ms).
[15:25:34.122] <TB0> INFO: 3438495 events read in total (146325ms).
[15:26:04.463] <TB0> INFO: 4160000 events read in total (176666ms).
[15:26:04.507] <TB0> INFO: Test took 177728ms.
[15:26:04.613] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:34.320] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[15:26:34.329] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:26:34.333] <TB0> INFO: run 1 of 1
[15:26:34.645] <TB0> INFO: Expecting 3723200 events.
[15:27:27.933] <TB0> INFO: 1176735 events read in total (52573ms).
[15:28:15.414] <TB0> INFO: 2333255 events read in total (100054ms).
[15:29:05.568] <TB0> INFO: 3476625 events read in total (150208ms).
[15:29:16.459] <TB0> INFO: 3723200 events read in total (161099ms).
[15:29:16.508] <TB0> INFO: Test took 162175ms.
[15:29:16.606] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:44.128] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 164 (-1/-1) hits flags = 528 (plus default)
[15:29:44.137] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:29:44.137] <TB0> INFO: run 1 of 1
[15:29:44.444] <TB0> INFO: Expecting 3432000 events.
[15:30:31.391] <TB0> INFO: 1232520 events read in total (46232ms).
[15:31:22.388] <TB0> INFO: 2437520 events read in total (97229ms).
[15:32:04.548] <TB0> INFO: 3432000 events read in total (139389ms).
[15:32:04.589] <TB0> INFO: Test took 140452ms.
[15:32:04.663] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:30.524] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 164 (-1/-1) hits flags = 528 (plus default)
[15:32:30.533] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:32:30.533] <TB0> INFO: run 1 of 1
[15:32:30.858] <TB0> INFO: Expecting 3432000 events.
[15:33:20.868] <TB0> INFO: 1231200 events read in total (49295ms).
[15:34:10.995] <TB0> INFO: 2434525 events read in total (99422ms).
[15:34:53.206] <TB0> INFO: 3432000 events read in total (141633ms).
[15:34:53.252] <TB0> INFO: Test took 142720ms.
[15:34:53.333] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:20.534] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 163 (-1/-1) hits flags = 528 (plus default)
[15:35:20.543] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:35:20.543] <TB0> INFO: run 1 of 1
[15:35:20.848] <TB0> INFO: Expecting 3411200 events.
[15:36:13.441] <TB0> INFO: 1234535 events read in total (51878ms).
[15:37:05.299] <TB0> INFO: 2440275 events read in total (103736ms).
[15:37:46.713] <TB0> INFO: 3411200 events read in total (145150ms).
[15:37:46.750] <TB0> INFO: Test took 146207ms.
[15:37:46.821] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:13.217] <TB0> INFO: PixTestTrim::trimBitTest() done
[15:38:13.219] <TB0> INFO: PixTestTrim::doTest() done, duration: 2736 seconds
[15:38:13.219] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:13.219] <TB0> INFO: Decoding statistics:
[15:38:13.219] <TB0> INFO: General information:
[15:38:13.219] <TB0> INFO: 16bit words read: 0
[15:38:13.219] <TB0> INFO: valid events total: 0
[15:38:13.219] <TB0> INFO: empty events: 0
[15:38:13.219] <TB0> INFO: valid events with pixels: 0
[15:38:13.219] <TB0> INFO: valid pixel hits: 0
[15:38:13.219] <TB0> INFO: Event errors: 0
[15:38:13.219] <TB0> INFO: start marker: 0
[15:38:13.219] <TB0> INFO: stop marker: 0
[15:38:13.219] <TB0> INFO: overflow: 0
[15:38:13.219] <TB0> INFO: invalid 5bit words: 0
[15:38:13.219] <TB0> INFO: invalid XOR eye diagram: 0
[15:38:13.219] <TB0> INFO: TBM errors: 0
[15:38:13.219] <TB0> INFO: flawed TBM headers: 0
[15:38:13.219] <TB0> INFO: flawed TBM trailers: 0
[15:38:13.219] <TB0> INFO: event ID mismatches: 0
[15:38:13.219] <TB0> INFO: ROC errors: 0
[15:38:13.219] <TB0> INFO: missing ROC header(s): 0
[15:38:13.219] <TB0> INFO: misplaced readback start: 0
[15:38:13.219] <TB0> INFO: Pixel decoding errors: 0
[15:38:13.219] <TB0> INFO: pixel data incomplete: 0
[15:38:13.219] <TB0> INFO: pixel address: 0
[15:38:13.219] <TB0> INFO: pulse height fill bit: 0
[15:38:13.219] <TB0> INFO: buffer corruption: 0
[15:38:14.142] <TB0> INFO: ######################################################################
[15:38:14.142] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:38:14.142] <TB0> INFO: ######################################################################
[15:38:14.478] <TB0> INFO: Expecting 41600 events.
[15:38:18.494] <TB0> INFO: 41600 events read in total (3302ms).
[15:38:18.495] <TB0> INFO: Test took 4352ms.
[15:38:18.503] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:19.186] <TB0> INFO: Expecting 41600 events.
[15:38:23.210] <TB0> INFO: 41600 events read in total (3309ms).
[15:38:23.210] <TB0> INFO: Test took 4351ms.
[15:38:23.217] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:23.658] <TB0> INFO: Expecting 41600 events.
[15:38:27.717] <TB0> INFO: 41600 events read in total (3344ms).
[15:38:27.718] <TB0> INFO: Test took 4383ms.
[15:38:27.725] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:28.164] <TB0> INFO: Expecting 2560 events.
[15:38:29.121] <TB0> INFO: 2560 events read in total (242ms).
[15:38:29.121] <TB0> INFO: Test took 1387ms.
[15:38:29.628] <TB0> INFO: Expecting 2560 events.
[15:38:30.584] <TB0> INFO: 2560 events read in total (241ms).
[15:38:30.584] <TB0> INFO: Test took 1463ms.
[15:38:31.092] <TB0> INFO: Expecting 2560 events.
[15:38:32.051] <TB0> INFO: 2560 events read in total (244ms).
[15:38:32.051] <TB0> INFO: Test took 1467ms.
[15:38:32.559] <TB0> INFO: Expecting 2560 events.
[15:38:33.520] <TB0> INFO: 2560 events read in total (246ms).
[15:38:33.521] <TB0> INFO: Test took 1470ms.
[15:38:34.029] <TB0> INFO: Expecting 2560 events.
[15:38:34.995] <TB0> INFO: 2560 events read in total (251ms).
[15:38:34.995] <TB0> INFO: Test took 1474ms.
[15:38:35.503] <TB0> INFO: Expecting 2560 events.
[15:38:36.485] <TB0> INFO: 2560 events read in total (263ms).
[15:38:36.485] <TB0> INFO: Test took 1489ms.
[15:38:36.993] <TB0> INFO: Expecting 2560 events.
[15:38:37.956] <TB0> INFO: 2560 events read in total (248ms).
[15:38:37.956] <TB0> INFO: Test took 1471ms.
[15:38:38.464] <TB0> INFO: Expecting 2560 events.
[15:38:39.437] <TB0> INFO: 2560 events read in total (258ms).
[15:38:39.437] <TB0> INFO: Test took 1480ms.
[15:38:39.945] <TB0> INFO: Expecting 2560 events.
[15:38:40.906] <TB0> INFO: 2560 events read in total (246ms).
[15:38:40.906] <TB0> INFO: Test took 1469ms.
[15:38:41.414] <TB0> INFO: Expecting 2560 events.
[15:38:42.376] <TB0> INFO: 2560 events read in total (247ms).
[15:38:42.376] <TB0> INFO: Test took 1470ms.
[15:38:42.884] <TB0> INFO: Expecting 2560 events.
[15:38:43.843] <TB0> INFO: 2560 events read in total (244ms).
[15:38:43.844] <TB0> INFO: Test took 1467ms.
[15:38:44.351] <TB0> INFO: Expecting 2560 events.
[15:38:45.313] <TB0> INFO: 2560 events read in total (247ms).
[15:38:45.314] <TB0> INFO: Test took 1470ms.
[15:38:45.821] <TB0> INFO: Expecting 2560 events.
[15:38:46.785] <TB0> INFO: 2560 events read in total (249ms).
[15:38:46.785] <TB0> INFO: Test took 1471ms.
[15:38:47.293] <TB0> INFO: Expecting 2560 events.
[15:38:48.255] <TB0> INFO: 2560 events read in total (247ms).
[15:38:48.255] <TB0> INFO: Test took 1469ms.
[15:38:48.762] <TB0> INFO: Expecting 2560 events.
[15:38:49.724] <TB0> INFO: 2560 events read in total (247ms).
[15:38:49.725] <TB0> INFO: Test took 1469ms.
[15:38:50.234] <TB0> INFO: Expecting 2560 events.
[15:38:51.197] <TB0> INFO: 2560 events read in total (248ms).
[15:38:51.197] <TB0> INFO: Test took 1472ms.
[15:38:51.200] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:38:51.707] <TB0> INFO: Expecting 655360 events.
[15:39:04.199] <TB0> INFO: 655360 events read in total (11777ms).
[15:39:04.208] <TB0> INFO: Expecting 655360 events.
[15:39:16.812] <TB0> INFO: 655360 events read in total (12014ms).
[15:39:16.825] <TB0> INFO: Expecting 655360 events.
[15:39:29.470] <TB0> INFO: 655360 events read in total (12051ms).
[15:39:29.485] <TB0> INFO: Expecting 655360 events.
[15:39:42.183] <TB0> INFO: 655360 events read in total (12103ms).
[15:39:42.207] <TB0> INFO: Expecting 655360 events.
[15:39:55.141] <TB0> INFO: 655360 events read in total (12362ms).
[15:39:55.164] <TB0> INFO: Expecting 655360 events.
[15:40:06.625] <TB0> INFO: 655360 events read in total (10887ms).
[15:40:06.652] <TB0> INFO: Expecting 655360 events.
[15:40:18.054] <TB0> INFO: 655360 events read in total (10824ms).
[15:40:18.084] <TB0> INFO: Expecting 655360 events.
[15:40:29.401] <TB0> INFO: 655360 events read in total (10732ms).
[15:40:29.433] <TB0> INFO: Expecting 655360 events.
[15:40:42.008] <TB0> INFO: 655360 events read in total (11990ms).
[15:40:42.047] <TB0> INFO: Expecting 655360 events.
[15:40:54.591] <TB0> INFO: 655360 events read in total (11984ms).
[15:40:54.634] <TB0> INFO: Expecting 655360 events.
[15:41:07.042] <TB0> INFO: 655360 events read in total (11855ms).
[15:41:07.086] <TB0> INFO: Expecting 655360 events.
[15:41:19.849] <TB0> INFO: 655360 events read in total (12203ms).
[15:41:19.897] <TB0> INFO: Expecting 655360 events.
[15:41:32.472] <TB0> INFO: 655360 events read in total (12021ms).
[15:41:32.540] <TB0> INFO: Expecting 655360 events.
[15:41:45.150] <TB0> INFO: 655360 events read in total (12079ms).
[15:41:45.208] <TB0> INFO: Expecting 655360 events.
[15:41:57.582] <TB0> INFO: 655360 events read in total (11821ms).
[15:41:57.641] <TB0> INFO: Expecting 655360 events.
[15:42:10.270] <TB0> INFO: 655360 events read in total (12075ms).
[15:42:10.342] <TB0> INFO: Test took 199142ms.
[15:42:10.428] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:42:10.735] <TB0> INFO: Expecting 655360 events.
[15:42:23.482] <TB0> INFO: 655360 events read in total (12032ms).
[15:42:23.492] <TB0> INFO: Expecting 655360 events.
[15:42:35.988] <TB0> INFO: 655360 events read in total (11896ms).
[15:42:36.000] <TB0> INFO: Expecting 655360 events.
[15:42:48.550] <TB0> INFO: 655360 events read in total (11967ms).
[15:42:48.565] <TB0> INFO: Expecting 655360 events.
[15:43:00.896] <TB0> INFO: 655360 events read in total (11741ms).
[15:43:00.916] <TB0> INFO: Expecting 655360 events.
[15:43:13.333] <TB0> INFO: 655360 events read in total (11831ms).
[15:43:13.356] <TB0> INFO: Expecting 655360 events.
[15:43:25.914] <TB0> INFO: 655360 events read in total (11969ms).
[15:43:25.940] <TB0> INFO: Expecting 655360 events.
[15:43:38.412] <TB0> INFO: 655360 events read in total (11889ms).
[15:43:38.444] <TB0> INFO: Expecting 655360 events.
[15:43:50.778] <TB0> INFO: 655360 events read in total (11759ms).
[15:43:50.810] <TB0> INFO: Expecting 655360 events.
[15:44:03.300] <TB0> INFO: 655360 events read in total (11908ms).
[15:44:03.338] <TB0> INFO: Expecting 655360 events.
[15:44:15.742] <TB0> INFO: 655360 events read in total (11830ms).
[15:44:15.783] <TB0> INFO: Expecting 655360 events.
[15:44:28.159] <TB0> INFO: 655360 events read in total (11813ms).
[15:44:28.202] <TB0> INFO: Expecting 655360 events.
[15:44:40.468] <TB0> INFO: 655360 events read in total (11697ms).
[15:44:40.520] <TB0> INFO: Expecting 655360 events.
[15:44:52.969] <TB0> INFO: 655360 events read in total (11885ms).
[15:44:53.024] <TB0> INFO: Expecting 655360 events.
[15:45:05.646] <TB0> INFO: 655360 events read in total (12065ms).
[15:45:05.702] <TB0> INFO: Expecting 655360 events.
[15:45:18.122] <TB0> INFO: 655360 events read in total (11868ms).
[15:45:18.179] <TB0> INFO: Expecting 655360 events.
[15:45:30.580] <TB0> INFO: 655360 events read in total (11838ms).
[15:45:30.641] <TB0> INFO: Test took 200213ms.
[15:45:30.829] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.837] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.850] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[15:45:30.858] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[15:45:30.865] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[15:45:30.872] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.879] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[15:45:30.885] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[15:45:30.892] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[15:45:30.899] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[15:45:30.906] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[15:45:30.913] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.920] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.927] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.934] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.941] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.948] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.954] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.961] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.968] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.975] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.982] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.989] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:30.996] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[15:45:31.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C0.dat
[15:45:31.027] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C1.dat
[15:45:31.028] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C2.dat
[15:45:31.028] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C3.dat
[15:45:31.028] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C4.dat
[15:45:31.028] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C5.dat
[15:45:31.028] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C6.dat
[15:45:31.028] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C7.dat
[15:45:31.028] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C8.dat
[15:45:31.051] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C9.dat
[15:45:31.051] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C10.dat
[15:45:31.051] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C11.dat
[15:45:31.051] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C12.dat
[15:45:31.052] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C13.dat
[15:45:31.052] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C14.dat
[15:45:31.052] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//dacParameters35_C15.dat
[15:45:31.364] <TB0> INFO: Expecting 41600 events.
[15:45:35.360] <TB0> INFO: 41600 events read in total (3281ms).
[15:45:35.360] <TB0> INFO: Test took 4305ms.
[15:45:35.992] <TB0> INFO: Expecting 41600 events.
[15:45:39.901] <TB0> INFO: 41600 events read in total (3194ms).
[15:45:39.902] <TB0> INFO: Test took 4219ms.
[15:45:40.566] <TB0> INFO: Expecting 41600 events.
[15:45:44.603] <TB0> INFO: 41600 events read in total (3322ms).
[15:45:44.604] <TB0> INFO: Test took 4373ms.
[15:45:44.928] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:45.059] <TB0> INFO: Expecting 2560 events.
[15:45:46.021] <TB0> INFO: 2560 events read in total (247ms).
[15:45:46.021] <TB0> INFO: Test took 1093ms.
[15:45:46.023] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:46.530] <TB0> INFO: Expecting 2560 events.
[15:45:47.531] <TB0> INFO: 2560 events read in total (286ms).
[15:45:47.532] <TB0> INFO: Test took 1509ms.
[15:45:47.534] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:48.040] <TB0> INFO: Expecting 2560 events.
[15:45:49.032] <TB0> INFO: 2560 events read in total (277ms).
[15:45:49.032] <TB0> INFO: Test took 1498ms.
[15:45:49.035] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:49.541] <TB0> INFO: Expecting 2560 events.
[15:45:50.518] <TB0> INFO: 2560 events read in total (262ms).
[15:45:50.518] <TB0> INFO: Test took 1483ms.
[15:45:50.521] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:51.026] <TB0> INFO: Expecting 2560 events.
[15:45:51.989] <TB0> INFO: 2560 events read in total (248ms).
[15:45:51.990] <TB0> INFO: Test took 1469ms.
[15:45:51.992] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:52.498] <TB0> INFO: Expecting 2560 events.
[15:45:53.461] <TB0> INFO: 2560 events read in total (248ms).
[15:45:53.461] <TB0> INFO: Test took 1469ms.
[15:45:53.463] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:53.970] <TB0> INFO: Expecting 2560 events.
[15:45:54.931] <TB0> INFO: 2560 events read in total (246ms).
[15:45:54.931] <TB0> INFO: Test took 1468ms.
[15:45:54.934] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:55.440] <TB0> INFO: Expecting 2560 events.
[15:45:56.397] <TB0> INFO: 2560 events read in total (243ms).
[15:45:56.397] <TB0> INFO: Test took 1463ms.
[15:45:56.400] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:56.906] <TB0> INFO: Expecting 2560 events.
[15:45:57.867] <TB0> INFO: 2560 events read in total (246ms).
[15:45:57.867] <TB0> INFO: Test took 1467ms.
[15:45:57.876] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:58.376] <TB0> INFO: Expecting 2560 events.
[15:45:59.382] <TB0> INFO: 2560 events read in total (291ms).
[15:45:59.382] <TB0> INFO: Test took 1506ms.
[15:45:59.385] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:45:59.891] <TB0> INFO: Expecting 2560 events.
[15:46:00.851] <TB0> INFO: 2560 events read in total (247ms).
[15:46:00.852] <TB0> INFO: Test took 1467ms.
[15:46:00.853] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:01.361] <TB0> INFO: Expecting 2560 events.
[15:46:02.324] <TB0> INFO: 2560 events read in total (248ms).
[15:46:02.324] <TB0> INFO: Test took 1471ms.
[15:46:02.327] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:02.832] <TB0> INFO: Expecting 2560 events.
[15:46:03.810] <TB0> INFO: 2560 events read in total (263ms).
[15:46:03.810] <TB0> INFO: Test took 1483ms.
[15:46:03.813] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:04.318] <TB0> INFO: Expecting 2560 events.
[15:46:05.280] <TB0> INFO: 2560 events read in total (247ms).
[15:46:05.280] <TB0> INFO: Test took 1468ms.
[15:46:05.282] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:05.788] <TB0> INFO: Expecting 2560 events.
[15:46:06.749] <TB0> INFO: 2560 events read in total (246ms).
[15:46:06.749] <TB0> INFO: Test took 1467ms.
[15:46:06.752] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:07.258] <TB0> INFO: Expecting 2560 events.
[15:46:08.220] <TB0> INFO: 2560 events read in total (247ms).
[15:46:08.220] <TB0> INFO: Test took 1468ms.
[15:46:08.223] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:08.729] <TB0> INFO: Expecting 2560 events.
[15:46:09.692] <TB0> INFO: 2560 events read in total (248ms).
[15:46:09.692] <TB0> INFO: Test took 1469ms.
[15:46:09.695] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:10.201] <TB0> INFO: Expecting 2560 events.
[15:46:11.164] <TB0> INFO: 2560 events read in total (248ms).
[15:46:11.164] <TB0> INFO: Test took 1469ms.
[15:46:11.167] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:11.673] <TB0> INFO: Expecting 2560 events.
[15:46:12.632] <TB0> INFO: 2560 events read in total (244ms).
[15:46:12.633] <TB0> INFO: Test took 1466ms.
[15:46:12.635] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:13.141] <TB0> INFO: Expecting 2560 events.
[15:46:14.105] <TB0> INFO: 2560 events read in total (249ms).
[15:46:14.105] <TB0> INFO: Test took 1470ms.
[15:46:14.108] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:14.614] <TB0> INFO: Expecting 2560 events.
[15:46:15.571] <TB0> INFO: 2560 events read in total (242ms).
[15:46:15.572] <TB0> INFO: Test took 1465ms.
[15:46:15.574] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:16.080] <TB0> INFO: Expecting 2560 events.
[15:46:17.036] <TB0> INFO: 2560 events read in total (242ms).
[15:46:17.036] <TB0> INFO: Test took 1463ms.
[15:46:17.038] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:17.544] <TB0> INFO: Expecting 2560 events.
[15:46:18.501] <TB0> INFO: 2560 events read in total (242ms).
[15:46:18.501] <TB0> INFO: Test took 1463ms.
[15:46:18.503] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:19.009] <TB0> INFO: Expecting 2560 events.
[15:46:19.965] <TB0> INFO: 2560 events read in total (241ms).
[15:46:19.966] <TB0> INFO: Test took 1463ms.
[15:46:19.967] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:20.474] <TB0> INFO: Expecting 2560 events.
[15:46:21.430] <TB0> INFO: 2560 events read in total (242ms).
[15:46:21.430] <TB0> INFO: Test took 1463ms.
[15:46:21.432] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:21.938] <TB0> INFO: Expecting 2560 events.
[15:46:22.894] <TB0> INFO: 2560 events read in total (241ms).
[15:46:22.894] <TB0> INFO: Test took 1462ms.
[15:46:22.897] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:23.403] <TB0> INFO: Expecting 2560 events.
[15:46:24.359] <TB0> INFO: 2560 events read in total (242ms).
[15:46:24.359] <TB0> INFO: Test took 1462ms.
[15:46:24.362] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:24.867] <TB0> INFO: Expecting 2560 events.
[15:46:25.823] <TB0> INFO: 2560 events read in total (241ms).
[15:46:25.824] <TB0> INFO: Test took 1462ms.
[15:46:25.825] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:26.332] <TB0> INFO: Expecting 2560 events.
[15:46:27.288] <TB0> INFO: 2560 events read in total (241ms).
[15:46:27.288] <TB0> INFO: Test took 1463ms.
[15:46:27.290] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:27.797] <TB0> INFO: Expecting 2560 events.
[15:46:28.753] <TB0> INFO: 2560 events read in total (241ms).
[15:46:28.753] <TB0> INFO: Test took 1463ms.
[15:46:28.755] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:29.261] <TB0> INFO: Expecting 2560 events.
[15:46:30.217] <TB0> INFO: 2560 events read in total (241ms).
[15:46:30.218] <TB0> INFO: Test took 1463ms.
[15:46:30.219] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:46:30.726] <TB0> INFO: Expecting 2560 events.
[15:46:31.682] <TB0> INFO: 2560 events read in total (242ms).
[15:46:31.682] <TB0> INFO: Test took 1463ms.
[15:46:32.373] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 498 seconds
[15:46:32.373] <TB0> INFO: PH scale (per ROC): 80 75 85 79 93 82 83 89 83 92 86 79 89 91 85 96
[15:46:32.373] <TB0> INFO: PH offset (per ROC): 165 176 176 155 144 154 136 156 145 153 154 177 133 148 141 161
[15:46:32.378] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:32.378] <TB0> INFO: Decoding statistics:
[15:46:32.378] <TB0> INFO: General information:
[15:46:32.378] <TB0> INFO: 16bit words read: 66444
[15:46:32.378] <TB0> INFO: valid events total: 5120
[15:46:32.378] <TB0> INFO: empty events: 2618
[15:46:32.378] <TB0> INFO: valid events with pixels: 2502
[15:46:32.378] <TB0> INFO: valid pixel hits: 2502
[15:46:32.378] <TB0> INFO: Event errors: 0
[15:46:32.378] <TB0> INFO: start marker: 0
[15:46:32.378] <TB0> INFO: stop marker: 0
[15:46:32.378] <TB0> INFO: overflow: 0
[15:46:32.378] <TB0> INFO: invalid 5bit words: 0
[15:46:32.378] <TB0> INFO: invalid XOR eye diagram: 0
[15:46:32.378] <TB0> INFO: TBM errors: 0
[15:46:32.378] <TB0> INFO: flawed TBM headers: 0
[15:46:32.378] <TB0> INFO: flawed TBM trailers: 0
[15:46:32.378] <TB0> INFO: event ID mismatches: 0
[15:46:32.378] <TB0> INFO: ROC errors: 0
[15:46:32.378] <TB0> INFO: missing ROC header(s): 0
[15:46:32.378] <TB0> INFO: misplaced readback start: 0
[15:46:32.378] <TB0> INFO: Pixel decoding errors: 0
[15:46:32.378] <TB0> INFO: pixel data incomplete: 0
[15:46:32.378] <TB0> INFO: pixel address: 0
[15:46:32.378] <TB0> INFO: pulse height fill bit: 0
[15:46:32.378] <TB0> INFO: buffer corruption: 0
[15:46:32.580] <TB0> INFO: ######################################################################
[15:46:32.580] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[15:46:32.580] <TB0> INFO: ######################################################################
[15:46:32.590] <TB0> INFO: scanning low vcal = 10
[15:46:32.898] <TB0> INFO: Expecting 41600 events.
[15:46:36.638] <TB0> INFO: 41600 events read in total (3025ms).
[15:46:36.639] <TB0> INFO: Test took 4049ms.
[15:46:36.640] <TB0> INFO: scanning low vcal = 20
[15:46:37.146] <TB0> INFO: Expecting 41600 events.
[15:46:40.841] <TB0> INFO: 41600 events read in total (2980ms).
[15:46:40.842] <TB0> INFO: Test took 4202ms.
[15:46:40.848] <TB0> INFO: scanning low vcal = 30
[15:46:41.349] <TB0> INFO: Expecting 41600 events.
[15:46:45.059] <TB0> INFO: 41600 events read in total (2995ms).
[15:46:45.060] <TB0> INFO: Test took 4212ms.
[15:46:45.062] <TB0> INFO: scanning low vcal = 40
[15:46:45.561] <TB0> INFO: Expecting 41600 events.
[15:46:49.729] <TB0> INFO: 41600 events read in total (3453ms).
[15:46:49.730] <TB0> INFO: Test took 4668ms.
[15:46:49.734] <TB0> INFO: scanning low vcal = 50
[15:46:50.174] <TB0> INFO: Expecting 41600 events.
[15:46:54.522] <TB0> INFO: 41600 events read in total (3634ms).
[15:46:54.523] <TB0> INFO: Test took 4789ms.
[15:46:54.526] <TB0> INFO: scanning low vcal = 60
[15:46:54.967] <TB0> INFO: Expecting 41600 events.
[15:46:59.299] <TB0> INFO: 41600 events read in total (3618ms).
[15:46:59.300] <TB0> INFO: Test took 4774ms.
[15:46:59.303] <TB0> INFO: scanning low vcal = 70
[15:46:59.752] <TB0> INFO: Expecting 41600 events.
[15:47:04.091] <TB0> INFO: 41600 events read in total (3624ms).
[15:47:04.091] <TB0> INFO: Test took 4788ms.
[15:47:04.094] <TB0> INFO: scanning low vcal = 80
[15:47:04.528] <TB0> INFO: Expecting 41600 events.
[15:47:08.932] <TB0> INFO: 41600 events read in total (3690ms).
[15:47:08.933] <TB0> INFO: Test took 4839ms.
[15:47:08.935] <TB0> INFO: scanning low vcal = 90
[15:47:09.382] <TB0> INFO: Expecting 41600 events.
[15:47:13.732] <TB0> INFO: 41600 events read in total (3636ms).
[15:47:13.733] <TB0> INFO: Test took 4798ms.
[15:47:13.737] <TB0> INFO: scanning low vcal = 100
[15:47:14.176] <TB0> INFO: Expecting 41600 events.
[15:47:18.705] <TB0> INFO: 41600 events read in total (3814ms).
[15:47:18.706] <TB0> INFO: Test took 4969ms.
[15:47:18.709] <TB0> INFO: scanning low vcal = 110
[15:47:19.149] <TB0> INFO: Expecting 41600 events.
[15:47:23.589] <TB0> INFO: 41600 events read in total (3725ms).
[15:47:23.590] <TB0> INFO: Test took 4881ms.
[15:47:23.592] <TB0> INFO: scanning low vcal = 120
[15:47:24.032] <TB0> INFO: Expecting 41600 events.
[15:47:28.464] <TB0> INFO: 41600 events read in total (3717ms).
[15:47:28.464] <TB0> INFO: Test took 4872ms.
[15:47:28.467] <TB0> INFO: scanning low vcal = 130
[15:47:28.904] <TB0> INFO: Expecting 41600 events.
[15:47:33.290] <TB0> INFO: 41600 events read in total (3671ms).
[15:47:33.291] <TB0> INFO: Test took 4824ms.
[15:47:33.293] <TB0> INFO: scanning low vcal = 140
[15:47:33.740] <TB0> INFO: Expecting 41600 events.
[15:47:38.139] <TB0> INFO: 41600 events read in total (3684ms).
[15:47:38.140] <TB0> INFO: Test took 4847ms.
[15:47:38.143] <TB0> INFO: scanning low vcal = 150
[15:47:38.592] <TB0> INFO: Expecting 41600 events.
[15:47:43.052] <TB0> INFO: 41600 events read in total (3745ms).
[15:47:43.054] <TB0> INFO: Test took 4911ms.
[15:47:43.058] <TB0> INFO: scanning low vcal = 160
[15:47:43.495] <TB0> INFO: Expecting 41600 events.
[15:47:47.951] <TB0> INFO: 41600 events read in total (3741ms).
[15:47:47.952] <TB0> INFO: Test took 4894ms.
[15:47:47.955] <TB0> INFO: scanning low vcal = 170
[15:47:48.400] <TB0> INFO: Expecting 41600 events.
[15:47:52.861] <TB0> INFO: 41600 events read in total (3747ms).
[15:47:52.862] <TB0> INFO: Test took 4907ms.
[15:47:52.869] <TB0> INFO: scanning low vcal = 180
[15:47:53.294] <TB0> INFO: Expecting 41600 events.
[15:47:57.690] <TB0> INFO: 41600 events read in total (3681ms).
[15:47:57.691] <TB0> INFO: Test took 4822ms.
[15:47:57.694] <TB0> INFO: scanning low vcal = 190
[15:47:58.135] <TB0> INFO: Expecting 41600 events.
[15:48:02.502] <TB0> INFO: 41600 events read in total (3652ms).
[15:48:02.503] <TB0> INFO: Test took 4809ms.
[15:48:02.505] <TB0> INFO: scanning low vcal = 200
[15:48:02.946] <TB0> INFO: Expecting 41600 events.
[15:48:07.417] <TB0> INFO: 41600 events read in total (3757ms).
[15:48:07.417] <TB0> INFO: Test took 4912ms.
[15:48:07.420] <TB0> INFO: scanning low vcal = 210
[15:48:07.855] <TB0> INFO: Expecting 41600 events.
[15:48:12.238] <TB0> INFO: 41600 events read in total (3668ms).
[15:48:12.239] <TB0> INFO: Test took 4819ms.
[15:48:12.241] <TB0> INFO: scanning low vcal = 220
[15:48:12.686] <TB0> INFO: Expecting 41600 events.
[15:48:17.120] <TB0> INFO: 41600 events read in total (3719ms).
[15:48:17.120] <TB0> INFO: Test took 4879ms.
[15:48:17.123] <TB0> INFO: scanning low vcal = 230
[15:48:17.568] <TB0> INFO: Expecting 41600 events.
[15:48:22.097] <TB0> INFO: 41600 events read in total (3814ms).
[15:48:22.097] <TB0> INFO: Test took 4974ms.
[15:48:22.100] <TB0> INFO: scanning low vcal = 240
[15:48:22.544] <TB0> INFO: Expecting 41600 events.
[15:48:26.983] <TB0> INFO: 41600 events read in total (3724ms).
[15:48:26.984] <TB0> INFO: Test took 4884ms.
[15:48:26.986] <TB0> INFO: scanning low vcal = 250
[15:48:27.427] <TB0> INFO: Expecting 41600 events.
[15:48:31.824] <TB0> INFO: 41600 events read in total (3682ms).
[15:48:31.824] <TB0> INFO: Test took 4838ms.
[15:48:31.828] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[15:48:32.270] <TB0> INFO: Expecting 41600 events.
[15:48:36.667] <TB0> INFO: 41600 events read in total (3682ms).
[15:48:36.668] <TB0> INFO: Test took 4840ms.
[15:48:36.670] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[15:48:37.103] <TB0> INFO: Expecting 41600 events.
[15:48:41.481] <TB0> INFO: 41600 events read in total (3663ms).
[15:48:41.482] <TB0> INFO: Test took 4812ms.
[15:48:41.485] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[15:48:41.927] <TB0> INFO: Expecting 41600 events.
[15:48:46.371] <TB0> INFO: 41600 events read in total (3729ms).
[15:48:46.372] <TB0> INFO: Test took 4887ms.
[15:48:46.375] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[15:48:46.817] <TB0> INFO: Expecting 41600 events.
[15:48:51.246] <TB0> INFO: 41600 events read in total (3714ms).
[15:48:51.247] <TB0> INFO: Test took 4871ms.
[15:48:51.250] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[15:48:51.691] <TB0> INFO: Expecting 41600 events.
[15:48:56.156] <TB0> INFO: 41600 events read in total (3750ms).
[15:48:56.157] <TB0> INFO: Test took 4907ms.
[15:48:56.659] <TB0> INFO: PixTestGainPedestal::measure() done
[15:49:35.002] <TB0> INFO: PixTestGainPedestal::fit() done
[15:49:35.002] <TB0> INFO: non-linearity mean: 0.952 0.955 0.958 0.955 0.953 0.946 0.948 0.952 0.955 0.953 0.952 0.958 0.950 0.952 0.951 0.953
[15:49:35.002] <TB0> INFO: non-linearity RMS: 0.007 0.005 0.005 0.005 0.005 0.006 0.006 0.007 0.006 0.005 0.006 0.006 0.005 0.005 0.006 0.005
[15:49:35.002] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[15:49:35.022] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[15:49:35.049] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[15:49:35.077] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[15:49:35.104] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[15:49:35.131] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[15:49:35.158] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[15:49:35.183] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[15:49:35.206] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[15:49:35.231] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[15:49:35.251] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[15:49:35.270] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[15:49:35.289] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[15:49:35.315] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[15:49:35.336] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[15:49:35.356] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[15:49:35.377] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 182 seconds
[15:49:35.377] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:35.377] <TB0> INFO: Decoding statistics:
[15:49:35.377] <TB0> INFO: General information:
[15:49:35.377] <TB0> INFO: 16bit words read: 2329580
[15:49:35.377] <TB0> INFO: valid events total: 83200
[15:49:35.377] <TB0> INFO: empty events: 0
[15:49:35.377] <TB0> INFO: valid events with pixels: 83200
[15:49:35.377] <TB0> INFO: valid pixel hits: 665590
[15:49:35.377] <TB0> INFO: Event errors: 0
[15:49:35.377] <TB0> INFO: start marker: 0
[15:49:35.377] <TB0> INFO: stop marker: 0
[15:49:35.377] <TB0> INFO: overflow: 0
[15:49:35.377] <TB0> INFO: invalid 5bit words: 0
[15:49:35.377] <TB0> INFO: invalid XOR eye diagram: 0
[15:49:35.377] <TB0> INFO: TBM errors: 0
[15:49:35.377] <TB0> INFO: flawed TBM headers: 0
[15:49:35.377] <TB0> INFO: flawed TBM trailers: 0
[15:49:35.377] <TB0> INFO: event ID mismatches: 0
[15:49:35.377] <TB0> INFO: ROC errors: 0
[15:49:35.377] <TB0> INFO: missing ROC header(s): 0
[15:49:35.377] <TB0> INFO: misplaced readback start: 0
[15:49:35.377] <TB0> INFO: Pixel decoding errors: 0
[15:49:35.377] <TB0> INFO: pixel data incomplete: 0
[15:49:35.377] <TB0> INFO: pixel address: 0
[15:49:35.377] <TB0> INFO: pulse height fill bit: 0
[15:49:35.377] <TB0> INFO: buffer corruption: 0
[15:49:35.384] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C15.dat
[15:49:35.385] <TB0> INFO: ######################################################################
[15:49:35.385] <TB0> INFO: PixTestReadback::doTest()
[15:49:35.385] <TB0> INFO: ######################################################################
[15:49:35.386] <TB0> INFO: PixTestReadback::RES sent once
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C0.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C1.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C2.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C3.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C4.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C5.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C6.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C7.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C8.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C9.dat
[15:49:46.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C10.dat
[15:49:46.545] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C11.dat
[15:49:46.545] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C12.dat
[15:49:46.545] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C13.dat
[15:49:46.545] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C14.dat
[15:49:46.545] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C15.dat
[15:49:46.575] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[15:49:46.575] <TB0> INFO: PixTestReadback::RES sent once
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C0.dat
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C1.dat
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C2.dat
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C3.dat
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C4.dat
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C5.dat
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C6.dat
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C7.dat
[15:49:57.716] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C8.dat
[15:49:57.717] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C9.dat
[15:49:57.717] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C10.dat
[15:49:57.717] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C11.dat
[15:49:57.717] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C12.dat
[15:49:57.717] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C13.dat
[15:49:57.717] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C14.dat
[15:49:57.717] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C15.dat
[15:49:57.747] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[15:49:57.748] <TB0> INFO: PixTestReadback::RES sent once
[15:50:06.326] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[15:50:06.326] <TB0> INFO: Vbg will be calibrated using Vd calibration
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.1calibrated Vbg = 1.20487 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.4calibrated Vbg = 1.20325 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 160.7calibrated Vbg = 1.21276 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.8calibrated Vbg = 1.21476 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 142.6calibrated Vbg = 1.22012 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.6calibrated Vbg = 1.22178 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148.8calibrated Vbg = 1.22487 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.5calibrated Vbg = 1.22013 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.5calibrated Vbg = 1.22564 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 144.5calibrated Vbg = 1.22434 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151.7calibrated Vbg = 1.22776 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 164.5calibrated Vbg = 1.22254 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 144.6calibrated Vbg = 1.20472 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 150.7calibrated Vbg = 1.2046 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 149.4calibrated Vbg = 1.20699 :::*/*/*/*/
[15:50:06.326] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157calibrated Vbg = 1.20736 :::*/*/*/*/
[15:50:06.330] <TB0> INFO: PixTestReadback::RES sent once
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C0.dat
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C1.dat
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C2.dat
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C3.dat
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C4.dat
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C5.dat
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C6.dat
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C7.dat
[15:53:00.289] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C8.dat
[15:53:00.290] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C9.dat
[15:53:00.290] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C10.dat
[15:53:00.290] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C11.dat
[15:53:00.290] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C12.dat
[15:53:00.290] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C13.dat
[15:53:00.290] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C14.dat
[15:53:00.290] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4076_FullQualification_2015-12-08_13h26m_1449577585//002_FulltestPxar_m20//readbackCal_C15.dat
[15:53:00.323] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[15:53:00.324] <TB0> INFO: PixTestReadback::doTest() done
[15:53:00.325] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:00.325] <TB0> INFO: Decoding statistics:
[15:53:00.325] <TB0> INFO: General information:
[15:53:00.325] <TB0> INFO: 16bit words read: 768
[15:53:00.325] <TB0> INFO: valid events total: 64
[15:53:00.325] <TB0> INFO: empty events: 64
[15:53:00.325] <TB0> INFO: valid events with pixels: 0
[15:53:00.325] <TB0> INFO: valid pixel hits: 0
[15:53:00.325] <TB0> INFO: Event errors: 0
[15:53:00.325] <TB0> INFO: start marker: 0
[15:53:00.325] <TB0> INFO: stop marker: 0
[15:53:00.325] <TB0> INFO: overflow: 0
[15:53:00.325] <TB0> INFO: invalid 5bit words: 0
[15:53:00.325] <TB0> INFO: invalid XOR eye diagram: 0
[15:53:00.325] <TB0> INFO: TBM errors: 0
[15:53:00.325] <TB0> INFO: flawed TBM headers: 0
[15:53:00.325] <TB0> INFO: flawed TBM trailers: 0
[15:53:00.325] <TB0> INFO: event ID mismatches: 0
[15:53:00.325] <TB0> INFO: ROC errors: 0
[15:53:00.325] <TB0> INFO: missing ROC header(s): 0
[15:53:00.325] <TB0> INFO: misplaced readback start: 0
[15:53:00.325] <TB0> INFO: Pixel decoding errors: 0
[15:53:00.325] <TB0> INFO: pixel data incomplete: 0
[15:53:00.325] <TB0> INFO: pixel address: 0
[15:53:00.325] <TB0> INFO: pulse height fill bit: 0
[15:53:00.325] <TB0> INFO: buffer corruption: 0
[15:53:00.341] <TB0> INFO: Decoding statistics:
[15:53:00.341] <TB0> INFO: General information:
[15:53:00.341] <TB0> INFO: 16bit words read: 2396792
[15:53:00.341] <TB0> INFO: valid events total: 88384
[15:53:00.341] <TB0> INFO: empty events: 2682
[15:53:00.341] <TB0> INFO: valid events with pixels: 85702
[15:53:00.341] <TB0> INFO: valid pixel hits: 668092
[15:53:00.341] <TB0> INFO: Event errors: 0
[15:53:00.341] <TB0> INFO: start marker: 0
[15:53:00.341] <TB0> INFO: stop marker: 0
[15:53:00.341] <TB0> INFO: overflow: 0
[15:53:00.341] <TB0> INFO: invalid 5bit words: 0
[15:53:00.341] <TB0> INFO: invalid XOR eye diagram: 0
[15:53:00.341] <TB0> INFO: TBM errors: 0
[15:53:00.341] <TB0> INFO: flawed TBM headers: 0
[15:53:00.341] <TB0> INFO: flawed TBM trailers: 0
[15:53:00.341] <TB0> INFO: event ID mismatches: 0
[15:53:00.341] <TB0> INFO: ROC errors: 0
[15:53:00.341] <TB0> INFO: missing ROC header(s): 0
[15:53:00.341] <TB0> INFO: misplaced readback start: 0
[15:53:00.341] <TB0> INFO: Pixel decoding errors: 0
[15:53:00.341] <TB0> INFO: pixel data incomplete: 0
[15:53:00.341] <TB0> INFO: pixel address: 0
[15:53:00.341] <TB0> INFO: pulse height fill bit: 0
[15:53:00.341] <TB0> INFO: buffer corruption: 0
[15:53:00.341] <TB0> INFO: enter test to run
[15:53:00.341] <TB0> INFO: test: exit no parameter change
[15:53:00.557] <TB0> QUIET: Connection to board 73 closed.
[15:53:00.637] <TB0> INFO: pXar: this is the end, my friend