[17:28:17.263] INFO: *** Welcome to pxar ***
[17:28:17.263] INFO: *** Today: 2016/09/29
[17:28:18.482] INFO: *** Version: v1.9.0-818-g96727
[17:28:18.482] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//dacParameters35_C15.dat
[17:28:18.522] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//tbmParameters_C0b.dat
[17:28:18.522] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//defaultMaskFile.dat
[17:28:18.522] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C15.dat
[17:28:18.666] INFO: clk: 4
[17:28:18.666] INFO: ctr: 4
[17:28:18.666] INFO: sda: 19
[17:28:18.666] INFO: tin: 9
[17:28:18.666] INFO: level: 15
[17:28:18.666] INFO: triggerdelay: 0
[17:28:18.666] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[17:28:18.666] INFO: Log level: INFO
[17:28:18.705] QUIET: Connection to board DTB_WREKRL opened.
[17:28:18.708] 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:
------------------------------------------------------
[17:28:18.711] INFO: RPC call hashes of host and DTB match: 398089610
[17:28:20.255] INFO: DUT info:
[17:28:20.255] INFO: The DUT currently contains the following objects:
[17:28:20.255] INFO: 2 TBM Cores tbm08c (2 ON)
[17:28:20.268] INFO: TBM Core alpha (0): 7 registers set
[17:28:20.268] INFO: TBM Core beta (1): 7 registers set
[17:28:20.268] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:28:20.268] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.268] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.269] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:20.721] INFO: enter 'restricted' command line mode
[17:28:20.721] INFO: enter test to run
[17:28:31.840] INFO: test: PixelAlive no parameter change
[17:28:31.840] INFO: running: pixelalive
[17:28:31.859] INFO: ----------------------------------------------------------------------
[17:28:31.859] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:28:31.860] INFO: ----------------------------------------------------------------------
[17:28:32.182] INFO: Expecting 41600 events.
[17:28:36.505] INFO: 41600 events read in total (3604ms).
[17:28:36.671] INFO: Test took 4808ms.
[17:28:36.683] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:28:36.001] INFO: PixTestAlive::aliveTest() done
[17:28:36.001] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:28:37.031] INFO: enter test to run
[17:35:17.990] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[17:35:17.990] INFO: running: highrate
[17:35:18.012] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[17:35:18.288] INFO: ----------------------------------------------------------------------
[17:35:18.288] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[17:35:18.288] INFO: ----------------------------------------------------------------------
[17:35:18.288] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[17:35:18.288] INFO: edge/corner pixel THR is adjusted
[17:35:18.288] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:35:19.267] INFO: Collecting data for 5 seconds...
[17:35:24.286] INFO: Done with hot pixel readout
[17:35:36.243] INFO: PixTest:: pg_setup set to default.
[17:35:36.244] INFO: 12 hot pixels found in step 0
[17:35:37.261] INFO: Collecting data for 5 seconds...
[17:35:42.281] INFO: Done with hot pixel readout
[17:35:54.225] INFO: PixTest:: pg_setup set to default.

[17:35:54.226] INFO: 10 hot pixels found in step 1
[17:35:55.225] INFO: Collecting data for 5 seconds...
[17:36:00.244] INFO: Done with hot pixel readout
[17:36:12.308] INFO: PixTest:: pg_setup set to default.
[17:36:12.309] INFO: 15 hot pixels found in step 2
[17:36:13.305] INFO: Collecting data for 5 seconds...
[17:36:18.324] INFO: Done with hot pixel readout
[17:36:30.395] INFO: PixTest:: pg_setup set to default.
[17:36:30.396] INFO: 9 hot pixels found in step 3
[17:36:31.392] INFO: Collecting data for 5 seconds...
[17:36:36.410] INFO: Done with hot pixel readout
[17:36:48.407] INFO: PixTest:: pg_setup set to default.
[17:36:48.408] INFO: 12 hot pixels found in step 4
[17:36:49.404] INFO: Collecting data for 5 seconds...
[17:36:54.424] INFO: Done with hot pixel readout
[17:37:06.781] INFO: PixTest:: pg_setup set to default.
[17:37:06.782] INFO: 16 hot pixels found in step 5
[17:37:07.777] INFO: Collecting data for 5 seconds...
[17:37:12.795] INFO: Done with hot pixel readout
[17:37:25.058] INFO: PixTest:: pg_setup set to default.
[17:37:25.059] INFO: 13 hot pixels found in step 6
[17:37:26.055] INFO: Collecting data for 5 seconds...
[17:37:31.074] INFO: Done with hot pixel readout
[17:37:43.304] INFO: PixTest:: pg_setup set to default.

