[20:25:40.588] INFO: *** Welcome to pxar ***
[20:25:40.588] INFO: *** Today: 2016/10/06
[20:25:40.842] INFO: *** Version: v1.9.0-818-g96727
[20:25:40.842] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//dacParameters35_C15.dat
[20:25:40.867] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//tbmParameters_C0b.dat
[20:25:40.867] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//defaultMaskFile.dat
[20:25:40.873] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C15.dat
[20:25:40.979] INFO: clk: 4
[20:25:40.979] INFO: ctr: 4
[20:25:40.979] INFO: sda: 19
[20:25:40.979] INFO: tin: 9
[20:25:40.979] INFO: level: 15
[20:25:40.979] INFO: triggerdelay: 0
[20:25:40.979] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[20:25:40.979] INFO: Log level: INFO
[20:25:40.998] QUIET: Connection to board DTB_WREKRL opened.
[20:25:40.001] 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:
------------------------------------------------------
[20:25:40.004] INFO: RPC call hashes of host and DTB match: 398089610
[20:25:42.540] INFO: DUT info:
[20:25:42.540] INFO: The DUT currently contains the following objects:
[20:25:42.540] INFO: 2 TBM Cores tbm08c (2 ON)
[20:25:42.540] INFO: TBM Core alpha (0): 7 registers set
[20:25:42.541] INFO: TBM Core beta (1): 7 registers set
[20:25:42.541] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[20:25:42.541] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.541] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.542] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.542] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.542] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.542] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:25:42.960] INFO: enter 'restricted' command line mode
[20:25:42.960] INFO: enter test to run
[20:25:49.835] INFO: test: PixelAlive no parameter change
[20:25:49.835] INFO: running: pixelalive
[20:25:49.844] INFO: ----------------------------------------------------------------------
[20:25:49.844] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:25:49.844] INFO: ----------------------------------------------------------------------
[20:25:50.165] INFO: Expecting 41600 events.
[20:25:54.485] INFO: 41600 events read in total (3602ms).
[20:25:54.655] INFO: Test took 4809ms.
[20:25:54.669] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:25:54.935] INFO: PixTestAlive::aliveTest() done
[20:25:54.935] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 1 0 0 0 0
[20:25:54.968] INFO: enter test to run
[20:26:57.035] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[20:26:57.035] INFO: running: highrate
[20:26:57.035] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[20:26:57.230] INFO: ----------------------------------------------------------------------
[20:26:57.230] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[20:26:57.230] INFO: ----------------------------------------------------------------------
[20:26:57.230] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[20:26:57.230] INFO: edge/corner pixel THR is adjusted
[20:26:57.230] INFO: PixTestHighRate::trimHotPixels: step 0...
[20:26:58.188] INFO: Collecting data for 5 seconds...
[20:27:03.206] INFO: Done with hot pixel readout
[20:27:14.666] INFO: PixTest:: pg_setup set to default.
[20:27:14.667] INFO: 3 hot pixels found in step 0
[20:27:15.678] INFO: Collecting data for 5 seconds...
[20:27:20.696] INFO: Done with hot pixel readout
[20:27:32.376] INFO: PixTest:: pg_setup set to default.
[20:27:32.377] INFO: 3 hot pixels found in step 1
[20:27:33.370] INFO: Collecting data for 5 seconds...
[20:27:38.390] INFO: Done with hot pixel readout
[20:27:50.158] INFO: PixTest:: pg_setup set to default.
[20:27:50.159] INFO: 4 hot pixels found in step 2
[20:27:51.151] INFO: Collecting data for 5 seconds...
[20:27:56.170] INFO: Done with hot pixel readout
[20:28:07.641] INFO: PixTest:: pg_setup set to default.
[20:28:07.642] INFO: 1 hot pixels found in step 3
[20:28:08.634] INFO: Collecting data for 5 seconds...
[20:28:13.653] INFO: Done with hot pixel readout
[20:28:25.293] INFO: PixTest:: pg_setup set to default.
[20:28:25.294] INFO: 2 hot pixels found in step 4
[20:28:26.286] INFO: Collecting data for 5 seconds...
[20:28:31.305] INFO: Done with hot pixel readout
[20:28:43.118] INFO: PixTest:: pg_setup set to default.
[20:28:43.119] INFO: 2 hot pixels found in step 5
[20:28:44.111] INFO: Collecting data for 5 seconds...
[20:28:49.129] INFO: Done with hot pixel readout
[20:29:00.594] INFO: PixTest:: pg_setup set to default.
[20:29:00.595] INFO: 1 hot pixels found in step 6
[20:29:01.610] INFO: Collecting data for 5 seconds...
[20:29:06.629] INFO: Done with hot pixel readout
[20:29:17.709] INFO: PixTest:: pg_setup set to default.
[20:29:17.710] INFO: 0 hot pixels found in step 7
[20:29:17.746] INFO: 0 hot pixels could not be trimmed and have been masked.
[20:29:17.750] INFO: PixTest::trimHotPixels() done
[20:29:17.750] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C0.dat
[20:29:17.756] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C1.dat
[20:29:17.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C2.dat
[20:29:17.767] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C3.dat
[20:29:17.773] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C4.dat
[20:29:17.778] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C5.dat
[20:29:17.783] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C6.dat
[20:29:17.789] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C7.dat
[20:29:17.794] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C8.dat
[20:29:17.800] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C9.dat
[20:29:17.805] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C10.dat
[20:29:17.810] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C11.dat
[20:29:17.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C12.dat
[20:29:17.821] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C13.dat
[20:29:17.826] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C14.dat
[20:29:17.832] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C15.dat
[20:29:17.837] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//defaultMaskFile.dat
[20:29:17.847] INFO: enter test to run
[20:29:42.417] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[20:29:42.418] INFO: running: highrate
[20:29:42.422] INFO: ----------------------------------------------------------------------
[20:29:42.422] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[20:29:42.422] INFO: ----------------------------------------------------------------------
[20:29:42.422] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[20:29:42.422] INFO: edge/corner pixel THR is adjusted
[20:29:42.422] INFO: PixTestHighRate::trimHotPixels: step 0...
[20:29:43.379] INFO: Collecting data for 1 seconds...
[20:29:44.384] INFO: Done with hot pixel readout
[20:29:48.490] INFO: PixTest:: pg_setup set to default.
[20:29:48.491] INFO: 0 hot pixels found in step 0
[20:29:48.496] INFO: 0 hot pixels could not be trimmed and have been masked.
[20:29:48.581] INFO: PixTest::trimHotPixels() done
[20:29:48.582] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C0.dat
[20:29:48.592] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C1.dat
[20:29:48.602] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C2.dat
[20:29:48.607] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C3.dat
[20:29:48.613] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C4.dat
[20:29:48.618] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C5.dat
[20:29:48.624] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C6.dat
[20:29:48.629] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C7.dat
[20:29:48.635] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C8.dat
[20:29:48.640] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C9.dat
[20:29:48.646] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C10.dat
[20:29:48.651] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C11.dat
[20:29:48.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C12.dat
[20:29:48.662] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C13.dat
[20:29:48.668] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C14.dat
[20:29:48.673] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C15.dat
[20:29:48.678] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-1-09_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//defaultMaskFile.dat
[20:29:48.688] INFO: enter test to run
[20:30:12.337] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[20:30:12.337] INFO: running: xray
[20:30:12.338] INFO: ----------------------------------------------------------------------
[20:30:12.338] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[20:30:12.339] INFO: ----------------------------------------------------------------------
[20:30:13.302] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[20:30:24.781] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[20:30:55.148] INFO: Resuming triggers.
[20:31:06.631] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[20:31:37.118] INFO: Resuming triggers.
[20:31:48.604] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[20:32:18.023] INFO: Resuming triggers.
[20:32:29.511] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[20:32:59.083] INFO: Resuming triggers.
[20:33:10.566] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[20:33:40.026] INFO: Resuming triggers.
[20:33:51.511] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[20:34:20.000] INFO: Resuming triggers.
[20:34:32.489] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[20:35:01.976] INFO: Resuming triggers.
[20:35:13.463] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[20:35:42.987] INFO: Resuming triggers.
[20:35:51.450] INFO: data taking finished, elapsed time: 100 seconds.
[20:36:13.373] INFO: PixTest:: pg_setup set to default.
[20:36:13.377] INFO: PixTestXray::doPhRun() done
[20:36:13.514] INFO: enter test to run
[20:36:41.103] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[20:36:41.103] INFO: running: xray
[20:36:41.104] INFO: ----------------------------------------------------------------------
[20:36:41.104] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[20:36:41.104] INFO: ----------------------------------------------------------------------
[20:36:42.068] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[20:36:48.770] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[20:37:18.946] INFO: Resuming triggers.
[20:37:25.650] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[20:37:55.846] INFO: Resuming triggers.
[20:38:02.549] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[20:38:32.805] INFO: Resuming triggers.
[20:38:39.507] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[20:39:09.685] INFO: Resuming triggers.
[20:39:16.385] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[20:39:46.999] INFO: Resuming triggers.
[20:39:53.703] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[20:40:24.685] INFO: Resuming triggers.
[20:40:31.390] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[20:41:02.498] INFO: Resuming triggers.
[20:41:09.205] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[20:41:40.086] INFO: Resuming triggers.
[20:41:46.787] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[20:42:17.533] INFO: Resuming triggers.
[20:42:24.237] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[20:42:54.779] INFO: Resuming triggers.
[20:43:01.482] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[20:43:32.993] INFO: Resuming triggers.
[20:43:39.693] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[20:44:10.846] INFO: Resuming triggers.
[20:44:17.547] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[20:44:48.874] INFO: Resuming triggers.
[20:44:55.579] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[20:45:26.372] INFO: Resuming triggers.
[20:45:32.886] INFO: data taking finished, elapsed time: 100 seconds.
[20:46:02.365] INFO: PixTest:: pg_setup set to default.
[20:46:02.368] INFO: PixTestXray::doPhRun() done
[20:46:02.517] INFO: enter test to run
[20:46:35.995] INFO: test: HighRate no parameter change
[20:46:35.995] INFO: running: highrate
[20:46:35.006] INFO: ----------------------------------------------------------------------
[20:46:35.006] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[20:46:35.006] INFO: ----------------------------------------------------------------------
[20:46:36.161] INFO: Expecting 768 events.
[20:46:37.295] INFO: 768 events read in total (418ms).
[20:46:37.295] INFO: Test took 1269ms.
[20:46:38.098] INFO: Expecting 41600 events.
[20:46:41.181] INFO: 41600 events read in total (2556ms).
[20:46:41.182] INFO: Test took 3861ms.
[20:46:41.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:41.946] INFO: Expecting 41600 events.
[20:46:45.128] INFO: 41600 events read in total (2655ms).
[20:46:45.129] INFO: Test took 3896ms.
[20:46:45.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:45.886] INFO: Expecting 41600 events.
[20:46:49.103] INFO: 41600 events read in total (2690ms).
[20:46:49.104] INFO: Test took 3924ms.
[20:46:49.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:49.860] INFO: Expecting 41600 events.
[20:46:53.079] INFO: 41600 events read in total (2692ms).
[20:46:53.080] INFO: Test took 3924ms.
[20:46:53.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:53.839] INFO: Expecting 41600 events.
[20:46:57.059] INFO: 41600 events read in total (2693ms).
[20:46:57.060] INFO: Test took 3928ms.
[20:46:57.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:57.818] INFO: Expecting 41600 events.
[20:47:01.043] INFO: 41600 events read in total (2698ms).
[20:47:01.044] INFO: Test took 3931ms.
[20:47:01.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:01.802] INFO: Expecting 41600 events.
[20:47:05.031] INFO: 41600 events read in total (2702ms).
[20:47:05.032] INFO: Test took 3935ms.
[20:47:05.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:05.793] INFO: Expecting 41600 events.
[20:47:09.013] INFO: 41600 events read in total (2693ms).
[20:47:09.014] INFO: Test took 3930ms.
[20:47:09.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:09.772] INFO: Expecting 41600 events.
[20:47:12.995] INFO: 41600 events read in total (2696ms).
[20:47:12.996] INFO: Test took 3931ms.
[20:47:13.029] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:13.755] INFO: Expecting 41600 events.
[20:47:16.992] INFO: 41600 events read in total (2710ms).
[20:47:16.993] INFO: Test took 3947ms.
[20:47:17.027] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:17.754] INFO: Expecting 41600 events.
[20:47:20.975] INFO: 41600 events read in total (2694ms).
[20:47:20.976] INFO: Test took 3931ms.
[20:47:21.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:21.735] INFO: Expecting 41600 events.
[20:47:24.948] INFO: 41600 events read in total (2686ms).
[20:47:24.949] INFO: Test took 3921ms.
[20:47:24.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:25.708] INFO: Expecting 41600 events.
[20:47:28.945] INFO: 41600 events read in total (2710ms).
[20:47:28.946] INFO: Test took 3945ms.
[20:47:28.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:29.706] INFO: Expecting 41600 events.
[20:47:32.949] INFO: 41600 events read in total (2716ms).
[20:47:32.951] INFO: Test took 3954ms.
[20:47:32.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:33.708] INFO: Expecting 41600 events.
[20:47:36.944] INFO: 41600 events read in total (2709ms).
[20:47:36.945] INFO: Test took 3942ms.
[20:47:36.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:37.701] INFO: Expecting 41600 events.
[20:47:40.923] INFO: 41600 events read in total (2695ms).
[20:47:40.924] INFO: Test took 3927ms.
[20:47:40.958] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:41.685] INFO: Expecting 41600 events.
[20:47:44.918] INFO: 41600 events read in total (2706ms).
[20:47:44.919] INFO: Test took 3943ms.
[20:47:44.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:45.680] INFO: Expecting 41600 events.
[20:47:48.901] INFO: 41600 events read in total (2694ms).
[20:47:48.902] INFO: Test took 3931ms.
[20:47:48.935] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:49.663] INFO: Expecting 41600 events.
[20:47:52.862] INFO: 41600 events read in total (2672ms).
[20:47:52.863] INFO: Test took 3910ms.
[20:47:52.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:53.623] INFO: Expecting 41600 events.
[20:47:56.711] INFO: 41600 events read in total (2561ms).
[20:47:56.712] INFO: Test took 3798ms.
[20:47:56.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:57.108] INFO: enter test to run
[20:48:01.370] INFO: test: HighRate no parameter change
[20:48:01.370] INFO: running: highrate
[20:48:01.371] INFO: ----------------------------------------------------------------------
[20:48:01.371] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[20:48:01.371] INFO: ----------------------------------------------------------------------
[20:48:01.987] INFO: Expecting 208000 events.
[20:48:13.817] INFO: 208000 events read in total (11303ms).
[20:48:13.820] INFO: Test took 12442ms.
[20:48:13.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:48:14.208] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[20:48:14.208] INFO: number of red-efficiency pixels: 60 49 74 148 129 127 178 110 87 125 143 97 101 57 28 31
[20:48:14.208] INFO: number of X-ray hits detected: 50338 47276 66567 103393 108499 111432 112614 73604 71305 102904 100665 83880 86605 49733 22711 24167
[20:48:14.208] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[20:48:14.208] INFO: number of Vcal hits detected: 207939 207950 207926 207843 207869 207867 207816 207887 207913 207868 207856 207852 207896 207942 207972 207969
[20:48:14.208] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[20:48:14.208] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[20:48:14.208] INFO: X-ray hit rate [MHz/cm2]: 14.8 13.9 19.5 30.3 31.8 32.7 33.0 21.6 20.9 30.2 29.5 24.6 25.4 14.6 6.7 7.1
[20:48:14.208] INFO: PixTestHighRate::doXPixelAlive() done
[20:48:14.262] INFO: PixTest:: pg_setup set to default.
[20:48:14.274] INFO: enter test to run
[20:48:32.554] INFO: test: HighRate no parameter change
[20:48:32.554] INFO: running: highrate
[20:48:32.555] INFO: ----------------------------------------------------------------------
[20:48:32.555] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[20:48:32.555] INFO: ----------------------------------------------------------------------
[20:48:33.167] INFO: Expecting 208000 events.
[20:48:46.698] INFO: 208000 events read in total (13004ms).
[20:48:46.703] INFO: Test took 14137ms.
[20:48:46.990] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:48:47.290] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[20:48:47.290] INFO: number of red-efficiency pixels: 134 135 236 498 339 380 560 309 277 371 471 253 322 164 49 60
[20:48:47.290] INFO: number of X-ray hits detected: 105814 97143 138375 215839 225699 231885 233522 154287 149182 216272 210351 176075 179671 105193 48014 50265
[20:48:47.290] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[20:48:47.290] INFO: number of Vcal hits detected: 207861 207860 207749 207452 207636 207584 207389 207666 207703 207609 207488 207685 207654 207830 207950 207940
[20:48:47.290] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[20:48:47.290] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.8 99.8 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[20:48:47.290] INFO: X-ray hit rate [MHz/cm2]: 31.0 28.5 40.6 63.3 66.2 68.0 68.4 45.2 43.7 63.4 61.7 51.6 52.7 30.8 14.1 14.7
[20:48:47.290] INFO: PixTestHighRate::doXPixelAlive() done
[20:48:47.336] INFO: PixTest:: pg_setup set to default.
[20:48:47.348] INFO: enter test to run
[20:49:57.169] INFO: test: HighRate no parameter change
[20:49:57.169] INFO: running: highrate
[20:49:57.171] INFO: ----------------------------------------------------------------------
[20:49:57.171] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[20:49:57.171] INFO: ----------------------------------------------------------------------
[20:49:57.788] INFO: Expecting 208000 events.
[20:50:13.295] INFO: 208000 events read in total (14980ms).
[20:50:13.303] INFO: Test took 16124ms.
[20:50:13.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:14.088] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[20:50:14.088] INFO: number of red-efficiency pixels: 243 241 544 1115 847 896 1276 667 609 810 1043 589 701 367 97 81
[20:50:14.088] INFO: number of X-ray hits detected: 161960 148422 212404 328348 343020 353093 356938 235758 227687 328913 319027 267678 275504 158769 73353 77448
[20:50:14.088] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[20:50:14.088] INFO: number of Vcal hits detected: 207740 207728 207325 206585 207015 206878 206323 207171 207296 207047 206743 207278 207191 207573 207902 207917
[20:50:14.088] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.4 99.6 99.5 99.3 99.7 99.7 99.6 99.4 99.7 99.7 99.8 100.0 100.0
[20:50:14.088] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.3 99.5 99.5 99.2 99.6 99.7 99.5 99.4 99.7 99.6 99.8 100.0 100.0
[20:50:14.088] INFO: X-ray hit rate [MHz/cm2]: 47.5 43.5 62.3 96.2 100.5 103.5 104.6 69.1 66.7 96.4 93.5 78.5 80.8 46.5 21.5 22.7
[20:50:14.088] INFO: PixTestHighRate::doXPixelAlive() done
[20:50:14.140] INFO: PixTest:: pg_setup set to default.
[20:50:14.155] INFO: enter test to run
[20:50:39.769] INFO: test: exit no parameter change
[20:50:40.182] QUIET: Connection to board 33 closed.
[20:50:40.193] INFO: pXar: this is the end, my friend