[16:20:17.232] INFO: *** Welcome to pxar ***
[16:20:17.233] INFO: *** Today: 2016/09/09
[16:20:18.049] INFO: *** Version: v1.9.0-818-g96727
[16:20:18.049] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//dacParameters35_C15.dat
[16:20:18.074] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:20:18.074] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//defaultMaskFile.dat
[16:20:18.079] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C15.dat
[16:20:18.180] INFO: clk: 4
[16:20:18.180] INFO: ctr: 4
[16:20:18.180] INFO: sda: 19
[16:20:18.180] INFO: tin: 9
[16:20:18.180] INFO: level: 15
[16:20:18.180] INFO: triggerdelay: 0
[16:20:18.180] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[16:20:18.180] INFO: Log level: INFO
[16:20:18.200] QUIET: Connection to board DTB_WREKRL opened.
[16:20:18.204] 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:
------------------------------------------------------
[16:20:18.206] INFO: RPC call hashes of host and DTB match: 398089610
[16:20:19.731] INFO: DUT info:
[16:20:19.731] INFO: The DUT currently contains the following objects:
[16:20:19.731] INFO: 2 TBM Cores tbm08c (2 ON)
[16:20:19.747] INFO: TBM Core alpha (0): 7 registers set
[16:20:19.748] INFO: TBM Core beta (1): 7 registers set
[16:20:19.748] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:20:19.748] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:19.748] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:20.175] INFO: enter 'restricted' command line mode
[16:20:20.175] INFO: enter test to run
[16:20:28.521] INFO: test: PixelAlive no parameter change
[16:20:28.521] INFO: running: pixelalive
[16:20:28.539] INFO: ----------------------------------------------------------------------
[16:20:28.540] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:20:28.540] INFO: ----------------------------------------------------------------------
[16:20:28.863] INFO: Expecting 41600 events.
[16:20:33.204] INFO: 41600 events read in total (3622ms).
[16:20:33.369] INFO: Test took 4826ms.
[16:20:33.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:33.679] INFO: PixTestAlive::aliveTest() done
[16:20:33.679] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[16:20:33.708] INFO: enter test to run
[16:20:54.881] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:20:54.881] INFO: running: highrate
[16:20:54.882] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:20:55.165] INFO: ----------------------------------------------------------------------
[16:20:55.165] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:20:55.165] INFO: ----------------------------------------------------------------------
[16:20:55.165] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:20:55.165] INFO: edge/corner pixel THR is adjusted
[16:20:55.165] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:20:56.145] INFO: Collecting data for 5 seconds...
[16:21:01.162] INFO: Done with hot pixel readout
[16:21:12.735] INFO: PixTest:: pg_setup set to default.
[16:21:12.736] INFO: 6 hot pixels found in step 0
[16:21:13.722] INFO: Collecting data for 5 seconds...
[16:21:18.738] INFO: Done with hot pixel readout
[16:21:30.270] INFO: PixTest:: pg_setup set to default.
[16:21:30.271] INFO: 6 hot pixels found in step 1
[16:21:31.264] INFO: Collecting data for 5 seconds...
[16:21:36.280] INFO: Done with hot pixel readout
[16:21:47.786] INFO: PixTest:: pg_setup set to default.
[16:21:47.787] INFO: 3 hot pixels found in step 2
[16:21:48.777] INFO: Collecting data for 5 seconds...
[16:21:53.793] INFO: Done with hot pixel readout
[16:22:05.347] INFO: PixTest:: pg_setup set to default.
[16:22:05.348] INFO: 3 hot pixels found in step 3
[16:22:06.338] INFO: Collecting data for 5 seconds...
[16:22:11.355] INFO: Done with hot pixel readout
[16:22:22.938] INFO: PixTest:: pg_setup set to default.
[16:22:22.939] INFO: 4 hot pixels found in step 4
[16:22:23.929] INFO: Collecting data for 5 seconds...
[16:22:28.945] INFO: Done with hot pixel readout
[16:22:40.517] INFO: PixTest:: pg_setup set to default.
[16:22:40.518] INFO: 4 hot pixels found in step 5
[16:22:41.508] INFO: Collecting data for 5 seconds...
[16:22:46.524] INFO: Done with hot pixel readout
[16:22:58.042] INFO: PixTest:: pg_setup set to default.
[16:22:58.043] INFO: 1 hot pixels found in step 6
[16:22:59.033] INFO: Collecting data for 5 seconds...
[16:23:04.049] INFO: Done with hot pixel readout
[16:23:15.628] INFO: PixTest:: pg_setup set to default.
[16:23:15.629] INFO: 2 hot pixels found in step 7
[16:23:16.619] INFO: Collecting data for 5 seconds...
[16:23:21.635] INFO: Done with hot pixel readout
[16:23:33.211] INFO: PixTest:: pg_setup set to default.
[16:23:33.212] INFO: 3 hot pixels found in step 8
[16:23:34.204] INFO: Collecting data for 5 seconds...
[16:23:39.220] INFO: Done with hot pixel readout
[16:23:50.826] INFO: PixTest:: pg_setup set to default.
[16:23:50.827] INFO: 2 hot pixels found in step 9
[16:23:51.817] INFO: Collecting data for 5 seconds...
[16:23:56.834] INFO: Done with hot pixel readout
[16:24:08.416] INFO: PixTest:: pg_setup set to default.
[16:24:08.416] INFO: 3 hot pixels found in step 10
[16:24:09.407] INFO: Collecting data for 5 seconds...
[16:24:14.423] INFO: Done with hot pixel readout
[16:24:25.998] INFO: PixTest:: pg_setup set to default.
[16:24:25.999] INFO: 3 hot pixels found in step 11
[16:24:26.989] INFO: Collecting data for 5 seconds...
[16:24:31.006] INFO: Done with hot pixel readout
[16:24:43.598] INFO: PixTest:: pg_setup set to default.
[16:24:43.599] INFO: 1 hot pixels found in step 12
[16:24:44.590] INFO: Collecting data for 5 seconds...
[16:24:49.606] INFO: Done with hot pixel readout
[16:25:01.175] INFO: PixTest:: pg_setup set to default.
[16:25:01.176] INFO: 1 hot pixels found in step 13
[16:25:02.168] INFO: Collecting data for 5 seconds...
[16:25:07.184] INFO: Done with hot pixel readout
[16:25:18.799] INFO: PixTest:: pg_setup set to default.
[16:25:18.800] INFO: 0 hot pixels found in step 14
[16:25:18.834] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:25:18.911] INFO: PixTest::trimHotPixels() done
[16:25:18.911] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C0.dat
[16:25:18.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C1.dat
[16:25:18.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C2.dat
[16:25:18.936] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C3.dat
[16:25:18.941] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C4.dat
[16:25:18.946] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C5.dat
[16:25:18.951] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C6.dat
[16:25:18.957] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C7.dat
[16:25:18.962] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C8.dat
[16:25:18.967] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C9.dat
[16:25:18.973] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C10.dat
[16:25:18.978] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C11.dat
[16:25:18.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C12.dat
[16:25:18.988] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C13.dat
[16:25:18.994] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C14.dat
[16:25:18.999] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C15.dat
[16:25:18.004] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//defaultMaskFile.dat
[16:25:19.021] INFO: enter test to run
[16:25:46.487] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:25:46.487] INFO: running: highrate
[16:25:46.492] INFO: ----------------------------------------------------------------------
[16:25:46.492] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:25:46.492] INFO: ----------------------------------------------------------------------
[16:25:46.492] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:25:46.492] INFO: edge/corner pixel THR is adjusted
[16:25:46.492] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:25:47.449] INFO: Collecting data for 1 seconds...
[16:25:48.453] INFO: Done with hot pixel readout
[16:25:51.709] INFO: PixTest:: pg_setup set to default.
[16:25:51.710] INFO: 0 hot pixels found in step 0
[16:25:51.717] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:25:51.829] INFO: PixTest::trimHotPixels() done
[16:25:51.829] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C0.dat
[16:25:51.839] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C1.dat
[16:25:51.847] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C2.dat
[16:25:51.853] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C3.dat
[16:25:51.858] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C4.dat
[16:25:51.863] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C5.dat
[16:25:51.869] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C6.dat
[16:25:51.874] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C7.dat
[16:25:51.880] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C8.dat
[16:25:51.885] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C9.dat
[16:25:51.891] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C10.dat
[16:25:51.896] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C11.dat
[16:25:51.901] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C12.dat
[16:25:51.907] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C13.dat
[16:25:51.912] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C14.dat
[16:25:51.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//trimParameters35_C15.dat
[16:25:51.923] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-15_FPIXTest-17C-FNAL-160908-0917-150V_2016-09-08_09h17m_1473344239/000_FPIXTest_p17//defaultMaskFile.dat
[16:25:51.933] INFO: enter test to run
[16:26:24.919] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:26:24.919] INFO: running: xray
[16:26:24.937] INFO: ----------------------------------------------------------------------
[16:26:24.937] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:26:24.937] INFO: ----------------------------------------------------------------------
[16:26:25.900] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:26:37.657] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:27:07.463] INFO: Resuming triggers.
[16:27:19.221] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[16:27:48.696] INFO: Resuming triggers.
[16:28:00.454] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[16:28:29.772] INFO: Resuming triggers.
[16:28:41.528] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[16:29:11.845] INFO: Resuming triggers.
[16:29:23.606] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[16:29:53.707] INFO: Resuming triggers.
[16:30:05.466] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[16:30:35.873] INFO: Resuming triggers.
[16:30:47.631] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[16:31:18.031] INFO: Resuming triggers.
[16:31:29.794] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[16:31:59.319] INFO: Resuming triggers.
[16:32:05.613] INFO: data taking finished, elapsed time: 100 seconds.
[16:32:21.714] INFO: PixTest:: pg_setup set to default.
[16:32:21.718] INFO: PixTestXray::doPhRun() done
[16:32:21.916] INFO: enter test to run
[16:32:49.988] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:32:49.988] INFO: running: xray
[16:32:49.989] INFO: ----------------------------------------------------------------------
[16:32:49.989] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:32:49.989] INFO: ----------------------------------------------------------------------
[16:32:50.953] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:32:57.979] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:33:28.247] INFO: Resuming triggers.
[16:33:35.270] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[16:34:05.604] INFO: Resuming triggers.
[16:34:12.632] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[16:34:42.898] INFO: Resuming triggers.
[16:34:49.927] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[16:35:20.177] INFO: Resuming triggers.
[16:35:27.205] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[16:35:57.469] INFO: Resuming triggers.
[16:36:04.490] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:36:34.775] INFO: Resuming triggers.
[16:36:41.794] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[16:37:12.054] INFO: Resuming triggers.
[16:37:19.074] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:37:49.363] INFO: Resuming triggers.
[16:37:56.383] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[16:38:27.368] INFO: Resuming triggers.
[16:38:34.392] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[16:39:05.208] INFO: Resuming triggers.
[16:39:12.227] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[16:39:43.277] INFO: Resuming triggers.
[16:39:50.303] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:40:21.163] INFO: Resuming triggers.
[16:40:28.184] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:40:58.851] INFO: Resuming triggers.
[16:41:05.878] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[16:41:36.977] INFO: Resuming triggers.
[16:41:38.984] INFO: data taking finished, elapsed time: 100 seconds.
[16:41:48.306] INFO: PixTest:: pg_setup set to default.
[16:41:48.309] INFO: PixTestXray::doPhRun() done
[16:41:48.462] INFO: enter test to run
[16:42:23.249] INFO: test: HighRate no parameter change
[16:42:23.249] INFO: running: highrate
[16:42:23.263] INFO: ----------------------------------------------------------------------
[16:42:23.263] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:42:23.263] INFO: ----------------------------------------------------------------------
[16:42:23.438] INFO: Expecting 768 events.
[16:42:24.582] INFO: 768 events read in total (429ms).
[16:42:24.582] INFO: Test took 1298ms.
[16:42:25.385] INFO: Expecting 41600 events.
[16:42:28.463] INFO: 41600 events read in total (2551ms).
[16:42:28.464] INFO: Test took 3851ms.
[16:42:28.495] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:29.227] INFO: Expecting 41600 events.
[16:42:32.471] INFO: 41600 events read in total (2718ms).
[16:42:32.472] INFO: Test took 3960ms.
[16:42:32.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:33.229] INFO: Expecting 41600 events.
[16:42:36.530] INFO: 41600 events read in total (2775ms).
[16:42:36.531] INFO: Test took 4008ms.
[16:42:36.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:37.292] INFO: Expecting 41600 events.
[16:42:40.519] INFO: 41600 events read in total (2701ms).
[16:42:40.520] INFO: Test took 3936ms.
[16:42:40.554] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:41.274] INFO: Expecting 41600 events.
[16:42:44.625] INFO: 41600 events read in total (2824ms).
[16:42:44.626] INFO: Test took 4053ms.
[16:42:44.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:45.381] INFO: Expecting 41600 events.
[16:42:48.741] INFO: 41600 events read in total (2833ms).
[16:42:48.742] INFO: Test took 4066ms.
[16:42:48.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:49.485] INFO: Expecting 41600 events.
[16:42:52.739] INFO: 41600 events read in total (2727ms).
[16:42:52.740] INFO: Test took 3944ms.
[16:42:52.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:53.499] INFO: Expecting 41600 events.
[16:42:56.773] INFO: 41600 events read in total (2748ms).
[16:42:56.774] INFO: Test took 3984ms.
[16:42:56.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:57.532] INFO: Expecting 41600 events.
[16:43:00.836] INFO: 41600 events read in total (2777ms).
[16:43:00.836] INFO: Test took 4011ms.
[16:43:00.872] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:01.591] INFO: Expecting 41600 events.
[16:43:04.870] INFO: 41600 events read in total (2752ms).
[16:43:04.871] INFO: Test took 3978ms.
[16:43:04.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:05.628] INFO: Expecting 41600 events.
[16:43:08.869] INFO: 41600 events read in total (2715ms).
[16:43:08.870] INFO: Test took 3947ms.
[16:43:08.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:09.631] INFO: Expecting 41600 events.
[16:43:12.899] INFO: 41600 events read in total (2741ms).
[16:43:12.900] INFO: Test took 3978ms.
[16:43:12.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:13.659] INFO: Expecting 41600 events.
[16:43:16.953] INFO: 41600 events read in total (2767ms).
[16:43:16.954] INFO: Test took 4003ms.
[16:43:16.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:17.709] INFO: Expecting 41600 events.
[16:43:20.975] INFO: 41600 events read in total (2739ms).
[16:43:20.975] INFO: Test took 3969ms.
[16:43:21.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:21.732] INFO: Expecting 41600 events.
[16:43:24.971] INFO: 41600 events read in total (2712ms).
[16:43:24.972] INFO: Test took 3945ms.
[16:43:24.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:25.730] INFO: Expecting 41600 events.
[16:43:28.005] INFO: 41600 events read in total (2748ms).
[16:43:29.006] INFO: Test took 3984ms.
[16:43:29.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:29.767] INFO: Expecting 41600 events.
[16:43:33.058] INFO: 41600 events read in total (2764ms).
[16:43:33.059] INFO: Test took 4001ms.
[16:43:33.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:33.816] INFO: Expecting 41600 events.
[16:43:37.164] INFO: 41600 events read in total (2821ms).
[16:43:37.165] INFO: Test took 4054ms.
[16:43:37.197] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:37.924] INFO: Expecting 41600 events.
[16:43:41.194] INFO: 41600 events read in total (2743ms).
[16:43:41.195] INFO: Test took 3980ms.
[16:43:41.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:41.951] INFO: Expecting 41600 events.
[16:43:45.044] INFO: 41600 events read in total (2566ms).
[16:43:45.045] INFO: Test took 3800ms.
[16:43:45.077] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:45.442] INFO: enter test to run
[16:44:17.048] INFO: test: HighRate no parameter change
[16:44:17.048] INFO: running: highrate
[16:44:17.049] INFO: ----------------------------------------------------------------------
[16:44:17.049] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:44:17.049] INFO: ----------------------------------------------------------------------
[16:44:17.708] INFO: Expecting 208000 events.
[16:44:29.888] INFO: 208000 events read in total (11653ms).
[16:44:29.891] INFO: Test took 12815ms.
[16:44:30.025] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:30.274] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[16:44:30.274] INFO: number of red-efficiency pixels: 71 47 84 124 136 117 120 100 78 111 105 109 107 45 21 35
[16:44:30.274] INFO: number of X-ray hits detected: 62120 40883 62515 97646 101760 104700 101376 76760 72128 92114 90292 76433 79814 47619 19045 23988
[16:44:30.274] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:44:30.274] INFO: number of Vcal hits detected: 207928 207952 207914 207873 207859 207880 207876 207899 207921 207888 207890 207890 207893 207955 207979 207916
[16:44:30.274] 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 99.9 99.9 99.9 100.0 100.0 100.0
[16:44:30.274] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[16:44:30.274] INFO: X-ray hit rate [MHz/cm2]: 18.2 12.0 18.3 28.6 29.8 30.7 29.7 22.5 21.1 27.0 26.5 22.4 23.4 14.0 5.6 7.0
[16:44:30.274] INFO: PixTestHighRate::doXPixelAlive() done
[16:44:30.323] INFO: PixTest:: pg_setup set to default.
[16:44:30.339] INFO: enter test to run
[16:45:05.695] INFO: test: HighRate no parameter change
[16:45:05.695] INFO: running: highrate
[16:45:05.696] INFO: ----------------------------------------------------------------------
[16:45:05.696] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:45:05.697] INFO: ----------------------------------------------------------------------
[16:45:06.317] INFO: Expecting 208000 events.
[16:45:19.801] INFO: 208000 events read in total (12957ms).
[16:45:19.806] INFO: Test took 14100ms.
[16:45:20.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:20.365] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[16:45:20.365] INFO: number of red-efficiency pixels: 183 147 253 438 428 423 360 261 261 349 410 266 285 135 43 59
[16:45:20.365] INFO: number of X-ray hits detected: 126170 82491 127799 200137 208156 213203 207939 156062 146820 187363 184405 155874 164069 97380 39203 49766
[16:45:20.365] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:45:20.365] INFO: number of Vcal hits detected: 207808 207848 207725 207532 207554 207547 207619 207719 207727 207632 207573 207721 207699 207857 207955 207891
[16:45:20.365] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[16:45:20.365] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[16:45:20.365] INFO: X-ray hit rate [MHz/cm2]: 37.0 24.2 37.5 58.7 61.0 62.5 60.9 45.7 43.0 54.9 54.1 45.7 48.1 28.5 11.5 14.6
[16:45:20.365] INFO: PixTestHighRate::doXPixelAlive() done
[16:45:20.411] INFO: PixTest:: pg_setup set to default.
[16:45:20.428] INFO: enter test to run
[16:45:47.895] INFO: test: HighRate no parameter change
[16:45:47.895] INFO: running: highrate
[16:45:47.896] INFO: ----------------------------------------------------------------------
[16:45:47.896] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:45:47.896] INFO: ----------------------------------------------------------------------
[16:45:48.511] INFO: Expecting 208000 events.
[16:46:03.697] INFO: 208000 events read in total (14659ms).
[16:46:03.704] INFO: Test took 15799ms.
[16:46:04.110] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:04.446] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[16:46:04.446] INFO: number of red-efficiency pixels: 348 273 595 941 1027 929 747 589 535 900 895 563 640 269 92 101
[16:46:04.446] INFO: number of X-ray hits detected: 192669 125915 194150 304549 317089 326286 318285 238954 225231 286062 281707 237740 248420 149414 60125 75431
[16:46:04.446] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:46:04.446] INFO: number of Vcal hits detected: 207598 207703 207259 206857 206748 206868 207125 207305 207378 206948 206950 207356 207284 207711 207906 207844
[16:46:04.446] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.5 99.6 99.7 99.7 99.5 99.5 99.7 99.7 99.9 100.0 100.0
[16:46:04.446] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.5 99.4 99.5 99.6 99.7 99.7 99.5 99.5 99.7 99.7 99.9 100.0 99.9
[16:46:04.446] INFO: X-ray hit rate [MHz/cm2]: 56.5 36.9 56.9 89.3 92.9 95.6 93.3 70.0 66.0 83.8 82.6 69.7 72.8 43.8 17.6 22.1
[16:46:04.446] INFO: PixTestHighRate::doXPixelAlive() done
[16:46:04.493] INFO: PixTest:: pg_setup set to default.
[16:46:04.514] INFO: enter test to run
[16:46:07.287] INFO: test: exit no parameter change
[16:46:07.733] QUIET: Connection to board 33 closed.
[16:46:07.742] INFO: pXar: this is the end, my friend