Attachment 700329 Details for Bug 913351 – development.log

development.log

file_913351.txt (text/plain), 90.18 KB, created by Rony Gong 🔥 on 2013-02-21 03:30:17 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rony Gong 🔥

Created: 2013-02-21 03:30:17 UTC

Size: 90.18 KB

patch

obsolete

>2013-02-20 21:55:47.805 [INFO ] Started GET "/broker/rest/api" for 66.187.233.202 at 2013-02-20 21:55:47 -0500 (pid:2604)
>2013-02-20 21:55:47.809 [INFO ] Processing by ApiController#show as JSON (pid:2604)
>2013-02-20 21:55:47.809 [INFO ] Processing by ApiController#show as JSON (pid:2604)
>2013-02-20 21:55:47.816 [INFO ] Completed 200 OK in 7ms (Views: 2.8ms) (pid:2604)
>2013-02-20 21:55:48.197 [INFO ] Started GET "/broker/rest/cartridges" for 66.187.233.202 at 2013-02-20 21:55:48 -0500 (pid:2604)
>2013-02-20 21:55:48.198 [INFO ] Processing by CartridgesController#index as JSON (pid:2604)
>2013-02-20 21:55:48.205 [DEBUG] SUCCESS ACTION=LIST_CARTRIDGES USER_ID= LOGIN= List all cartridges (pid:2604)
>2013-02-20 21:55:48.216 [INFO ] Completed 200 OK in 18ms (Views: 9.8ms) (pid:2604)
>2013-02-20 21:55:49.965 [INFO ] Started GET "/broker/rest/domains" for 66.187.233.202 at 2013-02-20 21:55:49 -0500 (pid:2604)
>2013-02-20 21:55:49.967 [INFO ] Processing by DomainsController#index as JSON (pid:2604)
>2013-02-20 21:55:49.968 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID= LOGIN= MESSAGE=OpenShift::AccessDeniedException Access denied (pid:2604)
>2013-02-20 21:55:49.969 [INFO ] Filter chain halted as :authenticate rendered or redirected (pid:2604)
>2013-02-20 21:55:49.969 [INFO ] Completed 401 Unauthorized in 2ms (pid:2604)
>2013-02-20 21:55:50.325 [INFO ] Started GET "/broker/rest/domains" for 66.187.233.202 at 2013-02-20 21:55:50 -0500 (pid:2604)
>2013-02-20 21:55:50.327 [INFO ] Processing by DomainsController#index as JSON (pid:2604)
>2013-02-20 21:55:50.329 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"qgong+1@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (0.7472ms) (pid:2604)
>2013-02-20 21:55:50.329 [DEBUG] Getting domains for user qgong+1@redhat.com (pid:2604)
>2013-02-20 21:55:50.330 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.3662ms) (pid:2604)
>2013-02-20 21:55:50.331 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51258c005734f3d83c000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (0.3529ms) (pid:2604)
>2013-02-20 21:55:50.333 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.5338ms) (pid:2604)
>2013-02-20 21:55:50.334 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.3364ms) (pid:2604)
>2013-02-20 21:55:50.337 [DEBUG] SUCCESS ACTION=LIST_DOMAINS USER_ID=51258c005734f3d83c000001 LOGIN=qgong+1@redhat.com (pid:2604)
>2013-02-20 21:55:50.341 [INFO ] Completed 200 OK in 14ms (Views: 3.0ms) (pid:2604)
>2013-02-20 21:55:50.707 [INFO ] Started POST "/broker/rest/domains/qgong4/applications" for 66.187.233.202 at 2013-02-20 21:55:50 -0500 (pid:2604)
>2013-02-20 21:55:50.710 [INFO ] Processing by ApplicationsController#create as JSON (pid:2604)
>2013-02-20 21:55:50.711 [INFO ] Parameters: {"name"=>"qphp2", "cartridges"=>["php-5.3"], "domain_id"=>"qgong4", "application"=>{"name"=>"qphp2"}} (pid:2604)
>2013-02-20 21:55:50.713 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"qgong+1@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (0.7601ms) (pid:2604)
>2013-02-20 21:55:50.714 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"51258c005734f3d83c000001", "canonical_namespace"=>"qgong4"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4504ms) (pid:2604)
>2013-02-20 21:55:50.715 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51258c005734f3d83c000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (0.3409ms) (pid:2604)
>2013-02-20 21:55:50.716 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.4203ms) (pid:2604)
>2013-02-20 21:55:50.717 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.3364ms) (pid:2604)
>2013-02-20 21:55:50.718 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"applications", :query=>{"domain_id"=>"51258c275734f3d83c000004", "canonical_name"=>"qphp2"}} (0.3495ms) (pid:2604)
>2013-02-20 21:55:50.718 [DEBUG] Checking to see if user limit for number of apps has been reached (pid:2604)
>2013-02-20 21:55:50.724 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=applications documents=[{"_id"=>"51258cb65734f3d83c000018", "uuid"=>"51258cb65734f3d83c000018", "domain_requires"=>[], "group_overrides"=>[], "user_ids"=>[], "aliases"=>[], "component_start_order"=>[], "component_stop_order"=>[], "component_configure_order"=>[], "default_gear_size"=>"small", "init_git_url"=>nil, "canonical_name"=>"qphp2", "name"=>"qphp2", "scalable"=>false, "domain_id"=>"51258c275734f3d83c000004", "updated_at"=>2013-02-21 02:55:50 UTC, "created_at"=>2013-02-21 02:55:50 UTC}] flags=[] (pid:2604)
>2013-02-20 21:55:50.724 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7534ms) (pid:2604)
>2013-02-20 21:55:50.729 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51258c005734f3d83c000001", "$or"=>[{"app_ids.51258cb65734f3d83c000018"=>{"$exists"=>false}}, {"app_ids.51258cb65734f3d83c000018"=>{"$lt"=>1361415350}}]}, :new=>true, :update=>{"$set"=>{"app_ids.51258cb65734f3d83c000018"=>1361415950}}} (0.7365ms) (pid:2604)
>2013-02-20 21:55:50.732 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$push"=>{"pending_op_groups"=>{"_id"=>"51258cb65734f3d83c000019", "num_gears_added"=>0, "num_gears_removed"=>0, "num_gears_created"=>0, "num_gears_destroyed"=>0, "num_gears_rolled_back"=>0, "user_agent"=>"rhc/1.4.8 (ruby 1.8.7; x86_64-linux) (2.3.2, ruby 1.8.7 (2011-06-30) [x86_64-linux])", "op_type"=>:add_features, "args"=>{"features"=>["php-5.3"], "group_overrides"=>[], "init_git_url"=>nil}, "parent_op_id"=>nil, "updated_at"=>2013-02-21 02:55:50 UTC, "created_at"=>2013-02-21 02:55:50 UTC}}} flags=[] (pid:2604)
>2013-02-20 21:55:50.732 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9453ms) (pid:2604)
>2013-02-20 21:55:50.733 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"51258cb65734f3d83c000018"} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4005ms) (pid:2604)
>2013-02-20 21:55:50.750 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"51258c275734f3d83c000004"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (0.4740ms) (pid:2604)
>2013-02-20 21:55:50.752 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51258c005734f3d83c000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (0.4435ms) (pid:2604)
>2013-02-20 21:55:50.753 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.4933ms) (pid:2604)
>2013-02-20 21:55:50.754 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.4280ms) (pid:2604)
>2013-02-20 21:55:50.755 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"_id"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.3195ms) (pid:2604)
>2013-02-20 21:55:50.789 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"51258c005734f3d83c000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.7019ms) (pid:2604)
>2013-02-20 21:55:50.790 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51258c005734f3d83c000001", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000004e08928>"=>1361415350}], "app_ids.51258cb65734f3d83c000018"=>{"$exists"=>true}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1361415950}}} (0.6568ms) (pid:2604)
>2013-02-20 21:55:50.791 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"51258c005734f3d83c000001"} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.3953ms) (pid:2604)
>2013-02-20 21:55:50.800 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$pushAll"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51258cb65734f3d83c00001a", "state"=>:init, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "op_type"=>:set_group_overrides, "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}]}}, {"_id"=>"51258cb65734f3d83c00001c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026"}}, {"_id"=>"51258cb65734f3d83c00001d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["51258cb65734f3d83c00001c"]}, {"_id"=>"51258cb65734f3d83c00001e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001d"]}, {"_id"=>"51258cb65734f3d83c00001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001e"], "retry_rollback_op"=>"51258cb65734f3d83c00001e"}, {"_id"=>"51258cb65734f3d83c000022", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong+1@redhat.com", "app_name"=>"qphp2", "gear_ref"=>"51258cb65734f3d83c000018", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000020", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000023", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}, {"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000021", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "prereq"=>["51258cb65734f3d83c00001c"]}, {"_id"=>"51258cb65734f3d83c000024", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["51258cb65734f3d83c000021", "51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000025", "state"=>:init, "retry_count"=>0, "saved_values"=>{:connections=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["51258cb65734f3d83c00001a", "51258cb65734f3d83c00001c", "51258cb65734f3d83c00001d", "51258cb65734f3d83c00001e", "51258cb65734f3d83c00001f", "51258cb65734f3d83c000022", "51258cb65734f3d83c000020", "51258cb65734f3d83c000023", "51258cb65734f3d83c000021", "51258cb65734f3d83c000024"]}, {"_id"=>"51258cb65734f3d83c000027", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51258cb65734f3d83c000025"]}]}} flags=[] (pid:2604)
>2013-02-20 21:55:50.801 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.5309ms) (pid:2604)
>2013-02-20 21:55:50.809 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.num_gears_added"=>1, "pending_op_groups.0.updated_at"=>2013-02-21 02:55:50 UTC}} flags=[] (pid:2604)
>2013-02-20 21:55:50.809 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5465ms) (pid:2604)
>2013-02-20 21:55:50.811 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.4442ms) (pid:2604)
>2013-02-20 21:55:50.812 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.3383ms) (pid:2604)
>2013-02-20 21:55:50.815 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"51258c005734f3d83c000001"} update={"$set"=>{"consumed_gears"=>1, "updated_at"=>2013-02-21 02:55:50 UTC}} flags=[] (pid:2604)
>2013-02-20 21:55:50.815 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4563ms) (pid:2604)
>2013-02-20 21:55:50.816 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51258c005734f3d83c000001", "locked"=>true, "app_ids.51258cb65734f3d83c000018"=>{"$exists"=>true}}, :new=>true, :update=>{"$set"=>{"locked"=>false}}} (0.4928ms) (pid:2604)
>2013-02-20 21:55:50.818 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51258cb65734f3d83c000018"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.9282ms) (pid:2604)
>2013-02-20 21:55:50.918 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51258cb65734f3d83c00001a", "state"=>:init, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "op_type"=>:set_group_overrides, "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}]}}, {"_id"=>"51258cb65734f3d83c00001c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026"}}, {"_id"=>"51258cb65734f3d83c00001d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["51258cb65734f3d83c00001c"]}, {"_id"=>"51258cb65734f3d83c00001e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001d"]}, {"_id"=>"51258cb65734f3d83c00001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001e"], "retry_rollback_op"=>"51258cb65734f3d83c00001e"}, {"_id"=>"51258cb65734f3d83c000022", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong+1@redhat.com", "app_name"=>"qphp2", "gear_ref"=>"51258cb65734f3d83c000018", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000020", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000023", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}, {"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000021", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "prereq"=>["51258cb65734f3d83c00001c"]}, {"_id"=>"51258cb65734f3d83c000024", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["51258cb65734f3d83c000021", "51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000025", "state"=>:init, "retry_count"=>0, "saved_values"=>{"connections"=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["51258cb65734f3d83c00001a", "51258cb65734f3d83c00001c", "51258cb65734f3d83c00001d", "51258cb65734f3d83c00001e", "51258cb65734f3d83c00001f", "51258cb65734f3d83c000022", "51258cb65734f3d83c000020", "51258cb65734f3d83c000023", "51258cb65734f3d83c000021", "51258cb65734f3d83c000024"]}, {"_id"=>"51258cb65734f3d83c000027", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51258cb65734f3d83c000025"]}]}} flags=[] (pid:2604)
>2013-02-20 21:55:50.918 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.6463ms) (pid:2604)
>2013-02-20 21:55:50.927 [DEBUG] Execute set_group_overrides (pid:2604)
>2013-02-20 21:55:50.932 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}], "updated_at"=>2013-02-21 02:55:50 UTC}} flags=[] (pid:2604)
>2013-02-20 21:55:50.933 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8388ms) (pid:2604)
>2013-02-20 21:55:50.934 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:completed}} flags=[] (pid:2604)
>2013-02-20 21:55:50.934 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5648ms) (pid:2604)
>2013-02-20 21:55:50.934 [DEBUG] Execute create_group_instance (pid:2604)
>2013-02-20 21:55:50.936 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$push"=>{"group_instances"=>{"_id"=>"51258cb65734f3d83c000026"}}} flags=[] (pid:2604)
>2013-02-20 21:55:50.936 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5159ms) (pid:2604)
>2013-02-20 21:55:50.937 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:completed}} flags=[] (pid:2604)
>2013-02-20 21:55:50.937 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4566ms) (pid:2604)
>2013-02-20 21:55:50.939 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51258cb65734f3d83c000018"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.0028ms) (pid:2604)
>2013-02-20 21:55:50.949 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51258cb65734f3d83c00001a", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001c", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["51258cb65734f3d83c00001c"]}, {"_id"=>"51258cb65734f3d83c00001e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001d"]}, {"_id"=>"51258cb65734f3d83c00001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001e"], "retry_rollback_op"=>"51258cb65734f3d83c00001e"}, {"_id"=>"51258cb65734f3d83c000022", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong+1@redhat.com", "app_name"=>"qphp2", "gear_ref"=>"51258cb65734f3d83c000018", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000020", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000023", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}, {"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000021", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "prereq"=>["51258cb65734f3d83c00001c"]}, {"_id"=>"51258cb65734f3d83c000024", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["51258cb65734f3d83c000021", "51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000025", "state"=>:init, "retry_count"=>0, "saved_values"=>{"connections"=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["51258cb65734f3d83c00001a", "51258cb65734f3d83c00001c", "51258cb65734f3d83c00001d", "51258cb65734f3d83c00001e", "51258cb65734f3d83c00001f", "51258cb65734f3d83c000022", "51258cb65734f3d83c000020", "51258cb65734f3d83c000023", "51258cb65734f3d83c000021", "51258cb65734f3d83c000024"]}, {"_id"=>"51258cb65734f3d83c000027", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51258cb65734f3d83c000025"]}]}} flags=[] (pid:2604)
>2013-02-20 21:55:50.950 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.3354ms) (pid:2604)
>2013-02-20 21:55:50.960 [DEBUG] Execute init_gear (pid:2604)
>2013-02-20 21:55:50.963 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"51258cb65734f3d83c000018", "uuid"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true, "name"=>"qphp2"}}} flags=[] (pid:2604)
>2013-02-20 21:55:50.963 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8774ms) (pid:2604)
>2013-02-20 21:55:50.968 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:completed}} flags=[] (pid:2604)
>2013-02-20 21:55:50.968 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5224ms) (pid:2604)
>2013-02-20 21:55:50.968 [DEBUG] Execute new_component (pid:2604)
>2013-02-20 21:55:50.970 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$push"=>{"component_instances"=>{"_id"=>"51258cb65734f3d83c00002a", "component_properties"=>{}, "cartridge_name"=>"php-5.3", "component_name"=>"php-5.3", "group_instance_id"=>"51258cb65734f3d83c000026"}}} flags=[] (pid:2604)
>2013-02-20 21:55:50.970 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5860ms) (pid:2604)
>2013-02-20 21:55:50.971 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.8.state"=>:completed}} flags=[] (pid:2604)
>2013-02-20 21:55:50.971 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5548ms) (pid:2604)
>2013-02-20 21:55:50.974 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51258cb65734f3d83c000018"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.1232ms) (pid:2604)
>2013-02-20 21:55:50.983 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51258cb65734f3d83c00001a", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001c", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001d", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001d"]}, {"_id"=>"51258cb65734f3d83c00001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001e"], "retry_rollback_op"=>"51258cb65734f3d83c00001e"}, {"_id"=>"51258cb65734f3d83c000022", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong+1@redhat.com", "app_name"=>"qphp2", "gear_ref"=>"51258cb65734f3d83c000018", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000020", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000023", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}, {"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000021", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "op_type"=>:new_component, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c000024", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["51258cb65734f3d83c000021", "51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000025", "state"=>:init, "retry_count"=>0, "saved_values"=>{"connections"=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["51258cb65734f3d83c00001a", "51258cb65734f3d83c00001c", "51258cb65734f3d83c00001d", "51258cb65734f3d83c00001e", "51258cb65734f3d83c00001f", "51258cb65734f3d83c000022", "51258cb65734f3d83c000020", "51258cb65734f3d83c000023", "51258cb65734f3d83c000021", "51258cb65734f3d83c000024"]}, {"_id"=>"51258cb65734f3d83c000027", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51258cb65734f3d83c000025"]}]}} flags=[] (pid:2604)
>2013-02-20 21:55:50.984 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.1744ms) (pid:2604)
>2013-02-20 21:55:50.992 [DEBUG] Execute reserve_uid (pid:2604)
>2013-02-20 21:55:51.217 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=districts selector={"$query"=>{"available_capacity"=>{"$gt"=>0}, "gear_size"=>"small", "active_server_identities_size"=>{"$gt"=>0}}, "$orderby"=>{"available_capacity"=>-1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (8.1453ms) (pid:2604)
>2013-02-20 21:55:51.256 [DEBUG] DEBUG: find_available_impl: district_uuid: 51258b9a5734f38307000001 (pid:2604)
>2013-02-20 21:55:51.316 [DEBUG] DEBUG: rpc_get_fact: fact=active_capacity (pid:2604)
>2013-02-20 21:55:51.630 [DEBUG] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x00000004d7c1a8> (pid:2604)
>2013-02-20 21:55:53.940 [DEBUG] Current server: ip-10-99-10-252 active capacity: 1.0 (pid:2604)
>2013-02-20 21:55:53.941 [DEBUG] DEBUG: find_available_impl: current_server: ip-10-99-10-252: 1.0 (pid:2604)
>2013-02-20 21:55:53.988 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>"ip-10-99-10-252"}} flags=[] (pid:2604)
>2013-02-20 21:55:53.990 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.5877ms) (pid:2604)
>2013-02-20 21:55:53.111 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"districts", :query=>{"uuid"=>"51258b9a5734f38307000001", "available_capacity"=>{"$gt"=>0}}, :update=>{"$pop"=>{"available_uids"=>-1}, "$inc"=>{"available_capacity"=>-1}}} (11.6365ms) (pid:2604)
>2013-02-20 21:55:53.113 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"group_instances.0.gears.0.uid"=>1001}} flags=[] (pid:2604)
>2013-02-20 21:55:53.114 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6714ms) (pid:2604)
>2013-02-20 21:55:53.115 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:completed}} flags=[] (pid:2604)
>2013-02-20 21:55:53.115 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6506ms) (pid:2604)
>2013-02-20 21:55:53.117 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51258cb65734f3d83c000018"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.0695ms) (pid:2604)
>2013-02-20 21:55:53.128 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51258cb65734f3d83c00001a", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001c", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001d", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001e", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "op_type"=>:reserve_uid, "prereq"=>["51258cb65734f3d83c00001d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001e"], "retry_rollback_op"=>"51258cb65734f3d83c00001e"}, {"_id"=>"51258cb65734f3d83c000022", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong+1@redhat.com", "app_name"=>"qphp2", "gear_ref"=>"51258cb65734f3d83c000018", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000020", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000023", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}, {"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000021", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "op_type"=>:new_component, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c000024", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["51258cb65734f3d83c000021", "51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000025", "state"=>:init, "retry_count"=>0, "saved_values"=>{"connections"=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["51258cb65734f3d83c00001a", "51258cb65734f3d83c00001c", "51258cb65734f3d83c00001d", "51258cb65734f3d83c00001e", "51258cb65734f3d83c00001f", "51258cb65734f3d83c000022", "51258cb65734f3d83c000020", "51258cb65734f3d83c000023", "51258cb65734f3d83c000021", "51258cb65734f3d83c000024"]}, {"_id"=>"51258cb65734f3d83c000027", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51258cb65734f3d83c000025"]}]}} flags=[] (pid:2604)
>2013-02-20 21:55:53.128 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.2879ms) (pid:2604)
>2013-02-20 21:55:53.136 [DEBUG] Execute create_gear (pid:2604)
>2013-02-20 21:55:53.138 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000004f5c400> (pid:2604)
>2013-02-20 21:55:53.138 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"51258cb65734f3d83c000018", "--with-app-name"=>"qphp2", "--with-container-uuid"=>"51258cb65734f3d83c000018", "--with-container-name"=>"qphp2", "--with-namespace"=>"qgong4", "--with-uid"=>1001, "--with-request-id"=>"aacd0916de0c5286c6ad73a02c5ebf00", "--cart-name"=>"openshift-origin-node"}}, ip-10-99-10-252, {'identity' => ip-10-99-10-252}) (pid:2604)
>2013-02-20 21:55:54.507 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000004fb9948 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-99-10-252", :statuscode=>1, :statusmsg=>"cartridge_do_action failed -1. Output comparison of String with 6501 failed", :data=>{:time=>nil, :output=>"comparison of String with 6501 failed", :exitcode=>-1}}>] (pid:2604)
>2013-02-20 21:55:54.509 [DEBUG] DEBUG: server results: comparison of String with 6501 failed (pid:2604)
>2013-02-20 21:55:54.510 [ERROR] Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:2604)
>2013-02-20 21:55:54.510 [ERROR] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.5.4/lib/openshift/mcollective_application_container_proxy.rb:2544:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.5.4/lib/openshift/mcollective_application_container_proxy.rb:408:in `block in create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.5.4/lib/openshift/mcollective_application_container_proxy.rb:405:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.5.4/lib/openshift/mcollective_application_container_proxy.rb:405:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/gear.rb:76:in `create_gear'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/pending_app_op_group.rb:180:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/pending_app_op_group.rb:137:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/pending_app_op_group.rb:137:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:977:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:347:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:1019:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:345:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:100:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/controllers/applications_controller.rb:97: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:448:in `block in _run__147948369459846241__process_action__1689559634589866844__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_268'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/lib/openshift/controller/action_log.rb:34:in `set_logged_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_268'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:403:in `_run__147948369459846241__process_action__1689559634589866844__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.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34: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__1193452891808360194__call__3544290175496080025__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/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.17/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/abstract_request_handler.rb:517:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/utils.rb:470:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:2604)
>2013-02-20 21:55:54.511 [ERROR] Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:2604)
>2013-02-20 21:55:54.511 [DEBUG] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.5.4/lib/openshift/mcollective_application_container_proxy.rb:2544:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.5.4/lib/openshift/mcollective_application_container_proxy.rb:408:in `block in create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.5.4/lib/openshift/mcollective_application_container_proxy.rb:405:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.5.4/lib/openshift/mcollective_application_container_proxy.rb:405:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/gear.rb:76:in `create_gear'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/pending_app_op_group.rb:180:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/pending_app_op_group.rb:137:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/pending_app_op_group.rb:137:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:977:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:347:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:1019:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:345:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/models/application.rb:100:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/app/controllers/applications_controller.rb:97: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:448:in `block in _run__147948369459846241__process_action__1689559634589866844__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_268'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.5.5/lib/openshift/controller/action_log.rb:34:in `set_logged_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_268'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:403:in `_run__147948369459846241__process_action__1689559634589866844__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.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34: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__1193452891808360194__call__3544290175496080025__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/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.17/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/abstract_request_handler.rb:517:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/utils.rb:470:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.17/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:2604)
>2013-02-20 21:55:54.526 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51258cb65734f3d83c000018"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (8.4188ms) (pid:2604)
>2013-02-20 21:55:54.623 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51258cb65734f3d83c00001a", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001c", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001d", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001e", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "op_type"=>:reserve_uid, "prereq"=>["51258cb65734f3d83c00001d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001e"], "retry_rollback_op"=>"51258cb65734f3d83c00001e"}, {"_id"=>"51258cb65734f3d83c000022", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong+1@redhat.com", "app_name"=>"qphp2", "gear_ref"=>"51258cb65734f3d83c000018", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000020", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000023", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}, {"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000021", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "op_type"=>:new_component, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c000024", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["51258cb65734f3d83c000021", "51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000025", "state"=>:init, "retry_count"=>0, "saved_values"=>{"connections"=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["51258cb65734f3d83c00001a", "51258cb65734f3d83c00001c", "51258cb65734f3d83c00001d", "51258cb65734f3d83c00001e", "51258cb65734f3d83c00001f", "51258cb65734f3d83c000022", "51258cb65734f3d83c000020", "51258cb65734f3d83c000023", "51258cb65734f3d83c000021", "51258cb65734f3d83c000024"]}, {"_id"=>"51258cb65734f3d83c000027", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51258cb65734f3d83c000025"]}]}} flags=[] (pid:2604)
>2013-02-20 21:55:54.623 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.0881ms) (pid:2604)
>2013-02-20 21:55:54.630 [DEBUG] Rollback set_group_overrides (pid:2604)
>2013-02-20 21:55:54.635 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"group_overrides"=>[], "updated_at"=>2013-02-21 02:55:54 UTC}} flags=[] (pid:2604)
>2013-02-20 21:55:54.635 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7384ms) (pid:2604)
>2013-02-20 21:55:54.637 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:rolledback}} flags=[] (pid:2604)
>2013-02-20 21:55:54.637 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5898ms) (pid:2604)
>2013-02-20 21:55:54.637 [DEBUG] Rollback reserve_uid (pid:2604)
>2013-02-20 21:55:54.648 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"districts", :query=>{"uuid"=>"51258b9a5734f38307000001", "available_uids"=>{"$nin"=>[1001]}}, :update=>{"$push"=>{"available_uids"=>1001}, "$inc"=>{"available_capacity"=>1}}} (10.0138ms) (pid:2604)
>2013-02-20 21:55:54.650 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>nil}} flags=[] (pid:2604)
>2013-02-20 21:55:54.650 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6769ms) (pid:2604)
>2013-02-20 21:55:54.651 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (pid:2604)
>2013-02-20 21:55:54.651 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4370ms) (pid:2604)
>2013-02-20 21:55:54.652 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:rolledback}} flags=[] (pid:2604)
>2013-02-20 21:55:54.652 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5019ms) (pid:2604)
>2013-02-20 21:55:54.652 [DEBUG] Rollback new_component (pid:2604)
>2013-02-20 21:55:54.654 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$pull"=>{"component_instances"=>{"_id"=>"51258cb65734f3d83c00002a"}}} flags=[] (pid:2604)
>2013-02-20 21:55:54.654 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4489ms) (pid:2604)
>2013-02-20 21:55:54.656 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.8.state"=>:rolledback}} flags=[] (pid:2604)
>2013-02-20 21:55:54.656 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.0700ms) (pid:2604)
>2013-02-20 21:55:54.658 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51258cb65734f3d83c000018"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.0016ms) (pid:2604)
>2013-02-20 21:55:54.692 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51258cb65734f3d83c00001a", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:rolledback}, {"_id"=>"51258cb65734f3d83c00001c", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001d", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001e", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "op_type"=>:reserve_uid, "prereq"=>["51258cb65734f3d83c00001d"], "retry_count"=>0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"51258cb65734f3d83c00001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001e"], "retry_rollback_op"=>"51258cb65734f3d83c00001e"}, {"_id"=>"51258cb65734f3d83c000022", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong+1@redhat.com", "app_name"=>"qphp2", "gear_ref"=>"51258cb65734f3d83c000018", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000020", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000023", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}, {"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000021", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "op_type"=>:new_component, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"51258cb65734f3d83c000024", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["51258cb65734f3d83c000021", "51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000025", "state"=>:init, "retry_count"=>0, "saved_values"=>{"connections"=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["51258cb65734f3d83c00001a", "51258cb65734f3d83c00001c", "51258cb65734f3d83c00001d", "51258cb65734f3d83c00001e", "51258cb65734f3d83c00001f", "51258cb65734f3d83c000022", "51258cb65734f3d83c000020", "51258cb65734f3d83c000023", "51258cb65734f3d83c000021", "51258cb65734f3d83c000024"]}, {"_id"=>"51258cb65734f3d83c000027", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51258cb65734f3d83c000025"]}]}} flags=[] (pid:2604)
>2013-02-20 21:55:54.693 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.2484ms) (pid:2604)
>2013-02-20 21:55:54.698 [DEBUG] Rollback init_gear (pid:2604)
>2013-02-20 21:55:54.699 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$pull"=>{"group_instances.0.gears"=>{"_id"=>"51258cb65734f3d83c000018"}}} flags=[] (pid:2604)
>2013-02-20 21:55:54.700 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6695ms) (pid:2604)
>2013-02-20 21:55:54.704 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:rolledback}} flags=[] (pid:2604)
>2013-02-20 21:55:54.704 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4992ms) (pid:2604)
>2013-02-20 21:55:54.706 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51258cb65734f3d83c000018"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.0574ms) (pid:2604)
>2013-02-20 21:55:54.716 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51258cb65734f3d83c00001a", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:rolledback}, {"_id"=>"51258cb65734f3d83c00001c", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51258cb65734f3d83c00001d", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"51258cb65734f3d83c00001e", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "op_type"=>:reserve_uid, "prereq"=>["51258cb65734f3d83c00001d"], "retry_count"=>0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"51258cb65734f3d83c00001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001e"], "retry_rollback_op"=>"51258cb65734f3d83c00001e"}, {"_id"=>"51258cb65734f3d83c000022", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong+1@redhat.com", "app_name"=>"qphp2", "gear_ref"=>"51258cb65734f3d83c000018", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000020", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c00001f"]}, {"_id"=>"51258cb65734f3d83c000023", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}, {"_id"=>"51258c205734f3d83c000003", "type"=>"ssh-rsa", "name"=>"51258c005734f3d83c000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAw0foFqMvPpcVSGMa99En9Fu88yrP82DA5k0Mn3ipEOGlYIE/xVA7PXvO2zYO/OZsQF+mdLWmXBizEJNj192q5YYgazJzQMi0jZwEMQo4MQzrgTHA5pxNJKSU2UmpD2sRVGKb0NXdaCqlza2X0TM6h8GK3gYxnds3SwUvR4DgkLi2e4P5EhG+iRIKUpVXHvHYFyCrrYq5OnQKXsrz79r2OURMfvk4z5SVSxmteHfXw8gxPnb5oZQQgGvqNcsbtwiskkKPz2UVXfIoZamA6UUyJQVkhFin4hJeu2LvHbNhWrUjhpX60Y7zeI5xdKhp+vL2SM/rVfB2q6f0jJENHmB8YQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018"}, "prereq"=>["51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000021", "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "op_type"=>:new_component, "prereq"=>["51258cb65734f3d83c00001c"], "retry_count"=>0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"51258cb65734f3d83c000024", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51258cb65734f3d83c000026", "gear_id"=>"51258cb65734f3d83c000018", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["51258cb65734f3d83c000021", "51258cb65734f3d83c000020"]}, {"_id"=>"51258cb65734f3d83c000025", "state"=>:init, "retry_count"=>0, "saved_values"=>{"connections"=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["51258cb65734f3d83c00001a", "51258cb65734f3d83c00001c", "51258cb65734f3d83c00001d", "51258cb65734f3d83c00001e", "51258cb65734f3d83c00001f", "51258cb65734f3d83c000022", "51258cb65734f3d83c000020", "51258cb65734f3d83c000023", "51258cb65734f3d83c000021", "51258cb65734f3d83c000024"]}, {"_id"=>"51258cb65734f3d83c000027", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51258cb65734f3d83c000025"]}]}} flags=[] (pid:2604)
>2013-02-20 21:55:54.716 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.2150ms) (pid:2604)
>2013-02-20 21:55:54.721 [DEBUG] Rollback create_group_instance (pid:2604)
>2013-02-20 21:55:54.722 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$pull"=>{"group_instances"=>{"_id"=>"51258cb65734f3d83c000026"}}} flags=[] (pid:2604)
>2013-02-20 21:55:54.722 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5400ms) (pid:2604)
>2013-02-20 21:55:54.724 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:rolledback}} flags=[] (pid:2604)
>2013-02-20 21:55:54.724 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5441ms) (pid:2604)
>2013-02-20 21:55:54.725 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"51258c005734f3d83c000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5133ms) (pid:2604)
>2013-02-20 21:55:54.727 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51258c005734f3d83c000001", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000004c192e8>"=>1361415354}], "app_ids.51258cb65734f3d83c000018"=>{"$exists"=>true}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1361415954}}} (0.6089ms) (pid:2604)
>2013-02-20 21:55:54.728 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"51258c005734f3d83c000001"} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4435ms) (pid:2604)
>2013-02-20 21:55:54.730 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.3996ms) (pid:2604)
>2013-02-20 21:55:54.731 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"51258c005734f3d83c000001"} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.4246ms) (pid:2604)
>2013-02-20 21:55:54.733 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"51258c005734f3d83c000001"} update={"$set"=>{"consumed_gears"=>0, "updated_at"=>2013-02-21 02:55:54 UTC}} flags=[] (pid:2604)
>2013-02-20 21:55:54.733 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4349ms) (pid:2604)
>2013-02-20 21:55:54.735 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51258c005734f3d83c000001", "locked"=>true, "app_ids.51258cb65734f3d83c000018"=>{"$exists"=>true}}, :new=>true, :update=>{"$set"=>{"locked"=>false}}} (0.5493ms) (pid:2604)
>2013-02-20 21:55:54.736 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} update={"$pull"=>{"pending_op_groups"=>{"_id"=>"51258cb65734f3d83c000019"}}} flags=[] (pid:2604)
>2013-02-20 21:55:54.736 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5012ms) (pid:2604)
>2013-02-20 21:55:54.737 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51258c005734f3d83c000001", "locked"=>false, "app_ids.51258cb65734f3d83c000018"=>{"$exists"=>true}}, :new=>true, :update=>{"$unset"=>{"app_ids.51258cb65734f3d83c000018"=>""}}} (0.4716ms) (pid:2604)
>2013-02-20 21:55:54.738 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=applications selector={"_id"=>"51258cb65734f3d83c000018"} flags=[:remove_first] (pid:2604)
>2013-02-20 21:55:54.738 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5019ms) (pid:2604)
>2013-02-20 21:55:54.739 [DEBUG] SUCCESS ACTION=ADD_APPLICATION USER_ID=51258c005734f3d83c000001 LOGIN=qgong+1@redhat.com DOMAIN=qgong4 Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:2604)
>2013-02-20 21:55:54.740 [INFO ] Completed 422 Unprocessable Entity in 4029ms (Views: 0.5ms) (pid:2604)

Actions: View

Attachments on bug 913351: 700329