[10:32:15.486] INFO: *** Welcome to pxar ***
[10:32:15.486] INFO: *** Today: 2016/06/21
[10:32:15.720] INFO: *** Version: v1.9.0-814-g7497
[10:32:15.720] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//dacParameters35_C15.dat
[10:32:15.785] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:32:15.785] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//defaultMaskFile.dat
[10:32:15.787] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C15.dat
[10:32:15.929] INFO: clk: 4
[10:32:15.929] INFO: ctr: 4
[10:32:15.929] INFO: sda: 19
[10:32:15.929] INFO: tin: 9
[10:32:15.929] INFO: level: 15
[10:32:15.929] INFO: triggerdelay: 0
[10:32:15.929] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:32:15.929] INFO: Log level: INFO
[10:32:15.945] QUIET: Connection to board DTB_WREK4U opened.
[10:32:15.948] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[10:32:15.951] INFO: RPC call hashes of host and DTB match: 398089610
[10:32:17.481] INFO: DUT info:
[10:32:17.481] INFO: The DUT currently contains the following objects:
[10:32:17.481] INFO: 2 TBM Cores tbm08c (2 ON)
[10:32:17.481] INFO: TBM Core alpha (0): 7 registers set
[10:32:17.481] INFO: TBM Core beta (1): 7 registers set
[10:32:17.481] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:32:17.481] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.481] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.482] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.482] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.482] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.482] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.482] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:17.885] INFO: enter 'restricted' command line mode
[10:32:17.885] INFO: enter test to run
[10:32:33.169] INFO: test: PixelAlive no parameter change
[10:32:33.169] INFO: running: pixelalive
[10:32:33.178] INFO: ----------------------------------------------------------------------
[10:32:33.178] 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:32:33.178] INFO: ----------------------------------------------------------------------
[10:32:33.502] INFO: Expecting 41600 events.
[10:32:37.861] INFO: 41600 events read in total (3640ms).
[10:32:38.025] INFO: Test took 4845ms.
[10:32:38.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:38.298] INFO: PixTestAlive::aliveTest() done
[10:32:38.298] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:32:38.327] INFO: enter test to run
[10:33:30.240] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:33:30.241] INFO: running: highrate
[10:33:30.255] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:33:30.571] INFO: ----------------------------------------------------------------------
[10:33:30.571] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:33:30.571] INFO: ----------------------------------------------------------------------
[10:33:30.571] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:33:30.571] INFO: edge/corner pixel THR is adjusted
[10:33:30.571] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:33:31.531] INFO: Collecting data for 5 seconds...
[10:33:36.548] INFO: Done with hot pixel readout
[10:33:47.347] INFO: PixTest:: pg_setup set to default.
[10:33:47.348] INFO: 5 hot pixels found in step 0
[10:33:48.335] INFO: Collecting data for 5 seconds...
[10:33:53.352] INFO: Done with hot pixel readout
[10:34:04.378] INFO: PixTest:: pg_setup set to default.
[10:34:04.379] INFO: 6 hot pixels found in step 1
[10:34:05.365] INFO: Collecting data for 5 seconds...
[10:34:10.381] INFO: Done with hot pixel readout
[10:34:21.372] INFO: PixTest:: pg_setup set to default.
[10:34:21.373] INFO: 4 hot pixels found in step 2
[10:34:22.359] INFO: Collecting data for 5 seconds...
[10:34:27.376] INFO: Done with hot pixel readout
[10:34:38.181] INFO: PixTest:: pg_setup set to default.
[10:34:38.182] INFO: 6 hot pixels found in step 3
[10:34:39.170] INFO: Collecting data for 5 seconds...
[10:34:44.186] INFO: Done with hot pixel readout
[10:34:55.221] INFO: PixTest:: pg_setup set to default.
[10:34:55.222] INFO: 5 hot pixels found in step 4
[10:34:56.209] INFO: Collecting data for 5 seconds...
[10:35:01.225] INFO: Done with hot pixel readout
[10:35:11.938] INFO: PixTest:: pg_setup set to default.
[10:35:11.939] INFO: 4 hot pixels found in step 5
[10:35:12.924] INFO: Collecting data for 5 seconds...
[10:35:17.941] INFO: Done with hot pixel readout
[10:35:28.993] INFO: PixTest:: pg_setup set to default.
[10:35:28.994] INFO: 2 hot pixels found in step 6
[10:35:29.981] INFO: Collecting data for 5 seconds...
[10:35:34.996] INFO: Done with hot pixel readout
[10:35:46.016] INFO: PixTest:: pg_setup set to default.
[10:35:46.017] INFO: 3 hot pixels found in step 7
[10:35:47.003] INFO: Collecting data for 5 seconds...
[10:35:52.019] INFO: Done with hot pixel readout
[10:36:03.037] INFO: PixTest:: pg_setup set to default.
[10:36:03.038] INFO: 1 hot pixels found in step 8
[10:36:04.023] INFO: Collecting data for 5 seconds...
[10:36:09.041] INFO: Done with hot pixel readout
[10:36:20.101] INFO: PixTest:: pg_setup set to default.
[10:36:20.102] INFO: 0 hot pixels found in step 9
[10:36:20.132] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:36:20.136] INFO: PixTest::trimHotPixels() done
[10:36:20.136] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C0.dat
[10:36:20.142] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C1.dat
[10:36:20.149] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C2.dat
[10:36:20.155] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C3.dat
[10:36:20.160] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C4.dat
[10:36:20.166] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C5.dat
[10:36:20.171] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C6.dat
[10:36:20.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C7.dat
[10:36:20.182] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C8.dat
[10:36:20.187] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C9.dat
[10:36:20.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C10.dat
[10:36:20.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C11.dat
[10:36:20.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C12.dat
[10:36:20.209] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C13.dat
[10:36:20.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C14.dat
[10:36:20.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C15.dat
[10:36:20.226] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//defaultMaskFile.dat
[10:36:20.236] INFO: enter test to run
[10:37:12.839] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:37:12.839] INFO: running: highrate
[10:37:12.844] INFO: ----------------------------------------------------------------------
[10:37:12.844] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:37:12.844] INFO: ----------------------------------------------------------------------
[10:37:12.844] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:37:12.844] INFO: edge/corner pixel THR is adjusted
[10:37:12.844] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:37:13.801] INFO: Collecting data for 1 seconds...
[10:37:14.805] INFO: Done with hot pixel readout
[10:37:18.388] INFO: PixTest:: pg_setup set to default.
[10:37:18.389] INFO: 0 hot pixels found in step 0
[10:37:18.395] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:37:18.498] INFO: PixTest::trimHotPixels() done
[10:37:18.499] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C0.dat
[10:37:18.511] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C1.dat
[10:37:18.555] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C2.dat
[10:37:18.567] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C3.dat
[10:37:18.577] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C4.dat
[10:37:18.583] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C5.dat
[10:37:18.588] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C6.dat
[10:37:18.594] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C7.dat
[10:37:18.599] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C8.dat
[10:37:18.605] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C9.dat
[10:37:18.610] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C10.dat
[10:37:18.616] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C11.dat
[10:37:18.621] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C12.dat
[10:37:18.627] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C13.dat
[10:37:18.632] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C14.dat
[10:37:18.638] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C15.dat
[10:37:18.643] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-H-1-07_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//defaultMaskFile.dat
[10:37:18.654] INFO: enter test to run
[10:38:05.959] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:38:05.959] INFO: running: xray
[10:38:05.960] INFO: ----------------------------------------------------------------------
[10:38:05.960] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:38:05.960] INFO: ----------------------------------------------------------------------
[10:38:06.923] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:38:19.245] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:38:48.324] INFO: Resuming triggers.
[10:39:00.647] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[10:39:29.599] INFO: Resuming triggers.
[10:39:41.926] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[10:40:10.818] INFO: Resuming triggers.
[10:40:23.147] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[10:40:52.713] INFO: Resuming triggers.
[10:41:05.042] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[10:41:34.358] INFO: Resuming triggers.
[10:41:46.685] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[10:42:15.489] INFO: Resuming triggers.
[10:42:27.817] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[10:42:57.822] INFO: Resuming triggers.
[10:43:10.153] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[10:43:40.172] INFO: Resuming triggers.
[10:43:41.894] INFO: data taking finished, elapsed time: 100 seconds.
[10:43:46.334] INFO: PixTest:: pg_setup set to default.
[10:43:46.337] INFO: PixTestXray::doPhRun() done
[10:43:46.475] INFO: enter test to run
[10:44:18.972] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:44:18.972] INFO: running: xray
[10:44:18.973] INFO: ----------------------------------------------------------------------
[10:44:18.973] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:44:18.973] INFO: ----------------------------------------------------------------------
[10:44:19.937] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:44:27.647] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:44:57.498] INFO: Resuming triggers.
[10:45:05.207] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[10:45:35.109] INFO: Resuming triggers.
[10:45:42.816] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:46:12.835] INFO: Resuming triggers.
[10:46:20.541] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[10:46:50.503] INFO: Resuming triggers.
[10:46:58.210] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[10:47:28.261] INFO: Resuming triggers.
[10:47:35.968] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:48:06.124] INFO: Resuming triggers.
[10:48:13.827] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[10:48:43.674] INFO: Resuming triggers.
[10:48:51.376] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[10:49:21.188] INFO: Resuming triggers.
[10:49:28.893] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:49:58.873] INFO: Resuming triggers.
[10:50:06.575] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[10:50:36.434] INFO: Resuming triggers.
[10:50:44.139] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[10:51:14.122] INFO: Resuming triggers.
[10:51:21.826] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:51:51.735] INFO: Resuming triggers.
[10:51:59.444] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:52:30.183] INFO: Resuming triggers.
[10:52:30.329] INFO: data taking finished, elapsed time: 100 seconds.
[10:52:31.358] INFO: PixTest:: pg_setup set to default.
[10:52:31.362] INFO: PixTestXray::doPhRun() done
[10:52:31.534] INFO: enter test to run
[10:53:23.400] INFO: test: HighRate no parameter change
[10:53:23.401] INFO: running: highrate
[10:53:23.402] INFO: ----------------------------------------------------------------------
[10:53:23.402] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:53:23.402] INFO: ----------------------------------------------------------------------
[10:53:23.551] INFO: Expecting 768 events.
[10:53:24.689] INFO: 768 events read in total (422ms).
[10:53:24.689] INFO: Test took 1272ms.
[10:53:25.492] INFO: Expecting 41600 events.
[10:53:28.535] INFO: 41600 events read in total (2516ms).
[10:53:28.536] INFO: Test took 3841ms.
[10:53:28.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:29.294] INFO: Expecting 41600 events.
[10:53:32.417] INFO: 41600 events read in total (2597ms).
[10:53:32.418] INFO: Test took 3839ms.
[10:53:32.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:33.178] INFO: Expecting 41600 events.
[10:53:36.358] INFO: 41600 events read in total (2653ms).
[10:53:36.359] INFO: Test took 3898ms.
[10:53:36.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:37.106] INFO: Expecting 41600 events.
[10:53:40.243] INFO: 41600 events read in total (2610ms).
[10:53:40.244] INFO: Test took 3837ms.
[10:53:40.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:41.006] INFO: Expecting 41600 events.
[10:53:44.238] INFO: 41600 events read in total (2705ms).
[10:53:44.239] INFO: Test took 3951ms.
[10:53:44.268] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:44.998] INFO: Expecting 41600 events.
[10:53:48.223] INFO: 41600 events read in total (2698ms).
[10:53:48.224] INFO: Test took 3941ms.
[10:53:48.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:48.984] INFO: Expecting 41600 events.
[10:53:52.114] INFO: 41600 events read in total (2603ms).
[10:53:52.115] INFO: Test took 3846ms.
[10:53:52.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:52.872] INFO: Expecting 41600 events.
[10:53:56.045] INFO: 41600 events read in total (2646ms).
[10:53:56.046] INFO: Test took 3886ms.
[10:53:56.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:56.805] INFO: Expecting 41600 events.
[10:54:00.017] INFO: 41600 events read in total (2686ms).
[10:54:00.018] INFO: Test took 3928ms.
[10:54:00.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:00.777] INFO: Expecting 41600 events.
[10:54:03.978] INFO: 41600 events read in total (2674ms).
[10:54:03.979] INFO: Test took 3917ms.
[10:54:04.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:04.738] INFO: Expecting 41600 events.
[10:54:07.940] INFO: 41600 events read in total (2675ms).
[10:54:07.941] INFO: Test took 3918ms.
[10:54:07.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:08.702] INFO: Expecting 41600 events.
[10:54:11.901] INFO: 41600 events read in total (2673ms).
[10:54:11.902] INFO: Test took 3916ms.
[10:54:11.931] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:12.659] INFO: Expecting 41600 events.
[10:54:15.843] INFO: 41600 events read in total (2657ms).
[10:54:15.844] INFO: Test took 3898ms.
[10:54:15.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:16.599] INFO: Expecting 41600 events.
[10:54:19.775] INFO: 41600 events read in total (2649ms).
[10:54:19.776] INFO: Test took 3886ms.
[10:54:19.805] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:20.540] INFO: Expecting 41600 events.
[10:54:23.861] INFO: 41600 events read in total (2795ms).
[10:54:23.862] INFO: Test took 4042ms.
[10:54:23.891] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:24.621] INFO: Expecting 41600 events.
[10:54:27.870] INFO: 41600 events read in total (2722ms).
[10:54:27.870] INFO: Test took 3963ms.
[10:54:27.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:28.628] INFO: Expecting 41600 events.
[10:54:31.794] INFO: 41600 events read in total (2639ms).
[10:54:31.794] INFO: Test took 3879ms.
[10:54:31.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:32.553] INFO: Expecting 41600 events.
[10:54:35.785] INFO: 41600 events read in total (2705ms).
[10:54:35.786] INFO: Test took 3947ms.
[10:54:35.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:36.548] INFO: Expecting 41600 events.
[10:54:39.899] INFO: 41600 events read in total (2825ms).
[10:54:39.900] INFO: Test took 4068ms.
[10:54:39.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:40.664] INFO: Expecting 41600 events.
[10:54:43.745] INFO: 41600 events read in total (2554ms).
[10:54:43.746] INFO: Test took 3801ms.
[10:54:43.774] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:44.141] INFO: enter test to run
[10:54:54.776] INFO: test: HighRate no parameter change
[10:54:54.776] INFO: running: highrate
[10:54:54.777] INFO: ----------------------------------------------------------------------
[10:54:54.777] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:54:54.777] INFO: ----------------------------------------------------------------------
[10:54:55.394] INFO: Expecting 208000 events.
[10:55:06.988] INFO: 208000 events read in total (11067ms).
[10:55:06.991] INFO: Test took 12208ms.
[10:55:07.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:07.350] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 160 0 0
[10:55:07.350] INFO: number of red-efficiency pixels: 51 27 76 111 84 111 110 96 65 89 80 74 72 207 18 35
[10:55:07.350] INFO: number of X-ray hits detected: 52080 33730 52355 80598 90196 93004 90788 71292 59671 74041 72342 62565 65179 38695 19024 23878
[10:55:07.350] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:55:07.350] INFO: number of Vcal hits detected: 207948 207973 207921 207887 207915 207885 207887 207904 207935 207911 207920 207926 207928 199952 207982 207965
[10:55:07.350] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:55:07.350] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 96.1 100.0 100.0
[10:55:07.350] INFO: X-ray hit rate [MHz/cm2]: 15.3 9.9 15.3 23.6 26.4 27.3 26.6 20.9 17.5 21.7 21.2 18.3 19.1 11.3 5.6 7.0
[10:55:07.350] INFO: PixTestHighRate::doXPixelAlive() done
[10:55:07.397] INFO: PixTest:: pg_setup set to default.
[10:55:07.413] INFO: enter test to run
[10:55:38.320] INFO: test: HighRate no parameter change
[10:55:38.320] INFO: running: highrate
[10:55:38.321] INFO: ----------------------------------------------------------------------
[10:55:38.321] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:55:38.321] INFO: ----------------------------------------------------------------------
[10:55:38.941] INFO: Expecting 208000 events.
[10:55:52.114] INFO: 208000 events read in total (12647ms).
[10:55:52.119] INFO: Test took 13788ms.
[10:55:52.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:52.637] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 160 0 0
[10:55:52.637] INFO: number of red-efficiency pixels: 156 98 224 242 272 406 351 257 184 254 184 141 197 264 45 59
[10:55:52.637] INFO: number of X-ray hits detected: 107722 69315 109202 166134 186772 192354 187693 146923 124048 151600 149808 129003 135610 80272 39895 49665
[10:55:52.637] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:55:52.638] INFO: number of Vcal hits detected: 207836 207900 207767 207744 207712 207578 207631 207728 207808 207731 207809 207855 207786 199892 207955 207939
[10:55:52.638] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:55:52.638] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 96.1 100.0 100.0
[10:55:52.638] INFO: X-ray hit rate [MHz/cm2]: 31.6 20.3 32.0 48.7 54.7 56.4 55.0 43.1 36.4 44.4 43.9 37.8 39.7 23.5 11.7 14.6
[10:55:52.638] INFO: PixTestHighRate::doXPixelAlive() done
[10:55:52.682] INFO: PixTest:: pg_setup set to default.
[10:55:52.697] INFO: enter test to run
[10:56:19.887] INFO: test: HighRate no parameter change
[10:56:19.887] INFO: running: highrate
[10:56:19.888] INFO: ----------------------------------------------------------------------
[10:56:19.888] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:56:19.888] INFO: ----------------------------------------------------------------------
[10:56:20.504] INFO: Expecting 208000 events.
[10:56:34.748] INFO: 208000 events read in total (13718ms).
[10:56:34.754] INFO: Test took 14857ms.
[10:56:35.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:35.424] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 160 0 0
[10:56:35.424] INFO: number of red-efficiency pixels: 365 194 404 564 629 850 833 583 335 548 397 327 434 331 52 100
[10:56:35.424] INFO: number of X-ray hits detected: 162095 105404 164381 252625 282443 291488 283680 222877 187408 229698 227513 196710 206110 121864 60897 75337
[10:56:35.424] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:56:35.424] INFO: number of Vcal hits detected: 207579 207794 207527 207376 207315 206983 207036 207319 207640 207394 207585 207654 207532 199817 207946 207897
[10:56:35.424] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.5 99.6 99.7 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[10:56:35.424] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.5 99.5 99.7 99.8 99.7 99.8 99.8 99.8 96.1 100.0 100.0
[10:56:35.424] INFO: X-ray hit rate [MHz/cm2]: 47.5 30.9 48.2 74.0 82.8 85.4 83.1 65.3 54.9 67.3 66.7 57.7 60.4 35.7 17.8 22.1
[10:56:35.424] INFO: PixTestHighRate::doXPixelAlive() done
[10:56:35.470] INFO: PixTest:: pg_setup set to default.
[10:56:35.488] INFO: enter test to run
[10:56:39.527] INFO: test: exit no parameter change
[10:56:39.812] QUIET: Connection to board 32 closed.
[10:56:39.813] INFO: pXar: this is the end, my friend