[13:46:16.470] INFO: *** Welcome to pxar ***
[13:46:16.471] INFO: *** Today: 2016/06/10
[13:46:16.491] INFO: *** Version: v1.9.0-814-g7497
[13:46:16.491] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//dacParameters35_C15.dat
[13:46:16.521] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//tbmParameters_C0b.dat
[13:46:16.521] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//defaultMaskFile.dat
[13:46:16.529] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C15.dat
[13:46:16.632] INFO: clk: 4
[13:46:16.632] INFO: ctr: 4
[13:46:16.632] INFO: sda: 19
[13:46:16.632] INFO: tin: 9
[13:46:16.632] INFO: level: 15
[13:46:16.632] INFO: triggerdelay: 0
[13:46:16.632] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:46:16.633] INFO: Log level: INFO
[13:46:16.651] QUIET: Connection to board DTB_WREKRL opened.
[13:46:16.654] 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:
------------------------------------------------------
[13:46:16.657] INFO: RPC call hashes of host and DTB match: 398089610
[13:46:18.189] INFO: DUT info:
[13:46:18.189] INFO: The DUT currently contains the following objects:
[13:46:18.189] INFO: 2 TBM Cores tbm08c (2 ON)
[13:46:18.190] INFO: TBM Core alpha (0): 7 registers set
[13:46:18.190] INFO: TBM Core beta (1): 7 registers set
[13:46:18.190] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:46:18.190] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.190] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.594] INFO: enter 'restricted' command line mode
[13:46:18.594] INFO: enter test to run
[13:46:27.462] INFO: test: PixelAlive no parameter change
[13:46:27.462] INFO: running: pixelalive
[13:46:27.471] INFO: ----------------------------------------------------------------------
[13:46:27.471] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:46:27.471] INFO: ----------------------------------------------------------------------
[13:46:27.788] INFO: Expecting 41600 events.
[13:46:32.111] INFO: 41600 events read in total (3605ms).
[13:46:32.279] INFO: Test took 4806ms.
[13:46:32.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:32.553] INFO: PixTestAlive::aliveTest() done
[13:46:32.553] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:46:32.584] INFO: enter test to run
[13:47:03.686] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:47:03.686] INFO: running: highrate
[13:47:03.686] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//phCalibrationFitErr35_C15.dat
[13:47:03.933] INFO: ----------------------------------------------------------------------
[13:47:03.933] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:47:03.933] INFO: ----------------------------------------------------------------------
[13:47:03.933] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:47:03.933] INFO: edge/corner pixel THR is adjusted
[13:47:03.933] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:47:04.891] INFO: Collecting data for 5 seconds...
[13:47:09.910] INFO: Done with hot pixel readout
[13:47:21.747] INFO: PixTest:: pg_setup set to default.
[13:47:21.748] INFO: 9 hot pixels found in step 0
[13:47:22.739] INFO: Collecting data for 5 seconds...
[13:47:27.757] INFO: Done with hot pixel readout
[13:47:39.331] INFO: PixTest:: pg_setup set to default.
[13:47:39.332] INFO: 7 hot pixels found in step 1
[13:47:40.328] INFO: Collecting data for 5 seconds...
[13:47:45.344] INFO: Done with hot pixel readout
[13:47:56.997] INFO: PixTest:: pg_setup set to default.

