Attachment 716874 Details for Bug 928175 – RHSM log

RHSM log

rhsmLog.txt (text/plain), 17.81 KB, created by Shwetha Kallesh on 2013-03-27 04:59:38 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Shwetha Kallesh

Created: 2013-03-27 04:59:38 UTC

Size: 17.81 KB

patch

obsolete

>[root@rhel7 ~]# tail -f /var/log/rhsm/rhsm.log
>2013-03-26 16:23:39,722 [DEBUG]  @injection.py:64 - Returning instance for feature IDENTITY
>2013-03-26 16:23:39,722 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:23:39,722 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:23:39,723 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:23:39,723 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:23:39,723 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:23:39,724 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/consumers/6130b588-b49a-41de-8506-aa06ad7b59af
>2013-03-26 16:23:39,786 [DEBUG]  @connection.py:434 - Response status: 200
>2013-03-26 16:23:39,787 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:23:39,792 [INFO]  @managercli.py:199 - Attempted to auto-attach/heal the system.
>2013-03-26 16:24:43,617 [ERROR]  @plugins.py:826 - Could not find /usr/lib/rhsm-plugins for plugin import
>2013-03-26 16:24:43,618 [DEBUG]  @plugins.py:508 - loaded plugin modules: []
>2013-03-26 16:24:43,618 [DEBUG]  @plugins.py:509 - loaded plugins: {}
>2013-03-26 16:24:43,619 [DEBUG]  @plugins.py:481 - Calling PluginManager init
>2013-03-26 16:24:43,673 [DEBUG]  @profile.py:95 - Loading current RPM profile.
>2013-03-26 16:24:43,775 [INFO]  @managercli.py:300 - Client Versions: {'python-rhsm': '1.8.7-1.el7', 'subscription-manager': '1.8.4-1.el7'} 
>2013-03-26 16:24:43,776 [INFO]  @connection.py:586 - Using certificate authentication: key = /etc/pki/consumer/key.pem, cert = /etc/pki/consumer/cert.pem, ca = /etc/rhsm/ca/, insecure = False
>2013-03-26 16:24:43,776 [INFO]  @connection.py:597 - Connection Built: host: 10.70.35.179, port: 8443, handler: /candlepin
>2013-03-26 16:24:43,777 [INFO]  @connection.py:593 - Using no auth
>2013-03-26 16:24:43,777 [INFO]  @connection.py:597 - Connection Built: host: 10.70.35.179, port: 8443, handler: /candlepin
>2013-03-26 16:24:43,780 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:43,781 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:43,781 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:43,782 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:43,782 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:43,783 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/
>2013-03-26 16:24:43,791 [DEBUG]  @connection.py:434 - Response status: 200
>2013-03-26 16:24:43,792 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:43,793 [DEBUG]  @connection.py:613 - Server supports the following resources:
>2013-03-26 16:24:43,794 [DEBUG]  @connection.py:614 - {'': '/', 'hypervisors': '/hypervisors', 'serials': '/serials', 'consumers': '/consumers', 'migrations': '/migrations', 'content': '/content', 'entitlements': '/entitlements', 'statistics/generate': '/statistics/generate', 'status': '/status', 'jobs': '/jobs', 'users': '/users', 'subscriptions': '/subscriptions', 'rules': '/rules', 'consumertypes': '/consumertypes', 'activation_keys': '/activation_keys', 'atom': '/atom', 'owners': '/owners', 'roles': '/roles', 'admin': '/admin', 'events': '/events', 'products': '/products', 'pools': '/pools', 'crl': '/crl'}
>2013-03-26 16:24:43,794 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:43,794 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:43,795 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:43,795 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:43,796 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:43,797 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/status
>2013-03-26 16:24:43,808 [DEBUG]  @connection.py:434 - Response status: 200
>2013-03-26 16:24:43,809 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:43,815 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:43,815 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:43,816 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:43,816 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:43,817 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:43,818 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/status
>2013-03-26 16:24:43,829 [DEBUG]  @connection.py:434 - Response status: 200
>2013-03-26 16:24:43,830 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:43,831 [INFO]  @managercli.py:311 - Server Versions: {'candlepin': '0.8.0-1', 'server-type': 'Red Hat Subscription Management'} 
>2013-03-26 16:24:43,832 [DEBUG]  @injection.py:61 - Returning callable provider for feature CERT_SORTER: <class 'subscription_manager.cert_sorter.CertSorter'>
>2013-03-26 16:24:43,832 [DEBUG]  @injection.py:64 - Returning instance for feature IDENTITY
>2013-03-26 16:24:43,836 [DEBUG]  @certdirectory.py:187 - Installed product IDs: ['1000000000000023', '100000000000002', '88888', '37080']
>2013-03-26 16:24:43,837 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:43,837 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:43,838 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:43,838 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:43,839 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:43,840 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/consumers/6130b588-b49a-41de-8506-aa06ad7b59af/compliance
>2013-03-26 16:24:43,859 [DEBUG]  @connection.py:434 - Response status: 410
>2013-03-26 16:24:43,859 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:43,861 [DEBUG]  @injection.py:61 - Returning callable provider for feature PRODUCT_DATE_RANGE_CALCULATOR: <class 'subscription_manager.validity.ValidProductDateRangeCalculator'>
>2013-03-26 16:24:43,862 [DEBUG]  @injection.py:64 - Returning instance for feature IDENTITY
>2013-03-26 16:24:43,862 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:43,863 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:43,864 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:43,864 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:43,865 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:43,866 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/consumers/6130b588-b49a-41de-8506-aa06ad7b59af
>2013-03-26 16:24:43,883 [DEBUG]  @connection.py:434 - Response status: 410
>2013-03-26 16:24:43,884 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:43,885 [ERROR]  @managercli.py:144 - exception caught in subscription-manager
>2013-03-26 16:24:43,885 [ERROR]  @managercli.py:145 - Consumer 6130b588-b49a-41de-8506-aa06ad7b59af has been deleted
>Traceback (most recent call last):
>  File "/sbin/subscription-manager", line 90, in <module>
>    sys.exit(abs(main() or 0))
>  File "/sbin/subscription-manager", line 81, in main
>    return managercli.ManagerCLI().main()
>  File "/usr/share/rhsm/subscription_manager/managercli.py", line 2168, in main
>    return CLI.main(self)
>  File "/usr/share/rhsm/subscription_manager/cli.py", line 160, in main
>    return cmd.main()
>  File "/usr/share/rhsm/subscription_manager/managercli.py", line 473, in main
>    return_code = self._do_command()
>  File "/usr/share/rhsm/subscription_manager/managercli.py", line 2015, in _do_command
>    self.entitlement_dir, self.cp)
>  File "/usr/share/rhsm/subscription_manager/managerlib.py", line 89, in getInstalledProductStatus
>    calculator = FEATURES.require(PRODUCT_DATE_RANGE_CALCULATOR, uep)
>  File "/usr/share/rhsm/subscription_manager/injection.py", line 62, in require
>    return provider(*args, **kwargs)
>  File "/usr/share/rhsm/subscription_manager/validity.py", line 30, in __init__
>    self.consumer_data = self.uep.getConsumer(self.identity.uuid)
>  File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 731, in getConsumer
>    return self.conn.request_get(method)
>  File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 481, in request_get
>    return self._request("GET", method)
>  File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 443, in _request
>    self.validateResponse(result)
>  File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 465, in validateResponse
>    parsed['displayMessage'], parsed['deletedId'])
>GoneException: Consumer 6130b588-b49a-41de-8506-aa06ad7b59af has been deleted
>2013-03-26 16:24:54,803 [ERROR]  @plugins.py:826 - Could not find /usr/lib/rhsm-plugins for plugin import
>2013-03-26 16:24:54,803 [DEBUG]  @plugins.py:508 - loaded plugin modules: []
>2013-03-26 16:24:54,804 [DEBUG]  @plugins.py:509 - loaded plugins: {}
>2013-03-26 16:24:54,804 [DEBUG]  @plugins.py:481 - Calling PluginManager init
>2013-03-26 16:24:54,857 [DEBUG]  @profile.py:95 - Loading current RPM profile.
>2013-03-26 16:24:54,960 [INFO]  @managercli.py:300 - Client Versions: {'python-rhsm': '1.8.7-1.el7', 'subscription-manager': '1.8.4-1.el7'} 
>2013-03-26 16:24:54,961 [INFO]  @connection.py:586 - Using certificate authentication: key = /etc/pki/consumer/key.pem, cert = /etc/pki/consumer/cert.pem, ca = /etc/rhsm/ca/, insecure = False
>2013-03-26 16:24:54,961 [INFO]  @connection.py:597 - Connection Built: host: 10.70.35.179, port: 8443, handler: /candlepin
>2013-03-26 16:24:54,962 [INFO]  @connection.py:593 - Using no auth
>2013-03-26 16:24:54,962 [INFO]  @connection.py:597 - Connection Built: host: 10.70.35.179, port: 8443, handler: /candlepin
>2013-03-26 16:24:54,965 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:54,965 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:54,966 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:54,966 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:54,967 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:54,967 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/
>2013-03-26 16:24:54,978 [DEBUG]  @connection.py:434 - Response status: 200
>2013-03-26 16:24:54,979 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:54,982 [DEBUG]  @connection.py:613 - Server supports the following resources:
>2013-03-26 16:24:54,982 [DEBUG]  @connection.py:614 - {'': '/', 'hypervisors': '/hypervisors', 'serials': '/serials', 'consumers': '/consumers', 'migrations': '/migrations', 'content': '/content', 'entitlements': '/entitlements', 'statistics/generate': '/statistics/generate', 'status': '/status', 'jobs': '/jobs', 'users': '/users', 'subscriptions': '/subscriptions', 'rules': '/rules', 'consumertypes': '/consumertypes', 'activation_keys': '/activation_keys', 'atom': '/atom', 'owners': '/owners', 'roles': '/roles', 'admin': '/admin', 'events': '/events', 'products': '/products', 'pools': '/pools', 'crl': '/crl'}
>2013-03-26 16:24:54,983 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:54,984 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:54,985 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:54,985 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:54,986 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:54,987 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/status
>2013-03-26 16:24:54,998 [DEBUG]  @connection.py:434 - Response status: 200
>2013-03-26 16:24:54,999 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:55,006 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:55,006 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:55,007 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:55,007 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:55,008 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:55,009 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/status
>2013-03-26 16:24:55,020 [DEBUG]  @connection.py:434 - Response status: 200
>2013-03-26 16:24:55,021 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:55,022 [INFO]  @managercli.py:311 - Server Versions: {'candlepin': '0.8.0-1', 'server-type': 'Red Hat Subscription Management'} 
>2013-03-26 16:24:55,023 [DEBUG]  @injection.py:61 - Returning callable provider for feature CERT_SORTER: <class 'subscription_manager.cert_sorter.CertSorter'>
>2013-03-26 16:24:55,023 [DEBUG]  @injection.py:64 - Returning instance for feature IDENTITY
>2013-03-26 16:24:55,027 [DEBUG]  @certdirectory.py:187 - Installed product IDs: ['1000000000000023', '100000000000002', '88888', '37080']
>2013-03-26 16:24:55,028 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:55,028 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:55,029 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:55,029 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:55,030 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:55,031 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/consumers/6130b588-b49a-41de-8506-aa06ad7b59af/compliance
>2013-03-26 16:24:55,058 [DEBUG]  @connection.py:434 - Response status: 410
>2013-03-26 16:24:55,059 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:55,061 [DEBUG]  @injection.py:61 - Returning callable provider for feature PRODUCT_DATE_RANGE_CALCULATOR: <class 'subscription_manager.validity.ValidProductDateRangeCalculator'>
>2013-03-26 16:24:55,061 [DEBUG]  @injection.py:64 - Returning instance for feature IDENTITY
>2013-03-26 16:24:55,062 [DEBUG]  @connection.py:394 - Loading CA PEM certificates from: /etc/rhsm/ca/
>2013-03-26 16:24:55,063 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-ca.pem'
>2013-03-26 16:24:55,064 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/10.pem'
>2013-03-26 16:24:55,065 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/candlepin-stage.pem'
>2013-03-26 16:24:55,065 [DEBUG]  @connection.py:376 - Loading CA certificate: '/etc/rhsm/ca/redhat-uep.pem'
>2013-03-26 16:24:55,066 [DEBUG]  @connection.py:415 - Making request: GET /candlepin/consumers/6130b588-b49a-41de-8506-aa06ad7b59af
>2013-03-26 16:24:55,086 [DEBUG]  @connection.py:434 - Response status: 410
>2013-03-26 16:24:55,087 [WARNING]  @connection.py:438 - Clock skew detected, please check your system time
>2013-03-26 16:24:55,088 [ERROR]  @managercli.py:144 - exception caught in subscription-manager
>2013-03-26 16:24:55,089 [ERROR]  @managercli.py:145 - Consumer 6130b588-b49a-41de-8506-aa06ad7b59af has been deleted
>Traceback (most recent call last):
>  File "/sbin/subscription-manager", line 90, in <module>
>    sys.exit(abs(main() or 0))
>  File "/sbin/subscription-manager", line 81, in main
>    return managercli.ManagerCLI().main()
>  File "/usr/share/rhsm/subscription_manager/managercli.py", line 2168, in main
>    return CLI.main(self)
>  File "/usr/share/rhsm/subscription_manager/cli.py", line 160, in main
>    return cmd.main()
>  File "/usr/share/rhsm/subscription_manager/managercli.py", line 473, in main
>    return_code = self._do_command()
>  File "/usr/share/rhsm/subscription_manager/managercli.py", line 2015, in _do_command
>    self.entitlement_dir, self.cp)
>  File "/usr/share/rhsm/subscription_manager/managerlib.py", line 89, in getInstalledProductStatus
>    calculator = FEATURES.require(PRODUCT_DATE_RANGE_CALCULATOR, uep)
>  File "/usr/share/rhsm/subscription_manager/injection.py", line 62, in require
>    return provider(*args, **kwargs)
>  File "/usr/share/rhsm/subscription_manager/validity.py", line 30, in __init__
>    self.consumer_data = self.uep.getConsumer(self.identity.uuid)
>  File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 731, in getConsumer
>    return self.conn.request_get(method)
>  File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 481, in request_get
>    return self._request("GET", method)
>  File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 443, in _request
>    self.validateResponse(result)
>  File "/usr/lib64/python2.7/site-packages/rhsm/connection.py", line 465, in validateResponse
>    parsed['displayMessage'], parsed['deletedId'])
>

Actions: View

Attachments on bug 928175: 716874