Bug 969991

Summary: Getting Traceback, takes lot of itme with pulp-admin install
Product: [Retired] Pulp Reporter: Dhaval <dhaval.joshi>
Component: consumersAssignee: pulp-bugs
Status: CLOSED UPSTREAM QA Contact: Preethi Thomas <pthomas>
Severity: medium Docs Contact:
Priority: medium    
Version: 2.1.1CC: rbarlow, skarmark
Target Milestone: ---Keywords: Triaged
Target Release: ---   
Hardware: x86_64   
OS: Linux   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2015-02-28 21:56:20 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Attachments:
Description Flags
server_log none

Description Dhaval 2013-06-03 09:26:27 UTC
Created attachment 756208 [details]
server_log

Description of problem:

2013-06-03 10:17:36,595 - ERROR - Client-side exception occurred
Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/pulp/client/extensions/core.py", line 478, in run
    exit_code = Cli.run(self, args)
  File "/usr/lib/python2.6/site-packages/okaara/cli.py", line 966, in run
    exit_code = command_or_section.execute(self.prompt, remaining_args)
  File "/usr/lib/python2.6/site-packages/pulp/client/extensions/extensions.py", line 224, in execute
    return self.method(*arg_list, **clean_kwargs)
  File "/usr/lib/python2.6/site-packages/pulp/client/commands/consumer/content.py", line 345, in run
    self.process(consumer_id, task)
  File "/usr/lib/python2.6/site-packages/pulp/client/commands/polling.py", line 67, in process
    return self.failed(resource_id, task)
  File "/usr/lib/pulp/admin/extensions/rpm_admin_consumer/package.py", line 329, in failed
    details = task.result['details'][TYPE_ID_RPM]['details']
TypeError: 'NoneType' object is unsubscriptable


Version-Release number of selected component (if applicable):


How reproducible: 

Steps to Reproduce:
1. on HOST1 try to install 300MB package, it takes lot of time in downloading

2. same time on second host ( HOST2)  try to install another package, it gives some errors
3.

Actual results:

pulp.log

2013-06-03 10:17:25,561 gofer.rmi.policy:INFO: sent (host2):
{'classname': 'Content', 'kws': {}, 'args': ([{'unit_key': {'name': 'sunjdk1502264'}, 'type_id': 'rpm'}], {'apply': True, 'reboot': False}), 'method': 'uninstall', 'cntr': None}
2013-06-03 10:17:25,562 gofer.rmi.async:INFO: tracking: {'replyto': 'pulp.task;{create:always,node:{type:queue,durable:True,},link:{durable:True,reliability:at-least-once,x-subscribe:{exclusive:True}}}', 'ts': (1370251055.561842, 1370261845.561842), 'any': 'f0980aac-6d93-472a-b63a-784547828a4b', 'idx': 0, 'sn': '2bd6aa45-0396-4586-bd1e-be80dfb66efe'}
2013-06-03 10:17:36,091 gofer.rmi.async:INFO: sn:2bd6aa45-0396-4586-bd1e-be80dfb66efe timeout detected
2013-06-03 10:17:36,092 gofer.rmi.async:INFO: send (timeout) for sn:2bd6aa45-0396-4586-bd1e-be80dfb66efe to:pulp.task;{create:always,node:{type:queue,durable:True,},link:{durable:True,reliability:at-least-once,x-subscribe:{exclusive:True}}}
2013-06-03 10:17:36,101 gofer.rmi.async:INFO: 2bd6aa45-0396-4586-bd1e-be80dfb66efe
2013-06-03 10:17:36,101 pulp.server.agent.direct.services:INFO: Task RMI (failed)
Failed
  sn : 2bd6aa45-0396-4586-bd1e-be80dfb66efe
  origin : 48015421-35ed-4052-8564-9c8e3d9e8ede
  user data : f0980aac-6d93-472a-b63a-784547828a4b
  exval: ('2bd6aa45-0396-4586-bd1e-be80dfb66efe', 0)
  xmodule: gofer.rmi.policy
  xclass: RequestTimeout
  xstate: {'trace': 'Traceback (most recent call last):\n\n  File "/usr/lib/python2.6/site-packages/gofer/rmi/async.py", line 451, in process\n    raise RequestTimeout(sn, je.idx)\n\nRequestTimeout: (\'2bd6aa45-0396-4586-bd1e-be80dfb66efe\', 0)\n'}
  xargs: ['2bd6aa45-0396-4586-bd1e-be80dfb66efe', 0]
2013-06-03 10:17:36,101 pulp.server.dispatch.task:INFO: FAILURE: Task f0980aac-6d93-472a-b63a-784547828a4b: CallRequest: AgentManager.uninstall_content(u'host2', units=[{'unit_key': {'name': 'sunjdk1502264'}, 'type_id': 'rpm'}], options={'apply': True, 'reboot': False})
----------------------------------------




Expected results:


Additional info:

Comment 1 Dhaval 2013-06-03 10:00:26 UTC
I restarted pulp-agent on Clinet and it worked, not sure why it was not responding ... also it takes lot of time on installation first time..

