Description of problem: I started a repo sync for around 18 repos. Sync was running from last 40+ hours and due to some reason sync finished with 'Unknown' state. I didn't change the configuration for sync frequency. The default freq was set as 6 hours. System date was correct this time. ------------------------------------------------------------------------------ -= Red Hat Update Infrastructure Management Tool =- -= Repository Synchronization Status =- Last Refreshed: 14:20:49 (updated every 5 seconds, ctrl+c to exit) Next Sync Last Sync Last Result ------------------------------------------------------------------------------ Red Hat Enterprise Linux Server 5 (RPMs) (5.6-i386) Unknown 10-20-2011 20:07 Success Red Hat Enterprise Linux Server 5 (RPMs) (5.6-x86_64) Unknown 10-20-2011 20:14 Success Red Hat Enterprise Linux Server 5 (RPMs) (5.7-i386) Unknown 10-20-2011 20:07 Success Red Hat Enterprise Linux Server 5 (RPMs) (5.7-x86_64) Unknown 10-20-2011 20:15 Success Red Hat Enterprise Linux Server 5 (RPMs) (5Server-i386) Unknown 10-20-2011 20:39 Success Red Hat Enterprise Linux Server 5 (RPMs) (5Server-x86_64) Unknown 10-21-2011 03:15 Success Red Hat Enterprise Linux Server 6 (RPMs) (6.0-i386) Unknown 10-21-2011 01:15 Success Red Hat Enterprise Linux Server 6 (RPMs) (6.0-x86_64) Unknown 10-21-2011 01:11 Success Red Hat Enterprise Linux Server 6 (RPMs) (6.1-i386) Unknown 10-21-2011 04:54 Success Red Hat Enterprise Linux Server 6 (RPMs) (6.1-x86_64) Unknown 10-21-2011 05:18 Success Red Hat Enterprise Linux Server 6 (RPMs) (6Server-i386) Unknown 10-21-2011 02:43 Success Red Hat Enterprise Linux Server 6 (RPMs) (6Server-x86_64) Unknown 10-21-2011 03:04 Success Red Hat Enterprise Linux Server 6 Optional (RPMs) (6.0-i386) Unknown 10-21-2011 00:18 Success Red Hat Enterprise Linux Server 6 Optional (RPMs) (6.0-x86_64) Unknown 10-21-2011 00:39 Success Red Hat Enterprise Linux Server 6 Optional (RPMs) (6.1-i386) Unknown 10-21-2011 01:03 Success Red Hat Enterprise Linux Server 6 Optional (RPMs) (6.1-x86_64) Unknown 10-21-2011 01:01 Success Red Hat Enterprise Linux Server 6 Optional (RPMs) (6Server-i386) Unknown 10-21-2011 00:47 Success Red Hat Enterprise Linux Server 6 Optional (RPMs) (6Server-x86_64) Unknown 10-21-2011 01:01 Success Connected: dhcp201-152.englab.pnq.redhat.com ------------------------------------------------------------------------------ ssl_error_logs: ============== [root@dhcp201-152 ~]# tail -f /var/log/httpd/ssl_error_log [Fri Oct 21 02:51:33 2011] [error] [client 10.65.201.152] Premature end of script headers: webservices.wsgi /var/log/httpd/error_log: ============ [Fri Oct 21 02:52:00 2011] [error] import sha [Fri Oct 21 02:52:13 2011] [error] mod_wsgi (pid=14335): Target WSGI script '/srv/pulp/webservices.wsgi' cannot be loaded as Python module. [Fri Oct 21 02:52:13 2011] [error] mod_wsgi (pid=14335): Exception occurred processing WSGI script '/srv/pulp/webservices.wsgi'. [Fri Oct 21 02:52:13 2011] [error] Traceback (most recent call last): [Fri Oct 21 02:52:13 2011] [error] File "/srv/pulp/webservices.wsgi", line 20, in <module> [Fri Oct 21 02:52:13 2011] [error] application = wsgi_application() [Fri Oct 21 02:52:13 2011] [error] File "/usr/lib/python2.6/site-packages/pulp/server/webservices/application.py", line 198, in wsgi_application [Fri Oct 21 02:52:13 2011] [error] _initialize_pulp() [Fri Oct 21 02:52:13 2011] [error] File "/usr/lib/python2.6/site-packages/pulp/server/webservices/application.py", line 186, in _initialize_pulp [Fri Oct 21 02:52:13 2011] [error] _update_sync_schedules() [Fri Oct 21 02:52:13 2011] [error] File "/usr/lib/python2.6/site-packages/pulp/server/webservices/application.py", line 130, in _update_sync_schedules [Fri Oct 21 02:52:13 2011] [error] param = _sync_schedule_param(_repo["sync_schedule"], repo_sync_freq_iso) [Fri Oct 21 02:52:13 2011] [error] File "/usr/lib/python2.6/site-packages/pulp/server/webservices/application.py", line 117, in _sync_schedule_param [Fri Oct 21 02:52:13 2011] [error] interval, start, runs = parse_iso8601_interval(sync_schedule) [Fri Oct 21 02:52:13 2011] [error] File "/usr/lib/python2.6/site-packages/pulp/common/dateutils.py", line 195, in parse_iso8601_interval [Fri Oct 21 02:52:13 2011] [error] for part in _iso8601_delimiter.split(interval_str): [Fri Oct 21 02:52:13 2011] [error] TypeError: expected string or buffer Traceback (most recent call last): File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 499, in <module> main() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 496, in main process() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 454, in process sys.stdout.flush() IOError: [Errno 32] Broken pipe Traceback (most recent call last): File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 499, in <module> main() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 496, in main process() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 454, in process sys.stdout.flush() IOError: [Errno 32] Broken pipe Traceback (most recent call last): File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 499, in <module> main() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 496, in main process() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 454, in process sys.stdout.flush() IOError: [Errno 32] Broken pipe Traceback (most recent call last): Traceback (most recent call last): File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 499, in <module> main() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 496, in main process() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 454, in process sys.stdout.flush() IOError: [Errno 32] Broken pipe File "/usr/lib/python2.6/site-packages/grinder/WriteFunction.py", line 48, in callback LOG.debug("processing chunk %s" % len(chunk)) File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 369, in __call__ self.__send(msg, args) File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 421, in __send sys.stdout.flush() IOError: [Errno 32] Broken pipe Traceback (most recent call last): File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 499, in <module> main() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 496, in main process() File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 454, in process sys.stdout.flush() IOError: [Errno 32] Broken pipe [root@dhcp201-152 ~]# Version-Release number of selected component (if applicable): RHEL-6.1-RHUI-2.0.1-20111017.0-Server-x86_64-DVD1.iso How reproducible: Steps to Reproduce: 1. 2. 3. Actual results: Next sync showing 'Unknown' Expected results: Sync should finish without any error. Additional info:
Created attachment 529456 [details] pulp.logs are attached.
Created attachment 529457 [details] rhui.log attached
This was caused by a custom repo, which has no sync schedule. I added a check in the code to account for this. commit 4bfc6423ca1c1eba13d22db2b8a7789f2c64308f Author: James Slagle <jslagle> Date: Fri Oct 21 10:32:41 2011 -0400 747880 Allow for custom repos to have no sync schedule
To verify, change the sync schedule again in the tools config file and restart the pulp-server service. The repos should then be showing the new schedule in rhui-manager.
Connected: dhcp201-133.englab.pnq.redhat.com ------------------------------------------------------------------------------ rhui (home) => s ------------------------------------------------------------------------------ -= Red Hat Update Infrastructure Management Tool =- -= Synchronization Status =- dr display repo sync summary vr view the details of the last repository sync sr sync an individual repository immediately dc display CDS sync summary vc view the details of the last CDS sync sc sync an individual CDS immediately Connected: dhcp201-133.englab.pnq.redhat.com ------------------------------------------------------------------------------ rhui (sync) => dr ------------------------------------------------------------------------------ -= Red Hat Update Infrastructure Management Tool =- -= Repository Synchronization Status =- Last Refreshed: 17:28:37 (updated every 5 seconds, ctrl+c to exit) Next Sync Last Sync Last Result ------------------------------------------------------------------------------ Red Hat Enterprise Linux Server 6 Optional (RPMs) (6.1-i386) 10-26-2011 17:39 10-21-2011 21:41 Success Red Hat Enterprise Linux Server 6 Optional (RPMs) (6Server-x86_64) 10-26-2011 19:00 10-21-2011 22:23 Success Red Hat Update Infrastructure 2.0 (RPMs) (6.0-i386) 10-26-2011 19:26 10-26-2011 17:27 Success Red Hat Update Infrastructure 2.0 (RPMs) (6.0-x86_64) 10-26-2011 19:26 10-26-2011 17:27 Success Red Hat Update Infrastructure 2.0 (RPMs) (6.1-i386) 10-26-2011 19:26 10-26-2011 17:27 Success Red Hat Update Infrastructure 2.0 (RPMs) (6.1-x86_64) 10-26-2011 19:26 10-26-2011 17:27 Success Red Hat Update Infrastructure 2.0 (RPMs) (6Server-i386) 10-26-2011 19:26 10-26-2011 17:27 Success Red Hat Update Infrastructure 2.0 (RPMs) (6Server-x86_64) 10-26-2011 19:26 10-26-2011 17:27 Success Connected: dhcp201-133.englab.pnq.redhat.com
[root@dhcp201-133 ~]# rpm -qav | grep -ie rhui -ie pulp rh-rhel6optional-rhui12-2.7-1.el6.noarch pulp-0.0.214-7.el6.noarch pulp-common-0.0.214-7.el6.noarch rh-rhui-tools-2.0.44-1.el6.noarch pulp-client-0.0.214-7.el6.noarch
Since the problem described in this bug report should be resolved in a recent advisory, it has been closed with a resolution of ERRATA. For information on the advisory, and where to find the updated files, follow the link below. If the solution does not work for you, open a new bug report. https://access.redhat.com/errata/RHBA-2017:0367