[10:28:49.493] INFO: *** Welcome to pxar ***
[10:28:49.493] INFO: *** Today: 2016/06/16
[10:28:49.510] INFO: *** Version: v1.9.0-814-g7497
[10:28:49.510] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//dacParameters35_C15.dat
[10:28:49.538] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:28:49.538] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[10:28:49.538] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[10:28:49.620] INFO: clk: 4
[10:28:49.620] INFO: ctr: 4
[10:28:49.620] INFO: sda: 19
[10:28:49.620] INFO: tin: 9
[10:28:49.620] INFO: level: 15
[10:28:49.620] INFO: triggerdelay: 0
[10:28:49.620] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:28:49.620] INFO: Log level: INFO
[10:28:49.637] QUIET: Connection to board DTB_WREKRL opened.
[10:28:49.641] 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:
------------------------------------------------------
[10:28:49.643] INFO: RPC call hashes of host and DTB match: 398089610
[10:28:51.180] INFO: DUT info:
[10:28:51.180] INFO: The DUT currently contains the following objects:
[10:28:51.180] INFO: 2 TBM Cores tbm08c (2 ON)
[10:28:51.180] INFO: TBM Core alpha (0): 7 registers set
[10:28:51.180] INFO: TBM Core beta (1): 7 registers set
[10:28:51.180] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:28:51.180] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.180] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.181] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.181] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:51.584] INFO: enter 'restricted' command line mode
[10:28:51.584] INFO: enter test to run
[10:29:02.152] INFO: test: PixelAlive no parameter change
[10:29:02.152] INFO: running: pixelalive
[10:29:02.161] INFO: ----------------------------------------------------------------------
[10:29:02.161] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:29:02.161] INFO: ----------------------------------------------------------------------
[10:29:02.474] INFO: Expecting 41600 events.
[10:29:06.796] INFO: 41600 events read in total (3603ms).
[10:29:06.966] INFO: Test took 4803ms.
[10:29:06.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:07.240] INFO: PixTestAlive::aliveTest() done
[10:29:07.240] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[10:29:07.270] INFO: enter test to run
[10:30:00.623] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:30:00.623] INFO: running: highrate
[10:30:00.623] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:30:00.848] INFO: ----------------------------------------------------------------------
[10:30:00.848] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:30:00.848] INFO: ----------------------------------------------------------------------
[10:30:00.848] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:30:00.848] INFO: edge/corner pixel THR is adjusted
[10:30:00.848] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:30:01.808] INFO: Collecting data for 5 seconds...
[10:30:06.825] INFO: Done with hot pixel readout
[10:30:18.712] INFO: PixTest:: pg_setup set to default.
[10:30:18.713] INFO: 8 hot pixels found in step 0
[10:30:19.705] INFO: Collecting data for 5 seconds...
[10:30:24.720] INFO: Done with hot pixel readout
[10:30:36.538] INFO: PixTest:: pg_setup set to default.
[10:30:36.539] INFO: 2 hot pixels found in step 1
[10:30:37.531] INFO: Collecting data for 5 seconds...
[10:30:42.546] INFO: Done with hot pixel readout
[10:30:54.370] INFO: PixTest:: pg_setup set to default.
[10:30:54.371] INFO: 6 hot pixels found in step 2
[10:30:55.364] INFO: Collecting data for 5 seconds...
[10:31:00.380] INFO: Done with hot pixel readout
[10:31:12.192] INFO: PixTest:: pg_setup set to default.
[10:31:12.193] INFO: 5 hot pixels found in step 3
[10:31:13.184] INFO: Collecting data for 5 seconds...
[10:31:18.200] INFO: Done with hot pixel readout
[10:31:29.999] INFO: PixTest:: pg_setup set to default.
[10:31:29.000] INFO: 2 hot pixels found in step 4
[10:31:30.992] INFO: Collecting data for 5 seconds...
[10:31:36.007] INFO: Done with hot pixel readout
[10:31:47.828] INFO: PixTest:: pg_setup set to default.
[10:31:47.829] INFO: 3 hot pixels found in step 5
[10:31:48.821] INFO: Collecting data for 5 seconds...
[10:31:53.836] INFO: Done with hot pixel readout
[10:32:05.663] INFO: PixTest:: pg_setup set to default.
[10:32:05.664] INFO: 1 hot pixels found in step 6
[10:32:06.657] INFO: Collecting data for 5 seconds...
[10:32:11.672] INFO: Done with hot pixel readout
[10:32:23.520] INFO: PixTest:: pg_setup set to default.
[10:32:23.521] INFO: 5 hot pixels found in step 7
[10:32:24.513] INFO: Collecting data for 5 seconds...
[10:32:29.528] INFO: Done with hot pixel readout
[10:32:41.336] INFO: PixTest:: pg_setup set to default.
[10:32:41.337] INFO: 3 hot pixels found in step 8
[10:32:42.330] INFO: Collecting data for 5 seconds...
[10:32:47.345] INFO: Done with hot pixel readout
[10:32:59.135] INFO: PixTest:: pg_setup set to default.
[10:32:59.136] INFO: 4 hot pixels found in step 9
[10:33:00.128] INFO: Collecting data for 5 seconds...
[10:33:05.144] INFO: Done with hot pixel readout
[10:33:16.955] INFO: PixTest:: pg_setup set to default.
[10:33:16.956] INFO: 5 hot pixels found in step 10
[10:33:17.947] INFO: Collecting data for 5 seconds...
[10:33:22.963] INFO: Done with hot pixel readout
[10:33:34.786] INFO: PixTest:: pg_setup set to default.
[10:33:34.787] INFO: 4 hot pixels found in step 11
[10:33:35.779] INFO: Collecting data for 5 seconds...
[10:33:40.794] INFO: Done with hot pixel readout
[10:33:52.581] INFO: PixTest:: pg_setup set to default.
[10:33:52.582] INFO: 4 hot pixels found in step 12
[10:33:53.575] INFO: Collecting data for 5 seconds...
[10:33:58.590] INFO: Done with hot pixel readout
[10:34:10.401] INFO: PixTest:: pg_setup set to default.
[10:34:10.402] INFO: 0 hot pixels found in step 13
[10:34:10.438] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:34:10.442] INFO: PixTest::trimHotPixels() done
[10:34:10.442] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat
[10:34:10.448] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C1.dat
[10:34:10.454] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C2.dat
[10:34:10.459] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C3.dat
[10:34:10.465] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C4.dat
[10:34:10.470] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C5.dat
[10:34:10.475] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C6.dat
[10:34:10.480] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C7.dat
[10:34:10.485] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C8.dat
[10:34:10.491] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C9.dat
[10:34:10.496] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C10.dat
[10:34:10.501] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C11.dat
[10:34:10.506] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C12.dat
[10:34:10.511] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C13.dat
[10:34:10.517] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C14.dat
[10:34:10.522] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[10:34:10.527] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[10:34:10.537] INFO: enter test to run
[10:34:40.317] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:34:40.317] INFO: running: highrate
[10:34:40.322] INFO: ----------------------------------------------------------------------
[10:34:40.322] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:34:40.322] INFO: ----------------------------------------------------------------------
[10:34:40.322] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:34:40.322] INFO: edge/corner pixel THR is adjusted
[10:34:40.322] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:34:41.280] INFO: Collecting data for 1 seconds...
[10:34:42.283] INFO: Done with hot pixel readout
[10:34:46.333] INFO: PixTest:: pg_setup set to default.
[10:34:46.334] INFO: 0 hot pixels found in step 0
[10:34:46.339] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:34:46.430] INFO: PixTest::trimHotPixels() done
[10:34:46.430] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat
[10:34:46.437] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C1.dat
[10:34:46.445] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C2.dat
[10:34:46.450] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C3.dat
[10:34:46.456] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C4.dat
[10:34:46.461] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C5.dat
[10:34:46.466] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C6.dat
[10:34:46.471] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C7.dat
[10:34:46.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C8.dat
[10:34:46.482] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C9.dat
[10:34:46.487] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C10.dat
[10:34:46.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C11.dat
[10:34:46.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C12.dat
[10:34:46.503] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C13.dat
[10:34:46.508] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C14.dat
[10:34:46.514] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[10:34:46.519] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-2-49_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[10:34:46.529] INFO: enter test to run
[10:35:19.597] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:35:19.597] INFO: running: xray
[10:35:19.598] INFO: ----------------------------------------------------------------------
[10:35:19.598] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:35:19.598] INFO: ----------------------------------------------------------------------
[10:35:20.563] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:35:31.960] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:36:01.360] INFO: Resuming triggers.
[10:36:12.755] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:36:42.272] INFO: Resuming triggers.
[10:36:53.666] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:37:23.109] INFO: Resuming triggers.
[10:37:34.505] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:38:04.008] INFO: Resuming triggers.
[10:38:15.401] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:38:44.874] INFO: Resuming triggers.
[10:38:56.270] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[10:39:25.458] INFO: Resuming triggers.
[10:39:36.858] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:40:06.266] INFO: Resuming triggers.
[10:40:17.668] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:40:47.045] INFO: Resuming triggers.
[10:40:56.171] INFO: data taking finished, elapsed time: 100 seconds.
[10:41:19.823] INFO: PixTest:: pg_setup set to default.
[10:41:19.826] INFO: PixTestXray::doPhRun() done
[10:41:19.961] INFO: enter test to run
[10:42:03.274] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:42:03.274] INFO: running: xray
[10:42:03.276] INFO: ----------------------------------------------------------------------
[10:42:03.276] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:42:03.276] INFO: ----------------------------------------------------------------------
[10:42:04.239] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:42:10.871] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:42:40.913] INFO: Resuming triggers.
[10:42:47.548] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:43:17.638] INFO: Resuming triggers.
[10:43:24.270] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:43:54.283] INFO: Resuming triggers.
[10:44:00.913] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:44:30.979] INFO: Resuming triggers.
[10:44:37.609] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:45:07.560] INFO: Resuming triggers.
[10:45:14.187] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:45:44.203] INFO: Resuming triggers.
[10:45:50.832] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:46:20.869] INFO: Resuming triggers.
[10:46:27.499] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:46:57.504] INFO: Resuming triggers.
[10:47:04.133] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:47:34.160] INFO: Resuming triggers.
[10:47:40.787] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:48:10.923] INFO: Resuming triggers.
[10:48:17.552] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:48:47.571] INFO: Resuming triggers.
[10:48:54.204] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:49:24.272] INFO: Resuming triggers.
[10:49:30.905] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:50:01.302] INFO: Resuming triggers.
[10:50:07.936] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:50:37.913] INFO: Resuming triggers.
[10:50:44.545] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:51:14.658] INFO: Resuming triggers.
[10:51:15.501] INFO: data taking finished, elapsed time: 100 seconds.
[10:51:19.779] INFO: PixTest:: pg_setup set to default.
[10:51:19.783] INFO: PixTestXray::doPhRun() done
[10:51:19.940] INFO: enter test to run
[10:51:53.374] INFO: test: HighRate no parameter change
[10:51:53.374] INFO: running: highrate
[10:51:53.376] INFO: ----------------------------------------------------------------------
[10:51:53.376] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:51:53.376] INFO: ----------------------------------------------------------------------
[10:51:53.516] INFO: Expecting 768 events.
[10:51:54.650] INFO: 768 events read in total (419ms).
[10:51:54.650] INFO: Test took 1268ms.
[10:51:55.453] INFO: Expecting 41600 events.
[10:51:58.475] INFO: 41600 events read in total (2495ms).
[10:51:58.476] INFO: Test took 3819ms.
[10:51:58.509] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:59.240] INFO: Expecting 41600 events.
[10:52:02.414] INFO: 41600 events read in total (2647ms).
[10:52:02.415] INFO: Test took 3889ms.
[10:52:02.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:03.177] INFO: Expecting 41600 events.
[10:52:06.393] INFO: 41600 events read in total (2689ms).
[10:52:06.394] INFO: Test took 3929ms.
[10:52:06.426] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:07.149] INFO: Expecting 41600 events.
[10:52:10.370] INFO: 41600 events read in total (2694ms).
[10:52:10.371] INFO: Test took 3927ms.
[10:52:10.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:11.131] INFO: Expecting 41600 events.
[10:52:14.354] INFO: 41600 events read in total (2697ms).
[10:52:14.355] INFO: Test took 3933ms.
[10:52:14.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:15.114] INFO: Expecting 41600 events.
[10:52:18.337] INFO: 41600 events read in total (2696ms).
[10:52:18.339] INFO: Test took 3932ms.
[10:52:18.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:19.098] INFO: Expecting 41600 events.
[10:52:22.352] INFO: 41600 events read in total (2727ms).
[10:52:22.353] INFO: Test took 3956ms.
[10:52:22.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:23.105] INFO: Expecting 41600 events.
[10:52:26.361] INFO: 41600 events read in total (2729ms).
[10:52:26.362] INFO: Test took 3958ms.
[10:52:26.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:27.118] INFO: Expecting 41600 events.
[10:52:30.361] INFO: 41600 events read in total (2716ms).
[10:52:30.362] INFO: Test took 3949ms.
[10:52:30.396] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:31.121] INFO: Expecting 41600 events.
[10:52:34.356] INFO: 41600 events read in total (2708ms).
[10:52:34.357] INFO: Test took 3944ms.
[10:52:34.391] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:35.117] INFO: Expecting 41600 events.
[10:52:38.340] INFO: 41600 events read in total (2696ms).
[10:52:38.341] INFO: Test took 3931ms.
[10:52:38.375] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:39.100] INFO: Expecting 41600 events.
[10:52:42.353] INFO: 41600 events read in total (2726ms).
[10:52:42.355] INFO: Test took 3963ms.
[10:52:42.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:43.101] INFO: Expecting 41600 events.
[10:52:46.366] INFO: 41600 events read in total (2738ms).
[10:52:46.367] INFO: Test took 3955ms.
[10:52:46.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:47.123] INFO: Expecting 41600 events.
[10:52:50.366] INFO: 41600 events read in total (2716ms).
[10:52:50.368] INFO: Test took 3948ms.
[10:52:50.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:51.125] INFO: Expecting 41600 events.
[10:52:54.350] INFO: 41600 events read in total (2698ms).
[10:52:54.351] INFO: Test took 3931ms.
[10:52:54.384] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:55.111] INFO: Expecting 41600 events.
[10:52:58.356] INFO: 41600 events read in total (2718ms).
[10:52:58.357] INFO: Test took 3955ms.
[10:52:58.391] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:59.115] INFO: Expecting 41600 events.
[10:53:02.339] INFO: 41600 events read in total (2697ms).
[10:53:02.340] INFO: Test took 3930ms.
[10:53:02.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:03.098] INFO: Expecting 41600 events.
[10:53:06.334] INFO: 41600 events read in total (2709ms).
[10:53:06.335] INFO: Test took 3943ms.
[10:53:06.368] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:07.092] INFO: Expecting 41600 events.
[10:53:10.321] INFO: 41600 events read in total (2702ms).
[10:53:10.322] INFO: Test took 3935ms.
[10:53:10.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:11.083] INFO: Expecting 41600 events.
[10:53:14.180] INFO: 41600 events read in total (2571ms).
[10:53:14.181] INFO: Test took 3806ms.
[10:53:14.214] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:14.575] INFO: enter test to run
[10:53:19.030] INFO: test: HighRate no parameter change
[10:53:19.030] INFO: running: highrate
[10:53:19.031] INFO: ----------------------------------------------------------------------
[10:53:19.031] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:53:19.031] INFO: ----------------------------------------------------------------------
[10:53:19.643] INFO: Expecting 208000 events.
[10:53:31.532] INFO: 208000 events read in total (11362ms).
[10:53:31.535] INFO: Test took 12497ms.
[10:53:31.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:31.928] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[10:53:31.928] INFO: number of red-efficiency pixels: 64 54 95 110 158 144 135 108 85 112 106 74 119 64 18 29
[10:53:31.928] INFO: number of X-ray hits detected: 64265 44685 66354 103573 111275 111637 114134 81046 86833 94009 93760 76627 81459 48343 19466 25994
[10:53:31.928] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:53:31.929] INFO: number of Vcal hits detected: 207936 207945 207901 207886 207839 207849 207862 207891 207915 207835 207889 207926 207880 207935 207981 207970
[10:53:31.929] 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 100.0 99.9 100.0 100.0 100.0
[10:53:31.929] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[10:53:31.929] INFO: X-ray hit rate [MHz/cm2]: 18.8 13.1 19.4 30.4 32.6 32.7 33.5 23.8 25.5 27.6 27.5 22.5 23.9 14.2 5.7 7.6
[10:53:31.929] INFO: PixTestHighRate::doXPixelAlive() done
[10:53:31.978] INFO: PixTest:: pg_setup set to default.
[10:53:31.994] INFO: enter test to run
[10:53:50.550] INFO: test: HighRate no parameter change
[10:53:50.550] INFO: running: highrate
[10:53:50.551] INFO: ----------------------------------------------------------------------
[10:53:50.551] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:53:50.551] INFO: ----------------------------------------------------------------------
[10:53:51.164] INFO: Expecting 208000 events.
[10:54:04.838] INFO: 208000 events read in total (13147ms).
[10:54:04.843] INFO: Test took 14283ms.
[10:54:05.135] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:05.436] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[10:54:05.436] INFO: number of red-efficiency pixels: 209 164 326 473 550 576 425 360 392 367 374 316 313 195 47 68
[10:54:05.436] INFO: number of X-ray hits detected: 136148 95319 141207 218391 236615 237959 241838 172417 186619 200130 199387 163069 174175 103104 41680 55150
[10:54:05.436] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:54:05.436] INFO: number of Vcal hits detected: 207776 207823 207641 207487 207399 207359 207548 207608 207585 207564 207605 207669 207672 207795 207953 207927
[10:54:05.436] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:54:05.436] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[10:54:05.436] INFO: X-ray hit rate [MHz/cm2]: 39.9 27.9 41.4 64.0 69.4 69.7 70.9 50.5 54.7 58.7 58.4 47.8 51.1 30.2 12.2 16.2
[10:54:05.436] INFO: PixTestHighRate::doXPixelAlive() done
[10:54:05.482] INFO: PixTest:: pg_setup set to default.
[10:54:05.499] INFO: enter test to run
[10:54:26.653] INFO: test: HighRate no parameter change
[10:54:26.653] INFO: running: highrate
[10:54:26.655] INFO: ----------------------------------------------------------------------
[10:54:26.655] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:54:26.655] INFO: ----------------------------------------------------------------------
[10:54:27.271] INFO: Expecting 208000 events.
[10:54:42.864] INFO: 208000 events read in total (15067ms).
[10:54:42.872] INFO: Test took 16207ms.
[10:54:43.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:43.659] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[10:54:43.659] INFO: number of red-efficiency pixels: 457 312 669 1194 1360 1351 995 806 801 814 975 625 759 417 78 120
[10:54:43.659] INFO: number of X-ray hits detected: 208106 146429 214876 335114 360356 362578 369462 264404 284809 307151 304230 248766 265213 157469 63972 84944
[10:54:43.659] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:54:43.659] INFO: number of Vcal hits detected: 207460 207646 207122 206456 206266 206168 206776 206993 207058 206991 206826 207288 207128 207510 207918 207879
[10:54:43.659] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.3 99.2 99.2 99.5 99.6 99.6 99.6 99.5 99.7 99.6 99.8 100.0 99.9
[10:54:43.659] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.6 99.3 99.2 99.1 99.4 99.5 99.5 99.5 99.4 99.7 99.6 99.8 100.0 99.9
[10:54:43.659] INFO: X-ray hit rate [MHz/cm2]: 61.0 42.9 63.0 98.2 105.6 106.3 108.3 77.5 83.5 90.0 89.2 72.9 77.7 46.2 18.8 24.9
[10:54:43.660] INFO: PixTestHighRate::doXPixelAlive() done
[10:54:43.707] INFO: PixTest:: pg_setup set to default.
[10:54:43.728] INFO: enter test to run
[10:54:47.909] INFO: test: exit no parameter change
[10:54:48.224] QUIET: Connection to board 33 closed.
[10:54:48.226] INFO: pXar: this is the end, my friend