Bug 1869161

Summary:	rhsmcertd checkin ignoring cache and POSTing package profiles [rhel-8.2.0.z]
Product:	Red Hat Enterprise Linux 8	Reporter:	RHEL Program Management Team <pgm-rhel-tools>
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:	cdonnell, jhnidek, jsefler, mjia, redakkan, wpoteat
Target Milestone:	rc	Keywords:	Regression, Triaged, ZStream
Target Release:	---	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:	1858296	Environment:
Last Closed:	2020-09-08 09:37:25 UTC	Type:	---
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:	1858296
Bug Blocks:

Comment 4 Craig Donnelly 2020-08-19 13:28:16 UTC

Verifying 8.2.z stream build: subscription-manager-1.26.20-1.el8_2.x86_64:

Reproducing issue on subscription-manager-rhsm-certificates-1.26.17-1.el8_2.x86_64 (current 8.2.z prod):

> dnf module enable squid -y
> splay set to 0, interval 1 minute.
> systemctl restart rhsmcertd

2020-08-19 09:04:21,564 [DEBUG] rhsmcertd-worker:37544:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:04:22,303 [DEBUG] rhsmcertd-worker:37544:MainThread @connection.py:618 - Response: status=204, requestUuid=5cb1070b-0b79-4eae-95b5-3df387ea923c, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:04:23,580 [DEBUG] rhsmcertd-worker:37544:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1/profiles
2020-08-19 09:04:24,315 [DEBUG] rhsmcertd-worker:37544:MainThread @connection.py:618 - Response: status=204, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1/profiles"
2020-08-19 09:04:24,927 [DEBUG] rhsmcertd-worker:37544:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:04:25,555 [DEBUG] rhsmcertd-worker:37544:MainThread @connection.py:618 - Response: status=204, requestUuid=140a51bd-9081-4043-b38c-45cd9a8183a5, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:05:20,126 [DEBUG] rhsmcertd-worker:37632:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:05:20,825 [DEBUG] rhsmcertd-worker:37632:MainThread @connection.py:618 - Response: status=204, requestUuid=cf4077b4-cd32-4f7d-b3f0-7fba426b29e0, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:05:22,253 [DEBUG] rhsmcertd-worker:37632:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1/profiles
2020-08-19 09:05:22,816 [DEBUG] rhsmcertd-worker:37632:MainThread @connection.py:618 - Response: status=204, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1/profiles"
2020-08-19 09:05:23,459 [DEBUG] rhsmcertd-worker:37632:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:05:23,979 [DEBUG] rhsmcertd-worker:37632:MainThread @connection.py:618 - Response: status=204, requestUuid=36355bce-195b-4276-a597-18f355993330, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:06:21,777 [DEBUG] rhsmcertd-worker:37719:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1/profiles
2020-08-19 09:06:22,541 [DEBUG] rhsmcertd-worker:37719:MainThread @connection.py:618 - Response: status=204, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1/profiles"
2020-08-19 09:06:23,204 [DEBUG] rhsmcertd-worker:37719:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:06:23,761 [DEBUG] rhsmcertd-worker:37719:MainThread @connection.py:618 - Response: status=204, requestUuid=4205704c-32af-489f-bc0f-c14ea2820567, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"

>> We can see package profile upload on every checkin above

> Updating to new build:

# dnf update
Updating Subscription Management repositories.
Last metadata expiration check: 0:19:35 ago on Wed 19 Aug 2020 08:47:02 AM EDT.
Dependencies resolved.
==============================================================================================================================================================================================================================================
 Package                                                             Architecture                        Version                                       Repository                                                                        Size
==============================================================================================================================================================================================================================================
Upgrading:
 dnf-plugin-subscription-manager                                     x86_64                              1.26.20-1.el8_2                               brew-task-repo-subscription-manager-1.26.20-1.el8_2                              277 k
 python3-subscription-manager-rhsm                                   x86_64                              1.26.20-1.el8_2                               brew-task-repo-subscription-manager-1.26.20-1.el8_2                              347 k
 python3-syspurpose                                                  x86_64                              1.26.20-1.el8_2                               brew-task-repo-subscription-manager-1.26.20-1.el8_2                              286 k
 subscription-manager                                                x86_64                              1.26.20-1.el8_2                               brew-task-repo-subscription-manager-1.26.20-1.el8_2                              1.1 M
 subscription-manager-rhsm-certificates                              x86_64                              1.26.20-1.el8_2                               brew-task-repo-subscription-manager-1.26.20-1.el8_2                              250 k