[13:47:56.998] INFO: 6 hot pixels found in step 2
[13:47:57.994] INFO: Collecting data for 5 seconds...
[13:48:03.011] INFO: Done with hot pixel readout
[13:48:14.917] INFO: PixTest:: pg_setup set to default.
[13:48:14.918] INFO: 6 hot pixels found in step 3
[13:48:15.915] INFO: Collecting data for 5 seconds...
[13:48:20.933] INFO: Done with hot pixel readout
[13:48:33.091] INFO: PixTest:: pg_setup set to default.
[13:48:33.092] INFO: 4 hot pixels found in step 4
[13:48:34.088] INFO: Collecting data for 5 seconds...
[13:48:39.106] INFO: Done with hot pixel readout
[13:48:51.273] INFO: PixTest:: pg_setup set to default.
[13:48:51.273] INFO: 4 hot pixels found in step 5
[13:48:52.270] INFO: Collecting data for 5 seconds...
[13:48:57.289] INFO: Done with hot pixel readout
[13:49:09.534] INFO: PixTest:: pg_setup set to default.
[13:49:09.535] INFO: 5 hot pixels found in step 6
[13:49:10.531] INFO: Collecting data for 5 seconds...
[13:49:15.550] INFO: Done with hot pixel readout
[13:49:28.011] INFO: PixTest:: pg_setup set to default.
[13:49:28.012] INFO: 0 hot pixels found in step 7
[13:49:28.053] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:49:28.057] INFO: PixTest::trimHotPixels() done
[13:49:28.057] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C0.dat
[13:49:28.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C1.dat
[13:49:28.068] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C2.dat
[13:49:28.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C3.dat
[13:49:28.080] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C4.dat
[13:49:28.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C5.dat
[13:49:28.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C6.dat
[13:49:28.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C7.dat
[13:49:28.102] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C8.dat
[13:49:28.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C9.dat
[13:49:28.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C10.dat
[13:49:28.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C11.dat
[13:49:28.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C12.dat
[13:49:28.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C13.dat
[13:49:28.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C14.dat
[13:49:28.140] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C15.dat
[13:49:28.145] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//defaultMaskFile.dat
[13:49:28.156] INFO: enter test to run
[13:49:46.556] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:49:46.556] INFO: running: highrate
[13:49:46.561] INFO: ----------------------------------------------------------------------
[13:49:46.561] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:49:46.561] INFO: ----------------------------------------------------------------------
[13:49:46.561] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:49:46.561] INFO: edge/corner pixel THR is adjusted
[13:49:46.561] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:49:47.518] INFO: Collecting data for 1 seconds...
[13:49:48.521] INFO: Done with hot pixel readout
[13:49:52.923] INFO: PixTest:: pg_setup set to default.
[13:49:52.923] INFO: 0 hot pixels found in step 0
[13:49:52.929] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:49:52.001] INFO: PixTest::trimHotPixels() done
[13:49:52.001] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C0.dat
[13:49:53.012] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C1.dat
[13:49:53.018] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C2.dat
[13:49:53.023] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C3.dat
[13:49:53.028] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C4.dat
[13:49:53.034] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C5.dat
[13:49:53.039] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C6.dat
[13:49:53.044] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C7.dat
[13:49:53.049] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C8.dat
[13:49:53.055] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C9.dat
[13:49:53.060] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C10.dat
[13:49:53.065] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C11.dat
[13:49:53.070] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C12.dat
[13:49:53.076] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C13.dat
[13:49:53.081] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C14.dat
[13:49:53.086] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//trimParameters35_C15.dat
[13:49:53.091] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-T-NJ_FPIXTest-m20C-FNAL-160606-1155_2016-06-06_11h55m_1465232129/000_FPIXTest_m20//defaultMaskFile.dat
[13:49:53.101] INFO: enter test to run
[13:50:23.524] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:50:23.524] INFO: running: xray
[13:50:23.525] INFO: ----------------------------------------------------------------------
[13:50:23.525] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:50:23.525] INFO: ----------------------------------------------------------------------
[13:50:24.488] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:50:34.899] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[13:51:06.702] INFO: Resuming triggers.
[13:51:17.116] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:51:47.796] INFO: Resuming triggers.
[13:51:58.212] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[13:52:28.892] INFO: Resuming triggers.
[13:52:39.312] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:53:10.184] INFO: Resuming triggers.
[13:53:20.602] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[13:53:51.498] INFO: Resuming triggers.
[13:54:01.921] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[13:54:32.741] INFO: Resuming triggers.
[13:54:43.167] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:55:14.062] INFO: Resuming triggers.
[13:55:24.483] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[13:55:55.355] INFO: Resuming triggers.
[13:56:05.778] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[13:56:36.672] INFO: Resuming triggers.
[13:56:43.229] INFO: data taking finished, elapsed time: 100 seconds.
[13:57:02.925] INFO: PixTest:: pg_setup set to default.
[13:57:02.928] INFO: PixTestXray::doPhRun() done
[13:57:03.068] INFO: enter test to run
[13:57:30.697] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:57:30.697] INFO: running: xray
[13:57:30.698] INFO: ----------------------------------------------------------------------
[13:57:30.699] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:57:30.699] INFO: ----------------------------------------------------------------------
[13:57:31.662] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:57:37.433] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[13:58:09.409] INFO: Resuming triggers.
[13:58:15.184] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:58:47.970] INFO: Resuming triggers.
[13:58:53.747] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[13:59:26.124] INFO: Resuming triggers.
[13:59:31.899] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:00:04.939] INFO: Resuming triggers.
[14:00:10.715] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[14:00:44.265] INFO: Resuming triggers.
[14:00:50.040] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:01:22.791] INFO: Resuming triggers.
[14:01:28.569] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[14:02:01.256] INFO: Resuming triggers.
[14:02:07.033] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:02:38.969] INFO: Resuming triggers.
[14:02:44.748] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:03:16.769] INFO: Resuming triggers.
[14:03:22.546] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:03:54.474] INFO: Resuming triggers.
[14:04:00.255] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[14:04:32.144] INFO: Resuming triggers.
[14:04:37.926] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:05:09.865] INFO: Resuming triggers.
[14:05:15.647] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[14:05:47.621] INFO: Resuming triggers.
[14:05:53.403] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:06:25.389] INFO: Resuming triggers.
[14:06:31.174] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[14:07:03.414] INFO: Resuming triggers.
[14:07:09.199] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:07:41.367] INFO: Resuming triggers.
[14:07:47.153] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[14:08:19.190] INFO: Resuming triggers.
[14:08:21.282] INFO: data taking finished, elapsed time: 100 seconds.
[14:08:33.164] INFO: PixTest:: pg_setup set to default.
[14:08:33.167] INFO: PixTestXray::doPhRun() done
[14:08:33.316] INFO: enter test to run
[14:09:20.652] INFO: test: HighRate no parameter change
[14:09:20.652] INFO: running: highrate
[14:09:20.653] INFO: ----------------------------------------------------------------------
[14:09:20.653] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:09:20.653] INFO: ----------------------------------------------------------------------
[14:09:20.794] INFO: Expecting 768 events.
[14:09:21.928] INFO: 768 events read in total (418ms).
[14:09:21.929] INFO: Test took 1269ms.
[14:09:22.732] INFO: Expecting 41600 events.
[14:09:25.818] INFO: 41600 events read in total (2559ms).
[14:09:25.819] INFO: Test took 3883ms.
[14:09:25.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:26.580] INFO: Expecting 41600 events.
[14:09:29.803] INFO: 41600 events read in total (2696ms).
[14:09:29.804] INFO: Test took 3929ms.
[14:09:29.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:30.556] INFO: Expecting 41600 events.
[14:09:33.804] INFO: 41600 events read in total (2721ms).
[14:09:33.806] INFO: Test took 3944ms.
[14:09:33.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:34.562] INFO: Expecting 41600 events.
[14:09:37.835] INFO: 41600 events read in total (2746ms).
[14:09:37.836] INFO: Test took 3973ms.
[14:09:37.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:38.591] INFO: Expecting 41600 events.
[14:09:41.868] INFO: 41600 events read in total (2750ms).
[14:09:41.869] INFO: Test took 3976ms.
[14:09:41.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:42.625] INFO: Expecting 41600 events.
[14:09:45.900] INFO: 41600 events read in total (2748ms).
[14:09:45.901] INFO: Test took 3974ms.
[14:09:45.939] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:46.655] INFO: Expecting 41600 events.
[14:09:49.954] INFO: 41600 events read in total (2772ms).
[14:09:49.955] INFO: Test took 3997ms.
[14:09:49.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:50.707] INFO: Expecting 41600 events.
[14:09:53.990] INFO: 41600 events read in total (2757ms).
[14:09:53.991] INFO: Test took 3976ms.
[14:09:54.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:54.747] INFO: Expecting 41600 events.
[14:09:57.004] INFO: 41600 events read in total (2730ms).
[14:09:58.005] INFO: Test took 3953ms.
[14:09:58.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:58.762] INFO: Expecting 41600 events.
[14:10:02.006] INFO: 41600 events read in total (2717ms).
[14:10:02.007] INFO: Test took 3943ms.
[14:10:02.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:02.750] INFO: Expecting 41600 events.
[14:10:06.037] INFO: 41600 events read in total (2760ms).
[14:10:06.038] INFO: Test took 3972ms.
[14:10:06.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:06.790] INFO: Expecting 41600 events.
[14:10:10.103] INFO: 41600 events read in total (2786ms).
[14:10:10.104] INFO: Test took 4008ms.
[14:10:10.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:10.859] INFO: Expecting 41600 events.
[14:10:14.096] INFO: 41600 events read in total (2710ms).
[14:10:14.097] INFO: Test took 3932ms.
[14:10:14.136] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:14.851] INFO: Expecting 41600 events.
[14:10:17.916] INFO: 41600 events read in total (2538ms).
[14:10:17.917] INFO: Test took 3761ms.
[14:10:17.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:18.668] INFO: Expecting 41600 events.
[14:10:21.806] INFO: 41600 events read in total (2612ms).
[14:10:21.807] INFO: Test took 3829ms.
[14:10:21.848] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:22.562] INFO: Expecting 41600 events.
[14:10:25.762] INFO: 41600 events read in total (2673ms).
[14:10:25.764] INFO: Test took 3897ms.
[14:10:25.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:26.520] INFO: Expecting 41600 events.
[14:10:29.715] INFO: 41600 events read in total (2669ms).
[14:10:29.716] INFO: Test took 3892ms.
[14:10:29.756] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:30.468] INFO: Expecting 41600 events.
[14:10:33.482] INFO: 41600 events read in total (2487ms).
[14:10:33.483] INFO: Test took 3707ms.
[14:10:33.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:34.236] INFO: Expecting 41600 events.
[14:10:37.452] INFO: 41600 events read in total (2689ms).
[14:10:37.453] INFO: Test took 3910ms.
[14:10:37.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:38.206] INFO: Expecting 41600 events.
[14:10:41.449] INFO: 41600 events read in total (2716ms).
[14:10:41.450] INFO: Test took 3938ms.
[14:10:41.493] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:41.836] INFO: enter test to run
[14:12:08.730] INFO: test: HighRate no parameter change
[14:12:08.731] INFO: running: highrate
[14:12:08.732] INFO: ----------------------------------------------------------------------
[14:12:08.732] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:12:08.732] INFO: ----------------------------------------------------------------------
[14:12:09.353] INFO: Expecting 208000 events.
[14:12:21.620] INFO: 208000 events read in total (11740ms).
[14:12:21.623] INFO: Test took 12882ms.
[14:12:21.792] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:22.052] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:12:22.052] INFO: number of red-efficiency pixels: 80 50 79 123 156 155 176 135 103 149 174 110 98 63 29 30
[14:12:22.052] INFO: number of X-ray hits detected: 70259 51188 78093 122089 129022 133480 132121 99405 87435 113982 115359 103161 101847 60015 23538 28675
[14:12:22.053] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:12:22.053] INFO: number of Vcal hits detected: 207916 207948 207921 207874 207840 207844 207819 207862 207896 207847 207820 207889 207895 207935 207971 207970
[14:12:22.053] 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 100.0 100.0 100.0 100.0
[14:12:22.053] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:12:22.053] INFO: X-ray hit rate [MHz/cm2]: 20.6 15.0 22.9 35.8 37.8 39.1 38.7 29.1 25.6 33.4 33.8 30.2 29.9 17.6 6.9 8.4
[14:12:22.053] INFO: PixTestHighRate::doXPixelAlive() done
[14:12:22.098] INFO: PixTest:: pg_setup set to default.
[14:12:22.114] INFO: enter test to run
[14:13:03.178] INFO: test: HighRate no parameter change
[14:13:03.178] INFO: running: highrate
[14:13:03.179] INFO: ----------------------------------------------------------------------
[14:13:03.179] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:13:03.180] INFO: ----------------------------------------------------------------------
[14:13:03.809] INFO: Expecting 208000 events.
[14:13:18.401] INFO: 208000 events read in total (14065ms).
[14:13:18.407] INFO: Test took 15217ms.
[14:13:18.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:19.091] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:13:19.091] INFO: number of red-efficiency pixels: 199 177 311 502 564 616 594 484 295 634 631 366 458 277 59 85
[14:13:19.091] INFO: number of X-ray hits detected: 151960 109249 169670 265171 279405 288186 286400 215691 190252 246903 249744 223901 221622 130798 50996 62512
[14:13:19.091] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:13:19.091] INFO: number of Vcal hits detected: 207785 207812 207663 207433 207389 207319 207333 207462 207689 207309 207277 207615 207488 207696 207940 207915
[14:13:19.091] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.9 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[14:13:19.091] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.9 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[14:13:19.091] INFO: X-ray hit rate [MHz/cm2]: 44.5 32.0 49.7 77.7 81.9 84.5 83.9 63.2 55.8 72.4 73.2 65.6 65.0 38.3 14.9 18.3
[14:13:19.091] INFO: PixTestHighRate::doXPixelAlive() done
[14:13:19.139] INFO: PixTest:: pg_setup set to default.
[14:13:19.154] INFO: enter test to run
[14:13:37.106] INFO: test: HighRate no parameter change
[14:13:37.106] INFO: running: highrate
[14:13:37.107] INFO: ----------------------------------------------------------------------
[14:13:37.107] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:13:37.107] INFO: ----------------------------------------------------------------------
[14:13:37.729] INFO: Expecting 208000 events.
[14:13:54.597] INFO: 208000 events read in total (16341ms).
[14:13:54.606] INFO: Test took 17488ms.
[14:13:55.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:55.543] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:13:55.543] INFO: number of red-efficiency pixels: 453 439 734 1281 1347 1525 1374 1206 613 1419 1420 890 1115 660 113 117
[14:13:55.543] INFO: number of X-ray hits detected: 230910 167425 257828 403181 426183 438078 435170 328912 288841 375975 379096 342316 335340 198861 77708 95807
[14:13:55.543] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:13:55.543] INFO: number of Vcal hits detected: 207477 207500 206970 206390 206223 205852 206142 206279 207279 206130 206045 206902 206597 207164 207881 207879
[14:13:55.543] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.3 99.2 99.1 99.2 99.3 99.7 99.2 99.1 99.5 99.4 99.6 99.9 99.9
[14:13:55.543] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.5 99.2 99.1 99.0 99.1 99.2 99.7 99.1 99.1 99.5 99.3 99.6 99.9 99.9
[14:13:55.543] INFO: X-ray hit rate [MHz/cm2]: 67.7 49.1 75.6 118.2 124.9 128.4 127.6 96.4 84.7 110.2 111.1 100.3 98.3 58.3 22.8 28.1
[14:13:55.543] INFO: PixTestHighRate::doXPixelAlive() done
[14:13:55.598] INFO: PixTest:: pg_setup set to default.
[14:13:55.611] INFO: enter test to run
[14:13:58.481] INFO: test: exit no parameter change
[14:13:58.799] QUIET: Connection to board 33 closed.
[14:13:58.800] INFO: pXar: this is the end, my friend