[17:37:43.305] INFO: 15 hot pixels found in step 7
[17:37:44.300] INFO: Collecting data for 5 seconds...
[17:37:49.320] INFO: Done with hot pixel readout
[17:38:01.325] INFO: PixTest:: pg_setup set to default.

[17:38:01.326] INFO: 10 hot pixels found in step 8
[17:38:02.323] INFO: Collecting data for 5 seconds...
[17:38:07.342] INFO: Done with hot pixel readout
[17:38:18.587] INFO: PixTest:: pg_setup set to default.
[17:38:18.588] INFO: 14 hot pixels found in step 9
[17:38:19.583] INFO: Collecting data for 5 seconds...
[17:38:24.603] INFO: Done with hot pixel readout
[17:38:36.397] INFO: PixTest:: pg_setup set to default.
[17:38:36.398] INFO: 8 hot pixels found in step 10
[17:38:37.394] INFO: Collecting data for 5 seconds...
[17:38:42.414] INFO: Done with hot pixel readout
[17:38:54.792] INFO: PixTest:: pg_setup set to default.
[17:38:54.793] INFO: 14 hot pixels found in step 11
[17:38:55.789] INFO: Collecting data for 5 seconds...
[17:39:00.809] INFO: Done with hot pixel readout
[17:39:13.327] INFO: PixTest:: pg_setup set to default.
[17:39:13.328] INFO: 7 hot pixels found in step 12
[17:39:14.324] INFO: Collecting data for 5 seconds...
[17:39:19.341] INFO: Done with hot pixel readout
[17:39:31.410] INFO: PixTest:: pg_setup set to default.

