[10:30:50.248] INFO: *** Welcome to pxar ***
[10:30:50.248] INFO: *** Today: 2016/06/30
[10:30:50.978] INFO: *** Version: v1.9.0-814-g7497
[10:30:50.978] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//dacParameters35_C15.dat
[10:30:51.033] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:30:51.033] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//defaultMaskFile.dat
[10:30:51.039] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C15.dat
[10:30:51.145] INFO: clk: 4
[10:30:51.145] INFO: ctr: 4
[10:30:51.145] INFO: sda: 19
[10:30:51.145] INFO: tin: 9
[10:30:51.145] INFO: level: 15
[10:30:51.145] INFO: triggerdelay: 0
[10:30:51.145] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:30:51.145] INFO: Log level: INFO
[10:30:51.164] QUIET: Connection to board DTB_WREKRL opened.
[10:30:51.167] 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:30:51.171] INFO: RPC call hashes of host and DTB match: 398089610
[10:30:52.699] INFO: DUT info:
[10:30:52.699] INFO: The DUT currently contains the following objects:
[10:30:52.699] INFO: 2 TBM Cores tbm08c (2 ON)
[10:30:52.712] INFO: TBM Core alpha (0): 7 registers set
[10:30:52.712] INFO: TBM Core beta (1): 7 registers set
[10:30:52.712] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:30:52.712] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.712] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.712] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.712] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.712] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.712] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:52.713] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:53.156] INFO: enter 'restricted' command line mode
[10:30:53.156] INFO: enter test to run
[10:30:58.251] INFO: test: PixelAlive no parameter change
[10:30:58.251] INFO: running: pixelalive
[10:30:58.318] INFO: ----------------------------------------------------------------------
[10:30:58.318] 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:30:58.318] INFO: ----------------------------------------------------------------------
[10:30:58.640] INFO: Expecting 41600 events.
[10:31:02.000] INFO: 41600 events read in total (3641ms).
[10:31:03.165] INFO: Test took 4843ms.
[10:31:03.174] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:03.674] INFO: PixTestAlive::aliveTest() done
[10:31:03.674] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[10:31:03.826] INFO: enter test to run
[10:32:01.419] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:32:01.419] INFO: running: highrate
[10:32:01.429] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:32:01.673] INFO: ----------------------------------------------------------------------
[10:32:01.674] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:32:01.674] INFO: ----------------------------------------------------------------------
[10:32:01.674] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:32:01.674] INFO: edge/corner pixel THR is adjusted
[10:32:01.674] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:32:02.633] INFO: Collecting data for 5 seconds...
[10:32:07.649] INFO: Done with hot pixel readout
[10:32:18.001] INFO: PixTest:: pg_setup set to default.
[10:32:19.002] INFO: 4 hot pixels found in step 0
[10:32:20.014] INFO: Collecting data for 5 seconds...
[10:32:25.032] INFO: Done with hot pixel readout
[10:32:36.363] INFO: PixTest:: pg_setup set to default.
[10:32:36.364] INFO: 6 hot pixels found in step 1
[10:32:37.354] INFO: Collecting data for 5 seconds...
[10:32:42.370] INFO: Done with hot pixel readout
[10:32:53.694] INFO: PixTest:: pg_setup set to default.
[10:32:53.695] INFO: 3 hot pixels found in step 2
[10:32:54.686] INFO: Collecting data for 5 seconds...
[10:32:59.703] INFO: Done with hot pixel readout
[10:33:11.057] INFO: PixTest:: pg_setup set to default.
[10:33:11.058] INFO: 3 hot pixels found in step 3
[10:33:12.048] INFO: Collecting data for 5 seconds...
[10:33:17.064] INFO: Done with hot pixel readout
[10:33:28.483] INFO: PixTest:: pg_setup set to default.
[10:33:28.484] INFO: 4 hot pixels found in step 4
[10:33:29.474] INFO: Collecting data for 5 seconds...
[10:33:34.492] INFO: Done with hot pixel readout
[10:33:45.725] INFO: PixTest:: pg_setup set to default.
[10:33:45.726] INFO: 1 hot pixels found in step 5
[10:33:46.716] INFO: Collecting data for 5 seconds...
[10:33:51.735] INFO: Done with hot pixel readout
[10:34:03.029] INFO: PixTest:: pg_setup set to default.
[10:34:03.030] INFO: 0 hot pixels found in step 6
[10:34:03.065] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:34:03.086] INFO: PixTest::trimHotPixels() done
[10:34:03.087] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C0.dat
[10:34:03.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C1.dat
[10:34:03.110] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C2.dat
[10:34:03.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C3.dat
[10:34:03.124] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C4.dat
[10:34:03.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C5.dat
[10:34:03.135] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C6.dat
[10:34:03.140] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C7.dat
[10:34:03.146] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C8.dat
[10:34:03.151] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C9.dat
[10:34:03.157] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C10.dat
[10:34:03.162] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C11.dat
[10:34:03.168] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C12.dat
[10:34:03.173] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C13.dat
[10:34:03.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C14.dat
[10:34:03.184] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C15.dat
[10:34:03.189] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//defaultMaskFile.dat
[10:34:03.201] INFO: enter test to run
[10:35:05.825] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:35:05.825] INFO: running: highrate
[10:35:05.830] INFO: ----------------------------------------------------------------------
[10:35:05.830] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:35:05.830] INFO: ----------------------------------------------------------------------
[10:35:05.830] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:35:05.830] INFO: edge/corner pixel THR is adjusted
[10:35:05.830] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:35:06.787] INFO: Collecting data for 1 seconds...
[10:35:07.791] INFO: Done with hot pixel readout
[10:35:11.518] INFO: PixTest:: pg_setup set to default.
[10:35:11.519] INFO: 0 hot pixels found in step 0
[10:35:11.525] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:35:11.624] INFO: PixTest::trimHotPixels() done
[10:35:11.624] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C0.dat
[10:35:11.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C1.dat
[10:35:11.645] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C2.dat
[10:35:11.650] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C3.dat
[10:35:11.656] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C4.dat
[10:35:11.661] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C5.dat
[10:35:11.666] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C6.dat
[10:35:11.672] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C7.dat
[10:35:11.677] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C8.dat
[10:35:11.683] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C9.dat
[10:35:11.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C10.dat
[10:35:11.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C11.dat
[10:35:11.699] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C12.dat
[10:35:11.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C13.dat
[10:35:11.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C14.dat
[10:35:11.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//trimParameters35_C15.dat
[10:35:11.720] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-30_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h39m_1466617145/000_FPIXTest_p17//defaultMaskFile.dat
[10:35:11.731] INFO: enter test to run
[10:35:51.673] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:35:51.674] INFO: running: xray
[10:35:51.675] INFO: ----------------------------------------------------------------------
[10:35:51.675] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:35:51.675] INFO: ----------------------------------------------------------------------
[10:35:52.654] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:36:04.614] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:36:34.777] INFO: Resuming triggers.
[10:36:46.739] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:37:16.827] INFO: Resuming triggers.
[10:37:28.793] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:37:57.903] INFO: Resuming triggers.
[10:38:09.874] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[10:38:39.260] INFO: Resuming triggers.
[10:38:51.225] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:39:20.480] INFO: Resuming triggers.
[10:39:32.444] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:40:01.731] INFO: Resuming triggers.
[10:40:13.698] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[10:40:42.738] INFO: Resuming triggers.
[10:40:54.711] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[10:41:23.927] INFO: Resuming triggers.
[10:41:28.545] INFO: data taking finished, elapsed time: 100 seconds.
[10:41:40.156] INFO: PixTest:: pg_setup set to default.
[10:41:40.160] INFO: PixTestXray::doPhRun() done
[10:41:40.298] INFO: enter test to run
[10:42:12.879] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:42:12.879] INFO: running: xray
[10:42:12.880] INFO: ----------------------------------------------------------------------
[10:42:12.880] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:42:12.880] INFO: ----------------------------------------------------------------------
[10:42:13.850] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:42:21.171] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:42:51.466] INFO: Resuming triggers.
[10:42:58.792] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[10:43:29.044] INFO: Resuming triggers.
[10:43:36.367] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[10:44:06.558] INFO: Resuming triggers.
[10:44:13.882] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[10:44:44.196] INFO: Resuming triggers.
[10:44:51.521] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[10:45:21.861] INFO: Resuming triggers.
[10:45:29.181] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[10:46:00.004] INFO: Resuming triggers.
[10:46:07.324] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:46:38.497] INFO: Resuming triggers.
[10:46:45.815] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:47:16.770] INFO: Resuming triggers.
[10:47:24.094] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[10:47:55.669] INFO: Resuming triggers.
[10:48:02.989] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:48:34.747] INFO: Resuming triggers.
[10:48:42.071] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:49:12.635] INFO: Resuming triggers.
[10:49:19.958] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[10:49:50.249] INFO: Resuming triggers.
[10:49:57.571] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[10:50:27.677] INFO: Resuming triggers.
[10:50:32.831] INFO: data taking finished, elapsed time: 100 seconds.
[10:50:54.213] INFO: PixTest:: pg_setup set to default.
[10:50:54.217] INFO: PixTestXray::doPhRun() done
[10:50:54.384] INFO: enter test to run
[10:52:30.418] INFO: test: HighRate no parameter change
[10:52:30.419] INFO: running: highrate
[10:52:30.420] INFO: ----------------------------------------------------------------------
[10:52:30.420] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:52:30.420] INFO: ----------------------------------------------------------------------
[10:52:30.567] INFO: Expecting 768 events.
[10:52:31.708] INFO: 768 events read in total (418ms).
[10:52:31.708] INFO: Test took 1276ms.
[10:52:32.511] INFO: Expecting 41600 events.
[10:52:35.571] INFO: 41600 events read in total (2533ms).
[10:52:35.572] INFO: Test took 3857ms.
[10:52:35.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:36.335] INFO: Expecting 41600 events.
[10:52:39.490] INFO: 41600 events read in total (2628ms).
[10:52:39.491] INFO: Test took 3873ms.
[10:52:39.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:40.255] INFO: Expecting 41600 events.
[10:52:43.436] INFO: 41600 events read in total (2654ms).
[10:52:43.437] INFO: Test took 3899ms.
[10:52:43.467] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:44.198] INFO: Expecting 41600 events.
[10:52:47.410] INFO: 41600 events read in total (2686ms).
[10:52:47.411] INFO: Test took 3927ms.
[10:52:47.441] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:48.174] INFO: Expecting 41600 events.
[10:52:51.371] INFO: 41600 events read in total (2670ms).
[10:52:51.372] INFO: Test took 3913ms.
[10:52:51.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:52.135] INFO: Expecting 41600 events.
[10:52:55.327] INFO: 41600 events read in total (2665ms).
[10:52:55.328] INFO: Test took 3908ms.
[10:52:55.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:56.088] INFO: Expecting 41600 events.
[10:52:59.302] INFO: 41600 events read in total (2687ms).
[10:52:59.303] INFO: Test took 3928ms.
[10:52:59.334] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:00.065] INFO: Expecting 41600 events.
[10:53:03.261] INFO: 41600 events read in total (2669ms).
[10:53:03.262] INFO: Test took 3910ms.
[10:53:03.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:04.024] INFO: Expecting 41600 events.
[10:53:07.221] INFO: 41600 events read in total (2670ms).
[10:53:07.222] INFO: Test took 3913ms.
[10:53:07.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:07.984] INFO: Expecting 41600 events.
[10:53:11.183] INFO: 41600 events read in total (2672ms).
[10:53:11.184] INFO: Test took 3916ms.
[10:53:11.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:11.948] INFO: Expecting 41600 events.
[10:53:15.154] INFO: 41600 events read in total (2679ms).
[10:53:15.155] INFO: Test took 3923ms.
[10:53:15.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:15.916] INFO: Expecting 41600 events.
[10:53:19.115] INFO: 41600 events read in total (2672ms).
[10:53:19.116] INFO: Test took 3912ms.
[10:53:19.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:19.879] INFO: Expecting 41600 events.
[10:53:23.087] INFO: 41600 events read in total (2681ms).
[10:53:23.088] INFO: Test took 3925ms.
[10:53:23.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:23.853] INFO: Expecting 41600 events.
[10:53:27.079] INFO: 41600 events read in total (2700ms).
[10:53:27.080] INFO: Test took 3944ms.
[10:53:27.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:27.840] INFO: Expecting 41600 events.
[10:53:31.029] INFO: 41600 events read in total (2662ms).
[10:53:31.030] INFO: Test took 3903ms.
[10:53:31.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:31.791] INFO: Expecting 41600 events.
[10:53:34.999] INFO: 41600 events read in total (2681ms).
[10:53:34.000] INFO: Test took 3922ms.
[10:53:35.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:35.760] INFO: Expecting 41600 events.
[10:53:38.975] INFO: 41600 events read in total (2688ms).
[10:53:38.975] INFO: Test took 3927ms.
[10:53:39.005] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:39.738] INFO: Expecting 41600 events.
[10:53:42.942] INFO: 41600 events read in total (2677ms).
[10:53:42.943] INFO: Test took 3920ms.
[10:53:42.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:43.702] INFO: Expecting 41600 events.
[10:53:46.880] INFO: 41600 events read in total (2651ms).
[10:53:46.881] INFO: Test took 3890ms.
[10:53:46.911] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:47.644] INFO: Expecting 41600 events.
[10:53:50.728] INFO: 41600 events read in total (2557ms).
[10:53:50.729] INFO: Test took 3802ms.
[10:53:50.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:51.121] INFO: enter test to run
[10:54:12.346] INFO: test: HighRate no parameter change
[10:54:12.346] INFO: running: highrate
[10:54:12.347] INFO: ----------------------------------------------------------------------
[10:54:12.347] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:54:12.347] INFO: ----------------------------------------------------------------------
[10:54:12.958] INFO: Expecting 208000 events.
[10:54:24.625] INFO: 208000 events read in total (11140ms).
[10:54:24.627] INFO: Test took 12272ms.
[10:54:24.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:24.000] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[10:54:24.000] INFO: number of red-efficiency pixels: 69 34 71 119 130 104 135 93 59 96 105 72 76 47 22 33
[10:54:24.000] INFO: number of X-ray hits detected: 61879 39234 56622 91748 93475 92030 94323 68710 69629 87589 85542 71550 75810 45383 17781 21965
[10:54:24.000] 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:54:24.000] INFO: number of Vcal hits detected: 207929 207965 207928 207878 207864 207885 207865 207905 207932 207902 207893 207928 207922 207952 207978 207918
[10:54:24.000] 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 100.0 100.0 100.0 100.0
[10:54:24.000] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[10:54:24.000] INFO: X-ray hit rate [MHz/cm2]: 18.1 11.5 16.6 26.9 27.4 27.0 27.6 20.1 20.4 25.7 25.1 21.0 22.2 13.3 5.2 6.4
[10:54:24.000] INFO: PixTestHighRate::doXPixelAlive() done
[10:54:25.051] INFO: PixTest:: pg_setup set to default.
[10:54:25.062] INFO: enter test to run
[10:54:46.897] INFO: test: HighRate no parameter change
[10:54:46.897] INFO: running: highrate
[10:54:46.899] INFO: ----------------------------------------------------------------------
[10:54:46.899] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:54:46.899] INFO: ----------------------------------------------------------------------
[10:54:47.512] INFO: Expecting 208000 events.
[10:55:00.626] INFO: 208000 events read in total (12587ms).
[10:55:00.630] INFO: Test took 13723ms.
[10:55:00.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:01.174] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[10:55:01.174] INFO: number of red-efficiency pixels: 213 94 213 398 362 281 368 199 191 285 266 199 240 112 46 45
[10:55:01.174] INFO: number of X-ray hits detected: 127106 81060 116650 188549 192882 190208 193441 142036 142795 180602 175340 147488 157058 94625 37322 45257
[10:55:01.174] 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:01.174] INFO: number of Vcal hits detected: 207768 207904 207773 207575 207621 207686 207615 207790 207793 207701 207722 207791 207753 207887 207953 207905
[10:55:01.174] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:55:01.174] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:55:01.174] INFO: X-ray hit rate [MHz/cm2]: 37.3 23.8 34.2 55.3 56.5 55.8 56.7 41.6 41.9 52.9 51.4 43.2 46.0 27.7 10.9 13.3
[10:55:01.174] INFO: PixTestHighRate::doXPixelAlive() done
[10:55:01.224] INFO: PixTest:: pg_setup set to default.
[10:55:01.240] INFO: enter test to run
[10:55:22.633] INFO: test: HighRate no parameter change
[10:55:22.633] INFO: running: highrate
[10:55:22.634] INFO: ----------------------------------------------------------------------
[10:55:22.634] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:55:22.634] INFO: ----------------------------------------------------------------------
[10:55:23.251] INFO: Expecting 208000 events.
[10:55:37.938] INFO: 208000 events read in total (14160ms).
[10:55:37.944] INFO: Test took 15300ms.
[10:55:38.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:38.658] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[10:55:38.658] INFO: number of red-efficiency pixels: 443 186 488 879 875 631 911 400 394 621 618 396 458 240 75 82
[10:55:38.658] INFO: number of X-ray hits detected: 192292 123122 176050 286660 291391 287421 293347 216930 216645 274017 266549 224987 236687 142404 56711 69396
[10:55:38.658] 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:38.658] INFO: number of Vcal hits detected: 207447 207801 207427 206955 206973 207239 206915 207552 207562 207299 207300 207570 207498 207744 207924 207864
[10:55:38.658] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.7 99.5 99.8 99.8 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[10:55:38.658] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.5 99.5 99.6 99.5 99.8 99.8 99.7 99.7 99.8 99.8 99.9 100.0 99.9
[10:55:38.658] INFO: X-ray hit rate [MHz/cm2]: 56.4 36.1 51.6 84.0 85.4 84.2 86.0 63.6 63.5 80.3 78.1 65.9 69.4 41.7 16.6 20.3
[10:55:38.658] INFO: PixTestHighRate::doXPixelAlive() done
[10:55:38.711] INFO: PixTest:: pg_setup set to default.
[10:55:38.728] INFO: enter test to run
[10:56:04.065] INFO: test: exit no parameter change
[10:56:04.423] QUIET: Connection to board 33 closed.
[10:56:04.432] INFO: pXar: this is the end, my friend