[15:36:18.057] INFO: *** Welcome to pxar ***
[15:36:18.057] INFO: *** Today: 2016/07/18
[15:36:18.083] INFO: *** Version: v1.9.0-814-g7497
[15:36:18.083] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//dacParameters35_C15.dat
[15:36:18.136] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:36:18.136] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//defaultMaskFile.dat
[15:36:18.140] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C15.dat
[15:36:18.472] INFO: clk: 4
[15:36:18.472] INFO: ctr: 4
[15:36:18.472] INFO: sda: 19
[15:36:18.472] INFO: tin: 9
[15:36:18.472] INFO: level: 15
[15:36:18.472] INFO: triggerdelay: 0
[15:36:18.472] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:36:18.472] INFO: Log level: INFO
[15:36:18.488] QUIET: Connection to board DTB_WREKRL opened.
[15:36:18.491] 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:
------------------------------------------------------
[15:36:18.494] INFO: RPC call hashes of host and DTB match: 398089610
[15:36:20.024] INFO: DUT info:
[15:36:20.025] INFO: The DUT currently contains the following objects:
[15:36:20.025] INFO: 2 TBM Cores tbm08c (2 ON)
[15:36:20.025] INFO: TBM Core alpha (0): 7 registers set
[15:36:20.025] INFO: TBM Core beta (1): 7 registers set
[15:36:20.025] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:36:20.025] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.025] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:20.445] INFO: enter 'restricted' command line mode
[15:36:20.445] INFO: enter test to run
[15:36:23.266] INFO: test: PixelAlive no parameter change
[15:36:23.266] INFO: running: pixelalive
[15:36:23.275] INFO: ----------------------------------------------------------------------
[15:36:23.275] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:23.275] INFO: ----------------------------------------------------------------------
[15:36:23.602] INFO: Expecting 41600 events.
[15:36:27.766] INFO: 41600 events read in total (3446ms).
[15:36:27.926] INFO: Test took 4647ms.
[15:36:27.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:28.211] INFO: PixTestAlive::aliveTest() done
[15:36:28.211] INFO: number of dead pixels (per ROC): 0 0 0 0 7 0 0 1 30 0 0 1 0 0 0 0
[15:36:28.238] INFO: enter test to run
[15:38:49.129] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:38:49.129] INFO: running: highrate
[15:38:49.129] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:38:49.387] INFO: ----------------------------------------------------------------------
[15:38:49.387] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:38:49.387] INFO: ----------------------------------------------------------------------
[15:38:49.387] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:38:49.387] INFO: edge/corner pixel THR is adjusted
[15:38:49.387] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:38:50.347] INFO: Collecting data for 5 seconds...
[15:38:55.364] INFO: Done with hot pixel readout
[15:39:06.987] INFO: PixTest:: pg_setup set to default.
[15:39:06.988] INFO: 17 hot pixels found in step 0
[15:39:07.976] INFO: Collecting data for 5 seconds...
[15:39:12.992] INFO: Done with hot pixel readout
[15:39:24.053] INFO: PixTest:: pg_setup set to default.

