Attachment 691550 Details for Bug 906751 – development.log

development.log

development.log (text/x-log), 88.31 KB, created by Jianwei Hou on 2013-02-01 11:33:13 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jianwei Hou

Created: 2013-02-01 11:33:13 UTC

Size: 88.31 KB

patch

obsolete

>2013-02-01 06:31:34.693 [INFO ] Started GET "/broker/rest/api" for 209.132.188.254 at 2013-02-01 06:31:34 -0500 (pid:23130)
>2013-02-01 06:31:34.705 [INFO ] Processing by BaseController#show as JSON (pid:23130)
>2013-02-01 06:31:34.705 [DEBUG] application/json (pid:23130)
>2013-02-01 06:31:34.705 [INFO ] Processing by BaseController#show as JSON (pid:23130)
>2013-02-01 06:31:34.705 [DEBUG] application/json (pid:23130)
>2013-02-01 06:31:34.724 [INFO ] Completed 200 OK in 19ms (Views: 9.8ms) (pid:23130)
>2013-02-01 06:31:35.971 [INFO ] Started GET "/broker/rest/cartridges" for 209.132.188.254 at 2013-02-01 06:31:35 -0500 (pid:23130)
>2013-02-01 06:31:35.984 [INFO ] Processing by CartridgesController#index as JSON (pid:23130)
>2013-02-01 06:31:35.986 [DEBUG] application/json (pid:23130)
>2013-02-01 06:31:35.105 [DEBUG] [REQ_ID=] ACTION=LIST_CARTRIDGES List all cartridges (pid:23130)
>2013-02-01 06:31:35.115 [INFO ] Completed 200 OK in 17ms (Views: 8.8ms) (pid:23130)
>2013-02-01 06:31:36.699 [INFO ] Started GET "/broker/rest/domains" for 209.132.188.254 at 2013-02-01 06:31:36 -0500 (pid:23130)
>2013-02-01 06:31:36.705 [INFO ] Processing by DomainsController#index as JSON (pid:23130)
>2013-02-01 06:31:36.705 [INFO ] Processing by DomainsController#index as JSON (pid:23130)
>2013-02-01 06:31:36.713 [DEBUG] [REQ_ID=d83d6fbb7f0044898f559d1f09d5e465] ACTION=AUTHENTICATE Access denied (pid:23130)
>2013-02-01 06:31:36.713 [INFO ] Filter chain halted as :authenticate rendered or redirected (pid:23130)
>2013-02-01 06:31:36.713 [INFO ] Completed 401 Unauthorized in 7ms (pid:23130)
>2013-02-01 06:31:37.722 [INFO ] Started GET "/broker/rest/domains" for 209.132.188.254 at 2013-02-01 06:31:37 -0500 (pid:23130)
>2013-02-01 06:31:37.743 [INFO ] Processing by DomainsController#index as JSON (pid:23130)
>2013-02-01 06:31:37.128 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"jhou@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (52.2008ms) (pid:23130)
>2013-02-01 06:31:37.128 [DEBUG] application/json (pid:23130)
>2013-02-01 06:31:37.129 [DEBUG] Getting domains for user jhou@redhat.com (pid:23130)
>2013-02-01 06:31:37.130 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.6161ms) (pid:23130)
>2013-02-01 06:31:37.137 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"510b679fb0b1917a5e000043"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (5.3890ms) (pid:23130)
>2013-02-01 06:31:37.138 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.4997ms) (pid:23130)
>2013-02-01 06:31:37.139 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.3498ms) (pid:23130)
>2013-02-01 06:31:37.148 [DEBUG] [REQ_ID=45b0185da97944ee92c197fac094de1e] ACTION=LIST_DOMAINS (pid:23130)
>2013-02-01 06:31:37.167 [INFO ] Completed 200 OK in 93ms (Views: 6.8ms) (pid:23130)
>2013-02-01 06:31:38.890 [INFO ] Started POST "/broker/rest/domains/281t1/applications" for 209.132.188.254 at 2013-02-01 06:31:38 -0500 (pid:23130)
>2013-02-01 06:31:38.894 [INFO ] Processing by ApplicationsController#create as JSON (pid:23130)
>2013-02-01 06:31:38.894 [INFO ] Parameters: {"name"=>"php2s", "cartridges"=>["php-5.3"], "scale"=>true, "domain_id"=>"281t1", "application"=>{"name"=>"php2s"}} (pid:23130)
>2013-02-01 06:31:38.899 [DEBUG] [REQ_ID=8b3c942efc2a4febb472c08640f17a45] ACTION=AUTHENTICATE Access denied (pid:23130)
>2013-02-01 06:31:38.899 [INFO ] Filter chain halted as :authenticate rendered or redirected (pid:23130)
>2013-02-01 06:31:38.899 [INFO ] Completed 401 Unauthorized in 2ms (pid:23130)
>2013-02-01 06:31:39.250 [INFO ] Started POST "/broker/rest/domains/281t1/applications" for 209.132.188.254 at 2013-02-01 06:31:39 -0500 (pid:23130)
>2013-02-01 06:31:39.254 [INFO ] Processing by ApplicationsController#create as JSON (pid:23130)
>2013-02-01 06:31:39.255 [INFO ] Parameters: {"name"=>"php2s", "cartridges"=>["php-5.3"], "scale"=>true, "domain_id"=>"281t1", "application"=>{"name"=>"php2s"}} (pid:23130)
>2013-02-01 06:31:39.254 [INFO ] Processing by ApplicationsController#create as JSON (pid:23130)
>2013-02-01 06:31:39.255 [INFO ] Parameters: {"name"=>"php2s", "cartridges"=>["php-5.3"], "scale"=>true, "domain_id"=>"281t1", "application"=>{"name"=>"php2s"}} (pid:23130)
>2013-02-01 06:31:39.264 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"jhou@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (8.8017ms) (pid:23130)
>2013-02-01 06:31:39.265 [DEBUG] application/json (pid:23130)
>2013-02-01 06:31:39.267 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"510b679fb0b1917a5e000043", "canonical_namespace"=>"281t1"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 fields=nil (0.5789ms) (pid:23130)
>2013-02-01 06:31:39.267 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"510b679fb0b1917a5e000043"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.3331ms) (pid:23130)
>2013-02-01 06:31:39.269 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.4754ms) (pid:23130)
>2013-02-01 06:31:39.269 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.3505ms) (pid:23130)
>2013-02-01 06:31:39.271 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"applications", :query=>{"domain_id"=>"510b679fb0b1917a5e000046", "canonical_name"=>"php2s"}} (0.5133ms) (pid:23130)
>2013-02-01 06:31:39.271 [DEBUG] Checking to see if user limit for number of apps has been reached (pid:23130)
>2013-02-01 06:31:39.278 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=applications documents=[{"_id"=>"510ba79bb0b1915a5a000286", "uuid"=>"510ba79bb0b1915a5a000286", "domain_requires"=>[], "group_overrides"=>[], "user_ids"=>[], "aliases"=>[], "component_start_order"=>[], "component_stop_order"=>[], "component_configure_order"=>[], "default_gear_size"=>"small", "init_git_url"=>nil, "name"=>"php2s", "canonical_name"=>"php2s", "scalable"=>true, "domain_id"=>"510b679fb0b1917a5e000046", "updated_at"=>2013-02-01 11:31:39 UTC, "created_at"=>2013-02-01 11:31:39 UTC}] flags=[] (pid:23130)
>2013-02-01 06:31:39.278 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7982ms) (pid:23130)
>2013-02-01 06:31:39.285 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"510b679fb0b1917a5e000043", "app_ids"=>{"$nin"=>["510ba79bb0b1915a5a000286"]}}, :new=>true, :update=>{"$push"=>{:app_ids=>"510ba79bb0b1915a5a000286"}}} (1.3411ms) (pid:23130)
>2013-02-01 06:31:39.288 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$push"=>{"pending_op_groups"=>{"_id"=>"510ba79bb0b1915a5a000287", "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.3 (ruby 1.9.3; x86_64-linux) (2.3.2, ruby 1.9.3 (2012-12-25) [x86_64-linux])", "op_type"=>:add_features, "args"=>{"features"=>["php-5.3", "web_proxy"], "group_overrides"=>[], "init_git_url"=>nil}, "parent_op_id"=>nil, "updated_at"=>2013-02-01 11:31:39 UTC, "created_at"=>2013-02-01 11:31:39 UTC}}} flags=[] (pid:23130)
>2013-02-01 06:31:39.289 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9470ms) (pid:23130)
>2013-02-01 06:31:39.290 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[] limit=-1 skip=0 fields=nil (0.5136ms) (pid:23130)
>2013-02-01 06:31:39.308 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"510b679fb0b1917a5e000046"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5543ms) (pid:23130)
>2013-02-01 06:31:39.311 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"510b679fb0b1917a5e000043"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.3674ms) (pid:23130)
>2013-02-01 06:31:39.312 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.5372ms) (pid:23130)
>2013-02-01 06:31:39.312 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.3514ms) (pid:23130)
>2013-02-01 06:31:39.315 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"_id"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.3326ms) (pid:23130)
>2013-02-01 06:31:39.323 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"_id"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.4065ms) (pid:23130)
>2013-02-01 06:31:39.520 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"510b679fb0b1917a5e000043"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (5.6069ms) (pid:23130)
>2013-02-01 06:31:39.526 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"510b679fb0b1917a5e000043", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x000000047bf418>"=>1359718299}], "app_ids"=>"510ba79bb0b1915a5a000286"}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1359718899}}} (2.4922ms) (pid:23130)
>2013-02-01 06:31:39.528 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"510b679fb0b1917a5e000043"} flags=[] limit=-1 skip=0 fields=nil (1.3726ms) (pid:23130)
>2013-02-01 06:31:39.571 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287"} update={"$pushAll"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"510ba79bb0b1915a5a000288", "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}, {"components"=>[{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}], "min_gears"=>1, "max_gears"=>-1}]}}, {"_id"=>"510ba79bb0b1915a5a00028a", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2"}}, {"_id"=>"510ba79bb0b1915a5a00028d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2", "gear_id"=>"510ba79bb0b1915a5a000286"}, "prereq"=>["510ba79bb0b1915a5a00028c"], "retry_rollback_op"=>"510ba79bb0b1915a5a00028c"}, {"_id"=>"510ba79bb0b1915a5a00028b", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2", "gear_id"=>"510ba79bb0b1915a5a000286", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["510ba79bb0b1915a5a00028a"]}, {"_id"=>"510ba79bb0b1915a5a000299", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"jhou@redhat.com", "gear_ref"=>"510ba79bb0b1915a5a000286", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["510ba79bb0b1915a5a00028b"]}, {"_id"=>"510ba79bb0b1915a5a00028c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2", "gear_id"=>"510ba79bb0b1915a5a000286"}, "prereq"=>["510ba79bb0b1915a5a00028b"]}, {"_id"=>"510ba79bb0b1915a5a00028e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2", "gear_id"=>"510ba79bb0b1915a5a000286"}, "prereq"=>["510ba79bb0b1915a5a00028d"]}, {"_id"=>"510ba79bb0b1915a5a00029a", "state"=>:init, "retry_count"=>0, "saved_values"=>{"additional_filesystem_gb"=>0}, "op_type"=>:set_gear_additional_filesystem_gb, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2", "gear_id"=>"510ba79bb0b1915a5a000286", "additional_filesystem_gb"=>0}, "prereq"=>["510ba79bb0b1915a5a00028b"]}, {"_id"=>"510ba79bb0b1915a5a00029b", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"510b679fb0b1917a5e000047", "type"=>"ssh-rsa", "_type"=>"SystemSshKey", "name"=>"domain-jenkins1", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA4NIFTbUcaNXs9u6+aUKnEyuHfBBJrcPU+HjVCl+sDVdyzw69lqnjMe5BiZW9cYaSAChXY1d3jKzODYOkiOYpB6BRkjRb/OEwEobcjgRMmBVo1vn4zstxowP6/4USM6TbQ8SjxUCEs1WuNVe/jsGxBTWeKMR6QHG8MCB4PIQMxIs1MvPEBvnA43bzchrAkRjdKlufCj+nJSX1AALBS55nv4PGRMk6Pum1NMAo0xQ+x3HV0RqEX1UFrJ405Q3m+qeyvHO2v1zAs5duqBcM03AsSv0RXEcwarTqfjJLMoWppwnUhLnVHdmF1aUxrXleUL9DxqaMh1r96mFMB9QYQs72iw=="}, {"_id"=>"510b679fb0b1917a5e000044", "type"=>"ssh-rsa", "name"=>"510b679fb0b1917a5e000043-default", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDIMOzBLZumZeYA+QIksSOEVwIm6CWjgwwlA+josEgKIHJiQzyAQHDS06fg7zrUdlaeS1eyO1z9QCgsvxIn/Dy8wvID56houVssTfNKVt9uc5ngIc0s/HidPpMXEG4RBJrWCiu320qCTGadyB4p9HmCy9VGhOHWJI3JEhwGJgdxD4WKySHgkG3BoVF0NxVIXyUUtNW50y32ow4u/zbW9f0mDsxWBeO/bTL8F2SpeplEmGkklSNetlZUmaV1m4QeGh5qOpGHsbMX99nAuiq5N1cc5SLBlzMiugpQvUewN6HPSa5Kt3848n5fojWagFxk2vp8s7i9mIj33qnr0gnRQrCZ"}, {"_id"=>"510b679fb0b1917a5e000044", "type"=>"ssh-rsa", "name"=>"510b679fb0b1917a5e000043-default", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDIMOzBLZumZeYA+QIksSOEVwIm6CWjgwwlA+josEgKIHJiQzyAQHDS06fg7zrUdlaeS1eyO1z9QCgsvxIn/Dy8wvID56houVssTfNKVt9uc5ngIc0s/HidPpMXEG4RBJrWCiu320qCTGadyB4p9HmCy9VGhOHWJI3JEhwGJgdxD4WKySHgkG3BoVF0NxVIXyUUtNW50y32ow4u/zbW9f0mDsxWBeO/bTL8F2SpeplEmGkklSNetlZUmaV1m4QeGh5qOpGHsbMX99nAuiq5N1cc5SLBlzMiugpQvUewN6HPSa5Kt3848n5fojWagFxk2vp8s7i9mIj33qnr0gnRQrCZ"}], "add_env_vars"=>[], "group_instance_id"=>"510ba79bb0b1915a5a0002a2", "gear_id"=>"510ba79bb0b1915a5a000286"}, "prereq"=>["510ba79bb0b1915a5a00028e"]}, {"_id"=>"510ba79bb0b1915a5a00028f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "prereq"=>["510ba79bb0b1915a5a00028a"]}, {"_id"=>"510ba79bb0b1915a5a000290", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2", "gear_id"=>"510ba79bb0b1915a5a000286", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}, "init_git_url"=>nil}, "prereq"=>["510ba79bb0b1915a5a00028f", "510ba79bb0b1915a5a00028e"]}, {"_id"=>"510ba79bb0b1915a5a00029c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:expose_port, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a2", "gear_id"=>"510ba79bb0b1915a5a000286", "comp_spec"=>{"comp"=>"php-5.3", "cart"=>"php-5.3"}}, "prereq"=>["510ba79bb0b1915a5a000290", "510ba79bb0b1915a5a00028e"]}, {"_id"=>"510ba79bb0b1915a5a000292", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3"}}, {"_id"=>"510ba79bb0b1915a5a000295", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3", "gear_id"=>"510ba79bb0b1915a5a000286"}, "prereq"=>["510ba79bb0b1915a5a000294"], "retry_rollback_op"=>"510ba79bb0b1915a5a000294"}, {"_id"=>"510ba79bb0b1915a5a000293", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3", "gear_id"=>"510ba79bb0b1915a5a000286", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["510ba79bb0b1915a5a000292"]}, {"_id"=>"510ba79bb0b1915a5a00029d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"jhou@redhat.com", "gear_ref"=>"510ba79bb0b1915a5a000286", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["510ba79bb0b1915a5a000293"]}, {"_id"=>"510ba79bb0b1915a5a000294", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3", "gear_id"=>"510ba79bb0b1915a5a000286"}, "prereq"=>["510ba79bb0b1915a5a000293"]}, {"_id"=>"510ba79bb0b1915a5a000296", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3", "gear_id"=>"510ba79bb0b1915a5a000286"}, "prereq"=>["510ba79bb0b1915a5a000295"]}, {"_id"=>"510ba79bb0b1915a5a00029e", "state"=>:init, "retry_count"=>0, "saved_values"=>{"additional_filesystem_gb"=>0}, "op_type"=>:set_gear_additional_filesystem_gb, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3", "gear_id"=>"510ba79bb0b1915a5a000286", "additional_filesystem_gb"=>0}, "prereq"=>["510ba79bb0b1915a5a000293"]}, {"_id"=>"510ba79bb0b1915a5a00029f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"510b679fb0b1917a5e000047", "type"=>"ssh-rsa", "_type"=>"SystemSshKey", "name"=>"domain-jenkins1", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA4NIFTbUcaNXs9u6+aUKnEyuHfBBJrcPU+HjVCl+sDVdyzw69lqnjMe5BiZW9cYaSAChXY1d3jKzODYOkiOYpB6BRkjRb/OEwEobcjgRMmBVo1vn4zstxowP6/4USM6TbQ8SjxUCEs1WuNVe/jsGxBTWeKMR6QHG8MCB4PIQMxIs1MvPEBvnA43bzchrAkRjdKlufCj+nJSX1AALBS55nv4PGRMk6Pum1NMAo0xQ+x3HV0RqEX1UFrJ405Q3m+qeyvHO2v1zAs5duqBcM03AsSv0RXEcwarTqfjJLMoWppwnUhLnVHdmF1aUxrXleUL9DxqaMh1r96mFMB9QYQs72iw=="}, {"_id"=>"510b679fb0b1917a5e000044", "type"=>"ssh-rsa", "name"=>"510b679fb0b1917a5e000043-default", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDIMOzBLZumZeYA+QIksSOEVwIm6CWjgwwlA+josEgKIHJiQzyAQHDS06fg7zrUdlaeS1eyO1z9QCgsvxIn/Dy8wvID56houVssTfNKVt9uc5ngIc0s/HidPpMXEG4RBJrWCiu320qCTGadyB4p9HmCy9VGhOHWJI3JEhwGJgdxD4WKySHgkG3BoVF0NxVIXyUUtNW50y32ow4u/zbW9f0mDsxWBeO/bTL8F2SpeplEmGkklSNetlZUmaV1m4QeGh5qOpGHsbMX99nAuiq5N1cc5SLBlzMiugpQvUewN6HPSa5Kt3848n5fojWagFxk2vp8s7i9mIj33qnr0gnRQrCZ"}, {"_id"=>"510b679fb0b1917a5e000044", "type"=>"ssh-rsa", "name"=>"510b679fb0b1917a5e000043-default", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDIMOzBLZumZeYA+QIksSOEVwIm6CWjgwwlA+josEgKIHJiQzyAQHDS06fg7zrUdlaeS1eyO1z9QCgsvxIn/Dy8wvID56houVssTfNKVt9uc5ngIc0s/HidPpMXEG4RBJrWCiu320qCTGadyB4p9HmCy9VGhOHWJI3JEhwGJgdxD4WKySHgkG3BoVF0NxVIXyUUtNW50y32ow4u/zbW9f0mDsxWBeO/bTL8F2SpeplEmGkklSNetlZUmaV1m4QeGh5qOpGHsbMX99nAuiq5N1cc5SLBlzMiugpQvUewN6HPSa5Kt3848n5fojWagFxk2vp8s7i9mIj33qnr0gnRQrCZ"}], "add_env_vars"=>[], "group_instance_id"=>"510ba79bb0b1915a5a0002a3", "gear_id"=>"510ba79bb0b1915a5a000286"}, "prereq"=>["510ba79bb0b1915a5a000296"]}, {"_id"=>"510ba79bb0b1915a5a000297", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3", "comp_spec"=>{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}}, "prereq"=>["510ba79bb0b1915a5a000292", "510ba79bb0b1915a5a000290"]}, {"_id"=>"510ba79bb0b1915a5a000298", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3", "gear_id"=>"510ba79bb0b1915a5a000286", "comp_spec"=>{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}, "init_git_url"=>nil}, "prereq"=>["510ba79bb0b1915a5a000297", "510ba79bb0b1915a5a000296", "510ba79bb0b1915a5a000290"]}, {"_id"=>"510ba79bb0b1915a5a0002a0", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:expose_port, "args"=>{"group_instance_id"=>"510ba79bb0b1915a5a0002a3", "gear_id"=>"510ba79bb0b1915a5a000286", "comp_spec"=>{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}}, "prereq"=>["510ba79bb0b1915a5a000298", "510ba79bb0b1915a5a000296"]}, {"_id"=>"510ba79bb0b1915a5a0002a1", "state"=>:init, "retry_count"=>0, "saved_values"=>{:connections=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"php-5.3", "comp"=>"php-5.3"}, "to_comp_inst"=>{"cart"=>"haproxy-1.4", "comp"=>"web_proxy"}, "from_connector_name"=>"publish-http-url", "to_connector_name"=>"set-proxy", "connection_type"=>"NET_TCP:httpd-proxy-info"}, {"from_comp_inst"=>{"cart"=>"php-5.3", "comp"=>"php-5.3"}, "to_comp_inst"=>{"cart"=>"haproxy-1.4", "comp"=>"web_proxy"}, "from_connector_name"=>"publish-gear-endpoint", "to_connector_name"=>"set-gear-endpoints", "connection_type"=>"NET_TCP:gear-endpoint-info"}]}, "prereq"=>["510ba79bb0b1915a5a000288", "510ba79bb0b1915a5a00028a", "510ba79bb0b1915a5a00028d", "510ba79bb0b1915a5a00028b", "510ba79bb0b1915a5a000299", "510ba79bb0b1915a5a00028c", "510ba79bb0b1915a5a00028e", "510ba79bb0b1915a5a00029a", "510ba79bb0b1915a5a00029b", "510ba79bb0b1915a5a00028f", "510ba79bb0b1915a5a000290", "510ba79bb0b1915a5a00029c", "510ba79bb0b1915a5a000292", "510ba79bb0b1915a5a000295", "510ba79bb0b1915a5a000293", "510ba79bb0b1915a5a00029d", "510ba79bb0b1915a5a000294", "510ba79bb0b1915a5a000296", "510ba79bb0b1915a5a00029e", "510ba79bb0b1915a5a00029f", "510ba79bb0b1915a5a000297", "510ba79bb0b1915a5a000298", "510ba79bb0b1915a5a0002a0"]}, {"_id"=>"510ba79bb0b1915a5a0002a4", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["510ba79bb0b1915a5a0002a1"]}]}} flags=[] (pid:23130)
>2013-02-01 06:31:39.572 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (6.8254ms) (pid:23130)
>2013-02-01 06:31:39.572 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (6.8254ms) (pid:23130)
>2013-02-01 06:31:39.611 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287"} update={"$set"=>{"pending_op_groups.0.num_gears_added"=>2, "pending_op_groups.0.updated_at"=>2013-02-01 11:31:39 UTC}} flags=[] (pid:23130)
>2013-02-01 06:31:39.612 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (25.1651ms) (pid:23130)
>2013-02-01 06:31:39.615 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.5162ms) (pid:23130)
>2013-02-01 06:31:39.615 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.3550ms) (pid:23130)
>2013-02-01 06:31:39.619 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"510b679fb0b1917a5e000043"} update={"$set"=>{"consumed_gears"=>39, "updated_at"=>2013-02-01 11:31:39 UTC}} flags=[] (pid:23130)
>2013-02-01 06:31:39.619 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5844ms) (pid:23130)
>2013-02-01 06:31:39.620 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"510b679fb0b1917a5e000043", "locked"=>true, "app_ids"=>"510ba79bb0b1915a5a000286"}, :new=>true, :update=>{"$set"=>{"locked"=>false}}} (0.5641ms) (pid:23130)
>2013-02-01 06:31:39.623 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.7204ms) (pid:23130)
>2013-02-01 06:31:39.643 [DEBUG] Execute set_group_overrides (pid:23130)
>2013-02-01 06:31:39.652 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_overrides"=>[{"components"=>[{"comp"=>"php-5.3", "cart"=>"php-5.3"}], "min_gears"=>1, "max_gears"=>-1}, {"components"=>[{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}], "min_gears"=>1, "max_gears"=>-1}], "updated_at"=>2013-02-01 11:31:39 UTC}} flags=[] (pid:23130)
>2013-02-01 06:31:39.652 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.5221ms) (pid:23130)
>2013-02-01 06:31:39.654 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000288"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:39.656 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7174ms) (pid:23130)
>2013-02-01 06:31:39.656 [DEBUG] Execute create_group_instance (pid:23130)
>2013-02-01 06:31:39.659 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$push"=>{"group_instances"=>{"_id"=>"510ba79bb0b1915a5a0002a2"}}} flags=[] (pid:23130)
>2013-02-01 06:31:39.659 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.4591ms) (pid:23130)
>2013-02-01 06:31:39.661 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028a"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:39.662 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.0390ms) (pid:23130)
>2013-02-01 06:31:39.662 [DEBUG] Execute create_group_instance (pid:23130)
>2013-02-01 06:31:39.664 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$push"=>{"group_instances"=>{"_id"=>"510ba79bb0b1915a5a0002a3"}}} flags=[] (pid:23130)
>2013-02-01 06:31:39.664 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5274ms) (pid:23130)
>2013-02-01 06:31:39.667 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000292"} update={"$set"=>{"pending_op_groups.0.pending_ops.12.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:39.667 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.6277ms) (pid:23130)
>2013-02-01 06:31:39.670 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.6556ms) (pid:23130)
>2013-02-01 06:31:39.701 [DEBUG] Execute init_gear (pid:23130)
>2013-02-01 06:31:39.708 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a2"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"510ba79bb0b1915a5a000286", "uuid"=>"510ba79bb0b1915a5a000286", "host_singletons"=>true, "app_dns"=>true, "name"=>"php2s"}}} flags=[] (pid:23130)
>2013-02-01 06:31:39.709 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.2902ms) (pid:23130)
>2013-02-01 06:31:39.718 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028b"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:39.718 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.5985ms) (pid:23130)
>2013-02-01 06:31:39.751 [DEBUG] Execute new_component (pid:23130)
>2013-02-01 06:31:39.756 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$push"=>{"component_instances"=>{"_id"=>"510ba79bb0b1915a5a0002a8", "component_properties"=>{}, "cartridge_name"=>"php-5.3", "component_name"=>"php-5.3", "group_instance_id"=>"510ba79bb0b1915a5a0002a2"}}} flags=[] (pid:23130)
>2013-02-01 06:31:39.756 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8140ms) (pid:23130)
>2013-02-01 06:31:39.758 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028f"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:39.760 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.9460ms) (pid:23130)
>2013-02-01 06:31:39.760 [DEBUG] Execute init_gear (pid:23130)
>2013-02-01 06:31:39.766 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a3"} update={"$push"=>{"group_instances.1.gears"=>{"_id"=>"510ba79bb0b1915a5a000286", "uuid"=>"510ba79bb0b1915a5a000286", "host_singletons"=>true, "app_dns"=>true, "name"=>"php2s"}}} flags=[] (pid:23130)
>2013-02-01 06:31:39.767 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.1586ms) (pid:23130)
>2013-02-01 06:31:39.777 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000293"} update={"$set"=>{"pending_op_groups.0.pending_ops.14.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:39.777 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (5.0015ms) (pid:23130)
>2013-02-01 06:31:39.782 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (3.2892ms) (pid:23130)
>2013-02-01 06:31:39.807 [DEBUG] Execute track_usage (pid:23130)
>2013-02-01 06:31:39.816 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage_records documents=[{"_id"=>"510ba79bb0b1915a5a0002aa", "event"=>"begin", "time"=>2013-02-01 11:31:39 UTC, "gear_id"=>"510ba79bb0b1915a5a000286", "usage_type"=>"GEAR_USAGE", "login"=>"jhou@redhat.com", "gear_size"=>"small", "updated_at"=>2013-02-01 11:31:39 UTC, "created_at"=>2013-02-01 11:31:39 UTC}] flags=[] (pid:23130)
>2013-02-01 06:31:39.816 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.7881ms) (pid:23130)
>2013-02-01 06:31:39.821 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage documents=[{"_id"=>"510ba79bb0b1915a5a0002ab", "login"=>"jhou@redhat.com", "gear_id"=>"510ba79bb0b1915a5a000286", "begin_time"=>2013-02-01 11:31:39 UTC, "usage_type"=>"GEAR_USAGE", "gear_size"=>"small", "updated_at"=>2013-02-01 11:31:39 UTC, "created_at"=>2013-02-01 11:31:39 UTC}] flags=[] (pid:23130)
>2013-02-01 06:31:39.822 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7610ms) (pid:23130)
>2013-02-01 06:31:39.826 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000299"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:39.826 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.0750ms) (pid:23130)
>2013-02-01 06:31:39.826 [DEBUG] Execute reserve_uid (pid:23130)
>2013-02-01 06:31:39.831 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=districts selector={"$query"=>{"available_capacity"=>{"$gte"=>0}, "gear_size"=>"small", "active_server_identities_size"=>{"$gte"=>0}}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6754ms) (pid:23130)
>2013-02-01 06:31:39.832 [DEBUG] DEBUG: rpc_get_fact: fact=active_capacity (pid:23130)
>2013-02-01 06:31:39.835 [DEBUG] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x000000066ee028> (pid:23130)
>2013-02-01 06:31:41.929 [DEBUG] Current server: domU-12-31-39-16-7E-BD active capacity: 48.0 (pid:23130)
>2013-02-01 06:31:41.929 [DEBUG] DEBUG: find_available_impl: current_server: domU-12-31-39-16-7E-BD: 48.0 (pid:23130)
>2013-02-01 06:31:41.938 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a2", "group_instances.0.gears._id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>"domU-12-31-39-16-7E-BD"}} flags=[] (pid:23130)
>2013-02-01 06:31:41.938 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (7.3249ms) (pid:23130)
>2013-02-01 06:31:42.318 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a2", "group_instances.0.gears._id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (pid:23130)
>2013-02-01 06:31:42.346 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.0366ms) (pid:23130)
>2013-02-01 06:31:42.363 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028c"} update={"$set"=>{"pending_op_groups.0.pending_ops.5.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:42.365 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8283ms) (pid:23130)
>2013-02-01 06:31:42.373 [DEBUG] Execute set_gear_additional_filesystem_gb (pid:23130)
>2013-02-01 06:31:42.379 [DEBUG] Execute track_usage (pid:23130)
>2013-02-01 06:31:42.512 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage_records documents=[{"_id"=>"510ba79eb0b1915a5a0002ac", "event"=>"begin", "time"=>2013-02-01 11:31:42 UTC, "gear_id"=>"510ba79bb0b1915a5a000286", "usage_type"=>"GEAR_USAGE", "login"=>"jhou@redhat.com", "gear_size"=>"small", "updated_at"=>2013-02-01 11:31:42 UTC, "created_at"=>2013-02-01 11:31:42 UTC}] flags=[] (pid:23130)
>2013-02-01 06:31:42.514 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.2757ms) (pid:23130)
>2013-02-01 06:31:42.563 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage documents=[{"_id"=>"510ba79eb0b1915a5a0002ad", "login"=>"jhou@redhat.com", "gear_id"=>"510ba79bb0b1915a5a000286", "begin_time"=>2013-02-01 11:31:42 UTC, "usage_type"=>"GEAR_USAGE", "gear_size"=>"small", "updated_at"=>2013-02-01 11:31:42 UTC, "created_at"=>2013-02-01 11:31:42 UTC}] flags=[] (pid:23130)
>2013-02-01 06:31:42.599 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.1216ms) (pid:23130)
>2013-02-01 06:31:42.125 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00029d"} update={"$set"=>{"pending_op_groups.0.pending_ops.15.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:42.125 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (64.3790ms) (pid:23130)
>2013-02-01 06:31:42.126 [DEBUG] Execute reserve_uid (pid:23130)
>2013-02-01 06:31:42.128 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=districts selector={"$query"=>{"available_capacity"=>{"$gte"=>0}, "gear_size"=>"small", "active_server_identities_size"=>{"$gte"=>0}}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6404ms) (pid:23130)
>2013-02-01 06:31:42.128 [DEBUG] DEBUG: rpc_get_fact: fact=active_capacity (pid:23130)
>2013-02-01 06:31:42.130 [DEBUG] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x000000061436f0> (pid:23130)
>2013-02-01 06:31:44.321 [DEBUG] Current server: domU-12-31-39-16-7E-BD active capacity: 48.0 (pid:23130)
>2013-02-01 06:31:44.322 [DEBUG] DEBUG: find_available_impl: current_server: domU-12-31-39-16-7E-BD: 48.0 (pid:23130)
>2013-02-01 06:31:44.325 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a3", "group_instances.1.gears._id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_instances.1.gears.0.server_identity"=>"domU-12-31-39-16-7E-BD"}} flags=[] (pid:23130)
>2013-02-01 06:31:44.325 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.1916ms) (pid:23130)
>2013-02-01 06:31:44.400 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a3", "group_instances.1.gears._id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_instances.1.gears.0.uid"=>nil}} flags=[] (pid:23130)
>2013-02-01 06:31:44.400 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8886ms) (pid:23130)
>2013-02-01 06:31:44.408 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000294"} update={"$set"=>{"pending_op_groups.0.pending_ops.16.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:44.410 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.1583ms) (pid:23130)
>2013-02-01 06:31:44.413 [DEBUG] Execute set_gear_additional_filesystem_gb (pid:23130)
>2013-02-01 06:31:44.768 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"addtl-fs-gb", :gear=>"510ba79bb0b1915a5a000286", :job=>{:cartridge=>"openshift-origin-node", :action=>"set-quota", :args=>{"--uuid"=>"510ba79bb0b1915a5a000286", "--blocks"=>0}}, :result_stdout=>"", :result_stderr=>"Error: quota: user 510ba79bb0b1915a5a000286 does not exist.\n executing command quota -w 510ba79bb0b1915a5a000286 | awk '/^.*\\/dev/ {print $1\":\"$2\":\"$3\":\"$4\":\"$5\":\"$6\":\"$7}'; exit ${PIPESTATUS[0]}", :result_exit_code=>-1}, {:tag=>"addtl-fs-gb", :gear=>"510ba79bb0b1915a5a000286", :job=>{:cartridge=>"openshift-origin-node", :action=>"set-quota", :args=>{"--uuid"=>"510ba79bb0b1915a5a000286", "--blocks"=>0}}, :result_stdout=>"", :result_stderr=>"Error: quota: user 510ba79bb0b1915a5a000286 does not exist.\n executing command quota -w 510ba79bb0b1915a5a000286 | awk '/^.*\\/dev/ {print $1\":\"$2\":\"$3\":\"$4\":\"$5\":\"$6\":\"$7}'; exit ${PIPESTATUS[0]}", :result_exit_code=>-1}], exitcode: 0, from: domU-12-31-39-16-7E-BD (pid:23130)
>2013-02-01 06:31:44.776 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00029a"} update={"$set"=>{"pending_op_groups.0.pending_ops.7.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:44.776 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.7251ms) (pid:23130)
>2013-02-01 06:31:44.781 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00029e"} update={"$set"=>{"pending_op_groups.0.pending_ops.18.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:44.782 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.4900ms) (pid:23130)
>2013-02-01 06:31:44.794 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (2.4192ms) (pid:23130)
>2013-02-01 06:31:44.875 [DEBUG] Execute create_gear (pid:23130)
>2013-02-01 06:31:44.880 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000006218198> (pid:23130)
>2013-02-01 06:31:44.882 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"510ba79bb0b1915a5a000286", "--with-app-name"=>"php2s", "--with-container-uuid"=>"510ba79bb0b1915a5a000286", "--with-container-name"=>"php2s", "--with-namespace"=>"281t1"}}, domU-12-31-39-16-7E-BD, {'identity' => domU-12-31-39-16-7E-BD}) (pid:23130)
>2013-02-01 06:31:47.822 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x0000000613e8a8 @agent="openshift", @action="cartridge_do", @results={:sender=>"domU-12-31-39-16-7E-BD", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (pid:23130)
>2013-02-01 06:31:47.840 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287"} update={"$inc"=>{"pending_op_groups.0.num_gears_created"=>1}} flags=[] (pid:23130)
>2013-02-01 06:31:47.841 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (9.3560ms) (pid:23130)
>2013-02-01 06:31:47.843 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028d"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:completed}} flags=[] (pid:23130)
>2013-02-01 06:31:47.843 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.4827ms) (pid:23130)
>2013-02-01 06:31:47.844 [DEBUG] Execute create_gear (pid:23130)
>2013-02-01 06:31:47.850 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x0000000653eac0> (pid:23130)
>2013-02-01 06:31:47.851 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"510ba79bb0b1915a5a000286", "--with-app-name"=>"php2s", "--with-container-uuid"=>"510ba79bb0b1915a5a000286", "--with-container-name"=>"php2s", "--with-namespace"=>"281t1"}}, domU-12-31-39-16-7E-BD, {'identity' => domU-12-31-39-16-7E-BD}) (pid:23130)
>2013-02-01 06:31:48.154 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000006478398 @agent="openshift", @action="cartridge_do", @results={:sender=>"domU-12-31-39-16-7E-BD", :statuscode=>1, :statusmsg=>"cartridge_do_action failed 129. Output ERROR: unable to create user account(9): useradd -u 549 -d /var/lib/openshift/510ba79bb0b1915a5a000286/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 510ba79bb0b1915a5a000286 stdout: useradd: user '510ba79bb0b1915a5a000286' already exists\n stderr: useradd: user '510ba79bb0b1915a5a000286' already exists\n", :data=>{:time=>nil, :output=>"ERROR: unable to create user account(9): useradd -u 549 -d /var/lib/openshift/510ba79bb0b1915a5a000286/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 510ba79bb0b1915a5a000286 stdout: useradd: user '510ba79bb0b1915a5a000286' already exists\n stderr: useradd: user '510ba79bb0b1915a5a000286' already exists\n", :exitcode=>129}}>] (pid:23130)
>2013-02-01 06:31:48.157 [DEBUG] DEBUG: server results: ERROR: unable to create user account(9): useradd -u 549 -d /var/lib/openshift/510ba79bb0b1915a5a000286/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 510ba79bb0b1915a5a000286 stdout: useradd: user '510ba79bb0b1915a5a000286' already exists (pid:23130)
>2013-02-01 06:31:48.157 [DEBUG] DEBUG: server results:  stderr: useradd: user '510ba79bb0b1915a5a000286' already exists (pid:23130)
>2013-02-01 06:31:48.157 [ERROR] Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:23130)
>2013-02-01 06:31:48.158 [ERROR] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.4.3/lib/openshift/mcollective_application_container_proxy.rb:2551:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.4.3/lib/openshift/mcollective_application_container_proxy.rb:412:in `block in create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.4.3/lib/openshift/mcollective_application_container_proxy.rb:401:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.4.3/lib/openshift/mcollective_application_container_proxy.rb:401:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/gear.rb:79:in `create_gear'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/pending_app_op_group.rb:170:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/pending_app_op_group.rb:127:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/pending_app_op_group.rb:127:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:965:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:337:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:1007:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:335:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:96:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/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:458:in `_run__2001432861082981703__process_action__2581996648048741962__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__707552404608612864__call__3164863534600212261__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:23130)
>2013-02-01 06:31:48.158 [ERROR] Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:23130)
>2013-02-01 06:31:48.158 [ERROR] Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:23130)
>2013-02-01 06:31:48.160 [DEBUG] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.4.3/lib/openshift/mcollective_application_container_proxy.rb:2551:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.4.3/lib/openshift/mcollective_application_container_proxy.rb:412:in `block in create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.4.3/lib/openshift/mcollective_application_container_proxy.rb:401:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.4.3/lib/openshift/mcollective_application_container_proxy.rb:401:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/gear.rb:79:in `create_gear'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/pending_app_op_group.rb:170:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/pending_app_op_group.rb:127:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/pending_app_op_group.rb:127:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:965:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:337:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:1007:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:335:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/app/models/application.rb:96:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.4.3/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:458:in `_run__2001432861082981703__process_action__2581996648048741962__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__707552404608612864__call__3164863534600212261__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:23130)
>2013-02-01 06:31:48.176 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (13.4704ms) (pid:23130)
>2013-02-01 06:31:48.203 [DEBUG] Rollback set_group_overrides (pid:23130)
>2013-02-01 06:31:48.222 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_overrides"=>[], "updated_at"=>2013-02-01 11:31:48 UTC}} flags=[] (pid:23130)
>2013-02-01 06:31:48.222 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (5.3496ms) (pid:23130)
>2013-02-01 06:31:48.228 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000288"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:48.229 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.3993ms) (pid:23130)
>2013-02-01 06:31:48.229 [DEBUG] Rollback create_gear (pid:23130)
>2013-02-01 06:31:48.238 [DEBUG] DEBUG: Sending to Nurture:application: app_uuid='510ba79bb0b1915a5a000286' action='deconfigure' (pid:23130)
>2013-02-01 06:31:48.334 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x000000062c08c0> (pid:23130)
>2013-02-01 06:31:48.344 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"510ba79bb0b1915a5a000286", "--with-app-name"=>"php2s", "--with-container-uuid"=>"510ba79bb0b1915a5a000286", "--with-container-name"=>"php2s", "--with-namespace"=>"281t1"}}, domU-12-31-39-16-7E-BD, {'identity' => domU-12-31-39-16-7E-BD}) (pid:23130)
>2013-02-01 06:31:54.719 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000041e2ba8 @agent="openshift", @action="cartridge_do", @results={:sender=>"domU-12-31-39-16-7E-BD", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (pid:23130)
>2013-02-01 06:31:54.736 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287"} update={"$inc"=>{"pending_op_groups.0.num_gears_rolled_back"=>1}} flags=[] (pid:23130)
>2013-02-01 06:31:54.736 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (7.7124ms) (pid:23130)
>2013-02-01 06:31:54.775 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028d"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:54.775 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (37.5392ms) (pid:23130)
>2013-02-01 06:31:54.775 [DEBUG] Rollback track_usage (pid:23130)
>2013-02-01 06:31:54.777 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage_records selector={"$query"=>{"login"=>"jhou@redhat.com", "gear_id"=>"510ba79bb0b1915a5a000286", "event"=>"begin", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.7951ms) (pid:23130)
>2013-02-01 06:31:54.782 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=usage_records selector={"_id"=>"510ba79bb0b1915a5a0002aa"} flags=[:remove_first] (pid:23130)
>2013-02-01 06:31:54.782 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.4254ms) (pid:23130)
>2013-02-01 06:31:54.783 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage selector={"$query"=>{"login"=>"jhou@redhat.com", "gear_id"=>"510ba79bb0b1915a5a000286", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{"begin_time"=>-1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5338ms) (pid:23130)
>2013-02-01 06:31:54.786 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=usage selector={"_id"=>"510ba79eb0b1915a5a0002ad"} flags=[:remove_first] (pid:23130)
>2013-02-01 06:31:54.787 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5236ms) (pid:23130)
>2013-02-01 06:31:54.789 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000299"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:54.789 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.3802ms) (pid:23130)
>2013-02-01 06:31:54.789 [DEBUG] Rollback set_gear_additional_filesystem_gb (pid:23130)
>2013-02-01 06:31:54.791 [DEBUG] Rollback new_component (pid:23130)
>2013-02-01 06:31:54.796 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$pull"=>{"component_instances"=>{"_id"=>"510ba79bb0b1915a5a0002a8"}}} flags=[] (pid:23130)
>2013-02-01 06:31:54.797 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6564ms) (pid:23130)
>2013-02-01 06:31:54.800 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028f"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:54.801 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.2755ms) (pid:23130)
>2013-02-01 06:31:54.801 [DEBUG] Rollback track_usage (pid:23130)
>2013-02-01 06:31:54.803 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage_records selector={"$query"=>{"login"=>"jhou@redhat.com", "gear_id"=>"510ba79bb0b1915a5a000286", "event"=>"begin", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.8523ms) (pid:23130)
>2013-02-01 06:31:54.806 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=usage_records selector={"_id"=>"510ba79eb0b1915a5a0002ac"} flags=[:remove_first] (pid:23130)
>2013-02-01 06:31:54.808 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5496ms) (pid:23130)
>2013-02-01 06:31:54.811 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage selector={"$query"=>{"login"=>"jhou@redhat.com", "gear_id"=>"510ba79bb0b1915a5a000286", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{"begin_time"=>-1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (2.3568ms) (pid:23130)
>2013-02-01 06:31:54.812 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=usage selector={"_id"=>"510ba79bb0b1915a5a0002ab"} flags=[:remove_first] (pid:23130)
>2013-02-01 06:31:54.813 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6759ms) (pid:23130)
>2013-02-01 06:31:54.817 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00029d"} update={"$set"=>{"pending_op_groups.0.pending_ops.15.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:54.817 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.7578ms) (pid:23130)
>2013-02-01 06:31:54.817 [DEBUG] Rollback reserve_uid (pid:23130)
>2013-02-01 06:31:54.930 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a3", "group_instances.1.gears._id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_instances.1.gears.0.server_identity"=>nil}} flags=[] (pid:23130)
>2013-02-01 06:31:54.933 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.1230ms) (pid:23130)
>2013-02-01 06:31:54.936 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a3", "group_instances.1.gears._id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_instances.1.gears.0.uid"=>nil}} flags=[] (pid:23130)
>2013-02-01 06:31:54.936 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.7209ms) (pid:23130)
>2013-02-01 06:31:54.941 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000294"} update={"$set"=>{"pending_op_groups.0.pending_ops.16.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:54.941 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.6190ms) (pid:23130)
>2013-02-01 06:31:54.941 [DEBUG] Rollback set_gear_additional_filesystem_gb (pid:23130)
>2013-02-01 06:31:55.345 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"addtl-fs-gb", :gear=>"510ba79bb0b1915a5a000286", :job=>{:cartridge=>"openshift-origin-node", :action=>"set-quota", :args=>{"--uuid"=>"510ba79bb0b1915a5a000286", "--blocks"=>0}}, :result_stdout=>"", :result_stderr=>"Error: quota: user 510ba79bb0b1915a5a000286 does not exist.\n executing command quota -w 510ba79bb0b1915a5a000286 | awk '/^.*\\/dev/ {print $1\":\"$2\":\"$3\":\"$4\":\"$5\":\"$6\":\"$7}'; exit ${PIPESTATUS[0]}", :result_exit_code=>-1}, {:tag=>"addtl-fs-gb", :gear=>"510ba79bb0b1915a5a000286", :job=>{:cartridge=>"openshift-origin-node", :action=>"set-quota", :args=>{"--uuid"=>"510ba79bb0b1915a5a000286", "--blocks"=>0}}, :result_stdout=>"", :result_stderr=>"Error: quota: user 510ba79bb0b1915a5a000286 does not exist.\n executing command quota -w 510ba79bb0b1915a5a000286 | awk '/^.*\\/dev/ {print $1\":\"$2\":\"$3\":\"$4\":\"$5\":\"$6\":\"$7}'; exit ${PIPESTATUS[0]}", :result_exit_code=>-1}], exitcode: 0, from: domU-12-31-39-16-7E-BD (pid:23130)
>2013-02-01 06:31:55.366 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"updated_at"=>2013-02-01 11:31:55 UTC, "pending_op_groups.0.pending_ops.7.state"=>:rolledback, "pending_op_groups.0.pending_ops.18.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:55.366 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (4.5860ms) (pid:23130)
>2013-02-01 06:31:55.373 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (4.8766ms) (pid:23130)
>2013-02-01 06:31:55.387 [DEBUG] Rollback reserve_uid (pid:23130)
>2013-02-01 06:31:55.484 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a2", "group_instances.0.gears._id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>nil}} flags=[] (pid:23130)
>2013-02-01 06:31:55.484 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9544ms) (pid:23130)
>2013-02-01 06:31:55.485 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a2", "group_instances.0.gears._id"=>"510ba79bb0b1915a5a000286"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (pid:23130)
>2013-02-01 06:31:55.485 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4358ms) (pid:23130)
>2013-02-01 06:31:55.489 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028c"} update={"$set"=>{"pending_op_groups.0.pending_ops.5.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:55.490 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.2294ms) (pid:23130)
>2013-02-01 06:31:55.490 [DEBUG] Rollback init_gear (pid:23130)
>2013-02-01 06:31:55.495 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a3"} update={"$pull"=>{"group_instances.1.gears"=>{"_id"=>"510ba79bb0b1915a5a000286"}}} flags=[] (pid:23130)
>2013-02-01 06:31:55.495 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.6778ms) (pid:23130)
>2013-02-01 06:31:55.506 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000293"} update={"$set"=>{"pending_op_groups.0.pending_ops.14.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:55.506 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (4.2253ms) (pid:23130)
>2013-02-01 06:31:55.509 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.6980ms) (pid:23130)
>2013-02-01 06:31:55.518 [DEBUG] Rollback init_gear (pid:23130)
>2013-02-01 06:31:55.521 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "group_instances._id"=>"510ba79bb0b1915a5a0002a2"} update={"$pull"=>{"group_instances.0.gears"=>{"_id"=>"510ba79bb0b1915a5a000286"}}} flags=[] (pid:23130)
>2013-02-01 06:31:55.521 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.0583ms) (pid:23130)
>2013-02-01 06:31:55.530 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028b"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:55.530 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9205ms) (pid:23130)
>2013-02-01 06:31:55.530 [DEBUG] Rollback create_group_instance (pid:23130)
>2013-02-01 06:31:55.532 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$pull"=>{"group_instances"=>{"_id"=>"510ba79bb0b1915a5a0002a3"}}} flags=[] (pid:23130)
>2013-02-01 06:31:55.534 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4647ms) (pid:23130)
>2013-02-01 06:31:55.535 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a000292"} update={"$set"=>{"pending_op_groups.0.pending_ops.12.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:55.536 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7205ms) (pid:23130)
>2013-02-01 06:31:55.541 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"510ba79bb0b1915a5a000286"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (4.3261ms) (pid:23130)
>2013-02-01 06:31:55.546 [DEBUG] Rollback create_group_instance (pid:23130)
>2013-02-01 06:31:55.549 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$pull"=>{"group_instances"=>{"_id"=>"510ba79bb0b1915a5a0002a2"}}} flags=[] (pid:23130)
>2013-02-01 06:31:55.551 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.5421ms) (pid:23130)
>2013-02-01 06:31:55.553 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286", "pending_op_groups._id"=>"510ba79bb0b1915a5a000287", "pending_op_groups.0.pending_ops._id"=>"510ba79bb0b1915a5a00028a"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:rolledback}} flags=[] (pid:23130)
>2013-02-01 06:31:55.554 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.4923ms) (pid:23130)
>2013-02-01 06:31:55.582 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"510b679fb0b1917a5e000043"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.2095ms) (pid:23130)
>2013-02-01 06:31:55.584 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"510b679fb0b1917a5e000043", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000006292510>"=>1359718315}], "app_ids"=>"510ba79bb0b1915a5a000286"}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1359718915}}} (0.8969ms) (pid:23130)
>2013-02-01 06:31:55.586 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"510b679fb0b1917a5e000043"} flags=[] limit=-1 skip=0 fields=nil (0.4027ms) (pid:23130)
>2013-02-01 06:31:55.591 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (1.3552ms) (pid:23130)
>2013-02-01 06:31:55.594 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"510b679fb0b1917a5e000043"} flags=[:slave_ok] limit=0 skip=0 fields=nil (2.0823ms) (pid:23130)
>2013-02-01 06:31:55.601 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"510b679fb0b1917a5e000043"} update={"$set"=>{"consumed_gears"=>37, "updated_at"=>2013-02-01 11:31:55 UTC}} flags=[] (pid:23130)
>2013-02-01 06:31:55.601 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6740ms) (pid:23130)
>2013-02-01 06:31:55.603 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"510b679fb0b1917a5e000043", "locked"=>true, "app_ids"=>"510ba79bb0b1915a5a000286"}, :new=>true, :update=>{"$set"=>{"locked"=>false}}} (0.5059ms) (pid:23130)
>2013-02-01 06:31:55.607 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} update={"$pull"=>{"pending_op_groups"=>{"_id"=>"510ba79bb0b1915a5a000287"}}} flags=[] (pid:23130)
>2013-02-01 06:31:55.607 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.6946ms) (pid:23130)
>2013-02-01 06:31:55.608 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"510b679fb0b1917a5e000043", "locked"=>false, "app_ids"=>"510ba79bb0b1915a5a000286"}, :new=>true, :update=>{"$pull"=>{:app_ids=>"510ba79bb0b1915a5a000286"}}} (0.5383ms) (pid:23130)
>2013-02-01 06:31:55.609 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=applications selector={"_id"=>"510ba79bb0b1915a5a000286"} flags=[:remove_first] (pid:23130)
>2013-02-01 06:31:55.611 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4272ms) (pid:23130)
>2013-02-01 06:31:55.613 [DEBUG] [REQ_ID=91b7d010492345c8845ac2ef1fc019e8] ACTION=ADD_APPLICATION Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:23130)
>2013-02-01 06:31:55.616 [INFO ] Completed 422 Unprocessable Entity in 16361ms (Views: 0.7ms) (pid:23130)
>
>

Actions: View

Attachments on bug 906751: 691550 | 691551