[09:36:27.418] INFO: *** Welcome to pxar ***
[09:36:27.418] INFO: *** Today: 2016/10/11
[09:36:28.529] INFO: *** Version: v1.9.0-818-g96727
[09:36:28.529] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//dacParameters35_C15.dat
[09:36:28.582] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//tbmParameters_C0b.dat
[09:36:28.582] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//defaultMaskFile.dat
[09:36:28.584] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C15.dat
[09:36:28.764] INFO: clk: 4
[09:36:28.764] INFO: ctr: 4
[09:36:28.764] INFO: sda: 19
[09:36:28.764] INFO: tin: 9
[09:36:28.764] INFO: level: 15
[09:36:28.764] INFO: triggerdelay: 0
[09:36:28.764] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[09:36:28.764] INFO: Log level: INFO
[09:36:28.783] QUIET: Connection to board DTB_WREKRL opened.
[09:36:28.787] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[09:36:28.789] INFO: RPC call hashes of host and DTB match: 398089610
[09:36:30.343] INFO: DUT info:
[09:36:30.343] INFO: The DUT currently contains the following objects:
[09:36:30.343] INFO: 2 TBM Cores tbm08c (2 ON)
[09:36:30.343] INFO: TBM Core alpha (0): 7 registers set
[09:36:30.343] INFO: TBM Core beta (1): 7 registers set
[09:36:30.343] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:36:30.343] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.343] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.343] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.343] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.343] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.343] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.343] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.344] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:36:30.775] INFO: enter 'restricted' command line mode
[09:36:30.776] INFO: enter test to run
[09:36:38.963] INFO: test: PixelAlive no parameter change
[09:36:38.963] INFO: running: pixelalive
[09:36:39.029] INFO: ----------------------------------------------------------------------
[09:36:39.029] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:36:39.029] INFO: ----------------------------------------------------------------------
[09:36:39.346] INFO: Expecting 41600 events.
[09:36:43.792] INFO: 41600 events read in total (3728ms).
[09:36:43.955] INFO: Test took 4924ms.
[09:36:43.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:44.530] INFO: PixTestAlive::aliveTest() done
[09:36:44.531] INFO: number of dead pixels (per ROC): 4 0 0 0 0 0 0 0 3 0 0 0 0 0 0 0
[09:36:44.706] INFO: enter test to run
[09:37:20.419] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[09:37:20.419] INFO: running: highrate
[09:37:20.474] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[09:37:20.738] INFO: ----------------------------------------------------------------------
[09:37:20.738] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[09:37:20.738] INFO: ----------------------------------------------------------------------
[09:37:20.738] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[09:37:20.738] INFO: edge/corner pixel THR is adjusted
[09:37:20.738] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:37:21.696] INFO: Collecting data for 5 seconds...
[09:37:26.714] INFO: Done with hot pixel readout
[09:37:38.416] INFO: PixTest:: pg_setup set to default.
[09:37:38.417] INFO: 9 hot pixels found in step 0
[09:37:39.426] INFO: Collecting data for 5 seconds...
[09:37:44.446] INFO: Done with hot pixel readout
[09:37:56.120] INFO: PixTest:: pg_setup set to default.
[09:37:56.121] INFO: 5 hot pixels found in step 1
[09:37:57.107] INFO: Collecting data for 5 seconds...
[09:38:02.127] INFO: Done with hot pixel readout
[09:38:13.807] INFO: PixTest:: pg_setup set to default.
[09:38:13.808] INFO: 2 hot pixels found in step 2
[09:38:14.795] INFO: Collecting data for 5 seconds...
[09:38:19.815] INFO: Done with hot pixel readout
[09:38:31.631] INFO: PixTest:: pg_setup set to default.
[09:38:31.632] INFO: 7 hot pixels found in step 3
[09:38:32.619] INFO: Collecting data for 5 seconds...
[09:38:37.638] INFO: Done with hot pixel readout
[09:38:49.201] INFO: PixTest:: pg_setup set to default.
[09:38:49.202] INFO: 4 hot pixels found in step 4
[09:38:50.189] INFO: Collecting data for 5 seconds...
[09:38:55.205] INFO: Done with hot pixel readout
[09:39:06.671] INFO: PixTest:: pg_setup set to default.

