Promotion Errors: how to solve?

Hi,

Using CentOS 7.3 Katello 3.2 and Foreman 1.13.4

I'm getting an error while promoting a LCE. It is stuck on Paused. I can
skip the erroneous step, but then the repos are broken.

I'm confused about how to cancel, kill and/or unwind the job once it gets
into this state. I have been skipping, deleting and trying again, but that
seems like an awfully long way around?

When I look in dynaflow it's always this step that fails

85: Actions::Pulp::Repository::CopyYumMetadataFile (skipping) [ 57540.42s /
1.59s ]

I note that the error below has references to Katello 3.2.1.1:

/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:16:in
`block in run'

But I thought I was using Katello 3.2.2 - and, infact, if I look at the
about page I see the inconsistency there too - the plugins screen and the
"installed packages" screen disagree (see attached screenshots attached).
Could this be the issue?

Here is the error:
Action:

Actions::Pulp::Repository::CopyYumMetadataFile

Input:

{"source_pulp_id"=>
"Peter_MacCallum_Cancer_Centre-GAFFA-3_0-_sssd-Copr_repository_for_sssd_1_15",
"target_pulp_id"=>
"Peter_MacCallum_Cancer_Centre-Gaffa-dev-GAFFA-_sssd-Copr_repository_for_sssd_1_15",
"clauses"=>nil,
"remote_user"=>"admin",
"remote_cp_user"=>"admin"}

Output:

{"pulp_tasks"=>
[{"exception"=>nil,
"task_type"=>
"pulp.server.managers.repo.unit_association.associate_from_repo",
"_href"=>"/pulp/api/v2/tasks/2a32001e-ed4d-4c83-9a02-cffd045b8f30/",
"task_id"=>"2a32001e-ed4d-4c83-9a02-cffd045b8f30",
"tags"=>
["pulp:repository:Peter_MacCallum_Cancer_Centre-Gaffa-dev-GAFFA-_sssd-Copr_repository_for_sssd_1_15",
"pulp:repository:Peter_MacCallum_Cancer_Centre-GAFFA-3_0-_sssd-Copr_repository_for_sssd_1_15",
"pulp:action:associate"],
"finish_time"=>"2017-02-20T21:40:42Z",
"_ns"=>"task_status",
"start_time"=>"2017-02-20T21:40:41Z",
"traceback"=>
"Traceback (most recent call last):\n File
"/usr/lib/python2.7/site-packages/celery/app/trace.py", line 240, in
trace_task\n R = retval = fun(*args, **kwargs)\n File
"/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line
484, in call\n return super(Task, self).call(*args,
**kwargs)\n File
"/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line
103, in call\n return super(PulpTask, self).call(*args,
**kwargs)\n File
"/usr/lib/python2.7/site-packages/celery/app/trace.py", line 437, in
protected_call\n return self.run(*args, **kwargs)\n File
"/usr/lib/python2.7/site-packages/pulp/server/managers/repo/unit_association.py",
line 273, in associate_from_repo\n units=transfer_units)\n File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/importer.py",
line 58, in import_units\n return associate.associate(source_repo,
dest_repo, import_conduit, config, units)\n File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 53, in associate\n associated_units =
set([_associate_unit(dest_repo, unit) for unit in units])\n File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 339, in _associate_unit\n return associate_copy_for_repo(unit,
dest_repo, True)\n File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 379, in associate_copy_for_repo\n
new_unit.safe_import_content(unit._storage_path)\n File
"/usr/lib/python2.7/site-packages/pulp/server/db/model/init.py",
line 905, in safe_import_content\n self.import_content(path,
location)\n File
"/usr/lib/python2.7/site-packages/pulp/server/db/model/init.py",
line 879, in import_content\n storage.put(self, path, location)\n
File "/usr/lib/python2.7/site-packages/pulp/server/content/storage.py",
line 123, in put\n mkdir(os.path.dirname(destination))\n File
"/usr/lib/python2.7/site-packages/pulp/server/content/storage.py",
line 20, in mkdir\n os.makedirs(path)\n File
"/usr/lib64/python2.7/os.py", line 157, in makedirs\n mkdir(name,
mode)\nPulpExecutionException: Pulp exception occurred:
PulpExecutionException\n",
"spawned_tasks"=>[],
"progress_report"=>{},
"queue"=>
"reserved_resource_worker-2@vmpr-res-utils.unix.petermac.org.au.dq",
"state"=>"error",
"worker_name"=>
"reserved_resource_worker-2@vmpr-res-utils.unix.petermac.org.au",
"error"=>
{"code"=>"PLP0000",
"data"=>{},
"description"=>"Pulp exception occurred: PulpExecutionException",
"sub_errors"=>[]},
"_id"=>{"$oid"=>"58ab62595ddf3db34f386add"},
"id"=>"58ab62595ddf3db34f386add"}],
"poll_attempts"=>{"total"=>3, "failed"=>3}}

Exception:

Katello::Errors::PulpError: PLP0000: Pulp exception occurred:
PulpExecutionException

Backtrace:

/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/abstract_async_task.rb:121:in
block in external_task=' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/abstract_async_task.rb:119:ineach'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/abstract_async_task.rb:119:in
external_task=' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/repository/abstract_copy_content.rb:40:inexternal_task='
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action/polling.rb:98:in
poll_external_task_with_rescue' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action/polling.rb:21:inrun'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action/cancellable.rb:9:in
run' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/abstract_async_task.rb:45:inrun'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:506:in
block (3 levels) in execute_run' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:incall'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:17:inpass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:30:in
run' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:22:incall'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:17:inpass'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:16:in
block in run' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:40:inblock in as_remote_user'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/models/katello/concerns/user_extensions.rb:21:in
cp_config' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:27:inas_cp_user'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:39:in
as_remote_user' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:16:inrun'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:22:in
call' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:inpass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:17:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action/progress.rb:30:inwith_progress_calculation'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action/progress.rb:16:in
run' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:22:incall'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:17:inpass'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/keep_locale.rb:11:in
block in run' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/keep_locale.rb:22:inwith_locale'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/keep_locale.rb:11:in
run' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:22:incall'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:17:inpass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:30:in
run' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:22:incall'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/world.rb:30:in
execute' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:505:inblock (2 levels) in execute_run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:504:in
catch' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:504:inblock in execute_run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:419:in
call' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:419:inblock in with_error_handling'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:419:in
catch' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:419:inwith_error_handling'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:499:in
execute_run' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:260:inexecute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/execution_plan/steps/abstract_flow_step.rb:9:in
block (2 levels) in execute' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/execution_plan/steps/abstract.rb:155:incall'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/execution_plan/steps/abstract.rb:155:in
with_meta_calculation' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/execution_plan/steps/abstract_flow_step.rb:8:inblock in execute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/execution_plan/steps/abstract_flow_step.rb:22:in
open_action' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/execution_plan/steps/abstract_flow_step.rb:7:inexecute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/executors/parallel/worker.rb:15:in
block in on_message' /opt/theforeman/tfm/root/usr/share/gems/gems/algebrick-0.7.3/lib/algebrick/matchers/abstract.rb:74:inblock in assigns'
/opt/theforeman/tfm/root/usr/share/gems/gems/algebrick-0.7.3/lib/algebrick/matchers/abstract.rb:73:in
tap' /opt/theforeman/tfm/root/usr/share/gems/gems/algebrick-0.7.3/lib/algebrick/matchers/abstract.rb:73:inassigns'
/opt/theforeman/tfm/root/usr/share/gems/gems/algebrick-0.7.3/lib/algebrick/matching.rb:56:in
match_value' /opt/theforeman/tfm/root/usr/share/gems/gems/algebrick-0.7.3/lib/algebrick/matching.rb:36:inblock in match?'
/opt/theforeman/tfm/root/usr/share/gems/gems/algebrick-0.7.3/lib/algebrick/matching.rb:35:in
each' /opt/theforeman/tfm/root/usr/share/gems/gems/algebrick-0.7.3/lib/algebrick/matching.rb:35:inmatch?'
/opt/theforeman/tfm/root/usr/share/gems/gems/algebrick-0.7.3/lib/algebrick/matching.rb:23:in
match' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/executors/parallel/worker.rb:12:inon_message'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/context.rb:46:in
on_envelope' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/executes_context.rb:7:inon_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/abstract.rb:25:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/actor.rb:26:inon_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/abstract.rb:25:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/awaits.rb:15:inon_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/abstract.rb:25:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/sets_results.rb:14:inon_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/abstract.rb:25:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/buffer.rb:38:inprocess_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/buffer.rb:31:in
process_envelopes?' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/buffer.rb:20:inon_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/abstract.rb:25:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/termination.rb:55:inon_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/abstract.rb:25:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/removes_child.rb:10:inon_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/abstract.rb:25:in
pass' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/behaviour/sets_results.rb:14:inon_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/core.rb:161:in
process_envelope' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/core.rb:95:inblock in on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/core.rb:118:in
block (2 levels) in schedule_execution' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/synchronization/mri_lockable_object.rb:38:inblock in synchronize'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/synchronization/mri_lockable_object.rb:38:in
synchronize' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/synchronization/mri_lockable_object.rb:38:insynchronize'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.0/lib/concurrent/actor/core.rb:115:in
block in schedule_execution' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/serialized_execution.rb:18:incall'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/serialized_execution.rb:18:in
call' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/serialized_execution.rb:96:inwork'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/serialized_execution.rb:77:in
block in call_job' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/ruby_thread_pool_executor.rb:333:incall'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/ruby_thread_pool_executor.rb:333:in
run_task' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/ruby_thread_pool_executor.rb:322:inblock (3 levels) in create_worker'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/ruby_thread_pool_executor.rb:305:in
loop' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/ruby_thread_pool_executor.rb:305:inblock (2 levels) in create_worker'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/ruby_thread_pool_executor.rb:304:in
catch' /opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-1.0.0/lib/concurrent/executor/ruby_thread_pool_executor.rb:304:inblock in create_worker'
/opt/theforeman/tfm/root/usr/share/gems/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:in
call' /opt/theforeman/tfm/root/usr/share/gems/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:inblock in create_with_logging_context'

··· ------ The most dangerous phrase in the language is, "We've alwa

Note that the page it sends me to for the error is only available for
RedHat subs

Here is the relevant error log from /var/log/messages

Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) Exception
from importer [yum_importer] while importing units into repository
[Peter_MacCallum_Cancer_Centre-Gaffa-dev-GAFFA-_sssd-Copr_repository_for
_sssd_1_15]
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) Traceback
(most recent call last):
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/managers/repo/unit_association.py",
line 273, in associate_from_repo
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400)
units=transfer_units)
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/importer.py",
line 58, in import_units
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) return
associate.associate(source_repo, dest_repo, import_conduit, config, units)
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 53, in associate
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400)
associated_units = set([_associate_unit(dest_repo, unit) for unit in units])
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 339, in _associate_unit
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) return
associate_copy_for_repo(unit, dest_repo, True)
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 379, in associate_copy_for_repo
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400)
new_unit.safe_import_content(unit._storage_path)
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/db/model/init.py", line
905, in safe_import_content
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400)
self.import_content(path, location)
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/db/model/init.py", line
879, in import_content
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400)
storage.put(self, path, location)
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/content/storage.py", line
123, in put
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400)
mkdir(os.path.dirname(destination))
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/content/storage.py", line 20,
in mkdir
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400)
os.makedirs(path)
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) File
"/usr/lib64/python2.7/os.py", line 157, in makedirs
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400)
mkdir(name, mode)
Feb 21 09:56:39 vmpr-res-utils pulp:
pulp.server.managers.repo.unit_association:ERROR: (26559-74400) OSError:
[Errno 13] Permission denied:
'/var/lib/pulp/content/units/yum_repo_metadata_file/58/4ac97de57ae21338b1cdeb70281a8dac9181fabf2b195dc6429c93c
c0562df'
Feb 21 09:56:39 vmpr-res-utils pulp: pulp.server.async.tasks:INFO: Task
failed : [0e040447-76a1-4e4f-a94e-e7d37dc844d5]
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.strategy:INFO: Received
task:
pulp.server.managers.repo.unit_association.associate_from_repo[4b98df35-a913-4de1-bcff-f775a6ee6681]
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.strategy:INFO: Received
task:
pulp.server.async.tasks._release_resource[540d5a16-a554-45e9-aa0e-8aef9e09a6f8]
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR: (26293-74400)
Task
pulp.server.managers.repo.unit_association.associate_from_repo[0e040447-76a1-4e4f-a94e-e7d37dc844d5]
raised unexpected: PulpExecutionException()
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR: (26293-74400)
Traceback (most recent call last):
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/celery/app/trace.py", line 240, in
trace_task
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) R = retval = fun(*args, **kwargs)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line 484, in
call
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) return super(Task, self).call(*args, **kwargs)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line 103, in
call
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) return super(PulpTask, self).call(*args, **kwargs)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/celery/app/trace.py", line 437, in
protected_call
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) return self.run(*args, **kwargs)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/managers/repo/unit_association.py",
line 273, in associate_from_repo
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) units=transfer_units)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/importer.py",
line 58, in import_units
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) return associate.associate(source_repo, dest_repo,
import_conduit, config, units)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 53, in associate
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) associated_units = set([_associate_unit(dest_repo, unit)
for unit in units])
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 339, in _associate_unit
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) return associate_copy_for_repo(unit, dest_repo, True)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py",
line 379, in associate_copy_for_repo
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) new_unit.safe_import_content(unit._storage_path)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/db/model/init.py", line
905, in safe_import_content
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) self.import_content(path, location)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/db/model/init.py", line
879, in import_content
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) storage.put(self, path, location)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/content/storage.py", line
123, in put
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) mkdir(os.path.dirname(destination))
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File
"/usr/lib/python2.7/site-packages/pulp/server/content/storage.py", line 20,
in mkdir
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) os.makedirs(path)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) File "/usr/lib64/python2.7/os.py", line 157, in makedirs
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR:
(26293-74400) mkdir(name, mode)
Feb 21 09:56:39 vmpr-res-utils pulp: celery.worker.job:ERROR: (26293-74400)
PulpExecutionException: Pulp exception occurred: PulpExecutionException

··· ------ The most dangerous phrase in the language is, "We've always done it this way."
  • Grace Hopper

On 21 February 2017 at 08:56, Lachlan Musicman datakid@gmail.com wrote:

Hi,

Using CentOS 7.3 Katello 3.2 and Foreman 1.13.4

I’m getting an error while promoting a LCE. It is stuck on Paused. I can
skip the erroneous step, but then the repos are broken.

I’m confused about how to cancel, kill and/or unwind the job once it gets
into this state. I have been skipping, deleting and trying again, but that
seems like an awfully long way around?

When I look in dynaflow it’s always this step that fails

85: Actions::Pulp::Repository::CopyYumMetadataFile (skipping) [ 57540.42s
/ 1.59s ]

I note that the error below has references to Katello 3.2.1.1:

/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:16:in `block in run’

