ERF42-4429 [Foreman::Exception]: Could not run Ansible roles for ****

Problem:
When I go into a host and press the Button “Run Ansible Roles” " there appear an error popup:

ERF42-4429 [Foreman::Exception]: Could not run Ansible roles for ****

When I select a Host in the Hosts list and select the Action “Play Ansible Roles” there appear an error popup:

ERF42-9244 [Foreman::Exception]: Could not run Ansible roles for #<Host::Managed::ActiveRecord_Relation:0x00007f542b7910b8>

When I select “Schedule Remote Job” and select “Ansible Playbook” - “Ansible Roles - Ansible Default” all works as I expected.

I turn the log to Debug but there is no further informations.

Expected outcome:
Foremans runs Job “Ansible Playbook” - “Ansible Roles - Ansible Default”.

Foreman and Proxy versions:
Foreman 1.20.1

Foreman and Proxy plugin versions:
foreman_ansible: 2.2.11
foreman_discovery: 14.0.1

Can you please post the full backtrace from /var/log/foreman/production.log? that may give us additional hints as to why it is failing.

This is the log form the moment I click the button in the host. I have masked some personal information. if you need more information or if you want me to change any log settings please say so.

2019-02-21T10:14:49 [I|app|] Started GET "/ansible/hosts/105/play_roles" for 172.29.6.15 at 2019-02-21 10:14:49 +0100
2019-02-21T10:14:49 [I|app|d4360] Processing by HostsController#play_roles as HTML
2019-02-21T10:14:49 [I|app|d4360]   Parameters: {"id"=>"105"}
2019-02-21T10:14:49 [I|app|d4360] Current user set to admin (admin)
2019-02-21T10:14:49 [D|app|d4360] Current location set to City
2019-02-21T10:14:49 [D|app|d4360] Current organization set to Company Name
2019-02-21T10:14:49 [I|app|d4360] Redirected to https://foreman.domain.local/hosts/testhost.domain.local
2019-02-21T10:14:49 [I|app|d4360] Completed 302 Found in 491ms (ActiveRecord: 36.1ms)
2019-02-21T10:14:49 [I|app|] Started GET "/hosts/testhost.domain.local" for 172.29.6.15 at 2019-02-21 10:14:49 +0100
2019-02-21T10:14:49 [I|app|14b20] Processing by HostsController#show as HTML
2019-02-21T10:14:49 [I|app|14b20]   Parameters: {"id"=>"testhost.domain.local"}
2019-02-21T10:14:49 [I|app|14b20] Current user set to admin (admin)
2019-02-21T10:14:49 [D|app|14b20] Current location set to City
2019-02-21T10:14:49 [D|app|14b20] Current organization set to Company Name
2019-02-21T10:14:49 [I|app|14b20]   Rendering hosts/show.html.erb within layouts/application
2019-02-21T10:14:49 [I|app|14b20]   Rendered hosts/show.html.erb within layouts/application (57.0ms)
2019-02-21T10:14:49 [I|app|14b20]   Rendered layouts/_application_content.html.erb (1.0ms)
2019-02-21T10:14:49 [I|app|14b20]   Rendering layouts/base.html.erb
2019-02-21T10:14:49 [I|app|14b20]   Rendered layouts/base.html.erb (20.1ms)
2019-02-21T10:14:49 [I|app|14b20] Completed 200 OK in 98ms (Views: 68.5ms | ActiveRecord: 17.5ms)
2019-02-21T10:14:50 [I|app|] Started GET "/notification_recipients" for 172.29.6.15 at 2019-02-21 10:14:50 +0100
2019-02-21T10:14:50 [I|app|b40ed] Processing by NotificationRecipientsController#index as JSON
2019-02-21T10:14:50 [I|app|b40ed] Current user set to admin (admin)
2019-02-21T10:14:50 [D|app|b40ed] Current location set to City
2019-02-21T10:14:50 [D|app|b40ed] Current organization set to Company Name
2019-02-21T10:14:50 [D|not|b40ed] Cache Hit: notification, reading cache for notification-6
2019-02-21T10:14:50 [D|app|b40ed] Body: {"notifications":[{"id":953,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-21T09:02:09.558Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/625","title":"Job Details"}]}}]}
2019-02-21T10:14:50 [I|app|b40ed] Completed 200 OK in 8ms (Views: 0.1ms | ActiveRecord: 1.3ms)
2019-02-21T10:14:50 [I|app|] Started GET "/hosts/testhost.domain.local/overview" for 172.29.6.15 at 2019-02-21 10:14:50 +0100
2019-02-21T10:14:50 [I|app|ca548] Processing by HostsController#overview as HTML
2019-02-21T10:14:50 [I|app|ca548]   Parameters: {"id"=>"testhost.domain.local"}
2019-02-21T10:14:50 [I|app|ca548] Current user set to admin (admin)
2019-02-21T10:14:50 [I|app|] Started GET "/hosts/testhost.domain.local/templates" for 172.29.6.15 at 2019-02-21 10:14:50 +0100
2019-02-21T10:14:50 [D|app|ca548] Current location set to City
2019-02-21T10:14:50 [D|app|ca548] Current organization set to Company Name
2019-02-21T10:14:50 [I|app|] Started GET "/hosts/testhost.domain.local/nics" for 172.29.6.15 at 2019-02-21 10:14:50 +0100
2019-02-21T10:14:50 [I|app|358dd] Processing by HostsController#templates as HTML
2019-02-21T10:14:50 [I|app|358dd]   Parameters: {"id"=>"testhost.domain.local"}
2019-02-21T10:14:50 [I|app|] Started GET "/hosts/testhost.domain.local/resources?range=7" for 172.29.6.15 at 2019-02-21 10:14:50 +0100
2019-02-21T10:14:50 [I|app|] Started GET "/hosts/testhost.domain.local/runtime?range=7" for 172.29.6.15 at 2019-02-21 10:14:50 +0100
2019-02-21T10:14:50 [I|app|358dd] Current user set to admin (admin)
2019-02-21T10:14:50 [I|app|a9a3f] Processing by HostsController#nics as HTML
2019-02-21T10:14:50 [I|app|a9a3f]   Parameters: {"id"=>"testhost.domain.local"}
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [I|app|a9a3f] Current user set to admin (admin)
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [I|app|ae47e] Processing by HostsController#runtime as HTML
2019-02-21T10:14:50 [I|app|ae47e]   Parameters: {"range"=>"7", "id"=>"testhost.domain.local"}
2019-02-21T10:14:50 [D|app|a9a3f] Current location set to City
2019-02-21T10:14:50 [D|app|a9a3f] Current organization set to Company Name
2019-02-21T10:14:50 [I|app|35eee] Processing by HostsController#resources as HTML
2019-02-21T10:14:50 [I|app|35eee]   Parameters: {"range"=>"7", "id"=>"testhost.domain.local"}
2019-02-21T10:14:50 [I|app|35eee] Current user set to admin (admin)
2019-02-21T10:14:50 [I|app|ae47e] Current user set to admin (admin)
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|35eee] Current location set to City
2019-02-21T10:14:50 [D|app|35eee] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|ae47e] Current location set to City
2019-02-21T10:14:50 [D|app|ae47e] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [I|app|35eee]   Rendered hosts/_resources.html.erb (10.3ms)
2019-02-21T10:14:50 [I|app|35eee] Completed 200 OK in 35ms (Views: 5.9ms | ActiveRecord: 19.2ms)
2019-02-21T10:14:50 [I|app|ae47e]   Rendered hosts/_runtime.html.erb (4.1ms)
2019-02-21T10:14:50 [I|app|ae47e] Completed 200 OK in 42ms (Views: 5.1ms | ActiveRecord: 26.7ms)
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current location set to City
2019-02-21T10:14:50 [D|app|358dd] Current organization set to Company Name
2019-02-21T10:14:50 [I|app|a9a3f]   Rendered hosts/_nics.html.erb (152.6ms)
2019-02-21T10:14:50 [I|app|a9a3f] Completed 200 OK in 168ms (Views: 141.8ms | ActiveRecord: 16.5ms)
2019-02-21T10:14:50 [I|app|358dd]   Rendered hosts/_templates.html.erb (22.4ms)
2019-02-21T10:14:50 [I|app|358dd] Completed 200 OK in 192ms (Views: 25.0ms | ActiveRecord: 56.8ms)
2019-02-21T10:14:50 [I|app|ca548]   Rendered hosts/_overview.html.erb (197.5ms)
2019-02-21T10:14:50 [I|app|ca548] Completed 200 OK in 214ms (Views: 171.2ms | ActiveRecord: 33.4ms)
2019-02-21T10:15:00 [I|app|] Started GET "/notification_recipients" for 172.29.6.15 at 2019-02-21 10:15:00 +0100
2019-02-21T10:15:00 [I|app|5d446] Processing by NotificationRecipientsController#index as JSON
2019-02-21T10:15:00 [I|app|5d446] Current user set to admin (admin)
2019-02-21T10:15:00 [D|app|5d446] Current location set to City
2019-02-21T10:15:00 [D|app|5d446] Current organization set to Company Name
2019-02-21T10:15:00 [D|not|5d446] Cache Hit: notification, reading cache for notification-6
2019-02-21T10:15:00 [D|app|5d446] Body: {"notifications":[{"id":953,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-21T09:02:09.558Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/625","title":"Job Details"}]}}]}
2019-02-21T10:15:00 [I|app|5d446] Completed 200 OK in 8ms (Views: 0.1ms | ActiveRecord: 1.4ms)

And this is the Log when I use the “Action” Combolist in the hostslist

2019-02-21T10:19:50 [I|dyn|] start terminating throttle_limiter...
2019-02-21T10:19:50 [I|dyn|] start terminating client dispatcher...
2019-02-21T10:19:50 [I|dyn|] start terminating client dispatcher...
2019-02-21T10:19:50 [I|dyn|] start terminating client dispatcher...
2019-02-21T10:19:50 [I|dyn|] stop listening for new events...
2019-02-21T10:19:50 [I|dyn|] stop listening for new events...
2019-02-21T10:19:50 [I|dyn|] stop listening for new events...
2019-02-21T10:19:50 [I|dyn|] start terminating clock...
2019-02-21T10:19:50 [I|dyn|] start terminating clock...
2019-02-21T10:19:50 [I|dyn|] start terminating clock...
2019-02-21T10:21:15 [I|app|] Started GET "/hosts" for 172.29.6.15 at 2019-02-21 10:21:15 +0100
2019-02-21T10:21:15 [I|app|da739] Processing by HostsController#index as HTML
2019-02-21T10:21:15 [I|app|da739] Current user set to admin (admin)
2019-02-21T10:21:15 [D|app|da739] Current location set to City
2019-02-21T10:21:15 [D|app|da739] Current organization set to Company Name
2019-02-21T10:21:16 [I|app|da739]   Rendering hosts/index.html.erb within layouts/application
2019-02-21T10:21:16 [I|app|da739]   Rendered common/_pagination.html.erb (0.8ms)
2019-02-21T10:21:16 [I|app|da739]   Rendered hosts/_list.html.erb (140.1ms)
2019-02-21T10:21:16 [I|app|da739]   Rendered hosts/index.html.erb within layouts/application (151.9ms)
2019-02-21T10:21:16 [I|app|da739]   Rendered common/_searchbar.html.erb (0.7ms)
2019-02-21T10:21:16 [I|app|da739]   Rendered layouts/_application_content.html.erb (1.8ms)
2019-02-21T10:21:16 [I|app|da739]   Rendering layouts/base.html.erb
2019-02-21T10:21:16 [I|app|da739]   Rendered layouts/base.html.erb (14.0ms)
2019-02-21T10:21:16 [I|app|da739] Completed 200 OK in 247ms (Views: 150.1ms | ActiveRecord: 38.9ms)
2019-02-21T10:21:16 [I|app|] Started GET "/hosts" for 172.29.6.15 at 2019-02-21 10:21:16 +0100
2019-02-21T10:21:16 [I|app|a5099] Processing by HostsController#index as HTML
2019-02-21T10:21:16 [I|app|a5099] Current user set to admin (admin)
2019-02-21T10:21:16 [D|app|a5099] Current location set to City
2019-02-21T10:21:16 [D|app|a5099] Current organization set to Company Name
2019-02-21T10:21:16 [I|app|a5099]   Rendering hosts/index.html.erb within layouts/application
2019-02-21T10:21:16 [I|app|a5099]   Rendered common/_pagination.html.erb (0.3ms)
2019-02-21T10:21:16 [I|app|a5099]   Rendered hosts/_list.html.erb (129.7ms)
2019-02-21T10:21:16 [I|app|a5099]   Rendered hosts/index.html.erb within layouts/application (136.4ms)
2019-02-21T10:21:16 [I|app|a5099]   Rendered common/_searchbar.html.erb (0.3ms)
2019-02-21T10:21:16 [I|app|a5099]   Rendered layouts/_application_content.html.erb (0.8ms)
2019-02-21T10:21:16 [I|app|a5099]   Rendering layouts/base.html.erb
2019-02-21T10:21:16 [I|app|a5099]   Rendered layouts/base.html.erb (13.7ms)
2019-02-21T10:21:16 [I|app|a5099] Completed 200 OK in 190ms (Views: 135.3ms | ActiveRecord: 24.3ms)
2019-02-21T10:21:16 [I|app|] Started GET "/notification_recipients" for 172.29.6.15 at 2019-02-21 10:21:16 +0100
2019-02-21T10:21:16 [I|app|c4f70] Processing by NotificationRecipientsController#index as JSON
2019-02-21T10:21:16 [I|app|c4f70] Current user set to admin (admin)
2019-02-21T10:21:16 [D|app|c4f70] Current location set to City
2019-02-21T10:21:16 [D|app|c4f70] Current organization set to Company Name
2019-02-21T10:21:16 [D|not|c4f70] Cache Hit: notification, reading cache for notification-6
2019-02-21T10:21:16 [D|app|c4f70] Body: {"notifications":[{"id":953,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-21T09:02:09.558Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/625","title":"Job Details"}]}}]}
2019-02-21T10:21:16 [I|app|c4f70] Completed 200 OK in 9ms (Views: 0.1ms | ActiveRecord: 1.3ms)
2019-02-21T10:21:16 [I|app|] Started GET "/notification_recipients" for 172.29.6.15 at 2019-02-21 10:21:16 +0100
2019-02-21T10:21:16 [I|app|1a482] Processing by NotificationRecipientsController#index as JSON
2019-02-21T10:21:16 [I|app|1a482] Current user set to admin (admin)
2019-02-21T10:21:16 [D|app|1a482] Current location set to City
2019-02-21T10:21:16 [D|app|1a482] Current organization set to Company Name
2019-02-21T10:21:16 [D|not|1a482] Cache Hit: notification, reading cache for notification-6
2019-02-21T10:21:16 [D|app|1a482] Body: {"notifications":[{"id":953,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-21T09:02:09.558Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/625","title":"Job Details"}]}}]}
2019-02-21T10:21:16 [I|app|1a482] Completed 200 OK in 8ms (Views: 0.1ms | ActiveRecord: 1.4ms)
2019-02-21T10:21:16 [I|app|] Started GET "/hosts/83/power" for 172.29.6.15 at 2019-02-21 10:21:16 +0100
2019-02-21T10:21:16 [I|app|31863] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:16 [I|app|31863]   Parameters: {"id"=>"83"}
2019-02-21T10:21:16 [I|app|31863] Current user set to admin (admin)
2019-02-21T10:21:16 [I|app|] Started GET "/hosts/1/power" for 172.29.6.15 at 2019-02-21 10:21:16 +0100
2019-02-21T10:21:16 [D|app|31863] Current location set to City
2019-02-21T10:21:16 [D|app|31863] Current organization set to Company Name
2019-02-21T10:21:16 [I|app|31863] Completed 200 OK in 20ms (Views: 0.3ms | ActiveRecord: 6.1ms)
2019-02-21T10:21:16 [I|app|] Started GET "/hosts/53/power" for 172.29.6.15 at 2019-02-21 10:21:16 +0100
2019-02-21T10:21:16 [I|app|33f4e] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:16 [I|app|33f4e]   Parameters: {"id"=>"53"}
2019-02-21T10:21:16 [I|app|33f4e] Current user set to admin (admin)
2019-02-21T10:21:16 [D|app|33f4e] Current location set to City
2019-02-21T10:21:16 [D|app|33f4e] Current organization set to Company Name
2019-02-21T10:21:16 [I|app|61747] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:16 [I|app|61747]   Parameters: {"id"=>"1"}
2019-02-21T10:21:16 [I|app|33f4e] Completed 200 OK in 17ms (Views: 0.2ms | ActiveRecord: 4.0ms)
2019-02-21T10:21:16 [I|app|] Started GET "/hosts/79/power" for 172.29.6.15 at 2019-02-21 10:21:16 +0100
2019-02-21T10:21:16 [I|app|c8e43] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:16 [I|app|c8e43]   Parameters: {"id"=>"79"}
2019-02-21T10:21:16 [I|app|c8e43] Current user set to admin (admin)
2019-02-21T10:21:16 [D|app|c8e43] Current location set to City
2019-02-21T10:21:16 [D|app|c8e43] Current organization set to Company Name
2019-02-21T10:21:16 [I|app|c8e43] Completed 200 OK in 17ms (Views: 0.2ms | ActiveRecord: 3.8ms)
2019-02-21T10:21:16 [I|app|] Started GET "/hosts/82/power" for 172.29.6.15 at 2019-02-21 10:21:16 +0100
2019-02-21T10:21:16 [I|app|61747] Current user set to admin (admin)
2019-02-21T10:21:16 [I|app|00a72] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:16 [I|app|00a72]   Parameters: {"id"=>"82"}
2019-02-21T10:21:16 [I|app|00a72] Current user set to admin (admin)
2019-02-21T10:21:16 [D|app|00a72] Current location set to City
2019-02-21T10:21:16 [D|app|00a72] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|00a72] Completed 200 OK in 19ms (Views: 0.3ms | ActiveRecord: 4.3ms)
2019-02-21T10:21:17 [D|app|61747] Current location set to City
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/26/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [D|app|61747] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|00d9e] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|00d9e]   Parameters: {"id"=>"26"}
2019-02-21T10:21:17 [I|app|00d9e] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|00d9e] Current location set to City
2019-02-21T10:21:17 [D|app|00d9e] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|00d9e] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.5ms)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/61/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|13db5] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|13db5]   Parameters: {"id"=>"61"}
2019-02-21T10:21:17 [I|app|13db5] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|13db5] Current location set to City
2019-02-21T10:21:17 [D|app|13db5] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|13db5] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.5ms)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/86/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|c128c] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|c128c]   Parameters: {"id"=>"86"}
2019-02-21T10:21:17 [I|app|c128c] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|c128c] Current location set to City
2019-02-21T10:21:17 [D|app|c128c] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|c128c] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.6ms)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/62/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|b8ab0] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|b8ab0]   Parameters: {"id"=>"62"}
2019-02-21T10:21:17 [I|app|b8ab0] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|b8ab0] Current location set to City
2019-02-21T10:21:17 [D|app|b8ab0] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|b8ab0] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.5ms)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/101/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|e2cab] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|e2cab]   Parameters: {"id"=>"101"}
2019-02-21T10:21:17 [I|app|61747] Completed 200 OK in 161ms (Views: 0.5ms | ActiveRecord: 14.8ms)
2019-02-21T10:21:17 [I|app|e2cab] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|e2cab] Current location set to City
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/63/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [D|app|e2cab] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|37098] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|37098]   Parameters: {"id"=>"63"}
2019-02-21T10:21:17 [I|app|37098] Current user set to admin (admin)
2019-02-21T10:21:17 [I|app|e2cab] Completed 200 OK in 16ms (Views: 0.3ms | ActiveRecord: 3.5ms)
2019-02-21T10:21:17 [D|app|37098] Current location set to City
2019-02-21T10:21:17 [D|app|37098] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/64/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|c3d9b] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|c3d9b]   Parameters: {"id"=>"64"}
2019-02-21T10:21:17 [I|app|c3d9b] Current user set to admin (admin)
2019-02-21T10:21:17 [I|app|37098] Completed 200 OK in 16ms (Views: 0.2ms | ActiveRecord: 4.1ms)
2019-02-21T10:21:17 [D|app|c3d9b] Current location set to City
2019-02-21T10:21:17 [D|app|c3d9b] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/65/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|5c3e0] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|5c3e0]   Parameters: {"id"=>"65"}
2019-02-21T10:21:17 [I|app|5c3e0] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|5c3e0] Current location set to City
2019-02-21T10:21:17 [D|app|5c3e0] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|5c3e0] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.3ms)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/66/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|f9a3d] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|f9a3d]   Parameters: {"id"=>"66"}
2019-02-21T10:21:17 [I|app|f9a3d] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|f9a3d] Current location set to City
2019-02-21T10:21:17 [I|app|c3d9b] Completed 200 OK in 40ms (Views: 0.3ms | ActiveRecord: 5.8ms)
2019-02-21T10:21:17 [D|app|f9a3d] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/67/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|ee227] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|ee227]   Parameters: {"id"=>"67"}
2019-02-21T10:21:17 [I|app|f9a3d] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.9ms)
2019-02-21T10:21:17 [I|app|ee227] Current user set to admin (admin)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/69/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [D|app|ee227] Current location set to City
2019-02-21T10:21:17 [D|app|ee227] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|bb505] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|bb505]   Parameters: {"id"=>"69"}
2019-02-21T10:21:17 [I|app|bb505] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|bb505] Current location set to City
2019-02-21T10:21:17 [D|app|bb505] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|ee227] Completed 200 OK in 16ms (Views: 0.2ms | ActiveRecord: 4.0ms)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/72/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|0b4cb] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|0b4cb]   Parameters: {"id"=>"72"}
2019-02-21T10:21:17 [I|app|bb505] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.5ms)
2019-02-21T10:21:17 [I|app|0b4cb] Current user set to admin (admin)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/71/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [D|app|0b4cb] Current location set to City
2019-02-21T10:21:17 [D|app|0b4cb] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|b26f5] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|b26f5]   Parameters: {"id"=>"71"}
2019-02-21T10:21:17 [I|app|b26f5] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|b26f5] Current location set to City
2019-02-21T10:21:17 [D|app|b26f5] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|0b4cb] Completed 200 OK in 14ms (Views: 0.2ms | ActiveRecord: 3.2ms)
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/73/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [I|app|b6888] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|b6888]   Parameters: {"id"=>"73"}
2019-02-21T10:21:17 [I|app|b26f5] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.9ms)
2019-02-21T10:21:17 [I|app|b6888] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|b6888] Current location set to City
2019-02-21T10:21:17 [I|app|] Started GET "/hosts/76/power" for 172.29.6.15 at 2019-02-21 10:21:17 +0100
2019-02-21T10:21:17 [D|app|b6888] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|c7f5d] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:17 [I|app|c7f5d]   Parameters: {"id"=>"76"}
2019-02-21T10:21:17 [I|app|c7f5d] Current user set to admin (admin)
2019-02-21T10:21:17 [D|app|c7f5d] Current location set to City
2019-02-21T10:21:17 [D|app|c7f5d] Current organization set to Company Name
2019-02-21T10:21:17 [I|app|b6888] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.8ms)
2019-02-21T10:21:17 [I|app|c7f5d] Completed 200 OK in 14ms (Views: 0.2ms | ActiveRecord: 3.3ms)
2019-02-21T10:21:20 [I|app|] Started GET "/hosts?page=2" for 172.29.6.15 at 2019-02-21 10:21:20 +0100
2019-02-21T10:21:20 [I|app|2a4f1] Processing by HostsController#index as HTML
2019-02-21T10:21:20 [I|app|2a4f1]   Parameters: {"page"=>"2"}
2019-02-21T10:21:20 [I|app|2a4f1] Current user set to admin (admin)
2019-02-21T10:21:20 [D|app|2a4f1] Current location set to City
2019-02-21T10:21:20 [D|app|2a4f1] Current organization set to Company Name
2019-02-21T10:21:20 [I|app|2a4f1]   Rendering hosts/index.html.erb within layouts/application
2019-02-21T10:21:21 [I|app|2a4f1]   Rendered common/_pagination.html.erb (0.8ms)
2019-02-21T10:21:21 [I|app|2a4f1]   Rendered hosts/_list.html.erb (427.4ms)
2019-02-21T10:21:21 [I|app|2a4f1]   Rendered hosts/index.html.erb within layouts/application (454.4ms)
2019-02-21T10:21:21 [I|app|2a4f1]   Rendered common/_searchbar.html.erb (0.6ms)
2019-02-21T10:21:21 [I|app|2a4f1]   Rendered layouts/_application_content.html.erb (2.3ms)
2019-02-21T10:21:21 [I|app|2a4f1]   Rendering layouts/base.html.erb
2019-02-21T10:21:21 [I|app|2a4f1]   Rendered layouts/base.html.erb (20.2ms)
2019-02-21T10:21:21 [I|app|2a4f1] Completed 200 OK in 633ms (Views: 464.1ms | ActiveRecord: 45.9ms)
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/75/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/36/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/91/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|01226] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|01226]   Parameters: {"id"=>"75"}
2019-02-21T10:21:21 [I|app|176ae] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|176ae]   Parameters: {"id"=>"36"}
2019-02-21T10:21:21 [I|app|01226] Current user set to admin (admin)
2019-02-21T10:21:21 [I|app|176ae] Current user set to admin (admin)
2019-02-21T10:21:21 [D|app|176ae] Current location set to City
2019-02-21T10:21:21 [D|app|01226] Current location set to City
2019-02-21T10:21:21 [D|app|176ae] Current organization set to Company Name
2019-02-21T10:21:21 [D|app|01226] Current organization set to Company Name
2019-02-21T10:21:21 [I|app|176ae] Completed 200 OK in 16ms (Views: 0.2ms | ActiveRecord: 3.5ms)
2019-02-21T10:21:21 [I|app|01226] Completed 200 OK in 16ms (Views: 0.2ms | ActiveRecord: 3.6ms)
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/102/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/47/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|97442] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|97442]   Parameters: {"id"=>"102"}
2019-02-21T10:21:21 [I|app|abf17] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|abf17]   Parameters: {"id"=>"47"}
2019-02-21T10:21:21 [I|app|97442] Current user set to admin (admin)
2019-02-21T10:21:21 [I|app|abf17] Current user set to admin (admin)
2019-02-21T10:21:21 [D|app|97442] Current location set to City
2019-02-21T10:21:21 [D|app|abf17] Current location set to City
2019-02-21T10:21:21 [D|app|97442] Current organization set to Company Name
2019-02-21T10:21:21 [D|app|abf17] Current organization set to Company Name
2019-02-21T10:21:21 [I|app|26a93] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|26a93]   Parameters: {"id"=>"91"}
2019-02-21T10:21:21 [I|app|97442] Completed 200 OK in 16ms (Views: 0.3ms | ActiveRecord: 3.8ms)
2019-02-21T10:21:21 [I|app|abf17] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.3ms)
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/97/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/90/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|55749] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|55749]   Parameters: {"id"=>"90"}
2019-02-21T10:21:21 [I|app|9c54d] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|9c54d]   Parameters: {"id"=>"97"}
2019-02-21T10:21:21 [I|app|9c54d] Current user set to admin (admin)
2019-02-21T10:21:21 [I|app|55749] Current user set to admin (admin)
2019-02-21T10:21:21 [D|app|9c54d] Current location set to City
2019-02-21T10:21:21 [D|app|55749] Current location set to City
2019-02-21T10:21:21 [D|app|9c54d] Current organization set to Company Name
2019-02-21T10:21:21 [D|app|55749] Current organization set to Company Name
2019-02-21T10:21:21 [I|app|9c54d] Completed 200 OK in 15ms (Views: 0.3ms | ActiveRecord: 3.4ms)
2019-02-21T10:21:21 [I|app|55749] Completed 200 OK in 15ms (Views: 0.3ms | ActiveRecord: 3.8ms)
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/80/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/117/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|8c9c7] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|8c9c7]   Parameters: {"id"=>"80"}
2019-02-21T10:21:21 [I|app|a87ab] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|a87ab]   Parameters: {"id"=>"117"}
2019-02-21T10:21:21 [I|app|8c9c7] Current user set to admin (admin)
2019-02-21T10:21:21 [I|app|a87ab] Current user set to admin (admin)
2019-02-21T10:21:21 [D|app|8c9c7] Current location set to City
2019-02-21T10:21:21 [D|app|a87ab] Current location set to City
2019-02-21T10:21:21 [I|app|26a93] Current user set to admin (admin)
2019-02-21T10:21:21 [D|app|8c9c7] Current organization set to Company Name
2019-02-21T10:21:21 [D|app|a87ab] Current organization set to Company Name
2019-02-21T10:21:21 [I|app|8c9c7] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.4ms)
2019-02-21T10:21:21 [I|app|a87ab] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.6ms)
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/105/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|] Started GET "/hosts/85/power" for 172.29.6.15 at 2019-02-21 10:21:21 +0100
2019-02-21T10:21:21 [I|app|72b4c] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|72b4c]   Parameters: {"id"=>"85"}
2019-02-21T10:21:21 [I|app|3ceee] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:21 [I|app|3ceee]   Parameters: {"id"=>"105"}
2019-02-21T10:21:21 [I|app|72b4c] Current user set to admin (admin)
2019-02-21T10:21:21 [D|app|26a93] Current location set to City
2019-02-21T10:21:21 [I|app|3ceee] Current user set to admin (admin)
2019-02-21T10:21:21 [D|app|72b4c] Current location set to City
2019-02-21T10:21:21 [D|app|3ceee] Current location set to City
2019-02-21T10:21:21 [D|app|72b4c] Current organization set to Company Name
2019-02-21T10:21:21 [D|app|3ceee] Current organization set to Company Name
2019-02-21T10:21:21 [D|app|26a93] Current organization set to Company Name
2019-02-21T10:21:21 [I|app|72b4c] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.5ms)
2019-02-21T10:21:21 [I|app|3ceee] Completed 200 OK in 15ms (Views: 0.3ms | ActiveRecord: 3.7ms)
2019-02-21T10:21:21 [I|app|26a93] Completed 200 OK in 170ms (Views: 0.5ms | ActiveRecord: 14.8ms)
2019-02-21T10:21:26 [I|app|] Started GET "/ansible/hosts/multiple_play_roles?host_ids%5B%5D=105" for 172.29.6.15 at 2019-02-21 10:21:26 +0100
2019-02-21T10:21:26 [I|app|34efc] Processing by HostsController#multiple_play_roles as HTML
2019-02-21T10:21:26 [I|app|34efc]   Parameters: {"host_ids"=>["105"]}
2019-02-21T10:21:26 [I|app|34efc] Current user set to admin (admin)
2019-02-21T10:21:26 [D|app|34efc] Current location set to City
2019-02-21T10:21:26 [D|app|34efc] Current organization set to Company Name
2019-02-21T10:21:27 [I|app|] Started GET "/notification_recipients" for 172.29.6.15 at 2019-02-21 10:21:27 +0100
2019-02-21T10:21:27 [I|app|e5f50] Processing by NotificationRecipientsController#index as JSON
2019-02-21T10:21:27 [I|app|e5f50] Current user set to admin (admin)
2019-02-21T10:21:27 [D|app|e5f50] Current location set to City
2019-02-21T10:21:27 [D|app|e5f50] Current organization set to Company Name
2019-02-21T10:21:27 [D|not|e5f50] Cache Hit: notification, reading cache for notification-6
2019-02-21T10:21:27 [D|app|e5f50] Body: {"notifications":[{"id":953,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-21T09:02:09.558Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/625","title":"Job Details"}]}}]}
2019-02-21T10:21:27 [I|app|e5f50] Completed 200 OK in 71ms (Views: 0.4ms | ActiveRecord: 7.5ms)
2019-02-21T10:21:27 [I|app|34efc] Redirected to https://bsgforeman.domain.local/hosts
2019-02-21T10:21:27 [I|app|34efc] Completed 302 Found in 485ms (ActiveRecord: 37.9ms)
2019-02-21T10:21:27 [I|app|] Started GET "/hosts" for 172.29.6.15 at 2019-02-21 10:21:27 +0100
2019-02-21T10:21:27 [I|app|44c71] Processing by HostsController#index as HTML
2019-02-21T10:21:27 [I|app|44c71] Current user set to admin (admin)
2019-02-21T10:21:27 [D|app|44c71] Current location set to City
2019-02-21T10:21:27 [D|app|44c71] Current organization set to Company Name
2019-02-21T10:21:27 [I|app|44c71]   Rendering hosts/index.html.erb within layouts/application
2019-02-21T10:21:27 [I|app|44c71]   Rendered common/_pagination.html.erb (0.8ms)
2019-02-21T10:21:27 [I|app|44c71]   Rendered hosts/_list.html.erb (153.8ms)
2019-02-21T10:21:27 [I|app|44c71]   Rendered hosts/index.html.erb within layouts/application (180.4ms)
2019-02-21T10:21:27 [I|app|44c71]   Rendered common/_searchbar.html.erb (0.6ms)
2019-02-21T10:21:27 [I|app|44c71]   Rendered layouts/_application_content.html.erb (2.3ms)
2019-02-21T10:21:27 [I|app|44c71]   Rendering layouts/base.html.erb
2019-02-21T10:21:27 [I|app|44c71]   Rendered layouts/base.html.erb (19.6ms)
2019-02-21T10:21:27 [I|app|44c71] Completed 200 OK in 334ms (Views: 183.5ms | ActiveRecord: 49.7ms)
2019-02-21T10:21:28 [I|app|] Started GET "/notification_recipients" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|69ea4] Processing by NotificationRecipientsController#index as JSON
2019-02-21T10:21:28 [I|app|69ea4] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|69ea4] Current location set to City
2019-02-21T10:21:28 [D|app|69ea4] Current organization set to Company Name
2019-02-21T10:21:28 [D|not|69ea4] Cache Hit: notification, reading cache for notification-6
2019-02-21T10:21:28 [D|app|69ea4] Body: {"notifications":[{"id":953,"seen":false,"level":"success","text":"A job 'Run Ansible roles' has finished successfully","created_at":"2019-02-21T09:02:09.558Z","group":"Jobs","actions":{"links":[{"href":"/job_invocations/625","title":"Job Details"}]}}]}
2019-02-21T10:21:28 [I|app|69ea4] Completed 200 OK in 9ms (Views: 0.1ms | ActiveRecord: 1.3ms)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/83/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|8c6d7] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|8c6d7]   Parameters: {"id"=>"83"}
2019-02-21T10:21:28 [I|app|8c6d7] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/1/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [D|app|8c6d7] Current location set to City
2019-02-21T10:21:28 [D|app|8c6d7] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|f5095] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|f5095]   Parameters: {"id"=>"1"}
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/53/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|f5095] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|f5095] Current location set to City
2019-02-21T10:21:28 [D|app|f5095] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|8c6d7] Completed 200 OK in 16ms (Views: 0.3ms | ActiveRecord: 4.0ms)
2019-02-21T10:21:28 [I|app|20638] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|20638]   Parameters: {"id"=>"53"}
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/79/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|20638] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|20638] Current location set to City
2019-02-21T10:21:28 [I|app|80dae] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|80dae]   Parameters: {"id"=>"79"}
2019-02-21T10:21:28 [D|app|20638] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/82/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|6cd3b] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|6cd3b]   Parameters: {"id"=>"82"}
2019-02-21T10:21:28 [I|app|6cd3b] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|6cd3b] Current location set to City
2019-02-21T10:21:28 [I|app|20638] Completed 200 OK in 17ms (Views: 0.2ms | ActiveRecord: 4.6ms)
2019-02-21T10:21:28 [D|app|6cd3b] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|80dae] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|80dae] Current location set to City
2019-02-21T10:21:28 [D|app|80dae] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|6cd3b] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.3ms)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/26/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/61/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|0a90f] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|0a90f]   Parameters: {"id"=>"61"}
2019-02-21T10:21:28 [I|app|a1b5f] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|a1b5f]   Parameters: {"id"=>"26"}
2019-02-21T10:21:28 [I|app|0a90f] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|a1b5f] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|0a90f] Current location set to City
2019-02-21T10:21:28 [D|app|0a90f] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|80dae] Completed 200 OK in 35ms (Views: 0.2ms | ActiveRecord: 22.8ms)
2019-02-21T10:21:28 [D|app|a1b5f] Current location set to City
2019-02-21T10:21:28 [D|app|a1b5f] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|0a90f] Completed 200 OK in 14ms (Views: 0.2ms | ActiveRecord: 3.3ms)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/86/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/62/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|a60a5] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|a60a5]   Parameters: {"id"=>"86"}
2019-02-21T10:21:28 [I|app|cd71b] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|cd71b]   Parameters: {"id"=>"62"}
2019-02-21T10:21:28 [I|app|cd71b] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|a1b5f] Completed 200 OK in 26ms (Views: 0.2ms | ActiveRecord: 14.7ms)
2019-02-21T10:21:28 [D|app|cd71b] Current location set to City
2019-02-21T10:21:28 [I|app|a60a5] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|cd71b] Current organization set to Company Name
2019-02-21T10:21:28 [D|app|a60a5] Current location set to City
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/101/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [D|app|a60a5] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|de9c7] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|de9c7]   Parameters: {"id"=>"101"}
2019-02-21T10:21:28 [I|app|cd71b] Completed 200 OK in 17ms (Views: 0.2ms | ActiveRecord: 5.5ms)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/63/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|de9c7] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|bf673] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|bf673]   Parameters: {"id"=>"63"}
2019-02-21T10:21:28 [I|app|bf673] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|a60a5] Completed 200 OK in 29ms (Views: 0.2ms | ActiveRecord: 17.3ms)
2019-02-21T10:21:28 [D|app|bf673] Current location set to City
2019-02-21T10:21:28 [D|app|de9c7] Current location set to City
2019-02-21T10:21:28 [D|app|bf673] Current organization set to Company Name
2019-02-21T10:21:28 [D|app|de9c7] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/64/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|f5095] Completed 200 OK in 102ms (Views: 0.3ms | ActiveRecord: 8.7ms)
2019-02-21T10:21:28 [I|app|bf673] Completed 200 OK in 16ms (Views: 0.2ms | ActiveRecord: 4.5ms)
2019-02-21T10:21:28 [I|app|5082f] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|5082f]   Parameters: {"id"=>"64"}
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/65/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/66/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|48c24] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|48c24]   Parameters: {"id"=>"65"}
2019-02-21T10:21:28 [I|app|de9c7] Completed 200 OK in 30ms (Views: 0.2ms | ActiveRecord: 18.9ms)
2019-02-21T10:21:28 [I|app|927a2] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|927a2]   Parameters: {"id"=>"66"}
2019-02-21T10:21:28 [I|app|48c24] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|927a2] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|5082f] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/69/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [D|app|927a2] Current location set to City
2019-02-21T10:21:28 [D|app|927a2] Current organization set to Company Name
2019-02-21T10:21:28 [D|app|5082f] Current location set to City
2019-02-21T10:21:28 [D|app|48c24] Current location set to City
2019-02-21T10:21:28 [I|app|c3c2e] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|c3c2e]   Parameters: {"id"=>"69"}
2019-02-21T10:21:28 [D|app|5082f] Current organization set to Company Name
2019-02-21T10:21:28 [D|app|48c24] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|c3c2e] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|c3c2e] Current location set to City
2019-02-21T10:21:28 [D|app|c3c2e] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|927a2] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.2ms)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/72/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|912c3] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|912c3]   Parameters: {"id"=>"72"}
2019-02-21T10:21:28 [I|app|c3c2e] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.7ms)
2019-02-21T10:21:28 [I|app|48c24] Completed 200 OK in 26ms (Views: 0.2ms | ActiveRecord: 14.0ms)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/67/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/71/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|5082f] Completed 200 OK in 35ms (Views: 0.3ms | ActiveRecord: 22.3ms)
2019-02-21T10:21:28 [I|app|912c3] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|18f1a] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|18f1a]   Parameters: {"id"=>"67"}
2019-02-21T10:21:28 [D|app|912c3] Current location set to City
2019-02-21T10:21:28 [I|app|32d5d] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|32d5d]   Parameters: {"id"=>"71"}
2019-02-21T10:21:28 [I|app|18f1a] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|912c3] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|32d5d] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|18f1a] Current location set to City
2019-02-21T10:21:28 [D|app|32d5d] Current location set to City
2019-02-21T10:21:28 [D|app|18f1a] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/73/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [D|app|32d5d] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|912c3] Completed 200 OK in 20ms (Views: 0.2ms | ActiveRecord: 8.8ms)
2019-02-21T10:21:28 [I|app|18f1a] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 4.0ms)
2019-02-21T10:21:28 [I|app|] Started GET "/hosts/76/power" for 172.29.6.15 at 2019-02-21 10:21:28 +0100
2019-02-21T10:21:28 [I|app|33daa] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|33daa]   Parameters: {"id"=>"73"}
2019-02-21T10:21:28 [I|app|33daa] Current user set to admin (admin)
2019-02-21T10:21:28 [I|app|89194] Processing by HostsController#get_power_state as JSON
2019-02-21T10:21:28 [I|app|89194]   Parameters: {"id"=>"76"}
2019-02-21T10:21:28 [D|app|33daa] Current location set to City
2019-02-21T10:21:28 [I|app|32d5d] Completed 200 OK in 21ms (Views: 0.2ms | ActiveRecord: 9.1ms)
2019-02-21T10:21:28 [I|app|89194] Current user set to admin (admin)
2019-02-21T10:21:28 [D|app|33daa] Current organization set to Company Name
2019-02-21T10:21:28 [D|app|89194] Current location set to City
2019-02-21T10:21:28 [D|app|89194] Current organization set to Company Name
2019-02-21T10:21:28 [I|app|33daa] Completed 200 OK in 16ms (Views: 0.2ms | ActiveRecord: 4.1ms)
2019-02-21T10:21:28 [I|app|89194] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 3.9ms)

