Bug 1200803

Summary: Clicking on the web based vmrc links - vnc /spice or vmrc console results in "size of BinaryBlobPart id [400000000000002] is incorrect [vm_infra/html5_console] " error
Product: Red Hat CloudForms Management Engine Reporter: Ramesh A <rananda>
Component: UI - OPSAssignee: Dan Clarizio <dclarizi>
Status: CLOSED DUPLICATE QA Contact: Dave Johnson <dajohnso>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: 5.4.0CC: hkataria, jhardy, jkrocil, mpovolny, obarenbo
Target Milestone: GA   
Target Release: 5.4.0   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2015-03-11 17:50:18 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Attachments:
Description Flags
vmrc-error none

Description Ramesh A 2015-03-11 12:14:17 UTC
Created attachment 1000360 [details]
vmrc-error

Description of problem:
Clicking on the web based vmrc links - vnc /spice or vmrc console results in "size of BinaryBlobPart id [400000000000002] is incorrect [vm_infra/html5_console] " error

Version-Release number of selected component (if applicable):
5.4.0.0.11.20150309201930_8b6a235

How reproducible:
100%

Steps to Reproduce:
1. Navigate to Infrastructure ==> Virtual Machines ==> Select any virtual machine
2. Click on the either of the web based vmrc links ie, "VNC / Spice" or  "VMRC console" in the vm details page


Actual results:
Throws "size of BinaryBlobPart id [400000000000002] is incorrect [vm_infra/html5_console] " error in UI.
Refer the screenshot "vmrc-error" and production.log file for more information

Expected results:
Should open up the vmrc console as per the selected option

Additional info:

production.log:
===============

