[10:34:19.515] INFO: *** Welcome to pxar ***
[10:34:19.515] INFO: *** Today: 2016/07/26
[10:34:20.358] INFO: *** Version: v1.9.0-818-g96727
[10:34:20.358] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//dacParameters35_C15.dat
[10:34:20.451] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:34:20.451] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//defaultMaskFile.dat
[10:34:20.458] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C15.dat
[10:34:20.623] INFO: clk: 4
[10:34:20.623] INFO: ctr: 4
[10:34:20.623] INFO: sda: 19
[10:34:20.624] INFO: tin: 9
[10:34:20.624] INFO: level: 15
[10:34:20.624] INFO: triggerdelay: 0
[10:34:20.624] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:34:20.624] INFO: Log level: INFO
[10:34:20.645] QUIET: Connection to board DTB_WREKRL opened.
[10:34:20.648] 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:
------------------------------------------------------
[10:34:20.651] INFO: RPC call hashes of host and DTB match: 398089610
[10:34:22.179] INFO: DUT info:
[10:34:22.179] INFO: The DUT currently contains the following objects:
[10:34:22.179] INFO: 2 TBM Cores tbm08c (2 ON)
[10:34:22.199] INFO: TBM Core alpha (0): 7 registers set
[10:34:22.199] INFO: TBM Core beta (1): 7 registers set
[10:34:22.199] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:34:22.199] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.199] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.200] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:22.614] INFO: enter 'restricted' command line mode
[10:34:22.614] INFO: enter test to run
[10:34:41.938] INFO: test: PixelAlive no parameter change
[10:34:41.938] INFO: running: pixelalive
[10:34:41.960] INFO: ----------------------------------------------------------------------
[10:34:41.960] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:34:41.960] INFO: ----------------------------------------------------------------------
[10:34:42.273] INFO: Expecting 41600 events.
[10:34:46.633] INFO: 41600 events read in total (3641ms).
[10:34:46.798] INFO: Test took 4836ms.
[10:34:46.807] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:47.363] INFO: PixTestAlive::aliveTest() done
[10:34:47.364] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[10:34:47.471] INFO: enter test to run
[10:36:04.889] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:36:04.889] INFO: running: highrate
[10:36:04.900] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:36:05.211] INFO: ----------------------------------------------------------------------
[10:36:05.211] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:36:05.211] INFO: ----------------------------------------------------------------------
[10:36:05.211] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:36:05.211] INFO: edge/corner pixel THR is adjusted
[10:36:05.211] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:36:06.188] INFO: Collecting data for 5 seconds...
[10:36:11.205] INFO: Done with hot pixel readout
[10:36:22.632] INFO: PixTest:: pg_setup set to default.
[10:36:22.633] INFO: 23 hot pixels found in step 0
[10:36:23.624] INFO: Collecting data for 5 seconds...
[10:36:28.643] INFO: Done with hot pixel readout
[10:36:39.849] INFO: PixTest:: pg_setup set to default.
[10:36:39.850] INFO: 29 hot pixels found in step 1
[10:36:40.842] INFO: Collecting data for 5 seconds...
[10:36:45.862] INFO: Done with hot pixel readout
[10:36:57.414] INFO: PixTest:: pg_setup set to default.
[10:36:57.415] INFO: 25 hot pixels found in step 2
[10:36:58.407] INFO: Collecting data for 5 seconds...
[10:37:03.428] INFO: Done with hot pixel readout
[10:37:14.892] INFO: PixTest:: pg_setup set to default.


[10:37:14.893] INFO: 22 hot pixels found in step 3
[10:37:15.886] INFO: Collecting data for 5 seconds...
[10:37:20.905] INFO: Done with hot pixel readout
[10:37:32.450] INFO: PixTest:: pg_setup set to default.



[10:37:32.451] INFO: 28 hot pixels found in step 4
[10:37:33.445] INFO: Collecting data for 5 seconds...
[10:37:38.465] INFO: Done with hot pixel readout
[10:37:49.002] INFO: PixTest:: pg_setup set to default.



[10:37:49.003] INFO: 23 hot pixels found in step 5
[10:37:50.995] INFO: Collecting data for 5 seconds...
[10:37:56.015] INFO: Done with hot pixel readout
[10:38:07.446] INFO: PixTest:: pg_setup set to default.



