Attachment 668311 Details for Bug 889932 – development.log

development.log

file_889932.txt (text/plain), 75.50 KB, created by Rony Gong 🔥 on 2012-12-24 04:31:22 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rony Gong 🔥

Created: 2012-12-24 04:31:22 UTC

Size: 75.50 KB

patch

obsolete

>2012-12-23 21:45:27.637 [INFO ] Started GET "/broker/rest/api" for 203.114.244.88 at 2012-12-23 21:45:27 -0500 (pid:2603)
>2012-12-23 21:45:27.639 [INFO ] Processing by BaseController#show as JSON (pid:2603)
>2012-12-23 21:45:27.639 [DEBUG] application/json (pid:2603)
>2012-12-23 21:45:27.648 [INFO ] Completed 200 OK in 8ms (Views: 3.6ms) (pid:2603)
>2012-12-23 21:45:28.801 [INFO ] Started GET "/broker/rest/domains" for 203.114.244.88 at 2012-12-23 21:45:28 -0500 (pid:2603)
>2012-12-23 21:45:28.808 [INFO ] Processing by DomainsController#index as JSON (pid:2603)
>2012-12-23 21:45:28.819 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"qgong@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (9.0303ms) (pid:2603)
>2012-12-23 21:45:28.820 [DEBUG] application/json (pid:2603)
>2012-12-23 21:45:28.820 [DEBUG] Getting domains for user qgong@redhat.com (pid:2603)
>2012-12-23 21:45:28.820 [DEBUG] #<Mongoid::Criteria:0x00000006b3ea60> (pid:2603)
>2012-12-23 21:45:28.822 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.6516ms) (pid:2603)
>2012-12-23 21:45:28.824 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"50d7b7929967662b0a000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.4692ms) (pid:2603)
>2012-12-23 21:45:28.827 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (2.3897ms) (pid:2603)
>2012-12-23 21:45:28.828 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.4444ms) (pid:2603)
>2012-12-23 21:45:28.835 [DEBUG] [REQ_ID=0cd72574a7184048958a0f59cebc9e52] ACTION=LIST_DOMAINS (pid:2603)
>2012-12-23 21:45:28.845 [INFO ] Completed 200 OK in 37ms (Views: 8.8ms) (pid:2603)
>2012-12-23 21:45:29.965 [INFO ] Started GET "/broker/rest/cartridges" for 203.114.244.88 at 2012-12-23 21:45:29 -0500 (pid:2603)
>2012-12-23 21:45:29.968 [INFO ] Processing by CartridgesController#index as JSON (pid:2603)
>2012-12-23 21:45:29.968 [DEBUG] application/json (pid:2603)
>2012-12-23 21:45:29.975 [DEBUG] [REQ_ID=] ACTION=LIST_CARTRIDGES List all cartridges (pid:2603)
>2012-12-23 21:45:29.983 [INFO ] Completed 200 OK in 15ms (Views: 7.3ms) (pid:2603)
>2012-12-23 21:45:31.482 [INFO ] Started POST "/broker/rest/domains/qgong15/applications" for 203.114.244.88 at 2012-12-23 21:45:31 -0500 (pid:2603)
>2012-12-23 21:45:31.483 [INFO ] Processing by ApplicationsController#create as JSON (pid:2603)
>2012-12-23 21:45:31.484 [INFO ] Parameters: {"cartridge"=>"jenkins-1.4", "name"=>"jenkins", "domain_id"=>"qgong15"} (pid:2603)
>2012-12-23 21:45:31.487 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"qgong@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.9539ms) (pid:2603)
>2012-12-23 21:45:31.488 [DEBUG] application/json (pid:2603)
>2012-12-23 21:45:31.489 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"50d7b7929967662b0a000001", "namespace"=>"qgong15"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6106ms) (pid:2603)
>2012-12-23 21:45:31.490 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"50d7b7929967662b0a000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.3357ms) (pid:2603)
>2012-12-23 21:45:31.491 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.3400ms) (pid:2603)
>2012-12-23 21:45:31.492 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.2575ms) (pid:2603)
>2012-12-23 21:45:31.493 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"applications", :query=>{"domain_id"=>"50d7b7f59967662b0a000004", "name"=>"jenkins"}} (0.4184ms) (pid:2603)
>2012-12-23 21:45:31.493 [DEBUG] Checking to see if user limit for number of apps has been reached (pid:2603)
>2012-12-23 21:45:31.502 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=applications documents=[{"_id"=>"50d7c1cb9967662b0a00013d", "domain_requires"=>[], "group_overrides"=>[], "user_ids"=>[], "aliases"=>[], "component_start_order"=>[], "component_stop_order"=>[], "component_configure_order"=>[], "default_gear_size"=>nil, "init_git_url"=>nil, "name"=>"jenkins", "scalable"=>nil, "domain_id"=>"50d7b7f59967662b0a000004", "updated_at"=>2012-12-24 02:45:31 UTC, "created_at"=>2012-12-24 02:45:31 UTC}] flags=[] (0.5877ms) (pid:2603)
>2012-12-23 21:45:31.508 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7b7929967662b0a000001", "app_ids"=>{"$nin"=>["50d7c1cb9967662b0a00013d"]}}, :new=>true, :update=>{"$push"=>{:app_ids=>"50d7c1cb9967662b0a00013d"}}} (0.8173ms) (pid:2603)
>2012-12-23 21:45:31.511 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$push"=>{"pending_op_groups"=>{"_id"=>"50d7c1cb9967662b0a00013e", "num_gears_added"=>0, "num_gears_removed"=>0, "num_gears_created"=>0, "num_gears_destroyed"=>0, "num_gears_rolled_back"=>0, "op_type"=>:add_features, "args"=>{"features"=>["jenkins-1.4"], "group_overrides"=>[], "init_git_url"=>nil}, "parent_op_id"=>nil, "updated_at"=>2012-12-24 02:45:31 UTC, "created_at"=>2012-12-24 02:45:31 UTC}}} flags=[] (0.4539ms) (pid:2603)
>2012-12-23 21:45:31.513 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.9835ms) (pid:2603)
>2012-12-23 21:45:31.528 [DEBUG]  (pid:2603)
>2012-12-23 21:45:31.529 [DEBUG] ----------------------------------- (pid:2603)
>2012-12-23 21:45:31.529 [DEBUG] features: ["jenkins-1.4"], group_overrides: [{"components"=>[{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}], "max_gears"=>1}] (pid:2603)
>2012-12-23 21:45:31.530 [DEBUG] final group instances: [{:component_instances=>[{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}], :scale=>{:min=>1, :max=>1, :gear_size=>nil, :additional_filesystem_gb=>0}, :_id=>"50d7c1cb9967662b0a00013f"}] (pid:2603)
>2012-12-23 21:45:31.530 [DEBUG] changes: [{:from=>nil, :to=>"50d7c1cb9967662b0a00013f", :added=>[{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}], :removed=>[], :from_scale=>{:min=>1, :max=>-1, :current=>0, :additional_filesystem_gb=>0, :gear_size=>"small"}, :to_scale=>{:min=>1, :max=>1, :gear_size=>nil, :additional_filesystem_gb=>0}}], moves: [] (pid:2603)
>2012-12-23 21:45:31.530 [DEBUG] ----------------------------------- (pid:2603)
>2012-12-23 21:45:31.530 [DEBUG]  (pid:2603)
>2012-12-23 21:45:31.678 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"50d7b7f59967662b0a000004"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6831ms) (pid:2603)
>2012-12-23 21:45:31.680 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"50d7b7929967662b0a000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.4425ms) (pid:2603)
>2012-12-23 21:45:31.681 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.3953ms) (pid:2603)
>2012-12-23 21:45:31.682 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.2584ms) (pid:2603)
>2012-12-23 21:45:31.702 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"50d7b7929967662b0a000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.7133ms) (pid:2603)
>2012-12-23 21:45:31.706 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7b7929967662b0a000001", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000005746a08>"=>1356317131}], "app_ids"=>{"$in"=>["50d7c1cb9967662b0a00013d"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1356317731}}} (0.7613ms) (pid:2603)
>2012-12-23 21:45:31.707 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.7160ms) (pid:2603)
>2012-12-23 21:45:31.731 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e"} update={"$pushAll"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"50d7c1cb9967662b0a000140", "state"=>:init, "retry_count"=>0, "saved_values"=>{"current_group_overrides"=>[]}, "op_type"=>:set_group_overrides, "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}], "max_gears"=>1}]}}, {"_id"=>"50d7c1cb9967662b0a000142", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_size"=>nil, "additional_filesystem_gb"=>0}}, {"_id"=>"50d7c1cb9967662b0a000145", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d"}, "prereq"=>["50d7c1cb9967662b0a000144"], "retry_rollback_op"=>"50d7c1cb9967662b0a000144"}, {"_id"=>"50d7c1cb9967662b0a000143", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["50d7c1cb9967662b0a000142"]}, {"_id"=>"50d7c1cb9967662b0a000149", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"qgong@redhat.com", "gear_ref"=>"50d7c1cb9967662b0a00013d", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>nil}, "prereq"=>["50d7c1cb9967662b0a000143"]}, {"_id"=>"50d7c1cb9967662b0a000144", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d"}, "prereq"=>["50d7c1cb9967662b0a000143"]}, {"_id"=>"50d7c1cb9967662b0a000146", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d"}, "prereq"=>["50d7c1cb9967662b0a000145"]}, {"_id"=>"50d7c1cb9967662b0a00014a", "state"=>:init, "retry_count"=>0, "saved_values"=>{"additional_filesystem_gb"=>0}, "op_type"=>:set_gear_additional_filesystem_gb, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d", "additional_filesystem_gb"=>0}, "prereq"=>["50d7c1cb9967662b0a000143"]}, {"_id"=>"50d7c1cb9967662b0a00014b", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"50d7b7d79967662b0a000003", "type"=>"ssh-rsa", "name"=>"default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAlDd4u1sjlJsKCkglZmU5mWQQNptGh6Pl8tGyv0d4Uh6lB0mHm2Nj9ERXM4hSNkXj4XzSdGJc94n/SGx2Zw6iroAd2S7m88xAkYHCeVarMaMYy5dSpixAzW1ibHtyL1PSrvThJOkUc5vKEFEKa1hLvmu9AH3O0TAdC36mMAMJFByPuIAT/koTXvGTQLZxQicGdcAKY3LgIwu8zs9Q/ffYHbaJLaoBR0AoDgUwg7mo3k4DD5aZhtiahnFRT1dSPmPklpFWOu/Qe0bNT1PRzGNybDKgjwHR9HDeNluy0DYYhE9adswbkicWLaOzYGFFaZYQpz1sgvVtX1Tt7Oo+Y/AJgQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d"}, "prereq"=>["50d7c1cb9967662b0a000146"]}, {"_id"=>"50d7c1cb9967662b0a000147", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "comp_spec"=>{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}}, "prereq"=>["50d7c1cb9967662b0a000142"]}, {"_id"=>"50d7c1cb9967662b0a000148", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d", "comp_spec"=>{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}, "init_git_url"=>nil}, "prereq"=>["50d7c1cb9967662b0a000147", "50d7c1cb9967662b0a000146"]}, {"_id"=>"50d7c1cb9967662b0a00014c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:expose_port, "args"=>{"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d", "comp_spec"=>{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}}, "prereq"=>["50d7c1cb9967662b0a000148", "50d7c1cb9967662b0a000146"]}, {"_id"=>"50d7c1cb9967662b0a00014d", "state"=>:init, "retry_count"=>0, "saved_values"=>{:current_connections=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["50d7c1cb9967662b0a000140", "50d7c1cb9967662b0a000142", "50d7c1cb9967662b0a000145", "50d7c1cb9967662b0a000143", "50d7c1cb9967662b0a000149", "50d7c1cb9967662b0a000144", "50d7c1cb9967662b0a000146", "50d7c1cb9967662b0a00014a", "50d7c1cb9967662b0a00014b", "50d7c1cb9967662b0a000147", "50d7c1cb9967662b0a000148", "50d7c1cb9967662b0a00014c"]}, {"_id"=>"50d7c1cb9967662b0a00014e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["50d7c1cb9967662b0a00014d"]}]}} flags=[] (1.8938ms) (pid:2603)
>2012-12-23 21:45:31.776 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e"} update={"$set"=>{"pending_op_groups.0.num_gears_added"=>1, "pending_op_groups.0.updated_at"=>2012-12-24 02:45:31 UTC}} flags=[] (0.6316ms) (pid:2603)
>2012-12-23 21:45:31.779 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.5398ms) (pid:2603)
>2012-12-23 21:45:31.780 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.2711ms) (pid:2603)
>2012-12-23 21:45:31.783 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"50d7b7929967662b0a000001"} update={"$set"=>{"consumed_gears"=>4, "updated_at"=>2012-12-24 02:45:31 UTC}} flags=[] (0.4029ms) (pid:2603)
>2012-12-23 21:45:31.785 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7b7929967662b0a000001", "locked"=>true, "app_ids"=>{"$in"=>["50d7c1cb9967662b0a00013d"]}}, :new=>false, :update=>{"$set"=>{"locked"=>false}}} (0.7880ms) (pid:2603)
>2012-12-23 21:45:31.785 [DEBUG] ----------------------------------- (pid:2603)
>2012-12-23 21:45:31.786 [DEBUG] #<PendingAppOpGroup _id: 50d7c1cb9967662b0a00013e, _type: nil, created_at: 2012-12-24 02:45:31 UTC, updated_at: 2012-12-24 02:45:31 UTC, op_type: :add_features, args: {"features"=>["jenkins-1.4"], "group_overrides"=>[], "init_git_url"=>nil}, parent_op_id: nil, num_gears_added: 1, num_gears_removed: 0, num_gears_created: 0, num_gears_destroyed: 0, num_gears_rolled_back: 0> (pid:2603)
>2012-12-23 21:45:31.786 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000140, _type: nil, op_type: :set_group_overrides, state: :init, args: {"group_overrides"=>[{"components"=>[{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}], "max_gears"=>1}]}, prereq: nil, retry_count: 0, retry_rollback_op: nil, saved_values: {"current_group_overrides"=>[]}> (pid:2603)
>2012-12-23 21:45:31.786 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000142, _type: nil, op_type: :create_group_instance, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_size"=>nil, "additional_filesystem_gb"=>0}, prereq: nil, retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.787 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000145, _type: nil, op_type: :create_gear, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d"}, prereq: ["50d7c1cb9967662b0a000144"], retry_count: 0, retry_rollback_op: "50d7c1cb9967662b0a000144", saved_values: []> (pid:2603)
>2012-12-23 21:45:31.787 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000143, _type: nil, op_type: :init_gear, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d", "host_singletons"=>true, "app_dns"=>true}, prereq: ["50d7c1cb9967662b0a000142"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.787 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000149, _type: nil, op_type: :track_usage, state: :init, args: {"login"=>"qgong@redhat.com", "gear_ref"=>"50d7c1cb9967662b0a00013d", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>nil}, prereq: ["50d7c1cb9967662b0a000143"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.788 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000144, _type: nil, op_type: :reserve_uid, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d"}, prereq: ["50d7c1cb9967662b0a000143"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.788 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000146, _type: nil, op_type: :register_dns, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d"}, prereq: ["50d7c1cb9967662b0a000145"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.788 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a00014a, _type: nil, op_type: :set_gear_additional_filesystem_gb, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d", "additional_filesystem_gb"=>0}, prereq: ["50d7c1cb9967662b0a000143"], retry_count: 0, retry_rollback_op: nil, saved_values: {"additional_filesystem_gb"=>0}> (pid:2603)
>2012-12-23 21:45:31.788 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a00014b, _type: nil, op_type: :update_configuration, state: :init, args: {"add_keys_attrs"=>[{"_id"=>"50d7b7d79967662b0a000003", "type"=>"ssh-rsa", "name"=>"default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAlDd4u1sjlJsKCkglZmU5mWQQNptGh6Pl8tGyv0d4Uh6lB0mHm2Nj9ERXM4hSNkXj4XzSdGJc94n/SGx2Zw6iroAd2S7m88xAkYHCeVarMaMYy5dSpixAzW1ibHtyL1PSrvThJOkUc5vKEFEKa1hLvmu9AH3O0TAdC36mMAMJFByPuIAT/koTXvGTQLZxQicGdcAKY3LgIwu8zs9Q/ffYHbaJLaoBR0AoDgUwg7mo3k4DD5aZhtiahnFRT1dSPmPklpFWOu/Qe0bNT1PRzGNybDKgjwHR9HDeNluy0DYYhE9adswbkicWLaOzYGFFaZYQpz1sgvVtX1Tt7Oo+Y/AJgQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d"}, prereq: ["50d7c1cb9967662b0a000146"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.789 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000147, _type: nil, op_type: :new_component, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "comp_spec"=>{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}}, prereq: ["50d7c1cb9967662b0a000142"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.789 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a000148, _type: nil, op_type: :add_component, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d", "comp_spec"=>{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}, "init_git_url"=>nil}, prereq: ["50d7c1cb9967662b0a000147", "50d7c1cb9967662b0a000146"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.789 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a00014c, _type: nil, op_type: :expose_port, state: :init, args: {"group_instance_id"=>"50d7c1cb9967662b0a00013f", "gear_id"=>"50d7c1cb9967662b0a00013d", "comp_spec"=>{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}}, prereq: ["50d7c1cb9967662b0a000148", "50d7c1cb9967662b0a000146"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.790 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a00014d, _type: nil, op_type: :set_connections, state: :init, args: {"connections"=>[]}, prereq: ["50d7c1cb9967662b0a000140", "50d7c1cb9967662b0a000142", "50d7c1cb9967662b0a000145", "50d7c1cb9967662b0a000143", "50d7c1cb9967662b0a000149", "50d7c1cb9967662b0a000144", "50d7c1cb9967662b0a000146", "50d7c1cb9967662b0a00014a", "50d7c1cb9967662b0a00014b", "50d7c1cb9967662b0a000147", "50d7c1cb9967662b0a000148", "50d7c1cb9967662b0a00014c"], retry_count: 0, retry_rollback_op: nil, saved_values: {:current_connections=>[]}> (pid:2603)
>2012-12-23 21:45:31.790 [DEBUG] #<PendingAppOp _id: 50d7c1cb9967662b0a00014e, _type: nil, op_type: :execute_connections, state: :init, args: nil, prereq: ["50d7c1cb9967662b0a00014d"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:2603)
>2012-12-23 21:45:31.790 [DEBUG] ----------------------------------- (pid:2603)
>2012-12-23 21:45:31.792 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.1494ms) (pid:2603)
>2012-12-23 21:45:31.804 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$set"=>{"group_overrides"=>[{"components"=>[{"comp"=>"jenkins-1.4", "cart"=>"jenkins-1.4"}], "max_gears"=>1}], "updated_at"=>2012-12-24 02:45:31 UTC}} flags=[] (0.5491ms) (pid:2603)
>2012-12-23 21:45:31.808 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000140"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:completed}} flags=[] (3.3321ms) (pid:2603)
>2012-12-23 21:45:31.810 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$push"=>{"group_instances"=>{"_id"=>"50d7c1cb9967662b0a00013f", "gear_size"=>"small", "addtl_fs_gb"=>0}}} flags=[] (0.3054ms) (pid:2603)
>2012-12-23 21:45:31.811 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000142"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:completed}} flags=[] (0.4022ms) (pid:2603)
>2012-12-23 21:45:31.815 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (2.9700ms) (pid:2603)
>2012-12-23 21:45:31.829 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "group_instances._id"=>"50d7c1cb9967662b0a00013f"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"50d7c1cb9967662b0a00013d", "host_singletons"=>true, "app_dns"=>true, "name"=>"jenkins"}}} flags=[] (0.7899ms) (pid:2603)
>2012-12-23 21:45:31.833 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000143"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:completed}} flags=[] (0.5739ms) (pid:2603)
>2012-12-23 21:45:31.834 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$push"=>{"component_instances"=>{"_id"=>"50d7c1cb9967662b0a000151", "component_properties"=>{}, "cartridge_name"=>"jenkins-1.4", "component_name"=>"jenkins-1.4", "group_instance_id"=>"50d7c1cb9967662b0a00013f"}}} flags=[] (0.3014ms) (pid:2603)
>2012-12-23 21:45:31.836 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000147"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:completed}} flags=[] (0.4795ms) (pid:2603)
>2012-12-23 21:45:31.838 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.3027ms) (pid:2603)
>2012-12-23 21:45:31.847 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage_records documents=[{"_id"=>"50d7c1cb9967662b0a000152", "event"=>"begin", "time"=>2012-12-24 02:45:31 UTC, "gear_id"=>"50d7c1cb9967662b0a00013d", "usage_type"=>"GEAR_USAGE", "login"=>"qgong@redhat.com", "updated_at"=>2012-12-24 02:45:31 UTC, "created_at"=>2012-12-24 02:45:31 UTC}] flags=[] (0.5231ms) (pid:2603)
>2012-12-23 21:45:31.849 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage documents=[{"_id"=>"50d7c1cb9967662b0a000153", "login"=>"qgong@redhat.com", "gear_id"=>"50d7c1cb9967662b0a00013d", "begin_time"=>2012-12-24 02:45:31 UTC, "usage_type"=>"GEAR_USAGE", "updated_at"=>2012-12-24 02:45:31 UTC, "created_at"=>2012-12-24 02:45:31 UTC}] flags=[] (0.5174ms) (pid:2603)
>2012-12-23 21:45:31.851 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000149"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:completed}} flags=[] (0.5796ms) (pid:2603)
>2012-12-23 21:45:31.853 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=districts selector={"available_capacity"=>{"$gte"=>0}, "gear_size"=>"small", "active_server_identities_size"=>{"$gte"=>0}} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.6752ms) (pid:2603)
>2012-12-23 21:45:31.853 [DEBUG] DEBUG: rpc_get_fact: fact=active_capacity (pid:2603)
>2012-12-23 21:45:31.857 [DEBUG] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x00000006a583f8> (pid:2603)
>2012-12-23 21:45:32.902 [DEBUG] Current server: ip-10-118-62-244 active capacity: 5.0 (pid:2603)
>2012-12-23 21:45:32.929 [DEBUG] CURRENT SERVER: ip-10-118-62-244 (pid:2603)
>2012-12-23 21:45:32.929 [DEBUG] DEBUG: find_available_impl: current_server: ip-10-118-62-244: 5.0 (pid:2603)
>2012-12-23 21:45:32.930 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "group_instances._id"=>"50d7c1cb9967662b0a00013f", "group_instances.0.gears._id"=>"50d7c1cb9967662b0a00013d"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>"ip-10-118-62-244"}} flags=[] (0.7508ms) (pid:2603)
>2012-12-23 21:45:33.207 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "group_instances._id"=>"50d7c1cb9967662b0a00013f", "group_instances.0.gears._id"=>"50d7c1cb9967662b0a00013d"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (0.6032ms) (pid:2603)
>2012-12-23 21:45:33.214 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000144"} update={"$set"=>{"pending_op_groups.0.pending_ops.5.state"=>:completed}} flags=[] (0.1154ms) (pid:2603)
>2012-12-23 21:45:33.251 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a00014a"} update={"$set"=>{"pending_op_groups.0.pending_ops.7.state"=>:completed}} flags=[] (0.1509ms) (pid:2603)
>2012-12-23 21:45:33.362 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (324.3053ms) (pid:2603)
>2012-12-23 21:45:33.372 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000006a5ddf8> (pid:2603)
>2012-12-23 21:45:33.372 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"50d7c1cb9967662b0a00013d", "--with-app-name"=>"jenkins", "--with-container-uuid"=>"50d7c1cb9967662b0a00013d", "--with-container-name"=>"jenkins", "--with-namespace"=>"qgong15"}}, ip-10-118-62-244, {'identity' => ip-10-118-62-244}) (pid:2603)
>2012-12-23 21:45:35.681 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000068cb710 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-118-62-244", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (pid:2603)
>2012-12-23 21:45:35.685 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e"} update={"$inc"=>{"pending_op_groups.0.num_gears_created"=>1}} flags=[] (1.2679ms) (pid:2603)
>2012-12-23 21:45:35.686 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000145"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:completed}} flags=[] (0.4621ms) (pid:2603)
>2012-12-23 21:45:35.689 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (2.3272ms) (pid:2603)
>2012-12-23 21:45:35.832 [DEBUG] DEBUG: DYNECT Login with path: /REST/Session/ (pid:2603)
>2012-12-23 21:45:36.502 [DEBUG] DEBUG: DYNECT Response: {"status": "success", "data": {"token": "FlYx9MkZSuS/WkV9RAAuFJwn9Lk3XjOBeR3xVoR7RmyKFbrZc++a7WTycSJi65NNFrUuI2sD865Eoy+aNDGyNk5lzhzTwB7v2Kqp47Y38YWqxOCiQqcgvwCsGvD0vBg5aqk0d3NQqJCoEsruE48ijlfP7qn7S5EL", "version": "3.3.7"}, "job_id": 234975537, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2603)
>2012-12-23 21:45:36.502 [DEBUG] DEBUG: DYNECT Response data: {"token"=>"FlYx9MkZSuS/WkV9RAAuFJwn9Lk3XjOBeR3xVoR7RmyKFbrZc++a7WTycSJi65NNFrUuI2sD865Eoy+aNDGyNk5lzhzTwB7v2Kqp47Y38YWqxOCiQqcgvwCsGvD0vBg5aqk0d3NQqJCoEsruE48ijlfP7qn7S5EL", "version"=>"3.3.7"} (pid:2603)
>2012-12-23 21:45:36.592 [DEBUG] DEBUG: Public ip being configured 'ec2-184-73-121-221.compute-1.amazonaws.com' to app 'jenkins' (pid:2603)
>2012-12-23 21:45:36.593 [DEBUG] DEBUG: DYNECT put/post with path: /REST/CNAMERecord/rhcloud.com/jenkins-qgong15.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-184-73-121-221.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:37.232 [DEBUG] DEBUG: Retrying dyn_put_post after exception caught from DNS request: fqdn already in use. Please choose another. (pid:2603)
>2012-12-23 21:45:37.233 [DEBUG] DEBUG: DYNECT put/post with path: /REST/CNAMERecord/rhcloud.com/jenkins-qgong15.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-184-73-121-221.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:37.881 [DEBUG] DEBUG: Retrying dyn_put_post after exception caught from DNS request: fqdn already in use. Please choose another. (pid:2603)
>2012-12-23 21:45:37.882 [DEBUG] DEBUG: DYNECT put/post with path: /REST/CNAMERecord/rhcloud.com/jenkins-qgong15.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-184-73-121-221.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:38.581 [DEBUG] DNS entry already exists for jenkins-qgong15.  Attempting to modify... (pid:2603)
>2012-12-23 21:45:38.581 [DEBUG] DEBUG: Public ip being modified 'ec2-184-73-121-221.compute-1.amazonaws.com' to app 'jenkins' (pid:2603)
>2012-12-23 21:45:38.582 [DEBUG] DEBUG: DYNECT put/post with path: /REST/CNAMERecord/rhcloud.com/jenkins-qgong15.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-184-73-121-221.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:39.245 [DEBUG] DEBUG: DYNECT Response: {"status": "success", "data": {"zone": "rhcloud.com", "ttl": 60, "fqdn": "jenkins-qgong15.dev.rhcloud.com", "record_type": "CNAME", "rdata": {"cname": "ec2-184-73-121-221.compute-1.amazonaws.com."}, "record_id": 0}, "job_id": 234975547, "msgs": [{"INFO": "update: Record updated", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2603)
>2012-12-23 21:45:39.245 [DEBUG] DEBUG: DYNECT Response data: {"zone"=>"rhcloud.com", "ttl"=>60, "fqdn"=>"jenkins-qgong15.dev.rhcloud.com", "record_type"=>"CNAME", "rdata"=>{"cname"=>"ec2-184-73-121-221.compute-1.amazonaws.com."}, "record_id"=>0} (pid:2603)
>2012-12-23 21:45:39.246 [DEBUG] DEBUG: DYNECT put/post with path: /REST/Zone/rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:41.900 [DEBUG] DEBUG: DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "increment", "serial": 3713627, "zone": "rhcloud.com"}, "job_id": 234975550, "msgs": [{"INFO": "publish: rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2603)
>2012-12-23 21:45:41.902 [DEBUG] DEBUG: DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"increment", "serial"=>3713627, "zone"=>"rhcloud.com"} (pid:2603)
>2012-12-23 21:45:41.904 [DEBUG] DEBUG: DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:41.526 [DEBUG] DEBUG: DYNECT Response: {"status": "success", "data": {}, "job_id": 234975560, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2603)
>2012-12-23 21:45:41.526 [DEBUG] DEBUG: DYNECT Response data: {} (pid:2603)
>2012-12-23 21:45:41.528 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000146"} update={"$set"=>{"pending_op_groups.0.pending_ops.6.state"=>:completed}} flags=[] (0.8278ms) (pid:2603)
>2012-12-23 21:45:41.530 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.4174ms) (pid:2603)
>2012-12-23 21:45:41.553 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000006862f58> (pid:2603)
>2012-12-23 21:45:41.553 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"jenkins-1.4", :action=>"configure", :args=>"'jenkins' 'qgong15' '50d7c1cb9967662b0a00013d'"}, ip-10-118-62-244, {'identity' => ip-10-118-62-244}) (pid:2603)
>2012-12-23 21:45:53.635 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000006798f28 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-118-62-244", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"SSH_KEY_ADD: AAAAB3NzaC1yc2EAAAABIwAAAQEAoMWJEK+NgaykLCZSX6xG92keUHyt5p0KPyxzQt5GjTh3ja7T7qKgXzRzVU33NinBk4kZ5c2ZHGpGDJ9sNPkvUbssSFR+UzfXtDP9xa9/sesqXwuk2mkkPwGxtEd2RLQX2T2WXntdKMOnHA2+VGymIO59tjDOIx4/DVbSSbkaoHwU+sqKqV70ZyU9S3dqD3W3EUzgDoh42rTeNX/ybMZDSsLRTXe34T2lYg78/CRlr1v7PuNDsNRoVxbS+OB+22teFvYhh6BM3S8KdrsVXBoDUyBniUActCvgqOGZM9TBBoGm5nobWY/0vH1rxhbF9R52x/aAgrCjIdz0tIWye5AFFQ==\nBROKER_AUTH_KEY_ADD: \n/var/lib/openshift/50d7c1cb9967662b0a00013d/git/jenkins.git /tmp\n/tmp\nCLIENT_RESULT: \nCLIENT_RESULT: Jenkins created successfully.  Please make note of these credentials:\nCLIENT_RESULT: \nCLIENT_RESULT:    User: admin\nCLIENT_RESULT:    Password: s14gtgWuNBEg\nCLIENT_RESULT: \nCLIENT_RESULT: Note:  You can change your password at: https://jenkins-qgong15.dev.rhcloud.com/me/configure\nCLIENT_RESULT: \nCART_PROPERTIES: username=system_builder\nCART_PROPERTIES: password=GdZ8eS2yA5uH\nENV_VAR_ADD: JENKINS_URL=https://jenkins-qgong15.dev.rhcloud.com/\nENV_VAR_ADD: JENKINS_USERNAME=system_builder\nENV_VAR_ADD: JENKINS_PASSWORD=GdZ8eS2yA5uH\n", :exitcode=>0}}>] (pid:2603)
>2012-12-23 21:45:53.673 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "component_instances._id"=>"50d7c1cb9967662b0a000151"} update={"$set"=>{"component_instances.0.component_properties"=>{"username"=>"system_builder", "password"=>"GdZ8eS2yA5uH"}}} flags=[] (29.1483ms) (pid:2603)
>2012-12-23 21:45:53.673 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "component_instances._id"=>"50d7c1cb9967662b0a000151"} update={"$set"=>{"component_instances.0.component_properties"=>{"username"=>"system_builder", "password"=>"GdZ8eS2yA5uH"}}} flags=[] (29.1483ms) (pid:2603)
>2012-12-23 21:45:53.683 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$push"=>{:pending_op_groups=>{"_id"=>"50d7c1e19967662b0a000154", "args"=>{"iv"=>"EvYmnFXHdvvu4Z1Q4BpuWT5iBATFmg9Rz+5HQ412bclTuYXRyYn3XPaSqmrY\nURhTotsFG2D0Z6oWZt45YmLGQ0c+7iaJ1v4eLLM4rQQudvX+riB+lYwHmxKv\n/6q5eo1m0OYNy9bilXQJV2mpS4igVWHQfjm+krL2xSmtmw18QD5SjX+dpXEp\n2Selb0rI4vSB9q1sAWG2AP2g9++BCFWU3/4AvzsLdq9l6n04MACh5PxLkKq+\nkpFQrhxRxBFPOLVOu6O1h1hlnlWTdY10eydSbM6XKoBQavoNRap3dUmo+G1B\n5LTKdoVAQ+QJlZ1G1CtLRkUJurFlcy6Q9MQm8VlFNA==\n", "token"=>"flFUgtRteCBq97R+cxxQWPpDi3+dYwW/Jqybc7wEtUdnUVRAGiGzUavPPEWS\nsxilAD9G/tpJAyUSMzMLdxUSYOh+q8sxKZjJZOiU8hc2gPK21hWPFnS2KIec\nA3Kq9dQH\n"}, "created_at"=>nil, "num_gears_added"=>0, "num_gears_created"=>0, "num_gears_destroyed"=>0, "num_gears_removed"=>0, "num_gears_rolled_back"=>0, "op_type"=>:add_broker_auth_key, "parent_op_id"=>nil, "updated_at"=>nil}}} flags=[:multi] (0.2499ms) (pid:2603)
>2012-12-23 21:45:53.685 [ERROR] undefined method `attributes' for #<Hash:0x000000065c3108> (pid:2603)
>2012-12-23 21:45:53.687 [ERROR] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:784:in `block in process_commands'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:784:in `map'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:784:in `process_commands'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/mongoid/relations/proxy.rb:117:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/gear.rb:114:in `add_component'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:166:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:905:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:319:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:937:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:316:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:91:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/controllers/applications_controller.rb:96:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:18:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:458:in `_run__1802142399776906901__process_action__29127386076630936__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__2737829902978203702__call__1759605036383940828__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:514:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/utils.rb:479:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:2603)
>2012-12-23 21:45:53.687 [ERROR] undefined method `attributes' for #<Hash:0x000000065c3108> (pid:2603)
>2012-12-23 21:45:53.687 [ERROR] undefined method `attributes' for #<Hash:0x000000065c3108> (pid:2603)
>2012-12-23 21:45:53.689 [DEBUG] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:784:in `block in process_commands'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:784:in `map'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:784:in `process_commands'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/mongoid/relations/proxy.rb:117:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/gear.rb:114:in `add_component'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:166:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:905:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:319:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:937:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:316:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:91:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/controllers/applications_controller.rb:96:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:18:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:458:in `_run__1802142399776906901__process_action__29127386076630936__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__2737829902978203702__call__1759605036383940828__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:514:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/utils.rb:479:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:2603)
>2012-12-23 21:45:53.709 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (17.2906ms) (pid:2603)
>2012-12-23 21:45:53.725 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$set"=>{"group_overrides"=>nil, "updated_at"=>2012-12-24 02:45:53 UTC}} flags=[] (2.8942ms) (pid:2603)
>2012-12-23 21:45:53.728 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000140"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:rolledback}} flags=[] (2.6650ms) (pid:2603)
>2012-12-23 21:45:53.730 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage_records selector={"$query"=>{"login"=>"qgong@redhat.com", "gear_id"=>"50d7c1cb9967662b0a00013d", "event"=>"begin", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6745ms) (pid:2603)
>2012-12-23 21:45:53.731 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=usage_records selector={"_id"=>"50d7c1cb9967662b0a000152"} flags=[:remove_first] (0.3607ms) (pid:2603)
>2012-12-23 21:45:53.732 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage selector={"$query"=>{"gear_id"=>"50d7c1cb9967662b0a00013d", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{"begin_time"=>-1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5012ms) (pid:2603)
>2012-12-23 21:45:53.733 [DEBUG] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=usage selector={"_id"=>"50d7c1cb9967662b0a000153"} flags=[:remove_first] (0.5221ms) (pid:2603)
>2012-12-23 21:45:53.736 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000149"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:rolledback}} flags=[] (2.6171ms) (pid:2603)
>2012-12-23 21:45:53.737 [DEBUG] DEBUG: DYNECT Login with path: /REST/Session/ (pid:2603)
>2012-12-23 21:45:54.365 [DEBUG] DEBUG: DYNECT Response: {"status": "success", "data": {"token": "ic9zfsfjE+cE5edyzoOdPvidIVWzEusv6dCyJyR0uaeHXxqdD+sOlPcpDw9St3z9xUG3WjOLTWvdswEtbGARS7riWw6+Jk4PBJRZw/hqxBHj2Q56DMyTbZuP9ZVHD6ApKvRDyybyKpXgdzw9WX0/yChpCviFDZh/4jL/W5DO1Xs=", "version": "3.3.7"}, "job_id": 234975637, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2603)
>2012-12-23 21:45:54.365 [DEBUG] DEBUG: DYNECT Response data: {"token"=>"ic9zfsfjE+cE5edyzoOdPvidIVWzEusv6dCyJyR0uaeHXxqdD+sOlPcpDw9St3z9xUG3WjOLTWvdswEtbGARS7riWw6+Jk4PBJRZw/hqxBHj2Q56DMyTbZuP9ZVHD6ApKvRDyybyKpXgdzw9WX0/yChpCviFDZh/4jL/W5DO1Xs=", "version"=>"3.3.7"} (pid:2603)
>2012-12-23 21:45:54.366 [DEBUG] DEBUG: DYNECT delete with path: /REST/CNAMERecord/rhcloud.com/jenkins-qgong15.dev.rhcloud.com/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:54.908 [DEBUG] DEBUG: DYNECT Response: {"status": "success", "data": {}, "job_id": 234975640, "msgs": [{"INFO": "delete: 1 records deleted", "SOURCE": "API-B", "ERR_CD": null, "LVL": "INFO"}]} (pid:2603)
>2012-12-23 21:45:54.908 [DEBUG] DEBUG: DYNECT Response data: {} (pid:2603)
>2012-12-23 21:45:54.908 [DEBUG] DEBUG: DYNECT put/post with path: /REST/Zone/rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:56.659 [DEBUG] DEBUG: DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "increment", "serial": 3713629, "zone": "rhcloud.com"}, "job_id": 234975644, "msgs": [{"INFO": "publish: rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2603)
>2012-12-23 21:45:56.659 [DEBUG] DEBUG: DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"increment", "serial"=>3713629, "zone"=>"rhcloud.com"} (pid:2603)
>2012-12-23 21:45:56.659 [DEBUG] DEBUG: DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2603)
>2012-12-23 21:45:57.783 [DEBUG] DEBUG: DYNECT Response: {"status": "success", "data": {}, "job_id": 234975651, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2603)
>2012-12-23 21:45:57.785 [DEBUG] DEBUG: DYNECT Response data: {} (pid:2603)
>2012-12-23 21:45:57.801 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000146"} update={"$set"=>{"pending_op_groups.0.pending_ops.6.state"=>:rolledback}} flags=[] (0.9606ms) (pid:2603)
>2012-12-23 21:45:57.849 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$pull"=>{"component_instances"=>{"_id"=>"50d7c1cb9967662b0a000151"}}} flags=[] (2.8632ms) (pid:2603)
>2012-12-23 21:45:57.865 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000147"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:rolledback}} flags=[] (0.8187ms) (pid:2603)
>2012-12-23 21:45:57.935 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$set"=>{"updated_at"=>2012-12-24 02:45:57 UTC, "pending_op_groups.0.pending_ops.7.state"=>:rolledback}} flags=[] (3.6321ms) (pid:2603)
>2012-12-23 21:45:57.978 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (3.1216ms) (pid:2603)
>2012-12-23 21:45:57.110 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000006eab608> (pid:2603)
>2012-12-23 21:45:57.111 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"50d7c1cb9967662b0a00013d", "--with-app-name"=>"jenkins", "--with-container-uuid"=>"50d7c1cb9967662b0a00013d", "--with-container-name"=>"jenkins", "--with-namespace"=>"qgong15"}}, ip-10-118-62-244, {'identity' => ip-10-118-62-244}) (pid:2603)
>2012-12-23 21:46:03.715 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x0000000692d230 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-118-62-244", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"SSH_KEY_REMOVE: \nENV_VAR_REMOVE: JENKINS_URL\nENV_VAR_REMOVE: JENKINS_USERNAME\nENV_VAR_REMOVE: JENKINS_PASSWORD\nSSH_KEY_REMOVE: \nENV_VAR_REMOVE: JENKINS_URL\nENV_VAR_REMOVE: JENKINS_USERNAME\nENV_VAR_REMOVE: JENKINS_PASSWORD\n", :exitcode=>0}}>] (pid:2603)
>2012-12-23 21:46:03.755 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e"} update={"$inc"=>{"pending_op_groups.0.num_gears_rolled_back"=>1}} flags=[] (10.9172ms) (pid:2603)
>2012-12-23 21:46:03.756 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000145"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:rolledback}} flags=[] (0.1755ms) (pid:2603)
>2012-12-23 21:46:03.761 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (4.2043ms) (pid:2603)
>2012-12-23 21:46:03.899 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "group_instances._id"=>"50d7c1cb9967662b0a00013f", "group_instances.0.gears._id"=>"50d7c1cb9967662b0a00013d"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>nil}} flags=[] (0.6123ms) (pid:2603)
>2012-12-23 21:46:03.900 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "group_instances._id"=>"50d7c1cb9967662b0a00013f", "group_instances.0.gears._id"=>"50d7c1cb9967662b0a00013d"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (0.4408ms) (pid:2603)
>2012-12-23 21:46:03.904 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000144"} update={"$set"=>{"pending_op_groups.0.pending_ops.5.state"=>:rolledback}} flags=[] (0.4222ms) (pid:2603)
>2012-12-23 21:46:03.907 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.9076ms) (pid:2603)
>2012-12-23 21:46:03.916 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "group_instances._id"=>"50d7c1cb9967662b0a00013f"} update={"$pull"=>{"group_instances.0.gears"=>{"_id"=>"50d7c1cb9967662b0a00013d"}}} flags=[] (0.5646ms) (pid:2603)
>2012-12-23 21:46:03.922 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000143"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:rolledback}} flags=[] (3.5388ms) (pid:2603)
>2012-12-23 21:46:03.925 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7c1cb9967662b0a00013d"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.1718ms) (pid:2603)
>2012-12-23 21:46:03.935 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$pull"=>{"group_instances"=>{"_id"=>"50d7c1cb9967662b0a00013f"}}} flags=[] (0.5062ms) (pid:2603)
>2012-12-23 21:46:03.936 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d", "pending_op_groups._id"=>"50d7c1cb9967662b0a00013e", "pending_op_groups.0.pending_ops._id"=>"50d7c1cb9967662b0a000142"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:rolledback}} flags=[] (0.6268ms) (pid:2603)
>2012-12-23 21:46:03.940 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"50d7b7929967662b0a000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (3.4075ms) (pid:2603)
>2012-12-23 21:46:03.943 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7b7929967662b0a000001", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000006dbbc70>"=>1356317163}], "app_ids"=>{"$in"=>["50d7c1cb9967662b0a00013d"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1356317763}}} (2.4412ms) (pid:2603)
>2012-12-23 21:46:03.946 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (2.4087ms) (pid:2603)
>2012-12-23 21:46:03.955 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (5.4059ms) (pid:2603)
>2012-12-23 21:46:03.956 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"50d7b7929967662b0a000001"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.4699ms) (pid:2603)
>2012-12-23 21:46:03.964 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"50d7b7929967662b0a000001"} update={"$set"=>{"consumed_gears"=>3, "updated_at"=>2012-12-24 02:46:03 UTC}} flags=[] (0.4344ms) (pid:2603)
>2012-12-23 21:46:03.967 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7b7929967662b0a000001", "locked"=>true, "app_ids"=>{"$in"=>["50d7c1cb9967662b0a00013d"]}}, :new=>false, :update=>{"$set"=>{"locked"=>false}}} (0.6521ms) (pid:2603)
>2012-12-23 21:46:03.971 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7c1cb9967662b0a00013d"} update={"$pull"=>{"pending_op_groups"=>{"_id"=>"50d7c1cb9967662b0a00013e"}}} flags=[] (0.4830ms) (pid:2603)
>2012-12-23 21:46:03.973 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7b7929967662b0a000001", "locked"=>false, "app_ids"=>{"$in"=>["50d7c1cb9967662b0a00013d"]}}, :new=>false, :update=>{"$pop"=>{:app_ids=>"50d7c1cb9967662b0a00013d"}}} (0.6433ms) (pid:2603)
>2012-12-23 21:46:04.976 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"domain_id"=>"50d7b7f59967662b0a000004"} flags=[:slave_ok] limit=0 skip=0 fields=nil (1.3790ms) (pid:2603)
>2012-12-23 21:46:05.348 [DEBUG] [REQ_ID=704c8bdfdfb54905b58f6b51b08f624a] ACTION=ADD_APPLICATION Application jenkins was created., TODO (pid:2603)
>2012-12-23 21:46:05.374 [INFO ] Completed 201 Created in 33889ms (Views: 20.4ms) (pid:2603)

Actions: View

Attachments on bug 889932: 668311 | 678006