Description of problem: When trying to navigate to an ansible-playbook service to review the provisioning stdout the UI crashes. I ran into this problem on an upgraded 5.9.0 to 5.9.1 appliance. Then I built a new 5.9.1.0 appliance and the same issue is happening. Version-Release number of selected component (if applicable): 5.9.1.0 How reproducible: 100% Steps to Reproduce: 1. deploy an ansible-playbook service and attempt to see the provisioning stdout of the playbook Actual results: UI error is thrown. Expected results: to see the stdout out of the playbook. Additional info: URL https://2cloudforms-summitautomatingse-xw4shiqm.srv.ravcloud.com/api/tasks/1000000000121?attributes=task_results Status 500 Internal Server Error Content-Type application/json; charset=utf-8 Data {"error":{"kind":"internal_server_error","message":"\"\\xE2\" from ASCII-8BIT to UTF-8","klass":"Encoding::UndefinedConversionError"}} [----] I, [2018-03-18T02:10:11.068500 #2547:aa693c] INFO -- : Completed 500 Internal Server Error in 75ms (Views: 0.4ms | ActiveRecord: 20.0ms) Here is the production log: [----] I, [2018-03-18T02:09:40.021410 #2531:bd2540] INFO -- : Started POST "/service/tree_select?id=s-1r3" for 64.146.226.199 at 2018-03-18 02:09:40 -0400 [----] I, [2018-03-18T02:09:40.025034 #2531:bd2540] INFO -- : Processing by ServiceController#tree_select as JS [----] I, [2018-03-18T02:09:40.025210 #2531:bd2540] INFO -- : Parameters: {"id"=>"s-1r3"} [----] I, [2018-03-18T02:09:40.102826 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_flash_msg.html.haml (0.1ms) [----] I, [2018-03-18T02:09:40.104073 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.2ms) [----] I, [2018-03-18T02:09:40.104610 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.105065 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.105266 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (1.9ms) [----] I, [2018-03-18T02:09:40.110470 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.110996 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.111313 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.112149 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.5ms) [----] I, [2018-03-18T02:09:40.112603 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.112808 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (6.9ms) [----] I, [2018-03-18T02:09:40.113424 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.113790 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual_tags.html.haml (0.6ms) [----] I, [2018-03-18T02:09:40.116835 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.117184 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (2.8ms) [----] I, [2018-03-18T02:09:40.117383 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_raw.html.haml (14.1ms) [----] I, [2018-03-18T02:09:40.117498 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_generic.html.haml (14.9ms) [----] I, [2018-03-18T02:09:40.120317 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_info_msg.html.haml (0.1ms) [----] I, [2018-03-18T02:09:40.121701 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/angular/_gtl.html.haml (1.9ms) [----] I, [2018-03-18T02:09:40.122039 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_gtl.html.haml (2.3ms) [----] I, [2018-03-18T02:09:40.127102 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.2ms) [----] I, [2018-03-18T02:09:40.127432 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.127752 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.128201 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.128775 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.129207 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (3.5ms) [----] I, [2018-03-18T02:09:40.136797 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual_table.html.haml (0.1ms) [----] I, [2018-03-18T02:09:40.144695 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.1ms) [----] I, [2018-03-18T02:09:40.145159 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.145487 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.146126 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:09:40.146446 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (6.4ms) [----] I, [2018-03-18T02:09:40.158181 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.1ms) [----] I, [2018-03-18T02:09:40.158397 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (8.6ms) [----] I, [2018-03-18T02:09:40.158514 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_raw.html.haml (36.0ms) [----] I, [2018-03-18T02:09:40.158784 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_tabs.html.haml (36.4ms) [----] I, [2018-03-18T02:09:40.176269 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/service/_svcs_show.html.haml (81.4ms) [----] I, [2018-03-18T02:09:40.183272 #2531:bd2540] INFO -- : Completed 200 OK in 158ms (Views: 3.8ms | ActiveRecord: 22.2ms) [----] I, [2018-03-18T02:09:40.423475 #2531:bd5f10] INFO -- : Started GET "/static/ansible-raw-stdout.html.haml" for 64.146.226.199 at 2018-03-18 02:09:40 -0400 [----] I, [2018-03-18T02:09:40.426409 #2531:bd5f10] INFO -- : Processing by StaticController#show as HTML [----] I, [2018-03-18T02:09:40.426639 #2531:bd5f10] INFO -- : Parameters: {"id"=>"ansible-raw-stdout.html.haml"} [----] I, [2018-03-18T02:09:40.427442 #2531:bd5f10] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/static/ansible-raw-stdout.html.haml [----] I, [2018-03-18T02:09:40.428249 #2531:bd5f10] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/static/ansible-raw-stdout.html.haml (0.6ms) [----] I, [2018-03-18T02:09:40.428661 #2531:bd5f10] INFO -- : Completed 200 OK in 2ms (Views: 1.3ms | ActiveRecord: 0.0ms) [----] I, [2018-03-18T02:09:40.543451 #2531:bd5f10] INFO -- : Started POST "/service/report_data" for 64.146.226.199 at 2018-03-18 02:09:40 -0400 [----] I, [2018-03-18T02:09:40.546268 #2531:bd5f10] INFO -- : Processing by ServiceController#report_data as HTML [----] I, [2018-03-18T02:09:40.546478 #2531:bd5f10] INFO -- : Parameters: {"model_name"=>"Vm", "model"=>"Vm", "active_tree"=>"svcs_tree", "parent_id"=>"1000000000003", "model_id"=>"1000000000003", "explorer"=>true, "records[]"=>[], "records"=>[], "additional_options"=>{"named_scope"=>nil, "gtl_dbname"=>nil, "model"=>"Vm", "match_via_descendants"=>nil, "parent_id"=>1000000000003, "parent_class_name"=>"ServiceAnsiblePlaybook", "parent_method"=>"all_vms", "association"=>nil, "view_suffix"=>nil, "row_button"=>nil, "menu_click"=>nil, "sb_controller"=>nil, "listicon"=>nil, "embedded"=>nil, "showlinks"=>nil, "policy_sim"=>nil, "in_a_form"=>nil, "lastaction"=>"show", "display"=>"main", "gtl_type"=>"grid", "supported_features_filter"=>nil, "clickable"=>nil}, "service"=>{}} [----] I, [2018-03-18T02:09:40.636823 #2531:bd5f10] INFO -- : Completed 200 OK in 90ms (Views: 1.0ms | ActiveRecord: 14.2ms) [----] I, [2018-03-18T02:09:40.968843 #2547:aa693c] INFO -- : Started GET "/api/tasks/1000000000112?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:09:40 -0400 [----] I, [2018-03-18T02:09:40.972234 #2547:aa693c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:09:40.972407 #2547:aa693c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000112"} [----] I, [2018-03-18T02:09:41.003753 #2547:aa693c] INFO -- : Completed 200 OK in 31ms (Views: 0.1ms | ActiveRecord: 5.3ms) [----] I, [2018-03-18T02:09:42.113317 #2547:aa62e8] INFO -- : Started GET "/api/tasks/1000000000112?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:09:42 -0400 [----] I, [2018-03-18T02:09:42.117080 #2547:aa62e8] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:09:42.117260 #2547:aa62e8] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000112"} [----] I, [2018-03-18T02:09:42.147086 #2547:aa62e8] INFO -- : Completed 200 OK in 30ms (Views: 0.1ms | ActiveRecord: 4.8ms) [----] I, [2018-03-18T02:09:43.259643 #2547:aa6554] INFO -- : Started GET "/api/tasks/1000000000112?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:09:43 -0400 [----] I, [2018-03-18T02:09:43.263340 #2547:aa6554] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:09:43.263518 #2547:aa6554] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000112"} [----] I, [2018-03-18T02:09:43.294174 #2547:aa6554] INFO -- : Completed 200 OK in 30ms (Views: 0.2ms | ActiveRecord: 4.6ms) [----] I, [2018-03-18T02:09:44.403341 #2547:aa67e8] INFO -- : Started GET "/api/tasks/1000000000112?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:09:44 -0400 [----] I, [2018-03-18T02:09:44.407078 #2547:aa67e8] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:09:44.407257 #2547:aa67e8] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000112"} [----] I, [2018-03-18T02:09:44.437510 #2547:aa67e8] INFO -- : Completed 200 OK in 30ms (Views: 0.1ms | ActiveRecord: 4.4ms) [----] I, [2018-03-18T02:09:45.542178 #2547:aa6b1c] INFO -- : Started GET "/api/tasks/1000000000112?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:09:45 -0400 [----] I, [2018-03-18T02:09:45.545710 #2547:aa6b1c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:09:45.546014 #2547:aa6b1c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000112"} [----] I, [2018-03-18T02:09:45.577335 #2547:aa6b1c] INFO -- : Completed 200 OK in 31ms (Views: 0.1ms | ActiveRecord: 5.2ms) [----] I, [2018-03-18T02:09:46.686186 #2547:aa693c] INFO -- : Started GET "/api/tasks/1000000000112?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:09:46 -0400 [----] I, [2018-03-18T02:09:46.689460 #2547:aa693c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:09:46.689636 #2547:aa693c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000112"} [----] I, [2018-03-18T02:09:46.718402 #2547:aa693c] INFO -- : Completed 200 OK in 29ms (Views: 0.3ms | ActiveRecord: 4.5ms) [----] I, [2018-03-18T02:09:47.831310 #2547:aa62e8] INFO -- : Started GET "/api/tasks/1000000000112?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:09:47 -0400 [----] I, [2018-03-18T02:09:47.835090 #2547:aa62e8] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:09:47.835268 #2547:aa62e8] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000112"} [----] I, [2018-03-18T02:09:47.911501 #2547:aa62e8] INFO -- : Completed 200 OK in 76ms (Views: 0.3ms | ActiveRecord: 16.9ms) [----] I, [2018-03-18T02:09:48.854363 #2547:aa6554] INFO -- : Started GET "/api" for 64.146.226.199 at 2018-03-18 02:09:48 -0400 [----] I, [2018-03-18T02:09:48.857821 #2547:aa6554] INFO -- : Processing by Api::ApiController#index as JSON [----] I, [2018-03-18T02:09:48.886515 #2547:aa6554] INFO -- : Completed 200 OK in 28ms (Views: 0.1ms | ActiveRecord: 3.4ms) [----] I, [2018-03-18T02:09:48.953511 #2547:aa67e8] INFO -- : Started DELETE "/api/tasks/1000000000112" for 64.146.226.199 at 2018-03-18 02:09:48 -0400 [----] I, [2018-03-18T02:09:48.955494 #2547:aa67e8] INFO -- : Processing by Api::TasksController#destroy as JSON [----] I, [2018-03-18T02:09:48.955752 #2547:aa67e8] INFO -- : Parameters: {"c_id"=>"1000000000112"} [----] I, [2018-03-18T02:09:48.989155 #2547:aa67e8] INFO -- : Completed 204 No Content in 33ms (ActiveRecord: 7.4ms) [----] I, [2018-03-18T02:10:02.037454 #2531:bd248c] INFO -- : Started POST "/service/tree_select?id=s-1r4" for 64.146.226.199 at 2018-03-18 02:10:02 -0400 [----] I, [2018-03-18T02:10:02.041133 #2531:bd248c] INFO -- : Processing by ServiceController#tree_select as JS [----] I, [2018-03-18T02:10:02.041310 #2531:bd248c] INFO -- : Parameters: {"id"=>"s-1r4"} [----] I, [2018-03-18T02:10:02.121049 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_flash_msg.html.haml (0.1ms) [----] I, [2018-03-18T02:10:02.122142 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.122462 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.122781 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.123176 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (1.5ms) [----] I, [2018-03-18T02:10:02.128284 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.128697 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.129152 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.129506 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.129833 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.130091 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (6.4ms) [----] I, [2018-03-18T02:10:02.130478 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.130703 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual_tags.html.haml (0.4ms) [----] I, [2018-03-18T02:10:02.133360 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.133645 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (2.6ms) [----] I, [2018-03-18T02:10:02.133762 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_raw.html.haml (12.4ms) [----] I, [2018-03-18T02:10:02.134008 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_generic.html.haml (13.1ms) [----] I, [2018-03-18T02:10:02.136143 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_info_msg.html.haml (0.1ms) [----] I, [2018-03-18T02:10:02.137321 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/angular/_gtl.html.haml (1.6ms) [----] I, [2018-03-18T02:10:02.137448 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_gtl.html.haml (2.0ms) [----] I, [2018-03-18T02:10:02.142221 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.142461 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.142786 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.143157 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.143470 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.143732 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (2.9ms) [----] I, [2018-03-18T02:10:02.151609 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual_table.html.haml (0.1ms) [----] I, [2018-03-18T02:10:02.159749 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.160205 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.160441 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.160762 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:10:02.161029 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (6.2ms) [----] I, [2018-03-18T02:10:02.173360 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.1ms) [----] I, [2018-03-18T02:10:02.173496 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (9.7ms) [----] I, [2018-03-18T02:10:02.173695 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_raw.html.haml (35.8ms) [----] I, [2018-03-18T02:10:02.173808 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_tabs.html.haml (36.0ms) [----] I, [2018-03-18T02:10:02.191723 #2531:bd248c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/service/_svcs_show.html.haml (78.4ms) [----] I, [2018-03-18T02:10:02.200610 #2531:bd248c] INFO -- : Completed 200 OK in 159ms (Views: 4.9ms | ActiveRecord: 24.0ms) [----] I, [2018-03-18T02:10:02.462086 #2531:bd2400] INFO -- : Started GET "/static/ansible-raw-stdout.html.haml" for 64.146.226.199 at 2018-03-18 02:10:02 -0400 [----] I, [2018-03-18T02:10:02.465488 #2531:bd2400] INFO -- : Processing by StaticController#show as HTML [----] I, [2018-03-18T02:10:02.465744 #2531:bd2400] INFO -- : Parameters: {"id"=>"ansible-raw-stdout.html.haml"} [----] I, [2018-03-18T02:10:02.466823 #2531:bd2400] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/static/ansible-raw-stdout.html.haml [----] I, [2018-03-18T02:10:02.467302 #2531:bd2400] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/static/ansible-raw-stdout.html.haml (0.1ms) [----] I, [2018-03-18T02:10:02.467652 #2531:bd2400] INFO -- : Completed 200 OK in 1ms (Views: 1.2ms | ActiveRecord: 0.0ms) [----] I, [2018-03-18T02:10:02.576168 #2531:bd2400] INFO -- : Started POST "/service/report_data" for 64.146.226.199 at 2018-03-18 02:10:02 -0400 [----] I, [2018-03-18T02:10:02.581116 #2531:bd2400] INFO -- : Processing by ServiceController#report_data as HTML [----] I, [2018-03-18T02:10:02.581346 #2531:bd2400] INFO -- : Parameters: {"model_name"=>"Vm", "model"=>"Vm", "active_tree"=>"svcs_tree", "parent_id"=>"1000000000004", "model_id"=>"1000000000004", "explorer"=>true, "records[]"=>[], "records"=>[], "additional_options"=>{"named_scope"=>nil, "gtl_dbname"=>nil, "model"=>"Vm", "match_via_descendants"=>nil, "parent_id"=>1000000000004, "parent_class_name"=>"ServiceAnsiblePlaybook", "parent_method"=>"all_vms", "association"=>nil, "view_suffix"=>nil, "row_button"=>nil, "menu_click"=>nil, "sb_controller"=>nil, "listicon"=>nil, "embedded"=>nil, "showlinks"=>nil, "policy_sim"=>nil, "in_a_form"=>nil, "lastaction"=>"show", "display"=>"main", "gtl_type"=>"grid", "supported_features_filter"=>nil, "clickable"=>nil}, "service"=>{}} [----] I, [2018-03-18T02:10:02.683621 #2531:bd2400] INFO -- : Completed 200 OK in 102ms (Views: 1.2ms | ActiveRecord: 15.0ms) [----] I, [2018-03-18T02:10:02.984052 #2547:aa6b1c] INFO -- : Started GET "/api/tasks/1000000000113?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:10:02 -0400 [----] I, [2018-03-18T02:10:02.987722 #2547:aa6b1c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:10:02.988019 #2547:aa6b1c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:03.020408 #2547:aa6b1c] INFO -- : Completed 200 OK in 32ms (Views: 0.3ms | ActiveRecord: 5.6ms) [----] I, [2018-03-18T02:10:03.147332 #2547:aa693c] INFO -- : Started GET "/api/providers" for 127.0.0.1 at 2018-03-18 02:10:03 -0400 [----] I, [2018-03-18T02:10:03.150843 #2547:aa693c] INFO -- : Processing by Api::ProvidersController#index as JSON [----] I, [2018-03-18T02:10:03.152104 #2547:aa693c] INFO -- : Filter chain halted as :require_api_user_or_token rendered or redirected [----] I, [2018-03-18T02:10:03.152386 #2547:aa693c] INFO -- : Completed 401 Unauthorized in 1ms (ActiveRecord: 0.0ms) [----] I, [2018-03-18T02:10:03.159768 #2547:aa62e8] INFO -- : Started GET "/api/providers" for 127.0.0.1 at 2018-03-18 02:10:03 -0400 [----] I, [2018-03-18T02:10:03.162033 #2547:aa62e8] INFO -- : Processing by Api::ProvidersController#index as JSON [----] I, [2018-03-18T02:10:03.303523 #2547:aa62e8] INFO -- : Completed 200 OK in 141ms (Views: 0.1ms | ActiveRecord: 13.2ms) [----] I, [2018-03-18T02:10:03.772699 #2547:aa6554] INFO -- : Started POST "/api/providers" for 127.0.0.1 at 2018-03-18 02:10:03 -0400 [----] I, [2018-03-18T02:10:03.776825 #2547:aa6554] INFO -- : Processing by Api::ProvidersController#update as JSON [----] I, [2018-03-18T02:10:03.777160 #2547:aa6554] INFO -- : Parameters: {"resources"=>[{"href"=>"https://localhost/api/providers/1000000000002"}, {"href"=>"https://localhost/api/providers/1000000000004"}, {"href"=>"https://localhost/api/providers/1000000000003"}, {"href"=>"https://localhost/api/providers/1000000000001"}, {"href"=>"https://localhost/api/providers/1000000000005"}, {"href"=>"https://localhost/api/providers/1000000000009"}, {"href"=>"https://localhost/api/providers/1000000000006"}, {"href"=>"https://localhost/api/providers/1000000000007"}, {"href"=>"https://localhost/api/providers/1000000000008"}], "provider"=>{}} [----] I, [2018-03-18T02:10:03.778515 #2547:aa6554] INFO -- : Filter chain halted as :require_api_user_or_token rendered or redirected [----] I, [2018-03-18T02:10:03.778992 #2547:aa6554] INFO -- : Completed 401 Unauthorized in 1ms (ActiveRecord: 0.0ms) [----] I, [2018-03-18T02:10:03.786780 #2547:aa67e8] INFO -- : Started POST "/api/providers" for 127.0.0.1 at 2018-03-18 02:10:03 -0400 [----] I, [2018-03-18T02:10:03.789653 #2547:aa67e8] INFO -- : Processing by Api::ProvidersController#update as JSON [----] I, [2018-03-18T02:10:03.789990 #2547:aa67e8] INFO -- : Parameters: {"resources"=>[{"href"=>"https://localhost/api/providers/1000000000002"}, {"href"=>"https://localhost/api/providers/1000000000004"}, {"href"=>"https://localhost/api/providers/1000000000003"}, {"href"=>"https://localhost/api/providers/1000000000001"}, {"href"=>"https://localhost/api/providers/1000000000005"}, {"href"=>"https://localhost/api/providers/1000000000009"}, {"href"=>"https://localhost/api/providers/1000000000006"}, {"href"=>"https://localhost/api/providers/1000000000007"}, {"href"=>"https://localhost/api/providers/1000000000008"}], "provider"=>{}} [----] I, [2018-03-18T02:10:04.111850 #2547:aa67e8] INFO -- : Completed 200 OK in 322ms (Views: 0.2ms | ActiveRecord: 65.6ms) [----] I, [2018-03-18T02:10:04.125183 #2547:aa6b1c] INFO -- : Started GET "/api/tasks/1000000000113?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:10:04 -0400 [----] I, [2018-03-18T02:10:04.128786 #2547:aa6b1c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:10:04.129083 #2547:aa6b1c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:04.161355 #2547:aa6b1c] INFO -- : Completed 200 OK in 32ms (Views: 0.1ms | ActiveRecord: 5.5ms) [----] I, [2018-03-18T02:10:05.270661 #2547:aa693c] INFO -- : Started GET "/api/tasks/1000000000113?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:10:05 -0400 [----] I, [2018-03-18T02:10:05.274380 #2547:aa693c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:10:05.274622 #2547:aa693c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:05.304447 #2547:aa693c] INFO -- : Completed 200 OK in 29ms (Views: 0.3ms | ActiveRecord: 4.4ms) [----] I, [2018-03-18T02:10:06.413471 #2547:aa62e8] INFO -- : Started GET "/api/tasks/1000000000113?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:10:06 -0400 [----] I, [2018-03-18T02:10:06.417209 #2547:aa62e8] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:10:06.417390 #2547:aa62e8] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:06.446824 #2547:aa62e8] INFO -- : Completed 200 OK in 29ms (Views: 0.1ms | ActiveRecord: 4.9ms) [----] I, [2018-03-18T02:10:07.556492 #2547:aa6554] INFO -- : Started GET "/api/tasks/1000000000113?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:10:07 -0400 [----] I, [2018-03-18T02:10:07.560377 #2547:aa6554] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:10:07.560618 #2547:aa6554] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:07.591685 #2547:aa6554] INFO -- : Completed 200 OK in 31ms (Views: 0.1ms | ActiveRecord: 4.4ms) [----] I, [2018-03-18T02:10:08.707017 #2547:aa67e8] INFO -- : Started GET "/api/tasks/1000000000113?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:10:08 -0400 [----] I, [2018-03-18T02:10:08.710689 #2547:aa67e8] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:10:08.710997 #2547:aa67e8] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:08.742265 #2547:aa67e8] INFO -- : Completed 200 OK in 31ms (Views: 0.1ms | ActiveRecord: 4.9ms) [----] I, [2018-03-18T02:10:09.848297 #2547:aa6b1c] INFO -- : Started GET "/api/tasks/1000000000113?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:10:09 -0400 [----] I, [2018-03-18T02:10:09.852090 #2547:aa6b1c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:10:09.852183 #2547:aa6b1c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:09.882801 #2547:aa6b1c] INFO -- : Completed 200 OK in 30ms (Views: 0.1ms | ActiveRecord: 4.7ms) [----] I, [2018-03-18T02:10:10.989461 #2547:aa693c] INFO -- : Started GET "/api/tasks/1000000000113?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:10:10 -0400 [----] I, [2018-03-18T02:10:10.993174 #2547:aa693c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:10:10.993354 #2547:aa693c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:11.068500 #2547:aa693c] INFO -- : Completed 500 Internal Server Error in 75ms (Views: 0.4ms | ActiveRecord: 20.0ms) [----] I, [2018-03-18T02:10:11.568083 #2547:aa62e8] INFO -- : Started DELETE "/api/tasks/1000000000113" for 64.146.226.199 at 2018-03-18 02:10:11 -0400 [----] I, [2018-03-18T02:10:11.571729 #2547:aa62e8] INFO -- : Processing by Api::TasksController#destroy as JSON [----] I, [2018-03-18T02:10:11.572018 #2547:aa62e8] INFO -- : Parameters: {"c_id"=>"1000000000113"} [----] I, [2018-03-18T02:10:11.608701 #2547:aa62e8] INFO -- : Completed 204 No Content in 36ms (ActiveRecord: 8.0ms) [----] W, [2018-03-18T02:10:27.073988 #24580:6f5130] WARN -- : Q-task_id([1000000000120]) Scoped order and limit are ignored, it's forced to be batch order and batch size. [----] I, [2018-03-18T02:10:48.774508 #2547:aa6554] INFO -- : Started GET "/api" for 64.146.226.199 at 2018-03-18 02:10:48 -0400 [----] I, [2018-03-18T02:10:48.779191 #2547:aa6554] INFO -- : Processing by Api::ApiController#index as JSON [----] I, [2018-03-18T02:10:48.822377 #2547:aa6554] INFO -- : Completed 200 OK in 42ms (Views: 0.3ms | ActiveRecord: 4.0ms) [----] I, [2018-03-18T02:11:00.427511 #2531:bd3d3c] INFO -- : Started POST "/service/reload/1000000000004" for 64.146.226.199 at 2018-03-18 02:11:00 -0400 [----] I, [2018-03-18T02:11:00.431270 #2531:bd3d3c] INFO -- : Processing by ServiceController#reload as JS [----] I, [2018-03-18T02:11:00.431362 #2531:bd3d3c] INFO -- : Parameters: {"id"=>"1000000000004"} [----] I, [2018-03-18T02:11:00.516840 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_flash_msg.html.haml (0.1ms) [----] I, [2018-03-18T02:11:00.518031 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.518274 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.518605 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.518733 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (1.4ms) [----] I, [2018-03-18T02:11:00.523434 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.523786 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.524159 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.524418 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.1ms) [----] I, [2018-03-18T02:11:00.524733 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.524853 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (5.8ms) [----] I, [2018-03-18T02:11:00.525427 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.525654 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual_tags.html.haml (0.5ms) [----] I, [2018-03-18T02:11:00.528259 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.528389 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (2.5ms) [----] I, [2018-03-18T02:11:00.528500 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_raw.html.haml (11.3ms) [----] I, [2018-03-18T02:11:00.528702 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_generic.html.haml (12.0ms) [----] I, [2018-03-18T02:11:00.531034 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_info_msg.html.haml (0.2ms) [----] I, [2018-03-18T02:11:00.532255 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/angular/_gtl.html.haml (1.8ms) [----] I, [2018-03-18T02:11:00.532376 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_gtl.html.haml (2.0ms) [----] I, [2018-03-18T02:11:00.537410 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.537735 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.538154 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.538383 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.538695 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.538976 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (2.7ms) [----] I, [2018-03-18T02:11:00.546755 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual_table.html.haml (0.1ms) [----] I, [2018-03-18T02:11:00.555187 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.1ms) [----] I, [2018-03-18T02:11:00.555426 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.555782 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.556164 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.556284 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (6.1ms) [----] I, [2018-03-18T02:11:00.568641 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_icon_or_image.html.haml (0.0ms) [----] I, [2018-03-18T02:11:00.568793 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/shared/summary/_textual.html.haml (9.3ms) [----] I, [2018-03-18T02:11:00.569112 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_raw.html.haml (36.3ms) [----] I, [2018-03-18T02:11:00.569331 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/layouts/_textual_groups_tabs.html.haml (36.5ms) [----] I, [2018-03-18T02:11:00.586416 #2531:bd3d3c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/service/_svcs_show.html.haml (77.9ms) [----] I, [2018-03-18T02:11:00.594679 #2531:bd3d3c] INFO -- : Completed 200 OK in 163ms (Views: 4.9ms | ActiveRecord: 22.0ms) [----] I, [2018-03-18T02:11:00.846802 #2531:bd2540] INFO -- : Started GET "/static/ansible-raw-stdout.html.haml" for 64.146.226.199 at 2018-03-18 02:11:00 -0400 [----] I, [2018-03-18T02:11:00.849781 #2531:bd2540] INFO -- : Processing by StaticController#show as HTML [----] I, [2018-03-18T02:11:00.849996 #2531:bd2540] INFO -- : Parameters: {"id"=>"ansible-raw-stdout.html.haml"} [----] I, [2018-03-18T02:11:00.850688 #2531:bd2540] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/static/ansible-raw-stdout.html.haml [----] I, [2018-03-18T02:11:00.851011 #2531:bd2540] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-4f64c0463ce4/app/views/static/ansible-raw-stdout.html.haml (0.1ms) [----] I, [2018-03-18T02:11:00.851281 #2531:bd2540] INFO -- : Completed 200 OK in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms) [----] I, [2018-03-18T02:11:00.957678 #2531:bd2540] INFO -- : Started POST "/service/report_data" for 64.146.226.199 at 2018-03-18 02:11:00 -0400 [----] I, [2018-03-18T02:11:00.960709 #2531:bd2540] INFO -- : Processing by ServiceController#report_data as HTML [----] I, [2018-03-18T02:11:00.960843 #2531:bd2540] INFO -- : Parameters: {"model_name"=>"Vm", "model"=>"Vm", "active_tree"=>"svcs_tree", "parent_id"=>"1000000000004", "model_id"=>"1000000000004", "explorer"=>true, "records[]"=>[], "records"=>[], "additional_options"=>{"named_scope"=>nil, "gtl_dbname"=>nil, "model"=>"Vm", "match_via_descendants"=>nil, "parent_id"=>1000000000004, "parent_class_name"=>"ServiceAnsiblePlaybook", "parent_method"=>"all_vms", "association"=>nil, "view_suffix"=>nil, "row_button"=>nil, "menu_click"=>nil, "sb_controller"=>nil, "listicon"=>nil, "embedded"=>nil, "showlinks"=>nil, "policy_sim"=>nil, "in_a_form"=>nil, "lastaction"=>"show", "display"=>"main", "gtl_type"=>"grid", "supported_features_filter"=>nil, "clickable"=>nil}, "service"=>{}} [----] I, [2018-03-18T02:11:01.063029 #2531:bd2540] INFO -- : Completed 200 OK in 102ms (Views: 1.4ms | ActiveRecord: 14.8ms) [----] I, [2018-03-18T02:11:01.411768 #2547:aa67e8] INFO -- : Started GET "/api/tasks/1000000000121?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:11:01 -0400 [----] I, [2018-03-18T02:11:01.415301 #2547:aa67e8] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:11:01.415395 #2547:aa67e8] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000121"} [----] I, [2018-03-18T02:11:01.446333 #2547:aa67e8] INFO -- : Completed 200 OK in 31ms (Views: 0.1ms | ActiveRecord: 5.1ms) [----] I, [2018-03-18T02:11:02.558423 #2547:aa6b1c] INFO -- : Started GET "/api/tasks/1000000000121?attributes=task_results" for 64.146.226.199 at 2018-03-18 02:11:02 -0400 [----] I, [2018-03-18T02:11:02.562378 #2547:aa6b1c] INFO -- : Processing by Api::TasksController#show as JSON [----] I, [2018-03-18T02:11:02.562467 #2547:aa6b1c] INFO -- : Parameters: {"attributes"=>"task_results", "c_id"=>"1000000000121"} [----] I, [2018-03-18T02:11:02.636009 #2547:aa6b1c] INFO -- : Completed 500 Internal Server Error in 73ms (Views: 0.2ms | ActiveRecord: 24.5ms) [----] I, [2018-03-18T02:11:03.141405 #2547:aa693c] INFO -- : Started DELETE "/api/tasks/1000000000121" for 64.146.226.199 at 2018-03-18 02:11:03 -0400 [----] I, [2018-03-18T02:11:03.144991 #2547:aa693c] INFO -- : Processing by Api::TasksController#destroy as JSON [----] I, [2018-03-18T02:11:03.145087 #2547:aa693c] INFO -- : Parameters: {"c_id"=>"1000000000121"} [----] I, [2018-03-18T02:11:03.184992 #2547:aa693c] INFO -- : Completed 204 No Content in 40ms (ActiveRecord: 10.0ms) [----] I, [2018-03-18T02:11:48.976179 #2547:aa62e8] INFO -- : Started GET "/api" for 64.146.226.199 at 2018-03-18 02:11:48 -0400 [----] I, [2018-03-18T02:11:48.979603 #2547:aa62e8] INFO -- : Processing by Api::ApiController#index as JSON [----] I, [2018-03-18T02:11:49.008320 #2547:aa62e8] INFO -- : Completed 200 OK in 29ms (Views: 0.1ms | ActiveRecord: 2.8ms) [----] I, [2018-03-18T02:12:49.216627 #2547:aa6554] INFO -- : Started GET "/api" for 64.146.226.199 at 2018-03-18 02:12:49 -0400 [----] I, [2018-03-18T02:12:49.220128 #2547:aa6554] INFO -- : Processing by Api::ApiController#index as JSON [----] I, [2018-03-18T02:12:49.262346 #2547:aa6554] INFO -- : Completed 200 OK in 42ms (Views: 0.2ms | ActiveRecord: 4.2ms) [----] I, [2018-03-18T02:13:49.002344 #2547:aa67e8] INFO -- : Started GET "/api" for 64.146.226.199 at 2018-03-18 02:13:49 -0400 [----] I, [2018-03-18T02:13:49.005794 #2547:aa67e8] INFO -- : Processing by Api::ApiController#index as JSON [----] I, [2018-03-18T02:13:49.039185 #2547:aa67e8] INFO -- : Completed 200 OK in 33ms (Views: 0.2ms | ActiveRecord: 3.7ms) [----] I, [2018-03-18T02:14:48.837489 #2547:aa6b1c] INFO -- : Started GET "/api" for 64.146.226.199 at 2018-03-18 02:14:48 -0400 [----] I, [2018-03-18T02:14:48.841056 #2547:aa6b1c] INFO -- : Processing by Api::ApiController#index as JSON [----] I, [2018-03-18T02:14:48.870488 #2547:aa6b1c] INFO -- : Completed 200 OK in 29ms (Views: 0.3ms | ActiveRecord: 2.8ms) [----] I, [2018-03-18T02:15:49.053355 #2547:aa693c] INFO -- : Started GET "/api" for 64.146.226.199 at 2018-03-18 02:15:49 -0400 [----] I, [2018-03-18T02:15:49.056842 #2547:aa693c] INFO -- : Processing by Api::ApiController#index as JSON [----] I, [2018-03-18T02:15:49.088671 #2547:aa693c] INFO -- : Completed 200 OK in 32ms (Views: 0.1ms | ActiveRecord: 3.2ms) [root@cloudforms-repl log]#
Please assess the impact of this issue and update the severity accordingly. Please refer to https://bugzilla.redhat.com/page.cgi?id=fields.html#bug_severity for a reminder on each severity's definition. If it's something like a tracker bug where it doesn't matter, please set the severity to Low.
Hey Kevin , I am not able to recreate this issue on 5.9.1.0.20180314203929_67fd99d. can you please check appliance https://10.8.196.183. I have created few ansible services with different playbooks . Can you attach the playbook that you used. Thanks, Shveta
Playbooks used : https://github.com/patchkez/ansible_playbooks/blob/master/become_root_and_touch.yml and https://github.com/patchkez/ansible_playbooks/blob/master/dump_all_variables.yml
I can see the error in Kevin's system. May be it is something to with Regex pattern validation in the dialog . Dan , I have an appliance where I have the ansible playbook used in this BZ. Someone from dev will have to take a look at the logs to understand more .
Created attachment 1410270 [details] Screenshot of Ansible Output
Lucy - Please review. We may need to ask the Ansible team about comoment #14.
New commit detected on ManageIQ/manageiq/master: https://github.com/ManageIQ/manageiq/commit/0559e91d4d9e6ab8f994d787a18c146e929d65a2 commit 0559e91d4d9e6ab8f994d787a18c146e929d65a2 Author: Tim Wade <hello> AuthorDate: Wed Apr 4 15:20:57 2018 -0400 Commit: Tim Wade <hello> CommitDate: Wed Apr 4 15:20:57 2018 -0400 Force UTF-8 encoding on task results Since task results are stored as binary blobs, they can be set as strings in whatever encoding you like. This can create problems for the API, which blindly tries to convert all attributes to JSON - any value containing ASCII with ansi escape codes will fail with a conversion error. It seems reasonable to expect that all string values should be encoded as UTF-8 - the alternative is for the API to have to force the encoding on every single string that comes its way. This change forces incoming values to be converted to UTF-8 prior to being stored. It will also convert outgoing values in the case that they have already been persisted prior to this change - though this extra check I'm sure could eventually be removed. Fixes https://bugzilla.redhat.com/show_bug.cgi?id=1557731 app/models/miq_task.rb | 4 +- spec/models/miq_task_spec.rb | 23 + 2 files changed, 26 insertions(+), 1 deletion(-)
Verified in 5.10.0.23