these are the log settings:

:logging:
  :level:debug
  :production:
    :type: file
    :layout: pattern

Thank you for reporting this, unfortunately I do not see any errors in the logs. I’ll test to see if I can reproduce.

@Edll, everything works for me on a fresh Foreman install. When you get the errors, are you logged in as admin? What version of foreman_remote_execution do you have?

I get the error when in logged in as internal “admin” and I get the error when i logged in as LDAP User with Administration rights.

foreman_remote_execution version is 1.6.7

Is there something I need to assign to the host group or organization/location?

I installed Forman first and then Ansible later. The strange thing is that I only get the error with the button but if I do the whole thing using the "Schedule Remote Job” function it works. Therefore I suspect that it is possibly a faulty configuration.

That is really strange. No additional configuration should be needed, the button worked for me right away…

How does the button work? Is this some kind of shortcut? Is there anything else I can debug?

The button is a shortcut for running a job template right away on the given host. The job template that’s used is determined by feature configuration. In order to change it, go to Administer -> Remote execution features -> see ansible_run_host. By default, it should be set to Ansible Roles - Ansible Default which is the job template that will be executed once you click the button. It’s really strange we don’t see anything in uploaded logs. If it creates job invocation and you’re on its detail page, could you go to a detail for a given host by clicking its name in hosts table, you should see the “terminal” like window with buttons on top of it. Click Task Details, which should have Result: error. Swtich to error tab and upload what you see there.

1 Like

I found my mistake. Under “Administer/Remote Execution Feature/Job Template” was “Module Action - Ansible Default” selected. If I change this to “Ansible Roles - Ansible Default” the button works.

Thanks for the help!

Maybe you can reproduce this with this information and find out why no log output at all comes to the error.