Bug 1400817

Summary: Failed to refresh local data against SAM1.4
Product: Red Hat Enterprise Linux 6 Reporter: qianzhan
Component: subscription-managerAssignee: candlepin-bugs
Status: CLOSED DUPLICATE QA Contact: John Sefler <jsefler>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: 6.9CC: csnyder, redakkan, skallesh, weiliu
Target Milestone: rc   
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2016-12-05 16:18:53 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:

Description qianzhan 2016-12-02 07:31:23 UTC
Description of problem:
Failed to refresh local data against SAM1.4

Version-Release number of selected component (if applicable):
RHEL-6.9-20161201.0:
subscription-manager: 1.18.5-1.el6
python-rhsm: 1.18.5-1.el6
SAM-1.4.1-RHEL-6-20141113.0

How reproducible:
always

Steps to Reproduce:
1. Register RHEL6.9 against SAM1.4
2. Run 'subscription-manager refresh'

[root@test ~]# subscription-manager register
Registering to: ent-02-vm-02.lab.eng.nay.redhat.com:443/sam/api
Username: admin
Password: 
The system has been registered with ID: 5c32045d-8f69-47cd-8a32-70aca4845510 
[root@test ~]# subscription-manager refresh
'module' object has no attribute 'BadStatusLine'
[root@test ~]# echo $?
70

3. Check rhsm.log:

[root@test ~]# cat /var/log/rhsm/rhsm.log
2016-12-02 02:25:10,266 [INFO] subscription-manager:10772:MainThread @managercli.py:389 - Client Versions: {'python-rhsm': '1.18.5-1.el6', 'subscription-manager': '1.18.5-1.el6'}
2016-12-02 02:25:10,267 [INFO] subscription-manager:10772:MainThread @connection.py:758 - Connection built: host=ent-02-vm-02.lab.eng.nay.redhat.com port=443 handler=/sam/api auth=identity_cert ca_dir=/etc/rhsm/ca/ insecure=False
2016-12-02 02:25:10,267 [INFO] subscription-manager:10772:MainThread @connection.py:758 - Connection built: host=ent-02-vm-02.lab.eng.nay.redhat.com port=443 handler=/sam/api auth=none
2016-12-02 02:25:10,268 [INFO] subscription-manager:10772:MainThread @managercli.py:389 - Client Versions: {'python-rhsm': '1.18.5-1.el6', 'subscription-manager': '1.18.5-1.el6'}
2016-12-02 02:25:10,279 [INFO] subscription-manager:10772:MainThread @managercli.py:364 - Consumer Identity name=None uuid=None
2016-12-02 02:25:10,282 [INFO] subscription-manager:10772:MainThread @managercli.py:364 - Consumer Identity name=None uuid=None
2016-12-02 02:25:14,139 [INFO] subscription-manager:10772:MainThread @connection.py:758 - Connection built: host=ent-02-vm-02.lab.eng.nay.redhat.com port=443 handler=/sam/api auth=basic username=admin
2016-12-02 02:25:16,354 [INFO] subscription-manager:10772:MainThread @hwprobe.py:918 - collected virt facts: virt.is_guest=False, virt.host_type=Not Applicable, virt.uuid=Not Set
2016-12-02 02:25:16,355 [INFO] subscription-manager:10772:MainThread @facts.py:139 - Loading custom facts from: /etc/rhsm/facts/cpuinfo_override.facts
2016-12-02 02:25:16,355 [INFO] subscription-manager:10772:MainThread @facts.py:139 - Loading custom facts from: /etc/rhsm/facts/custom.facts
2016-12-02 02:25:18,290 [INFO] subscription-manager:10772:MainThread @connection.py:525 - Response: status=200, request="GET /sam/api/users/admin/owners"
2016-12-02 02:25:18,291 [WARNING] subscription-manager:10772:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:20,306 [INFO] subscription-manager:10772:MainThread @connection.py:525 - Response: status=200, request="GET /sam/api/"
2016-12-02 02:25:20,307 [WARNING] subscription-manager:10772:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:23,573 [INFO] subscription-manager:10772:MainThread @connection.py:525 - Response: status=200, request="POST /sam/api/consumers?owner=ACME_Corporation"
2016-12-02 02:25:23,574 [WARNING] subscription-manager:10772:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:23,585 [INFO] subscription-manager:10772:MainThread @managerlib.py:77 - Consumer created: {'consumer_name': 'test', 'uuid': '5c32045d-8f69-47cd-8a32-70aca4845510'}
2016-12-02 02:25:23,591 [INFO] subscription-manager:10772:MainThread @connection.py:758 - Connection built: host=ent-02-vm-02.lab.eng.nay.redhat.com port=443 handler=/sam/api auth=identity_cert ca_dir=/etc/rhsm/ca/ insecure=False
2016-12-02 02:25:25,522 [INFO] subscription-manager:10772:MainThread @connection.py:525 - Response: status=200, request="GET /sam/api/"
2016-12-02 02:25:25,523 [WARNING] subscription-manager:10772:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:27,422 [INFO] subscription-manager:10772:MainThread @connection.py:525 - Response: status=200, request="GET /sam/api/status"
2016-12-02 02:25:27,423 [WARNING] subscription-manager:10772:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:27,423 [INFO] subscription-manager:10772:MainThread @managercli.py:400 - Server Versions: {'rules-version': 'Unknown', 'candlepin': '1.4.3.28-1.el6sam_splice-Headpin', 'server-type': 'Red Hat Subscription Management'}
2016-12-02 02:25:28,096 [INFO] rhsmd:10820:MainThread @rhsmd:261 - rhsmd started
2016-12-02 02:25:28,136 [INFO] rhsmd:10820:MainThread @connection.py:758 - Connection built: host=ent-02-vm-02.lab.eng.nay.redhat.com port=443 handler=/sam/api auth=identity_cert ca_dir=/etc/rhsm/ca/ insecure=False
2016-12-02 02:25:29,420 [INFO] subscription-manager:10772:MainThread @connection.py:525 - Response: status=200, request="GET /sam/api/"
2016-12-02 02:25:29,421 [WARNING] subscription-manager:10772:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:29,424 [INFO] subscription-manager:10772:MainThread @cache.py:398 - Server does not support packages, skipping profile upload.
2016-12-02 02:25:30,162 [INFO] rhsmd:10820:MainThread @connection.py:525 - Response: status=200, request="GET /sam/api/consumers/5c32045d-8f69-47cd-8a32-70aca4845510/compliance"
2016-12-02 02:25:30,163 [WARNING] rhsmd:10820:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:30,164 [INFO] rhsmd:10820:MainThread @cert_sorter.py:205 - Product status: valid_products= partial_products= expired_products= unentitled_producs=69 future_products= valid_until=None
2016-12-02 02:25:31,423 [INFO] subscription-manager:10772:MainThread @connection.py:525 - Response: status=200, request="GET /sam/api/consumers/5c32045d-8f69-47cd-8a32-70aca4845510/compliance"
2016-12-02 02:25:31,423 [WARNING] subscription-manager:10772:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:31,424 [INFO] subscription-manager:10772:MainThread @cert_sorter.py:205 - Product status: valid_products= partial_products= expired_products= unentitled_producs=69 future_products= valid_until=None
2016-12-02 02:25:31,562 [INFO] rhsmd:10824:MainThread @rhsmd:261 - rhsmd started
2016-12-02 02:25:31,610 [INFO] rhsmd:10824:MainThread @connection.py:758 - Connection built: host=ent-02-vm-02.lab.eng.nay.redhat.com port=443 handler=/sam/api auth=identity_cert ca_dir=/etc/rhsm/ca/ insecure=False
2016-12-02 02:25:33,602 [INFO] rhsmd:10824:MainThread @connection.py:525 - Response: status=200, request="GET /sam/api/consumers/5c32045d-8f69-47cd-8a32-70aca4845510/compliance"
2016-12-02 02:25:33,602 [WARNING] rhsmd:10824:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:25:33,604 [INFO] rhsmd:10824:MainThread @cert_sorter.py:205 - Product status: valid_products= partial_products= expired_products= unentitled_producs=69 future_products= valid_until=None
2016-12-02 02:26:55,590 [INFO] subscription-manager:10826:MainThread @managercli.py:389 - Client Versions: {'python-rhsm': '1.18.5-1.el6', 'subscription-manager': '1.18.5-1.el6'}
2016-12-02 02:26:55,591 [INFO] subscription-manager:10826:MainThread @connection.py:758 - Connection built: host=ent-02-vm-02.lab.eng.nay.redhat.com port=443 handler=/sam/api auth=identity_cert ca_dir=/etc/rhsm/ca/ insecure=False
2016-12-02 02:26:55,591 [INFO] subscription-manager:10826:MainThread @connection.py:758 - Connection built: host=ent-02-vm-02.lab.eng.nay.redhat.com port=443 handler=/sam/api auth=none
2016-12-02 02:26:55,592 [INFO] subscription-manager:10826:MainThread @managercli.py:364 - Consumer Identity name=test uuid=5c32045d-8f69-47cd-8a32-70aca4845510
2016-12-02 02:26:58,333 [INFO] subscription-manager:10826:MainThread @connection.py:525 - Response: status=404, request="PUT /sam/api/consumers/5c32045d-8f69-47cd-8a32-70aca4845510/certificates?lazy_regen=true"
2016-12-02 02:26:58,333 [WARNING] subscription-manager:10826:MainThread @connection.py:529 - Clock skew detected, please check your system time
2016-12-02 02:26:58,334 [ERROR] subscription-manager:10826:MainThread @managercli.py:177 - Unable to perform refresh due to the following exception: 'module' object has no attribute 'BadStatusLine'
2016-12-02 02:26:58,334 [ERROR] subscription-manager:10826:MainThread @managercli.py:178 - 'module' object has no attribute 'BadStatusLine'
Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/subscription_manager/managercli.py", line 664, in _do_command
    if not self.cp.regenEntitlementCertificates(identity.uuid, True):
  File "/usr/lib64/python2.6/site-packages/rhsm/connection.py", line 1290, in regenEntitlementCertificates
    except (RemoteServerException, httplib.BadStatusLine, RestlibException) as e:
AttributeError: 'module' object has no attribute 'BadStatusLine'


Actual results:
As step 2 and step 3.

Expected results:
Refresh should be successful.

Additional info:

Comment 3 Chris Snyder 2016-12-05 16:18:53 UTC

*** This bug has been marked as a duplicate of bug 1397201 ***