[root@pulp]# pulp-admin -padmin -uadmin rpm consumer package install run --consumer-id host1 --name nmr-sunjdk1502264
This command may be exited via ctrl+c without affecting the request.

Refresh Repository Metadata             [ OK ]
Downloading Packages
 Downloading: sunjdk1502264-1.5.0_22_64-1.x86_64.rpm | 52 MB

where it tries to download package ? it takes lot of time here ..


[root@pulp ~]# pulp-admin tasks details --task-id 539456f1-cada-460c-9757-1d311882ee6b
+----------------------------------------------------------------------+
                              Task Details
+----------------------------------------------------------------------+

Operations:   unit_install
Resources:    host2(consumer)
State:        Successful
Start Time:   2013-06-03T09:45:17Z
Finish Time:  2013-06-03T09:55:09Z
Result:
  Details:
    Rpm:
      Details:
        Deps:
        Errors:
        Resolved:
          Arch:    x86_64
          Epoch:   0
          Name:    sunjdk1502264
          Qname:   sunjdk1502264-1.5.0_22_64-1.x86_64
          Release: 1
          Repoid:  osbuild
          Version: 1.5.0_22_64
      Succeeded: True
  Num Changes: 1
  Reboot:
    Details:
    Scheduled: False
  Succeeded:   True
Task Id:      539456f1-cada-460c-9757-1d311882ee6b
Progress:
  Details:
  Steps:   [[u'Refresh Repository Metadata', True], [u'Downloading Packages',
           True], [u'Check Package Signatures', True], [u'Running Test
           Transaction', True], [u'Running Transaction', True]]


----------- Second time installation time taken

+----------------------------------------------------------------------+
                                 Tasks
+----------------------------------------------------------------------+

Operations:  unit_install
Resources:   host2(consumer)
State:       Successful
Start Time:  2013-06-03T09:58:49Z
Finish Time: 2013-06-03T09:58:58Z
Result:
  Details:
    Rpm:
      Details:
        Deps:
        Errors:
        Resolved:
          Arch:    x86_64
          Epoch:   0
          Name:    sunjdk1502264
          Qname:   sunjdk1502264-1.5.0_22_64-1.x86_64
          Release: 1
          Repoid:  osbuild
          Version: 1.5.0_22_64
      Succeeded: True
  Num Changes: 1
  Reboot:
    Details:
    Scheduled: False
  Succeeded:   True
Task Id:     df39e342-4e25-4503-81cf-cb93622c48ca

Comment 2 Dhaval 2013-06-03 10:01:20 UTC
I restarted pulp-agent on Clinet and it worked, not sure why it was not responding ... also it takes lot of time on installation first time..

[root@pulp]# pulp-admin -padmin -uadmin rpm consumer package install run --consumer-id host1 --name sunjdk1502264
This command may be exited via ctrl+c without affecting the request.

Refresh Repository Metadata             [ OK ]
Downloading Packages
 Downloading: sunjdk1502264-1.5.0_22_64-1.x86_64.rpm | 52 MB

where it tries to download package ? it takes lot of time here ..


[root@pulp ~]# pulp-admin tasks details --task-id 539456f1-cada-460c-9757-1d311882ee6b
+----------------------------------------------------------------------+
                              Task Details
+----------------------------------------------------------------------+

Operations:   unit_install
Resources:    host2(consumer)
State:        Successful
Start Time:   2013-06-03T09:45:17Z
Finish Time:  2013-06-03T09:55:09Z
Result:
  Details:
    Rpm:
      Details:
        Deps:
        Errors:
        Resolved:
          Arch:    x86_64
          Epoch:   0
          Name:    sunjdk1502264
          Qname:   sunjdk1502264-1.5.0_22_64-1.x86_64
          Release: 1
          Repoid:  osbuild
          Version: 1.5.0_22_64
      Succeeded: True
  Num Changes: 1
  Reboot:
    Details:
    Scheduled: False
  Succeeded:   True
Task Id:      539456f1-cada-460c-9757-1d311882ee6b
Progress:
  Details:
  Steps:   [[u'Refresh Repository Metadata', True], [u'Downloading Packages',
           True], [u'Check Package Signatures', True], [u'Running Test
           Transaction', True], [u'Running Transaction', True]]


----------- Second time installation time taken

+----------------------------------------------------------------------+
                                 Tasks
+----------------------------------------------------------------------+

Operations:  unit_install
Resources:   host2(consumer)
State:       Successful
Start Time:  2013-06-03T09:58:49Z
Finish Time: 2013-06-03T09:58:58Z
Result:
  Details:
    Rpm:
      Details:
        Deps:
        Errors:
        Resolved:
          Arch:    x86_64
          Epoch:   0
          Name:    sunjdk1502264
          Qname:   sunjdk1502264-1.5.0_22_64-1.x86_64
          Release: 1
          Repoid:  osbuild
          Version: 1.5.0_22_64
      Succeeded: True
  Num Changes: 1
  Reboot:
    Details:
    Scheduled: False
  Succeeded:   True
Task Id:     df39e342-4e25-4503-81cf-cb93622c48ca

Comment 3 Brian Bouterse 2015-02-28 21:56:20 UTC
Moved to https://pulp.plan.io/issues/352