Attachment 676185 Details for Bug 893917 – broker log

broker log

development.log (text/x-log), 44.50 KB, created by Jianwei Hou on 2013-01-10 09:33:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jianwei Hou

Created: 2013-01-10 09:33:07 UTC

Size: 44.50 KB

patch

obsolete

>2013-01-10 04:28:14.701 [INFO ] Started DELETE "/broker/rest/domains/395t/applications/eap1" for 203.114.244.88 at 2013-01-10 04:28:14 -0500 (pid:9873)
>2013-01-10 04:28:14.704 [INFO ] Processing by ApplicationsController#destroy as JSON (pid:9873)
>2013-01-10 04:28:14.704 [INFO ] Parameters: {"domain_id"=>"395t", "id"=>"eap1"} (pid:9873)
>2013-01-10 04:28:14.708 [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 (1.0738ms) (pid:9873)
>2013-01-10 04:28:14.709 [DEBUG] application/json (pid:9873)
>2013-01-10 04:28:14.710 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"50ee7da54b0193e56c00003e", "canonical_namespace"=>"395t"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5567ms) (pid:9873)
>2013-01-10 04:28:14.711 [DEBUG] [REQ_ID=fefa45b0086f4a8cb041e364b4144c76] ACTION=DELETE_APPLICATION Found domain 395t (pid:9873)
>2013-01-10 04:28:14.712 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"domain_id"=>"50ee7da54b0193e56c000040", "canonical_name"=>"eap1"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.7260ms) (pid:9873)
>2013-01-10 04:28:14.713 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"50ee7da54b0193e56c000040"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6249ms) (pid:9873)
>2013-01-10 04:28:14.715 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"domain_id"=>"50ee7da54b0193e56c000040"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.7956ms) (pid:9873)
>2013-01-10 04:28:14.722 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50ee7da54b0193e56c00003e", "app_ids"=>{"$nin"=>["50ee7eea4b0193eb7b00003c"]}}, :new=>true, :update=>{"$push"=>{:app_ids=>"50ee7eea4b0193eb7b00003c"}}} (0.9108ms) (pid:9873)
>2013-01-10 04:28:14.725 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50ee7eea4b0193eb7b00003c"} update={"$push"=>{"pending_op_groups"=>{"_id"=>"50ee89ae4b01939126000014", "num_gears_added"=>0, "num_gears_removed"=>0, "num_gears_created"=>0, "num_gears_destroyed"=>0, "num_gears_rolled_back"=>0, "op_type"=>:remove_features, "args"=>{"features"=>["jbosseap-6.0"], "group_overrides"=>[]}, "parent_op_id"=>nil, "updated_at"=>2013-01-10 09:28:14 UTC, "created_at"=>2013-01-10 09:28:14 UTC}}} flags=[] (0.4873ms) (pid:9873)
>2013-01-10 04:28:14.726 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50ee7eea4b0193eb7b00003c"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.7131ms) (pid:9873)
>2013-01-10 04:28:14.731 [DEBUG]  (pid:9873)
>2013-01-10 04:28:14.731 [DEBUG] ----------------------------------- (pid:9873)
>2013-01-10 04:28:14.732 [DEBUG] features: [], group_overrides: [{"components"=>[nil], "max_gears"=>1}] (pid:9873)
>2013-01-10 04:28:14.732 [DEBUG] final group instances: [] (pid:9873)
>2013-01-10 04:28:14.732 [DEBUG] changes: [{:from=>"50ee7eea4b0193eb7b00003e", :to=>nil, :added=>[], :removed=>[{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}], :from_scale=>{:current=>1, :additional_filesystem_gb=>0, :gear_size=>"small"}, :to_scale=>{:min=>1, :max=>-1}}], moves: [] (pid:9873)
>2013-01-10 04:28:14.732 [DEBUG] ----------------------------------- (pid:9873)
>2013-01-10 04:28:14.732 [DEBUG]  (pid:9873)
>2013-01-10 04:28:14.743 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"50ee7da54b0193e56c000040"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5460ms) (pid:9873)
>2013-01-10 04:28:14.746 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"50ee7da54b0193e56c00003e"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.9258ms) (pid:9873)
>2013-01-10 04:28:14.747 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50ee7da54b0193e56c00003e"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.5152ms) (pid:9873)
>2013-01-10 04:28:14.748 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"50ee7da54b0193e56c00003e"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.4098ms) (pid:9873)
>2013-01-10 04:28:14.752 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"50ee7da54b0193e56c00003e"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6111ms) (pid:9873)
>2013-01-10 04:28:14.754 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50ee7da54b0193e56c00003e", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000006eafe10>"=>1357810094}], "app_ids"=>{"$in"=>["50ee7eea4b0193eb7b00003c"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1357810694}}} (0.9267ms) (pid:9873)
>2013-01-10 04:28:14.755 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"50ee7da54b0193e56c00003e"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6697ms) (pid:9873)
>2013-01-10 04:28:14.767 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50ee7eea4b0193eb7b00003c", "pending_op_groups._id"=>"50ee89ae4b01939126000014"} update={"$pushAll"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"50ee89ae4b0193912600001b", "state"=>:init, "retry_count"=>0, "saved_values"=>{"current_group_overrides"=>nil}, "op_type"=>:set_group_overrides, "args"=>{"group_overrides"=>[{"components"=>[], "max_gears"=>1}]}}, {"_id"=>"50ee89ae4b01939126000015", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:remove_component, "args"=>{"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c", "comp_spec"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}}}, {"_id"=>"50ee89ae4b0193912600001c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:del_component, "args"=>{"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "comp_spec"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}}, "prereq"=>["50ee89ae4b01939126000015"]}, {"_id"=>"50ee89ae4b01939126000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:destroy_gear, "args"=>{"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c"}}, {"_id"=>"50ee89ae4b01939126000017", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:deregister_dns, "args"=>{"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c"}, "prereq"=>["50ee89ae4b01939126000016"]}, {"_id"=>"50ee89ae4b01939126000018", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:unreserve_uid, "args"=>{"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c"}, "prereq"=>["50ee89ae4b01939126000017"]}, {"_id"=>"50ee89ae4b01939126000019", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:delete_gear, "args"=>{"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c"}, "prereq"=>["50ee89ae4b01939126000018"]}, {"_id"=>"50ee89ae4b0193912600001a", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"jhou@redhat.com", "gear_ref"=>"50ee7eea4b0193eb7b00003c", "event"=>"end", "usage_type"=>"GEAR_USAGE"}, "prereq"=>["50ee89ae4b01939126000019"]}, {"_id"=>"50ee89ae4b0193912600001d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:destroy_group_instance, "args"=>{"group_instance_id"=>"50ee7eea4b0193eb7b00003e"}, "prereq"=>["50ee89ae4b01939126000016", "50ee89ae4b01939126000017", "50ee89ae4b01939126000018", "50ee89ae4b01939126000019", "50ee89ae4b0193912600001a"]}, {"_id"=>"50ee89ae4b0193912600001e", "state"=>:init, "retry_count"=>0, "saved_values"=>{:current_connections=>[{"from_comp_inst"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}, "to_comp_inst"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}, "from_connector_name"=>"publish_jboss_cluster", "to_connector_name"=>"set_jboss_cluster", "connection_type"=>"NET_TCP:jbosseap-cluster-info"}, {"from_comp_inst"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}, "to_comp_inst"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}, "from_connector_name"=>"publish_jboss_remoting", "to_connector_name"=>"set_jboss_remoting", "connection_type"=>"NET_TCP:jbosseap-remoting-info"}]}, "op_type"=>:set_connections, "args"=>{"connections"=>[]}, "prereq"=>["50ee89ae4b0193912600001b", "50ee89ae4b01939126000015", "50ee89ae4b0193912600001c", "50ee89ae4b01939126000016", "50ee89ae4b01939126000017", "50ee89ae4b01939126000018", "50ee89ae4b01939126000019", "50ee89ae4b0193912600001a", "50ee89ae4b0193912600001d"]}, {"_id"=>"50ee89ae4b0193912600001f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["50ee89ae4b0193912600001e"]}]}} flags=[] (1.2774ms) (pid:9873)
>2013-01-10 04:28:14.775 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50ee7eea4b0193eb7b00003c", "pending_op_groups._id"=>"50ee89ae4b01939126000014"} update={"$set"=>{"pending_op_groups.0.num_gears_removed"=>1, "pending_op_groups.0.updated_at"=>2013-01-10 09:28:14 UTC}} flags=[] (0.6044ms) (pid:9873)
>2013-01-10 04:28:14.838 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"50ee7da54b0193e56c00003e"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.8581ms) (pid:9873)
>2013-01-10 04:28:14.839 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"user_ids"=>"50ee7da54b0193e56c00003e"} flags=[:slave_ok] limit=0 skip=0 fields=nil (0.4113ms) (pid:9873)
>2013-01-10 04:28:14.842 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50ee7da54b0193e56c00003e", "locked"=>true, "app_ids"=>{"$in"=>["50ee7eea4b0193eb7b00003c"]}}, :new=>false, :update=>{"$set"=>{"locked"=>false}}} (0.6380ms) (pid:9873)
>2013-01-10 04:28:14.842 [DEBUG] ----------------------------------- (pid:9873)
>2013-01-10 04:28:14.843 [DEBUG] #<PendingAppOpGroup _id: 50ee89ae4b01939126000014, _type: nil, created_at: 2013-01-10 09:28:14 UTC, updated_at: 2013-01-10 09:28:14 UTC, op_type: :remove_features, args: {"features"=>["jbosseap-6.0"], "group_overrides"=>[]}, parent_op_id: nil, num_gears_added: 0, num_gears_removed: 1, num_gears_created: 0, num_gears_destroyed: 0, num_gears_rolled_back: 0> (pid:9873)
>2013-01-10 04:28:14.843 [DEBUG] #<PendingAppOp _id: 50ee89ae4b0193912600001b, _type: nil, op_type: :set_group_overrides, state: :init, args: {"group_overrides"=>[{"components"=>[], "max_gears"=>1}]}, prereq: nil, retry_count: 0, retry_rollback_op: nil, saved_values: {"current_group_overrides"=>nil}> (pid:9873)
>2013-01-10 04:28:14.843 [DEBUG] #<PendingAppOp _id: 50ee89ae4b01939126000015, _type: nil, op_type: :remove_component, state: :init, args: {"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c", "comp_spec"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}}, prereq: nil, retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.843 [DEBUG] #<PendingAppOp _id: 50ee89ae4b0193912600001c, _type: nil, op_type: :del_component, state: :init, args: {"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "comp_spec"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}}, prereq: ["50ee89ae4b01939126000015"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.844 [DEBUG] #<PendingAppOp _id: 50ee89ae4b01939126000016, _type: nil, op_type: :destroy_gear, state: :init, args: {"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c"}, prereq: nil, retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.844 [DEBUG] #<PendingAppOp _id: 50ee89ae4b01939126000017, _type: nil, op_type: :deregister_dns, state: :init, args: {"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c"}, prereq: ["50ee89ae4b01939126000016"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.844 [DEBUG] #<PendingAppOp _id: 50ee89ae4b01939126000018, _type: nil, op_type: :unreserve_uid, state: :init, args: {"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c"}, prereq: ["50ee89ae4b01939126000017"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.845 [DEBUG] #<PendingAppOp _id: 50ee89ae4b01939126000019, _type: nil, op_type: :delete_gear, state: :init, args: {"group_instance_id"=>"50ee7eea4b0193eb7b00003e", "gear_id"=>"50ee7eea4b0193eb7b00003c"}, prereq: ["50ee89ae4b01939126000018"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.845 [DEBUG] #<PendingAppOp _id: 50ee89ae4b0193912600001a, _type: nil, op_type: :track_usage, state: :init, args: {"login"=>"jhou@redhat.com", "gear_ref"=>"50ee7eea4b0193eb7b00003c", "event"=>"end", "usage_type"=>"GEAR_USAGE"}, prereq: ["50ee89ae4b01939126000019"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.845 [DEBUG] #<PendingAppOp _id: 50ee89ae4b0193912600001d, _type: nil, op_type: :destroy_group_instance, state: :init, args: {"group_instance_id"=>"50ee7eea4b0193eb7b00003e"}, prereq: ["50ee89ae4b01939126000016", "50ee89ae4b01939126000017", "50ee89ae4b01939126000018", "50ee89ae4b01939126000019", "50ee89ae4b0193912600001a"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.845 [DEBUG] #<PendingAppOp _id: 50ee89ae4b0193912600001e, _type: nil, op_type: :set_connections, state: :init, args: {"connections"=>[]}, prereq: ["50ee89ae4b0193912600001b", "50ee89ae4b01939126000015", "50ee89ae4b0193912600001c", "50ee89ae4b01939126000016", "50ee89ae4b01939126000017", "50ee89ae4b01939126000018", "50ee89ae4b01939126000019", "50ee89ae4b0193912600001a", "50ee89ae4b0193912600001d"], retry_count: 0, retry_rollback_op: nil, saved_values: {:current_connections=>[{"from_comp_inst"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}, "to_comp_inst"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}, "from_connector_name"=>"publish_jboss_cluster", "to_connector_name"=>"set_jboss_cluster", "connection_type"=>"NET_TCP:jbosseap-cluster-info"}, {"from_comp_inst"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}, "to_comp_inst"=>{"cart"=>"jbosseap-6.0", "comp"=>"jbosseap-6.0"}, "from_connector_name"=>"publish_jboss_remoting", "to_connector_name"=>"set_jboss_remoting", "connection_type"=>"NET_TCP:jbosseap-remoting-info"}]}> (pid:9873)
>2013-01-10 04:28:14.846 [DEBUG] #<PendingAppOp _id: 50ee89ae4b0193912600001f, _type: nil, op_type: :execute_connections, state: :init, args: nil, prereq: ["50ee89ae4b0193912600001e"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:9873)
>2013-01-10 04:28:14.846 [DEBUG] ----------------------------------- (pid:9873)
>2013-01-10 04:28:14.848 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50ee7eea4b0193eb7b00003c"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.2517ms) (pid:9873)
>2013-01-10 04:28:14.859 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50ee7eea4b0193eb7b00003c"} update={"$set"=>{"group_overrides"=>[{"components"=>[], "max_gears"=>1}], "updated_at"=>2013-01-10 09:28:14 UTC}} flags=[] (0.7217ms) (pid:9873)
>2013-01-10 04:28:14.860 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50ee7eea4b0193eb7b00003c", "pending_op_groups._id"=>"50ee89ae4b01939126000014", "pending_op_groups.0.pending_ops._id"=>"50ee89ae4b0193912600001b"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:completed}} flags=[] (0.5345ms) (pid:9873)
>2013-01-10 04:28:14.866 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000006664dc8> (pid:9873)
>2013-01-10 04:28:14.866 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"jbosseap-6.0", :action=>"deconfigure", :args=>"'eap1' '395t' '50ee7eea4b0193eb7b00003c'"}, ip-10-147-139-140, {'identity' => ip-10-147-139-140}) (pid:9873)
>2013-01-10 04:28:15.547 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"50ee370e4b0193240a000004"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.8495ms) (pid:27877)
>2013-01-10 04:28:15.548 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50ee370e4b0193240a000004", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000006ef3c28>"=>1357810095}], "app_ids"=>{"$in"=>["50ee67ff4b0193f3060000b0"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1357810695}}} (0.5558ms) (pid:27877)
>2013-01-10 04:28:16.557 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"50ee370e4b0193240a000004"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (7.7634ms) (pid:27877)
>2013-01-10 04:28:16.559 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50ee370e4b0193240a000004", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000006f039e8>"=>1357810096}], "app_ids"=>{"$in"=>["50ee67ff4b0193f3060000b0"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1357810696}}} (1.1106ms) (pid:27877)
>2013-01-10 04:28:16.988 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x0000000695a820 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-147-139-140", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"/usr/bin/openshift-port-proxy-cfg: line 208: [: : integer expression expected\n/usr/bin/openshift-port-proxy-cfg: line 208: [: : integer expression expected\n/usr/bin/openshift-port-proxy-cfg: line 208: [: : integer expression expected\n/usr/bin/openshift-port-proxy-cfg: line 208: [: : integer expression expected\nCLIENT_INTERNAL_ERROR: \nCLIENT_INTERNAL_ERROR: Dest port must be an integer between 16384 and 65535\nError: Failed at 35671 127.1.8.1:\nERROR: Failed to process: setproxies 35671 127.1.8.1:\nError: Failed to update proxy.\nFailed to add proxy port\nCLIENT_INTERNAL_ERROR: Dest port must be an integer between 16384 and 65535\nError: Failed at 35671 127.1.8.1:\nERROR: Failed to process: setproxies 35671 127.1.8.1:\nError: Failed to update proxy.\nFailed to add proxy port\nCLIENT_INTERNAL_ERROR: Dest port must be an integer between 16384 and 65535\nError: Failed at 35671 127.1.8.1:\nERROR: Failed to process: setproxies 35671 127.1.8.1:\nError: Failed to update proxy.\nFailed to add proxy port\nCLIENT_INTERNAL_ERROR: Dest port must be an integer between 16384 and 65535\nError: Failed at 35671 127.1.8.1:\nERROR: Failed to process: setproxies 35671 127.1.8.1:\nError: Failed to update proxy.\nFailed to add proxy port\n", :exitcode=>0}}>] (pid:9873)
>2013-01-10 04:28:17.112 [ERROR] undefined local variable or method `result' for #<ResultIO:0x00000006c33718> (pid:9873)
>2013-01-10 04:28:17.119 [ERROR] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/result_io.rb:108:in `block in parse_output'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/result_io.rb:99:in `each_line'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/result_io.rb:99:in `parse_output'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:1126:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:1207:in `run_cartridge_command'", "/var/www/openshift/broker/lib/express/broker/mcollective_ext.rb:12:in `run_cartridge_command'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:304:in `deconfigure_cartridge'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/gear.rb:136:in `remove_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:126:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:126:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:938:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:352:in `block in remove_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:969:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:350:in `remove_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:372:in `destroy_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/controllers/applications_controller.rb:148:in `destroy'", "/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__3080209020943141242__process_action__1112636389859728999__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__2293252697721056776__call__2269422480948184218__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:9873)
>2013-01-10 04:28:17.122 [ERROR] undefined local variable or method `result' for #<ResultIO:0x00000006c33718> (pid:9873)
>2013-01-10 04:28:17.122 [ERROR] undefined local variable or method `result' for #<ResultIO:0x00000006c33718> (pid:9873)
>2013-01-10 04:28:17.287 [DEBUG] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/result_io.rb:108:in `block in parse_output'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/result_io.rb:99:in `each_line'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/result_io.rb:99:in `parse_output'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:1126:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:1207:in `run_cartridge_command'", "/var/www/openshift/broker/lib/express/broker/mcollective_ext.rb:12:in `run_cartridge_command'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:304:in `deconfigure_cartridge'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/gear.rb:136:in `remove_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:126:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:126:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:938:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:352:in `block in remove_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:969:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:350:in `remove_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:372:in `destroy_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/controllers/applications_controller.rb:148:in `destroy'", "/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__3080209020943141242__process_action__1112636389859728999__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__2293252697721056776__call__2269422480948184218__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:9873)
>2013-01-10 04:28:17.459 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50ee7eea4b0193eb7b00003c"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (15.5120ms) (pid:9873)
>2013-01-10 04:28:17.104 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50ee7eea4b0193eb7b00003c"} update={"$set"=>{"group_overrides"=>nil, "updated_at"=>2013-01-10 09:28:17 UTC}} flags=[] (0.6049ms) (pid:9873)
>2013-01-10 04:28:17.121 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50ee7eea4b0193eb7b00003c", "pending_op_groups._id"=>"50ee89ae4b01939126000014", "pending_op_groups.0.pending_ops._id"=>"50ee89ae4b0193912600001b"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:rolledback}} flags=[] (15.6198ms) (pid:9873)
>2013-01-10 04:28:17.122 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50ee7eea4b0193eb7b00003c"} update={"$pull"=>{"pending_op_groups"=>{"_id"=>"50ee89ae4b01939126000014"}}} flags=[] (0.3023ms) (pid:9873)
>2013-01-10 04:28:17.136 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50ee7da54b0193e56c00003e", "locked"=>false, "app_ids"=>{"$in"=>["50ee7eea4b0193eb7b00003c"]}}, :new=>false, :update=>{"$pop"=>{:app_ids=>"50ee7eea4b0193eb7b00003c"}}} (0.8252ms) (pid:9873)
>2013-01-10 04:28:17.136 [INFO ] Completed 500 Internal Server Error in 2432ms (pid:9873)
>2013-01-10 04:28:17.175 [FATAL] NameError (undefined local variable or method `result' for #<ResultIO:0x00000006c33718>):
>  lib/express/broker/mcollective_ext.rb:12:in `run_cartridge_command' (pid:9873)
>2013-01-10 04:28:17.287 [INFO ] Rendered /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.9ms) (pid:9873)

Actions: View

Attachments on bug 893917: 676185 | 676186