Note: This bug is displayed in read-only format because
the product is no longer active in Red Hat Bugzilla.
RHEL Engineering is moving the tracking of its product development work on RHEL 6 through RHEL 9 to Red Hat Jira (issues.redhat.com). If you're a Red Hat customer, please continue to file support cases via the Red Hat customer portal. If you're not, please head to the "RHEL project" in Red Hat Jira and file new tickets here. Individual Bugzilla bugs in the statuses "NEW", "ASSIGNED", and "POST" are being migrated throughout September 2023. Bugs of Red Hat partners with an assigned Engineering Partner Manager (EPM) are migrated in late September as per pre-agreed dates. Bugs against components "kernel", "kernel-rt", and "kpatch" are only migrated if still in "NEW" or "ASSIGNED". If you cannot log in to RH Jira, please consult article #7032570. That failing, please send an e-mail to the RH Jira admins at rh-issues@redhat.com to troubleshoot your issue as a user management inquiry. The email creates a ServiceNow ticket with Red Hat. Individual Bugzilla bugs that are migrated will be moved to status "CLOSED", resolution "MIGRATED", and set with "MigratedToJIRA" in "Keywords". The link to the successor Jira issue will be found under "Links", have a little "two-footprint" icon next to it, and direct you to the "RHEL project" in Red Hat Jira (issue links are of type "https://issues.redhat.com/browse/RHEL-XXXX", where "X" is a digit). This same link will be available in a blue banner at the top of the page informing you that that bug has been migrated.
Description of problem:
Virt-who doesn't catch the update when restoring the guest to active from suspend after restarting the service for the first interval loop
Version-Release number of selected component (if applicable):
virt-who-1.30.16-1.el8.noarch
How reproducible:
100%
Steps to Reproduce:
1. configure the virt-who.conf
[root@hp-z220-07 virt-who.d]# cat /etc/virt-who.conf
[global]
debug=true
interval=120
2. create the virt-who config file:
[root@hp-z220-07 virt-who.d]# cat esx.conf
[virtwho-esx]
type=esx
hypervisor_id=hostname
server=[server]
username=[hostname]
password=[password]
owner=Default_Organization
rhsm_hostname=[server]
rhsm_username=admin
rhsm_password=admin
rhsm_prefix=/rhsm
rhsm_port=443
3. Suspend the esx guest
4. run the virt-who by command 'service virt-who restart & tail -f /var/log/rhsm/rhsm.log', when getting the msg about 'Response status: 200', start the guest immediately
5. Getting the mapping info after restarting the virtwho service:
------------------------------------------------------
Redirecting to /bin/systemctl restart virt-who.service
2023-08-02 05:15:18,899 [virtwho.main DEBUG] MainProcess(770517):MainThread @executor.py:terminate:225 - virt-who is shutting down
2023-08-02 05:15:18,975 [virtwho.destination_2547277137715876194 DEBUG] MainProcess(770517):Thread-3 @virt.py:run:426 - Thread 'destination_2547277137715876194' terminated
2023-08-02 05:15:19,896 [virtwho.main DEBUG] MainProcess(770517):Thread-2 @virt.py:run:426 - Thread 'virtwho-esx' terminated
2023-08-02 05:15:20,215 [virtwho.parser WARNING] MainProcess(770559):MainThread @parser.py:read_config_env_variables:237 - The use of environment variables and the use of the sysconfig file to configure virt-who are deprecated. Their use will be ignored in the next major release.
2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "oneshot" not set, using default: False
2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "print_" not set, using default: False
2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "log_per_config" not set, using default: False
2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "configs" not set, using default: []
2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "reporter_id" not set, using default: hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e
2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "log_file" not set, using default: rhsm.log
2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "log_dir" not set, using default: /var/log/rhsm
2023-08-02 05:15:20,219 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [virtwho-esx]: Value for "simplified_vim" not set, using default: True
2023-08-02 05:15:20,219 [virtwho.rhsm_log INFO] MainProcess(770559):MainThread @executor.py:__init__:54 - Using config named 'virtwho-esx'
2023-08-02 05:15:20,219 [virtwho.rhsm_log INFO] MainProcess(770559):MainThread @main.py:main:176 - Using configuration "virtwho-esx" ("esx" mode)
2023-08-02 05:15:20,219 [virtwho.rhsm_log INFO] MainProcess(770559):MainThread @main.py:main:178 - Using reporter_id='hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e'
2023-08-02 05:15:20,220 [virtwho.main DEBUG] MainProcess(770559):MainThread @executor.py:run:187 - Starting infinite loop with 120 seconds interval
2023-08-02 05:15:20,229 [rhsm.https DEBUG] MainProcess(770559):MainThread @https.py:<module>:56 - Using standard libs to provide httplib and ssl
2023-08-02 05:15:20,235 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @virt.py:run:407 - Thread 'virtwho-esx' started
2023-08-02 05:15:20,235 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_prepare:136 - Log into ESX
2023-08-02 05:15:20,236 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @virt.py:run:407 - Thread 'destination_8663284226366541201' started
2023-08-02 05:15:20,340 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_prepare:139 - Creating ESX event filter
2023-08-02 05:15:20,379 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_run:170 - calling esx service
2023-08-02 05:15:20,464 [virtwho.main INFO] MainProcess(770559):Thread-2 @virt.py:_send_data:986 - Report for config "virtwho-esx" gathered, placing in datastore
2023-08-02 05:15:21,237 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used
2023-08-02 05:15:21,237 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin
2023-08-02 05:15:21,237 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin
2023-08-02 05:15:21,237 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752
2023-08-02 05:15:21,239 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/
2023-08-02 05:15:21,318 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.012628793716430664, Smoothed response time: 0.012628793716430664
2023-08-02 05:15:21,319 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/"
2023-08-02 05:15:21,319 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status
2023-08-02 05:15:21,381 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011539459228515625, Smoothed response time: 0.01251986026763916
2023-08-02 05:15:21,381 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status"
2023-08-02 05:15:21,382 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting']
2023-08-02 05:15:21,382 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e
2023-08-02 05:15:21,615 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011245012283325195, Smoothed response time: 0.012392375469207764
2023-08-02 05:15:21,615 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e"
2023-08-02 05:15:21,616 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used
2023-08-02 05:15:21,616 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @virt.py:_send_data:651 - Hosts-to-guests mapping for config "virtwho-esx": 5 hypervisors and 8 guests found
2023-08-02 05:15:21,617 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin
2023-08-02 05:15:21,616 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @virt.py:_send_data:687 - Sending updated Host-to-guest mapping to "Default_Organization" including 5 hypervisors and 8 guests
2023-08-02 05:15:21,616 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin
2023-08-02 05:15:21,616 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752
2023-08-02 05:15:21,618 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/
2023-08-02 05:15:21,677 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011118650436401367, Smoothed response time: 0.011118650436401367
2023-08-02 05:15:21,677 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/"
2023-08-02 05:15:21,678 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status
2023-08-02 05:15:21,678 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_is_rhsm_server_async:292 - Checking if server has capability 'hypervisor_async'
2023-08-02 05:15:21,734 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.010668516159057617, Smoothed response time: 0.011073637008666991
2023-08-02 05:15:21,734 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status"
2023-08-02 05:15:21,735 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting']
2023-08-02 05:15:21,736 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: POST /rhsm/hypervisors/Default_Organization?env=&cloaked=False&reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e
2023-08-02 05:15:21,735 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_is_rhsm_server_async:296 - Server has capability 'hypervisors_async'
2023-08-02 05:15:21,735 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:hypervisorCheckIn:213 - Host-to-guest mapping being sent to 'Default_Organization': {
"hypervisors": [
{
"hypervisorId": {
"hypervisorId": "bootp-73-213-144.lab.eng.pek2.redhat.com"
},
"name": "bootp-73-213-144.lab.eng.pek2.redhat.com",
"guestIds": [
{
"guestId": "09a33b42-d988-8179-3ae7-ac341efae918",
"state": 3,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
},
{
"guestId": "423bd245-0e9d-d482-955c-495fd8e21f3f",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
}
],
"facts": {
"cpu.cpu_socket(s)": "1",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "190b4d56-a632-b69b-5449-4c3afcd64007",
"hypervisor.cluster": "Cluster-Virtwho-85",
"hypervisor.version": "8.0.0"
}
},
{
"hypervisorId": {
"hypervisorId": "vm-212-147.lab.eng.pek2.redhat.com"
},
"name": "vm-212-147.lab.eng.pek2.redhat.com",
"guestIds": [
{
"guestId": "38973b42-b791-bb2b-0a04-5828ba35d948",
"state": 5,
"attributes": {
"virtWhoType": "esx",
"active": 0
}
},
{
"guestId": "423b5f04-6966-2fd3-fc68-c2696a537cce",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
},
{
"guestId": "fb633b42-78f1-5ec3-da83-5971d5b919a6",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
}
],
"facts": {
"cpu.cpu_socket(s)": "1",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "3a444d56-4207-677f-eb25-5fac1e7395d7",
"hypervisor.cluster": "Cluster-Virtwho-83",
"hypervisor.version": "8.0.0"
}
},
{
"hypervisorId": {
"hypervisorId": "bootp-73-213-211.lab.eng.pek2.redhat.com"
},
"name": "bootp-73-213-211.lab.eng.pek2.redhat.com",
"guestIds": [
{
"guestId": "423b3dcf-23ab-bb56-2bff-0b6aae4e9528",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
},
{
"guestId": "c25b3b42-d6d0-5ccb-e92b-c7b7018a6565",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
}
],
"facts": {
"cpu.cpu_socket(s)": "1",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "ccbf4d56-1dae-d6ab-00f0-a6a873468ef2",
"hypervisor.cluster": "Cluster-Virtwho-plugin",
"hypervisor.version": "8.0.0"
}
},
{
"hypervisorId": {
"hypervisorId": "bootp-73-5-217.rhts.eng.pek2.redhat.com"
},
"name": "bootp-73-5-217.rhts.eng.pek2.redhat.com",
"guestIds": [],
"facts": {
"cpu.cpu_socket(s)": "4",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "90374d56-42dc-e363-6ad1-8840981fb89f",
"hypervisor.version": "8.0.0"
}
},
{
"hypervisorId": {
"hypervisorId": "vm-212-255.lab.eng.pek2.redhat.com"
},
"name": "vm-212-255.lab.eng.pek2.redhat.com",
"guestIds": [
{
"guestId": "564d9498-c6bf-1bd4-1b08-5494679c2e83",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
}
],
"facts": {
"cpu.cpu_socket(s)": "2",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "ee124d56-fea8-3ff1-695a-b4635445e92a",
"hypervisor.version": "6.7.0"
}
}
]
}
2023-08-02 05:15:22,249 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011012077331542969, Smoothed response time: 0.011067481040954591
2023-08-02 05:15:22,249 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="POST /rhsm/hypervisors/Default_Organization?env=&cloaked=False&reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e"
------------------------------------------------------
5. After the interval time(120s), getting the virt-who log:
------------------------------------------------------
2023-08-02 05:17:20,372 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @virt.py:check_report_status:778 - Existing report state: 1
2023-08-02 05:17:20,588 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_run:170 - calling esx service
2023-08-02 05:17:20,596 [virtwho.main INFO] MainProcess(770559):Thread-2 @virt.py:_send_data:986 - Report for config "virtwho-esx" gathered, placing in datastore
2023-08-02 05:17:35,387 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used
2023-08-02 05:17:35,387 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin
2023-08-02 05:17:35,388 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin
2023-08-02 05:17:35,387 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752
2023-08-02 05:17:35,388 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/
2023-08-02 05:17:35,522 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.012730121612548828, Smoothed response time: 0.012730121612548828
2023-08-02 05:17:35,522 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/"
2023-08-02 05:17:35,523 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/jobs/8ac984f089844d300189b587b738195d?result_data=True
2023-08-02 05:17:35,523 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:check_report_state:340 - Checking status of job 8ac984f089844d300189b587b738195d
2023-08-02 05:17:35,766 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011625289916992188, Smoothed response time: 0.012619638442993164
2023-08-02 05:17:35,766 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/jobs/8ac984f089844d300189b587b738195d?result_data=True"
2023-08-02 05:17:35,767 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:check_report_state:368 - Number of mappings unchanged: 5
2023-08-02 05:17:35,767 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:check_report_state:369 - Mapping for config "destination_8663284226366541201" updated
2023-08-02 05:17:35,768 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used
2023-08-02 05:17:35,768 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @virt.py:_get_data_common:551 - Duplicate report found for config "virtwho-esx", ignoring
2023-08-02 05:17:35,769 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin
2023-08-02 05:17:35,768 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin
2023-08-02 05:17:35,768 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752
2023-08-02 05:17:35,770 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/
2023-08-02 05:17:35,835 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.01138758659362793, Smoothed response time: 0.01138758659362793
2023-08-02 05:17:35,835 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/"
2023-08-02 05:17:35,835 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status
2023-08-02 05:17:35,898 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011496543884277344, Smoothed response time: 0.011398482322692872
2023-08-02 05:17:35,898 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status"
2023-08-02 05:17:35,898 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting']
2023-08-02 05:17:35,899 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e
2023-08-02 05:17:36,167 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011539459228515625, Smoothed response time: 0.011412580013275147
2023-08-02 05:17:36,167 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e"
2023-08-02 05:17:36,167 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @virt.py:_send_data:612 - No data to send, waiting for next interval
------------------------------------------------------
Noted the last line: 'No data to send, waiting for next interval', virt-who doesn't catch the update for the first loop
6. continue to check the log for the next interval, virt-who can catch the update:
------------------------------------------------------
2023-08-02 05:19:20,720 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_run:170 - calling esx service
2023-08-02 05:19:20,726 [virtwho.main INFO] MainProcess(770559):Thread-2 @virt.py:_send_data:986 - Report for config "virtwho-esx" gathered, placing in datastore
2023-08-02 05:19:21,277 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used
2023-08-02 05:19:21,276 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin
2023-08-02 05:19:21,277 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752
2023-08-02 05:19:21,278 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin
2023-08-02 05:19:21,278 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/
2023-08-02 05:19:21,362 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.012140989303588867, Smoothed response time: 0.012140989303588867
2023-08-02 05:19:21,362 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/"
2023-08-02 05:19:21,362 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status
2023-08-02 05:19:21,430 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011748075485229492, Smoothed response time: 0.01210169792175293
2023-08-02 05:19:21,430 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status"
2023-08-02 05:19:21,430 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting']
2023-08-02 05:19:21,431 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e
2023-08-02 05:19:21,674 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.010841846466064453, Smoothed response time: 0.011975712776184083
2023-08-02 05:19:21,674 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e"
2023-08-02 05:19:21,675 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used
2023-08-02 05:19:21,675 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @virt.py:_send_data:651 - Hosts-to-guests mapping for config "virtwho-esx": 5 hypervisors and 8 guests found
2023-08-02 05:19:21,675 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @virt.py:_send_data:687 - Sending updated Host-to-guest mapping to "Default_Organization" including 5 hypervisors and 8 guests
2023-08-02 05:19:21,676 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin
2023-08-02 05:19:21,675 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin
2023-08-02 05:19:21,675 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752
2023-08-02 05:19:21,677 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/
2023-08-02 05:19:21,731 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011556148529052734, Smoothed response time: 0.011556148529052734
2023-08-02 05:19:21,731 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/"
2023-08-02 05:19:21,732 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_is_rhsm_server_async:292 - Checking if server has capability 'hypervisor_async'
2023-08-02 05:19:21,732 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status
2023-08-02 05:19:21,788 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.01147007942199707, Smoothed response time: 0.011547541618347168
2023-08-02 05:19:21,788 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status"
2023-08-02 05:19:21,789 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting']
2023-08-02 05:19:21,789 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: POST /rhsm/hypervisors/Default_Organization?env=&cloaked=False&reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e
2023-08-02 05:19:21,789 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_is_rhsm_server_async:296 - Server has capability 'hypervisors_async'
2023-08-02 05:19:21,789 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:hypervisorCheckIn:213 - Host-to-guest mapping being sent to 'Default_Organization': {
"hypervisors": [
{
"hypervisorId": {
"hypervisorId": "bootp-73-213-144.lab.eng.pek2.redhat.com"
},
"name": "bootp-73-213-144.lab.eng.pek2.redhat.com",
"guestIds": [
{
"guestId": "09a33b42-d988-8179-3ae7-ac341efae918",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
},
{
"guestId": "423bd245-0e9d-d482-955c-495fd8e21f3f",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
}
],
"facts": {
"cpu.cpu_socket(s)": "1",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "190b4d56-a632-b69b-5449-4c3afcd64007",
"hypervisor.cluster": "Cluster-Virtwho-85",
"hypervisor.version": "8.0.0"
}
},
{
"hypervisorId": {
"hypervisorId": "vm-212-147.lab.eng.pek2.redhat.com"
},
"name": "vm-212-147.lab.eng.pek2.redhat.com",
"guestIds": [
{
"guestId": "38973b42-b791-bb2b-0a04-5828ba35d948",
"state": 5,
"attributes": {
"virtWhoType": "esx",
"active": 0
}
},
{
"guestId": "423b5f04-6966-2fd3-fc68-c2696a537cce",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
},
{
"guestId": "fb633b42-78f1-5ec3-da83-5971d5b919a6",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
}
],
"facts": {
"cpu.cpu_socket(s)": "1",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "3a444d56-4207-677f-eb25-5fac1e7395d7",
"hypervisor.cluster": "Cluster-Virtwho-83",
"hypervisor.version": "8.0.0"
}
},
{
"hypervisorId": {
"hypervisorId": "bootp-73-213-211.lab.eng.pek2.redhat.com"
},
"name": "bootp-73-213-211.lab.eng.pek2.redhat.com",
"guestIds": [
{
"guestId": "423b3dcf-23ab-bb56-2bff-0b6aae4e9528",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
},
{
"guestId": "c25b3b42-d6d0-5ccb-e92b-c7b7018a6565",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
}
],
"facts": {
"cpu.cpu_socket(s)": "1",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "ccbf4d56-1dae-d6ab-00f0-a6a873468ef2",
"hypervisor.cluster": "Cluster-Virtwho-plugin",
"hypervisor.version": "8.0.0"
}
},
{
"hypervisorId": {
"hypervisorId": "bootp-73-5-217.rhts.eng.pek2.redhat.com"
},
"name": "bootp-73-5-217.rhts.eng.pek2.redhat.com",
"guestIds": [],
"facts": {
"cpu.cpu_socket(s)": "4",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "90374d56-42dc-e363-6ad1-8840981fb89f",
"hypervisor.version": "8.0.0"
}
},
{
"hypervisorId": {
"hypervisorId": "vm-212-255.lab.eng.pek2.redhat.com"
},
"name": "vm-212-255.lab.eng.pek2.redhat.com",
"guestIds": [
{
"guestId": "564d9498-c6bf-1bd4-1b08-5494679c2e83",
"state": 1,
"attributes": {
"virtWhoType": "esx",
"active": 1
}
}
],
"facts": {
"cpu.cpu_socket(s)": "2",
"hypervisor.type": "VMware ESXi",
"dmi.system.uuid": "ee124d56-fea8-3ff1-695a-b4635445e92a",
"hypervisor.version": "6.7.0"
}
}
]
}
2023-08-02 05:19:22,331 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.010886192321777344, Smoothed response time: 0.011481406688690186
2023-08-02 05:19:22,332 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="POST /rhsm/hypervisors/Default_Organization?env=&cloaked=False&reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e"
------------------------------------------------------
Expected results:
Virt-who should catch the update for the first interval loop
Additional info:
1.Works fine (can catch the update) when suspend the guest from active.
2.Works fine without restarting the service, it can catch the update everytime after that no matter start or suspend the guest.
Comment 6RHEL Program Management
2023-10-10 10:30:13 UTC
Issue migration from Bugzilla to Jira is in process at this time. This will be the last message in Jira copied from the Bugzilla bug.
Comment 7RHEL Program Management
2023-10-10 10:32:15 UTC
This BZ has been automatically migrated to the issues.redhat.com Red Hat Issue Tracker. All future work related to this report will be managed there.
Due to differences in account names between systems, some fields were not replicated. Be sure to add yourself to Jira issue's "Watchers" field to continue receiving updates and add others to the "Need Info From" field to continue requesting information.
To find the migrated issue, look in the "Links" section for a direct link to the new issue location. The issue key will have an icon of 2 footprints next to it, and begin with "RHEL-" followed by an integer. You can also find this issue by visiting https://issues.redhat.com/issues/?jql= and searching the "Bugzilla Bug" field for this BZ's number, e.g. a search like:
"Bugzilla Bug" = 1234567
In the event you have trouble locating or viewing this issue, you can file an issue by sending mail to rh-issues. You can also visit https://access.redhat.com/articles/7032570 for general account information.
Description of problem: Virt-who doesn't catch the update when restoring the guest to active from suspend after restarting the service for the first interval loop Version-Release number of selected component (if applicable): virt-who-1.30.16-1.el8.noarch How reproducible: 100% Steps to Reproduce: 1. configure the virt-who.conf [root@hp-z220-07 virt-who.d]# cat /etc/virt-who.conf [global] debug=true interval=120 2. create the virt-who config file: [root@hp-z220-07 virt-who.d]# cat esx.conf [virtwho-esx] type=esx hypervisor_id=hostname server=[server] username=[hostname] password=[password] owner=Default_Organization rhsm_hostname=[server] rhsm_username=admin rhsm_password=admin rhsm_prefix=/rhsm rhsm_port=443 3. Suspend the esx guest 4. run the virt-who by command 'service virt-who restart & tail -f /var/log/rhsm/rhsm.log', when getting the msg about 'Response status: 200', start the guest immediately 5. Getting the mapping info after restarting the virtwho service: ------------------------------------------------------ Redirecting to /bin/systemctl restart virt-who.service 2023-08-02 05:15:18,899 [virtwho.main DEBUG] MainProcess(770517):MainThread @executor.py:terminate:225 - virt-who is shutting down 2023-08-02 05:15:18,975 [virtwho.destination_2547277137715876194 DEBUG] MainProcess(770517):Thread-3 @virt.py:run:426 - Thread 'destination_2547277137715876194' terminated 2023-08-02 05:15:19,896 [virtwho.main DEBUG] MainProcess(770517):Thread-2 @virt.py:run:426 - Thread 'virtwho-esx' terminated 2023-08-02 05:15:20,215 [virtwho.parser WARNING] MainProcess(770559):MainThread @parser.py:read_config_env_variables:237 - The use of environment variables and the use of the sysconfig file to configure virt-who are deprecated. Their use will be ignored in the next major release. 2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "oneshot" not set, using default: False 2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "print_" not set, using default: False 2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "log_per_config" not set, using default: False 2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "configs" not set, using default: [] 2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "reporter_id" not set, using default: hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e 2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "log_file" not set, using default: rhsm.log 2023-08-02 05:15:20,218 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [global]: Value for "log_dir" not set, using default: /var/log/rhsm 2023-08-02 05:15:20,219 [virtwho.rhsm_log DEBUG] MainProcess(770559):MainThread @config.py:init_config:1594 - [virtwho-esx]: Value for "simplified_vim" not set, using default: True 2023-08-02 05:15:20,219 [virtwho.rhsm_log INFO] MainProcess(770559):MainThread @executor.py:__init__:54 - Using config named 'virtwho-esx' 2023-08-02 05:15:20,219 [virtwho.rhsm_log INFO] MainProcess(770559):MainThread @main.py:main:176 - Using configuration "virtwho-esx" ("esx" mode) 2023-08-02 05:15:20,219 [virtwho.rhsm_log INFO] MainProcess(770559):MainThread @main.py:main:178 - Using reporter_id='hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e' 2023-08-02 05:15:20,220 [virtwho.main DEBUG] MainProcess(770559):MainThread @executor.py:run:187 - Starting infinite loop with 120 seconds interval 2023-08-02 05:15:20,229 [rhsm.https DEBUG] MainProcess(770559):MainThread @https.py:<module>:56 - Using standard libs to provide httplib and ssl 2023-08-02 05:15:20,235 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @virt.py:run:407 - Thread 'virtwho-esx' started 2023-08-02 05:15:20,235 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_prepare:136 - Log into ESX 2023-08-02 05:15:20,236 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @virt.py:run:407 - Thread 'destination_8663284226366541201' started 2023-08-02 05:15:20,340 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_prepare:139 - Creating ESX event filter 2023-08-02 05:15:20,379 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_run:170 - calling esx service 2023-08-02 05:15:20,464 [virtwho.main INFO] MainProcess(770559):Thread-2 @virt.py:_send_data:986 - Report for config "virtwho-esx" gathered, placing in datastore 2023-08-02 05:15:21,237 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used 2023-08-02 05:15:21,237 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin 2023-08-02 05:15:21,237 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin 2023-08-02 05:15:21,237 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752 2023-08-02 05:15:21,239 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/ 2023-08-02 05:15:21,318 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.012628793716430664, Smoothed response time: 0.012628793716430664 2023-08-02 05:15:21,319 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/" 2023-08-02 05:15:21,319 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status 2023-08-02 05:15:21,381 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011539459228515625, Smoothed response time: 0.01251986026763916 2023-08-02 05:15:21,381 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status" 2023-08-02 05:15:21,382 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting'] 2023-08-02 05:15:21,382 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e 2023-08-02 05:15:21,615 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011245012283325195, Smoothed response time: 0.012392375469207764 2023-08-02 05:15:21,615 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e" 2023-08-02 05:15:21,616 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used 2023-08-02 05:15:21,616 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @virt.py:_send_data:651 - Hosts-to-guests mapping for config "virtwho-esx": 5 hypervisors and 8 guests found 2023-08-02 05:15:21,617 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin 2023-08-02 05:15:21,616 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @virt.py:_send_data:687 - Sending updated Host-to-guest mapping to "Default_Organization" including 5 hypervisors and 8 guests 2023-08-02 05:15:21,616 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin 2023-08-02 05:15:21,616 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752 2023-08-02 05:15:21,618 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/ 2023-08-02 05:15:21,677 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011118650436401367, Smoothed response time: 0.011118650436401367 2023-08-02 05:15:21,677 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/" 2023-08-02 05:15:21,678 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status 2023-08-02 05:15:21,678 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_is_rhsm_server_async:292 - Checking if server has capability 'hypervisor_async' 2023-08-02 05:15:21,734 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.010668516159057617, Smoothed response time: 0.011073637008666991 2023-08-02 05:15:21,734 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status" 2023-08-02 05:15:21,735 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting'] 2023-08-02 05:15:21,736 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: POST /rhsm/hypervisors/Default_Organization?env=&cloaked=False&reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e 2023-08-02 05:15:21,735 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_is_rhsm_server_async:296 - Server has capability 'hypervisors_async' 2023-08-02 05:15:21,735 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:hypervisorCheckIn:213 - Host-to-guest mapping being sent to 'Default_Organization': { "hypervisors": [ { "hypervisorId": { "hypervisorId": "bootp-73-213-144.lab.eng.pek2.redhat.com" }, "name": "bootp-73-213-144.lab.eng.pek2.redhat.com", "guestIds": [ { "guestId": "09a33b42-d988-8179-3ae7-ac341efae918", "state": 3, "attributes": { "virtWhoType": "esx", "active": 1 } }, { "guestId": "423bd245-0e9d-d482-955c-495fd8e21f3f", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } } ], "facts": { "cpu.cpu_socket(s)": "1", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "190b4d56-a632-b69b-5449-4c3afcd64007", "hypervisor.cluster": "Cluster-Virtwho-85", "hypervisor.version": "8.0.0" } }, { "hypervisorId": { "hypervisorId": "vm-212-147.lab.eng.pek2.redhat.com" }, "name": "vm-212-147.lab.eng.pek2.redhat.com", "guestIds": [ { "guestId": "38973b42-b791-bb2b-0a04-5828ba35d948", "state": 5, "attributes": { "virtWhoType": "esx", "active": 0 } }, { "guestId": "423b5f04-6966-2fd3-fc68-c2696a537cce", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } }, { "guestId": "fb633b42-78f1-5ec3-da83-5971d5b919a6", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } } ], "facts": { "cpu.cpu_socket(s)": "1", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "3a444d56-4207-677f-eb25-5fac1e7395d7", "hypervisor.cluster": "Cluster-Virtwho-83", "hypervisor.version": "8.0.0" } }, { "hypervisorId": { "hypervisorId": "bootp-73-213-211.lab.eng.pek2.redhat.com" }, "name": "bootp-73-213-211.lab.eng.pek2.redhat.com", "guestIds": [ { "guestId": "423b3dcf-23ab-bb56-2bff-0b6aae4e9528", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } }, { "guestId": "c25b3b42-d6d0-5ccb-e92b-c7b7018a6565", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } } ], "facts": { "cpu.cpu_socket(s)": "1", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "ccbf4d56-1dae-d6ab-00f0-a6a873468ef2", "hypervisor.cluster": "Cluster-Virtwho-plugin", "hypervisor.version": "8.0.0" } }, { "hypervisorId": { "hypervisorId": "bootp-73-5-217.rhts.eng.pek2.redhat.com" }, "name": "bootp-73-5-217.rhts.eng.pek2.redhat.com", "guestIds": [], "facts": { "cpu.cpu_socket(s)": "4", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "90374d56-42dc-e363-6ad1-8840981fb89f", "hypervisor.version": "8.0.0" } }, { "hypervisorId": { "hypervisorId": "vm-212-255.lab.eng.pek2.redhat.com" }, "name": "vm-212-255.lab.eng.pek2.redhat.com", "guestIds": [ { "guestId": "564d9498-c6bf-1bd4-1b08-5494679c2e83", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } } ], "facts": { "cpu.cpu_socket(s)": "2", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "ee124d56-fea8-3ff1-695a-b4635445e92a", "hypervisor.version": "6.7.0" } } ] } 2023-08-02 05:15:22,249 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011012077331542969, Smoothed response time: 0.011067481040954591 2023-08-02 05:15:22,249 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="POST /rhsm/hypervisors/Default_Organization?env=&cloaked=False&reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e" ------------------------------------------------------ 5. After the interval time(120s), getting the virt-who log: ------------------------------------------------------ 2023-08-02 05:17:20,372 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @virt.py:check_report_status:778 - Existing report state: 1 2023-08-02 05:17:20,588 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_run:170 - calling esx service 2023-08-02 05:17:20,596 [virtwho.main INFO] MainProcess(770559):Thread-2 @virt.py:_send_data:986 - Report for config "virtwho-esx" gathered, placing in datastore 2023-08-02 05:17:35,387 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used 2023-08-02 05:17:35,387 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin 2023-08-02 05:17:35,388 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin 2023-08-02 05:17:35,387 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752 2023-08-02 05:17:35,388 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/ 2023-08-02 05:17:35,522 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.012730121612548828, Smoothed response time: 0.012730121612548828 2023-08-02 05:17:35,522 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/" 2023-08-02 05:17:35,523 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/jobs/8ac984f089844d300189b587b738195d?result_data=True 2023-08-02 05:17:35,523 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:check_report_state:340 - Checking status of job 8ac984f089844d300189b587b738195d 2023-08-02 05:17:35,766 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011625289916992188, Smoothed response time: 0.012619638442993164 2023-08-02 05:17:35,766 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/jobs/8ac984f089844d300189b587b738195d?result_data=True" 2023-08-02 05:17:35,767 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:check_report_state:368 - Number of mappings unchanged: 5 2023-08-02 05:17:35,767 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:check_report_state:369 - Mapping for config "destination_8663284226366541201" updated 2023-08-02 05:17:35,768 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used 2023-08-02 05:17:35,768 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @virt.py:_get_data_common:551 - Duplicate report found for config "virtwho-esx", ignoring 2023-08-02 05:17:35,769 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin 2023-08-02 05:17:35,768 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin 2023-08-02 05:17:35,768 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752 2023-08-02 05:17:35,770 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/ 2023-08-02 05:17:35,835 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.01138758659362793, Smoothed response time: 0.01138758659362793 2023-08-02 05:17:35,835 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/" 2023-08-02 05:17:35,835 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status 2023-08-02 05:17:35,898 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011496543884277344, Smoothed response time: 0.011398482322692872 2023-08-02 05:17:35,898 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status" 2023-08-02 05:17:35,898 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting'] 2023-08-02 05:17:35,899 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e 2023-08-02 05:17:36,167 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011539459228515625, Smoothed response time: 0.011412580013275147 2023-08-02 05:17:36,167 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e" 2023-08-02 05:17:36,167 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @virt.py:_send_data:612 - No data to send, waiting for next interval ------------------------------------------------------ Noted the last line: 'No data to send, waiting for next interval', virt-who doesn't catch the update for the first loop 6. continue to check the log for the next interval, virt-who can catch the update: ------------------------------------------------------ 2023-08-02 05:19:20,720 [virtwho.main DEBUG] MainProcess(770559):Thread-2 @esx.py:_run:170 - calling esx service 2023-08-02 05:19:20,726 [virtwho.main INFO] MainProcess(770559):Thread-2 @virt.py:_send_data:986 - Report for config "virtwho-esx" gathered, placing in datastore 2023-08-02 05:19:21,277 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used 2023-08-02 05:19:21,276 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin 2023-08-02 05:19:21,277 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752 2023-08-02 05:19:21,278 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin 2023-08-02 05:19:21,278 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/ 2023-08-02 05:19:21,362 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.012140989303588867, Smoothed response time: 0.012140989303588867 2023-08-02 05:19:21,362 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/" 2023-08-02 05:19:21,362 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status 2023-08-02 05:19:21,430 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011748075485229492, Smoothed response time: 0.01210169792175293 2023-08-02 05:19:21,430 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status" 2023-08-02 05:19:21,430 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting'] 2023-08-02 05:19:21,431 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e 2023-08-02 05:19:21,674 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.010841846466064453, Smoothed response time: 0.011975712776184083 2023-08-02 05:19:21,674 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="PUT /rhsm/hypervisors/Default_Organization/heartbeat?reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e" 2023-08-02 05:19:21,675 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:211 - Environment variable NO_PROXY= will be used 2023-08-02 05:19:21,675 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @virt.py:_send_data:651 - Hosts-to-guests mapping for config "virtwho-esx": 5 hypervisors and 8 guests found 2023-08-02 05:19:21,675 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @virt.py:_send_data:687 - Sending updated Host-to-guest mapping to "Default_Organization" including 5 hypervisors and 8 guests 2023-08-02 05:19:21,676 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:__init__:310 - Connection built: host=hp-dl360g9-08-vm-03.rhts.eng.pek2.redhat.com port=443 handler=/rhsm auth=basic username=admin 2023-08-02 05:19:21,675 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:148 - Authenticating with RHSM username admin 2023-08-02 05:19:21,675 [virtwho.destination_8663284226366541201 INFO] MainProcess(770559):Thread-3 @subscriptionmanager.py:_connect:159 - X-Correlation-ID: c8be598973ef4d7c94650945722e6752 2023-08-02 05:19:21,677 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status/ 2023-08-02 05:19:21,731 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.011556148529052734, Smoothed response time: 0.011556148529052734 2023-08-02 05:19:21,731 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status/" 2023-08-02 05:19:21,732 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_is_rhsm_server_async:292 - Checking if server has capability 'hypervisor_async' 2023-08-02 05:19:21,732 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: GET /rhsm/status 2023-08-02 05:19:21,788 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.01147007942199707, Smoothed response time: 0.011547541618347168 2023-08-02 05:19:21,788 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="GET /rhsm/status" 2023-08-02 05:19:21,789 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_load_manager_capabilities:1112 - Server has the following capabilities: ['instance_multiplier', 'derived_product', 'vcpu', 'cert_v3', 'hypervisors_heartbeat', 'remove_by_pool_id', 'syspurpose', 'storage_band', 'cores', 'multi_environment', 'hypervisors_async', 'org_level_content_access', 'guest_limit', 'ram', 'batch_bind', 'combined_reporting'] 2023-08-02 05:19:21,789 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:779 - Making request: POST /rhsm/hypervisors/Default_Organization?env=&cloaked=False&reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e 2023-08-02 05:19:21,789 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:_is_rhsm_server_async:296 - Server has capability 'hypervisors_async' 2023-08-02 05:19:21,789 [virtwho.destination_8663284226366541201 DEBUG] MainProcess(770559):Thread-3 @subscriptionmanager.py:hypervisorCheckIn:213 - Host-to-guest mapping being sent to 'Default_Organization': { "hypervisors": [ { "hypervisorId": { "hypervisorId": "bootp-73-213-144.lab.eng.pek2.redhat.com" }, "name": "bootp-73-213-144.lab.eng.pek2.redhat.com", "guestIds": [ { "guestId": "09a33b42-d988-8179-3ae7-ac341efae918", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } }, { "guestId": "423bd245-0e9d-d482-955c-495fd8e21f3f", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } } ], "facts": { "cpu.cpu_socket(s)": "1", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "190b4d56-a632-b69b-5449-4c3afcd64007", "hypervisor.cluster": "Cluster-Virtwho-85", "hypervisor.version": "8.0.0" } }, { "hypervisorId": { "hypervisorId": "vm-212-147.lab.eng.pek2.redhat.com" }, "name": "vm-212-147.lab.eng.pek2.redhat.com", "guestIds": [ { "guestId": "38973b42-b791-bb2b-0a04-5828ba35d948", "state": 5, "attributes": { "virtWhoType": "esx", "active": 0 } }, { "guestId": "423b5f04-6966-2fd3-fc68-c2696a537cce", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } }, { "guestId": "fb633b42-78f1-5ec3-da83-5971d5b919a6", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } } ], "facts": { "cpu.cpu_socket(s)": "1", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "3a444d56-4207-677f-eb25-5fac1e7395d7", "hypervisor.cluster": "Cluster-Virtwho-83", "hypervisor.version": "8.0.0" } }, { "hypervisorId": { "hypervisorId": "bootp-73-213-211.lab.eng.pek2.redhat.com" }, "name": "bootp-73-213-211.lab.eng.pek2.redhat.com", "guestIds": [ { "guestId": "423b3dcf-23ab-bb56-2bff-0b6aae4e9528", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } }, { "guestId": "c25b3b42-d6d0-5ccb-e92b-c7b7018a6565", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } } ], "facts": { "cpu.cpu_socket(s)": "1", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "ccbf4d56-1dae-d6ab-00f0-a6a873468ef2", "hypervisor.cluster": "Cluster-Virtwho-plugin", "hypervisor.version": "8.0.0" } }, { "hypervisorId": { "hypervisorId": "bootp-73-5-217.rhts.eng.pek2.redhat.com" }, "name": "bootp-73-5-217.rhts.eng.pek2.redhat.com", "guestIds": [], "facts": { "cpu.cpu_socket(s)": "4", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "90374d56-42dc-e363-6ad1-8840981fb89f", "hypervisor.version": "8.0.0" } }, { "hypervisorId": { "hypervisorId": "vm-212-255.lab.eng.pek2.redhat.com" }, "name": "vm-212-255.lab.eng.pek2.redhat.com", "guestIds": [ { "guestId": "564d9498-c6bf-1bd4-1b08-5494679c2e83", "state": 1, "attributes": { "virtWhoType": "esx", "active": 1 } } ], "facts": { "cpu.cpu_socket(s)": "2", "hypervisor.type": "VMware ESXi", "dmi.system.uuid": "ee124d56-fea8-3ff1-695a-b4635445e92a", "hypervisor.version": "6.7.0" } } ] } 2023-08-02 05:19:22,331 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_update_smoothed_response_time:884 - Response time: 0.010886192321777344, Smoothed response time: 0.011481406688690186 2023-08-02 05:19:22,332 [rhsm.connection DEBUG] MainProcess(770559):Thread-3 @connection.py:_request:861 - Response: status=200, request="POST /rhsm/hypervisors/Default_Organization?env=&cloaked=False&reporter_id=hp-z220-07.qe.lab.eng.nay.redhat.com-933caa0665544daaa9aa3d2c43ee9e1e" ------------------------------------------------------ Expected results: Virt-who should catch the update for the first interval loop Additional info: 1.Works fine (can catch the update) when suspend the guest from active. 2.Works fine without restarting the service, it can catch the update everytime after that no matter start or suspend the guest.