Project

General

Profile

Bug #15192 » rhsm.log

Dylan Baars, 05/25/2016 09:53 PM

 
2016-05-26 13:14:27,991 [DEBUG] subscription-manager:10191 @ga_loader.py:89 - ga_loader GaImporterGtk3
2016-05-26 13:14:27,994 [DEBUG] subscription-manager:10191 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:14:27,995 [DEBUG] subscription-manager:10191 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:14:27,995 [DEBUG] subscription-manager:10191 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:14:27,995 [DEBUG] subscription-manager:10191 @identity.py:146 - Reload of consumer identity cert /etc/pki/consumer/cert.pem raised an exception with msg: [Errno 2] No such file or directory: '/etc/pki/consumer/key.pem'
2016-05-26 13:14:28,022 [INFO] subscription-manager:10191 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:14:31,471 [DEBUG] subscription-manager:10208 @ga_loader.py:89 - ga_loader GaImporterGtk3
2016-05-26 13:14:31,473 [DEBUG] subscription-manager:10208 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:14:31,473 [DEBUG] subscription-manager:10208 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:14:31,473 [DEBUG] subscription-manager:10208 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:14:31,474 [DEBUG] subscription-manager:10208 @identity.py:146 - Reload of consumer identity cert /etc/pki/consumer/cert.pem raised an exception with msg: [Errno 2] No such file or directory: '/etc/pki/consumer/key.pem'
2016-05-26 13:14:31,491 [INFO] subscription-manager:10208 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:14:31,492 [INFO] subscription-manager:10208 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:14:31,493 [INFO] subscription-manager:10208 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=none
2016-05-26 13:14:31,497 [DEBUG] subscription-manager:10208 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:31,497 [DEBUG] subscription-manager:10208 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:14:31,696 [DEBUG] subscription-manager:10208 @connection.py:555 - Response: status=200
2016-05-26 13:14:31,699 [DEBUG] subscription-manager:10208 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:14:31,701 [DEBUG] subscription-manager:10208 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:31,702 [DEBUG] subscription-manager:10208 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:14:32,759 [DEBUG] subscription-manager:10208 @connection.py:555 - Response: status=200
2016-05-26 13:14:32,761 [INFO] subscription-manager:10208 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'This system is currently not registered.'}
2016-05-26 13:14:32,761 [INFO] subscription-manager:10208 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:14:32,762 [INFO] subscription-manager:10208 @managercli.py:328 - Consumer Identity name=None uuid=None
2016-05-26 13:14:32,762 [INFO] subscription-manager:10208 @managercli.py:328 - Consumer Identity name=None uuid=None
2016-05-26 13:14:32,880 [DEBUG] subscription-manager:10208 @hwprobe.py:554 - cpu info: {'cpu.cpu(s)': 2, 'cpu.core(s)_per_socket': 1, 'cpu.thread(s)_per_core': 1, 'cpu.topology_source': 'kernel /sys cpu sibling lists', 'cpu.cpu_socket(s)': 2}
2016-05-26 13:14:32,933 [DEBUG] subscription-manager:10208 @hwprobe.py:772 - Running 'virt-what'
2016-05-26 13:14:33,020 [DEBUG] subscription-manager:10208 @hwprobe.py:776 - virt-what stdout: vmware

2016-05-26 13:14:33,020 [DEBUG] subscription-manager:10208 @hwprobe.py:777 - virt-what stderr:
2016-05-26 13:14:33,229 [INFO] subscription-manager:10208 @hwprobe.py:854 - collected virt facts: virt.is_guest=True, virt.host_type=vmware, virt.uuid=420585ee-75ea-44c1-f09d-f274e001d0ae
2016-05-26 13:14:33,232 [DEBUG] subscription-manager:10208 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:33,232 [DEBUG] subscription-manager:10208 @connection.py:523 - Making request: POST /rhsm/consumers?owner=NIWA&activation_keys=Jenkins%20master%20server
2016-05-26 13:14:41,865 [DEBUG] subscription-manager:10208 @connection.py:555 - Response: status=200
2016-05-26 13:14:41,870 [DEBUG] subscription-manager:10208 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:14:41,895 [INFO] subscription-manager:10208 @managerlib.py:75 - Consumer created: {'consumer_name': 'testcislave01d.niwa.local', 'uuid': '7808f40d-42e1-4691-8bf5-6b761d17b2ae'}
2016-05-26 13:14:41,895 [DEBUG] subscription-manager:10208 @utils.py:287 - No virt-who pid file, not attempting to restart
2016-05-26 13:14:41,895 [DEBUG] subscription-manager:10208 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:14:41,898 [DEBUG] subscription-manager:10208 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:41,898 [DEBUG] subscription-manager:10208 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:14:42,272 [DEBUG] subscription-manager:10208 @connection.py:555 - Response: status=200
2016-05-26 13:14:42,274 [INFO] subscription-manager:10208 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'Red Hat Subscription Management'}
2016-05-26 13:14:42,275 [DEBUG] subscription-manager:10208 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:42,276 [DEBUG] subscription-manager:10208 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:14:42,353 [DEBUG] subscription-manager:10208 @connection.py:555 - Response: status=200
2016-05-26 13:14:42,355 [DEBUG] subscription-manager:10208 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:14:42,355 [DEBUG] subscription-manager:10208 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:14:42,355 [DEBUG] subscription-manager:10208 @cache.py:393 - Cache does not exist
2016-05-26 13:14:42,355 [DEBUG] subscription-manager:10208 @cache.py:140 - System data has changed, updating server.
2016-05-26 13:14:42,356 [DEBUG] subscription-manager:10208 @profile.py:97 - Loading current RPM profile.
2016-05-26 13:14:42,430 [DEBUG] subscription-manager:10208 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:42,433 [DEBUG] subscription-manager:10208 @connection.py:523 - Making request: PUT /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/packages
2016-05-26 13:14:47,783 [DEBUG] subscription-manager:10208 @connection.py:555 - Response: status=200
2016-05-26 13:14:47,808 [DEBUG] subscription-manager:10208 @cache.py:110 - Wrote cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:14:47,810 [DEBUG] subscription-manager:10208 @cache.py:110 - Wrote cache: /var/lib/rhsm/facts/facts.json
2016-05-26 13:14:47,810 [DEBUG] subscription-manager:10208 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:14:47,810 [DEBUG] subscription-manager:10208 @cache.py:155 - No changes.
2016-05-26 13:14:47,810 [INFO] subscription-manager:10208 @managercli.py:1128 - System registered, updating entitlements if needed
2016-05-26 13:14:47,812 [DEBUG] subscription-manager:10208 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:47,813 [DEBUG] subscription-manager:10208 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/certificates/serials
2016-05-26 13:14:48,140 [DEBUG] subscription-manager:10208 @connection.py:555 - Response: status=200
2016-05-26 13:14:48,143 [DEBUG] subscription-manager:10208 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:48,143 [DEBUG] subscription-manager:10208 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/certificates?serials=3580651109045762180,5741596008418962223,4011842312208144404,3102455938659997665,7739421572442180630
2016-05-26 13:14:48,223 [DEBUG] subscription-manager:10208 @connection.py:555 - Response: status=500
2016-05-26 13:14:48,225 [ERROR] subscription-manager:10208 @managercli.py:160 - exception caught in subscription-manager
2016-05-26 13:14:48,225 [ERROR] subscription-manager:10208 @managercli.py:161 - undefined method `[]' for nil:NilClass
Traceback (most recent call last):
File "/usr/sbin/subscription-manager", line 86, in <module>
sys.exit(abs(main() or 0))
File "/usr/sbin/subscription-manager", line 77, in main
return managercli.ManagerCLI().main()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 2629, in main
return CLI.main(self)
File "/usr/share/rhsm/subscription_manager/cli.py", line 159, in main
return cmd.main()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 489, in main
return_code = self._do_command()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 1131, in _do_command
self.entcertlib.update()
File "/usr/share/rhsm/subscription_manager/certlib.py", line 31, in update
self.report = self.locker.run(self._do_update)
File "/usr/share/rhsm/subscription_manager/certlib.py", line 17, in run
return action()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 43, in _do_update
return action.perform()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 129, in perform
self.install(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 159, in install
cert_bundles = self.get_certificates_by_serial_list(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 248, in get_certificates_by_serial_list
serials=sn_list)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 1090, in getCertificates
return self.conn.request_get(method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 644, in request_get
return self._request("GET", method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 564, in _request
self.validateResponse(result, request_type, handler)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 611, in validateResponse
raise RestlibException(response['status'], error_msg, response.get('headers'))
RestlibException: undefined method `[]' for nil:NilClass
2016-05-26 13:14:48,568 [DEBUG] yum:10242 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:14:48,574 [INFO] yum:10242 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:14:48,575 [INFO] yum:10242 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:14:48,576 [DEBUG] yum:10242 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:48,576 [DEBUG] yum:10242 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:14:48,677 [DEBUG] yum:10242 @connection.py:555 - Response: status=200
2016-05-26 13:14:48,679 [DEBUG] yum:10242 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:14:48,679 [ERROR] yum:10242 @cache.py:128 - Unable to read cache: /var/lib/rhsm/cache/written_overrides.json
2016-05-26 13:14:48,680 [DEBUG] yum:10242 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:14:48,680 [DEBUG] yum:10242 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/content_overrides
2016-05-26 13:14:48,864 [DEBUG] yum:10242 @connection.py:555 - Response: status=200
2016-05-26 13:14:48,865 [DEBUG] yum:10242 @cache.py:273 - Started thread to write cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:14:48,866 [DEBUG] yum:10242 @__init__.py:85 - Searching for content of type: yum
2016-05-26 13:14:48,867 [DEBUG] yum:10242 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:14:48,867 [DEBUG] yum:10242 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/written_overrides.json
2016-05-26 13:14:48,868 [INFO] yum:10242 @repolib.py:296 - repos updated: Repo updates

