[Spacewalk-list] Updates not happening - EL5 client - 0.7

[Tom Brown]

Hi

I have my clients subscribed to an el5 updates channel and packages are 
showing as requiring an update - I set these to update from within 
spacewalk and then run rhn_check on the client.

Seemingly recently this has stopped working or is inconsistent  in that 
spacewalk tells me that the update was actioned but the packages are not 
being updated and the systems are still showing as needing an update in 
spacewalk - Here is the output from rhn_check, can anyone spot anything 
that might be amiss or where to look for the issue?

thanks

# rhn_check -vvv
D: opening  db environment /var/lib/rpm/Packages joinenv
D: opening  db index       /var/lib/rpm/Packages rdonly mode=0x0
D: locked   db index       /var/lib/rpm/Packages
D: opening  db index       /var/lib/rpm/Providename rdonly mode=0x0
D: check_action {'action': "<?xml 
version='1.0'?>\n<methodCall>\n<methodName>packages.update</methodName>\n<params>\n<param>\n<value><array><data>\n<value><array><data>\n<value><string>dbus</string></value>\n<value><string>1.1.2</string></value>\n<value><string>12.el5_4.1</string></value>\n<value><string></string></value>\n<value><string>x86_64</string></value>\n</data></array></value>\n<value><array><data>\n<value><string>dbus-libs</string></value>\n<value><string>1.1.2</string></value>\n<value><string>12.el5_4.1</string></value>\n<value><string></string></value>\n<value><string>x86_64</string></value>\n</data></array></value>\n<value><array><data>\n<value><string>kernel-xen</string></value>\n<value><string>2.6.18</string></value>\n<value><string>164.10.1.el5</string></value>\n<value><string></string></value>\n<value><string>x86_64</string></value>\n</data></array></value>\n<value><array><data>\n<value><string>tzdata</string></value>\n<value><string>2009u</string></value>\n<value><string>1.el5</string></value>\n<value><string></string></value>\n<value><string>noarch</string></value>\n</data></array></value>\n</data></array></value>\n</param>\n</params>\n</methodCall>\n", 
'version': 2, 'id': 104}
updateLoginInfo() login info
D: login(forceUpdate=True) invoked
logging into up2date server
D: writeCachedLogin() invoked
D: Wrote pickled loginInfo at  1262953325.43  with expiration of  
1262956925.43  seconds.
successfully retrieved authentication token from up2date server
D: logininfo: {'X-RHN-Server-Id': 1000010017, 'X-RHN-Auth-Server-Time': 
'1262953325.42', 'X-RHN-Auth': 'q0o3JPKg6Ynqw4IciFMlTw==', 
'X-RHN-Auth-Channels': [['rhel5-server-updates', '20100108103006', '1', 
'1']], 'X-RHN-Auth-User-Id': '', 'X-RHN-Auth-Expire-Offset': '3600.0'}
D: handle_action {'action': "<?xml 
version='1.0'?>\n<methodCall>\n<methodName>packages.update</methodName>\n<params>\n<param>\n<value><array><data>\n<value><array><data>\n<value><string>dbus</string></value>\n<value><string>1.1.2</string></value>\n<value><string>12.el5_4.1</string></value>\n<value><string></string></value>\n<value><string>x86_64</string></value>\n</data></array></value>\n<value><array><data>\n<value><string>dbus-libs</string></value>\n<value><string>1.1.2</string></value>\n<value><string>12.el5_4.1</string></value>\n<value><string></string></value>\n<value><string>x86_64</string></value>\n</data></array></value>\n<value><array><data>\n<value><string>kernel-xen</string></value>\n<value><string>2.6.18</string></value>\n<value><string>164.10.1.el5</string></value>\n<value><string></string></value>\n<value><string>x86_64</string></value>\n</data></array></value>\n<value><array><data>\n<value><string>tzdata</string></value>\n<value><string>2009u</string></value>\n<value><string>1.el5</string></value>\n<value><string></string></value>\n<value><string>noarch</string></value>\n</data></array></value>\n</data></array></value>\n</param>\n</params>\n</methodCall>\n", 
'version': 2, 'id': 104}
D: handle_action actionid = 104, version = 2
D: do_call packages.update ([['dbus', '1.1.2', '12.el5_4.1', '', 
'x86_64'], ['dbus-libs', '1.1.2', '12.el5_4.1', '', 'x86_64'], 
['kernel-xen', '2.6.18', '164.10.1.el5', '', 'x86_64'], ['tzdata', 
'2009u', '1.el5', '', 'noarch']],)
Loaded plugins: fastestmirror, rhnplugin
D: opening  db environment /var/lib/rpm/Packages joinenv
D: opening  db index       /var/lib/rpm/Packages rdonly mode=0x0
D: opening  db index       /var/lib/rpm/Providename rdonly mode=0x0
D: closed   db index       /var/lib/rpm/Providename
D: closed   db index       /var/lib/rpm/Packages
D: closed   db environment /var/lib/rpm/Packages
D: May free Score board((nil))
Loading mirror speeds from cached hostfile
Loading mirror speeds from cached hostfile
D: Called update [['dbus', '1.1.2', '12.el5_4.1', '', 'x86_64'], 
['dbus-libs', '1.1.2', '12.el5_4.1', '', 'x86_64'], ['kernel-xen', 
'2.6.18', '164.10.1.el5', '', 'x86_64'], ['tzdata', '2009u', '1.el5', 
'', 'noarch']]
D: opening  db environment /var/lib/rpm/Packages joinenv
D: opening  db index       /var/lib/rpm/Packages rdonly mode=0x0
D: opening  db index       /var/lib/rpm/Name rdonly mode=0x0
D:  read h#     309 Header sanity check: OK
D:  read h#     302 Header sanity check: OK
D:  read h#     291 Header sanity check: OK
D:  read h#     493 Header sanity check: OK
D:  read h#     503 Header sanity check: OK
D:  read h#     347 Header sanity check: OK
D: opening  db index       /var/lib/rpm/Basenames rdonly mode=0x0
D: closed   db index       /var/lib/rpm/Basenames
D: closed   db index       /var/lib/rpm/Name
D: closed   db index       /var/lib/rpm/Packages
D: closed   db environment /var/lib/rpm/Packages
D: May free Score board((nil))
D: Dependencies Resolved
D: Downloading Packages:
D: Running Transaction Test
D: May free Score board((nil))
D: Finished Transaction Test
D: Transaction Test Succeeded
D: May free Score board((nil))
D: opening  db environment /var/lib/rpm/Packages joinenv
D: opening  db index       /var/lib/rpm/Packages rdonly mode=0x0
D: closed   db index       /var/lib/rpm/Packages
D: closed   db environment /var/lib/rpm/Packages
D: ========== recording tsort relations
D: ========== tsorting packages (order, #predecessors, #succesors, tree, 
depth, breadth)
D: Running Transaction
Updating package profile
D: May free Score board((nil))
D: Sending back response (0, 'Update Succeeded', {})
D: do_call packages.checkNeedUpdate ('rhnsd=1',)
D: Called refresh_rpmlist
Updating package profile
D: local action status:  (0, 'rpmlist refreshed', {})
D: closed   db index       /var/lib/rpm/Providename
D: closed   db index       /var/lib/rpm/Packages
D: closed   db environment /var/lib/rpm/Packages
D: May free Score board((nil))