[12:38:28.317] INFO: *** Welcome to pxar ***
[12:38:28.317] INFO: *** Today: 2016/09/06
[12:38:29.120] INFO: *** Version: v1.9.0-818-g96727
[12:38:29.121] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//dacParameters35_C15.dat
[12:38:29.188] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:38:29.188] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//defaultMaskFile.dat
[12:38:29.192] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C15.dat
[12:38:29.288] INFO: clk: 4
[12:38:29.288] INFO: ctr: 4
[12:38:29.288] INFO: sda: 19
[12:38:29.288] INFO: tin: 9
[12:38:29.288] INFO: level: 15
[12:38:29.288] INFO: triggerdelay: 0
[12:38:29.288] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:38:29.288] INFO: Log level: INFO
[12:38:29.308] QUIET: Connection to board DTB_WREKRL opened.
[12:38:29.311] 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:
------------------------------------------------------
[12:38:29.314] INFO: RPC call hashes of host and DTB match: 398089610
[12:38:30.859] INFO: DUT info:
[12:38:30.859] INFO: The DUT currently contains the following objects:
[12:38:30.859] INFO: 2 TBM Cores tbm08c (2 ON)
[12:38:30.859] INFO: TBM Core alpha (0): 7 registers set
[12:38:30.859] INFO: TBM Core beta (1): 7 registers set
[12:38:30.859] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:38:30.859] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.859] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.860] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.860] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.860] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.860] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:30.860] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:31.283] INFO: enter 'restricted' command line mode
[12:38:31.283] INFO: enter test to run
[12:38:46.191] INFO: test: PixelAlive no parameter change
[12:38:46.191] INFO: running: pixelalive
[12:38:46.252] INFO: ----------------------------------------------------------------------
[12:38:46.253] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:38:46.253] INFO: ----------------------------------------------------------------------
[12:38:46.571] INFO: Expecting 41600 events.
[12:38:50.910] INFO: 41600 events read in total (3620ms).
[12:38:51.080] INFO: Test took 4823ms.
[12:38:51.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:51.645] INFO: PixTestAlive::aliveTest() done
[12:38:51.645] INFO: number of dead pixels (per ROC): 5 0 0 0 0 0 0 1 0 0 2 0 0 0 0 0
[12:38:51.826] INFO: enter test to run
[12:39:29.615] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:39:29.615] INFO: running: highrate
[12:39:29.632] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:39:29.876] INFO: ----------------------------------------------------------------------
[12:39:29.876] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:39:29.876] INFO: ----------------------------------------------------------------------
[12:39:29.876] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:39:29.876] INFO: edge/corner pixel THR is adjusted
[12:39:29.876] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:39:30.836] INFO: Collecting data for 5 seconds...
[12:39:35.854] INFO: Done with hot pixel readout
[12:39:47.529] INFO: PixTest:: pg_setup set to default.
[12:39:47.530] INFO: 3 hot pixels found in step 0
[12:39:48.521] INFO: Collecting data for 5 seconds...
[12:39:53.538] INFO: Done with hot pixel readout
[12:40:05.138] INFO: PixTest:: pg_setup set to default.
[12:40:05.138] INFO: 4 hot pixels found in step 1
[12:40:06.131] INFO: Collecting data for 5 seconds...
[12:40:11.147] INFO: Done with hot pixel readout
[12:40:22.789] INFO: PixTest:: pg_setup set to default.
[12:40:22.790] INFO: 2 hot pixels found in step 2
[12:40:23.782] INFO: Collecting data for 5 seconds...
[12:40:28.798] INFO: Done with hot pixel readout
[12:40:40.415] INFO: PixTest:: pg_setup set to default.
[12:40:40.416] INFO: 1 hot pixels found in step 3
[12:40:41.407] INFO: Collecting data for 5 seconds...
[12:40:46.424] INFO: Done with hot pixel readout
[12:40:58.008] INFO: PixTest:: pg_setup set to default.
[12:40:58.009] INFO: 2 hot pixels found in step 4
[12:40:58.001] INFO: Collecting data for 5 seconds...
[12:41:04.017] INFO: Done with hot pixel readout
[12:41:15.601] INFO: PixTest:: pg_setup set to default.
[12:41:15.602] INFO: 6 hot pixels found in step 5
[12:41:16.594] INFO: Collecting data for 5 seconds...
[12:41:21.611] INFO: Done with hot pixel readout
[12:41:33.087] INFO: PixTest:: pg_setup set to default.
[12:41:33.088] INFO: 3 hot pixels found in step 6
[12:41:34.081] INFO: Collecting data for 5 seconds...
[12:41:39.098] INFO: Done with hot pixel readout
[12:41:50.606] INFO: PixTest:: pg_setup set to default.
[12:41:50.607] INFO: 3 hot pixels found in step 7
[12:41:51.599] INFO: Collecting data for 5 seconds...
[12:41:56.616] INFO: Done with hot pixel readout
[12:42:08.131] INFO: PixTest:: pg_setup set to default.
[12:42:08.132] INFO: 0 hot pixels found in step 8
[12:42:08.169] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:42:08.179] INFO: PixTest::trimHotPixels() done
[12:42:08.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C0.dat
[12:42:08.187] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C1.dat
[12:42:08.197] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C2.dat
[12:42:08.203] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C3.dat
[12:42:08.208] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C4.dat
[12:42:08.214] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C5.dat
[12:42:08.219] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C6.dat
[12:42:08.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C7.dat
[12:42:08.230] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C8.dat
[12:42:08.235] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C9.dat
[12:42:08.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C10.dat
[12:42:08.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C11.dat
[12:42:08.251] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C12.dat
[12:42:08.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C13.dat
[12:42:08.262] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C14.dat
[12:42:08.268] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C15.dat
[12:42:08.273] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//defaultMaskFile.dat
[12:42:08.283] INFO: enter test to run
[12:42:43.630] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:42:43.630] INFO: running: highrate
[12:42:43.634] INFO: ----------------------------------------------------------------------
[12:42:43.634] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:42:43.634] INFO: ----------------------------------------------------------------------
[12:42:43.634] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:42:43.634] INFO: edge/corner pixel THR is adjusted
[12:42:43.634] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:42:44.591] INFO: Collecting data for 1 seconds...
[12:42:45.595] INFO: Done with hot pixel readout
[12:42:49.553] INFO: PixTest:: pg_setup set to default.
[12:42:49.554] INFO: 0 hot pixels found in step 0
[12:42:49.559] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:42:49.645] INFO: PixTest::trimHotPixels() done
[12:42:49.646] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C0.dat
[12:42:49.661] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C1.dat
[12:42:49.670] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C2.dat
[12:42:49.679] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C3.dat
[12:42:49.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C4.dat
[12:42:49.697] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C5.dat
[12:42:49.706] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C6.dat
[12:42:49.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C7.dat
[12:42:49.724] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C8.dat
[12:42:49.733] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C9.dat
[12:42:49.740] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C10.dat
[12:42:49.749] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C11.dat
[12:42:49.758] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C12.dat
[12:42:49.766] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C13.dat
[12:42:49.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C14.dat
[12:42:49.784] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C15.dat
[12:42:49.793] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-26_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//defaultMaskFile.dat
[12:42:49.805] INFO: enter test to run
[12:43:12.053] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:43:12.054] INFO: running: xray
[12:43:12.055] INFO: ----------------------------------------------------------------------
[12:43:12.055] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:43:12.055] INFO: ----------------------------------------------------------------------
[12:43:13.038] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:43:24.624] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:43:53.488] INFO: Resuming triggers.
[12:44:05.081] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:44:34.871] INFO: Resuming triggers.
[12:44:46.458] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:45:16.673] INFO: Resuming triggers.
[12:45:28.265] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:45:58.502] INFO: Resuming triggers.
[12:46:10.092] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:46:40.297] INFO: Resuming triggers.
[12:46:51.889] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:47:21.989] INFO: Resuming triggers.
[12:47:33.582] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:48:02.890] INFO: Resuming triggers.
[12:48:14.479] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[12:48:43.760] INFO: Resuming triggers.
[12:48:51.408] INFO: data taking finished, elapsed time: 100 seconds.
[12:49:11.035] INFO: PixTest:: pg_setup set to default.
[12:49:11.038] INFO: PixTestXray::doPhRun() done
[12:49:11.260] INFO: enter test to run
[12:50:03.339] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:50:03.339] INFO: running: xray
[12:50:03.340] INFO: ----------------------------------------------------------------------
[12:50:03.340] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:50:03.340] INFO: ----------------------------------------------------------------------
[12:50:04.304] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:50:11.118] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:50:41.164] INFO: Resuming triggers.
[12:50:47.977] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[12:51:18.027] INFO: Resuming triggers.
[12:51:24.843] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:51:54.711] INFO: Resuming triggers.
[12:52:01.529] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:52:31.498] INFO: Resuming triggers.
[12:52:38.314] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[12:53:08.453] INFO: Resuming triggers.
[12:53:15.269] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[12:53:45.300] INFO: Resuming triggers.
[12:53:52.116] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:54:22.054] INFO: Resuming triggers.
[12:54:28.870] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[12:54:59.375] INFO: Resuming triggers.
[12:55:06.193] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[12:55:36.350] INFO: Resuming triggers.
[12:55:43.170] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[12:56:13.354] INFO: Resuming triggers.
[12:56:20.175] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[12:56:50.879] INFO: Resuming triggers.
[12:56:57.697] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:57:28.577] INFO: Resuming triggers.
[12:57:35.398] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[12:58:06.331] INFO: Resuming triggers.
[12:58:13.154] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[12:58:44.226] INFO: Resuming triggers.
[12:58:49.136] INFO: data taking finished, elapsed time: 100 seconds.
[12:59:11.838] INFO: PixTest:: pg_setup set to default.
[12:59:11.841] INFO: PixTestXray::doPhRun() done
[12:59:11.995] INFO: enter test to run
[12:59:39.711] INFO: test: HighRate no parameter change
[12:59:39.711] INFO: running: highrate
[12:59:39.730] INFO: ----------------------------------------------------------------------
[12:59:39.730] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:59:39.730] INFO: ----------------------------------------------------------------------
[12:59:39.885] INFO: Expecting 768 events.
[12:59:41.019] INFO: 768 events read in total (418ms).
[12:59:41.019] INFO: Test took 1268ms.
[12:59:41.822] INFO: Expecting 41600 events.
[12:59:44.861] INFO: 41600 events read in total (2512ms).
[12:59:44.863] INFO: Test took 3809ms.
[12:59:44.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:45.622] INFO: Expecting 41600 events.
[12:59:48.801] INFO: 41600 events read in total (2653ms).
[12:59:48.802] INFO: Test took 3891ms.
[12:59:48.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:49.560] INFO: Expecting 41600 events.
[12:59:52.786] INFO: 41600 events read in total (2699ms).
[12:59:52.787] INFO: Test took 3934ms.
[12:59:52.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:53.544] INFO: Expecting 41600 events.
[12:59:56.757] INFO: 41600 events read in total (2686ms).
[12:59:56.758] INFO: Test took 3920ms.
[12:59:56.791] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:57.513] INFO: Expecting 41600 events.
[13:00:00.749] INFO: 41600 events read in total (2709ms).
[13:00:00.750] INFO: Test took 3942ms.
[13:00:00.784] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:01.505] INFO: Expecting 41600 events.
[13:00:04.728] INFO: 41600 events read in total (2696ms).
[13:00:04.729] INFO: Test took 3927ms.
[13:00:04.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:05.489] INFO: Expecting 41600 events.
[13:00:08.715] INFO: 41600 events read in total (2700ms).
[13:00:08.716] INFO: Test took 3936ms.
[13:00:08.749] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:09.477] INFO: Expecting 41600 events.
[13:00:12.695] INFO: 41600 events read in total (2692ms).
[13:00:12.696] INFO: Test took 3930ms.
[13:00:12.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:13.454] INFO: Expecting 41600 events.
[13:00:16.680] INFO: 41600 events read in total (2699ms).
[13:00:16.681] INFO: Test took 3934ms.
[13:00:16.715] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:17.439] INFO: Expecting 41600 events.
[13:00:20.677] INFO: 41600 events read in total (2711ms).
[13:00:20.678] INFO: Test took 3946ms.
[13:00:20.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:21.438] INFO: Expecting 41600 events.
[13:00:24.660] INFO: 41600 events read in total (2695ms).
[13:00:24.661] INFO: Test took 3933ms.
[13:00:24.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:25.420] INFO: Expecting 41600 events.
[13:00:28.654] INFO: 41600 events read in total (2707ms).
[13:00:28.655] INFO: Test took 3942ms.
[13:00:28.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:29.410] INFO: Expecting 41600 events.
[13:00:32.650] INFO: 41600 events read in total (2714ms).
[13:00:32.651] INFO: Test took 3943ms.
[13:00:32.684] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:33.407] INFO: Expecting 41600 events.
[13:00:36.635] INFO: 41600 events read in total (2701ms).
[13:00:36.636] INFO: Test took 3936ms.
[13:00:36.670] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:37.395] INFO: Expecting 41600 events.
[13:00:40.619] INFO: 41600 events read in total (2697ms).
[13:00:40.620] INFO: Test took 3931ms.
[13:00:40.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:41.376] INFO: Expecting 41600 events.
[13:00:44.615] INFO: 41600 events read in total (2712ms).
[13:00:44.616] INFO: Test took 3945ms.
[13:00:44.649] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:45.374] INFO: Expecting 41600 events.
[13:00:48.609] INFO: 41600 events read in total (2709ms).
[13:00:48.610] INFO: Test took 3942ms.
[13:00:48.643] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:49.365] INFO: Expecting 41600 events.
[13:00:52.575] INFO: 41600 events read in total (2683ms).
[13:00:52.576] INFO: Test took 3915ms.
[13:00:52.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:53.337] INFO: Expecting 41600 events.
[13:00:56.565] INFO: 41600 events read in total (2701ms).
[13:00:56.566] INFO: Test took 3939ms.
[13:00:56.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:57.322] INFO: Expecting 41600 events.
[13:01:00.481] INFO: 41600 events read in total (2632ms).
[13:01:00.482] INFO: Test took 3866ms.
[13:01:00.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:00.886] INFO: enter test to run
[13:01:07.822] INFO: test: HighRate no parameter change
[13:01:07.822] INFO: running: highrate
[13:01:07.823] INFO: ----------------------------------------------------------------------
[13:01:07.823] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:01:07.823] INFO: ----------------------------------------------------------------------
[13:01:08.435] INFO: Expecting 208000 events.
[13:01:20.233] INFO: 208000 events read in total (11271ms).
[13:01:20.236] INFO: Test took 12406ms.
[13:01:20.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:20.630] INFO: number of dead pixels (per ROC): 5 0 0 0 0 160 0 1 0 0 2 0 0 0 0 0
[13:01:20.630] INFO: number of red-efficiency pixels: 82 53 64 121 159 298 126 99 70 125 90 119 93 50 30 15
[13:01:20.630] INFO: number of X-ray hits detected: 69868 43377 67432 105956 108144 107964 108466 80524 73039 95800 95817 82239 83776 49870 20998 22840
[13:01:20.630] 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:01:20.630] INFO: number of Vcal hits detected: 207673 207947 207935 207877 207837 199859 207869 207850 207928 207872 207811 207880 207898 207947 207970 207985
[13:01:20.630] 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 99.9 100.0 100.0 100.0 100.0
[13:01:20.630] INFO: Vcal hit overall efficiency (%): 99.8 100.0 100.0 99.9 99.9 96.1 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[13:01:20.630] INFO: X-ray hit rate [MHz/cm2]: 20.5 12.7 19.8 31.1 31.7 31.6 31.8 23.6 21.4 28.1 28.1 24.1 24.6 14.6 6.2 6.7
[13:01:20.630] INFO: PixTestHighRate::doXPixelAlive() done
[13:01:20.680] INFO: PixTest:: pg_setup set to default.
[13:01:20.693] INFO: enter test to run
[13:01:46.030] INFO: test: HighRate no parameter change
[13:01:46.030] INFO: running: highrate
[13:01:46.031] INFO: ----------------------------------------------------------------------
[13:01:46.031] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:01:46.031] INFO: ----------------------------------------------------------------------
[13:01:46.650] INFO: Expecting 208000 events.
[13:02:00.172] INFO: 208000 events read in total (12995ms).
[13:02:00.177] INFO: Test took 14135ms.
[13:02:00.457] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:00.754] INFO: number of dead pixels (per ROC): 5 0 0 0 0 160 0 1 0 0 2 0 0 0 0 0
[13:02:00.754] INFO: number of red-efficiency pixels: 238 141 256 417 443 609 414 287 214 437 317 349 252 142 51 57
[13:02:00.754] INFO: number of X-ray hits detected: 143649 89256 140061 219147 224549 221257 223762 166877 151218 197928 197582 169935 172247 102084 43339 47075
[13:02:00.754] 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:02:00.754] INFO: number of Vcal hits detected: 207503 207851 207723 207550 207530 199514 207545 207641 207775 207531 207563 207625 207719 207852 207949 207943
[13:02:00.754] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[13:02:00.754] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.8 99.8 95.9 99.8 99.8 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[13:02:00.754] INFO: X-ray hit rate [MHz/cm2]: 42.1 26.2 41.1 64.2 65.8 64.9 65.6 48.9 44.3 58.0 57.9 49.8 50.5 29.9 12.7 13.8
[13:02:00.754] INFO: PixTestHighRate::doXPixelAlive() done
[13:02:00.806] INFO: PixTest:: pg_setup set to default.
[13:02:00.821] INFO: enter test to run
[13:02:41.862] INFO: test: HighRate no parameter change
[13:02:41.862] INFO: running: highrate
[13:02:41.863] INFO: ----------------------------------------------------------------------
[13:02:41.863] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:02:41.863] INFO: ----------------------------------------------------------------------
[13:02:42.478] INFO: Expecting 208000 events.
[13:02:57.840] INFO: 208000 events read in total (14835ms).
[13:02:57.847] INFO: Test took 15974ms.
[13:02:58.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:58.609] INFO: number of dead pixels (per ROC): 5 0 0 0 0 160 0 1 0 0 2 0 0 0 0 0
[13:02:58.609] INFO: number of red-efficiency pixels: 491 271 573 859 1006 1196 979 600 419 983 702 751 547 339 80 81
[13:02:58.609] INFO: number of X-ray hits detected: 219166 136291 213143 332506 340541 335637 340842 253982 230112 302449 301140 260150 263713 155442 66519 71549
[13:02:58.609] 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:02:58.609] INFO: number of Vcal hits detected: 207177 207696 207289 206940 206764 198695 206817 207232 207532 206827 207092 207126 207368 207620 207917 207916
[13:02:58.609] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.4 99.5 99.7 99.8 99.5 99.6 99.6 99.7 99.8 100.0 100.0
[13:02:58.609] INFO: Vcal hit overall efficiency (%): 99.6 99.9 99.7 99.5 99.4 95.5 99.4 99.6 99.8 99.4 99.6 99.6 99.7 99.8 100.0 100.0
[13:02:58.609] INFO: X-ray hit rate [MHz/cm2]: 64.2 39.9 62.5 97.5 99.8 98.4 99.9 74.4 67.4 88.7 88.3 76.3 77.3 45.6 19.5 21.0
[13:02:58.609] INFO: PixTestHighRate::doXPixelAlive() done
[13:02:58.663] INFO: PixTest:: pg_setup set to default.
[13:02:58.676] INFO: enter test to run
[13:03:11.797] INFO: test: exit no parameter change
[13:03:12.165] QUIET: Connection to board 33 closed.
[13:03:12.176] INFO: pXar: this is the end, my friend