[17:39:31.411] INFO: 9 hot pixels found in step 13
[17:39:32.407] INFO: Collecting data for 5 seconds...
[17:39:37.424] INFO: Done with hot pixel readout
[17:39:49.482] INFO: PixTest:: pg_setup set to default.
[17:39:49.483] INFO: 7 hot pixels found in step 14
[17:39:49.521] INFO: 7 hot pixels could not be trimmed and have been masked.
[17:39:49.597] INFO: PixTest::trimHotPixels() done
[17:39:49.608] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C0.dat
[17:39:49.620] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C1.dat
[17:39:49.626] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C2.dat
[17:39:49.632] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C3.dat
[17:39:49.637] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C4.dat
[17:39:49.642] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C5.dat
[17:39:49.648] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C6.dat
[17:39:49.653] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C7.dat
[17:39:49.658] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C8.dat
[17:39:49.663] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C9.dat
[17:39:49.668] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C10.dat
[17:39:49.674] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C11.dat
[17:39:49.679] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C12.dat
[17:39:49.684] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C13.dat
[17:39:49.689] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C14.dat
[17:39:49.695] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C15.dat
[17:39:49.700] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//defaultMaskFile.dat
[17:39:49.718] INFO: enter test to run
[17:40:34.996] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[17:40:34.996] INFO: running: highrate
[17:40:34.000] INFO: ----------------------------------------------------------------------
[17:40:34.000] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[17:40:34.000] INFO: ----------------------------------------------------------------------
[17:40:34.000] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[17:40:34.000] INFO: edge/corner pixel THR is adjusted
[17:40:34.000] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:40:35.958] INFO: Collecting data for 1 seconds...
[17:40:36.962] INFO: Done with hot pixel readout
[17:40:41.181] INFO: PixTest:: pg_setup set to default.
[17:40:41.182] INFO: 0 hot pixels found in step 0
[17:40:41.187] INFO: 0 hot pixels could not be trimmed and have been masked.
[17:40:41.272] INFO: PixTest::trimHotPixels() done
[17:40:41.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C0.dat
[17:40:41.298] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C1.dat
[17:40:41.304] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C2.dat
[17:40:41.309] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C3.dat
[17:40:41.314] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C4.dat
[17:40:41.319] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C5.dat
[17:40:41.325] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C6.dat
[17:40:41.330] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C7.dat
[17:40:41.335] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C8.dat
[17:40:41.340] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C9.dat
[17:40:41.346] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C10.dat
[17:40:41.351] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C11.dat
[17:40:41.356] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C12.dat
[17:40:41.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C13.dat
[17:40:41.367] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C14.dat
[17:40:41.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C15.dat
[17:40:41.377] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-15_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//defaultMaskFile.dat
[17:40:41.387] INFO: enter test to run
[17:41:07.379] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[17:41:07.379] INFO: running: xray
[17:41:07.381] INFO: ----------------------------------------------------------------------
[17:41:07.381] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:41:07.381] INFO: ----------------------------------------------------------------------
[17:41:08.364] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:41:19.365] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[17:41:49.173] INFO: Resuming triggers.
[17:42:00.177] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[17:42:30.095] INFO: Resuming triggers.
[17:42:41.101] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[17:43:10.801] INFO: Resuming triggers.
[17:43:21.807] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[17:43:51.689] INFO: Resuming triggers.
[17:44:02.695] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[17:44:32.441] INFO: Resuming triggers.
[17:44:43.450] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[17:45:13.311] INFO: Resuming triggers.
[17:45:24.321] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[17:45:54.150] INFO: Resuming triggers.
[17:46:05.160] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[17:46:35.010] INFO: Resuming triggers.
[17:46:46.016] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[17:47:16.522] INFO: Resuming triggers.
[17:47:17.801] INFO: data taking finished, elapsed time: 100 seconds.
[17:47:21.724] INFO: PixTest:: pg_setup set to default.
[17:47:21.727] INFO: PixTestXray::doPhRun() done
[17:47:21.928] INFO: enter test to run
[17:47:54.729] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[17:47:54.729] INFO: running: xray
[17:47:54.730] INFO: ----------------------------------------------------------------------
[17:47:54.730] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:47:54.730] INFO: ----------------------------------------------------------------------
[17:47:55.721] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:48:02.064] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[17:48:33.020] INFO: Resuming triggers.
[17:48:39.363] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[17:49:10.707] INFO: Resuming triggers.
[17:49:17.051] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[17:49:48.369] INFO: Resuming triggers.
[17:49:54.714] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[17:50:26.156] INFO: Resuming triggers.
[17:50:32.497] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[17:51:04.504] INFO: Resuming triggers.
[17:51:10.847] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[17:51:42.936] INFO: Resuming triggers.
[17:51:49.276] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[17:52:20.369] INFO: Resuming triggers.
[17:52:26.708] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[17:52:57.199] INFO: Resuming triggers.
[17:53:03.540] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[17:53:34.017] INFO: Resuming triggers.
[17:53:40.360] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[17:54:10.943] INFO: Resuming triggers.
[17:54:17.287] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[17:54:47.778] INFO: Resuming triggers.
[17:54:54.116] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[17:55:24.609] INFO: Resuming triggers.
[17:55:30.953] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[17:56:01.470] INFO: Resuming triggers.
[17:56:07.821] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[17:56:38.282] INFO: Resuming triggers.
[17:56:44.635] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[17:57:15.308] INFO: Resuming triggers.
[17:57:20.512] INFO: data taking finished, elapsed time: 100 seconds.
[17:57:45.654] INFO: PixTest:: pg_setup set to default.
[17:57:45.658] INFO: PixTestXray::doPhRun() done
[17:57:45.809] INFO: enter test to run
[17:58:22.788] INFO: test: HighRate no parameter change
[17:58:22.788] INFO: running: highrate
[17:58:22.810] INFO: ----------------------------------------------------------------------
[17:58:22.810] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:58:22.811] INFO: ----------------------------------------------------------------------
[17:58:22.965] INFO: Expecting 768 events.
[17:58:24.099] INFO: 768 events read in total (418ms).
[17:58:24.099] INFO: Test took 1268ms.
[17:58:24.902] INFO: Expecting 41600 events.
[17:58:27.971] INFO: 41600 events read in total (2542ms).
[17:58:27.972] INFO: Test took 3846ms.
[17:58:28.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:28.734] INFO: Expecting 41600 events.
[17:58:31.936] INFO: 41600 events read in total (2675ms).
[17:58:31.938] INFO: Test took 3911ms.
[17:58:31.976] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:32.695] INFO: Expecting 41600 events.
[17:58:35.943] INFO: 41600 events read in total (2721ms).
[17:58:35.944] INFO: Test took 3949ms.
[17:58:35.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:36.695] INFO: Expecting 41600 events.
[17:58:39.974] INFO: 41600 events read in total (2752ms).
[17:58:39.975] INFO: Test took 3975ms.
[17:58:40.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:40.729] INFO: Expecting 41600 events.
[17:58:43.996] INFO: 41600 events read in total (2741ms).
[17:58:43.997] INFO: Test took 3966ms.
[17:58:44.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:44.753] INFO: Expecting 41600 events.
[17:58:48.027] INFO: 41600 events read in total (2747ms).
[17:58:48.028] INFO: Test took 3974ms.
[17:58:48.065] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:48.781] INFO: Expecting 41600 events.
[17:58:52.061] INFO: 41600 events read in total (2753ms).
[17:58:52.062] INFO: Test took 3976ms.
[17:58:52.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:52.817] INFO: Expecting 41600 events.
[17:58:56.101] INFO: 41600 events read in total (2757ms).
[17:58:56.102] INFO: Test took 3983ms.
[17:58:56.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:58:56.854] INFO: Expecting 41600 events.
[17:59:00.133] INFO: 41600 events read in total (2752ms).
[17:59:00.134] INFO: Test took 3976ms.
[17:59:00.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:00.886] INFO: Expecting 41600 events.
[17:59:04.158] INFO: 41600 events read in total (2745ms).
[17:59:04.159] INFO: Test took 3968ms.
[17:59:04.197] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:04.916] INFO: Expecting 41600 events.
[17:59:08.197] INFO: 41600 events read in total (2754ms).
[17:59:08.198] INFO: Test took 3981ms.
[17:59:08.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:08.950] INFO: Expecting 41600 events.
[17:59:12.228] INFO: 41600 events read in total (2751ms).
[17:59:12.229] INFO: Test took 3973ms.
[17:59:12.268] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:12.981] INFO: Expecting 41600 events.
[17:59:16.253] INFO: 41600 events read in total (2745ms).
[17:59:16.254] INFO: Test took 3967ms.
[17:59:16.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:17.009] INFO: Expecting 41600 events.
[17:59:20.281] INFO: 41600 events read in total (2745ms).
[17:59:20.282] INFO: Test took 3972ms.
[17:59:20.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:21.034] INFO: Expecting 41600 events.
[17:59:24.315] INFO: 41600 events read in total (2754ms).
[17:59:24.316] INFO: Test took 3977ms.
[17:59:24.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:25.071] INFO: Expecting 41600 events.
[17:59:28.350] INFO: 41600 events read in total (2752ms).
[17:59:28.351] INFO: Test took 3977ms.
[17:59:28.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:29.108] INFO: Expecting 41600 events.
[17:59:32.379] INFO: 41600 events read in total (2744ms).
[17:59:32.380] INFO: Test took 3973ms.
[17:59:32.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:33.133] INFO: Expecting 41600 events.
[17:59:36.411] INFO: 41600 events read in total (2751ms).
[17:59:36.412] INFO: Test took 3975ms.
[17:59:36.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:37.167] INFO: Expecting 41600 events.
[17:59:40.418] INFO: 41600 events read in total (2724ms).
[17:59:40.419] INFO: Test took 3950ms.
[17:59:40.457] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:41.172] INFO: Expecting 41600 events.
[17:59:44.307] INFO: 41600 events read in total (2608ms).
[17:59:44.308] INFO: Test took 3832ms.
[17:59:44.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:59:44.703] INFO: enter test to run
[17:59:58.420] INFO: test: HighRate no parameter change
[17:59:58.420] INFO: running: highrate
[17:59:58.421] INFO: ----------------------------------------------------------------------
[17:59:58.421] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:59:58.421] INFO: ----------------------------------------------------------------------
[17:59:59.043] INFO: Expecting 208000 events.
[18:00:11.220] INFO: 208000 events read in total (11650ms).
[18:00:11.224] INFO: Test took 12793ms.
[18:00:11.391] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:00:11.651] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:00:11.651] INFO: number of red-efficiency pixels: 87 45 91 138 157 151 159 127 111 178 136 112 118 74 15 27
[18:00:11.651] INFO: number of X-ray hits detected: 75726 50248 74604 117799 126834 133778 124535 90410 85669 115707 114961 97500 101799 62202 23042 24186
[18:00:11.651] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:00:11.651] INFO: number of Vcal hits detected: 207911 207955 207906 207859 207835 207847 207837 207872 207884 207802 207862 207887 207878 207924 207985 207973
[18:00:11.651] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[18:00:11.651] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[18:00:11.651] INFO: X-ray hit rate [MHz/cm2]: 22.2 14.7 21.9 34.5 37.2 39.2 36.5 26.5 25.1 33.9 33.7 28.6 29.8 18.2 6.8 7.1
[18:00:11.652] INFO: PixTestHighRate::doXPixelAlive() done
[18:00:11.697] INFO: PixTest:: pg_setup set to default.
[18:00:11.711] INFO: enter test to run
[18:00:36.707] INFO: test: HighRate no parameter change
[18:00:36.707] INFO: running: highrate
[18:00:36.709] INFO: ----------------------------------------------------------------------
[18:00:36.709] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:00:36.709] INFO: ----------------------------------------------------------------------
[18:00:37.331] INFO: Expecting 208000 events.
[18:00:51.390] INFO: 208000 events read in total (13533ms).
[18:00:51.395] INFO: Test took 14677ms.
[18:00:51.721] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:00:52.032] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:00:52.032] INFO: number of red-efficiency pixels: 270 164 249 413 483 472 526 358 318 453 449 296 397 224 48 50
[18:00:52.032] INFO: number of X-ray hits detected: 148134 98644 147402 231074 251142 263210 244060 177804 168000 227263 227451 191236 200869 122527 45504 48087
[18:00:52.032] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:00:52.032] INFO: number of Vcal hits detected: 207707 207829 207729 207563 207462 207489 207419 207605 207654 207445 207509 207683 207577 207763 207952 207950
[18:00:52.032] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.7 99.8 99.9 99.8 99.9 100.0 100.0
[18:00:52.032] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[18:00:52.032] INFO: X-ray hit rate [MHz/cm2]: 43.4 28.9 43.2 67.7 73.6 77.1 71.5 52.1 49.2 66.6 66.7 56.1 58.9 35.9 13.3 14.1
[18:00:52.032] INFO: PixTestHighRate::doXPixelAlive() done
[18:00:52.077] INFO: PixTest:: pg_setup set to default.
[18:00:52.088] INFO: enter test to run
[18:01:23.867] INFO: test: HighRate no parameter change
[18:01:23.867] INFO: running: highrate
[18:01:23.868] INFO: ----------------------------------------------------------------------
[18:01:23.868] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:01:23.868] INFO: ----------------------------------------------------------------------
[18:01:24.490] INFO: Expecting 208000 events.
[18:01:40.825] INFO: 208000 events read in total (15808ms).
[18:01:40.833] INFO: Test took 16955ms.
[18:01:41.353] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:01:41.721] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:01:41.721] INFO: number of red-efficiency pixels: 654 329 561 980 1107 1101 1349 839 729 1042 1067 728 933 565 86 69
[18:01:41.721] INFO: number of X-ray hits detected: 228731 151697 227171 356884 384680 405358 375720 273901 258646 349454 349980 295480 308151 189181 69743 74662
[18:01:41.721] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:01:41.721] INFO: number of Vcal hits detected: 207208 207627 207300 206799 206607 206598 206136 206909 207115 206522 206657 207124 206883 207273 207908 207927
[18:01:41.721] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.7 99.5 99.4 99.4 99.2 99.5 99.6 99.4 99.4 99.6 99.5 99.7 100.0 100.0
[18:01:41.721] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.7 99.4 99.3 99.3 99.1 99.5 99.6 99.3 99.4 99.6 99.5 99.7 100.0 100.0
[18:01:41.721] INFO: X-ray hit rate [MHz/cm2]: 67.0 44.5 66.6 104.6 112.8 118.8 110.1 80.3 75.8 102.4 102.6 86.6 90.3 55.5 20.4 21.9
[18:01:41.721] INFO: PixTestHighRate::doXPixelAlive() done
[18:01:41.769] INFO: PixTest:: pg_setup set to default.
[18:01:41.782] INFO: enter test to run
[18:01:44.947] INFO: test: exit no parameter change
[18:01:45.378] QUIET: Connection to board 33 closed.
[18:01:45.389] INFO: pXar: this is the end, my friend