[09:39:06.672] INFO: 6 hot pixels found in step 5
[09:39:07.658] INFO: Collecting data for 5 seconds...
[09:39:12.674] INFO: Done with hot pixel readout
[09:39:24.170] INFO: PixTest:: pg_setup set to default.
[09:39:24.171] INFO: 6 hot pixels found in step 6
[09:39:25.158] INFO: Collecting data for 5 seconds...
[09:39:30.174] INFO: Done with hot pixel readout
[09:39:41.533] INFO: PixTest:: pg_setup set to default.

[09:39:41.534] INFO: 4 hot pixels found in step 7
[09:39:42.522] INFO: Collecting data for 5 seconds...
[09:39:47.539] INFO: Done with hot pixel readout
[09:39:58.977] INFO: PixTest:: pg_setup set to default.
[09:39:58.978] INFO: 3 hot pixels found in step 8
[09:39:59.965] INFO: Collecting data for 5 seconds...
[09:40:04.982] INFO: Done with hot pixel readout
[09:40:16.371] INFO: PixTest:: pg_setup set to default.

[09:40:16.372] INFO: 6 hot pixels found in step 9
[09:40:17.358] INFO: Collecting data for 5 seconds...
[09:40:22.374] INFO: Done with hot pixel readout
[09:40:33.783] INFO: PixTest:: pg_setup set to default.
[09:40:33.784] INFO: 2 hot pixels found in step 10
[09:40:34.772] INFO: Collecting data for 5 seconds...
[09:40:39.788] INFO: Done with hot pixel readout
[09:40:51.252] INFO: PixTest:: pg_setup set to default.

[09:40:51.253] INFO: 3 hot pixels found in step 11
[09:40:52.240] INFO: Collecting data for 5 seconds...
[09:40:57.256] INFO: Done with hot pixel readout
[09:41:08.771] INFO: PixTest:: pg_setup set to default.

[09:41:08.772] INFO: 5 hot pixels found in step 12
[09:41:09.759] INFO: Collecting data for 5 seconds...
[09:41:14.775] INFO: Done with hot pixel readout
[09:41:26.230] INFO: PixTest:: pg_setup set to default.