[----] I, [2015-03-11T08:05:28.288791 #2276:1049eac]  INFO -- : Started POST "/vm_infra/html5_console/254" for 127.0.0.1 at 2015-03-11 08:05:28 -0400
[----] I, [2015-03-11T08:05:28.293048 #2276:1049eac]  INFO -- : Processing by VmInfraController#html5_console as JS
[----] I, [2015-03-11T08:05:28.293169 #2276:1049eac]  INFO -- :   Parameters: {"id"=>"254"}
[----] I, [2015-03-11T08:05:28.293169 #2276:1049eac]  INFO -- :   Parameters: {"id"=>"254"}
[----] I, [2015-03-11T08:05:28.364085 #2276:1049eac]  INFO -- : Completed 200 OK in 70.7ms (Views: 1.6ms | ActiveRecord: 8.8ms)
[----] I, [2015-03-11T08:05:30.562410 #2276:1049eac]  INFO -- : Started POST "/vm_infra/wait_for_task?escape=false&task_id=16" for 127.0.0.1 at 2015-03-11 08:05:30 -0400
[----] I, [2015-03-11T08:05:30.566003 #2276:1049eac]  INFO -- : Processing by VmInfraController#wait_for_task as JS
[----] I, [2015-03-11T08:05:30.566117 #2276:1049eac]  INFO -- :   Parameters: {"authenticity_token"=>"J2CZfYV7UGPRZM6VA0e8WNrI/SK/lp0UlcjhehYJb9w=", "escape"=>"false", "task_id"=>"16"}
[----] I, [2015-03-11T08:05:30.594322 #2276:1049eac]  INFO -- : Completed 200 OK in 28.0ms (Views: 1.4ms | ActiveRecord: 1.2ms)
[----] I, [2015-03-11T08:05:32.789917 #2276:1049eac]  INFO -- : Started POST "/vm_infra/wait_for_task?escape=false&task_id=16" for 127.0.0.1 at 2015-03-11 08:05:32 -0400
[----] I, [2015-03-11T08:05:32.793285 #2276:1049eac]  INFO -- : Processing by VmInfraController#wait_for_task as JS
[----] I, [2015-03-11T08:05:32.793377 #2276:1049eac]  INFO -- :   Parameters: {"authenticity_token"=>"J2CZfYV7UGPRZM6VA0e8WNrI/SK/lp0UlcjhehYJb9w=", "escape"=>"false", "task_id"=>"16"}
[----] F, [2015-03-11T08:05:32.815972 #2276:1049eac] FATAL -- : Error caught: [RuntimeError] size of BinaryBlobPart id [10] is incorrect
/var/www/miq/vmdb/app/models/binary_blob_part.rb:19:in `data'
/var/www/miq/vmdb/app/models/binary_blob.rb:14:in `block in binary'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activerecord/lib/active_record/associations/collection_proxy.rb:91:in `each'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activerecord/lib/active_record/associations/collection_proxy.rb:91:in `inject'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activerecord/lib/active_record/associations/collection_proxy.rb:91:in `method_missing'
/var/www/miq/vmdb/app/models/binary_blob.rb:14:in `binary'
/var/www/miq/vmdb/app/models/miq_task.rb:168:in `task_results'
/var/www/miq/vmdb/app/controllers/vm_common.rb:1436:in `console_after_task'
/var/www/miq/vmdb/app/controllers/vm_common.rb:95:in `html5_console'
/var/www/miq/vmdb/app/controllers/application_controller.rb:358:in `wait_for_task'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/abstract_controller/base.rb:167:in `process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal/rendering.rb:10:in `process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/abstract_controller/callbacks.rb:18:in `block in process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/callbacks.rb:557:in `_run__2252495060943768177__process_action__859016161654579206__callbacks'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/callbacks.rb:405:in `__run_callback'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/callbacks.rb:81:in `run_callbacks'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/abstract_controller/callbacks.rb:17:in `process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal/rescue.rb:29:in `process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/notifications.rb:123:in `block in instrument'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/notifications.rb:123:in `instrument'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activerecord/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/abstract_controller/base.rb:121:in `process'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/abstract_controller/rendering.rb:45:in `process'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal.rb:203:in `dispatch'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_controller/metal.rb:246:in `block in action'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/routing/route_set.rb:73:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/routing/route_set.rb:36:in `call'
/opt/rh/cfme-gemset/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
/opt/rh/cfme-gemset/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
/opt/rh/cfme-gemset/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/routing/route_set.rb:608:in `call'
/opt/rh/cfme-gemset/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
/opt/rh/cfme-gemset/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
/opt/rh/cfme-gemset/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
/opt/rh/cfme-gemset/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
/opt/rh/cfme-gemset/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
/opt/rh/cfme-gemset/gems/rack-1.4.5/lib/rack/etag.rb:23:in `call'
/opt/rh/cfme-gemset/gems/rack-1.4.5/lib/rack/conditionalget.rb:35:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/head.rb:14:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/flash.rb:242:in `call'
/opt/rh/cfme-gemset/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:210:in `context'
/opt/rh/cfme-gemset/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:205:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/cookies.rb:341:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activerecord/lib/active_record/query_cache.rb:64:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activerecord/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/callbacks.rb:405:in `_run__4559502224878657160__call__3530929704347879564__callbacks'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/callbacks.rb:405:in `__run_callback'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/activesupport/lib/active_support/callbacks.rb:81:in `run_callbacks'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/actionpack/lib/action_dispatch/middleware/debug_exceptions.rb:16: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'
/opt/rh/cfme-gemset/gems/rack-1.4.5/lib/rack/methodoverride.rb:21:in `call'
/opt/rh/cfme-gemset/gems/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'
/opt/rh/cfme-gemset/gems/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'
/opt/rh/cfme-gemset/gems/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'
/opt/rh/cfme-gemset/gems/thin-1.3.1/lib/thin/connection.rb:80:in `block in pre_process'
/opt/rh/cfme-gemset/gems/thin-1.3.1/lib/thin/connection.rb:78:in `catch'
/opt/rh/cfme-gemset/gems/thin-1.3.1/lib/thin/connection.rb:78:in `pre_process'
/opt/rh/cfme-gemset/gems/thin-1.3.1/lib/thin/connection.rb:53:in `process'
/opt/rh/cfme-gemset/gems/thin-1.3.1/lib/thin/connection.rb:38:in `receive_data'
/opt/rh/cfme-gemset/gems/eventmachine-1.0.7/lib/eventmachine.rb:187:in `run_machine'
/opt/rh/cfme-gemset/gems/eventmachine-1.0.7/lib/eventmachine.rb:187:in `run'
/opt/rh/cfme-gemset/gems/thin-1.3.1/lib/thin/backends/base.rb:61:in `start'
/opt/rh/cfme-gemset/gems/thin-1.3.1/lib/thin/server.rb:159:in `start'
/opt/rh/cfme-gemset/gems/rack-1.4.5/lib/rack/handler/thin.rb:13:in `run'
/opt/rh/cfme-gemset/gems/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-11T08:05:32.817650 #2276:1049eac]  INFO -- :   Rendered layouts/_exception_contents.html.haml (0.3ms)
[----] I, [2015-03-11T08:05:32.818471 #2276:1049eac]  INFO -- : Completed 200 OK in 24.9ms (Views: 2.0ms | ActiveRecord: 2.0ms)
[----] I, [2015-03-11T08:06:03.673563 #2276:1049eac]  INFO -- : Started POST "/dashboard/window_sizes?width=1500&height=506&" for 127.0.0.1 at 2015-03-11 08:06:03 -0400
[----] I, [2015-03-11T08:06:03.677067 #2276:1049eac]  INFO -- : Processing by DashboardController#window_sizes as JS
[----] I, [2015-03-11T08:06:03.677164 #2276:1049eac]  INFO -- :   Parameters: {"width"=>"1500", "height"=>"506"}
[----] I, [2015-03-11T08:06:03.692026 #2276:1049eac]  INFO -- :   Rendered text template (0.0ms)
[----] I, [2015-03-11T08:06:03.692302 #2276:1049eac]  INFO -- : Completed 200 OK in 15.0ms (Views: 0.7ms | ActiveRecord: 1.1ms)