[15:06:20.451] INFO: *** Welcome to pxar ***
[15:06:20.451] INFO: *** Today: 2016/11/17
[15:06:21.571] INFO: *** Version: v1.9.0-818-g96727
[15:06:21.592] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//dacParameters35_C15.dat
[15:06:21.670] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:06:21.670] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[15:06:21.676] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[15:06:21.838] INFO: clk: 4
[15:06:21.838] INFO: ctr: 4
[15:06:21.838] INFO: sda: 19
[15:06:21.838] INFO: tin: 9
[15:06:21.838] INFO: level: 15
[15:06:21.838] INFO: triggerdelay: 0
[15:06:21.838] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:06:21.838] INFO: Log level: INFO
[15:06:21.856] QUIET: Connection to board DTB_WREHUL opened.
[15:06:21.860] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 30
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREHUL
MAC address: 40D85511801E
Hostname: pixelDTB030
Comment:
------------------------------------------------------
[15:06:21.862] INFO: RPC call hashes of host and DTB match: 398089610
[15:06:23.412] INFO: DUT info:
[15:06:23.412] INFO: The DUT currently contains the following objects:
[15:06:23.412] INFO: 2 TBM Cores tbm08c (2 ON)
[15:06:23.412] INFO: TBM Core alpha (0): 7 registers set
[15:06:23.412] INFO: TBM Core beta (1): 7 registers set
[15:06:23.412] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:06:23.412] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.412] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.412] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.412] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.412] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.412] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.412] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.413] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:23.844] INFO: enter 'restricted' command line mode
[15:06:23.844] INFO: enter test to run
[15:06:36.624] INFO: test: PixelAlive no parameter change
[15:06:36.624] INFO: running: pixelalive
[15:06:36.689] INFO: ----------------------------------------------------------------------
[15:06:36.689] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:06:36.689] INFO: ----------------------------------------------------------------------
[15:06:36.004] INFO: Expecting 41600 events.
[15:06:41.377] INFO: 41600 events read in total (3654ms).
[15:06:41.541] INFO: Test took 4849ms.
[15:06:41.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:42.224] INFO: PixTestAlive::aliveTest() done
[15:06:42.224] INFO: number of dead pixels (per ROC): 0 6 0 1 0 0 1 0 0 0 0 0 0 0 0 1
[15:06:42.371] INFO: enter test to run
[15:07:32.456] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:07:32.456] INFO: running: highrate
[15:07:32.476] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:07:32.752] INFO: ----------------------------------------------------------------------
[15:07:32.752] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:07:32.752] INFO: ----------------------------------------------------------------------
[15:07:32.752] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:07:32.752] INFO: edge/corner pixel THR is adjusted
[15:07:32.752] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:07:33.712] INFO: Collecting data for 5 seconds...
[15:07:38.729] INFO: Done with hot pixel readout
[15:07:50.367] INFO: PixTest:: pg_setup set to default.
[15:07:50.367] INFO: 3 hot pixels found in step 0
[15:07:51.380] INFO: Collecting data for 5 seconds...
[15:07:56.396] INFO: Done with hot pixel readout
[15:08:08.027] INFO: PixTest:: pg_setup set to default.
[15:08:08.028] INFO: 2 hot pixels found in step 1
[15:08:09.023] INFO: Collecting data for 5 seconds...
[15:08:14.040] INFO: Done with hot pixel readout
[15:08:25.775] INFO: PixTest:: pg_setup set to default.
[15:08:25.776] INFO: 0 hot pixels found in step 2
[15:08:25.813] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:08:25.833] INFO: PixTest::trimHotPixels() done
[15:08:25.833] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat
[15:08:25.852] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C1.dat
[15:08:25.864] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C2.dat
[15:08:25.875] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C3.dat
[15:08:25.880] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C4.dat
[15:08:25.886] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C5.dat
[15:08:25.891] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C6.dat
[15:08:25.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C7.dat
[15:08:25.902] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C8.dat
[15:08:25.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C9.dat
[15:08:25.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C10.dat
[15:08:25.933] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C11.dat
[15:08:25.938] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C12.dat
[15:08:25.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C13.dat
[15:08:25.949] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C14.dat
[15:08:25.955] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[15:08:25.960] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[15:08:25.974] INFO: enter test to run
[15:09:34.239] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:09:34.239] INFO: running: highrate
[15:09:34.244] INFO: ----------------------------------------------------------------------
[15:09:34.244] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:09:34.244] INFO: ----------------------------------------------------------------------
[15:09:34.244] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:09:34.244] INFO: edge/corner pixel THR is adjusted
[15:09:34.244] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:09:35.201] INFO: Collecting data for 1 seconds...
[15:09:36.205] INFO: Done with hot pixel readout
[15:09:40.238] INFO: PixTest:: pg_setup set to default.
[15:09:40.239] INFO: 0 hot pixels found in step 0
[15:09:40.244] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:09:40.336] INFO: PixTest::trimHotPixels() done
[15:09:40.336] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat
[15:09:40.348] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C1.dat
[15:09:40.359] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C2.dat
[15:09:40.365] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C3.dat
[15:09:40.370] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C4.dat
[15:09:40.376] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C5.dat
[15:09:40.381] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C6.dat
[15:09:40.387] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C7.dat
[15:09:40.392] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C8.dat
[15:09:40.397] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C9.dat
[15:09:40.403] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C10.dat
[15:09:40.408] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C11.dat
[15:09:40.414] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C12.dat
[15:09:40.419] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C13.dat
[15:09:40.424] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C14.dat
[15:09:40.430] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[15:09:40.435] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-02_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[15:09:40.450] INFO: enter test to run
[15:10:14.671] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:10:14.671] INFO: running: xray
[15:10:14.672] INFO: ----------------------------------------------------------------------
[15:10:14.672] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:10:14.672] INFO: ----------------------------------------------------------------------
[15:10:15.643] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:10:27.147] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:10:57.077] INFO: Resuming triggers.
[15:11:08.579] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:11:38.544] INFO: Resuming triggers.
[15:11:50.049] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:12:19.480] INFO: Resuming triggers.
[15:12:30.988] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[15:13:00.490] INFO: Resuming triggers.
[15:13:11.995] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:13:41.646] INFO: Resuming triggers.
[15:13:53.152] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:14:22.815] INFO: Resuming triggers.
[15:14:34.322] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:15:04.117] INFO: Resuming triggers.
[15:15:15.629] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[15:15:46.045] INFO: Resuming triggers.
[15:15:54.355] INFO: data taking finished, elapsed time: 100 seconds.
[15:16:16.474] INFO: PixTest:: pg_setup set to default.
[15:16:16.477] INFO: PixTestXray::doPhRun() done
[15:16:16.682] INFO: enter test to run
[15:17:09.892] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:17:09.892] INFO: running: xray
[15:17:09.893] INFO: ----------------------------------------------------------------------
[15:17:09.893] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:17:09.893] INFO: ----------------------------------------------------------------------
[15:17:10.862] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:17:17.583] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:17:48.965] INFO: Resuming triggers.
[15:17:55.685] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:18:26.486] INFO: Resuming triggers.
[15:18:33.208] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:19:03.640] INFO: Resuming triggers.
[15:19:10.359] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[15:19:40.576] INFO: Resuming triggers.
[15:19:47.293] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[15:20:17.455] INFO: Resuming triggers.
[15:20:24.174] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[15:20:54.265] INFO: Resuming triggers.
[15:21:00.981] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:21:31.179] INFO: Resuming triggers.
[15:21:37.895] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[15:22:08.099] INFO: Resuming triggers.
[15:22:14.816] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[15:22:44.958] INFO: Resuming triggers.
[15:22:51.676] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[15:23:21.760] INFO: Resuming triggers.
[15:23:28.479] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[15:23:58.706] INFO: Resuming triggers.
[15:24:05.425] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:24:35.454] INFO: Resuming triggers.
[15:24:42.175] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[15:25:12.991] INFO: Resuming triggers.
[15:25:19.710] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:25:50.582] INFO: Resuming triggers.
[15:25:56.854] INFO: data taking finished, elapsed time: 100 seconds.
[15:26:25.463] INFO: PixTest:: pg_setup set to default.
[15:26:25.466] INFO: PixTestXray::doPhRun() done
[15:26:25.619] INFO: enter test to run
[15:26:52.288] INFO: test: HighRate no parameter change
[15:26:52.288] INFO: running: highrate
[15:26:52.309] INFO: ----------------------------------------------------------------------
[15:26:52.309] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:26:52.309] INFO: ----------------------------------------------------------------------
[15:26:52.475] INFO: Expecting 768 events.
[15:26:53.627] INFO: 768 events read in total (431ms).
[15:26:53.627] INFO: Test took 1298ms.
[15:26:54.430] INFO: Expecting 41600 events.
[15:26:57.610] INFO: 41600 events read in total (2653ms).
[15:26:57.611] INFO: Test took 3920ms.
[15:26:57.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:58.362] INFO: Expecting 41600 events.
[15:27:01.583] INFO: 41600 events read in total (2694ms).
[15:27:01.584] INFO: Test took 3905ms.
[15:27:01.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:02.339] INFO: Expecting 41600 events.
[15:27:05.557] INFO: 41600 events read in total (2691ms).
[15:27:05.557] INFO: Test took 3920ms.
[15:27:05.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:06.316] INFO: Expecting 41600 events.
[15:27:09.534] INFO: 41600 events read in total (2691ms).
[15:27:09.535] INFO: Test took 3925ms.
[15:27:09.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:10.287] INFO: Expecting 41600 events.
[15:27:13.495] INFO: 41600 events read in total (2682ms).
[15:27:13.496] INFO: Test took 3908ms.
[15:27:13.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:14.252] INFO: Expecting 41600 events.
[15:27:17.497] INFO: 41600 events read in total (2719ms).
[15:27:17.498] INFO: Test took 3950ms.
[15:27:17.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:18.256] INFO: Expecting 41600 events.
[15:27:21.551] INFO: 41600 events read in total (2768ms).
[15:27:21.552] INFO: Test took 4001ms.
[15:27:21.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:22.308] INFO: Expecting 41600 events.
[15:27:25.572] INFO: 41600 events read in total (2737ms).
[15:27:25.573] INFO: Test took 3968ms.
[15:27:25.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:26.329] INFO: Expecting 41600 events.
[15:27:29.607] INFO: 41600 events read in total (2751ms).
[15:27:29.608] INFO: Test took 3983ms.
[15:27:29.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:30.367] INFO: Expecting 41600 events.
[15:27:33.623] INFO: 41600 events read in total (2730ms).
[15:27:33.624] INFO: Test took 3963ms.
[15:27:33.659] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:34.381] INFO: Expecting 41600 events.
[15:27:37.660] INFO: 41600 events read in total (2752ms).
[15:27:37.661] INFO: Test took 3985ms.
[15:27:37.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:38.420] INFO: Expecting 41600 events.
[15:27:41.736] INFO: 41600 events read in total (2789ms).
[15:27:41.737] INFO: Test took 4023ms.
[15:27:41.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:42.494] INFO: Expecting 41600 events.
[15:27:45.759] INFO: 41600 events read in total (2738ms).
[15:27:45.760] INFO: Test took 3970ms.
[15:27:45.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:46.519] INFO: Expecting 41600 events.
[15:27:49.740] INFO: 41600 events read in total (2694ms).
[15:27:49.741] INFO: Test took 3929ms.
[15:27:49.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:50.499] INFO: Expecting 41600 events.
[15:27:53.735] INFO: 41600 events read in total (2709ms).
[15:27:53.736] INFO: Test took 3942ms.
[15:27:53.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:54.491] INFO: Expecting 41600 events.
[15:27:57.709] INFO: 41600 events read in total (2691ms).
[15:27:57.710] INFO: Test took 3922ms.
[15:27:57.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:27:58.468] INFO: Expecting 41600 events.
[15:28:01.726] INFO: 41600 events read in total (2732ms).
[15:28:01.727] INFO: Test took 3965ms.
[15:28:01.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:02.480] INFO: Expecting 41600 events.
[15:28:05.725] INFO: 41600 events read in total (2718ms).
[15:28:05.726] INFO: Test took 3946ms.
[15:28:05.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:06.483] INFO: Expecting 41600 events.
[15:28:09.768] INFO: 41600 events read in total (2758ms).
[15:28:09.769] INFO: Test took 3991ms.
[15:28:09.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:10.524] INFO: Expecting 41600 events.
[15:28:13.679] INFO: 41600 events read in total (2628ms).
[15:28:13.680] INFO: Test took 3860ms.
[15:28:13.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:14.075] INFO: enter test to run
[15:28:37.575] INFO: test: HighRate no parameter change
[15:28:37.575] INFO: running: highrate
[15:28:37.576] INFO: ----------------------------------------------------------------------
[15:28:37.577] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:28:37.577] INFO: ----------------------------------------------------------------------
[15:28:38.199] INFO: Expecting 208000 events.
[15:28:50.366] INFO: 208000 events read in total (11640ms).
[15:28:50.368] INFO: Test took 12784ms.
[15:28:50.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:28:50.763] INFO: number of dead pixels (per ROC): 0 6 0 1 0 0 1 0 0 0 0 0 0 0 0 1
[15:28:50.777] INFO: number of red-efficiency pixels: 52 56 73 144 115 162 134 86 95 155 127 102 94 75 32 37
[15:28:50.777] INFO: number of X-ray hits detected: 65206 43286 64658 101433 107635 111223 111351 71533 67132 102064 100987 88503 91703 54327 21083 26489
[15:28:50.777] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:28:50.777] INFO: number of Vcal hits detected: 207947 207601 207927 207804 207885 207834 207814 207913 207904 207844 207872 207897 207905 207925 207968 207914
[15:28:50.777] 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
[15:28:50.777] INFO: Vcal hit overall efficiency (%): 100.0 99.8 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[15:28:50.777] INFO: X-ray hit rate [MHz/cm2]: 19.1 12.7 19.0 29.7 31.5 32.6 32.6 21.0 19.7 29.9 29.6 25.9 26.9 15.9 6.2 7.8
[15:28:50.777] INFO: PixTestHighRate::doXPixelAlive() done
[15:28:50.823] INFO: PixTest:: pg_setup set to default.
[15:28:50.835] INFO: enter test to run
[15:29:15.791] INFO: test: HighRate no parameter change
[15:29:15.791] INFO: running: highrate
[15:29:15.792] INFO: ----------------------------------------------------------------------
[15:29:15.792] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:29:15.792] INFO: ----------------------------------------------------------------------
[15:29:16.410] INFO: Expecting 208000 events.
[15:29:30.216] INFO: 208000 events read in total (13280ms).
[15:29:30.221] INFO: Test took 14418ms.
[15:29:30.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:30.844] INFO: number of dead pixels (per ROC): 0 7 0 1 0 0 1 0 0 0 0 0 0 0 0 1
[15:29:30.844] INFO: number of red-efficiency pixels: 200 145 211 487 411 508 381 262 238 405 371 265 261 181 59 60
[15:29:30.844] INFO: number of X-ray hits detected: 136238 90121 134770 211981 225476 232744 232977 149278 140244 213264 211219 185877 191700 113492 44270 55097
[15:29:30.844] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:29:30.844] INFO: number of Vcal hits detected: 207790 207507 207769 207416 207562 207451 207528 207726 207748 207563 207602 207720 207723 207804 207941 207889
[15:29:30.844] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[15:29:30.844] INFO: Vcal hit overall efficiency (%): 99.9 99.8 99.9 99.7 99.8 99.7 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[15:29:30.844] INFO: X-ray hit rate [MHz/cm2]: 39.9 26.4 39.5 62.1 66.1 68.2 68.3 43.8 41.1 62.5 61.9 54.5 56.2 33.3 13.0 16.1
[15:29:30.844] INFO: PixTestHighRate::doXPixelAlive() done
[15:29:30.891] INFO: PixTest:: pg_setup set to default.
[15:29:30.907] INFO: enter test to run
[15:30:02.103] INFO: test: HighRate no parameter change
[15:30:02.103] INFO: running: highrate
[15:30:02.104] INFO: ----------------------------------------------------------------------
[15:30:02.104] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:30:02.104] INFO: ----------------------------------------------------------------------
[15:30:02.725] INFO: Expecting 208000 events.
[15:30:18.915] INFO: 208000 events read in total (15664ms).
[15:30:18.922] INFO: Test took 16809ms.
[15:30:19.376] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:19.731] INFO: number of dead pixels (per ROC): 0 6 0 1 0 0 1 0 0 0 0 0 0 0 0 1
[15:30:19.732] INFO: number of red-efficiency pixels: 420 269 450 1132 830 1083 897 584 495 1039 768 602 566 435 88 95
[15:30:19.732] INFO: number of X-ray hits detected: 205088 136492 204855 321382 340091 351715 350433 226242 213408 320933 318777 281644 289553 172343 66801 83208
[15:30:19.732] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:30:19.732] INFO: number of Vcal hits detected: 207525 207369 207449 206497 206985 206635 206877 207266 207433 206725 207096 207322 207383 207462 207907 207853
[15:30:19.732] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.4 99.6 99.4 99.5 99.7 99.8 99.4 99.6 99.7 99.7 99.8 100.0 100.0
[15:30:19.732] INFO: Vcal hit overall efficiency (%): 99.8 99.7 99.7 99.3 99.5 99.3 99.5 99.6 99.7 99.4 99.6 99.7 99.7 99.7 100.0 99.9
[15:30:19.732] INFO: X-ray hit rate [MHz/cm2]: 60.1 40.0 60.0 94.2 99.7 103.1 102.7 66.3 62.6 94.1 93.4 82.6 84.9 50.5 19.6 24.4
[15:30:19.732] INFO: PixTestHighRate::doXPixelAlive() done
[15:30:19.779] INFO: PixTest:: pg_setup set to default.
[15:30:19.795] INFO: enter test to run
[15:30:57.878] INFO: test: exit no parameter change
[15:30:58.904] QUIET: Connection to board 30 closed.
[15:30:59.062] INFO: pXar: this is the end, my friend