[10:38:07.447] INFO: 22 hot pixels found in step 6
[10:38:08.439] INFO: Collecting data for 5 seconds...
[10:38:13.457] INFO: Done with hot pixel readout
[10:38:25.107] INFO: PixTest:: pg_setup set to default.






[10:38:25.108] INFO: 22 hot pixels found in step 7
[10:38:26.101] INFO: Collecting data for 5 seconds...
[10:38:31.119] INFO: Done with hot pixel readout
[10:38:43.059] INFO: PixTest:: pg_setup set to default.







[10:38:43.060] INFO: 15 hot pixels found in step 8
[10:38:44.054] INFO: Collecting data for 5 seconds...
[10:38:49.075] INFO: Done with hot pixel readout
[10:39:00.988] INFO: PixTest:: pg_setup set to default.







[10:39:00.989] INFO: 16 hot pixels found in step 9
[10:39:01.982] INFO: Collecting data for 5 seconds...
[10:39:06.001] INFO: Done with hot pixel readout
[10:39:18.339] INFO: PixTest:: pg_setup set to default.








[10:39:18.340] INFO: 16 hot pixels found in step 10
[10:39:19.333] INFO: Collecting data for 5 seconds...
[10:39:24.351] INFO: Done with hot pixel readout
[10:39:35.000] INFO: PixTest:: pg_setup set to default.








[10:39:35.001] INFO: 17 hot pixels found in step 11
[10:39:36.993] INFO: Collecting data for 5 seconds...
[10:39:42.012] INFO: Done with hot pixel readout
[10:39:53.834] INFO: PixTest:: pg_setup set to default.








[10:39:53.835] INFO: 15 hot pixels found in step 12
[10:39:54.827] INFO: Collecting data for 5 seconds...
[10:39:59.844] INFO: Done with hot pixel readout
[10:40:11.499] INFO: PixTest:: pg_setup set to default.







[10:40:11.500] INFO: 12 hot pixels found in step 13
[10:40:12.491] INFO: Collecting data for 5 seconds...
[10:40:17.511] INFO: Done with hot pixel readout
[10:40:29.253] INFO: PixTest:: pg_setup set to default.