Total repo updates: 0
Updated
<NONE>
Added (new)
<NONE>
Deleted
<NONE>
2016-05-26 13:15:05,252 [DEBUG] yum:10256 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:15:05,257 [INFO] yum:10256 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:15:05,258 [INFO] yum:10256 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:15:05,259 [DEBUG] yum:10256 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:15:05,260 [DEBUG] yum:10256 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:15:05,373 [DEBUG] yum:10256 @connection.py:555 - Response: status=200
2016-05-26 13:15:05,375 [DEBUG] yum:10256 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:15:05,376 [DEBUG] yum:10256 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:15:05,377 [DEBUG] yum:10256 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/content_overrides
2016-05-26 13:15:05,560 [DEBUG] yum:10256 @connection.py:555 - Response: status=200
2016-05-26 13:15:05,563 [DEBUG] yum:10256 @cache.py:273 - Started thread to write cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:15:05,564 [DEBUG] yum:10256 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:15:05,564 [DEBUG] yum:10256 @__init__.py:85 - Searching for content of type: yum
2016-05-26 13:15:05,565 [DEBUG] yum:10256 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/written_overrides.json
2016-05-26 13:15:05,565 [INFO] yum:10256 @repolib.py:296 - repos updated: Repo updates

Total repo updates: 0
Updated
<NONE>
Added (new)
<NONE>
Deleted
<NONE>
2016-05-26 13:15:12,067 [DEBUG] yum:10256 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:15:12,067 [DEBUG] yum:10256 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:15:12,067 [DEBUG] yum:10256 @productid.py:811 - Unable to load productid metadata for repos: ['NIWA_CentOS_7_Base']
2016-05-26 13:15:12,814 [DEBUG] yum:10256 @productid.py:439 - Checking for product id certs to install or update.
2016-05-26 13:15:12,814 [DEBUG] yum:10256 @productid.py:444 - active set(['NIWA_CentOS_7_Base'])
2016-05-26 13:15:12,814 [DEBUG] yum:10256 @productid.py:445 - enabled []
2016-05-26 13:15:12,814 [DEBUG] yum:10256 @productid.py:579 - about to run post_product_id_install
2016-05-26 13:15:12,814 [DEBUG] yum:10256 @productid.py:590 - about to run post_product_id_update
2016-05-26 13:15:17,211 [DEBUG] yum:10408 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:15:17,217 [INFO] yum:10408 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:15:17,218 [INFO] yum:10408 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:15:17,219 [DEBUG] yum:10408 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:15:17,220 [DEBUG] yum:10408 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:15:17,311 [DEBUG] yum:10408 @connection.py:555 - Response: status=200
2016-05-26 13:15:17,313 [DEBUG] yum:10408 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:15:17,314 [DEBUG] yum:10408 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:15:17,314 [DEBUG] yum:10408 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/content_overrides
2016-05-26 13:15:17,481 [DEBUG] yum:10408 @connection.py:555 - Response: status=200
2016-05-26 13:15:17,484 [DEBUG] yum:10408 @cache.py:273 - Started thread to write cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:15:17,485 [DEBUG] yum:10408 @__init__.py:85 - Searching for content of type: yum
2016-05-26 13:15:17,485 [DEBUG] yum:10408 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:15:17,486 [DEBUG] yum:10408 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/written_overrides.json
2016-05-26 13:15:17,486 [INFO] yum:10408 @repolib.py:296 - repos updated: Repo updates

