[09:47:57.385] INFO: *** Welcome to pxar ***
[09:47:57.385] INFO: *** Today: 2016/04/19
[09:47:57.406] INFO: *** Version: v1.9.0-796-gef167-dirty
[09:47:57.406] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//dacParameters35_C15.dat
[09:47:57.434] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//tbmParameters_C0b.dat
[09:47:57.434] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//defaultMaskFile.dat
[09:47:57.443] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C15.dat
[09:47:57.553] INFO: clk: 4
[09:47:57.553] INFO: ctr: 4
[09:47:57.553] INFO: sda: 19
[09:47:57.553] INFO: tin: 9
[09:47:57.553] INFO: level: 15
[09:47:57.553] INFO: triggerdelay: 0
[09:47:57.553] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[09:47:57.553] INFO: Log level: INFO
[09:47:57.572] QUIET: Connection to board DTB_WREKRL opened.
[09:47:57.575] 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:
------------------------------------------------------
[09:47:57.579] INFO: RPC call hashes of host and DTB match: 398089610
[09:47:59.108] INFO: DUT info:
[09:47:59.108] INFO: The DUT currently contains the following objects:
[09:47:59.108] INFO: 2 TBM Cores tbm08c (2 ON)
[09:47:59.108] INFO: TBM Core alpha (0): 7 registers set
[09:47:59.108] INFO: TBM Core beta (1): 7 registers set
[09:47:59.108] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:47:59.108] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.108] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.109] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.109] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:47:59.512] INFO: enter 'restricted' command line mode
[09:47:59.512] INFO: enter test to run
[09:48:19.433] INFO: test: PixelAlive no parameter change
[09:48:19.433] INFO: running: pixelalive
[09:48:19.442] INFO: ----------------------------------------------------------------------
[09:48:19.442] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:48:19.442] INFO: ----------------------------------------------------------------------
[09:48:19.762] INFO: Expecting 41600 events.
[09:48:24.081] INFO: 41600 events read in total (3601ms).
[09:48:24.250] INFO: Test took 4804ms.
[09:48:24.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:24.522] INFO: PixTestAlive::aliveTest() done
[09:48:24.522] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:48:24.554] INFO: enter test to run
[09:49:18.888] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[09:49:18.889] INFO: running: highrate
[09:49:18.889] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[09:49:19.118] INFO: ----------------------------------------------------------------------
[09:49:19.118] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[09:49:19.118] INFO: ----------------------------------------------------------------------
[09:49:19.118] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[09:49:19.118] INFO: edge/corner pixel THR is adjusted
[09:49:19.119] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:49:20.077] INFO: Collecting data for 5 seconds...
[09:49:25.093] INFO: Done with hot pixel readout
[09:49:37.169] INFO: PixTest:: pg_setup set to default.
[09:49:37.170] INFO: 3 hot pixels found in step 0
[09:49:38.161] INFO: Collecting data for 5 seconds...
[09:49:43.178] INFO: Done with hot pixel readout
[09:49:55.215] INFO: PixTest:: pg_setup set to default.
[09:49:55.216] INFO: 5 hot pixels found in step 1
[09:49:56.211] INFO: Collecting data for 5 seconds...
[09:50:01.229] INFO: Done with hot pixel readout
[09:50:13.281] INFO: PixTest:: pg_setup set to default.

