Project

General

Profile

Bug #26037

Can't execute ansible role with Foreman 1.21 RC4

Added by John D. 10 months ago. Updated 7 months ago.

Status:
New
Priority:
High
Assignee:
-
Target version:
-
Difficulty:
Triaged:
No
Bugzilla link:
Pull request:
Fixed in Releases:
Found in Releases:

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

History

#1 Updated by Michał Matuszak 9 months 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

#2 Updated by John D. 9 months ago

  • Priority changed from Normal to High

#3 Updated by Michał Matuszak 9 months 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..

#4 Updated by John D. 9 months ago

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

#5 Updated by Sebastian Grebe 8 months 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

#6 Updated by waza dex 7 months ago

same problem...

Also available in: Atom PDF