[14:36:13.212] INFO: *** Welcome to pxar ***
[14:36:13.212] INFO: *** Today: 2016/06/20
[14:36:13.366] INFO: *** Version: v1.9.0-814-g7497
[14:36:13.366] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//dacParameters35_C15.dat
[14:36:13.379] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:36:13.379] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//defaultMaskFile.dat
[14:36:13.380] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C15.dat
[14:36:13.475] INFO: clk: 4
[14:36:13.475] INFO: ctr: 4
[14:36:13.475] INFO: sda: 19
[14:36:13.475] INFO: tin: 9
[14:36:13.475] INFO: level: 15
[14:36:13.475] INFO: triggerdelay: 0
[14:36:13.475] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:36:13.475] INFO: Log level: INFO
[14:36:13.493] QUIET: Connection to board DTB_WREKRL opened.
[14:36:13.497] 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:
------------------------------------------------------
[14:36:13.499] INFO: RPC call hashes of host and DTB match: 398089610
[14:36:15.036] INFO: DUT info:
[14:36:15.036] INFO: The DUT currently contains the following objects:
[14:36:15.036] INFO: 2 TBM Cores tbm08c (2 ON)
[14:36:15.037] INFO: TBM Core alpha (0): 7 registers set
[14:36:15.037] INFO: TBM Core beta (1): 7 registers set
[14:36:15.037] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:36:15.037] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.037] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:15.440] INFO: enter 'restricted' command line mode
[14:36:15.440] INFO: enter test to run
[14:36:18.307] INFO: test: PixelAlive no parameter change
[14:36:18.307] INFO: running: pixelalive
[14:36:18.316] INFO: ----------------------------------------------------------------------
[14:36:18.316] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:36:18.316] INFO: ----------------------------------------------------------------------
[14:36:18.634] INFO: Expecting 41600 events.
[14:36:22.958] INFO: 41600 events read in total (3605ms).
[14:36:23.124] INFO: Test took 4806ms.
[14:36:23.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:23.400] INFO: PixTestAlive::aliveTest() done
[14:36:23.400] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:36:23.432] INFO: enter test to run
[14:37:51.914] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:37:51.914] INFO: running: highrate
[14:37:51.915] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:37:52.147] INFO: ----------------------------------------------------------------------
[14:37:52.147] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:37:52.147] INFO: ----------------------------------------------------------------------
[14:37:52.147] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:37:52.147] INFO: edge/corner pixel THR is adjusted
[14:37:52.147] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:37:53.107] INFO: Collecting data for 5 seconds...
[14:37:58.122] INFO: Done with hot pixel readout
[14:38:09.691] INFO: PixTest:: pg_setup set to default.
[14:38:09.692] INFO: 14 hot pixels found in step 0
[14:38:10.680] INFO: Collecting data for 5 seconds...
[14:38:15.695] INFO: Done with hot pixel readout
[14:38:27.156] INFO: PixTest:: pg_setup set to default.
[14:38:27.157] INFO: 13 hot pixels found in step 1
[14:38:28.150] INFO: Collecting data for 5 seconds...
[14:38:33.165] INFO: Done with hot pixel readout
[14:38:44.646] INFO: PixTest:: pg_setup set to default.
[14:38:44.647] INFO: 14 hot pixels found in step 2
[14:38:45.637] INFO: Collecting data for 5 seconds...
[14:38:50.653] INFO: Done with hot pixel readout
[14:39:02.110] INFO: PixTest:: pg_setup set to default.
[14:39:02.111] INFO: 13 hot pixels found in step 3
[14:39:03.101] INFO: Collecting data for 5 seconds...
[14:39:08.116] INFO: Done with hot pixel readout
[14:39:19.642] INFO: PixTest:: pg_setup set to default.
[14:39:19.642] INFO: 5 hot pixels found in step 4
[14:39:20.632] INFO: Collecting data for 5 seconds...
[14:39:25.648] INFO: Done with hot pixel readout
[14:39:37.158] INFO: PixTest:: pg_setup set to default.
[14:39:37.159] INFO: 10 hot pixels found in step 5
[14:39:38.149] INFO: Collecting data for 5 seconds...
[14:39:43.165] INFO: Done with hot pixel readout
[14:39:54.666] INFO: PixTest:: pg_setup set to default.
[14:39:54.667] INFO: 7 hot pixels found in step 6
[14:39:55.658] INFO: Collecting data for 5 seconds...
[14:40:00.673] INFO: Done with hot pixel readout
[14:40:12.090] INFO: PixTest:: pg_setup set to default.
[14:40:12.091] INFO: 9 hot pixels found in step 7
[14:40:13.081] INFO: Collecting data for 5 seconds...
[14:40:18.097] INFO: Done with hot pixel readout
[14:40:29.662] INFO: PixTest:: pg_setup set to default.
[14:40:29.663] INFO: 3 hot pixels found in step 8
[14:40:30.654] INFO: Collecting data for 5 seconds...
[14:40:35.669] INFO: Done with hot pixel readout
[14:40:47.214] INFO: PixTest:: pg_setup set to default.