Transaction Summary
==============================================================================================================================================================================================================================================
Upgrade  5 Packages

Total download size: 2.2 M
Is this ok [y/N]: y
Downloading Packages:
(1/5): python3-syspurpose-1.26.20-1.el8_2.x86_64.rpm                                                                                                                                                          700 kB/s | 286 kB     00:00    
(2/5): python3-subscription-manager-rhsm-1.26.20-1.el8_2.x86_64.rpm                                                                                                                                           844 kB/s | 347 kB     00:00    
(3/5): dnf-plugin-subscription-manager-1.26.20-1.el8_2.x86_64.rpm                                                                                                                                             650 kB/s | 277 kB     00:00    
(4/5): subscription-manager-rhsm-certificates-1.26.20-1.el8_2.x86_64.rpm                                                                                                                                      2.0 MB/s | 250 kB     00:00    
(5/5): subscription-manager-1.26.20-1.el8_2.x86_64.rpm                                                                                                                                                        6.0 MB/s | 1.1 MB     00:00    
----------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
Total                                                                                                                                                                                                         3.7 MB/s | 2.2 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.26.20-1.el8_2.x86_64                                                                                                                                                        1/1 
  Upgrading        : subscription-manager-rhsm-certificates-1.26.20-1.el8_2.x86_64                                                                                                                                                       1/10 
  Upgrading        : python3-subscription-manager-rhsm-1.26.20-1.el8_2.x86_64                                                                                                                                                            2/10 
  Upgrading        : python3-syspurpose-1.26.20-1.el8_2.x86_64                                                                                                                                                                           3/10 
  Upgrading        : dnf-plugin-subscription-manager-1.26.20-1.el8_2.x86_64                                                                                                                                                              4/10 
  Upgrading        : subscription-manager-1.26.20-1.el8_2.x86_64                                                                                                                                                                         5/10 
  Running scriptlet: subscription-manager-1.26.20-1.el8_2.x86_64                                                                                                                                                                         5/10 
  Running scriptlet: subscription-manager-1.26.17-1.el8_2.x86_64                                                                                                                                                                         6/10 
  Cleanup          : subscription-manager-1.26.17-1.el8_2.x86_64                                                                                                                                                                         6/10 
  Running scriptlet: subscription-manager-1.26.17-1.el8_2.x86_64                                                                                                                                                                         6/10 
  Cleanup          : python3-syspurpose-1.26.17-1.el8_2.x86_64                                                                                                                                                                           7/10 
  Cleanup          : python3-subscription-manager-rhsm-1.26.17-1.el8_2.x86_64                                                                                                                                                            8/10 
  Cleanup          : subscription-manager-rhsm-certificates-1.26.17-1.el8_2.x86_64                                                                                                                                                       9/10 
  Cleanup          : dnf-plugin-subscription-manager-1.26.17-1.el8_2.x86_64                                                                                                                                                             10/10 
  Running scriptlet: dnf-plugin-subscription-manager-1.26.17-1.el8_2.x86_64                                                                                                                                                             10/10 
  Verifying        : dnf-plugin-subscription-manager-1.26.20-1.el8_2.x86_64                                                                                                                                                              1/10 
  Verifying        : dnf-plugin-subscription-manager-1.26.17-1.el8_2.x86_64                                                                                                                                                              2/10 
  Verifying        : python3-subscription-manager-rhsm-1.26.20-1.el8_2.x86_64                                                                                                                                                            3/10 
  Verifying        : python3-subscription-manager-rhsm-1.26.17-1.el8_2.x86_64                                                                                                                                                            4/10 
  Verifying        : python3-syspurpose-1.26.20-1.el8_2.x86_64                                                                                                                                                                           5/10 
  Verifying        : python3-syspurpose-1.26.17-1.el8_2.x86_64                                                                                                                                                                           6/10 
  Verifying        : subscription-manager-1.26.20-1.el8_2.x86_64                                                                                                                                                                         7/10 
  Verifying        : subscription-manager-1.26.17-1.el8_2.x86_64                                                                                                                                                                         8/10 
  Verifying        : subscription-manager-rhsm-certificates-1.26.20-1.el8_2.x86_64                                                                                                                                                       9/10 
  Verifying        : subscription-manager-rhsm-certificates-1.26.17-1.el8_2.x86_64                                                                                                                                                      10/10 
