[10:16:17.524] INFO: *** Welcome to pxar ***
[10:16:17.524] INFO: *** Today: 2016/08/22
[10:16:17.563] INFO: *** Version: v1.9.0-818-g96727
[10:16:17.563] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//dacParameters35_C15.dat
[10:16:17.603] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:16:17.603] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//defaultMaskFile.dat
[10:16:17.610] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C15.dat
[10:16:17.697] INFO: clk: 4
[10:16:17.697] INFO: ctr: 4
[10:16:17.697] INFO: sda: 19
[10:16:17.697] INFO: tin: 9
[10:16:17.697] INFO: level: 15
[10:16:17.697] INFO: triggerdelay: 0
[10:16:17.697] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:16:17.697] INFO: Log level: INFO
[10:16:17.715] QUIET: Connection to board DTB_WREKRL opened.
[10:16:17.718] 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:
------------------------------------------------------
[10:16:17.721] INFO: RPC call hashes of host and DTB match: 398089610
[10:16:19.249] INFO: DUT info:
[10:16:19.250] INFO: The DUT currently contains the following objects:
[10:16:19.250] INFO: 2 TBM Cores tbm08c (2 ON)
[10:16:19.250] INFO: TBM Core alpha (0): 7 registers set
[10:16:19.250] INFO: TBM Core beta (1): 7 registers set
[10:16:19.250] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:16:19.250] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.250] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:19.654] INFO: enter 'restricted' command line mode
[10:16:19.654] INFO: enter test to run
[10:16:24.118] INFO: test: PixelAlive no parameter change
[10:16:24.118] INFO: running: pixelalive
[10:16:24.127] INFO: ----------------------------------------------------------------------
[10:16:24.127] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:16:24.127] INFO: ----------------------------------------------------------------------
[10:16:24.447] INFO: Expecting 41600 events.
[10:16:28.803] INFO: 41600 events read in total (3637ms).
[10:16:28.967] INFO: Test took 4837ms.
[10:16:28.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:16:29.260] INFO: PixTestAlive::aliveTest() done
[10:16:29.260] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 72 6 0 0 0 0 0 0
[10:16:29.290] INFO: enter test to run
[10:16:53.270] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:16:53.270] INFO: running: highrate
[10:16:53.298] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:16:53.575] INFO: ----------------------------------------------------------------------
[10:16:53.575] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:16:53.575] INFO: ----------------------------------------------------------------------
[10:16:53.575] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:16:53.575] INFO: edge/corner pixel THR is adjusted
[10:16:53.575] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:16:54.672] INFO: Collecting data for 5 seconds...
[10:16:59.690] INFO: Done with hot pixel readout
[10:17:10.978] INFO: PixTest:: pg_setup set to default.
[10:17:10.979] INFO: 21 hot pixels found in step 0
[10:17:11.965] INFO: Collecting data for 5 seconds...
[10:17:16.981] INFO: Done with hot pixel readout
[10:17:26.043] INFO: PixTest:: pg_setup set to default.
[10:17:26.043] INFO: 14 hot pixels found in step 1
[10:17:27.035] INFO: Collecting data for 5 seconds...
[10:17:32.048] INFO: Done with hot pixel readout
[10:17:43.149] INFO: PixTest:: pg_setup set to default.
[10:17:43.150] INFO: 10 hot pixels found in step 2
[10:17:44.140] INFO: Collecting data for 5 seconds...
[10:17:49.156] INFO: Done with hot pixel readout
[10:18:00.337] INFO: PixTest:: pg_setup set to default.
[10:18:00.338] INFO: 12 hot pixels found in step 3
[10:18:01.327] INFO: Collecting data for 5 seconds...
[10:18:06.344] INFO: Done with hot pixel readout
[10:18:17.590] INFO: PixTest:: pg_setup set to default.

[10:18:17.591] INFO: 10 hot pixels found in step 4
[10:18:18.580] INFO: Collecting data for 5 seconds...
[10:18:23.597] INFO: Done with hot pixel readout
[10:18:34.894] INFO: PixTest:: pg_setup set to default.



