[11:17:46.653] INFO: *** Welcome to pxar ***
[11:17:46.653] INFO: *** Today: 2016/09/02
[11:17:47.472] INFO: *** Version: v1.9.0-818-g96727
[11:17:47.490] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//dacParameters35_C15.dat
[11:17:47.570] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:17:47.570] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//defaultMaskFile.dat
[11:17:47.576] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C15.dat
[11:17:47.705] INFO: clk: 4
[11:17:47.705] INFO: ctr: 4
[11:17:47.705] INFO: sda: 19
[11:17:47.705] INFO: tin: 9
[11:17:47.705] INFO: level: 15
[11:17:47.705] INFO: triggerdelay: 0
[11:17:47.706] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[11:17:47.706] INFO: Log level: INFO
[11:17:47.722] QUIET: Connection to board DTB_WREKRL opened.
[11:17:47.725] 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:17:47.728] INFO: RPC call hashes of host and DTB match: 398089610
[11:17:49.267] INFO: DUT info:
[11:17:49.267] INFO: The DUT currently contains the following objects:
[11:17:49.267] INFO: 2 TBM Cores tbm08c (2 ON)
[11:17:49.267] INFO: TBM Core alpha (0): 7 registers set
[11:17:49.267] INFO: TBM Core beta (1): 7 registers set
[11:17:49.267] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:17:49.268] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.268] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.269] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.269] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:49.700] INFO: enter 'restricted' command line mode
[11:17:49.700] INFO: enter test to run
[11:17:55.433] INFO: test: PixelAlive no parameter change
[11:17:55.433] INFO: running: pixelalive
[11:17:55.487] INFO: ----------------------------------------------------------------------
[11:17:55.487] 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:17:55.487] INFO: ----------------------------------------------------------------------
[11:17:55.808] INFO: Expecting 41600 events.
[11:18:00.231] INFO: 41600 events read in total (3704ms).
[11:18:00.393] INFO: Test took 4903ms.
[11:18:00.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:00.699] INFO: PixTestAlive::aliveTest() done
[11:18:00.699] INFO: number of dead pixels (per ROC): 0 2 0 0 0 0 0 0 0 0 0 0 0 0 2 0
[11:18:00.801] INFO: enter test to run
[11:18:58.576] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:18:58.577] INFO: running: highrate
[11:18:58.599] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:18:58.904] INFO: ----------------------------------------------------------------------
[11:18:58.904] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:18:58.904] INFO: ----------------------------------------------------------------------
[11:18:58.904] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:18:58.904] INFO: edge/corner pixel THR is adjusted
[11:18:58.904] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:18:59.862] INFO: Collecting data for 5 seconds...
[11:19:04.880] INFO: Done with hot pixel readout
[11:19:16.586] INFO: PixTest:: pg_setup set to default.

[11:19:16.587] INFO: 2 hot pixels found in step 0
[11:19:17.601] INFO: Collecting data for 5 seconds...
[11:19:22.618] INFO: Done with hot pixel readout
[11:19:34.227] INFO: PixTest:: pg_setup set to default.

[11:19:34.228] INFO: 3 hot pixels found in step 1
[11:19:35.223] INFO: Collecting data for 5 seconds...
[11:19:40.239] INFO: Done with hot pixel readout
[11:19:51.899] INFO: PixTest:: pg_setup set to default.

[11:19:51.900] INFO: 1 hot pixels found in step 2
[11:19:51.937] INFO: 1 hot pixels could not be trimmed and have been masked.
[11:19:51.956] INFO: PixTest::trimHotPixels() done
[11:19:51.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C0.dat
[11:19:51.962] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C1.dat
[11:19:51.968] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C2.dat
[11:19:51.973] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C3.dat
[11:19:51.978] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C4.dat
[11:19:51.984] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C5.dat
[11:19:51.990] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C6.dat
[11:19:52.009] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C7.dat
[11:19:52.014] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C8.dat
[11:19:52.020] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C9.dat
[11:19:52.026] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C10.dat
[11:19:52.031] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C11.dat
[11:19:52.037] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C12.dat
[11:19:52.042] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C13.dat
[11:19:52.047] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C14.dat
[11:19:52.054] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C15.dat
[11:19:52.059] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//defaultMaskFile.dat
[11:19:52.069] INFO: enter test to run
[11:20:26.496] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:20:26.496] INFO: running: highrate
[11:20:26.500] INFO: ----------------------------------------------------------------------
[11:20:26.500] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:20:26.500] INFO: ----------------------------------------------------------------------
[11:20:26.500] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:20:26.500] INFO: edge/corner pixel THR is adjusted
[11:20:26.500] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:20:27.458] INFO: Collecting data for 1 seconds...
[11:20:28.462] INFO: Done with hot pixel readout
[11:20:32.365] INFO: PixTest:: pg_setup set to default.