Installed products updated.

Upgraded:
  dnf-plugin-subscription-manager-1.26.20-1.el8_2.x86_64                python3-subscription-manager-rhsm-1.26.20-1.el8_2.x86_64         python3-syspurpose-1.26.20-1.el8_2.x86_64         subscription-manager-1.26.20-1.el8_2.x86_64        
  subscription-manager-rhsm-certificates-1.26.20-1.el8_2.x86_64        

> Verifying no extraneous pkg profile uploads:

# systemctl restart rhsmcertd
# tail -f rhsm.log | grep -e PUT -e POST -e profiles

2020-08-19 09:08:59,240 [DEBUG] rhsmcertd-worker:37905:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:09:00,009 [DEBUG] rhsmcertd-worker:37905:MainThread @connection.py:618 - Response: status=204, requestUuid=f24cdb69-1050-4159-a608-d53015a33315, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:09:01,407 [DEBUG] rhsmcertd-worker:37905:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1/profiles
2020-08-19 09:09:02,062 [DEBUG] rhsmcertd-worker:37905:MainThread @connection.py:618 - Response: status=204, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1/profiles"
2020-08-19 09:10:07,642 [DEBUG] rhsmcertd-worker:37994:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:10:08,372 [DEBUG] rhsmcertd-worker:37994:MainThread @connection.py:618 - Response: status=204, requestUuid=580fc6da-a9c2-4257-9a73-68bc341a9e94, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:12:08,535 [DEBUG] rhsmcertd-worker:38127:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:12:09,301 [DEBUG] rhsmcertd-worker:38127:MainThread @connection.py:618 - Response: status=204, requestUuid=a3bf9b4d-4989-4af9-93a5-b7e3f5ef95d6, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:15:08,362 [DEBUG] rhsmcertd-worker:38310:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:15:09,119 [DEBUG] rhsmcertd-worker:38310:MainThread @connection.py:618 - Response: status=204, requestUuid=589d4cca-1e6b-4b33-8766-18e2e1ca6f9d, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:17:07,622 [DEBUG] rhsmcertd-worker:38448:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:17:08,229 [DEBUG] rhsmcertd-worker:38448:MainThread @connection.py:618 - Response: status=204, requestUuid=a9107163-bd98-466c-bbf5-b4ca201cd8da, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:18:07,236 [DEBUG] rhsmcertd-worker:38535:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:18:07,951 [DEBUG] rhsmcertd-worker:38535:MainThread @connection.py:618 - Response: status=204, requestUuid=eba38193-2b00-4f5c-b927-b0f2852282c5, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:19:07,267 [DEBUG] rhsmcertd-worker:38621:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:19:08,008 [DEBUG] rhsmcertd-worker:38621:MainThread @connection.py:618 - Response: status=204, requestUuid=a7b1dbbd-d205-46fd-bbb8-eb339e795db2, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:20:08,003 [DEBUG] rhsmcertd-worker:38707:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:20:08,745 [DEBUG] rhsmcertd-worker:38707:MainThread @connection.py:618 - Response: status=204, requestUuid=e725e4b4-5ac5-4c34-a460-4576a2ebdd57, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"
2020-08-19 09:21:07,451 [DEBUG] rhsmcertd-worker:38793:MainThread @connection.py:571 - Making request: PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1
2020-08-19 09:21:08,072 [DEBUG] rhsmcertd-worker:38793:MainThread @connection.py:618 - Response: status=204, requestUuid=1344db41-b536-4f68-bdd5-37058537c4c8, request="PUT /subscription/consumers/0b47dd82-0fb7-41d5-9009-bd3d9f921cd1"

> Only one pkg profile upload, after the dnf update of subman - no extras.

VERIFIED.

Comment 7 errata-xmlrpc 2020-09-08 09:37:25 UTC

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:3649