[18:29:46.681] <TB0> INFO: *** Welcome to pxar ***
[18:29:46.681] <TB0> INFO: *** Today: 2016/11/04
[18:29:46.687] <TB0> INFO: *** Version: c8ba-dirty
[18:29:46.687] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C15.dat
[18:29:46.687] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//tbmParameters_C1b.dat
[18:29:46.687] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//defaultMaskFile.dat
[18:29:46.687] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters_C15.dat
[18:29:46.741] <TB0> INFO: clk: 4
[18:29:46.741] <TB0> INFO: ctr: 4
[18:29:46.741] <TB0> INFO: sda: 19
[18:29:46.741] <TB0> INFO: tin: 9
[18:29:46.741] <TB0> INFO: level: 15
[18:29:46.741] <TB0> INFO: triggerdelay: 0
[18:29:46.741] <TB0> QUIET: Instanciating API for pxar v2.1.0+867~g2c7f7f2
[18:29:46.741] <TB0> INFO: Log level: INFO
[18:29:46.749] <TB0> INFO: Found DTB DTB_WS6AYH
[18:29:46.757] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[18:29:46.759] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[18:29:46.761] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[18:29:48.245] <TB0> INFO: DUT info:
[18:29:48.245] <TB0> INFO: The DUT currently contains the following objects:
[18:29:48.245] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[18:29:48.245] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:29:48.245] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:29:48.245] <TB0> INFO: TBM Core alpha (2): 7 registers set
[18:29:48.245] <TB0> INFO: TBM Core beta (3): 7 registers set
[18:29:48.245] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[18:29:48.245] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.245] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.245] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.245] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.245] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.245] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.246] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:29:48.646] <TB0> INFO: enter 'restricted' command line mode
[18:29:48.646] <TB0> INFO: enter test to run
[18:29:48.646] <TB0> INFO: test: pretest no parameter change
[18:29:48.646] <TB0> INFO: running: pretest
[18:29:49.193] <TB0> INFO: ######################################################################
[18:29:49.193] <TB0> INFO: PixTestPretest::doTest()
[18:29:49.193] <TB0> INFO: ######################################################################
[18:29:49.194] <TB0> INFO: ----------------------------------------------------------------------
[18:29:49.194] <TB0> INFO: PixTestPretest::programROC()
[18:29:49.194] <TB0> INFO: ----------------------------------------------------------------------
[18:30:07.207] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[18:30:07.207] <TB0> INFO: IA differences per ROC: 17.7 19.3 19.3 18.5 20.1 16.9 17.7 17.7 19.3 20.9 19.3 17.7 19.3 20.1 18.5 18.5
[18:30:07.243] <TB0> INFO: ----------------------------------------------------------------------
[18:30:07.243] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[18:30:07.243] <TB0> INFO: ----------------------------------------------------------------------
[18:30:28.478] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[18:30:28.478] <TB0> INFO: i(loss) [mA/ROC]: 19.3 18.5 19.3 18.5 18.5 19.3 18.5 20.1 18.5 19.3 18.5 19.3 19.3 18.5 18.5 19.3
[18:30:28.507] <TB0> INFO: ----------------------------------------------------------------------
[18:30:28.507] <TB0> INFO: PixTestPretest::findTiming()
[18:30:28.507] <TB0> INFO: ----------------------------------------------------------------------
[18:30:28.507] <TB0> INFO: PixTestCmd::init()

