[11:43:32.520] INFO: *** Welcome to pxar ***
[11:43:32.520] INFO: *** Today: 2016/06/10
[11:43:32.770] INFO: *** Version: v1.9.0-814-g7497
[11:43:32.770] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//dacParameters35_C15.dat
[11:43:32.815] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:43:32.815] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[11:43:32.828] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[11:43:32.917] INFO: clk: 4
[11:43:32.917] INFO: ctr: 4
[11:43:32.917] INFO: sda: 19
[11:43:32.917] INFO: tin: 9
[11:43:32.917] INFO: level: 15
[11:43:32.917] INFO: triggerdelay: 0
[11:43:32.917] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[11:43:32.917] INFO: Log level: INFO
[11:43:32.933] QUIET: Connection to board DTB_WREKRL opened.
[11:43:32.936] 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:
------------------------------------------------------
[11:43:32.939] INFO: RPC call hashes of host and DTB match: 398089610
[11:43:34.475] INFO: DUT info:
[11:43:34.475] INFO: The DUT currently contains the following objects:
[11:43:34.475] INFO: 2 TBM Cores tbm08c (2 ON)
[11:43:34.475] INFO: TBM Core alpha (0): 7 registers set
[11:43:34.475] INFO: TBM Core beta (1): 7 registers set
[11:43:34.475] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:43:34.475] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.475] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:34.886] INFO: enter 'restricted' command line mode
[11:43:34.886] INFO: enter test to run
[11:43:40.388] INFO: test: PixelAlive no parameter change
[11:43:40.388] INFO: running: pixelalive
[11:43:40.400] INFO: ----------------------------------------------------------------------
[11:43:40.400] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:43:40.401] INFO: ----------------------------------------------------------------------
[11:43:40.732] INFO: Expecting 41600 events.
[11:43:45.092] INFO: 41600 events read in total (3642ms).
[11:43:45.257] INFO: Test took 4854ms.
[11:43:45.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:45.528] INFO: PixTestAlive::aliveTest() done
[11:43:45.528] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0
[11:43:45.558] INFO: enter test to run
[11:44:14.732] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:44:14.732] INFO: running: highrate
[11:44:14.732] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:44:14.948] INFO: ----------------------------------------------------------------------
[11:44:14.948] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:44:14.948] INFO: ----------------------------------------------------------------------
[11:44:14.948] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:44:14.948] INFO: edge/corner pixel THR is adjusted
[11:44:14.948] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:44:15.906] INFO: Collecting data for 5 seconds...
[11:44:20.925] INFO: Done with hot pixel readout
[11:44:32.633] INFO: PixTest:: pg_setup set to default.
[11:44:32.634] INFO: 5 hot pixels found in step 0
[11:44:33.619] INFO: Collecting data for 5 seconds...
[11:44:38.638] INFO: Done with hot pixel readout
[11:44:50.127] INFO: PixTest:: pg_setup set to default.
[11:44:50.128] INFO: 4 hot pixels found in step 1
[11:44:51.120] INFO: Collecting data for 5 seconds...
[11:44:56.140] INFO: Done with hot pixel readout
[11:45:07.686] INFO: PixTest:: pg_setup set to default.
[11:45:07.687] INFO: 3 hot pixels found in step 2
[11:45:08.678] INFO: Collecting data for 5 seconds...
[11:45:13.695] INFO: Done with hot pixel readout
[11:45:25.273] INFO: PixTest:: pg_setup set to default.
[11:45:25.274] INFO: 5 hot pixels found in step 3
[11:45:26.263] INFO: Collecting data for 5 seconds...
[11:45:31.280] INFO: Done with hot pixel readout
[11:45:42.795] INFO: PixTest:: pg_setup set to default.
[11:45:42.796] INFO: 3 hot pixels found in step 4
[11:45:43.785] INFO: Collecting data for 5 seconds...
[11:45:48.802] INFO: Done with hot pixel readout
[11:46:00.277] INFO: PixTest:: pg_setup set to default.
[11:46:00.278] INFO: 4 hot pixels found in step 5
[11:46:01.267] INFO: Collecting data for 5 seconds...
[11:46:06.284] INFO: Done with hot pixel readout
[11:46:17.893] INFO: PixTest:: pg_setup set to default.
[11:46:17.894] INFO: 2 hot pixels found in step 6
[11:46:18.885] INFO: Collecting data for 5 seconds...
[11:46:23.903] INFO: Done with hot pixel readout
[11:46:35.072] INFO: PixTest:: pg_setup set to default.