[10:40:29.254] INFO: 11 hot pixels found in step 14
[10:40:29.290] INFO: 11 hot pixels could not be trimmed and have been masked.
[10:40:29.354] INFO: PixTest::trimHotPixels() done
[10:40:29.354] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C0.dat
[10:40:29.377] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C1.dat
[10:40:29.383] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C2.dat
[10:40:29.389] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C3.dat
[10:40:29.395] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C4.dat
[10:40:29.400] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C5.dat
[10:40:29.405] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C6.dat
[10:40:29.410] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C7.dat
[10:40:29.416] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C8.dat
[10:40:29.421] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C9.dat
[10:40:29.426] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C10.dat
[10:40:29.432] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C11.dat
[10:40:29.437] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C12.dat
[10:40:29.442] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C13.dat
[10:40:29.447] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C14.dat
[10:40:29.453] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C15.dat
[10:40:29.458] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//defaultMaskFile.dat
[10:40:29.475] INFO: enter test to run
[10:42:19.126] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:42:19.127] INFO: running: highrate
[10:42:19.131] INFO: ----------------------------------------------------------------------
[10:42:19.131] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:42:19.131] INFO: ----------------------------------------------------------------------
[10:42:19.131] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:42:19.131] INFO: edge/corner pixel THR is adjusted
[10:42:19.131] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:42:20.089] INFO: Collecting data for 1 seconds...
[10:42:21.093] INFO: Done with hot pixel readout
[10:42:25.035] INFO: PixTest:: pg_setup set to default.
[10:42:25.035] INFO: 0 hot pixels found in step 0
[10:42:25.041] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:42:25.134] INFO: PixTest::trimHotPixels() done
[10:42:25.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C0.dat
[10:42:25.144] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C1.dat
[10:42:25.149] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C2.dat
[10:42:25.154] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C3.dat
[10:42:25.160] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C4.dat
[10:42:25.165] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C5.dat
[10:42:25.170] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C6.dat
[10:42:25.175] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C7.dat
[10:42:25.181] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C8.dat
[10:42:25.186] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C9.dat
[10:42:25.191] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C10.dat
[10:42:25.196] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C11.dat
[10:42:25.201] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C12.dat
[10:42:25.207] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C13.dat
[10:42:25.212] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C14.dat
[10:42:25.217] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//trimParameters35_C15.dat
[10:42:25.222] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-44_FPIXTest-17C-FNAL-160712-1223_2016-07-12_12h23m_1468344199/000_FPIXTest_p17//defaultMaskFile.dat
[10:42:25.233] INFO: enter test to run
[10:43:14.670] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:43:14.670] INFO: running: xray
[10:43:14.672] INFO: ----------------------------------------------------------------------
[10:43:14.672] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:43:14.672] INFO: ----------------------------------------------------------------------
[10:43:15.655] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:43:27.251] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:43:56.507] INFO: Resuming triggers.
[10:44:08.103] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:44:37.366] INFO: Resuming triggers.
[10:44:48.966] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:45:18.359] INFO: Resuming triggers.
[10:45:29.961] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:45:59.253] INFO: Resuming triggers.
[10:46:10.857] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:46:40.221] INFO: Resuming triggers.
[10:46:51.825] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:47:21.070] INFO: Resuming triggers.
[10:47:32.673] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:48:01.979] INFO: Resuming triggers.
[10:48:13.583] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:48:43.323] INFO: Resuming triggers.
[10:48:50.843] INFO: data taking finished, elapsed time: 100 seconds.
[10:49:10.277] INFO: PixTest:: pg_setup set to default.
[10:49:10.280] INFO: PixTestXray::doPhRun() done
[10:49:10.504] INFO: enter test to run
[10:49:57.476] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:49:57.476] INFO: running: xray
[10:49:57.477] INFO: ----------------------------------------------------------------------
[10:49:57.477] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:49:57.477] INFO: ----------------------------------------------------------------------
[10:49:58.440] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:50:05.374] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:50:36.308] INFO: Resuming triggers.
[10:50:43.241] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:51:14.717] INFO: Resuming triggers.
[10:51:21.645] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:51:53.172] INFO: Resuming triggers.
[10:52:00.101] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[10:52:30.944] INFO: Resuming triggers.
[10:52:37.877] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:53:08.014] INFO: Resuming triggers.
[10:53:14.943] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[10:53:45.105] INFO: Resuming triggers.
[10:53:52.036] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[10:54:22.103] INFO: Resuming triggers.
[10:54:29.038] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[10:54:59.056] INFO: Resuming triggers.
[10:55:05.991] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[10:55:36.112] INFO: Resuming triggers.
[10:55:43.046] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:56:13.191] INFO: Resuming triggers.
[10:56:20.128] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[10:56:50.260] INFO: Resuming triggers.
[10:56:57.201] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:57:27.314] INFO: Resuming triggers.
[10:57:34.252] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[10:58:04.454] INFO: Resuming triggers.
[10:58:11.396] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[10:58:41.571] INFO: Resuming triggers.
[10:58:44.831] INFO: data taking finished, elapsed time: 100 seconds.
[10:58:59.283] INFO: PixTest:: pg_setup set to default.
[10:58:59.287] INFO: PixTestXray::doPhRun() done
[10:58:59.437] INFO: enter test to run
[11:01:06.975] INFO: test: HighRate no parameter change
[11:01:06.975] INFO: running: highrate
[11:01:06.976] INFO: ----------------------------------------------------------------------
[11:01:06.976] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:01:06.976] INFO: ----------------------------------------------------------------------
[11:01:07.115] INFO: Expecting 768 events.
[11:01:08.244] INFO: 768 events read in total (414ms).
[11:01:08.245] INFO: Test took 1264ms.
[11:01:09.048] INFO: Expecting 41600 events.
[11:01:12.103] INFO: 41600 events read in total (2528ms).
[11:01:12.104] INFO: Test took 3829ms.
[11:01:12.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:12.862] INFO: Expecting 41600 events.
[11:01:16.099] INFO: 41600 events read in total (2710ms).
[11:01:16.100] INFO: Test took 3945ms.
[11:01:16.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:16.862] INFO: Expecting 41600 events.
[11:01:20.047] INFO: 41600 events read in total (2658ms).
[11:01:20.048] INFO: Test took 3897ms.
[11:01:20.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:20.799] INFO: Expecting 41600 events.
[11:01:24.006] INFO: 41600 events read in total (2680ms).
[11:01:24.007] INFO: Test took 3907ms.
[11:01:24.041] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:24.764] INFO: Expecting 41600 events.
[11:01:27.991] INFO: 41600 events read in total (2701ms).
[11:01:27.992] INFO: Test took 3934ms.
[11:01:28.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:28.747] INFO: Expecting 41600 events.
[11:01:31.969] INFO: 41600 events read in total (2696ms).
[11:01:31.970] INFO: Test took 3925ms.
[11:01:31.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:32.728] INFO: Expecting 41600 events.
[11:01:35.962] INFO: 41600 events read in total (2708ms).
[11:01:35.963] INFO: Test took 3941ms.
[11:01:35.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:36.719] INFO: Expecting 41600 events.
[11:01:39.937] INFO: 41600 events read in total (2691ms).
[11:01:39.937] INFO: Test took 3923ms.
[11:01:39.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:40.695] INFO: Expecting 41600 events.
[11:01:43.950] INFO: 41600 events read in total (2728ms).
[11:01:43.951] INFO: Test took 3962ms.
[11:01:43.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:44.700] INFO: Expecting 41600 events.
[11:01:47.877] INFO: 41600 events read in total (2650ms).
[11:01:47.878] INFO: Test took 3876ms.
[11:01:47.911] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:48.632] INFO: Expecting 41600 events.
[11:01:51.895] INFO: 41600 events read in total (2736ms).
[11:01:51.896] INFO: Test took 3968ms.
[11:01:51.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:52.652] INFO: Expecting 41600 events.
[11:01:55.779] INFO: 41600 events read in total (2600ms).
[11:01:55.780] INFO: Test took 3833ms.
[11:01:55.814] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:56.540] INFO: Expecting 41600 events.
[11:01:59.711] INFO: 41600 events read in total (2644ms).
[11:01:59.712] INFO: Test took 3880ms.
[11:01:59.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:00.469] INFO: Expecting 41600 events.
[11:02:03.690] INFO: 41600 events read in total (2694ms).
[11:02:03.690] INFO: Test took 3927ms.
[11:02:03.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:04.446] INFO: Expecting 41600 events.
[11:02:07.644] INFO: 41600 events read in total (2672ms).
[11:02:07.645] INFO: Test took 3904ms.
[11:02:07.678] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:08.402] INFO: Expecting 41600 events.
[11:02:11.626] INFO: 41600 events read in total (2697ms).
[11:02:11.627] INFO: Test took 3931ms.
[11:02:11.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:12.384] INFO: Expecting 41600 events.
[11:02:15.577] INFO: 41600 events read in total (2666ms).
[11:02:15.577] INFO: Test took 3899ms.
[11:02:15.611] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:16.335] INFO: Expecting 41600 events.
[11:02:19.525] INFO: 41600 events read in total (2663ms).
[11:02:19.526] INFO: Test took 3898ms.
[11:02:19.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:20.284] INFO: Expecting 41600 events.
[11:02:23.582] INFO: 41600 events read in total (2771ms).
[11:02:23.583] INFO: Test took 4006ms.
[11:02:23.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:24.323] INFO: Expecting 41600 events.
[11:02:27.356] INFO: 41600 events read in total (2506ms).
[11:02:27.356] INFO: Test took 3722ms.
[11:02:27.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:27.752] INFO: enter test to run
[11:02:37.038] INFO: test: HighRate no parameter change
[11:02:37.038] INFO: running: highrate
[11:02:37.039] INFO: ----------------------------------------------------------------------
[11:02:37.039] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:02:37.039] INFO: ----------------------------------------------------------------------
[11:02:37.652] INFO: Expecting 208000 events.
[11:02:49.661] INFO: 208000 events read in total (11482ms).
[11:02:49.664] INFO: Test took 12618ms.
[11:02:49.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:50.059] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[11:02:50.059] INFO: number of red-efficiency pixels: 107 61 98 148 159 140 116 82 94 123 125 82 89 69 19 34
[11:02:50.059] INFO: number of X-ray hits detected: 71796 43512 65553 102503 102524 113027 105131 69045 66933 96341 95125 80714 86132 52660 21011 25532
[11:02:50.059] 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:02:50.059] INFO: number of Vcal hits detected: 207889 207938 207899 207723 207838 207858 207881 207916 207906 207875 207874 207918 207909 207929 207981 207966
[11:02:50.059] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[11:02:50.059] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[11:02:50.059] INFO: X-ray hit rate [MHz/cm2]: 21.0 12.8 19.2 30.0 30.1 33.1 30.8 20.2 19.6 28.2 27.9 23.7 25.2 15.4 6.2 7.5
[11:02:50.059] INFO: PixTestHighRate::doXPixelAlive() done
[11:02:50.104] INFO: PixTest:: pg_setup set to default.
[11:02:50.117] INFO: enter test to run
[11:03:37.302] INFO: test: HighRate no parameter change
[11:03:37.302] INFO: running: highrate
[11:03:37.303] INFO: ----------------------------------------------------------------------
[11:03:37.303] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:03:37.303] INFO: ----------------------------------------------------------------------
[11:03:37.922] INFO: Expecting 208000 events.
[11:03:51.700] INFO: 208000 events read in total (13251ms).
[11:03:51.705] INFO: Test took 14392ms.
[11:03:51.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:52.297] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[11:03:52.297] INFO: number of red-efficiency pixels: 271 154 287 356 459 415 329 197 251 403 412 230 335 151 55 58
[11:03:52.297] INFO: number of X-ray hits detected: 145454 88305 134312 209282 210260 230173 214727 141071 137824 195146 192887 164639 176532 108471 43725 52511
[11:03:52.297] 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:03:52.297] INFO: number of Vcal hits detected: 207703 207839 207688 207460 207501 207535 207650 207792 207740 207572 207568 207761 207643 207843 207945 207942
[11:03:52.297] 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.9 99.8 99.9 100.0 100.0
[11:03:52.297] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[11:03:52.297] INFO: X-ray hit rate [MHz/cm2]: 42.6 25.9 39.4 61.3 61.6 67.5 62.9 41.3 40.4 57.2 56.5 48.3 51.7 31.8 12.8 15.4
[11:03:52.297] INFO: PixTestHighRate::doXPixelAlive() done
[11:03:52.343] INFO: PixTest:: pg_setup set to default.
[11:03:52.356] INFO: enter test to run
[11:04:19.726] INFO: test: HighRate no parameter change
[11:04:19.726] INFO: running: highrate
[11:04:19.727] INFO: ----------------------------------------------------------------------
[11:04:19.727] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:04:19.727] INFO: ----------------------------------------------------------------------
[11:04:20.344] INFO: Expecting 208000 events.
[11:04:35.773] INFO: 208000 events read in total (14902ms).
[11:04:35.780] INFO: Test took 16044ms.
[11:04:36.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:36.567] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[11:04:36.567] INFO: number of red-efficiency pixels: 570 277 608 770 1048 972 859 422 510 926 924 548 718 318 83 92
[11:04:36.567] INFO: number of X-ray hits detected: 218542 133230 203762 312747 315916 346774 324206 211691 207760 293230 290109 248134 265921 162287 65241 79239
[11:04:36.567] 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:04:36.567] INFO: number of Vcal hits detected: 207247 207682 207197 206897 206715 206757 206994 207513 207431 206905 206899 207388 207142 207631 207916 207904
[11:04:36.567] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.6 99.4 99.5 99.6 99.8 99.8 99.5 99.5 99.7 99.6 99.8 100.0 100.0
[11:04:36.568] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.6 99.5 99.4 99.4 99.5 99.8 99.7 99.5 99.5 99.7 99.6 99.8 100.0 100.0
[11:04:36.568] INFO: X-ray hit rate [MHz/cm2]: 64.1 39.1 59.7 91.7 92.6 101.6 95.0 62.0 60.9 85.9 85.0 72.7 77.9 47.6 19.1 23.2
[11:04:36.568] INFO: PixTestHighRate::doXPixelAlive() done
[11:04:36.612] INFO: PixTest:: pg_setup set to default.
[11:04:36.629] INFO: enter test to run
[11:05:12.861] INFO: test: exit no parameter change
[11:05:13.248] QUIET: Connection to board 33 closed.
[11:05:13.249] INFO: pXar: this is the end, my friend