[15:39:24.054] INFO: 15 hot pixels found in step 1
[15:39:25.046] INFO: Collecting data for 5 seconds...
[15:39:30.063] INFO: Done with hot pixel readout
[15:39:41.332] INFO: PixTest:: pg_setup set to default.
[15:39:41.333] INFO: 13 hot pixels found in step 2
[15:39:42.326] INFO: Collecting data for 5 seconds...
[15:39:47.341] INFO: Done with hot pixel readout
[15:39:58.626] INFO: PixTest:: pg_setup set to default.
[15:39:58.626] INFO: 12 hot pixels found in step 3
[15:39:59.618] INFO: Collecting data for 5 seconds...
[15:40:04.633] INFO: Done with hot pixel readout
[15:40:15.857] INFO: PixTest:: pg_setup set to default.
[15:40:15.858] INFO: 9 hot pixels found in step 4
[15:40:16.850] INFO: Collecting data for 5 seconds...
[15:40:21.866] INFO: Done with hot pixel readout
[15:40:33.287] INFO: PixTest:: pg_setup set to default.
[15:40:33.288] INFO: 8 hot pixels found in step 5
[15:40:34.279] INFO: Collecting data for 5 seconds...
[15:40:39.296] INFO: Done with hot pixel readout
[15:40:50.655] INFO: PixTest:: pg_setup set to default.
[15:40:50.656] INFO: 7 hot pixels found in step 6
[15:40:51.649] INFO: Collecting data for 5 seconds...
[15:40:56.664] INFO: Done with hot pixel readout
[15:41:07.432] INFO: PixTest:: pg_setup set to default.
[15:41:07.433] INFO: 5 hot pixels found in step 7
[15:41:08.426] INFO: Collecting data for 5 seconds...
[15:41:13.441] INFO: Done with hot pixel readout
[15:41:24.700] INFO: PixTest:: pg_setup set to default.
[15:41:24.701] INFO: 6 hot pixels found in step 8
[15:41:25.693] INFO: Collecting data for 5 seconds...
[15:41:30.710] INFO: Done with hot pixel readout
[15:41:42.340] INFO: PixTest:: pg_setup set to default.
[15:41:42.341] INFO: 3 hot pixels found in step 9
[15:41:43.333] INFO: Collecting data for 5 seconds...
[15:41:48.349] INFO: Done with hot pixel readout
[15:41:59.537] INFO: PixTest:: pg_setup set to default.
[15:41:59.538] INFO: 4 hot pixels found in step 10
[15:42:00.530] INFO: Collecting data for 5 seconds...
[15:42:05.545] INFO: Done with hot pixel readout
[15:42:16.880] INFO: PixTest:: pg_setup set to default.
[15:42:16.881] INFO: 1 hot pixels found in step 11
[15:42:17.872] INFO: Collecting data for 5 seconds...
[15:42:22.888] INFO: Done with hot pixel readout
[15:42:34.131] INFO: PixTest:: pg_setup set to default.
[15:42:34.131] INFO: 5 hot pixels found in step 12
[15:42:35.124] INFO: Collecting data for 5 seconds...
[15:42:40.140] INFO: Done with hot pixel readout
[15:42:51.271] INFO: PixTest:: pg_setup set to default.
[15:42:51.272] INFO: 3 hot pixels found in step 13
[15:42:52.264] INFO: Collecting data for 5 seconds...
[15:42:57.281] INFO: Done with hot pixel readout
[15:43:08.573] INFO: PixTest:: pg_setup set to default.
[15:43:08.574] INFO: 2 hot pixels found in step 14
[15:43:08.609] INFO: 2 hot pixels could not be trimmed and have been masked.
[15:43:08.613] INFO: PixTest::trimHotPixels() done
[15:43:08.613] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C0.dat
[15:43:08.619] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C1.dat
[15:43:08.625] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C2.dat
[15:43:08.631] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C3.dat
[15:43:08.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C4.dat
[15:43:08.641] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C5.dat
[15:43:08.646] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C6.dat
[15:43:08.651] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C7.dat
[15:43:08.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C8.dat
[15:43:08.662] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C9.dat
[15:43:08.667] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C10.dat
[15:43:08.673] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C11.dat
[15:43:08.678] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C12.dat
[15:43:08.683] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C13.dat
[15:43:08.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C14.dat
[15:43:08.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C15.dat
[15:43:08.698] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//defaultMaskFile.dat
[15:43:08.709] INFO: enter test to run
[15:43:42.103] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:43:42.103] INFO: running: highrate
[15:43:42.107] INFO: ----------------------------------------------------------------------
[15:43:42.107] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:43:42.107] INFO: ----------------------------------------------------------------------
[15:43:42.107] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:43:42.107] INFO: edge/corner pixel THR is adjusted
[15:43:42.107] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:43:43.064] INFO: Collecting data for 1 seconds...
[15:43:44.068] INFO: Done with hot pixel readout
[15:43:47.939] INFO: PixTest:: pg_setup set to default.
[15:43:47.940] INFO: 0 hot pixels found in step 0
[15:43:47.945] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:43:48.037] INFO: PixTest::trimHotPixels() done
[15:43:48.038] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C0.dat
[15:43:48.049] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C1.dat
[15:43:48.055] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C2.dat
[15:43:48.060] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C3.dat
[15:43:48.065] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C4.dat
[15:43:48.071] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C5.dat
[15:43:48.076] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C6.dat
[15:43:48.081] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C7.dat
[15:43:48.087] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C8.dat
[15:43:48.092] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C9.dat
[15:43:48.097] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C10.dat
[15:43:48.102] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C11.dat
[15:43:48.108] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C12.dat
[15:43:48.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C13.dat
[15:43:48.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C14.dat
[15:43:48.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C15.dat
[15:43:48.129] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-15_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//defaultMaskFile.dat
[15:43:48.138] INFO: enter test to run
[15:45:20.695] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:45:20.695] INFO: running: xray
[15:45:20.696] INFO: ----------------------------------------------------------------------
[15:45:20.696] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:45:20.696] INFO: ----------------------------------------------------------------------
[15:45:21.659] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:45:33.224] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:46:01.790] INFO: Resuming triggers.
[15:46:13.359] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:46:42.936] INFO: Resuming triggers.
[15:46:54.503] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:47:23.733] INFO: Resuming triggers.
[15:47:35.298] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:48:04.793] INFO: Resuming triggers.
[15:48:16.361] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:48:46.187] INFO: Resuming triggers.
[15:48:57.759] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:49:28.070] INFO: Resuming triggers.
[15:49:39.641] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:50:09.496] INFO: Resuming triggers.
[15:50:21.066] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:50:49.541] INFO: Resuming triggers.
[15:50:57.331] INFO: data taking finished, elapsed time: 100 seconds.
[15:51:17.095] INFO: PixTest:: pg_setup set to default.
[15:51:17.098] INFO: PixTestXray::doPhRun() done
[15:51:17.233] INFO: enter test to run
[15:54:30.691] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:54:30.691] INFO: running: xray
[15:54:30.692] INFO: ----------------------------------------------------------------------
[15:54:30.692] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:54:30.692] INFO: ----------------------------------------------------------------------
[15:54:31.660] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:54:38.579] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:55:08.207] INFO: Resuming triggers.
[15:55:15.127] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:55:45.077] INFO: Resuming triggers.
[15:55:51.999] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:56:21.574] INFO: Resuming triggers.
[15:56:28.500] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:56:58.699] INFO: Resuming triggers.
[15:57:05.620] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:57:35.259] INFO: Resuming triggers.
[15:57:42.179] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:58:11.808] INFO: Resuming triggers.
[15:58:18.724] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:58:48.957] INFO: Resuming triggers.
[15:58:55.875] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:59:26.375] INFO: Resuming triggers.
[15:59:33.299] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[16:00:04.350] INFO: Resuming triggers.
[16:00:11.275] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[16:00:40.839] INFO: Resuming triggers.
[16:00:47.761] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[16:01:18.261] INFO: Resuming triggers.
[16:01:25.187] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[16:01:55.572] INFO: Resuming triggers.
[16:02:02.495] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[16:02:31.469] INFO: Resuming triggers.
[16:02:38.390] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:03:08.059] INFO: Resuming triggers.
[16:03:11.478] INFO: data taking finished, elapsed time: 100 seconds.
[16:03:26.273] INFO: PixTest:: pg_setup set to default.
[16:03:26.276] INFO: PixTestXray::doPhRun() done
[16:03:26.425] INFO: enter test to run
[16:04:33.359] INFO: test: HighRate no parameter change
[16:04:33.359] INFO: running: highrate
[16:04:33.377] INFO: ----------------------------------------------------------------------
[16:04:33.377] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:04:33.377] INFO: ----------------------------------------------------------------------
[16:04:33.533] INFO: Expecting 768 events.
[16:04:34.667] INFO: 768 events read in total (419ms).
[16:04:34.667] INFO: Test took 1268ms.
[16:04:35.470] INFO: Expecting 41600 events.
[16:04:38.347] INFO: 41600 events read in total (2350ms).
[16:04:38.349] INFO: Test took 3676ms.
[16:04:38.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:39.112] INFO: Expecting 41600 events.
[16:04:42.277] INFO: 41600 events read in total (2639ms).
[16:04:42.278] INFO: Test took 3880ms.
[16:04:42.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:43.041] INFO: Expecting 41600 events.
[16:04:46.247] INFO: 41600 events read in total (2679ms).
[16:04:46.248] INFO: Test took 3921ms.
[16:04:46.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:47.008] INFO: Expecting 41600 events.
[16:04:50.219] INFO: 41600 events read in total (2684ms).
[16:04:50.220] INFO: Test took 3920ms.
[16:04:50.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:50.979] INFO: Expecting 41600 events.
[16:04:54.204] INFO: 41600 events read in total (2698ms).
[16:04:54.204] INFO: Test took 3932ms.
[16:04:54.238] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:54.963] INFO: Expecting 41600 events.
[16:04:58.197] INFO: 41600 events read in total (2707ms).
[16:04:58.198] INFO: Test took 3942ms.
[16:04:58.231] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:58.956] INFO: Expecting 41600 events.
[16:05:02.163] INFO: 41600 events read in total (2680ms).
[16:05:02.164] INFO: Test took 3914ms.
[16:05:02.197] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:02.925] INFO: Expecting 41600 events.
[16:05:06.070] INFO: 41600 events read in total (2618ms).
[16:05:06.071] INFO: Test took 3857ms.
[16:05:06.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:06.828] INFO: Expecting 41600 events.
[16:05:10.031] INFO: 41600 events read in total (2676ms).
[16:05:10.032] INFO: Test took 3910ms.
[16:05:10.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:10.789] INFO: Expecting 41600 events.
[16:05:13.000] INFO: 41600 events read in total (2684ms).
[16:05:13.001] INFO: Test took 3918ms.
[16:05:14.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:14.757] INFO: Expecting 41600 events.
[16:05:17.973] INFO: 41600 events read in total (2689ms).
[16:05:17.974] INFO: Test took 3923ms.
[16:05:18.007] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:18.730] INFO: Expecting 41600 events.
[16:05:21.948] INFO: 41600 events read in total (2691ms).
[16:05:21.949] INFO: Test took 3924ms.
[16:05:21.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:22.708] INFO: Expecting 41600 events.
[16:05:25.832] INFO: 41600 events read in total (2597ms).
[16:05:25.833] INFO: Test took 3833ms.
[16:05:25.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:26.580] INFO: Expecting 41600 events.
[16:05:29.760] INFO: 41600 events read in total (2653ms).
[16:05:29.761] INFO: Test took 3877ms.
[16:05:29.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:30.519] INFO: Expecting 41600 events.
[16:05:33.756] INFO: 41600 events read in total (2711ms).
[16:05:33.757] INFO: Test took 3946ms.
[16:05:33.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:34.516] INFO: Expecting 41600 events.
[16:05:37.682] INFO: 41600 events read in total (2639ms).
[16:05:37.683] INFO: Test took 3874ms.
[16:05:37.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:38.443] INFO: Expecting 41600 events.
[16:05:41.639] INFO: 41600 events read in total (2669ms).
[16:05:41.639] INFO: Test took 3904ms.
[16:05:41.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:42.398] INFO: Expecting 41600 events.
[16:05:45.529] INFO: 41600 events read in total (2604ms).
[16:05:45.530] INFO: Test took 3839ms.
[16:05:45.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:46.293] INFO: Expecting 41600 events.
[16:05:49.522] INFO: 41600 events read in total (2702ms).
[16:05:49.523] INFO: Test took 3942ms.
[16:05:49.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:50.279] INFO: Expecting 41600 events.
[16:05:53.422] INFO: 41600 events read in total (2616ms).
[16:05:53.423] INFO: Test took 3849ms.
[16:05:53.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:53.819] INFO: enter test to run
[16:07:36.462] INFO: test: HighRate no parameter change
[16:07:36.462] INFO: running: highrate
[16:07:36.463] INFO: ----------------------------------------------------------------------
[16:07:36.463] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:07:36.463] INFO: ----------------------------------------------------------------------
[16:07:37.074] INFO: Expecting 208000 events.
[16:07:48.623] INFO: 208000 events read in total (11022ms).
[16:07:48.626] INFO: Test took 12154ms.
[16:07:48.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:49.016] INFO: number of dead pixels (per ROC): 0 0 0 0 6 0 0 1 30 0 0 1 0 0 0 0
[16:07:49.016] INFO: number of red-efficiency pixels: 87 52 68 136 143 126 124 79 129 123 118 116 103 64 21 30
[16:07:49.016] INFO: number of X-ray hits detected: 59434 39620 63183 100652 104229 104504 109812 79964 78266 98813 96724 86181 85448 50484 21126 26305
[16:07:49.016] 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:07:49.016] INFO: number of Vcal hits detected: 207912 207947 207928 207861 207554 207873 207875 207870 206396 207877 207881 207832 207897 207935 207979 207970
[16:07:49.016] INFO: Vcal hit fiducial 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 100.0 100.0 100.0 100.0
[16:07:49.016] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.8 99.9 99.9 99.9 99.2 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[16:07:49.016] INFO: X-ray hit rate [MHz/cm2]: 17.4 11.6 18.5 29.5 30.6 30.6 32.2 23.4 22.9 29.0 28.4 25.3 25.0 14.8 6.2 7.7
[16:07:49.016] INFO: PixTestHighRate::doXPixelAlive() done
[16:07:49.066] INFO: PixTest:: pg_setup set to default.
[16:07:49.083] INFO: enter test to run
[16:10:01.917] INFO: test: HighRate no parameter change
[16:10:01.917] INFO: running: highrate
[16:10:01.918] INFO: ----------------------------------------------------------------------
[16:10:01.918] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:10:01.918] INFO: ----------------------------------------------------------------------
[16:10:02.537] INFO: Expecting 208000 events.
[16:10:15.702] INFO: 208000 events read in total (12638ms).
[16:10:15.707] INFO: Test took 13780ms.
[16:10:15.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:16.291] INFO: number of dead pixels (per ROC): 0 0 0 0 6 0 0 1 30 0 0 1 0 0 0 0
[16:10:16.291] INFO: number of red-efficiency pixels: 180 110 208 421 352 370 421 292 281 383 356 324 309 150 58 78
[16:10:16.291] INFO: number of X-ray hits detected: 120345 79864 128094 205881 211282 213063 222930 161969 159791 199643 196301 174471 173979 102453 42785 53987
[16:10:16.291] 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:10:16.291] INFO: number of Vcal hits detected: 207811 207886 207770 207544 207326 207609 207551 207639 206238 207591 207615 207604 207675 207839 207942 207921
[16:10:16.291] 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.8 99.9 99.9 100.0 100.0
[16:10:16.291] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.2 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[16:10:16.291] INFO: X-ray hit rate [MHz/cm2]: 35.3 23.4 37.5 60.3 61.9 62.5 65.3 47.5 46.8 58.5 57.5 51.1 51.0 30.0 12.5 15.8
[16:10:16.291] INFO: PixTestHighRate::doXPixelAlive() done
[16:10:16.344] INFO: PixTest:: pg_setup set to default.
[16:10:16.361] INFO: enter test to run
[16:10:34.876] INFO: test: HighRate no parameter change
[16:10:34.876] INFO: running: highrate
[16:10:34.878] INFO: ----------------------------------------------------------------------
[16:10:34.878] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:10:34.878] INFO: ----------------------------------------------------------------------
[16:10:35.505] INFO: Expecting 208000 events.
[16:10:50.100] INFO: 208000 events read in total (14068ms).
[16:10:50.107] INFO: Test took 15220ms.
[16:10:50.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:50.905] INFO: number of dead pixels (per ROC): 0 0 0 0 6 0 0 1 30 0 0 1 0 0 0 0
[16:10:50.906] INFO: number of red-efficiency pixels: 414 207 490 1011 809 814 961 597 548 866 927 771 727 286 67 97
[16:10:50.906] INFO: number of X-ray hits detected: 181960 122044 195627 311586 320001 324466 339445 245901 241783 301911 298428 264485 263599 156460 65610 82126
[16:10:50.906] 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:10:50.906] INFO: number of Vcal hits detected: 207527 207781 207418 206760 206758 207028 206807 207253 205903 206980 206868 207025 207172 207679 207930 207902
[16:10:50.906] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.5 99.6 99.6 99.5 99.7 99.7 99.6 99.5 99.6 99.6 99.9 100.0 100.0
[16:10:50.906] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.4 99.5 99.4 99.6 99.0 99.5 99.5 99.5 99.6 99.8 100.0 100.0
[16:10:50.906] INFO: X-ray hit rate [MHz/cm2]: 53.3 35.8 57.3 91.3 93.8 95.1 99.5 72.1 70.9 88.5 87.5 77.5 77.3 45.9 19.2 24.1
[16:10:50.906] INFO: PixTestHighRate::doXPixelAlive() done
[16:10:50.954] INFO: PixTest:: pg_setup set to default.
[16:10:50.970] INFO: enter test to run
[16:11:01.004] INFO: test: exit no parameter change
[16:11:01.288] QUIET: Connection to board 33 closed.
[16:11:01.289] INFO: pXar: this is the end, my friend