[13:54:35.036] INFO: *** Welcome to pxar ***
[13:54:35.036] INFO: *** Today: 2016/07/21
[13:54:35.223] INFO: *** Version: v1.9.0-814-g7497
[13:54:35.223] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//dacParameters35_C15.dat
[13:54:35.248] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:54:35.248] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//defaultMaskFile.dat
[13:54:35.251] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C15.dat
[13:54:35.359] INFO: clk: 4
[13:54:35.359] INFO: ctr: 4
[13:54:35.359] INFO: sda: 19
[13:54:35.359] INFO: tin: 9
[13:54:35.359] INFO: level: 15
[13:54:35.359] INFO: triggerdelay: 0
[13:54:35.359] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:54:35.359] INFO: Log level: INFO
[13:54:35.377] QUIET: Connection to board DTB_WREKRL opened.
[13:54:35.400] 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:54:35.403] INFO: RPC call hashes of host and DTB match: 398089610
[13:54:36.936] INFO: DUT info:
[13:54:36.936] INFO: The DUT currently contains the following objects:
[13:54:36.936] INFO: 2 TBM Cores tbm08c (2 ON)
[13:54:36.936] INFO: TBM Core alpha (0): 7 registers set
[13:54:36.936] INFO: TBM Core beta (1): 7 registers set
[13:54:36.936] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:54:36.936] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.936] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.937] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.937] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.937] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.937] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.937] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:36.937] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:54:37.357] INFO: enter 'restricted' command line mode
[13:54:37.357] INFO: enter test to run
[13:54:46.278] INFO: test: PixelAlive no parameter change
[13:54:46.278] INFO: running: pixelalive
[13:54:46.337] INFO: ----------------------------------------------------------------------
[13:54:46.337] 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:54:46.337] INFO: ----------------------------------------------------------------------
[13:54:46.660] INFO: Expecting 41600 events.
[13:54:51.005] INFO: 41600 events read in total (3626ms).
[13:54:51.174] INFO: Test took 4833ms.
[13:54:51.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:51.446] INFO: PixTestAlive::aliveTest() done
[13:54:51.447] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:54:51.478] INFO: enter test to run
[13:56:30.494] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:56:30.494] INFO: running: highrate
[13:56:30.494] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:56:30.742] INFO: ----------------------------------------------------------------------
[13:56:30.742] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:56:30.742] INFO: ----------------------------------------------------------------------
[13:56:30.742] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:56:30.742] INFO: edge/corner pixel THR is adjusted
[13:56:30.742] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:56:31.702] INFO: Collecting data for 5 seconds...
[13:56:36.718] INFO: Done with hot pixel readout
[13:56:48.231] INFO: PixTest:: pg_setup set to default.
[13:56:48.232] INFO: 2 hot pixels found in step 0
[13:56:49.220] INFO: Collecting data for 5 seconds...
[13:56:54.236] INFO: Done with hot pixel readout
[13:57:05.857] INFO: PixTest:: pg_setup set to default.
[13:57:05.858] INFO: 4 hot pixels found in step 1
[13:57:06.851] INFO: Collecting data for 5 seconds...
[13:57:11.866] INFO: Done with hot pixel readout
[13:57:23.337] INFO: PixTest:: pg_setup set to default.
[13:57:23.338] INFO: 3 hot pixels found in step 2
[13:57:24.328] INFO: Collecting data for 5 seconds...
[13:57:29.344] INFO: Done with hot pixel readout
[13:57:40.436] INFO: PixTest:: pg_setup set to default.
[13:57:40.437] INFO: 4 hot pixels found in step 3
[13:57:41.427] INFO: Collecting data for 5 seconds...
[13:57:46.444] INFO: Done with hot pixel readout
[13:57:57.717] INFO: PixTest:: pg_setup set to default.
[13:57:57.718] INFO: 3 hot pixels found in step 4
[13:57:58.708] INFO: Collecting data for 5 seconds...
[13:58:03.725] INFO: Done with hot pixel readout
[13:58:15.023] INFO: PixTest:: pg_setup set to default.
[13:58:15.024] INFO: 3 hot pixels found in step 5
[13:58:16.013] INFO: Collecting data for 5 seconds...
[13:58:21.030] INFO: Done with hot pixel readout
[13:58:32.545] INFO: PixTest:: pg_setup set to default.
[13:58:32.546] INFO: 3 hot pixels found in step 6
[13:58:33.536] INFO: Collecting data for 5 seconds...
[13:58:38.552] INFO: Done with hot pixel readout
[13:58:49.966] INFO: PixTest:: pg_setup set to default.
[13:58:49.967] INFO: 4 hot pixels found in step 7
[13:58:50.957] INFO: Collecting data for 5 seconds...
[13:58:55.973] INFO: Done with hot pixel readout
[13:59:07.390] INFO: PixTest:: pg_setup set to default.
[13:59:07.391] INFO: 2 hot pixels found in step 8
[13:59:08.382] INFO: Collecting data for 5 seconds...
[13:59:13.398] INFO: Done with hot pixel readout
[13:59:25.025] INFO: PixTest:: pg_setup set to default.
[13:59:25.026] INFO: 0 hot pixels found in step 9
[13:59:25.061] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:59:25.064] INFO: PixTest::trimHotPixels() done
[13:59:25.065] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C0.dat
[13:59:25.070] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C1.dat
[13:59:25.076] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C2.dat
[13:59:25.081] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C3.dat
[13:59:25.087] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C4.dat
[13:59:25.093] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C5.dat
[13:59:25.098] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C6.dat
[13:59:25.103] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C7.dat
[13:59:25.109] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C8.dat
[13:59:25.114] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C9.dat
[13:59:25.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C10.dat
[13:59:25.125] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C11.dat
[13:59:25.131] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C12.dat
[13:59:25.136] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C13.dat
[13:59:25.142] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C14.dat
[13:59:25.147] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C15.dat
[13:59:25.153] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//defaultMaskFile.dat
[13:59:25.163] INFO: enter test to run
[13:59:56.476] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:59:56.476] INFO: running: highrate
[13:59:56.481] INFO: ----------------------------------------------------------------------
[13:59:56.481] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:59:56.481] INFO: ----------------------------------------------------------------------
[13:59:56.481] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:59:56.481] INFO: edge/corner pixel THR is adjusted
[13:59:56.481] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:59:57.438] INFO: Collecting data for 1 seconds...
[13:59:58.442] INFO: Done with hot pixel readout
[14:00:02.276] INFO: PixTest:: pg_setup set to default.
[14:00:02.277] INFO: 0 hot pixels found in step 0
[14:00:02.283] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:00:02.372] INFO: PixTest::trimHotPixels() done
[14:00:02.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C0.dat
[14:00:02.381] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C1.dat
[14:00:02.386] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C2.dat
[14:00:02.392] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C3.dat
[14:00:02.397] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C4.dat
[14:00:02.402] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C5.dat
[14:00:02.408] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C6.dat
[14:00:02.413] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C7.dat
[14:00:02.419] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C8.dat
[14:00:02.424] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C9.dat
[14:00:02.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C10.dat
[14:00:02.435] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C11.dat
[14:00:02.440] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C12.dat
[14:00:02.446] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C13.dat
[14:00:02.451] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C14.dat
[14:00:02.457] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//trimParameters35_C15.dat
[14:00:02.462] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-26_FPIXTest-17C-FNAL-160713-1210_2016-07-13_12h28m_1468430933/000_FPIXTest_p17//defaultMaskFile.dat
[14:00:02.471] INFO: enter test to run
[14:00:40.748] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:00:40.748] INFO: running: xray
[14:00:40.759] INFO: ----------------------------------------------------------------------
[14:00:40.759] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:00:40.759] INFO: ----------------------------------------------------------------------
[14:00:41.722] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:00:53.413] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:01:22.488] INFO: Resuming triggers.
[14:01:34.181] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:02:04.154] INFO: Resuming triggers.
[14:02:15.845] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:02:45.618] INFO: Resuming triggers.
[14:02:57.309] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:03:26.556] INFO: Resuming triggers.
[14:03:38.248] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:04:07.507] INFO: Resuming triggers.
[14:04:19.197] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:04:48.468] INFO: Resuming triggers.
[14:05:00.156] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:05:29.351] INFO: Resuming triggers.
[14:05:41.042] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:06:10.286] INFO: Resuming triggers.
[14:06:17.098] INFO: data taking finished, elapsed time: 100 seconds.
[14:06:34.457] INFO: PixTest:: pg_setup set to default.
[14:06:34.460] INFO: PixTestXray::doPhRun() done
[14:06:34.595] INFO: enter test to run
[14:07:13.857] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:07:13.857] INFO: running: xray
[14:07:13.858] INFO: ----------------------------------------------------------------------
[14:07:13.858] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:07:13.858] INFO: ----------------------------------------------------------------------
[14:07:14.823] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:07:21.874] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:07:51.848] INFO: Resuming triggers.
[14:07:58.906] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[14:08:28.943] INFO: Resuming triggers.
[14:08:35.000] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:09:06.050] INFO: Resuming triggers.
[14:09:13.111] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[14:09:43.166] INFO: Resuming triggers.
[14:09:50.223] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:10:20.196] INFO: Resuming triggers.
[14:10:27.255] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[14:10:57.281] INFO: Resuming triggers.
[14:11:04.339] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:11:34.962] INFO: Resuming triggers.
[14:11:42.019] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[14:12:12.001] INFO: Resuming triggers.
[14:12:20.062] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[14:12:50.175] INFO: Resuming triggers.
[14:12:57.230] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[14:13:27.399] INFO: Resuming triggers.
[14:13:34.458] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[14:14:05.777] INFO: Resuming triggers.
[14:14:12.831] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[14:14:43.905] INFO: Resuming triggers.
[14:14:50.963] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:15:21.284] INFO: Resuming triggers.
[14:15:28.345] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:15:58.283] INFO: Resuming triggers.
[14:15:59.819] INFO: data taking finished, elapsed time: 100 seconds.
[14:16:06.794] INFO: PixTest:: pg_setup set to default.
[14:16:06.797] INFO: PixTestXray::doPhRun() done
[14:16:06.954] INFO: enter test to run
[14:16:47.405] INFO: test: HighRate no parameter change
[14:16:47.406] INFO: running: highrate
[14:16:47.407] INFO: ----------------------------------------------------------------------
[14:16:47.407] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:16:47.407] INFO: ----------------------------------------------------------------------
[14:16:47.547] INFO: Expecting 768 events.
[14:16:48.681] INFO: 768 events read in total (419ms).
[14:16:48.681] INFO: Test took 1268ms.
[14:16:49.484] INFO: Expecting 41600 events.
[14:16:52.593] INFO: 41600 events read in total (2582ms).
[14:16:52.594] INFO: Test took 3906ms.
[14:16:52.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:53.356] INFO: Expecting 41600 events.
[14:16:56.540] INFO: 41600 events read in total (2657ms).
[14:16:56.541] INFO: Test took 3899ms.
[14:16:56.574] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:57.298] INFO: Expecting 41600 events.
[14:17:00.513] INFO: 41600 events read in total (2688ms).
[14:17:00.514] INFO: Test took 3922ms.
[14:17:00.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:01.273] INFO: Expecting 41600 events.
[14:17:04.489] INFO: 41600 events read in total (2689ms).
[14:17:04.490] INFO: Test took 3927ms.
[14:17:04.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:05.251] INFO: Expecting 41600 events.
[14:17:08.444] INFO: 41600 events read in total (2666ms).
[14:17:08.445] INFO: Test took 3906ms.
[14:17:08.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:09.202] INFO: Expecting 41600 events.
[14:17:12.404] INFO: 41600 events read in total (2675ms).
[14:17:12.405] INFO: Test took 3909ms.
[14:17:12.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:13.161] INFO: Expecting 41600 events.
[14:17:16.321] INFO: 41600 events read in total (2633ms).
[14:17:16.322] INFO: Test took 3867ms.
[14:17:16.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:17.082] INFO: Expecting 41600 events.
[14:17:20.315] INFO: 41600 events read in total (2706ms).
[14:17:20.316] INFO: Test took 3943ms.
[14:17:20.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:21.073] INFO: Expecting 41600 events.
[14:17:24.290] INFO: 41600 events read in total (2690ms).
[14:17:24.291] INFO: Test took 3924ms.
[14:17:24.323] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:25.053] INFO: Expecting 41600 events.
[14:17:28.269] INFO: 41600 events read in total (2689ms).
[14:17:28.270] INFO: Test took 3930ms.
[14:17:28.303] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:29.027] INFO: Expecting 41600 events.
[14:17:32.231] INFO: 41600 events read in total (2677ms).
[14:17:32.232] INFO: Test took 3912ms.
[14:17:32.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:32.991] INFO: Expecting 41600 events.
[14:17:36.208] INFO: 41600 events read in total (2690ms).
[14:17:36.209] INFO: Test took 3926ms.
[14:17:36.241] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:36.967] INFO: Expecting 41600 events.
[14:17:40.174] INFO: 41600 events read in total (2680ms).
[14:17:40.175] INFO: Test took 3916ms.
[14:17:40.208] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:40.934] INFO: Expecting 41600 events.
[14:17:44.170] INFO: 41600 events read in total (2709ms).
[14:17:44.171] INFO: Test took 3944ms.
[14:17:44.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:44.929] INFO: Expecting 41600 events.
[14:17:48.033] INFO: 41600 events read in total (2577ms).
[14:17:48.034] INFO: Test took 3812ms.
[14:17:48.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:48.791] INFO: Expecting 41600 events.
[14:17:52.019] INFO: 41600 events read in total (2701ms).
[14:17:52.020] INFO: Test took 3935ms.
[14:17:52.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:52.781] INFO: Expecting 41600 events.
[14:17:55.002] INFO: 41600 events read in total (2694ms).
[14:17:56.003] INFO: Test took 3934ms.
[14:17:56.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:56.761] INFO: Expecting 41600 events.
[14:17:59.989] INFO: 41600 events read in total (2701ms).
[14:17:59.990] INFO: Test took 3936ms.
[14:18:00.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:00.751] INFO: Expecting 41600 events.
[14:18:03.961] INFO: 41600 events read in total (2683ms).
[14:18:03.962] INFO: Test took 3921ms.
[14:18:03.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:04.724] INFO: Expecting 41600 events.
[14:18:07.795] INFO: 41600 events read in total (2544ms).
[14:18:07.796] INFO: Test took 3784ms.
[14:18:07.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:08.197] INFO: enter test to run
[14:19:07.236] INFO: test: HighRate no parameter change
[14:19:07.236] INFO: running: highrate
[14:19:07.238] INFO: ----------------------------------------------------------------------
[14:19:07.238] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:19:07.238] INFO: ----------------------------------------------------------------------
[14:19:07.854] INFO: Expecting 208000 events.
[14:19:19.630] INFO: 208000 events read in total (11250ms).
[14:19:19.633] INFO: Test took 12387ms.
[14:19:19.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:20.019] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:19:20.019] INFO: number of red-efficiency pixels: 83 38 80 110 105 144 124 96 123 129 122 101 125 78 17 22
[14:19:20.019] INFO: number of X-ray hits detected: 61632 36152 57270 88261 93359 99664 100774 76695 79025 105583 101530 88784 90556 55146 21251 24588
[14:19:20.019] 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:19:20.019] INFO: number of Vcal hits detected: 207768 207962 207916 207889 207890 207853 207873 207900 207873 207866 207874 207897 207874 207921 207983 207978
[14:19:20.019] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[14:19:20.019] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[14:19:20.019] INFO: X-ray hit rate [MHz/cm2]: 18.1 10.6 16.8 25.9 27.4 29.2 29.5 22.5 23.2 30.9 29.8 26.0 26.5 16.2 6.2 7.2
[14:19:20.019] INFO: PixTestHighRate::doXPixelAlive() done
[14:19:20.065] INFO: PixTest:: pg_setup set to default.
[14:19:20.080] INFO: enter test to run
[14:19:51.100] INFO: test: HighRate no parameter change
[14:19:51.100] INFO: running: highrate
[14:19:51.101] INFO: ----------------------------------------------------------------------
[14:19:51.101] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:19:51.101] INFO: ----------------------------------------------------------------------
[14:19:51.715] INFO: Expecting 208000 events.
[14:20:04.926] INFO: 208000 events read in total (12684ms).
[14:20:04.931] INFO: Test took 13821ms.
[14:20:05.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:05.500] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:20:05.500] INFO: number of red-efficiency pixels: 249 82 243 296 301 371 390 247 296 419 434 271 317 176 63 62
[14:20:05.500] INFO: number of X-ray hits detected: 125248 73012 114967 179642 187754 202067 202598 156336 160582 212607 204799 178146 181809 111248 42863 50103
[14:20:05.500] 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:20:05.500] INFO: number of Vcal hits detected: 207588 207915 207735 207696 207680 207607 207582 207729 207690 207542 207538 207712 207669 207816 207932 207937
[14:20:05.500] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[14:20:05.500] INFO: Vcal hit overall efficiency (%): 99.8 100.0 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[14:20:05.500] INFO: X-ray hit rate [MHz/cm2]: 36.7 21.4 33.7 52.7 55.0 59.2 59.4 45.8 47.1 62.3 60.0 52.2 53.3 32.6 12.6 14.7
[14:20:05.501] INFO: PixTestHighRate::doXPixelAlive() done
[14:20:05.549] INFO: PixTest:: pg_setup set to default.
[14:20:05.562] INFO: enter test to run
[14:20:41.020] INFO: test: HighRate no parameter change
[14:20:41.020] INFO: running: highrate
[14:20:41.021] INFO: ----------------------------------------------------------------------
[14:20:41.021] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:20:41.021] INFO: ----------------------------------------------------------------------
[14:20:41.636] INFO: Expecting 208000 events.
[14:20:56.609] INFO: 208000 events read in total (14446ms).
[14:20:56.616] INFO: Test took 15585ms.
[14:20:57.021] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:57.358] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:20:57.358] INFO: number of red-efficiency pixels: 445 178 477 559 631 753 827 533 657 930 831 625 812 371 85 98
[14:20:57.358] INFO: number of X-ray hits detected: 187904 109124 172072 267550 281164 303569 303393 234278 241259 317581 307915 267448 272752 166959 64364 75100
[14:20:57.358] 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:20:57.358] INFO: number of Vcal hits detected: 207322 207814 207430 207391 207299 207156 207020 207375 207234 206883 206987 207284 207059 207579 207913 207900
[14:20:57.358] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.6 99.6 99.7 99.6 99.5 99.6 99.7 99.6 99.8 100.0 100.0
[14:20:57.358] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.7 99.7 99.6 99.5 99.7 99.6 99.5 99.5 99.7 99.5 99.8 100.0 100.0
[14:20:57.358] INFO: X-ray hit rate [MHz/cm2]: 55.1 32.0 50.4 78.4 82.4 89.0 88.9 68.7 70.7 93.1 90.3 78.4 79.9 48.9 18.9 22.0
[14:20:57.358] INFO: PixTestHighRate::doXPixelAlive() done
[14:20:57.406] INFO: PixTest:: pg_setup set to default.
[14:20:57.424] INFO: enter test to run
[14:21:28.403] INFO: test: exit no parameter change
[14:21:28.721] QUIET: Connection to board 33 closed.
[14:21:28.723] INFO: pXar: this is the end, my friend