Problem:
Cannot sync deb repo to katello
Expected outcome:
Repo synced successfully
Foreman and Proxy versions:
foreman-1.20.1-1.el7.noarch
Foreman and Proxy plugin versions:
katello-3.10.0-0.6.rc1.el7.noarch
Other relevant data:
[e.g. logs from Foreman and/or the Proxy, modified templates, commands issued, etc]
(for logs, surround with three back-ticks to get proper formatting, e.g.)
/var/log/messages
Jan 11 11:50:36 foreman pulp: kombu.transport.qpid:INFO: Connected to qpid with SASL mechanism ANONYMOUS
Jan 11 11:50:36 foreman pulp: celery.worker.strategy:INFO: Received task: pulp.server.async.tasks._queue_reserved_task[0d946bd7-8c4b-4cc6-afb2-3d2c7052a107]
Jan 11 11:50:36 foreman pulp: celery.worker.strategy:INFO: Received task: pulp.server.managers.repo.sync.sync[f522018b-f0d5-40a5-8092-df79a0103089]
Jan 11 11:50:36 foreman pulp: celery.app.trace:INFO: [0d946bd7] Task pulp.server.async.tasks._queue_reserved_task[0d946bd7-8c4b-4cc6-afb2-3d2c7052a107] succeeded in 0.0166063010111s: None
Jan 11 11:50:36 foreman pulp: celery.worker.strategy:INFO: Received task: pulp.server.async.tasks._release_resource[72aa54db-b037-45c1-8ee9-d945cedbe261]
Jan 11 11:50:36 foreman pulp: pulp_deb.plugins.importers.importer:INFO: [f522018b] Repo sync started.
Jan 11 11:50:36 foreman pulp: pulp_deb.plugins.importers.sync:INFO: [f522018b] Downloading http://archive.ubuntu.com/ubuntu/dists/bionic/Release
Jan 11 11:50:36 foreman pulp: requests.packages.urllib3.connectionpool:INFO: Starting new HTTP connection (1): archive.ubuntu.com
Jan 11 11:50:36 foreman pulp: requests.packages.urllib3.connectionpool:INFO: Starting new HTTP connection (2): archive.ubuntu.com
Jan 11 11:50:36 foreman pulp: nectar.downloaders.threaded:INFO: Download succeeded: http://archive.ubuntu.com/ubuntu/dists/bionic/Release.gpg.
Jan 11 11:50:36 foreman pulp: nectar.downloaders.threaded:INFO: Download succeeded: http://archive.ubuntu.com/ubuntu/dists/bionic/Release.
Jan 11 11:50:37 foreman pulp: pulp_deb.plugins.importers.sync:INFO: [f522018b] Importing GPG-Key: [{'text': 'No valid data found', 'problem': '0', 'fingerprint': None}]
Jan 11 11:50:37 foreman pulp: pulp.server.async.tasks:INFO: [f522018b] Task failed : [f522018b-f0d5-40a5-8092-df79a0103089]
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) Task pulp.server.managers.repo.sync.sync[f522018b-f0d5-40a5-8092-df79a0103089] raised unexpected: Exception("GPG-Key not imported: [{'text': 'No valid data found', 'problem': '0', 'fingerprint': None}]",)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) Traceback (most recent call last):
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/celery/app/trace.py", line 367, in trace_task
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) R = retval = fun(*args, **kwargs)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line 529, in __call__
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) return super(Task, self).__call__(*args, **kwargs)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line 107, in __call__
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) return super(PulpTask, self).__call__(*args, **kwargs)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/celery/app/trace.py", line 622, in __protected_call__
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) return self.run(*args, **kwargs)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp/server/controllers/repository.py", line 770, in sync
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) sync_report = sync_repo(transfer_repo, conduit, call_config)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py", line 737, in wrap_f
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) return f(*args, **kwargs)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp_deb/plugins/importers/importer.py", line 135, in sync_repo
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) report = self._current_sync.process_lifecycle()
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py", line 572, in process_lifecycle
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) super(PluginStep, self).process_lifecycle()
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py", line 163, in process_lifecycle
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) step.process()
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py", line 256, in process
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) self._process_block()
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py", line 303, in _process_block
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) self.process_main()
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp_deb/plugins/importers/sync.py", line 212, in process_main
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) self.verify_release(release)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) File "/usr/lib/python2.7/site-packages/pulp_deb/plugins/importers/sync.py", line 173, in verify_release
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) raise Exception("GPG-Key not imported: %r" % import_res.results)
Jan 11 11:50:37 foreman pulp: celery.app.trace:ERROR: [f522018b] (7144-97856) Exception: GPG-Key not imported: [{'text': 'No valid data found', 'problem': '0', 'fingerprint': None}]
Jan 11 11:50:37 foreman pulp: celery.app.trace:INFO: [72aa54db] Task pulp.server.async.tasks._release_resource[72aa54db-b037-45c1-8ee9-d945cedbe261] succeeded in 0.00199385301676s: None
/var/log/foreman/production.log
2019-01-11T11:50:37 [I|app|] Started POST "/foreman_tasks/api/tasks/bulk_search" for 10.100.0.252 at 2019-01-11 11:50:37 +0300
2019-01-11T11:50:37 [I|app|bb9fa] Processing by ForemanTasks::Api::TasksController#bulk_search as JSON
2019-01-11T11:50:37 [I|app|bb9fa] Parameters: {"searches"=>[{"type"=>"task", "task_id"=>"bf490d09-9ecb-43aa-9112-669a2b305777", "search_id"=>"1"}], "task"=>{}}
2019-01-11T11:50:37 [I|app|bb9fa] Current user set to a.ivanov (admin)
2019-01-11T11:50:37 [I|app|bb9fa] Completed 200 OK in 36ms (Views: 0.8ms | ActiveRecord: 4.6ms)
2019-01-11T11:50:36 [I|app|] Current user set to a.ivanov (admin)
2019-01-11T11:50:36 [I|kat|] GET: https://foreman.talmer.local/pulp/api/v2/tasks/f522018b-f0d5-40a5-8092-df79a0103089/: {"content_type"=>"application/json", "accept"=>"application/json"}
Response: 200: {"exception": null, "task_type": "pulp.server.managers.repo.sync.sync", "_href": "/pulp/api/v2/tasks/f522018b-f0d5-40a5-8092-df79a0103089/", "task_id": "f522018b-f0d5-40a5-8092-df79a0103089", "tags": ["pulp:repository:551f1986-0797-403e-9c41-fbae6263bd0a", "pulp:action:sync"], "finish_time": null, "_ns": "task_status", "start_time": "2019-01-11T08:50:36Z", "traceback": null, "spawned_tasks": [], "progress_report": {"deb_importer": [{"num_success": 2, "description": "Retrieving metadata: release file(s)", "step_type": "sync_step_release_download", "items_total": 2, "state": "IN_PROGRESS", "error_details": [], "details": "", "num_failures": 0, "step_id": "e3bd2c0c-bde0-4415-b8b3-5e1439010cd9", "num_processed": 2}, {"num_success": 0, "description": "Parse Release Files", "step_type": "sync_step_release_parse", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "080b544a-6f6b-40bc-8112-cfe818086e3a", "num_processed": 0}, {"num_success": 0, "description": "Retrieving metadata: Packages files", "step_type": "sync_step_packages_download", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "d0cf65e4-d9cd-457e-9d45-3a0c5c74cd46", "num_processed": 0}, {"num_success": 0, "description": "Parse Packages Files", "step_type": "sync_step_packages_parse", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "7e4da012-3c9a-4f20-8845-e15b821fab77", "num_processed": 0}, {"num_success": 0, "description": "Copying units already in pulp", "step_type": "get_local", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "b65d106f-2266-4ca3-963f-0f41dc0ae5d8", "num_processed": 0}, {"num_success": 0, "description": "Prepare Package Download", "step_type": "sync_step_unit_download_requests", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "8d3aa623-fe2f-4d70-b182-68fb56ab21fd", "num_processed": 0}, {"num_success": 0, "description": "Retrieving units", "step_type": "sync_step_unit_download", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "00cedaad-a8de-42ec-8fc6-033bf344d80c", "num_processed": 0}, {"num_success": 0, "description": "Save and associate downloaded units", "step_type": "sync_step_save", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "734e2f5b-4f8d-4549-81e9-e704c5b424be", "num_processed": 0}, {"num_success": 0, "description": "Save metadata", "step_type": "sync_step_save_meta", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "eef85f15-00e5-4d2e-9d9f-2ef416bf6bed", "num_processed": 0}, {"num_success": 0, "description": "Orphan removed units", "step_type": "sync_step_orphan_removed_units", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "5fcb8368-973c-4c7d-9c01-59b229555de7", "num_processed": 0}]}, "queue": "reserved_resource_worker-3@foreman.talmer.local.dq2", "state": "running", "worker_name": "reserved_resource_worker-3@foreman.talmer.local", "result": null, "error": null, "_id": {"$oid": "5c3858dc15e908de9ae980bd"}, "id": "5c3858dc15e908de9ae980bd"}
2019-01-11T11:50:38 [I|app|] Current user set to a.ivanov (admin)
2019-01-11T11:50:38 [I|kat|] GET: https://foreman.talmer.local/pulp/api/v2/tasks/f522018b-f0d5-40a5-8092-df79a0103089/: {"content_type"=>"application/json", "accept"=>"application/json"}
Response: 200: {"exception": null, "task_type": "pulp.server.managers.repo.sync.sync", "_href": "/pulp/api/v2/tasks/f522018b-f0d5-40a5-8092-df79a0103089/", "task_id": "f522018b-f0d5-40a5-8092-df79a0103089", "tags": ["pulp:repository:551f1986-0797-403e-9c41-fbae6263bd0a", "pulp:action:sync"], "finish_time": "2019-01-11T08:50:37Z", "_ns": "task_status", "start_time": "2019-01-11T08:50:36Z", "traceback": "Traceback (most recent call last):\n File \"/usr/lib/python2.7/site-packages/celery/app/trace.py\", line 367, in trace_task\n R = retval = fun(*args, **kwargs)\n File \"/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py\", line 529, in __call__\n return super(Task, self).__call__(*args, **kwargs)\n File \"/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py\", line 107, in __call__\n return super(PulpTask, self).__call__(*args, **kwargs)\n File \"/usr/lib/python2.7/site-packages/celery/app/trace.py\", line 622, in __protected_call__\n return self.run(*args, **kwargs)\n File \"/usr/lib/python2.7/site-packages/pulp/server/controllers/repository.py\", line 770, in sync\n sync_report = sync_repo(transfer_repo, conduit, call_config)\n File \"/usr/lib/python2.7/site-packages/pulp/server/async/tasks.py\", line 737, in wrap_f\n return f(*args, **kwargs)\n File \"/usr/lib/python2.7/site-packages/pulp_deb/plugins/importers/importer.py\", line 135, in sync_repo\n report = self._current_sync.process_lifecycle()\n File \"/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py\", line 572, in process_lifecycle\n super(PluginStep, self).process_lifecycle()\n File \"/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py\", line 163, in process_lifecycle\n step.process()\n File \"/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py\", line 256, in process\n self._process_block()\n File \"/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py\", line 303, in _process_block\n self.process_main()\n File \"/usr/lib/python2.7/site-packages/pulp_deb/plugins/importers/sync.py\", line 212, in process_main\n self.verify_release(release)\n File \"/usr/lib/python2.7/site-packages/pulp_deb/plugins/importers/sync.py\", line 173, in verify_release\n raise Exception(\"GPG-Key not imported: %r\" % import_res.results)\nException: GPG-Key not imported: [{'text': 'No valid data found', 'problem': '0', 'fingerprint': None}]\n", "spawned_tasks": [], "progress_report": {"deb_importer": [{"num_success": 2, "description": "Retrieving metadata: release file(s)", "step_type": "sync_step_release_download", "items_total": 2, "state": "FINISHED", "error_details": [], "details": "", "num_failures": 0, "step_id": "e3bd2c0c-bde0-4415-b8b3-5e1439010cd9", "num_processed": 2}, {"num_success": 0, "description": "Parse Release Files", "step_type": "sync_step_release_parse", "items_total": 1, "state": "FAILED", "error_details": [{"traceback": " File \"/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py\", line 256, in process\n self._process_block()\n\n File \"/usr/lib/python2.7/site-packages/pulp/plugins/util/publish_step.py\", line 303, in _process_block\n self.process_main()\n\n File \"/usr/lib/python2.7/site-packages/pulp_deb/plugins/importers/sync.py\", line 212, in process_main\n self.verify_release(release)\n\n File \"/usr/lib/python2.7/site-packages/pulp_deb/plugins/importers/sync.py\", line 173, in verify_release\n raise Exception(\"GPG-Key not imported: %r\" % import_res.results)\n", "error": "GPG-Key not imported: [{'text': 'No valid data found', 'problem': '0', 'fingerprint': None}]"}], "details": "", "num_failures": 1, "step_id": "080b544a-6f6b-40bc-8112-cfe818086e3a", "num_processed": 1}, {"num_success": 0, "description": "Retrieving metadata: Packages files", "step_type": "sync_step_packages_download", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "d0cf65e4-d9cd-457e-9d45-3a0c5c74cd46", "num_processed": 0}, {"num_success": 0, "description": "Parse Packages Files", "step_type": "sync_step_packages_parse", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "7e4da012-3c9a-4f20-8845-e15b821fab77", "num_processed": 0}, {"num_success": 0, "description": "Copying units already in pulp", "step_type": "get_local", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "b65d106f-2266-4ca3-963f-0f41dc0ae5d8", "num_processed": 0}, {"num_success": 0, "description": "Prepare Package Download", "step_type": "sync_step_unit_download_requests", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "8d3aa623-fe2f-4d70-b182-68fb56ab21fd", "num_processed": 0}, {"num_success": 0, "description": "Retrieving units", "step_type": "sync_step_unit_download", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "00cedaad-a8de-42ec-8fc6-033bf344d80c", "num_processed": 0}, {"num_success": 0, "description": "Save and associate downloaded units", "step_type": "sync_step_save", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "734e2f5b-4f8d-4549-81e9-e704c5b424be", "num_processed": 0}, {"num_success": 0, "description": "Save metadata", "step_type": "sync_step_save_meta", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "eef85f15-00e5-4d2e-9d9f-2ef416bf6bed", "num_processed": 0}, {"num_success": 0, "description": "Orphan removed units", "step_type": "sync_step_orphan_removed_units", "items_total": 1, "state": "NOT_STARTED", "error_details": [], "details": "", "num_failures": 0, "step_id": "5fcb8368-973c-4c7d-9c01-59b229555de7", "num_processed": 0}]}, "queue": "reserved_resource_worker-3@foreman.talmer.local.dq2", "state": "error", "worker_name": "reserved_resource_worker-3@foreman.talmer.local", "result": null, "error": {"code": "PLP0000", "data": {}, "description": "GPG-Key not imported: [{'text': 'No valid data found', 'problem': '0', 'fingerprint': None}]", "sub_errors": []}, "_id": {"$oid": "5c3858dc15e908de9ae980bd"}, "id": "5c3858dc15e908de9ae980bd"}
2019-01-11T11:50:38 [E|bac|] PLP0000: GPG-Key not imported: [{'text': 'No valid data found', 'problem': '0', 'fingerprint': None}] (Katello::Errors::PulpError)
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/pulp/abstract_async_task.rb:122:in `block in external_task='
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/pulp/abstract_async_task.rb:120:in `each'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/pulp/abstract_async_task.rb:120:in `external_task='
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/pulp/repository/sync.rb:34:in `external_task='
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action/polling.rb:98:in `poll_external_task_with_rescue'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action/polling.rb:21:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action/cancellable.rb:13:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/pulp/abstract_async_task.rb:45:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:539:in `block (3 levels) in execute_run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:26:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:18:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:31:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:26:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:18:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/middleware/remote_action.rb:16:in `block in run'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/middleware/remote_action.rb:40:in `block in as_remote_user'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/models/katello/concerns/user_extensions.rb:21:in `cp_config'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/middleware/remote_action.rb:27:in `as_cp_user'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/middleware/remote_action.rb:39:in `as_remote_user'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/middleware/remote_action.rb:16:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:26:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:18:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/lib/actions/middleware/rails_executor_wrap.rb:14:in `block in run'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/execution_wrapper.rb:87:in `wrap'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/lib/actions/middleware/rails_executor_wrap.rb:13:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:26:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:18:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action/progress.rb:30:in `with_progress_calculation'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action/progress.rb:16:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:26:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:18:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/middleware/keep_locale.rb:11:in `block in run'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/middleware/keep_locale.rb:22:in `with_locale'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0/app/lib/actions/middleware/keep_locale.rb:11:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:26:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:18:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/lib/actions/middleware/keep_current_user.rb:15:in `block in run'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/lib/actions/middleware/keep_current_user.rb:43:in `restore_curent_user'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/lib/actions/middleware/keep_current_user.rb:15:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:26:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:18:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/lib/actions/middleware/keep_current_taxonomies.rb:15:in `block in run'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/lib/actions/middleware/keep_current_taxonomies.rb:44:in `restore_current_taxonomies'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/lib/actions/middleware/keep_current_taxonomies.rb:15:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:26:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:18:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware.rb:31:in `run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/stack.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/middleware/world.rb:30:in `execute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:538:in `block (2 levels) in execute_run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:537:in `catch'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:537:in `block in execute_run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:451:in `block in with_error_handling'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:451:in `catch'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:451:in `with_error_handling'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:532:in `execute_run'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/action.rb:278:in `execute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/execution_plan/steps/abstract_flow_step.rb:17:in `block (2 levels) in execute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/execution_plan/steps/abstract.rb:162:in `with_meta_calculation'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/execution_plan/steps/abstract_flow_step.rb:16:in `block in execute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/execution_plan/steps/abstract_flow_step.rb:30:in `open_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/execution_plan/steps/abstract_flow_step.rb:15:in `execute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/director.rb:56:in `execute'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/executors/parallel/worker.rb:14:in `block in on_message'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/executors.rb:12:in `run_user_code'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/executors/parallel/worker.rb:13:in `on_message'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/context.rb:46:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/executes_context.rb:7:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/abstract.rb:25:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-1.1.2/lib/dynflow/actor.rb:26:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/abstract.rb:25:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/awaits.rb:15:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/abstract.rb:25:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/sets_results.rb:14:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/abstract.rb:25:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/buffer.rb:38:in `process_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/buffer.rb:31:in `process_envelopes?'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/buffer.rb:20:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/abstract.rb:25:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/termination.rb:55:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/abstract.rb:25:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/removes_child.rb:10:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/abstract.rb:25:in `pass'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/behaviour/sets_results.rb:14:in `on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/core.rb:161:in `process_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/core.rb:95:in `block in on_envelope'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/core.rb:118:in `block (2 levels) in schedule_execution'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/synchronization/mri_lockable_object.rb:38:in `block in synchronize'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/synchronization/mri_lockable_object.rb:38:in `synchronize'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/synchronization/mri_lockable_object.rb:38:in `synchronize'
/opt/theforeman/tfm/root/usr/share/gems/gems/concurrent-ruby-edge-0.2.4/lib/concurrent/actor/core.rb:115:in `block in schedule_execution'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/serialized_execution.rb:18:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/serialized_execution.rb:96:in `work'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/serialized_execution.rb:77:in `block in call_job'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/ruby_thread_pool_executor.rb:348:in `run_task'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/ruby_thread_pool_executor.rb:337:in `block (3 levels) in create_worker'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/ruby_thread_pool_executor.rb:320:in `loop'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/ruby_thread_pool_executor.rb:320:in `block (2 levels) in create_worker'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/ruby_thread_pool_executor.rb:319:in `catch'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/concurrent-ruby-1.0.5/lib/concurrent/executor/ruby_thread_pool_executor.rb:319:in `block in create_worker'
/opt/theforeman/tfm/root/usr/share/gems/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2019-01-11T11:50:38 [I|app|] Started GET "/notification_recipients" for 10.100.0.252 at 2019-01-11 11:50:38 +0300
2019-01-11T11:50:38 [I|app|474f4] Processing by NotificationRecipientsController#index as JSON
2019-01-11T11:50:38 [I|app|474f4] Current user set to a.ivanov (admin)
2019-01-11T11:50:38 [I|app|474f4] Completed 200 OK in 8ms (Views: 0.1ms | ActiveRecord: 1.2ms)
2019-01-11T11:50:39 [I|app|] Started POST "/foreman_tasks/api/tasks/bulk_search" for 10.100.0.252 at 2019-01-11 11:50:39 +0300
2019-01-11T11:50:39 [I|app|c677e] Processing by ForemanTasks::Api::TasksController#bulk_search as JSON
2019-01-11T11:50:39 [I|app|c677e] Parameters: {"searches"=>[{"type"=>"task", "task_id"=>"bf490d09-9ecb-43aa-9112-669a2b305777", "search_id"=>"1"}], "task"=>{}}
2019-01-11T11:50:39 [I|app|c677e] Current user set to a.ivanov (admin)
2019-01-11T11:50:39 [I|app|c677e] Completed 200 OK in 35ms (Views: 0.8ms | ActiveRecord: 4.3ms)
2019-01-11T11:50:43 [I|app|] Started GET "/foreman_tasks/tasks/bf490d09-9ecb-43aa-9112-669a2b305777" for 10.100.0.252 at 2019-01-11 11:50:43 +0300
2019-01-11T11:50:43 [I|app|1ed7c] Processing by ForemanTasks::TasksController#show as HTML
2019-01-11T11:50:43 [I|app|1ed7c] Parameters: {"id"=>"bf490d09-9ecb-43aa-9112-669a2b305777"}
2019-01-11T11:50:43 [I|app|1ed7c] Current user set to a.ivanov (admin)
2019-01-11T11:50:43 [I|app|1ed7c] Rendering /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/views/foreman_tasks/tasks/show.html.erb within layouts/application
2019-01-11T11:50:43 [I|app|1ed7c] Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/views/foreman_tasks/tasks/_details.html.erb (41.0ms)
2019-01-11T11:50:43 [I|app|1ed7c] Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/views/foreman_tasks/tasks/_running_steps.html.erb (0.2ms)
2019-01-11T11:50:43 [I|app|1ed7c] Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/views/foreman_tasks/tasks/_errors.html.erb (13.3ms)
2019-01-11T11:50:43 [I|app|1ed7c] Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/views/foreman_tasks/tasks/_raw.html.erb (1.9ms)
2019-01-11T11:50:43 [I|app|1ed7c] Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/views/foreman_tasks/tasks/_locks.html.erb (1.6ms)
2019-01-11T11:50:43 [I|app|1ed7c] Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.3/app/views/foreman_tasks/tasks/show.html.erb within layouts/application (60.6ms)
2019-01-11T11:50:43 [I|app|1ed7c] Rendered layouts/_application_content.html.erb (0.3ms)
2019-01-11T11:50:43 [I|app|1ed7c] Rendering layouts/base.html.erb
2019-01-11T11:50:43 [I|app|1ed7c] Rendered layouts/base.html.erb (16.3ms)
2019-01-11T11:50:43 [I|app|1ed7c] Completed 200 OK in 87ms (Views: 74.2ms | ActiveRecord: 6.0ms)
2019-01-11T11:50:44 [I|app|] Started GET "/notification_recipients" for 10.100.0.252 at 2019-01-11 11:50:44 +0300
2019-01-11T11:50:44 [I|app|81553] Processing by NotificationRecipientsController#index as JSON
2019-01-11T11:50:44 [I|app|81553] Current user set to a.ivanov (admin)
2019-01-11T11:50:44 [I|app|81553] Completed 200 OK in 8ms (Views: 0.1ms | ActiveRecord: 1.5ms)