[11:20:32.365] INFO: 1 hot pixels found in step 0
[11:20:32.371] INFO: 1 hot pixels could not be trimmed and have been masked.
[11:20:32.466] INFO: PixTest::trimHotPixels() done
[11:20:32.467] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C0.dat
[11:20:32.474] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C1.dat
[11:20:32.480] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C2.dat
[11:20:32.485] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C3.dat
[11:20:32.490] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C4.dat
[11:20:32.496] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C5.dat
[11:20:32.501] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C6.dat
[11:20:32.506] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C7.dat
[11:20:32.512] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C8.dat
[11:20:32.517] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C9.dat
[11:20:32.522] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C10.dat
[11:20:32.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C11.dat
[11:20:32.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C12.dat
[11:20:32.538] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C13.dat
[11:20:32.544] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C14.dat
[11:20:32.549] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C15.dat
[11:20:32.554] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-06_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//defaultMaskFile.dat
[11:20:32.564] INFO: enter test to run
[11:21:38.680] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:21:38.680] INFO: running: xray
[11:21:38.681] INFO: ----------------------------------------------------------------------
[11:21:38.681] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:21:38.681] INFO: ----------------------------------------------------------------------
[11:21:39.658] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:21:51.273] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:22:20.697] INFO: Resuming triggers.
[11:22:32.309] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:23:01.727] INFO: Resuming triggers.
[11:23:13.344] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:23:42.750] INFO: Resuming triggers.
[11:23:54.366] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:24:23.656] INFO: Resuming triggers.
[11:24:35.271] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[11:25:04.555] INFO: Resuming triggers.
[11:25:16.171] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[11:25:45.545] INFO: Resuming triggers.
[11:25:57.165] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:26:26.327] INFO: Resuming triggers.
[11:26:37.949] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[11:27:07.559] INFO: Resuming triggers.
[11:27:14.950] INFO: data taking finished, elapsed time: 100 seconds.
[11:27:33.969] INFO: PixTest:: pg_setup set to default.
[11:27:33.972] INFO: PixTestXray::doPhRun() done
[11:27:34.162] INFO: enter test to run
[11:28:36.629] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:28:36.629] INFO: running: xray
[11:28:36.630] INFO: ----------------------------------------------------------------------
[11:28:36.630] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:28:36.630] INFO: ----------------------------------------------------------------------
[11:28:37.597] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:28:44.528] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:29:15.135] INFO: Resuming triggers.
[11:29:22.066] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:29:53.093] INFO: Resuming triggers.
[11:30:00.024] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:30:31.551] INFO: Resuming triggers.
[11:30:38.481] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:31:09.561] INFO: Resuming triggers.
[11:31:16.492] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:31:47.757] INFO: Resuming triggers.
[11:31:54.688] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[11:32:25.059] INFO: Resuming triggers.
[11:32:31.991] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[11:33:02.224] INFO: Resuming triggers.
[11:33:09.160] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[11:33:39.361] INFO: Resuming triggers.
[11:33:46.294] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[11:34:16.466] INFO: Resuming triggers.
[11:34:23.397] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[11:34:53.547] INFO: Resuming triggers.
[11:35:00.482] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[11:35:30.688] INFO: Resuming triggers.
[11:35:37.625] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[11:36:07.865] INFO: Resuming triggers.
[11:36:14.803] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[11:36:45.050] INFO: Resuming triggers.
[11:36:51.989] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[11:37:22.164] INFO: Resuming triggers.
[11:37:25.443] INFO: data taking finished, elapsed time: 100 seconds.
[11:37:40.010] INFO: PixTest:: pg_setup set to default.
[11:37:40.013] INFO: PixTestXray::doPhRun() done
[11:37:40.165] INFO: enter test to run
[11:38:27.033] INFO: test: HighRate no parameter change
[11:38:27.033] INFO: running: highrate
[11:38:27.048] INFO: ----------------------------------------------------------------------
[11:38:27.048] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:38:27.048] INFO: ----------------------------------------------------------------------
[11:38:27.203] INFO: Expecting 768 events.
[11:38:28.337] INFO: 768 events read in total (419ms).
[11:38:28.338] INFO: Test took 1269ms.
[11:38:29.141] INFO: Expecting 41600 events.
[11:38:32.213] INFO: 41600 events read in total (2545ms).
[11:38:32.214] INFO: Test took 3850ms.
[11:38:32.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:32.973] INFO: Expecting 41600 events.
[11:38:36.153] INFO: 41600 events read in total (2653ms).
[11:38:36.154] INFO: Test took 3891ms.
[11:38:36.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:36.911] INFO: Expecting 41600 events.
[11:38:40.116] INFO: 41600 events read in total (2678ms).
[11:38:40.117] INFO: Test took 3914ms.
[11:38:40.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:40.877] INFO: Expecting 41600 events.
[11:38:44.101] INFO: 41600 events read in total (2697ms).
[11:38:44.102] INFO: Test took 3933ms.
[11:38:44.135] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:44.862] INFO: Expecting 41600 events.
[11:38:48.081] INFO: 41600 events read in total (2692ms).
[11:38:48.082] INFO: Test took 3929ms.
[11:38:48.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:48.838] INFO: Expecting 41600 events.
[11:38:52.080] INFO: 41600 events read in total (2715ms).
[11:38:52.081] INFO: Test took 3949ms.
[11:38:52.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:52.841] INFO: Expecting 41600 events.
[11:38:56.073] INFO: 41600 events read in total (2706ms).
[11:38:56.074] INFO: Test took 3944ms.
[11:38:56.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:56.831] INFO: Expecting 41600 events.
[11:39:00.049] INFO: 41600 events read in total (2691ms).
[11:39:00.050] INFO: Test took 3924ms.
[11:39:00.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:00.809] INFO: Expecting 41600 events.
[11:39:04.031] INFO: 41600 events read in total (2695ms).
[11:39:04.032] INFO: Test took 3931ms.
[11:39:04.065] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:04.791] INFO: Expecting 41600 events.
[11:39:08.026] INFO: 41600 events read in total (2708ms).
[11:39:08.027] INFO: Test took 3943ms.
[11:39:08.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:08.785] INFO: Expecting 41600 events.
[11:39:12.009] INFO: 41600 events read in total (2697ms).
[11:39:12.010] INFO: Test took 3932ms.
[11:39:12.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:12.766] INFO: Expecting 41600 events.
[11:39:15.985] INFO: 41600 events read in total (2692ms).
[11:39:15.986] INFO: Test took 3926ms.
[11:39:16.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:16.747] INFO: Expecting 41600 events.
[11:39:19.980] INFO: 41600 events read in total (2706ms).
[11:39:19.981] INFO: Test took 3945ms.
[11:39:20.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:20.742] INFO: Expecting 41600 events.
[11:39:23.977] INFO: 41600 events read in total (2708ms).
[11:39:23.978] INFO: Test took 3946ms.
[11:39:24.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:24.736] INFO: Expecting 41600 events.
[11:39:27.962] INFO: 41600 events read in total (2699ms).
[11:39:27.963] INFO: Test took 3933ms.
[11:39:27.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:28.725] INFO: Expecting 41600 events.
[11:39:31.953] INFO: 41600 events read in total (2701ms).
[11:39:31.954] INFO: Test took 3940ms.
[11:39:31.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:32.710] INFO: Expecting 41600 events.
[11:39:35.938] INFO: 41600 events read in total (2701ms).
[11:39:35.939] INFO: Test took 3935ms.
[11:39:35.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:36.696] INFO: Expecting 41600 events.
[11:39:39.920] INFO: 41600 events read in total (2698ms).
[11:39:39.921] INFO: Test took 3930ms.
[11:39:39.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:40.677] INFO: Expecting 41600 events.
[11:39:43.894] INFO: 41600 events read in total (2690ms).
[11:39:43.895] INFO: Test took 3924ms.
[11:39:43.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:44.650] INFO: Expecting 41600 events.
[11:39:47.756] INFO: 41600 events read in total (2579ms).
[11:39:47.757] INFO: Test took 3810ms.
[11:39:47.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:48.154] INFO: enter test to run
[11:40:29.112] INFO: test: HighRate no parameter change
[11:40:29.112] INFO: running: highrate
[11:40:29.113] INFO: ----------------------------------------------------------------------
[11:40:29.113] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:40:29.113] INFO: ----------------------------------------------------------------------
[11:40:29.735] INFO: Expecting 208000 events.
[11:40:41.525] INFO: 208000 events read in total (11263ms).
[11:40:41.528] INFO: Test took 12406ms.
[11:40:41.673] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:41.925] INFO: number of dead pixels (per ROC): 0 9 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[11:40:41.925] INFO: number of red-efficiency pixels: 77 51 78 113 121 129 124 86 84 131 145 100 96 59 193 34
[11:40:41.925] INFO: number of X-ray hits detected: 56724 36509 63418 99437 99397 107604 109724 80605 80051 102485 100482 82731 86804 52198 22949 25946
[11:40:41.925] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:40:41.925] INFO: number of Vcal hits detected: 207922 207480 207922 207886 207875 207870 207870 207913 207915 207866 207854 207899 207903 207930 203276 207966
[11:40:41.925] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 97.7 100.0
[11:40:41.925] INFO: Vcal hit overall efficiency (%): 100.0 99.8 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 97.7 100.0
[11:40:41.925] INFO: X-ray hit rate [MHz/cm2]: 16.6 10.7 18.6 29.1 29.1 31.5 32.2 23.6 23.5 30.0 29.5 24.2 25.4 15.3 6.7 7.6
[11:40:41.925] INFO: PixTestHighRate::doXPixelAlive() done
[11:40:41.971] INFO: PixTest:: pg_setup set to default.
[11:40:41.980] INFO: enter test to run
[11:41:07.591] INFO: test: HighRate no parameter change
[11:41:07.591] INFO: running: highrate
[11:41:07.593] INFO: ----------------------------------------------------------------------
[11:41:07.593] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:41:07.593] INFO: ----------------------------------------------------------------------
[11:41:08.217] INFO: Expecting 208000 events.
[11:41:21.552] INFO: 208000 events read in total (12808ms).
[11:41:21.557] INFO: Test took 13955ms.
[11:41:21.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:22.152] INFO: number of dead pixels (per ROC): 0 9 0 0 0 0 0 0 0 0 0 33 0 0 1 0
[11:41:22.152] INFO: number of red-efficiency pixels: 183 130 233 404 426 438 452 323 198 433 447 359 284 162 229 77
[11:41:22.152] INFO: number of X-ray hits detected: 114557 73502 127526 199903 199421 215565 222213 161863 161080 204413 202128 167841 175472 104816 43465 52608
[11:41:22.152] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:41:22.152] INFO: number of Vcal hits detected: 207804 207385 207747 207569 207539 207510 207507 207642 207795 207528 207522 205589 207699 207827 202191 207921
[11:41:22.152] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.6 99.9 99.9 97.1 100.0
[11:41:22.152] INFO: Vcal hit overall efficiency (%): 99.9 99.7 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 98.8 99.9 99.9 97.2 100.0
[11:41:22.152] INFO: X-ray hit rate [MHz/cm2]: 33.6 21.5 37.4 58.6 58.5 63.2 65.1 47.4 47.2 59.9 59.2 49.2 51.4 30.7 12.7 15.4
[11:41:22.152] INFO: PixTestHighRate::doXPixelAlive() done
[11:41:22.197] INFO: PixTest:: pg_setup set to default.
[11:41:22.208] INFO: enter test to run
[11:42:04.431] INFO: test: HighRate no parameter change
[11:42:04.431] INFO: running: highrate
[11:42:04.432] INFO: ----------------------------------------------------------------------
[11:42:04.432] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:42:04.433] INFO: ----------------------------------------------------------------------
[11:42:05.053] INFO: Expecting 208000 events.
[11:42:20.561] INFO: 208000 events read in total (14981ms).
[11:42:20.568] INFO: Test took 16127ms.
[11:42:20.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:21.344] INFO: number of dead pixels (per ROC): 0 9 0 0 0 0 0 0 0 0 0 61 0 0 1 0
[11:42:21.344] INFO: number of red-efficiency pixels: 389 218 574 877 949 1079 993 757 481 1067 974 846 668 346 265 119
[11:42:21.344] INFO: number of X-ray hits detected: 173648 111411 193069 303227 300968 327419 336032 245030 245359 310502 305389 253360 265530 159158 63110 79609
[11:42:21.344] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:42:21.344] INFO: number of Vcal hits detected: 207559 207307 207279 206961 206840 206639 206748 207047 207453 206713 206810 202030 207237 207599 201518 207875
[11:42:21.344] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.4 99.5 99.6 99.8 99.4 99.5 98.6 99.7 99.8 96.8 99.9
[11:42:21.344] INFO: Vcal hit overall efficiency (%): 99.8 99.7 99.7 99.5 99.4 99.3 99.4 99.5 99.7 99.4 99.4 97.1 99.6 99.8 96.9 99.9
[11:42:21.344] INFO: X-ray hit rate [MHz/cm2]: 50.9 32.7 56.6 88.9 88.2 96.0 98.5 71.8 71.9 91.0 89.5 74.3 77.8 46.7 18.5 23.3
[11:42:21.344] INFO: PixTestHighRate::doXPixelAlive() done
[11:42:21.394] INFO: PixTest:: pg_setup set to default.
[11:42:21.406] INFO: enter test to run
[11:43:05.231] INFO: test: exit no parameter change
[11:43:05.667] QUIET: Connection to board 33 closed.
[11:43:05.676] INFO: pXar: this is the end, my friend