[13:00:47.510] INFO: *** Welcome to pxar ***
[13:00:47.510] INFO: *** Today: 2016/07/28
[13:00:47.789] INFO: *** Version: v1.9.0-818-g96727
[13:00:47.789] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//dacParameters35_C15.dat
[13:00:47.835] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:00:47.836] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//defaultMaskFile.dat
[13:00:47.842] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C15.dat
[13:00:47.934] INFO: clk: 4
[13:00:47.934] INFO: ctr: 4
[13:00:47.934] INFO: sda: 19
[13:00:47.934] INFO: tin: 9
[13:00:47.934] INFO: level: 15
[13:00:47.935] INFO: triggerdelay: 0
[13:00:47.935] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:00:47.935] INFO: Log level: INFO
[13:00:47.953] QUIET: Connection to board DTB_WREKRL opened.
[13:00:47.957] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[13:00:47.960] INFO: RPC call hashes of host and DTB match: 398089610
[13:00:49.485] INFO: DUT info:
[13:00:49.485] INFO: The DUT currently contains the following objects:
[13:00:49.485] INFO: 2 TBM Cores tbm08c (2 ON)
[13:00:49.485] INFO: TBM Core alpha (0): 7 registers set
[13:00:49.485] INFO: TBM Core beta (1): 7 registers set
[13:00:49.486] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:00:49.486] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.486] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:49.889] INFO: enter 'restricted' command line mode
[13:00:49.889] INFO: enter test to run
[13:00:57.758] INFO: test: PixelAlive no parameter change
[13:00:57.758] INFO: running: pixelalive
[13:00:57.767] INFO: ----------------------------------------------------------------------
[13:00:57.767] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:00:57.767] INFO: ----------------------------------------------------------------------
[13:00:58.088] INFO: Expecting 41600 events.
[13:01:02.425] INFO: 41600 events read in total (3618ms).
[13:01:02.590] INFO: Test took 4821ms.
[13:01:02.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:02.861] INFO: PixTestAlive::aliveTest() done
[13:01:02.861] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:01:02.891] INFO: enter test to run
[13:01:43.134] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:01:43.134] INFO: running: highrate
[13:01:43.134] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:01:43.363] INFO: ----------------------------------------------------------------------
[13:01:43.363] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:01:43.364] INFO: ----------------------------------------------------------------------
[13:01:43.364] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:01:43.364] INFO: edge/corner pixel THR is adjusted
[13:01:43.364] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:01:44.324] INFO: Collecting data for 5 seconds...
[13:01:49.340] INFO: Done with hot pixel readout
[13:02:00.737] INFO: PixTest:: pg_setup set to default.
[13:02:00.738] INFO: 17 hot pixels found in step 0
[13:02:01.745] INFO: Collecting data for 5 seconds...
[13:02:06.762] INFO: Done with hot pixel readout
[13:02:18.127] INFO: PixTest:: pg_setup set to default.
[13:02:18.128] INFO: 17 hot pixels found in step 1
[13:02:19.114] INFO: Collecting data for 5 seconds...
[13:02:24.131] INFO: Done with hot pixel readout
[13:02:35.485] INFO: PixTest:: pg_setup set to default.
[13:02:35.486] INFO: 15 hot pixels found in step 2
[13:02:36.472] INFO: Collecting data for 5 seconds...
[13:02:41.488] INFO: Done with hot pixel readout
[13:02:52.822] INFO: PixTest:: pg_setup set to default.
[13:02:52.822] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:02:52.822] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:02:52.823] INFO: 17 hot pixels found in step 3
[13:02:53.809] INFO: Collecting data for 5 seconds...
[13:02:58.825] INFO: Done with hot pixel readout
[13:03:10.239] INFO: PixTest:: pg_setup set to default.
[13:03:10.239] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:03:10.239] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:03:10.240] INFO: 7 hot pixels found in step 4
[13:03:11.226] INFO: Collecting data for 5 seconds...
[13:03:16.242] INFO: Done with hot pixel readout
[13:03:27.648] INFO: PixTest:: pg_setup set to default.
[13:03:27.648] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:03:27.648] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:03:27.648] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:03:27.649] INFO: 11 hot pixels found in step 5
[13:03:28.635] INFO: Collecting data for 5 seconds...
[13:03:33.651] INFO: Done with hot pixel readout
[13:03:44.954] INFO: PixTest:: pg_setup set to default.
[13:03:44.954] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:03:44.954] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:03:44.954] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:03:44.955] INFO: 11 hot pixels found in step 6
[13:03:45.940] INFO: Collecting data for 5 seconds...
[13:03:50.960] INFO: Done with hot pixel readout
[13:04:02.252] INFO: PixTest:: pg_setup set to default.
[13:04:02.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:02.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:02.253] INFO: 4 hot pixels found in step 7
[13:04:03.238] INFO: Collecting data for 5 seconds...
[13:04:08.256] INFO: Done with hot pixel readout
[13:04:19.271] INFO: PixTest:: pg_setup set to default.
[13:04:19.271] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:19.271] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:19.272] INFO: 5 hot pixels found in step 8
[13:04:20.257] INFO: Collecting data for 5 seconds...
[13:04:25.276] INFO: Done with hot pixel readout
[13:04:36.534] INFO: PixTest:: pg_setup set to default.
[13:04:36.534] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:36.534] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:36.535] INFO: 6 hot pixels found in step 9
[13:04:37.522] INFO: Collecting data for 5 seconds...
[13:04:42.541] INFO: Done with hot pixel readout
[13:04:53.869] INFO: PixTest:: pg_setup set to default.
[13:04:53.869] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:53.869] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:53.870] INFO: 3 hot pixels found in step 10
[13:04:54.856] INFO: Collecting data for 5 seconds...
[13:04:59.876] INFO: Done with hot pixel readout
[13:05:11.302] INFO: PixTest:: pg_setup set to default.
[13:05:11.302] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:11.302] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:11.302] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:11.303] INFO: 6 hot pixels found in step 11
[13:05:12.289] INFO: Collecting data for 5 seconds...
[13:05:17.307] INFO: Done with hot pixel readout
[13:05:28.524] INFO: PixTest:: pg_setup set to default.
[13:05:28.524] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:28.524] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:28.525] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:28.525] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:28.525] INFO: 4 hot pixels found in step 12
[13:05:28.556] INFO: 4 hot pixels could not be trimmed and have been masked.
[13:05:28.560] INFO: PixTest::trimHotPixels() done
[13:05:28.562] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C0.dat
[13:05:28.568] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C1.dat
[13:05:28.575] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C2.dat
[13:05:28.580] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C3.dat
[13:05:28.586] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C4.dat
[13:05:28.592] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C5.dat
[13:05:28.597] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C6.dat
[13:05:28.603] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C7.dat
[13:05:28.608] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C8.dat
[13:05:28.614] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C9.dat
[13:05:28.619] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C10.dat
[13:05:28.624] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C11.dat
[13:05:28.629] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C12.dat
[13:05:28.635] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C13.dat
[13:05:28.640] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C14.dat
[13:05:28.645] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C15.dat
[13:05:28.651] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//defaultMaskFile.dat
[13:05:28.662] INFO: enter test to run
[13:06:25.692] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:06:25.692] INFO: running: highrate
[13:06:25.697] INFO: ----------------------------------------------------------------------
[13:06:25.697] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:06:25.697] INFO: ----------------------------------------------------------------------
[13:06:25.697] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:06:25.697] INFO: edge/corner pixel THR is adjusted
[13:06:25.697] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:06:26.658] INFO: Collecting data for 1 seconds...
[13:06:27.662] INFO: Done with hot pixel readout
[13:06:31.507] INFO: PixTest:: pg_setup set to default.
[13:06:31.508] INFO: 0 hot pixels found in step 0
[13:06:31.514] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:06:31.605] INFO: PixTest::trimHotPixels() done
[13:06:31.605] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C0.dat
[13:06:31.616] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C1.dat
[13:06:31.625] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C2.dat
[13:06:31.631] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C3.dat
[13:06:31.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C4.dat
[13:06:31.642] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C5.dat
[13:06:31.647] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C6.dat
[13:06:31.653] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C7.dat
[13:06:31.658] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C8.dat
[13:06:31.664] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C9.dat
[13:06:31.669] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C10.dat
[13:06:31.674] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C11.dat
[13:06:31.680] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C12.dat
[13:06:31.685] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C13.dat
[13:06:31.691] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C14.dat
[13:06:31.696] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//trimParameters35_C15.dat
[13:06:31.702] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-3-30_FPIXTest-17C-FNAL-160722-1101_2016-07-22_11h01m_1469203318/000_FPIXTest_p17//defaultMaskFile.dat
[13:06:31.712] INFO: enter test to run
[13:07:13.588] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:07:13.588] INFO: running: xray
[13:07:13.589] INFO: ----------------------------------------------------------------------
[13:07:13.589] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:07:13.589] INFO: ----------------------------------------------------------------------
[13:07:14.552] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:07:26.364] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:07:56.584] INFO: Resuming triggers.
[13:08:08.398] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:08:37.638] INFO: Resuming triggers.
[13:08:49.450] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:09:18.628] INFO: Resuming triggers.
[13:09:30.440] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:09:59.653] INFO: Resuming triggers.
[13:10:11.466] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:10:40.627] INFO: Resuming triggers.
[13:10:52.440] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:11:21.422] INFO: Resuming triggers.
[13:11:33.237] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:12:02.426] INFO: Resuming triggers.
[13:12:14.242] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:12:43.390] INFO: Resuming triggers.
[13:12:49.223] INFO: data taking finished, elapsed time: 100 seconds.
[13:13:03.947] INFO: PixTest:: pg_setup set to default.
[13:13:03.950] INFO: PixTestXray::doPhRun() done
[13:13:04.082] INFO: enter test to run
[13:14:15.793] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:14:15.793] INFO: running: xray
[13:14:15.794] INFO: ----------------------------------------------------------------------
[13:14:15.794] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:14:15.794] INFO: ----------------------------------------------------------------------
[13:14:16.758] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:14:23.937] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[13:14:54.054] INFO: Resuming triggers.
[13:15:01.232] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[13:15:31.405] INFO: Resuming triggers.
[13:15:38.581] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:16:09.320] INFO: Resuming triggers.
[13:16:16.498] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[13:16:46.855] INFO: Resuming triggers.
[13:16:54.027] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:17:24.744] INFO: Resuming triggers.
[13:17:31.918] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[13:18:02.640] INFO: Resuming triggers.
[13:18:09.813] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:18:41.610] INFO: Resuming triggers.
[13:18:48.788] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:19:19.966] INFO: Resuming triggers.
[13:19:27.140] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[13:19:57.618] INFO: Resuming triggers.
[13:20:04.795] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[13:20:34.902] INFO: Resuming triggers.
[13:20:42.076] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[13:21:12.210] INFO: Resuming triggers.
[13:21:19.389] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[13:21:49.488] INFO: Resuming triggers.
[13:21:56.663] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:22:26.760] INFO: Resuming triggers.
[13:22:33.821] INFO: data taking finished, elapsed time: 100 seconds.
[13:23:03.565] INFO: PixTest:: pg_setup set to default.
[13:23:03.568] INFO: PixTestXray::doPhRun() done
[13:23:03.715] INFO: enter test to run
[13:24:02.285] INFO: test: HighRate no parameter change
[13:24:02.285] INFO: running: highrate
[13:24:02.292] INFO: ----------------------------------------------------------------------
[13:24:02.292] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:24:02.292] INFO: ----------------------------------------------------------------------
[13:24:02.447] INFO: Expecting 768 events.
[13:24:03.580] INFO: 768 events read in total (418ms).
[13:24:03.581] INFO: Test took 1268ms.
[13:24:04.384] INFO: Expecting 41600 events.
[13:24:07.487] INFO: 41600 events read in total (2576ms).
[13:24:07.488] INFO: Test took 3901ms.
[13:24:07.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:08.250] INFO: Expecting 41600 events.
[13:24:11.409] INFO: 41600 events read in total (2633ms).
[13:24:11.410] INFO: Test took 3874ms.
[13:24:11.442] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:12.171] INFO: Expecting 41600 events.
[13:24:15.381] INFO: 41600 events read in total (2684ms).
[13:24:15.382] INFO: Test took 3923ms.
[13:24:15.415] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:16.138] INFO: Expecting 41600 events.
[13:24:19.346] INFO: 41600 events read in total (2681ms).
[13:24:19.347] INFO: Test took 3916ms.
[13:24:19.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:20.108] INFO: Expecting 41600 events.
[13:24:23.315] INFO: 41600 events read in total (2680ms).
[13:24:23.316] INFO: Test took 3920ms.
[13:24:23.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:24.076] INFO: Expecting 41600 events.
[13:24:27.298] INFO: 41600 events read in total (2695ms).
[13:24:27.299] INFO: Test took 3933ms.
[13:24:27.330] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:28.057] INFO: Expecting 41600 events.
[13:24:31.270] INFO: 41600 events read in total (2687ms).
[13:24:31.271] INFO: Test took 3923ms.
[13:24:31.303] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:32.031] INFO: Expecting 41600 events.
[13:24:35.257] INFO: 41600 events read in total (2699ms).
[13:24:35.258] INFO: Test took 3937ms.
[13:24:35.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:36.020] INFO: Expecting 41600 events.
[13:24:39.237] INFO: 41600 events read in total (2690ms).
[13:24:39.238] INFO: Test took 3932ms.
[13:24:39.269] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:39.997] INFO: Expecting 41600 events.
[13:24:43.212] INFO: 41600 events read in total (2690ms).
[13:24:43.213] INFO: Test took 3927ms.
[13:24:43.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:43.976] INFO: Expecting 41600 events.
[13:24:47.192] INFO: 41600 events read in total (2689ms).
[13:24:47.193] INFO: Test took 3930ms.
[13:24:47.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:47.949] INFO: Expecting 41600 events.
[13:24:51.152] INFO: 41600 events read in total (2676ms).
[13:24:51.153] INFO: Test took 3911ms.
[13:24:51.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:51.910] INFO: Expecting 41600 events.
[13:24:55.129] INFO: 41600 events read in total (2692ms).
[13:24:55.130] INFO: Test took 3927ms.
[13:24:55.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:55.892] INFO: Expecting 41600 events.
[13:24:59.112] INFO: 41600 events read in total (2693ms).
[13:24:59.113] INFO: Test took 3935ms.
[13:24:59.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:59.874] INFO: Expecting 41600 events.
[13:25:03.108] INFO: 41600 events read in total (2707ms).
[13:25:03.109] INFO: Test took 3947ms.
[13:25:03.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:03.870] INFO: Expecting 41600 events.
[13:25:07.088] INFO: 41600 events read in total (2692ms).
[13:25:07.089] INFO: Test took 3932ms.
[13:25:07.121] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:07.847] INFO: Expecting 41600 events.
[13:25:11.075] INFO: 41600 events read in total (2701ms).
[13:25:11.076] INFO: Test took 3938ms.
[13:25:11.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:11.836] INFO: Expecting 41600 events.
[13:25:15.057] INFO: 41600 events read in total (2694ms).
[13:25:15.058] INFO: Test took 3933ms.
[13:25:15.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:15.817] INFO: Expecting 41600 events.
[13:25:19.017] INFO: 41600 events read in total (2673ms).
[13:25:19.018] INFO: Test took 3912ms.
[13:25:19.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:19.776] INFO: Expecting 41600 events.
[13:25:22.845] INFO: 41600 events read in total (2542ms).
[13:25:22.846] INFO: Test took 3778ms.
[13:25:22.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:23.252] INFO: enter test to run
[13:25:42.924] INFO: test: HighRate no parameter change
[13:25:42.924] INFO: running: highrate
[13:25:42.925] INFO: ----------------------------------------------------------------------
[13:25:42.925] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:25:42.925] INFO: ----------------------------------------------------------------------
[13:25:43.542] INFO: Expecting 208000 events.
[13:25:55.298] INFO: 208000 events read in total (11229ms).
[13:25:55.301] INFO: Test took 12366ms.
[13:25:55.435] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:55.683] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:25:55.683] INFO: number of red-efficiency pixels: 97 44 88 111 138 136 133 96 71 99 98 105 99 56 17 31
[13:25:55.683] INFO: number of X-ray hits detected: 63998 43580 62151 97541 104083 104798 104207 70100 61799 88005 88553 77182 81580 46675 20287 24075
[13:25:55.683] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:25:55.683] INFO: number of Vcal hits detected: 207903 207903 207909 207886 207860 207863 207865 207904 207929 207899 207902 207894 207899 207944 207983 207969
[13:25:55.683] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:25:55.683] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0
[13:25:55.683] INFO: X-ray hit rate [MHz/cm2]: 18.8 12.8 18.2 28.6 30.5 30.7 30.5 20.5 18.1 25.8 26.0 22.6 23.9 13.7 5.9 7.1
[13:25:55.683] INFO: PixTestHighRate::doXPixelAlive() done
[13:25:55.729] INFO: PixTest:: pg_setup set to default.
[13:25:55.744] INFO: enter test to run
[13:26:28.620] INFO: test: HighRate no parameter change
[13:26:28.620] INFO: running: highrate
[13:26:28.621] INFO: ----------------------------------------------------------------------
[13:26:28.621] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:26:28.621] INFO: ----------------------------------------------------------------------
[13:26:29.236] INFO: Expecting 208000 events.
[13:26:42.504] INFO: 208000 events read in total (12742ms).
[13:26:42.509] INFO: Test took 13879ms.
[13:26:42.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:43.068] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:26:43.068] INFO: number of red-efficiency pixels: 222 123 219 283 419 440 402 263 174 235 316 274 251 160 46 70
[13:26:43.068] INFO: number of X-ray hits detected: 128860 88183 125871 197035 210643 212587 210960 142022 125382 178133 179318 157989 165154 94487 40888 49155
[13:26:43.068] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:26:43.068] INFO: number of Vcal hits detected: 207764 207824 207767 207688 207540 207519 207569 207709 207820 207754 207667 207713 207739 207831 207954 207928
[13:26:43.068] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[13:26:43.068] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[13:26:43.068] INFO: X-ray hit rate [MHz/cm2]: 37.8 25.8 36.9 57.8 61.7 62.3 61.8 41.6 36.8 52.2 52.6 46.3 48.4 27.7 12.0 14.4
[13:26:43.068] INFO: PixTestHighRate::doXPixelAlive() done
[13:26:43.114] INFO: PixTest:: pg_setup set to default.
[13:26:43.124] INFO: enter test to run
[13:27:27.251] INFO: test: HighRate no parameter change
[13:27:27.251] INFO: running: highrate
[13:27:27.253] INFO: ----------------------------------------------------------------------
[13:27:27.253] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:27:27.253] INFO: ----------------------------------------------------------------------
[13:27:27.873] INFO: Expecting 208000 events.
[13:27:42.747] INFO: 208000 events read in total (14347ms).
[13:27:42.754] INFO: Test took 15490ms.
[13:27:43.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:43.479] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:27:43.479] INFO: number of red-efficiency pixels: 492 274 544 686 1009 1094 1005 600 332 553 712 585 458 340 73 75
[13:27:43.479] INFO: number of X-ray hits detected: 195401 132751 190577 298998 319171 321960 319453 215354 190028 269912 271509 239073 250431 144224 62415 74300
[13:27:43.479] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:27:43.479] INFO: number of Vcal hits detected: 207409 207655 207355 207194 206775 206637 206781 207285 207633 207396 207184 207347 207500 207622 207927 207923
[13:27:43.479] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.4 99.5 99.7 99.8 99.7 99.6 99.7 99.8 99.8 100.0 100.0
[13:27:43.480] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.7 99.6 99.4 99.3 99.4 99.7 99.8 99.7 99.6 99.7 99.8 99.8 100.0 100.0
[13:27:43.480] INFO: X-ray hit rate [MHz/cm2]: 57.3 38.9 55.9 87.6 93.6 94.4 93.6 63.1 55.7 79.1 79.6 70.1 73.4 42.3 18.3 21.8
[13:27:43.480] INFO: PixTestHighRate::doXPixelAlive() done
[13:27:43.530] INFO: PixTest:: pg_setup set to default.
[13:27:43.543] INFO: enter test to run
[13:28:00.195] INFO: test: exit no parameter change
[13:28:00.454] QUIET: Connection to board 33 closed.
[13:28:00.455] INFO: pXar: this is the end, my friend