[07:10:23.844] INFO: *** Welcome to pxar ***
[07:10:23.844] INFO: *** Today: 2016/10/13
[07:10:23.863] INFO: *** Version: v1.9.0-818-g96727
[07:10:23.863] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//dacParameters35_C15.dat
[07:10:23.892] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//tbmParameters_C0b.dat
[07:10:23.892] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//defaultMaskFile.dat
[07:10:23.902] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C15.dat
[07:10:23.992] INFO: clk: 4
[07:10:23.992] INFO: ctr: 4
[07:10:23.992] INFO: sda: 19
[07:10:23.992] INFO: tin: 9
[07:10:23.992] INFO: level: 15
[07:10:23.992] INFO: triggerdelay: 0
[07:10:23.992] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[07:10:23.992] INFO: Log level: INFO
[07:10:24.007] QUIET: Connection to board DTB_WREKRL opened.
[07:10:24.010] 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:
------------------------------------------------------
[07:10:24.013] INFO: RPC call hashes of host and DTB match: 398089610
[07:10:25.540] INFO: DUT info:
[07:10:25.540] INFO: The DUT currently contains the following objects:
[07:10:25.540] INFO: 2 TBM Cores tbm08c (2 ON)
[07:10:25.540] INFO: TBM Core alpha (0): 7 registers set
[07:10:25.540] INFO: TBM Core beta (1): 7 registers set
[07:10:25.540] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:10:25.540] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.540] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:10:25.944] INFO: enter 'restricted' command line mode
[07:10:25.944] INFO: enter test to run
[07:10:27.518] INFO: test: PixelAlive no parameter change
[07:10:27.518] INFO: running: pixelalive
[07:10:27.527] INFO: ----------------------------------------------------------------------
[07:10:27.527] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:10:27.527] INFO: ----------------------------------------------------------------------
[07:10:27.849] INFO: Expecting 41600 events.
[07:10:32.177] INFO: 41600 events read in total (3610ms).
[07:10:32.346] INFO: Test took 4815ms.
[07:10:32.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:10:32.623] INFO: PixTestAlive::aliveTest() done
[07:10:32.623] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:10:32.653] INFO: enter test to run
[07:10:57.637] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[07:10:57.637] INFO: running: highrate
[07:10:57.651] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[07:10:57.894] INFO: ----------------------------------------------------------------------
[07:10:57.894] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[07:10:57.894] INFO: ----------------------------------------------------------------------
[07:10:57.894] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[07:10:57.894] INFO: edge/corner pixel THR is adjusted
[07:10:57.894] INFO: PixTestHighRate::trimHotPixels: step 0...
[07:10:58.853] INFO: Collecting data for 5 seconds...
[07:11:03.871] INFO: Done with hot pixel readout
[07:11:15.208] INFO: PixTest:: pg_setup set to default.
[07:11:15.209] INFO: 0 hot pixels found in step 0
[07:11:15.221] INFO: 0 hot pixels could not be trimmed and have been masked.
[07:11:15.250] INFO: PixTest::trimHotPixels() done
[07:11:15.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C0.dat
[07:11:15.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C1.dat
[07:11:15.278] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C2.dat
[07:11:15.283] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C3.dat
[07:11:15.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C4.dat
[07:11:15.293] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C5.dat
[07:11:15.299] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C6.dat
[07:11:15.304] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C7.dat
[07:11:15.309] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C8.dat
[07:11:15.315] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C9.dat
[07:11:15.320] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C10.dat
[07:11:15.325] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C11.dat
[07:11:15.330] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C12.dat
[07:11:15.335] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C13.dat
[07:11:15.341] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C14.dat
[07:11:15.346] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C15.dat
[07:11:15.351] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//defaultMaskFile.dat
[07:11:15.361] INFO: enter test to run
[07:12:04.229] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[07:12:04.229] INFO: running: highrate
[07:12:04.234] INFO: ----------------------------------------------------------------------
[07:12:04.234] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[07:12:04.234] INFO: ----------------------------------------------------------------------
[07:12:04.234] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[07:12:04.234] INFO: edge/corner pixel THR is adjusted
[07:12:04.234] INFO: PixTestHighRate::trimHotPixels: step 0...
[07:12:05.192] INFO: Collecting data for 1 seconds...
[07:12:06.195] INFO: Done with hot pixel readout
[07:12:09.952] INFO: PixTest:: pg_setup set to default.
[07:12:09.953] INFO: 0 hot pixels found in step 0
[07:12:09.958] INFO: 0 hot pixels could not be trimmed and have been masked.
[07:12:10.064] INFO: PixTest::trimHotPixels() done
[07:12:10.065] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C0.dat
[07:12:10.073] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C1.dat
[07:12:10.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C2.dat
[07:12:10.083] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C3.dat
[07:12:10.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C4.dat
[07:12:10.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C5.dat
[07:12:10.099] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C6.dat
[07:12:10.105] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C7.dat
[07:12:10.110] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C8.dat
[07:12:10.115] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C9.dat
[07:12:10.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C10.dat
[07:12:10.125] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C11.dat
[07:12:10.131] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C12.dat
[07:12:10.136] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C13.dat
[07:12:10.141] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C14.dat
[07:12:10.146] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//trimParameters35_C15.dat
[07:12:10.151] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-28_FPIXTest-17C-FNAL-161010-1222-150V_2016-10-10_12h22m_1476120169/000_FPIXTest_p17//defaultMaskFile.dat
[07:12:10.161] INFO: enter test to run
[07:12:29.701] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[07:12:29.701] INFO: running: xray
[07:12:29.702] INFO: ----------------------------------------------------------------------
[07:12:29.702] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[07:12:29.702] INFO: ----------------------------------------------------------------------
[07:12:30.676] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[07:12:42.358] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[07:13:12.168] INFO: Resuming triggers.
[07:13:23.851] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[07:13:53.279] INFO: Resuming triggers.
[07:14:04.963] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[07:14:34.843] INFO: Resuming triggers.
[07:14:46.527] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[07:15:14.648] INFO: Resuming triggers.
[07:15:26.330] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[07:15:55.677] INFO: Resuming triggers.
[07:16:07.363] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[07:16:36.919] INFO: Resuming triggers.
[07:16:48.605] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[07:17:17.600] INFO: Resuming triggers.
[07:17:29.286] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[07:17:58.707] INFO: Resuming triggers.
[07:18:05.599] INFO: data taking finished, elapsed time: 100 seconds.
[07:18:23.304] INFO: PixTest:: pg_setup set to default.
[07:18:23.307] INFO: PixTestXray::doPhRun() done
[07:18:23.557] INFO: enter test to run
[07:18:40.234] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[07:18:40.234] INFO: running: xray
[07:18:40.235] INFO: ----------------------------------------------------------------------
[07:18:40.235] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[07:18:40.236] INFO: ----------------------------------------------------------------------
[07:18:41.198] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[07:18:48.475] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[07:19:19.271] INFO: Resuming triggers.
[07:19:26.549] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[07:19:58.007] INFO: Resuming triggers.
[07:20:05.286] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[07:20:36.561] INFO: Resuming triggers.
[07:20:43.841] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[07:21:14.973] INFO: Resuming triggers.
[07:21:22.252] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[07:21:53.461] INFO: Resuming triggers.
[07:22:00.742] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[07:22:32.461] INFO: Resuming triggers.
[07:22:39.741] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[07:23:10.217] INFO: Resuming triggers.
[07:23:17.500] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[07:23:47.632] INFO: Resuming triggers.
[07:23:54.909] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[07:24:25.149] INFO: Resuming triggers.
[07:24:32.430] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[07:25:02.693] INFO: Resuming triggers.
[07:25:09.969] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[07:25:40.180] INFO: Resuming triggers.
[07:25:47.459] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[07:26:17.661] INFO: Resuming triggers.
[07:26:24.937] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[07:26:55.033] INFO: Resuming triggers.
[07:27:00.770] INFO: data taking finished, elapsed time: 100 seconds.
[07:27:24.775] INFO: PixTest:: pg_setup set to default.
[07:27:24.778] INFO: PixTestXray::doPhRun() done
[07:27:24.949] INFO: enter test to run
[07:27:48.271] INFO: test: HighRate no parameter change
[07:27:48.271] INFO: running: highrate
[07:27:48.300] INFO: ----------------------------------------------------------------------
[07:27:48.300] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[07:27:48.300] INFO: ----------------------------------------------------------------------
[07:27:48.449] INFO: Expecting 768 events.
[07:27:49.583] INFO: 768 events read in total (419ms).
[07:27:49.583] INFO: Test took 1269ms.
[07:27:50.387] INFO: Expecting 41600 events.
[07:27:53.444] INFO: 41600 events read in total (2531ms).
[07:27:53.445] INFO: Test took 3809ms.
[07:27:53.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:54.213] INFO: Expecting 41600 events.
[07:27:57.356] INFO: 41600 events read in total (2616ms).
[07:27:57.357] INFO: Test took 3867ms.
[07:27:57.386] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:58.123] INFO: Expecting 41600 events.
[07:28:01.301] INFO: 41600 events read in total (2651ms).
[07:28:01.302] INFO: Test took 3901ms.
[07:28:01.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:02.063] INFO: Expecting 41600 events.
[07:28:05.238] INFO: 41600 events read in total (2648ms).
[07:28:05.239] INFO: Test took 3891ms.
[07:28:05.268] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:05.001] INFO: Expecting 41600 events.
[07:28:09.188] INFO: 41600 events read in total (2661ms).
[07:28:09.189] INFO: Test took 3905ms.
[07:28:09.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:09.952] INFO: Expecting 41600 events.
[07:28:13.126] INFO: 41600 events read in total (2647ms).
[07:28:13.127] INFO: Test took 3892ms.
[07:28:13.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:13.892] INFO: Expecting 41600 events.
[07:28:17.072] INFO: 41600 events read in total (2653ms).
[07:28:17.073] INFO: Test took 3901ms.
[07:28:17.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:17.837] INFO: Expecting 41600 events.
[07:28:21.043] INFO: 41600 events read in total (2679ms).
[07:28:21.044] INFO: Test took 3925ms.
[07:28:21.073] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:21.804] INFO: Expecting 41600 events.
[07:28:24.988] INFO: 41600 events read in total (2657ms).
[07:28:24.989] INFO: Test took 3899ms.
[07:28:25.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:25.746] INFO: Expecting 41600 events.
[07:28:28.926] INFO: 41600 events read in total (2653ms).
[07:28:28.927] INFO: Test took 3894ms.
[07:28:28.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:29.690] INFO: Expecting 41600 events.
[07:28:32.875] INFO: 41600 events read in total (2658ms).
[07:28:32.876] INFO: Test took 3905ms.
[07:28:32.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:33.638] INFO: Expecting 41600 events.
[07:28:36.835] INFO: 41600 events read in total (2670ms).
[07:28:36.836] INFO: Test took 3916ms.
[07:28:36.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:37.598] INFO: Expecting 41600 events.
[07:28:40.783] INFO: 41600 events read in total (2658ms).
[07:28:40.784] INFO: Test took 3902ms.
[07:28:40.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:41.545] INFO: Expecting 41600 events.
[07:28:44.743] INFO: 41600 events read in total (2671ms).
[07:28:44.744] INFO: Test took 3915ms.
[07:28:44.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:45.507] INFO: Expecting 41600 events.
[07:28:48.682] INFO: 41600 events read in total (2648ms).
[07:28:48.683] INFO: Test took 3894ms.
[07:28:48.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:49.448] INFO: Expecting 41600 events.
[07:28:52.626] INFO: 41600 events read in total (2651ms).
[07:28:52.627] INFO: Test took 3899ms.
[07:28:52.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:53.387] INFO: Expecting 41600 events.
[07:28:56.563] INFO: 41600 events read in total (2649ms).
[07:28:56.564] INFO: Test took 3893ms.
[07:28:56.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:57.327] INFO: Expecting 41600 events.
[07:29:00.523] INFO: 41600 events read in total (2669ms).
[07:29:00.524] INFO: Test took 3915ms.
[07:29:00.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:01.287] INFO: Expecting 41600 events.
[07:29:04.457] INFO: 41600 events read in total (2643ms).
[07:29:04.458] INFO: Test took 3890ms.
[07:29:04.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:05.218] INFO: Expecting 41600 events.
[07:29:08.225] INFO: 41600 events read in total (2481ms).
[07:29:08.226] INFO: Test took 3723ms.
[07:29:08.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:08.629] INFO: enter test to run
[07:29:42.654] INFO: test: HighRate no parameter change
[07:29:42.654] INFO: running: highrate
[07:29:42.655] INFO: ----------------------------------------------------------------------
[07:29:42.655] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:29:42.655] INFO: ----------------------------------------------------------------------
[07:29:43.271] INFO: Expecting 208000 events.
[07:29:55.062] INFO: 208000 events read in total (11264ms).
[07:29:55.065] INFO: Test took 12400ms.
[07:29:55.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:55.430] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 18 0 0 0 0 0 0
[07:29:55.430] INFO: number of red-efficiency pixels: 57 38 70 78 113 114 108 70 71 127 97 65 101 56 19 21
[07:29:55.430] INFO: number of X-ray hits detected: 49321 33675 52201 80695 87978 89418 90302 64414 64169 79929 77684 68016 70747 42613 17247 21228
[07:29:55.430] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:29:55.430] INFO: number of Vcal hits detected: 207942 207962 207930 207918 207886 207882 207891 207928 207929 206483 207902 207930 207896 207943 207981 207978
[07:29:55.430] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 99.7 100.0 100.0 100.0 100.0 100.0 100.0
[07:29:55.430] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.3 100.0 100.0 100.0 100.0 100.0 100.0
[07:29:55.430] INFO: X-ray hit rate [MHz/cm2]: 14.5 9.9 15.3 23.7 25.8 26.2 26.5 18.9 18.8 23.4 22.8 19.9 20.7 12.5 5.1 6.2
[07:29:55.430] INFO: PixTestHighRate::doXPixelAlive() done
[07:29:55.476] INFO: PixTest:: pg_setup set to default.
[07:29:55.492] INFO: enter test to run
[07:30:11.566] INFO: test: HighRate no parameter change
[07:30:11.566] INFO: running: highrate
[07:30:11.567] INFO: ----------------------------------------------------------------------
[07:30:11.567] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:30:11.567] INFO: ----------------------------------------------------------------------
[07:30:12.185] INFO: Expecting 208000 events.
[07:30:25.496] INFO: 208000 events read in total (12784ms).
[07:30:25.501] INFO: Test took 13925ms.
[07:30:25.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:30:26.066] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 6 0 0 0 0 0 0
[07:30:26.066] INFO: number of red-efficiency pixels: 197 114 217 321 448 428 456 282 246 406 271 236 315 174 46 59
[07:30:26.067] INFO: number of X-ray hits detected: 115884 79226 122966 187105 202630 206454 209778 149237 149473 184844 182187 156603 164864 99325 40172 49945
[07:30:26.067] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:30:26.067] INFO: number of Vcal hits detected: 207792 207881 207761 207652 207517 207528 207502 207699 207744 200959 207716 207746 207666 207820 207953 207940
[07:30:26.067] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 96.6 99.9 99.9 99.9 99.9 100.0 100.0
[07:30:26.067] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 96.6 99.9 99.9 99.8 99.9 100.0 100.0
[07:30:26.067] INFO: X-ray hit rate [MHz/cm2]: 34.0 23.2 36.0 54.8 59.4 60.5 61.5 43.7 43.8 54.2 53.4 45.9 48.3 29.1 11.8 14.6
[07:30:26.067] INFO: PixTestHighRate::doXPixelAlive() done
[07:30:26.116] INFO: PixTest:: pg_setup set to default.
[07:30:26.130] INFO: enter test to run
[07:30:41.541] INFO: test: HighRate no parameter change
[07:30:41.542] INFO: running: highrate
[07:30:41.543] INFO: ----------------------------------------------------------------------
[07:30:41.543] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:30:41.543] INFO: ----------------------------------------------------------------------
[07:30:42.160] INFO: Expecting 208000 events.
[07:30:57.400] INFO: 208000 events read in total (14713ms).
[07:30:57.407] INFO: Test took 15854ms.
[07:30:57.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[07:30:58.148] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 10 0 0 0 0 0 0
[07:30:58.148] INFO: number of red-efficiency pixels: 368 232 479 734 1021 1026 1061 623 571 785 624 496 704 307 86 106
[07:30:58.148] INFO: number of X-ray hits detected: 174227 119807 185207 283191 307382 313170 317423 225753 226904 278487 275037 238925 249632 151334 61162 75767
[07:30:58.148] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:30:58.148] INFO: number of Vcal hits detected: 207577 207738 207396 207159 206754 206716 206659 207263 207351 199715 207300 207430 207180 207657 207912 207889
[07:30:58.148] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.4 99.4 99.7 99.7 96.2 99.7 99.8 99.6 99.9 100.0 100.0
[07:30:58.148] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.4 99.4 99.6 99.7 96.0 99.7 99.7 99.6 99.8 100.0 99.9
[07:30:58.148] INFO: X-ray hit rate [MHz/cm2]: 51.1 35.1 54.3 83.0 90.1 91.8 93.0 66.2 66.5 81.6 80.6 70.0 73.2 44.4 17.9 22.2
[07:30:58.148] INFO: PixTestHighRate::doXPixelAlive() done
[07:30:58.193] INFO: PixTest:: pg_setup set to default.
[07:30:58.208] INFO: enter test to run
[07:31:12.125] INFO: test: exit no parameter change
[07:31:12.585] QUIET: Connection to board 33 closed.
[07:31:12.640] INFO: pXar: this is the end, my friend