Bug 1858296
| Summary: | rhsmcertd checkin ignoring cache and POSTing package profiles | |||
|---|---|---|---|---|
| Product: | Red Hat Enterprise Linux 8 | Reporter: | Craig Donnelly <cdonnell> | |
| Component: | subscription-manager | Assignee: | Jiri Hnidek <jhnidek> | |
| Status: | CLOSED ERRATA | QA Contact: | Red Hat subscription-manager QE Team <rhsm-qe> | |
| Severity: | high | Docs Contact: | ||
| Priority: | high | |||
| Version: | 8.3 | CC: | jhnidek, mjia, qianzhan, redakkan, wpoteat | |
| Target Milestone: | rc | Keywords: | Regression, Triaged, ZStream | |
| Target Release: | 8.3 | Flags: | pm-rhel:
mirror+
|
|
| Hardware: | Unspecified | |||
| OS: | Unspecified | |||
| Whiteboard: | ||||
| Fixed In Version: | Doc Type: | If docs needed, set a value | ||
| Doc Text: | Story Points: | --- | ||
| Clone Of: | ||||
| : | 1869161 (view as bug list) | Environment: | ||
| Last Closed: | 2020-11-04 01:39:41 UTC | Type: | Bug | |
| Regression: | --- | Mount Type: | --- | |
| Documentation: | --- | CRM: | ||
| Verified Versions: | Category: | --- | ||
| oVirt Team: | --- | RHEL 7.3 requirements from Atomic Host: | ||
| Cloudforms Team: | --- | Target Upstream Version: | ||
| Embargoed: | ||||
| Bug Depends On: | ||||
| Bug Blocks: | 1869161 | |||
*** Bug 1861628 has been marked as a duplicate of this bug. *** VERIFYING against subcsription-manager-1.27.13-1: >> Reproducing against subscription-manager-1.27.8-1: >> Enable a dnf module stream: # dnf module enable squid -y > tail -f /var/log/rhsm/rhsm.log 2020-08-12 15:57:15,571 [DEBUG] rhsmcertd-worker:729119:MainThread @connection.py:939 - Server supports the following resources: {'consumers': '/rhsm/environments/:environment_id/consumers', 'hypervisors': '/rhsm/hypervisors', ':owner': '/rhsm/hypervisors/:owner', 'environments': '/rhsm/owners/:organization_id/environments', 'pools': '/rhsm/pools', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'owners': '/rhsm/users/:login/owners', 'accessible_content': '/rhsm/consumers/:id/accessible_content', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'release': '/rhsm/consumers/:id/release', 'compliance': '/rhsm/consumers/:id/compliance', 'purpose_compliance': '/rhsm/consumers/:id/purpose_compliance', 'serials': '/rhsm/consumers/:id/certificates/serials', 'entitlements': '/rhsm/entitlements', ':poolId': '/rhsm/consumers/:id/entitlements/pool/:poolId', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'owner': '/rhsm/consumers/:id/owner', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'deleted_consumers': '/rhsm/deleted_consumers', 'subscriptions': '/rhsm/subscriptions', 'profiles': '/rhsm/consumers/:id/profiles', 'profile': '/rhsm/consumers/:id/profile', 'packages': '/rhsm/consumers/:id/packages', 'tracer': '/rhsm/consumers/:id/tracer', 'checkin': '/rhsm/consumers/:id/checkin', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'available_releases': '/rhsm/consumers/:id/available_releases', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', ':jobId': '/rhsm/jobs/:jobId', 'status': '/rhsm/status'} 2020-08-12 15:57:17,615 [DEBUG] rhsmcertd-worker:729119:MainThread @connection.py:676 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c/profiles 2020-08-12 15:57:17,973 [DEBUG] rhsmcertd-worker:729119:MainThread @connection.py:747 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c/profiles" 2020-08-12 15:58:15,582 [DEBUG] rhsmcertd-worker:729168:MainThread @connection.py:939 - Server supports the following resources: {'consumers': '/rhsm/environments/:environment_id/consumers', 'hypervisors': '/rhsm/hypervisors', ':owner': '/rhsm/hypervisors/:owner', 'environments': '/rhsm/owners/:organization_id/environments', 'pools': '/rhsm/pools', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'owners': '/rhsm/users/:login/owners', 'accessible_content': '/rhsm/consumers/:id/accessible_content', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'release': '/rhsm/consumers/:id/release', 'compliance': '/rhsm/consumers/:id/compliance', 'purpose_compliance': '/rhsm/consumers/:id/purpose_compliance', 'serials': '/rhsm/consumers/:id/certificates/serials', 'entitlements': '/rhsm/entitlements', ':poolId': '/rhsm/consumers/:id/entitlements/pool/:poolId', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'owner': '/rhsm/consumers/:id/owner', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'deleted_consumers': '/rhsm/deleted_consumers', 'subscriptions': '/rhsm/subscriptions', 'profiles': '/rhsm/consumers/:id/profiles', 'profile': '/rhsm/consumers/:id/profile', 'packages': '/rhsm/consumers/:id/packages', 'tracer': '/rhsm/consumers/:id/tracer', 'checkin': '/rhsm/consumers/:id/checkin', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'available_releases': '/rhsm/consumers/:id/available_releases', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', ':jobId': '/rhsm/jobs/:jobId', 'status': '/rhsm/status'} 2020-08-12 15:58:16,128 [DEBUG] rhsmcertd-worker:729168:MainThread @connection.py:676 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 15:58:16,391 [DEBUG] rhsmcertd-worker:729168:MainThread @connection.py:747 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 15:58:17,885 [DEBUG] rhsmcertd-worker:729168:MainThread @connection.py:676 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c/profiles 2020-08-12 15:58:18,298 [DEBUG] rhsmcertd-worker:729168:MainThread @connection.py:747 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c/profiles" 2020-08-12 15:59:15,614 [DEBUG] rhsmcertd-worker:729256:MainThread @connection.py:939 - Server supports the following resources: {'consumers': '/rhsm/environments/:environment_id/consumers', 'hypervisors': '/rhsm/hypervisors', ':owner': '/rhsm/hypervisors/:owner', 'environments': '/rhsm/owners/:organization_id/environments', 'pools': '/rhsm/pools', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'owners': '/rhsm/users/:login/owners', 'accessible_content': '/rhsm/consumers/:id/accessible_content', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'release': '/rhsm/consumers/:id/release', 'compliance': '/rhsm/consumers/:id/compliance', 'purpose_compliance': '/rhsm/consumers/:id/purpose_compliance', 'serials': '/rhsm/consumers/:id/certificates/serials', 'entitlements': '/rhsm/entitlements', ':poolId': '/rhsm/consumers/:id/entitlements/pool/:poolId', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'owner': '/rhsm/consumers/:id/owner', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'deleted_consumers': '/rhsm/deleted_consumers', 'subscriptions': '/rhsm/subscriptions', 'profiles': '/rhsm/consumers/:id/profiles', 'profile': '/rhsm/consumers/:id/profile', 'packages': '/rhsm/consumers/:id/packages', 'tracer': '/rhsm/consumers/:id/tracer', 'checkin': '/rhsm/consumers/:id/checkin', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'available_releases': '/rhsm/consumers/:id/available_releases', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', ':jobId': '/rhsm/jobs/:jobId', 'status': '/rhsm/status'} 2020-08-12 15:59:16,178 [DEBUG] rhsmcertd-worker:729256:MainThread @connection.py:676 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 15:59:16,448 [DEBUG] rhsmcertd-worker:729256:MainThread @connection.py:747 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 15:59:17,932 [DEBUG] rhsmcertd-worker:729256:MainThread @connection.py:676 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c/profiles 2020-08-12 15:59:18,331 [DEBUG] rhsmcertd-worker:729256:MainThread @connection.py:747 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c/profiles" 2020-08-12 16:00:15,687 [DEBUG] rhsmcertd-worker:729344:MainThread @connection.py:939 - Server supports the following resources: {'consumers': '/rhsm/environments/:environment_id/consumers', 'hypervisors': '/rhsm/hypervisors', ':owner': '/rhsm/hypervisors/:owner', 'environments': '/rhsm/owners/:organization_id/environments', 'pools': '/rhsm/pools', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'owners': '/rhsm/users/:login/owners', 'accessible_content': '/rhsm/consumers/:id/accessible_content', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'release': '/rhsm/consumers/:id/release', 'compliance': '/rhsm/consumers/:id/compliance', 'purpose_compliance': '/rhsm/consumers/:id/purpose_compliance', 'serials': '/rhsm/consumers/:id/certificates/serials', 'entitlements': '/rhsm/entitlements', ':poolId': '/rhsm/consumers/:id/entitlements/pool/:poolId', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'owner': '/rhsm/consumers/:id/owner', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'deleted_consumers': '/rhsm/deleted_consumers', 'subscriptions': '/rhsm/subscriptions', 'profiles': '/rhsm/consumers/:id/profiles', 'profile': '/rhsm/consumers/:id/profile', 'packages': '/rhsm/consumers/:id/packages', 'tracer': '/rhsm/consumers/:id/tracer', 'checkin': '/rhsm/consumers/:id/checkin', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'available_releases': '/rhsm/consumers/:id/available_releases', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', ':jobId': '/rhsm/jobs/:jobId', 'status': '/rhsm/status'} 2020-08-12 16:00:17,984 [DEBUG] rhsmcertd-worker:729344:MainThread @connection.py:676 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c/profiles 2020-08-12 16:00:18,391 [DEBUG] rhsmcertd-worker:729344:MainThread @connection.py:747 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c/profiles" >> We can see package profile is PUT to server on each rhsmcertd checkin above, which is incorrect behaviour. Updating to sub-man-1.27.13-1: # yum update subscription-manager python3-syspurpose Updating Subscription Management repositories. Red Hat Enterprise Linux 8 for x86_64 - BaseOS (RPMs) 35 kB/s | 2.4 kB 00:00 Red Hat Enterprise Linux 8 for x86_64 - AppStream (RPMs) 40 kB/s | 2.8 kB 00:00 Dependencies resolved. ===================================================================================================================== Package Arch Version Repository Size ===================================================================================================================== Upgrading: dnf-plugin-subscription-manager x86_64 1.27.13-1.el8 brew-task-repo-subscription-manager-1.27.13-1.el8 285 k python3-subscription-manager-rhsm x86_64 1.27.13-1.el8 brew-task-repo-subscription-manager-1.27.13-1.el8 358 k python3-syspurpose x86_64 1.27.13-1.el8 brew-task-repo-subscription-manager-1.27.13-1.el8 294 k subscription-manager x86_64 1.27.13-1.el8 brew-task-repo-subscription-manager-1.27.13-1.el8 1.1 M subscription-manager-rhsm-certificates x86_64 1.27.13-1.el8 brew-task-repo-subscription-manager-1.27.13-1.el8 258 k Transaction Summary ===================================================================================================================== Upgrade 5 Packages Total download size: 2.3 M Is this ok [y/N]: y Downloading Packages: (1/5): dnf-plugin-subscription-manager-1.27.13-1.el8.x86_64.rpm 651 kB/s | 285 kB 00:00 (2/5): python3-syspurpose-1.27.13-1.el8.x86_64.rpm 642 kB/s | 294 kB 00:00 (3/5): python3-subscription-manager-rhsm-1.27.13-1.el8.x86_64.rpm 777 kB/s | 358 kB 00:00 (4/5): subscription-manager-rhsm-certificates-1.27.13-1.el8.x86_64.rpm 2.3 MB/s | 258 kB 00:00 (5/5): subscription-manager-1.27.13-1.el8.x86_64.rpm 5.4 MB/s | 1.1 MB 00:00 --------------------------------------------------------------------------------------------------------------------- Total 3.5 MB/s | 2.3 MB 00:00 Running transaction check Transaction check succeeded. Running transaction test Transaction test succeeded. Running transaction Preparing : 1/1 Running scriptlet: subscription-manager-rhsm-certificates-1.27.13-1.el8.x86_64 1/1 Upgrading : subscription-manager-rhsm-certificates-1.27.13-1.el8.x86_64 1/10 Upgrading : python3-subscription-manager-rhsm-1.27.13-1.el8.x86_64 2/10 Upgrading : python3-syspurpose-1.27.13-1.el8.x86_64 3/10 Upgrading : dnf-plugin-subscription-manager-1.27.13-1.el8.x86_64 4/10 Upgrading : subscription-manager-1.27.13-1.el8.x86_64 5/10 warning: /etc/rhsm/rhsm.conf created as /etc/rhsm/rhsm.conf.rpmnew Running scriptlet: subscription-manager-1.27.13-1.el8.x86_64 5/10 Running scriptlet: subscription-manager-1.27.8-1.el8.x86_64 6/10 Cleanup : subscription-manager-1.27.8-1.el8.x86_64 6/10 Running scriptlet: subscription-manager-1.27.8-1.el8.x86_64 6/10 Cleanup : python3-syspurpose-1.27.8-1.el8.x86_64 7/10 Cleanup : python3-subscription-manager-rhsm-1.27.8-1.el8.x86_64 8/10 Cleanup : subscription-manager-rhsm-certificates-1.27.8-1.el8.x86_64 9/10 Cleanup : dnf-plugin-subscription-manager-1.27.8-1.el8.x86_64 10/10 Running scriptlet: dnf-plugin-subscription-manager-1.27.8-1.el8.x86_64 10/10 Verifying : dnf-plugin-subscription-manager-1.27.13-1.el8.x86_64 1/10 Verifying : dnf-plugin-subscription-manager-1.27.8-1.el8.x86_64 2/10 Verifying : python3-subscription-manager-rhsm-1.27.13-1.el8.x86_64 3/10 Verifying : python3-subscription-manager-rhsm-1.27.8-1.el8.x86_64 4/10 Verifying : python3-syspurpose-1.27.13-1.el8.x86_64 5/10 Verifying : python3-syspurpose-1.27.8-1.el8.x86_64 6/10 Verifying : subscription-manager-1.27.13-1.el8.x86_64 7/10 Verifying : subscription-manager-1.27.8-1.el8.x86_64 8/10 Verifying : subscription-manager-rhsm-certificates-1.27.13-1.el8.x86_64 9/10 Verifying : subscription-manager-rhsm-certificates-1.27.8-1.el8.x86_64 10/10 Installed products updated. Upgraded: dnf-plugin-subscription-manager-1.27.13-1.el8.x86_64 python3-subscription-manager-rhsm-1.27.13-1.el8.x86_64 python3-syspurpose-1.27.13-1.el8.x86_64 subscription-manager-1.27.13-1.el8.x86_64 subscription-manager-rhsm-certificates-1.27.13-1.el8.x86_64 Complete! >> Now checking rhsmcertd checkins: > # tail -f /var/log/rhsm/rhsm.log: 2020-08-12 16:25:37,115 [DEBUG] rhsmcertd-worker:731481:MainThread @connection.py:756 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 16:26:37,090 [DEBUG] rhsmcertd-worker:731569:MainThread @connection.py:685 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 16:26:37,370 [DEBUG] rhsmcertd-worker:731569:MainThread @connection.py:756 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 16:28:36,947 [DEBUG] rhsmcertd-worker:731708:MainThread @connection.py:685 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 16:28:37,313 [DEBUG] rhsmcertd-worker:731708:MainThread @connection.py:756 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 16:31:37,620 [DEBUG] rhsmcertd-worker:731902:MainThread @connection.py:685 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 16:31:37,904 [DEBUG] rhsmcertd-worker:731902:MainThread @connection.py:756 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 16:32:37,094 [DEBUG] rhsmcertd-worker:731994:MainThread @connection.py:685 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 16:32:37,363 [DEBUG] rhsmcertd-worker:731994:MainThread @connection.py:756 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 16:33:36,957 [DEBUG] rhsmcertd-worker:732084:MainThread @connection.py:685 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 16:33:37,351 [DEBUG] rhsmcertd-worker:732084:MainThread @connection.py:756 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 16:34:37,072 [DEBUG] rhsmcertd-worker:732176:MainThread @connection.py:685 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 16:34:37,348 [DEBUG] rhsmcertd-worker:732176:MainThread @connection.py:756 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" 2020-08-12 16:37:37,068 [DEBUG] rhsmcertd-worker:732363:MainThread @connection.py:685 - Making request: PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c 2020-08-12 16:37:37,343 [DEBUG] rhsmcertd-worker:732363:MainThread @connection.py:756 - Response: status=200, request="PUT /rhsm/consumers/453aaf8c-6f5b-4ed1-a0bb-fb510616e14c" >> No extraneous PUTs for package profiles outside of package list changes which occurred via install/remove/update of packages on the host. VERIFIED. Since the problem described in this bug report should be resolved in a recent advisory, it has been closed with a resolution of ERRATA. For information on the advisory (subscription-manager bug fix and enhancement update), and where to find the updated files, follow the link below. If the solution does not work for you, open a new bug report. https://access.redhat.com/errata/RHBA-2020:4460 |
Description of problem: In current RHEL 8.3 builds, rhsmcertd checkins are spamming package profile updates on checkins even though there have not been any changes on the host. The package profile report occurs on /every/ rhsmcertd checkin and also causes a regenerate applicability for that host on satellite. This could create poor performance for satellite environments. This can be observed while watching /var/log/foreman/production.log on satellite. <Snip of report> 2020-07-17T09:08:46 [I|app|604b8cd3] Started PUT "/rhsm/consumers/a22de5de-8221-43da-8cd4-229ee1ce3587/profiles" for 10.16.6.78 at 2020-07-17 09:08:46 -0400 2020-07-17T09:08:46 [I|app|604b8cd3] Processing by Katello::Api::Rhsm::CandlepinDynflowProxyController#upload_profiles as JSON 2020-07-17T09:08:46 [I|app|604b8cd3] Parameters: {"id"=>"a22de5de-8221-43da-8cd4-229ee1ce3587"} 2020-07-17T09:08:46 [I|app|604b8cd3] Current user set to a22de5de-8221-43da-8cd4-229ee1ce3587 (regular) 2020-07-17T09:08:46 [I|bac|604b8cd3] Task {label: Actions::Katello::Host::UploadProfiles, id: 1aefb4fe-acfd-4a78-b168-f7a9c8d435bc, execution_plan_id: 54cbf934-c0dc-4ba2-bb7b-db0e17c72124} state changed: planning 2020-07-17T09:08:46 [I|kat|] GET: https://cragsat66.usersys.redhat.com/pulp/api/v2/users/: {"content_type"=>"application/json", "accept"=>"application/json"} Response: 200: [{"_id": {"$oid": "5db038727d9f942276cd8fe8"}, "name": "admin", "roles": ["super-users"], "_ns": "users", "login": "admin", "_href": "/pulp/api/v2/users/admin/"}] 2020-07-17T09:08:46 [I|bac|] Task {label: Actions::Katello::Host::UploadProfiles, id: 1aefb4fe-acfd-4a78-b168-f7a9c8d435bc, execution_plan_id: 54cbf934-c0dc-4ba2-bb7b-db0e17c72124} state changed: planned 2020-07-17T09:08:46 [I|app|] Current user set to a22de5de-8221-43da-8cd4-229ee1ce3587 (regular) 2020-07-17T09:08:46 [I|app|] Completed 200 OK in 386ms (Views: 9.2ms | ActiveRecord: 44.8ms) 2020-07-17T09:08:47 [I|app|d2feb4a3] Started GET "/rhsm/consumers/a22de5de-8221-43da-8cd4-229ee1ce3587" for 10.16.6.78 at 2020-07-17 09:08:47 -0400 2020-07-17T09:08:47 [I|app|d2feb4a3] Processing by Katello::Api::Rhsm::CandlepinProxiesController#consumer_show as JSON 2020-07-17T09:08:47 [I|app|d2feb4a3] Parameters: {"id"=>"a22de5de-8221-43da-8cd4-229ee1ce3587"} 2020-07-17T09:08:47 [I|app|d2feb4a3] Current user set to a22de5de-8221-43da-8cd4-229ee1ce3587 (regular) 2020-07-17T09:05:53 [I|kat|] POST: https://cragsat66.usersys.redhat.com/pulp/api/v2/consumers/a22de5de-8221-43da-8cd4-229ee1ce3587/actions/content/regenerate_applicability/: {}: {"content_type"=>"application/json", "accept"=>"application/json"} Response: 202: {"spawned_tasks": [{"_href": "/pulp/api/v2/tasks/840607fb-ee17-40d6-b922-b1fcc72de434/", "task_id": "840607fb-ee17-40d6-b922-b1fcc72de434"}], "result": null, "error": null} 2020-07-17T09:05:53 [I|kat|] GET: https://cragsat66.usersys.redhat.com/pulp/api/v2/tasks/840607fb-ee17-40d6-b922-b1fcc72de434/: {"content_type"=>"application/json", "accept"=>"application/json"} Response: 200: {"exception": null, "task_type": "pulp.server.managers.consumer.applicability.regenerate_applicability_for_consumers", "_href": "/pulp/api/v2/tasks/840607fb-ee17-40d6-b922-b1fcc72de434/", "task_id": "840607fb-ee17-40d6-b922-b1fcc72de434", "tags": ["pulp:action:consumer_content_applicability_regeneration"], "finish_time": null, "_ns": "task_status", "start_time": null, "traceback": null, "spawned_tasks": [], "progress_report": {}, "queue": "", "state": "waiting", "worker_name": null, "result": null, "error": null, "_id": {"$oid": "5f11a2312d5256a4f496078d"}, "id": "5f11a2312d5256a4f496078d"} 2020-07-17T09:05:54 [I|kat|] GET: https://cragsat66.usersys.redhat.com/pulp/api/v2/tasks/840607fb-ee17-40d6-b922-b1fcc72de434/: {"content_type"=>"application/json", "accept"=>"application/json"} Response: 200: {"exception": null, "task_type": "pulp.server.managers.consumer.applicability.regenerate_applicability_for_consumers", "_href": "/pulp/api/v2/tasks/840607fb-ee17-40d6-b922-b1fcc72de434/", "task_id": "840607fb-ee17-40d6-b922-b1fcc72de434", "tags": ["pulp:action:consumer_content_applicability_regeneration"], "finish_time": "2020-07-17T13:05:53Z", "_ns": "task_status", "start_time": "2020-07-17T13:05:53Z", "traceback": null, "spawned_tasks": [], "progress_report": {}, "queue": "reserved_resource_worker-0.redhat.com.dq2", "state": "finished", "worker_name": "reserved_resource_worker-0.redhat.com", "result": null, "error": null, "_id": {"$oid": "5f11a2312d5256a4f496078d"}, "id": "5f11a2312d5256a4f496078d"} 2020-07-17T09:05:54 [I|bac|] Task {label: Actions::Katello::Host::UploadProfiles, id: ea4fd9b3-ee57-4bf4-ae80-5d21641b31d9, execution_plan_id: d146c91e-02fe-443a-8e67-d405cede4b1b} state changed: stopped result: success 2020-07-17T09:05:54 [I|bac|] Task {label: Actions::Katello::Host::UploadProfiles, id: ea4fd9b3-ee57-4bf4-ae80-5d21641b31d9, execution_plan_id: d146c91e-02fe-443a-8e67-d405cede4b1b} state changed: stopped result: success 2020-07-17T09:05:55 [I|kat|] POST: https://cragsat66.usersys.redhat.com/pulp/api/v2/consumers/content/applicability/: {"criteria":{"filters":{"id":{"$in":["a22de5de-8221-43da-8cd4-229ee1ce3587"]}}},"content_types":["modulemd"]}: {"content_type"=>"application/json", "accept"=>"application/json"} Response: 200: [{"applicability": {"modulemd": ["1f5d01eb-417a-4163-9e82-89f94b11a66f"]}, "consumers": ["a22de5de-8221-43da-8cd4-229ee1ce3587"]}] 2020-07-17T09:05:55 [I|kat|] POST: https://cragsat66.usersys.redhat.com/pulp/api/v2/consumers/content/applicability/: {"criteria":{"filters":{"id":{"$in":["a22de5de-8221-43da-8cd4-229ee1ce3587"]}}},"content_types":["erratum"]}: {"content_type"=>"application/json", "accept"=>"application/json"} Response: 200: [{"applicability": {"erratum": ["34e24a43-3f4d-4daa-9ada-c1c79f0633ea", "ebe7a08c-d500-46c6-b804-d1f06756c44a", "b17ad711-2eff-4e58-964a-826259b8245a"]}, "consumers": ["a22de5de-8221-43da-8cd4-229ee1ce3587"]}] 2020-07-17T09:05:55 [I|kat|] POST: https://cragsat66.usersys.redhat.com/pulp/api/v2/consumers/content/applicability/: {"criteria":{"filters":{"id":{"$in":["a22de5de-8221-43da-8cd4-229ee1ce3587"]}}},"content_types":["rpm"]}: {"content_type"=>"application/json", "accept"=>"application/json"} Response: 200: [{"applicability": {"rpm": ["ac9f2461-0b5b-495b-9eb5-1c3add75a4d5", "9b945634-5105-40c9-9e36-a2fc6bf74be8", "3c64f883-579b-46b6-b734-b39e93394a2e", "e99c4a34-afe3-4538-aedd-b15b991b423c"]}, "consumers": ["a22de5de-8221-43da-8cd4-229ee1ce3587"]}] 2020-07-17T09:08:46 [I|bac|] Task {label: Actions::Katello::Host::UploadProfiles, id: 1aefb4fe-acfd-4a78-b168-f7a9c8d435bc, execution_plan_id: 54cbf934-c0dc-4ba2-bb7b-db0e17c72124} state changed: running 2020-07-17T09:08:47 [I|kat|] POST: https://cragsat66.usersys.redhat.com/pulp/api/v2/consumers/a22de5de-8221-43da-8cd4-229ee1ce3587/profiles//: {"content_type":"rpm","profile":[{"name":"libverto-libevent","version":"0.3.0","release":"5.el8","arch":"x86_64","epoch" </SNIP> Version-Release number of selected component (if applicable): subscription-manager-1.27.8-1.el8.x86_64 How reproducible: 100% on 8.3 Steps to Reproduce: 1. Register 8.3 snap to satellite 2. set splay to 0 + change checkin time for rhsmcertd 3. systemctl restart rhsmcertd && tail /var/log/foreman/production.log You can verify host via IP addr, and see package profile reported indefinitely. Actual results: Package profile is updated every time rhsmcertd checks in. Expected results: One package profile upload any time package list changes for host, otherwise no updates. Additional info: >> This issue may or may not be a regression, there seems to be some form of it in 8.2 builds - but it seems to manifest differently - and may be ultimately related to cache verification issues.