[14:34:15.230] INFO: *** Welcome to pxar ***
[14:34:15.230] INFO: *** Today: 2016/05/30
[14:34:15.246] INFO: *** Version: v1.9.0-796-gef167-dirty
[14:34:15.246] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//dacParameters35_C15.dat
[14:34:15.284] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:34:15.284] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//defaultMaskFile.dat
[14:34:15.286] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C15.dat
[14:34:15.367] INFO: clk: 4
[14:34:15.367] INFO: ctr: 4
[14:34:15.367] INFO: sda: 19
[14:34:15.367] INFO: tin: 9
[14:34:15.367] INFO: level: 15
[14:34:15.367] INFO: triggerdelay: 0
[14:34:15.367] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[14:34:15.367] INFO: Log level: INFO
[14:34:15.385] QUIET: Connection to board DTB_WREKRL opened.
[14:34:15.388] 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:
------------------------------------------------------
[14:34:15.391] INFO: RPC call hashes of host and DTB match: 398089610
[14:34:16.925] INFO: DUT info:
[14:34:16.925] INFO: The DUT currently contains the following objects:
[14:34:16.925] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:16.925] INFO: TBM Core alpha (0): 7 registers set
[14:34:16.925] INFO: TBM Core beta (1): 7 registers set
[14:34:16.925] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:34:16.925] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:16.925] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:34:17.329] INFO: enter 'restricted' command line mode
[14:34:17.329] INFO: enter test to run
[14:34:21.024] INFO: test: PixelAlive no parameter change
[14:34:21.024] INFO: running: pixelalive
[14:34:21.034] INFO: ----------------------------------------------------------------------
[14:34:21.034] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:34:21.034] INFO: ----------------------------------------------------------------------
[14:34:21.351] INFO: Expecting 41600 events.
[14:34:25.667] INFO: 41600 events read in total (3598ms).
[14:34:25.835] INFO: Test took 4799ms.
[14:34:25.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:26.114] INFO: PixTestAlive::aliveTest() done
[14:34:26.114] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[14:34:26.145] INFO: enter test to run
[14:35:34.288] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:35:34.288] INFO: running: highrate
[14:35:34.288] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:35:34.515] INFO: ----------------------------------------------------------------------
[14:35:34.515] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:35:34.515] INFO: ----------------------------------------------------------------------
[14:35:34.515] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:35:34.516] INFO: edge/corner pixel THR is adjusted
[14:35:34.516] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:35:35.475] INFO: Collecting data for 5 seconds...
[14:35:40.490] INFO: Done with hot pixel readout
[14:35:52.120] INFO: PixTest:: pg_setup set to default.
[14:35:52.121] INFO: 1 hot pixels found in step 0
[14:35:53.110] INFO: Collecting data for 5 seconds...
[14:35:58.126] INFO: Done with hot pixel readout
[14:36:09.705] INFO: PixTest:: pg_setup set to default.
[14:36:09.706] INFO: 0 hot pixels found in step 1
[14:36:09.739] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:36:09.742] INFO: PixTest::trimHotPixels() done
[14:36:09.742] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C0.dat
[14:36:09.748] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C1.dat
[14:36:09.754] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C2.dat
[14:36:09.759] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C3.dat
[14:36:09.764] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C4.dat
[14:36:09.769] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C5.dat
[14:36:09.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C6.dat
[14:36:09.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C7.dat
[14:36:09.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C8.dat
[14:36:09.790] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C9.dat
[14:36:09.795] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C10.dat
[14:36:09.801] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C11.dat
[14:36:09.806] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C12.dat
[14:36:09.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C13.dat
[14:36:09.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C14.dat
[14:36:09.821] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C15.dat
[14:36:09.827] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//defaultMaskFile.dat
[14:36:09.836] INFO: enter test to run
[14:36:39.095] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:36:39.095] INFO: running: highrate
[14:36:39.100] INFO: ----------------------------------------------------------------------
[14:36:39.100] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:36:39.100] INFO: ----------------------------------------------------------------------
[14:36:39.100] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:36:39.100] INFO: edge/corner pixel THR is adjusted
[14:36:39.100] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:36:40.061] INFO: Collecting data for 1 seconds...
[14:36:41.064] INFO: Done with hot pixel readout
[14:36:45.019] INFO: PixTest:: pg_setup set to default.
[14:36:45.020] INFO: 0 hot pixels found in step 0
[14:36:45.025] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:36:45.120] INFO: PixTest::trimHotPixels() done
[14:36:45.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C0.dat
[14:36:45.130] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C1.dat
[14:36:45.136] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C2.dat
[14:36:45.141] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C3.dat
[14:36:45.146] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C4.dat
[14:36:45.151] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C5.dat
[14:36:45.157] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C6.dat
[14:36:45.162] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C7.dat
[14:36:45.167] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C8.dat
[14:36:45.172] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C9.dat
[14:36:45.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C10.dat
[14:36:45.182] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C11.dat
[14:36:45.188] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C12.dat
[14:36:45.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C13.dat
[14:36:45.198] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C14.dat
[14:36:45.203] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//trimParameters35_C15.dat
[14:36:45.208] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-25_FPIXTest-17C-FNAL-160523-1522_2016-05-23_15h22m_1464034935/000_FPIXTest_p17//defaultMaskFile.dat
[14:36:45.218] INFO: enter test to run
[14:37:45.983] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:37:45.983] INFO: running: xray
[14:37:45.984] INFO: ----------------------------------------------------------------------
[14:37:45.984] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:37:45.984] INFO: ----------------------------------------------------------------------
[14:37:46.947] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:37:58.434] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:38:27.701] INFO: Resuming triggers.
[14:38:39.188] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:39:08.431] INFO: Resuming triggers.
[14:39:19.916] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:39:49.316] INFO: Resuming triggers.
[14:40:00.803] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:40:30.091] INFO: Resuming triggers.
[14:40:41.577] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:41:10.798] INFO: Resuming triggers.
[14:41:22.286] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[14:41:51.540] INFO: Resuming triggers.
[14:42:03.034] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:42:32.371] INFO: Resuming triggers.
[14:42:43.861] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:43:13.162] INFO: Resuming triggers.
[14:43:21.581] INFO: data taking finished, elapsed time: 100 seconds.
[14:43:43.248] INFO: PixTest:: pg_setup set to default.
[14:43:43.251] INFO: PixTestXray::doPhRun() done
[14:43:43.386] INFO: enter test to run
[14:44:05.908] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:44:05.908] INFO: running: xray
[14:44:05.909] INFO: ----------------------------------------------------------------------
[14:44:05.909] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:44:05.909] INFO: ----------------------------------------------------------------------
[14:44:06.874] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:44:13.652] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:44:43.817] INFO: Resuming triggers.
[14:44:50.595] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:45:20.612] INFO: Resuming triggers.
[14:45:27.389] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:45:57.373] INFO: Resuming triggers.
[14:46:04.149] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:46:34.159] INFO: Resuming triggers.
[14:46:40.937] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:47:10.914] INFO: Resuming triggers.
[14:47:17.691] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[14:47:47.740] INFO: Resuming triggers.
[14:47:54.519] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[14:48:24.544] INFO: Resuming triggers.
[14:48:31.324] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[14:49:01.335] INFO: Resuming triggers.
[14:49:08.114] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[14:49:38.162] INFO: Resuming triggers.
[14:49:44.944] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[14:50:14.914] INFO: Resuming triggers.
[14:50:21.696] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[14:50:51.655] INFO: Resuming triggers.
[14:50:58.438] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:51:28.419] INFO: Resuming triggers.
[14:51:35.201] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[14:52:05.246] INFO: Resuming triggers.
[14:52:12.032] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[14:52:42.089] INFO: Resuming triggers.
[14:52:47.492] INFO: data taking finished, elapsed time: 100 seconds.
[14:53:11.658] INFO: PixTest:: pg_setup set to default.
[14:53:11.661] INFO: PixTestXray::doPhRun() done
[14:53:11.807] INFO: enter test to run
[14:53:50.680] INFO: test: HighRate no parameter change
[14:53:50.680] INFO: running: highrate
[14:53:50.681] INFO: ----------------------------------------------------------------------
[14:53:50.681] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:53:50.681] INFO: ----------------------------------------------------------------------
[14:53:50.822] INFO: Expecting 768 events.
[14:53:51.956] INFO: 768 events read in total (418ms).
[14:53:51.956] INFO: Test took 1268ms.
[14:53:52.759] INFO: Expecting 41600 events.
[14:53:55.753] INFO: 41600 events read in total (2467ms).
[14:53:55.754] INFO: Test took 3790ms.
[14:53:55.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:56.517] INFO: Expecting 41600 events.
[14:53:59.667] INFO: 41600 events read in total (2623ms).
[14:53:59.668] INFO: Test took 3864ms.
[14:53:59.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:00.426] INFO: Expecting 41600 events.
[14:54:03.644] INFO: 41600 events read in total (2691ms).
[14:54:03.645] INFO: Test took 3926ms.
[14:54:03.679] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:04.405] INFO: Expecting 41600 events.
[14:54:07.629] INFO: 41600 events read in total (2697ms).
[14:54:07.630] INFO: Test took 3934ms.
[14:54:07.664] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:08.389] INFO: Expecting 41600 events.
[14:54:11.609] INFO: 41600 events read in total (2693ms).
[14:54:11.610] INFO: Test took 3928ms.
[14:54:11.643] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:12.367] INFO: Expecting 41600 events.
[14:54:15.597] INFO: 41600 events read in total (2703ms).
[14:54:15.598] INFO: Test took 3936ms.
[14:54:15.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:16.358] INFO: Expecting 41600 events.
[14:54:19.603] INFO: 41600 events read in total (2719ms).
[14:54:19.604] INFO: Test took 3953ms.
[14:54:19.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:20.364] INFO: Expecting 41600 events.
[14:54:23.598] INFO: 41600 events read in total (2707ms).
[14:54:23.599] INFO: Test took 3942ms.
[14:54:23.634] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:24.357] INFO: Expecting 41600 events.
[14:54:27.597] INFO: 41600 events read in total (2714ms).
[14:54:27.598] INFO: Test took 3946ms.
[14:54:27.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:28.358] INFO: Expecting 41600 events.
[14:54:31.600] INFO: 41600 events read in total (2715ms).
[14:54:31.601] INFO: Test took 3950ms.
[14:54:31.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:32.356] INFO: Expecting 41600 events.
[14:54:35.590] INFO: 41600 events read in total (2708ms).
[14:54:35.591] INFO: Test took 3939ms.
[14:54:35.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:36.352] INFO: Expecting 41600 events.
[14:54:39.580] INFO: 41600 events read in total (2701ms).
[14:54:39.581] INFO: Test took 3938ms.
[14:54:39.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:40.337] INFO: Expecting 41600 events.
[14:54:43.570] INFO: 41600 events read in total (2706ms).
[14:54:43.571] INFO: Test took 3938ms.
[14:54:43.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:44.332] INFO: Expecting 41600 events.
[14:54:47.574] INFO: 41600 events read in total (2715ms).
[14:54:47.575] INFO: Test took 3951ms.
[14:54:47.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:48.332] INFO: Expecting 41600 events.
[14:54:51.570] INFO: 41600 events read in total (2711ms).
[14:54:51.571] INFO: Test took 3944ms.
[14:54:51.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:52.327] INFO: Expecting 41600 events.
[14:54:55.565] INFO: 41600 events read in total (2711ms).
[14:54:55.566] INFO: Test took 3942ms.
[14:54:55.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:56.322] INFO: Expecting 41600 events.
[14:54:59.573] INFO: 41600 events read in total (2724ms).
[14:54:59.574] INFO: Test took 3956ms.
[14:54:59.608] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:00.330] INFO: Expecting 41600 events.
[14:55:03.571] INFO: 41600 events read in total (2714ms).
[14:55:03.572] INFO: Test took 3945ms.
[14:55:03.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:04.330] INFO: Expecting 41600 events.
[14:55:07.557] INFO: 41600 events read in total (2700ms).
[14:55:07.558] INFO: Test took 3934ms.
[14:55:07.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:08.318] INFO: Expecting 41600 events.
[14:55:11.476] INFO: 41600 events read in total (2631ms).
[14:55:11.477] INFO: Test took 3866ms.
[14:55:11.510] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:11.873] INFO: enter test to run
[14:55:38.895] INFO: test: HighRate no parameter change
[14:55:38.895] INFO: running: highrate
[14:55:38.896] INFO: ----------------------------------------------------------------------
[14:55:38.896] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:55:38.896] INFO: ----------------------------------------------------------------------
[14:55:39.515] INFO: Expecting 208000 events.
[14:55:51.333] INFO: 208000 events read in total (11291ms).
[14:55:51.336] INFO: Test took 12430ms.
[14:55:51.482] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:51.734] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[14:55:51.734] INFO: number of red-efficiency pixels: 74 41 86 108 115 137 184 115 90 104 99 94 117 69 21 28
[14:55:51.734] INFO: number of X-ray hits detected: 58150 41367 66944 102521 112080 114000 122022 89819 84960 98688 94489 82152 84211 50439 21670 25457
[14:55:51.734] 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:55:51.734] INFO: number of Vcal hits detected: 207923 207958 207912 207889 207882 207861 207810 207883 207905 207896 207897 207905 207882 207930 207927 207972
[14:55:51.734] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0
[14:55:51.734] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0
[14:55:51.734] INFO: X-ray hit rate [MHz/cm2]: 17.0 12.1 19.6 30.0 32.9 33.4 35.8 26.3 24.9 28.9 27.7 24.1 24.7 14.8 6.4 7.5
[14:55:51.734] INFO: PixTestHighRate::doXPixelAlive() done
[14:55:51.783] INFO: PixTest:: pg_setup set to default.
[14:55:51.800] INFO: enter test to run
[14:56:50.334] INFO: test: HighRate no parameter change
[14:56:50.334] INFO: running: highrate
[14:56:50.335] INFO: ----------------------------------------------------------------------
[14:56:50.335] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:56:50.335] INFO: ----------------------------------------------------------------------
[14:56:50.953] INFO: Expecting 208000 events.
[14:57:04.455] INFO: 208000 events read in total (12975ms).
[14:57:04.460] INFO: Test took 14114ms.
[14:57:04.749] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:05.046] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[14:57:05.046] INFO: number of red-efficiency pixels: 175 81 212 286 402 510 517 414 293 294 282 256 302 146 42 43
[14:57:05.046] INFO: number of X-ray hits detected: 117677 83481 135304 207216 227845 229237 246518 182051 172845 198456 191301 167749 170613 101914 44209 51046
[14:57:05.046] 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:57:05.046] INFO: number of Vcal hits detected: 207813 207917 207777 207697 207572 207437 207431 207538 207691 207695 207706 207734 207684 207842 207909 207957
[14:57:05.046] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.7 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[14:57:05.046] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.7 99.7 99.8 99.9 99.9 99.9 99.9 99.8 99.9 100.0 100.0
[14:57:05.046] INFO: X-ray hit rate [MHz/cm2]: 34.5 24.5 39.7 60.7 66.8 67.2 72.3 53.4 50.7 58.2 56.1 49.2 50.0 29.9 13.0 15.0
[14:57:05.046] INFO: PixTestHighRate::doXPixelAlive() done
[14:57:05.095] INFO: PixTest:: pg_setup set to default.
[14:57:05.111] INFO: enter test to run
[14:57:41.158] INFO: test: HighRate no parameter change
[14:57:41.158] INFO: running: highrate
[14:57:41.159] INFO: ----------------------------------------------------------------------
[14:57:41.159] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:57:41.159] INFO: ----------------------------------------------------------------------
[14:57:41.773] INFO: Expecting 208000 events.
[14:57:57.046] INFO: 208000 events read in total (14746ms).
[14:57:57.053] INFO: Test took 15886ms.
[14:57:57.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:57.819] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[14:57:57.819] INFO: number of red-efficiency pixels: 318 178 433 651 902 1100 1277 803 616 617 593 521 634 382 74 88
[14:57:57.819] INFO: number of X-ray hits detected: 175641 124279 201735 309144 337664 339622 366486 271118 257352 297233 283395 250426 254731 152114 65916 76654
[14:57:57.819] 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:57:57.819] INFO: number of Vcal hits detected: 207653 207815 207484 207257 206953 206613 206301 206942 207282 207298 207337 207416 207293 207560 207873 207911
[14:57:57.819] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.5 99.4 99.3 99.6 99.7 99.7 99.7 99.8 99.7 99.8 100.0 100.0
[14:57:57.819] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.3 99.2 99.5 99.7 99.7 99.7 99.7 99.7 99.8 99.9 100.0
[14:57:57.819] INFO: X-ray hit rate [MHz/cm2]: 51.5 36.4 59.1 90.6 99.0 99.5 107.4 79.5 75.4 87.1 83.1 73.4 74.7 44.6 19.3 22.5
[14:57:57.819] INFO: PixTestHighRate::doXPixelAlive() done
[14:57:57.869] INFO: PixTest:: pg_setup set to default.
[14:57:57.880] INFO: enter test to run
[14:58:25.494] INFO: test: exit no parameter change
[14:58:25.816] QUIET: Connection to board 33 closed.
[14:58:25.817] INFO: pXar: this is the end, my friend