Attachment 668717 Details for Bug 890101 – development.log

development.log

file_890101.txt (text/plain), 67.75 KB, created by Rony Gong 🔥 on 2012-12-25 06:04:20 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rony Gong 🔥

Created: 2012-12-25 06:04:20 UTC

Size: 67.75 KB

patch

obsolete

>2012-12-25 00:37:43.343 [INFO ] Started GET "/broker/rest/api" for 203.114.244.88 at 2012-12-25 00:37:43 -0500 (pid:1783)
>2012-12-25 00:37:43.345 [INFO ] Processing by BaseController#show as JSON (pid:1783)
>2012-12-25 00:37:43.345 [DEBUG] application/json (pid:1783)
>2012-12-25 00:37:43.353 [INFO ] Completed 200 OK in 8ms (Views: 3.0ms) (pid:1783)
>2012-12-25 00:37:44.415 [INFO ] Started GET "/broker/rest/domains" for 203.114.244.88 at 2012-12-25 00:37:44 -0500 (pid:1783)
>2012-12-25 00:37:44.417 [INFO ] Processing by DomainsController#index as JSON (pid:1783)
>2012-12-25 00:37:44.419 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"qgong@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.7222ms) (pid:1783)
>2012-12-25 00:37:44.420 [DEBUG] application/json (pid:1783)
>2012-12-25 00:37:44.420 [DEBUG] Getting domains for user qgong@redhat.com (pid:1783)
>2012-12-25 00:37:44.421 [DEBUG] #<Mongoid::Criteria:0x000000067eff80> (pid:1783)
>2012-12-25 00:37:44.421 [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.4585ms) (pid:1783)
>2012-12-25 00:37:44.423 [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.3862ms) (pid:1783)
>2012-12-25 00:37:44.424 [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.5291ms) (pid:1783)
>2012-12-25 00:37:44.425 [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.4032ms) (pid:1783)
>2012-12-25 00:37:44.428 [DEBUG] [REQ_ID=013e43985f8442a89c5e11501047cb4b] ACTION=LIST_DOMAINS (pid:1783)
>2012-12-25 00:37:44.432 [INFO ] Completed 200 OK in 15ms (Views: 3.3ms) (pid:1783)
>2012-12-25 00:37:45.629 [INFO ] Started GET "/broker/rest/cartridges" for 203.114.244.88 at 2012-12-25 00:37:45 -0500 (pid:1783)
>2012-12-25 00:37:45.631 [INFO ] Processing by CartridgesController#index as JSON (pid:1783)
>2012-12-25 00:37:45.631 [DEBUG] application/json (pid:1783)
>2012-12-25 00:37:45.636 [DEBUG] [REQ_ID=] ACTION=LIST_CARTRIDGES List all cartridges (pid:1783)
>2012-12-25 00:37:45.645 [INFO ] Completed 200 OK in 13ms (Views: 7.2ms) (pid:1783)
>2012-12-25 00:37:47.665 [INFO ] Started POST "/broker/rest/domains/qgong21/applications" for 203.114.244.88 at 2012-12-25 00:37:47 -0500 (pid:1783)
>2012-12-25 00:37:47.680 [INFO ] Processing by ApplicationsController#create as JSON (pid:1783)
>2012-12-25 00:37:47.682 [INFO ] Parameters: {"cartridge"=>"php-5.3", "name"=>"q4php", "domain_id"=>"qgong21"} (pid:1783)
>2012-12-25 00:37:47.704 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"qgong@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6809ms) (pid:1783)
>2012-12-25 00:37:47.707 [DEBUG] application/json (pid:1783)
>2012-12-25 00:37:47.720 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"50d904e60ba57ba208000001", "namespace"=>"qgong21"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6526ms) (pid:1783)
>2012-12-25 00:37:47.730 [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.3581ms) (pid:1783)
>2012-12-25 00:37:47.742 [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.4282ms) (pid:1783)
>2012-12-25 00:37:47.749 [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.3178ms) (pid:1783)
>2012-12-25 00:37:47.760 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"applications", :query=>{"domain_id"=>"50d908780ba57ba208000004", "name"=>"q4php"}} (0.3660ms) (pid:1783)
>2012-12-25 00:37:47.761 [DEBUG] Checking to see if user limit for number of apps has been reached (pid:1783)
>2012-12-25 00:37:47.808 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=applications documents=[{"_id"=>"50d93bab0ba57bf7060000ee", "domain_requires"=>[], "group_overrides"=>[], "user_ids"=>[], "aliases"=>[], "component_start_order"=>[], "component_stop_order"=>[], "component_configure_order"=>[], "default_gear_size"=>nil, "init_git_url"=>nil, "name"=>"q4php", "scalable"=>nil, "domain_id"=>"50d908780ba57ba208000004", "updated_at"=>2012-12-25 05:37:47 UTC, "created_at"=>2012-12-25 05:37:47 UTC}] flags=[] (0.4818ms) (pid:1783)
>2012-12-25 00:37:47.826 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d904e60ba57ba208000001", "app_ids"=>{"$nin"=>["50d93bab0ba57bf7060000ee"]}}, :new=>true, :update=>{"$push"=>{:app_ids=>"50d93bab0ba57bf7060000ee"}}} (0.6297ms) (pid:1783)
>2012-12-25 00:37:47.849 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee"} update={"$push"=>{"pending_op_groups"=>{"_id"=>"50d93bab0ba57bf7060000ef", "num_gears_added"=>0, "num_gears_removed"=>0, "num_gears_created"=>0, "num_gears_destroyed"=>0, "num_gears_rolled_back"=>0, "op_type"=>:add_features, "args"=>{"features"=>["php-5.3"], "group_overrides"=>[], "init_git_url"=>nil}, "parent_op_id"=>nil, "updated_at"=>2012-12-25 05:37:47 UTC, "created_at"=>2012-12-25 05:37:47 UTC}}} flags=[] (0.4199ms) (pid:1783)
>2012-12-25 00:37:47.859 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d93bab0ba57bf7060000ee"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.4106ms) (pid:1783)
>2012-12-25 00:37:47.150 [DEBUG]  (pid:1783)
>2012-12-25 00:37:47.150 [DEBUG] ----------------------------------- (pid:1783)
>2012-12-25 00:37:47.150 [DEBUG] features: ["php-5.3"], group_overrides: [{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "max_gears"=>1}] (pid:1783)
>2012-12-25 00:37:47.150 [DEBUG] final group instances: [{:component_instances=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], :scale=>{:min=>1, :max=>1, :gear_size=>nil, :additional_filesystem_gb=>0}, :_id=>"50d93bab0ba57bf7060000f0"}] (pid:1783)
>2012-12-25 00:37:47.150 [DEBUG] changes: [{:from=>nil, :to=>"50d93bab0ba57bf7060000f0", :added=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], :removed=>[], :from_scale=>{:min=>1, :max=>-1, :current=>0, :additional_filesystem_gb=>0, :gear_size=>"small"}, :to_scale=>{:min=>1, :max=>1, :gear_size=>nil, :additional_filesystem_gb=>0}}], moves: [] (pid:1783)
>2012-12-25 00:37:47.151 [DEBUG] ----------------------------------- (pid:1783)
>2012-12-25 00:37:47.151 [DEBUG]  (pid:1783)
>2012-12-25 00:37:47.155 [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.6862ms) (pid:1783)
>2012-12-25 00:37:47.157 [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.3660ms) (pid:1783)
>2012-12-25 00:37:47.158 [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.6170ms) (pid:1783)
>2012-12-25 00:37:47.159 [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.2849ms) (pid:1783)
>2012-12-25 00:37:47.168 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"50d904e60ba57ba208000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.4117ms) (pid:1783)
>2012-12-25 00:37:47.169 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d904e60ba57ba208000001", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x000000060fb3c8>"=>1356413867}], "app_ids"=>{"$in"=>["50d93bab0ba57bf7060000ee"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1356414467}}} (0.6392ms) (pid:1783)
>2012-12-25 00:37:47.170 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"50d904e60ba57ba208000001"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.4323ms) (pid:1783)
>2012-12-25 00:37:47.182 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef"} update={"$pushAll"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"50d93bab0ba57bf7060000f1", "state"=>:init, "retry_count"=>0, "saved_values"=>{"current_group_overrides"=>[]}, "op_type"=>:set_group_overrides, "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "max_gears"=>1}]}}, {"_id"=>"50d93bab0ba57bf7060000f3", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_size"=>nil, "additional_filesystem_gb"=>0}}, {"_id"=>"50d93bab0ba57bf7060000f6", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee"}, "prereq"=>["50d93bab0ba57bf7060000f5"], "retry_rollback_op"=>"50d93bab0ba57bf7060000f5"}, {"_id"=>"50d93bab0ba57bf7060000f4", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["50d93bab0ba57bf7060000f3"]}, {"_id"=>"50d93bab0ba57bf7060000fa", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong@redhat.com", "gear_ref"=>"50d93bab0ba57bf7060000ee", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>nil}, "prereq"=>["50d93bab0ba57bf7060000f4"]}, {"_id"=>"50d93bab0ba57bf7060000f5", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee"}, "prereq"=>["50d93bab0ba57bf7060000f4"]}, {"_id"=>"50d93bab0ba57bf7060000f7", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee"}, "prereq"=>["50d93bab0ba57bf7060000f6"]}, {"_id"=>"50d93bab0ba57bf7060000fb", "state"=>:init, "retry_count"=>0, "saved_values"=>{"additional_filesystem_gb"=>0}, "op_type"=>:set_gear_additional_filesystem_gb, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee", "additional_filesystem_gb"=>0}, "prereq"=>["50d93bab0ba57bf7060000f4"]}, {"_id"=>"50d93bab0ba57bf7060000fc", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"50d905060ba57ba208000003", "type"=>"ssh-rsa", "name"=>"default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee"}, "prereq"=>["50d93bab0ba57bf7060000f7"]}, {"_id"=>"50d93bab0ba57bf7060000f8", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "prereq"=>["50d93bab0ba57bf7060000f3"]}, {"_id"=>"50d93bab0ba57bf7060000f9", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["50d93bab0ba57bf7060000f8", "50d93bab0ba57bf7060000f7"]}, {"_id"=>"50d93bab0ba57bf7060000fd", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:expose_port, "args"=>{"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "prereq"=>["50d93bab0ba57bf7060000f9", "50d93bab0ba57bf7060000f7"]}, {"_id"=>"50d93bab0ba57bf7060000fe", "state"=>:init, "retry_count"=>0, "saved_values"=>{:current_connections=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["50d93bab0ba57bf7060000f1", "50d93bab0ba57bf7060000f3", "50d93bab0ba57bf7060000f6", "50d93bab0ba57bf7060000f4", "50d93bab0ba57bf7060000fa", "50d93bab0ba57bf7060000f5", "50d93bab0ba57bf7060000f7", "50d93bab0ba57bf7060000fb", "50d93bab0ba57bf7060000fc", "50d93bab0ba57bf7060000f8", "50d93bab0ba57bf7060000f9", "50d93bab0ba57bf7060000fd"]}, {"_id"=>"50d93bab0ba57bf7060000ff", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["50d93bab0ba57bf7060000fe"]}]}} flags=[] (2.6653ms) (pid:1783)
>2012-12-25 00:37:47.191 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef"} update={"$set"=>{"pending_op_groups.0.num_gears_added"=>1, "pending_op_groups.0.updated_at"=>2012-12-25 05:37:47 UTC}} flags=[] (0.4194ms) (pid:1783)
>2012-12-25 00:37:47.194 [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.4950ms) (pid:1783)
>2012-12-25 00:37:47.195 [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.2882ms) (pid:1783)
>2012-12-25 00:37:47.198 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"50d904e60ba57ba208000001"} update={"$set"=>{"consumed_gears"=>6, "updated_at"=>2012-12-25 05:37:47 UTC}} flags=[] (0.2828ms) (pid:1783)
>2012-12-25 00:37:47.199 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d904e60ba57ba208000001", "locked"=>true, "app_ids"=>{"$in"=>["50d93bab0ba57bf7060000ee"]}}, :new=>false, :update=>{"$set"=>{"locked"=>false}}} (0.5043ms) (pid:1783)
>2012-12-25 00:37:47.199 [DEBUG] ----------------------------------- (pid:1783)
>2012-12-25 00:37:47.200 [DEBUG] #<PendingAppOpGroup _id: 50d93bab0ba57bf7060000ef, _type: nil, created_at: 2012-12-25 05:37:47 UTC, updated_at: 2012-12-25 05:37:47 UTC, op_type: :add_features, args: {"features"=>["php-5.3"], "group_overrides"=>[], "init_git_url"=>nil}, parent_op_id: nil, num_gears_added: 1, num_gears_removed: 0, num_gears_created: 0, num_gears_destroyed: 0, num_gears_rolled_back: 0> (pid:1783)
>2012-12-25 00:37:47.200 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000f1, _type: nil, op_type: :set_group_overrides, state: :init, args: {"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "max_gears"=>1}]}, prereq: nil, retry_count: 0, retry_rollback_op: nil, saved_values: {"current_group_overrides"=>[]}> (pid:1783)
>2012-12-25 00:37:47.200 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000f3, _type: nil, op_type: :create_group_instance, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_size"=>nil, "additional_filesystem_gb"=>0}, prereq: nil, retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.200 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000f6, _type: nil, op_type: :create_gear, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee"}, prereq: ["50d93bab0ba57bf7060000f5"], retry_count: 0, retry_rollback_op: "50d93bab0ba57bf7060000f5", saved_values: []> (pid:1783)
>2012-12-25 00:37:47.200 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000f4, _type: nil, op_type: :init_gear, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee", "host_singletons"=>true, "app_dns"=>true}, prereq: ["50d93bab0ba57bf7060000f3"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.201 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000fa, _type: nil, op_type: :track_usage, state: :init, args: {"login"=>"qgong@redhat.com", "gear_ref"=>"50d93bab0ba57bf7060000ee", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>nil}, prereq: ["50d93bab0ba57bf7060000f4"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.201 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000f5, _type: nil, op_type: :reserve_uid, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee"}, prereq: ["50d93bab0ba57bf7060000f4"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.201 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000f7, _type: nil, op_type: :register_dns, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee"}, prereq: ["50d93bab0ba57bf7060000f6"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.201 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000fb, _type: nil, op_type: :set_gear_additional_filesystem_gb, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee", "additional_filesystem_gb"=>0}, prereq: ["50d93bab0ba57bf7060000f4"], retry_count: 0, retry_rollback_op: nil, saved_values: {"additional_filesystem_gb"=>0}> (pid:1783)
>2012-12-25 00:37:47.202 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000fc, _type: nil, op_type: :update_configuration, state: :init, args: {"add_keys_attrs"=>[{"_id"=>"50d905060ba57ba208000003", "type"=>"ssh-rsa", "name"=>"default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee"}, prereq: ["50d93bab0ba57bf7060000f7"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.202 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000f8, _type: nil, op_type: :new_component, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, prereq: ["50d93bab0ba57bf7060000f3"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.202 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000f9, _type: nil, op_type: :add_component, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, prereq: ["50d93bab0ba57bf7060000f8", "50d93bab0ba57bf7060000f7"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.202 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000fd, _type: nil, op_type: :expose_port, state: :init, args: {"group_instance_id"=>"50d93bab0ba57bf7060000f0", "gear_id"=>"50d93bab0ba57bf7060000ee", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, prereq: ["50d93bab0ba57bf7060000f9", "50d93bab0ba57bf7060000f7"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.203 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000fe, _type: nil, op_type: :set_connections, state: :init, args: {"connections"=>[]}, prereq: ["50d93bab0ba57bf7060000f1", "50d93bab0ba57bf7060000f3", "50d93bab0ba57bf7060000f6", "50d93bab0ba57bf7060000f4", "50d93bab0ba57bf7060000fa", "50d93bab0ba57bf7060000f5", "50d93bab0ba57bf7060000f7", "50d93bab0ba57bf7060000fb", "50d93bab0ba57bf7060000fc", "50d93bab0ba57bf7060000f8", "50d93bab0ba57bf7060000f9", "50d93bab0ba57bf7060000fd"], retry_count: 0, retry_rollback_op: nil, saved_values: {:current_connections=>[]}> (pid:1783)
>2012-12-25 00:37:47.203 [DEBUG] #<PendingAppOp _id: 50d93bab0ba57bf7060000ff, _type: nil, op_type: :execute_connections, state: :init, args: nil, prereq: ["50d93bab0ba57bf7060000fe"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:1783)
>2012-12-25 00:37:47.203 [DEBUG] ----------------------------------- (pid:1783)
>2012-12-25 00:37:47.205 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d93bab0ba57bf7060000ee"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.0855ms) (pid:1783)
>2012-12-25 00:37:47.215 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee"} update={"$set"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "max_gears"=>1}], "updated_at"=>2012-12-25 05:37:47 UTC}} flags=[] (0.3748ms) (pid:1783)
>2012-12-25 00:37:47.216 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000f1"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:completed}} flags=[] (0.5834ms) (pid:1783)
>2012-12-25 00:37:47.218 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee"} update={"$push"=>{"group_instances"=>{"_id"=>"50d93bab0ba57bf7060000f0", "gear_size"=>"small", "addtl_fs_gb"=>0}}} flags=[] (0.2637ms) (pid:1783)
>2012-12-25 00:37:47.219 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000f3"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:completed}} flags=[] (0.4485ms) (pid:1783)
>2012-12-25 00:37:47.221 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d93bab0ba57bf7060000ee"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.1353ms) (pid:1783)
>2012-12-25 00:37:47.229 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "group_instances._id"=>"50d93bab0ba57bf7060000f0"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"50d93bab0ba57bf7060000ee", "host_singletons"=>true, "app_dns"=>true, "name"=>"q4php"}}} flags=[] (0.5116ms) (pid:1783)
>2012-12-25 00:37:47.232 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000f4"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:completed}} flags=[] (0.4249ms) (pid:1783)
>2012-12-25 00:37:47.233 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee"} update={"$push"=>{"component_instances"=>{"_id"=>"50d93bab0ba57bf706000102", "component_properties"=>{}, "cartridge_name"=>"php-5.3", "component_name"=>"php-5.3", "group_instance_id"=>"50d93bab0ba57bf7060000f0"}}} flags=[] (0.3598ms) (pid:1783)
>2012-12-25 00:37:47.234 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000f8"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:completed}} flags=[] (0.4892ms) (pid:1783)
>2012-12-25 00:37:47.236 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d93bab0ba57bf7060000ee"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.2023ms) (pid:1783)
>2012-12-25 00:37:47.243 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage_records documents=[{"_id"=>"50d93bab0ba57bf706000103", "event"=>"begin", "time"=>2012-12-25 05:37:47 UTC, "gear_id"=>"50d93bab0ba57bf7060000ee", "usage_type"=>"GEAR_USAGE", "login"=>"qgong@redhat.com", "updated_at"=>2012-12-25 05:37:47 UTC, "created_at"=>2012-12-25 05:37:47 UTC}] flags=[] (0.3984ms) (pid:1783)
>2012-12-25 00:37:47.245 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage documents=[{"_id"=>"50d93bab0ba57bf706000104", "login"=>"qgong@redhat.com", "gear_id"=>"50d93bab0ba57bf7060000ee", "begin_time"=>2012-12-25 05:37:47 UTC, "usage_type"=>"GEAR_USAGE", "updated_at"=>2012-12-25 05:37:47 UTC, "created_at"=>2012-12-25 05:37:47 UTC}] flags=[] (0.4075ms) (pid:1783)
>2012-12-25 00:37:47.246 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000fa"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:completed}} flags=[] (0.4194ms) (pid:1783)
>2012-12-25 00:37:47.263 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=districts selector={"available_capacity"=>{"$gte"=>0}, "gear_size"=>"small", "active_server_identities_size"=>{"$gte"=>0}} flags=[:slave_ok] limit=0 skip=0 fields=nil (15.7084ms) (pid:1783)
>2012-12-25 00:37:47.264 [ERROR] undefined method `>' for false:FalseClass (pid:1783)
>2012-12-25 00:37:47.264 [ERROR] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/district.rb:44:in `sort'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/district.rb:44:in `find_available'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:33:in `find_available_impl'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/lib/openshift/application_container_proxy.rb:22:in `find_available'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/gear.rb:58:in `reserve_uid'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:154:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:905:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:319:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:937:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:316:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:91:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/controllers/applications_controller.rb:96:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:18:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:458:in `_run__2686690151553007994__process_action__2265754231628701415__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__915588837484752555__call__1100037428762684133__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:514:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/utils.rb:479:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:1783)
>2012-12-25 00:37:47.264 [ERROR] undefined method `>' for false:FalseClass (pid:1783)
>2012-12-25 00:37:47.265 [DEBUG] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/district.rb:44:in `sort'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/district.rb:44:in `find_available'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:33:in `find_available_impl'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/lib/openshift/application_container_proxy.rb:22:in `find_available'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/gear.rb:58:in `reserve_uid'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:154:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:905:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:319:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:937:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:316:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:91:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/controllers/applications_controller.rb:96:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:18:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:458:in `_run__2686690151553007994__process_action__2265754231628701415__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__915588837484752555__call__1100037428762684133__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:514:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/utils.rb:479:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:1783)
>2012-12-25 00:37:47.264 [ERROR] undefined method `>' for false:FalseClass (pid:1783)
>2012-12-25 00:37:47.265 [DEBUG] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/district.rb:44:in `sort'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/district.rb:44:in `find_available'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:33:in `find_available_impl'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/lib/openshift/application_container_proxy.rb:22:in `find_available'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/gear.rb:58:in `reserve_uid'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:154:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:905:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:319:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:937:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:316:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:91:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/controllers/applications_controller.rb:96:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:18:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:458:in `_run__2686690151553007994__process_action__2265754231628701415__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__915588837484752555__call__1100037428762684133__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:514:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/utils.rb:479:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:1783)
>2012-12-25 00:37:47.267 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d93bab0ba57bf7060000ee"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.3556ms) (pid:1783)
>2012-12-25 00:37:47.273 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee"} update={"$set"=>{"group_overrides"=>nil, "updated_at"=>2012-12-25 05:37:47 UTC}} flags=[] (0.3121ms) (pid:1783)
>2012-12-25 00:37:47.274 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000f1"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:rolledback}} flags=[] (0.4964ms) (pid:1783)
>2012-12-25 00:37:47.275 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage_records selector={"$query"=>{"login"=>"qgong@redhat.com", "gear_id"=>"50d93bab0ba57bf7060000ee", "event"=>"begin", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6559ms) (pid:1783)
>2012-12-25 00:37:47.276 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=usage_records selector={"_id"=>"50d93bab0ba57bf706000103"} flags=[:remove_first] (0.3295ms) (pid:1783)
>2012-12-25 00:37:47.277 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage selector={"$query"=>{"gear_id"=>"50d93bab0ba57bf7060000ee", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{"begin_time"=>-1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.4795ms) (pid:1783)
>2012-12-25 00:37:47.278 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=usage selector={"_id"=>"50d93bab0ba57bf706000104"} flags=[:remove_first] (0.2241ms) (pid:1783)
>2012-12-25 00:37:47.279 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000fa"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:rolledback}} flags=[] (0.4222ms) (pid:1783)
>2012-12-25 00:37:47.280 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee"} update={"$pull"=>{"component_instances"=>{"_id"=>"50d93bab0ba57bf706000102"}}} flags=[] (0.3009ms) (pid:1783)
>2012-12-25 00:37:47.281 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000f8"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:rolledback}} flags=[] (0.4911ms) (pid:1783)
>2012-12-25 00:37:47.283 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d93bab0ba57bf7060000ee"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.1055ms) (pid:1783)
>2012-12-25 00:37:47.287 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "group_instances._id"=>"50d93bab0ba57bf7060000f0"} update={"$pull"=>{"group_instances.0.gears"=>{"_id"=>"50d93bab0ba57bf7060000ee"}}} flags=[] (0.9091ms) (pid:1783)
>2012-12-25 00:37:47.290 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000f4"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:rolledback}} flags=[] (0.4597ms) (pid:1783)
>2012-12-25 00:37:47.291 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d93bab0ba57bf7060000ee"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.0476ms) (pid:1783)
>2012-12-25 00:37:47.294 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee"} update={"$pull"=>{"group_instances"=>{"_id"=>"50d93bab0ba57bf7060000f0"}}} flags=[] (0.2584ms) (pid:1783)
>2012-12-25 00:37:47.295 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee", "pending_op_groups._id"=>"50d93bab0ba57bf7060000ef", "pending_op_groups.0.pending_ops._id"=>"50d93bab0ba57bf7060000f3"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:rolledback}} flags=[] (0.4139ms) (pid:1783)
>2012-12-25 00:37:47.296 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"50d904e60ba57ba208000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.4280ms) (pid:1783)
>2012-12-25 00:37:47.297 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d904e60ba57ba208000001", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000006e74298>"=>1356413867}], "app_ids"=>{"$in"=>["50d93bab0ba57bf7060000ee"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1356414467}}} (0.6707ms) (pid:1783)
>2012-12-25 00:37:47.298 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"50d904e60ba57ba208000001"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.3948ms) (pid:1783)
>2012-12-25 00:37:47.300 [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.4518ms) (pid:1783)
>2012-12-25 00:37:47.301 [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.2828ms) (pid:1783)
>2012-12-25 00:37:47.304 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"50d904e60ba57ba208000001"} update={"$set"=>{"consumed_gears"=>5, "updated_at"=>2012-12-25 05:37:47 UTC}} flags=[] (0.2530ms) (pid:1783)
>2012-12-25 00:37:47.305 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d904e60ba57ba208000001", "locked"=>true, "app_ids"=>{"$in"=>["50d93bab0ba57bf7060000ee"]}}, :new=>false, :update=>{"$set"=>{"locked"=>false}}} (0.4482ms) (pid:1783)
>2012-12-25 00:37:47.305 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d93bab0ba57bf7060000ee"} update={"$pull"=>{"pending_op_groups"=>{"_id"=>"50d93bab0ba57bf7060000ef"}}} flags=[] (0.2589ms) (pid:1783)
>2012-12-25 00:37:47.307 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d904e60ba57ba208000001", "locked"=>false, "app_ids"=>{"$in"=>["50d93bab0ba57bf7060000ee"]}}, :new=>false, :update=>{"$pop"=>{:app_ids=>"50d93bab0ba57bf7060000ee"}}} (0.4656ms) (pid:1783)
>2012-12-25 00:37:48.310 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"domain_id"=>"50d908780ba57ba208000004"} flags=[:slave_ok] limit=0 skip=0 fields=nil (1.2970ms) (pid:1783)
>2012-12-25 00:37:48.324 [DEBUG] [REQ_ID=54f770ad441e41fabcd01a67abb435b0] ACTION=ADD_APPLICATION Application q4php was created., TODO (pid:1783)
>2012-12-25 00:37:48.333 [INFO ] Completed 201 Created in 1265ms (Views: 6.7ms) (pid:1783)

Actions: View

Attachments on bug 890101: 668717