Total repo updates: 0
Updated
<NONE>
Added (new)
<NONE>
Deleted
<NONE>
2016-05-26 13:17:48,975 [DEBUG] rhsmcertd-worker:11663 @base_action_client.py:85 - running lib: <subscription_manager.installedproductslib.InstalledProductsActionInvoker object at 0x1a0ee10>
2016-05-26 13:17:48,976 [INFO] rhsmcertd-worker:11663 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:17:48,977 [DEBUG] rhsmcertd-worker:11663 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:17:48,978 [DEBUG] rhsmcertd-worker:11663 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:17:48,979 [DEBUG] rhsmcertd-worker:11663 @cache.py:155 - No changes.
2016-05-26 13:17:48,979 [DEBUG] rhsmcertd-worker:11663 @base_action_client.py:85 - running lib: <subscription_manager.healinglib.HealingActionInvoker object at 0x1a598d0>
2016-05-26 13:17:48,980 [DEBUG] rhsmcertd-worker:11663 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:17:48,981 [DEBUG] rhsmcertd-worker:11663 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:17:48,983 [DEBUG] rhsmcertd-worker:11663 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:48,984 [DEBUG] rhsmcertd-worker:11663 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:17:49,244 [DEBUG] rhsmcertd-worker:11663 @connection.py:555 - Response: status=200
2016-05-26 13:17:49,247 [DEBUG] rhsmcertd-worker:11663 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:17:49,248 [DEBUG] rhsmcertd-worker:11663 @cache.py:155 - No changes.
2016-05-26 13:17:49,248 [DEBUG] rhsmcertd-worker:11663 @certdirectory.py:216 - Installed product IDs: []
2016-05-26 13:17:49,250 [DEBUG] rhsmcertd-worker:11663 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:49,250 [DEBUG] rhsmcertd-worker:11663 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/compliance
2016-05-26 13:17:49,332 [DEBUG] rhsmcertd-worker:11663 @connection.py:555 - Response: status=500
2016-05-26 13:17:49,332 [ERROR] rhsmcertd-worker:11663 @cache.py:193 - undefined method `[]' for nil:NilClass
Traceback (most recent call last):
File "/usr/share/rhsm/subscription_manager/cache.py", line 181, in load_status
self._sync_with_server(uep, uuid)
File "/usr/share/rhsm/subscription_manager/cache.py", line 290, in _sync_with_server
self.server_status = uep.getCompliance(uuid)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 1021, in getCompliance
return self.conn.request_get(method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 644, in request_get
return self._request("GET", method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 564, in _request
self.validateResponse(result, request_type, handler)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 611, in validateResponse
raise RestlibException(response['status'], error_msg, response.get('headers'))
RestlibException: undefined method `[]' for nil:NilClass
2016-05-26 13:17:49,336 [DEBUG] rhsmcertd-worker:11663 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:17:49,336 [DEBUG] rhsmcertd-worker:11663 @cache.py:155 - No changes.
2016-05-26 13:17:49,336 [DEBUG] rhsmcertd-worker:11663 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:17:49,337 [WARNING] rhsmcertd-worker:11663 @healinglib.py:96 - Found invalid entitlements for today: 2016-05-26 01:17:49.247852+00:00
2016-05-26 13:17:49,339 [DEBUG] rhsmcertd-worker:11663 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:49,339 [DEBUG] rhsmcertd-worker:11663 @connection.py:523 - Making request: POST /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/entitlements?entitle_date=2016-05-26T01%3A17%3A49.247852%2B00%3A00
2016-05-26 13:17:49,416 [DEBUG] rhsmcertd-worker:11663 @connection.py:555 - Response: status=500
2016-05-26 13:17:49,417 [ERROR] rhsmcertd-worker:11663 @healinglib.py:133 - Error attempting to auto-heal:
2016-05-26 13:17:49,417 [ERROR] rhsmcertd-worker:11663 @healinglib.py:134 - undefined method `[]' for nil:NilClass
Traceback (most recent call last):
File "/usr/share/rhsm/subscription_manager/healinglib.py", line 98, in perform
ents = self.uep.bind(uuid, today)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 1133, in bind
return self.conn.request_post(method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 647, in request_post
return self._request("POST", method, params)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 564, in _request
self.validateResponse(result, request_type, handler)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 611, in validateResponse
raise RestlibException(response['status'], error_msg, response.get('headers'))
RestlibException: undefined method `[]' for nil:NilClass
2016-05-26 13:17:49,419 [DEBUG] rhsmcertd-worker:11663 @base_action_client.py:85 - running lib: <subscription_manager.entcertlib.EntCertActionInvoker object at 0x1a1efd0>
2016-05-26 13:17:49,420 [INFO] rhsmcertd-worker:11663 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:17:49,422 [DEBUG] rhsmcertd-worker:11663 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:49,422 [DEBUG] rhsmcertd-worker:11663 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/certificates/serials
2016-05-26 13:17:49,739 [DEBUG] rhsmcertd-worker:11663 @connection.py:555 - Response: status=200
2016-05-26 13:17:49,741 [DEBUG] rhsmcertd-worker:11663 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:49,742 [DEBUG] rhsmcertd-worker:11663 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/certificates?serials=3580651109045762180,5741596008418962223,4011842312208144404,3102455938659997665,7739421572442180630
2016-05-26 13:17:49,819 [DEBUG] rhsmcertd-worker:11663 @connection.py:555 - Response: status=500
2016-05-26 13:17:49,820 [WARNING] rhsmcertd-worker:11663 @base_action_client.py:72 - Exception caught while running <subscription_manager.entcertlib.EntCertActionInvoker object at 0x1a1efd0> update
2016-05-26 13:17:49,820 [ERROR] rhsmcertd-worker:11663 @base_action_client.py:73 - undefined method `[]' for nil:NilClass
Traceback (most recent call last):
File "/usr/share/rhsm/subscription_manager/base_action_client.py", line 63, in _run_update
update_report = lib.update()
File "/usr/share/rhsm/subscription_manager/certlib.py", line 31, in update
self.report = self.locker.run(self._do_update)
File "/usr/share/rhsm/subscription_manager/certlib.py", line 17, in run
return action()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 43, in _do_update
return action.perform()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 129, in perform
self.install(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 159, in install
cert_bundles = self.get_certificates_by_serial_list(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 248, in get_certificates_by_serial_list
serials=sn_list)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 1090, in getCertificates
return self.conn.request_get(method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 644, in request_get
return self._request("GET", method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 564, in _request
self.validateResponse(result, request_type, handler)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 611, in validateResponse
raise RestlibException(response['status'], error_msg, response.get('headers'))
RestlibException: undefined method `[]' for nil:NilClass
2016-05-26 13:17:50,105 [DEBUG] rhsmcertd-worker:11664 @base_action_client.py:85 - running lib: <subscription_manager.entcertlib.EntCertActionInvoker object at 0x2ca2fd0>
2016-05-26 13:17:50,106 [INFO] rhsmcertd-worker:11664 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:17:50,107 [DEBUG] rhsmcertd-worker:11664 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:17:50,109 [DEBUG] rhsmcertd-worker:11664 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:50,110 [DEBUG] rhsmcertd-worker:11664 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/certificates/serials
2016-05-26 13:17:50,361 [DEBUG] rhsmcertd-worker:11664 @connection.py:555 - Response: status=200
2016-05-26 13:17:50,364 [DEBUG] rhsmcertd-worker:11664 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:50,364 [DEBUG] rhsmcertd-worker:11664 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/certificates?serials=3580651109045762180,5741596008418962223,4011842312208144404,3102455938659997665,7739421572442180630
2016-05-26 13:17:50,457 [DEBUG] rhsmcertd-worker:11664 @connection.py:555 - Response: status=500
2016-05-26 13:17:50,458 [WARNING] rhsmcertd-worker:11664 @base_action_client.py:72 - Exception caught while running <subscription_manager.entcertlib.EntCertActionInvoker object at 0x2ca2fd0> update
2016-05-26 13:17:50,458 [ERROR] rhsmcertd-worker:11664 @base_action_client.py:73 - undefined method `[]' for nil:NilClass
Traceback (most recent call last):
File "/usr/share/rhsm/subscription_manager/base_action_client.py", line 63, in _run_update
update_report = lib.update()
File "/usr/share/rhsm/subscription_manager/certlib.py", line 31, in update
self.report = self.locker.run(self._do_update)
File "/usr/share/rhsm/subscription_manager/certlib.py", line 17, in run
return action()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 43, in _do_update
return action.perform()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 129, in perform
self.install(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 159, in install
cert_bundles = self.get_certificates_by_serial_list(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 248, in get_certificates_by_serial_list
serials=sn_list)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 1090, in getCertificates
return self.conn.request_get(method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 644, in request_get
return self._request("GET", method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 564, in _request
self.validateResponse(result, request_type, handler)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 611, in validateResponse
raise RestlibException(response['status'], error_msg, response.get('headers'))
RestlibException: undefined method `[]' for nil:NilClass
2016-05-26 13:17:50,460 [DEBUG] rhsmcertd-worker:11664 @base_action_client.py:85 - running lib: <subscription_manager.identitycertlib.IdentityCertActionInvoker object at 0x2cdca90>
2016-05-26 13:17:50,462 [DEBUG] rhsmcertd-worker:11664 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:50,463 [DEBUG] rhsmcertd-worker:11664 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:17:50,724 [DEBUG] rhsmcertd-worker:11664 @connection.py:555 - Response: status=200
2016-05-26 13:17:50,728 [DEBUG] rhsmcertd-worker:11664 @base_action_client.py:85 - running lib: <subscription_manager.content_action_client.ContentActionClient object at 0x2c92e10>
2016-05-26 13:17:50,729 [DEBUG] rhsmcertd-worker:11664 @base_action_client.py:85 - running lib: <subscription_manager.repolib.RepoActionInvoker object at 0x2cdcc10>
2016-05-26 13:17:50,731 [DEBUG] rhsmcertd-worker:11664 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:50,731 [DEBUG] rhsmcertd-worker:11664 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:17:50,837 [DEBUG] rhsmcertd-worker:11664 @connection.py:555 - Response: status=200
2016-05-26 13:17:50,839 [DEBUG] rhsmcertd-worker:11664 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:17:50,842 [DEBUG] rhsmcertd-worker:11664 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:50,842 [DEBUG] rhsmcertd-worker:11664 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/content_overrides
2016-05-26 13:17:51,074 [DEBUG] rhsmcertd-worker:11664 @connection.py:555 - Response: status=200
2016-05-26 13:17:51,076 [DEBUG] rhsmcertd-worker:11664 @cache.py:273 - Started thread to write cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:17:51,077 [DEBUG] rhsmcertd-worker:11664 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:17:51,078 [DEBUG] rhsmcertd-worker:11664 @__init__.py:85 - Searching for content of type: yum
2016-05-26 13:17:51,078 [DEBUG] rhsmcertd-worker:11664 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/written_overrides.json
2016-05-26 13:17:51,080 [INFO] rhsmcertd-worker:11664 @repolib.py:296 - repos updated: Repo updates

