[10:57:37.858] INFO: *** Welcome to pxar ***
[10:57:37.858] INFO: *** Today: 2016/05/27
[10:57:38.400] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:57:38.401] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//dacParameters35_C15.dat
[10:57:38.485] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:57:38.485] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//defaultMaskFile.dat
[10:57:38.488] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C15.dat
[10:57:38.603] INFO: clk: 4
[10:57:38.603] INFO: ctr: 4
[10:57:38.603] INFO: sda: 19
[10:57:38.603] INFO: tin: 9
[10:57:38.603] INFO: level: 15
[10:57:38.603] INFO: triggerdelay: 0
[10:57:38.603] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:57:38.603] INFO: Log level: INFO
[10:57:38.622] QUIET: Connection to board DTB_WREKRL opened.
[10:57:38.625] 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:57:38.628] INFO: RPC call hashes of host and DTB match: 398089610
[10:57:40.173] INFO: DUT info:
[10:57:40.173] INFO: The DUT currently contains the following objects:
[10:57:40.173] INFO: 2 TBM Cores tbm08c (2 ON)
[10:57:40.174] INFO: TBM Core alpha (0): 7 registers set
[10:57:40.174] INFO: TBM Core beta (1): 7 registers set
[10:57:40.174] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:57:40.174] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.174] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:57:40.617] INFO: enter 'restricted' command line mode
[10:57:40.617] INFO: enter test to run
[10:57:48.384] INFO: test: PixelAlive no parameter change
[10:57:48.384] INFO: running: pixelalive
[10:57:48.426] INFO: ----------------------------------------------------------------------
[10:57:48.426] 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:57:48.426] INFO: ----------------------------------------------------------------------
[10:57:48.748] INFO: Expecting 41600 events.
[10:57:53.102] INFO: 41600 events read in total (3636ms).
[10:57:53.270] INFO: Test took 4840ms.
[10:57:53.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:53.543] INFO: PixTestAlive::aliveTest() done
[10:57:53.543] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:57:53.595] INFO: enter test to run
[10:58:15.336] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:58:15.336] INFO: running: highrate
[10:58:15.337] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:58:15.600] INFO: ----------------------------------------------------------------------
[10:58:15.600] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:58:15.600] INFO: ----------------------------------------------------------------------
[10:58:15.600] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:58:15.600] INFO: edge/corner pixel THR is adjusted
[10:58:15.600] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:58:16.560] INFO: Collecting data for 5 seconds...
[10:58:21.576] INFO: Done with hot pixel readout
[10:58:33.075] INFO: PixTest:: pg_setup set to default.
[10:58:33.076] INFO: 0 hot pixels found in step 0
[10:58:33.089] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:58:33.092] INFO: PixTest::trimHotPixels() done
[10:58:33.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C0.dat
[10:58:33.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C1.dat
[10:58:33.124] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C2.dat
[10:58:33.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C3.dat
[10:58:33.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C4.dat
[10:58:33.140] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C5.dat
[10:58:33.145] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C6.dat
[10:58:33.151] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C7.dat
[10:58:33.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C8.dat
[10:58:33.161] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C9.dat
[10:58:33.167] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C10.dat
[10:58:33.173] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C11.dat
[10:58:33.178] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C12.dat
[10:58:33.183] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C13.dat
[10:58:33.189] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C14.dat
[10:58:33.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C15.dat
[10:58:33.200] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//defaultMaskFile.dat
[10:58:33.217] INFO: enter test to run
[10:58:57.312] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:58:57.312] INFO: running: highrate
[10:58:57.316] INFO: ----------------------------------------------------------------------
[10:58:57.316] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:58:57.316] INFO: ----------------------------------------------------------------------
[10:58:57.316] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:58:57.316] INFO: edge/corner pixel THR is adjusted
[10:58:57.316] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:58:58.274] INFO: Collecting data for 1 seconds...
[10:58:59.277] INFO: Done with hot pixel readout
[10:59:03.194] INFO: PixTest:: pg_setup set to default.
[10:59:03.195] INFO: 0 hot pixels found in step 0
[10:59:03.201] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:59:03.288] INFO: PixTest::trimHotPixels() done
[10:59:03.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C0.dat
[10:59:03.294] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C1.dat
[10:59:03.299] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C2.dat
[10:59:03.305] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C3.dat
[10:59:03.310] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C4.dat
[10:59:03.316] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C5.dat
[10:59:03.321] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C6.dat
[10:59:03.327] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C7.dat
[10:59:03.332] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C8.dat
[10:59:03.338] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C9.dat
[10:59:03.343] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C10.dat
[10:59:03.349] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C11.dat
[10:59:03.354] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C12.dat
[10:59:03.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C13.dat
[10:59:03.365] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C14.dat
[10:59:03.370] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//trimParameters35_C15.dat
[10:59:03.376] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-3-42_FPIXTest-17C-FNAL-160518-1313_2016-05-18_13h13m_1463595192/000_FPIXTest_p17//defaultMaskFile.dat
[10:59:03.385] INFO: enter test to run
[10:59:28.560] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:59:28.560] INFO: running: xray
[10:59:28.561] INFO: ----------------------------------------------------------------------
[10:59:28.561] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:59:28.561] INFO: ----------------------------------------------------------------------
[10:59:29.539] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:59:41.051] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:00:10.111] INFO: Resuming triggers.
[11:00:21.623] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[11:00:51.202] INFO: Resuming triggers.
[11:01:02.713] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:01:32.765] INFO: Resuming triggers.
[11:01:44.281] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[11:02:13.034] INFO: Resuming triggers.
[11:02:24.550] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[11:02:53.795] INFO: Resuming triggers.
[11:03:05.310] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[11:03:34.733] INFO: Resuming triggers.
[11:03:46.251] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[11:04:15.594] INFO: Resuming triggers.
[11:04:27.114] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[11:04:56.561] INFO: Resuming triggers.
[11:05:04.787] INFO: data taking finished, elapsed time: 100 seconds.
[11:05:25.935] INFO: PixTest:: pg_setup set to default.
[11:05:25.939] INFO: PixTestXray::doPhRun() done
[11:05:26.074] INFO: enter test to run
[11:06:04.917] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:06:04.917] INFO: running: xray
[11:06:04.918] INFO: ----------------------------------------------------------------------
[11:06:04.918] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:06:04.918] INFO: ----------------------------------------------------------------------
[11:06:05.881] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:06:12.747] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:06:42.539] INFO: Resuming triggers.
[11:06:49.406] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:07:19.412] INFO: Resuming triggers.
[11:07:26.282] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:07:56.281] INFO: Resuming triggers.
[11:08:03.154] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:08:33.087] INFO: Resuming triggers.
[11:08:39.964] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:09:10.071] INFO: Resuming triggers.
[11:09:16.948] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[11:09:46.940] INFO: Resuming triggers.
[11:09:53.817] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:10:24.208] INFO: Resuming triggers.
[11:10:31.087] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:11:00.857] INFO: Resuming triggers.
[11:11:07.738] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[11:11:36.804] INFO: Resuming triggers.
[11:11:43.682] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[11:12:13.450] INFO: Resuming triggers.
[11:12:20.327] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[11:12:50.279] INFO: Resuming triggers.
[11:12:57.158] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[11:13:27.070] INFO: Resuming triggers.
[11:13:33.947] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[11:14:03.475] INFO: Resuming triggers.
[11:14:10.354] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[11:14:39.882] INFO: Resuming triggers.
[11:14:43.962] INFO: data taking finished, elapsed time: 100 seconds.
[11:15:01.486] INFO: PixTest:: pg_setup set to default.
[11:15:01.489] INFO: PixTestXray::doPhRun() done
[11:15:01.638] INFO: enter test to run
[11:15:50.273] INFO: test: HighRate no parameter change
[11:15:50.273] INFO: running: highrate
[11:15:50.274] INFO: ----------------------------------------------------------------------
[11:15:50.274] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:15:50.274] INFO: ----------------------------------------------------------------------
[11:15:50.414] INFO: Expecting 768 events.
[11:15:51.548] INFO: 768 events read in total (419ms).
[11:15:51.548] INFO: Test took 1268ms.
[11:15:52.351] INFO: Expecting 41600 events.
[11:15:55.413] INFO: 41600 events read in total (2535ms).
[11:15:55.414] INFO: Test took 3834ms.
[11:15:55.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:56.178] INFO: Expecting 41600 events.
[11:15:59.278] INFO: 41600 events read in total (2575ms).
[11:15:59.279] INFO: Test took 3815ms.
[11:15:59.314] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:00.037] INFO: Expecting 41600 events.
[11:16:03.250] INFO: 41600 events read in total (2686ms).
[11:16:03.251] INFO: Test took 3920ms.
[11:16:03.285] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:04.009] INFO: Expecting 41600 events.
[11:16:07.153] INFO: 41600 events read in total (2617ms).
[11:16:07.154] INFO: Test took 3851ms.
[11:16:07.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:07.913] INFO: Expecting 41600 events.
[11:16:11.118] INFO: 41600 events read in total (2678ms).
[11:16:11.119] INFO: Test took 3913ms.
[11:16:11.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:11.880] INFO: Expecting 41600 events.
[11:16:15.106] INFO: 41600 events read in total (2699ms).
[11:16:15.107] INFO: Test took 3936ms.
[11:16:15.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:15.864] INFO: Expecting 41600 events.
[11:16:19.042] INFO: 41600 events read in total (2651ms).
[11:16:19.043] INFO: Test took 3883ms.
[11:16:19.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:19.798] INFO: Expecting 41600 events.
[11:16:23.005] INFO: 41600 events read in total (2680ms).
[11:16:23.006] INFO: Test took 3910ms.
[11:16:23.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:23.766] INFO: Expecting 41600 events.
[11:16:26.927] INFO: 41600 events read in total (2634ms).
[11:16:26.928] INFO: Test took 3869ms.
[11:16:26.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:27.686] INFO: Expecting 41600 events.
[11:16:30.885] INFO: 41600 events read in total (2672ms).
[11:16:30.886] INFO: Test took 3906ms.
[11:16:30.921] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:31.645] INFO: Expecting 41600 events.
[11:16:34.858] INFO: 41600 events read in total (2686ms).
[11:16:34.859] INFO: Test took 3918ms.
[11:16:34.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:35.615] INFO: Expecting 41600 events.
[11:16:38.602] INFO: 41600 events read in total (2460ms).
[11:16:38.603] INFO: Test took 3691ms.
[11:16:38.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:39.361] INFO: Expecting 41600 events.
[11:16:42.543] INFO: 41600 events read in total (2656ms).
[11:16:42.544] INFO: Test took 3888ms.
[11:16:42.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:43.303] INFO: Expecting 41600 events.
[11:16:46.461] INFO: 41600 events read in total (2631ms).
[11:16:46.462] INFO: Test took 3867ms.
[11:16:46.500] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:47.218] INFO: Expecting 41600 events.
[11:16:50.411] INFO: 41600 events read in total (2666ms).
[11:16:50.412] INFO: Test took 3892ms.
[11:16:50.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:51.171] INFO: Expecting 41600 events.
[11:16:54.383] INFO: 41600 events read in total (2685ms).
[11:16:54.384] INFO: Test took 3919ms.
[11:16:54.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:55.140] INFO: Expecting 41600 events.
[11:16:58.330] INFO: 41600 events read in total (2663ms).
[11:16:58.331] INFO: Test took 3895ms.
[11:16:58.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:59.087] INFO: Expecting 41600 events.
[11:17:02.290] INFO: 41600 events read in total (2676ms).
[11:17:02.291] INFO: Test took 3908ms.
[11:17:02.325] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:03.048] INFO: Expecting 41600 events.
[11:17:06.235] INFO: 41600 events read in total (2660ms).
[11:17:06.237] INFO: Test took 3893ms.
[11:17:06.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:06.996] INFO: Expecting 41600 events.
[11:17:10.100] INFO: 41600 events read in total (2577ms).
[11:17:10.102] INFO: Test took 3812ms.
[11:17:10.135] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:10.497] INFO: enter test to run
[11:17:17.944] INFO: test: HighRate no parameter change
[11:17:17.944] INFO: running: highrate
[11:17:17.945] INFO: ----------------------------------------------------------------------
[11:17:17.945] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:17:17.945] INFO: ----------------------------------------------------------------------
[11:17:18.553] INFO: Expecting 208000 events.
[11:17:30.215] INFO: 208000 events read in total (11135ms).
[11:17:30.218] INFO: Test took 12266ms.
[11:17:30.361] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:30.611] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:17:30.611] INFO: number of red-efficiency pixels: 66 38 78 130 177 156 120 97 90 108 97 90 111 37 21 29
[11:17:30.611] INFO: number of X-ray hits detected: 59270 39788 67591 109489 114313 114688 114879 88478 75900 91064 90656 77045 79504 48087 21751 23593
[11:17:30.611] 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:17:30.611] INFO: number of Vcal hits detected: 207933 207961 207921 207867 207816 207840 207879 207902 207909 207891 207901 207896 207885 207962 207979 207971
[11:17:30.611] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0
[11:17:30.611] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 99.9 100.0 100.0 100.0
[11:17:30.611] INFO: X-ray hit rate [MHz/cm2]: 17.4 11.7 19.8 32.1 33.5 33.6 33.7 25.9 22.2 26.7 26.6 22.6 23.3 14.1 6.4 6.9
[11:17:30.611] INFO: PixTestHighRate::doXPixelAlive() done
[11:17:30.658] INFO: PixTest:: pg_setup set to default.
[11:17:30.674] INFO: enter test to run
[11:18:04.888] INFO: test: HighRate no parameter change
[11:18:04.888] INFO: running: highrate
[11:18:04.889] INFO: ----------------------------------------------------------------------
[11:18:04.889] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:18:04.889] INFO: ----------------------------------------------------------------------
[11:18:05.504] INFO: Expecting 208000 events.
[11:18:18.691] INFO: 208000 events read in total (12660ms).
[11:18:18.696] INFO: Test took 13798ms.
[11:18:18.978] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:19.273] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:18:19.273] INFO: number of red-efficiency pixels: 167 77 214 330 513 506 461 286 252 337 317 229 271 133 40 47
[11:18:19.273] INFO: number of X-ray hits detected: 119018 80363 137084 219768 231693 230781 231498 179200 153554 183004 182301 155789 160192 97242 43747 47050
[11:18:19.273] 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:18:19.273] INFO: number of Vcal hits detected: 207826 207921 207766 207645 207437 207444 207497 207701 207738 207635 207667 207733 207721 207863 207958 207953
[11:18:19.273] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.7 99.7 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[11:18:19.273] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.7 99.7 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[11:18:19.273] INFO: X-ray hit rate [MHz/cm2]: 34.9 23.6 40.2 64.4 67.9 67.6 67.9 52.5 45.0 53.6 53.4 45.7 47.0 28.5 12.8 13.8
[11:18:19.273] INFO: PixTestHighRate::doXPixelAlive() done
[11:18:19.320] INFO: PixTest:: pg_setup set to default.
[11:18:19.336] INFO: enter test to run
[11:18:40.631] INFO: test: HighRate no parameter change
[11:18:40.631] INFO: running: highrate
[11:18:40.632] INFO: ----------------------------------------------------------------------
[11:18:40.632] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:18:40.632] INFO: ----------------------------------------------------------------------
[11:18:41.246] INFO: Expecting 208000 events.
[11:18:55.743] INFO: 208000 events read in total (13971ms).
[11:18:55.751] INFO: Test took 15110ms.
[11:18:56.172] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:56.514] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:18:56.537] INFO: number of red-efficiency pixels: 270 172 505 860 1305 1290 1032 630 534 797 744 453 568 258 71 92
[11:18:56.537] INFO: number of X-ray hits detected: 182349 123582 209489 337579 353554 354355 353629 273530 235064 280456 280300 239141 245796 149463 67941 73321
[11:18:56.537] 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:18:56.537] INFO: number of Vcal hits detected: 207700 207813 207365 206999 206304 206202 206705 207259 207399 207086 207139 207448 207358 207728 207929 207908
[11:18:56.537] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.6 99.3 99.2 99.5 99.7 99.7 99.6 99.6 99.8 99.7 99.9 100.0 100.0
[11:18:56.537] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.5 99.2 99.1 99.4 99.6 99.7 99.6 99.6 99.7 99.7 99.9 100.0 100.0
[11:18:56.537] INFO: X-ray hit rate [MHz/cm2]: 53.4 36.2 61.4 98.9 103.6 103.9 103.7 80.2 68.9 82.2 82.2 70.1 72.0 43.8 19.9 21.5
[11:18:56.537] INFO: PixTestHighRate::doXPixelAlive() done
[11:18:56.586] INFO: PixTest:: pg_setup set to default.
[11:18:56.599] INFO: enter test to run
[11:18:58.151] INFO: test: exit no parameter change
[11:18:58.526] QUIET: Connection to board 33 closed.
[11:18:58.535] INFO: pXar: this is the end, my friend