[10:18:34.895] INFO: 7 hot pixels found in step 5
[10:18:35.884] INFO: Collecting data for 5 seconds...
[10:18:40.900] INFO: Done with hot pixel readout
[10:18:52.177] INFO: PixTest:: pg_setup set to default.



[10:18:52.178] INFO: 7 hot pixels found in step 6
[10:18:53.167] INFO: Collecting data for 5 seconds...
[10:18:58.182] INFO: Done with hot pixel readout
[10:19:09.445] INFO: PixTest:: pg_setup set to default.


[10:19:09.446] INFO: 7 hot pixels found in step 7
[10:19:10.436] INFO: Collecting data for 5 seconds...
[10:19:15.453] INFO: Done with hot pixel readout
[10:19:26.819] INFO: PixTest:: pg_setup set to default.




[10:19:26.820] INFO: 4 hot pixels found in step 8
[10:19:26.852] INFO: 4 hot pixels could not be trimmed and have been masked.
[10:19:26.931] INFO: PixTest::trimHotPixels() done
[10:19:26.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C0.dat
[10:19:26.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C1.dat
[10:19:26.948] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C2.dat
[10:19:26.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C3.dat
[10:19:26.958] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C4.dat
[10:19:26.963] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C5.dat
[10:19:26.969] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C6.dat
[10:19:26.974] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C7.dat
[10:19:26.979] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C8.dat
[10:19:26.984] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C9.dat
[10:19:26.989] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C10.dat
[10:19:26.995] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C11.dat
[10:19:26.000] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C12.dat
[10:19:26.005] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C13.dat
[10:19:27.010] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C14.dat
[10:19:27.015] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C15.dat
[10:19:27.021] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//defaultMaskFile.dat
[10:19:27.038] INFO: enter test to run
[10:20:45.004] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:20:45.004] INFO: running: highrate
[10:20:46.009] INFO: ----------------------------------------------------------------------
[10:20:46.009] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:20:46.009] INFO: ----------------------------------------------------------------------
[10:20:46.009] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:20:46.009] INFO: edge/corner pixel THR is adjusted
[10:20:46.009] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:20:46.967] INFO: Collecting data for 1 seconds...
[10:20:47.970] INFO: Done with hot pixel readout
[10:20:51.713] INFO: PixTest:: pg_setup set to default.
[10:20:51.713] INFO: 0 hot pixels found in step 0
[10:20:51.719] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:20:51.820] INFO: PixTest::trimHotPixels() done
[10:20:51.820] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C0.dat
[10:20:51.832] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C1.dat
[10:20:51.843] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C2.dat
[10:20:51.848] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C3.dat
[10:20:51.854] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C4.dat
[10:20:51.859] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C5.dat
[10:20:51.864] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C6.dat
[10:20:51.869] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C7.dat
[10:20:51.874] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C8.dat
[10:20:51.880] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C9.dat
[10:20:51.885] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C10.dat
[10:20:51.890] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C11.dat
[10:20:51.895] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C12.dat
[10:20:51.901] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C13.dat
[10:20:51.906] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C14.dat
[10:20:51.911] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//trimParameters35_C15.dat
[10:20:51.916] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-4-46_FPIXTest-17C-FNAL-160817-1209-300V_2016-08-17_12h09m_1471453783/000_FPIXTest_p17//defaultMaskFile.dat
[10:20:51.925] INFO: enter test to run
[10:21:12.420] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:21:12.420] INFO: running: xray
[10:21:12.421] INFO: ----------------------------------------------------------------------
[10:21:12.421] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:21:12.421] INFO: ----------------------------------------------------------------------
[10:21:13.398] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:21:25.555] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:21:54.395] INFO: Resuming triggers.
[10:22:06.549] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[10:22:34.947] INFO: Resuming triggers.
[10:22:47.107] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[10:23:15.634] INFO: Resuming triggers.
[10:23:27.792] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[10:23:56.744] INFO: Resuming triggers.
[10:24:08.901] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:24:38.740] INFO: Resuming triggers.
[10:24:50.905] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:25:21.085] INFO: Resuming triggers.
[10:25:33.250] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[10:26:03.225] INFO: Resuming triggers.
[10:26:15.389] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[10:26:44.923] INFO: Resuming triggers.
[10:26:47.001] INFO: data taking finished, elapsed time: 100 seconds.
[10:26:55.718] INFO: PixTest:: pg_setup set to default.
[10:26:55.721] INFO: PixTestXray::doPhRun() done
[10:26:55.930] INFO: enter test to run
[10:27:20.953] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:27:20.953] INFO: running: xray
[10:27:20.954] INFO: ----------------------------------------------------------------------
[10:27:20.954] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:27:20.954] INFO: ----------------------------------------------------------------------
[10:27:21.919] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:27:29.276] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:27:59.232] INFO: Resuming triggers.
[10:28:06.598] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[10:28:36.554] INFO: Resuming triggers.
[10:28:43.917] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:29:13.816] INFO: Resuming triggers.
[10:29:21.177] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[10:29:51.154] INFO: Resuming triggers.
[10:29:58.520] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[10:30:28.370] INFO: Resuming triggers.
[10:30:35.733] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[10:31:05.727] INFO: Resuming triggers.
[10:31:13.094] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:31:43.039] INFO: Resuming triggers.
[10:31:50.410] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:32:20.319] INFO: Resuming triggers.
[10:32:27.689] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:32:57.601] INFO: Resuming triggers.
[10:33:04.967] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[10:33:35.205] INFO: Resuming triggers.
[10:33:42.575] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:34:12.201] INFO: Resuming triggers.
[10:34:19.570] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[10:34:49.924] INFO: Resuming triggers.
[10:34:57.293] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[10:35:27.350] INFO: Resuming triggers.
[10:35:31.920] INFO: data taking finished, elapsed time: 100 seconds.
[10:35:50.894] INFO: PixTest:: pg_setup set to default.
[10:35:50.897] INFO: PixTestXray::doPhRun() done
[10:35:51.049] INFO: enter test to run
[10:36:11.742] INFO: test: HighRate no parameter change
[10:36:11.742] INFO: running: highrate
[10:36:11.750] INFO: ----------------------------------------------------------------------
[10:36:11.750] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:36:11.750] INFO: ----------------------------------------------------------------------
[10:36:11.896] INFO: Expecting 768 events.
[10:36:13.043] INFO: 768 events read in total (428ms).
[10:36:13.043] INFO: Test took 1281ms.
[10:36:13.846] INFO: Expecting 41600 events.
[10:36:16.910] INFO: 41600 events read in total (2537ms).
[10:36:16.911] INFO: Test took 3847ms.
[10:36:16.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:17.671] INFO: Expecting 41600 events.
[10:36:20.796] INFO: 41600 events read in total (2598ms).
[10:36:20.797] INFO: Test took 3840ms.
[10:36:20.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:21.562] INFO: Expecting 41600 events.
[10:36:24.800] INFO: 41600 events read in total (2711ms).
[10:36:24.801] INFO: Test took 3958ms.
[10:36:24.831] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:25.561] INFO: Expecting 41600 events.
[10:36:28.727] INFO: 41600 events read in total (2639ms).
[10:36:28.728] INFO: Test took 3880ms.
[10:36:28.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:29.489] INFO: Expecting 41600 events.
[10:36:32.839] INFO: 41600 events read in total (2823ms).
[10:36:32.840] INFO: Test took 4066ms.
[10:36:32.871] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:33.596] INFO: Expecting 41600 events.
[10:36:36.829] INFO: 41600 events read in total (2706ms).
[10:36:36.830] INFO: Test took 3942ms.
[10:36:36.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:37.583] INFO: Expecting 41600 events.
[10:36:40.831] INFO: 41600 events read in total (2721ms).
[10:36:40.832] INFO: Test took 3956ms.
[10:36:40.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:41.590] INFO: Expecting 41600 events.
[10:36:44.821] INFO: 41600 events read in total (2704ms).
[10:36:44.822] INFO: Test took 3941ms.
[10:36:44.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:45.584] INFO: Expecting 41600 events.
[10:36:48.812] INFO: 41600 events read in total (2702ms).
[10:36:48.813] INFO: Test took 3943ms.
[10:36:48.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:49.571] INFO: Expecting 41600 events.
[10:36:52.809] INFO: 41600 events read in total (2691ms).
[10:36:52.810] INFO: Test took 3951ms.
[10:36:52.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:53.572] INFO: Expecting 41600 events.
[10:36:56.831] INFO: 41600 events read in total (2732ms).
[10:36:56.832] INFO: Test took 3963ms.
[10:36:56.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:57.592] INFO: Expecting 41600 events.
[10:37:00.805] INFO: 41600 events read in total (2686ms).
[10:37:00.806] INFO: Test took 3928ms.
[10:37:00.837] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:01.567] INFO: Expecting 41600 events.
[10:37:04.823] INFO: 41600 events read in total (2729ms).
[10:37:04.823] INFO: Test took 3969ms.
[10:37:04.854] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:05.584] INFO: Expecting 41600 events.
[10:37:08.815] INFO: 41600 events read in total (2705ms).
[10:37:08.816] INFO: Test took 3946ms.
[10:37:08.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:09.573] INFO: Expecting 41600 events.
[10:37:12.792] INFO: 41600 events read in total (2692ms).
[10:37:12.793] INFO: Test took 3929ms.
[10:37:12.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:13.555] INFO: Expecting 41600 events.
[10:37:16.783] INFO: 41600 events read in total (2701ms).
[10:37:16.784] INFO: Test took 3945ms.
[10:37:16.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:17.543] INFO: Expecting 41600 events.
[10:37:20.750] INFO: 41600 events read in total (2680ms).
[10:37:20.751] INFO: Test took 3919ms.
[10:37:20.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:21.514] INFO: Expecting 41600 events.
[10:37:24.703] INFO: 41600 events read in total (2662ms).
[10:37:24.703] INFO: Test took 3906ms.
[10:37:24.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:25.459] INFO: Expecting 41600 events.
[10:37:28.708] INFO: 41600 events read in total (2722ms).
[10:37:28.709] INFO: Test took 3959ms.
[10:37:28.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:29.464] INFO: Expecting 41600 events.
[10:37:32.522] INFO: 41600 events read in total (2531ms).
[10:37:32.523] INFO: Test took 3768ms.
[10:37:32.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:32.906] INFO: enter test to run
[10:38:17.389] INFO: test: HighRate no parameter change
[10:38:17.389] INFO: running: highrate
[10:38:17.390] INFO: ----------------------------------------------------------------------
[10:38:17.390] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:38:17.390] INFO: ----------------------------------------------------------------------
[10:38:18.012] INFO: Expecting 208000 events.
[10:38:29.900] INFO: 208000 events read in total (11361ms).
[10:38:29.903] INFO: Test took 12503ms.
[10:38:30.029] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:30.276] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 72 6 0 0 0 0 0 0
[10:38:30.276] INFO: number of red-efficiency pixels: 64 54 74 117 119 136 127 84 134 92 104 78 78 43 18 24
[10:38:30.276] INFO: number of X-ray hits detected: 60180 38537 57600 91719 99401 99737 97508 69094 60170 77669 80370 73411 74174 43518 17461 20964
[10:38:30.276] 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:38:30.276] INFO: number of Vcal hits detected: 207934 207945 207923 207882 207879 207862 207871 207913 204338 207612 207896 207921 207921 207956 207982 207976
[10:38:30.276] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:38:30.276] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 98.2 99.8 100.0 100.0 100.0 100.0 100.0 100.0
[10:38:30.276] INFO: X-ray hit rate [MHz/cm2]: 17.6 11.3 16.9 26.9 29.1 29.2 28.6 20.3 17.6 22.8 23.6 21.5 21.7 12.8 5.1 6.1
[10:38:30.276] INFO: PixTestHighRate::doXPixelAlive() done
[10:38:30.324] INFO: PixTest:: pg_setup set to default.
[10:38:30.340] INFO: enter test to run
[10:38:47.309] INFO: test: HighRate no parameter change
[10:38:47.309] INFO: running: highrate
[10:38:47.310] INFO: ----------------------------------------------------------------------
[10:38:47.310] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:38:47.310] INFO: ----------------------------------------------------------------------
[10:38:47.929] INFO: Expecting 208000 events.
[10:39:01.199] INFO: 208000 events read in total (12743ms).
[10:39:01.204] INFO: Test took 13886ms.
[10:39:01.467] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:01.756] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 72 6 0 0 0 0 0 0
[10:39:01.756] INFO: number of red-efficiency pixels: 203 118 204 268 348 480 438 275 243 318 278 258 230 133 40 51
[10:39:01.756] INFO: number of X-ray hits detected: 125188 80611 119974 192736 207611 207887 202653 145089 125657 162738 167551 152984 155473 91462 36513 44262
[10:39:01.756] 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:39:01.756] INFO: number of Vcal hits detected: 207782 207879 207776 207719 207637 207481 207524 207704 204223 207370 207714 207729 207757 207863 207960 207948
[10:39:01.756] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:39:01.756] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 98.2 99.7 99.9 99.9 99.9 99.9 100.0 100.0
[10:39:01.756] INFO: X-ray hit rate [MHz/cm2]: 36.7 23.6 35.2 56.5 60.9 60.9 59.4 42.5 36.8 47.7 49.1 44.8 45.6 26.8 10.7 13.0
[10:39:01.756] INFO: PixTestHighRate::doXPixelAlive() done
[10:39:01.801] INFO: PixTest:: pg_setup set to default.
[10:39:01.816] INFO: enter test to run
[10:39:17.060] INFO: test: HighRate no parameter change
[10:39:17.060] INFO: running: highrate
[10:39:17.062] INFO: ----------------------------------------------------------------------
[10:39:17.062] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:39:17.062] INFO: ----------------------------------------------------------------------
[10:39:17.685] INFO: Expecting 208000 events.
[10:39:32.879] INFO: 208000 events read in total (14667ms).
[10:39:32.885] INFO: Test took 15815ms.
[10:39:33.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:33.622] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 72 6 0 0 0 0 0 0
[10:39:33.622] INFO: number of red-efficiency pixels: 398 238 530 668 772 1114 1073 602 365 625 710 587 446 331 59 85
[10:39:33.622] INFO: number of X-ray hits detected: 194088 123973 185826 297929 320291 321762 312217 223971 193597 251542 259841 235218 240867 141272 55585 68339
[10:39:33.622] 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:39:33.622] INFO: number of Vcal hits detected: 207514 207739 207334 207247 207124 206579 206695 207291 204089 207018 207172 207335 207508 207624 207939 207914
[10:39:33.622] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.7 99.6 99.4 99.4 99.7 99.9 99.7 99.6 99.7 99.8 99.8 100.0 100.0
[10:39:33.622] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.6 99.3 99.4 99.7 98.1 99.5 99.6 99.7 99.8 99.8 100.0 100.0
[10:39:33.622] INFO: X-ray hit rate [MHz/cm2]: 56.9 36.3 54.5 87.3 93.9 94.3 91.5 65.6 56.7 73.7 76.2 68.9 70.6 41.4 16.3 20.0
[10:39:33.622] INFO: PixTestHighRate::doXPixelAlive() done
[10:39:33.668] INFO: PixTest:: pg_setup set to default.
[10:39:33.681] INFO: enter test to run
[10:39:36.484] INFO: test: exit no parameter change
[10:39:37.186] QUIET: Connection to board 33 closed.
[10:39:37.212] INFO: pXar: this is the end, my friend