Total repo updates: 0
Updated
<NONE>
Added (new)
<NONE>
Deleted
<NONE>
2016-05-26 13:17:51,081 [DEBUG] rhsmcertd-worker:11664 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:17:51,081 [DEBUG] rhsmcertd-worker:11664 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:17:51,082 [DEBUG] rhsmcertd-worker:11664 @base_action_client.py:85 - running lib: <subscription_manager.factlib.FactsActionInvoker object at 0x2cdc750>
2016-05-26 13:17:51,142 [DEBUG] rhsmcertd-worker:11664 @hwprobe.py:554 - cpu info: {'cpu.cpu(s)': 2, 'cpu.core(s)_per_socket': 1, 'cpu.thread(s)_per_core': 1, 'cpu.topology_source': 'kernel /sys cpu sibling lists', 'cpu.cpu_socket(s)': 2}
2016-05-26 13:17:51,183 [DEBUG] rhsmcertd-worker:11664 @hwprobe.py:772 - Running 'virt-what'
2016-05-26 13:17:51,272 [DEBUG] rhsmcertd-worker:11664 @hwprobe.py:776 - virt-what stdout: vmware

2016-05-26 13:17:51,272 [DEBUG] rhsmcertd-worker:11664 @hwprobe.py:777 - virt-what stderr:
2016-05-26 13:17:51,499 [INFO] rhsmcertd-worker:11664 @hwprobe.py:854 - collected virt facts: virt.is_guest=True, virt.host_type=vmware, virt.uuid=420585ee-75ea-44c1-f09d-f274e001d0ae
2016-05-26 13:17:51,500 [DEBUG] rhsmcertd-worker:11664 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/facts/facts.json
2016-05-26 13:17:51,501 [DEBUG] rhsmcertd-worker:11664 @hwprobe.py:554 - cpu info: {'cpu.cpu(s)': 2, 'cpu.core(s)_per_socket': 1, 'cpu.thread(s)_per_core': 1, 'cpu.topology_source': 'kernel /sys cpu sibling lists', 'cpu.cpu_socket(s)': 2}
2016-05-26 13:17:51,510 [DEBUG] rhsmcertd-worker:11664 @hwprobe.py:772 - Running 'virt-what'
2016-05-26 13:17:51,573 [DEBUG] rhsmcertd-worker:11664 @hwprobe.py:776 - virt-what stdout: vmware