[11:46:35.073] INFO: 3 hot pixels found in step 7
[11:46:36.063] INFO: Collecting data for 5 seconds...
[11:46:41.079] INFO: Done with hot pixel readout
[11:46:52.418] INFO: PixTest:: pg_setup set to default.
[11:46:52.419] INFO: 3 hot pixels found in step 8
[11:46:53.408] INFO: Collecting data for 5 seconds...
[11:46:58.418] INFO: Done with hot pixel readout
[11:47:07.450] INFO: PixTest:: pg_setup set to default.
[11:47:07.451] INFO: 0 hot pixels found in step 9
[11:47:07.487] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:47:07.490] INFO: PixTest::trimHotPixels() done
[11:47:07.490] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat
[11:47:07.496] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C1.dat
[11:47:07.503] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C2.dat
[11:47:07.509] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C3.dat
[11:47:07.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C4.dat
[11:47:07.520] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C5.dat
[11:47:07.580] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C6.dat
[11:47:07.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C7.dat
[11:47:07.598] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C8.dat
[11:47:07.604] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C9.dat
[11:47:07.609] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C10.dat
[11:47:07.614] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C11.dat
[11:47:07.619] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C12.dat
[11:47:07.625] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C13.dat
[11:47:07.630] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C14.dat
[11:47:07.635] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[11:47:07.641] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[11:47:07.650] INFO: enter test to run
[11:49:54.649] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:49:54.649] INFO: running: highrate
[11:49:54.654] INFO: ----------------------------------------------------------------------
[11:49:54.654] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:49:54.654] INFO: ----------------------------------------------------------------------
[11:49:54.654] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:49:54.654] INFO: edge/corner pixel THR is adjusted
[11:49:54.654] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:49:55.611] INFO: Collecting data for 1 seconds...
[11:49:56.615] INFO: Done with hot pixel readout
[11:50:00.535] INFO: PixTest:: pg_setup set to default.
[11:50:00.536] INFO: 0 hot pixels found in step 0
[11:50:00.541] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:50:00.629] INFO: PixTest::trimHotPixels() done
[11:50:00.630] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat
[11:50:00.640] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C1.dat
[11:50:00.648] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C2.dat
[11:50:00.653] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C3.dat
[11:50:00.658] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C4.dat
[11:50:00.663] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C5.dat
[11:50:00.669] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C6.dat
[11:50:00.674] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C7.dat
[11:50:00.679] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C8.dat
[11:50:00.684] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C9.dat
[11:50:00.690] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C10.dat
[11:50:00.695] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C11.dat
[11:50:00.700] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C12.dat
[11:50:00.705] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C13.dat
[11:50:00.712] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C14.dat
[11:50:00.717] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[11:50:00.723] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-1-18_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[11:50:00.732] INFO: enter test to run
[11:50:26.201] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:50:26.201] INFO: running: xray
[11:50:26.202] INFO: ----------------------------------------------------------------------
[11:50:26.202] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:50:26.202] INFO: ----------------------------------------------------------------------
[11:50:27.165] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:50:38.891] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:51:08.454] INFO: Resuming triggers.
[11:51:20.186] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:51:49.623] INFO: Resuming triggers.
[11:52:01.353] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:52:30.582] INFO: Resuming triggers.
[11:52:42.316] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:53:12.574] INFO: Resuming triggers.
[11:53:24.307] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:53:53.410] INFO: Resuming triggers.
[11:54:05.142] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[11:54:33.599] INFO: Resuming triggers.
[11:54:45.332] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:55:11.767] INFO: Resuming triggers.
[11:55:23.498] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[11:55:51.823] INFO: Resuming triggers.
[11:55:58.313] INFO: data taking finished, elapsed time: 100 seconds.
[11:56:15.042] INFO: PixTest:: pg_setup set to default.
[11:56:15.045] INFO: PixTestXray::doPhRun() done
[11:56:15.182] INFO: enter test to run
[11:57:06.054] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:57:06.054] INFO: running: xray
[11:57:06.055] INFO: ----------------------------------------------------------------------
[11:57:06.055] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:57:06.055] INFO: ----------------------------------------------------------------------
[11:57:07.019] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:57:13.920] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:57:44.285] INFO: Resuming triggers.
[11:57:51.189] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:58:21.637] INFO: Resuming triggers.
[11:58:28.538] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:58:58.914] INFO: Resuming triggers.
[11:59:05.816] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:59:36.249] INFO: Resuming triggers.
[11:59:43.152] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:00:13.548] INFO: Resuming triggers.
[12:00:20.448] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[12:00:50.300] INFO: Resuming triggers.
[12:00:57.201] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[12:01:27.400] INFO: Resuming triggers.
[12:01:34.301] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[12:02:04.500] INFO: Resuming triggers.
[12:02:11.407] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[12:02:41.875] INFO: Resuming triggers.
[12:02:48.783] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:03:18.947] INFO: Resuming triggers.
[12:03:25.855] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[12:03:56.205] INFO: Resuming triggers.
[12:04:03.109] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[12:04:33.813] INFO: Resuming triggers.
[12:04:40.721] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[12:05:10.198] INFO: Resuming triggers.
[12:05:17.104] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[12:05:47.402] INFO: Resuming triggers.
[12:05:51.080] INFO: data taking finished, elapsed time: 100 seconds.
[12:06:08.030] INFO: PixTest:: pg_setup set to default.
[12:06:08.033] INFO: PixTestXray::doPhRun() done
[12:06:08.186] INFO: enter test to run
[12:06:28.842] INFO: test: HighRate no parameter change
[12:06:28.842] INFO: running: highrate
[12:06:28.856] INFO: ----------------------------------------------------------------------
[12:06:28.856] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:06:28.856] INFO: ----------------------------------------------------------------------
[12:06:29.012] INFO: Expecting 768 events.
[12:06:30.146] INFO: 768 events read in total (419ms).
[12:06:30.146] INFO: Test took 1269ms.
[12:06:30.949] INFO: Expecting 41600 events.
[12:06:34.106] INFO: 41600 events read in total (2630ms).
[12:06:34.107] INFO: Test took 3936ms.
[12:06:34.139] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:34.866] INFO: Expecting 41600 events.
[12:06:38.150] INFO: 41600 events read in total (2757ms).
[12:06:38.151] INFO: Test took 3995ms.
[12:06:38.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:38.904] INFO: Expecting 41600 events.
[12:06:42.170] INFO: 41600 events read in total (2740ms).
[12:06:42.171] INFO: Test took 3971ms.
[12:06:42.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:42.922] INFO: Expecting 41600 events.
[12:06:46.220] INFO: 41600 events read in total (2771ms).
[12:06:46.220] INFO: Test took 3999ms.
[12:06:46.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:46.980] INFO: Expecting 41600 events.
[12:06:50.240] INFO: 41600 events read in total (2734ms).
[12:06:50.241] INFO: Test took 3971ms.
[12:06:50.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:50.990] INFO: Expecting 41600 events.
[12:06:54.295] INFO: 41600 events read in total (2778ms).
[12:06:54.296] INFO: Test took 4005ms.
[12:06:54.329] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:55.053] INFO: Expecting 41600 events.
[12:06:58.282] INFO: 41600 events read in total (2703ms).
[12:06:58.283] INFO: Test took 3937ms.
[12:06:58.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:59.042] INFO: Expecting 41600 events.
[12:07:02.502] INFO: 41600 events read in total (2933ms).
[12:07:02.503] INFO: Test took 4171ms.
[12:07:02.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:03.260] INFO: Expecting 41600 events.
[12:07:06.560] INFO: 41600 events read in total (2773ms).
[12:07:06.561] INFO: Test took 4007ms.
[12:07:06.595] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:07.320] INFO: Expecting 41600 events.
[12:07:10.582] INFO: 41600 events read in total (2735ms).
[12:07:10.583] INFO: Test took 3971ms.
[12:07:10.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:11.343] INFO: Expecting 41600 events.
[12:07:14.716] INFO: 41600 events read in total (2847ms).
[12:07:14.717] INFO: Test took 4082ms.
[12:07:14.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:15.473] INFO: Expecting 41600 events.
[12:07:18.879] INFO: 41600 events read in total (2879ms).
[12:07:18.879] INFO: Test took 4109ms.
[12:07:18.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:19.629] INFO: Expecting 41600 events.
[12:07:22.868] INFO: 41600 events read in total (2712ms).
[12:07:22.868] INFO: Test took 3938ms.
[12:07:22.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:23.621] INFO: Expecting 41600 events.
[12:07:26.881] INFO: 41600 events read in total (2733ms).
[12:07:26.882] INFO: Test took 3963ms.
[12:07:26.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:27.632] INFO: Expecting 41600 events.
[12:07:31.018] INFO: 41600 events read in total (2859ms).
[12:07:31.019] INFO: Test took 4087ms.
[12:07:31.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:31.769] INFO: Expecting 41600 events.
[12:07:35.023] INFO: 41600 events read in total (2727ms).
[12:07:35.024] INFO: Test took 3955ms.
[12:07:35.058] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:35.783] INFO: Expecting 41600 events.
[12:07:38.997] INFO: 41600 events read in total (2687ms).
[12:07:38.998] INFO: Test took 3923ms.
[12:07:39.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:39.760] INFO: Expecting 41600 events.
[12:07:42.989] INFO: 41600 events read in total (2702ms).
[12:07:42.990] INFO: Test took 3941ms.
[12:07:43.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:43.748] INFO: Expecting 41600 events.
[12:07:46.984] INFO: 41600 events read in total (2710ms).
[12:07:46.985] INFO: Test took 3945ms.
[12:07:47.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:47.743] INFO: Expecting 41600 events.
[12:07:50.837] INFO: 41600 events read in total (2567ms).
[12:07:50.838] INFO: Test took 3803ms.
[12:07:50.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:51.235] INFO: enter test to run
[12:09:08.184] INFO: test: HighRate no parameter change
[12:09:08.185] INFO: running: highrate
[12:09:08.186] INFO: ----------------------------------------------------------------------
[12:09:08.186] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:09:08.186] INFO: ----------------------------------------------------------------------
[12:09:08.800] INFO: Expecting 208000 events.
[12:09:20.590] INFO: 208000 events read in total (11264ms).
[12:09:20.593] INFO: Test took 12398ms.
[12:09:20.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:20.981] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0
[12:09:20.981] INFO: number of red-efficiency pixels: 72 32 84 137 123 143 159 84 88 130 86 80 97 50 39 27
[12:09:20.981] INFO: number of X-ray hits detected: 60264 40388 64640 97620 108386 111358 115450 77023 75613 90159 85430 74915 76369 47754 22935 26961
[12:09:20.981] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:09:20.981] INFO: number of Vcal hits detected: 207925 207968 207915 207860 207875 207854 207736 207916 207909 207867 207914 207920 207902 207949 207959 207973
[12:09:20.982] 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 100.0 100.0 100.0 100.0 100.0 100.0
[12:09:20.982] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[12:09:20.982] INFO: X-ray hit rate [MHz/cm2]: 17.7 11.8 18.9 28.6 31.8 32.6 33.8 22.6 22.2 26.4 25.0 22.0 22.4 14.0 6.7 7.9
[12:09:20.982] INFO: PixTestHighRate::doXPixelAlive() done
[12:09:21.038] INFO: PixTest:: pg_setup set to default.
[12:09:21.050] INFO: enter test to run
[12:09:37.128] INFO: test: HighRate no parameter change
[12:09:37.128] INFO: running: highrate
[12:09:37.130] INFO: ----------------------------------------------------------------------
[12:09:37.130] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:09:37.130] INFO: ----------------------------------------------------------------------
[12:09:37.744] INFO: Expecting 208000 events.
[12:09:51.064] INFO: 208000 events read in total (12793ms).
[12:09:51.068] INFO: Test took 13930ms.
[12:09:51.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:51.629] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0
[12:09:51.629] INFO: number of red-efficiency pixels: 203 116 177 374 333 480 539 299 200 303 295 235 248 132 53 58
[12:09:51.629] INFO: number of X-ray hits detected: 121327 81477 129282 197875 217475 224709 232290 156030 153107 181661 172567 151151 153754 95797 45828 54864
[12:09:51.629] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:09:51.629] INFO: number of Vcal hits detected: 207781 207879 207814 207603 207648 207481 207305 207675 207798 207682 207689 207749 207743 207862 207946 207941
[12:09:51.629] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:09:51.629] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[12:09:51.629] INFO: X-ray hit rate [MHz/cm2]: 35.6 23.9 37.9 58.0 63.7 65.9 68.1 45.7 44.9 53.2 50.6 44.3 45.1 28.1 13.4 16.1
[12:09:51.629] INFO: PixTestHighRate::doXPixelAlive() done
[12:09:51.682] INFO: PixTest:: pg_setup set to default.
[12:09:51.697] INFO: enter test to run
[12:10:09.128] INFO: test: HighRate no parameter change
[12:10:09.128] INFO: running: highrate
[12:10:09.129] INFO: ----------------------------------------------------------------------
[12:10:09.129] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:10:09.129] INFO: ----------------------------------------------------------------------
[12:10:09.745] INFO: Expecting 208000 events.
[12:10:24.976] INFO: 208000 events read in total (14704ms).
[12:10:24.983] INFO: Test took 15845ms.
[12:10:25.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:25.747] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0
[12:10:25.747] INFO: number of red-efficiency pixels: 425 222 481 1032 880 1301 1375 734 425 817 652 520 593 267 103 83
[12:10:25.747] INFO: number of X-ray hits detected: 191038 128488 204329 309644 342692 352879 364306 247701 240882 286302 272112 238920 243871 151965 72263 86960
[12:10:25.747] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:10:25.747] INFO: number of Vcal hits detected: 207469 207763 207423 206738 206912 206287 206037 207059 207535 207052 207245 207419 207330 207713 207893 207913
[12:10:25.747] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.5 99.5 99.2 99.2 99.6 99.8 99.6 99.7 99.8 99.7 99.9 99.9 100.0
[12:10:25.747] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.4 99.5 99.2 99.1 99.5 99.8 99.5 99.6 99.7 99.7 99.9 99.9 100.0
[12:10:25.747] INFO: X-ray hit rate [MHz/cm2]: 56.0 37.7 59.9 90.8 100.4 103.4 106.8 72.6 70.6 83.9 79.8 70.0 71.5 44.5 21.2 25.5
[12:10:25.747] INFO: PixTestHighRate::doXPixelAlive() done
[12:10:25.794] INFO: PixTest:: pg_setup set to default.
[12:10:25.812] INFO: enter test to run
[12:10:31.312] INFO: test: exit no parameter change
[12:10:31.641] QUIET: Connection to board 33 closed.
[12:10:31.651] INFO: pXar: this is the end, my friend