[16:37:13.109] INFO: *** Welcome to pxar ***
[16:37:13.109] INFO: *** Today: 2016/06/24
[16:37:13.645] INFO: *** Version: v1.9.0-814-g7497
[16:37:13.645] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//dacParameters35_C15.dat
[16:37:13.907] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:37:13.908] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[16:37:13.965] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[16:37:14.969] INFO: clk: 4
[16:37:14.969] INFO: ctr: 4
[16:37:14.969] INFO: sda: 19
[16:37:14.969] INFO: tin: 9
[16:37:14.969] INFO: level: 15
[16:37:14.969] INFO: triggerdelay: 0
[16:37:14.969] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[16:37:14.969] INFO: Log level: INFO
[16:37:14.989] QUIET: Connection to board DTB_WREKRL opened.
[16:37:14.993] 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:
------------------------------------------------------
[16:37:14.996] INFO: RPC call hashes of host and DTB match: 398089610
[16:37:16.528] INFO: DUT info:
[16:37:16.528] INFO: The DUT currently contains the following objects:
[16:37:16.528] INFO: 2 TBM Cores tbm08c (2 ON)
[16:37:16.528] INFO: TBM Core alpha (0): 7 registers set
[16:37:16.528] INFO: TBM Core beta (1): 7 registers set
[16:37:16.528] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:37:16.528] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.528] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.529] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:37:16.951] INFO: enter 'restricted' command line mode
[16:37:16.951] INFO: enter test to run
[16:37:23.113] INFO: test: PixelAlive no parameter change
[16:37:23.113] INFO: running: pixelalive
[16:37:23.148] INFO: ----------------------------------------------------------------------
[16:37:23.148] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:37:23.148] INFO: ----------------------------------------------------------------------
[16:37:23.467] INFO: Expecting 41600 events.
[16:37:27.804] INFO: 41600 events read in total (3618ms).
[16:37:27.968] INFO: Test took 4817ms.
[16:37:27.978] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:28.240] INFO: PixTestAlive::aliveTest() done
[16:37:28.240] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:37:28.271] INFO: enter test to run
[16:38:05.017] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:38:05.017] INFO: running: highrate
[16:38:05.017] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:38:05.331] INFO: ----------------------------------------------------------------------
[16:38:05.332] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:38:05.332] INFO: ----------------------------------------------------------------------
[16:38:05.332] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:38:05.332] INFO: edge/corner pixel THR is adjusted
[16:38:05.332] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:38:06.290] INFO: Collecting data for 5 seconds...
[16:38:11.309] INFO: Done with hot pixel readout
[16:38:22.385] INFO: PixTest:: pg_setup set to default.
[16:38:22.386] INFO: 0 hot pixels found in step 0
[16:38:22.398] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:38:22.406] INFO: PixTest::trimHotPixels() done
[16:38:22.448] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat
[16:38:22.453] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C1.dat
[16:38:22.458] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C2.dat
[16:38:22.464] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C3.dat
[16:38:22.469] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C4.dat
[16:38:22.474] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C5.dat
[16:38:22.480] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C6.dat
[16:38:22.485] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C7.dat
[16:38:22.490] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C8.dat
[16:38:22.496] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C9.dat
[16:38:22.501] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C10.dat
[16:38:22.507] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C11.dat
[16:38:22.512] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C12.dat
[16:38:22.517] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C13.dat
[16:38:22.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C14.dat
[16:38:22.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[16:38:22.533] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[16:38:22.543] INFO: enter test to run
[16:43:01.127] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:43:01.127] INFO: running: highrate
[16:43:01.131] INFO: ----------------------------------------------------------------------
[16:43:01.131] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:43:01.131] INFO: ----------------------------------------------------------------------
[16:43:01.131] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:43:01.131] INFO: edge/corner pixel THR is adjusted
[16:43:01.131] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:43:02.089] INFO: Collecting data for 1 seconds...
[16:43:03.092] INFO: Done with hot pixel readout
[16:43:06.853] INFO: PixTest:: pg_setup set to default.
[16:43:06.854] INFO: 0 hot pixels found in step 0
[16:43:06.861] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:43:06.960] INFO: PixTest::trimHotPixels() done
[16:43:06.960] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat
[16:43:06.971] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C1.dat
[16:43:06.978] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C2.dat
[16:43:06.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C3.dat
[16:43:06.988] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C4.dat
[16:43:06.993] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C5.dat
[16:43:06.998] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C6.dat
[16:43:07.003] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C7.dat
[16:43:07.008] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C8.dat
[16:43:07.014] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C9.dat
[16:43:07.019] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C10.dat
[16:43:07.024] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C11.dat
[16:43:07.029] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C12.dat
[16:43:07.034] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C13.dat
[16:43:07.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C14.dat
[16:43:07.045] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[16:43:07.050] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-46_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[16:43:07.059] INFO: enter test to run
[16:43:27.102] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:43:27.102] INFO: running: xray
[16:43:27.103] INFO: ----------------------------------------------------------------------
[16:43:27.103] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:43:27.103] INFO: ----------------------------------------------------------------------
[16:43:28.066] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:43:40.105] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:44:09.446] INFO: Resuming triggers.
[16:44:21.487] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[16:44:51.348] INFO: Resuming triggers.
[16:45:03.389] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:45:30.666] INFO: Resuming triggers.
[16:45:42.709] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[16:46:11.984] INFO: Resuming triggers.
[16:46:24.025] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[16:46:53.296] INFO: Resuming triggers.
[16:47:05.341] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[16:47:34.639] INFO: Resuming triggers.
[16:47:46.686] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:48:15.959] INFO: Resuming triggers.
[16:48:28.010] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:48:57.335] INFO: Resuming triggers.
[16:49:01.312] INFO: data taking finished, elapsed time: 100 seconds.
[16:49:11.330] INFO: PixTest:: pg_setup set to default.
[16:49:11.334] INFO: PixTestXray::doPhRun() done
[16:49:11.470] INFO: enter test to run
[16:50:03.468] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:50:03.468] INFO: running: xray
[16:50:03.469] INFO: ----------------------------------------------------------------------
[16:50:03.469] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:50:03.469] INFO: ----------------------------------------------------------------------
[16:50:04.433] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:50:11.767] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:50:42.461] INFO: Resuming triggers.
[16:50:49.794] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[16:51:20.870] INFO: Resuming triggers.
[16:51:28.210] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[16:51:59.406] INFO: Resuming triggers.
[16:52:06.747] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[16:52:37.841] INFO: Resuming triggers.
[16:52:45.177] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:53:15.704] INFO: Resuming triggers.
[16:53:23.044] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[16:53:53.277] INFO: Resuming triggers.
[16:54:00.624] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:54:30.897] INFO: Resuming triggers.
[16:54:38.235] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[16:55:08.519] INFO: Resuming triggers.
[16:55:15.862] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[16:55:46.116] INFO: Resuming triggers.
[16:55:53.456] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[16:56:23.669] INFO: Resuming triggers.
[16:56:31.011] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[16:57:01.308] INFO: Resuming triggers.
[16:57:08.651] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[16:57:39.017] INFO: Resuming triggers.
[16:57:46.357] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[16:58:16.639] INFO: Resuming triggers.
[16:58:21.566] INFO: data taking finished, elapsed time: 100 seconds.
[16:58:42.026] INFO: PixTest:: pg_setup set to default.
[16:58:42.029] INFO: PixTestXray::doPhRun() done
[16:58:42.176] INFO: enter test to run
[16:59:09.376] INFO: test: HighRate no parameter change
[16:59:09.376] INFO: running: highrate
[16:59:09.377] INFO: ----------------------------------------------------------------------
[16:59:09.377] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:59:09.377] INFO: ----------------------------------------------------------------------
[16:59:09.519] INFO: Expecting 768 events.
[16:59:10.659] INFO: 768 events read in total (418ms).
[16:59:10.659] INFO: Test took 1274ms.
[16:59:11.462] INFO: Expecting 41600 events.
[16:59:14.415] INFO: 41600 events read in total (2426ms).
[16:59:14.416] INFO: Test took 3749ms.
[16:59:14.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:15.183] INFO: Expecting 41600 events.
[16:59:18.284] INFO: 41600 events read in total (2575ms).
[16:59:18.285] INFO: Test took 3826ms.
[16:59:18.314] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:19.052] INFO: Expecting 41600 events.
[16:59:22.221] INFO: 41600 events read in total (2642ms).
[16:59:22.222] INFO: Test took 3892ms.
[16:59:22.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:22.986] INFO: Expecting 41600 events.
[16:59:26.169] INFO: 41600 events read in total (2656ms).
[16:59:26.170] INFO: Test took 3901ms.
[16:59:26.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:26.935] INFO: Expecting 41600 events.
[16:59:30.115] INFO: 41600 events read in total (2653ms).
[16:59:30.116] INFO: Test took 3901ms.
[16:59:30.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:30.877] INFO: Expecting 41600 events.
[16:59:34.054] INFO: 41600 events read in total (2650ms).
[16:59:34.055] INFO: Test took 3895ms.
[16:59:34.084] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:34.820] INFO: Expecting 41600 events.
[16:59:37.988] INFO: 41600 events read in total (2641ms).
[16:59:37.989] INFO: Test took 3890ms.
[16:59:38.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:38.752] INFO: Expecting 41600 events.
[16:59:41.933] INFO: 41600 events read in total (2654ms).
[16:59:41.934] INFO: Test took 3899ms.
[16:59:41.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:42.694] INFO: Expecting 41600 events.
[16:59:45.884] INFO: 41600 events read in total (2663ms).
[16:59:45.885] INFO: Test took 3906ms.
[16:59:45.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:46.649] INFO: Expecting 41600 events.
[16:59:49.832] INFO: 41600 events read in total (2656ms).
[16:59:49.833] INFO: Test took 3901ms.
[16:59:49.861] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:50.596] INFO: Expecting 41600 events.
[16:59:53.767] INFO: 41600 events read in total (2644ms).
[16:59:53.768] INFO: Test took 3889ms.
[16:59:53.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:54.532] INFO: Expecting 41600 events.
[16:59:57.738] INFO: 41600 events read in total (2679ms).
[16:59:57.739] INFO: Test took 3926ms.
[16:59:57.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:58.501] INFO: Expecting 41600 events.
[17:00:01.651] INFO: 41600 events read in total (2623ms).
[17:00:01.651] INFO: Test took 3867ms.
[17:00:01.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:02.413] INFO: Expecting 41600 events.
[17:00:05.607] INFO: 41600 events read in total (2667ms).
[17:00:05.608] INFO: Test took 3911ms.
[17:00:05.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:06.365] INFO: Expecting 41600 events.
[17:00:09.550] INFO: 41600 events read in total (2658ms).
[17:00:09.551] INFO: Test took 3898ms.
[17:00:09.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:10.316] INFO: Expecting 41600 events.
[17:00:13.505] INFO: 41600 events read in total (2662ms).
[17:00:13.506] INFO: Test took 3910ms.
[17:00:13.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:14.264] INFO: Expecting 41600 events.
[17:00:17.445] INFO: 41600 events read in total (2654ms).
[17:00:17.446] INFO: Test took 3894ms.
[17:00:17.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:18.211] INFO: Expecting 41600 events.
[17:00:21.402] INFO: 41600 events read in total (2664ms).
[17:00:21.403] INFO: Test took 3912ms.
[17:00:21.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:22.165] INFO: Expecting 41600 events.
[17:00:25.351] INFO: 41600 events read in total (2660ms).
[17:00:25.352] INFO: Test took 3904ms.
[17:00:25.381] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:26.114] INFO: Expecting 41600 events.
[17:00:29.202] INFO: 41600 events read in total (2561ms).
[17:00:29.203] INFO: Test took 3805ms.
[17:00:29.231] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:29.604] INFO: enter test to run
[17:01:02.047] INFO: test: HighRate no parameter change
[17:01:02.047] INFO: running: highrate
[17:01:02.049] INFO: ----------------------------------------------------------------------
[17:01:02.049] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:01:02.049] INFO: ----------------------------------------------------------------------
[17:01:02.665] INFO: Expecting 208000 events.
[17:01:14.252] INFO: 208000 events read in total (11060ms).
[17:01:14.257] INFO: Test took 12200ms.
[17:01:14.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:14.625] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:01:14.625] INFO: number of red-efficiency pixels: 56 45 56 110 90 89 89 94 59 98 105 88 75 66 22 23
[17:01:14.625] INFO: number of X-ray hits detected: 51010 33379 47374 74993 80837 80329 84882 63753 64539 92945 91029 77533 79090 46746 18090 20017
[17:01:14.625] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:01:14.625] INFO: number of Vcal hits detected: 207943 207955 207895 207887 207907 207909 207908 207906 207939 207901 207893 207911 207922 207931 207977 207977
[17:01:14.625] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[17:01:14.625] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[17:01:14.625] INFO: X-ray hit rate [MHz/cm2]: 15.0 9.8 13.9 22.0 23.7 23.5 24.9 18.7 18.9 27.2 26.7 22.7 23.2 13.7 5.3 5.9
[17:01:14.625] INFO: PixTestHighRate::doXPixelAlive() done
[17:01:14.673] INFO: PixTest:: pg_setup set to default.
[17:01:14.683] INFO: enter test to run
[17:01:33.839] INFO: test: HighRate no parameter change
[17:01:33.839] INFO: running: highrate
[17:01:33.840] INFO: ----------------------------------------------------------------------
[17:01:33.840] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:01:33.840] INFO: ----------------------------------------------------------------------
[17:01:34.463] INFO: Expecting 208000 events.
[17:01:47.623] INFO: 208000 events read in total (12634ms).
[17:01:47.628] INFO: Test took 13779ms.
[17:01:47.892] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:48.181] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:01:48.181] INFO: number of red-efficiency pixels: 168 73 166 261 234 272 274 245 168 392 335 234 246 161 46 41
[17:01:48.181] INFO: number of X-ray hits detected: 109676 70819 102274 160006 172633 171213 181704 136824 137702 198734 195726 165347 170743 100277 38853 42786
[17:01:48.181] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:01:48.181] INFO: number of Vcal hits detected: 207826 207926 207774 207723 207757 207719 207718 207739 207822 207584 207644 207754 207743 207833 207954 207957
[17:01:48.181] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[17:01:48.181] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[17:01:48.181] INFO: X-ray hit rate [MHz/cm2]: 32.1 20.8 30.0 46.9 50.6 50.2 53.3 40.1 40.4 58.3 57.4 48.5 50.0 29.4 11.4 12.5
[17:01:48.181] INFO: PixTestHighRate::doXPixelAlive() done
[17:01:48.227] INFO: PixTest:: pg_setup set to default.
[17:01:48.244] INFO: enter test to run
[17:02:09.359] INFO: test: HighRate no parameter change
[17:02:09.359] INFO: running: highrate
[17:02:09.360] INFO: ----------------------------------------------------------------------
[17:02:09.360] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:02:09.360] INFO: ----------------------------------------------------------------------
[17:02:09.981] INFO: Expecting 208000 events.
[17:02:24.824] INFO: 208000 events read in total (14316ms).
[17:02:24.830] INFO: Test took 15461ms.
[17:02:25.219] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:25.552] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:02:25.552] INFO: number of red-efficiency pixels: 396 139 341 625 589 646 608 556 383 953 848 506 531 392 101 79
[17:02:25.552] INFO: number of X-ray hits detected: 168426 110413 156119 247692 266151 264029 279882 210541 212872 303795 299407 254147 261182 154889 60366 65731
[17:02:25.553] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:02:25.553] INFO: number of Vcal hits detected: 207563 207857 207572 207312 207339 207270 207289 207350 207584 206875 206993 207450 207426 207540 207892 207921
[17:02:25.553] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.8 99.5 99.6 99.8 99.7 99.8 100.0 100.0
[17:02:25.553] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.6 99.7 99.7 99.8 99.5 99.5 99.7 99.7 99.8 99.9 100.0
[17:02:25.553] INFO: X-ray hit rate [MHz/cm2]: 49.4 32.4 45.8 72.6 78.0 77.4 82.0 61.7 62.4 89.0 87.8 74.5 76.6 45.4 17.7 19.3
[17:02:25.553] INFO: PixTestHighRate::doXPixelAlive() done
[17:02:25.598] INFO: PixTest:: pg_setup set to default.
[17:02:25.614] INFO: enter test to run
[17:02:31.967] INFO: test: exit no parameter change
[17:02:32.368] QUIET: Connection to board 33 closed.
[17:02:32.379] INFO: pXar: this is the end, my friend