2016-05-26 13:17:51,573 [DEBUG] rhsmcertd-worker:11664 @hwprobe.py:777 - virt-what stderr:
2016-05-26 13:17:51,766 [INFO] rhsmcertd-worker:11664 @hwprobe.py:854 - collected virt facts: virt.is_guest=True, virt.host_type=vmware, virt.uuid=420585ee-75ea-44c1-f09d-f274e001d0ae
2016-05-26 13:17:51,767 [DEBUG] rhsmcertd-worker:11664 @cache.py:140 - System data has changed, updating server.
2016-05-26 13:17:51,767 [DEBUG] rhsmcertd-worker:11664 @facts.py:153 - Updating facts on server
2016-05-26 13:17:51,769 [DEBUG] rhsmcertd-worker:11664 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:51,770 [DEBUG] rhsmcertd-worker:11664 @connection.py:523 - Making request: PUT /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:17:55,585 [DEBUG] rhsmcertd-worker:11664 @connection.py:555 - Response: status=200
2016-05-26 13:17:55,586 [DEBUG] rhsmcertd-worker:11664 @cache.py:110 - Wrote cache: /var/lib/rhsm/facts/facts.json
2016-05-26 13:17:55,586 [INFO] rhsmcertd-worker:11664 @factlib.py:103 - Facts have been updated.
2016-05-26 13:17:55,587 [DEBUG] rhsmcertd-worker:11664 @base_action_client.py:85 - running lib: <subscription_manager.packageprofilelib.PackageProfileActionInvoker object at 0x2cdc910>
2016-05-26 13:17:55,587 [DEBUG] rhsmcertd-worker:11664 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:17:55,588 [DEBUG] rhsmcertd-worker:11664 @profile.py:84 - Loading RPM profile from file.
2016-05-26 13:17:55,593 [DEBUG] rhsmcertd-worker:11664 @profile.py:97 - Loading current RPM profile.
2016-05-26 13:17:55,947 [DEBUG] rhsmcertd-worker:11664 @cache.py:140 - System data has changed, updating server.
2016-05-26 13:17:55,949 [DEBUG] rhsmcertd-worker:11664 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:17:55,952 [DEBUG] rhsmcertd-worker:11664 @connection.py:523 - Making request: PUT /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/packages
2016-05-26 13:17:57,819 [DEBUG] rhsmcertd-worker:11664 @connection.py:555 - Response: status=200
2016-05-26 13:17:57,845 [DEBUG] rhsmcertd-worker:11664 @cache.py:110 - Wrote cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:17:57,846 [DEBUG] rhsmcertd-worker:11664 @base_action_client.py:85 - running lib: <subscription_manager.installedproductslib.InstalledProductsActionInvoker object at 0x2cdc9d0>
2016-05-26 13:17:57,846 [DEBUG] rhsmcertd-worker:11664 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:17:57,846 [DEBUG] rhsmcertd-worker:11664 @cache.py:155 - No changes.
2016-05-26 13:34:01,349 [DEBUG] subscription-manager:11820 @ga_loader.py:89 - ga_loader GaImporterGtk3
2016-05-26 13:34:01,359 [DEBUG] subscription-manager:11820 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:34:01,359 [DEBUG] subscription-manager:11820 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:34:01,359 [DEBUG] subscription-manager:11820 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:34:01,441 [INFO] subscription-manager:11820 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:34:01,443 [INFO] subscription-manager:11820 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:34:01,444 [INFO] subscription-manager:11820 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=none
2016-05-26 13:34:01,446 [DEBUG] subscription-manager:11820 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:01,447 [DEBUG] subscription-manager:11820 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:34:01,669 [DEBUG] subscription-manager:11820 @connection.py:555 - Response: status=200
2016-05-26 13:34:01,671 [DEBUG] subscription-manager:11820 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:34:01,673 [DEBUG] subscription-manager:11820 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:01,673 [DEBUG] subscription-manager:11820 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:34:02,006 [DEBUG] subscription-manager:11820 @connection.py:555 - Response: status=200
2016-05-26 13:34:02,008 [INFO] subscription-manager:11820 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'Red Hat Subscription Management'}
2016-05-26 13:34:02,009 [DEBUG] subscription-manager:11820 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:34:02,009 [DEBUG] subscription-manager:11820 @cache.py:155 - No changes.
2016-05-26 13:34:02,009 [DEBUG] subscription-manager:11820 @certdirectory.py:216 - Installed product IDs: []
2016-05-26 13:34:02,011 [DEBUG] subscription-manager:11820 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:02,011 [DEBUG] subscription-manager:11820 @connection.py:523 - Making request: GET /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae/compliance
2016-05-26 13:34:02,114 [DEBUG] subscription-manager:11820 @connection.py:555 - Response: status=500
2016-05-26 13:34:02,115 [ERROR] subscription-manager:11820 @cache.py:193 - undefined method `[]' for nil:NilClass
Traceback (most recent call last):
File "/usr/share/rhsm/subscription_manager/cache.py", line 181, in load_status
self._sync_with_server(uep, uuid)
File "/usr/share/rhsm/subscription_manager/cache.py", line 290, in _sync_with_server
self.server_status = uep.getCompliance(uuid)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 1021, in getCompliance
return self.conn.request_get(method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 644, in request_get
return self._request("GET", method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 564, in _request
self.validateResponse(result, request_type, handler)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 611, in validateResponse
raise RestlibException(response['status'], error_msg, response.get('headers'))
RestlibException: undefined method `[]' for nil:NilClass
2016-05-26 13:34:02,117 [DEBUG] subscription-manager:11820 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:34:02,117 [DEBUG] subscription-manager:11820 @cache.py:155 - No changes.
2016-05-26 13:34:02,118 [DEBUG] subscription-manager:11820 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:34:02,119 [INFO] subscription-manager:11820 @managercli.py:328 - Consumer Identity name=testcislave01d.niwa.local uuid=7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:34:32,754 [DEBUG] subscription-manager:11822 @ga_loader.py:89 - ga_loader GaImporterGtk3
2016-05-26 13:34:32,759 [DEBUG] subscription-manager:11822 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:34:32,759 [DEBUG] subscription-manager:11822 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:34:32,759 [DEBUG] subscription-manager:11822 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:34:32,811 [INFO] subscription-manager:11822 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:34:32,812 [INFO] subscription-manager:11822 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:34:32,812 [INFO] subscription-manager:11822 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=none
2016-05-26 13:34:32,814 [DEBUG] subscription-manager:11822 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:32,814 [DEBUG] subscription-manager:11822 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:34:33,045 [DEBUG] subscription-manager:11822 @connection.py:555 - Response: status=200
2016-05-26 13:34:33,047 [DEBUG] subscription-manager:11822 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:34:33,049 [DEBUG] subscription-manager:11822 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:33,049 [DEBUG] subscription-manager:11822 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:34:33,547 [DEBUG] subscription-manager:11822 @connection.py:555 - Response: status=200
2016-05-26 13:34:33,549 [INFO] subscription-manager:11822 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'Red Hat Subscription Management'}
2016-05-26 13:34:33,550 [INFO] subscription-manager:11822 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:34:33,550 [INFO] subscription-manager:11822 @managercli.py:328 - Consumer Identity name=testcislave01d.niwa.local uuid=7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:34:37,763 [DEBUG] subscription-manager:11824 @ga_loader.py:89 - ga_loader GaImporterGtk3
2016-05-26 13:34:37,768 [DEBUG] subscription-manager:11824 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:34:37,768 [DEBUG] subscription-manager:11824 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:34:37,768 [DEBUG] subscription-manager:11824 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:34:37,819 [INFO] subscription-manager:11824 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:34:37,820 [INFO] subscription-manager:11824 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:34:37,821 [INFO] subscription-manager:11824 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=none
2016-05-26 13:34:37,822 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:37,823 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:34:37,908 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=200
2016-05-26 13:34:37,910 [DEBUG] subscription-manager:11824 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:34:37,912 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:37,912 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:34:38,313 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=200
2016-05-26 13:34:38,315 [INFO] subscription-manager:11824 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'Red Hat Subscription Management'}
2016-05-26 13:34:38,315 [INFO] subscription-manager:11824 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:34:38,316 [INFO] subscription-manager:11824 @managercli.py:328 - Consumer Identity name=testcislave01d.niwa.local uuid=7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:34:38,316 [INFO] subscription-manager:11824 @managercli.py:328 - Consumer Identity name=testcislave01d.niwa.local uuid=7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:34:38,317 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:38,318 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: DELETE /rhsm/consumers/7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:34:42,930 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=204
2016-05-26 13:34:42,932 [INFO] subscription-manager:11824 @managerlib.py:790 - Successfully un-registered.
2016-05-26 13:34:42,932 [DEBUG] subscription-manager:11824 @managerlib.py:836 - Removing identity cert: /etc/pki/consumer/key.pem
2016-05-26 13:34:42,933 [DEBUG] subscription-manager:11824 @managerlib.py:836 - Removing identity cert: /etc/pki/consumer/cert.pem
2016-05-26 13:34:42,933 [DEBUG] subscription-manager:11824 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:34:42,934 [DEBUG] subscription-manager:11824 @identity.py:146 - Reload of consumer identity cert /etc/pki/consumer/cert.pem raised an exception with msg: [Errno 2] No such file or directory: '/etc/pki/consumer/key.pem'
2016-05-26 13:34:42,934 [DEBUG] subscription-manager:11824 @cache.py:86 - Deleting cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:34:42,935 [DEBUG] subscription-manager:11824 @cache.py:86 - Deleting cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:34:42,935 [DEBUG] subscription-manager:11824 @cache.py:86 - Deleting cache: /var/lib/rhsm/facts/facts.json
2016-05-26 13:34:42,936 [DEBUG] subscription-manager:11824 @cache.py:86 - Deleting cache: /var/lib/rhsm/cache/content_overrides.json
2016-05-26 13:34:42,936 [DEBUG] subscription-manager:11824 @cache.py:86 - Deleting cache: /var/lib/rhsm/cache/written_overrides.json
2016-05-26 13:34:42,937 [INFO] subscription-manager:11824 @managerlib.py:861 - Cleaned local data
2016-05-26 13:34:42,937 [INFO] subscription-manager:11824 @managercli.py:1031 - --force specified, unregistered old consumer: 7808f40d-42e1-4691-8bf5-6b761d17b2ae
2016-05-26 13:34:42,965 [DEBUG] subscription-manager:11824 @hwprobe.py:554 - cpu info: {'cpu.cpu(s)': 2, 'cpu.core(s)_per_socket': 1, 'cpu.thread(s)_per_core': 1, 'cpu.topology_source': 'kernel /sys cpu sibling lists', 'cpu.cpu_socket(s)': 2}
2016-05-26 13:34:42,979 [DEBUG] subscription-manager:11824 @hwprobe.py:772 - Running 'virt-what'
2016-05-26 13:34:43,079 [DEBUG] subscription-manager:11824 @hwprobe.py:776 - virt-what stdout: vmware

