Project

General

Profile

Actions
Copy link

Bug #26037

closed

Can't execute ansible role with Foreman 1.21 RC4

Added by John D. almost 6 years ago. Updated over 3 years ago.

Status:
Rejected
Priority:
High
Assignee:
-
Target version:
-
Difficulty:
Triaged:
No
Bugzilla link:
Pull request:
Fixed in Releases:
Found in Releases:
Red Hat JIRA:

Description

I updated from Foreman 1.20 to 1.21.

ii  ansible                                  2.2.1.0-2                         all          Configuration management, deployment, and task execution system
ii  ruby-foreman-ansible                     2.3.1-1                           all          Foreman Ansible plugin
ii  ruby-smart-proxy-ansible                 2.1.0-1                           all          Ansible support for Foreman smart proxy
+ Ansible 2.7.7 installed via pip (which works on my other Foreman's <= 1.20)

On 1.20 the roles work's fine. Now i am getting this error:

Scroll to bottom
   1:
/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 1: /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 
   2:
---: not found
   3:
/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 2: /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 
   4:
-: not found
   5:
/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 3: /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 
   6:
tasks:: not found
   7:
/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 4: /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 
   8:
-: not found
   9:
/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 5: /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 
  10:
debug:: not found
  11:
/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 6: /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 
  12:
var:: not found
  13:
/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 7: /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 
  14:
roles:: not found
  15:
/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 8: /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script: 
  16:
-: not found
  17:
Exit status: 127

Production log:

2019-02-12T12:49:00 [I|app|5b665625] Started GET "/ansible/hosts/55/play_roles" for 10.208.1.159 at 2019-02-12 12:49:00 +0100
2019-02-12T12:49:00 [I|app|5b665625] Processing by HostsController#play_roles as HTML
2019-02-12T12:49:00 [I|app|5b665625]   Parameters: {"id"=>"55"}
2019-02-12T12:49:00 [I|app|5b665625] Current user set to admin (admin)
2019-02-12T12:49:00 [D|app|5b665625] Current location set to none
2019-02-12T12:49:00 [D|app|5b665625] Current organization set to none
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on job_category Ansible Playbook
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on description 
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on concurrency_level 
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on time_span 
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on execution_timeout_interval 
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on password [redacted]
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on key_passphrase 
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on remote_execution_feature_id 1
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) create event on sudo_password 
2019-02-12T12:49:00 [I|aud|5b665625] JobInvocation (214) update event on description , Run Ansible roles
2019-02-12T12:49:00 [D|dyn|5b665625] ExecutionPlan 0a23fd43-857e-41c2-9a5f-5dfc7c8e3948      pending >>  planning
2019-02-12T12:49:00 [D|dyn|5b665625]          Step 0a23fd43-857e-41c2-9a5f-5dfc7c8e3948: 1   pending >>   running in phase     Plan Actions::RemoteExecution::RunHostsJob
2019-02-12T12:49:00 [I|app|5b665625] Current user set to admin (admin)
2019-02-12T12:49:00 [I|app|5b665625] Current user set to admin (admin)
2019-02-12T12:49:00 [D|dyn|5b665625]          Step 0a23fd43-857e-41c2-9a5f-5dfc7c8e3948: 1   running >>   success in phase     Plan Actions::RemoteExecution::RunHostsJob
2019-02-12T12:49:00 [D|dyn|5b665625] ExecutionPlan 0a23fd43-857e-41c2-9a5f-5dfc7c8e3948     planning >>   planned
2019-02-12T12:49:00 [I|app|5b665625] Current user set to admin (admin)
2019-02-12T12:49:00 [D|app|] Current organization set to none
2019-02-12T12:49:00 [D|app|] Current location set to none
2019-02-12T12:49:00 [I|app|] Redirected to https://foreman.example.de/job_invocations/214
2019-02-12T12:49:00 [I|app|] Completed 302 Found in 216ms (ActiveRecord: 56.5ms)
2019-02-12T12:49:00 [I|app|76bf5d64] Started GET "/job_invocations/214" for 10.208.1.159 at 2019-02-12 12:49:00 +0100
2019-02-12T12:49:00 [I|app|76bf5d64] Processing by JobInvocationsController#show as HTML
2019-02-12T12:49:00 [I|app|76bf5d64]   Parameters: {"id"=>"214"}
2019-02-12T12:49:00 [I|app|76bf5d64] Current user set to admin (admin)
2019-02-12T12:49:00 [D|app|76bf5d64] Current location set to none
2019-02-12T12:49:00 [D|app|76bf5d64] Current organization set to none
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendering vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.html.erb within layouts/application
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_card_results.html.erb (0.2ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_card_schedule.html.erb (2.3ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_card_target_hosts.html.erb (2.1ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_host_name_td.html.erb (0.8ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_host_status_td.html.erb (0.5ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_host_actions_td.html.erb (1.6ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered common/_pagination.html.erb (0.6ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_tab_hosts.html.erb (7.8ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_tab_overview.html.erb (21.2ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_user_input.html.erb (11.7ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_tab_preview_templates.html.erb (19.0ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.html.erb within layouts/application (62.1ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered layouts/_application_content.html.erb (0.5ms)
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendering layouts/base.html.erb
2019-02-12T12:49:00 [I|app|76bf5d64]   Rendered layouts/base.html.erb (29.5ms)
2019-02-12T12:49:00 [I|app|76bf5d64] Completed 200 OK in 113ms (Views: 82.7ms | ActiveRecord: 14.3ms)
2019-02-12T12:49:01 [I|app|6d74257f] Started GET "/notification_recipients" for 10.208.1.159 at 2019-02-12 12:49:01 +0100
2019-02-12T12:49:01 [I|app|6d74257f] Processing by NotificationRecipientsController#index as JSON
2019-02-12T12:49:01 [I|app|6d74257f] Current user set to admin (admin)
2019-02-12T12:49:01 [D|app|6d74257f] Current location set to none
2019-02-12T12:49:01 [D|app|6d74257f] Current organization set to none
2019-02-12T12:49:01 [D|not|6d74257f] Cache Hit: notification, reading cache for notification-4
2019-02-12T12:49:01 [D|app|6d74257f] Body: {"notifications":[{"id":416,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-11T15:30:31.039Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/193","title":"Job Details"}]}},{"id":415,"seen":false,"level":"success","text":"A job 'Ansible - Test Playbook' has finished successfully","created_at":"2019-02-11T15:06:05.445Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/192","title":"Job Details"}]}},{"id":414,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-11T13:54:07.373Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/191","title":"Job Details"}]}},{"id":413,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-11T13:27:01.217Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/190","title":"Job Details"}]}},{"id":412,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-11T12:08:08.811Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/189","title":"Job Details"}]}},{"id":411,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-11T11:58:58.346Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/188","title":"Job Details"}]}},{"id":335,"seen":false,"level":"warning","text":"The organizations_enabled setting has been deprecated and will be removed in version 1.21","created_at":"2019-01-27T05:25:22.635Z","group":"Deprecations","actions":{"links":[{"href":"https://community.theforeman.org/t/proposal-remove-support-for-disabling-taxonomies-or-login/10972","title":"Further Information","external":true}]}},{"id":334,"seen":false,"level":"warning","text":"The locations_enabled setting has been deprecated and will be removed in version 1.21","created_at":"2019-01-27T05:25:22.574Z","group":"Deprecations","actions":{"links":[{"href":"https://community.theforeman.org/t/proposal-remove-support-for-disabling-taxonomies-or-login/10972","title":"Further Information","external":true}]}}]}
2019-02-12T12:49:01 [I|app|6d74257f] Completed 200 OK in 10ms (Views: 0.2ms | ActiveRecord: 0.7ms)
2019-02-12T12:49:01 [I|app|cd7201f6] Started GET "/job_invocations/chart?id=214" for 10.208.1.159 at 2019-02-12 12:49:01 +0100
2019-02-12T12:49:01 [I|app|cd7201f6] Processing by JobInvocationsController#chart as JSON
2019-02-12T12:49:01 [I|app|cd7201f6]   Parameters: {"id"=>"214"}
2019-02-12T12:49:01 [I|app|cd7201f6] Current user set to admin (admin)
2019-02-12T12:49:01 [D|app|cd7201f6] Current location set to none
2019-02-12T12:49:01 [D|app|cd7201f6] Current organization set to none
2019-02-12T12:49:01 [I|app|cd7201f6] Completed 200 OK in 38ms (Views: 0.4ms | ActiveRecord: 4.0ms)
2019-02-12T12:49:01 [I|app|975737d2] Started GET "/job_invocations/214?hosts_needs_refresh=&host_ids_needing_name_update%5B%5D=55&host_ids_needing_status_update%5B%5D=55&_=1549972140739" for 10.208.1.159 at 2019-02-12 12:49:01 +0100
2019-02-12T12:49:01 [I|app|975737d2] Processing by JobInvocationsController#show as JS
2019-02-12T12:49:01 [I|app|975737d2]   Parameters: {"hosts_needs_refresh"=>"", "host_ids_needing_name_update"=>["55"], "host_ids_needing_status_update"=>["55"], "_"=>"1549972140739", "id"=>"214"}
2019-02-12T12:49:01 [I|app|975737d2] Current user set to admin (admin)
2019-02-12T12:49:01 [D|app|975737d2] Current location set to none
2019-02-12T12:49:01 [D|app|975737d2] Current organization set to none
2019-02-12T12:49:01 [I|app|975737d2]   Rendering vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.js.erb
2019-02-12T12:49:01 [I|app|975737d2]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_host_name_td.html.erb (0.9ms)
2019-02-12T12:49:01 [I|app|975737d2]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_host_status_td.html.erb (0.5ms)
2019-02-12T12:49:01 [I|app|975737d2]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.js.erb (22.5ms)
2019-02-12T12:49:01 [I|app|975737d2] Completed 200 OK in 42ms (Views: 20.8ms | ActiveRecord: 5.6ms)
2019-02-12T12:49:02 [I|app|c5990c14] Started GET "/job_invocations/chart?id=214" for 10.208.1.159 at 2019-02-12 12:49:02 +0100
2019-02-12T12:49:02 [I|app|c5990c14] Processing by JobInvocationsController#chart as JSON
2019-02-12T12:49:02 [I|app|c5990c14]   Parameters: {"id"=>"214"}
2019-02-12T12:49:02 [I|app|c5990c14] Current user set to admin (admin)
2019-02-12T12:49:02 [D|app|c5990c14] Current location set to none
2019-02-12T12:49:02 [D|app|c5990c14] Current organization set to none
2019-02-12T12:49:02 [I|app|c5990c14] Completed 200 OK in 32ms (Views: 0.4ms | ActiveRecord: 3.8ms)
2019-02-12T12:49:02 [I|app|3ecc3cbd] Started GET "/job_invocations/214?hosts_needs_refresh=&host_ids_needing_status_update%5B%5D=55&_=1549972140740" for 10.208.1.159 at 2019-02-12 12:49:02 +0100
2019-02-12T12:49:03 [I|app|3ecc3cbd] Processing by JobInvocationsController#show as JS
2019-02-12T12:49:03 [I|app|3ecc3cbd]   Parameters: {"hosts_needs_refresh"=>"", "host_ids_needing_status_update"=>["55"], "_"=>"1549972140740", "id"=>"214"}
2019-02-12T12:49:03 [I|app|3ecc3cbd] Current user set to admin (admin)
2019-02-12T12:49:03 [D|app|3ecc3cbd] Current location set to none
2019-02-12T12:49:03 [D|app|3ecc3cbd] Current organization set to none
2019-02-12T12:49:03 [I|app|3ecc3cbd]   Rendering vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.js.erb
2019-02-12T12:49:03 [I|app|3ecc3cbd]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_host_status_td.html.erb (0.5ms)
2019-02-12T12:49:03 [I|app|3ecc3cbd]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.js.erb (19.5ms)
2019-02-12T12:49:03 [I|app|3ecc3cbd] Completed 200 OK in 39ms (Views: 17.9ms | ActiveRecord: 5.5ms)
2019-02-12T12:49:03 [I|app|23418601] Started POST "/foreman_tasks/api/tasks/callback" for 10.208.213.10 at 2019-02-12 12:49:03 +0100
2019-02-12T12:49:03 [I|app|23418601] Processing by ForemanTasks::Api::TasksController#callback as XML
2019-02-12T12:49:03 [I|app|23418601]   Parameters: {"callback"=>{"task_id"=>"9e8d2a3f-d21e-414c-a1ab-870e230186ae", "step_id"=>3}, "data"=>{"result"=>[{"output_type"=>"stdout", "output"=>"/var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: 1: /var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: ", "timestamp"=>1549972143.2827482}, {"output_type"=>"stdout", "output"=>"---: not found", "timestamp"=>1549972143.2828517}, {"output_type"=>"stdout", "output"=>"\r\n", "timestamp"=>1549972143.282969}, {"output_type"=>"stdout", "output"=>"/var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: 2: /var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: ", "timestamp"=>1549972143.2831519}, {"output_type"=>"stdout", "output"=>"-: not found", "timestamp"=>1549972143.2832546}, {"output_type"=>"stdout", "output"=>"\r\n", "timestamp"=>1549972143.2833457}, {"output_type"=>"stdout", "output"=>"/var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: 3: /var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: ", "timestamp"=>1549972143.2835078}, {"output_type"=>"stdout", "output"=>"tasks:: not found", "timestamp"=>1549972143.2836647}, {"output_type"=>"stdout", "output"=>"\r\n", "timestamp"=>1549972143.2837822}, {"output_type"=>"stdout", "output"=>"/var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: 4: /var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: ", "timestamp"=>1549972143.2839136}, {"output_type"=>"stdout", "output"=>"-: not found", "timestamp"=>1549972143.2840402}, {"output_type"=>"stdout", "output"=>"\r\n", "timestamp"=>1549972143.2841306}, {"output_type"=>"stdout", "output"=>"/var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: 5: /var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: ", "timestamp"=>1549972143.2842855}, {"output_type"=>"stdout", "output"=>"debug:: not found", "timestamp"=>1549972143.284383}, {"output_type"=>"stdout", "output"=>"\r\n", "timestamp"=>1549972143.2844718}, {"output_type"=>"stdout", "output"=>"/var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: 6: /var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: ", "timestamp"=>1549972143.284638}, {"output_type"=>"stdout", "output"=>"var:: not found", "timestamp"=>1549972143.2847645}, {"output_type"=>"stdout", "output"=>"\r\n", "timestamp"=>1549972143.2848554}, {"output_type"=>"stdout", "output"=>"/var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: 7: /var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: ", "timestamp"=>1549972143.2850173}, {"output_type"=>"stdout", "output"=>"roles:: not found", "timestamp"=>1549972143.2851396}, {"output_type"=>"stdout", "output"=>"\r\n", "timestamp"=>1549972143.2852411}, {"output_type"=>"stdout", "output"=>"/var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: 8: /var/tmp/foreman-ssh-cmd-3dd16084-380c-4409-b450-0f255071e3ba/script: ", "timestamp"=>1549972143.285409}, {"output_type"=>"stdout", "output"=>"-: not found", "timestamp"=>1549972143.2855105}, {"output_type"=>"stdout", "output"=>"\r\n", "timestamp"=>1549972143.2856002}], "runner_id"=>"3dd16084-380c-4409-b450-0f255071e3ba", "exit_status"=>127}, "task"=>{}}
2019-02-12T12:49:03 [D|app|23418601] Examining client certificate to extract dn and sans
2019-02-12T12:49:03 [D|app|23418601] Client sent certificate with subject 'foreman.example.de' and subject alt names '["foreman.example.de", "puppet", "puppet.example.de"]'
2019-02-12T12:49:03 [D|app|23418601] Verifying request from ["foreman.example.de", "puppet", "puppet.example.de"] against ["foreman.example.de", "foreman.example.de", "foreman"]
2019-02-12T12:49:03 [D|app|23418601] Current user set to foreman_api_admin (admin)
2019-02-12T12:49:03 [D|app|23418601] Body: {"message":"processing"}
2019-02-12T12:49:03 [I|app|23418601] Completed 200 OK in 17ms (Views: 0.2ms | ActiveRecord: 3.6ms)
2019-02-12T12:49:03 [I|app|57b550ae] Started GET "/job_invocations/chart?id=214" for 10.208.1.159 at 2019-02-12 12:49:03 +0100
2019-02-12T12:49:03 [I|app|57b550ae] Processing by JobInvocationsController#chart as JSON
2019-02-12T12:49:03 [I|app|57b550ae]   Parameters: {"id"=>"214"}
2019-02-12T12:49:03 [I|app|57b550ae] Current user set to admin (admin)
2019-02-12T12:49:03 [D|app|57b550ae] Current location set to none
2019-02-12T12:49:03 [D|app|57b550ae] Current organization set to none
2019-02-12T12:49:03 [I|app|57b550ae] Completed 200 OK in 34ms (Views: 0.4ms | ActiveRecord: 4.1ms)
2019-02-12T12:49:04 [I|app|e0240207] Started GET "/job_invocations/214?hosts_needs_refresh=&host_ids_needing_status_update%5B%5D=55&_=1549972140741" for 10.208.1.159 at 2019-02-12 12:49:04 +0100
2019-02-12T12:49:04 [I|app|e0240207] Processing by JobInvocationsController#show as JS
2019-02-12T12:49:04 [I|app|e0240207]   Parameters: {"hosts_needs_refresh"=>"", "host_ids_needing_status_update"=>["55"], "_"=>"1549972140741", "id"=>"214"}
2019-02-12T12:49:04 [I|app|e0240207] Current user set to admin (admin)
2019-02-12T12:49:04 [D|app|e0240207] Current location set to none
2019-02-12T12:49:04 [D|app|e0240207] Current organization set to none
2019-02-12T12:49:04 [I|app|e0240207]   Rendering vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.js.erb
2019-02-12T12:49:04 [I|app|e0240207]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/_host_status_td.html.erb (0.6ms)
2019-02-12T12:49:04 [I|app|e0240207]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.js.erb (20.3ms)
2019-02-12T12:49:04 [I|app|e0240207] Completed 200 OK in 40ms (Views: 18.9ms | ActiveRecord: 5.5ms)
2019-02-12T12:49:04 [I|app|31a210ea] Started GET "/job_invocations/chart?id=214" for 10.208.1.159 at 2019-02-12 12:49:04 +0100
2019-02-12T12:49:04 [I|app|31a210ea] Processing by JobInvocationsController#chart as JSON
2019-02-12T12:49:04 [I|app|31a210ea]   Parameters: {"id"=>"214"}
2019-02-12T12:49:04 [I|app|31a210ea] Current user set to admin (admin)
2019-02-12T12:49:04 [D|app|31a210ea] Current location set to none
2019-02-12T12:49:04 [D|app|31a210ea] Current organization set to none
2019-02-12T12:49:04 [I|app|31a210ea] Completed 200 OK in 33ms (Views: 0.4ms | ActiveRecord: 3.8ms)
2019-02-12T12:49:05 [I|app|8e4f9bf9] Started GET "/job_invocations/214?hosts_needs_refresh=&_=1549972140742" for 10.208.1.159 at 2019-02-12 12:49:05 +0100
2019-02-12T12:49:05 [I|app|8e4f9bf9] Processing by JobInvocationsController#show as JS
2019-02-12T12:49:05 [I|app|8e4f9bf9]   Parameters: {"hosts_needs_refresh"=>"", "_"=>"1549972140742", "id"=>"214"}
2019-02-12T12:49:05 [I|app|8e4f9bf9] Current user set to admin (admin)
2019-02-12T12:49:05 [D|app|8e4f9bf9] Current location set to none
2019-02-12T12:49:05 [D|app|8e4f9bf9] Current organization set to none
2019-02-12T12:49:05 [I|app|8e4f9bf9]   Rendering vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.js.erb
2019-02-12T12:49:05 [I|app|8e4f9bf9]   Rendered vendor/ruby/2.3.0/gems/foreman_remote_execution-1.7.0/app/views/job_invocations/show.js.erb (16.0ms)
2019-02-12T12:49:05 [I|app|8e4f9bf9] Completed 200 OK in 36ms (Views: 14.9ms | ActiveRecord: 5.2ms

Proxy Log

2019-02-12T12:49:55  [D] accept: 10.208.213.10:57232
2019-02-12T12:49:55  [D] Rack::Handler::WEBrick is invoked.
2019-02-12T12:49:55 8393ec72 [I] Started GET /tasks/count state=running
2019-02-12T12:49:55 8393ec72 [I] Finished GET /tasks/count with 200 (1.06 ms)
2019-02-12T12:49:55  [D] close: 10.208.213.10:57232
2019-02-12T12:49:56  [D] accept: 10.208.213.10:57234
2019-02-12T12:49:56  [D] Rack::Handler::WEBrick is invoked.
2019-02-12T12:49:56 d2e0bbbf [I] Started POST /tasks/ 
2019-02-12T12:49:56 d2e0bbbf [D] ExecutionPlan 98086233-6181-4395-b63f-0bf80cfbba63      pending >>  planning
2019-02-12T12:49:56 d2e0bbbf [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 1   pending >>   running in phase     Plan ForemanRemoteExecutionCore::Actions::RunScript
2019-02-12T12:49:56 d2e0bbbf [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 4   pending >>   running in phase     Plan SmartProxyDynflowCore::Callback::Action
2019-02-12T12:49:56 d2e0bbbf [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 4   running >>   success in phase     Plan SmartProxyDynflowCore::Callback::Action
2019-02-12T12:49:56 d2e0bbbf [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 1   running >>   success in phase     Plan ForemanRemoteExecutionCore::Actions::RunScript
2019-02-12T12:49:56 d2e0bbbf [D] ExecutionPlan 98086233-6181-4395-b63f-0bf80cfbba63     planning >>   planned
2019-02-12T12:49:56 d2e0bbbf [I] Finished POST /tasks/ with 200 (18.73 ms)
2019-02-12T12:49:56  [D] ExecutionPlan 98086233-6181-4395-b63f-0bf80cfbba63      planned >>   running
2019-02-12T12:49:56  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 2   pending >>   running in phase      Run ForemanRemoteExecutionCore::Actions::RunScript
2019-02-12T12:49:56  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 2   running >> suspended in phase      Run ForemanRemoteExecutionCore::Actions::RunScript
2019-02-12T12:49:56  [D] start runner 5a402fc5-247a-425c-9095-c1f65ec13acf
2019-02-12T12:49:56  [D] opening session to root@10.208.213.215
2019-02-12T12:49:56  [D] close: 10.208.213.10:57234
2019-02-12T12:49:56  [D] Sending data to /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script on remote host:
---
- hosts: all
  tasks:
    - name: Display all parameters known for the Foreman host
      debug:
        var: foreman_params
  roles:
    - base

2019-02-12T12:49:56  [D] executing script:
  | sh <<WRAPPER
  | (/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/script < /dev/null; echo \$?>/var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/exit_code) | /usr/bin/tee /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/output
  | exit \$(cat /var/tmp/foreman-ssh-cmd-5a402fc5-247a-425c-9095-c1f65ec13acf/exit_code)
  | WRAPPER

2019-02-12T12:49:56  [D] refresh runner 5a402fc5-247a-425c-9095-c1f65ec13acf
2019-02-12T12:49:56  [D] refreshing runner
2019-02-12T12:49:56  [D] planning to refresh 5a402fc5-247a-425c-9095-c1f65ec13acf
2019-02-12T12:49:57  [D] Ticker ticking for 1 events
2019-02-12T12:49:57  [D] refresh runner 5a402fc5-247a-425c-9095-c1f65ec13acf
2019-02-12T12:49:57  [D] refreshing runner
2019-02-12T12:49:57  [D] finish runner 5a402fc5-247a-425c-9095-c1f65ec13acf
2019-02-12T12:49:57  [D] closing session for command [5a402fc5-247a-425c-9095-c1f65ec13acf],0 actors left 
2019-02-12T12:49:57  [D] terminate 5a402fc5-247a-425c-9095-c1f65ec13acf
2019-02-12T12:49:57  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 2 got event #<ForemanTasksCore::Runner::Update:0x0056168a2946d0>
2019-02-12T12:49:57  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 2 suspended >>   running in phase      Run ForemanRemoteExecutionCore::Actions::RunScript
2019-02-12T12:49:57  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 2   running >>   success in phase      Run ForemanRemoteExecutionCore::Actions::RunScript
2019-02-12T12:49:57  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 5   pending >>   running in phase      Run SmartProxyDynflowCore::Callback::Action
2019-02-12T12:49:57  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 5   running >>   success in phase      Run SmartProxyDynflowCore::Callback::Action
2019-02-12T12:49:57  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 3   pending >>   running in phase Finalize ForemanRemoteExecutionCore::Actions::RunScript
2019-02-12T12:49:57  [E] Script execution failed
2019-02-12T12:49:57  [D]          Step 98086233-6181-4395-b63f-0bf80cfbba63: 3   running >>     error in phase Finalize ForemanRemoteExecutionCore::Actions::RunScript
2019-02-12T12:49:57  [D] ExecutionPlan 98086233-6181-4395-b63f-0bf80cfbba63      running >>    paused
2019-02-12T12:49:57  [D] ExecutionPlan 98086233-6181-4395-b63f-0bf80cfbba63       paused >>   stopped

Actions
Copy link
 #1

Updated by Michał Matuszak almost 6 years ago

John D. wrote:

I updated from Foreman 1.20 to 1.21.

[...]

On 1.20 the roles work's fine. Now i am getting this error:
[...]

Production log:
[...]

Proxy Log
[...]

I have the same problem

Actions
Copy link
 #2

Updated by John D. almost 6 years ago

  • Priority changed from Normal to High
Actions
Copy link
 #3

Updated by Michał Matuszak almost 6 years ago

File /var/tmp/foreman-ssh-cmd-*/script contains playbook template:

---
- hosts: all
  tasks:
    - name: Display all parameters known for the Foreman host
      debug:
        var: foreman_params
  roles:
    - role1
    - role2
    - role3

so shell execution failed..

Actions
Copy link
 #4

Updated by John D. almost 6 years ago

Still exists in version 1.21.0 (before that I had version 1.21.0 RC4).

Actions
Copy link
 #5

Updated by Sebastian Grebe over 5 years ago

Same problem hire

ii  ansible                               2.6.3-1ppa~trusty                 all          Ansible IT Automation
ii  ruby-foreman-ansible                  2.3.3-1                           all          Foreman Ansible plugin
ii  ruby-smart-proxy-ansible              2.1.2-1                           all          Ansible support for Foreman smart proxy
Actions
Copy link
 #6

Updated by waza dex over 5 years ago

same problem...

Actions
Copy link
 #7

Updated by Ondřej Ezr over 3 years ago

  • Status changed from New to Rejected

Closing this, as it is too old and we failed to reproduce this.
Feel free to reopen this if you are still facing this issue.

Actions
Copy link

Also available in: Atom PDF