[09:41:26.232] INFO: 6 hot pixels found in step 13
[09:41:27.220] INFO: Collecting data for 5 seconds...
[09:41:32.236] INFO: Done with hot pixel readout
[09:41:43.743] INFO: PixTest:: pg_setup set to default.
[09:41:43.744] INFO: 3 hot pixels found in step 14
[09:41:43.775] INFO: 3 hot pixels could not be trimmed and have been masked.
[09:41:43.795] INFO: PixTest::trimHotPixels() done
[09:41:43.826] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C0.dat
[09:41:43.836] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C1.dat
[09:41:43.842] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C2.dat
[09:41:43.848] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C3.dat
[09:41:43.853] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C4.dat
[09:41:43.859] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C5.dat
[09:41:43.865] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C6.dat
[09:41:43.870] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C7.dat
[09:41:43.875] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C8.dat
[09:41:43.881] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C9.dat
[09:41:43.886] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C10.dat
[09:41:43.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C11.dat
[09:41:43.898] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C12.dat
[09:41:43.903] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C13.dat
[09:41:43.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C14.dat
[09:41:43.914] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C15.dat
[09:41:43.919] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//defaultMaskFile.dat
[09:41:43.935] INFO: enter test to run
[09:42:04.561] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[09:42:04.561] INFO: running: highrate
[09:42:04.566] INFO: ----------------------------------------------------------------------
[09:42:04.566] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[09:42:04.566] INFO: ----------------------------------------------------------------------
[09:42:04.566] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[09:42:04.566] INFO: edge/corner pixel THR is adjusted
[09:42:04.566] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:42:05.529] INFO: Collecting data for 1 seconds...
[09:42:06.533] INFO: Done with hot pixel readout
[09:42:10.365] INFO: PixTest:: pg_setup set to default.
[09:42:10.366] INFO: 0 hot pixels found in step 0
[09:42:10.371] INFO: 0 hot pixels could not be trimmed and have been masked.
[09:42:10.470] INFO: PixTest::trimHotPixels() done
[09:42:10.471] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C0.dat
[09:42:10.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C1.dat
[09:42:10.492] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C2.dat
[09:42:10.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C3.dat
[09:42:10.503] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C4.dat
[09:42:10.509] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C5.dat
[09:42:10.514] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C6.dat
[09:42:10.520] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C7.dat
[09:42:10.525] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C8.dat
[09:42:10.531] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C9.dat
[09:42:10.536] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C10.dat
[09:42:10.541] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C11.dat
[09:42:10.547] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C12.dat
[09:42:10.552] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C13.dat
[09:42:10.558] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C14.dat
[09:42:10.563] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//trimParameters35_C15.dat
[09:42:10.568] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-2-01_FPIXTest-17C-FNAL-161005-0928-150V_2016-10-05_09h28m_1475677711/000_FPIXTest_p17//defaultMaskFile.dat
[09:42:10.577] INFO: enter test to run
[09:42:33.889] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[09:42:33.889] INFO: running: xray
[09:42:33.890] INFO: ----------------------------------------------------------------------
[09:42:33.890] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:42:33.890] INFO: ----------------------------------------------------------------------
[09:42:34.865] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:42:46.619] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[09:43:15.005] INFO: Resuming triggers.
[09:43:27.763] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[09:43:57.128] INFO: Resuming triggers.
[09:44:08.884] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[09:44:38.154] INFO: Resuming triggers.
[09:44:49.912] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[09:45:19.243] INFO: Resuming triggers.
[09:45:30.006] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[09:46:00.150] INFO: Resuming triggers.
[09:46:11.913] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[09:46:41.635] INFO: Resuming triggers.
[09:46:53.395] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[09:47:22.004] INFO: Resuming triggers.
[09:47:34.766] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[09:48:04.192] INFO: Resuming triggers.
[09:48:10.454] INFO: data taking finished, elapsed time: 100 seconds.
[09:48:26.375] INFO: PixTest:: pg_setup set to default.
[09:48:26.378] INFO: PixTestXray::doPhRun() done
[09:48:26.606] INFO: enter test to run
[09:48:51.366] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[09:48:51.366] INFO: running: xray
[09:48:51.367] INFO: ----------------------------------------------------------------------
[09:48:51.367] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:48:51.367] INFO: ----------------------------------------------------------------------
[09:48:52.330] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:48:59.379] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[09:49:30.264] INFO: Resuming triggers.
[09:49:37.313] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[09:50:08.379] INFO: Resuming triggers.
[09:50:15.424] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[09:50:46.095] INFO: Resuming triggers.
[09:50:53.141] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[09:51:23.590] INFO: Resuming triggers.
[09:51:30.634] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[09:52:02.237] INFO: Resuming triggers.
[09:52:09.277] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[09:52:40.024] INFO: Resuming triggers.
[09:52:47.064] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[09:53:18.363] INFO: Resuming triggers.
[09:53:25.405] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[09:53:56.656] INFO: Resuming triggers.
[09:54:03.696] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[09:54:35.338] INFO: Resuming triggers.
[09:54:42.380] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[09:55:12.509] INFO: Resuming triggers.
[09:55:19.550] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[09:55:49.717] INFO: Resuming triggers.
[09:55:56.759] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[09:56:26.924] INFO: Resuming triggers.
[09:56:33.967] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[09:57:04.333] INFO: Resuming triggers.
[09:57:11.376] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[09:57:41.941] INFO: Resuming triggers.
[09:57:43.687] INFO: data taking finished, elapsed time: 100 seconds.
[09:57:51.227] INFO: PixTest:: pg_setup set to default.
[09:57:51.231] INFO: PixTestXray::doPhRun() done
[09:57:51.399] INFO: enter test to run
[09:58:32.154] INFO: test: HighRate no parameter change
[09:58:32.155] INFO: running: highrate
[09:58:32.172] INFO: ----------------------------------------------------------------------
[09:58:32.173] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:58:32.173] INFO: ----------------------------------------------------------------------
[09:58:32.326] INFO: Expecting 768 events.
[09:58:33.460] INFO: 768 events read in total (418ms).
[09:58:33.461] INFO: Test took 1269ms.
[09:58:34.264] INFO: Expecting 41600 events.
[09:58:37.260] INFO: 41600 events read in total (2469ms).
[09:58:37.261] INFO: Test took 3765ms.
[09:58:37.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:38.024] INFO: Expecting 41600 events.
[09:58:41.135] INFO: 41600 events read in total (2584ms).
[09:58:41.136] INFO: Test took 3831ms.
[09:58:41.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:41.901] INFO: Expecting 41600 events.
[09:58:45.078] INFO: 41600 events read in total (2650ms).
[09:58:45.079] INFO: Test took 3897ms.
[09:58:45.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:45.842] INFO: Expecting 41600 events.
[09:58:49.026] INFO: 41600 events read in total (2657ms).
[09:58:49.027] INFO: Test took 3904ms.
[09:58:49.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:49.790] INFO: Expecting 41600 events.
[09:58:52.902] INFO: 41600 events read in total (2585ms).
[09:58:52.903] INFO: Test took 3832ms.
[09:58:52.931] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:53.667] INFO: Expecting 41600 events.
[09:58:56.855] INFO: 41600 events read in total (2661ms).
[09:58:56.856] INFO: Test took 3909ms.
[09:58:56.883] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:57.620] INFO: Expecting 41600 events.
[09:59:00.822] INFO: 41600 events read in total (2675ms).
[09:59:00.823] INFO: Test took 3925ms.
[09:59:00.851] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:01.585] INFO: Expecting 41600 events.
[09:59:04.783] INFO: 41600 events read in total (2671ms).
[09:59:04.784] INFO: Test took 3917ms.
[09:59:04.814] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:05.543] INFO: Expecting 41600 events.
[09:59:08.719] INFO: 41600 events read in total (2649ms).
[09:59:08.720] INFO: Test took 3890ms.
[09:59:08.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:09.480] INFO: Expecting 41600 events.
[09:59:12.671] INFO: 41600 events read in total (2664ms).
[09:59:12.672] INFO: Test took 3909ms.
[09:59:12.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:13.437] INFO: Expecting 41600 events.
[09:59:16.613] INFO: 41600 events read in total (2650ms).
[09:59:16.614] INFO: Test took 3898ms.
[09:59:16.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:17.376] INFO: Expecting 41600 events.
[09:59:20.576] INFO: 41600 events read in total (2673ms).
[09:59:20.577] INFO: Test took 3918ms.
[09:59:20.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:21.341] INFO: Expecting 41600 events.
[09:59:24.512] INFO: 41600 events read in total (2644ms).
[09:59:24.513] INFO: Test took 3892ms.
[09:59:24.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:25.276] INFO: Expecting 41600 events.
[09:59:28.460] INFO: 41600 events read in total (2657ms).
[09:59:28.460] INFO: Test took 3903ms.
[09:59:28.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:29.224] INFO: Expecting 41600 events.
[09:59:32.405] INFO: 41600 events read in total (2654ms).
[09:59:32.406] INFO: Test took 3903ms.
[09:59:32.433] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:33.166] INFO: Expecting 41600 events.
[09:59:36.344] INFO: 41600 events read in total (2651ms).
[09:59:36.344] INFO: Test took 3894ms.
[09:59:36.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:37.109] INFO: Expecting 41600 events.
[09:59:40.302] INFO: 41600 events read in total (2666ms).
[09:59:40.303] INFO: Test took 3915ms.
[09:59:40.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:41.066] INFO: Expecting 41600 events.
[09:59:44.226] INFO: 41600 events read in total (2633ms).
[09:59:44.227] INFO: Test took 3879ms.
[09:59:44.255] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:44.987] INFO: Expecting 41600 events.
[09:59:48.145] INFO: 41600 events read in total (2631ms).
[09:59:48.146] INFO: Test took 3876ms.
[09:59:48.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:48.912] INFO: Expecting 41600 events.
[09:59:51.950] INFO: 41600 events read in total (2512ms).
[09:59:51.951] INFO: Test took 3762ms.
[09:59:51.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:52.346] INFO: enter test to run
[10:00:25.450] INFO: test: HighRate no parameter change
[10:00:25.450] INFO: running: highrate
[10:00:25.451] INFO: ----------------------------------------------------------------------
[10:00:25.451] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:00:25.451] INFO: ----------------------------------------------------------------------
[10:00:26.083] INFO: Expecting 208000 events.
[10:00:37.644] INFO: 208000 events read in total (11034ms).
[10:00:37.646] INFO: Test took 12168ms.
[10:00:37.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:37.002] INFO: number of dead pixels (per ROC): 4 0 0 0 0 0 0 0 3 0 0 0 0 0 0 0
[10:00:38.022] INFO: number of red-efficiency pixels: 73 30 66 96 120 105 94 75 76 72 88 73 95 42 13 10
[10:00:38.022] INFO: number of X-ray hits detected: 51310 32314 54893 81742 87803 90271 86761 63919 57320 72614 75161 64151 67771 40933 17045 21509
[10:00:38.022] 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:00:38.022] INFO: number of Vcal hits detected: 207729 207968 207933 207904 207878 207894 207905 207924 207776 207928 207911 207924 207905 207955 207987 207990
[10:00:38.022] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:00:38.022] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 100.0 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:00:38.022] INFO: X-ray hit rate [MHz/cm2]: 15.0 9.5 16.1 24.0 25.7 26.5 25.4 18.7 16.8 21.3 22.0 18.8 19.9 12.0 5.0 6.3
[10:00:38.022] INFO: PixTestHighRate::doXPixelAlive() done
[10:00:38.068] INFO: PixTest:: pg_setup set to default.
[10:00:38.076] INFO: enter test to run
[10:00:58.097] INFO: test: HighRate no parameter change
[10:00:58.097] INFO: running: highrate
[10:00:58.099] INFO: ----------------------------------------------------------------------
[10:00:58.099] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:00:58.099] INFO: ----------------------------------------------------------------------
[10:00:58.711] INFO: Expecting 208000 events.
[10:01:12.090] INFO: 208000 events read in total (12852ms).
[10:01:12.095] INFO: Test took 13987ms.
[10:01:12.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:12.673] INFO: number of dead pixels (per ROC): 4 0 0 0 0 0 0 0 3 0 0 0 0 0 0 0
[10:01:12.673] INFO: number of red-efficiency pixels: 148 107 279 378 465 493 449 272 229 344 291 263 288 168 45 74
[10:01:12.673] INFO: number of X-ray hits detected: 126401 79982 134920 201631 216438 221288 212896 159005 142889 178004 186730 157472 166051 101545 41220 53664
[10:01:12.673] 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:01:12.673] INFO: number of Vcal hits detected: 207651 207880 207695 207597 207494 207450 207516 207705 207618 207633 207695 207722 207702 207828 207952 207925
[10:01:12.673] 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.9 99.9 99.9 99.9 100.0 100.0
[10:01:12.673] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.8 99.8 99.7 99.8 99.9 99.8 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[10:01:12.673] INFO: X-ray hit rate [MHz/cm2]: 37.0 23.4 39.5 59.1 63.4 64.9 62.4 46.6 41.9 52.2 54.7 46.2 48.7 29.8 12.1 15.7
[10:01:12.673] INFO: PixTestHighRate::doXPixelAlive() done
[10:01:12.718] INFO: PixTest:: pg_setup set to default.
[10:01:12.730] INFO: enter test to run
[10:01:40.065] INFO: test: HighRate no parameter change
[10:01:40.065] INFO: running: highrate
[10:01:40.066] INFO: ----------------------------------------------------------------------
[10:01:40.066] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:01:40.066] INFO: ----------------------------------------------------------------------
[10:01:40.680] INFO: Expecting 208000 events.
[10:01:56.085] INFO: 208000 events read in total (14878ms).
[10:01:56.092] INFO: Test took 16018ms.
[10:01:56.510] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:56.853] INFO: number of dead pixels (per ROC): 4 0 0 0 0 0 0 0 3 0 0 0 0 0 0 0
[10:01:56.853] INFO: number of red-efficiency pixels: 347 163 695 969 1125 1140 1100 621 487 730 637 618 699 322 69 109
[10:01:56.853] INFO: number of X-ray hits detected: 190467 120531 203901 304545 325127 333597 322382 239920 215008 269047 279506 239691 251580 153259 62807 80751
[10:01:56.853] 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:01:56.854] INFO: number of Vcal hits detected: 207429 207812 207069 206818 206574 206552 206619 207254 207313 207157 207259 207301 207202 207639 207929 207885
[10:01:56.854] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.6 99.5 99.4 99.4 99.4 99.7 99.8 99.6 99.7 99.7 99.7 99.8 100.0 99.9
[10:01:56.854] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.4 99.3 99.3 99.3 99.6 99.7 99.6 99.6 99.7 99.6 99.8 100.0 99.9
[10:01:56.854] INFO: X-ray hit rate [MHz/cm2]: 55.8 35.3 59.8 89.3 95.3 97.8 94.5 70.3 63.0 78.9 81.9 70.3 73.7 44.9 18.4 23.7
[10:01:56.854] INFO: PixTestHighRate::doXPixelAlive() done
[10:01:56.903] INFO: PixTest:: pg_setup set to default.
[10:01:56.923] INFO: enter test to run
[10:02:07.353] INFO: test: exit no parameter change
[10:02:08.176] QUIET: Connection to board 33 closed.
[10:02:08.483] INFO: pXar: this is the end, my friend