[16:28:02.539] INFO: *** Welcome to pxar ***
[16:28:02.539] INFO: *** Today: 2016/11/17
[16:28:04.557] INFO: *** Version: v1.9.0-818-g96727
[16:28:04.557] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//dacParameters35_C15.dat
[16:28:04.601] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:28:04.601] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[16:28:04.643] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[16:28:05.269] INFO: clk: 4
[16:28:05.269] INFO: ctr: 4
[16:28:05.269] INFO: sda: 19
[16:28:05.269] INFO: tin: 9
[16:28:05.269] INFO: level: 15
[16:28:05.269] INFO: triggerdelay: 0
[16:28:05.269] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[16:28:05.269] INFO: Log level: INFO
[16:28:05.339] QUIET: Connection to board DTB_WREHUL opened.
[16:28:05.342] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 30
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREHUL
MAC address: 40D85511801E
Hostname: pixelDTB030
Comment:
------------------------------------------------------
[16:28:05.345] INFO: RPC call hashes of host and DTB match: 398089610
[16:28:06.915] INFO: DUT info:
[16:28:06.915] INFO: The DUT currently contains the following objects:
[16:28:06.915] INFO: 2 TBM Cores tbm08c (2 ON)
[16:28:06.927] INFO: TBM Core alpha (0): 7 registers set
[16:28:06.927] INFO: TBM Core beta (1): 7 registers set
[16:28:06.927] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:28:06.927] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.927] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.928] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.928] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.928] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.928] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:06.928] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:07.379] INFO: enter 'restricted' command line mode
[16:28:07.379] INFO: enter test to run
[16:28:14.264] INFO: test: PixelAlive no parameter change
[16:28:14.264] INFO: running: pixelalive
[16:28:14.285] INFO: ----------------------------------------------------------------------
[16:28:14.285] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:28:14.285] INFO: ----------------------------------------------------------------------
[16:28:14.600] INFO: Expecting 41600 events.
[16:28:18.965] INFO: 41600 events read in total (3647ms).
[16:28:19.135] INFO: Test took 4848ms.
[16:28:19.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:19.441] INFO: PixTestAlive::aliveTest() done
[16:28:19.441] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 2 0 0 0 0 0 1 0 1 0
[16:28:19.474] INFO: enter test to run
[16:28:59.975] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:28:59.975] INFO: running: highrate
[16:28:59.976] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:29:00.497] INFO: ----------------------------------------------------------------------
[16:29:00.497] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:29:00.497] INFO: ----------------------------------------------------------------------
[16:29:00.497] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:29:00.497] INFO: edge/corner pixel THR is adjusted
[16:29:00.497] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:29:01.523] INFO: Collecting data for 5 seconds...
[16:29:06.543] INFO: Done with hot pixel readout
[16:29:18.663] INFO: PixTest:: pg_setup set to default.
[16:29:18.663] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:29:18.664] INFO: 21 hot pixels found in step 0
[16:29:19.656] INFO: Collecting data for 5 seconds...
[16:29:24.673] INFO: Done with hot pixel readout
[16:29:36.777] INFO: PixTest:: pg_setup set to default.
[16:29:36.777] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:29:36.778] INFO: 22 hot pixels found in step 1
[16:29:37.777] INFO: Collecting data for 5 seconds...
[16:29:42.797] INFO: Done with hot pixel readout
[16:29:55.055] INFO: PixTest:: pg_setup set to default.
[16:29:55.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:29:55.056] INFO: 14 hot pixels found in step 2
[16:29:56.051] INFO: Collecting data for 5 seconds...
[16:30:01.069] INFO: Done with hot pixel readout
[16:30:13.340] INFO: PixTest:: pg_setup set to default.
[16:30:13.340] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:30:13.340] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:30:13.341] INFO: 15 hot pixels found in step 3
[16:30:14.336] INFO: Collecting data for 5 seconds...
[16:30:19.354] INFO: Done with hot pixel readout
[16:30:31.641] INFO: PixTest:: pg_setup set to default.
[16:30:31.642] INFO: 21 hot pixels found in step 4
[16:30:32.637] INFO: Collecting data for 5 seconds...
[16:30:37.655] INFO: Done with hot pixel readout
[16:30:49.865] INFO: PixTest:: pg_setup set to default.
[16:30:49.866] INFO: 13 hot pixels found in step 5
[16:30:50.860] INFO: Collecting data for 5 seconds...
[16:30:55.878] INFO: Done with hot pixel readout
[16:31:08.032] INFO: PixTest:: pg_setup set to default.
[16:31:08.032] INFO: 13 hot pixels found in step 6
[16:31:09.028] INFO: Collecting data for 5 seconds...
[16:31:14.047] INFO: Done with hot pixel readout
[16:31:26.302] INFO: PixTest:: pg_setup set to default.
[16:31:26.302] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:31:26.302] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:31:26.303] INFO: 16 hot pixels found in step 7
[16:31:27.298] INFO: Collecting data for 5 seconds...
[16:31:32.316] INFO: Done with hot pixel readout
[16:31:44.811] INFO: PixTest:: pg_setup set to default.
[16:31:44.811] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:31:44.812] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:31:44.812] INFO: 19 hot pixels found in step 8
[16:31:45.806] INFO: Collecting data for 5 seconds...
[16:31:50.825] INFO: Done with hot pixel readout
[16:32:03.036] INFO: PixTest:: pg_setup set to default.
[16:32:03.036] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:32:03.036] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:32:03.037] INFO: 16 hot pixels found in step 9
[16:32:04.032] INFO: Collecting data for 5 seconds...
[16:32:09.051] INFO: Done with hot pixel readout
[16:32:21.223] INFO: PixTest:: pg_setup set to default.
[16:32:21.223] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:32:21.223] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:32:21.224] INFO: 15 hot pixels found in step 10
[16:32:22.218] INFO: Collecting data for 5 seconds...
[16:32:27.240] INFO: Done with hot pixel readout
[16:32:39.780] INFO: PixTest:: pg_setup set to default.
[16:32:39.781] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:32:39.781] INFO: 17 hot pixels found in step 11
[16:32:40.775] INFO: Collecting data for 5 seconds...
[16:32:45.793] INFO: Done with hot pixel readout
[16:32:57.673] INFO: PixTest:: pg_setup set to default.
[16:32:57.673] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:32:57.673] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:32:57.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:32:57.674] INFO: 14 hot pixels found in step 12
[16:32:58.668] INFO: Collecting data for 5 seconds...
[16:33:03.685] INFO: Done with hot pixel readout
[16:33:15.618] INFO: PixTest:: pg_setup set to default.
[16:33:15.619] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:33:15.619] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:33:15.619] INFO: 9 hot pixels found in step 13
[16:33:16.614] INFO: Collecting data for 5 seconds...
[16:33:21.631] INFO: Done with hot pixel readout
[16:33:33.541] INFO: PixTest:: pg_setup set to default.
[16:33:33.541] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:33:33.542] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:33:33.542] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:33:33.542] INFO: 13 hot pixels found in step 14
[16:33:33.579] INFO: 13 hot pixels could not be trimmed and have been masked.
[16:33:33.583] INFO: PixTest::trimHotPixels() done
[16:33:33.583] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat
[16:33:33.608] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C1.dat
[16:33:33.620] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C2.dat
[16:33:33.629] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C3.dat
[16:33:33.634] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C4.dat
[16:33:33.639] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C5.dat
[16:33:33.645] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C6.dat
[16:33:33.650] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C7.dat
[16:33:33.655] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C8.dat
[16:33:33.661] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C9.dat
[16:33:33.666] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C10.dat
[16:33:33.671] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C11.dat
[16:33:33.677] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C12.dat
[16:33:33.682] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C13.dat
[16:33:33.687] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C14.dat
[16:33:33.692] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[16:33:33.698] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[16:33:33.716] INFO: enter test to run
[16:34:16.837] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:34:16.837] INFO: running: highrate
[16:34:16.842] INFO: ----------------------------------------------------------------------
[16:34:16.842] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:34:16.842] INFO: ----------------------------------------------------------------------
[16:34:16.842] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:34:16.842] INFO: edge/corner pixel THR is adjusted
[16:34:16.842] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:34:17.799] INFO: Collecting data for 1 seconds...
[16:34:18.803] INFO: Done with hot pixel readout
[16:34:22.950] INFO: PixTest:: pg_setup set to default.
[16:34:22.951] INFO: 0 hot pixels found in step 0
[16:34:22.957] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:34:23.043] INFO: PixTest::trimHotPixels() done
[16:34:23.043] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat
[16:34:23.053] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C1.dat
[16:34:23.059] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C2.dat
[16:34:23.064] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C3.dat
[16:34:23.070] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C4.dat
[16:34:23.075] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C5.dat
[16:34:23.081] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C6.dat
[16:34:23.086] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C7.dat
[16:34:23.092] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C8.dat
[16:34:23.097] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C9.dat
[16:34:23.103] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C10.dat
[16:34:23.108] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C11.dat
[16:34:23.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C12.dat
[16:34:23.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C13.dat
[16:34:23.124] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C14.dat
[16:34:23.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[16:34:23.135] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-20_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[16:34:23.150] INFO: enter test to run
[16:34:51.061] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:34:51.061] INFO: running: xray
[16:34:51.062] INFO: ----------------------------------------------------------------------
[16:34:51.062] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:34:51.062] INFO: ----------------------------------------------------------------------
[16:34:52.025] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:35:03.281] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:35:33.036] INFO: Resuming triggers.
[16:35:44.291] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:36:14.051] INFO: Resuming triggers.
[16:36:25.306] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[16:36:54.933] INFO: Resuming triggers.
[16:37:06.188] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[16:37:35.813] INFO: Resuming triggers.
[16:37:47.067] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:38:16.703] INFO: Resuming triggers.
[16:38:27.956] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[16:38:57.623] INFO: Resuming triggers.
[16:39:08.873] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:39:38.770] INFO: Resuming triggers.
[16:39:50.021] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[16:40:19.110] INFO: Resuming triggers.
[16:40:29.424] INFO: data taking finished, elapsed time: 100 seconds.
[16:40:57.204] INFO: PixTest:: pg_setup set to default.
[16:40:57.207] INFO: PixTestXray::doPhRun() done
[16:40:57.500] INFO: enter test to run
[16:41:45.114] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:41:45.114] INFO: running: xray
[16:41:45.115] INFO: ----------------------------------------------------------------------
[16:41:45.115] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:41:45.115] INFO: ----------------------------------------------------------------------
[16:41:46.079] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:41:52.550] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[16:42:23.680] INFO: Resuming triggers.
[16:42:30.150] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:43:01.231] INFO: Resuming triggers.
[16:43:07.702] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[16:43:38.976] INFO: Resuming triggers.
[16:43:45.448] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:44:16.442] INFO: Resuming triggers.
[16:44:22.916] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[16:44:54.547] INFO: Resuming triggers.
[16:45:01.018] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:45:32.917] INFO: Resuming triggers.
[16:45:39.391] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:46:10.871] INFO: Resuming triggers.
[16:46:17.343] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:46:49.052] INFO: Resuming triggers.
[16:46:55.525] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[16:47:27.162] INFO: Resuming triggers.
[16:47:33.640] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[16:48:04.226] INFO: Resuming triggers.
[16:48:10.701] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[16:48:41.029] INFO: Resuming triggers.
[16:48:47.501] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[16:49:17.965] INFO: Resuming triggers.
[16:49:24.440] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[16:49:55.023] INFO: Resuming triggers.
[16:50:01.493] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:50:31.878] INFO: Resuming triggers.
[16:50:38.348] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:51:08.887] INFO: Resuming triggers.
[16:51:12.158] INFO: data taking finished, elapsed time: 100 seconds.
[16:51:27.948] INFO: PixTest:: pg_setup set to default.
[16:51:27.952] INFO: PixTestXray::doPhRun() done
[16:51:28.112] INFO: enter test to run
[16:52:02.438] INFO: test: HighRate no parameter change
[16:52:02.456] INFO: running: highrate
[16:52:02.475] INFO: ----------------------------------------------------------------------
[16:52:02.475] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:52:02.475] INFO: ----------------------------------------------------------------------
[16:52:02.621] INFO: Expecting 768 events.
[16:52:03.755] INFO: 768 events read in total (418ms).
[16:52:03.756] INFO: Test took 1269ms.
[16:52:04.559] INFO: Expecting 41600 events.
[16:52:07.674] INFO: 41600 events read in total (2588ms).
[16:52:07.675] INFO: Test took 3893ms.
[16:52:07.710] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:08.430] INFO: Expecting 41600 events.
[16:52:11.650] INFO: 41600 events read in total (2693ms).
[16:52:11.651] INFO: Test took 3922ms.
[16:52:11.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:12.406] INFO: Expecting 41600 events.
[16:52:15.649] INFO: 41600 events read in total (2716ms).
[16:52:15.650] INFO: Test took 3945ms.
[16:52:15.685] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:16.404] INFO: Expecting 41600 events.
[16:52:19.657] INFO: 41600 events read in total (2726ms).
[16:52:19.657] INFO: Test took 3954ms.
[16:52:19.693] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:20.413] INFO: Expecting 41600 events.
[16:52:23.667] INFO: 41600 events read in total (2727ms).
[16:52:23.668] INFO: Test took 3956ms.
[16:52:23.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:24.425] INFO: Expecting 41600 events.
[16:52:27.698] INFO: 41600 events read in total (2745ms).
[16:52:27.700] INFO: Test took 3977ms.
[16:52:27.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:28.456] INFO: Expecting 41600 events.
[16:52:31.728] INFO: 41600 events read in total (2745ms).
[16:52:31.729] INFO: Test took 3974ms.
[16:52:31.765] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:32.484] INFO: Expecting 41600 events.
[16:52:35.750] INFO: 41600 events read in total (2739ms).
[16:52:35.752] INFO: Test took 3969ms.
[16:52:35.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:36.507] INFO: Expecting 41600 events.
[16:52:39.721] INFO: 41600 events read in total (2687ms).
[16:52:39.722] INFO: Test took 3914ms.
[16:52:39.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:40.477] INFO: Expecting 41600 events.
[16:52:43.741] INFO: 41600 events read in total (2737ms).
[16:52:43.742] INFO: Test took 3965ms.
[16:52:43.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:44.496] INFO: Expecting 41600 events.
[16:52:47.750] INFO: 41600 events read in total (2727ms).
[16:52:47.751] INFO: Test took 3955ms.
[16:52:47.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:48.508] INFO: Expecting 41600 events.
[16:52:51.757] INFO: 41600 events read in total (2723ms).
[16:52:51.758] INFO: Test took 3954ms.
[16:52:51.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:52.514] INFO: Expecting 41600 events.
[16:52:55.785] INFO: 41600 events read in total (2744ms).
[16:52:55.786] INFO: Test took 3973ms.
[16:52:55.822] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:52:56.537] INFO: Expecting 41600 events.
[16:52:59.809] INFO: 41600 events read in total (2745ms).
[16:52:59.810] INFO: Test took 3970ms.
[16:52:59.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:53:00.566] INFO: Expecting 41600 events.
[16:53:03.830] INFO: 41600 events read in total (2737ms).
[16:53:03.831] INFO: Test took 3968ms.
[16:53:03.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:53:04.587] INFO: Expecting 41600 events.
[16:53:07.865] INFO: 41600 events read in total (2751ms).
[16:53:07.866] INFO: Test took 3979ms.
[16:53:07.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:53:08.623] INFO: Expecting 41600 events.
[16:53:11.887] INFO: 41600 events read in total (2738ms).
[16:53:11.888] INFO: Test took 3967ms.
[16:53:11.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:53:12.638] INFO: Expecting 41600 events.
[16:53:15.893] INFO: 41600 events read in total (2728ms).
[16:53:15.894] INFO: Test took 3952ms.
[16:53:15.930] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:53:16.653] INFO: Expecting 41600 events.
[16:53:19.883] INFO: 41600 events read in total (2704ms).
[16:53:19.884] INFO: Test took 3935ms.
[16:53:19.920] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:53:20.639] INFO: Expecting 41600 events.
[16:53:23.733] INFO: 41600 events read in total (2567ms).
[16:53:23.734] INFO: Test took 3795ms.
[16:53:23.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:53:24.130] INFO: enter test to run
[16:54:22.061] INFO: test: HighRate no parameter change
[16:54:22.061] INFO: running: highrate
[16:54:22.062] INFO: ----------------------------------------------------------------------
[16:54:22.062] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:54:22.062] INFO: ----------------------------------------------------------------------
[16:54:22.678] INFO: Expecting 208000 events.
[16:54:34.815] INFO: 208000 events read in total (11610ms).
[16:54:34.818] INFO: Test took 12746ms.
[16:54:34.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:54:35.231] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 2 0 0 0 0 0 1 0 1 0
[16:54:35.231] INFO: number of red-efficiency pixels: 98 48 72 156 133 113 155 88 99 168 146 129 106 62 44 30
[16:54:35.231] INFO: number of X-ray hits detected: 69956 45539 69254 107467 111856 115148 122553 81897 78755 116869 110931 92628 92060 56763 22757 26304
[16:54:35.231] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:54:35.231] INFO: number of Vcal hits detected: 207900 207950 207877 207787 207865 207884 207744 207910 207899 207828 207853 207868 207844 207937 207907 207970
[16:54:35.231] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[16:54:35.231] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[16:54:35.231] INFO: X-ray hit rate [MHz/cm2]: 20.5 13.3 20.3 31.5 32.8 33.8 35.9 24.0 23.1 34.3 32.5 27.1 27.0 16.6 6.7 7.7
[16:54:35.231] INFO: PixTestHighRate::doXPixelAlive() done
[16:54:35.279] INFO: PixTest:: pg_setup set to default.
[16:54:35.295] INFO: enter test to run
[16:55:18.580] INFO: test: HighRate no parameter change
[16:55:18.581] INFO: running: highrate
[16:55:18.582] INFO: ----------------------------------------------------------------------
[16:55:18.582] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:55:18.582] INFO: ----------------------------------------------------------------------
[16:55:19.200] INFO: Expecting 208000 events.
[16:55:33.362] INFO: 208000 events read in total (13635ms).
[16:55:33.368] INFO: Test took 14777ms.
[16:55:33.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:33.996] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 2 0 0 0 0 0 1 0 1 0
[16:55:33.996] INFO: number of red-efficiency pixels: 266 149 272 462 414 398 497 309 274 629 526 395 275 228 73 68
[16:55:33.997] INFO: number of X-ray hits detected: 144819 93233 143098 222187 229968 237993 253623 170310 163393 240811 230093 190720 190747 118619 46957 53996
[16:55:33.997] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:55:33.997] INFO: number of Vcal hits detected: 207710 207847 207660 207448 207550 207575 207356 207666 207704 207303 207426 207580 207662 207757 207872 207930
[16:55:33.997] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.7 99.7 99.8 99.9 99.9 100.0 100.0
[16:55:33.997] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.8 99.8 99.7 99.8 99.9 99.7 99.7 99.8 99.8 99.9 99.9 100.0
[16:55:33.997] INFO: X-ray hit rate [MHz/cm2]: 42.4 27.3 41.9 65.1 67.4 69.8 74.3 49.9 47.9 70.6 67.4 55.9 55.9 34.8 13.8 15.8
[16:55:33.997] INFO: PixTestHighRate::doXPixelAlive() done
[16:55:34.041] INFO: PixTest:: pg_setup set to default.
[16:55:34.053] INFO: enter test to run
[16:55:59.444] INFO: test: HighRate no parameter change
[16:55:59.444] INFO: running: highrate
[16:55:59.445] INFO: ----------------------------------------------------------------------
[16:55:59.445] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:55:59.445] INFO: ----------------------------------------------------------------------
[16:56:00.061] INFO: Expecting 208000 events.
[16:56:16.054] INFO: 208000 events read in total (15466ms).
[16:56:16.062] INFO: Test took 16605ms.
[16:56:16.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:56:16.891] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 2 0 0 0 0 0 1 0 1 0
[16:56:16.891] INFO: number of red-efficiency pixels: 554 316 551 1197 893 934 1124 550 650 1438 1292 843 697 552 113 113
[16:56:16.891] INFO: number of X-ray hits detected: 218914 142001 215299 333847 347412 359827 383707 257489 247576 364508 345344 287206 288726 179727 71691 81597
[16:56:16.891] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:56:16.891] INFO: number of Vcal hits detected: 207333 207645 207213 206440 206921 206856 206449 207315 207215 206070 206361 206980 207163 207332 207831 207883
[16:56:16.891] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.7 99.3 99.5 99.5 99.4 99.7 99.7 99.2 99.3 99.6 99.7 99.7 99.9 99.9
[16:56:16.891] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.6 99.2 99.5 99.5 99.3 99.7 99.6 99.1 99.2 99.5 99.6 99.7 99.9 99.9
[16:56:16.891] INFO: X-ray hit rate [MHz/cm2]: 64.2 41.6 63.1 97.9 101.8 105.5 112.5 75.5 72.6 106.8 101.2 84.2 84.6 52.7 21.0 23.9
[16:56:16.891] INFO: PixTestHighRate::doXPixelAlive() done
[16:56:16.936] INFO: PixTest:: pg_setup set to default.
[16:56:16.951] INFO: enter test to run
[16:56:57.820] INFO: test: exit no parameter change
[16:56:58.358] QUIET: Connection to board 30 closed.
[16:56:58.394] INFO: pXar: this is the end, my friend