[12:38:23.359] INFO: *** Welcome to pxar ***
[12:38:23.359] INFO: *** Today: 2016/06/10
[12:38:24.154] INFO: *** Version: v1.9.0-814-g7497
[12:38:24.154] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//dacParameters35_C15.dat
[12:38:24.177] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:38:24.177] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[12:38:24.186] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[12:38:24.315] INFO: clk: 4
[12:38:24.315] INFO: ctr: 4
[12:38:24.315] INFO: sda: 19
[12:38:24.315] INFO: tin: 9
[12:38:24.315] INFO: level: 15
[12:38:24.315] INFO: triggerdelay: 0
[12:38:24.315] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:38:24.315] INFO: Log level: INFO
[12:38:24.333] QUIET: Connection to board DTB_WREKRL opened.
[12:38:24.336] 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:
------------------------------------------------------
[12:38:24.339] INFO: RPC call hashes of host and DTB match: 398089610
[12:38:25.872] INFO: DUT info:
[12:38:25.872] INFO: The DUT currently contains the following objects:
[12:38:25.872] INFO: 2 TBM Cores tbm08c (2 ON)
[12:38:25.872] INFO: TBM Core alpha (0): 7 registers set
[12:38:25.872] INFO: TBM Core beta (1): 7 registers set
[12:38:25.872] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:38:25.872] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:25.872] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:26.276] INFO: enter 'restricted' command line mode
[12:38:26.276] INFO: enter test to run
[12:38:29.876] INFO: test: PixelAlive no parameter change
[12:38:29.876] INFO: running: pixelalive
[12:38:29.885] INFO: ----------------------------------------------------------------------
[12:38:29.885] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:38:29.885] INFO: ----------------------------------------------------------------------
[12:38:30.205] INFO: Expecting 41600 events.
[12:38:34.532] INFO: 41600 events read in total (3608ms).
[12:38:34.697] INFO: Test took 4809ms.
[12:38:34.706] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:35.046] INFO: PixTestAlive::aliveTest() done
[12:38:35.046] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:38:35.079] INFO: enter test to run
[12:39:35.867] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:39:35.867] INFO: running: highrate
[12:39:35.884] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:39:36.156] INFO: ----------------------------------------------------------------------
[12:39:36.157] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:39:36.157] INFO: ----------------------------------------------------------------------
[12:39:36.157] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:39:36.157] INFO: edge/corner pixel THR is adjusted
[12:39:36.157] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:39:37.115] INFO: Collecting data for 5 seconds...
[12:39:42.134] INFO: Done with hot pixel readout
[12:39:53.603] INFO: PixTest:: pg_setup set to default.
[12:39:53.604] INFO: 0 hot pixels found in step 0
[12:39:53.616] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:39:53.620] INFO: PixTest::trimHotPixels() done
[12:39:53.638] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat
[12:39:53.645] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C1.dat
[12:39:53.651] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C2.dat
[12:39:53.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C3.dat
[12:39:53.663] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C4.dat
[12:39:53.668] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C5.dat
[12:39:53.674] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C6.dat
[12:39:53.680] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C7.dat
[12:39:53.686] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C8.dat
[12:39:53.692] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C9.dat
[12:39:53.697] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C10.dat
[12:39:53.703] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C11.dat
[12:39:53.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C12.dat
[12:39:53.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C13.dat
[12:39:53.721] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C14.dat
[12:39:53.726] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[12:39:53.732] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[12:39:53.742] INFO: enter test to run
[12:40:19.035] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:40:19.035] INFO: running: highrate
[12:40:19.039] INFO: ----------------------------------------------------------------------
[12:40:19.039] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:40:19.039] INFO: ----------------------------------------------------------------------
[12:40:19.040] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:40:19.040] INFO: edge/corner pixel THR is adjusted
[12:40:19.040] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:40:19.997] INFO: Collecting data for 1 seconds...
[12:40:20.001] INFO: Done with hot pixel readout
[12:40:24.754] INFO: PixTest:: pg_setup set to default.
[12:40:24.755] INFO: 0 hot pixels found in step 0
[12:40:24.761] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:40:24.857] INFO: PixTest::trimHotPixels() done
[12:40:24.857] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C0.dat
[12:40:24.869] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C1.dat
[12:40:24.877] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C2.dat
[12:40:24.883] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C3.dat
[12:40:24.889] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C4.dat
[12:40:24.895] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C5.dat
[12:40:24.900] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C6.dat
[12:40:24.906] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C7.dat
[12:40:24.912] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C8.dat
[12:40:24.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C9.dat
[12:40:24.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C10.dat
[12:40:24.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C11.dat
[12:40:24.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C12.dat
[12:40:24.941] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C13.dat
[12:40:24.947] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C14.dat
[12:40:24.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//trimParameters35_C15.dat
[12:40:24.959] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-1-15_FPIXTest-17C-FNAL-160608-1151_2016-06-08_11h51m_1465404672/000_FPIXTest_p17//defaultMaskFile.dat
[12:40:24.969] INFO: enter test to run
[12:40:56.035] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:40:56.035] INFO: running: xray
[12:40:56.036] INFO: ----------------------------------------------------------------------
[12:40:56.036] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:40:56.036] INFO: ----------------------------------------------------------------------
[12:40:56.999] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:41:08.868] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:41:39.407] INFO: Resuming triggers.
[12:41:51.277] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:42:21.517] INFO: Resuming triggers.
[12:42:33.385] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:43:03.513] INFO: Resuming triggers.
[12:43:15.378] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:43:44.675] INFO: Resuming triggers.
[12:43:56.540] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:44:25.927] INFO: Resuming triggers.
[12:44:37.792] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[12:45:07.103] INFO: Resuming triggers.
[12:45:18.964] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[12:45:48.177] INFO: Resuming triggers.
[12:46:00.038] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[12:46:29.239] INFO: Resuming triggers.
[12:46:34.668] INFO: data taking finished, elapsed time: 100 seconds.
[12:46:48.310] INFO: PixTest:: pg_setup set to default.
[12:46:48.313] INFO: PixTestXray::doPhRun() done
[12:46:48.448] INFO: enter test to run
[12:47:24.720] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:47:24.720] INFO: running: xray
[12:47:24.721] INFO: ----------------------------------------------------------------------
[12:47:24.721] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:47:24.721] INFO: ----------------------------------------------------------------------
[12:47:25.686] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:47:32.784] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:48:02.770] INFO: Resuming triggers.
[12:48:09.866] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:48:39.967] INFO: Resuming triggers.
[12:48:47.061] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:49:17.102] INFO: Resuming triggers.
[12:49:24.202] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:49:54.240] INFO: Resuming triggers.
[12:50:01.337] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:50:31.285] INFO: Resuming triggers.
[12:50:38.382] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[12:51:09.247] INFO: Resuming triggers.
[12:51:16.347] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:51:47.232] INFO: Resuming triggers.
[12:51:54.335] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[12:52:24.592] INFO: Resuming triggers.
[12:52:31.690] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[12:53:03.394] INFO: Resuming triggers.
[12:53:10.496] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[12:53:41.383] INFO: Resuming triggers.
[12:53:48.485] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[12:54:18.941] INFO: Resuming triggers.
[12:54:26.042] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[12:54:56.143] INFO: Resuming triggers.
[12:55:03.244] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[12:55:33.293] INFO: Resuming triggers.
[12:55:40.392] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[12:56:10.370] INFO: Resuming triggers.
[12:56:11.326] INFO: data taking finished, elapsed time: 100 seconds.
[12:56:15.694] INFO: PixTest:: pg_setup set to default.
[12:56:15.698] INFO: PixTestXray::doPhRun() done
[12:56:15.849] INFO: enter test to run
[12:56:37.676] INFO: test: HighRate no parameter change
[12:56:37.676] INFO: running: highrate
[12:56:37.677] INFO: ----------------------------------------------------------------------
[12:56:37.677] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:56:37.677] INFO: ----------------------------------------------------------------------
[12:56:37.832] INFO: Expecting 768 events.
[12:56:38.966] INFO: 768 events read in total (419ms).
[12:56:38.966] INFO: Test took 1269ms.
[12:56:39.769] INFO: Expecting 41600 events.
[12:56:42.736] INFO: 41600 events read in total (2440ms).
[12:56:42.737] INFO: Test took 3746ms.
[12:56:42.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:43.503] INFO: Expecting 41600 events.
[12:56:46.647] INFO: 41600 events read in total (2617ms).
[12:56:46.648] INFO: Test took 3865ms.
[12:56:46.679] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:47.409] INFO: Expecting 41600 events.
[12:56:50.585] INFO: 41600 events read in total (2649ms).
[12:56:50.586] INFO: Test took 3890ms.
[12:56:50.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:51.349] INFO: Expecting 41600 events.
[12:56:54.547] INFO: 41600 events read in total (2672ms).
[12:56:54.548] INFO: Test took 3913ms.
[12:56:54.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:55.311] INFO: Expecting 41600 events.
[12:56:58.518] INFO: 41600 events read in total (2681ms).
[12:56:58.519] INFO: Test took 3924ms.
[12:56:58.551] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:59.280] INFO: Expecting 41600 events.
[12:57:02.479] INFO: 41600 events read in total (2672ms).
[12:57:02.480] INFO: Test took 3911ms.
[12:57:02.512] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:03.243] INFO: Expecting 41600 events.
[12:57:06.423] INFO: 41600 events read in total (2653ms).
[12:57:06.424] INFO: Test took 3894ms.
[12:57:06.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:07.183] INFO: Expecting 41600 events.
[12:57:10.383] INFO: 41600 events read in total (2673ms).
[12:57:10.384] INFO: Test took 3911ms.
[12:57:10.415] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:11.143] INFO: Expecting 41600 events.
[12:57:14.368] INFO: 41600 events read in total (2698ms).
[12:57:14.369] INFO: Test took 3936ms.
[12:57:14.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:15.128] INFO: Expecting 41600 events.
[12:57:18.352] INFO: 41600 events read in total (2697ms).
[12:57:18.352] INFO: Test took 3936ms.
[12:57:18.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:19.111] INFO: Expecting 41600 events.
[12:57:22.315] INFO: 41600 events read in total (2677ms).
[12:57:22.316] INFO: Test took 3916ms.
[12:57:22.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:23.078] INFO: Expecting 41600 events.
[12:57:26.296] INFO: 41600 events read in total (2691ms).
[12:57:26.297] INFO: Test took 3931ms.
[12:57:26.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:27.057] INFO: Expecting 41600 events.
[12:57:30.270] INFO: 41600 events read in total (2687ms).
[12:57:30.271] INFO: Test took 3926ms.
[12:57:30.302] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:31.032] INFO: Expecting 41600 events.
[12:57:34.231] INFO: 41600 events read in total (2672ms).
[12:57:34.232] INFO: Test took 3912ms.
[12:57:34.263] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:34.992] INFO: Expecting 41600 events.
[12:57:38.210] INFO: 41600 events read in total (2691ms).
[12:57:38.211] INFO: Test took 3931ms.
[12:57:38.242] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:38.969] INFO: Expecting 41600 events.
[12:57:42.195] INFO: 41600 events read in total (2699ms).
[12:57:42.196] INFO: Test took 3935ms.
[12:57:42.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:42.953] INFO: Expecting 41600 events.
[12:57:46.154] INFO: 41600 events read in total (2674ms).
[12:57:46.155] INFO: Test took 3911ms.
[12:57:46.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:46.913] INFO: Expecting 41600 events.
[12:57:50.118] INFO: 41600 events read in total (2678ms).
[12:57:50.119] INFO: Test took 3915ms.
[12:57:50.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:50.880] INFO: Expecting 41600 events.
[12:57:54.085] INFO: 41600 events read in total (2678ms).
[12:57:54.086] INFO: Test took 3918ms.
[12:57:54.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:54.841] INFO: Expecting 41600 events.
[12:57:57.000] INFO: 41600 events read in total (2632ms).
[12:57:57.002] INFO: Test took 3868ms.
[12:57:58.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:58.401] INFO: enter test to run
[12:58:03.283] INFO: test: HighRate no parameter change
[12:58:03.283] INFO: running: highrate
[12:58:03.284] INFO: ----------------------------------------------------------------------
[12:58:03.284] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:58:03.284] INFO: ----------------------------------------------------------------------
[12:58:03.894] INFO: Expecting 208000 events.
[12:58:15.623] INFO: 208000 events read in total (11202ms).
[12:58:15.626] INFO: Test took 12334ms.
[12:58:15.755] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:15.001] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:58:15.001] INFO: number of red-efficiency pixels: 55 35 81 101 106 120 145 101 69 117 119 83 88 67 21 32
[12:58:15.001] INFO: number of X-ray hits detected: 57724 39478 61519 98688 101333 107058 107313 78181 63850 81872 82120 68506 75222 45134 22052 24357
[12:58:15.001] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:58:15.001] INFO: number of Vcal hits detected: 207944 207964 207915 207896 207892 207876 207853 207897 207931 207881 207881 207916 207912 207932 207979 207968
[12:58:15.001] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[12:58:15.001] INFO: Vcal hit overall 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 100.0 100.0
[12:58:15.001] INFO: X-ray hit rate [MHz/cm2]: 16.9 11.6 18.0 28.9 29.7 31.4 31.5 22.9 18.7 24.0 24.1 20.1 22.0 13.2 6.5 7.1
[12:58:15.001] INFO: PixTestHighRate::doXPixelAlive() done
[12:58:16.050] INFO: PixTest:: pg_setup set to default.
[12:58:16.066] INFO: enter test to run
[12:58:32.275] INFO: test: HighRate no parameter change
[12:58:32.275] INFO: running: highrate
[12:58:32.276] INFO: ----------------------------------------------------------------------
[12:58:32.276] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:58:32.276] INFO: ----------------------------------------------------------------------
[12:58:32.892] INFO: Expecting 208000 events.
[12:58:46.143] INFO: 208000 events read in total (12724ms).
[12:58:46.147] INFO: Test took 13862ms.
[12:58:46.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:46.715] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:58:46.715] INFO: number of red-efficiency pixels: 180 89 245 302 420 446 482 318 194 265 276 223 249 138 58 65
[12:58:46.715] INFO: number of X-ray hits detected: 119451 81192 127847 203597 209022 221814 222314 162978 132716 170761 170770 143117 156372 93674 45486 51012
[12:58:46.715] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:58:46.715] INFO: number of Vcal hits detected: 207812 207907 207726 207678 207543 207514 207479 207658 207802 207720 207716 207766 207741 207854 207940 207933
[12:58:46.715] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:58:46.715] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:58:46.715] INFO: X-ray hit rate [MHz/cm2]: 35.0 23.8 37.5 59.7 61.3 65.0 65.2 47.8 38.9 50.1 50.1 41.9 45.8 27.5 13.3 15.0
[12:58:46.715] INFO: PixTestHighRate::doXPixelAlive() done
[12:58:46.766] INFO: PixTest:: pg_setup set to default.
[12:58:46.778] INFO: enter test to run
[12:59:41.274] INFO: test: HighRate no parameter change
[12:59:41.274] INFO: running: highrate
[12:59:41.275] INFO: ----------------------------------------------------------------------
[12:59:41.276] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:59:41.276] INFO: ----------------------------------------------------------------------
[12:59:41.892] INFO: Expecting 208000 events.
[12:59:56.793] INFO: 208000 events read in total (14375ms).
[12:59:56.799] INFO: Test took 15515ms.
[12:59:57.208] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:57.541] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:59:57.542] INFO: number of red-efficiency pixels: 365 179 561 749 988 1029 1105 660 404 679 663 479 534 310 96 92
[12:59:57.542] INFO: number of X-ray hits detected: 180206 122676 192724 307191 316046 335744 334721 245671 200850 257906 257667 214928 237338 141189 69345 77479
[12:59:57.542] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:59:57.542] INFO: number of Vcal hits detected: 207600 207810 207313 207141 206817 206749 206662 207179 207558 207213 207250 207468 207403 207656 207902 207907
[12:59:57.542] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.5 99.4 99.6 99.8 99.7 99.7 99.8 99.7 99.9 100.0 100.0
[12:59:57.542] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.4 99.4 99.6 99.8 99.6 99.6 99.7 99.7 99.8 100.0 100.0
[12:59:57.542] INFO: X-ray hit rate [MHz/cm2]: 52.8 36.0 56.5 90.0 92.6 98.4 98.1 72.0 58.9 75.6 75.5 63.0 69.6 41.4 20.3 22.7
[12:59:57.542] INFO: PixTestHighRate::doXPixelAlive() done
[12:59:57.588] INFO: PixTest:: pg_setup set to default.
[12:59:57.602] INFO: enter test to run
[13:00:05.170] INFO: test: exit no parameter change
[13:00:05.466] QUIET: Connection to board 33 closed.
[13:00:05.467] INFO: pXar: this is the end, my friend