Attachment 716989 Details for Bug 919379 – development.log

development.log

file_919379.txt (text/plain), 58.20 KB, created by Rony Gong 🔥 on 2013-03-27 10:08:06 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rony Gong 🔥

Created: 2013-03-27 10:08:06 UTC

Size: 58.20 KB

patch

obsolete

>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (34.2062ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :sparse=>true, :ns=>"openshift_broker_dev.applications", :key=>{:"group_instances.gears.uuid"=>1}, :name=>"group_instances.gears.uuid_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7041ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:ns=>"openshift_broker_dev.applications", :key=>{:domain_id=>1}, :name=>"domain_id_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5684ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.domains", :key=>{:canonical_namespace=>1}, :name=>"canonical_namespace_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5553ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:ns=>"openshift_broker_dev.domains", :key=>{:owner_id=>1}, :name=>"owner_id_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (14.5664ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:ns=>"openshift_broker_dev.domains", :key=>{:user_ids=>1}, :name=>"user_ids_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5441ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.cloud_users", :key=>{:login=>1}, :name=>"login_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5531ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:ns=>"openshift_broker_dev.usage_records", :key=>{:gear_id=>1}, :name=>"gear_id_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7327ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:ns=>"openshift_broker_dev.usage", :key=>{:gear_id=>1}, :name=>"gear_id_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5605ms)
>  MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.districts", :key=>{:name=>1}, :name=>"name_1"}] flags=[]
>                         COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7141ms)
>2013-03-27 06:05:00.928 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:ns=>"openshift_broker_dev.locks", :key=>{:user_id=>1}, :name=>"user_id_1"}] flags=[] (pid:6186)
>2013-03-27 06:05:00.928 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.0099ms) (pid:6186)
>2013-03-27 06:05:01.492 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"pending_op_groups.created_at"=>{"$lt"=>2013-03-27 10:05:01 UTC}} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (4.1530ms) (pid:6186)
>2013-03-27 06:05:01.500 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"5152b859696513c001000005"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (0.4110ms) (pid:6186)
>2013-03-27 06:05:01.512 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"5152b84f696513c001000001", "$or"=>[{"app_ids.5152c3b369651364ed000004"=>{"$exists"=>false}}, {"app_ids.5152c3b369651364ed000004"=>{"$lt"=>1364378701}}]}, :new=>true, :update=>{"$set"=>{"app_ids.5152c3b369651364ed000004"=>1364379301}}} (5.0058ms) (pid:6186)
>2013-03-27 06:05:01.521 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"5152c3b369651364ed000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (6.4549ms) (pid:6186)
>2013-03-27 06:05:01.561 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"5152c3b369651364ed000006", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}], "min_gears"=>1.0, "max_gears"=>1.0}]}, "op_type"=>"set_group_overrides", "retry_count"=>0.0, "saved_values"=>{"group_overrides"=>[]}, "state"=>"completed"}, {"_id"=>"5152c3b369651364ed000008", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012"}, "op_type"=>"create_group_instance", "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed000009", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>"init_gear", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000a", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"reserve_uid", "prereq"=>["5152c3b369651364ed000009"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000b", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"create_gear", "prereq"=>["5152c3b369651364ed00000a"], "retry_count"=>0.0, "retry_rollback_op"=>"5152c3b369651364ed00000a", "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000e", "args"=>{"user_id"=>"5152b84f696513c001000001", "parent_user_id"=>nil, "app_name"=>"qperl", "gear_ref"=>"5152c3b369651364ed000004", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>"track_usage", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000c", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"register_dns", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000f", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"update_configuration", "args"=>{"add_keys_attrs"=>[{"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}, {"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "prereq"=>["5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed00000d", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}}, "op_type"=>"new_component", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed000010", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"add_component", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}, "init_git_url"=>nil}, "prereq"=>["5152c3b369651364ed00000d", "5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed000011", "state"=>"init", "retry_count"=>0.0, "saved_values"=>{"connections"=>[]}, "op_type"=>"set_connections", "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "to_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "from_connector_name"=>"get-proxy-url", "to_connector_name"=>"set-proxy-path", "connection_type"=>"STRING:proxy-path"}]}, "prereq"=>["5152c3b369651364ed000006", "5152c3b369651364ed000008", "5152c3b369651364ed000009", "5152c3b369651364ed00000a", "5152c3b369651364ed00000b", "5152c3b369651364ed00000e", "5152c3b369651364ed00000c", "5152c3b369651364ed00000f", "5152c3b369651364ed00000d", "5152c3b369651364ed000010"]}, {"_id"=>"5152c3b369651364ed000013", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"execute_connections", "prereq"=>["5152c3b369651364ed000011"]}]}} flags=[] (pid:6186)
>2013-03-27 06:05:01.562 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (4.4856ms) (pid:6186)
>2013-03-27 06:05:01.594 [DEBUG] Execute set_group_overrides (pid:6186)
>2013-03-27 06:05:01.635 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:completed}} flags=[] (pid:6186)
>2013-03-27 06:05:01.636 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.2084ms) (pid:6186)
>2013-03-27 06:05:01.637 [DEBUG] Execute create_group_instance (pid:6186)
>2013-03-27 06:05:01.812 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$push"=>{"group_instances"=>{"_id"=>"5152c3b369651364ed000012"}}} flags=[] (pid:6186)
>2013-03-27 06:05:01.813 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (172.1694ms) (pid:6186)
>2013-03-27 06:05:01.818 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:completed}} flags=[] (pid:6186)
>2013-03-27 06:05:01.819 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.3772ms) (pid:6186)
>2013-03-27 06:05:01.826 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"5152c3b369651364ed000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (5.2812ms) (pid:6186)
>2013-03-27 06:05:01.865 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"5152c3b369651364ed000006", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}], "min_gears"=>1.0, "max_gears"=>1.0}]}, "op_type"=>"set_group_overrides", "retry_count"=>0.0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"5152c3b369651364ed000008", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012"}, "op_type"=>"create_group_instance", "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000009", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>"init_gear", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000a", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"reserve_uid", "prereq"=>["5152c3b369651364ed000009"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000b", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"create_gear", "prereq"=>["5152c3b369651364ed00000a"], "retry_count"=>0.0, "retry_rollback_op"=>"5152c3b369651364ed00000a", "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000e", "args"=>{"user_id"=>"5152b84f696513c001000001", "parent_user_id"=>nil, "app_name"=>"qperl", "gear_ref"=>"5152c3b369651364ed000004", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>"track_usage", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000c", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"register_dns", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000f", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"update_configuration", "args"=>{"add_keys_attrs"=>[{"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}, {"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "prereq"=>["5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed00000d", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}}, "op_type"=>"new_component", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed000010", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"add_component", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}, "init_git_url"=>nil}, "prereq"=>["5152c3b369651364ed00000d", "5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed000011", "state"=>"init", "retry_count"=>0.0, "saved_values"=>{"connections"=>[]}, "op_type"=>"set_connections", "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "to_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "from_connector_name"=>"get-proxy-url", "to_connector_name"=>"set-proxy-path", "connection_type"=>"STRING:proxy-path"}]}, "prereq"=>["5152c3b369651364ed000006", "5152c3b369651364ed000008", "5152c3b369651364ed000009", "5152c3b369651364ed00000a", "5152c3b369651364ed00000b", "5152c3b369651364ed00000e", "5152c3b369651364ed00000c", "5152c3b369651364ed00000f", "5152c3b369651364ed00000d", "5152c3b369651364ed000010"]}, {"_id"=>"5152c3b369651364ed000013", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"execute_connections", "prereq"=>["5152c3b369651364ed000011"]}]}} flags=[] (pid:6186)
>2013-03-27 06:05:01.866 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (5.6472ms) (pid:6186)
>2013-03-27 06:05:01.896 [DEBUG] Execute init_gear (pid:6186)
>2013-03-27 06:05:01.913 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"5152c3b369651364ed000004", "uuid"=>"5152c3b36965130978000001", "name"=>"qperl", "host_singletons"=>true, "app_dns"=>true}}} flags=[] (pid:6186)
>2013-03-27 06:05:01.913 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.1916ms) (pid:6186)
>2013-03-27 06:05:01.941 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:completed}} flags=[] (pid:6186)
>2013-03-27 06:05:01.941 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7005ms) (pid:6186)
>2013-03-27 06:05:01.942 [DEBUG] Execute new_component (pid:6186)
>2013-03-27 06:05:01.950 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$push"=>{"component_instances"=>{"_id"=>"5152c44d696513dbec000003", "component_properties"=>{}, "cartridge_name"=>"perl-5.10", "component_name"=>"perl-5.10", "group_instance_id"=>"5152c3b369651364ed000012"}}} flags=[] (pid:6186)
>2013-03-27 06:05:01.956 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7949ms) (pid:6186)
>2013-03-27 06:05:01.957 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.8.state"=>:completed}} flags=[] (pid:6186)
>2013-03-27 06:05:01.958 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7396ms) (pid:6186)
>2013-03-27 06:05:01.964 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"5152c3b369651364ed000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.4584ms) (pid:6186)
>2013-03-27 06:05:02.247 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"5152c3b369651364ed000006", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}], "min_gears"=>1.0, "max_gears"=>1.0}]}, "op_type"=>"set_group_overrides", "retry_count"=>0.0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"5152c3b369651364ed000008", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012"}, "op_type"=>"create_group_instance", "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000009", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>"init_gear", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed00000a", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"reserve_uid", "prereq"=>["5152c3b369651364ed000009"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000b", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"create_gear", "prereq"=>["5152c3b369651364ed00000a"], "retry_count"=>0.0, "retry_rollback_op"=>"5152c3b369651364ed00000a", "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000e", "args"=>{"user_id"=>"5152b84f696513c001000001", "parent_user_id"=>nil, "app_name"=>"qperl", "gear_ref"=>"5152c3b369651364ed000004", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>"track_usage", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000c", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"register_dns", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000f", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"update_configuration", "args"=>{"add_keys_attrs"=>[{"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}, {"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "prereq"=>["5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed00000d", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}}, "op_type"=>"new_component", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000010", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"add_component", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}, "init_git_url"=>nil}, "prereq"=>["5152c3b369651364ed00000d", "5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed000011", "state"=>"init", "retry_count"=>0.0, "saved_values"=>{"connections"=>[]}, "op_type"=>"set_connections", "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "to_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "from_connector_name"=>"get-proxy-url", "to_connector_name"=>"set-proxy-path", "connection_type"=>"STRING:proxy-path"}]}, "prereq"=>["5152c3b369651364ed000006", "5152c3b369651364ed000008", "5152c3b369651364ed000009", "5152c3b369651364ed00000a", "5152c3b369651364ed00000b", "5152c3b369651364ed00000e", "5152c3b369651364ed00000c", "5152c3b369651364ed00000f", "5152c3b369651364ed00000d", "5152c3b369651364ed000010"]}, {"_id"=>"5152c3b369651364ed000013", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"execute_connections", "prereq"=>["5152c3b369651364ed000011"]}]}} flags=[] (pid:6186)
>2013-03-27 06:05:02.329 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (7.5688ms) (pid:6186)
>2013-03-27 06:05:02.296 [DEBUG] Execute reserve_uid (pid:6186)
>2013-03-27 06:05:02.346 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=districts selector={} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (3.7796ms) (pid:6186)
>2013-03-27 06:05:02.369 [DEBUG] DEBUG: rpc_get_fact: fact=active_capacity (pid:6186)
>2013-03-27 06:05:02.594 [DEBUG] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x000000052aae90> (pid:6186)
>2013-03-27 06:05:04.757 [DEBUG] Current server: ip-10-151-108-218 active capacity: 1.0 (pid:6186)
>2013-03-27 06:05:04.758 [DEBUG] DEBUG: find_available_impl: current_server: ip-10-151-108-218: 1.0 (pid:6186)
>2013-03-27 06:05:04.760 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>"ip-10-151-108-218"}} flags=[] (pid:6186)
>2013-03-27 06:05:04.760 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.0254ms) (pid:6186)
>2013-03-27 06:05:04.832 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (pid:6186)
>2013-03-27 06:05:04.833 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.4895ms) (pid:6186)
>2013-03-27 06:05:04.834 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:completed}} flags=[] (pid:6186)
>2013-03-27 06:05:04.834 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7043ms) (pid:6186)
>2013-03-27 06:05:04.838 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"5152c3b369651364ed000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.4098ms) (pid:6186)
>2013-03-27 06:05:04.850 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"5152c3b369651364ed000006", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}], "min_gears"=>1.0, "max_gears"=>1.0}]}, "op_type"=>"set_group_overrides", "retry_count"=>0.0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"5152c3b369651364ed000008", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012"}, "op_type"=>"create_group_instance", "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000009", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>"init_gear", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed00000a", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"reserve_uid", "prereq"=>["5152c3b369651364ed000009"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed00000b", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"create_gear", "prereq"=>["5152c3b369651364ed00000a"], "retry_count"=>0.0, "retry_rollback_op"=>"5152c3b369651364ed00000a", "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000e", "args"=>{"user_id"=>"5152b84f696513c001000001", "parent_user_id"=>nil, "app_name"=>"qperl", "gear_ref"=>"5152c3b369651364ed000004", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>"track_usage", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000c", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"register_dns", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000f", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"update_configuration", "args"=>{"add_keys_attrs"=>[{"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}, {"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "prereq"=>["5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed00000d", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}}, "op_type"=>"new_component", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000010", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"add_component", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}, "init_git_url"=>nil}, "prereq"=>["5152c3b369651364ed00000d", "5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed000011", "state"=>"init", "retry_count"=>0.0, "saved_values"=>{"connections"=>[]}, "op_type"=>"set_connections", "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "to_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "from_connector_name"=>"get-proxy-url", "to_connector_name"=>"set-proxy-path", "connection_type"=>"STRING:proxy-path"}]}, "prereq"=>["5152c3b369651364ed000006", "5152c3b369651364ed000008", "5152c3b369651364ed000009", "5152c3b369651364ed00000a", "5152c3b369651364ed00000b", "5152c3b369651364ed00000e", "5152c3b369651364ed00000c", "5152c3b369651364ed00000f", "5152c3b369651364ed00000d", "5152c3b369651364ed000010"]}, {"_id"=>"5152c3b369651364ed000013", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"execute_connections", "prereq"=>["5152c3b369651364ed000011"]}]}} flags=[] (pid:6186)
>2013-03-27 06:05:04.851 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.5709ms) (pid:6186)
>2013-03-27 06:05:04.859 [DEBUG] Execute create_gear (pid:6186)
>2013-03-27 06:05:04.862 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"5152c3b36965130978000001", "--with-app-name"=>"qperl", "--with-container-uuid"=>"5152c3b36965130978000001", "--with-container-name"=>"qperl", "--with-namespace"=>"qgong1", "--with-request-id"=>nil, "--cart-name"=>"openshift-origin-node"}}, ip-10-151-108-218, {'identity' => ip-10-151-108-218}) (Request ID: ) (pid:6186)
>2013-03-27 06:05:04.991 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000050564f0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-151-108-218", :statuscode=>1, :statusmsg=>"cartridge_do_action failed 129. Output ERROR: unable to create user account(9): useradd -u 501 -d /var/lib/openshift/5152c3b36965130978000001/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 5152c3b36965130978000001 stdout: useradd: user '5152c3b36965130978000001' already exists\n stderr: useradd: user '5152c3b36965130978000001' already exists\n", :data=>{:time=>nil, :output=>"ERROR: unable to create user account(9): useradd -u 501 -d /var/lib/openshift/5152c3b36965130978000001/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 5152c3b36965130978000001 stdout: useradd: user '5152c3b36965130978000001' already exists\n stderr: useradd: user '5152c3b36965130978000001' already exists\n", :exitcode=>129}}>] (Request ID: ) (pid:6186)
>2013-03-27 06:05:04.994 [DEBUG] DEBUG: MCollective Response Time (execute_direct: app-create): 0.134702548s  (Request ID: ) (pid:6186)
>2013-03-27 06:05:04.994 [DEBUG] DEBUG: server results: ERROR: unable to create user account(9): useradd -u 501 -d /var/lib/openshift/5152c3b36965130978000001/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 5152c3b36965130978000001 stdout: useradd: user '5152c3b36965130978000001' already exists (pid:6186)
>2013-03-27 06:05:04.995 [DEBUG] DEBUG: server results:  stderr: useradd: user '5152c3b36965130978000001' already exists (pid:6186)
>2013-03-27 06:05:04.995 [ERROR] Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:6186)
>2013-03-27 06:05:04.995 [ERROR] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.6.5/lib/openshift/mcollective_application_container_proxy.rb:2535:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.6.5/lib/openshift/mcollective_application_container_proxy.rb:399:in `block in create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.6.5/lib/openshift/mcollective_application_container_proxy.rb:396:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.6.5/lib/openshift/mcollective_application_container_proxy.rb:396:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.6.7/app/models/gear.rb:76:in `create_gear'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.6.7/app/models/pending_app_op_group.rb:189:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.6.7/app/models/pending_app_op_group.rb:146:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.6.7/app/models/pending_app_op_group.rb:146:in `execute'", "/usr/sbin/oo-admin-clear-pending-ops:72:in `block (2 levels) in clean_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.6.7/app/models/application.rb:1146:in `run_in_application_lock'", "/usr/sbin/oo-admin-clear-pending-ops:69:in `block in clean_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/relations/proxy.rb:143:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/relations/proxy.rb:143:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/relations/embedded/many.rb:396:in `method_missing'", "/usr/sbin/oo-admin-clear-pending-ops:65:in `clean_app'", "/usr/sbin/oo-admin-clear-pending-ops:159:in `block in <main>'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/contextual/mongo.rb:577:in `yield_document'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/contextual/mongo.rb:133:in `block (2 levels) in each'", "/opt/rh/ruby193/root/usr/share/gems/gems/moped-1.3.2/lib/moped/query.rb:78:in `block in each'", "/opt/rh/ruby193/root/usr/share/gems/gems/moped-1.3.2/lib/moped/cursor.rb:26:in `block in each'", "/opt/rh/ruby193/root/usr/share/gems/gems/moped-1.3.2/lib/moped/cursor.rb:26:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/moped-1.3.2/lib/moped/cursor.rb:26:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/moped-1.3.2/lib/moped/query.rb:77:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/moped-1.3.2/lib/moped/query.rb:77:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/contextual/mongo.rb:132:in `block in each'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/contextual/mongo.rb:556:in `selecting'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/contextual/mongo.rb:131:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/contextual.rb:18:in `each'", "/usr/sbin/oo-admin-clear-pending-ops:157:in `<main>'"] (pid:6186)
>2013-03-27 06:05:04.999 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"5152c3b369651364ed000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.9460ms) (pid:6186)
>2013-03-27 06:05:05.203 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"5152c3b369651364ed000006", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}], "min_gears"=>1.0, "max_gears"=>1.0}]}, "op_type"=>"set_group_overrides", "retry_count"=>0.0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"5152c3b369651364ed000008", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012"}, "op_type"=>"create_group_instance", "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000009", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>"init_gear", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed00000a", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"reserve_uid", "prereq"=>["5152c3b369651364ed000009"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed00000b", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"create_gear", "prereq"=>["5152c3b369651364ed00000a"], "retry_count"=>0.0, "retry_rollback_op"=>"5152c3b369651364ed00000a", "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000e", "args"=>{"user_id"=>"5152b84f696513c001000001", "parent_user_id"=>nil, "app_name"=>"qperl", "gear_ref"=>"5152c3b369651364ed000004", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>"track_usage", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000c", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"register_dns", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000f", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"update_configuration", "args"=>{"add_keys_attrs"=>[{"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}, {"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "prereq"=>["5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed00000d", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}}, "op_type"=>"new_component", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000010", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"add_component", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}, "init_git_url"=>nil}, "prereq"=>["5152c3b369651364ed00000d", "5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed000011", "state"=>"init", "retry_count"=>0.0, "saved_values"=>{"connections"=>[]}, "op_type"=>"set_connections", "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "to_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "from_connector_name"=>"get-proxy-url", "to_connector_name"=>"set-proxy-path", "connection_type"=>"STRING:proxy-path"}]}, "prereq"=>["5152c3b369651364ed000006", "5152c3b369651364ed000008", "5152c3b369651364ed000009", "5152c3b369651364ed00000a", "5152c3b369651364ed00000b", "5152c3b369651364ed00000e", "5152c3b369651364ed00000c", "5152c3b369651364ed00000f", "5152c3b369651364ed00000d", "5152c3b369651364ed000010"]}, {"_id"=>"5152c3b369651364ed000013", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"execute_connections", "prereq"=>["5152c3b369651364ed000011"]}]}} flags=[] (pid:6186)
>2013-03-27 06:05:05.214 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.7537ms) (pid:6186)
>2013-03-27 06:05:05.302 [DEBUG] Rollback set_group_overrides (pid:6186)
>2013-03-27 06:05:05.367 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"group_overrides"=>[], "updated_at"=>2013-03-27 10:05:05 UTC}} flags=[] (pid:6186)
>2013-03-27 06:05:05.369 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6809ms) (pid:6186)
>2013-03-27 06:05:05.386 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:rolledback}} flags=[] (pid:6186)
>2013-03-27 06:05:05.388 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6649ms) (pid:6186)
>2013-03-27 06:05:05.389 [DEBUG] Rollback reserve_uid (pid:6186)
>2013-03-27 06:05:05.113 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>nil}} flags=[] (pid:6186)
>2013-03-27 06:05:05.113 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6962ms) (pid:6186)
>2013-03-27 06:05:05.114 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (pid:6186)
>2013-03-27 06:05:05.114 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5803ms) (pid:6186)
>2013-03-27 06:05:05.116 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:rolledback}} flags=[] (pid:6186)
>2013-03-27 06:05:05.116 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6390ms) (pid:6186)
>2013-03-27 06:05:05.116 [DEBUG] Rollback new_component (pid:6186)
>2013-03-27 06:05:05.120 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$pull"=>{"component_instances"=>{"_id"=>"5152c3b369651364ed000016"}}} flags=[] (pid:6186)
>2013-03-27 06:05:05.120 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.9128ms) (pid:6186)
>2013-03-27 06:05:05.121 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.8.state"=>:rolledback}} flags=[] (pid:6186)
>2013-03-27 06:05:05.121 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7186ms) (pid:6186)
>2013-03-27 06:05:05.124 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"5152c3b369651364ed000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.2429ms) (pid:6186)
>2013-03-27 06:05:05.134 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"5152c3b369651364ed000006", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}], "min_gears"=>1.0, "max_gears"=>1.0}]}, "op_type"=>"set_group_overrides", "retry_count"=>0.0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:rolledback}, {"_id"=>"5152c3b369651364ed000008", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012"}, "op_type"=>"create_group_instance", "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000009", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>"init_gear", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed00000a", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"reserve_uid", "prereq"=>["5152c3b369651364ed000009"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"5152c3b369651364ed00000b", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"create_gear", "prereq"=>["5152c3b369651364ed00000a"], "retry_count"=>0.0, "retry_rollback_op"=>"5152c3b369651364ed00000a", "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000e", "args"=>{"user_id"=>"5152b84f696513c001000001", "parent_user_id"=>nil, "app_name"=>"qperl", "gear_ref"=>"5152c3b369651364ed000004", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>"track_usage", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000c", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"register_dns", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000f", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"update_configuration", "args"=>{"add_keys_attrs"=>[{"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}, {"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "prereq"=>["5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed00000d", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}}, "op_type"=>"new_component", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"5152c3b369651364ed000010", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"add_component", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}, "init_git_url"=>nil}, "prereq"=>["5152c3b369651364ed00000d", "5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed000011", "state"=>"init", "retry_count"=>0.0, "saved_values"=>{"connections"=>[]}, "op_type"=>"set_connections", "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "to_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "from_connector_name"=>"get-proxy-url", "to_connector_name"=>"set-proxy-path", "connection_type"=>"STRING:proxy-path"}]}, "prereq"=>["5152c3b369651364ed000006", "5152c3b369651364ed000008", "5152c3b369651364ed000009", "5152c3b369651364ed00000a", "5152c3b369651364ed00000b", "5152c3b369651364ed00000e", "5152c3b369651364ed00000c", "5152c3b369651364ed00000f", "5152c3b369651364ed00000d", "5152c3b369651364ed000010"]}, {"_id"=>"5152c3b369651364ed000013", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"execute_connections", "prereq"=>["5152c3b369651364ed000011"]}]}} flags=[] (pid:6186)
>2013-03-27 06:05:05.135 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.3862ms) (pid:6186)
>2013-03-27 06:05:05.140 [DEBUG] Rollback init_gear (pid:6186)
>2013-03-27 06:05:05.142 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$pull"=>{"group_instances.0.gears"=>{"_id"=>"5152c3b369651364ed000004"}}} flags=[] (pid:6186)
>2013-03-27 06:05:05.142 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7007ms) (pid:6186)
>2013-03-27 06:05:05.147 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:rolledback}} flags=[] (pid:6186)
>2013-03-27 06:05:05.147 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6280ms) (pid:6186)
>2013-03-27 06:05:05.150 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"5152c3b369651364ed000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.2102ms) (pid:6186)
>2013-03-27 06:05:05.160 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"5152c3b369651364ed000006", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}], "min_gears"=>1.0, "max_gears"=>1.0}]}, "op_type"=>"set_group_overrides", "retry_count"=>0.0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:rolledback}, {"_id"=>"5152c3b369651364ed000008", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012"}, "op_type"=>"create_group_instance", "retry_count"=>0.0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"5152c3b369651364ed000009", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>"init_gear", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"5152c3b369651364ed00000a", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"reserve_uid", "prereq"=>["5152c3b369651364ed000009"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"5152c3b369651364ed00000b", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"create_gear", "prereq"=>["5152c3b369651364ed00000a"], "retry_count"=>0.0, "retry_rollback_op"=>"5152c3b369651364ed00000a", "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000e", "args"=>{"user_id"=>"5152b84f696513c001000001", "parent_user_id"=>nil, "app_name"=>"qperl", "gear_ref"=>"5152c3b369651364ed000004", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>"track_usage", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000c", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "op_type"=>"register_dns", "prereq"=>["5152c3b369651364ed00000b"], "retry_count"=>0.0, "saved_values"=>[], "state"=>"completed"}, {"_id"=>"5152c3b369651364ed00000f", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"update_configuration", "args"=>{"add_keys_attrs"=>[{"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}, {"_id"=>"5152b855696513c001000004", "type"=>"ssh-rsa", "name"=>"5152b84f696513c001000001-default", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA8AFi9s4d2BvxnoiilfJyExZl6CZg+HXcwAMPihBZGnlBWdLNT1qETUT5LoVv8f9b3YIyL5UNUOvZE9l6eRZ8C3PeXATnuqd1lcHSedTDbZMMGvPbIt47vmiUZ+YBx8Ssno5AFnEYx33TAYoSO6pKoRbmiu7N/LacsAfxBKlHqv/CamtaGejwHv1R8FfXDLrh+KeYG5f4RBlEhY5TPBIYGwPimAI7sSl4jMxI9WLhIT57gwXbWsR8aG3GDmNiFSGuz3ox+jAl7rz2h/6ocAL6boL5QoxJinNvl1WTrfcaqGnCok8hTh7li679XVgyPto0efXlbTNN/qZOZA+gyRR+oQ==", "_type"=>"UserSshKey"}], "add_env_vars"=>[], "group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004"}, "prereq"=>["5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed00000d", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}}, "op_type"=>"new_component", "prereq"=>["5152c3b369651364ed000008"], "retry_count"=>0.0, "saved_values"=>[], "state"=>:rolledback}, {"_id"=>"5152c3b369651364ed000010", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"add_component", "args"=>{"group_instance_id"=>"5152c3b369651364ed000012", "gear_id"=>"5152c3b369651364ed000004", "comp_spec"=>{"comp"=>"perl-5.10", "cart"=>"perl-5.10"}, "init_git_url"=>nil}, "prereq"=>["5152c3b369651364ed00000d", "5152c3b369651364ed00000c"]}, {"_id"=>"5152c3b369651364ed000011", "state"=>"init", "retry_count"=>0.0, "saved_values"=>{"connections"=>[]}, "op_type"=>"set_connections", "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "to_comp_inst"=>{"cart"=>"perl-5.10", "comp"=>"perl-5.10"}, "from_connector_name"=>"get-proxy-url", "to_connector_name"=>"set-proxy-path", "connection_type"=>"STRING:proxy-path"}]}, "prereq"=>["5152c3b369651364ed000006", "5152c3b369651364ed000008", "5152c3b369651364ed000009", "5152c3b369651364ed00000a", "5152c3b369651364ed00000b", "5152c3b369651364ed00000e", "5152c3b369651364ed00000c", "5152c3b369651364ed00000f", "5152c3b369651364ed00000d", "5152c3b369651364ed000010"]}, {"_id"=>"5152c3b369651364ed000013", "state"=>"init", "retry_count"=>0.0, "saved_values"=>[], "op_type"=>"execute_connections", "prereq"=>["5152c3b369651364ed000011"]}]}} flags=[] (pid:6186)
>2013-03-27 06:05:05.160 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.3645ms) (pid:6186)
>2013-03-27 06:05:05.213 [DEBUG] Rollback create_group_instance (pid:6186)
>2013-03-27 06:05:05.215 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$pull"=>{"group_instances"=>{"_id"=>"5152c3b369651364ed000012"}}} flags=[] (pid:6186)
>2013-03-27 06:05:05.215 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9081ms) (pid:6186)
>2013-03-27 06:05:05.217 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:rolledback}} flags=[] (pid:6186)
>2013-03-27 06:05:05.217 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5994ms) (pid:6186)
>2013-03-27 06:05:05.219 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"5152b84f696513c001000001", "locked"=>false, "app_ids.5152c3b369651364ed000004"=>{"$exists"=>true}}, :new=>true, :update=>{"$unset"=>{"app_ids.5152c3b369651364ed000004"=>""}}} (0.7675ms) (pid:6186)
>2013-03-27 06:05:05.220 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"5152c3b369651364ed000004"} update={"$pull"=>{"pending_op_groups"=>{"_id"=>"5152c3b369651364ed000005"}}} flags=[] (pid:6186)
>2013-03-27 06:05:05.220 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6244ms) (pid:6186)
>2013-03-27 06:05:05.223 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"pending_ops.created_at"=>{"$lt"=>2013-03-27 10:05:01 UTC}} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.4010ms) (pid:6186)
>2013-03-27 06:05:05.224 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"pending_ops.created_at"=>{"$lt"=>2013-03-27 10:05:01 UTC}} flags=[:slave_ok] limit=0 skip=0 batch_size=nil fields=nil (0.3734ms) (pid:6186)

Actions: View

Attachments on bug 919379: 716989 | 716990 | 716992