[18:30:59.701] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[18:30:59.701] <TB0> INFO: (success/tries = 100/100), width = 4
[18:31:01.201] <TB0> INFO: ----------------------------------------------------------------------
[18:31:01.201] <TB0> INFO: PixTestPretest::findWorkingPixel()
[18:31:01.201] <TB0> INFO: ----------------------------------------------------------------------
[18:31:01.293] <TB0> INFO: Expecting 231680 events.
[18:31:10.962] <TB0> INFO: 231680 events read in total (9078ms).
[18:31:10.969] <TB0> INFO: Test took 9766ms.
[18:31:11.213] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[18:31:11.244] <TB0> INFO: ----------------------------------------------------------------------
[18:31:11.244] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[18:31:11.244] <TB0> INFO: ----------------------------------------------------------------------
[18:31:11.336] <TB0> INFO: Expecting 231680 events.
[18:31:20.002] <TB0> INFO: 231680 events read in total (9074ms).
[18:31:21.009] <TB0> INFO: Test took 9761ms.
[18:31:21.269] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[18:31:21.269] <TB0> INFO: CalDel: 87 81 91 95 85 86 90 98 102 93 83 81 92 83 86 90
[18:31:21.269] <TB0> INFO: VthrComp: 54 51 51 51 52 51 51 51 51 54 51 53 53 51 51 51
[18:31:21.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C0.dat
[18:31:21.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C1.dat
[18:31:21.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C2.dat
[18:31:21.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C3.dat
[18:31:21.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C4.dat
[18:31:21.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C5.dat
[18:31:21.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C6.dat
[18:31:21.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C7.dat
[18:31:21.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C8.dat
[18:31:21.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C9.dat
[18:31:21.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C10.dat
[18:31:21.274] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C11.dat
[18:31:21.274] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C12.dat
[18:31:21.274] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C13.dat
[18:31:21.274] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C14.dat
[18:31:21.274] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters_C15.dat
[18:31:21.274] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//tbmParameters_C0a.dat
[18:31:21.274] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//tbmParameters_C0b.dat
[18:31:21.274] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//tbmParameters_C1a.dat
[18:31:21.274] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//tbmParameters_C1b.dat
[18:31:21.274] <TB0> INFO: PixTestPretest::doTest() done, duration: 92 seconds
[18:31:21.407] <TB0> INFO: enter test to run
[18:31:21.407] <TB0> INFO: test: fulltest no parameter change
[18:31:21.407] <TB0> INFO: running: fulltest
[18:31:21.407] <TB0> INFO: ######################################################################
[18:31:21.407] <TB0> INFO: PixTestFullTest::doTest()
[18:31:21.407] <TB0> INFO: ######################################################################
[18:31:21.408] <TB0> INFO: ######################################################################
[18:31:21.408] <TB0> INFO: PixTestAlive::doTest()
[18:31:21.408] <TB0> INFO: ######################################################################
[18:31:21.409] <TB0> INFO: ----------------------------------------------------------------------
[18:31:21.409] <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)
[18:31:21.409] <TB0> INFO: ----------------------------------------------------------------------
[18:31:21.642] <TB0> INFO: Expecting 41600 events.
[18:31:25.158] <TB0> INFO: 41600 events read in total (2925ms).
[18:31:25.158] <TB0> INFO: Test took 3748ms.
[18:31:25.384] <TB0> INFO: PixTestAlive::aliveTest() done
[18:31:25.384] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:31:25.386] <TB0> INFO: ----------------------------------------------------------------------
[18:31:25.386] <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)
[18:31:25.386] <TB0> INFO: ----------------------------------------------------------------------
[18:31:25.618] <TB0> INFO: Expecting 41600 events.
[18:31:28.527] <TB0> INFO: 41600 events read in total (2318ms).
[18:31:28.527] <TB0> INFO: Test took 3141ms.
[18:31:28.528] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[18:31:28.766] <TB0> INFO: PixTestAlive::maskTest() done
[18:31:28.766] <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
[18:31:28.767] <TB0> INFO: ----------------------------------------------------------------------
[18:31:28.767] <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)
[18:31:28.767] <TB0> INFO: ----------------------------------------------------------------------
[18:31:28.000] <TB0> INFO: Expecting 41600 events.
[18:31:32.443] <TB0> INFO: 41600 events read in total (2851ms).
[18:31:32.443] <TB0> INFO: Test took 3674ms.
[18:31:32.670] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[18:31:32.670] <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
[18:31:32.670] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[18:31:32.670] <TB0> INFO: Decoding statistics:
[18:31:32.670] <TB0> INFO: General information:
[18:31:32.670] <TB0> INFO: 16bit words read: 0
[18:31:32.670] <TB0> INFO: valid events total: 0
[18:31:32.670] <TB0> INFO: empty events: 0
[18:31:32.670] <TB0> INFO: valid events with pixels: 0
[18:31:32.670] <TB0> INFO: valid pixel hits: 0
[18:31:32.670] <TB0> INFO: Event errors: 0
[18:31:32.670] <TB0> INFO: start marker: 0
[18:31:32.670] <TB0> INFO: stop marker: 0
[18:31:32.670] <TB0> INFO: overflow: 0
[18:31:32.670] <TB0> INFO: invalid 5bit words: 0
[18:31:32.670] <TB0> INFO: invalid XOR eye diagram: 0
[18:31:32.670] <TB0> INFO: frame (failed synchr.): 0
[18:31:32.670] <TB0> INFO: idle data (no TBM trl): 0
[18:31:32.670] <TB0> INFO: no data (only TBM hdr): 0
[18:31:32.670] <TB0> INFO: TBM errors: 0
[18:31:32.670] <TB0> INFO: flawed TBM headers: 0
[18:31:32.670] <TB0> INFO: flawed TBM trailers: 0
[18:31:32.670] <TB0> INFO: event ID mismatches: 0
[18:31:32.670] <TB0> INFO: ROC errors: 0
[18:31:32.670] <TB0> INFO: missing ROC header(s): 0
[18:31:32.670] <TB0> INFO: misplaced readback start: 0
[18:31:32.670] <TB0> INFO: Pixel decoding errors: 0
[18:31:32.670] <TB0> INFO: pixel data incomplete: 0
[18:31:32.671] <TB0> INFO: pixel address: 0
[18:31:32.671] <TB0> INFO: pulse height fill bit: 0
[18:31:32.671] <TB0> INFO: buffer corruption: 0
[18:31:32.677] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C15.dat
[18:31:32.678] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr_C15.dat

[18:31:32.678] <TB0> INFO: ######################################################################
[18:31:32.678] <TB0> INFO: PixTestReadback::doTest()
[18:31:32.678] <TB0> INFO: ######################################################################
[18:31:32.678] <TB0> INFO: ----------------------------------------------------------------------
[18:31:32.678] <TB0> INFO: PixTestReadback::CalibrateVd()
[18:31:32.678] <TB0> INFO: ----------------------------------------------------------------------
[18:31:42.636] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C0.dat
[18:31:42.636] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C1.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C2.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C3.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C4.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C5.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C6.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C7.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C8.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C9.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C10.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C11.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C12.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C13.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C14.dat
[18:31:42.637] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C15.dat
[18:31:42.665] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:31:42.665] <TB0> INFO: ----------------------------------------------------------------------
[18:31:42.665] <TB0> INFO: PixTestReadback::CalibrateVa()
[18:31:42.665] <TB0> INFO: ----------------------------------------------------------------------
[18:31:52.554] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C0.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C1.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C2.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C3.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C4.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C5.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C6.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C7.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C8.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C9.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C10.dat
[18:31:52.555] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C11.dat
[18:31:52.556] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C12.dat
[18:31:52.556] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C13.dat
[18:31:52.556] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C14.dat
[18:31:52.556] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C15.dat
[18:31:52.585] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:31:52.585] <TB0> INFO: ----------------------------------------------------------------------
[18:31:52.585] <TB0> INFO: PixTestReadback::readbackVbg()
[18:31:52.585] <TB0> INFO: ----------------------------------------------------------------------
[18:32:00.224] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:32:00.224] <TB0> INFO: ----------------------------------------------------------------------
[18:32:00.224] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[18:32:00.224] <TB0> INFO: ----------------------------------------------------------------------
[18:32:00.224] <TB0> INFO: Vbg will be calibrated using Vd calibration
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.7calibrated Vbg = 1.19046 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.9calibrated Vbg = 1.19342 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.5calibrated Vbg = 1.18199 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.1calibrated Vbg = 1.18331 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 161.6calibrated Vbg = 1.18923 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.4calibrated Vbg = 1.19235 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151.3calibrated Vbg = 1.19279 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153calibrated Vbg = 1.19966 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 160.4calibrated Vbg = 1.19659 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 161.9calibrated Vbg = 1.18911 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 157calibrated Vbg = 1.1832 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159.3calibrated Vbg = 1.17691 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 163.5calibrated Vbg = 1.1868 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 146.3calibrated Vbg = 1.18248 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156calibrated Vbg = 1.19096 :::*/*/*/*/
[18:32:00.224] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.2calibrated Vbg = 1.19088 :::*/*/*/*/
[18:32:00.226] <TB0> INFO: ----------------------------------------------------------------------
[18:32:00.226] <TB0> INFO: PixTestReadback::CalibrateIa()
[18:32:00.226] <TB0> INFO: ----------------------------------------------------------------------
[18:34:40.510] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C0.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C1.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C2.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C3.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C4.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C5.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C6.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C7.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C8.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C9.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C10.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C11.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C12.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C13.dat
[18:34:40.511] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C14.dat
[18:34:40.512] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//readbackCal_C15.dat
[18:34:40.540] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:34:40.541] <TB0> INFO: PixTestReadback::doTest() done
[18:34:40.541] <TB0> INFO: Decoding statistics:
[18:34:40.541] <TB0> INFO: General information:
[18:34:40.541] <TB0> INFO: 16bit words read: 1536
[18:34:40.541] <TB0> INFO: valid events total: 256
[18:34:40.541] <TB0> INFO: empty events: 256
[18:34:40.541] <TB0> INFO: valid events with pixels: 0
[18:34:40.541] <TB0> INFO: valid pixel hits: 0
[18:34:40.541] <TB0> INFO: Event errors: 0
[18:34:40.541] <TB0> INFO: start marker: 0
[18:34:40.541] <TB0> INFO: stop marker: 0
[18:34:40.541] <TB0> INFO: overflow: 0
[18:34:40.541] <TB0> INFO: invalid 5bit words: 0
[18:34:40.541] <TB0> INFO: invalid XOR eye diagram: 0
[18:34:40.541] <TB0> INFO: frame (failed synchr.): 0
[18:34:40.541] <TB0> INFO: idle data (no TBM trl): 0
[18:34:40.541] <TB0> INFO: no data (only TBM hdr): 0
[18:34:40.541] <TB0> INFO: TBM errors: 0
[18:34:40.541] <TB0> INFO: flawed TBM headers: 0
[18:34:40.541] <TB0> INFO: flawed TBM trailers: 0
[18:34:40.541] <TB0> INFO: event ID mismatches: 0
[18:34:40.541] <TB0> INFO: ROC errors: 0
[18:34:40.541] <TB0> INFO: missing ROC header(s): 0
[18:34:40.541] <TB0> INFO: misplaced readback start: 0
[18:34:40.541] <TB0> INFO: Pixel decoding errors: 0
[18:34:40.541] <TB0> INFO: pixel data incomplete: 0
[18:34:40.541] <TB0> INFO: pixel address: 0
[18:34:40.541] <TB0> INFO: pulse height fill bit: 0
[18:34:40.541] <TB0> INFO: buffer corruption: 0
[18:34:40.577] <TB0> INFO: ######################################################################
[18:34:40.577] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[18:34:40.577] <TB0> INFO: ######################################################################
[18:34:40.579] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[18:34:40.591] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[18:34:40.591] <TB0> INFO: run 1 of 1
[18:34:40.825] <TB0> INFO: Expecting 3120000 events.
[18:35:10.886] <TB0> INFO: 669025 events read in total (29469ms).











[18:35:40.660] <TB0> INFO: 1340835 events read in total (59243ms).











[18:36:10.793] <TB0> INFO: 2011420 events read in total (89377ms).











[18:36:40.677] <TB0> INFO: 2681630 events read in total (119260ms).




















[18:37:00.232] <TB0> INFO: 3120000 events read in total (138815ms).
[18:37:00.299] <TB0> INFO: Test took 139709ms.
[18:37:26.231] <TB0> INFO: PixTestBBMap::doTest() done with 3 decoding errors: , duration: 165 seconds
[18:37:26.231] <TB0> INFO: number of dead bumps (per ROC): 4 1 0 20 9 3 2 2 3 1 18 4 23 15 2 0
[18:37:26.231] <TB0> INFO: separation cut (per ROC): 109 109 104 98 107 106 103 107 100 111 103 113 115 109 106 107
[18:37:26.231] <TB0> INFO: Decoding statistics:
[18:37:26.231] <TB0> INFO: General information:
[18:37:26.231] <TB0> INFO: 16bit words read: 0
[18:37:26.231] <TB0> INFO: valid events total: 0
[18:37:26.231] <TB0> INFO: empty events: 0
[18:37:26.231] <TB0> INFO: valid events with pixels: 0
[18:37:26.231] <TB0> INFO: valid pixel hits: 0
[18:37:26.231] <TB0> INFO: Event errors: 0
[18:37:26.231] <TB0> INFO: start marker: 0
[18:37:26.231] <TB0> INFO: stop marker: 0
[18:37:26.231] <TB0> INFO: overflow: 0
[18:37:26.231] <TB0> INFO: invalid 5bit words: 0
[18:37:26.231] <TB0> INFO: invalid XOR eye diagram: 0
[18:37:26.231] <TB0> INFO: frame (failed synchr.): 0
[18:37:26.231] <TB0> INFO: idle data (no TBM trl): 0
[18:37:26.231] <TB0> INFO: no data (only TBM hdr): 0
[18:37:26.231] <TB0> INFO: TBM errors: 0
[18:37:26.231] <TB0> INFO: flawed TBM headers: 0
[18:37:26.231] <TB0> INFO: flawed TBM trailers: 0
[18:37:26.231] <TB0> INFO: event ID mismatches: 0
[18:37:26.231] <TB0> INFO: ROC errors: 0
[18:37:26.231] <TB0> INFO: missing ROC header(s): 0
[18:37:26.231] <TB0> INFO: misplaced readback start: 0
[18:37:26.231] <TB0> INFO: Pixel decoding errors: 0
[18:37:26.231] <TB0> INFO: pixel data incomplete: 0
[18:37:26.231] <TB0> INFO: pixel address: 0
[18:37:26.231] <TB0> INFO: pulse height fill bit: 0
[18:37:26.231] <TB0> INFO: buffer corruption: 0
[18:37:26.274] <TB0> INFO: ######################################################################
[18:37:26.274] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:37:26.274] <TB0> INFO: ######################################################################
[18:37:26.274] <TB0> INFO: ----------------------------------------------------------------------
[18:37:26.274] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:37:26.274] <TB0> INFO: ----------------------------------------------------------------------
[18:37:26.274] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[18:37:26.286] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[18:37:26.286] <TB0> INFO: run 1 of 1
[18:37:26.519] <TB0> INFO: Expecting 36608000 events.
[18:37:50.269] <TB0> INFO: 697400 events read in total (23158ms).
[18:38:12.883] <TB0> INFO: 1379450 events read in total (45772ms).
[18:38:35.574] <TB0> INFO: 2060900 events read in total (68463ms).
[18:38:58.066] <TB0> INFO: 2741000 events read in total (90955ms).
[18:39:20.541] <TB0> INFO: 3420600 events read in total (113430ms).
[18:39:43.148] <TB0> INFO: 4097900 events read in total (136037ms).
[18:40:05.981] <TB0> INFO: 4778100 events read in total (158870ms).
[18:40:28.647] <TB0> INFO: 5457600 events read in total (181536ms).
[18:40:51.022] <TB0> INFO: 6135700 events read in total (203911ms).
[18:41:13.916] <TB0> INFO: 6813300 events read in total (226805ms).
[18:41:36.219] <TB0> INFO: 7488600 events read in total (249108ms).
[18:41:58.486] <TB0> INFO: 8164400 events read in total (271375ms).
[18:42:20.897] <TB0> INFO: 8841050 events read in total (293786ms).
[18:42:43.417] <TB0> INFO: 9517200 events read in total (316306ms).
[18:43:06.176] <TB0> INFO: 10192200 events read in total (339065ms).
[18:43:28.676] <TB0> INFO: 10866900 events read in total (361565ms).
[18:43:50.878] <TB0> INFO: 11542350 events read in total (383767ms).
[18:44:13.255] <TB0> INFO: 12217050 events read in total (406144ms).
[18:44:35.877] <TB0> INFO: 12890900 events read in total (428766ms).
[18:44:58.396] <TB0> INFO: 13565900 events read in total (451285ms).
[18:45:20.902] <TB0> INFO: 14239600 events read in total (473791ms).
[18:45:43.179] <TB0> INFO: 14915050 events read in total (496068ms).
[18:46:05.473] <TB0> INFO: 15588950 events read in total (518362ms).
[18:46:28.050] <TB0> INFO: 16260400 events read in total (540939ms).
[18:46:50.439] <TB0> INFO: 16931150 events read in total (563328ms).
[18:47:13.121] <TB0> INFO: 17604250 events read in total (586010ms).
[18:47:35.777] <TB0> INFO: 18274050 events read in total (608666ms).
[18:47:58.408] <TB0> INFO: 18944050 events read in total (631297ms).
[18:48:20.815] <TB0> INFO: 19613350 events read in total (653704ms).
[18:48:43.524] <TB0> INFO: 20282550 events read in total (676413ms).
[18:49:06.092] <TB0> INFO: 20950950 events read in total (698981ms).
[18:49:28.452] <TB0> INFO: 21620000 events read in total (721341ms).
[18:49:50.974] <TB0> INFO: 22286750 events read in total (743863ms).
[18:50:13.539] <TB0> INFO: 22954850 events read in total (766428ms).
[18:50:36.015] <TB0> INFO: 23622100 events read in total (788904ms).
[18:50:58.416] <TB0> INFO: 24288300 events read in total (811305ms).
[18:51:20.595] <TB0> INFO: 24956550 events read in total (833484ms).
[18:51:43.124] <TB0> INFO: 25625450 events read in total (856013ms).
[18:52:05.640] <TB0> INFO: 26294050 events read in total (878529ms).
[18:52:27.927] <TB0> INFO: 26961200 events read in total (900816ms).
[18:52:50.445] <TB0> INFO: 27631050 events read in total (923334ms).
[18:53:12.955] <TB0> INFO: 28297350 events read in total (945844ms).
[18:53:35.429] <TB0> INFO: 28964350 events read in total (968318ms).
[18:53:57.746] <TB0> INFO: 29629550 events read in total (990635ms).
[18:54:19.981] <TB0> INFO: 30297150 events read in total (1012870ms).
[18:54:42.390] <TB0> INFO: 30964000 events read in total (1035279ms).
[18:55:04.591] <TB0> INFO: 31627300 events read in total (1057480ms).
[18:55:27.074] <TB0> INFO: 32292300 events read in total (1079963ms).
[18:55:49.337] <TB0> INFO: 32960050 events read in total (1102226ms).
[18:56:11.646] <TB0> INFO: 33628600 events read in total (1124535ms).
[18:56:34.169] <TB0> INFO: 34296850 events read in total (1147058ms).
[18:56:56.838] <TB0> INFO: 34964000 events read in total (1169727ms).
[18:57:18.974] <TB0> INFO: 35630650 events read in total (1191863ms).
[18:57:41.427] <TB0> INFO: 36307250 events read in total (1214316ms).
[18:57:51.513] <TB0> INFO: 36608000 events read in total (1224402ms).
[18:57:51.565] <TB0> INFO: Test took 1225279ms.
[18:57:51.894] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:57:53.381] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:57:55.086] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:57:56.909] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:57:58.829] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:00.806] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:02.698] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:04.602] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:06.534] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:08.329] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:10.275] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:11.936] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:13.521] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:15.393] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:17.166] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:19.157] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:58:21.025] <TB0> INFO: PixTestScurves::scurves() done
[18:58:21.025] <TB0> INFO: Vcal mean: 132.98 131.00 115.88 122.40 127.56 123.57 107.28 125.43 122.50 129.93 126.22 134.05 131.63 124.65 125.74 118.53
[18:58:21.025] <TB0> INFO: Vcal RMS: 6.39 6.06 5.37 6.26 5.87 5.74 5.04 6.63 6.51 7.12 6.25 6.11 6.47 5.96 6.26 6.84
[18:58:21.025] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1254 seconds
[18:58:21.025] <TB0> INFO: Decoding statistics:
[18:58:21.025] <TB0> INFO: General information:
[18:58:21.025] <TB0> INFO: 16bit words read: 0
[18:58:21.026] <TB0> INFO: valid events total: 0
[18:58:21.026] <TB0> INFO: empty events: 0
[18:58:21.026] <TB0> INFO: valid events with pixels: 0
[18:58:21.026] <TB0> INFO: valid pixel hits: 0
[18:58:21.026] <TB0> INFO: Event errors: 0
[18:58:21.026] <TB0> INFO: start marker: 0
[18:58:21.026] <TB0> INFO: stop marker: 0
[18:58:21.026] <TB0> INFO: overflow: 0
[18:58:21.026] <TB0> INFO: invalid 5bit words: 0
[18:58:21.026] <TB0> INFO: invalid XOR eye diagram: 0
[18:58:21.026] <TB0> INFO: frame (failed synchr.): 0
[18:58:21.026] <TB0> INFO: idle data (no TBM trl): 0
[18:58:21.026] <TB0> INFO: no data (only TBM hdr): 0
[18:58:21.026] <TB0> INFO: TBM errors: 0
[18:58:21.026] <TB0> INFO: flawed TBM headers: 0
[18:58:21.026] <TB0> INFO: flawed TBM trailers: 0
[18:58:21.026] <TB0> INFO: event ID mismatches: 0
[18:58:21.026] <TB0> INFO: ROC errors: 0
[18:58:21.026] <TB0> INFO: missing ROC header(s): 0
[18:58:21.026] <TB0> INFO: misplaced readback start: 0
[18:58:21.026] <TB0> INFO: Pixel decoding errors: 0
[18:58:21.026] <TB0> INFO: pixel data incomplete: 0
[18:58:21.026] <TB0> INFO: pixel address: 0
[18:58:21.026] <TB0> INFO: pulse height fill bit: 0
[18:58:21.026] <TB0> INFO: buffer corruption: 0
[18:58:21.090] <TB0> INFO: ######################################################################
[18:58:21.090] <TB0> INFO: PixTestTrim::doTest()
[18:58:21.090] <TB0> INFO: ######################################################################
[18:58:21.091] <TB0> INFO: ----------------------------------------------------------------------
[18:58:21.091] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[18:58:21.091] <TB0> INFO: ----------------------------------------------------------------------
[18:58:21.131] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[18:58:21.131] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:58:21.141] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[18:58:21.141] <TB0> INFO: run 1 of 1
[18:58:21.376] <TB0> INFO: Expecting 5025280 events.
[18:58:52.339] <TB0> INFO: 832272 events read in total (30368ms).
[18:59:22.157] <TB0> INFO: 1661360 events read in total (60186ms).
[18:59:52.081] <TB0> INFO: 2487464 events read in total (90111ms).
[19:00:21.906] <TB0> INFO: 3310272 events read in total (119935ms).
[19:00:51.767] <TB0> INFO: 4130360 events read in total (149796ms).
[19:01:21.693] <TB0> INFO: 4947936 events read in total (179722ms).
[19:01:24.785] <TB0> INFO: 5025280 events read in total (182814ms).
[19:01:24.831] <TB0> INFO: Test took 183690ms.
[19:01:42.263] <TB0> INFO: ROC 0 VthrComp = 131
[19:01:42.263] <TB0> INFO: ROC 1 VthrComp = 129
[19:01:42.263] <TB0> INFO: ROC 2 VthrComp = 118
[19:01:42.263] <TB0> INFO: ROC 3 VthrComp = 119
[19:01:42.263] <TB0> INFO: ROC 4 VthrComp = 126
[19:01:42.264] <TB0> INFO: ROC 5 VthrComp = 127
[19:01:42.264] <TB0> INFO: ROC 6 VthrComp = 109
[19:01:42.264] <TB0> INFO: ROC 7 VthrComp = 123
[19:01:42.264] <TB0> INFO: ROC 8 VthrComp = 113
[19:01:42.264] <TB0> INFO: ROC 9 VthrComp = 131
[19:01:42.264] <TB0> INFO: ROC 10 VthrComp = 126
[19:01:42.264] <TB0> INFO: ROC 11 VthrComp = 134
[19:01:42.265] <TB0> INFO: ROC 12 VthrComp = 133
[19:01:42.265] <TB0> INFO: ROC 13 VthrComp = 129
[19:01:42.265] <TB0> INFO: ROC 14 VthrComp = 122
[19:01:42.265] <TB0> INFO: ROC 15 VthrComp = 122
[19:01:42.498] <TB0> INFO: Expecting 41600 events.
[19:01:45.988] <TB0> INFO: 41600 events read in total (2899ms).
[19:01:45.989] <TB0> INFO: Test took 3723ms.
[19:01:45.998] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[19:01:45.998] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:01:46.007] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:01:46.007] <TB0> INFO: run 1 of 1
[19:01:46.285] <TB0> INFO: Expecting 5025280 events.
[19:02:12.736] <TB0> INFO: 592160 events read in total (25860ms).
[19:02:38.250] <TB0> INFO: 1183720 events read in total (51374ms).
[19:03:03.495] <TB0> INFO: 1774640 events read in total (76619ms).
[19:03:28.856] <TB0> INFO: 2364848 events read in total (101980ms).
[19:03:54.400] <TB0> INFO: 2952960 events read in total (127524ms).
[19:04:19.600] <TB0> INFO: 3540352 events read in total (152724ms).
[19:04:45.067] <TB0> INFO: 4126128 events read in total (178191ms).
[19:05:10.214] <TB0> INFO: 4711728 events read in total (203338ms).
[19:05:23.872] <TB0> INFO: 5025280 events read in total (216996ms).
[19:05:23.929] <TB0> INFO: Test took 217922ms.
[19:05:51.411] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 65.8296 for pixel 19/36 mean/min/max = 49.772/33.6509/65.8931
[19:05:51.411] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 62.2768 for pixel 3/19 mean/min/max = 47.2982/32.239/62.3575
[19:05:51.412] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 59.5658 for pixel 9/12 mean/min/max = 45.7669/31.8115/59.7222
[19:05:51.412] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 61.2109 for pixel 5/3 mean/min/max = 46.3919/31.5676/61.2163
[19:05:51.413] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 63.0971 for pixel 15/1 mean/min/max = 47.0654/30.9536/63.1772
[19:05:51.413] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 59.8955 for pixel 47/16 mean/min/max = 45.9451/31.8495/60.0407
[19:05:51.413] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 60.292 for pixel 14/75 mean/min/max = 47.9756/35.5765/60.3746
[19:05:51.414] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 63.5455 for pixel 20/10 mean/min/max = 48.1133/32.6142/63.6124
[19:05:51.414] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 64.2104 for pixel 18/6 mean/min/max = 48.2205/32.2259/64.215
[19:05:51.415] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 64.718 for pixel 12/11 mean/min/max = 48.3247/31.7797/64.8697
[19:05:51.415] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 61.1237 for pixel 28/7 mean/min/max = 46.8269/32.4197/61.2341
[19:05:51.416] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 62.5764 for pixel 22/7 mean/min/max = 48.215/33.7427/62.6873
[19:05:51.416] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 61.5823 for pixel 51/11 mean/min/max = 47.4447/33.1472/61.7422
[19:05:51.416] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 59.1566 for pixel 4/0 mean/min/max = 45.5817/31.9867/59.1768
[19:05:51.417] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 63.1359 for pixel 6/5 mean/min/max = 47.4888/31.8016/63.176
[19:05:51.417] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 61.7611 for pixel 0/0 mean/min/max = 47.0051/32.1372/61.873
[19:05:51.418] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:05:51.506] <TB0> INFO: Expecting 411648 events.
[19:06:00.755] <TB0> INFO: 411648 events read in total (8657ms).
[19:06:00.765] <TB0> INFO: Expecting 411648 events.
[19:06:09.968] <TB0> INFO: 411648 events read in total (8800ms).
[19:06:09.977] <TB0> INFO: Expecting 411648 events.
[19:06:19.107] <TB0> INFO: 411648 events read in total (8727ms).
[19:06:19.120] <TB0> INFO: Expecting 411648 events.
[19:06:28.219] <TB0> INFO: 411648 events read in total (8696ms).
[19:06:28.234] <TB0> INFO: Expecting 411648 events.
[19:06:37.277] <TB0> INFO: 411648 events read in total (8640ms).
[19:06:37.301] <TB0> INFO: Expecting 411648 events.
[19:06:46.350] <TB0> INFO: 411648 events read in total (8646ms).
[19:06:46.375] <TB0> INFO: Expecting 411648 events.
[19:06:55.420] <TB0> INFO: 411648 events read in total (8641ms).
[19:06:55.441] <TB0> INFO: Expecting 411648 events.
[19:07:04.551] <TB0> INFO: 411648 events read in total (8707ms).
[19:07:04.576] <TB0> INFO: Expecting 411648 events.
[19:07:13.604] <TB0> INFO: 411648 events read in total (8625ms).
[19:07:13.639] <TB0> INFO: Expecting 411648 events.
[19:07:22.661] <TB0> INFO: 411648 events read in total (8619ms).
[19:07:22.692] <TB0> INFO: Expecting 411648 events.
[19:07:31.764] <TB0> INFO: 411648 events read in total (8669ms).
[19:07:31.804] <TB0> INFO: Expecting 411648 events.
[19:07:40.804] <TB0> INFO: 411648 events read in total (8597ms).
[19:07:40.842] <TB0> INFO: Expecting 411648 events.
[19:07:49.838] <TB0> INFO: 411648 events read in total (8593ms).
[19:07:49.882] <TB0> INFO: Expecting 411648 events.
[19:07:58.978] <TB0> INFO: 411648 events read in total (8693ms).
[19:07:59.024] <TB0> INFO: Expecting 411648 events.
[19:08:08.112] <TB0> INFO: 411648 events read in total (8685ms).
[19:08:08.153] <TB0> INFO: Expecting 411648 events.
[19:08:17.217] <TB0> INFO: 411648 events read in total (8661ms).
[19:08:17.263] <TB0> INFO: Test took 145845ms.
[19:08:18.009] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[19:08:18.021] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:08:18.021] <TB0> INFO: run 1 of 1
[19:08:18.255] <TB0> INFO: Expecting 5025280 events.
[19:08:44.249] <TB0> INFO: 595472 events read in total (25402ms).
[19:09:09.497] <TB0> INFO: 1187952 events read in total (50650ms).
[19:09:35.075] <TB0> INFO: 1778816 events read in total (76228ms).
[19:10:00.760] <TB0> INFO: 2368624 events read in total (101913ms).
[19:10:26.246] <TB0> INFO: 2958456 events read in total (127399ms).
[19:10:51.821] <TB0> INFO: 3549032 events read in total (152974ms).
[19:11:17.292] <TB0> INFO: 4139496 events read in total (178445ms).
[19:11:42.733] <TB0> INFO: 4731216 events read in total (203886ms).
[19:11:55.830] <TB0> INFO: 5025280 events read in total (216984ms).
[19:11:55.923] <TB0> INFO: Test took 217903ms.
[19:12:19.704] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.085985 .. 147.598986
[19:12:19.938] <TB0> INFO: Expecting 208000 events.
[19:12:29.227] <TB0> INFO: 208000 events read in total (8697ms).
[19:12:29.229] <TB0> INFO: Test took 9524ms.
[19:12:29.290] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[19:12:29.303] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:12:29.303] <TB0> INFO: run 1 of 1
[19:12:29.581] <TB0> INFO: Expecting 5258240 events.
[19:12:56.050] <TB0> INFO: 585928 events read in total (25878ms).
[19:13:21.250] <TB0> INFO: 1171560 events read in total (51078ms).
[19:13:46.507] <TB0> INFO: 1756856 events read in total (76336ms).
[19:14:11.400] <TB0> INFO: 2342552 events read in total (101228ms).
[19:14:36.705] <TB0> INFO: 2928352 events read in total (126533ms).
[19:15:01.941] <TB0> INFO: 3513168 events read in total (151769ms).
[19:15:26.790] <TB0> INFO: 4098256 events read in total (176618ms).
[19:15:51.980] <TB0> INFO: 4682736 events read in total (201808ms).
[19:16:16.808] <TB0> INFO: 5258240 events read in total (226636ms).
[19:16:16.911] <TB0> INFO: Test took 227609ms.
[19:16:44.937] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 26.904875 .. 46.998306
[19:16:45.196] <TB0> INFO: Expecting 208000 events.
[19:16:55.570] <TB0> INFO: 208000 events read in total (9782ms).
[19:16:55.571] <TB0> INFO: Test took 10634ms.
[19:16:55.635] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 56 (-1/-1) hits flags = 528 (plus default)
[19:16:55.645] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:16:55.645] <TB0> INFO: run 1 of 1
[19:16:55.926] <TB0> INFO: Expecting 1364480 events.
[19:17:23.907] <TB0> INFO: 663768 events read in total (27389ms).
[19:17:51.082] <TB0> INFO: 1325296 events read in total (54565ms).
[19:17:53.120] <TB0> INFO: 1364480 events read in total (56602ms).
[19:17:53.157] <TB0> INFO: Test took 57512ms.
[19:18:07.123] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 27.043677 .. 49.567752
[19:18:07.397] <TB0> INFO: Expecting 208000 events.
[19:18:16.963] <TB0> INFO: 208000 events read in total (8974ms).
[19:18:16.964] <TB0> INFO: Test took 9840ms.
[19:18:17.044] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 17 .. 59 (-1/-1) hits flags = 528 (plus default)
[19:18:17.057] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:18:17.057] <TB0> INFO: run 1 of 1
[19:18:17.346] <TB0> INFO: Expecting 1431040 events.
[19:18:45.207] <TB0> INFO: 647888 events read in total (27270ms).
[19:19:12.229] <TB0> INFO: 1294184 events read in total (54292ms).
[19:19:18.393] <TB0> INFO: 1431040 events read in total (60456ms).
[19:19:18.428] <TB0> INFO: Test took 61372ms.
[19:19:30.941] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 27.558639 .. 54.423131
[19:19:31.177] <TB0> INFO: Expecting 208000 events.
[19:19:41.244] <TB0> INFO: 208000 events read in total (9475ms).
[19:19:41.245] <TB0> INFO: Test took 10303ms.
[19:19:41.298] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 17 .. 64 (-1/-1) hits flags = 528 (plus default)
[19:19:41.310] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:19:41.310] <TB0> INFO: run 1 of 1
[19:19:41.588] <TB0> INFO: Expecting 1597440 events.
[19:20:09.430] <TB0> INFO: 631848 events read in total (27250ms).
[19:20:36.251] <TB0> INFO: 1263128 events read in total (54071ms).
[19:20:50.447] <TB0> INFO: 1597440 events read in total (68267ms).
[19:20:50.487] <TB0> INFO: Test took 69177ms.
[19:21:05.251] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[19:21:05.251] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:21:05.261] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:21:05.261] <TB0> INFO: run 1 of 1
[19:21:05.494] <TB0> INFO: Expecting 1364480 events.
[19:21:33.904] <TB0> INFO: 668752 events read in total (27819ms).
[19:22:01.562] <TB0> INFO: 1337016 events read in total (55477ms).
[19:22:03.137] <TB0> INFO: 1364480 events read in total (57052ms).
[19:22:03.166] <TB0> INFO: Test took 57904ms.
[19:22:16.538] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C0.dat
[19:22:16.538] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C1.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C2.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C3.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C4.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C5.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C6.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C7.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C8.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C9.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C10.dat
[19:22:16.539] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C11.dat
[19:22:16.540] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C12.dat
[19:22:16.540] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C13.dat
[19:22:16.540] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C14.dat
[19:22:16.540] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C15.dat
[19:22:16.540] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C0.dat
[19:22:16.546] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C1.dat
[19:22:16.555] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C2.dat
[19:22:16.563] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C3.dat
[19:22:16.572] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C4.dat
[19:22:16.581] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C5.dat
[19:22:16.589] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C6.dat
[19:22:16.598] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C7.dat
[19:22:16.607] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C8.dat
[19:22:16.615] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C9.dat
[19:22:16.624] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C10.dat
[19:22:16.631] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C11.dat
[19:22:16.640] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C12.dat
[19:22:16.648] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C13.dat
[19:22:16.656] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C14.dat
[19:22:16.664] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters35_C15.dat
[19:22:16.673] <TB0> INFO: PixTestTrim::trimTest() done
[19:22:16.673] <TB0> INFO: vtrim: 148 158 125 125 146 156 120 155 144 145 146 153 135 120 146 135
[19:22:16.673] <TB0> INFO: vthrcomp: 131 129 118 119 126 127 109 123 113 131 126 134 133 129 122 122
[19:22:16.673] <TB0> INFO: vcal mean: 35.46 35.19 34.94 35.11 35.23 35.05 34.96 35.38 35.40 35.14 35.34 35.61 35.08 35.01 35.54 35.03
[19:22:16.673] <TB0> INFO: vcal RMS: 1.51 1.29 1.06 1.24 1.47 1.26 0.98 1.65 1.54 1.26 1.62 1.83 1.17 1.17 1.72 1.01
[19:22:16.673] <TB0> INFO: bits mean: 9.21 10.18 9.97 9.92 10.10 10.49 9.00 10.01 9.95 9.72 10.16 9.87 9.02 9.79 10.09 9.03
[19:22:16.673] <TB0> INFO: bits RMS: 2.47 2.31 2.58 2.60 2.54 2.30 2.32 2.38 2.42 2.44 2.40 2.34 2.66 2.66 2.46 2.83
[19:22:16.681] <TB0> INFO: ----------------------------------------------------------------------
[19:22:16.681] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[19:22:16.681] <TB0> INFO: ----------------------------------------------------------------------
[19:22:16.684] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[19:22:16.697] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:22:16.697] <TB0> INFO: run 1 of 1
[19:22:16.933] <TB0> INFO: Expecting 4160000 events.
[19:22:49.309] <TB0> INFO: 767675 events read in total (31784ms).
[19:23:20.893] <TB0> INFO: 1526775 events read in total (63368ms).
[19:23:52.765] <TB0> INFO: 2280890 events read in total (95240ms).
[19:24:24.568] <TB0> INFO: 3030535 events read in total (127043ms).
[19:24:55.816] <TB0> INFO: 3778205 events read in total (158291ms).
[19:25:11.678] <TB0> INFO: 4160000 events read in total (174153ms).
[19:25:11.725] <TB0> INFO: Test took 175028ms.
[19:25:38.057] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 209 (-1/-1) hits flags = 528 (plus default)
[19:25:38.068] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:25:38.068] <TB0> INFO: run 1 of 1
[19:25:38.304] <TB0> INFO: Expecting 4368000 events.
[19:26:09.737] <TB0> INFO: 728255 events read in total (30841ms).
[19:26:40.325] <TB0> INFO: 1449405 events read in total (61429ms).
[19:27:11.204] <TB0> INFO: 2167220 events read in total (92308ms).
[19:27:41.755] <TB0> INFO: 2880785 events read in total (122859ms).
[19:28:12.234] <TB0> INFO: 3593065 events read in total (153338ms).
[19:28:42.607] <TB0> INFO: 4305250 events read in total (183711ms).
[19:28:45.641] <TB0> INFO: 4368000 events read in total (186745ms).
[19:28:45.694] <TB0> INFO: Test took 187626ms.
[19:29:12.744] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 223 (-1/-1) hits flags = 528 (plus default)
[19:29:12.757] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:29:12.757] <TB0> INFO: run 1 of 1
[19:29:13.044] <TB0> INFO: Expecting 4659200 events.
[19:29:45.102] <TB0> INFO: 712155 events read in total (31466ms).
[19:30:15.631] <TB0> INFO: 1417805 events read in total (61995ms).
[19:30:46.016] <TB0> INFO: 2121100 events read in total (92380ms).
[19:31:16.853] <TB0> INFO: 2820790 events read in total (123217ms).
[19:31:47.253] <TB0> INFO: 3518850 events read in total (153617ms).
[19:32:17.311] <TB0> INFO: 4215440 events read in total (183675ms).
[19:32:36.483] <TB0> INFO: 4659200 events read in total (202847ms).
[19:32:36.545] <TB0> INFO: Test took 203788ms.
[19:33:09.697] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 216 (-1/-1) hits flags = 528 (plus default)
[19:33:09.709] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:33:09.709] <TB0> INFO: run 1 of 1
[19:33:09.975] <TB0> INFO: Expecting 4513600 events.
[19:33:41.456] <TB0> INFO: 720205 events read in total (30889ms).
[19:34:11.920] <TB0> INFO: 1433285 events read in total (61353ms).
[19:34:42.472] <TB0> INFO: 2143900 events read in total (91905ms).
[19:35:12.891] <TB0> INFO: 2850355 events read in total (122324ms).
[19:35:43.195] <TB0> INFO: 3555610 events read in total (152628ms).
[19:36:13.555] <TB0> INFO: 4259970 events read in total (182988ms).
[19:36:24.615] <TB0> INFO: 4513600 events read in total (194048ms).
[19:36:24.674] <TB0> INFO: Test took 194965ms.
[19:36:52.193] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 215 (-1/-1) hits flags = 528 (plus default)
[19:36:52.203] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:36:52.203] <TB0> INFO: run 1 of 1
[19:36:52.437] <TB0> INFO: Expecting 4492800 events.
[19:37:23.674] <TB0> INFO: 721370 events read in total (30646ms).
[19:37:53.916] <TB0> INFO: 1435790 events read in total (60888ms).
[19:38:24.194] <TB0> INFO: 2147250 events read in total (91166ms).
[19:38:54.255] <TB0> INFO: 2854635 events read in total (121227ms).
[19:39:24.300] <TB0> INFO: 3560725 events read in total (151272ms).
[19:39:55.982] <TB0> INFO: 4266025 events read in total (182954ms).
[19:40:06.279] <TB0> INFO: 4492800 events read in total (193251ms).
[19:40:06.335] <TB0> INFO: Test took 194132ms.
[19:40:32.789] <TB0> INFO: PixTestTrim::trimBitTest() done
[19:40:32.790] <TB0> INFO: PixTestTrim::doTest() done, duration: 2531 seconds
[19:40:32.790] <TB0> INFO: Decoding statistics:
[19:40:32.790] <TB0> INFO: General information:
[19:40:32.790] <TB0> INFO: 16bit words read: 0
[19:40:32.790] <TB0> INFO: valid events total: 0
[19:40:32.790] <TB0> INFO: empty events: 0
[19:40:32.790] <TB0> INFO: valid events with pixels: 0
[19:40:32.790] <TB0> INFO: valid pixel hits: 0
[19:40:32.790] <TB0> INFO: Event errors: 0
[19:40:32.790] <TB0> INFO: start marker: 0
[19:40:32.790] <TB0> INFO: stop marker: 0
[19:40:32.790] <TB0> INFO: overflow: 0
[19:40:32.790] <TB0> INFO: invalid 5bit words: 0
[19:40:32.790] <TB0> INFO: invalid XOR eye diagram: 0
[19:40:32.790] <TB0> INFO: frame (failed synchr.): 0
[19:40:32.790] <TB0> INFO: idle data (no TBM trl): 0
[19:40:32.790] <TB0> INFO: no data (only TBM hdr): 0
[19:40:32.790] <TB0> INFO: TBM errors: 0
[19:40:32.790] <TB0> INFO: flawed TBM headers: 0
[19:40:32.790] <TB0> INFO: flawed TBM trailers: 0
[19:40:32.790] <TB0> INFO: event ID mismatches: 0
[19:40:32.790] <TB0> INFO: ROC errors: 0
[19:40:32.790] <TB0> INFO: missing ROC header(s): 0
[19:40:32.790] <TB0> INFO: misplaced readback start: 0
[19:40:32.790] <TB0> INFO: Pixel decoding errors: 0
[19:40:32.790] <TB0> INFO: pixel data incomplete: 0
[19:40:32.790] <TB0> INFO: pixel address: 0
[19:40:32.790] <TB0> INFO: pulse height fill bit: 0
[19:40:32.790] <TB0> INFO: buffer corruption: 0
[19:40:33.401] <TB0> INFO: ######################################################################
[19:40:33.401] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[19:40:33.401] <TB0> INFO: ######################################################################
[19:40:33.633] <TB0> INFO: Expecting 41600 events.
[19:40:37.123] <TB0> INFO: 41600 events read in total (2898ms).
[19:40:37.124] <TB0> INFO: Test took 3722ms.
[19:40:37.577] <TB0> INFO: Expecting 41600 events.
[19:40:41.066] <TB0> INFO: 41600 events read in total (2897ms).
[19:40:41.067] <TB0> INFO: Test took 3738ms.
[19:40:41.355] <TB0> INFO: Expecting 41600 events.
[19:40:44.839] <TB0> INFO: 41600 events read in total (2893ms).
[19:40:44.839] <TB0> INFO: Test took 3749ms.
[19:40:45.138] <TB0> INFO: Expecting 41600 events.
[19:40:48.584] <TB0> INFO: 41600 events read in total (2854ms).
[19:40:48.585] <TB0> INFO: Test took 3722ms.
[19:40:48.873] <TB0> INFO: Expecting 41600 events.
[19:40:52.371] <TB0> INFO: 41600 events read in total (2906ms).
[19:40:52.372] <TB0> INFO: Test took 3763ms.
[19:40:52.660] <TB0> INFO: Expecting 41600 events.
[19:40:56.259] <TB0> INFO: 41600 events read in total (3008ms).
[19:40:56.260] <TB0> INFO: Test took 3865ms.
[19:40:56.550] <TB0> INFO: Expecting 41600 events.
[19:41:00.075] <TB0> INFO: 41600 events read in total (2933ms).
[19:41:00.076] <TB0> INFO: Test took 3790ms.
[19:41:00.364] <TB0> INFO: Expecting 41600 events.
[19:41:03.806] <TB0> INFO: 41600 events read in total (2851ms).
[19:41:03.807] <TB0> INFO: Test took 3708ms.
[19:41:04.095] <TB0> INFO: Expecting 41600 events.
[19:41:07.633] <TB0> INFO: 41600 events read in total (2946ms).
[19:41:07.634] <TB0> INFO: Test took 3803ms.
[19:41:07.922] <TB0> INFO: Expecting 41600 events.
[19:41:11.369] <TB0> INFO: 41600 events read in total (2856ms).
[19:41:11.370] <TB0> INFO: Test took 3713ms.
[19:41:11.658] <TB0> INFO: Expecting 41600 events.
[19:41:15.194] <TB0> INFO: 41600 events read in total (2945ms).
[19:41:15.195] <TB0> INFO: Test took 3802ms.
[19:41:15.483] <TB0> INFO: Expecting 41600 events.
[19:41:18.968] <TB0> INFO: 41600 events read in total (2893ms).
[19:41:18.969] <TB0> INFO: Test took 3750ms.
[19:41:19.257] <TB0> INFO: Expecting 41600 events.
[19:41:22.695] <TB0> INFO: 41600 events read in total (2847ms).
[19:41:22.696] <TB0> INFO: Test took 3704ms.
[19:41:22.984] <TB0> INFO: Expecting 41600 events.
[19:41:26.446] <TB0> INFO: 41600 events read in total (2871ms).
[19:41:26.447] <TB0> INFO: Test took 3728ms.
[19:41:26.735] <TB0> INFO: Expecting 41600 events.
[19:41:30.176] <TB0> INFO: 41600 events read in total (2849ms).
[19:41:30.177] <TB0> INFO: Test took 3707ms.
[19:41:30.476] <TB0> INFO: Expecting 41600 events.
[19:41:33.935] <TB0> INFO: 41600 events read in total (2867ms).
[19:41:33.936] <TB0> INFO: Test took 3735ms.
[19:41:34.224] <TB0> INFO: Expecting 41600 events.
[19:41:37.811] <TB0> INFO: 41600 events read in total (2996ms).
[19:41:37.812] <TB0> INFO: Test took 3853ms.
[19:41:38.102] <TB0> INFO: Expecting 41600 events.
[19:41:41.591] <TB0> INFO: 41600 events read in total (2897ms).
[19:41:41.592] <TB0> INFO: Test took 3755ms.
[19:41:41.880] <TB0> INFO: Expecting 41600 events.
[19:41:45.330] <TB0> INFO: 41600 events read in total (2859ms).
[19:41:45.330] <TB0> INFO: Test took 3715ms.
[19:41:45.618] <TB0> INFO: Expecting 41600 events.
[19:41:49.113] <TB0> INFO: 41600 events read in total (2903ms).
[19:41:49.114] <TB0> INFO: Test took 3760ms.
[19:41:49.402] <TB0> INFO: Expecting 41600 events.
[19:41:52.870] <TB0> INFO: 41600 events read in total (2877ms).
[19:41:52.870] <TB0> INFO: Test took 3733ms.
[19:41:53.165] <TB0> INFO: Expecting 41600 events.
[19:41:56.670] <TB0> INFO: 41600 events read in total (2914ms).
[19:41:56.671] <TB0> INFO: Test took 3777ms.
[19:41:56.958] <TB0> INFO: Expecting 41600 events.
[19:42:00.422] <TB0> INFO: 41600 events read in total (2872ms).
[19:42:00.423] <TB0> INFO: Test took 3729ms.
[19:42:00.724] <TB0> INFO: Expecting 41600 events.
[19:42:04.163] <TB0> INFO: 41600 events read in total (2848ms).
[19:42:04.164] <TB0> INFO: Test took 3715ms.
[19:42:04.452] <TB0> INFO: Expecting 41600 events.
[19:42:07.894] <TB0> INFO: 41600 events read in total (2851ms).
[19:42:07.895] <TB0> INFO: Test took 3708ms.
[19:42:08.186] <TB0> INFO: Expecting 41600 events.
[19:42:11.721] <TB0> INFO: 41600 events read in total (2943ms).
[19:42:11.721] <TB0> INFO: Test took 3800ms.
[19:42:12.019] <TB0> INFO: Expecting 41600 events.
[19:42:15.468] <TB0> INFO: 41600 events read in total (2857ms).
[19:42:15.469] <TB0> INFO: Test took 3724ms.
[19:42:15.759] <TB0> INFO: Expecting 41600 events.
[19:42:19.235] <TB0> INFO: 41600 events read in total (2884ms).
[19:42:19.236] <TB0> INFO: Test took 3742ms.
[19:42:19.525] <TB0> INFO: Expecting 2560 events.
[19:42:20.409] <TB0> INFO: 2560 events read in total (292ms).
[19:42:20.409] <TB0> INFO: Test took 1161ms.
[19:42:20.717] <TB0> INFO: Expecting 2560 events.
[19:42:21.599] <TB0> INFO: 2560 events read in total (290ms).
[19:42:21.600] <TB0> INFO: Test took 1190ms.
[19:42:21.908] <TB0> INFO: Expecting 2560 events.
[19:42:22.794] <TB0> INFO: 2560 events read in total (295ms).
[19:42:22.794] <TB0> INFO: Test took 1194ms.
[19:42:23.102] <TB0> INFO: Expecting 2560 events.
[19:42:23.984] <TB0> INFO: 2560 events read in total (291ms).
[19:42:23.984] <TB0> INFO: Test took 1189ms.
[19:42:24.292] <TB0> INFO: Expecting 2560 events.
[19:42:25.170] <TB0> INFO: 2560 events read in total (286ms).
[19:42:25.170] <TB0> INFO: Test took 1184ms.
[19:42:25.477] <TB0> INFO: Expecting 2560 events.
[19:42:26.361] <TB0> INFO: 2560 events read in total (292ms).
[19:42:26.361] <TB0> INFO: Test took 1191ms.
[19:42:26.669] <TB0> INFO: Expecting 2560 events.
[19:42:27.547] <TB0> INFO: 2560 events read in total (287ms).
[19:42:27.547] <TB0> INFO: Test took 1186ms.
[19:42:27.855] <TB0> INFO: Expecting 2560 events.
[19:42:28.734] <TB0> INFO: 2560 events read in total (287ms).
[19:42:28.734] <TB0> INFO: Test took 1187ms.
[19:42:29.041] <TB0> INFO: Expecting 2560 events.
[19:42:29.920] <TB0> INFO: 2560 events read in total (287ms).
[19:42:29.920] <TB0> INFO: Test took 1186ms.
[19:42:30.228] <TB0> INFO: Expecting 2560 events.
[19:42:31.113] <TB0> INFO: 2560 events read in total (293ms).
[19:42:31.113] <TB0> INFO: Test took 1192ms.
[19:42:31.420] <TB0> INFO: Expecting 2560 events.
[19:42:32.301] <TB0> INFO: 2560 events read in total (287ms).
[19:42:32.301] <TB0> INFO: Test took 1187ms.
[19:42:32.609] <TB0> INFO: Expecting 2560 events.
[19:42:33.487] <TB0> INFO: 2560 events read in total (286ms).
[19:42:33.488] <TB0> INFO: Test took 1186ms.
[19:42:33.795] <TB0> INFO: Expecting 2560 events.
[19:42:34.677] <TB0> INFO: 2560 events read in total (290ms).
[19:42:34.677] <TB0> INFO: Test took 1189ms.
[19:42:34.985] <TB0> INFO: Expecting 2560 events.
[19:42:35.870] <TB0> INFO: 2560 events read in total (294ms).
[19:42:35.870] <TB0> INFO: Test took 1192ms.
[19:42:36.178] <TB0> INFO: Expecting 2560 events.
[19:42:37.060] <TB0> INFO: 2560 events read in total (291ms).
[19:42:37.060] <TB0> INFO: Test took 1190ms.
[19:42:37.368] <TB0> INFO: Expecting 2560 events.
[19:42:38.249] <TB0> INFO: 2560 events read in total (290ms).
[19:42:38.249] <TB0> INFO: Test took 1189ms.
[19:42:38.252] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:42:38.558] <TB0> INFO: Expecting 655360 events.
[19:42:52.790] <TB0> INFO: 655360 events read in total (13640ms).
[19:42:52.801] <TB0> INFO: Expecting 655360 events.
[19:43:06.846] <TB0> INFO: 655360 events read in total (13642ms).
[19:43:06.861] <TB0> INFO: Expecting 655360 events.
[19:43:20.993] <TB0> INFO: 655360 events read in total (13729ms).
[19:43:21.017] <TB0> INFO: Expecting 655360 events.
[19:43:35.096] <TB0> INFO: 655360 events read in total (13676ms).
[19:43:35.120] <TB0> INFO: Expecting 655360 events.
[19:43:49.171] <TB0> INFO: 655360 events read in total (13648ms).
[19:43:49.198] <TB0> INFO: Expecting 655360 events.
[19:44:03.254] <TB0> INFO: 655360 events read in total (13653ms).
[19:44:03.297] <TB0> INFO: Expecting 655360 events.
[19:44:17.487] <TB0> INFO: 655360 events read in total (13787ms).
[19:44:17.523] <TB0> INFO: Expecting 655360 events.
[19:44:31.683] <TB0> INFO: 655360 events read in total (13757ms).
[19:44:31.736] <TB0> INFO: Expecting 655360 events.
[19:44:45.783] <TB0> INFO: 655360 events read in total (13644ms).
[19:44:45.826] <TB0> INFO: Expecting 655360 events.
[19:44:59.894] <TB0> INFO: 655360 events read in total (13665ms).
[19:44:59.940] <TB0> INFO: Expecting 655360 events.
[19:45:14.133] <TB0> INFO: 655360 events read in total (13790ms).
[19:45:14.183] <TB0> INFO: Expecting 655360 events.
[19:45:28.282] <TB0> INFO: 655360 events read in total (13696ms).
[19:45:28.339] <TB0> INFO: Expecting 655360 events.
[19:45:42.444] <TB0> INFO: 655360 events read in total (13702ms).
[19:45:42.505] <TB0> INFO: Expecting 655360 events.
[19:45:56.536] <TB0> INFO: 655360 events read in total (13628ms).
[19:45:56.602] <TB0> INFO: Expecting 655360 events.
[19:46:10.767] <TB0> INFO: 655360 events read in total (13762ms).
[19:46:10.835] <TB0> INFO: Expecting 655360 events.
[19:46:24.948] <TB0> INFO: 655360 events read in total (13710ms).
[19:46:25.031] <TB0> INFO: Test took 226779ms.
[19:46:25.115] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:46:25.380] <TB0> INFO: Expecting 655360 events.
[19:46:39.405] <TB0> INFO: 655360 events read in total (13433ms).
[19:46:39.416] <TB0> INFO: Expecting 655360 events.
[19:46:53.168] <TB0> INFO: 655360 events read in total (13349ms).
[19:46:53.182] <TB0> INFO: Expecting 655360 events.
[19:47:07.197] <TB0> INFO: 655360 events read in total (13612ms).
[19:47:07.216] <TB0> INFO: Expecting 655360 events.
[19:47:21.170] <TB0> INFO: 655360 events read in total (13552ms).
[19:47:21.206] <TB0> INFO: Expecting 655360 events.
[19:47:35.146] <TB0> INFO: 655360 events read in total (13537ms).
[19:47:35.172] <TB0> INFO: Expecting 655360 events.
[19:47:49.219] <TB0> INFO: 655360 events read in total (13644ms).
[19:47:49.260] <TB0> INFO: Expecting 655360 events.
[19:48:03.344] <TB0> INFO: 655360 events read in total (13681ms).
[19:48:03.379] <TB0> INFO: Expecting 655360 events.
[19:48:17.367] <TB0> INFO: 655360 events read in total (13586ms).
[19:48:17.405] <TB0> INFO: Expecting 655360 events.
[19:48:31.517] <TB0> INFO: 655360 events read in total (13709ms).
[19:48:31.561] <TB0> INFO: Expecting 655360 events.
[19:48:45.547] <TB0> INFO: 655360 events read in total (13583ms).
[19:48:45.593] <TB0> INFO: Expecting 655360 events.
[19:48:59.416] <TB0> INFO: 655360 events read in total (13420ms).
[19:48:59.471] <TB0> INFO: Expecting 655360 events.
[19:49:13.341] <TB0> INFO: 655360 events read in total (13467ms).
[19:49:13.414] <TB0> INFO: Expecting 655360 events.
[19:49:27.298] <TB0> INFO: 655360 events read in total (13481ms).
[19:49:27.358] <TB0> INFO: Expecting 655360 events.
[19:49:41.326] <TB0> INFO: 655360 events read in total (13565ms).
[19:49:41.391] <TB0> INFO: Expecting 655360 events.
[19:49:55.314] <TB0> INFO: 655360 events read in total (13519ms).
[19:49:55.384] <TB0> INFO: Expecting 655360 events.
[19:50:09.312] <TB0> INFO: 655360 events read in total (13525ms).
[19:50:09.402] <TB0> INFO: Test took 224287ms.
[19:50:09.629] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.635] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:09.641] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[19:50:09.647] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[19:50:09.654] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.660] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.666] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.672] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:09.678] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[19:50:09.685] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.691] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:09.697] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[19:50:09.704] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[19:50:09.710] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[19:50:09.716] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.723] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:09.729] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.735] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:09.742] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.748] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.754] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.760] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.767] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.772] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:09.777] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[19:50:09.781] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[19:50:09.786] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[19:50:09.790] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[19:50:09.795] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[19:50:09.799] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[19:50:09.804] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.808] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.813] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:09.817] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[19:50:09.822] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[19:50:09.826] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[19:50:09.831] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[19:50:09.835] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[19:50:09.840] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[19:50:09.844] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.849] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:09.882] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C0.dat
[19:50:09.882] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C1.dat
[19:50:09.882] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C2.dat
[19:50:09.882] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C3.dat
[19:50:09.882] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C4.dat
[19:50:09.882] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C5.dat
[19:50:09.882] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C6.dat
[19:50:09.882] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C7.dat
[19:50:09.883] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C8.dat
[19:50:09.883] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C9.dat
[19:50:09.883] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C10.dat
[19:50:09.883] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C11.dat
[19:50:09.883] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C12.dat
[19:50:09.883] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C13.dat
[19:50:09.883] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C14.dat
[19:50:09.883] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters35_C15.dat
[19:50:10.117] <TB0> INFO: Expecting 41600 events.
[19:50:13.210] <TB0> INFO: 41600 events read in total (2502ms).
[19:50:13.211] <TB0> INFO: Test took 3325ms.
[19:50:13.687] <TB0> INFO: Expecting 41600 events.
[19:50:16.671] <TB0> INFO: 41600 events read in total (2393ms).
[19:50:16.671] <TB0> INFO: Test took 3249ms.
[19:50:17.114] <TB0> INFO: Expecting 41600 events.
[19:50:20.211] <TB0> INFO: 41600 events read in total (2506ms).
[19:50:20.212] <TB0> INFO: Test took 3330ms.
[19:50:20.432] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:20.520] <TB0> INFO: Expecting 2560 events.
[19:50:21.404] <TB0> INFO: 2560 events read in total (292ms).
[19:50:21.404] <TB0> INFO: Test took 972ms.
[19:50:21.406] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:21.713] <TB0> INFO: Expecting 2560 events.
[19:50:22.599] <TB0> INFO: 2560 events read in total (295ms).
[19:50:22.599] <TB0> INFO: Test took 1193ms.
[19:50:22.601] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:22.907] <TB0> INFO: Expecting 2560 events.
[19:50:23.792] <TB0> INFO: 2560 events read in total (293ms).
[19:50:23.793] <TB0> INFO: Test took 1192ms.
[19:50:23.794] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:24.101] <TB0> INFO: Expecting 2560 events.
[19:50:24.987] <TB0> INFO: 2560 events read in total (294ms).
[19:50:24.987] <TB0> INFO: Test took 1193ms.
[19:50:24.989] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:25.296] <TB0> INFO: Expecting 2560 events.
[19:50:26.179] <TB0> INFO: 2560 events read in total (292ms).
[19:50:26.179] <TB0> INFO: Test took 1190ms.
[19:50:26.181] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:26.488] <TB0> INFO: Expecting 2560 events.
[19:50:27.371] <TB0> INFO: 2560 events read in total (292ms).
[19:50:27.371] <TB0> INFO: Test took 1190ms.
[19:50:27.373] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:27.680] <TB0> INFO: Expecting 2560 events.
[19:50:28.565] <TB0> INFO: 2560 events read in total (294ms).
[19:50:28.565] <TB0> INFO: Test took 1192ms.
[19:50:28.567] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:28.874] <TB0> INFO: Expecting 2560 events.
[19:50:29.757] <TB0> INFO: 2560 events read in total (292ms).
[19:50:29.757] <TB0> INFO: Test took 1190ms.
[19:50:29.760] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:30.066] <TB0> INFO: Expecting 2560 events.
[19:50:30.945] <TB0> INFO: 2560 events read in total (288ms).
[19:50:30.945] <TB0> INFO: Test took 1185ms.
[19:50:30.947] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:31.253] <TB0> INFO: Expecting 2560 events.
[19:50:32.132] <TB0> INFO: 2560 events read in total (287ms).
[19:50:32.133] <TB0> INFO: Test took 1186ms.
[19:50:32.134] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:32.441] <TB0> INFO: Expecting 2560 events.
[19:50:33.320] <TB0> INFO: 2560 events read in total (287ms).
[19:50:33.320] <TB0> INFO: Test took 1186ms.
[19:50:33.322] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:33.628] <TB0> INFO: Expecting 2560 events.
[19:50:34.511] <TB0> INFO: 2560 events read in total (291ms).
[19:50:34.511] <TB0> INFO: Test took 1189ms.
[19:50:34.513] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:34.819] <TB0> INFO: Expecting 2560 events.
[19:50:35.698] <TB0> INFO: 2560 events read in total (288ms).
[19:50:35.698] <TB0> INFO: Test took 1185ms.
[19:50:35.700] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:36.006] <TB0> INFO: Expecting 2560 events.
[19:50:36.887] <TB0> INFO: 2560 events read in total (289ms).
[19:50:36.887] <TB0> INFO: Test took 1187ms.
[19:50:36.889] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:37.196] <TB0> INFO: Expecting 2560 events.
[19:50:38.076] <TB0> INFO: 2560 events read in total (289ms).
[19:50:38.076] <TB0> INFO: Test took 1187ms.
[19:50:38.078] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:38.384] <TB0> INFO: Expecting 2560 events.
[19:50:39.262] <TB0> INFO: 2560 events read in total (286ms).
[19:50:39.262] <TB0> INFO: Test took 1184ms.
[19:50:39.264] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:39.571] <TB0> INFO: Expecting 2560 events.
[19:50:40.450] <TB0> INFO: 2560 events read in total (288ms).
[19:50:40.450] <TB0> INFO: Test took 1186ms.
[19:50:40.452] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:40.758] <TB0> INFO: Expecting 2560 events.
[19:50:41.638] <TB0> INFO: 2560 events read in total (289ms).
[19:50:41.639] <TB0> INFO: Test took 1187ms.
[19:50:41.641] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:41.947] <TB0> INFO: Expecting 2560 events.
[19:50:42.828] <TB0> INFO: 2560 events read in total (289ms).
[19:50:42.828] <TB0> INFO: Test took 1187ms.
[19:50:42.830] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:43.137] <TB0> INFO: Expecting 2560 events.
[19:50:44.015] <TB0> INFO: 2560 events read in total (287ms).
[19:50:44.015] <TB0> INFO: Test took 1185ms.
[19:50:44.017] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:44.323] <TB0> INFO: Expecting 2560 events.
[19:50:45.203] <TB0> INFO: 2560 events read in total (288ms).
[19:50:45.203] <TB0> INFO: Test took 1186ms.
[19:50:45.205] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:45.512] <TB0> INFO: Expecting 2560 events.
[19:50:46.390] <TB0> INFO: 2560 events read in total (287ms).
[19:50:46.390] <TB0> INFO: Test took 1185ms.
[19:50:46.392] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:46.698] <TB0> INFO: Expecting 2560 events.
[19:50:47.576] <TB0> INFO: 2560 events read in total (286ms).
[19:50:47.576] <TB0> INFO: Test took 1184ms.
[19:50:47.579] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:47.885] <TB0> INFO: Expecting 2560 events.
[19:50:48.767] <TB0> INFO: 2560 events read in total (291ms).
[19:50:48.767] <TB0> INFO: Test took 1189ms.
[19:50:48.769] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:49.076] <TB0> INFO: Expecting 2560 events.
[19:50:49.959] <TB0> INFO: 2560 events read in total (292ms).
[19:50:49.959] <TB0> INFO: Test took 1190ms.
[19:50:49.961] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:50.268] <TB0> INFO: Expecting 2560 events.
[19:50:51.153] <TB0> INFO: 2560 events read in total (294ms).
[19:50:51.153] <TB0> INFO: Test took 1192ms.
[19:50:51.155] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:51.462] <TB0> INFO: Expecting 2560 events.
[19:50:52.347] <TB0> INFO: 2560 events read in total (294ms).
[19:50:52.347] <TB0> INFO: Test took 1192ms.
[19:50:52.350] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:52.656] <TB0> INFO: Expecting 2560 events.
[19:50:53.539] <TB0> INFO: 2560 events read in total (292ms).
[19:50:53.539] <TB0> INFO: Test took 1190ms.
[19:50:53.541] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:53.848] <TB0> INFO: Expecting 2560 events.
[19:50:54.733] <TB0> INFO: 2560 events read in total (293ms).
[19:50:54.733] <TB0> INFO: Test took 1192ms.
[19:50:54.735] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:55.042] <TB0> INFO: Expecting 2560 events.
[19:50:55.922] <TB0> INFO: 2560 events read in total (289ms).
[19:50:55.923] <TB0> INFO: Test took 1188ms.
[19:50:55.925] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:56.231] <TB0> INFO: Expecting 2560 events.
[19:50:57.114] <TB0> INFO: 2560 events read in total (291ms).
[19:50:57.114] <TB0> INFO: Test took 1189ms.
[19:50:57.116] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:57.423] <TB0> INFO: Expecting 2560 events.
[19:50:58.307] <TB0> INFO: 2560 events read in total (293ms).
[19:50:58.307] <TB0> INFO: Test took 1191ms.
[19:50:58.770] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 625 seconds
[19:50:58.770] <TB0> INFO: PH scale (per ROC): 43 50 49 48 33 46 49 46 35 42 53 40 56 48 41 62
[19:50:58.770] <TB0> INFO: PH offset (per ROC): 112 131 101 104 95 109 125 134 84 106 124 99 116 132 96 125
[19:50:58.775] <TB0> INFO: Decoding statistics:
[19:50:58.775] <TB0> INFO: General information:
[19:50:58.775] <TB0> INFO: 16bit words read: 127888
[19:50:58.775] <TB0> INFO: valid events total: 20480
[19:50:58.775] <TB0> INFO: empty events: 17976
[19:50:58.775] <TB0> INFO: valid events with pixels: 2504
[19:50:58.776] <TB0> INFO: valid pixel hits: 2504
[19:50:58.776] <TB0> INFO: Event errors: 0
[19:50:58.776] <TB0> INFO: start marker: 0
[19:50:58.776] <TB0> INFO: stop marker: 0
[19:50:58.776] <TB0> INFO: overflow: 0
[19:50:58.776] <TB0> INFO: invalid 5bit words: 0
[19:50:58.776] <TB0> INFO: invalid XOR eye diagram: 0
[19:50:58.776] <TB0> INFO: frame (failed synchr.): 0
[19:50:58.776] <TB0> INFO: idle data (no TBM trl): 0
[19:50:58.776] <TB0> INFO: no data (only TBM hdr): 0
[19:50:58.776] <TB0> INFO: TBM errors: 0
[19:50:58.776] <TB0> INFO: flawed TBM headers: 0
[19:50:58.776] <TB0> INFO: flawed TBM trailers: 0
[19:50:58.776] <TB0> INFO: event ID mismatches: 0
[19:50:58.776] <TB0> INFO: ROC errors: 0
[19:50:58.776] <TB0> INFO: missing ROC header(s): 0
[19:50:58.776] <TB0> INFO: misplaced readback start: 0
[19:50:58.776] <TB0> INFO: Pixel decoding errors: 0
[19:50:58.776] <TB0> INFO: pixel data incomplete: 0
[19:50:58.776] <TB0> INFO: pixel address: 0
[19:50:58.776] <TB0> INFO: pulse height fill bit: 0
[19:50:58.776] <TB0> INFO: buffer corruption: 0
[19:50:59.044] <TB0> INFO: ######################################################################
[19:50:59.044] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[19:50:59.044] <TB0> INFO: ######################################################################
[19:50:59.055] <TB0> INFO: scanning low vcal = 10
[19:50:59.288] <TB0> INFO: Expecting 41600 events.
[19:51:02.881] <TB0> INFO: 41600 events read in total (3002ms).
[19:51:02.881] <TB0> INFO: Test took 3825ms.
[19:51:02.883] <TB0> INFO: scanning low vcal = 20
[19:51:03.183] <TB0> INFO: Expecting 41600 events.
[19:51:06.754] <TB0> INFO: 41600 events read in total (2980ms).
[19:51:06.755] <TB0> INFO: Test took 3872ms.
[19:51:06.756] <TB0> INFO: scanning low vcal = 30
[19:51:07.055] <TB0> INFO: Expecting 41600 events.
[19:51:10.666] <TB0> INFO: 41600 events read in total (3020ms).
[19:51:10.667] <TB0> INFO: Test took 3911ms.
[19:51:10.669] <TB0> INFO: scanning low vcal = 40
[19:51:10.947] <TB0> INFO: Expecting 41600 events.
[19:51:14.827] <TB0> INFO: 41600 events read in total (3289ms).
[19:51:14.828] <TB0> INFO: Test took 4159ms.
[19:51:14.831] <TB0> INFO: scanning low vcal = 50
[19:51:15.108] <TB0> INFO: Expecting 41600 events.
[19:51:19.079] <TB0> INFO: 41600 events read in total (3380ms).
[19:51:19.079] <TB0> INFO: Test took 4248ms.
[19:51:19.082] <TB0> INFO: scanning low vcal = 60
[19:51:19.359] <TB0> INFO: Expecting 41600 events.
[19:51:23.276] <TB0> INFO: 41600 events read in total (3326ms).
[19:51:23.277] <TB0> INFO: Test took 4195ms.
[19:51:23.280] <TB0> INFO: scanning low vcal = 70
[19:51:23.557] <TB0> INFO: Expecting 41600 events.
[19:51:27.470] <TB0> INFO: 41600 events read in total (3322ms).
[19:51:27.471] <TB0> INFO: Test took 4191ms.
[19:51:27.473] <TB0> INFO: scanning low vcal = 80
[19:51:27.750] <TB0> INFO: Expecting 41600 events.
[19:51:31.725] <TB0> INFO: 41600 events read in total (3384ms).
[19:51:31.726] <TB0> INFO: Test took 4252ms.
[19:51:31.729] <TB0> INFO: scanning low vcal = 90
[19:51:32.005] <TB0> INFO: Expecting 41600 events.
[19:51:35.927] <TB0> INFO: 41600 events read in total (3330ms).
[19:51:35.928] <TB0> INFO: Test took 4199ms.
[19:51:35.931] <TB0> INFO: scanning low vcal = 100
[19:51:36.208] <TB0> INFO: Expecting 41600 events.
[19:51:40.123] <TB0> INFO: 41600 events read in total (3324ms).
[19:51:40.124] <TB0> INFO: Test took 4193ms.
[19:51:40.127] <TB0> INFO: scanning low vcal = 110
[19:51:40.404] <TB0> INFO: Expecting 41600 events.
[19:51:44.349] <TB0> INFO: 41600 events read in total (3354ms).
[19:51:44.350] <TB0> INFO: Test took 4223ms.
[19:51:44.353] <TB0> INFO: scanning low vcal = 120
[19:51:44.629] <TB0> INFO: Expecting 41600 events.
[19:51:48.585] <TB0> INFO: 41600 events read in total (3364ms).
[19:51:48.586] <TB0> INFO: Test took 4233ms.
[19:51:48.588] <TB0> INFO: scanning low vcal = 130
[19:51:48.865] <TB0> INFO: Expecting 41600 events.
[19:51:52.846] <TB0> INFO: 41600 events read in total (3390ms).
[19:51:52.847] <TB0> INFO: Test took 4259ms.
[19:51:52.850] <TB0> INFO: scanning low vcal = 140
[19:51:53.126] <TB0> INFO: Expecting 41600 events.
[19:51:57.043] <TB0> INFO: 41600 events read in total (3325ms).
[19:51:57.044] <TB0> INFO: Test took 4194ms.
[19:51:57.046] <TB0> INFO: scanning low vcal = 150
[19:51:57.323] <TB0> INFO: Expecting 41600 events.
[19:52:01.298] <TB0> INFO: 41600 events read in total (3383ms).
[19:52:01.299] <TB0> INFO: Test took 4252ms.
[19:52:01.301] <TB0> INFO: scanning low vcal = 160
[19:52:01.579] <TB0> INFO: Expecting 41600 events.
[19:52:05.535] <TB0> INFO: 41600 events read in total (3365ms).
[19:52:05.536] <TB0> INFO: Test took 4235ms.
[19:52:05.538] <TB0> INFO: scanning low vcal = 170
[19:52:05.815] <TB0> INFO: Expecting 41600 events.
[19:52:09.728] <TB0> INFO: 41600 events read in total (3321ms).
[19:52:09.728] <TB0> INFO: Test took 4190ms.
[19:52:09.731] <TB0> INFO: scanning low vcal = 180
[19:52:10.008] <TB0> INFO: Expecting 41600 events.
[19:52:13.975] <TB0> INFO: 41600 events read in total (3376ms).
[19:52:13.975] <TB0> INFO: Test took 4244ms.
[19:52:13.978] <TB0> INFO: scanning low vcal = 190
[19:52:14.255] <TB0> INFO: Expecting 41600 events.
[19:52:18.202] <TB0> INFO: 41600 events read in total (3356ms).
[19:52:18.202] <TB0> INFO: Test took 4224ms.
[19:52:18.205] <TB0> INFO: scanning low vcal = 200
[19:52:18.482] <TB0> INFO: Expecting 41600 events.
[19:52:22.398] <TB0> INFO: 41600 events read in total (3325ms).
[19:52:22.399] <TB0> INFO: Test took 4194ms.
[19:52:22.402] <TB0> INFO: scanning low vcal = 210
[19:52:22.678] <TB0> INFO: Expecting 41600 events.
[19:52:26.609] <TB0> INFO: 41600 events read in total (3339ms).
[19:52:26.610] <TB0> INFO: Test took 4208ms.
[19:52:26.613] <TB0> INFO: scanning low vcal = 220
[19:52:26.889] <TB0> INFO: Expecting 41600 events.
[19:52:30.856] <TB0> INFO: 41600 events read in total (3375ms).
[19:52:30.856] <TB0> INFO: Test took 4243ms.
[19:52:30.859] <TB0> INFO: scanning low vcal = 230
[19:52:31.135] <TB0> INFO: Expecting 41600 events.
[19:52:35.068] <TB0> INFO: 41600 events read in total (3341ms).
[19:52:35.069] <TB0> INFO: Test took 4210ms.
[19:52:35.072] <TB0> INFO: scanning low vcal = 240
[19:52:35.348] <TB0> INFO: Expecting 41600 events.
[19:52:39.316] <TB0> INFO: 41600 events read in total (3376ms).
[19:52:39.317] <TB0> INFO: Test took 4245ms.
[19:52:39.320] <TB0> INFO: scanning low vcal = 250
[19:52:39.597] <TB0> INFO: Expecting 41600 events.
[19:52:43.555] <TB0> INFO: 41600 events read in total (3367ms).
[19:52:43.556] <TB0> INFO: Test took 4236ms.
[19:52:43.560] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[19:52:43.836] <TB0> INFO: Expecting 41600 events.
[19:52:47.768] <TB0> INFO: 41600 events read in total (3341ms).
[19:52:47.769] <TB0> INFO: Test took 4209ms.
[19:52:47.772] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[19:52:48.048] <TB0> INFO: Expecting 41600 events.
[19:52:52.099] <TB0> INFO: 41600 events read in total (3459ms).
[19:52:52.100] <TB0> INFO: Test took 4328ms.
[19:52:52.102] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[19:52:52.379] <TB0> INFO: Expecting 41600 events.
[19:52:56.346] <TB0> INFO: 41600 events read in total (3376ms).
[19:52:56.347] <TB0> INFO: Test took 4245ms.
[19:52:56.350] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[19:52:56.627] <TB0> INFO: Expecting 41600 events.
[19:53:00.616] <TB0> INFO: 41600 events read in total (3397ms).
[19:53:00.617] <TB0> INFO: Test took 4266ms.
[19:53:00.620] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[19:53:00.897] <TB0> INFO: Expecting 41600 events.
[19:53:04.841] <TB0> INFO: 41600 events read in total (3353ms).
[19:53:04.842] <TB0> INFO: Test took 4222ms.
[19:53:05.313] <TB0> INFO: PixTestGainPedestal::measure() done
[19:53:45.571] <TB0> INFO: PixTestGainPedestal::fit() done
[19:53:45.571] <TB0> INFO: non-linearity mean: 0.949 0.979 0.945 0.948 0.955 0.962 0.979 0.983 0.934 0.943 0.981 0.933 0.984 0.979 0.963 0.981
[19:53:45.571] <TB0> INFO: non-linearity RMS: 0.070 0.005 0.092 0.096 0.172 0.028 0.005 0.004 0.162 0.085 0.005 0.096 0.004 0.003 0.036 0.005
[19:53:45.571] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C0.dat
[19:53:45.592] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C1.dat
[19:53:45.611] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C2.dat
[19:53:45.630] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C3.dat
[19:53:45.649] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C4.dat
[19:53:45.669] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C5.dat
[19:53:45.688] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C6.dat
[19:53:45.707] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C7.dat
[19:53:45.726] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C8.dat
[19:53:45.745] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C9.dat
[19:53:45.764] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C10.dat
[19:53:45.783] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C11.dat
[19:53:45.802] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C12.dat
[19:53:45.822] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C13.dat
[19:53:45.842] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C14.dat
[19:53:45.862] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//phCalibrationFitErr35_C15.dat
[19:53:45.883] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[19:53:45.883] <TB0> INFO: Decoding statistics:
[19:53:45.883] <TB0> INFO: General information:
[19:53:45.883] <TB0> INFO: 16bit words read: 3299382
[19:53:45.883] <TB0> INFO: valid events total: 332800
[19:53:45.883] <TB0> INFO: empty events: 882
[19:53:45.883] <TB0> INFO: valid events with pixels: 331918
[19:53:45.883] <TB0> INFO: valid pixel hits: 651291
[19:53:45.883] <TB0> INFO: Event errors: 0
[19:53:45.883] <TB0> INFO: start marker: 0
[19:53:45.883] <TB0> INFO: stop marker: 0
[19:53:45.883] <TB0> INFO: overflow: 0
[19:53:45.883] <TB0> INFO: invalid 5bit words: 0
[19:53:45.883] <TB0> INFO: invalid XOR eye diagram: 0
[19:53:45.883] <TB0> INFO: frame (failed synchr.): 0
[19:53:45.883] <TB0> INFO: idle data (no TBM trl): 0
[19:53:45.883] <TB0> INFO: no data (only TBM hdr): 0
[19:53:45.883] <TB0> INFO: TBM errors: 0
[19:53:45.883] <TB0> INFO: flawed TBM headers: 0
[19:53:45.883] <TB0> INFO: flawed TBM trailers: 0
[19:53:45.883] <TB0> INFO: event ID mismatches: 0
[19:53:45.883] <TB0> INFO: ROC errors: 0
[19:53:45.883] <TB0> INFO: missing ROC header(s): 0
[19:53:45.883] <TB0> INFO: misplaced readback start: 0
[19:53:45.883] <TB0> INFO: Pixel decoding errors: 0
[19:53:45.883] <TB0> INFO: pixel data incomplete: 0
[19:53:45.883] <TB0> INFO: pixel address: 0
[19:53:45.883] <TB0> INFO: pulse height fill bit: 0
[19:53:45.883] <TB0> INFO: buffer corruption: 0
[19:53:45.901] <TB0> INFO: Decoding statistics:
[19:53:45.901] <TB0> INFO: General information:
[19:53:45.901] <TB0> INFO: 16bit words read: 3428806
[19:53:45.901] <TB0> INFO: valid events total: 353536
[19:53:45.901] <TB0> INFO: empty events: 19114
[19:53:45.901] <TB0> INFO: valid events with pixels: 334422
[19:53:45.901] <TB0> INFO: valid pixel hits: 653795
[19:53:45.901] <TB0> INFO: Event errors: 0
[19:53:45.901] <TB0> INFO: start marker: 0
[19:53:45.901] <TB0> INFO: stop marker: 0
[19:53:45.901] <TB0> INFO: overflow: 0
[19:53:45.901] <TB0> INFO: invalid 5bit words: 0
[19:53:45.901] <TB0> INFO: invalid XOR eye diagram: 0
[19:53:45.901] <TB0> INFO: frame (failed synchr.): 0
[19:53:45.901] <TB0> INFO: idle data (no TBM trl): 0
[19:53:45.901] <TB0> INFO: no data (only TBM hdr): 0
[19:53:45.901] <TB0> INFO: TBM errors: 0
[19:53:45.901] <TB0> INFO: flawed TBM headers: 0
[19:53:45.901] <TB0> INFO: flawed TBM trailers: 0
[19:53:45.901] <TB0> INFO: event ID mismatches: 0
[19:53:45.901] <TB0> INFO: ROC errors: 0
[19:53:45.901] <TB0> INFO: missing ROC header(s): 0
[19:53:45.901] <TB0> INFO: misplaced readback start: 0
[19:53:45.901] <TB0> INFO: Pixel decoding errors: 0
[19:53:45.901] <TB0> INFO: pixel data incomplete: 0
[19:53:45.901] <TB0> INFO: pixel address: 0
[19:53:45.901] <TB0> INFO: pulse height fill bit: 0
[19:53:45.901] <TB0> INFO: buffer corruption: 0
[19:53:45.901] <TB0> INFO: enter test to run
[19:53:45.901] <TB0> INFO: test: Trim80 no parameter change
[19:53:45.901] <TB0> INFO: running: trim80
[19:53:45.917] <TB0> INFO: ######################################################################
[19:53:45.917] <TB0> INFO: PixTestTrim80::doTest()
[19:53:45.917] <TB0> INFO: ######################################################################
[19:53:45.918] <TB0> INFO: ----------------------------------------------------------------------
[19:53:45.918] <TB0> INFO: PixTestTrim80::trimTest() ntrig = 8, vcal = 80
[19:53:45.918] <TB0> INFO: ----------------------------------------------------------------------
[19:53:45.973] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[19:53:45.974] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:53:45.985] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:53:45.985] <TB0> INFO: run 1 of 1
[19:53:46.217] <TB0> INFO: Expecting 5025280 events.
[19:54:13.868] <TB0> INFO: 688216 events read in total (27060ms).
[19:54:40.763] <TB0> INFO: 1371104 events read in total (53955ms).
[19:55:07.624] <TB0> INFO: 2051032 events read in total (80816ms).
[19:55:34.392] <TB0> INFO: 2728944 events read in total (107584ms).
[19:56:01.113] <TB0> INFO: 3407216 events read in total (134305ms).
[19:56:28.185] <TB0> INFO: 4085360 events read in total (161377ms).
[19:56:55.410] <TB0> INFO: 4763824 events read in total (188602ms).
[19:57:06.118] <TB0> INFO: 5025280 events read in total (199310ms).
[19:57:06.178] <TB0> INFO: Test took 200192ms.
[19:57:31.026] <TB0> INFO: ROC 0 VthrComp = 85
[19:57:31.026] <TB0> INFO: ROC 1 VthrComp = 80
[19:57:31.028] <TB0> INFO: ROC 2 VthrComp = 72
[19:57:31.029] <TB0> INFO: ROC 3 VthrComp = 74
[19:57:31.029] <TB0> INFO: ROC 4 VthrComp = 78
[19:57:31.030] <TB0> INFO: ROC 5 VthrComp = 75
[19:57:31.030] <TB0> INFO: ROC 6 VthrComp = 64
[19:57:31.031] <TB0> INFO: ROC 7 VthrComp = 77
[19:57:31.031] <TB0> INFO: ROC 8 VthrComp = 73
[19:57:31.031] <TB0> INFO: ROC 9 VthrComp = 82
[19:57:31.032] <TB0> INFO: ROC 10 VthrComp = 77
[19:57:31.032] <TB0> INFO: ROC 11 VthrComp = 85
[19:57:31.032] <TB0> INFO: ROC 12 VthrComp = 83
[19:57:31.033] <TB0> INFO: ROC 13 VthrComp = 77
[19:57:31.033] <TB0> INFO: ROC 14 VthrComp = 76
[19:57:31.034] <TB0> INFO: ROC 15 VthrComp = 72
[19:57:31.270] <TB0> INFO: Expecting 41600 events.
[19:57:34.776] <TB0> INFO: 41600 events read in total (2911ms).
[19:57:34.777] <TB0> INFO: Test took 3741ms.
[19:57:34.786] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[19:57:34.786] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:57:34.795] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:57:34.795] <TB0> INFO: run 1 of 1
[19:57:35.072] <TB0> INFO: Expecting 5025280 events.
[19:58:03.219] <TB0> INFO: 689648 events read in total (27555ms).
[19:58:30.373] <TB0> INFO: 1374104 events read in total (54710ms).
[19:58:57.456] <TB0> INFO: 2056920 events read in total (81792ms).
[19:59:24.510] <TB0> INFO: 2736968 events read in total (108846ms).
[19:59:51.413] <TB0> INFO: 3413304 events read in total (135749ms).
[20:00:18.618] <TB0> INFO: 4088672 events read in total (162954ms).
[20:00:45.930] <TB0> INFO: 4763112 events read in total (190266ms).
[20:00:56.578] <TB0> INFO: 5025280 events read in total (200914ms).
[20:00:56.636] <TB0> INFO: Test took 201841ms.
[20:01:20.607] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 108.597 for pixel 0/39 mean/min/max = 91.7582/74.8342/108.682
[20:01:20.608] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 108.008 for pixel 0/54 mean/min/max = 91.7986/75.3609/108.236
[20:01:20.608] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 107.978 for pixel 7/65 mean/min/max = 92.93/77.4691/108.391
[20:01:20.609] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 110.692 for pixel 1/9 mean/min/max = 93.9752/77.2098/110.741
[20:01:20.609] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 111.821 for pixel 18/2 mean/min/max = 94.8836/77.3179/112.449
[20:01:20.610] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 111.557 for pixel 11/76 mean/min/max = 94.6515/77.6699/111.633
[20:01:20.610] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 106.903 for pixel 0/39 mean/min/max = 91.3485/75.745/106.952
[20:01:20.610] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 110.649 for pixel 46/79 mean/min/max = 94.1134/77.5059/110.721
[20:01:20.611] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 111.587 for pixel 16/75 mean/min/max = 94.1648/76.5733/111.756
[20:01:20.611] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 110.714 for pixel 0/5 mean/min/max = 92.6568/74.1524/111.161
[20:01:20.612] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 111.416 for pixel 0/76 mean/min/max = 94.7712/77.9744/111.568
[20:01:20.612] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 107.367 for pixel 51/32 mean/min/max = 91.1231/74.5095/107.737
[20:01:20.612] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 107.775 for pixel 51/11 mean/min/max = 91.4352/75.036/107.834
[20:01:20.613] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 110.173 for pixel 0/79 mean/min/max = 93.93/77.5682/110.292
[20:01:20.613] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 112.221 for pixel 0/12 mean/min/max = 94.9869/77.5077/112.466
[20:01:20.614] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 112.643 for pixel 0/1 mean/min/max = 94.9709/76.9828/112.959
[20:01:20.614] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:01:20.702] <TB0> INFO: Expecting 411648 events.
[20:01:29.935] <TB0> INFO: 411648 events read in total (8641ms).
[20:01:29.942] <TB0> INFO: Expecting 411648 events.
[20:01:39.005] <TB0> INFO: 411648 events read in total (8660ms).
[20:01:39.015] <TB0> INFO: Expecting 411648 events.
[20:01:48.084] <TB0> INFO: 411648 events read in total (8666ms).
[20:01:48.096] <TB0> INFO: Expecting 411648 events.
[20:01:57.272] <TB0> INFO: 411648 events read in total (8773ms).
[20:01:57.292] <TB0> INFO: Expecting 411648 events.
[20:02:06.359] <TB0> INFO: 411648 events read in total (8664ms).
[20:02:06.376] <TB0> INFO: Expecting 411648 events.
[20:02:15.423] <TB0> INFO: 411648 events read in total (8644ms).
[20:02:15.448] <TB0> INFO: Expecting 411648 events.
[20:02:24.554] <TB0> INFO: 411648 events read in total (8702ms).
[20:02:24.576] <TB0> INFO: Expecting 411648 events.
[20:02:33.684] <TB0> INFO: 411648 events read in total (8705ms).
[20:02:33.708] <TB0> INFO: Expecting 411648 events.
[20:02:42.836] <TB0> INFO: 411648 events read in total (8725ms).
[20:02:42.873] <TB0> INFO: Expecting 411648 events.
[20:02:51.940] <TB0> INFO: 411648 events read in total (8664ms).
[20:02:51.970] <TB0> INFO: Expecting 411648 events.
[20:03:00.979] <TB0> INFO: 411648 events read in total (8606ms).
[20:03:01.012] <TB0> INFO: Expecting 411648 events.
[20:03:10.097] <TB0> INFO: 411648 events read in total (8682ms).
[20:03:10.147] <TB0> INFO: Expecting 411648 events.
[20:03:19.298] <TB0> INFO: 411648 events read in total (8748ms).
[20:03:19.353] <TB0> INFO: Expecting 411648 events.
[20:03:28.448] <TB0> INFO: 411648 events read in total (8692ms).
[20:03:28.490] <TB0> INFO: Expecting 411648 events.
[20:03:37.663] <TB0> INFO: 411648 events read in total (8770ms).
[20:03:37.713] <TB0> INFO: Expecting 411648 events.
[20:03:46.826] <TB0> INFO: 411648 events read in total (8710ms).
[20:03:46.874] <TB0> INFO: Test took 146260ms.
[20:03:48.529] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[20:03:48.539] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:03:48.540] <TB0> INFO: run 1 of 1
[20:03:48.814] <TB0> INFO: Expecting 5025280 events.
[20:04:16.353] <TB0> INFO: 665560 events read in total (26948ms).
[20:04:43.058] <TB0> INFO: 1328608 events read in total (53653ms).
[20:05:09.828] <TB0> INFO: 1990488 events read in total (80423ms).
[20:05:36.657] <TB0> INFO: 2651816 events read in total (107252ms).
[20:06:03.228] <TB0> INFO: 3308768 events read in total (133823ms).
[20:06:29.795] <TB0> INFO: 3965304 events read in total (160390ms).
[20:06:56.155] <TB0> INFO: 4619744 events read in total (186750ms).
[20:07:13.418] <TB0> INFO: 5025280 events read in total (204013ms).
[20:07:13.495] <TB0> INFO: Test took 204955ms.
[20:07:38.567] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 50.048923 .. 99.223535
[20:07:38.799] <TB0> INFO: Expecting 208000 events.
[20:07:48.689] <TB0> INFO: 208000 events read in total (9298ms).
[20:07:48.690] <TB0> INFO: Test took 10122ms.
[20:07:48.737] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 40 .. 109 (-1/-1) hits flags = 528 (plus default)
[20:07:48.747] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:07:48.747] <TB0> INFO: run 1 of 1
[20:07:49.025] <TB0> INFO: Expecting 2329600 events.
[20:08:17.652] <TB0> INFO: 701344 events read in total (28035ms).
[20:08:45.590] <TB0> INFO: 1398312 events read in total (55973ms).
[20:09:13.172] <TB0> INFO: 2087432 events read in total (83555ms).
[20:09:23.263] <TB0> INFO: 2329600 events read in total (93646ms).
[20:09:23.296] <TB0> INFO: Test took 94549ms.
[20:09:43.907] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 58.501169 .. 89.966583
[20:09:44.141] <TB0> INFO: Expecting 208000 events.
[20:09:53.978] <TB0> INFO: 208000 events read in total (9245ms).
[20:09:53.978] <TB0> INFO: Test took 10070ms.
[20:09:54.025] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 48 .. 99 (-1/-1) hits flags = 528 (plus default)
[20:09:54.035] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:09:54.035] <TB0> INFO: run 1 of 1
[20:09:54.313] <TB0> INFO: Expecting 1730560 events.
[20:10:23.069] <TB0> INFO: 713656 events read in total (28164ms).
[20:10:51.228] <TB0> INFO: 1426408 events read in total (56324ms).
[20:11:03.617] <TB0> INFO: 1730560 events read in total (68712ms).
[20:11:03.651] <TB0> INFO: Test took 69616ms.
[20:11:22.358] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 61.592069 .. 84.193110
[20:11:22.680] <TB0> INFO: Expecting 208000 events.
[20:11:32.739] <TB0> INFO: 208000 events read in total (9468ms).
[20:11:32.740] <TB0> INFO: Test took 10380ms.
[20:11:32.806] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 51 .. 94 (-1/-1) hits flags = 528 (plus default)
[20:11:32.816] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:11:32.816] <TB0> INFO: run 1 of 1
[20:11:33.094] <TB0> INFO: Expecting 1464320 events.
[20:12:02.418] <TB0> INFO: 731744 events read in total (28732ms).
[20:12:30.796] <TB0> INFO: 1462800 events read in total (57110ms).
[20:12:31.298] <TB0> INFO: 1464320 events read in total (57613ms).
[20:12:31.325] <TB0> INFO: Test took 58510ms.
[20:12:46.270] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 65.319439 .. 84.193110
[20:12:46.504] <TB0> INFO: Expecting 208000 events.
[20:12:56.489] <TB0> INFO: 208000 events read in total (9394ms).
[20:12:56.490] <TB0> INFO: Test took 10218ms.
[20:12:56.536] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 55 .. 94 (-1/-1) hits flags = 528 (plus default)
[20:12:56.547] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:12:56.547] <TB0> INFO: run 1 of 1
[20:12:56.825] <TB0> INFO: Expecting 1331200 events.
[20:13:26.086] <TB0> INFO: 720264 events read in total (28670ms).
[20:13:50.191] <TB0> INFO: 1331200 events read in total (52776ms).
[20:13:50.225] <TB0> INFO: Test took 53679ms.
[20:14:06.474] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 60 .. 100
[20:14:06.474] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 60 .. 100 (-1/-1) hits flags = 528 (plus default)
[20:14:06.485] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:14:06.485] <TB0> INFO: run 1 of 1
[20:14:06.725] <TB0> INFO: Expecting 1364480 events.
[20:14:34.728] <TB0> INFO: 668512 events read in total (27411ms).
[20:15:02.634] <TB0> INFO: 1336320 events read in total (55317ms).
[20:15:04.220] <TB0> INFO: 1364480 events read in total (56903ms).
[20:15:04.240] <TB0> INFO: Test took 57756ms.
[20:15:21.069] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C0.dat
[20:15:21.069] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C1.dat
[20:15:21.069] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C2.dat
[20:15:21.069] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C3.dat
[20:15:21.069] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C4.dat
[20:15:21.069] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C5.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C6.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C7.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C8.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C9.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C10.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C11.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C12.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C13.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C14.dat
[20:15:21.070] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//dacParameters80_C15.dat
[20:15:21.070] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C0.dat
[20:15:21.076] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C1.dat
[20:15:21.082] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C2.dat
[20:15:21.087] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C3.dat
[20:15:21.093] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C4.dat
[20:15:21.100] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C5.dat
[20:15:21.107] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C6.dat
[20:15:21.114] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C7.dat
[20:15:21.122] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C8.dat
[20:15:21.129] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C9.dat
[20:15:21.138] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C10.dat
[20:15:21.145] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C11.dat
[20:15:21.153] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C12.dat
[20:15:21.161] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C13.dat
[20:15:21.169] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C14.dat
[20:15:21.176] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1000_FullQualification_2016-11-04_10h05m_1478250315//003_FulltestTrim80_p17//trimParameters80_C15.dat
[20:15:21.184] <TB0> INFO: PixTestTrim80::trimTest() done
[20:15:21.184] <TB0> INFO: vtrim: 103 115 94 107 132 129 81 124 117 101 109 99 108 105 114 130
[20:15:21.184] <TB0> INFO: vthrcomp: 85 80 72 74 78 75 64 77 73 82 77 85 83 77 76 72
[20:15:21.184] <TB0> INFO: vcal mean: 79.93 79.96 79.95 80.01 79.99 79.96 79.93 79.97 79.95 79.95 79.96 80.02 79.92 79.97 79.95 79.95
[20:15:21.184] <TB0> INFO: vcal RMS: 0.81 0.83 0.69 0.80 0.83 0.75 0.69 0.81 0.83 0.83 0.74 0.76 0.83 0.75 0.77 0.75
[20:15:21.184] <TB0> INFO: bits mean: 10.17 10.53 9.65 9.83 10.26 10.10 10.10 10.04 10.13 9.97 9.27 10.25 10.08 9.62 9.54 10.02
[20:15:21.184] <TB0> INFO: bits RMS: 2.38 2.13 2.25 2.16 1.92 1.89 2.28 2.04 2.06 2.43 2.21 2.34 2.33 2.19 2.19 2.05
[20:15:21.191] <TB0> INFO: ----------------------------------------------------------------------
[20:15:21.191] <TB0> INFO: PixTestTrim80::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[20:15:21.191] <TB0> INFO: ----------------------------------------------------------------------
[20:15:21.194] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[20:15:21.204] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:15:21.204] <TB0> INFO: run 1 of 1
[20:15:21.438] <TB0> INFO: Expecting 4160000 events.
[20:15:53.405] <TB0> INFO: 767580 events read in total (31375ms).
[20:16:24.507] <TB0> INFO: 1526550 events read in total (62477ms).
[20:16:55.604] <TB0> INFO: 2280685 events read in total (93574ms).
[20:17:26.562] <TB0> INFO: 3030430 events read in total (124532ms).
[20:17:57.825] <TB0> INFO: 3778230 events read in total (155795ms).
[20:18:14.325] <TB0> INFO: 4160000 events read in total (172295ms).
[20:18:14.371] <TB0> INFO: Test took 173166ms.
[20:18:41.279] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 212 (-1/-1) hits flags = 528 (plus default)
[20:18:41.290] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:18:41.290] <TB0> INFO: run 1 of 1
[20:18:41.523] <TB0> INFO: Expecting 4430400 events.
[20:19:12.763] <TB0> INFO: 724310 events read in total (30648ms).
[20:19:43.344] <TB0> INFO: 1441830 events read in total (61229ms).
[20:20:13.896] <TB0> INFO: 2156485 events read in total (91781ms).
[20:20:44.400] <TB0> INFO: 2866725 events read in total (122286ms).
[20:21:14.663] <TB0> INFO: 3576015 events read in total (152548ms).
[20:21:45.398] <TB0> INFO: 4284280 events read in total (183283ms).
[20:21:52.154] <TB0> INFO: 4430400 events read in total (190039ms).
[20:21:52.227] <TB0> INFO: Test took 190937ms.
[20:22:20.455] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 215 (-1/-1) hits flags = 528 (plus default)
[20:22:20.467] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:22:20.467] <TB0> INFO: run 1 of 1
[20:22:20.715] <TB0> INFO: Expecting 4492800 events.
[20:22:51.576] <TB0> INFO: 721210 events read in total (30269ms).
[20:23:22.065] <TB0> INFO: 1435285 events read in total (60758ms).
[20:23:52.437] <TB0> INFO: 2146835 events read in total (91130ms).
[20:24:22.978] <TB0> INFO: 2854225 events read in total (121671ms).
[20:24:53.633] <TB0> INFO: 3560360 events read in total (152326ms).
[20:25:23.755] <TB0> INFO: 4265450 events read in total (182448ms).
[20:25:33.671] <TB0> INFO: 4492800 events read in total (192364ms).
[20:25:33.729] <TB0> INFO: Test took 193262ms.
[20:26:02.683] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 214 (-1/-1) hits flags = 528 (plus default)
[20:26:02.694] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:26:02.694] <TB0> INFO: run 1 of 1
[20:26:02.928] <TB0> INFO: Expecting 4472000 events.
[20:26:33.907] <TB0> INFO: 722600 events read in total (30387ms).
[20:27:04.341] <TB0> INFO: 1438155 events read in total (60821ms).
[20:27:35.205] <TB0> INFO: 2151075 events read in total (91685ms).
[20:28:05.888] <TB0> INFO: 2859440 events read in total (122368ms).
[20:28:36.198] <TB0> INFO: 3566655 events read in total (152678ms).
[20:29:06.141] <TB0> INFO: 4273035 events read in total (182621ms).
[20:29:14.893] <TB0> INFO: 4472000 events read in total (191373ms).
[20:29:14.949] <TB0> INFO: Test took 192255ms.
[20:29:42.272] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 215 (-1/-1) hits flags = 528 (plus default)
[20:29:42.282] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:29:42.282] <TB0> INFO: run 1 of 1
[20:29:42.524] <TB0> INFO: Expecting 4492800 events.
[20:30:13.850] <TB0> INFO: 721355 events read in total (30734ms).
[20:30:44.059] <TB0> INFO: 1435765 events read in total (60943ms).
[20:31:14.425] <TB0> INFO: 2147665 events read in total (91309ms).
[20:31:44.548] <TB0> INFO: 2855250 events read in total (121432ms).
[20:32:14.960] <TB0> INFO: 3561465 events read in total (151844ms).
[20:32:45.517] <TB0> INFO: 4266910 events read in total (182401ms).
[20:32:55.529] <TB0> INFO: 4492800 events read in total (192413ms).
[20:32:55.609] <TB0> INFO: Test took 193327ms.
[20:33:20.742] <TB0> INFO: PixTestTrim80::trimBitTest() done
[20:33:20.743] <TB0> INFO: PixTestTrim80::doTest() done, duration: 2374 seconds
[20:33:21.479] <TB0> INFO: enter test to run
[20:33:21.479] <TB0> INFO: test: exit no parameter change
[20:33:21.596] <TB0> QUIET: Connection to board 73 closed.
[20:33:21.597] <TB0> INFO: pXar: this is the end, my friend