[10:17:17.554] INFO: *** Welcome to pxar ***
[10:17:17.554] INFO: *** Today: 2016/04/26
[10:17:17.570] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:17:17.570] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//dacParameters35_C15.dat
[10:17:17.590] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:17:17.591] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//defaultMaskFile.dat
[10:17:17.591] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C15.dat
[10:17:17.667] INFO: clk: 4
[10:17:17.667] INFO: ctr: 4
[10:17:17.667] INFO: sda: 19
[10:17:17.667] INFO: tin: 9
[10:17:17.667] INFO: level: 15
[10:17:17.667] INFO: triggerdelay: 0
[10:17:17.667] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:17:17.667] INFO: Log level: INFO
[10:17:17.684] QUIET: Connection to board DTB_WREKRL opened.
[10:17:17.688] 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:17:17.691] INFO: RPC call hashes of host and DTB match: 398089610
[10:17:19.221] INFO: DUT info:
[10:17:19.221] INFO: The DUT currently contains the following objects:
[10:17:19.221] INFO: 2 TBM Cores tbm08c (2 ON)
[10:17:19.221] INFO: TBM Core alpha (0): 7 registers set
[10:17:19.221] INFO: TBM Core beta (1): 7 registers set
[10:17:19.221] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:17:19.221] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.222] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:19.626] INFO: enter 'restricted' command line mode
[10:17:19.626] INFO: enter test to run
[10:17:22.991] INFO: test: PixelAlive no parameter change
[10:17:22.991] INFO: running: pixelalive
[10:17:22.001] INFO: ----------------------------------------------------------------------
[10:17:22.001] 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:17:22.001] INFO: ----------------------------------------------------------------------
[10:17:23.318] INFO: Expecting 41600 events.
[10:17:27.651] INFO: 41600 events read in total (3614ms).
[10:17:27.815] INFO: Test took 4811ms.
[10:17:27.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:28.086] INFO: PixTestAlive::aliveTest() done
[10:17:28.086] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 16 0 0 0 0 0 0 0 0 0
[10:17:28.120] INFO: enter test to run
[10:17:49.183] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:17:49.183] INFO: running: highrate
[10:17:49.183] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:17:49.495] INFO: ----------------------------------------------------------------------
[10:17:49.496] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:17:49.496] INFO: ----------------------------------------------------------------------
[10:17:49.496] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:17:49.496] INFO: edge/corner pixel THR is adjusted
[10:17:49.496] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:17:50.456] INFO: Collecting data for 5 seconds...
[10:17:55.473] INFO: Done with hot pixel readout
[10:18:07.223] INFO: PixTest:: pg_setup set to default.
[10:18:07.224] INFO: 3 hot pixels found in step 0
[10:18:08.222] INFO: Collecting data for 5 seconds...
[10:18:13.240] INFO: Done with hot pixel readout
[10:18:24.929] INFO: PixTest:: pg_setup set to default.
[10:18:24.930] INFO: 1 hot pixels found in step 1
[10:18:25.926] INFO: Collecting data for 5 seconds...
[10:18:30.943] INFO: Done with hot pixel readout
[10:18:42.811] INFO: PixTest:: pg_setup set to default.
[10:18:42.812] INFO: 0 hot pixels found in step 2
[10:18:42.850] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:18:42.854] INFO: PixTest::trimHotPixels() done
[10:18:42.854] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C0.dat
[10:18:42.866] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C1.dat
[10:18:42.874] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C2.dat
[10:18:42.879] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C3.dat
[10:18:42.884] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C4.dat
[10:18:42.889] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C5.dat
[10:18:42.895] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C6.dat
[10:18:42.900] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C7.dat
[10:18:42.905] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C8.dat
[10:18:42.911] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C9.dat
[10:18:42.916] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C10.dat
[10:18:42.921] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C11.dat
[10:18:42.926] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C12.dat
[10:18:42.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C13.dat
[10:18:42.937] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C14.dat
[10:18:42.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C15.dat
[10:18:42.947] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//defaultMaskFile.dat
[10:18:42.958] INFO: enter test to run
[10:19:00.607] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:19:00.607] INFO: running: highrate
[10:19:00.611] INFO: ----------------------------------------------------------------------
[10:19:00.611] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:19:00.611] INFO: ----------------------------------------------------------------------
[10:19:00.611] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:19:00.611] INFO: edge/corner pixel THR is adjusted
[10:19:00.611] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:19:01.569] INFO: Collecting data for 1 seconds...
[10:19:02.573] INFO: Done with hot pixel readout
[10:19:06.641] INFO: PixTest:: pg_setup set to default.
[10:19:06.642] INFO: 0 hot pixels found in step 0
[10:19:06.647] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:19:06.735] INFO: PixTest::trimHotPixels() done
[10:19:06.735] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C0.dat
[10:19:06.747] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C1.dat
[10:19:06.754] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C2.dat
[10:19:06.759] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C3.dat
[10:19:06.764] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C4.dat
[10:19:06.769] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C5.dat
[10:19:06.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C6.dat
[10:19:06.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C7.dat
[10:19:06.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C8.dat
[10:19:06.790] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C9.dat
[10:19:06.796] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C10.dat
[10:19:06.801] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C11.dat
[10:19:06.806] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C12.dat
[10:19:06.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C13.dat
[10:19:06.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C14.dat
[10:19:06.822] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//trimParameters35_C15.dat
[10:19:06.827] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-1-28_FPIXTest-17C-FNAL-160421-1034_2016-04-21_10h34m_1461252897/000_FPIXTest_p17//defaultMaskFile.dat
[10:19:06.836] INFO: enter test to run
[10:19:28.918] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:19:28.919] INFO: running: xray
[10:19:28.941] INFO: ----------------------------------------------------------------------
[10:19:28.941] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:19:28.941] INFO: ----------------------------------------------------------------------
[10:19:29.905] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:19:41.252] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:20:10.654] INFO: Resuming triggers.
[10:20:22.003] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:20:51.478] INFO: Resuming triggers.
[10:21:02.827] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:21:32.163] INFO: Resuming triggers.
[10:21:43.515] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:22:09.528] INFO: Resuming triggers.
[10:22:20.878] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:22:50.228] INFO: Resuming triggers.
[10:23:01.580] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[10:23:30.961] INFO: Resuming triggers.
[10:23:42.310] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:24:11.777] INFO: Resuming triggers.
[10:24:23.129] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:24:52.527] INFO: Resuming triggers.
[10:25:02.048] INFO: data taking finished, elapsed time: 100 seconds.
[10:25:26.748] INFO: PixTest:: pg_setup set to default.
[10:25:26.751] INFO: PixTestXray::doPhRun() done
[10:25:26.888] INFO: enter test to run
[10:26:48.643] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:26:48.643] INFO: running: xray
[10:26:48.644] INFO: ----------------------------------------------------------------------
[10:26:48.645] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:26:48.645] INFO: ----------------------------------------------------------------------
[10:26:49.627] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:26:56.189] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:27:26.299] INFO: Resuming triggers.
[10:27:32.860] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:28:03.017] INFO: Resuming triggers.
[10:28:09.578] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:28:39.605] INFO: Resuming triggers.
[10:28:46.160] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:29:16.177] INFO: Resuming triggers.
[10:29:22.734] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[10:29:52.650] INFO: Resuming triggers.
[10:29:59.206] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:30:29.250] INFO: Resuming triggers.
[10:30:35.810] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:31:05.871] INFO: Resuming triggers.
[10:31:12.428] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:31:42.544] INFO: Resuming triggers.
[10:31:49.105] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:32:17.151] INFO: Resuming triggers.
[10:32:23.711] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[10:32:53.691] INFO: Resuming triggers.
[10:33:00.258] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:33:30.268] INFO: Resuming triggers.
[10:33:36.827] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:34:07.101] INFO: Resuming triggers.
[10:34:13.658] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[10:34:43.599] INFO: Resuming triggers.
[10:34:50.156] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:35:20.169] INFO: Resuming triggers.
[10:35:26.725] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[10:35:56.758] INFO: Resuming triggers.
[10:35:58.699] INFO: data taking finished, elapsed time: 100 seconds.
[10:36:07.891] INFO: PixTest:: pg_setup set to default.
[10:36:07.895] INFO: PixTestXray::doPhRun() done
[10:36:08.045] INFO: enter test to run
[10:36:43.807] INFO: test: HighRate no parameter change
[10:36:43.807] INFO: running: highrate
[10:36:43.808] INFO: ----------------------------------------------------------------------
[10:36:43.808] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:36:43.808] INFO: ----------------------------------------------------------------------
[10:36:43.980] INFO: Expecting 768 events.
[10:36:45.120] INFO: 768 events read in total (419ms).
[10:36:45.120] INFO: Test took 1274ms.
[10:36:45.923] INFO: Expecting 41600 events.
[10:36:48.952] INFO: 41600 events read in total (2502ms).
[10:36:48.953] INFO: Test took 3787ms.
[10:36:48.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:49.717] INFO: Expecting 41600 events.
[10:36:52.934] INFO: 41600 events read in total (2691ms).
[10:36:52.935] INFO: Test took 3929ms.
[10:36:52.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:53.691] INFO: Expecting 41600 events.
[10:36:56.943] INFO: 41600 events read in total (2726ms).
[10:36:56.944] INFO: Test took 3956ms.
[10:36:56.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:57.698] INFO: Expecting 41600 events.
[10:37:00.956] INFO: 41600 events read in total (2731ms).
[10:37:00.957] INFO: Test took 3960ms.
[10:37:00.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:01.716] INFO: Expecting 41600 events.
[10:37:04.973] INFO: 41600 events read in total (2730ms).
[10:37:04.974] INFO: Test took 3962ms.
[10:37:05.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:05.731] INFO: Expecting 41600 events.
[10:37:08.979] INFO: 41600 events read in total (2722ms).
[10:37:08.980] INFO: Test took 3952ms.
[10:37:09.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:09.735] INFO: Expecting 41600 events.
[10:37:12.981] INFO: 41600 events read in total (2720ms).
[10:37:12.982] INFO: Test took 3946ms.
[10:37:13.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:13.740] INFO: Expecting 41600 events.
[10:37:17.010] INFO: 41600 events read in total (2743ms).
[10:37:17.011] INFO: Test took 3974ms.
[10:37:17.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:17.769] INFO: Expecting 41600 events.
[10:37:21.018] INFO: 41600 events read in total (2722ms).
[10:37:21.019] INFO: Test took 3955ms.
[10:37:21.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:21.776] INFO: Expecting 41600 events.
[10:37:25.026] INFO: 41600 events read in total (2724ms).
[10:37:25.027] INFO: Test took 3955ms.
[10:37:25.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:25.783] INFO: Expecting 41600 events.
[10:37:29.048] INFO: 41600 events read in total (2738ms).
[10:37:29.049] INFO: Test took 3966ms.
[10:37:29.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:29.809] INFO: Expecting 41600 events.
[10:37:33.066] INFO: 41600 events read in total (2730ms).
[10:37:33.067] INFO: Test took 3962ms.
[10:37:33.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:33.824] INFO: Expecting 41600 events.
[10:37:37.074] INFO: 41600 events read in total (2723ms).
[10:37:37.075] INFO: Test took 3955ms.
[10:37:37.110] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:37.832] INFO: Expecting 41600 events.
[10:37:41.104] INFO: 41600 events read in total (2745ms).
[10:37:41.105] INFO: Test took 3977ms.
[10:37:41.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:41.863] INFO: Expecting 41600 events.
[10:37:45.078] INFO: 41600 events read in total (2688ms).
[10:37:45.079] INFO: Test took 3922ms.
[10:37:45.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:45.836] INFO: Expecting 41600 events.
[10:37:49.095] INFO: 41600 events read in total (2732ms).
[10:37:49.096] INFO: Test took 3962ms.
[10:37:49.131] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:49.852] INFO: Expecting 41600 events.
[10:37:53.116] INFO: 41600 events read in total (2737ms).
[10:37:53.117] INFO: Test took 3968ms.
[10:37:53.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:53.870] INFO: Expecting 41600 events.
[10:37:57.138] INFO: 41600 events read in total (2741ms).
[10:37:57.139] INFO: Test took 3969ms.
[10:37:57.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:57.896] INFO: Expecting 41600 events.
[10:38:01.145] INFO: 41600 events read in total (2722ms).
[10:38:01.146] INFO: Test took 3952ms.
[10:38:01.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:01.902] INFO: Expecting 41600 events.
[10:38:05.045] INFO: 41600 events read in total (2616ms).
[10:38:05.046] INFO: Test took 3844ms.
[10:38:05.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:05.441] INFO: enter test to run
[10:38:30.014] INFO: test: HighRate no parameter change
[10:38:30.014] INFO: running: highrate
[10:38:30.015] INFO: ----------------------------------------------------------------------
[10:38:30.015] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:38:30.015] INFO: ----------------------------------------------------------------------
[10:38:30.628] INFO: Expecting 208000 events.
[10:38:42.561] INFO: 208000 events read in total (11406ms).
[10:38:42.564] INFO: Test took 12539ms.
[10:38:42.718] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:42.972] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 16 0 0 0 0 0 0 0 0 0
[10:38:42.972] INFO: number of red-efficiency pixels: 102 44 92 146 155 185 171 103 116 134 119 101 102 66 17 24
[10:38:42.972] INFO: number of X-ray hits detected: 68405 47418 72005 114293 120733 128450 122119 91303 83403 98718 97232 85982 89239 54782 21326 27669
[10:38:42.972] 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:38:42.972] INFO: number of Vcal hits detected: 207894 207954 207905 207846 207842 207811 206849 207894 207883 207864 207880 207898 207897 207932 207983 207976
[10:38:42.972] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[10:38:42.972] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.4 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[10:38:42.972] INFO: X-ray hit rate [MHz/cm2]: 20.1 13.9 21.1 33.5 35.4 37.6 35.8 26.8 24.4 28.9 28.5 25.2 26.2 16.1 6.3 8.1
[10:38:42.972] INFO: PixTestHighRate::doXPixelAlive() done
[10:38:43.020] INFO: PixTest:: pg_setup set to default.
[10:38:43.031] INFO: enter test to run
[10:38:56.630] INFO: test: HighRate no parameter change
[10:38:56.630] INFO: running: highrate
[10:38:56.631] INFO: ----------------------------------------------------------------------
[10:38:56.631] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:38:56.631] INFO: ----------------------------------------------------------------------
[10:38:57.243] INFO: Expecting 208000 events.
[10:39:10.972] INFO: 208000 events read in total (13203ms).
[10:39:10.977] INFO: Test took 14337ms.
[10:39:11.288] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:11.591] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 17 0 0 0 0 0 0 0 0 0
[10:39:11.591] INFO: number of red-efficiency pixels: 266 139 321 510 491 648 609 314 271 408 394 289 274 225 46 65
[10:39:11.591] INFO: number of X-ray hits detected: 137260 95551 144997 230748 242959 257684 245639 185210 169703 198588 194942 173167 178819 110741 42839 55037
[10:39:11.592] 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:39:11.592] INFO: number of Vcal hits detected: 207708 207853 207646 207439 207472 207263 206350 207668 207717 207571 207576 207696 207716 207763 207954 207934
[10:39:11.592] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.7 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:39:11.592] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.6 99.2 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:39:11.592] INFO: X-ray hit rate [MHz/cm2]: 40.2 28.0 42.5 67.6 71.2 75.5 72.0 54.3 49.7 58.2 57.1 50.8 52.4 32.5 12.6 16.1
[10:39:11.592] INFO: PixTestHighRate::doXPixelAlive() done
[10:39:11.640] INFO: PixTest:: pg_setup set to default.
[10:39:11.652] INFO: enter test to run
[10:39:26.390] INFO: test: HighRate no parameter change
[10:39:26.390] INFO: running: highrate
[10:39:26.391] INFO: ----------------------------------------------------------------------
[10:39:26.391] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:39:26.391] INFO: ----------------------------------------------------------------------
[10:39:26.000] INFO: Expecting 208000 events.
[10:39:42.635] INFO: 208000 events read in total (15108ms).
[10:39:42.643] INFO: Test took 16244ms.
[10:39:43.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:43.440] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 16 0 0 0 0 0 0 0 0 0
[10:39:43.440] INFO: number of red-efficiency pixels: 565 303 705 1235 1106 1495 1448 740 643 957 921 645 570 457 74 103
[10:39:43.440] INFO: number of X-ray hits detected: 207090 144078 218094 346975 365966 386998 369587 277933 255515 298114 293739 260267 270481 166851 64585 83673
[10:39:43.440] 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:39:43.440] INFO: number of Vcal hits detected: 207344 207673 207050 206410 206635 205996 205161 207073 207282 206857 206917 207261 207377 207467 207925 207896
[10:39:43.440] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.3 99.4 99.1 99.2 99.6 99.7 99.5 99.5 99.7 99.7 99.8 100.0 100.0
[10:39:43.440] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.5 99.2 99.3 99.0 98.6 99.6 99.7 99.5 99.5 99.6 99.7 99.7 100.0 100.0
[10:39:43.440] INFO: X-ray hit rate [MHz/cm2]: 60.7 42.2 63.9 101.7 107.3 113.4 108.3 81.5 74.9 87.4 86.1 76.3 79.3 48.9 18.9 24.5
[10:39:43.440] INFO: PixTestHighRate::doXPixelAlive() done
[10:39:43.490] INFO: PixTest:: pg_setup set to default.
[10:39:43.508] INFO: enter test to run
[10:39:47.526] INFO: test: exit no parameter change
[10:39:47.848] QUIET: Connection to board 33 closed.
[10:39:47.863] INFO: pXar: this is the end, my friend