[09:50:13.282] INFO: 1 hot pixels found in step 2
[09:50:13.320] INFO: 1 hot pixels could not be trimmed and have been masked.
[09:50:13.323] INFO: PixTest::trimHotPixels() done
[09:50:13.323] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C0.dat
[09:50:13.329] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C1.dat
[09:50:13.335] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C2.dat
[09:50:13.342] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C3.dat
[09:50:13.347] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C4.dat
[09:50:13.352] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C5.dat
[09:50:13.357] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C6.dat
[09:50:13.362] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C7.dat
[09:50:13.368] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C8.dat
[09:50:13.374] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C9.dat
[09:50:13.379] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C10.dat
[09:50:13.384] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C11.dat
[09:50:13.389] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C12.dat
[09:50:13.394] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C13.dat
[09:50:13.400] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C14.dat
[09:50:13.406] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C15.dat
[09:50:13.411] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//defaultMaskFile.dat
[09:50:13.421] INFO: enter test to run
[09:51:37.792] INFO: test: \HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[09:51:37.792] INFO: running: \highrate
[09:51:37.813] INFO: command ->\highrate<- not known, ignored
[09:51:37.813] INFO: enter test to run
[09:51:53.527] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[09:51:53.527] INFO: running: highrate
[09:51:53.532] INFO: ----------------------------------------------------------------------
[09:51:53.532] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[09:51:53.532] INFO: ----------------------------------------------------------------------
[09:51:53.532] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[09:51:53.532] INFO: edge/corner pixel THR is adjusted
[09:51:53.532] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:51:54.490] INFO: Collecting data for 1 seconds...
[09:51:55.494] INFO: Done with hot pixel readout
[09:51:59.684] INFO: PixTest:: pg_setup set to default.
[09:51:59.685] INFO: 0 hot pixels found in step 0
[09:51:59.690] INFO: 0 hot pixels could not be trimmed and have been masked.
[09:51:59.774] INFO: PixTest::trimHotPixels() done
[09:51:59.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C0.dat
[09:51:59.782] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C1.dat
[09:51:59.792] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C2.dat
[09:51:59.797] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C3.dat
[09:51:59.802] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C4.dat
[09:51:59.807] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C5.dat
[09:51:59.812] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C6.dat
[09:51:59.818] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C7.dat
[09:51:59.823] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C8.dat
[09:51:59.828] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C9.dat
[09:51:59.833] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C10.dat
[09:51:59.839] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C11.dat
[09:51:59.844] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C12.dat
[09:51:59.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C13.dat
[09:51:59.854] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C14.dat
[09:51:59.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//trimParameters35_C15.dat
[09:51:59.865] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-10_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17//defaultMaskFile.dat
[09:51:59.874] INFO: enter test to run
[09:52:24.383] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[09:52:24.383] INFO: running: xray
[09:52:24.384] INFO: ----------------------------------------------------------------------
[09:52:24.384] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:52:24.384] INFO: ----------------------------------------------------------------------
[09:52:25.347] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:52:36.413] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[09:53:06.029] INFO: Resuming triggers.
[09:53:17.100] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[09:53:45.898] INFO: Resuming triggers.
[09:53:56.967] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[09:54:26.537] INFO: Resuming triggers.
[09:54:37.611] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[09:55:07.240] INFO: Resuming triggers.
[09:55:18.310] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[09:55:47.927] INFO: Resuming triggers.
[09:55:58.999] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[09:56:28.621] INFO: Resuming triggers.
[09:56:39.696] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[09:57:09.287] INFO: Resuming triggers.
[09:57:20.362] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[09:57:49.873] INFO: Resuming triggers.
[09:58:00.949] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[09:58:30.538] INFO: Resuming triggers.
[09:58:31.214] INFO: data taking finished, elapsed time: 100 seconds.
[09:58:33.395] INFO: PixTest:: pg_setup set to default.
[09:58:33.398] INFO: PixTestXray::doPhRun() done
[09:58:33.538] INFO: enter test to run
[10:00:18.740] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:00:18.740] INFO: running: xray
[10:00:18.742] INFO: ----------------------------------------------------------------------
[10:00:18.742] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:00:18.742] INFO: ----------------------------------------------------------------------
[10:00:19.713] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:00:26.066] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:00:56.270] INFO: Resuming triggers.
[10:01:02.621] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:01:32.825] INFO: Resuming triggers.
[10:01:39.180] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[10:02:09.381] INFO: Resuming triggers.
[10:02:15.729] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[10:02:45.982] INFO: Resuming triggers.
[10:02:52.336] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[10:03:21.546] INFO: Resuming triggers.
[10:03:27.895] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[10:03:58.057] INFO: Resuming triggers.
[10:04:04.405] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[10:04:34.889] INFO: Resuming triggers.
[10:04:41.239] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[10:05:11.479] INFO: Resuming triggers.
[10:05:17.835] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:05:48.084] INFO: Resuming triggers.
[10:05:54.436] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[10:06:24.591] INFO: Resuming triggers.
[10:06:30.947] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:07:01.210] INFO: Resuming triggers.
[10:07:07.565] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[10:07:37.744] INFO: Resuming triggers.
[10:07:44.094] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:08:14.297] INFO: Resuming triggers.
[10:08:20.650] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[10:08:50.893] INFO: Resuming triggers.
[10:08:57.250] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[10:09:27.556] INFO: Resuming triggers.
[10:09:32.600] INFO: data taking finished, elapsed time: 100 seconds.
[10:09:56.791] INFO: PixTest:: pg_setup set to default.
[10:09:56.795] INFO: PixTestXray::doPhRun() done
[10:09:56.944] INFO: enter test to run
[10:11:13.239] INFO: test: HighRate no parameter change
[10:11:13.239] INFO: running: highrate
[10:11:13.240] INFO: ----------------------------------------------------------------------
[10:11:13.240] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:11:13.240] INFO: ----------------------------------------------------------------------
[10:11:13.381] INFO: Expecting 768 events.
[10:11:14.515] INFO: 768 events read in total (418ms).
[10:11:14.515] INFO: Test took 1269ms.
[10:11:15.318] INFO: Expecting 41600 events.
[10:11:18.445] INFO: 41600 events read in total (2600ms).
[10:11:18.446] INFO: Test took 3922ms.
[10:11:18.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:19.202] INFO: Expecting 41600 events.
[10:11:22.436] INFO: 41600 events read in total (2707ms).
[10:11:22.437] INFO: Test took 3937ms.
[10:11:22.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:23.196] INFO: Expecting 41600 events.
[10:11:26.464] INFO: 41600 events read in total (2741ms).
[10:11:26.465] INFO: Test took 3971ms.
[10:11:26.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:27.218] INFO: Expecting 41600 events.
[10:11:30.476] INFO: 41600 events read in total (2731ms).
[10:11:30.477] INFO: Test took 3956ms.
[10:11:30.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:31.234] INFO: Expecting 41600 events.
[10:11:34.495] INFO: 41600 events read in total (2734ms).
[10:11:34.496] INFO: Test took 3963ms.
[10:11:34.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:35.253] INFO: Expecting 41600 events.
[10:11:38.525] INFO: 41600 events read in total (2745ms).
[10:11:38.526] INFO: Test took 3975ms.
[10:11:38.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:39.282] INFO: Expecting 41600 events.
[10:11:42.557] INFO: 41600 events read in total (2749ms).
[10:11:42.558] INFO: Test took 3976ms.
[10:11:42.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:43.311] INFO: Expecting 41600 events.
[10:11:46.578] INFO: 41600 events read in total (2740ms).
[10:11:46.579] INFO: Test took 3963ms.
[10:11:46.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:47.334] INFO: Expecting 41600 events.
[10:11:50.609] INFO: 41600 events read in total (2748ms).
[10:11:50.610] INFO: Test took 3975ms.
[10:11:50.646] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:51.365] INFO: Expecting 41600 events.
[10:11:54.644] INFO: 41600 events read in total (2752ms).
[10:11:54.645] INFO: Test took 3979ms.
[10:11:54.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:55.403] INFO: Expecting 41600 events.
[10:11:58.663] INFO: 41600 events read in total (2733ms).
[10:11:58.665] INFO: Test took 3964ms.
[10:11:58.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:59.422] INFO: Expecting 41600 events.
[10:12:02.673] INFO: 41600 events read in total (2724ms).
[10:12:02.674] INFO: Test took 3953ms.
[10:12:02.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:03.430] INFO: Expecting 41600 events.
[10:12:06.713] INFO: 41600 events read in total (2756ms).
[10:12:06.714] INFO: Test took 3983ms.
[10:12:06.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:07.470] INFO: Expecting 41600 events.
[10:12:10.734] INFO: 41600 events read in total (2737ms).
[10:12:10.735] INFO: Test took 3964ms.
[10:12:10.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:11.492] INFO: Expecting 41600 events.
[10:12:14.755] INFO: 41600 events read in total (2736ms).
[10:12:14.757] INFO: Test took 3967ms.
[10:12:14.799] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:15.513] INFO: Expecting 41600 events.
[10:12:18.797] INFO: 41600 events read in total (2757ms).
[10:12:18.798] INFO: Test took 3979ms.
[10:12:18.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:19.554] INFO: Expecting 41600 events.
[10:12:22.821] INFO: 41600 events read in total (2740ms).
[10:12:22.822] INFO: Test took 3967ms.
[10:12:22.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:23.580] INFO: Expecting 41600 events.
[10:12:26.851] INFO: 41600 events read in total (2744ms).
[10:12:26.852] INFO: Test took 3975ms.
[10:12:26.889] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:27.609] INFO: Expecting 41600 events.
[10:12:30.859] INFO: 41600 events read in total (2723ms).
[10:12:30.860] INFO: Test took 3953ms.
[10:12:30.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:31.616] INFO: Expecting 41600 events.
[10:12:34.753] INFO: 41600 events read in total (2610ms).
[10:12:34.754] INFO: Test took 3838ms.
[10:12:34.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:35.142] INFO: enter test to run
[10:12:54.302] INFO: test: HighRate no parameter change
[10:12:54.302] INFO: running: highrate
[10:12:54.303] INFO: ----------------------------------------------------------------------
[10:12:54.303] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:12:54.303] INFO: ----------------------------------------------------------------------
[10:12:54.914] INFO: Expecting 208000 events.
[10:13:06.912] INFO: 208000 events read in total (11471ms).
[10:13:06.915] INFO: Test took 12604ms.
[10:13:07.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:07.331] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:13:07.331] INFO: number of red-efficiency pixels: 80 58 108 163 166 168 163 141 108 141 120 120 108 80 45 24
[10:13:07.331] INFO: number of X-ray hits detected: 66931 49035 73106 118606 125531 122804 128017 92880 88097 106352 104773 90911 92666 56845 27079 30379
[10:13:07.331] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:13:07.331] INFO: number of Vcal hits detected: 207916 207942 207889 207834 207820 207825 207833 207854 207888 207854 207877 207879 207891 207912 207954 207976
[10:13:07.331] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[10:13:07.331] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:13:07.331] INFO: X-ray hit rate [MHz/cm2]: 19.6 14.4 21.4 34.8 36.8 36.0 37.5 27.2 25.8 31.2 30.7 26.6 27.2 16.7 7.9 8.9
[10:13:07.331] INFO: PixTestHighRate::doXPixelAlive() done
[10:13:07.377] INFO: PixTest:: pg_setup set to default.
[10:13:07.389] INFO: enter test to run
[10:13:34.606] INFO: test: HighRate no parameter change
[10:13:34.606] INFO: running: highrate
[10:13:34.607] INFO: ----------------------------------------------------------------------
[10:13:34.607] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:13:34.607] INFO: ----------------------------------------------------------------------
[10:13:35.220] INFO: Expecting 208000 events.
[10:13:49.119] INFO: 208000 events read in total (13373ms).
[10:13:49.125] INFO: Test took 14509ms.
[10:13:49.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:49.757] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:13:49.757] INFO: number of red-efficiency pixels: 209 146 343 594 502 624 453 439 253 405 345 325 309 201 50 79
[10:13:49.757] INFO: number of X-ray hits detected: 135666 100208 148180 240066 254988 250449 260937 188658 180197 216700 212221 184617 189831 116384 55170 61765
[10:13:49.757] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:13:49.757] INFO: number of Vcal hits detected: 207768 207849 207607 207341 207446 207284 207502 207507 207735 207568 207628 207652 207676 207772 207949 207920
[10:13:49.757] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:13:49.757] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[10:13:49.757] INFO: X-ray hit rate [MHz/cm2]: 39.8 29.4 43.4 70.4 74.7 73.4 76.5 55.3 52.8 63.5 62.2 54.1 55.6 34.1 16.2 18.1
[10:13:49.757] INFO: PixTestHighRate::doXPixelAlive() done
[10:13:49.806] INFO: PixTest:: pg_setup set to default.
[10:13:49.821] INFO: enter test to run
[10:14:44.949] INFO: test: HighRate no parameter change
[10:14:44.949] INFO: running: highrate
[10:14:44.950] INFO: ----------------------------------------------------------------------
[10:14:44.950] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:14:44.951] INFO: ----------------------------------------------------------------------
[10:14:45.566] INFO: Expecting 208000 events.
[10:15:01.544] INFO: 208000 events read in total (15451ms).
[10:15:01.552] INFO: Test took 16593ms.
[10:15:02.062] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:02.423] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:15:02.423] INFO: number of red-efficiency pixels: 469 339 749 1274 1069 1442 1028 893 529 954 766 596 651 400 119 122
[10:15:02.423] INFO: number of X-ray hits detected: 204129 150375 223562 361905 382222 375495 390362 282416 270764 325448 319744 278080 284222 174195 82548 94008
[10:15:02.423] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:15:02.423] INFO: number of Vcal hits detected: 207455 207629 206916 206319 206637 206063 206748 206829 207433 206852 207108 207307 207255 207524 207873 207873
[10:15:02.423] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.5 99.3 99.4 99.2 99.4 99.5 99.7 99.5 99.6 99.7 99.7 99.8 99.9 99.9
[10:15:02.423] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.5 99.2 99.3 99.1 99.4 99.4 99.7 99.4 99.6 99.7 99.6 99.8 99.9 99.9
[10:15:02.423] INFO: X-ray hit rate [MHz/cm2]: 59.8 44.1 65.5 106.1 112.0 110.1 114.4 82.8 79.4 95.4 93.7 81.5 83.3 51.1 24.2 27.6
[10:15:02.423] INFO: PixTestHighRate::doXPixelAlive() done
[10:15:02.469] INFO: PixTest:: pg_setup set to default.
[10:15:02.483] INFO: enter test to run
[10:15:09.813] INFO: test: exit no parameter change
[10:15:10.125] QUIET: Connection to board 33 closed.
[10:15:10.126] INFO: pXar: this is the end, my friend