Created attachment 1002444 [details] spinner in webUI Description of problem: VMRC not working Version-Release number of selected component (if applicable): 5.4.0.0.12.20150312013923_aa9b093 How reproducible: 100% Steps to Reproduce: 1. Navigate to Infrastructure ==> Virtual Machine ==> Select a vm and click on it 2. Click on any of the vmrc link (vnc/spice or vmrc console) Actual results: Spinner keeps on rotating forever in the webUI and time exception occurs evm.log. Refer the screenshot and evm.log file for more information Expected results: vmrc should pop out and work properly Additional info: evm.log: ======== [----] E, [2015-03-16T14:07:38.816621 #11789:a33ea0] ERROR -- : MIQ(MiqQueue.deliver) Message id: [23249], timed out after 600.002032963 seconds. Timeout threshold [600]
Created attachment 1002447 [details] Time out exception in webUI
I am having issues testing this. But I have found and fixed some bugs at 10.16.6.104. Can you retest, please, against this appliance? PR for this fixes is here: https://github.com/ManageIQ/manageiq/pull/2202
Hi Martin, Please find the test results. Tested in Firefox, Chrome and IE browsers 1. Web based VMRC console: ========================== Popup window is displayed. In Firefox and Chrome, it displays as "The Remote Console is connecting ..." where as in IE along with that message, alert is displayed stating "Connection failure: A user name was provided." Refer the screenshot "Web based VMRC console - Firefox and Chrome.png", "Web based VMRC console - IE.png" respectively for more details 2. Web based VNC or SPICE console: =================================== Opens a pop up windows and displays the UI as shown in the screenshot "Web based VNC or SPICE console - IE, Firefox, Chrome". throws error in production.log file as mentioned below production.log ============== [----] I, [2015-03-17T11:59:18.809583 #24350:b23ea0] INFO -- : Started POST "/vm_infra/html5_console/77" for 127.0.0.1 at 2015-03-17 11:59:18 -0400 [----] I, [2015-03-17T11:59:18.813848 #24350:b23ea0] INFO -- : Processing by VmInfraController#html5_console as JS [----] I, [2015-03-17T11:59:18.814030 #24350:b23ea0] INFO -- : Parameters: {"id"=>"77"} [----] I, [2015-03-17T11:59:18.814030 #24350:b23ea0] INFO -- : Parameters: {"id"=>"77"} [----] I, [2015-03-17T11:59:18.926148 #24350:b23ea0] INFO -- : Completed 200 OK in 111.9ms (Views: 2.3ms | ActiveRecord: 34.1ms) [----] I, [2015-03-17T11:59:21.183236 #24350:b23ea0] INFO -- : Started POST "/vm_infra/wait_for_task?escape=false&task_id=87" for 127.0.0.1 at 2015-03-17 11:59:21 -0400 [----] I, [2015-03-17T11:59:21.187696 #24350:b23ea0] INFO -- : Processing by VmInfraController#wait_for_task as JS [----] I, [2015-03-17T11:59:21.187817 #24350:b23ea0] INFO -- : Parameters: {"authenticity_token"=>"ienYJlR5/eTiQ/ntB05b/iT8Vm4PquGb6vAlV39L0eI=", "escape"=>"false", "task_id"=>"87"}[----] I, [2015-03-17T11:59:18.809583 #24350:b23ea0] INFO -- : Started POST "/vm_infra/html5_console/77" for 127.0.0.1 at 2015-03-17 11:59:18 -0400 [----] I, [2015-03-17T11:59:18.813848 #24350:b23ea0] INFO -- : Processing by VmInfraController#html5_console as JS [----] I, [2015-03-17T11:59:18.814030 #24350:b23ea0] INFO -- : Parameters: {"id"=>"77"} [----] I, [2015-03-17T11:59:18.814030 #24350:b23ea0] INFO -- : Parameters: {"id"=>"77"} [----] I, [2015-03-17T11:59:18.926148 #24350:b23ea0] INFO -- : Completed 200 OK in 111.9ms (Views: 2.3ms | ActiveRecord: 34.1ms) [----] I, [2015-03-17T11:59:21.183236 #24350:b23ea0] INFO -- : Started POST "/vm_infra/wait_for_task?escape=false&task_id=87" for 127.0.0.1 at 2015-03-17 11:59:21 -0400 [----] I, [2015-03-17T11:59:21.187696 #24350:b23ea0] INFO -- : Processing by VmInfraController#wait_for_task as JS [----] I, [2015-03-17T11:59:21.187817 #24350:b23ea0] INFO -- : Parameters: {"authenticity_token"=>"ienYJlR5/eTiQ/ntB05b/iT8Vm4PquGb6vAlV39L0eI=", "escape"=>"false", "task_id"=>"87"} [----] I, [2015-03-17T11:59:21.241687 #24350:b23ea0] INFO -- : Completed 200 OK in 53.6ms (Views: 2.2ms | ActiveRecord: 15.9ms) [----] I, [2015-03-17T11:59:24.592461 #24350:b23ea0] INFO -- : Started POST "/vm_infra/wait_for_task?escape=false&task_id=87" for 127.0.0.1 at 2015-03-17 11:59:24 -0400 [----] I, [2015-03-17T11:59:24.596847 #24350:b23ea0] INFO -- : Processing by VmInfraController#wait_for_task as JS [----] I, [2015-03-17T11:59:24.596994 #24350:b23ea0] INFO -- : Parameters: {"authenticity_token"=>"ienYJlR5/eTiQ/ntB05b/iT8Vm4PquGb6vAlV39L0eI=", "escape"=>"false", "task_id"=>"87"} [----] I, [2015-03-17T11:59:24.596994 #24350:b23ea0] INFO -- : Parameters: {"authenticity_token"=>"ienYJlR5/eTiQ/ntB05b/iT8Vm4PquGb6vAlV39L0eI=", "escape"=>"false", "task_id"=>"87"} [----] I, [2015-03-17T11:59:24.666254 #24350:b23ea0] INFO -- : Completed 200 OK in 69.0ms (Views: 1.9ms | ActiveRecord: 12.0ms) [----] I, [2015-03-17T11:59:24.964242 #24350:b23ea0] INFO -- : Started GET "/vm_infra/launch_html5_console/77" for 127.0.0.1 at 2015-03-17 11:59:24 -0400 [----] I, [2015-03-17T11:59:24.969717 #24350:b23ea0] INFO -- : Processing by VmInfraController#launch_html5_console as HTML [----] I, [2015-03-17T11:59:24.969866 #24350:b23ea0] INFO -- : Parameters: {"id"=>"77"} [----] I, [2015-03-17T11:59:24.969866 #24350:b23ea0] INFO -- : Parameters: {"id"=>"77"} [----] I, [2015-03-17T11:59:25.368490 #24350:b23ea0] INFO -- : Rendered vm_common/console_vnc.html.haml (18.2ms) [----] I, [2015-03-17T11:59:25.371741 #24350:b23ea0] INFO -- : Completed 200 OK in 401.6ms (Views: 21.4ms | ActiveRecord: 1.4ms) [----] I, [2015-03-17T11:59:26.305326 #24350:b23ea0] INFO -- : Started GET "/assets/miq_novnc-1a54f0257990d0dd3453083e087310a3.js" for 127.0.0.1 at 2015-03-17 11:59:26 -0400 [----] I, [2015-03-17T11:59:27.533613 #24350:b23ea0] INFO -- : Started GET "/favicon.ico" for 127.0.0.1 at 2015-03-17 11:59:27 -0400 [----] F, [2015-03-17T11:59:27.539265 #24350:b23ea0] FATAL -- : ActionController::RoutingError (No route matches [GET] "/favicon.ico"): /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/logger.rb:32:in `call_app' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/logger.rb:18:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/static.rb:63:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/engine.rb:484:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/application.rb:231:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/log_tailer.rb:17:in `call' thin (1.3.1) lib/thin/connection.rb:80:in `block in pre_process' thin (1.3.1) lib/thin/connection.rb:78:in `catch' thin (1.3.1) lib/thin/connection.rb:78:in `pre_process' thin (1.3.1) lib/thin/connection.rb:53:in `process' thin (1.3.1) lib/thin/connection.rb:38:in `receive_data' eventmachine (1.0.7) lib/eventmachine.rb:187:in `run_machine' eventmachine (1.0.7) lib/eventmachine.rb:187:in `run' thin (1.3.1) lib/thin/backends/base.rb:61:in `start' thin (1.3.1) lib/thin/server.rb:159:in `start' rack (1.4.5) lib/rack/handler/thin.rb:13:in `run' rack (1.4.5) lib/rack/server.rb:268:in `start' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands/server.rb:70:in `start' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:55:in `block in <top (required)>' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:50:in `tap' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:50:in `<top (required)>' script/rails:6:in `require' script/rails:6:in `<main>' [----] I, [2015-03-17T11:59:28.456412 #24350:b23ea0] INFO -- : Started GET "/favicon.ico" for 127.0.0.1 at 2015-03-17 11:59:28 -0400 [----] F, [2015-03-17T11:59:28.461530 #24350:b23ea0] FATAL -- : ActionController::RoutingError (No route matches [GET] "/favicon.ico"): /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/logger.rb:32:in `call_app' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/logger.rb:18:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/static.rb:63:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/engine.rb:484:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/application.rb:231:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/log_tailer.rb:17:in `call' thin (1.3.1) lib/thin/connection.rb:80:in `block in pre_process' thin (1.3.1) lib/thin/connection.rb:78:in `catch' thin (1.3.1) lib/thin/connection.rb:78:in `pre_process' thin (1.3.1) lib/thin/connection.rb:53:in `process' thin (1.3.1) lib/thin/connection.rb:38:in `receive_data' eventmachine (1.0.7) lib/eventmachine.rb:187:in `run_machine' eventmachine (1.0.7) lib/eventmachine.rb:187:in `run' thin (1.3.1) lib/thin/backends/base.rb:61:in `start' thin (1.3.1) lib/thin/server.rb:159:in `start' rack (1.4.5) lib/rack/handler/thin.rb:13:in `run' rack (1.4.5) lib/rack/server.rb:268:in `start' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands/server.rb:70:in `start' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:55:in `block in <top (required)>' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:50:in `tap' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:50:in `<top (required)>' script/rails:6:in `require' script/rails:6:in `<main>' [----] I, [2015-03-17T11:59:21.241687 #24350:b23ea0] INFO -- : Completed 200 OK in 53.6ms (Views: 2.2ms | ActiveRecord: 15.9ms) [----] I, [2015-03-17T11:59:24.592461 #24350:b23ea0] INFO -- : Started POST "/vm_infra/wait_for_task?escape=false&task_id=87" for 127.0.0.1 at 2015-03-17 11:59:24 -0400 [----] I, [2015-03-17T11:59:24.596847 #24350:b23ea0] INFO -- : Processing by VmInfraController#wait_for_task as JS [----] I, [2015-03-17T11:59:24.596994 #24350:b23ea0] INFO -- : Parameters: {"authenticity_token"=>"ienYJlR5/eTiQ/ntB05b/iT8Vm4PquGb6vAlV39L0eI=", "escape"=>"false", "task_id"=>"87"} [----] I, [2015-03-17T11:59:24.596994 #24350:b23ea0] INFO -- : Parameters: {"authenticity_token"=>"ienYJlR5/eTiQ/ntB05b/iT8Vm4PquGb6vAlV39L0eI=", "escape"=>"false", "task_id"=>"87"} [----] I, [2015-03-17T11:59:24.666254 #24350:b23ea0] INFO -- : Completed 200 OK in 69.0ms (Views: 1.9ms | ActiveRecord: 12.0ms) [----] I, [2015-03-17T11:59:24.964242 #24350:b23ea0] INFO -- : Started GET "/vm_infra/launch_html5_console/77" for 127.0.0.1 at 2015-03-17 11:59:24 -0400 [----] I, [2015-03-17T11:59:24.969717 #24350:b23ea0] INFO -- : Processing by VmInfraController#launch_html5_console as HTML [----] I, [2015-03-17T11:59:24.969866 #24350:b23ea0] INFO -- : Parameters: {"id"=>"77"} [----] I, [2015-03-17T11:59:24.969866 #24350:b23ea0] INFO -- : Parameters: {"id"=>"77"} [----] I, [2015-03-17T11:59:25.368490 #24350:b23ea0] INFO -- : Rendered vm_common/console_vnc.html.haml (18.2ms) [----] I, [2015-03-17T11:59:25.371741 #24350:b23ea0] INFO -- : Completed 200 OK in 401.6ms (Views: 21.4ms | ActiveRecord: 1.4ms) [----] I, [2015-03-17T11:59:26.305326 #24350:b23ea0] INFO -- : Started GET "/assets/miq_novnc-1a54f0257990d0dd3453083e087310a3.js" for 127.0.0.1 at 2015-03-17 11:59:26 -0400 [----] I, [2015-03-17T11:59:27.533613 #24350:b23ea0] INFO -- : Started GET "/favicon.ico" for 127.0.0.1 at 2015-03-17 11:59:27 -0400 [----] F, [2015-03-17T11:59:27.539265 #24350:b23ea0] FATAL -- : ActionController::RoutingError (No route matches [GET] "/favicon.ico"): /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/logger.rb:32:in `call_app' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/logger.rb:18:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/static.rb:63:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/engine.rb:484:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/application.rb:231:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/log_tailer.rb:17:in `call' thin (1.3.1) lib/thin/connection.rb:80:in `block in pre_process' thin (1.3.1) lib/thin/connection.rb:78:in `catch' thin (1.3.1) lib/thin/connection.rb:78:in `pre_process' thin (1.3.1) lib/thin/connection.rb:53:in `process' thin (1.3.1) lib/thin/connection.rb:38:in `receive_data' eventmachine (1.0.7) lib/eventmachine.rb:187:in `run_machine' eventmachine (1.0.7) lib/eventmachine.rb:187:in `run' thin (1.3.1) lib/thin/backends/base.rb:61:in `start' thin (1.3.1) lib/thin/server.rb:159:in `start' rack (1.4.5) lib/rack/handler/thin.rb:13:in `run' rack (1.4.5) lib/rack/server.rb:268:in `start' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands/server.rb:70:in `start' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:55:in `block in <top (required)>' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:50:in `tap' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:50:in `<top (required)>' script/rails:6:in `require' script/rails:6:in `<main>' [----] I, [2015-03-17T11:59:28.456412 #24350:b23ea0] INFO -- : Started GET "/favicon.ico" for 127.0.0.1 at 2015-03-17 11:59:28 -0400 [----] F, [2015-03-17T11:59:28.461530 #24350:b23ea0] FATAL -- : ActionController::RoutingError (No route matches [GET] "/favicon.ico"): /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/logger.rb:32:in `call_app' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/logger.rb:18:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/static.rb:63:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/engine.rb:484:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/application.rb:231:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/rack/log_tailer.rb:17:in `call' thin (1.3.1) lib/thin/connection.rb:80:in `block in pre_process' thin (1.3.1) lib/thin/connection.rb:78:in `catch' thin (1.3.1) lib/thin/connection.rb:78:in `pre_process' thin (1.3.1) lib/thin/connection.rb:53:in `process' thin (1.3.1) lib/thin/connection.rb:38:in `receive_data' eventmachine (1.0.7) lib/eventmachine.rb:187:in `run_machine' eventmachine (1.0.7) lib/eventmachine.rb:187:in `run' thin (1.3.1) lib/thin/backends/base.rb:61:in `start' thin (1.3.1) lib/thin/server.rb:159:in `start' rack (1.4.5) lib/rack/handler/thin.rb:13:in `run' rack (1.4.5) lib/rack/server.rb:268:in `start' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands/server.rb:70:in `start' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:55:in `block in <top (required)>' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:50:in `tap' /opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:50:in `<top (required)>' script/rails:6:in `require' script/rails:6:in `<main>'
Created attachment 1002872 [details] Web based VMRC console - IE
Created attachment 1002873 [details] Web based VNC or SPICE console - Firefox, Chrome
Created attachment 1002874 [details] Web based VNC or SPICE console - IE, Firefox, Chrome
Created attachment 1002877 [details] Web based VNC or SPICE console - IE, Firefox, Chrome
I updated the PR and the fix at 10.16.6.104. I was finally able to see the VMRC plugin actually working ;-) Thanks for the help and, please, check again.
Can be retested in latest master.
Good to go. Verified and working fine in 5.4.0.0.19.20150410165622_ad23806
Since the problem described in this bug report should be resolved in a recent advisory, it has been closed with a resolution of ERRATA. For information on the advisory, and where to find the updated files, follow the link below. If the solution does not work for you, open a new bug report. https://rhn.redhat.com/errata/RHBA-2015-1100.html