When provisioning a host in EC2 using a finish script, the API returns an error saying it failed to run the script. The Logs also report this when provisioning through the UI, but the error message is hidden. However, the provisioning finish script is uploaded and ran on the host successfully, Foreman just is not reporting this as such.
Expected outcome:
When running the same provisioning in Foreman 3.15 or earlier, the host builds just fine without error in the logs, api, or UI.
Foreman and Proxy versions:
3.16
Foreman and Proxy plugin versions:
foreman_discovery → 26.0.0
foreman_puppet → 9.0.0
Distribution and version:
EL9
Other relevant data:
API / Log error returns as Unable to build host: 422 {“errors”:{“base”:[“Failed to launch script on empty-test-box110-app.g1-cmh.qops.net: undefined method `’ for nil:NilClass”]}}
Logs
025-10-06T16:21:48 [I|app|bf5566ad] negotiating protocol version
2025-10-06T16:21:48 [I|app|bf5566ad] sending KEXINIT
2025-10-06T16:21:48 [I|app|bf5566ad] got KEXINIT from server
2025-10-06T16:21:48 [I|app|bf5566ad] negotiating algorithms
2025-10-06T16:21:48 [E|app|bf5566ad] could not connect to ssh-agent: Agent not configured
2025-10-06T16:21:48 [I|app|bf5566ad] global request received: hostkeys-00@openssh.com false
2025-10-06T16:21:48 [I|app|bf5566ad] channel_open_confirmation: 0 0 0 32768
2025-10-06T16:21:48 [I|app|bf5566ad] sending channel request "pty-req"
2025-10-06T16:21:48 [I|app|bf5566ad] sending channel request "exec"
2025-10-06T16:21:48 [I|app|bf5566ad] channel_window_adjust: 0 +2097152
2025-10-06T16:21:48 [I|app|bf5566ad] channel_success: 0
2025-10-06T16:21:48 [I|app|bf5566ad] channel_data: 0 20b
2025-10-06T16:21:52 [I|app|bf5566ad] channel_data: 0 81b
2025-10-06T16:21:53 [I|app|7c80832a] Started GET "/users/login" for ::1 at 2025-10-06 16:21:53 +0000
2025-10-06T16:21:53 [I|app|7c80832a] Processing by UsersController#login as */*
2025-10-06T16:21:53 [I|app|7c80832a] Rendered users/login.html.erb within layouts/login (Duration: 0.5ms | Allocations: 205)
2025-10-06T16:21:53 [I|app|7c80832a] Rendered layouts/base.html.erb (Duration: 1.7ms | Allocations: 1314)
2025-10-06T16:21:53 [I|app|7c80832a] Rendered layout layouts/login.html.erb (Duration: 2.6ms | Allocations: 1688)
2025-10-06T16:21:53 [I|app|7c80832a] Completed 200 OK in 8ms (Views: 3.0ms | ActiveRecord: 2.5ms | Allocations: 2863)
2025-10-06T16:21:53 [I|app|3722a758] Started GET "/users/login" for ::1 at 2025-10-06 16:21:53 +0000
2025-10-06T16:21:53 [I|app|3722a758] Processing by UsersController#login as HTML
2025-10-06T16:21:53 [I|app|3722a758] Rendered users/login.html.erb within layouts/login (Duration: 0.4ms | Allocations: 205)
2025-10-06T16:21:53 [I|app|3722a758] Rendered layouts/base.html.erb (Duration: 3.2ms | Allocations: 1569)
2025-10-06T16:21:53 [I|app|3722a758] Rendered layout layouts/login.html.erb (Duration: 4.0ms | Allocations: 1938)
2025-10-06T16:21:53 [I|app|3722a758] Completed 200 OK in 9ms (Views: 4.3ms | ActiveRecord: 2.5ms | Allocations: 3001)
2025-10-06T16:21:53 [I|app|bf5566ad] channel_data: 0 81b
2025-10-06T16:21:54 [I|app|5a6ce4b5] Started GET "/notification_recipients" for 10.66.62.118 at 2025-10-06 16:21:54 +0000
2025-10-06T16:21:54 [I|app|5a6ce4b5] Processing by NotificationRecipientsController#index as JSON
2025-10-06T16:21:54 [I|app|5a6ce4b5] Completed 200 OK in 4ms (Views: 0.1ms | ActiveRecord: 0.7ms | Allocations: 1454)
2025-10-06T16:22:00 [I|app|bf5566ad] channel_data: 0 81b
2025-10-06T16:22:02 [I|app|bf5566ad] channel_data: 0 81b
2025-10-06T16:22:03 [I|app|b5c10e36] Started GET "/users/login" for ::1 at 2025-10-06 16:22:03 +0000
2025-10-06T16:22:03 [I|app|b5c10e36] Processing by UsersController#login as HTML
2025-10-06T16:22:03 [I|app|b5c10e36] Rendered users/login.html.erb within layouts/login (Duration: 0.5ms | Allocations: 210)
2025-10-06T16:22:03 [I|app|b5c10e36] Rendered layouts/base.html.erb (Duration: 1.7ms | Allocations: 1317)
2025-10-06T16:22:03 [I|app|b5c10e36] Rendered layout layouts/login.html.erb (Duration: 2.6ms | Allocations: 1692)
2025-10-06T16:22:03 [I|app|b5c10e36] Completed 200 OK in 8ms (Views: 2.9ms | ActiveRecord: 2.5ms | Allocations: 2797)
2025-10-06T16:22:04 [I|app|c7957d5b] Started GET "/notification_recipients" for 10.66.62.118 at 2025-10-06 16:22:04 +0000
2025-10-06T16:22:04 [I|app|c7957d5b] Processing by NotificationRecipientsController#index as JSON
2025-10-06T16:22:04 [I|app|c7957d5b] Completed 200 OK in 4ms (Views: 0.1ms | ActiveRecord: 0.5ms | Allocations: 1453)
2025-10-06T16:22:08 [I|app|bf5566ad] channel_data: 0 81b
2025-10-06T16:22:08 [I|app|bf5566ad] channel_data: 0 81b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 22b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 11b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 19b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 18b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 25b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 20b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 21b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 81b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 82b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 82b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 27b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 30b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 26b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 29b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 21b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 1b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 1b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:09 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 1b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 112b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 116b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 38b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 40b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 38b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 40b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 24b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 38b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 9b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 9b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 9b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 9b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 4b
2025-10-06T16:22:10 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 208b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 25b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 38b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 35b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 66b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 61b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 66b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 64b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 68b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 69b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 71b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 72b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 63b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 64b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 64b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 68b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 64b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 67b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 67b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 70b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 74b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 62b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 65b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 63b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 71b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 66b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 63b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 70b
2025-10-06T16:22:11 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:12 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:12 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:13 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:13 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:13 [I|app|25bedfa5] Started GET "/users/login" for ::1 at 2025-10-06 16:22:13 +0000
2025-10-06T16:22:13 [I|app|25bedfa5] Processing by UsersController#login as HTML
2025-10-06T16:22:13 [I|app|25bedfa5] Rendered users/login.html.erb within layouts/login (Duration: 0.5ms | Allocations: 205)
2025-10-06T16:22:13 [I|app|25bedfa5] Rendered layouts/base.html.erb (Duration: 1.7ms | Allocations: 1315)
2025-10-06T16:22:13 [I|app|25bedfa5] Rendered layout layouts/login.html.erb (Duration: 2.5ms | Allocations: 1684)
2025-10-06T16:22:13 [I|app|25bedfa5] Completed 200 OK in 9ms (Views: 2.8ms | ActiveRecord: 3.2ms | Allocations: 2749)
2025-10-06T16:22:14 [I|app|b4b19a8e] Started GET "/notification_recipients" for 10.66.62.118 at 2025-10-06 16:22:14 +0000
2025-10-06T16:22:14 [I|app|b4b19a8e] Processing by NotificationRecipientsController#index as JSON
2025-10-06T16:22:14 [I|app|b4b19a8e] Completed 200 OK in 4ms (Views: 0.1ms | ActiveRecord: 0.5ms | Allocations: 1453)
2025-10-06T16:22:14 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:14 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 22b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 11b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 19b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 18b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 25b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 20b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 21b
2025-10-06T16:22:15 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 25b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 28b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 24b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 27b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 19b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 1b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 1b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 133b
2025-10-06T16:22:16 [I|app|bf5566ad] channel_data: 0 79b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 1b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 10b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 9b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 2b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_data: 0 80b
2025-10-06T16:22:17 [I|app|bf5566ad] channel_eof: 0
2025-10-06T16:22:17 [I|app|bf5566ad] channel_request: 0 exit-status false
2025-10-06T16:22:17 [I|app|bf5566ad] channel_close: 0
2025-10-06T16:22:17 [I|app|bf5566ad] 10.66.11.18 delete channel 0 which closed locally and remotely
2025-10-06T16:22:17 [I|app|bf5566ad] closing remaining channels (0 open)
2025-10-06T16:22:17 [W|app|bf5566ad] Failed to launch script on empty-test-box110-app.g1-cmh.qops.net: undefined method `[]' for nil:NilClass
2025-10-06T16:22:17 [I|app|bf5566ad] Backtrace for 'Failed to launch script on empty-test-box110-app.g1-cmh.qops.net: undefined method `[]' for nil:NilClass' error (NoMethodError): undefined method `[]' for nil:NilClass
bf5566ad | /usr/share/foreman/app/models/concerns/hostext/operating_system.rb:41:in `template_kinds'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration/templates.rb:43:in `kind'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration/templates.rb:35:in `template'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration/templates.rb:12:in `queue_render_checks'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:400:in `block in make_lambda'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:247:in `block in halting'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:599:in `block in invoke_after'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:599:in `each'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:599:in `invoke_after'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:108:in `run_callbacks'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:929:in `_run_validation_callbacks'
bf5566ad | /usr/share/gems/gems/activemodel-7.0.8.7/lib/active_model/validations/callbacks.rb:115:in `run_validations!'
bf5566ad | /usr/share/gems/gems/activemodel-7.0.8.7/lib/active_model/validations.rb:337:in `valid?'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/validations.rb:68:in `valid?'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration.rb:83:in `valid?'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/validations.rb:84:in `perform_validations'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/validations.rb:47:in `save'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/transactions.rb:298:in `block in save'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/transactions.rb:354:in `block in with_transaction_returning_status'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/transaction.rb:319:in `block in within_new_transaction'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:25:in `handle_interrupt'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:25:in `block in synchronize'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:21:in `handle_interrupt'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:21:in `synchronize'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/transaction.rb:317:in `within_new_transaction'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/database_statements.rb:316:in `transaction'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/transactions.rb:350:in `with_transaction_returning_status'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/transactions.rb:298:in `save'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/suppressor.rb:50:in `save'
bf5566ad | /usr/share/foreman/app/models/host/managed.rb:387:in `built'
bf5566ad | /usr/share/foreman/app/models/concerns/hostext/ui_notifications.rb:9:in `built'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration/ssh_provision.rb:66:in `setSSHProvision'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration.rb:227:in `execute'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration.rb:149:in `block in process'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration.rb:141:in `each'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration.rb:141:in `process'
bf5566ad | /usr/share/foreman/app/models/concerns/orchestration.rb:53:in `post_commit'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:400:in `block in make_lambda'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:271:in `block in simple'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:599:in `block in invoke_after'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:599:in `each'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:599:in `invoke_after'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:108:in `run_callbacks'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:929:in `_run_commit_callbacks'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/transactions.rb:321:in `committed!'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/transaction.rb:155:in `commit_records'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/transaction.rb:304:in `block in commit_transaction'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:25:in `handle_interrupt'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:25:in `block in synchronize'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:21:in `handle_interrupt'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:21:in `synchronize'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/transaction.rb:294:in `commit_transaction'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/transaction.rb:345:in `block in within_new_transaction'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:25:in `handle_interrupt'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:25:in `block in synchronize'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:21:in `handle_interrupt'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/concurrency/load_interlock_aware_monitor.rb:21:in `synchronize'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/transaction.rb:317:in `within_new_transaction'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/connection_adapters/abstract/database_statements.rb:316:in `transaction'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/transactions.rb:350:in `with_transaction_returning_status'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/transactions.rb:298:in `save'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/suppressor.rb:50:in `save'
bf5566ad | /usr/share/foreman/app/controllers/api/v2/hosts_controller.rb:148:in `create'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/abstract_controller/base.rb:215:in `process_action'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_controller/metal/rendering.rb:165:in `process_action'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/abstract_controller/callbacks.rb:234:in `block in process_action'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
bf5566ad | /usr/share/foreman/app/controllers/concerns/foreman/controller/timezone.rb:10:in `set_timezone'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:127:in `block in run_callbacks'
bf5566ad | /usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:127:in `block in run_callbacks'
bf5566ad | /usr/share/foreman/app/controllers/concerns/foreman/controller/topbar_sweeper.rb:12:in `set_topbar_sweeper_controller'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:127:in `block in run_callbacks'
bf5566ad | /usr/share/gems/gems/audited-5.8.0/lib/audited/sweeper.rb:16:in `around'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:127:in `block in run_callbacks'
bf5566ad | /usr/share/gems/gems/audited-5.8.0/lib/audited/sweeper.rb:16:in `around'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:127:in `block in run_callbacks'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:138:in `run_callbacks'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/abstract_controller/callbacks.rb:233:in `process_action'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_controller/metal/rescue.rb:23:in `process_action'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_controller/metal/instrumentation.rb:67:in `block in process_action'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/notifications.rb:206:in `block in instrument'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/notifications/instrumenter.rb:24:in `instrument'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/notifications.rb:206:in `instrument'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_controller/metal/instrumentation.rb:66:in `process_action'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_controller/metal/params_wrapper.rb:259:in `process_action'
bf5566ad | /usr/share/gems/gems/activerecord-7.0.8.7/lib/active_record/railties/controller_runtime.rb:27:in `process_action'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/abstract_controller/base.rb:151:in `process'
bf5566ad | /usr/share/gems/gems/actionview-7.0.8.7/lib/action_view/rendering.rb:39:in `process'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_controller/metal.rb:188:in `dispatch'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_controller/metal.rb:251:in `dispatch'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/routing/route_set.rb:32:in `serve'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/routing/mapper.rb:18:in `block in <class:Constraints>'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/routing/mapper.rb:48:in `serve'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/journey/router.rb:50:in `block in serve'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/journey/router.rb:32:in `each'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/journey/router.rb:32:in `serve'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/routing/route_set.rb:852:in `call'
bf5566ad | /usr/share/gems/gems/apipie-dsl-2.6.2/lib/apipie_dsl/static_dispatcher.rb:67:in `call'
bf5566ad | /usr/share/gems/gems/apipie-rails-1.4.2/lib/apipie/static_dispatcher.rb:74:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/static.rb:23:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/static.rb:23:in `call'
bf5566ad | /usr/share/foreman/lib/foreman/middleware/libvirt_connection_cleaner.rb:9:in `call'
bf5566ad | /usr/share/foreman/lib/foreman/middleware/telemetry.rb:10:in `call'
bf5566ad | /usr/share/gems/gems/apipie-rails-1.4.2/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/tempfile_reaper.rb:15:in `call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/etag.rb:27:in `call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/conditional_get.rb:40:in `call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/head.rb:12:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/http/permissions_policy.rb:38:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/http/content_security_policy.rb:39:in `call'
bf5566ad | /usr/share/foreman/lib/foreman/middleware/logging_context_session.rb:22:in `call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/session/abstract/id.rb:266:in `context'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/session/abstract/id.rb:260:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/cookies.rb:704:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/callbacks.rb:27:in `block in call'
bf5566ad | /usr/share/gems/gems/activesupport-7.0.8.7/lib/active_support/callbacks.rb:99:in `run_callbacks'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/callbacks.rb:26:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/debug_exceptions.rb:28:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/show_exceptions.rb:29:in `call'
bf5566ad | /usr/share/gems/gems/railties-7.0.8.7/lib/rails/rack/logger.rb:40:in `call_app'
bf5566ad | /usr/share/gems/gems/railties-7.0.8.7/lib/rails/rack/logger.rb:27:in `call'
bf5566ad | /usr/share/gems/gems/sprockets-rails-3.5.2/lib/sprockets/rails/quiet_assets.rb:17:in `call'
bf5566ad | /usr/share/foreman/lib/foreman/middleware/logging_context_request.rb:11:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/remote_ip.rb:93:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/request_id.rb:26:in `call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/method_override.rb:24:in `call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/runtime.rb:22:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/executor.rb:14:in `call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/sendfile.rb:110:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/ssl.rb:77:in `call'
bf5566ad | /usr/share/gems/gems/actionpack-7.0.8.7/lib/action_dispatch/middleware/host_authorization.rb:131:in `call'
bf5566ad | /usr/share/gems/gems/secure_headers-7.1.0/lib/secure_headers/middleware.rb:11:in `call'
bf5566ad | /usr/share/gems/gems/railties-7.0.8.7/lib/rails/engine.rb:530:in `call'
bf5566ad | /usr/share/gems/gems/railties-7.0.8.7/lib/rails/railtie.rb:226:in `public_send'
bf5566ad | /usr/share/gems/gems/railties-7.0.8.7/lib/rails/railtie.rb:226:in `method_missing'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/urlmap.rb:74:in `block in call'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/urlmap.rb:58:in `each'
bf5566ad | /usr/local/share/gems/gems/rack-2.2.18/lib/rack/urlmap.rb:58:in `call'
bf5566ad | /usr/share/gems/gems/puma-6.6.1/lib/puma/configuration.rb:279:in `call'
bf5566ad | /usr/share/gems/gems/puma-6.6.1/lib/puma/request.rb:99:in `block in handle_request'
bf5566ad | /usr/share/gems/gems/puma-6.6.1/lib/puma/thread_pool.rb:390:in `with_force_shutdown'
bf5566ad | /usr/share/gems/gems/puma-6.6.1/lib/puma/request.rb:98:in `handle_request'
bf5566ad | /usr/share/gems/gems/puma-6.6.1/lib/puma/server.rb:472:in `process_client'
bf5566ad | /usr/share/gems/gems/puma-6.6.1/lib/puma/server.rb:254:in `block in run'
bf5566ad | /usr/share/gems/gems/puma-6.6.1/lib/puma/thread_pool.rb:167:in `block in spawn_thread'
bf5566ad | /usr/share/gems/gems/logging-2.4.0/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2025-10-06T16:22:17 [W|app|bf5566ad] Rolling back due to a problem: [#<Orchestration::Task:0x00007f6d4f841480 @name="Configure instance empty-test-box110-app.g1-cmh.qops.net via SSH", @id="Configure instance empty-test-box110-app.g1-cmh.qops.net via SSH", @status="failed", @priority=2003, @action=[#<Host::Managed id: 4033, name: "empty-test-box110-app.g1-cmh.qops.net", last_compile: nil, last_report: nil, updated_at: "2025-10-06 16:21:17.278408671 +0000", created_at: "2025-10-06 16:21:17.278408671 +0000", root_pass: nil, architecture_id: 1, operatingsystem_id: 18, ptable_id: nil, medium_id: nil, build: true, comment: "", disk: nil, installed_at: nil, model_id: nil, hostgroup_id: 435, owner_id: 4, owner_type: "User", enabled: true, puppet_ca_proxy_id: nil, managed: true, use_image: nil, image_file: nil, uuid: "i-0566735ade8628b8c", compute_resource_id: 1, puppet_proxy_id: nil, certname: nil, image_id: 27, organization_id: 1, location_id: 3, type: "Host::Managed", otp: nil, realm_id: nil, compute_profile_id: 57, provision_method: nil, grub_pass: nil, discovery_rule_id: nil, global_status: 0, lookup_value_matcher: [FILTERED], pxe_loader: nil, initiated_at: nil, build_errors: nil, creator_id: 4>, :setSSHProvision], @created=1759767667.999983, @timestamp=2025-10-06 16:22:17.424724023 UTC>]