Attachment 668732 Details for Bug 890104 – development.log

development.log

file_890104.txt (text/plain), 26.57 KB, created by Rony Gong 🔥 on 2012-12-25 06:26:41 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rony Gong 🔥

Created: 2012-12-25 06:26:41 UTC

Size: 26.57 KB

patch

obsolete

> MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (10.2870ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.domains", :key=>{:namespace=>1}, :name=>"namespace_1"}] flags=[] (0.1884ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.cloud_users", :key=>{:login=>1}, :name=>"login_1"}] flags=[] (0.2947ms)
>2012-12-25 01:09:57.659 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"group_instances.gears._id"=>"50d940690ba57bf70600018f"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.2171ms) (pid:26593)
>2012-12-25 01:09:57.663 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"50d908780ba57ba208000004"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5012ms) (pid:26593)
>2012-12-25 01:09:57.666 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"50d904e60ba57ba208000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5834ms) (pid:26593)
>2012-12-25 01:09:57.669 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50d904e60ba57ba208000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.6039ms) (pid:26593)
>2012-12-25 01:09:57.670 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"50d904e60ba57ba208000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.2973ms) (pid:26593)
>2012-12-25 01:09:57.672 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d904e60ba57ba208000001", "app_ids"=>{"$nin"=>["50d940690ba57bf70600018f"]}}, :new=>true, :update=>{"$push"=>{:app_ids=>"50d940690ba57bf70600018f"}}} (0.8652ms) (pid:26593)
>2012-12-25 01:09:58.158 [DEBUG] DEBUG: Source district uuid: 50d908950ba57bab31000001 (pid:26593)
>2012-12-25 01:09:58.158 [DEBUG] DEBUG: Destination district uuid: 50d940ce0ba57b3452000001 (pid:26593)
>2012-12-25 01:09:58.238 [DEBUG] DEBUG: Getting existing app 'qpython' status before moving (pid:26593)
>2012-12-25 01:09:58.242 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000005afe290> (pid:26593)
>2012-12-25 01:09:58.242 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"python-2.6", :action=>"status", :args=>"'qpython' 'qgong21' '50d940690ba57bf70600018f'"}, ip-10-224-110-206, {'identity' => ip-10-224-110-206}) (pid:26593)
>2012-12-25 01:09:58.834 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000005f705f8 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-224-110-206", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"CLIENT_RESULT: Total Accesses: 0\nCLIENT_RESULT: Total kBytes: 0\nCLIENT_RESULT: Uptime: 707\nCLIENT_RESULT: ReqPerSec: 0\nCLIENT_RESULT: BytesPerSec: 0\nCLIENT_RESULT: BusyWorkers: 1\nCLIENT_RESULT: IdleWorkers: 0\nCLIENT_RESULT: Scoreboard: W....\nATTR: quota_blocks=1048576\nATTR: quota_files=40000\n", :exitcode=>0}}>] (pid:26593)
>2012-12-25 01:09:58.838 [DEBUG] DEBUG: Gear component 'python-2.6' was running (pid:26593)
>2012-12-25 01:09:58.852 [DEBUG] DEBUG: Stopping existing app cartridge 'mysql-5.1' before moving (pid:26593)
>2012-12-25 01:09:58.905 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x000000065a3a88> (pid:26593)
>2012-12-25 01:09:58.905 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"embedded/mysql-5.1", :action=>"stop", :args=>"'qpython' 'qgong21' '50d940690ba57bf70600018f'"}, ip-10-224-110-206, {'identity' => ip-10-224-110-206}) (pid:26593)
>2012-12-25 01:10:04.128 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000001c86328 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-224-110-206", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (pid:26593)
>2012-12-25 01:10:04.133 [DEBUG] DEBUG: Performing cartridge level pre-move for embedded mysql-5.1 for 'qpython' on ip-10-224-110-206 (pid:26593)
>2012-12-25 01:10:04.133 [DEBUG] DEBUG: Performing cartridge level pre-move for embedded mysql-5.1 for 'qpython' on ip-10-224-110-206 (pid:26593)
>2012-12-25 01:10:04.136 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000001c9bef8> (pid:26593)
>2012-12-25 01:10:04.136 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"embedded/mysql-5.1", :action=>"pre-move", :args=>"'qpython' 'qgong21' '50d940690ba57bf70600018f'"}, ip-10-224-110-206, {'identity' => ip-10-224-110-206}) (pid:26593)
>2012-12-25 01:10:41.868 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000001fc5070 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-224-110-206", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (pid:26593)
>2012-12-25 01:10:41.871 [DEBUG] DEBUG: Stopping existing app cartridge 'python-2.6' before moving (pid:26593)
>2012-12-25 01:10:41.873 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000001e7f508> (pid:26593)
>2012-12-25 01:10:41.873 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"python-2.6", :action=>"stop", :args=>"'qpython' 'qgong21' '50d940690ba57bf70600018f'"}, ip-10-224-110-206, {'identity' => ip-10-224-110-206}) (pid:26593)
>2012-12-25 01:10:43.641 [INFO ] Started GET "/broker/rest/domains/qgong21/applications/qsruby18" for 127.0.0.1 at 2012-12-25 01:10:43 -0500 (pid:1783)
>2012-12-25 01:10:43.647 [INFO ] Processing by ApplicationsController#show as JSON (pid:1783)
>2012-12-25 01:10:43.647 [INFO ] Parameters: {"broker_auth_key"=>"[FILTERED]", "broker_auth_iv"=>"[FILTERED]", "domain_id"=>"qgong21", "id"=>"qsruby18"} (pid:1783)
>2012-12-25 01:10:43.647 [INFO ] Processing by ApplicationsController#show as JSON (pid:1783)
>2012-12-25 01:10:43.647 [INFO ] Parameters: {"broker_auth_key"=>"[FILTERED]", "broker_auth_iv"=>"[FILTERED]", "domain_id"=>"qgong21", "id"=>"qsruby18"} (pid:1783)
>2012-12-25 01:10:43.660 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"_id"=>"qgong@redhat.com"} flags=[:slave_ok] limit=0 skip=0 fields=nil (3.9201ms) (pid:1783)
>2012-12-25 01:10:43.661 [INFO ] Completed 500 Internal Server Error in 14ms (pid:1783)
>2012-12-25 01:10:43.665 [FATAL] Mongoid::Errors::DocumentNotFound (
>Problem:
>  Document(s) not found for class CloudUser with id(s) qgong@redhat.com.
>Summary:
>  When calling CloudUser.find with an id or array of ids, each parameter must match a document in the database or this error will be raised. The search was for the id(s): qgong@redhat.com ... (1 total) and the following ids were not found: qgong@redhat.com.
>Resolution:
>  Search for an id that is in the database or set the Mongoid.raise_not_found_error configuration option to false, which will cause a nil to be returned instead of raising this error when searching for a single id, or only the matched documents when searching for multiples.):
>  mongoid (3.0.5) lib/mongoid/criteria.rb:553:in `check_for_missing_documents!'
>  mongoid (3.0.5) lib/mongoid/criteria.rb:177:in `execute_or_raise'
>  mongoid (3.0.5) lib/mongoid/criteria.rb:243:in `find'
>  mongoid (3.0.5) lib/mongoid/finders.rb:61:in `find'
>  openshift-origin-controller (1.3.2) lib/openshift/auth_service.rb:79:in `validate_broker_key'
>  openshift-origin-auth-streamline (1.2.1) lib/openshift/streamline_auth_service.rb:23:in `authenticate'
>  openshift-origin-controller (1.3.2) app/controllers/base_controller.rb:106:in `authenticate'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:429:in `_run__2686690151553007994__process_action__878133323474870097__callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks'
>  actionpack (3.2.8) lib/abstract_controller/callbacks.rb:17:in `process_action'
>  actionpack (3.2.8) lib/action_controller/metal/rescue.rb:29:in `process_action'
>  actionpack (3.2.8) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
>  activesupport (3.2.8) lib/active_support/notifications.rb:123:in `block in instrument'
>  activesupport (3.2.8) lib/active_support/notifications/instrumenter.rb:20:in `instrument'
>  activesupport (3.2.8) lib/active_support/notifications.rb:123:in `instrument'
>  actionpack (3.2.8) lib/action_controller/metal/instrumentation.rb:29:in `process_action'
>  actionpack (3.2.8) lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
>  actionpack (3.2.8) lib/abstract_controller/base.rb:121:in `process'
>  actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process'
>  actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch'
>  actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
>  actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action'
>  actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call'
>  actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
>  actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:36:in `call'
>  journey (1.0.4) lib/journey/router.rb:68:in `block in call'
>  journey (1.0.4) lib/journey/router.rb:56:in `each'
>  journey (1.0.4) lib/journey/router.rb:56:in `call'
>  actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:600:in `call'
>  mongo_mapper (0.11.1) lib/mongo_mapper/middleware/identity_map.rb:10:in `call'
>  mongoid (3.0.5) lib/rack/mongoid/middleware/identity_map.rb:33:in `block in call'
>  mongoid (3.0.5) lib/mongoid/unit_of_work.rb:39:in `unit_of_work'
>  mongoid (3.0.5) lib/rack/mongoid/middleware/identity_map.rb:33:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
>  rack (1.4.1) lib/rack/etag.rb:23:in `call'
>  rack (1.4.1) lib/rack/conditionalget.rb:25:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call'
>  rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context'
>  rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__915588837484752555__call__1100037428762684133__callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks'
>  actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
>  railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app'
>  railties (3.2.8) lib/rails/rack/logger.rb:16:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call'
>  rack (1.4.1) lib/rack/methodoverride.rb:21:in `call'
>  rack (1.4.1) lib/rack/runtime.rb:17:in `call'
>  activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call'
>  rack (1.4.1) lib/rack/lock.rb:15:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call'
>  rack-cache (1.2) lib/rack/cache/context.rb:136:in `forward'
>  rack-cache (1.2) lib/rack/cache/context.rb:245:in `fetch'
>  rack-cache (1.2) lib/rack/cache/context.rb:185:in `lookup'
>  rack-cache (1.2) lib/rack/cache/context.rb:66:in `call!'
>  rack-cache (1.2) lib/rack/cache/context.rb:51:in `call'
>  railties (3.2.8) lib/rails/engine.rb:479:in `call'
>  railties (3.2.8) lib/rails/application.rb:223:in `call'
>  railties (3.2.8) lib/rails/railtie/configurable.rb:30:in `method_missing'
>  passenger (3.0.12) lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'
>  passenger (3.0.12) lib/phusion_passenger/abstract_request_handler.rb:514:in `accept_and_process_next_request'
>  passenger (3.0.12) lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'
>  passenger (3.0.12) lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'
>  passenger (3.0.12) lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'
>  passenger (3.0.12) lib/phusion_passenger/utils.rb:479:in `safe_fork'
>  passenger (3.0.12) lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'
>  passenger (3.0.12) lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'
>  passenger (3.0.12) lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'
>  passenger (3.0.12) lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'
>  passenger (3.0.12) lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'
>  passenger (3.0.12) lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'
>  passenger (3.0.12) helper-scripts/passenger-spawn-server:102:in `<main>' (pid:1783)
>2012-12-25 01:10:43.679 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.5ms) (pid:1783)
>2012-12-25 01:10:43.683 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) (pid:1783)
>2012-12-25 01:10:43.683 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (9.8ms) (pid:1783)
>2012-12-25 01:10:44.100 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000001f73680 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-224-110-206", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"Waiting for stop to finish\nATTR: status=ALREADY_STOPPED\n", :exitcode=>0}}>] (pid:26593)
>2012-12-25 01:10:44.102 [DEBUG] DEBUG: Force stopping existing app cartridge 'python-2.6' before moving (pid:26593)
>2012-12-25 01:10:44.105 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000001f798c8> (pid:26593)
>2012-12-25 01:10:44.106 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"force-stop", :args=>{"--with-app-uuid"=>"50d940690ba57bf70600018f", "--with-container-uuid"=>"50d940690ba57bf70600018f"}}, ip-10-224-110-206, {'identity' => ip-10-224-110-206}) (pid:26593)
>2012-12-25 01:10:44.299 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000001ffb760 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-224-110-206", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (pid:26593)
>2012-12-25 01:10:44.316 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"districts", :query=>{"_id"=>"50d940ce0ba57b3452000001"}, :update=>{"$pop"=>{"available_uids"=>-1}, "$inc"=>{"available_capacity"=>-1}}} (14.6768ms) (pid:26593)
>2012-12-25 01:10:44.317 [DEBUG] DEBUG: Reserved uid '1002' on district: '50d940ce0ba57b3452000001' (pid:26593)
>2012-12-25 01:10:44.318 [DEBUG] DEBUG: Creating new account for gear 'qpython' on ip-10-100-203-152 (pid:26593)
>2012-12-25 01:10:44.319 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x0000000580b4e8> (pid:26593)
>2012-12-25 01:10:44.319 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"50d940690ba57bf70600018f", "--with-app-name"=>"qpython", "--with-container-uuid"=>"50d940690ba57bf70600018f", "--with-container-name"=>"qpython", "--with-namespace"=>"qgong21", "--with-uid"=>1002}}, ip-10-100-203-152, {'identity' => ip-10-100-203-152}) (pid:26593)
>2012-12-25 01:10:46.888 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000005c8a230 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-100-203-152", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (pid:26593)
>2012-12-25 01:10:46.904 [DEBUG] DEBUG: Moving content for app 'qpython', gear 'qpython' to ip-10-100-203-152 (pid:26593)
>2012-12-25 01:10:48.864 [DEBUG] Agent pid 28169
>unset SSH_AUTH_SOCK;
>unset SSH_AGENT_PID;
>echo Agent pid 28169 killed; (pid:26593)
>2012-12-25 01:10:48.968 [DEBUG] DEBUG: Performing cartridge level move for 'python-2.6' on ip-10-100-203-152 (pid:26593)
>2012-12-25 01:10:48.987 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x0000000658f9c0> (pid:26593)
>2012-12-25 01:10:48.988 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"python-2.6", :action=>"move", :args=>"'qpython' 'qgong21' '50d940690ba57bf70600018f'"}, ip-10-100-203-152, {'identity' => ip-10-100-203-152}) (pid:26593)
>2012-12-25 01:10:51.445 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000055deb98 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-100-203-152", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"Nothing found in .openshift/config/* to redeploy\nReloading node-web-proxy: \n", :exitcode=>0}}>] (pid:26593)
>2012-12-25 01:10:51.447 [DEBUG] DEBUG: Performing cartridge level move for embedded mysql-5.1 for 'qpython' on ip-10-100-203-152 (pid:26593)
>2012-12-25 01:10:51.449 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x000000064082a0> (pid:26593)
>2012-12-25 01:10:51.449 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"embedded/mysql-5.1", :action=>"move", :args=>"'qpython' 'qgong21' '50d940690ba57bf70600018f'"}, ip-10-100-203-152, {'identity' => ip-10-100-203-152}) (pid:26593)
>2012-12-25 01:11:45.834 [INFO ] Started GET "/broker/rest/domains/qgong21/applications/qsruby18" for 127.0.0.1 at 2012-12-25 01:11:45 -0500 (pid:1783)
>2012-12-25 01:11:45.842 [INFO ] Processing by ApplicationsController#show as JSON (pid:1783)
>2012-12-25 01:11:45.843 [INFO ] Parameters: {"broker_auth_key"=>"[FILTERED]", "broker_auth_iv"=>"[FILTERED]", "domain_id"=>"qgong21", "id"=>"qsruby18"} (pid:1783)
>2012-12-25 01:11:45.856 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (4.7889ms) (pid:1783)
>2012-12-25 01:11:45.858 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"_id"=>"qgong@redhat.com"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.2935ms) (pid:1783)
>2012-12-25 01:11:45.859 [INFO ] Completed 500 Internal Server Error in 16ms (pid:1783)
>2012-12-25 01:11:45.862 [FATAL] Mongoid::Errors::DocumentNotFound (
>Problem:
>  Document(s) not found for class CloudUser with id(s) qgong@redhat.com.
>Summary:
>  When calling CloudUser.find with an id or array of ids, each parameter must match a document in the database or this error will be raised. The search was for the id(s): qgong@redhat.com ... (1 total) and the following ids were not found: qgong@redhat.com.
>Resolution:
>  Search for an id that is in the database or set the Mongoid.raise_not_found_error configuration option to false, which will cause a nil to be returned instead of raising this error when searching for a single id, or only the matched documents when searching for multiples.):
>  mongoid (3.0.5) lib/mongoid/criteria.rb:553:in `check_for_missing_documents!'
>  mongoid (3.0.5) lib/mongoid/criteria.rb:177:in `execute_or_raise'
>  mongoid (3.0.5) lib/mongoid/criteria.rb:243:in `find'
>  mongoid (3.0.5) lib/mongoid/finders.rb:61:in `find'
>  openshift-origin-controller (1.3.2) lib/openshift/auth_service.rb:79:in `validate_broker_key'
>  openshift-origin-auth-streamline (1.2.1) lib/openshift/streamline_auth_service.rb:23:in `authenticate'
>  openshift-origin-controller (1.3.2) app/controllers/base_controller.rb:106:in `authenticate'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:429:in `_run__2686690151553007994__process_action__878133323474870097__callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks'
>  actionpack (3.2.8) lib/abstract_controller/callbacks.rb:17:in `process_action'
>  actionpack (3.2.8) lib/action_controller/metal/rescue.rb:29:in `process_action'
>  actionpack (3.2.8) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
>  activesupport (3.2.8) lib/active_support/notifications.rb:123:in `block in instrument'
>  activesupport (3.2.8) lib/active_support/notifications/instrumenter.rb:20:in `instrument'
>  activesupport (3.2.8) lib/active_support/notifications.rb:123:in `instrument'
>  actionpack (3.2.8) lib/action_controller/metal/instrumentation.rb:29:in `process_action'
>  actionpack (3.2.8) lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
>  actionpack (3.2.8) lib/abstract_controller/base.rb:121:in `process'
>  actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process'
>  actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch'
>  actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
>  actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action'
>  actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call'
>  actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
>  actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:36:in `call'
>  journey (1.0.4) lib/journey/router.rb:68:in `block in call'
>  journey (1.0.4) lib/journey/router.rb:56:in `each'
>  journey (1.0.4) lib/journey/router.rb:56:in `call'
>  actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:600:in `call'
>  mongo_mapper (0.11.1) lib/mongo_mapper/middleware/identity_map.rb:10:in `call'
>  mongoid (3.0.5) lib/rack/mongoid/middleware/identity_map.rb:33:in `block in call'
>  mongoid (3.0.5) lib/mongoid/unit_of_work.rb:39:in `unit_of_work'
>  mongoid (3.0.5) lib/rack/mongoid/middleware/identity_map.rb:33:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
>  rack (1.4.1) lib/rack/etag.rb:23:in `call'
>  rack (1.4.1) lib/rack/conditionalget.rb:25:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call'
>  rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context'
>  rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__915588837484752555__call__1100037428762684133__callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks'
>  actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
>  railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app'
>  railties (3.2.8) lib/rails/rack/logger.rb:16:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call'
>  rack (1.4.1) lib/rack/methodoverride.rb:21:in `call'
>  rack (1.4.1) lib/rack/runtime.rb:17:in `call'
>  activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call'
>  rack (1.4.1) lib/rack/lock.rb:15:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call'
>  rack-cache (1.2) lib/rack/cache/context.rb:136:in `forward'
>  rack-cache (1.2) lib/rack/cache/context.rb:245:in `fetch'
>  rack-cache (1.2) lib/rack/cache/context.rb:185:in `lookup'
>  rack-cache (1.2) lib/rack/cache/context.rb:66:in `call!'
>  rack-cache (1.2) lib/rack/cache/context.rb:51:in `call'
>  railties (3.2.8) lib/rails/engine.rb:479:in `call'
>  railties (3.2.8) lib/rails/application.rb:223:in `call'
>  railties (3.2.8) lib/rails/railtie/configurable.rb:30:in `method_missing'
>  passenger (3.0.12) lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'
>  passenger (3.0.12) lib/phusion_passenger/abstract_request_handler.rb:514:in `accept_and_process_next_request'
>  passenger (3.0.12) lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'
>  passenger (3.0.12) lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'
>  passenger (3.0.12) lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'
>  passenger (3.0.12) lib/phusion_passenger/utils.rb:479:in `safe_fork'
>  passenger (3.0.12) lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'
>  passenger (3.0.12) lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'
>  passenger (3.0.12) lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'
>  passenger (3.0.12) lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'
>  passenger (3.0.12) lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'
>  passenger (3.0.12) lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'
>  passenger (3.0.12) helper-scripts/passenger-spawn-server:102:in `<main>' (pid:1783)
>2012-12-25 01:11:45.876 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.6ms) (pid:1783)
>2012-12-25 01:11:45.881 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.5ms) (pid:1783)
>2012-12-25 01:11:45.881 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (10.2ms) (pid:1783)

Actions: View

Attachments on bug 890104: 668732