2016-05-26 13:34:43,079 [DEBUG] subscription-manager:11824 @hwprobe.py:777 - virt-what stderr:
2016-05-26 13:34:43,284 [INFO] subscription-manager:11824 @hwprobe.py:854 - collected virt facts: virt.is_guest=True, virt.host_type=vmware, virt.uuid=420585ee-75ea-44c1-f09d-f274e001d0ae
2016-05-26 13:34:43,285 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:43,286 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: POST /rhsm/consumers?owner=NIWA&activation_keys=Jenkins%20master%20server
2016-05-26 13:34:50,417 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=200
2016-05-26 13:34:50,422 [DEBUG] subscription-manager:11824 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:34:50,424 [INFO] subscription-manager:11824 @managerlib.py:75 - Consumer created: {'consumer_name': 'testcislave01d.niwa.local', 'uuid': '082bf247-b597-4d4e-9797-b1211a67ef4d'}
2016-05-26 13:34:50,424 [DEBUG] subscription-manager:11824 @utils.py:287 - No virt-who pid file, not attempting to restart
2016-05-26 13:34:50,425 [DEBUG] subscription-manager:11824 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:34:50,428 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:50,428 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:34:50,841 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=200
2016-05-26 13:34:50,843 [INFO] subscription-manager:11824 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'Red Hat Subscription Management'}
2016-05-26 13:34:50,845 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:50,845 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:34:50,944 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=200
2016-05-26 13:34:50,946 [DEBUG] subscription-manager:11824 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:34:50,946 [DEBUG] subscription-manager:11824 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:34:50,946 [DEBUG] subscription-manager:11824 @cache.py:393 - Cache does not exist
2016-05-26 13:34:50,947 [DEBUG] subscription-manager:11824 @cache.py:140 - System data has changed, updating server.
2016-05-26 13:34:50,947 [DEBUG] subscription-manager:11824 @profile.py:97 - Loading current RPM profile.
2016-05-26 13:34:51,044 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:51,047 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: PUT /rhsm/consumers/082bf247-b597-4d4e-9797-b1211a67ef4d/packages
2016-05-26 13:34:53,009 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=200
2016-05-26 13:34:53,035 [DEBUG] subscription-manager:11824 @cache.py:110 - Wrote cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:34:53,036 [DEBUG] subscription-manager:11824 @cache.py:110 - Wrote cache: /var/lib/rhsm/facts/facts.json
2016-05-26 13:34:53,037 [DEBUG] subscription-manager:11824 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:34:53,037 [DEBUG] subscription-manager:11824 @cache.py:155 - No changes.
2016-05-26 13:34:53,037 [INFO] subscription-manager:11824 @managercli.py:1128 - System registered, updating entitlements if needed
2016-05-26 13:34:53,039 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:53,040 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: GET /rhsm/consumers/082bf247-b597-4d4e-9797-b1211a67ef4d/certificates/serials
2016-05-26 13:34:53,333 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=200
2016-05-26 13:34:53,336 [DEBUG] subscription-manager:11824 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:34:53,336 [DEBUG] subscription-manager:11824 @connection.py:523 - Making request: GET /rhsm/consumers/082bf247-b597-4d4e-9797-b1211a67ef4d/certificates?serials=6557095577705680742,4682249099248512728,5473762682243103361,8553368293907899861,8330935627666421247
2016-05-26 13:34:53,419 [DEBUG] subscription-manager:11824 @connection.py:555 - Response: status=500
2016-05-26 13:34:53,420 [ERROR] subscription-manager:11824 @managercli.py:160 - exception caught in subscription-manager
2016-05-26 13:34:53,420 [ERROR] subscription-manager:11824 @managercli.py:161 - undefined method `[]' for nil:NilClass
Traceback (most recent call last):
File "/usr/sbin/subscription-manager", line 86, in <module>
sys.exit(abs(main() or 0))
File "/usr/sbin/subscription-manager", line 77, in main
return managercli.ManagerCLI().main()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 2629, in main
return CLI.main(self)
File "/usr/share/rhsm/subscription_manager/cli.py", line 159, in main
return cmd.main()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 489, in main
return_code = self._do_command()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 1131, in _do_command
self.entcertlib.update()
File "/usr/share/rhsm/subscription_manager/certlib.py", line 31, in update
self.report = self.locker.run(self._do_update)
File "/usr/share/rhsm/subscription_manager/certlib.py", line 17, in run
return action()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 43, in _do_update
return action.perform()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 129, in perform
self.install(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 159, in install
cert_bundles = self.get_certificates_by_serial_list(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 248, in get_certificates_by_serial_list
serials=sn_list)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 1090, in getCertificates
return self.conn.request_get(method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 644, in request_get
return self._request("GET", method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 564, in _request
self.validateResponse(result, request_type, handler)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 611, in validateResponse
raise RestlibException(response['status'], error_msg, response.get('headers'))
RestlibException: undefined method `[]' for nil:NilClass
2016-05-26 13:35:53,611 [DEBUG] subscription-manager:11866 @ga_loader.py:89 - ga_loader GaImporterGtk3
2016-05-26 13:35:53,615 [DEBUG] subscription-manager:11866 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:35:53,615 [DEBUG] subscription-manager:11866 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:35:53,616 [DEBUG] subscription-manager:11866 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:35:53,666 [INFO] subscription-manager:11866 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:35:53,666 [INFO] subscription-manager:11866 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:35:53,667 [INFO] subscription-manager:11866 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=none
2016-05-26 13:35:53,669 [DEBUG] subscription-manager:11866 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:35:53,669 [DEBUG] subscription-manager:11866 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:35:53,775 [DEBUG] subscription-manager:11866 @connection.py:555 - Response: status=200
2016-05-26 13:35:53,777 [DEBUG] subscription-manager:11866 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:35:53,779 [DEBUG] subscription-manager:11866 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:35:53,779 [DEBUG] subscription-manager:11866 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:35:54,143 [DEBUG] subscription-manager:11866 @connection.py:555 - Response: status=200
2016-05-26 13:35:54,145 [INFO] subscription-manager:11866 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'Red Hat Subscription Management'}
2016-05-26 13:35:54,146 [INFO] subscription-manager:11866 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:35:54,147 [INFO] subscription-manager:11866 @managercli.py:328 - Consumer Identity name=testcislave01d.niwa.local uuid=082bf247-b597-4d4e-9797-b1211a67ef4d
2016-05-26 13:35:56,355 [DEBUG] subscription-manager:11868 @ga_loader.py:89 - ga_loader GaImporterGtk3
2016-05-26 13:35:56,360 [DEBUG] subscription-manager:11868 @plugins.py:569 - loaded plugin modules: []
2016-05-26 13:35:56,360 [DEBUG] subscription-manager:11868 @plugins.py:570 - loaded plugins: {}
2016-05-26 13:35:56,360 [DEBUG] subscription-manager:11868 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:35:56,411 [INFO] subscription-manager:11868 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:35:56,412 [INFO] subscription-manager:11868 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=identity_cert ca_dir=/etc/rhsm/ca/ verify=False
2016-05-26 13:35:56,413 [INFO] subscription-manager:11868 @connection.py:778 - Connection built: host=wellkatellodev.niwa.local port=443 handler=/rhsm auth=none
2016-05-26 13:35:56,414 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:35:56,415 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:35:56,497 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=200
2016-05-26 13:35:56,498 [DEBUG] subscription-manager:11868 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:35:56,499 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:35:56,499 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:35:56,894 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=200
2016-05-26 13:35:56,896 [INFO] subscription-manager:11868 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'Red Hat Subscription Management'}
2016-05-26 13:35:56,897 [INFO] subscription-manager:11868 @managercli.py:352 - Client Versions: {'python-rhsm': '1.15.4-5.el7', 'subscription-manager': '1.15.9-15.el7.centos.0.1'}
2016-05-26 13:35:56,897 [INFO] subscription-manager:11868 @managercli.py:328 - Consumer Identity name=testcislave01d.niwa.local uuid=082bf247-b597-4d4e-9797-b1211a67ef4d
2016-05-26 13:35:56,897 [INFO] subscription-manager:11868 @managercli.py:328 - Consumer Identity name=testcislave01d.niwa.local uuid=082bf247-b597-4d4e-9797-b1211a67ef4d
2016-05-26 13:35:56,899 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:35:56,899 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: DELETE /rhsm/consumers/082bf247-b597-4d4e-9797-b1211a67ef4d
2016-05-26 13:36:00,751 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=204
2016-05-26 13:36:00,753 [INFO] subscription-manager:11868 @managerlib.py:790 - Successfully un-registered.
2016-05-26 13:36:00,753 [DEBUG] subscription-manager:11868 @managerlib.py:836 - Removing identity cert: /etc/pki/consumer/key.pem
2016-05-26 13:36:00,754 [DEBUG] subscription-manager:11868 @managerlib.py:836 - Removing identity cert: /etc/pki/consumer/cert.pem
2016-05-26 13:36:00,754 [DEBUG] subscription-manager:11868 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:36:00,755 [DEBUG] subscription-manager:11868 @identity.py:146 - Reload of consumer identity cert /etc/pki/consumer/cert.pem raised an exception with msg: [Errno 2] No such file or directory: '/etc/pki/consumer/key.pem'
2016-05-26 13:36:00,756 [DEBUG] subscription-manager:11868 @cache.py:86 - Deleting cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:36:00,756 [DEBUG] subscription-manager:11868 @cache.py:86 - Deleting cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:36:00,756 [DEBUG] subscription-manager:11868 @cache.py:86 - Deleting cache: /var/lib/rhsm/facts/facts.json
2016-05-26 13:36:00,757 [INFO] subscription-manager:11868 @managerlib.py:861 - Cleaned local data
2016-05-26 13:36:00,757 [INFO] subscription-manager:11868 @managercli.py:1031 - --force specified, unregistered old consumer: 082bf247-b597-4d4e-9797-b1211a67ef4d
2016-05-26 13:36:00,783 [DEBUG] subscription-manager:11868 @hwprobe.py:554 - cpu info: {'cpu.cpu(s)': 2, 'cpu.core(s)_per_socket': 1, 'cpu.thread(s)_per_core': 1, 'cpu.topology_source': 'kernel /sys cpu sibling lists', 'cpu.cpu_socket(s)': 2}
2016-05-26 13:36:00,796 [DEBUG] subscription-manager:11868 @hwprobe.py:772 - Running 'virt-what'
2016-05-26 13:36:00,883 [DEBUG] subscription-manager:11868 @hwprobe.py:776 - virt-what stdout: vmware