But I thought I was using Katello 3.2.2 - and, infact, if I look at the
about page I see the inconsistency there too - the plugins screen and the
“installed packages” screen disagree (see attached screenshots attached).
Could this be the issue?

Here is the error:
Action:

Actions::Pulp::Repository::CopyYumMetadataFile

Input:

{“source_pulp_id”=>
“Peter_MacCallum_Cancer_Centre-GAFFA-3_0-_sssd-Copr_repository_for_sssd_1_15”,
“target_pulp_id”=>
“Peter_MacCallum_Cancer_Centre-Gaffa-dev-GAFFA-_sssd-Copr_repository_for_sssd_1_15”,
“clauses”=>nil,
“remote_user”=>“admin”,
“remote_cp_user”=>“admin”}

Output:

{“pulp_tasks”=>
[{“exception”=>nil,
“task_type”=>
“pulp.server.managers.repo.unit_association.associate_from_repo”,
“_href”=>“/pulp/api/v2/tasks/2a32001e-ed4d-4c83-9a02-cffd045b8f30/”,
“task_id”=>“2a32001e-ed4d-4c83-9a02-cffd045b8f30”,
“tags”=>
[“pulp:repository:Peter_MacCallum_Cancer_Centre-Gaffa-dev-GAFFA-_sssd-Copr_repository_for_sssd_1_15”,
“pulp:repository:Peter_MacCallum_Cancer_Centre-GAFFA-3_0-_sssd-Copr_repository_for_sssd_1_15”,
“pulp:action:associate”],
“finish_time”=>“2017-02-20T21:40:42Z”,
“_ns”=>“task_status”,
“start_time”=>“2017-02-20T21:40:41Z”,
“traceback”=>
“Traceback (most recent call last):\n File "/usr/lib/python2.7/site-packages/celery/app/trace.py", line 240, in trace_task\n R = retval = fun(*args, **kwargs)\n File "/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line 484, in call\n return super(Task, self).call(*args, **kwargs)\n File "/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line 103, in call\n return super(PulpTask, self).call(*args, **kwargs)\n File "/usr/lib/python2.7/site-packages/celery/app/trace.py", line 437, in protected_call\n return self.run(*args, **kwargs)\n File "/usr/lib/python2.7/site-packages/pulp/server/managers/repo/unit_association.py", line 273, in associate_from_repo\n units=transfer_units)\n File "/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/importer.py", line 58, in import_units\n return associate.associate(source_repo, dest_repo, import_conduit, config, units)\n File "/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py", line 53, in associate\n associated_units = set([_associate_unit(dest_repo, unit) for unit in units])\n File "/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py", line 339, in _associate_unit\n return associate_copy_for_repo(unit, dest_repo, True)\n File "/usr/lib/python2.7/site-packages/pulp_rpm/plugins/importers/yum/associate.py", line 379, in associate_copy_for_repo\n new_unit.safe_import_content(unit._storage_path)\n File "/usr/lib/python2.7/site-packages/pulp/server/db/model/init.py", line 905, in safe_import_content\n self.import_content(path, location)\n File "/usr/lib/python2.7/site-packages/pulp/server/db/model/init.py", line 879, in import_content\n storage.put(self, path, location)\n File "/usr/lib/python2.7/site-packages/pulp/server/content/storage.py", line 123, in put\n mkdir(os.path.dirname(destination))\n File "/usr/lib/python2.7/site-packages/pulp/server/content/storage.py", line 20, in mkdir\n os.makedirs(path)\n File "/usr/lib64/python2.7/os.py", line 157, in makedirs\n mkdir(name, mode)\nPulpExecutionException: Pulp exception occurred: PulpExecutionException\n”,
“spawned_tasks”=>,
“progress_report”=>{},
“queue”=>
“reserved_resource_worker-2@vmpr-res-utils.unix.petermac.org.au.dq”,
“state”=>“error”,
“worker_name”=>
“reserved_resource_worker-2@vmpr-res-utils.unix.petermac.org.au”,
“error”=>
{“code”=>“PLP0000”,
“data”=>{},
“description”=>“Pulp exception occurred: PulpExecutionException”,
“sub_errors”=>},
“_id”=>{“$oid”=>“58ab62595ddf3db34f386add”},
“id”=>“58ab62595ddf3db34f386add”}],
“poll_attempts”=>{“total”=>3, “failed”=>3}}

Exception:

Katello::Errors::PulpError: PLP0000: Pulp exception occurred: PulpExecutionException

Backtrace:

/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/abstract_async_task.rb:121:in block in external_task=' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/abstract_async_task.rb:119:in each’
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/abstract_async_task.rb:119:in external_task=' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/repository/abstract_copy_content.rb:40:in external_task=’
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action/polling.rb:98:in poll_external_task_with_rescue' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action/polling.rb:21:in run’
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action/cancellable.rb:9:in run' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/pulp/abstract_async_task.rb:45:in run’
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/action.rb:506:in block (3 levels) in execute_run' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:in call’
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:in pass' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:17:in pass’
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:30:in run' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:22:in call’
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware/stack.rb:26:in pass' /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.15/lib/dynflow/middleware.rb:17:in pass’
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:16:in block in run' /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/lib/actions/middleware/remote_action.rb:40:in block in as_remote_user’
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.2.1.1/app/models/katello/concerns/user_extensions.rb:21:in `cp_config’
/opt/theforeman/tfm/root/usr/share/gem