[14:40:47.215] INFO: 7 hot pixels found in step 9
[14:40:48.205] INFO: Collecting data for 5 seconds...
[14:40:53.221] INFO: Done with hot pixel readout
[14:41:04.740] INFO: PixTest:: pg_setup set to default.
[14:41:04.741] INFO: 5 hot pixels found in step 10
[14:41:05.731] INFO: Collecting data for 5 seconds...
[14:41:10.747] INFO: Done with hot pixel readout
[14:41:22.243] INFO: PixTest:: pg_setup set to default.
[14:41:22.244] INFO: 2 hot pixels found in step 11
[14:41:23.234] INFO: Collecting data for 5 seconds...
[14:41:28.249] INFO: Done with hot pixel readout
[14:41:39.753] INFO: PixTest:: pg_setup set to default.
[14:41:39.754] INFO: 3 hot pixels found in step 12
[14:41:40.745] INFO: Collecting data for 5 seconds...
[14:41:45.760] INFO: Done with hot pixel readout
[14:41:57.283] INFO: PixTest:: pg_setup set to default.
[14:41:57.284] INFO: 2 hot pixels found in step 13
[14:41:58.273] INFO: Collecting data for 5 seconds...
[14:42:03.289] INFO: Done with hot pixel readout
[14:42:14.794] INFO: PixTest:: pg_setup set to default.
[14:42:14.795] INFO: 1 hot pixels found in step 14
[14:42:14.829] INFO: 1 hot pixels could not be trimmed and have been masked.
[14:42:14.832] INFO: PixTest::trimHotPixels() done
[14:42:14.833] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C0.dat
[14:42:14.839] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C1.dat
[14:42:14.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C2.dat
[14:42:14.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C3.dat
[14:42:14.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C4.dat
[14:42:14.881] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C5.dat
[14:42:14.886] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C6.dat
[14:42:14.891] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C7.dat
[14:42:14.896] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C8.dat
[14:42:14.901] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C9.dat
[14:42:14.907] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C10.dat
[14:42:14.927] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C11.dat
[14:42:14.932] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C12.dat
[14:42:14.937] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C13.dat
[14:42:14.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C14.dat
[14:42:14.948] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C15.dat
[14:42:14.953] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//defaultMaskFile.dat
[14:42:14.968] INFO: enter test to run
[14:43:39.840] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:43:39.840] INFO: running: highrate
[14:43:39.845] INFO: ----------------------------------------------------------------------
[14:43:39.845] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:43:39.845] INFO: ----------------------------------------------------------------------
[14:43:39.845] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:43:39.845] INFO: edge/corner pixel THR is adjusted
[14:43:39.845] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:43:40.803] INFO: Collecting data for 1 seconds...
[14:43:41.807] INFO: Done with hot pixel readout
[14:43:45.669] INFO: PixTest:: pg_setup set to default.
[14:43:45.670] INFO: 0 hot pixels found in step 0
[14:43:45.675] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:43:45.771] INFO: PixTest::trimHotPixels() done
[14:43:45.771] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C0.dat
[14:43:45.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C1.dat
[14:43:45.786] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C2.dat
[14:43:45.792] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C3.dat
[14:43:45.797] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C4.dat
[14:43:45.802] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C5.dat
[14:43:45.808] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C6.dat
[14:43:45.813] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C7.dat
[14:43:45.818] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C8.dat
[14:43:45.823] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C9.dat
[14:43:45.828] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C10.dat
[14:43:45.834] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C11.dat
[14:43:45.839] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C12.dat
[14:43:45.844] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C13.dat
[14:43:45.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C14.dat
[14:43:45.854] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//trimParameters35_C15.dat
[14:43:45.860] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-2-06_FPIXTest-17C-FNAL-160610-0958_2016-06-10_09h58m_1465570682/000_FPIXTest_p17//defaultMaskFile.dat
[14:43:45.876] INFO: enter test to run
[14:44:01.440] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:44:01.440] INFO: running: xray
[14:44:01.441] INFO: ----------------------------------------------------------------------
[14:44:01.441] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:44:01.441] INFO: ----------------------------------------------------------------------
[14:44:02.404] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:44:14.047] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:44:42.821] INFO: Resuming triggers.
[14:44:54.465] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:45:24.015] INFO: Resuming triggers.
[14:45:35.664] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:46:05.563] INFO: Resuming triggers.
[14:46:17.216] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:46:45.798] INFO: Resuming triggers.
[14:46:57.447] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:47:26.973] INFO: Resuming triggers.
[14:47:38.628] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:48:07.900] INFO: Resuming triggers.
[14:48:19.556] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:48:48.797] INFO: Resuming triggers.
[14:49:00.448] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:49:29.732] INFO: Resuming triggers.
[14:49:36.875] INFO: data taking finished, elapsed time: 100 seconds.
[14:49:55.008] INFO: PixTest:: pg_setup set to default.
[14:49:55.011] INFO: PixTestXray::doPhRun() done
[14:49:55.144] INFO: enter test to run
[14:51:06.349] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:51:06.349] INFO: running: xray
[14:51:06.350] INFO: ----------------------------------------------------------------------
[14:51:06.350] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:51:06.350] INFO: ----------------------------------------------------------------------
[14:51:07.315] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:51:14.333] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:51:44.278] INFO: Resuming triggers.
[14:51:51.300] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:52:21.260] INFO: Resuming triggers.
[14:52:28.280] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:52:58.184] INFO: Resuming triggers.
[14:53:05.204] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:53:35.160] INFO: Resuming triggers.
[14:53:42.178] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:54:12.146] INFO: Resuming triggers.
[14:54:19.167] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:54:49.033] INFO: Resuming triggers.
[14:54:56.054] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:55:26.042] INFO: Resuming triggers.
[14:55:33.067] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:56:03.603] INFO: Resuming triggers.
[14:56:10.630] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[14:56:41.069] INFO: Resuming triggers.
[14:56:48.094] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:57:18.774] INFO: Resuming triggers.
[14:57:25.801] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[14:57:56.932] INFO: Resuming triggers.
[14:58:03.963] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:58:34.987] INFO: Resuming triggers.
[14:58:42.021] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:59:12.187] INFO: Resuming triggers.
[14:59:19.217] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:59:49.177] INFO: Resuming triggers.
[14:59:51.172] INFO: data taking finished, elapsed time: 100 seconds.
[15:00:00.014] INFO: PixTest:: pg_setup set to default.
[15:00:00.018] INFO: PixTestXray::doPhRun() done
[15:00:00.166] INFO: enter test to run
[15:00:50.561] INFO: test: HighRate no parameter change
[15:00:50.561] INFO: running: highrate
[15:00:50.562] INFO: ----------------------------------------------------------------------
[15:00:50.562] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:00:50.562] INFO: ----------------------------------------------------------------------
[15:00:50.703] INFO: Expecting 768 events.
[15:00:51.836] INFO: 768 events read in total (418ms).
[15:00:51.837] INFO: Test took 1269ms.
[15:00:52.639] INFO: Expecting 41600 events.
[15:00:55.713] INFO: 41600 events read in total (2547ms).
[15:00:55.714] INFO: Test took 3872ms.
[15:00:55.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:56.479] INFO: Expecting 41600 events.
[15:00:59.649] INFO: 41600 events read in total (2643ms).
[15:00:59.650] INFO: Test took 3888ms.
[15:00:59.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:00.412] INFO: Expecting 41600 events.
[15:01:03.624] INFO: 41600 events read in total (2686ms).
[15:01:03.625] INFO: Test took 3926ms.
[15:01:03.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:04.387] INFO: Expecting 41600 events.
[15:01:07.605] INFO: 41600 events read in total (2691ms).
[15:01:07.606] INFO: Test took 3931ms.
[15:01:07.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:08.364] INFO: Expecting 41600 events.
[15:01:11.578] INFO: 41600 events read in total (2687ms).
[15:01:11.579] INFO: Test took 3925ms.
[15:01:11.611] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:12.337] INFO: Expecting 41600 events.
[15:01:15.561] INFO: 41600 events read in total (2697ms).
[15:01:15.562] INFO: Test took 3933ms.
[15:01:15.594] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:16.321] INFO: Expecting 41600 events.
[15:01:19.536] INFO: 41600 events read in total (2688ms).
[15:01:19.537] INFO: Test took 3925ms.
[15:01:19.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:20.297] INFO: Expecting 41600 events.
[15:01:23.517] INFO: 41600 events read in total (2693ms).
[15:01:23.518] INFO: Test took 3930ms.
[15:01:23.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:24.278] INFO: Expecting 41600 events.
[15:01:27.502] INFO: 41600 events read in total (2697ms).
[15:01:27.503] INFO: Test took 3935ms.
[15:01:27.535] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:28.263] INFO: Expecting 41600 events.
[15:01:31.487] INFO: 41600 events read in total (2697ms).
[15:01:31.487] INFO: Test took 3936ms.
[15:01:31.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:32.248] INFO: Expecting 41600 events.
[15:01:35.472] INFO: 41600 events read in total (2697ms).
[15:01:35.472] INFO: Test took 3934ms.
[15:01:35.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:36.234] INFO: Expecting 41600 events.
[15:01:39.467] INFO: 41600 events read in total (2706ms).
[15:01:39.468] INFO: Test took 3946ms.
[15:01:39.500] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:40.229] INFO: Expecting 41600 events.
[15:01:43.450] INFO: 41600 events read in total (2695ms).
[15:01:43.451] INFO: Test took 3934ms.
[15:01:43.482] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:44.209] INFO: Expecting 41600 events.
[15:01:47.425] INFO: 41600 events read in total (2689ms).
[15:01:47.426] INFO: Test took 3925ms.
[15:01:47.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:48.189] INFO: Expecting 41600 events.
[15:01:51.396] INFO: 41600 events read in total (2680ms).
[15:01:51.397] INFO: Test took 3922ms.
[15:01:51.430] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:52.157] INFO: Expecting 41600 events.
[15:01:55.381] INFO: 41600 events read in total (2697ms).
[15:01:55.382] INFO: Test took 3934ms.
[15:01:55.414] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:56.143] INFO: Expecting 41600 events.
[15:01:59.352] INFO: 41600 events read in total (2682ms).
[15:01:59.353] INFO: Test took 3922ms.
[15:01:59.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:00.115] INFO: Expecting 41600 events.
[15:02:03.323] INFO: 41600 events read in total (2681ms).
[15:02:03.324] INFO: Test took 3922ms.
[15:02:03.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:04.085] INFO: Expecting 41600 events.
[15:02:07.295] INFO: 41600 events read in total (2683ms).
[15:02:07.296] INFO: Test took 3924ms.
[15:02:07.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:08.057] INFO: Expecting 41600 events.
[15:02:11.144] INFO: 41600 events read in total (2560ms).
[15:02:11.145] INFO: Test took 3799ms.
[15:02:11.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:11.544] INFO: enter test to run
[15:02:35.048] INFO: test: HighRate no parameter change
[15:02:35.048] INFO: running: highrate
[15:02:35.049] INFO: ----------------------------------------------------------------------
[15:02:35.049] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:02:35.050] INFO: ----------------------------------------------------------------------
[15:02:35.667] INFO: Expecting 208000 events.
[15:02:47.387] INFO: 208000 events read in total (11193ms).
[15:02:47.390] INFO: Test took 12332ms.
[15:02:47.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:47.771] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:02:47.771] INFO: number of red-efficiency pixels: 62 41 73 114 123 136 149 90 83 113 99 94 86 48 16 37
[15:02:47.771] INFO: number of X-ray hits detected: 59483 41924 65090 95152 98802 108102 110291 80138 83391 90407 83085 73143 72801 44876 19534 25749
[15:02:47.771] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:02:47.771] INFO: number of Vcal hits detected: 207938 207958 207926 207885 207875 207862 207849 207910 207917 207885 207898 207905 207913 207951 207984 207963
[15:02:47.771] 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 100.0 100.0 100.0 100.0 100.0 100.0
[15:02:47.771] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[15:02:47.771] INFO: X-ray hit rate [MHz/cm2]: 17.4 12.3 19.1 27.9 29.0 31.7 32.3 23.5 24.4 26.5 24.4 21.4 21.3 13.2 5.7 7.5
[15:02:47.771] INFO: PixTestHighRate::doXPixelAlive() done
[15:02:47.819] INFO: PixTest:: pg_setup set to default.
[15:02:47.835] INFO: enter test to run
[15:03:18.712] INFO: test: HighRate no parameter change
[15:03:18.712] INFO: running: highrate
[15:03:18.713] INFO: ----------------------------------------------------------------------
[15:03:18.713] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:03:18.713] INFO: ----------------------------------------------------------------------
[15:03:19.328] INFO: Expecting 208000 events.
[15:03:32.625] INFO: 208000 events read in total (12770ms).
[15:03:32.630] INFO: Test took 13908ms.
[15:03:32.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:33.192] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:03:33.192] INFO: number of red-efficiency pixels: 197 126 207 366 327 347 537 242 239 369 287 268 203 127 43 54
[15:03:33.192] INFO: number of X-ray hits detected: 122915 86543 133586 195438 204336 222704 227060 166049 170758 185547 171224 151643 150324 92287 40816 53405
[15:03:33.192] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:03:33.192] INFO: number of Vcal hits detected: 207782 207871 207775 207606 207657 207629 207424 207737 207756 207604 207698 207711 207786 207868 207957 207943
[15:03:33.192] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[15:03:33.192] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[15:03:33.192] INFO: X-ray hit rate [MHz/cm2]: 36.0 25.4 39.2 57.3 59.9 65.3 66.6 48.7 50.1 54.4 50.2 44.4 44.1 27.0 12.0 15.7
[15:03:33.192] INFO: PixTestHighRate::doXPixelAlive() done
[15:03:33.247] INFO: PixTest:: pg_setup set to default.
[15:03:33.266] INFO: enter test to run
[15:03:47.744] INFO: test: HighRate no parameter change
[15:03:47.744] INFO: running: highrate
[15:03:47.745] INFO: ----------------------------------------------------------------------
[15:03:47.745] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:03:47.745] INFO: ----------------------------------------------------------------------
[15:03:48.366] INFO: Expecting 208000 events.
[15:04:03.384] INFO: 208000 events read in total (14491ms).
[15:04:03.392] INFO: Test took 15638ms.
[15:04:03.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:04.156] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:04:04.156] INFO: number of red-efficiency pixels: 391 261 468 896 685 831 1198 519 525 858 663 688 533 277 70 113
[15:04:04.156] INFO: number of X-ray hits detected: 185237 130037 201915 295952 307559 335926 343225 250712 258574 281266 260443 227934 227350 139928 61633 80945
[15:04:04.156] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:04:04.156] INFO: number of Vcal hits detected: 207530 207717 207439 206902 207221 207007 206465 207377 207405 206973 207263 207212 207413 207693 207926 207884
[15:04:04.156] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.5 99.7 99.6 99.3 99.7 99.7 99.6 99.7 99.7 99.7 99.9 100.0 99.9
[15:04:04.156] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.5 99.3 99.7 99.7 99.5 99.6 99.6 99.7 99.9 100.0 99.9
[15:04:04.156] INFO: X-ray hit rate [MHz/cm2]: 54.3 38.1 59.2 86.7 90.1 98.5 100.6 73.5 75.8 82.4 76.3 66.8 66.6 41.0 18.1 23.7
[15:04:04.156] INFO: PixTestHighRate::doXPixelAlive() done
[15:04:04.206] INFO: PixTest:: pg_setup set to default.
[15:04:04.219] INFO: enter test to run
[15:04:06.672] INFO: test: exit no parameter change
[15:04:06.985] QUIET: Connection to board 33 closed.
[15:04:06.986] INFO: pXar: this is the end, my friend