Attachment 703834 Details for Bug 916506 – development.log

development.log

development.log (text/x-log), 12.71 KB, created by Jianwei Hou on 2013-02-28 07:54:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jianwei Hou

Created: 2013-02-28 07:54:07 UTC

Size: 12.71 KB

patch

obsolete

>2013-02-28 02:30:38.180 [INFO ] Started GET "/broker/rest/api" for 203.114.244.88 at 2013-02-28 02:30:38 -0500 (pid:20436)
>2013-02-28 02:30:38.183 [INFO ] Processing by ApiController#show as JSON (pid:20436)
>2013-02-28 02:30:38.214 [INFO ] Completed 200 OK in 29ms (Views: 12.0ms) (pid:20436)
>2013-02-28 02:30:39.383 [INFO ] Started GET "/broker/rest/api" for 203.114.244.88 at 2013-02-28 02:30:39 -0500 (pid:20436)
>2013-02-28 02:30:39.385 [INFO ] Processing by ApiController#show as JSON (pid:20436)
>2013-02-28 02:30:39.407 [INFO ] Completed 200 OK in 22ms (Views: 14.0ms) (pid:20436)
>2013-02-28 02:30:40.569 [INFO ] Started GET "/broker/rest/domains" for 203.114.244.88 at 2013-02-28 02:30:40 -0500 (pid:20436)
>2013-02-28 02:30:40.571 [INFO ] Processing by DomainsController#index as JSON (pid:20436)
>2013-02-28 02:30:40.575 [INFO ] Filter chain halted as :authenticate_user! rendered or redirected (pid:20436)
>2013-02-28 02:30:40.575 [INFO ] Completed 401 Unauthorized in 1ms (pid:20436)
>2013-02-28 02:30:40.861 [INFO ] Started GET "/broker/rest/domains" for 203.114.244.88 at 2013-02-28 02:30:40 -0500 (pid:20436)
>2013-02-28 02:30:40.862 [INFO ] Processing by DomainsController#index as JSON (pid:20436)
>2013-02-28 02:30:40.872 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"jhou@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (7.5517ms) (pid:20436)
>2013-02-28 02:30:40.874 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=512ee63314db5147f0000001 LOGIN=jhou@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:20436)
>2013-02-28 02:30:40.875 [DEBUG] Getting domains for user jhou@redhat.com (pid:20436)
>2013-02-28 02:30:40.876 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"512ee63314db5147f0000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.6394ms) (pid:20436)
>2013-02-28 02:30:40.886 [DEBUG] SUCCESS ACTION=LIST_DOMAINS USER_ID=512ee63314db5147f0000001 LOGIN=jhou@redhat.com (pid:20436)
>2013-02-28 02:30:40.898 [INFO ] Completed 200 OK in 35ms (Views: 6.3ms) (pid:20436)
>2013-02-28 02:30:41.196 [INFO ] Started GET "/broker/rest/domains/311t1/applications?include=cartridges" for 203.114.244.88 at 2013-02-28 02:30:41 -0500 (pid:20436)
>2013-02-28 02:30:41.198 [INFO ] Processing by ApplicationsController#index as JSON (pid:20436)
>2013-02-28 02:30:41.198 [INFO ] Parameters: {"include"=>"cartridges", "domain_id"=>"311t1"} (pid:20436)
>2013-02-28 02:30:41.204 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"jhou@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (3.5062ms) (pid:20436)
>2013-02-28 02:30:41.206 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=512ee63314db5147f0000001 LOGIN=jhou@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:20436)
>2013-02-28 02:30:41.208 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"512ee63314db5147f0000001", "canonical_namespace"=>"311t1"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (1.0455ms) (pid:20436)
>2013-02-28 02:30:41.237 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"domain_id"=>"512ee63f14db5147f0000003"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (25.1827ms) (pid:20436)
>2013-02-28 02:30:51.341 [INFO ] Completed 500 Internal Server Error in 10142ms (pid:20436)
>2013-02-28 02:30:51.356 [FATAL] NoMethodError (undefined method `has_key?' for "nodejsapp":String):
>  mongoid (3.0.21) lib/mongoid/fields.rb:77:in `apply_default'
>  mongoid (3.0.21) lib/mongoid/fields.rb:50:in `block in apply_pre_processed_defaults'
>  mongoid (3.0.21) lib/mongoid/fields.rb:49:in `each'
>  mongoid (3.0.21) lib/mongoid/fields.rb:49:in `apply_pre_processed_defaults'
>  mongoid (3.0.21) lib/mongoid/fields.rb:95:in `apply_defaults'
>  mongoid (3.0.21) lib/mongoid/document.rb:338:in `instantiate'
>  mongoid (3.0.21) lib/mongoid/factory.rb:40:in `from_db'
>  mongoid (3.0.21) lib/mongoid/relations/builders/embedded/many.rb:25:in `block in build'
>  mongoid (3.0.21) lib/mongoid/relations/builders/embedded/many.rb:23:in `each'
>  mongoid (3.0.21) lib/mongoid/relations/builders/embedded/many.rb:23:in `build'
>  mongoid (3.0.21) lib/mongoid/relations/accessors.rb:43:in `create_relation'
>  mongoid (3.0.21) lib/mongoid/relations/accessors.rb:26:in `__build__'
>  mongoid (3.0.21) lib/mongoid/relations/accessors.rb:120:in `block (2 levels) in get_relation'
>  mongoid (3.0.21) lib/mongoid/threaded/lifecycle.rb:125:in `_loading'
>  mongoid (3.0.21) lib/mongoid/relations/accessors.rb:115:in `block in get_relation'
>  mongoid (3.0.21) lib/mongoid/threaded/lifecycle.rb:84:in `_building'
>  mongoid (3.0.21) lib/mongoid/relations/accessors.rb:114:in `get_relation'
>  mongoid (3.0.21) lib/mongoid/relations/accessors.rb:203:in `block in getter'
>  openshift-origin-controller (1.5.9) app/rest_models/rest_application13.rb:20:in `initialize'
>  openshift-origin-controller (1.5.9) app/helpers/rest_model_helper.rb:6:in `new'
>  openshift-origin-controller (1.5.9) app/helpers/rest_model_helper.rb:6:in `get_rest_application'
>  openshift-origin-controller (1.5.9) app/controllers/applications_controller.rb:26:in `block in index'
>  mongoid (3.0.21) lib/mongoid/relations/targets/enumerable.rb:442:in `map!'
>  mongoid (3.0.21) lib/mongoid/relations/targets/enumerable.rb:442:in `method_missing'
>  mongoid (3.0.21) lib/mongoid/relations/referenced/many.rb:395:in `method_missing'
>  openshift-origin-controller (1.5.9) app/controllers/applications_controller.rb:26:in `index'
>  actionpack (3.2.8) lib/action_controller/metal/implicit_render.rb:4:in `send_action'
>  actionpack (3.2.8) lib/abstract_controller/base.rb:167:in `process_action'
>  actionpack (3.2.8) lib/action_controller/metal/rendering.rb:10:in `process_action'
>  actionpack (3.2.8) lib/abstract_controller/callbacks.rb:18:in `block in process_action'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:459:in `block in _run__19255109380975592__process_action__3269464466487052550__callbacks'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_310'
>  openshift-origin-controller (1.5.9) lib/openshift/controller/action_log.rb:34:in `set_logged_request'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:214:in `_conditional_callback_around_310'
>  activesupport (3.2.8) lib/active_support/callbacks.rb:403:in `_run__19255109380975592__process_action__3269464466487052550__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.21) lib/rack/mongoid/middleware/identity_map.rb:34:in `block in call'
>  mongoid (3.0.21) lib/mongoid/unit_of_work.rb:39:in `unit_of_work'
>  mongoid (3.0.21) lib/rack/mongoid/middleware/identity_map.rb:34: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__741101967654347256__call__2510027471265567279__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'
>  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.17) lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'
>  passenger (3.0.17) lib/phusion_passenger/abstract_request_handler.rb:517:in `accept_and_process_next_request'
>  passenger (3.0.17) lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'
>  passenger (3.0.17) lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'
>  passenger (3.0.17) lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'
>  passenger (3.0.17) lib/phusion_passenger/utils.rb:470:in `safe_fork'
>  passenger (3.0.17) lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'
>  passenger (3.0.17) lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'
>  passenger (3.0.17) lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'
>  passenger (3.0.17) lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'
>  passenger (3.0.17) lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'
>  passenger (3.0.17) lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'
>  passenger (3.0.17) helper-scripts/passenger-spawn-server:102:in `<main>' (pid:20436)
>2013-02-28 02:30:51.395 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (7.9ms) (pid:20436)
>2013-02-28 02:30:51.406 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (3.8ms) (pid:20436)
>2013-02-28 02:30:51.407 [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 (28.3ms) (pid:20436)

Actions: View

Attachments on bug 916506: 703834 | 703835