2016-05-26 13:36:00,884 [DEBUG] subscription-manager:11868 @hwprobe.py:777 - virt-what stderr:
2016-05-26 13:36:01,069 [INFO] subscription-manager:11868 @hwprobe.py:854 - collected virt facts: virt.is_guest=True, virt.host_type=vmware, virt.uuid=420585ee-75ea-44c1-f09d-f274e001d0ae
2016-05-26 13:36:01,071 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:36:01,071 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: POST /rhsm/consumers?owner=NIWA&activation_keys=Standard%20Production%20Server
2016-05-26 13:36:06,683 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=200
2016-05-26 13:36:06,687 [DEBUG] subscription-manager:11868 @cache.py:110 - Wrote cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:36:06,689 [INFO] subscription-manager:11868 @managerlib.py:75 - Consumer created: {'consumer_name': 'testcislave01d.niwa.local', 'uuid': '4f81acfe-0acb-4d38-8eda-305a75d86f38'}
2016-05-26 13:36:06,690 [DEBUG] subscription-manager:11868 @utils.py:287 - No virt-who pid file, not attempting to restart
2016-05-26 13:36:06,690 [DEBUG] subscription-manager:11868 @identity.py:131 - Loading consumer info from identity certificates.
2016-05-26 13:36:06,694 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:36:06,694 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: GET /rhsm/status
2016-05-26 13:36:07,132 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=200
2016-05-26 13:36:07,134 [INFO] subscription-manager:11868 @managercli.py:363 - Server Versions: {'rules-version': '5.15.2', 'candlepin': '3.0.0.rc5-Unknown', 'server-type': 'Red Hat Subscription Management'}
2016-05-26 13:36:07,136 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:36:07,136 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: GET /rhsm/
2016-05-26 13:36:07,237 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=200
2016-05-26 13:36:07,239 [DEBUG] subscription-manager:11868 @connection.py:795 - Server supports the following resources: {'available_releases': '/rhsm/consumers/:id/available_releases', 'status': '/rhsm/status', 'guestids': '/rhsm/consumers/:id/guestids', 'content_overrides': '/rhsm/consumers/:id/content_overrides', 'environments': '/rhsm/owners/:organization_id/environments', 'hypervisors': '/rhsm/hypervisors', 'export': '/rhsm/consumers/:id/export', 'owner': '/rhsm/consumers/:id/owner', 'certificates': '/rhsm/consumers/:consumer_id/certificates', 'servicelevels': '/rhsm/owners/:organization_id/servicelevels', 'serials': '/rhsm/consumers/:id/certificates/serials', 'deleted_consumers': '/rhsm/deleted_consumers', 'consumers': '/rhsm/environments/:environment_id/consumers', 'entitlements': '/rhsm/entitlements', 'profile': '/rhsm/consumers/:id/profile', 'dry-run': '/rhsm/consumers/:id/entitlements/dry-run', 'subscriptions': '/rhsm/subscriptions', 'checkin': '/rhsm/consumers/:id/checkin', 'deletionrecord': '/rhsm/consumers/:id/deletionrecord', 'release': '/rhsm/consumers/:id/release', 'packages': '/rhsm/consumers/:id/packages', 'owners': '/rhsm/users/:login/owners', 'compliance': '/rhsm/consumers/:id/compliance', 'enabled_repos': '/rhsm/systems/:id/enabled_repos', 'pools': '/rhsm/pools'}
2016-05-26 13:36:07,239 [DEBUG] subscription-manager:11868 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:36:07,239 [DEBUG] subscription-manager:11868 @cache.py:393 - Cache does not exist
2016-05-26 13:36:07,240 [DEBUG] subscription-manager:11868 @cache.py:140 - System data has changed, updating server.
2016-05-26 13:36:07,240 [DEBUG] subscription-manager:11868 @profile.py:97 - Loading current RPM profile.
2016-05-26 13:36:07,325 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:36:07,328 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: PUT /rhsm/consumers/4f81acfe-0acb-4d38-8eda-305a75d86f38/packages
2016-05-26 13:36:09,151 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=200
2016-05-26 13:36:09,177 [DEBUG] subscription-manager:11868 @cache.py:110 - Wrote cache: /var/lib/rhsm/packages/packages.json
2016-05-26 13:36:09,178 [DEBUG] subscription-manager:11868 @cache.py:110 - Wrote cache: /var/lib/rhsm/facts/facts.json
2016-05-26 13:36:09,178 [DEBUG] subscription-manager:11868 @cache.py:138 - Checking current system info against cache: /var/lib/rhsm/cache/installed_products.json
2016-05-26 13:36:09,178 [DEBUG] subscription-manager:11868 @cache.py:155 - No changes.
2016-05-26 13:36:09,179 [INFO] subscription-manager:11868 @managercli.py:1128 - System registered, updating entitlements if needed
2016-05-26 13:36:09,180 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:36:09,181 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: GET /rhsm/consumers/4f81acfe-0acb-4d38-8eda-305a75d86f38/certificates/serials
2016-05-26 13:36:09,467 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=200
2016-05-26 13:36:09,470 [DEBUG] subscription-manager:11868 @connection.py:475 - Loaded CA certificates from /etc/rhsm/ca/: redhat-uep.pem, katello-server-ca.pem, katello-default-ca.pem
2016-05-26 13:36:09,470 [DEBUG] subscription-manager:11868 @connection.py:523 - Making request: GET /rhsm/consumers/4f81acfe-0acb-4d38-8eda-305a75d86f38/certificates?serials=4184630251041484700,8277184554186507893,4808845318156872057,8241600292010399491
2016-05-26 13:36:09,549 [DEBUG] subscription-manager:11868 @connection.py:555 - Response: status=500
2016-05-26 13:36:09,551 [ERROR] subscription-manager:11868 @managercli.py:160 - exception caught in subscription-manager
2016-05-26 13:36:09,551 [ERROR] subscription-manager:11868 @managercli.py:161 - undefined method `[]' for nil:NilClass
Traceback (most recent call last):
File "/usr/sbin/subscription-manager", line 86, in <module>
sys.exit(abs(main() or 0))
File "/usr/sbin/subscription-manager", line 77, in main
return managercli.ManagerCLI().main()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 2629, in main
return CLI.main(self)
File "/usr/share/rhsm/subscription_manager/cli.py", line 159, in main
return cmd.main()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 489, in main
return_code = self._do_command()
File "/usr/share/rhsm/subscription_manager/managercli.py", line 1131, in _do_command
self.entcertlib.update()
File "/usr/share/rhsm/subscription_manager/certlib.py", line 31, in update
self.report = self.locker.run(self._do_update)
File "/usr/share/rhsm/subscription_manager/certlib.py", line 17, in run
return action()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 43, in _do_update
return action.perform()
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 129, in perform
self.install(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 159, in install
cert_bundles = self.get_certificates_by_serial_list(missing_serials)
File "/usr/share/rhsm/subscription_manager/entcertlib.py", line 248, in get_certificates_by_serial_list
serials=sn_list)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 1090, in getCertificates
return self.conn.request_get(method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 644, in request_get
return self._request("GET", method)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 564, in _request
self.validateResponse(result, request_type, handler)
File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 611, in validateResponse
raise RestlibException(response['status'], error_msg, response.get('headers'))
RestlibException: undefined method `[]' for nil:NilClass
(1-1/2)