[13:18:18.160] INFO: *** Welcome to pxar ***
[13:18:18.160] INFO: *** Today: 2016/07/07
[13:18:18.470] INFO: *** Version: v1.9.0-814-g7497
[13:18:18.470] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//dacParameters35_C15.dat
[13:18:18.504] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:18:18.504] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//defaultMaskFile.dat
[13:18:18.505] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C15.dat
[13:18:18.610] INFO: clk: 4
[13:18:18.610] INFO: ctr: 4
[13:18:18.610] INFO: sda: 19
[13:18:18.610] INFO: tin: 9
[13:18:18.610] INFO: level: 15
[13:18:18.610] INFO: triggerdelay: 0
[13:18:18.610] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:18:18.610] INFO: Log level: INFO
[13:18:18.626] QUIET: Connection to board DTB_WREKRL opened.
[13:18:18.629] 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:
------------------------------------------------------
[13:18:18.632] INFO: RPC call hashes of host and DTB match: 398089610
[13:18:20.167] INFO: DUT info:
[13:18:20.167] INFO: The DUT currently contains the following objects:
[13:18:20.167] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:20.167] INFO: TBM Core alpha (0): 7 registers set
[13:18:20.167] INFO: TBM Core beta (1): 7 registers set
[13:18:20.167] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:18:20.167] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.167] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:18:20.570] INFO: enter 'restricted' command line mode
[13:18:20.570] INFO: enter test to run
[13:18:27.218] INFO: test: PixelAlive no parameter change
[13:18:27.218] INFO: running: pixelalive
[13:18:27.227] INFO: ----------------------------------------------------------------------
[13:18:27.227] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:18:27.227] INFO: ----------------------------------------------------------------------
[13:18:27.551] INFO: Expecting 41600 events.
[13:18:31.865] INFO: 41600 events read in total (3596ms).
[13:18:32.035] INFO: Test took 4806ms.
[13:18:32.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:32.315] INFO: PixTestAlive::aliveTest() done
[13:18:32.315] INFO: number of dead pixels (per ROC): 10 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[13:18:32.350] INFO: enter test to run
[13:20:04.449] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:20:04.449] INFO: running: highrate
[13:20:04.449] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:20:04.664] INFO: ----------------------------------------------------------------------
[13:20:04.664] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:20:04.664] INFO: ----------------------------------------------------------------------
[13:20:04.664] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:20:04.664] INFO: edge/corner pixel THR is adjusted
[13:20:04.664] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:20:05.623] INFO: Collecting data for 5 seconds...
[13:20:10.642] INFO: Done with hot pixel readout
[13:20:22.231] INFO: PixTest:: pg_setup set to default.
[13:20:22.232] INFO: 0 hot pixels found in step 0
[13:20:22.244] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:20:22.291] INFO: PixTest::trimHotPixels() done
[13:20:22.291] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C0.dat
[13:20:22.296] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C1.dat
[13:20:22.302] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C2.dat
[13:20:22.308] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C3.dat
[13:20:22.314] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C4.dat
[13:20:22.319] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C5.dat
[13:20:22.325] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C6.dat
[13:20:22.330] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C7.dat
[13:20:22.335] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C8.dat
[13:20:22.341] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C9.dat
[13:20:22.346] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C10.dat
[13:20:22.352] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C11.dat
[13:20:22.357] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C12.dat
[13:20:22.363] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C13.dat
[13:20:22.368] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C14.dat
[13:20:22.374] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C15.dat
[13:20:22.379] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//defaultMaskFile.dat
[13:20:22.391] INFO: enter test to run
[13:21:30.512] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:21:30.513] INFO: running: highrate
[13:21:30.517] INFO: ----------------------------------------------------------------------
[13:21:30.517] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:21:30.517] INFO: ----------------------------------------------------------------------
[13:21:30.517] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:21:30.517] INFO: edge/corner pixel THR is adjusted
[13:21:30.517] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:21:31.475] INFO: Collecting data for 1 seconds...
[13:21:32.479] INFO: Done with hot pixel readout
[13:21:36.403] INFO: PixTest:: pg_setup set to default.
[13:21:36.404] INFO: 0 hot pixels found in step 0
[13:21:36.409] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:21:36.503] INFO: PixTest::trimHotPixels() done
[13:21:36.504] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C0.dat
[13:21:36.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C1.dat
[13:21:36.522] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C2.dat
[13:21:36.527] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C3.dat
[13:21:36.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C4.dat
[13:21:36.538] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C5.dat
[13:21:36.543] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C6.dat
[13:21:36.549] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C7.dat
[13:21:36.554] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C8.dat
[13:21:36.559] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C9.dat
[13:21:36.565] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C10.dat
[13:21:36.570] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C11.dat
[13:21:36.576] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C12.dat
[13:21:36.581] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C13.dat
[13:21:36.586] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C14.dat
[13:21:36.592] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C15.dat
[13:21:36.597] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-45_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//defaultMaskFile.dat
[13:21:36.607] INFO: enter test to run
[13:22:27.816] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:22:27.816] INFO: running: xray
[13:22:27.817] INFO: ----------------------------------------------------------------------
[13:22:27.817] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:22:27.817] INFO: ----------------------------------------------------------------------
[13:22:28.783] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:22:40.355] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:23:09.641] INFO: Resuming triggers.
[13:23:21.211] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:23:50.375] INFO: Resuming triggers.
[13:24:01.943] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:24:31.350] INFO: Resuming triggers.
[13:24:42.922] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:25:12.236] INFO: Resuming triggers.
[13:25:23.806] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:25:53.152] INFO: Resuming triggers.
[13:26:04.727] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:26:34.089] INFO: Resuming triggers.
[13:26:45.662] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[13:27:15.021] INFO: Resuming triggers.
[13:27:26.593] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:27:56.033] INFO: Resuming triggers.
[13:28:03.786] INFO: data taking finished, elapsed time: 100 seconds.
[13:28:23.626] INFO: PixTest:: pg_setup set to default.
[13:28:23.629] INFO: PixTestXray::doPhRun() done
[13:28:23.798] INFO: enter test to run
[13:30:39.629] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:30:39.629] INFO: running: xray
[13:30:39.630] INFO: ----------------------------------------------------------------------
[13:30:39.630] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:30:39.630] INFO: ----------------------------------------------------------------------
[13:30:40.593] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:30:47.493] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:31:18.439] INFO: Resuming triggers.
[13:31:25.344] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:31:56.772] INFO: Resuming triggers.
[13:32:03.672] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:32:35.295] INFO: Resuming triggers.
[13:32:42.195] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:33:12.968] INFO: Resuming triggers.
[13:33:19.868] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:33:49.635] INFO: Resuming triggers.
[13:33:56.534] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:34:26.619] INFO: Resuming triggers.
[13:34:33.520] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[13:35:02.910] INFO: Resuming triggers.
[13:35:09.813] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:35:39.967] INFO: Resuming triggers.
[13:35:46.869] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[13:36:17.085] INFO: Resuming triggers.
[13:36:23.988] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:36:54.168] INFO: Resuming triggers.
[13:37:01.071] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[13:37:31.251] INFO: Resuming triggers.
[13:37:38.154] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:38:08.388] INFO: Resuming triggers.
[13:38:15.291] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:38:45.305] INFO: Resuming triggers.
[13:38:52.206] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[13:39:22.382] INFO: Resuming triggers.
[13:39:26.103] INFO: data taking finished, elapsed time: 100 seconds.
[13:39:42.730] INFO: PixTest:: pg_setup set to default.
[13:39:42.733] INFO: PixTestXray::doPhRun() done
[13:39:42.881] INFO: enter test to run
[13:40:38.425] INFO: test: HighRate no parameter change
[13:40:38.425] INFO: running: highrate
[13:40:38.442] INFO: ----------------------------------------------------------------------
[13:40:38.442] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:40:38.442] INFO: ----------------------------------------------------------------------
[13:40:38.624] INFO: Expecting 768 events.
[13:40:39.758] INFO: 768 events read in total (418ms).
[13:40:39.758] INFO: Test took 1269ms.
[13:40:40.561] INFO: Expecting 41600 events.
[13:40:43.674] INFO: 41600 events read in total (2586ms).
[13:40:43.675] INFO: Test took 3852ms.
[13:40:43.707] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:44.433] INFO: Expecting 41600 events.
[13:40:47.605] INFO: 41600 events read in total (2645ms).
[13:40:47.606] INFO: Test took 3872ms.
[13:40:47.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:48.368] INFO: Expecting 41600 events.
[13:40:51.589] INFO: 41600 events read in total (2694ms).
[13:40:51.590] INFO: Test took 3933ms.
[13:40:51.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:52.350] INFO: Expecting 41600 events.
[13:40:55.581] INFO: 41600 events read in total (2704ms).
[13:40:55.582] INFO: Test took 3941ms.
[13:40:55.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:56.339] INFO: Expecting 41600 events.
[13:40:59.557] INFO: 41600 events read in total (2691ms).
[13:40:59.558] INFO: Test took 3926ms.
[13:40:59.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:00.318] INFO: Expecting 41600 events.
[13:41:03.542] INFO: 41600 events read in total (2697ms).
[13:41:03.543] INFO: Test took 3933ms.
[13:41:03.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:04.303] INFO: Expecting 41600 events.
[13:41:07.548] INFO: 41600 events read in total (2718ms).
[13:41:07.549] INFO: Test took 3955ms.
[13:41:07.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:08.309] INFO: Expecting 41600 events.
[13:41:11.532] INFO: 41600 events read in total (2697ms).
[13:41:11.533] INFO: Test took 3932ms.
[13:41:11.566] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:12.293] INFO: Expecting 41600 events.
[13:41:15.528] INFO: 41600 events read in total (2708ms).
[13:41:15.529] INFO: Test took 3944ms.
[13:41:15.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:16.288] INFO: Expecting 41600 events.
[13:41:19.570] INFO: 41600 events read in total (2755ms).
[13:41:19.571] INFO: Test took 3990ms.
[13:41:19.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:20.326] INFO: Expecting 41600 events.
[13:41:23.659] INFO: 41600 events read in total (2807ms).
[13:41:23.660] INFO: Test took 4038ms.
[13:41:23.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:24.411] INFO: Expecting 41600 events.
[13:41:27.601] INFO: 41600 events read in total (2663ms).
[13:41:27.602] INFO: Test took 3890ms.
[13:41:27.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:28.361] INFO: Expecting 41600 events.
[13:41:31.619] INFO: 41600 events read in total (2731ms).
[13:41:31.620] INFO: Test took 3967ms.
[13:41:31.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:32.376] INFO: Expecting 41600 events.
[13:41:35.628] INFO: 41600 events read in total (2725ms).
[13:41:35.629] INFO: Test took 3956ms.
[13:41:35.663] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:36.386] INFO: Expecting 41600 events.
[13:41:39.640] INFO: 41600 events read in total (2727ms).
[13:41:39.641] INFO: Test took 3960ms.
[13:41:39.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:40.385] INFO: Expecting 41600 events.
[13:41:43.550] INFO: 41600 events read in total (2638ms).
[13:41:43.551] INFO: Test took 3859ms.
[13:41:43.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:44.310] INFO: Expecting 41600 events.
[13:41:47.554] INFO: 41600 events read in total (2717ms).
[13:41:47.555] INFO: Test took 3953ms.
[13:41:47.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:48.309] INFO: Expecting 41600 events.
[13:41:51.557] INFO: 41600 events read in total (2722ms).
[13:41:51.559] INFO: Test took 3952ms.
[13:41:51.595] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:52.317] INFO: Expecting 41600 events.
[13:41:55.454] INFO: 41600 events read in total (2610ms).
[13:41:55.455] INFO: Test took 3842ms.
[13:41:55.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:56.214] INFO: Expecting 41600 events.
[13:41:59.283] INFO: 41600 events read in total (2542ms).
[13:41:59.284] INFO: Test took 3777ms.
[13:41:59.320] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:59.683] INFO: enter test to run
[13:42:05.784] INFO: test: HighRate no parameter change
[13:42:05.784] INFO: running: highrate
[13:42:05.786] INFO: ----------------------------------------------------------------------
[13:42:05.786] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:42:05.786] INFO: ----------------------------------------------------------------------
[13:42:06.409] INFO: Expecting 208000 events.
[13:42:18.281] INFO: 208000 events read in total (11345ms).
[13:42:18.284] INFO: Test took 12480ms.
[13:42:18.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:18.688] INFO: number of dead pixels (per ROC): 9 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[13:42:18.688] INFO: number of red-efficiency pixels: 70 42 74 90 130 143 123 107 102 126 134 100 97 59 25 31
[13:42:18.689] INFO: number of X-ray hits detected: 55273 36612 61186 93742 104332 113550 111919 80349 82443 104952 102348 87481 86427 52170 20275 21665
[13:42:18.689] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:42:18.689] INFO: number of Vcal hits detected: 207434 207958 207924 207910 207866 207857 207869 207892 207894 207870 207861 207897 207902 207940 207974 207920
[13:42:18.689] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 100.0 100.0 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[13:42:18.689] INFO: Vcal hit overall efficiency (%): 99.7 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[13:42:18.689] INFO: X-ray hit rate [MHz/cm2]: 16.2 10.7 17.9 27.5 30.6 33.3 32.8 23.6 24.2 30.8 30.0 25.6 25.3 15.3 5.9 6.4
[13:42:18.689] INFO: PixTestHighRate::doXPixelAlive() done
[13:42:18.734] INFO: PixTest:: pg_setup set to default.
[13:42:18.747] INFO: enter test to run
[13:42:42.600] INFO: test: HighRate no parameter change
[13:42:42.600] INFO: running: highrate
[13:42:42.601] INFO: ----------------------------------------------------------------------
[13:42:42.601] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:42:42.601] INFO: ----------------------------------------------------------------------
[13:42:43.222] INFO: Expecting 208000 events.
[13:42:56.664] INFO: 208000 events read in total (12915ms).
[13:42:56.670] INFO: Test took 14060ms.
[13:42:56.950] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:57.249] INFO: number of dead pixels (per ROC): 9 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[13:42:57.249] INFO: number of red-efficiency pixels: 185 96 241 366 440 384 507 332 302 427 430 318 343 205 40 66
[13:42:57.249] INFO: number of X-ray hits detected: 112748 75607 123385 190246 211869 232051 227011 164266 168585 212822 208483 178087 175857 106784 40764 44144
[13:42:57.249] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:42:57.249] INFO: number of Vcal hits detected: 207302 207900 207744 207607 207537 207577 207429 207638 207683 207527 207524 207661 207629 207787 207958 207882
[13:42:57.249] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[13:42:57.249] INFO: Vcal hit overall efficiency (%): 99.7 100.0 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 99.9
[13:42:57.249] INFO: X-ray hit rate [MHz/cm2]: 33.0 22.2 36.2 55.8 62.1 68.0 66.5 48.1 49.4 62.4 61.1 52.2 51.5 31.3 11.9 12.9
[13:42:57.249] INFO: PixTestHighRate::doXPixelAlive() done
[13:42:57.296] INFO: PixTest:: pg_setup set to default.
[13:42:57.309] INFO: enter test to run
[13:43:35.639] INFO: test: HighRate no parameter change
[13:43:35.640] INFO: running: highrate
[13:43:35.641] INFO: ----------------------------------------------------------------------
[13:43:35.641] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:43:35.641] INFO: ----------------------------------------------------------------------
[13:43:36.265] INFO: Expecting 208000 events.
[13:43:51.252] INFO: 208000 events read in total (14460ms).
[13:43:51.259] INFO: Test took 15609ms.
[13:43:51.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:52.045] INFO: number of dead pixels (per ROC): 10 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[13:43:52.045] INFO: number of red-efficiency pixels: 350 207 523 864 946 925 1169 726 651 1060 1043 719 683 417 91 97
[13:43:52.045] INFO: number of X-ray hits detected: 169550 113784 185883 286443 320332 347672 340527 248216 254274 319607 313630 267269 265660 160728 61883 66220
[13:43:52.045] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:43:52.045] INFO: number of Vcal hits detected: 207104 207770 207362 206971 206821 206880 206448 207094 207228 206697 206712 207126 207217 207515 207907 207851
[13:43:52.045] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.5 99.3 99.6 99.7 99.4 99.4 99.6 99.7 99.8 100.0 100.0
[13:43:52.045] INFO: Vcal hit overall efficiency (%): 99.6 99.9 99.7 99.5 99.4 99.5 99.3 99.6 99.6 99.4 99.4 99.6 99.6 99.8 100.0 99.9
[13:43:52.045] INFO: X-ray hit rate [MHz/cm2]: 49.7 33.4 54.5 84.0 93.9 101.9 99.8 72.8 74.5 93.7 91.9 78.3 77.9 47.1 18.1 19.4
[13:43:52.045] INFO: PixTestHighRate::doXPixelAlive() done
[13:43:52.090] INFO: PixTest:: pg_setup set to default.
[13:43:52.107] INFO: enter test to run
[13:44:15.247] INFO: test: exit no parameter change
[13:44:15.878] QUIET: Connection to board 33 closed.
[13:44:15.997] INFO: pXar: this is the end, my friend