[11:07:58.043] INFO: *** Welcome to pxar ***
[11:07:58.043] INFO: *** Today: 2016/07/21
[11:07:58.120] INFO: *** Version: v1.9.0-814-g7497
[11:07:58.120] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//dacParameters35_C15.dat
[11:07:58.142] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:07:58.142] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//defaultMaskFile.dat
[11:07:58.151] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C15.dat
[11:07:58.238] INFO: clk: 4
[11:07:58.238] INFO: ctr: 4
[11:07:58.238] INFO: sda: 19
[11:07:58.238] INFO: tin: 9
[11:07:58.238] INFO: level: 15
[11:07:58.238] INFO: triggerdelay: 0
[11:07:58.238] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[11:07:58.238] INFO: Log level: INFO
[11:07:58.256] QUIET: Connection to board DTB_WREKRL opened.
[11:07:58.259] 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:
------------------------------------------------------
[11:07:58.262] INFO: RPC call hashes of host and DTB match: 398089610
[11:07:59.789] INFO: DUT info:
[11:07:59.789] INFO: The DUT currently contains the following objects:
[11:07:59.789] INFO: 2 TBM Cores tbm08c (2 ON)
[11:07:59.789] INFO: TBM Core alpha (0): 7 registers set
[11:07:59.790] INFO: TBM Core beta (1): 7 registers set
[11:07:59.790] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:07:59.790] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:07:59.790] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:08:00.194] INFO: enter 'restricted' command line mode
[11:08:00.194] INFO: enter test to run
[11:08:10.457] INFO: test: PixelAlive no parameter change
[11:08:10.457] INFO: running: pixelalive
[11:08:10.467] INFO: ----------------------------------------------------------------------
[11:08:10.467] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:08:10.467] INFO: ----------------------------------------------------------------------
[11:08:10.784] INFO: Expecting 41600 events.
[11:08:15.111] INFO: 41600 events read in total (3608ms).
[11:08:15.279] INFO: Test took 4809ms.
[11:08:15.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:15.556] INFO: PixTestAlive::aliveTest() done
[11:08:15.556] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 5 0 0 0 0 0 0 0 0
[11:08:15.587] INFO: enter test to run
[11:09:02.001] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:09:02.001] INFO: running: highrate
[11:09:02.001] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:09:03.225] INFO: ----------------------------------------------------------------------
[11:09:03.225] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:09:03.225] INFO: ----------------------------------------------------------------------
[11:09:03.225] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:09:03.225] INFO: edge/corner pixel THR is adjusted
[11:09:03.225] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:09:04.184] INFO: Collecting data for 5 seconds...
[11:09:09.204] INFO: Done with hot pixel readout
[11:09:20.765] INFO: PixTest:: pg_setup set to default.
[11:09:20.766] INFO: 18 hot pixels found in step 0
[11:09:21.756] INFO: Collecting data for 5 seconds...
[11:09:26.774] INFO: Done with hot pixel readout
[11:09:38.305] INFO: PixTest:: pg_setup set to default.
[11:09:38.306] INFO: 17 hot pixels found in step 1
[11:09:39.297] INFO: Collecting data for 5 seconds...
[11:09:44.316] INFO: Done with hot pixel readout
[11:09:55.862] INFO: PixTest:: pg_setup set to default.
[11:09:55.863] INFO: 15 hot pixels found in step 2
[11:09:56.854] INFO: Collecting data for 5 seconds...
[11:10:01.874] INFO: Done with hot pixel readout
[11:10:13.152] INFO: PixTest:: pg_setup set to default.
[11:10:13.153] INFO: 12 hot pixels found in step 3
[11:10:14.144] INFO: Collecting data for 5 seconds...
[11:10:19.164] INFO: Done with hot pixel readout
[11:10:30.605] INFO: PixTest:: pg_setup set to default.
[11:10:30.606] INFO: 14 hot pixels found in step 4
[11:10:31.597] INFO: Collecting data for 5 seconds...
[11:10:36.617] INFO: Done with hot pixel readout
[11:10:47.970] INFO: PixTest:: pg_setup set to default.
[11:10:47.971] INFO: 10 hot pixels found in step 5
[11:10:48.962] INFO: Collecting data for 5 seconds...
[11:10:53.982] INFO: Done with hot pixel readout
[11:11:05.295] INFO: PixTest:: pg_setup set to default.
[11:11:05.296] INFO: 10 hot pixels found in step 6
[11:11:06.287] INFO: Collecting data for 5 seconds...
[11:11:11.307] INFO: Done with hot pixel readout
[11:11:22.817] INFO: PixTest:: pg_setup set to default.
[11:11:22.818] INFO: 10 hot pixels found in step 7
[11:11:23.809] INFO: Collecting data for 5 seconds...
[11:11:28.829] INFO: Done with hot pixel readout
[11:11:40.469] INFO: PixTest:: pg_setup set to default.
[11:11:40.470] INFO: 9 hot pixels found in step 8
[11:11:41.461] INFO: Collecting data for 5 seconds...
[11:11:46.482] INFO: Done with hot pixel readout
[11:11:58.109] INFO: PixTest:: pg_setup set to default.
[11:11:58.110] INFO: 5 hot pixels found in step 9
[11:11:59.101] INFO: Collecting data for 5 seconds...
[11:12:04.123] INFO: Done with hot pixel readout
[11:12:15.880] INFO: PixTest:: pg_setup set to default.
[11:12:15.881] INFO: 9 hot pixels found in step 10
[11:12:16.875] INFO: Collecting data for 5 seconds...
[11:12:21.897] INFO: Done with hot pixel readout
[11:12:33.627] INFO: PixTest:: pg_setup set to default.
[11:12:33.628] INFO: 4 hot pixels found in step 11
[11:12:34.620] INFO: Collecting data for 5 seconds...
[11:12:39.641] INFO: Done with hot pixel readout
[11:12:51.495] INFO: PixTest:: pg_setup set to default.
[11:12:51.496] INFO: 10 hot pixels found in step 12
[11:12:52.493] INFO: Collecting data for 5 seconds...
[11:12:57.514] INFO: Done with hot pixel readout
[11:13:09.227] INFO: PixTest:: pg_setup set to default.
[11:13:09.228] INFO: 3 hot pixels found in step 13
[11:13:10.220] INFO: Collecting data for 5 seconds...
[11:13:15.242] INFO: Done with hot pixel readout
[11:13:26.670] INFO: PixTest:: pg_setup set to default.
[11:13:26.671] INFO: 9 hot pixels found in step 14
[11:13:26.707] INFO: 9 hot pixels could not be trimmed and have been masked.
[11:13:26.711] INFO: PixTest::trimHotPixels() done
[11:13:26.711] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C0.dat
[11:13:26.717] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C1.dat
[11:13:26.722] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C2.dat
[11:13:26.728] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C3.dat
[11:13:26.733] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C4.dat
[11:13:26.739] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C5.dat
[11:13:26.744] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C6.dat
[11:13:26.749] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C7.dat
[11:13:26.755] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C8.dat
[11:13:26.760] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C9.dat
[11:13:26.765] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C10.dat
[11:13:26.771] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C11.dat
[11:13:26.776] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C12.dat
[11:13:26.782] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C13.dat
[11:13:26.787] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C14.dat
[11:13:26.793] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C15.dat
[11:13:26.798] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//defaultMaskFile.dat
[11:13:26.808] INFO: enter test to run
[11:14:09.951] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:14:09.951] INFO: running: highrate
[11:14:09.956] INFO: ----------------------------------------------------------------------
[11:14:09.956] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:14:09.956] INFO: ----------------------------------------------------------------------
[11:14:09.956] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:14:09.956] INFO: edge/corner pixel THR is adjusted
[11:14:09.956] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:14:10.914] INFO: Collecting data for 1 seconds...
[11:14:11.918] INFO: Done with hot pixel readout
[11:14:15.778] INFO: PixTest:: pg_setup set to default.
[11:14:15.779] INFO: 0 hot pixels found in step 0
[11:14:15.784] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:14:15.884] INFO: PixTest::trimHotPixels() done
[11:14:15.884] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C0.dat
[11:14:15.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C1.dat
[11:14:15.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C2.dat
[11:14:15.902] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C3.dat
[11:14:15.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C4.dat
[11:14:15.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C5.dat
[11:14:15.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C6.dat
[11:14:15.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C7.dat
[11:14:15.928] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C8.dat
[11:14:15.934] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C9.dat
[11:14:15.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C10.dat
[11:14:15.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C11.dat
[11:14:15.950] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C12.dat
[11:14:15.955] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C13.dat
[11:14:15.960] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C14.dat
[11:14:15.965] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//trimParameters35_C15.dat
[11:14:15.970] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-07_FPIXTest-17C-FNAL-160719-1424_2016-07-19_14h24m_1468956291/000_FPIXTest_p17//defaultMaskFile.dat
[11:14:15.981] INFO: enter test to run
[11:14:55.047] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:14:55.047] INFO: running: xray
[11:14:55.048] INFO: ----------------------------------------------------------------------
[11:14:55.048] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:14:55.048] INFO: ----------------------------------------------------------------------
[11:14:56.011] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:15:07.676] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:15:37.248] INFO: Resuming triggers.
[11:15:48.916] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:16:18.461] INFO: Resuming triggers.
[11:16:30.128] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:16:59.561] INFO: Resuming triggers.
[11:17:11.232] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:17:40.636] INFO: Resuming triggers.
[11:17:52.303] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:18:21.638] INFO: Resuming triggers.
[11:18:33.304] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[11:19:02.744] INFO: Resuming triggers.
[11:19:14.414] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:19:43.796] INFO: Resuming triggers.
[11:19:55.465] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[11:20:24.682] INFO: Resuming triggers.
[11:20:31.706] INFO: data taking finished, elapsed time: 100 seconds.
[11:20:49.590] INFO: PixTest:: pg_setup set to default.
[11:20:49.593] INFO: PixTestXray::doPhRun() done
[11:20:49.753] INFO: enter test to run
[11:23:12.211] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:23:12.211] INFO: running: xray
[11:23:12.212] INFO: ----------------------------------------------------------------------
[11:23:12.212] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:23:12.212] INFO: ----------------------------------------------------------------------
[11:23:13.173] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:23:20.204] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:23:50.644] INFO: Resuming triggers.
[11:23:57.673] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[11:24:28.985] INFO: Resuming triggers.
[11:24:36.012] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[11:25:07.210] INFO: Resuming triggers.
[11:25:14.238] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:25:45.684] INFO: Resuming triggers.
[11:25:52.711] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:26:22.696] INFO: Resuming triggers.
[11:26:29.725] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[11:26:59.708] INFO: Resuming triggers.
[11:27:06.735] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[11:27:36.691] INFO: Resuming triggers.
[11:27:43.718] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[11:28:13.770] INFO: Resuming triggers.
[11:28:20.798] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[11:28:50.743] INFO: Resuming triggers.
[11:28:57.772] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[11:29:27.759] INFO: Resuming triggers.
[11:29:34.785] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[11:30:04.746] INFO: Resuming triggers.
[11:30:11.777] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[11:30:41.766] INFO: Resuming triggers.
[11:30:48.796] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[11:31:18.740] INFO: Resuming triggers.
[11:31:25.771] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[11:31:55.753] INFO: Resuming triggers.
[11:31:57.735] INFO: data taking finished, elapsed time: 100 seconds.
[11:32:06.517] INFO: PixTest:: pg_setup set to default.
[11:32:06.520] INFO: PixTestXray::doPhRun() done
[11:32:06.667] INFO: enter test to run
[11:32:52.344] INFO: test: HighRate no parameter change
[11:32:52.344] INFO: running: highrate
[11:32:52.345] INFO: ----------------------------------------------------------------------
[11:32:52.345] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:32:52.345] INFO: ----------------------------------------------------------------------
[11:32:52.486] INFO: Expecting 768 events.
[11:32:53.620] INFO: 768 events read in total (418ms).
[11:32:53.620] INFO: Test took 1268ms.
[11:32:54.423] INFO: Expecting 41600 events.
[11:32:57.476] INFO: 41600 events read in total (2526ms).
[11:32:57.477] INFO: Test took 3850ms.
[11:32:57.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:58.242] INFO: Expecting 41600 events.
[11:33:01.413] INFO: 41600 events read in total (2645ms).
[11:33:01.414] INFO: Test took 3890ms.
[11:33:01.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:02.173] INFO: Expecting 41600 events.
[11:33:05.387] INFO: 41600 events read in total (2687ms).
[11:33:05.388] INFO: Test took 3923ms.
[11:33:05.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:06.147] INFO: Expecting 41600 events.
[11:33:09.350] INFO: 41600 events read in total (2676ms).
[11:33:09.351] INFO: Test took 3912ms.
[11:33:09.384] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:10.113] INFO: Expecting 41600 events.
[11:33:13.342] INFO: 41600 events read in total (2702ms).
[11:33:13.343] INFO: Test took 3942ms.
[11:33:13.375] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:14.102] INFO: Expecting 41600 events.
[11:33:17.317] INFO: 41600 events read in total (2688ms).
[11:33:17.318] INFO: Test took 3924ms.
[11:33:17.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:18.079] INFO: Expecting 41600 events.
[11:33:21.299] INFO: 41600 events read in total (2694ms).
[11:33:21.299] INFO: Test took 3931ms.
[11:33:21.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:22.059] INFO: Expecting 41600 events.
[11:33:25.295] INFO: 41600 events read in total (2709ms).
[11:33:25.296] INFO: Test took 3947ms.
[11:33:25.329] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:26.055] INFO: Expecting 41600 events.
[11:33:29.283] INFO: 41600 events read in total (2701ms).
[11:33:29.284] INFO: Test took 3937ms.
[11:33:29.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:30.045] INFO: Expecting 41600 events.
[11:33:33.266] INFO: 41600 events read in total (2694ms).
[11:33:33.267] INFO: Test took 3932ms.
[11:33:33.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:34.028] INFO: Expecting 41600 events.
[11:33:37.256] INFO: 41600 events read in total (2701ms).
[11:33:37.257] INFO: Test took 3941ms.
[11:33:37.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:38.013] INFO: Expecting 41600 events.
[11:33:41.242] INFO: 41600 events read in total (2702ms).
[11:33:41.243] INFO: Test took 3936ms.
[11:33:41.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:41.000] INFO: Expecting 41600 events.
[11:33:45.230] INFO: 41600 events read in total (2703ms).
[11:33:45.231] INFO: Test took 3938ms.
[11:33:45.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:45.991] INFO: Expecting 41600 events.
[11:33:49.212] INFO: 41600 events read in total (2694ms).
[11:33:49.213] INFO: Test took 3931ms.
[11:33:49.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:49.974] INFO: Expecting 41600 events.
[11:33:53.209] INFO: 41600 events read in total (2708ms).
[11:33:53.210] INFO: Test took 3947ms.
[11:33:53.243] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:53.972] INFO: Expecting 41600 events.
[11:33:57.276] INFO: 41600 events read in total (2777ms).
[11:33:57.276] INFO: Test took 4014ms.
[11:33:57.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:58.035] INFO: Expecting 41600 events.
[11:34:01.351] INFO: 41600 events read in total (2789ms).
[11:34:01.352] INFO: Test took 4026ms.
[11:34:01.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:02.106] INFO: Expecting 41600 events.
[11:34:05.392] INFO: 41600 events read in total (2759ms).
[11:34:05.392] INFO: Test took 3988ms.
[11:34:05.426] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:06.151] INFO: Expecting 41600 events.
[11:34:09.482] INFO: 41600 events read in total (2804ms).
[11:34:09.483] INFO: Test took 4038ms.
[11:34:09.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:10.242] INFO: Expecting 41600 events.
[11:34:13.266] INFO: 41600 events read in total (2497ms).
[11:34:13.267] INFO: Test took 3733ms.
[11:34:13.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:13.663] INFO: enter test to run
[11:34:51.343] INFO: test: HighRate no parameter change
[11:34:51.343] INFO: running: highrate
[11:34:51.344] INFO: ----------------------------------------------------------------------
[11:34:51.344] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:34:51.344] INFO: ----------------------------------------------------------------------
[11:34:51.969] INFO: Expecting 208000 events.
[11:35:03.755] INFO: 208000 events read in total (11259ms).
[11:35:03.758] INFO: Test took 12403ms.
[11:35:03.898] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:35:04.149] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 5 0 100 0 0 0 0 0 0
[11:35:04.149] INFO: number of red-efficiency pixels: 85 55 79 119 103 151 114 91 88 191 85 89 98 55 16 31
[11:35:04.149] INFO: number of X-ray hits detected: 64154 42969 64610 101866 106368 112394 112555 79863 72125 83892 87925 77531 76928 45888 21363 23207
[11:35:04.149] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:35:04.149] INFO: number of Vcal hits detected: 207915 207896 207918 207880 207892 207845 207882 207663 207910 202882 207911 207909 207901 207945 207983 207969
[11:35:04.149] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[11:35:04.149] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.8 100.0 97.5 100.0 100.0 100.0 100.0 100.0 100.0
[11:35:04.149] INFO: X-ray hit rate [MHz/cm2]: 18.8 12.6 18.9 29.9 31.2 32.9 33.0 23.4 21.1 24.6 25.8 22.7 22.5 13.5 6.3 6.8
[11:35:04.149] INFO: PixTestHighRate::doXPixelAlive() done
[11:35:04.193] INFO: PixTest:: pg_setup set to default.
[11:35:04.204] INFO: enter test to run
[11:35:33.670] INFO: test: HighRate no parameter change
[11:35:33.670] INFO: running: highrate
[11:35:33.672] INFO: ----------------------------------------------------------------------
[11:35:33.672] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:35:33.672] INFO: ----------------------------------------------------------------------
[11:35:34.295] INFO: Expecting 208000 events.
[11:35:47.623] INFO: 208000 events read in total (12801ms).
[11:35:47.628] INFO: Test took 13948ms.
[11:35:47.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:35:48.196] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 5 0 101 0 0 0 0 0 0
[11:35:48.196] INFO: number of red-efficiency pixels: 231 122 190 417 469 514 390 238 222 409 333 245 262 152 53 53
[11:35:48.196] INFO: number of X-ray hits detected: 130615 87726 130679 207684 216475 229164 227990 161184 147352 168603 179478 156382 155444 92058 43982 47469
[11:35:48.196] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:35:48.196] INFO: number of Vcal hits detected: 207754 207822 207803 207544 207487 207426 207588 207499 207765 202545 207649 207737 207723 207839 207947 207947
[11:35:48.196] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[11:35:48.196] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.9 97.4 99.8 99.9 99.9 99.9 100.0 100.0
[11:35:48.196] INFO: X-ray hit rate [MHz/cm2]: 38.3 25.7 38.3 60.9 63.5 67.2 66.8 47.2 43.2 49.4 52.6 45.8 45.6 27.0 12.9 13.9
[11:35:48.196] INFO: PixTestHighRate::doXPixelAlive() done
[11:35:48.242] INFO: PixTest:: pg_setup set to default.
[11:35:48.257] INFO: enter test to run
[11:36:25.294] INFO: test: HighRate no parameter change
[11:36:25.294] INFO: running: highrate
[11:36:25.295] INFO: ----------------------------------------------------------------------
[11:36:25.295] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:36:25.295] INFO: ----------------------------------------------------------------------
[11:36:25.918] INFO: Expecting 208000 events.
[11:36:41.470] INFO: 208000 events read in total (15026ms).
[11:36:41.477] INFO: Test took 16171ms.
[11:36:41.889] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:36:42.232] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 5 0 140 0 0 0 0 0 0
[11:36:42.232] INFO: number of red-efficiency pixels: 462 281 412 1052 1119 1226 947 491 494 797 732 565 517 353 101 82
[11:36:42.232] INFO: number of X-ray hits detected: 198206 132611 198198 315316 328578 346996 345720 244883 223928 255616 272851 237716 236588 141592 66524 72503
[11:36:42.232] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:36:42.232] INFO: number of Vcal hits detected: 207435 207647 207511 206736 206565 206411 206832 207183 207454 200251 207161 207358 207428 207603 207896 207917
[11:36:42.232] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.4 99.4 99.3 99.5 99.7 99.8 99.7 99.6 99.7 99.8 99.8 100.0 100.0
[11:36:42.233] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.8 99.4 99.3 99.2 99.4 99.6 99.7 96.3 99.6 99.7 99.7 99.8 100.0 100.0
[11:36:42.233] INFO: X-ray hit rate [MHz/cm2]: 58.1 38.9 58.1 92.4 96.3 101.7 101.3 71.8 65.6 74.9 80.0 69.7 69.3 41.5 19.5 21.3
[11:36:42.233] INFO: PixTestHighRate::doXPixelAlive() done
[11:36:42.280] INFO: PixTest:: pg_setup set to default.
[11:36:42.300] INFO: enter test to run
[11:37:04.910] INFO: test: exit no parameter change
[11:37:05.196] QUIET: Connection to board 33 closed.
[11:37:05.197] INFO: pXar: this is the end, my friend