[14:59:20.029] <TB0> INFO: *** Welcome to pxar ***
[14:59:20.029] <TB0> INFO: *** Today: 2016/02/10
[14:59:20.507] <TB0> INFO: *** Version: 9751-dirty
[14:59:20.508] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C15.dat
[14:59:20.508] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:59:20.508] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//defaultMaskFile.dat
[14:59:20.508] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters_C15.dat
[14:59:20.576] <TB0> INFO: clk: 4
[14:59:20.576] <TB0> INFO: ctr: 4
[14:59:20.576] <TB0> INFO: sda: 19
[14:59:20.576] <TB0> INFO: tin: 9
[14:59:20.576] <TB0> INFO: level: 15
[14:59:20.576] <TB0> INFO: triggerdelay: 0
[14:59:20.576] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[14:59:20.576] <TB0> INFO: Log level: INFO
[14:59:20.583] <TB0> INFO: Found DTB DTB_WS6AYH
[14:59:20.592] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[14:59:20.594] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[14:59:20.596] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[14:59:22.119] <TB0> INFO: DUT info:
[14:59:22.119] <TB0> INFO: The DUT currently contains the following objects:
[14:59:22.119] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[14:59:22.119] <TB0> INFO: TBM Core alpha (0): 7 registers set
[14:59:22.119] <TB0> INFO: TBM Core beta (1): 7 registers set
[14:59:22.119] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:59:22.119] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.119] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:22.520] <TB0> INFO: enter 'restricted' command line mode
[14:59:22.520] <TB0> INFO: enter test to run
[14:59:22.520] <TB0> INFO: test: pretest no parameter change
[14:59:22.520] <TB0> INFO: running: pretest
[14:59:22.524] <TB0> INFO: ----------------------------------------------------------------------
[14:59:22.524] <TB0> INFO: PixTestPretest::programROC()
[14:59:22.524] <TB0> INFO: ----------------------------------------------------------------------
[14:59:40.540] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:59:40.540] <TB0> INFO: IA differences per ROC: 19.3 20.1 16.9 18.5 17.7 17.7 18.5 17.7 20.9 19.3 18.5 19.3 17.7 20.9 19.3 19.3
[14:59:40.657] <TB0> INFO: enter test to run
[14:59:40.657] <TB0> INFO: test: pretest no parameter change
[14:59:40.657] <TB0> INFO: running: pretest
[14:59:40.658] <TB0> INFO: ----------------------------------------------------------------------
[14:59:40.658] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:59:40.658] <TB0> INFO: ----------------------------------------------------------------------
[14:59:46.545] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[14:59:46.545] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 20.1 20.1 18.5 18.5 18.5 19.3 19.3 18.5 19.3 19.3 18.5 19.3
[14:59:46.579] <TB0> INFO: enter test to run
[14:59:46.579] <TB0> INFO: test: pretest no parameter change
[14:59:46.579] <TB0> INFO: running: pretest
[14:59:46.579] <TB0> INFO: ----------------------------------------------------------------------
[14:59:46.579] <TB0> INFO: PixTestPretest::findTiming()
[14:59:46.579] <TB0> INFO: ----------------------------------------------------------------------
[14:59:46.579] <TB0> INFO: PixTestCmd::init()
[14:59:47.537] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[15:01:16.511] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[15:01:16.511] <TB0> INFO: (success/tries = 100/100), width = 2
[15:01:16.512] <TB0> INFO: enter test to run
[15:01:16.512] <TB0> INFO: test: pretest no parameter change
[15:01:16.512] <TB0> INFO: running: pretest
[15:01:16.513] <TB0> INFO: ----------------------------------------------------------------------
[15:01:16.513] <TB0> INFO: PixTestPretest::findWorkingPixel()
[15:01:16.513] <TB0> INFO: ----------------------------------------------------------------------
[15:01:16.603] <TB0> INFO: Expecting 231680 events.
[15:01:22.378] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[15:01:22.464] <TB0> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[15:01:26.841] <TB0> INFO: 231680 events read in total (9682ms).
[15:01:26.845] <TB0> INFO: Test took 10330ms.
[15:01:27.072] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:01:27.128] <TB0> INFO: enter test to run
[15:01:27.128] <TB0> INFO: test: pretest no parameter change
[15:01:27.128] <TB0> INFO: running: pretest
[15:01:27.129] <TB0> INFO: ----------------------------------------------------------------------
[15:01:27.129] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[15:01:27.129] <TB0> INFO: ----------------------------------------------------------------------
[15:01:27.220] <TB0> INFO: Expecting 231680 events.
[15:01:37.507] <TB0> INFO: 231680 events read in total (9730ms).
[15:01:37.511] <TB0> INFO: Test took 10379ms.
[15:01:37.760] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[15:01:37.760] <TB0> INFO: CalDel: 125 113 141 161 132 145 140 137 137 146 145 144 156 155 134 144
[15:01:37.760] <TB0> INFO: VthrComp: 51 51 51 54 51 51 51 51 51 51 51 51 51 51 51 51
[15:01:37.797] <TB0> INFO: enter test to run
[15:01:37.798] <TB0> INFO: test: pretest no parameter change
[15:01:37.798] <TB0> INFO: running: pretest
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C0.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C1.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C2.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C3.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C4.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C5.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C6.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C7.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C8.dat
[15:01:37.798] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C9.dat
[15:01:37.799] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C10.dat
[15:01:37.799] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C11.dat
[15:01:37.799] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C12.dat
[15:01:37.799] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C13.dat
[15:01:37.799] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C14.dat
[15:01:37.799] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters_C15.dat
[15:01:37.799] <TB0> INFO: enter test to run
[15:01:37.799] <TB0> INFO: test: fulltest no parameter change
[15:01:37.799] <TB0> INFO: running: fulltest
[15:01:37.799] <TB0> INFO: ######################################################################
[15:01:37.799] <TB0> INFO: PixTestFullTest::doTest()
[15:01:37.799] <TB0> INFO: ######################################################################
[15:01:37.800] <TB0> INFO: ######################################################################
[15:01:37.800] <TB0> INFO: PixTestAlive::doTest()
[15:01:37.800] <TB0> INFO: ######################################################################
[15:01:37.801] <TB0> INFO: ----------------------------------------------------------------------
[15:01:37.801] <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)
[15:01:37.801] <TB0> INFO: ----------------------------------------------------------------------
[15:01:38.062] <TB0> INFO: Expecting 41600 events.
[15:01:41.922] <TB0> INFO: 41600 events read in total (3304ms).
[15:01:41.923] <TB0> INFO: Test took 4120ms.
[15:01:42.153] <TB0> INFO: PixTestAlive::aliveTest() done
[15:01:42.153] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[15:01:42.155] <TB0> INFO: ----------------------------------------------------------------------
[15:01:42.155] <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)
[15:01:42.155] <TB0> INFO: ----------------------------------------------------------------------
[15:01:42.427] <TB0> INFO: Expecting 41600 events.
[15:01:45.403] <TB0> INFO: 41600 events read in total (2420ms).
[15:01:45.403] <TB0> INFO: Test took 3247ms.
[15:01:45.404] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:01:45.652] <TB0> INFO: PixTestAlive::maskTest() done
[15:01:45.652] <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
[15:01:45.653] <TB0> INFO: ----------------------------------------------------------------------
[15:01:45.653] <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)
[15:01:45.653] <TB0> INFO: ----------------------------------------------------------------------
[15:01:45.938] <TB0> INFO: Expecting 41600 events.
[15:01:49.820] <TB0> INFO: 41600 events read in total (3326ms).
[15:01:49.821] <TB0> INFO: Test took 4166ms.
[15:01:50.050] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[15:01:50.050] <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
[15:01:50.050] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:01:50.050] <TB0> INFO: Decoding statistics:
[15:01:50.050] <TB0> INFO: General information:
[15:01:50.050] <TB0> INFO: 16bit words read: 0
[15:01:50.050] <TB0> INFO: valid events total: 0
[15:01:50.050] <TB0> INFO: empty events: 0
[15:01:50.050] <TB0> INFO: valid events with pixels: 0
[15:01:50.050] <TB0> INFO: valid pixel hits: 0
[15:01:50.050] <TB0> INFO: Event errors: 0
[15:01:50.050] <TB0> INFO: start marker: 0
[15:01:50.050] <TB0> INFO: stop marker: 0
[15:01:50.050] <TB0> INFO: overflow: 0
[15:01:50.050] <TB0> INFO: invalid 5bit words: 0
[15:01:50.050] <TB0> INFO: invalid XOR eye diagram: 0
[15:01:50.050] <TB0> INFO: frame (failed synchr.): 0
[15:01:50.050] <TB0> INFO: idle data (no TBM trl): 0
[15:01:50.050] <TB0> INFO: no data (only TBM hdr): 0
[15:01:50.050] <TB0> INFO: TBM errors: 0
[15:01:50.050] <TB0> INFO: flawed TBM headers: 0
[15:01:50.050] <TB0> INFO: flawed TBM trailers: 0
[15:01:50.050] <TB0> INFO: event ID mismatches: 0
[15:01:50.050] <TB0> INFO: ROC errors: 0
[15:01:50.050] <TB0> INFO: missing ROC header(s): 0
[15:01:50.050] <TB0> INFO: misplaced readback start: 0
[15:01:50.050] <TB0> INFO: Pixel decoding errors: 0
[15:01:50.050] <TB0> INFO: pixel data incomplete: 0
[15:01:50.051] <TB0> INFO: pixel address: 0
[15:01:50.051] <TB0> INFO: pulse height fill bit: 0
[15:01:50.051] <TB0> INFO: buffer corruption: 0
[15:01:50.062] <TB0> INFO: ######################################################################
[15:01:50.062] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:01:50.062] <TB0> INFO: ######################################################################
[15:01:50.064] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:01:50.077] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:01:50.077] <TB0> INFO: run 1 of 1
[15:01:50.364] <TB0> INFO: Expecting 3120000 events.
[15:02:31.614] <TB0> INFO: 882455 events read in total (40694ms).
[15:03:11.996] <TB0> INFO: 1753335 events read in total (81077ms).
[15:03:52.914] <TB0> INFO: 2639945 events read in total (121995ms).
[15:04:15.236] <TB0> INFO: 3120000 events read in total (144316ms).
[15:04:15.288] <TB0> INFO: Test took 145211ms.
[15:04:38.450] <TB0> INFO: PixTestBBMap::doTest() done, duration: 168 seconds
[15:04:38.450] <TB0> INFO: number of dead bumps (per ROC): 20 8 7 7 2 1 0 2 0 0 1 0 0 0 0 6
[15:04:38.450] <TB0> INFO: separation cut (per ROC): 82 110 94 95 94 93 90 96 96 104 89 94 91 108 97 92
[15:04:38.450] <TB0> INFO: Decoding statistics:
[15:04:38.450] <TB0> INFO: General information:
[15:04:38.450] <TB0> INFO: 16bit words read: 0
[15:04:38.450] <TB0> INFO: valid events total: 0
[15:04:38.450] <TB0> INFO: empty events: 0
[15:04:38.450] <TB0> INFO: valid events with pixels: 0
[15:04:38.450] <TB0> INFO: valid pixel hits: 0
[15:04:38.450] <TB0> INFO: Event errors: 0
[15:04:38.450] <TB0> INFO: start marker: 0
[15:04:38.450] <TB0> INFO: stop marker: 0
[15:04:38.450] <TB0> INFO: overflow: 0
[15:04:38.450] <TB0> INFO: invalid 5bit words: 0
[15:04:38.450] <TB0> INFO: invalid XOR eye diagram: 0
[15:04:38.450] <TB0> INFO: frame (failed synchr.): 0
[15:04:38.450] <TB0> INFO: idle data (no TBM trl): 0
[15:04:38.450] <TB0> INFO: no data (only TBM hdr): 0
[15:04:38.450] <TB0> INFO: TBM errors: 0
[15:04:38.450] <TB0> INFO: flawed TBM headers: 0
[15:04:38.450] <TB0> INFO: flawed TBM trailers: 0
[15:04:38.450] <TB0> INFO: event ID mismatches: 0
[15:04:38.450] <TB0> INFO: ROC errors: 0
[15:04:38.450] <TB0> INFO: missing ROC header(s): 0
[15:04:38.450] <TB0> INFO: misplaced readback start: 0
[15:04:38.450] <TB0> INFO: Pixel decoding errors: 0
[15:04:38.450] <TB0> INFO: pixel data incomplete: 0
[15:04:38.450] <TB0> INFO: pixel address: 0
[15:04:38.450] <TB0> INFO: pulse height fill bit: 0
[15:04:38.450] <TB0> INFO: buffer corruption: 0
[15:04:38.522] <TB0> INFO: ######################################################################
[15:04:38.522] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:04:38.522] <TB0> INFO: ######################################################################
[15:04:38.522] <TB0> INFO: ----------------------------------------------------------------------
[15:04:38.522] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:04:38.522] <TB0> INFO: ----------------------------------------------------------------------
[15:04:38.522] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:04:38.531] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[15:04:38.531] <TB0> INFO: run 1 of 1
[15:04:38.792] <TB0> INFO: Expecting 26208000 events.
[15:05:15.733] <TB0> INFO: 954100 events read in total (36384ms).
[15:05:50.211] <TB0> INFO: 1889450 events read in total (70862ms).
[15:06:23.180] <TB0> INFO: 2822400 events read in total (103831ms).
[15:06:56.347] <TB0> INFO: 3754700 events read in total (136998ms).
[15:07:29.660] <TB0> INFO: 4687650 events read in total (170311ms).
[15:08:03.415] <TB0> INFO: 5619100 events read in total (204066ms).
[15:08:36.847] <TB0> INFO: 6549150 events read in total (237498ms).
[15:09:10.343] <TB0> INFO: 7474800 events read in total (270994ms).
[15:09:43.532] <TB0> INFO: 8401550 events read in total (304183ms).
[15:10:16.920] <TB0> INFO: 9329100 events read in total (337571ms).
[15:10:50.214] <TB0> INFO: 10255200 events read in total (370865ms).
[15:11:23.420] <TB0> INFO: 11180350 events read in total (404071ms).
[15:11:56.531] <TB0> INFO: 12105050 events read in total (437182ms).
[15:12:29.805] <TB0> INFO: 13024500 events read in total (470456ms).
[15:13:03.253] <TB0> INFO: 13935550 events read in total (503904ms).
[15:13:36.388] <TB0> INFO: 14846850 events read in total (537039ms).
[15:14:10.038] <TB0> INFO: 15755450 events read in total (570689ms).
[15:14:43.682] <TB0> INFO: 16662600 events read in total (604333ms).
[15:15:16.882] <TB0> INFO: 17568900 events read in total (637533ms).
[15:15:50.437] <TB0> INFO: 18473700 events read in total (671088ms).
[15:16:23.809] <TB0> INFO: 19377700 events read in total (704460ms).
[15:16:56.910] <TB0> INFO: 20283350 events read in total (737561ms).
[15:17:30.093] <TB0> INFO: 21185850 events read in total (770744ms).
[15:18:03.828] <TB0> INFO: 22089200 events read in total (804479ms).
[15:18:36.777] <TB0> INFO: 22992900 events read in total (837428ms).
[15:19:10.353] <TB0> INFO: 23900000 events read in total (871004ms).
[15:19:43.939] <TB0> INFO: 24808200 events read in total (904590ms).
[15:20:17.621] <TB0> INFO: 25716350 events read in total (938272ms).
[15:20:35.531] <TB0> INFO: 26208000 events read in total (956182ms).
[15:20:35.557] <TB0> INFO: Test took 957026ms.
[15:20:35.793] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:37.244] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:38.911] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:40.541] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:41.955] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:43.328] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:44.773] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:46.220] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:47.690] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:49.232] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:50.778] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:52.390] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:53.881] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:55.718] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:57.183] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:20:58.636] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[15:21:00.093] <TB0> INFO: PixTestScurves::scurves() done
[15:21:00.093] <TB0> INFO: Vcal mean: 96.60 97.94 98.63 100.62 100.60 98.82 97.69 101.29 92.17 104.81 100.26 93.62 82.98 106.91 95.86 96.47
[15:21:00.093] <TB0> INFO: Vcal RMS: 4.85 6.12 5.95 8.01 6.07 6.17 8.59 5.38 5.72 6.00 5.61 5.87 4.63 5.90 6.02 4.66
[15:21:00.093] <TB0> INFO: PixTestScurves::fullTest() done, duration: 981 seconds
[15:21:00.093] <TB0> INFO: Decoding statistics:
[15:21:00.093] <TB0> INFO: General information:
[15:21:00.093] <TB0> INFO: 16bit words read: 0
[15:21:00.093] <TB0> INFO: valid events total: 0
[15:21:00.093] <TB0> INFO: empty events: 0
[15:21:00.093] <TB0> INFO: valid events with pixels: 0
[15:21:00.093] <TB0> INFO: valid pixel hits: 0
[15:21:00.093] <TB0> INFO: Event errors: 0
[15:21:00.093] <TB0> INFO: start marker: 0
[15:21:00.093] <TB0> INFO: stop marker: 0
[15:21:00.093] <TB0> INFO: overflow: 0
[15:21:00.093] <TB0> INFO: invalid 5bit words: 0
[15:21:00.093] <TB0> INFO: invalid XOR eye diagram: 0
[15:21:00.093] <TB0> INFO: frame (failed synchr.): 0
[15:21:00.093] <TB0> INFO: idle data (no TBM trl): 0
[15:21:00.093] <TB0> INFO: no data (only TBM hdr): 0
[15:21:00.093] <TB0> INFO: TBM errors: 0
[15:21:00.093] <TB0> INFO: flawed TBM headers: 0
[15:21:00.093] <TB0> INFO: flawed TBM trailers: 0
[15:21:00.093] <TB0> INFO: event ID mismatches: 0
[15:21:00.093] <TB0> INFO: ROC errors: 0
[15:21:00.093] <TB0> INFO: missing ROC header(s): 0
[15:21:00.093] <TB0> INFO: misplaced readback start: 0
[15:21:00.093] <TB0> INFO: Pixel decoding errors: 0
[15:21:00.093] <TB0> INFO: pixel data incomplete: 0
[15:21:00.093] <TB0> INFO: pixel address: 0
[15:21:00.093] <TB0> INFO: pulse height fill bit: 0
[15:21:00.093] <TB0> INFO: buffer corruption: 0
[15:21:00.165] <TB0> INFO: ######################################################################
[15:21:00.165] <TB0> INFO: PixTestTrim::doTest()
[15:21:00.165] <TB0> INFO: ######################################################################
[15:21:00.166] <TB0> INFO: ----------------------------------------------------------------------
[15:21:00.166] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:21:00.166] <TB0> INFO: ----------------------------------------------------------------------
[15:21:00.245] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:21:00.245] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:21:00.253] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:21:00.253] <TB0> INFO: run 1 of 1
[15:21:00.516] <TB0> INFO: Expecting 6281600 events.
[15:21:41.544] <TB0> INFO: 1108230 events read in total (40472ms).
[15:22:21.685] <TB0> INFO: 2209440 events read in total (80613ms).
[15:23:01.274] <TB0> INFO: 3303730 events read in total (120203ms).
[15:23:41.182] <TB0> INFO: 4405170 events read in total (160110ms).
[15:24:21.116] <TB0> INFO: 5508170 events read in total (200044ms).
[15:24:48.614] <TB0> INFO: 6281600 events read in total (227542ms).
[15:24:48.646] <TB0> INFO: Test took 228393ms.
[15:25:08.296] <TB0> INFO: ROC 0 VthrComp = 100
[15:25:08.296] <TB0> INFO: ROC 1 VthrComp = 98
[15:25:08.296] <TB0> INFO: ROC 2 VthrComp = 94
[15:25:08.296] <TB0> INFO: ROC 3 VthrComp = 95
[15:25:08.297] <TB0> INFO: ROC 4 VthrComp = 96
[15:25:08.297] <TB0> INFO: ROC 5 VthrComp = 93
[15:25:08.297] <TB0> INFO: ROC 6 VthrComp = 88
[15:25:08.297] <TB0> INFO: ROC 7 VthrComp = 99
[15:25:08.297] <TB0> INFO: ROC 8 VthrComp = 92
[15:25:08.297] <TB0> INFO: ROC 9 VthrComp = 101
[15:25:08.297] <TB0> INFO: ROC 10 VthrComp = 95
[15:25:08.297] <TB0> INFO: ROC 11 VthrComp = 89
[15:25:08.297] <TB0> INFO: ROC 12 VthrComp = 87
[15:25:08.297] <TB0> INFO: ROC 13 VthrComp = 103
[15:25:08.298] <TB0> INFO: ROC 14 VthrComp = 95
[15:25:08.298] <TB0> INFO: ROC 15 VthrComp = 99
[15:25:08.298] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:25:08.298] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:25:08.306] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:25:08.306] <TB0> INFO: run 1 of 1
[15:25:08.565] <TB0> INFO: Expecting 6281600 events.
[15:25:44.159] <TB0> INFO: 758000 events read in total (35037ms).
[15:26:18.241] <TB0> INFO: 1513860 events read in total (69119ms).
[15:26:52.998] <TB0> INFO: 2269720 events read in total (103876ms).
[15:27:27.789] <TB0> INFO: 3026190 events read in total (138667ms).
[15:28:02.091] <TB0> INFO: 3776320 events read in total (172969ms).
[15:28:37.148] <TB0> INFO: 4522740 events read in total (208026ms).
[15:29:11.776] <TB0> INFO: 5266550 events read in total (242654ms).
[15:29:46.243] <TB0> INFO: 6010660 events read in total (277121ms).
[15:29:59.233] <TB0> INFO: 6281600 events read in total (290111ms).
[15:29:59.276] <TB0> INFO: Test took 290970ms.
[15:30:25.044] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 55.7858 for pixel 2/6 mean/min/max = 44.1825/32.3087/56.0563
[15:30:25.044] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 59.457 for pixel 3/3 mean/min/max = 45.2576/30.9831/59.5321
[15:30:25.045] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 61.3731 for pixel 7/79 mean/min/max = 46.9167/32.3677/61.4658
[15:30:25.045] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 65.7826 for pixel 3/0 mean/min/max = 48.1761/30.4496/65.9026
[15:30:25.045] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 61.1691 for pixel 0/7 mean/min/max = 46.4091/31.4958/61.3224
[15:30:25.045] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 62.8933 for pixel 2/76 mean/min/max = 47.6671/32.4144/62.9197
[15:30:25.046] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 70.9229 for pixel 6/12 mean/min/max = 51.1513/30.5464/71.7561
[15:30:25.046] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.8494 for pixel 20/79 mean/min/max = 44.9061/31.8368/57.9753
[15:30:25.046] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 59.6568 for pixel 0/22 mean/min/max = 46.4655/33.066/59.865
[15:30:25.047] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 61.5001 for pixel 2/47 mean/min/max = 46.6893/31.8603/61.5182
[15:30:25.047] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 59.1523 for pixel 14/30 mean/min/max = 46.0518/32.8066/59.297
[15:30:25.047] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 62.0698 for pixel 8/1 mean/min/max = 47.761/33.2823/62.2396
[15:30:25.047] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 55.3177 for pixel 51/71 mean/min/max = 43.6461/31.896/55.3962
[15:30:25.048] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 62.1611 for pixel 1/79 mean/min/max = 46.7915/31.4054/62.1775
[15:30:25.048] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.6068 for pixel 15/29 mean/min/max = 45.7391/31.7765/59.7016
[15:30:25.048] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 56.2231 for pixel 0/43 mean/min/max = 43.9424/31.5052/56.3796
[15:30:25.049] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:30:25.147] <TB0> INFO: Expecting 514560 events.
[15:30:38.050] <TB0> INFO: 514560 events read in total (12347ms).
[15:30:38.056] <TB0> INFO: Expecting 514560 events.
[15:30:50.958] <TB0> INFO: 514560 events read in total (12482ms).
[15:30:50.965] <TB0> INFO: Expecting 514560 events.
[15:31:03.706] <TB0> INFO: 514560 events read in total (12330ms).
[15:31:03.714] <TB0> INFO: Expecting 514560 events.
[15:31:16.330] <TB0> INFO: 514560 events read in total (12193ms).
[15:31:16.341] <TB0> INFO: Expecting 514560 events.
[15:31:29.179] <TB0> INFO: 514560 events read in total (12423ms).
[15:31:29.190] <TB0> INFO: Expecting 514560 events.
[15:31:42.036] <TB0> INFO: 514560 events read in total (12427ms).
[15:31:42.050] <TB0> INFO: Expecting 514560 events.
[15:31:54.972] <TB0> INFO: 514560 events read in total (12510ms).
[15:31:54.988] <TB0> INFO: Expecting 514560 events.
[15:32:07.808] <TB0> INFO: 514560 events read in total (12418ms).
[15:32:07.825] <TB0> INFO: Expecting 514560 events.
[15:32:20.704] <TB0> INFO: 514560 events read in total (12467ms).
[15:32:20.723] <TB0> INFO: Expecting 514560 events.
[15:32:33.571] <TB0> INFO: 514560 events read in total (12439ms).
[15:32:33.592] <TB0> INFO: Expecting 514560 events.
[15:32:46.408] <TB0> INFO: 514560 events read in total (12410ms).
[15:32:46.431] <TB0> INFO: Expecting 514560 events.
[15:32:59.264] <TB0> INFO: 514560 events read in total (12427ms).
[15:32:59.288] <TB0> INFO: Expecting 514560 events.
[15:33:11.973] <TB0> INFO: 514560 events read in total (12283ms).
[15:33:11.999] <TB0> INFO: Expecting 514560 events.
[15:33:24.793] <TB0> INFO: 514560 events read in total (12390ms).
[15:33:24.822] <TB0> INFO: Expecting 514560 events.
[15:33:37.713] <TB0> INFO: 514560 events read in total (12490ms).
[15:33:37.745] <TB0> INFO: Expecting 514560 events.
[15:33:50.536] <TB0> INFO: 514560 events read in total (12390ms).
[15:33:50.566] <TB0> INFO: Test took 205518ms.
[15:33:51.456] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:33:51.465] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:33:51.465] <TB0> INFO: run 1 of 1
[15:33:51.737] <TB0> INFO: Expecting 6281600 events.
[15:34:26.861] <TB0> INFO: 743870 events read in total (34568ms).
[15:35:01.630] <TB0> INFO: 1485710 events read in total (69337ms).
[15:35:36.678] <TB0> INFO: 2227830 events read in total (104385ms).
[15:36:11.336] <TB0> INFO: 2970020 events read in total (139043ms).
[15:36:46.082] <TB0> INFO: 3705230 events read in total (173789ms).
[15:37:20.808] <TB0> INFO: 4435250 events read in total (208515ms).
[15:37:55.365] <TB0> INFO: 5162100 events read in total (243072ms).
[15:38:29.320] <TB0> INFO: 5887860 events read in total (277027ms).
[15:38:47.918] <TB0> INFO: 6281600 events read in total (295625ms).
[15:38:47.990] <TB0> INFO: Test took 296525ms.
[15:39:12.392] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.012010 .. 255.000000
[15:39:12.473] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:39:12.481] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:39:12.481] <TB0> INFO: run 1 of 1
[15:39:12.763] <TB0> INFO: Expecting 10649600 events.
[15:39:47.940] <TB0> INFO: 711940 events read in total (34621ms).
[15:40:21.492] <TB0> INFO: 1424280 events read in total (68173ms).
[15:40:55.832] <TB0> INFO: 2136770 events read in total (102513ms).
[15:41:30.287] <TB0> INFO: 2849380 events read in total (136968ms).
[15:42:04.399] <TB0> INFO: 3562250 events read in total (171080ms).
[15:42:38.534] <TB0> INFO: 4275140 events read in total (205215ms).
[15:43:12.806] <TB0> INFO: 4988240 events read in total (239487ms).
[15:43:46.576] <TB0> INFO: 5701120 events read in total (273257ms).
[15:44:21.298] <TB0> INFO: 6413760 events read in total (307979ms).
[15:44:55.758] <TB0> INFO: 7125540 events read in total (342439ms).
[15:45:30.054] <TB0> INFO: 7837010 events read in total (376735ms).
[15:46:04.387] <TB0> INFO: 8547330 events read in total (411068ms).
[15:46:38.394] <TB0> INFO: 9257810 events read in total (445075ms).
[15:47:12.773] <TB0> INFO: 9967760 events read in total (479454ms).
[15:47:45.749] <TB0> INFO: 10649600 events read in total (512430ms).
[15:47:45.867] <TB0> INFO: Test took 513386ms.
[15:48:18.077] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 15.049024 .. 67.854493
[15:48:18.169] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 77 (-1/-1) hits flags = 528 (plus default)
[15:48:18.178] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:48:18.182] <TB0> INFO: run 1 of 1
[15:48:18.470] <TB0> INFO: Expecting 3036800 events.
[15:48:54.933] <TB0> INFO: 834170 events read in total (35907ms).
[15:49:30.829] <TB0> INFO: 1668440 events read in total (71803ms).
[15:50:06.836] <TB0> INFO: 2501450 events read in total (107811ms).
[15:50:30.582] <TB0> INFO: 3036800 events read in total (131556ms).
[15:50:30.603] <TB0> INFO: Test took 132422ms.
[15:50:47.570] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 3.500000 .. 67.854493
[15:50:47.651] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 3 .. 77 (-1/-1) hits flags = 528 (plus default)
[15:50:47.659] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:50:47.659] <TB0> INFO: run 1 of 1
[15:50:47.921] <TB0> INFO: Expecting 3120000 events.
[15:51:24.856] <TB0> INFO: 843750 events read in total (36379ms).
[15:52:00.929] <TB0> INFO: 1687960 events read in total (72452ms).
[15:52:37.602] <TB0> INFO: 2531260 events read in total (109125ms).
[15:53:02.856] <TB0> INFO: 3120000 events read in total (134379ms).
[15:53:02.882] <TB0> INFO: Test took 135223ms.
[15:53:19.826] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 1.345307 .. 67.854493
[15:53:19.902] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 77 (-1/-1) hits flags = 528 (plus default)
[15:53:19.910] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:53:19.910] <TB0> INFO: run 1 of 1
[15:53:20.171] <TB0> INFO: Expecting 3203200 events.
[15:53:57.661] <TB0> INFO: 852060 events read in total (36934ms).
[15:54:33.635] <TB0> INFO: 1704310 events read in total (72909ms).
[15:55:09.894] <TB0> INFO: 2556120 events read in total (109168ms).
[15:55:37.585] <TB0> INFO: 3203200 events read in total (136858ms).
[15:55:37.610] <TB0> INFO: Test took 137700ms.
[15:55:54.760] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:55:54.760] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:55:54.767] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[15:55:54.768] <TB0> INFO: run 1 of 1
[15:55:55.057] <TB0> INFO: Expecting 1705600 events.
[15:56:33.120] <TB0> INFO: 879040 events read in total (37507ms).
[15:57:08.456] <TB0> INFO: 1705600 events read in total (72843ms).
[15:57:08.467] <TB0> INFO: Test took 73699ms.
[15:57:22.800] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:57:22.800] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:57:22.800] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:57:22.800] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:57:22.800] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:57:22.800] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:57:22.801] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:57:22.802] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:57:22.802] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:57:22.808] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:57:22.815] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:57:22.821] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:57:22.828] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:57:22.834] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:57:22.841] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:57:22.847] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:57:22.859] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:57:22.871] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:57:22.882] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:57:22.893] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:57:22.905] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:57:22.916] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:57:22.928] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:57:22.937] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:57:22.943] <TB0> INFO: PixTestTrim::trimTest() done
[15:57:22.943] <TB0> INFO: vtrim: 101 110 91 108 100 107 140 91 93 108 93 111 78 107 102 88
[15:57:22.943] <TB0> INFO: vthrcomp: 100 98 94 95 96 93 88 99 92 101 95 89 87 103 95 99
[15:57:22.943] <TB0> INFO: vcal mean: 34.96 34.97 34.96 35.02 34.99 34.97 35.00 35.00 35.02 34.97 34.97 34.99 34.97 34.98 34.96 35.00
[15:57:22.943] <TB0> INFO: vcal RMS: 0.84 0.91 0.89 0.94 0.88 1.08 2.07 0.87 0.78 0.89 1.04 0.92 0.99 0.88 0.99 0.86
[15:57:22.943] <TB0> INFO: bits mean: 9.99 9.93 9.02 8.95 9.24 9.41 9.03 9.43 8.48 9.39 9.39 9.35 9.71 9.35 9.78 9.81
[15:57:22.943] <TB0> INFO: bits RMS: 2.53 2.67 2.83 2.89 2.80 2.55 2.63 2.82 2.97 2.71 2.67 2.47 2.75 2.72 2.60 2.74
[15:57:22.949] <TB0> INFO: ----------------------------------------------------------------------
[15:57:22.949] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:57:22.949] <TB0> INFO: ----------------------------------------------------------------------
[15:57:22.951] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:57:22.959] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[15:57:22.959] <TB0> INFO: run 1 of 1
[15:57:23.220] <TB0> INFO: Expecting 4160000 events.
[15:58:05.671] <TB0> INFO: 973530 events read in total (41894ms).
[15:58:48.556] <TB0> INFO: 1937130 events read in total (84779ms).
[15:59:30.355] <TB0> INFO: 2888170 events read in total (126578ms).
[16:00:12.368] <TB0> INFO: 3836070 events read in total (168591ms).
[16:00:27.286] <TB0> INFO: 4160000 events read in total (183509ms).
[16:00:27.323] <TB0> INFO: Test took 184365ms.
[16:00:56.727] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 186 (-1/-1) hits flags = 528 (plus default)
[16:00:56.735] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:00:56.735] <TB0> INFO: run 1 of 1
[16:00:57.003] <TB0> INFO: Expecting 3889600 events.
[16:01:39.665] <TB0> INFO: 962950 events read in total (42107ms).
[16:02:22.513] <TB0> INFO: 1916425 events read in total (84954ms).
[16:03:04.369] <TB0> INFO: 2857575 events read in total (126810ms).
[16:03:46.116] <TB0> INFO: 3798080 events read in total (168557ms).
[16:03:50.442] <TB0> INFO: 3889600 events read in total (172883ms).
[16:03:50.490] <TB0> INFO: Test took 173755ms.
[16:04:17.447] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[16:04:17.454] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:04:17.454] <TB0> INFO: run 1 of 1
[16:04:17.713] <TB0> INFO: Expecting 3619200 events.
[16:05:01.087] <TB0> INFO: 995695 events read in total (42818ms).
[16:05:46.657] <TB0> INFO: 1978440 events read in total (88388ms).
[16:06:28.899] <TB0> INFO: 2948305 events read in total (130630ms).
[16:06:58.353] <TB0> INFO: 3619200 events read in total (160084ms).
[16:06:58.384] <TB0> INFO: Test took 160929ms.
[16:07:24.623] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[16:07:24.634] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:07:24.634] <TB0> INFO: run 1 of 1
[16:07:24.913] <TB0> INFO: Expecting 3577600 events.
[16:08:08.551] <TB0> INFO: 1000550 events read in total (43082ms).
[16:08:51.242] <TB0> INFO: 1987725 events read in total (85773ms).
[16:09:33.646] <TB0> INFO: 2962100 events read in total (128177ms).
[16:10:00.753] <TB0> INFO: 3577600 events read in total (155284ms).
[16:10:00.783] <TB0> INFO: Test took 156148ms.
[16:10:28.943] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[16:10:28.951] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:10:28.951] <TB0> INFO: run 1 of 1
[16:10:29.234] <TB0> INFO: Expecting 3598400 events.
[16:11:12.716] <TB0> INFO: 997130 events read in total (42925ms).
[16:11:55.724] <TB0> INFO: 1980975 events read in total (85933ms).
[16:12:38.029] <TB0> INFO: 2952190 events read in total (128238ms).
[16:13:06.274] <TB0> INFO: 3598400 events read in total (156483ms).
[16:13:06.306] <TB0> INFO: Test took 157355ms.
[16:13:32.003] <TB0> INFO: PixTestTrim::trimBitTest() done
[16:13:32.004] <TB0> INFO: PixTestTrim::doTest() done, duration: 3151 seconds
[16:13:32.004] <TB0> INFO: Decoding statistics:
[16:13:32.004] <TB0> INFO: General information:
[16:13:32.004] <TB0> INFO: 16bit words read: 0
[16:13:32.004] <TB0> INFO: valid events total: 0
[16:13:32.004] <TB0> INFO: empty events: 0
[16:13:32.004] <TB0> INFO: valid events with pixels: 0
[16:13:32.004] <TB0> INFO: valid pixel hits: 0
[16:13:32.004] <TB0> INFO: Event errors: 0
[16:13:32.004] <TB0> INFO: start marker: 0
[16:13:32.004] <TB0> INFO: stop marker: 0
[16:13:32.004] <TB0> INFO: overflow: 0
[16:13:32.004] <TB0> INFO: invalid 5bit words: 0
[16:13:32.004] <TB0> INFO: invalid XOR eye diagram: 0
[16:13:32.004] <TB0> INFO: frame (failed synchr.): 0
[16:13:32.004] <TB0> INFO: idle data (no TBM trl): 0
[16:13:32.004] <TB0> INFO: no data (only TBM hdr): 0
[16:13:32.004] <TB0> INFO: TBM errors: 0
[16:13:32.004] <TB0> INFO: flawed TBM headers: 0
[16:13:32.004] <TB0> INFO: flawed TBM trailers: 0
[16:13:32.004] <TB0> INFO: event ID mismatches: 0
[16:13:32.004] <TB0> INFO: ROC errors: 0
[16:13:32.004] <TB0> INFO: missing ROC header(s): 0
[16:13:32.004] <TB0> INFO: misplaced readback start: 0
[16:13:32.004] <TB0> INFO: Pixel decoding errors: 0
[16:13:32.004] <TB0> INFO: pixel data incomplete: 0
[16:13:32.004] <TB0> INFO: pixel address: 0
[16:13:32.004] <TB0> INFO: pulse height fill bit: 0
[16:13:32.004] <TB0> INFO: buffer corruption: 0
[16:13:32.701] <TB0> INFO: ######################################################################
[16:13:32.701] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:13:32.701] <TB0> INFO: ######################################################################
[16:13:32.966] <TB0> INFO: Expecting 41600 events.
[16:13:36.696] <TB0> INFO: 41600 events read in total (3174ms).
[16:13:36.697] <TB0> INFO: Test took 3995ms.
[16:13:37.170] <TB0> INFO: Expecting 41600 events.
[16:13:41.000] <TB0> INFO: 41600 events read in total (3274ms).
[16:13:41.000] <TB0> INFO: Test took 4100ms.
[16:13:41.297] <TB0> INFO: Expecting 41600 events.
[16:13:45.076] <TB0> INFO: 41600 events read in total (3223ms).
[16:13:45.077] <TB0> INFO: Test took 4054ms.
[16:13:45.331] <TB0> INFO: Expecting 2560 events.
[16:13:46.184] <TB0> INFO: 2560 events read in total (297ms).
[16:13:46.184] <TB0> INFO: Test took 1095ms.
[16:13:46.489] <TB0> INFO: Expecting 2560 events.
[16:13:47.342] <TB0> INFO: 2560 events read in total (297ms).
[16:13:47.342] <TB0> INFO: Test took 1157ms.
[16:13:47.647] <TB0> INFO: Expecting 2560 events.
[16:13:48.500] <TB0> INFO: 2560 events read in total (296ms).
[16:13:48.500] <TB0> INFO: Test took 1157ms.
[16:13:48.806] <TB0> INFO: Expecting 2560 events.
[16:13:49.663] <TB0> INFO: 2560 events read in total (301ms).
[16:13:49.663] <TB0> INFO: Test took 1162ms.
[16:13:49.968] <TB0> INFO: Expecting 2560 events.
[16:13:50.822] <TB0> INFO: 2560 events read in total (297ms).
[16:13:50.822] <TB0> INFO: Test took 1158ms.
[16:13:51.127] <TB0> INFO: Expecting 2560 events.
[16:13:51.981] <TB0> INFO: 2560 events read in total (298ms).
[16:13:51.981] <TB0> INFO: Test took 1159ms.
[16:13:52.287] <TB0> INFO: Expecting 2560 events.
[16:13:53.140] <TB0> INFO: 2560 events read in total (297ms).
[16:13:53.140] <TB0> INFO: Test took 1159ms.
[16:13:53.445] <TB0> INFO: Expecting 2560 events.
[16:13:54.298] <TB0> INFO: 2560 events read in total (297ms).
[16:13:54.298] <TB0> INFO: Test took 1158ms.
[16:13:54.603] <TB0> INFO: Expecting 2560 events.
[16:13:55.463] <TB0> INFO: 2560 events read in total (303ms).
[16:13:55.463] <TB0> INFO: Test took 1164ms.
[16:13:55.767] <TB0> INFO: Expecting 2560 events.
[16:13:56.623] <TB0> INFO: 2560 events read in total (300ms).
[16:13:56.624] <TB0> INFO: Test took 1160ms.
[16:13:56.928] <TB0> INFO: Expecting 2560 events.
[16:13:57.796] <TB0> INFO: 2560 events read in total (301ms).
[16:13:57.797] <TB0> INFO: Test took 1173ms.
[16:13:58.102] <TB0> INFO: Expecting 2560 events.
[16:13:58.955] <TB0> INFO: 2560 events read in total (297ms).
[16:13:58.956] <TB0> INFO: Test took 1159ms.
[16:13:59.261] <TB0> INFO: Expecting 2560 events.
[16:14:00.116] <TB0> INFO: 2560 events read in total (299ms).
[16:14:00.116] <TB0> INFO: Test took 1160ms.
[16:14:00.420] <TB0> INFO: Expecting 2560 events.
[16:14:01.276] <TB0> INFO: 2560 events read in total (299ms).
[16:14:01.276] <TB0> INFO: Test took 1159ms.
[16:14:01.581] <TB0> INFO: Expecting 2560 events.
[16:14:02.439] <TB0> INFO: 2560 events read in total (302ms).
[16:14:02.439] <TB0> INFO: Test took 1162ms.
[16:14:02.746] <TB0> INFO: Expecting 2560 events.
[16:14:03.600] <TB0> INFO: 2560 events read in total (298ms).
[16:14:03.600] <TB0> INFO: Test took 1161ms.
[16:14:03.604] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:14:03.907] <TB0> INFO: Expecting 655360 events.
[16:14:20.797] <TB0> INFO: 655360 events read in total (16334ms).
[16:14:20.806] <TB0> INFO: Expecting 655360 events.
[16:14:37.577] <TB0> INFO: 655360 events read in total (16369ms).
[16:14:37.589] <TB0> INFO: Expecting 655360 events.
[16:14:54.313] <TB0> INFO: 655360 events read in total (16322ms).
[16:14:54.328] <TB0> INFO: Expecting 655360 events.
[16:15:11.125] <TB0> INFO: 655360 events read in total (16395ms).
[16:15:11.143] <TB0> INFO: Expecting 655360 events.
[16:15:27.821] <TB0> INFO: 655360 events read in total (16276ms).
[16:15:27.843] <TB0> INFO: Expecting 655360 events.
[16:15:44.672] <TB0> INFO: 655360 events read in total (16427ms).
[16:15:44.701] <TB0> INFO: Expecting 655360 events.
[16:16:01.424] <TB0> INFO: 655360 events read in total (16321ms).
[16:16:01.453] <TB0> INFO: Expecting 655360 events.
[16:16:18.249] <TB0> INFO: 655360 events read in total (16394ms).
[16:16:18.283] <TB0> INFO: Expecting 655360 events.
[16:16:35.002] <TB0> INFO: 655360 events read in total (16318ms).
[16:16:35.037] <TB0> INFO: Expecting 655360 events.
[16:16:51.813] <TB0> INFO: 655360 events read in total (16374ms).
[16:16:51.853] <TB0> INFO: Expecting 655360 events.
[16:17:08.578] <TB0> INFO: 655360 events read in total (16324ms).
[16:17:08.619] <TB0> INFO: Expecting 655360 events.
[16:17:25.344] <TB0> INFO: 655360 events read in total (16323ms).
[16:17:25.393] <TB0> INFO: Expecting 655360 events.
[16:17:42.228] <TB0> INFO: 655360 events read in total (16433ms).
[16:17:42.289] <TB0> INFO: Expecting 655360 events.
[16:17:59.068] <TB0> INFO: 655360 events read in total (16377ms).
[16:17:59.121] <TB0> INFO: Expecting 655360 events.
[16:18:15.994] <TB0> INFO: 655360 events read in total (16471ms).
[16:18:16.057] <TB0> INFO: Expecting 655360 events.
[16:18:32.849] <TB0> INFO: 655360 events read in total (16390ms).
[16:18:32.915] <TB0> INFO: Test took 269311ms.
[16:18:32.994] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:33.225] <TB0> INFO: Expecting 655360 events.
[16:18:50.108] <TB0> INFO: 655360 events read in total (16327ms).
[16:18:50.117] <TB0> INFO: Expecting 655360 events.
[16:19:06.870] <TB0> INFO: 655360 events read in total (16352ms).
[16:19:06.882] <TB0> INFO: Expecting 655360 events.
[16:19:23.599] <TB0> INFO: 655360 events read in total (16315ms).
[16:19:23.615] <TB0> INFO: Expecting 655360 events.
[16:19:40.371] <TB0> INFO: 655360 events read in total (16354ms).
[16:19:40.392] <TB0> INFO: Expecting 655360 events.
[16:19:57.006] <TB0> INFO: 655360 events read in total (16212ms).
[16:19:57.028] <TB0> INFO: Expecting 655360 events.
[16:20:13.734] <TB0> INFO: 655360 events read in total (16305ms).
[16:20:13.760] <TB0> INFO: Expecting 655360 events.
[16:20:30.332] <TB0> INFO: 655360 events read in total (16170ms).
[16:20:30.361] <TB0> INFO: Expecting 655360 events.
[16:20:47.155] <TB0> INFO: 655360 events read in total (16392ms).
[16:20:47.187] <TB0> INFO: Expecting 655360 events.
[16:21:04.102] <TB0> INFO: 655360 events read in total (16513ms).
[16:21:04.140] <TB0> INFO: Expecting 655360 events.
[16:21:20.809] <TB0> INFO: 655360 events read in total (16268ms).
[16:21:20.849] <TB0> INFO: Expecting 655360 events.
[16:21:37.637] <TB0> INFO: 655360 events read in total (16386ms).
[16:21:37.680] <TB0> INFO: Expecting 655360 events.
[16:21:54.308] <TB0> INFO: 655360 events read in total (16226ms).
[16:21:54.354] <TB0> INFO: Expecting 655360 events.
[16:22:11.253] <TB0> INFO: 655360 events read in total (16497ms).
[16:22:11.313] <TB0> INFO: Expecting 655360 events.
[16:22:28.076] <TB0> INFO: 655360 events read in total (16361ms).
[16:22:28.132] <TB0> INFO: Expecting 655360 events.
[16:22:44.681] <TB0> INFO: 655360 events read in total (16147ms).
[16:22:44.741] <TB0> INFO: Expecting 655360 events.
[16:23:01.642] <TB0> INFO: 655360 events read in total (16499ms).
[16:23:01.707] <TB0> INFO: Test took 268714ms.
[16:23:01.891] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:01.897] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[16:23:01.904] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[16:23:01.910] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[16:23:01.917] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[16:23:01.928] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[16:23:01.935] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[16:23:01.941] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[16:23:01.948] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:01.954] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:01.961] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:01.967] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:01.974] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:01.981] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:01.987] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:01.994] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:02.000] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[16:23:02.007] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[16:23:02.013] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[16:23:02.020] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:02.027] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:02.034] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[16:23:02.040] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[16:23:02.047] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[16:23:02.054] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[16:23:02.061] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[16:23:02.068] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[16:23:02.074] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[16:23:02.081] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:02.088] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[16:23:02.094] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:02.101] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:02.108] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:02.115] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[16:23:02.121] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[16:23:02.128] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[16:23:02.135] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[16:23:02.142] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[16:23:02.148] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[16:23:02.155] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[16:23:02.162] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[16:23:02.168] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[16:23:02.175] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[16:23:02.182] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[16:23:02.218] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:23:02.218] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:23:02.218] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:23:02.218] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:23:02.219] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:23:02.220] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:23:02.220] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:23:02.220] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:23:02.511] <TB0> INFO: Expecting 41600 events.
[16:23:05.972] <TB0> INFO: 41600 events read in total (2905ms).
[16:23:05.973] <TB0> INFO: Test took 3751ms.
[16:23:06.418] <TB0> INFO: Expecting 41600 events.
[16:23:09.900] <TB0> INFO: 41600 events read in total (2926ms).
[16:23:09.901] <TB0> INFO: Test took 3752ms.
[16:23:10.358] <TB0> INFO: Expecting 41600 events.
[16:23:13.844] <TB0> INFO: 41600 events read in total (2930ms).
[16:23:13.844] <TB0> INFO: Test took 3768ms.
[16:23:14.028] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:14.116] <TB0> INFO: Expecting 2560 events.
[16:23:14.970] <TB0> INFO: 2560 events read in total (298ms).
[16:23:14.970] <TB0> INFO: Test took 942ms.
[16:23:14.973] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:15.276] <TB0> INFO: Expecting 2560 events.
[16:23:16.132] <TB0> INFO: 2560 events read in total (300ms).
[16:23:16.132] <TB0> INFO: Test took 1159ms.
[16:23:16.133] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:16.437] <TB0> INFO: Expecting 2560 events.
[16:23:17.291] <TB0> INFO: 2560 events read in total (297ms).
[16:23:17.291] <TB0> INFO: Test took 1158ms.
[16:23:17.293] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:17.596] <TB0> INFO: Expecting 2560 events.
[16:23:18.449] <TB0> INFO: 2560 events read in total (297ms).
[16:23:18.449] <TB0> INFO: Test took 1156ms.
[16:23:18.451] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:18.755] <TB0> INFO: Expecting 2560 events.
[16:23:19.614] <TB0> INFO: 2560 events read in total (303ms).
[16:23:19.614] <TB0> INFO: Test took 1163ms.
[16:23:19.617] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:19.920] <TB0> INFO: Expecting 2560 events.
[16:23:20.778] <TB0> INFO: 2560 events read in total (302ms).
[16:23:20.779] <TB0> INFO: Test took 1162ms.
[16:23:20.781] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:21.084] <TB0> INFO: Expecting 2560 events.
[16:23:21.940] <TB0> INFO: 2560 events read in total (299ms).
[16:23:21.941] <TB0> INFO: Test took 1160ms.
[16:23:21.943] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:22.245] <TB0> INFO: Expecting 2560 events.
[16:23:23.100] <TB0> INFO: 2560 events read in total (299ms).
[16:23:23.100] <TB0> INFO: Test took 1157ms.
[16:23:23.102] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:23.406] <TB0> INFO: Expecting 2560 events.
[16:23:24.261] <TB0> INFO: 2560 events read in total (299ms).
[16:23:24.261] <TB0> INFO: Test took 1159ms.
[16:23:24.264] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:24.567] <TB0> INFO: Expecting 2560 events.
[16:23:25.424] <TB0> INFO: 2560 events read in total (301ms).
[16:23:25.424] <TB0> INFO: Test took 1161ms.
[16:23:25.426] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:25.730] <TB0> INFO: Expecting 2560 events.
[16:23:26.585] <TB0> INFO: 2560 events read in total (299ms).
[16:23:26.585] <TB0> INFO: Test took 1159ms.
[16:23:26.588] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:26.890] <TB0> INFO: Expecting 2560 events.
[16:23:27.744] <TB0> INFO: 2560 events read in total (298ms).
[16:23:27.744] <TB0> INFO: Test took 1156ms.
[16:23:27.746] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:28.050] <TB0> INFO: Expecting 2560 events.
[16:23:28.908] <TB0> INFO: 2560 events read in total (302ms).
[16:23:28.909] <TB0> INFO: Test took 1163ms.
[16:23:28.911] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:29.214] <TB0> INFO: Expecting 2560 events.
[16:23:30.068] <TB0> INFO: 2560 events read in total (298ms).
[16:23:30.069] <TB0> INFO: Test took 1158ms.
[16:23:30.070] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:30.375] <TB0> INFO: Expecting 2560 events.
[16:23:31.229] <TB0> INFO: 2560 events read in total (298ms).
[16:23:31.229] <TB0> INFO: Test took 1159ms.
[16:23:31.232] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:31.534] <TB0> INFO: Expecting 2560 events.
[16:23:32.387] <TB0> INFO: 2560 events read in total (297ms).
[16:23:32.388] <TB0> INFO: Test took 1157ms.
[16:23:32.389] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:32.693] <TB0> INFO: Expecting 2560 events.
[16:23:33.548] <TB0> INFO: 2560 events read in total (299ms).
[16:23:33.548] <TB0> INFO: Test took 1159ms.
[16:23:33.550] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:33.855] <TB0> INFO: Expecting 2560 events.
[16:23:34.709] <TB0> INFO: 2560 events read in total (298ms).
[16:23:34.709] <TB0> INFO: Test took 1159ms.
[16:23:34.711] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:35.015] <TB0> INFO: Expecting 2560 events.
[16:23:35.873] <TB0> INFO: 2560 events read in total (301ms).
[16:23:35.873] <TB0> INFO: Test took 1162ms.
[16:23:35.875] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:36.179] <TB0> INFO: Expecting 2560 events.
[16:23:37.032] <TB0> INFO: 2560 events read in total (297ms).
[16:23:37.032] <TB0> INFO: Test took 1157ms.
[16:23:37.034] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:37.339] <TB0> INFO: Expecting 2560 events.
[16:23:38.198] <TB0> INFO: 2560 events read in total (303ms).
[16:23:38.198] <TB0> INFO: Test took 1164ms.
[16:23:38.201] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:38.503] <TB0> INFO: Expecting 2560 events.
[16:23:39.360] <TB0> INFO: 2560 events read in total (300ms).
[16:23:39.360] <TB0> INFO: Test took 1159ms.
[16:23:39.363] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:39.666] <TB0> INFO: Expecting 2560 events.
[16:23:40.525] <TB0> INFO: 2560 events read in total (303ms).
[16:23:40.525] <TB0> INFO: Test took 1163ms.
[16:23:40.527] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:40.830] <TB0> INFO: Expecting 2560 events.
[16:23:41.683] <TB0> INFO: 2560 events read in total (297ms).
[16:23:41.684] <TB0> INFO: Test took 1157ms.
[16:23:41.686] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:41.989] <TB0> INFO: Expecting 2560 events.
[16:23:42.848] <TB0> INFO: 2560 events read in total (302ms).
[16:23:42.848] <TB0> INFO: Test took 1162ms.
[16:23:42.850] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:43.155] <TB0> INFO: Expecting 2560 events.
[16:23:44.010] <TB0> INFO: 2560 events read in total (299ms).
[16:23:44.010] <TB0> INFO: Test took 1160ms.
[16:23:44.013] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:44.316] <TB0> INFO: Expecting 2560 events.
[16:23:45.168] <TB0> INFO: 2560 events read in total (296ms).
[16:23:45.168] <TB0> INFO: Test took 1155ms.
[16:23:45.170] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:45.474] <TB0> INFO: Expecting 2560 events.
[16:23:46.328] <TB0> INFO: 2560 events read in total (297ms).
[16:23:46.328] <TB0> INFO: Test took 1158ms.
[16:23:46.330] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:46.634] <TB0> INFO: Expecting 2560 events.
[16:23:47.489] <TB0> INFO: 2560 events read in total (299ms).
[16:23:47.490] <TB0> INFO: Test took 1160ms.
[16:23:47.492] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:47.796] <TB0> INFO: Expecting 2560 events.
[16:23:48.655] <TB0> INFO: 2560 events read in total (303ms).
[16:23:48.655] <TB0> INFO: Test took 1163ms.
[16:23:48.657] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:48.960] <TB0> INFO: Expecting 2560 events.
[16:23:49.815] <TB0> INFO: 2560 events read in total (299ms).
[16:23:49.815] <TB0> INFO: Test took 1158ms.
[16:23:49.818] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:23:50.120] <TB0> INFO: Expecting 2560 events.
[16:23:50.980] <TB0> INFO: 2560 events read in total (304ms).
[16:23:50.980] <TB0> INFO: Test took 1162ms.
[16:23:51.444] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 618 seconds
[16:23:51.444] <TB0> INFO: PH scale (per ROC): 75 77 66 65 75 80 65 73 66 67 66 68 74 75 75 75
[16:23:51.444] <TB0> INFO: PH offset (per ROC): 176 181 196 203 176 179 198 188 176 186 176 205 176 187 176 176
[16:23:51.452] <TB0> INFO: Decoding statistics:
[16:23:51.452] <TB0> INFO: General information:
[16:23:51.452] <TB0> INFO: 16bit words read: 91916
[16:23:51.452] <TB0> INFO: valid events total: 10240
[16:23:51.452] <TB0> INFO: empty events: 7741
[16:23:51.453] <TB0> INFO: valid events with pixels: 2499
[16:23:51.453] <TB0> INFO: valid pixel hits: 2499
[16:23:51.453] <TB0> INFO: Event errors: 0
[16:23:51.453] <TB0> INFO: start marker: 0
[16:23:51.453] <TB0> INFO: stop marker: 0
[16:23:51.453] <TB0> INFO: overflow: 0
[16:23:51.453] <TB0> INFO: invalid 5bit words: 0
[16:23:51.453] <TB0> INFO: invalid XOR eye diagram: 0
[16:23:51.453] <TB0> INFO: frame (failed synchr.): 0
[16:23:51.453] <TB0> INFO: idle data (no TBM trl): 0
[16:23:51.453] <TB0> INFO: no data (only TBM hdr): 0
[16:23:51.453] <TB0> INFO: TBM errors: 0
[16:23:51.453] <TB0> INFO: flawed TBM headers: 0
[16:23:51.453] <TB0> INFO: flawed TBM trailers: 0
[16:23:51.453] <TB0> INFO: event ID mismatches: 0
[16:23:51.453] <TB0> INFO: ROC errors: 0
[16:23:51.453] <TB0> INFO: missing ROC header(s): 0
[16:23:51.453] <TB0> INFO: misplaced readback start: 0
[16:23:51.453] <TB0> INFO: Pixel decoding errors: 0
[16:23:51.453] <TB0> INFO: pixel data incomplete: 0
[16:23:51.453] <TB0> INFO: pixel address: 0
[16:23:51.453] <TB0> INFO: pulse height fill bit: 0
[16:23:51.453] <TB0> INFO: buffer corruption: 0
[16:23:51.628] <TB0> INFO: ######################################################################
[16:23:51.628] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:23:51.628] <TB0> INFO: ######################################################################
[16:23:51.637] <TB0> INFO: scanning low vcal = 10
[16:23:51.900] <TB0> INFO: Expecting 41600 events.
[16:23:55.431] <TB0> INFO: 41600 events read in total (2974ms).
[16:23:55.431] <TB0> INFO: Test took 3794ms.
[16:23:55.433] <TB0> INFO: scanning low vcal = 20
[16:23:55.736] <TB0> INFO: Expecting 41600 events.
[16:23:59.288] <TB0> INFO: 41600 events read in total (2996ms).
[16:23:59.288] <TB0> INFO: Test took 3855ms.
[16:23:59.290] <TB0> INFO: scanning low vcal = 30
[16:23:59.593] <TB0> INFO: Expecting 41600 events.
[16:24:03.160] <TB0> INFO: 41600 events read in total (3010ms).
[16:24:03.161] <TB0> INFO: Test took 3871ms.
[16:24:03.162] <TB0> INFO: scanning low vcal = 40
[16:24:03.453] <TB0> INFO: Expecting 41600 events.
[16:24:07.677] <TB0> INFO: 41600 events read in total (3668ms).
[16:24:07.678] <TB0> INFO: Test took 4515ms.
[16:24:07.681] <TB0> INFO: scanning low vcal = 50
[16:24:07.959] <TB0> INFO: Expecting 41600 events.
[16:24:12.207] <TB0> INFO: 41600 events read in total (3692ms).
[16:24:12.207] <TB0> INFO: Test took 4526ms.
[16:24:12.210] <TB0> INFO: scanning low vcal = 60
[16:24:12.496] <TB0> INFO: Expecting 41600 events.
[16:24:16.726] <TB0> INFO: 41600 events read in total (3674ms).
[16:24:16.727] <TB0> INFO: Test took 4517ms.
[16:24:16.729] <TB0> INFO: scanning low vcal = 70
[16:24:17.005] <TB0> INFO: Expecting 41600 events.
[16:24:21.200] <TB0> INFO: 41600 events read in total (3639ms).
[16:24:21.200] <TB0> INFO: Test took 4471ms.
[16:24:21.203] <TB0> INFO: scanning low vcal = 80
[16:24:21.468] <TB0> INFO: Expecting 41600 events.
[16:24:25.692] <TB0> INFO: 41600 events read in total (3668ms).
[16:24:25.692] <TB0> INFO: Test took 4489ms.
[16:24:25.694] <TB0> INFO: scanning low vcal = 90
[16:24:25.969] <TB0> INFO: Expecting 41600 events.
[16:24:30.177] <TB0> INFO: 41600 events read in total (3652ms).
[16:24:30.178] <TB0> INFO: Test took 4483ms.
[16:24:30.181] <TB0> INFO: scanning low vcal = 100
[16:24:30.441] <TB0> INFO: Expecting 41600 events.
[16:24:34.681] <TB0> INFO: 41600 events read in total (3684ms).
[16:24:34.682] <TB0> INFO: Test took 4501ms.
[16:24:34.685] <TB0> INFO: scanning low vcal = 110
[16:24:34.975] <TB0> INFO: Expecting 41600 events.
[16:24:39.218] <TB0> INFO: 41600 events read in total (3687ms).
[16:24:39.218] <TB0> INFO: Test took 4533ms.
[16:24:39.220] <TB0> INFO: scanning low vcal = 120
[16:24:39.481] <TB0> INFO: Expecting 41600 events.
[16:24:43.684] <TB0> INFO: 41600 events read in total (3647ms).
[16:24:43.684] <TB0> INFO: Test took 4463ms.
[16:24:43.687] <TB0> INFO: scanning low vcal = 130
[16:24:43.946] <TB0> INFO: Expecting 41600 events.
[16:24:48.116] <TB0> INFO: 41600 events read in total (3613ms).
[16:24:48.116] <TB0> INFO: Test took 4429ms.
[16:24:48.119] <TB0> INFO: scanning low vcal = 140
[16:24:48.405] <TB0> INFO: Expecting 41600 events.
[16:24:52.627] <TB0> INFO: 41600 events read in total (3666ms).
[16:24:52.627] <TB0> INFO: Test took 4508ms.
[16:24:52.630] <TB0> INFO: scanning low vcal = 150
[16:24:52.894] <TB0> INFO: Expecting 41600 events.
[16:24:57.174] <TB0> INFO: 41600 events read in total (3724ms).
[16:24:57.175] <TB0> INFO: Test took 4545ms.
[16:24:57.178] <TB0> INFO: scanning low vcal = 160
[16:24:57.444] <TB0> INFO: Expecting 41600 events.
[16:25:01.650] <TB0> INFO: 41600 events read in total (3650ms).
[16:25:01.650] <TB0> INFO: Test took 4472ms.
[16:25:01.653] <TB0> INFO: scanning low vcal = 170
[16:25:01.916] <TB0> INFO: Expecting 41600 events.
[16:25:06.142] <TB0> INFO: 41600 events read in total (3670ms).
[16:25:06.143] <TB0> INFO: Test took 4490ms.
[16:25:06.147] <TB0> INFO: scanning low vcal = 180
[16:25:06.494] <TB0> INFO: Expecting 41600 events.
[16:25:10.686] <TB0> INFO: 41600 events read in total (3635ms).
[16:25:10.687] <TB0> INFO: Test took 4540ms.
[16:25:10.689] <TB0> INFO: scanning low vcal = 190
[16:25:10.949] <TB0> INFO: Expecting 41600 events.
[16:25:15.179] <TB0> INFO: 41600 events read in total (3674ms).
[16:25:15.179] <TB0> INFO: Test took 4490ms.
[16:25:15.182] <TB0> INFO: scanning low vcal = 200
[16:25:15.528] <TB0> INFO: Expecting 41600 events.
[16:25:19.751] <TB0> INFO: 41600 events read in total (3666ms).
[16:25:19.751] <TB0> INFO: Test took 4569ms.
[16:25:19.754] <TB0> INFO: scanning low vcal = 210
[16:25:20.016] <TB0> INFO: Expecting 41600 events.
[16:25:24.188] <TB0> INFO: 41600 events read in total (3616ms).
[16:25:24.188] <TB0> INFO: Test took 4434ms.
[16:25:24.191] <TB0> INFO: scanning low vcal = 220
[16:25:24.464] <TB0> INFO: Expecting 41600 events.
[16:25:28.665] <TB0> INFO: 41600 events read in total (3645ms).
[16:25:28.666] <TB0> INFO: Test took 4475ms.
[16:25:28.668] <TB0> INFO: scanning low vcal = 230
[16:25:28.933] <TB0> INFO: Expecting 41600 events.
[16:25:33.141] <TB0> INFO: 41600 events read in total (3652ms).
[16:25:33.142] <TB0> INFO: Test took 4474ms.
[16:25:33.144] <TB0> INFO: scanning low vcal = 240
[16:25:33.414] <TB0> INFO: Expecting 41600 events.
[16:25:37.595] <TB0> INFO: 41600 events read in total (3624ms).
[16:25:37.596] <TB0> INFO: Test took 4452ms.
[16:25:37.598] <TB0> INFO: scanning low vcal = 250
[16:25:37.860] <TB0> INFO: Expecting 41600 events.
[16:25:42.066] <TB0> INFO: 41600 events read in total (3649ms).
[16:25:42.067] <TB0> INFO: Test took 4469ms.
[16:25:42.071] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[16:25:42.364] <TB0> INFO: Expecting 41600 events.
[16:25:46.562] <TB0> INFO: 41600 events read in total (3641ms).
[16:25:46.562] <TB0> INFO: Test took 4491ms.
[16:25:46.564] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[16:25:46.825] <TB0> INFO: Expecting 41600 events.
[16:25:51.043] <TB0> INFO: 41600 events read in total (3661ms).
[16:25:51.044] <TB0> INFO: Test took 4480ms.
[16:25:51.046] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[16:25:51.310] <TB0> INFO: Expecting 41600 events.
[16:25:55.521] <TB0> INFO: 41600 events read in total (3654ms).
[16:25:55.522] <TB0> INFO: Test took 4475ms.
[16:25:55.524] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[16:25:55.788] <TB0> INFO: Expecting 41600 events.
[16:26:00.003] <TB0> INFO: 41600 events read in total (3659ms).
[16:26:00.004] <TB0> INFO: Test took 4480ms.
[16:26:00.006] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:26:00.271] <TB0> INFO: Expecting 41600 events.
[16:26:04.456] <TB0> INFO: 41600 events read in total (3629ms).
[16:26:04.456] <TB0> INFO: Test took 4450ms.
[16:26:04.892] <TB0> INFO: PixTestGainPedestal::measure() done
[16:26:36.834] <TB0> INFO: PixTestGainPedestal::fit() done
[16:26:36.834] <TB0> INFO: non-linearity mean: 0.967 0.959 0.957 0.956 0.963 0.961 0.962 0.961 0.954 0.956 0.962 0.956 0.955 0.962 0.960 0.965
[16:26:36.834] <TB0> INFO: non-linearity RMS: 0.004 0.005 0.006 0.006 0.005 0.006 0.007 0.007 0.005 0.007 0.004 0.005 0.006 0.006 0.004 0.004
[16:26:36.834] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:26:36.853] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:26:36.872] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:26:36.892] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:26:36.910] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:26:36.930] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:26:36.948] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:26:36.967] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:26:36.986] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:26:37.007] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:26:37.025] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:26:37.043] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:26:37.060] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:26:37.078] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:26:37.095] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:26:37.113] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:26:37.130] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[16:26:37.130] <TB0> INFO: Decoding statistics:
[16:26:37.130] <TB0> INFO: General information:
[16:26:37.130] <TB0> INFO: 16bit words read: 2662400
[16:26:37.130] <TB0> INFO: valid events total: 166400
[16:26:37.130] <TB0> INFO: empty events: 0
[16:26:37.130] <TB0> INFO: valid events with pixels: 166400
[16:26:37.130] <TB0> INFO: valid pixel hits: 665504
[16:26:37.130] <TB0> INFO: Event errors: 0
[16:26:37.130] <TB0> INFO: start marker: 0
[16:26:37.130] <TB0> INFO: stop marker: 0
[16:26:37.130] <TB0> INFO: overflow: 0
[16:26:37.130] <TB0> INFO: invalid 5bit words: 0
[16:26:37.130] <TB0> INFO: invalid XOR eye diagram: 0
[16:26:37.130] <TB0> INFO: frame (failed synchr.): 0
[16:26:37.130] <TB0> INFO: idle data (no TBM trl): 0
[16:26:37.130] <TB0> INFO: no data (only TBM hdr): 0
[16:26:37.130] <TB0> INFO: TBM errors: 0
[16:26:37.130] <TB0> INFO: flawed TBM headers: 0
[16:26:37.130] <TB0> INFO: flawed TBM trailers: 0
[16:26:37.130] <TB0> INFO: event ID mismatches: 0
[16:26:37.130] <TB0> INFO: ROC errors: 0
[16:26:37.130] <TB0> INFO: missing ROC header(s): 0
[16:26:37.130] <TB0> INFO: misplaced readback start: 0
[16:26:37.130] <TB0> INFO: Pixel decoding errors: 0
[16:26:37.130] <TB0> INFO: pixel data incomplete: 0
[16:26:37.130] <TB0> INFO: pixel address: 0
[16:26:37.130] <TB0> INFO: pulse height fill bit: 0
[16:26:37.130] <TB0> INFO: buffer corruption: 0
[16:26:37.137] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C15.dat
[16:26:37.138] <TB0> INFO: ######################################################################
[16:26:37.138] <TB0> INFO: PixTestReadback::doTest()
[16:26:37.138] <TB0> INFO: ######################################################################
[16:26:37.138] <TB0> INFO: ----------------------------------------------------------------------
[16:26:37.138] <TB0> INFO: PixTestReadback::CalibrateVd()
[16:26:37.138] <TB0> INFO: ----------------------------------------------------------------------
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C0.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C1.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C2.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C3.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C4.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C5.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C6.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C7.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C8.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C9.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C10.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C11.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C12.dat
[16:26:46.585] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C13.dat
[16:26:46.586] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C14.dat
[16:26:46.586] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C15.dat
[16:26:46.615] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:26:46.615] <TB0> INFO: ----------------------------------------------------------------------
[16:26:46.615] <TB0> INFO: PixTestReadback::CalibrateVa()
[16:26:46.615] <TB0> INFO: ----------------------------------------------------------------------
[16:26:56.074] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C0.dat
[16:26:56.074] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C1.dat
[16:26:56.074] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C2.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C3.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C4.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C5.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C6.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C7.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C8.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C9.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C10.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C11.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C12.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C13.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C14.dat
[16:26:56.075] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C15.dat
[16:26:56.101] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:26:56.101] <TB0> INFO: ----------------------------------------------------------------------
[16:26:56.101] <TB0> INFO: PixTestReadback::readbackVbg()
[16:26:56.101] <TB0> INFO: ----------------------------------------------------------------------
[16:27:03.389] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:27:03.390] <TB0> INFO: ----------------------------------------------------------------------
[16:27:03.390] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[16:27:03.390] <TB0> INFO: ----------------------------------------------------------------------
[16:27:03.390] <TB0> INFO: Vbg will be calibrated using Vd calibration
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.9calibrated Vbg = 1.22974 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.3calibrated Vbg = 1.22765 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.2calibrated Vbg = 1.23014 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.8calibrated Vbg = 1.24058 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 163.9calibrated Vbg = 1.24466 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 159.9calibrated Vbg = 1.24179 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 153calibrated Vbg = 1.24769 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 154.3calibrated Vbg = 1.2471 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151calibrated Vbg = 1.24993 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.9calibrated Vbg = 1.24143 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 159.7calibrated Vbg = 1.24261 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 158.6calibrated Vbg = 1.23023 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.1calibrated Vbg = 1.22958 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 159.1calibrated Vbg = 1.23026 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.5calibrated Vbg = 1.2263 :::*/*/*/*/
[16:27:03.390] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 148.9calibrated Vbg = 1.23264 :::*/*/*/*/
[16:27:03.392] <TB0> INFO: ----------------------------------------------------------------------
[16:27:03.392] <TB0> INFO: PixTestReadback::CalibrateIa()
[16:27:03.392] <TB0> INFO: ----------------------------------------------------------------------
[16:29:38.748] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C0.dat
[16:29:38.749] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C1.dat
[16:29:38.749] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C2.dat
[16:29:38.749] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C3.dat
[16:29:38.749] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C4.dat
[16:29:38.749] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C5.dat
[16:29:38.749] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C6.dat
[16:29:38.749] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C7.dat
[16:29:38.750] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C8.dat
[16:29:38.750] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C9.dat
[16:29:38.750] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C10.dat
[16:29:38.750] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C11.dat
[16:29:38.750] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C12.dat
[16:29:38.750] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C13.dat
[16:29:38.750] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C14.dat
[16:29:38.750] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2156_FullQualification17C_2016-02-10_10h02m_1455094972//004_FulltestPxar_p17//readbackCal_C15.dat
[16:29:38.779] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:29:38.780] <TB0> INFO: PixTestReadback::doTest() done
[16:29:38.780] <TB0> INFO: Decoding statistics:
[16:29:38.780] <TB0> INFO: General information:
[16:29:38.780] <TB0> INFO: 16bit words read: 1024
[16:29:38.780] <TB0> INFO: valid events total: 128
[16:29:38.780] <TB0> INFO: empty events: 128
[16:29:38.780] <TB0> INFO: valid events with pixels: 0
[16:29:38.780] <TB0> INFO: valid pixel hits: 0
[16:29:38.780] <TB0> INFO: Event errors: 0
[16:29:38.780] <TB0> INFO: start marker: 0
[16:29:38.780] <TB0> INFO: stop marker: 0
[16:29:38.780] <TB0> INFO: overflow: 0
[16:29:38.780] <TB0> INFO: invalid 5bit words: 0
[16:29:38.780] <TB0> INFO: invalid XOR eye diagram: 0
[16:29:38.780] <TB0> INFO: frame (failed synchr.): 0
[16:29:38.780] <TB0> INFO: idle data (no TBM trl): 0
[16:29:38.780] <TB0> INFO: no data (only TBM hdr): 0
[16:29:38.780] <TB0> INFO: TBM errors: 0
[16:29:38.780] <TB0> INFO: flawed TBM headers: 0
[16:29:38.780] <TB0> INFO: flawed TBM trailers: 0
[16:29:38.780] <TB0> INFO: event ID mismatches: 0
[16:29:38.780] <TB0> INFO: ROC errors: 0
[16:29:38.780] <TB0> INFO: missing ROC header(s): 0
[16:29:38.780] <TB0> INFO: misplaced readback start: 0
[16:29:38.780] <TB0> INFO: Pixel decoding errors: 0
[16:29:38.780] <TB0> INFO: pixel data incomplete: 0
[16:29:38.780] <TB0> INFO: pixel address: 0
[16:29:38.780] <TB0> INFO: pulse height fill bit: 0
[16:29:38.780] <TB0> INFO: buffer corruption: 0
[16:29:38.797] <TB0> INFO: Decoding statistics:
[16:29:38.797] <TB0> INFO: General information:
[16:29:38.797] <TB0> INFO: 16bit words read: 2755340
[16:29:38.797] <TB0> INFO: valid events total: 176768
[16:29:38.797] <TB0> INFO: empty events: 7869
[16:29:38.797] <TB0> INFO: valid events with pixels: 168899
[16:29:38.797] <TB0> INFO: valid pixel hits: 668003
[16:29:38.797] <TB0> INFO: Event errors: 0
[16:29:38.797] <TB0> INFO: start marker: 0
[16:29:38.797] <TB0> INFO: stop marker: 0
[16:29:38.797] <TB0> INFO: overflow: 0
[16:29:38.797] <TB0> INFO: invalid 5bit words: 0
[16:29:38.797] <TB0> INFO: invalid XOR eye diagram: 0
[16:29:38.797] <TB0> INFO: frame (failed synchr.): 0
[16:29:38.797] <TB0> INFO: idle data (no TBM trl): 0
[16:29:38.797] <TB0> INFO: no data (only TBM hdr): 0
[16:29:38.797] <TB0> INFO: TBM errors: 0
[16:29:38.797] <TB0> INFO: flawed TBM headers: 0
[16:29:38.797] <TB0> INFO: flawed TBM trailers: 0
[16:29:38.797] <TB0> INFO: event ID mismatches: 0
[16:29:38.797] <TB0> INFO: ROC errors: 0
[16:29:38.797] <TB0> INFO: missing ROC header(s): 0
[16:29:38.797] <TB0> INFO: misplaced readback start: 0
[16:29:38.797] <TB0> INFO: Pixel decoding errors: 0
[16:29:38.797] <TB0> INFO: pixel data incomplete: 0
[16:29:38.798] <TB0> INFO: pixel address: 0
[16:29:38.798] <TB0> INFO: pulse height fill bit: 0
[16:29:38.798] <TB0> INFO: buffer corruption: 0
[16:29:38.798] <TB0> INFO: enter test to run
[16:29:38.798] <TB0> INFO: test: exit no parameter change
[16:29:38.955] <TB0> QUIET: Connection to board 73 closed.
[16:29:39.034] <TB0> INFO: pXar: this is the end, my friend