[13:52:41.471] INFO: *** Welcome to pxar ***
[13:52:41.471] INFO: *** Today: 2016/08/30
[13:52:42.239] INFO: *** Version: v1.9.0-818-g96727
[13:52:42.239] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//dacParameters35_C15.dat
[13:52:42.279] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:52:42.293] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//defaultMaskFile.dat
[13:52:42.313] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C15.dat
[13:52:42.483] INFO: clk: 4
[13:52:42.483] INFO: ctr: 4
[13:52:42.483] INFO: sda: 19
[13:52:42.483] INFO: tin: 9
[13:52:42.483] INFO: level: 15
[13:52:42.483] INFO: triggerdelay: 0
[13:52:42.483] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:52:42.483] INFO: Log level: INFO
[13:52:42.499] QUIET: Connection to board DTB_WREKRL opened.
[13:52:42.503] 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:
------------------------------------------------------
[13:52:42.505] INFO: RPC call hashes of host and DTB match: 398089610
[13:52:44.041] INFO: DUT info:
[13:52:44.041] INFO: The DUT currently contains the following objects:
[13:52:44.041] INFO: 2 TBM Cores tbm08c (2 ON)
[13:52:44.041] INFO: TBM Core alpha (0): 7 registers set
[13:52:44.041] INFO: TBM Core beta (1): 7 registers set
[13:52:44.041] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:52:44.041] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.041] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.041] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.041] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.041] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.041] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.041] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.041] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.042] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.042] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.042] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.042] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.042] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.042] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.042] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.042] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:44.445] INFO: enter 'restricted' command line mode
[13:52:44.445] INFO: enter test to run
[13:52:46.991] INFO: test: PixelAlive no parameter change
[13:52:46.991] INFO: running: pixelalive
[13:52:47.077] INFO: ----------------------------------------------------------------------
[13:52:47.077] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:52:47.077] INFO: ----------------------------------------------------------------------
[13:52:47.416] INFO: Expecting 41600 events.
[13:52:51.701] INFO: 41600 events read in total (3567ms).
[13:52:51.866] INFO: Test took 4786ms.
[13:52:51.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:52.140] INFO: PixTestAlive::aliveTest() done
[13:52:52.140] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 1 0 0 0 0 0
[13:52:52.183] INFO: enter test to run
[13:53:39.311] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:53:39.311] INFO: running: highrate
[13:53:39.311] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:53:39.528] INFO: ----------------------------------------------------------------------
[13:53:39.528] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:53:39.528] INFO: ----------------------------------------------------------------------
[13:53:39.529] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:53:39.529] INFO: edge/corner pixel THR is adjusted
[13:53:39.529] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:53:40.487] INFO: Collecting data for 5 seconds...
[13:53:45.506] INFO: Done with hot pixel readout
[13:53:56.987] INFO: PixTest:: pg_setup set to default.
[13:53:56.988] INFO: 3 hot pixels found in step 0
[13:53:57.975] INFO: Collecting data for 5 seconds...
[13:54:02.994] INFO: Done with hot pixel readout
[13:54:14.622] INFO: PixTest:: pg_setup set to default.
[13:54:14.623] INFO: 3 hot pixels found in step 1
[13:54:15.615] INFO: Collecting data for 5 seconds...
[13:54:20.634] INFO: Done with hot pixel readout
[13:54:32.064] INFO: PixTest:: pg_setup set to default.
[13:54:32.065] INFO: 8 hot pixels found in step 2
[13:54:33.056] INFO: Collecting data for 5 seconds...
[13:54:38.074] INFO: Done with hot pixel readout
[13:54:49.691] INFO: PixTest:: pg_setup set to default.
[13:54:49.692] INFO: 0 hot pixels found in step 3
[13:54:49.728] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:54:49.732] INFO: PixTest::trimHotPixels() done
[13:54:49.732] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C0.dat
[13:54:49.738] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C1.dat
[13:54:49.744] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C2.dat
[13:54:49.749] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C3.dat
[13:54:49.755] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C4.dat
[13:54:49.761] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C5.dat
[13:54:49.766] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C6.dat
[13:54:49.772] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C7.dat
[13:54:49.777] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C8.dat
[13:54:49.783] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C9.dat
[13:54:49.789] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C10.dat
[13:54:49.794] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C11.dat
[13:54:49.800] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C12.dat
[13:54:49.805] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C13.dat
[13:54:49.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C14.dat
[13:54:49.817] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C15.dat
[13:54:49.822] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//defaultMaskFile.dat
[13:54:49.832] INFO: enter test to run
[13:56:11.894] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:56:11.894] INFO: running: highrate
[13:56:11.899] INFO: ----------------------------------------------------------------------
[13:56:11.899] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:56:11.899] INFO: ----------------------------------------------------------------------
[13:56:11.899] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:56:11.899] INFO: edge/corner pixel THR is adjusted
[13:56:11.899] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:56:12.856] INFO: Collecting data for 1 seconds...
[13:56:13.862] INFO: Done with hot pixel readout
[13:56:18.048] INFO: PixTest:: pg_setup set to default.
[13:56:18.049] INFO: 0 hot pixels found in step 0
[13:56:18.054] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:56:18.137] INFO: PixTest::trimHotPixels() done
[13:56:18.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C0.dat
[13:56:18.148] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C1.dat
[13:56:18.153] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C2.dat
[13:56:18.159] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C3.dat
[13:56:18.165] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C4.dat
[13:56:18.171] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C5.dat
[13:56:18.176] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C6.dat
[13:56:18.182] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C7.dat
[13:56:18.187] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C8.dat
[13:56:18.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C9.dat
[13:56:18.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C10.dat
[13:56:18.205] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C11.dat
[13:56:18.210] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C12.dat
[13:56:18.216] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C13.dat
[13:56:18.222] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C14.dat
[13:56:18.228] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//trimParameters35_C15.dat
[13:56:18.233] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-45_FPIXTest-17C-FNAL-160829-1249-300V_2016-08-29_12h49m_1472492982/000_FPIXTest_p17//defaultMaskFile.dat
[13:56:18.244] INFO: enter test to run
[13:57:27.205] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:57:27.206] INFO: running: xray
[13:57:27.207] INFO: ----------------------------------------------------------------------
[13:57:27.207] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:57:27.207] INFO: ----------------------------------------------------------------------
[13:57:28.184] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:57:39.469] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:58:08.853] INFO: Resuming triggers.
[13:58:20.144] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:58:49.682] INFO: Resuming triggers.
[13:59:00.971] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:59:30.461] INFO: Resuming triggers.
[13:59:41.748] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:00:11.170] INFO: Resuming triggers.
[14:00:22.460] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[14:00:51.957] INFO: Resuming triggers.
[14:01:03.250] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[14:01:32.673] INFO: Resuming triggers.
[14:01:43.967] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[14:02:12.421] INFO: Resuming triggers.
[14:02:23.716] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[14:02:52.974] INFO: Resuming triggers.
[14:03:02.988] INFO: data taking finished, elapsed time: 100 seconds.
[14:03:29.084] INFO: PixTest:: pg_setup set to default.
[14:03:29.087] INFO: PixTestXray::doPhRun() done
[14:03:29.316] INFO: enter test to run
[14:04:16.219] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:04:16.219] INFO: running: xray
[14:04:16.220] INFO: ----------------------------------------------------------------------
[14:04:16.220] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:04:16.220] INFO: ----------------------------------------------------------------------
[14:04:17.184] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:04:23.815] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:04:54.397] INFO: Resuming triggers.
[14:05:01.027] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:05:31.662] INFO: Resuming triggers.
[14:05:38.291] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[14:06:09.249] INFO: Resuming triggers.
[14:06:15.876] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[14:06:46.421] INFO: Resuming triggers.
[14:06:53.055] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:07:23.486] INFO: Resuming triggers.
[14:07:30.118] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[14:08:01.249] INFO: Resuming triggers.
[14:08:07.879] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:08:39.051] INFO: Resuming triggers.
[14:08:45.682] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[14:09:17.104] INFO: Resuming triggers.
[14:09:23.735] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:09:54.091] INFO: Resuming triggers.
[14:10:00.724] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[14:10:30.816] INFO: Resuming triggers.
[14:10:37.450] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[14:11:07.553] INFO: Resuming triggers.
[14:11:14.189] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[14:11:44.441] INFO: Resuming triggers.
[14:11:51.079] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[14:12:21.266] INFO: Resuming triggers.
[14:12:27.906] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:12:58.083] INFO: Resuming triggers.
[14:13:04.720] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:13:34.924] INFO: Resuming triggers.
[14:13:35.794] INFO: data taking finished, elapsed time: 100 seconds.
[14:13:40.092] INFO: PixTest:: pg_setup set to default.
[14:13:40.095] INFO: PixTestXray::doPhRun() done
[14:13:40.244] INFO: enter test to run
[14:15:24.606] INFO: test: HighRate no parameter change
[14:15:24.606] INFO: running: highrate
[14:15:24.625] INFO: ----------------------------------------------------------------------
[14:15:24.625] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:15:24.625] INFO: ----------------------------------------------------------------------
[14:15:24.779] INFO: Expecting 768 events.
[14:15:25.914] INFO: 768 events read in total (419ms).
[14:15:25.914] INFO: Test took 1269ms.
[14:15:26.718] INFO: Expecting 41600 events.
[14:15:29.821] INFO: 41600 events read in total (2576ms).
[14:15:29.822] INFO: Test took 3882ms.
[14:15:29.855] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:30.586] INFO: Expecting 41600 events.
[14:15:33.806] INFO: 41600 events read in total (2694ms).
[14:15:33.807] INFO: Test took 3935ms.
[14:15:33.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:34.568] INFO: Expecting 41600 events.
[14:15:37.803] INFO: 41600 events read in total (2708ms).
[14:15:37.804] INFO: Test took 3943ms.
[14:15:37.837] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:38.563] INFO: Expecting 41600 events.
[14:15:41.799] INFO: 41600 events read in total (2709ms).
[14:15:41.800] INFO: Test took 3944ms.
[14:15:41.834] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:42.559] INFO: Expecting 41600 events.
[14:15:45.803] INFO: 41600 events read in total (2717ms).
[14:15:45.804] INFO: Test took 3952ms.
[14:15:45.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:46.560] INFO: Expecting 41600 events.
[14:15:49.822] INFO: 41600 events read in total (2735ms).
[14:15:49.823] INFO: Test took 3965ms.
[14:15:49.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:50.577] INFO: Expecting 41600 events.
[14:15:53.818] INFO: 41600 events read in total (2714ms).
[14:15:53.819] INFO: Test took 3943ms.
[14:15:53.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:54.573] INFO: Expecting 41600 events.
[14:15:57.827] INFO: 41600 events read in total (2727ms).
[14:15:57.828] INFO: Test took 3956ms.
[14:15:57.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:58.582] INFO: Expecting 41600 events.
[14:16:01.849] INFO: 41600 events read in total (2740ms).
[14:16:01.850] INFO: Test took 3970ms.
[14:16:01.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:02.607] INFO: Expecting 41600 events.
[14:16:05.866] INFO: 41600 events read in total (2732ms).
[14:16:05.867] INFO: Test took 3963ms.
[14:16:05.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:06.622] INFO: Expecting 41600 events.
[14:16:09.852] INFO: 41600 events read in total (2704ms).
[14:16:09.853] INFO: Test took 3931ms.
[14:16:09.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:10.609] INFO: Expecting 41600 events.
[14:16:13.871] INFO: 41600 events read in total (2735ms).
[14:16:13.872] INFO: Test took 3966ms.
[14:16:13.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:14.629] INFO: Expecting 41600 events.
[14:16:17.883] INFO: 41600 events read in total (2727ms).
[14:16:17.884] INFO: Test took 3960ms.
[14:16:17.918] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:18.644] INFO: Expecting 41600 events.
[14:16:21.891] INFO: 41600 events read in total (2720ms).
[14:16:21.892] INFO: Test took 3956ms.
[14:16:21.926] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:22.650] INFO: Expecting 41600 events.
[14:16:25.907] INFO: 41600 events read in total (2731ms).
[14:16:25.908] INFO: Test took 3963ms.
[14:16:25.942] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:26.663] INFO: Expecting 41600 events.
[14:16:29.926] INFO: 41600 events read in total (2736ms).
[14:16:29.927] INFO: Test took 3967ms.
[14:16:29.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:30.682] INFO: Expecting 41600 events.
[14:16:33.926] INFO: 41600 events read in total (2717ms).
[14:16:33.927] INFO: Test took 3947ms.
[14:16:33.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:34.686] INFO: Expecting 41600 events.
[14:16:37.924] INFO: 41600 events read in total (2711ms).
[14:16:37.925] INFO: Test took 3944ms.
[14:16:37.960] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:38.687] INFO: Expecting 41600 events.
[14:16:41.917] INFO: 41600 events read in total (2703ms).
[14:16:41.918] INFO: Test took 3939ms.
[14:16:41.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:42.677] INFO: Expecting 41600 events.
[14:16:45.752] INFO: 41600 events read in total (2548ms).
[14:16:45.753] INFO: Test took 3781ms.
[14:16:45.787] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:46.152] INFO: enter test to run
[14:17:05.877] INFO: test: HighRate no parameter change
[14:17:05.878] INFO: running: highrate
[14:17:05.879] INFO: ----------------------------------------------------------------------
[14:17:05.879] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:17:05.879] INFO: ----------------------------------------------------------------------
[14:17:06.512] INFO: Expecting 208000 events.
[14:17:18.509] INFO: 208000 events read in total (11470ms).
[14:17:18.512] INFO: Test took 12623ms.
[14:17:18.664] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:18.920] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[14:17:18.920] INFO: number of red-efficiency pixels: 96 46 94 117 143 165 143 90 85 114 116 99 91 58 19 28
[14:17:18.920] INFO: number of X-ray hits detected: 72187 46426 70477 109574 114335 116609 112631 81938 77962 100007 96985 86618 89662 54600 22411 26883
[14:17:18.920] 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:17:18.920] INFO: number of Vcal hits detected: 207898 207953 207904 207878 207853 207833 207855 207908 207912 207882 207832 207901 207909 207942 207981 207971
[14:17:18.920] 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 100.0 100.0 100.0 100.0
[14:17:18.920] 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 100.0 100.0 100.0 100.0 100.0
[14:17:18.920] INFO: X-ray hit rate [MHz/cm2]: 21.2 13.6 20.7 32.1 33.5 34.2 33.0 24.0 22.9 29.3 28.4 25.4 26.3 16.0 6.6 7.9
[14:17:18.920] INFO: PixTestHighRate::doXPixelAlive() done
[14:17:18.967] INFO: PixTest:: pg_setup set to default.
[14:17:18.986] INFO: enter test to run
[14:17:50.797] INFO: test: HighRate no parameter change
[14:17:50.797] INFO: running: highrate
[14:17:50.798] INFO: ----------------------------------------------------------------------
[14:17:50.798] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:17:50.798] INFO: ----------------------------------------------------------------------
[14:17:51.417] INFO: Expecting 208000 events.
[14:18:04.995] INFO: 208000 events read in total (13052ms).
[14:18:04.001] INFO: Test took 14193ms.
[14:18:05.302] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:05.605] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[14:18:05.605] INFO: number of red-efficiency pixels: 285 155 319 430 430 547 464 352 294 331 344 333 345 225 59 73
[14:18:05.605] INFO: number of X-ray hits detected: 147683 94463 143816 221627 232349 234464 228311 165760 158488 203501 197903 175242 181288 110666 45467 54571
[14:18:05.605] 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:18:05.605] INFO: number of Vcal hits detected: 207685 207835 207643 207525 207525 207386 207487 207626 207692 207651 207593 207640 207639 207757 207941 207924
[14:18:05.606] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[14:18:05.606] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[14:18:05.606] INFO: X-ray hit rate [MHz/cm2]: 43.3 27.7 42.2 65.0 68.1 68.7 66.9 48.6 46.5 59.6 58.0 51.4 53.1 32.4 13.3 16.0
[14:18:05.606] INFO: PixTestHighRate::doXPixelAlive() done
[14:18:05.653] INFO: PixTest:: pg_setup set to default.
[14:18:05.668] INFO: enter test to run
[14:20:43.084] INFO: test: HighRate no parameter change
[14:20:43.084] INFO: running: highrate
[14:20:43.085] INFO: ----------------------------------------------------------------------
[14:20:43.085] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:20:43.085] INFO: ----------------------------------------------------------------------
[14:20:43.703] INFO: Expecting 208000 events.
[14:20:59.808] INFO: 208000 events read in total (15578ms).
[14:20:59.816] INFO: Test took 16721ms.
[14:21:00.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:00.649] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[14:21:00.649] INFO: number of red-efficiency pixels: 640 306 683 930 920 1311 1041 695 593 781 822 822 839 499 125 128
[14:21:00.649] INFO: number of X-ray hits detected: 224054 144536 219768 339335 352834 357777 348373 253262 242063 309987 302270 268779 276019 169461 70002 83782
[14:21:00.649] 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:21:00.649] INFO: number of Vcal hits detected: 207163 207657 207058 206817 206856 206216 206694 207141 207307 207090 206976 206990 207019 207373 207871 207868
[14:21:00.649] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.5 99.5 99.2 99.4 99.6 99.7 99.6 99.6 99.6 99.6 99.7 99.9 99.9
[14:21:00.649] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.5 99.4 99.5 99.1 99.4 99.6 99.7 99.6 99.5 99.5 99.5 99.7 99.9 99.9
[14:21:00.649] INFO: X-ray hit rate [MHz/cm2]: 65.7 42.4 64.4 99.5 103.4 104.9 102.1 74.2 71.0 90.9 88.6 78.8 80.9 49.7 20.5 24.6
[14:21:00.649] INFO: PixTestHighRate::doXPixelAlive() done
[14:21:00.694] INFO: PixTest:: pg_setup set to default.
[14:21:00.706] INFO: enter test to run
[14:21:56.795] INFO: test: exit no parameter change
[14:21:57.174] QUIET: Connection to board 33 closed.
[14:21:57.184] INFO: pXar: this is the end, my friend