[14:20:40.582] INFO: *** Welcome to pxar ***
[14:20:40.582] INFO: *** Today: 2016/08/29
[14:20:40.605] INFO: *** Version: v1.9.0-818-g96727
[14:20:40.605] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//dacParameters35_C15.dat
[14:20:40.623] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:20:40.623] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//defaultMaskFile.dat
[14:20:40.629] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C15.dat
[14:20:40.745] INFO: clk: 4
[14:20:40.745] INFO: ctr: 4
[14:20:40.745] INFO: sda: 19
[14:20:40.745] INFO: tin: 9
[14:20:40.745] INFO: level: 15
[14:20:40.745] INFO: triggerdelay: 0
[14:20:40.745] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:20:40.745] INFO: Log level: INFO
[14:20:40.761] QUIET: Connection to board DTB_WREKRL opened.
[14:20:40.764] 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:
------------------------------------------------------
[14:20:40.767] INFO: RPC call hashes of host and DTB match: 398089610
[14:20:42.295] INFO: DUT info:
[14:20:42.296] INFO: The DUT currently contains the following objects:
[14:20:42.296] INFO: 2 TBM Cores tbm08c (2 ON)
[14:20:42.296] INFO: TBM Core alpha (0): 7 registers set
[14:20:42.296] INFO: TBM Core beta (1): 7 registers set
[14:20:42.296] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:20:42.296] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.296] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:20:42.699] INFO: enter 'restricted' command line mode
[14:20:42.699] INFO: enter test to run
[14:21:13.280] INFO: test: PixelAlive no parameter change
[14:21:13.280] INFO: running: pixelalive
[14:21:13.289] INFO: ----------------------------------------------------------------------
[14:21:13.289] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:21:13.290] INFO: ----------------------------------------------------------------------
[14:21:13.610] INFO: Expecting 41600 events.
[14:21:18.033] INFO: 41600 events read in total (3705ms).
[14:21:18.194] INFO: Test took 4902ms.
[14:21:18.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:18.478] INFO: PixTestAlive::aliveTest() done
[14:21:18.478] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[14:21:18.508] INFO: enter test to run
[14:21:49.280] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:21:49.280] INFO: running: highrate
[14:21:49.280] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:21:49.485] INFO: ----------------------------------------------------------------------
[14:21:49.485] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:21:49.485] INFO: ----------------------------------------------------------------------
[14:21:49.485] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:21:49.485] INFO: edge/corner pixel THR is adjusted
[14:21:49.485] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:21:50.443] INFO: Collecting data for 5 seconds...
[14:21:55.462] INFO: Done with hot pixel readout
[14:22:07.335] INFO: PixTest:: pg_setup set to default.
[14:22:07.336] INFO: 3 hot pixels found in step 0
[14:22:08.346] INFO: Collecting data for 5 seconds...
[14:22:13.368] INFO: Done with hot pixel readout
[14:22:25.133] INFO: PixTest:: pg_setup set to default.
[14:22:25.134] INFO: 0 hot pixels found in step 1
[14:22:25.168] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:22:25.171] INFO: PixTest::trimHotPixels() done
[14:22:25.171] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C0.dat
[14:22:25.201] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C1.dat
[14:22:25.207] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C2.dat
[14:22:25.243] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C3.dat
[14:22:25.251] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C4.dat
[14:22:25.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C5.dat
[14:22:25.263] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C6.dat
[14:22:25.268] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C7.dat
[14:22:25.273] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C8.dat
[14:22:25.279] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C9.dat
[14:22:25.285] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C10.dat
[14:22:25.290] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C11.dat
[14:22:25.295] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C12.dat
[14:22:25.301] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C13.dat
[14:22:25.306] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C14.dat
[14:22:25.312] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C15.dat
[14:22:25.318] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//defaultMaskFile.dat
[14:22:25.333] INFO: enter test to run
[14:23:01.751] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:23:01.751] INFO: running: highrate
[14:23:01.756] INFO: ----------------------------------------------------------------------
[14:23:01.756] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:23:01.756] INFO: ----------------------------------------------------------------------
[14:23:01.756] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:23:01.756] INFO: edge/corner pixel THR is adjusted
[14:23:01.756] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:23:02.717] INFO: Collecting data for 1 seconds...
[14:23:03.722] INFO: Done with hot pixel readout
[14:23:07.823] INFO: PixTest:: pg_setup set to default.
[14:23:07.824] INFO: 0 hot pixels found in step 0
[14:23:07.830] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:23:07.913] INFO: PixTest::trimHotPixels() done
[14:23:07.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C0.dat
[14:23:07.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C1.dat
[14:23:07.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C2.dat
[14:23:07.934] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C3.dat
[14:23:07.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C4.dat
[14:23:07.945] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C5.dat
[14:23:07.951] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C6.dat
[14:23:07.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C7.dat
[14:23:07.961] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C8.dat
[14:23:07.967] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C9.dat
[14:23:07.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C10.dat
[14:23:07.978] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C11.dat
[14:23:07.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C12.dat
[14:23:07.989] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C13.dat
[14:23:07.994] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C14.dat
[14:23:07.999] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//trimParameters35_C15.dat
[14:23:07.004] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-16_FPIXTest-17C-FNAL-160816-1258-300V_2016-08-16_12h58m_1471370324/000_FPIXTest_p17//defaultMaskFile.dat
[14:23:08.017] INFO: enter test to run
[14:23:45.071] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:23:45.071] INFO: running: xray
[14:23:45.072] INFO: ----------------------------------------------------------------------
[14:23:45.072] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:23:45.072] INFO: ----------------------------------------------------------------------
[14:23:46.036] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:23:57.518] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:24:26.005] INFO: Resuming triggers.
[14:24:38.493] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:25:07.812] INFO: Resuming triggers.
[14:25:19.295] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:25:48.820] INFO: Resuming triggers.
[14:26:00.306] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:26:29.745] INFO: Resuming triggers.
[14:26:41.233] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:27:10.053] INFO: Resuming triggers.
[14:27:21.537] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[14:27:50.988] INFO: Resuming triggers.
[14:28:02.476] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:28:31.841] INFO: Resuming triggers.
[14:28:43.330] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:29:12.774] INFO: Resuming triggers.
[14:29:21.211] INFO: data taking finished, elapsed time: 100 seconds.
[14:29:42.969] INFO: PixTest:: pg_setup set to default.
[14:29:42.972] INFO: PixTestXray::doPhRun() done
[14:29:43.143] INFO: enter test to run
[14:30:38.156] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:30:38.156] INFO: running: xray
[14:30:38.157] INFO: ----------------------------------------------------------------------
[14:30:38.157] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:30:38.158] INFO: ----------------------------------------------------------------------
[14:30:39.121] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:30:45.794] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:31:15.940] INFO: Resuming triggers.
[14:31:22.614] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:31:53.456] INFO: Resuming triggers.
[14:32:00.131] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[14:32:30.873] INFO: Resuming triggers.
[14:32:37.546] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[14:33:08.122] INFO: Resuming triggers.
[14:33:14.799] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:33:45.024] INFO: Resuming triggers.
[14:33:51.695] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[14:34:23.356] INFO: Resuming triggers.
[14:34:30.034] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:35:00.995] INFO: Resuming triggers.
[14:35:07.669] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[14:35:38.180] INFO: Resuming triggers.
[14:35:44.856] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:36:15.090] INFO: Resuming triggers.
[14:36:21.766] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[14:36:51.932] INFO: Resuming triggers.
[14:36:58.611] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[14:37:28.937] INFO: Resuming triggers.
[14:37:35.614] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[14:38:05.677] INFO: Resuming triggers.
[14:38:12.353] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[14:38:42.459] INFO: Resuming triggers.
[14:38:49.135] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:39:19.227] INFO: Resuming triggers.
[14:39:25.910] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:39:56.072] INFO: Resuming triggers.
[14:39:56.282] INFO: data taking finished, elapsed time: 100 seconds.
[14:39:57.615] INFO: PixTest:: pg_setup set to default.
[14:39:57.619] INFO: PixTestXray::doPhRun() done
[14:39:57.765] INFO: enter test to run
[14:40:09.976] INFO: test: HighRate no parameter change
[14:40:09.977] INFO: running: highrate
[14:40:09.995] INFO: ----------------------------------------------------------------------
[14:40:09.995] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:40:09.995] INFO: ----------------------------------------------------------------------
[14:40:10.142] INFO: Expecting 768 events.
[14:40:11.275] INFO: 768 events read in total (418ms).
[14:40:11.276] INFO: Test took 1269ms.
[14:40:12.079] INFO: Expecting 41600 events.
[14:40:15.153] INFO: 41600 events read in total (2548ms).
[14:40:15.154] INFO: Test took 3870ms.
[14:40:15.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:15.918] INFO: Expecting 41600 events.
[14:40:19.104] INFO: 41600 events read in total (2659ms).
[14:40:19.105] INFO: Test took 3901ms.
[14:40:19.136] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:19.866] INFO: Expecting 41600 events.
[14:40:23.075] INFO: 41600 events read in total (2682ms).
[14:40:23.076] INFO: Test took 3922ms.
[14:40:23.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:23.837] INFO: Expecting 41600 events.
[14:40:27.062] INFO: 41600 events read in total (2698ms).
[14:40:27.063] INFO: Test took 3937ms.
[14:40:27.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:27.823] INFO: Expecting 41600 events.
[14:40:31.046] INFO: 41600 events read in total (2696ms).
[14:40:31.047] INFO: Test took 3933ms.
[14:40:31.079] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:31.809] INFO: Expecting 41600 events.
[14:40:35.024] INFO: 41600 events read in total (2688ms).
[14:40:35.025] INFO: Test took 3927ms.
[14:40:35.058] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:35.782] INFO: Expecting 41600 events.
[14:40:39.011] INFO: 41600 events read in total (2702ms).
[14:40:39.012] INFO: Test took 3936ms.
[14:40:39.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:39.772] INFO: Expecting 41600 events.
[14:40:42.981] INFO: 41600 events read in total (2682ms).
[14:40:42.982] INFO: Test took 3920ms.
[14:40:43.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:43.739] INFO: Expecting 41600 events.
[14:40:46.952] INFO: 41600 events read in total (2686ms).
[14:40:46.953] INFO: Test took 3921ms.
[14:40:46.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:47.709] INFO: Expecting 41600 events.
[14:40:50.926] INFO: 41600 events read in total (2690ms).
[14:40:50.927] INFO: Test took 3922ms.
[14:40:50.960] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:51.687] INFO: Expecting 41600 events.
[14:40:54.918] INFO: 41600 events read in total (2704ms).
[14:40:54.919] INFO: Test took 3941ms.
[14:40:54.951] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:55.673] INFO: Expecting 41600 events.
[14:40:58.895] INFO: 41600 events read in total (2696ms).
[14:40:58.896] INFO: Test took 3927ms.
[14:40:58.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:59.658] INFO: Expecting 41600 events.
[14:41:02.889] INFO: 41600 events read in total (2704ms).
[14:41:02.890] INFO: Test took 3943ms.
[14:41:02.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:03.648] INFO: Expecting 41600 events.
[14:41:06.872] INFO: 41600 events read in total (2697ms).
[14:41:06.873] INFO: Test took 3932ms.
[14:41:06.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:07.629] INFO: Expecting 41600 events.
[14:41:10.892] INFO: 41600 events read in total (2736ms).
[14:41:10.893] INFO: Test took 3970ms.
[14:41:10.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:11.650] INFO: Expecting 41600 events.
[14:41:14.867] INFO: 41600 events read in total (2690ms).
[14:41:14.868] INFO: Test took 3924ms.
[14:41:14.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:15.628] INFO: Expecting 41600 events.
[14:41:18.851] INFO: 41600 events read in total (2696ms).
[14:41:18.852] INFO: Test took 3935ms.
[14:41:18.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:19.612] INFO: Expecting 41600 events.
[14:41:22.834] INFO: 41600 events read in total (2695ms).
[14:41:22.834] INFO: Test took 3932ms.
[14:41:22.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:23.593] INFO: Expecting 41600 events.
[14:41:26.795] INFO: 41600 events read in total (2675ms).
[14:41:26.796] INFO: Test took 3911ms.
[14:41:26.829] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:27.557] INFO: Expecting 41600 events.
[14:41:30.654] INFO: 41600 events read in total (2570ms).
[14:41:30.655] INFO: Test took 3808ms.
[14:41:30.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:31.046] INFO: enter test to run
[14:41:47.744] INFO: test: HighRate no parameter change
[14:41:47.744] INFO: running: highrate
[14:41:47.745] INFO: ----------------------------------------------------------------------
[14:41:47.745] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:41:47.745] INFO: ----------------------------------------------------------------------
[14:41:48.365] INFO: Expecting 208000 events.
[14:42:00.119] INFO: 208000 events read in total (11227ms).
[14:42:00.121] INFO: Test took 12368ms.
[14:42:00.256] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:00.505] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 24 0 0 0
[14:42:00.505] INFO: number of red-efficiency pixels: 56 55 75 141 119 138 149 92 74 122 122 84 212 62 16 42
[14:42:00.505] INFO: number of X-ray hits detected: 58067 40971 62304 98725 105015 109967 109043 75399 69377 91010 93031 76484 84564 51458 22369 27042
[14:42:00.505] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:42:00.505] INFO: number of Vcal hits detected: 207943 207945 207902 207856 207879 207861 207850 207908 207926 207877 207867 207867 205215 207937 207984 207958
[14:42:00.505] 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.2 100.0 100.0 100.0
[14:42:00.505] 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 99.9 99.9 98.7 100.0 100.0 100.0
[14:42:00.505] INFO: X-ray hit rate [MHz/cm2]: 17.0 12.0 18.3 28.9 30.8 32.2 32.0 22.1 20.3 26.7 27.3 22.4 24.8 15.1 6.6 7.9
[14:42:00.505] INFO: PixTestHighRate::doXPixelAlive() done
[14:42:00.555] INFO: PixTest:: pg_setup set to default.
[14:42:00.569] INFO: enter test to run
[14:42:12.536] INFO: test: HighRate no parameter change
[14:42:12.536] INFO: running: highrate
[14:42:12.537] INFO: ----------------------------------------------------------------------
[14:42:12.537] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:42:12.537] INFO: ----------------------------------------------------------------------
[14:42:13.157] INFO: Expecting 208000 events.
[14:42:26.701] INFO: 208000 events read in total (13018ms).
[14:42:26.706] INFO: Test took 14161ms.
[14:42:26.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:27.293] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 27 0 0 0
[14:42:27.293] INFO: number of red-efficiency pixels: 159 137 232 463 413 427 596 275 224 372 335 253 526 224 61 78
[14:42:27.293] INFO: number of X-ray hits detected: 125351 88596 133578 212701 227522 237976 234866 164346 151167 198900 201208 168228 181428 112044 49315 59086
[14:42:27.293] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:42:27.293] INFO: number of Vcal hits detected: 207836 207856 207726 207506 207558 207528 207350 207700 207768 207599 207630 207687 200607 207763 207938 207920
[14:42:27.293] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.8 99.9 97.0 99.9 100.0 100.0
[14:42:27.293] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.8 99.8 96.4 99.9 100.0 100.0
[14:42:27.293] INFO: X-ray hit rate [MHz/cm2]: 36.7 26.0 39.2 62.3 66.7 69.8 68.8 48.2 44.3 58.3 59.0 49.3 53.2 32.8 14.5 17.3
[14:42:27.293] INFO: PixTestHighRate::doXPixelAlive() done
[14:42:27.341] INFO: PixTest:: pg_setup set to default.
[14:42:27.355] INFO: enter test to run
[14:42:43.455] INFO: test: HighRate no parameter change
[14:42:43.455] INFO: running: highrate
[14:42:43.457] INFO: ----------------------------------------------------------------------
[14:42:43.457] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:42:43.457] INFO: ----------------------------------------------------------------------
[14:42:44.071] INFO: Expecting 208000 events.
[14:42:59.409] INFO: 208000 events read in total (14811ms).
[14:42:59.416] INFO: Test took 15949ms.
[14:42:59.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:00.201] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 3 0 0 0
[14:43:00.201] INFO: number of red-efficiency pixels: 350 258 496 1043 935 1011 1258 577 588 1022 774 541 929 498 94 162
[14:43:00.201] INFO: number of X-ray hits detected: 189467 134480 203789 322391 343442 358710 354513 248996 229880 299706 304773 253393 273247 168913 74664 89505
[14:43:00.201] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:43:00.201] INFO: number of Vcal hits detected: 207601 207708 207374 206682 206900 206710 206325 207308 207329 206769 207068 207346 200037 207396 207904 207827
[14:43:00.201] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.4 99.5 99.4 99.3 99.7 99.7 99.5 99.6 99.7 96.1 99.7 100.0 99.9
[14:43:00.201] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.5 99.4 99.2 99.7 99.7 99.4 99.6 99.7 96.2 99.7 100.0 99.9
[14:43:00.201] INFO: X-ray hit rate [MHz/cm2]: 55.5 39.4 59.7 94.5 100.7 105.1 103.9 73.0 67.4 87.8 89.3 74.3 80.1 49.5 21.9 26.2
[14:43:00.201] INFO: PixTestHighRate::doXPixelAlive() done
[14:43:00.256] INFO: PixTest:: pg_setup set to default.
[14:43:00.277] INFO: enter test to run
[14:43:02.743] INFO: test: exit no parameter change
[14:43:03.099] QUIET: Connection to board 33 closed.
[14:43:03.108] INFO: pXar: this is the end, my friend