Attachment 754779 Details for Bug 967504 – whole broker log

whole broker log

development.log (text/x-log), 188.69 KB, created by Xiaoli Tian on 2013-05-30 10:57:43 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Xiaoli Tian

Created: 2013-05-30 10:57:43 UTC

Size: 188.69 KB

patch

obsolete

>
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (2.4996ms)
>  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.6926ms)
>  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.4601ms)
>  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.8430ms)
>  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} (0.4401ms)
>  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.4199ms)
>  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} (4.3867ms)
>  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.8776ms)
>  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.5608ms)
>  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.9298ms)
>[0;37m2013-05-30 02:06:38.155[0m [[0;37mDEBUG[0m] 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:4839)
>[0;37m2013-05-30 02:06:38.155[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9017ms) (pid:4839)
>[0;37m2013-05-30 02:06:38.384[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:4839)
>[0;37m2013-05-30 02:06:38.384[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8237ms) (pid:4839)
>[0;37m2013-05-30 02:06:38.560[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11211 (pid:4839)
>[0;37m2013-05-30 02:06:38.563[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 02:06:38 -0400 (pid:4839)
>[0;37m2013-05-30 02:06:38.703[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:4839)
>[0;37m2013-05-30 02:06:38.965[0m [[32mINFO [0m] Completed 200 OK in 262ms (Views: 5.0ms) (pid:4839)
>[0;37m2013-05-30 02:06:38.968[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 02:06:38 -0400 (pid:4839)
>[0;37m2013-05-30 02:06:38.971[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:4839)
>[0;37m2013-05-30 02:06:38.980[0m [[32mINFO [0m] Completed 200 OK in 8ms (Views: 4.5ms) (pid:4839)
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (2.0249ms)
>  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.6781ms)
>  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.4516ms)
>  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.7887ms)
>  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} (0.4239ms)
>  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.4201ms)
>  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.7739ms)
>  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.7772ms)
>  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.5217ms)
>  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.7124ms)
>[0;37m2013-05-30 02:06:46.526[0m [[0;37mDEBUG[0m] 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:4944)
>[0;37m2013-05-30 02:06:46.527[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8500ms) (pid:4944)
>[0;37m2013-05-30 02:06:46.719[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:4944)
>[0;37m2013-05-30 02:06:46.719[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9091ms) (pid:4944)
>[0;37m2013-05-30 02:06:53.531[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11211 (pid:4944)
>[0;37m2013-05-30 02:06:53.532[0m [[32mINFO [0m] Started GET "/broker/rest/cartridges/standalone" for 127.0.0.1 at 2013-05-30 02:06:53 -0400 (pid:4944)
>[0;37m2013-05-30 02:06:53.670[0m [[32mINFO [0m] Processing by CartridgesController#show as */* (pid:4944)
>[0;37m2013-05-30 02:06:53.670[0m [[32mINFO [0m] Parameters: {"id"=>"standalone"} (pid:4944)
>[0;37m2013-05-30 02:06:53.671[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11212 (pid:4944)
>[0;37m2013-05-30 02:06:56.185[0m [[0;37mDEBUG[0m] DEBUG: find_one_impl: current_server: ip-10-154-131-112 (pid:4944)
>[0;37m2013-05-30 02:06:56.189[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"cartridge-list", :args=>{"--porcelain"=>true, "--with-descriptors"=>true, "--cart-name"=>"openshift-origin-node"}}, ip-10-154-131-112, {'identity' => ip-10-154-131-112}) (Request ID: ce0ab2897ab0311f4111aa2ce52bb12e) (pid:4944)
>[0;37m2013-05-30 02:06:56.540[0m [[0;37mDEBUG[0m] SUCCESS ACTION=SHOW_CARTRIDGE USER_ID= LOGIN= List web_framework cartridges (pid:4944)
>[0;37m2013-05-30 02:06:56.669[0m [[32mINFO [0m] Completed 200 OK in 2999ms (Views: 9.0ms) (pid:4944)
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (8.3683ms)
>  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} (5.9867ms)
>  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.4992ms)
>  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} (3.0258ms)
>  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} (1.8735ms)
>  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} (2.3608ms)
>  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} (6.4044ms)
>  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} (4.6635ms)
>  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} (3.1688ms)
>  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.7401ms)
>[0;37m2013-05-30 05:06:01.956[0m [[0;37mDEBUG[0m] 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:1847)
>[0;37m2013-05-30 05:06:01.957[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (6.2249ms) (pid:1847)
>[0;37m2013-05-30 05:06:03.283[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:1847)
>[0;37m2013-05-30 05:06:03.283[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8342ms) (pid:1847)
>[0;37m2013-05-30 05:06:04.895[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11211 (pid:1847)
>[0;37m2013-05-30 05:06:04.107[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 05:06:04 -0400 (pid:1847)
>[0;37m2013-05-30 05:06:04.583[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:1847)
>[0;37m2013-05-30 05:06:05.297[0m [[32mINFO [0m] Completed 200 OK in 714ms (Views: 29.5ms) (pid:1847)
>[0;37m2013-05-30 05:06:05.309[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 05:06:05 -0400 (pid:1847)
>[0;37m2013-05-30 05:06:05.322[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:1847)
>[0;37m2013-05-30 05:06:05.364[0m [[32mINFO [0m] Completed 200 OK in 42ms (Views: 22.7ms) (pid:1847)
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (4.9112ms)
>  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.7420ms)
>  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} (3.3288ms)
>  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.7989ms)
>  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} (0.4418ms)
>  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.4380ms)
>  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} (3.9244ms)
>  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.8240ms)
>  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.6604ms)
>  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} (3.9220ms)
>[0;37m2013-05-30 05:06:24.520[0m [[0;37mDEBUG[0m] 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:2291)
>[0;37m2013-05-30 05:06:24.520[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9577ms) (pid:2291)
>[0;37m2013-05-30 05:06:24.900[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:2291)
>[0;37m2013-05-30 05:06:24.900[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.7158ms) (pid:2291)
>[0;37m2013-05-30 05:07:25.373[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11212 (pid:2291)
>[0;37m2013-05-30 05:07:25.375[0m [[32mINFO [0m] Started POST "/broker/rest/user/authorizations.json" for 127.0.0.1 at 2013-05-30 05:07:25 -0400 (pid:2291)
>[0;37m2013-05-30 05:07:25.522[0m [[32mINFO [0m] Processing by AuthorizationsController#create as JSON (pid:2291)
>[0;37m2013-05-30 05:07:25.522[0m [[32mINFO [0m] Parameters: {"note"=>"OpenShift Console (from 203.114.244.88 on Firefox)", "reuse"=>true, "scope"=>"session", "authorization"=>{"note"=>"OpenShift Console (from 203.114.244.88 on Firefox)"}} (pid:2291)
>[0;37m2013-05-30 05:07:27.234[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"aaa"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1484.4840ms) (pid:2291)
>[0;37m2013-05-30 05:07:27.252[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=cloud_users documents=[{"_id"=>"51a716cf4efd7f06fc000001", "plan_history"=>[], "consumed_gears"=>0, "plan_id"=>"free", "plan_state"=>"ACTIVE", "capabilities"=>{"subaccounts"=>false, "gear_sizes"=>["small"], "max_gears"=>3, "plan_upgrade_enabled"=>true}, "login"=>"aaa", "updated_at"=>2013-05-30 09:07:27 UTC, "created_at"=>2013-05-30 09:07:27 UTC}] flags=[] (pid:2291)
>[0;37m2013-05-30 05:07:27.252[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.1580ms) (pid:2291)
>[0;37m2013-05-30 05:07:27.291[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"51a716cf4efd7f06fc000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (38.2540ms) (pid:2291)
>[0;37m2013-05-30 05:07:27.294[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=locks documents=[{"_id"=>"51a716cf4efd7f06fc000002", "timeout"=>0, "app_ids"=>{}, "user_id"=>"51a716cf4efd7f06fc000001", "locked"=>false}] flags=[] (pid:2291)
>[0;37m2013-05-30 05:07:27.294[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6361ms) (pid:2291)
>[0;37m2013-05-30 05:07:27.336[0m [[0;37mDEBUG[0m] SUCCESS ACTION=CREATE_USER USER_ID= LOGIN= USER=51a716cf4efd7f06fc000001 LOGIN=aaa PROVIDER= Creating user (pid:2291)
>[0;37m2013-05-30 05:07:27.337[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a716cf4efd7f06fc000001 LOGIN=aaa IP=127.0.0.1 SCOPES=session Authenticated (pid:2291)
>[0;37m2013-05-30 05:07:27.339[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"user_id"=>"51a716cf4efd7f06fc000001", "note"=>"OpenShift Console (from 203.114.244.88 on Firefox)", "scopes"=>"session"}, "$orderby"=>{"created_at"=>-1}} flags=[] limit=10 skip=0 batch_size=nil fields=nil (0.5579ms) (pid:2291)
>[0;37m2013-05-30 05:07:27.343[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"token"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"} flags=[] limit=1 skip=0 batch_size=nil fields={:_id=>1} (0.5112ms) (pid:2291)
>[0;37m2013-05-30 05:07:27.460[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=authorizations documents=[{"_id"=>"51a716cf4efd7f06fc000003", "expires_in"=>86400, "note"=>"OpenShift Console (from 203.114.244.88 on Firefox)", "user_id"=>"51a716cf4efd7f06fc000001", "scopes"=>"session", "token"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7", "identity_id"=>"aaa", "updated_at"=>2013-05-30 09:07:27 UTC, "created_at"=>2013-05-30 09:07:27 UTC, "expires_at"=>2013-05-31 09:07:27 UTC}] flags=[] (pid:2291)
>[0;37m2013-05-30 05:07:27.460[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (110.9679ms) (pid:2291)
>[0;37m2013-05-30 05:07:27.463[0m [[0;37mDEBUG[0m] SUCCESS ACTION=ADD_AUTHORIZATION USER_ID=51a716cf4efd7f06fc000001 LOGIN=aaa TOKEN=05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7 SCOPE=session EXPIRES=2013-05-31 09:07:27 UTC IP=127.0.0.1 Create authorization (pid:2291)
>[0;37m2013-05-30 05:07:27.580[0m [[32mINFO [0m] Completed 201 Created in 2057ms (Views: 1.8ms) (pid:2291)
>[0;37m2013-05-30 05:07:28.369[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11211 (pid:2291)
>[0;37m2013-05-30 05:07:28.371[0m [[32mINFO [0m] Started GET "/broker/rest/domains.json" for 127.0.0.1 at 2013-05-30 05:07:28 -0400 (pid:2291)
>[0;37m2013-05-30 05:07:28.375[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:2291)
>[0;37m2013-05-30 05:07:28.378[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.9503ms) (pid:2291)
>[0;37m2013-05-30 05:07:28.379[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a716cf4efd7f06fc000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4451ms) (pid:2291)
>[0;37m2013-05-30 05:07:28.380[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a716cf4efd7f06fc000001 LOGIN=aaa IP=127.0.0.1 SCOPES=session Authenticated (pid:2291)
>[0;37m2013-05-30 05:07:28.380[0m [[0;37mDEBUG[0m] Getting domains for user aaa (pid:2291)
>[0;37m2013-05-30 05:07:28.634[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51a716cf4efd7f06fc000001"} flags=[] limit=0 skip=0 batch_size=nil fields=nil (252.7850ms) (pid:2291)
>[0;37m2013-05-30 05:07:28.634[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=51a716cf4efd7f06fc000001 LOGIN=aaa (pid:2291)
>[0;37m2013-05-30 05:07:28.637[0m [[32mINFO [0m] Completed 200 OK in 262ms (Views: 0.5ms) (pid:2291)
>[0;37m2013-05-30 05:07:28.962[0m [[32mINFO [0m] Started GET "/broker/rest/user.json" for 127.0.0.1 at 2013-05-30 05:07:28 -0400 (pid:2291)
>[0;37m2013-05-30 05:07:28.966[0m [[32mINFO [0m] Processing by UserExtController#show as JSON (pid:2291)
>[0;37m2013-05-30 05:07:28.968[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.9096ms) (pid:2291)
>[0;37m2013-05-30 05:07:28.970[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a716cf4efd7f06fc000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4759ms) (pid:2291)
>[0;37m2013-05-30 05:07:28.971[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a716cf4efd7f06fc000001 LOGIN=aaa IP=127.0.0.1 SCOPES=session Authenticated (pid:2291)
>[0;37m2013-05-30 05:07:28.972[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=51a716cf4efd7f06fc000001 LOGIN=aaa (pid:2291)
>[0;37m2013-05-30 05:07:28.974[0m [[32mINFO [0m] Completed 200 OK in 9ms (Views: 0.9ms) (pid:2291)
>[0;37m2013-05-30 05:07:28.981[0m [[32mINFO [0m] Started GET "/broker/rest/cartridges.json" for 127.0.0.1 at 2013-05-30 05:07:28 -0400 (pid:2291)
>[0;37m2013-05-30 05:07:28.984[0m [[32mINFO [0m] Processing by CartridgesController#index as JSON (pid:2291)
>[0;37m2013-05-30 05:07:34.963[0m [[0;37mDEBUG[0m] DEBUG: find_one_impl: current_server: ip-10-154-137-137 (pid:2291)
>[0;37m2013-05-30 05:07:34.969[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"cartridge-list", :args=>{"--porcelain"=>true, "--with-descriptors"=>true, "--cart-name"=>"openshift-origin-node"}}, ip-10-154-137-137, {'identity' => ip-10-154-137-137}) (Request ID: 3676afebcfa22f72f4f013bdf769e9db) (pid:2291)
>[0;37m2013-05-30 05:07:35.349[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_CARTRIDGE USER_ID= LOGIN= List all cartridges (pid:2291)
>[0;37m2013-05-30 05:07:35.365[0m [[32mINFO [0m] Completed 200 OK in 6381ms (Views: 13.5ms) (pid:2291)
>[0;37m2013-05-30 05:07:35.388[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 05:07:35 -0400 (pid:2291)
>[0;37m2013-05-30 05:07:35.391[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:2291)
>[0;37m2013-05-30 05:07:35.450[0m [[32mINFO [0m] Completed 200 OK in 58ms (Views: 4.6ms) (pid:2291)
>[0;37m2013-05-30 05:07:36.299[0m [[32mINFO [0m] Started GET "/broker/rest/environment.json" for 127.0.0.1 at 2013-05-30 05:07:36 -0400 (pid:2291)
>[0;37m2013-05-30 05:07:36.303[0m [[32mINFO [0m] Processing by EnvironmentController#show as JSON (pid:2291)
>[0;37m2013-05-30 05:07:36.304[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_ENVIRONMENT USER_ID= LOGIN= Showing broker environment (pid:2291)
>[0;37m2013-05-30 05:07:36.306[0m [[32mINFO [0m] Completed 200 OK in 3ms (Views: 0.5ms) (pid:2291)
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (6.4523ms)
>  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} (1.8623ms)
>  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} (22.9597ms)
>  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} (1.1120ms)
>  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} (2.0597ms)
>  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.6468ms)
>  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} (1.1108ms)
>  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} (4.8661ms)
>  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.8287ms)
>  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} (3.9463ms)
>[0;37m2013-05-30 05:10:45.233[0m [[0;37mDEBUG[0m] 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:4780)
>[0;37m2013-05-30 05:10:45.233[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8881ms) (pid:4780)
>[0;37m2013-05-30 05:10:45.699[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:4780)
>[0;37m2013-05-30 05:10:45.699[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8602ms) (pid:4780)
>[0;37m2013-05-30 05:10:45.942[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11211 (pid:4780)
>[0;37m2013-05-30 05:10:46.114[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 05:10:46 -0400 (pid:4780)
>[0;37m2013-05-30 05:10:46.397[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:4780)
>[0;37m2013-05-30 05:10:46.810[0m [[32mINFO [0m] Completed 200 OK in 413ms (Views: 20.2ms) (pid:4780)
>[0;37m2013-05-30 05:10:46.819[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 05:10:46 -0400 (pid:4780)
>[0;37m2013-05-30 05:10:46.828[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:4780)
>[0;37m2013-05-30 05:10:46.858[0m [[32mINFO [0m] Completed 200 OK in 25ms (Views: 15.8ms) (pid:4780)
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (4.3609ms)
>  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.7083ms)
>  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.4852ms)
>  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} (3.2103ms)
>  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} (0.4516ms)
>  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} (3.3858ms)
>  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} (3.5355ms)
>  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} (3.7665ms)
>  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.5205ms)
>  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} (4.1749ms)
>[0;37m2013-05-30 05:11:02.258[0m [[0;37mDEBUG[0m] 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:4875)
>[0;37m2013-05-30 05:11:02.260[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8876ms) (pid:4875)
>[0;37m2013-05-30 05:11:03.427[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:4875)
>[0;37m2013-05-30 05:11:03.429[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8113ms) (pid:4875)
>[0;37m2013-05-30 05:11:50.871[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11212 (pid:4875)
>[0;37m2013-05-30 05:11:50.894[0m [[32mINFO [0m] Started DELETE "/broker/rest/user/authorizations/05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7.json" for 127.0.0.1 at 2013-05-30 05:11:50 -0400 (pid:4875)
>[0;37m2013-05-30 05:11:50.175[0m [[32mINFO [0m] Processing by AuthorizationsController#destroy as JSON (pid:4875)
>[0;37m2013-05-30 05:11:50.175[0m [[32mINFO [0m] Parameters: {"id"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"} (pid:4875)
>[0;37m2013-05-30 05:11:50.180[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.9532ms) (pid:4875)
>[0;37m2013-05-30 05:11:50.182[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a716cf4efd7f06fc000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4196ms) (pid:4875)
>[0;37m2013-05-30 05:11:50.184[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a716cf4efd7f06fc000001 LOGIN=aaa IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 05:11:50.185[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"authorizations", :query=>{"user_id"=>"51a716cf4efd7f06fc000001", "$or"=>[{"token"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"}, {"_id"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"}]}} (0.5152ms) (pid:4875)
>[0;37m2013-05-30 05:11:50.186[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 DELETE       database=openshift_broker_dev collection=authorizations selector={"user_id"=>"51a716cf4efd7f06fc000001", "$or"=>[{"token"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"}, {"_id"=>"05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7"}]} flags=[] (pid:4875)
>[0;37m2013-05-30 05:11:50.186[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7112ms) (pid:4875)
>[0;37m2013-05-30 05:11:50.186[0m [[0;37mDEBUG[0m] SUCCESS ACTION=DELETE_AUTHORIZATION USER_ID=51a716cf4efd7f06fc000001 LOGIN=aaa Authorization 05ad294f57884f55aa2148892013bd9011908767c02f10697a33a087ffababb7 is revoked. (pid:4875)
>[0;37m2013-05-30 05:11:50.293[0m [[32mINFO [0m] Completed 204 No Content in 118ms (Views: 0.7ms) (pid:4875)
>[0;37m2013-05-30 06:20:44.792[0m [[32mINFO [0m] Started POST "/broker/rest/user/authorizations.json" for 127.0.0.1 at 2013-05-30 06:20:44 -0400 (pid:4875)
>[0;37m2013-05-30 06:20:44.800[0m [[32mINFO [0m] Processing by AuthorizationsController#create as JSON (pid:4875)
>[0;37m2013-05-30 06:20:44.800[0m [[32mINFO [0m] Parameters: {"note"=>"OpenShift Console (from 203.114.244.88 on Safari)", "reuse"=>true, "scope"=>"session", "authorization"=>{"note"=>"OpenShift Console (from 203.114.244.88 on Safari)"}} (pid:4875)
>[0;37m2013-05-30 06:20:45.411[0m [[0;37mDEBUG[0m] Response from Streamline took (/wapps/streamline/cloudVerify.html): 609.322776 ms (pid:4875)
>[0;37m2013-05-30 06:20:45.412[0m [[0;37mDEBUG[0m] POST Response code = 200 (pid:4875)
>[0;37m2013-05-30 06:20:45.414[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (0.6926ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.415[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"login"=>"xtian+test1@redhat.com"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5357ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.430[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=cloud_users documents=[{"_id"=>"51a727fd4efd7ff39d000001", "plan_history"=>[], "consumed_gears"=>0, "plan_id"=>"free", "plan_state"=>"ACTIVE", "capabilities"=>{"subaccounts"=>false, "gear_sizes"=>["small"], "max_gears"=>3, "plan_upgrade_enabled"=>true}, "login"=>"xtian+test1@redhat.com", "updated_at"=>2013-05-30 10:20:45 UTC, "created_at"=>2013-05-30 10:20:45 UTC}] flags=[] (pid:4875)
>[0;37m2013-05-30 06:20:45.430[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.1148ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.432[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4997ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.434[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=locks documents=[{"_id"=>"51a727fd4efd7ff39d000002", "timeout"=>0, "app_ids"=>{}, "user_id"=>"51a727fd4efd7ff39d000001", "locked"=>false}] flags=[] (pid:4875)
>[0;37m2013-05-30 06:20:45.435[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6771ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.435[0m [[0;37mDEBUG[0m] SUCCESS ACTION=CREATE_USER USER_ID= LOGIN= USER=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com PROVIDER= Creating user (pid:4875)
>[0;37m2013-05-30 06:20:45.435[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:20:45.437[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"user_id"=>"51a727fd4efd7ff39d000001", "note"=>"OpenShift Console (from 203.114.244.88 on Safari)", "scopes"=>"session"}, "$orderby"=>{"created_at"=>-1}} flags=[] limit=10 skip=0 batch_size=nil fields=nil (0.4604ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.440[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"} flags=[] limit=1 skip=0 batch_size=nil fields={:_id=>1} (0.4017ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.445[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=authorizations documents=[{"_id"=>"51a727fd4efd7ff39d000003", "expires_in"=>86400, "note"=>"OpenShift Console (from 203.114.244.88 on Safari)", "user_id"=>"51a727fd4efd7ff39d000001", "scopes"=>"session", "token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505", "identity_id"=>"xtian+test1@redhat.com", "updated_at"=>2013-05-30 10:20:45 UTC, "created_at"=>2013-05-30 10:20:45 UTC, "expires_at"=>2013-05-31 10:20:45 UTC}] flags=[] (pid:4875)
>[0;37m2013-05-30 06:20:45.445[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6404ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.448[0m [[0;37mDEBUG[0m] SUCCESS ACTION=ADD_AUTHORIZATION USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com TOKEN=8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505 SCOPE=session EXPIRES=2013-05-31 10:20:45 UTC IP=127.0.0.1 Create authorization (pid:4875)
>[0;37m2013-05-30 06:20:45.450[0m [[32mINFO [0m] Completed 201 Created in 650ms (Views: 1.5ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.753[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11211 (pid:4875)
>[0;37m2013-05-30 06:20:45.755[0m [[32mINFO [0m] Started GET "/broker/rest/domains.json" for 127.0.0.1 at 2013-05-30 06:20:45 -0400 (pid:4875)
>[0;37m2013-05-30 06:20:45.758[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:20:45.761[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.8929ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.763[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5209ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.764[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:20:45.764[0m [[0;37mDEBUG[0m] Getting domains for user xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:20:45.765[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51a727fd4efd7ff39d000001"} flags=[] limit=0 skip=0 batch_size=nil fields=nil (0.3667ms) (pid:4875)
>[0;37m2013-05-30 06:20:45.765[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:20:45.767[0m [[32mINFO [0m] Completed 200 OK in 8ms (Views: 0.4ms) (pid:4875)
>[0;37m2013-05-30 06:20:46.660[0m [[32mINFO [0m] Started GET "/broker/rest/user.json" for 127.0.0.1 at 2013-05-30 06:20:46 -0400 (pid:4875)
>[0;37m2013-05-30 06:20:46.118[0m [[32mINFO [0m] Processing by UserExtController#show as JSON (pid:4875)
>[0;37m2013-05-30 06:20:46.121[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.8838ms) (pid:4875)
>[0;37m2013-05-30 06:20:46.123[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5004ms) (pid:4875)
>[0;37m2013-05-30 06:20:46.124[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:20:46.125[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:20:46.129[0m [[32mINFO [0m] Completed 200 OK in 10ms (Views: 1.0ms) (pid:4875)
>[0;37m2013-05-30 06:20:46.137[0m [[32mINFO [0m] Started GET "/broker/rest/cartridges.json" for 127.0.0.1 at 2013-05-30 06:20:46 -0400 (pid:4875)
>[0;37m2013-05-30 06:20:46.139[0m [[32mINFO [0m] Processing by CartridgesController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:20:46.151[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_CARTRIDGE USER_ID= LOGIN= List all cartridges (pid:4875)
>[0;37m2013-05-30 06:20:46.164[0m [[32mINFO [0m] Completed 200 OK in 25ms (Views: 12.1ms) (pid:4875)
>[0;37m2013-05-30 06:20:46.186[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 06:20:46 -0400 (pid:4875)
>[0;37m2013-05-30 06:20:46.189[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:4875)
>[0;37m2013-05-30 06:20:46.199[0m [[32mINFO [0m] Completed 200 OK in 10ms (Views: 4.7ms) (pid:4875)
>[0;37m2013-05-30 06:20:46.659[0m [[32mINFO [0m] Started GET "/broker/rest/environment.json" for 127.0.0.1 at 2013-05-30 06:20:46 -0400 (pid:4875)
>[0;37m2013-05-30 06:20:46.663[0m [[32mINFO [0m] Processing by EnvironmentController#show as JSON (pid:4875)
>[0;37m2013-05-30 06:20:46.664[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_ENVIRONMENT USER_ID= LOGIN= Showing broker environment (pid:4875)
>[0;37m2013-05-30 06:20:46.666[0m [[32mINFO [0m] Completed 200 OK in 3ms (Views: 0.5ms) (pid:4875)
>[0;37m2013-05-30 06:21:45.133[0m [[32mINFO [0m] Started GET "/broker/rest/domains.json" for 127.0.0.1 at 2013-05-30 06:21:45 -0400 (pid:4875)
>[0;37m2013-05-30 06:21:45.135[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:21:45.137[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.8793ms) (pid:4875)
>[0;37m2013-05-30 06:21:45.139[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4344ms) (pid:4875)
>[0;37m2013-05-30 06:21:45.140[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:21:45.140[0m [[0;37mDEBUG[0m] Getting domains for user xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:21:45.141[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51a727fd4efd7ff39d000001"} flags=[] limit=0 skip=0 batch_size=nil fields=nil (0.3619ms) (pid:4875)
>[0;37m2013-05-30 06:21:45.141[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:21:45.143[0m [[32mINFO [0m] Completed 200 OK in 7ms (Views: 0.5ms) (pid:4875)
>[0;37m2013-05-30 06:21:45.611[0m [[32mINFO [0m] Started GET "/broker/rest/user.json" for 127.0.0.1 at 2013-05-30 06:21:45 -0400 (pid:4875)
>[0;37m2013-05-30 06:21:45.613[0m [[32mINFO [0m] Processing by UserExtController#show as JSON (pid:4875)
>[0;37m2013-05-30 06:21:45.615[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.7486ms) (pid:4875)
>[0;37m2013-05-30 06:21:45.616[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4292ms) (pid:4875)
>[0;37m2013-05-30 06:21:45.617[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:21:45.618[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:21:45.620[0m [[32mINFO [0m] Completed 200 OK in 6ms (Views: 0.7ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.366[0m [[32mINFO [0m] Started GET "/broker/rest/user.json" for 127.0.0.1 at 2013-05-30 06:21:56 -0400 (pid:4875)
>[0;37m2013-05-30 06:21:56.367[0m [[32mINFO [0m] Processing by UserExtController#show as JSON (pid:4875)
>[0;37m2013-05-30 06:21:56.369[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.7911ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.370[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4075ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.371[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:21:56.372[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:21:56.373[0m [[32mINFO [0m] Completed 200 OK in 6ms (Views: 0.7ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.384[0m [[32mINFO [0m] Started GET "/broker/rest/domains.json" for 127.0.0.1 at 2013-05-30 06:21:56 -0400 (pid:4875)
>[0;37m2013-05-30 06:21:56.385[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:21:56.386[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5744ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.388[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.6611ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.388[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:21:56.388[0m [[0;37mDEBUG[0m] Getting domains for user xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:21:56.389[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51a727fd4efd7ff39d000001"} flags=[] limit=0 skip=0 batch_size=nil fields=nil (0.3588ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.389[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:21:56.390[0m [[32mINFO [0m] Completed 200 OK in 5ms (Views: 0.3ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.437[0m [[32mINFO [0m] Started POST "/broker/rest/domains.json" for 127.0.0.1 at 2013-05-30 06:21:56 -0400 (pid:4875)
>[0;37m2013-05-30 06:21:56.439[0m [[32mINFO [0m] Processing by DomainsController#create as JSON (pid:4875)
>[0;37m2013-05-30 06:21:56.439[0m [[32mINFO [0m] Parameters: {"id"=>"domx1", "domain"=>{}} (pid:4875)
>[0;37m2013-05-30 06:21:56.441[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.7429ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.443[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4413ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.444[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:21:56.444[0m [[0;37mDEBUG[0m] Creating domain with namespace domx1 (pid:4875)
>[0;37m2013-05-30 06:21:56.448[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"domains", :query=>{"canonical_namespace"=>"domx1"}} (0.5062ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.449[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"domains", :query=>{"owner_id"=>"51a727fd4efd7ff39d000001"}} (0.3941ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.452[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=domains documents=[{"_id"=>"51a728444efd7ff39d000004", "env_vars"=>[], "user_ids"=>["51a727fd4efd7ff39d000001"], "canonical_namespace"=>"domx1", "namespace"=>"domx1", "owner_id"=>"51a727fd4efd7ff39d000001", "updated_at"=>2013-05-30 10:21:56 UTC, "created_at"=>2013-05-30 10:21:56 UTC}] flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.452[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8161ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.452[0m [[0;37mDEBUG[0m] Sending updated domain info domx1 to nurture (pid:4875)
>[0;37m2013-05-30 06:21:56.469[0m [[0;37mDEBUG[0m] SUCCESS ACTION=ADD_DOMAIN USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com DOMAIN=domx1 Created domain with namespace domx1 (pid:4875)
>[0;37m2013-05-30 06:21:56.473[0m [[32mINFO [0m] Completed 201 Created in 34ms (Views: 3.5ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.522[0m [[32mINFO [0m] Started POST "/broker/rest/domains/domx1/applications.json" for 127.0.0.1 at 2013-05-30 06:21:56 -0400 (pid:4875)
>[0;37m2013-05-30 06:21:56.577[0m [[32mINFO [0m] Processing by ApplicationsController#create as JSON (pid:4875)
>[0;37m2013-05-30 06:21:56.577[0m [[32mINFO [0m] Parameters: {"cartridges"=>["ruby-1.9", "mysql-5.1"], "gear_profile"=>"small", "initial_git_url"=>"git://github.com/openshift/rails-example.git", "name"=>"railsapp", "domain_id"=>"domx1", "application"=>{"name"=>"railsapp"}} (pid:4875)
>[0;37m2013-05-30 06:21:56.580[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.9077ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.581[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4339ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.583[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:21:56.584[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"51a727fd4efd7ff39d000001", "canonical_namespace"=>"domx1"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5810ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.586[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4151ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.587[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"applications", :query=>{"domain_id"=>"51a728444efd7ff39d000004", "canonical_name"=>"railsapp"}} (0.5288ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.587[0m [[0;37mDEBUG[0m] Checking to see if user limit for number of apps has been reached (pid:4875)
>[0;37m2013-05-30 06:21:56.596[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=applications documents=[{"_id"=>"51a728444efd7ff39d000005", "uuid"=>"895943249398067907002368", "domain_requires"=>[], "group_overrides"=>[], "downloaded_cart_map"=>{}, "user_ids"=>[], "component_start_order"=>[], "component_stop_order"=>[], "component_configure_order"=>[], "init_git_url"=>"git://github.com/openshift/rails-example.git", "analytics"=>{}, "canonical_name"=>"railsapp", "name"=>"railsapp", "default_gear_size"=>"small", "scalable"=>false, "domain_id"=>"51a728444efd7ff39d000004", "updated_at"=>2013-05-30 10:21:56 UTC, "created_at"=>2013-05-30 10:21:56 UTC}] flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.596[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7429ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.599[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"analytics"=>{"user_agent"=>"openshift_console/1.9.9 (ruby 1.9.3; x86_64-linux)"}, "updated_at"=>2013-05-30 10:21:56 UTC}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.599[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7877ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.609[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51a727fd4efd7ff39d000001", "$or"=>[{"app_ids.51a728444efd7ff39d000005"=>{"$exists"=>false}}, {"app_ids.51a728444efd7ff39d000005"=>{"$lt"=>1369909316}}]}, :new=>true, :update=>{"$set"=>{"app_ids.51a728444efd7ff39d000005"=>1369909916}}} (1.0989ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.613[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$push"=>{"pending_op_groups"=>{"_id"=>"51a728444efd7ff39d000006", "num_gears_added"=>0, "num_gears_removed"=>0, "num_gears_created"=>0, "num_gears_destroyed"=>0, "num_gears_rolled_back"=>0, "user_agent"=>"openshift_console/1.9.9 (ruby 1.9.3; x86_64-linux)", "op_type"=>:add_features, "args"=>{"features"=>["ruby-1.9", "mysql-5.1"], "group_overrides"=>[], "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "parent_op_id"=>nil, "updated_at"=>2013-05-30 10:21:56 UTC, "created_at"=>2013-05-30 10:21:56 UTC}}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.614[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7524ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.615[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"51a728444efd7ff39d000005"} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5169ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.623[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"51a728444efd7ff39d000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4628ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.624[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.3951ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.626[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"_id"=>"51a727fd4efd7ff39d000001"} flags=[] limit=0 skip=0 batch_size=nil fields=nil (0.3786ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.632[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=locks selector={"$query"=>{"user_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5708ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.633[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51a727fd4efd7ff39d000001", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000005d48690>"=>1369909316}], "app_ids.51a728444efd7ff39d000005"=>{"$exists"=>true}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1369909916}}} (0.6499ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.634[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={:_id=>"51a727fd4efd7ff39d000001"} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.3765ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.646[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$pushAll"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "state"=>:init, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "op_type"=>:set_group_overrides, "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}}, {"_id"=>"51a728444efd7ff39d000009", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}}, {"_id"=>"51a728444efd7ff39d00000a", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["51a728444efd7ff39d000009"]}, {"_id"=>"51a728444efd7ff39d00000b", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000a"]}, {"_id"=>"51a728444efd7ff39d00000c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000b"], "retry_rollback_op"=>"51a728444efd7ff39d00000b"}, {"_id"=>"51a728444efd7ff39d000014", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d00000d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d000015", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d00000e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "prereq"=>["51a728444efd7ff39d000009"]}, {"_id"=>"51a728444efd7ff39d00000f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.646[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.7383ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.656[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.num_gears_added"=>1, "pending_op_groups.0.updated_at"=>2013-05-30 10:21:56 UTC}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.656[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5851ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.659[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=cloud_users selector={"_id"=>"51a727fd4efd7ff39d000001"} update={"$set"=>{"consumed_gears"=>1, "updated_at"=>2013-05-30 10:21:56 UTC}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.659[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5553ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.660[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51a727fd4efd7ff39d000001", "locked"=>true, "app_ids.51a728444efd7ff39d000005"=>{"$exists"=>true}}, :new=>true, :update=>{"$set"=>{"locked"=>false}}} (0.6080ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.663[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.2791ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.675[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "state"=>:init, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "op_type"=>:set_group_overrides, "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}}, {"_id"=>"51a728444efd7ff39d000009", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}}, {"_id"=>"51a728444efd7ff39d00000a", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["51a728444efd7ff39d000009"]}, {"_id"=>"51a728444efd7ff39d00000b", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000a"]}, {"_id"=>"51a728444efd7ff39d00000c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000b"], "retry_rollback_op"=>"51a728444efd7ff39d00000b"}, {"_id"=>"51a728444efd7ff39d000014", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d00000d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d000015", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d00000e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "prereq"=>["51a728444efd7ff39d000009"]}, {"_id"=>"51a728444efd7ff39d00000f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.675[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.5805ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.688[0m [[0;37mDEBUG[0m] Execute set_group_overrides (pid:4875)
>[0;37m2013-05-30 06:21:56.693[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}], "updated_at"=>2013-05-30 10:21:56 UTC}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.693[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7334ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.694[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.694[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.5984ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.694[0m [[0;37mDEBUG[0m] Execute create_group_instance (pid:4875)
>[0;37m2013-05-30 06:21:56.697[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$push"=>{"group_instances"=>{"_id"=>"51a728444efd7ff39d000017"}}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.697[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6411ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.698[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.699[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6094ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.701[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.2851ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.714[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["51a728444efd7ff39d000009"]}, {"_id"=>"51a728444efd7ff39d00000b", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000a"]}, {"_id"=>"51a728444efd7ff39d00000c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000b"], "retry_rollback_op"=>"51a728444efd7ff39d00000b"}, {"_id"=>"51a728444efd7ff39d000014", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d00000d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d000015", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d00000e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "prereq"=>["51a728444efd7ff39d000009"]}, {"_id"=>"51a728444efd7ff39d00000f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.714[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.4717ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.726[0m [[0;37mDEBUG[0m] Execute init_gear (pid:4875)
>[0;37m2013-05-30 06:21:56.730[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"51a728444efd7ff39d000005", "uuid"=>"895943249398067907002368", "name"=>"railsapp", "host_singletons"=>true, "app_dns"=>true, "quarantined"=>false}}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.730[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.1613ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.735[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.735[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6382ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.736[0m [[0;37mDEBUG[0m] Execute new_component (pid:4875)
>[0;37m2013-05-30 06:21:56.738[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$push"=>{"component_instances"=>{"_id"=>"51a728444efd7ff39d00001a", "cartridge_vendor"=>"redhat", "version"=>"1.9", "component_properties"=>{}, "cartridge_name"=>"ruby-1.9", "component_name"=>"ruby-1.9", "group_instance_id"=>"51a728444efd7ff39d000017"}}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.738[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7701ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.739[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.8.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.740[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6368ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.742[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.3485ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.754[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000a"]}, {"_id"=>"51a728444efd7ff39d00000c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000b"], "retry_rollback_op"=>"51a728444efd7ff39d00000b"}, {"_id"=>"51a728444efd7ff39d000014", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d00000d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d000015", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:56.754[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.4749ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.817[0m [[0;37mDEBUG[0m] Execute reserve_uid (pid:4875)
>[0;37m2013-05-30 06:21:56.832[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=districts selector={} flags=[] limit=0 skip=0 batch_size=nil fields=nil (13.6297ms) (pid:4875)
>[0;37m2013-05-30 06:21:56.832[0m [[0;37mDEBUG[0m] DEBUG: rpc_get_fact: fact=active_capacity (pid:4875)
>[0;37m2013-05-30 06:21:57.782[0m [[0;37mDEBUG[0m] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x00000004e41f48> (pid:4875)
>[0;37m2013-05-30 06:21:59.282[0m [[0;37mDEBUG[0m] Current server: ip-10-154-137-137 active capacity: 0.0 (pid:4875)
>[0;37m2013-05-30 06:21:59.282[0m [[0;37mDEBUG[0m] DEBUG: find_available_impl: current_server: ip-10-154-137-137: 0.0 (pid:4875)
>[0;37m2013-05-30 06:21:59.284[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>"ip-10-154-137-137"}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:59.284[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.3068ms) (pid:4875)
>[0;37m2013-05-30 06:21:59.369[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:59.369[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8712ms) (pid:4875)
>[0;37m2013-05-30 06:21:59.372[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:59.373[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.8205ms) (pid:4875)
>[0;37m2013-05-30 06:21:59.377[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (3.3956ms) (pid:4875)
>[0;37m2013-05-30 06:21:59.390[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:reserve_uid, "prereq"=>["51a728444efd7ff39d00000a"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000b"], "retry_rollback_op"=>"51a728444efd7ff39d00000b"}, {"_id"=>"51a728444efd7ff39d000014", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d00000d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d000015", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:21:59.390[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.5507ms) (pid:4875)
>[0;37m2013-05-30 06:21:59.401[0m [[0;37mDEBUG[0m] Execute create_gear (pid:4875)
>[0;37m2013-05-30 06:21:59.405[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"895943249398067907002368", "--with-app-name"=>"railsapp", "--with-container-uuid"=>"895943249398067907002368", "--with-container-name"=>"railsapp", "--with-namespace"=>"domx1", "--with-request-id"=>"292a26b3bd313e15888469bf08046b1c", "--cart-name"=>"openshift-origin-node"}}, ip-10-154-137-137, {'identity' => ip-10-154-137-137}) (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:01.242[0m [[0;37mDEBUG[0m] DEBUG: [#<MCollective::RPC::Result:0x00000005f67368 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-154-137-137", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:01.246[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_direct: app-create): 1.844667392s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:01.253[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$inc"=>{"pending_op_groups.0.num_gears_created"=>1}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:01.253[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (5.0743ms) (pid:4875)
>[0;37m2013-05-30 06:22:01.254[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:01.254[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.9544ms) (pid:4875)
>[0;37m2013-05-30 06:22:01.263[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (7.8144ms) (pid:4875)
>[0;37m2013-05-30 06:22:01.348[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:reserve_uid, "prereq"=>["51a728444efd7ff39d00000a"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000c", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:create_gear, "prereq"=>["51a728444efd7ff39d00000b"], "retry_count"=>0, "retry_rollback_op"=>"51a728444efd7ff39d00000b", "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000014", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d00000d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000c"]}, {"_id"=>"51a728444efd7ff39d000015", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:01.348[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.0862ms) (pid:4875)
>[0;37m2013-05-30 06:22:01.359[0m [[0;37mDEBUG[0m] Execute track_usage (pid:4875)
>[0;37m2013-05-30 06:22:01.372[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage documents=[{"_id"=>"51a728494efd7ff39d00001b", "user_id"=>"51a727fd4efd7ff39d000001", "app_name"=>"railsapp", "gear_id"=>"51a728444efd7ff39d000005", "begin_time"=>2013-05-30 10:22:01 UTC, "created_at"=>2013-05-30 10:22:01 UTC, "usage_type"=>"GEAR_USAGE", "gear_size"=>"small", "updated_at"=>2013-05-30 10:22:01 UTC}] flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:01.372[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (8.2414ms) (pid:4875)
>[0;37m2013-05-30 06:22:01.378[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage_records documents=[{"_id"=>"51a728494efd7ff39d00001c", "event"=>"begin", "time"=>2013-05-30 10:22:01 UTC, "created_at"=>2013-05-30 10:22:01 UTC, "gear_id"=>"51a728444efd7ff39d000005", "usage_type"=>"GEAR_USAGE", "user_id"=>"51a727fd4efd7ff39d000001", "app_name"=>"railsapp", "gear_size"=>"small", "updated_at"=>2013-05-30 10:22:01 UTC}] flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:01.378[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.8501ms) (pid:4875)
>[0;37m2013-05-30 06:22:01.379[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.5.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:01.379[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7310ms) (pid:4875)
>[0;37m2013-05-30 06:22:01.380[0m [[0;37mDEBUG[0m] Execute register_dns (pid:4875)
>[0;37m2013-05-30 06:22:01.381[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Login with path: /REST/Session/ (pid:4875)
>[0;37m2013-05-30 06:22:02.717[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Response: {"status": "success", "data": {"token": "shkjIWtFsrZP145E6BqQVw3jOWv2jxRZt03h9aViph6hesKkviDYRbumZ5pXZNMtFO/KbUOTPfKQfjsIxVYWxO/9FtiuqD9RLkLOicQFJ5g7kvlb3EI1xw3EbapTOJxqh7goxb++loSH3wqh4D58pqAeamMB+BtA", "version": "3.4.0"}, "job_id": 365689523, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:4875)
>[0;37m2013-05-30 06:22:02.752[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Response data: {"token"=>"shkjIWtFsrZP145E6BqQVw3jOWv2jxRZt03h9aViph6hesKkviDYRbumZ5pXZNMtFO/KbUOTPfKQfjsIxVYWxO/9FtiuqD9RLkLOicQFJ5g7kvlb3EI1xw3EbapTOJxqh7goxb++loSH3wqh4D58pqAeamMB+BtA", "version"=>"3.4.0"} (pid:4875)
>[0;37m2013-05-30 06:22:02.777[0m [[0;37mDEBUG[0m] DEBUG: Dynect Response Time (dyn_login): 0.626330933s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:02.156[0m [[0;37mDEBUG[0m] DEBUG: Public ip being configured 'ec2-174-129-149-191.compute-1.amazonaws.com' to app 'railsapp' (pid:4875)
>[0;37m2013-05-30 06:22:02.156[0m [[0;37mDEBUG[0m] DEBUG: DYNECT put/post with path: /REST/CNAMERecord/rhcloud.com/railsapp-domx1.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-174-129-149-191.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:4875)
>[0;37m2013-05-30 06:22:02.802[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Response: {"status": "success", "data": {"zone": "rhcloud.com", "ttl": 60, "fqdn": "railsapp-domx1.dev.rhcloud.com", "record_type": "CNAME", "rdata": {"cname": "ec2-174-129-149-191.compute-1.amazonaws.com."}, "record_id": 0}, "job_id": 365689543, "msgs": [{"INFO": "add: Record added", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:4875)
>[0;37m2013-05-30 06:22:02.803[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Response data: {"zone"=>"rhcloud.com", "ttl"=>60, "fqdn"=>"railsapp-domx1.dev.rhcloud.com", "record_type"=>"CNAME", "rdata"=>{"cname"=>"ec2-174-129-149-191.compute-1.amazonaws.com."}, "record_id"=>0} (pid:4875)
>[0;37m2013-05-30 06:22:02.803[0m [[0;37mDEBUG[0m] DEBUG: Dynect Response Time (dyn_put_post): 0.646245602s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:02.803[0m [[0;37mDEBUG[0m] DEBUG: DYNECT put/post with path: /REST/Zone/rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:4875)
>[0;37m2013-05-30 06:22:04.863[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "increment", "serial": 6122064, "zone": "rhcloud.com"}, "job_id": 365689552, "msgs": [{"INFO": "publish: rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:4875)
>[0;37m2013-05-30 06:22:04.864[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"increment", "serial"=>6122064, "zone"=>"rhcloud.com"} (pid:4875)
>[0;37m2013-05-30 06:22:04.864[0m [[0;37mDEBUG[0m] DEBUG: Dynect Response Time (dyn_put_post): 2.060805725s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:04.864[0m [[0;37mDEBUG[0m] DEBUG: DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:4875)
>[0;37m2013-05-30 06:22:05.273[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Response: {"status": "success", "data": {}, "job_id": 365689573, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:4875)
>[0;37m2013-05-30 06:22:05.273[0m [[0;37mDEBUG[0m] DEBUG: DYNECT Response data: {} (pid:4875)
>[0;37m2013-05-30 06:22:05.273[0m [[0;37mDEBUG[0m] DEBUG: Dynect Response Time (dyn_delete): 0.409125053s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:05.275[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.6.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:05.275[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.0610ms) (pid:4875)
>[0;37m2013-05-30 06:22:05.279[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (2.8291ms) (pid:4875)
>[0;37m2013-05-30 06:22:05.303[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:reserve_uid, "prereq"=>["51a728444efd7ff39d00000a"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000c", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:create_gear, "prereq"=>["51a728444efd7ff39d00000b"], "retry_count"=>0, "retry_rollback_op"=>"51a728444efd7ff39d00000b", "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000014", "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>:track_usage, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000d", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:register_dns, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000015", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "prereq"=>["51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"]}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:05.304[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (4.8721ms) (pid:4875)
>[0;37m2013-05-30 06:22:05.320[0m [[0;37mDEBUG[0m] Execute update_configuration (pid:4875)
>[0;37m2013-05-30 06:22:05.324[0m [[0;37mDEBUG[0m] Execute add_component (pid:4875)
>[0;37m2013-05-30 06:22:05.330[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"ruby-1.9", :action=>"configure", :args=>{"--with-app-uuid"=>"895943249398067907002368", "--with-app-name"=>"railsapp", "--with-container-uuid"=>"895943249398067907002368", "--with-container-name"=>"railsapp", "--with-namespace"=>"domx1", "--with-request-id"=>"292a26b3bd313e15888469bf08046b1c", "--cart-name"=>"ruby-1.9", "--component-name"=>"ruby-1.9", "--with-software-version"=>"1.9", "--cartridge-vendor"=>"redhat", "--with-template-git-url"=>"git://github.com/openshift/rails-example.git"}}, ip-10-154-137-137, {'identity' => ip-10-154-137-137}) (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:10.315[0m [[0;37mDEBUG[0m] DEBUG: [#<MCollective::RPC::Result:0x000000061f7038 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-154-137-137", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"CLIENT_DEBUG: The cartridge ruby deployed a template application", :exitcode=>0}}>] (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:10.321[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_direct: configure): 4.995564486s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:10.327[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:10.327[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (4.0486ms) (pid:4875)
>[0;37m2013-05-30 06:22:10.330[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.7.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:10.330[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.0870ms) (pid:4875)
>[0;37m2013-05-30 06:22:10.346[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (5.1837ms) (pid:4875)
>[0;37m2013-05-30 06:22:10.363[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:reserve_uid, "prereq"=>["51a728444efd7ff39d00000a"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000c", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:create_gear, "prereq"=>["51a728444efd7ff39d00000b"], "retry_count"=>0, "retry_rollback_op"=>"51a728444efd7ff39d00000b", "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000014", "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>:track_usage, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000d", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:register_dns, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000015", "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:update_configuration, "prereq"=>["51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "op_type"=>:add_component, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:10.363[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.5862ms) (pid:4875)
>[0;37m2013-05-30 06:22:10.372[0m [[0;37mDEBUG[0m] Execute new_component (pid:4875)
>[0;37m2013-05-30 06:22:10.374[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$push"=>{"component_instances"=>{"_id"=>"51a728524efd7ff39d00001d", "cartridge_vendor"=>"redhat", "version"=>"5.1", "component_properties"=>{}, "cartridge_name"=>"mysql-5.1", "component_name"=>"mysql-5.1", "group_instance_id"=>"51a728444efd7ff39d000017"}}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:10.374[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8817ms) (pid:4875)
>[0;37m2013-05-30 06:22:10.376[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.11.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:10.376[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.6628ms) (pid:4875)
>[0;37m2013-05-30 06:22:10.378[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.3580ms) (pid:4875)
>[0;37m2013-05-30 06:22:10.390[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:reserve_uid, "prereq"=>["51a728444efd7ff39d00000a"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000c", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:create_gear, "prereq"=>["51a728444efd7ff39d00000b"], "retry_count"=>0, "retry_rollback_op"=>"51a728444efd7ff39d00000b", "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000014", "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>:track_usage, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000d", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:register_dns, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000015", "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:update_configuration, "prereq"=>["51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "op_type"=>:add_component, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000012", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:10.390[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.4377ms) (pid:4875)
>[0;37m2013-05-30 06:22:10.399[0m [[0;37mDEBUG[0m] Execute add_component (pid:4875)
>[0;37m2013-05-30 06:22:10.461[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"mysql-5.1", :action=>"configure", :args=>{"--with-app-uuid"=>"895943249398067907002368", "--with-app-name"=>"railsapp", "--with-container-uuid"=>"895943249398067907002368", "--with-container-name"=>"railsapp", "--with-namespace"=>"domx1", "--with-request-id"=>"292a26b3bd313e15888469bf08046b1c", "--cart-name"=>"mysql-5.1", "--component-name"=>"mysql-5.1", "--with-software-version"=>"5.1", "--cartridge-vendor"=>"redhat"}}, ip-10-154-137-137, {'identity' => ip-10-154-137-137}) (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:13.283[0m [[0;37mDEBUG[0m] DEBUG: [#<MCollective::RPC::Result:0x00000004b41258 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-154-137-137", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"Generating username and password\nInstalling mysqld\nInstalling MySQL system tables...\nOK\nFilling help tables...\nOK\n\nTo start mysqld at boot time you have to copy\nsupport-files/mysql.server to the right place for your system\n\nPLEASE REMEMBER TO SET A PASSWORD FOR THE MySQL root USER !\nTo do so, start the server, then issue the following commands:\n\n/usr/bin/mysqladmin -u root password 'new-password'\n/usr/bin/mysqladmin -u root -h ip-10-154-137-137 password 'new-password'\n\nAlternatively you can run:\n/usr/bin/mysql_secure_installation\n\nwhich will also give you the option of removing the test\ndatabases and anonymous user created by default.  This is\nstrongly recommended for production servers.\n\nSee the manual for more instructions.\n\nYou can start the MySQL daemon with:\ncd /usr ; /usr/bin/mysqld_safe &\n\nYou can test the MySQL daemon with mysql-test-run.pl\ncd /usr/mysql-test ; perl mysql-test-run.pl\n\nPlease report any problems with the /usr/bin/mysqlbug script!\n\nCLIENT_RESULT: MySQL 5.1 database added.  Please make note of these credentials:\nCLIENT_RESULT:        Root User: adminRD6YLti\nCLIENT_RESULT:    Root Password: p1m8ELqzfUA-\nCLIENT_RESULT:    Database Name: railsapp\nCLIENT_RESULT: Connection URL: mysql://$OPENSHIFT_MYSQL_DB_HOST:$OPENSHIFT_MYSQL_DB_PORT/\nCLIENT_RESULT: You can manage your new MySQL database by also embedding phpmyadmin-3.4.\nCLIENT_RESULT: The phpmyadmin username and password will be the same as the MySQL credentials above.\nCART_PROPERTIES: connection_url=mysql://$OPENSHIFT_MYSQL_DB_HOST:$OPENSHIFT_MYSQL_DB_PORT/\nCART_PROPERTIES: username=adminRD6YLti\nCART_PROPERTIES: password=p1m8ELqzfUA-\nCART_PROPERTIES: database_name=railsapp\nAPP_INFO: Connection URL: mysql://127.0.250.130:3306\n", :exitcode=>0}}>] (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:13.285[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_direct: configure): 2.879867542s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:13.293[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"component_instances.1.component_properties"=>{"connection_url"=>"mysql://$OPENSHIFT_MYSQL_DB_HOST:$OPENSHIFT_MYSQL_DB_PORT/", "username"=>"adminRD6YLti", "password"=>"p1m8ELqzfUA-", "database_name"=>"railsapp"}}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:13.293[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (5.5580ms) (pid:4875)
>[0;37m2013-05-30 06:22:13.298[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.12.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:13.298[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (4.4010ms) (pid:4875)
>[0;37m2013-05-30 06:22:13.303[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (4.0765ms) (pid:4875)
>[0;37m2013-05-30 06:22:13.328[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:reserve_uid, "prereq"=>["51a728444efd7ff39d00000a"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000c", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:create_gear, "prereq"=>["51a728444efd7ff39d00000b"], "retry_count"=>0, "retry_rollback_op"=>"51a728444efd7ff39d00000b", "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000014", "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>:track_usage, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000d", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:register_dns, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000015", "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:update_configuration, "prereq"=>["51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "op_type"=>:add_component, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000012", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "op_type"=>:add_component, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000013", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"]}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:13.328[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.7798ms) (pid:4875)
>[0;37m2013-05-30 06:22:13.337[0m [[0;37mDEBUG[0m] Execute post_configure_component (pid:4875)
>[0;37m2013-05-30 06:22:13.340[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"mysql-5.1", :action=>"post-configure", :args=>{"--with-app-uuid"=>"895943249398067907002368", "--with-app-name"=>"railsapp", "--with-container-uuid"=>"895943249398067907002368", "--with-container-name"=>"railsapp", "--with-namespace"=>"domx1", "--with-request-id"=>"292a26b3bd313e15888469bf08046b1c", "--cart-name"=>"mysql-5.1", "--component-name"=>"mysql-5.1", "--with-software-version"=>"5.1", "--cartridge-vendor"=>"redhat"}}, ip-10-154-137-137, {'identity' => ip-10-154-137-137}) (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:15.136[0m [[0;37mDEBUG[0m] DEBUG: [#<MCollective::RPC::Result:0x00000005e55790 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-154-137-137", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:15.138[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_direct: post-configure): 1.800672667s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:22:15.143[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.13.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:15.143[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (3.8311ms) (pid:4875)
>[0;37m2013-05-30 06:22:15.146[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.4360ms) (pid:4875)
>[0;37m2013-05-30 06:22:15.160[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:reserve_uid, "prereq"=>["51a728444efd7ff39d00000a"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000c", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:create_gear, "prereq"=>["51a728444efd7ff39d00000b"], "retry_count"=>0, "retry_rollback_op"=>"51a728444efd7ff39d00000b", "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000014", "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>:track_usage, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000d", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:register_dns, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000015", "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:update_configuration, "prereq"=>["51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "op_type"=>:add_component, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000012", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "op_type"=>:add_component, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000013", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "op_type"=>:post_configure_component, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000016", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"]}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:15.160[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.4675ms) (pid:4875)
>[0;37m2013-05-30 06:22:15.167[0m [[0;37mDEBUG[0m] Execute execute_connections (pid:4875)
>[0;37m2013-05-30 06:22:15.169[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.14.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:15.169[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7188ms) (pid:4875)
>[0;37m2013-05-30 06:22:15.171[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"_id"=>"51a728444efd7ff39d000005"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.3881ms) (pid:4875)
>[0;37m2013-05-30 06:22:15.183[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"51a728444efd7ff39d000007", "args"=>{"group_overrides"=>[{"components"=>[{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, {"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}], "min_gears"=>1, "max_gears"=>1}]}, "op_type"=>:set_group_overrides, "retry_count"=>0, "saved_values"=>{"group_overrides"=>[]}, "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000009", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017"}, "op_type"=>:create_group_instance, "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000a", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "host_singletons"=>true, "app_dns"=>true}, "op_type"=>:init_gear, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000b", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:reserve_uid, "prereq"=>["51a728444efd7ff39d00000a"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000c", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:create_gear, "prereq"=>["51a728444efd7ff39d00000b"], "retry_count"=>0, "retry_rollback_op"=>"51a728444efd7ff39d00000b", "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000014", "args"=>{"user_id"=>"51a727fd4efd7ff39d000001", "parent_user_id"=>nil, "app_name"=>"railsapp", "gear_ref"=>"51a728444efd7ff39d000005", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>"small"}, "op_type"=>:track_usage, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000d", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:register_dns, "prereq"=>["51a728444efd7ff39d00000c"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000015", "args"=>{"add_keys_attrs"=>[], "add_env_vars"=>[], "group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005"}, "op_type"=>:update_configuration, "prereq"=>["51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000e", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "cartridge_vendor"=>"redhat", "version"=>"1.9"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d00000f", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "op_type"=>:add_component, "prereq"=>["51a728444efd7ff39d00000e", "51a728444efd7ff39d00000d"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000010", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:post_configure_component, "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"ruby-1.9", "cart"=>"ruby-1.9"}, "init_git_url"=>"git://github.com/openshift/rails-example.git"}, "prereq"=>["51a728444efd7ff39d00000f", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000016"]}, {"_id"=>"51a728444efd7ff39d000011", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "cartridge_vendor"=>"redhat", "version"=>"5.1"}, "op_type"=>:new_component, "prereq"=>["51a728444efd7ff39d000009", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000012", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "op_type"=>:add_component, "prereq"=>["51a728444efd7ff39d000011", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000013", "args"=>{"group_instance_id"=>"51a728444efd7ff39d000017", "gear_id"=>"51a728444efd7ff39d000005", "comp_spec"=>{"comp"=>"mysql-5.1", "cart"=>"mysql-5.1"}, "init_git_url"=>nil}, "op_type"=>:post_configure_component, "prereq"=>["51a728444efd7ff39d000012", "51a728444efd7ff39d00000d", "51a728444efd7ff39d00000f"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}, {"_id"=>"51a728444efd7ff39d000016", "op_type"=>:execute_connections, "prereq"=>["51a728444efd7ff39d000007", "51a728444efd7ff39d000009", "51a728444efd7ff39d00000a", "51a728444efd7ff39d00000b", "51a728444efd7ff39d00000c", "51a728444efd7ff39d000014", "51a728444efd7ff39d00000d", "51a728444efd7ff39d000015", "51a728444efd7ff39d00000e", "51a728444efd7ff39d00000f", "51a728444efd7ff39d000011", "51a728444efd7ff39d000012", "51a728444efd7ff39d000013"], "retry_count"=>0, "saved_values"=>[], "state"=>:completed}]}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:22:15.183[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.4641ms) (pid:4875)
>[0;37m2013-05-30 06:22:15.190[0m [[0;37mDEBUG[0m] Execute post_configure_component (pid:4875)
>[0;37m2013-05-30 06:22:15.192[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"ruby-1.9", :action=>"post-configure", :args=>{"--with-app-uuid"=>"895943249398067907002368", "--with-app-name"=>"railsapp", "--with-container-uuid"=>"895943249398067907002368", "--with-container-name"=>"railsapp", "--with-namespace"=>"domx1", "--with-request-id"=>"292a26b3bd313e15888469bf08046b1c", "--cart-name"=>"ruby-1.9", "--component-name"=>"ruby-1.9", "--with-software-version"=>"1.9", "--cartridge-vendor"=>"redhat", "--with-template-git-url"=>"git://github.com/openshift/rails-example.git"}}, ip-10-154-137-137, {'identity' => ip-10-154-137-137}) (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:25:03.918[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11212 (pid:4780)
>[0;37m2013-05-30 06:25:03.117[0m [[32mINFO [0m] Started POST "/broker/nurture" for 127.0.0.1 at 2013-05-30 06:25:03 -0400 (pid:4780)
>[0;37m2013-05-30 06:25:03.131[0m [[32mINFO [0m] Processing by BrokerController#nurture_post as XML (pid:4780)
>[0;37m2013-05-30 06:25:03.131[0m [[32mINFO [0m] Parameters: {"json_data"=>"{\"app_uuid\":\"895943249398067907002368\",\"action\":\"push\"}"} (pid:4780)
>[0;37m2013-05-30 06:25:03.132[0m [[33mWARN [0m] WARNING: Can't verify CSRF token authenticity (pid:4780)
>[0;37m2013-05-30 06:25:03.133[0m [[0;37mDEBUG[0m] User-Agent = 'OpenShift' (pid:4780)
>[0;37m2013-05-30 06:25:03.140[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (0.6566ms) (pid:4780)
>[0;37m2013-05-30 06:25:03.145[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"group_instances.gears.uuid"=>"895943249398067907002368"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (3.8781ms) (pid:4780)
>[0;37m2013-05-30 06:25:03.215[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"analytics"=>{"user_agent"=>"openshift_console/1.9.9 (ruby 1.9.3; x86_64-linux)", "push"=>"2013-05-30 06:25:03"}, "updated_at"=>2013-05-30 10:25:03 UTC}} flags=[] (pid:4780)
>[0;37m2013-05-30 06:25:03.220[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.7307ms) (pid:4780)
>[0;37m2013-05-30 06:25:03.221[0m [[32mINFO [0m] Completed 200 OK in 90ms (Views: 0.2ms) (pid:4780)
>[0;37m2013-05-30 06:25:05.649[0m [[0;37mDEBUG[0m] DEBUG: [#<MCollective::RPC::Result:0x00000005cbcc58 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-154-137-137", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"Starting Ruby cart\nhttpd (pid 2181) already running\n", :exitcode=>0}}>] (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:25:05.671[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_direct: post-configure): 169.875631326s  (Request ID: 292a26b3bd313e15888469bf08046b1c) (pid:4875)
>[0;37m2013-05-30 06:25:05.694[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$set"=>{"pending_op_groups.0.pending_ops.10.state"=>:completed}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:25:05.757[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.1086ms) (pid:4875)
>[0;37m2013-05-30 06:25:05.818[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"51a728444efd7ff39d000005"} update={"$pull"=>{"pending_op_groups"=>{"_id"=>"51a728444efd7ff39d000006"}}} flags=[] (pid:4875)
>[0;37m2013-05-30 06:25:05.820[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (4.7331ms) (pid:4875)
>[0;37m2013-05-30 06:25:05.839[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"51a728444efd7ff39d000005"} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.6444ms) (pid:4875)
>[0;37m2013-05-30 06:25:05.879[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"51a728444efd7ff39d000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (2.3384ms) (pid:4875)
>[0;37m2013-05-30 06:25:05.946[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"51a727fd4efd7ff39d000001", "locked"=>false, "app_ids.51a728444efd7ff39d000005"=>{"$exists"=>true}}, :new=>true, :update=>{"$unset"=>{"app_ids.51a728444efd7ff39d000005"=>""}}} (4.5910ms) (pid:4875)
>[0;37m2013-05-30 06:25:05.955[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={:_id=>"51a728444efd7ff39d000004"} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.3791ms) (pid:4875)
>[0;37m2013-05-30 06:25:05.119[0m [[0;37mDEBUG[0m] SUCCESS ACTION=ADD_APPLICATION USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com APP=railsapp DOMAIN=domx1 APP_UUID=895943249398067907002368 Application railsapp was created. Application railsapp was created., MySQL 5.1 database added.  Please make note of these credentials:
>       Root User: adminRD6YLti
>   Root Password: p1m8ELqzfUA-
>   Database Name: railsapp
>Connection URL: mysql://$OPENSHIFT_MYSQL_DB_HOST:$OPENSHIFT_MYSQL_DB_PORT/
>You can manage your new MySQL database by also embedding phpmyadmin-3.4.
>The phpmyadmin username and password will be the same as the MySQL credentials above. (pid:4875)
>[0;37m2013-05-30 06:25:05.139[0m [[32mINFO [0m] Completed 201 Created in 188562ms (Views: 14.4ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.672[0m [[32mINFO [0m] Started GET "/broker/rest/domains.json" for 127.0.0.1 at 2013-05-30 06:38:43 -0400 (pid:4875)
>[0;37m2013-05-30 06:38:43.675[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:38:43.677[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (0.5891ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.678[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.6855ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.680[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4833ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.681[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:38:43.681[0m [[0;37mDEBUG[0m] Getting domains for user xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:38:43.682[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"owner_id"=>"51a727fd4efd7ff39d000001"} flags=[] limit=0 skip=0 batch_size=nil fields=nil (0.4678ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.683[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com (pid:4875)
>[0;37m2013-05-30 06:38:43.684[0m [[32mINFO [0m] Completed 200 OK in 9ms (Views: 0.4ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.692[0m [[32mINFO [0m] Started GET "/broker/rest/domains/domx1/applications.json" for 127.0.0.1 at 2013-05-30 06:38:43 -0400 (pid:4875)
>[0;37m2013-05-30 06:38:43.750[0m [[32mINFO [0m] Processing by ApplicationsController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:38:43.750[0m [[32mINFO [0m] Parameters: {"domain_id"=>"domx1"} (pid:4875)
>[0;37m2013-05-30 06:38:43.755[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.9310ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.756[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4406ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.757[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:38:43.759[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"51a727fd4efd7ff39d000001", "canonical_namespace"=>"domx1"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.6220ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.760[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"domain_id"=>"51a728444efd7ff39d000004"} flags=[] limit=0 skip=0 batch_size=nil fields=nil (0.7110ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.765[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_APPLICATION USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com DOMAIN=domx1 Found 1 applications for domain 'domx1' (pid:4875)
>[0;37m2013-05-30 06:38:43.768[0m [[32mINFO [0m] Completed 200 OK in 17ms (Views: 1.1ms) (pid:4875)
>[0;37m2013-05-30 06:38:43.778[0m [[32mINFO [0m] Started GET "/broker/rest/cartridges.json" for 127.0.0.1 at 2013-05-30 06:38:43 -0400 (pid:4875)
>[0;37m2013-05-30 06:38:43.781[0m [[32mINFO [0m] Processing by CartridgesController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:38:43.792[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_CARTRIDGE USER_ID= LOGIN= List all cartridges (pid:4875)
>[0;37m2013-05-30 06:38:43.805[0m [[32mINFO [0m] Completed 200 OK in 24ms (Views: 11.9ms) (pid:4875)
>[0;37m2013-05-30 06:40:00.655[0m [[32mINFO [0m] Started GET "/broker/rest/domains/domx1/applications/railsapp/gear_groups.json" for 127.0.0.1 at 2013-05-30 06:40:00 -0400 (pid:4875)
>[0;37m2013-05-30 06:40:00.665[0m [[32mINFO [0m] Processing by GearGroupsController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:40:00.665[0m [[32mINFO [0m] Parameters: {"domain_id"=>"domx1", "application_id"=>"railsapp"} (pid:4875)
>[0;37m2013-05-30 06:40:00.668[0m [[32mINFO [0m] Started GET "/broker/rest/user/keys.json" for 127.0.0.1 at 2013-05-30 06:40:00 -0400 (pid:4780)
>[0;37m2013-05-30 06:40:00.674[0m [[32mINFO [0m] Processing by KeysController#index as JSON (pid:4780)
>[0;37m2013-05-30 06:40:00.676[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.8268ms) (pid:4875)
>[0;37m2013-05-30 06:40:00.684[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4289ms) (pid:4875)
>[0;37m2013-05-30 06:40:00.685[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:40:00.691[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"51a727fd4efd7ff39d000001", "canonical_namespace"=>"domx1"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5798ms) (pid:4875)
>[0;37m2013-05-30 06:40:00.692[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"domain_id"=>"51a728444efd7ff39d000004", "canonical_name"=>"railsapp"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.7541ms) (pid:4875)
>[0;37m2013-05-30 06:40:00.695[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (5.7216ms) (pid:4780)
>[0;37m2013-05-30 06:40:00.697[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.5744ms) (pid:4780)
>[0;37m2013-05-30 06:40:00.700[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"51a728444efd7ff39d000004"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (1.1754ms) (pid:4875)
>[0;37m2013-05-30 06:40:00.705[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (7.7362ms) (pid:4780)
>[0;37m2013-05-30 06:40:00.707[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4780)
>[0;37m2013-05-30 06:40:00.710[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_KEY USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com Found 0 ssh keys (pid:4780)
>[0;37m2013-05-30 06:40:00.716[0m [[32mINFO [0m] Completed 200 OK in 41ms (Views: 0.4ms) (pid:4780)
>[0;37m2013-05-30 06:40:00.824[0m [[0;37mDEBUG[0m] DEBUG: Output of parallel execute: [{:tag=>"", :gear=>"895943249398067907002368", :job=>{:cartridge=>"openshift-origin-node", :action=>"app-state-show", :args=>{"--with-app-uuid"=>"895943249398067907002368", "--with-app-name"=>"railsapp", "--with-container-uuid"=>"895943249398067907002368", "--with-container-name"=>"railsapp", "--with-namespace"=>"domx1", "--with-request-id"=>"9b60c758b1e9942acbc3e93e61559a83"}}, :result_stdout=>"started", :result_stderr=>"", :result_exit_code=>0}], exitcode: 0, from: ip-10-154-137-137  (Request ID: 9b60c758b1e9942acbc3e93e61559a83) (pid:4875)
>[0;37m2013-05-30 06:40:00.826[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_parallel): 0.12244461s  (Request ID: 9b60c758b1e9942acbc3e93e61559a83) (pid:4875)
>[0;37m2013-05-30 06:40:02.959[0m [[0;37mDEBUG[0m] DEBUG: find_one_impl: current_server: ip-10-154-137-137 (pid:4875)
>[0;37m2013-05-30 06:40:03.316[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_GEAR_GROUP USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com APP=railsapp DOMAIN=domx1 APP_UUID=895943249398067907002368 Showing gear groups for application 'railsapp' with domain 'domx1' (pid:4875)
>[0;37m2013-05-30 06:40:03.331[0m [[32mINFO [0m] Completed 200 OK in 2666ms (Views: 1.3ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.344[0m [[32mINFO [0m] Started GET "/broker/rest/domains/domx1/applications/railsapp.json?include=cartridges" for 127.0.0.1 at 2013-05-30 06:40:03 -0400 (pid:4875)
>[0;37m2013-05-30 06:40:03.359[0m [[32mINFO [0m] Processing by ApplicationsController#show as JSON (pid:4875)
>[0;37m2013-05-30 06:40:03.359[0m [[32mINFO [0m] Parameters: {"include"=>"cartridges", "domain_id"=>"domx1", "id"=>"railsapp"} (pid:4875)
>[0;37m2013-05-30 06:40:03.368[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.8633ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.373[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.4423ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.374[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:40:03.382[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"51a727fd4efd7ff39d000001", "canonical_namespace"=>"domx1"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.6194ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.386[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"domain_id"=>"51a728444efd7ff39d000004", "canonical_name"=>"railsapp"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (2.6631ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.420[0m [[0;37mDEBUG[0m] SUCCESS ACTION=SHOW_APPLICATION USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com APP=railsapp DOMAIN=domx1 APP_UUID=895943249398067907002368 Application 'railsapp' found (pid:4875)
>[0;37m2013-05-30 06:40:03.437[0m [[32mINFO [0m] Completed 200 OK in 78ms (Views: 11.2ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.698[0m [[32mINFO [0m] Started GET "/broker/rest/domains/domx1/applications/railsapp/aliases.json" for 127.0.0.1 at 2013-05-30 06:40:03 -0400 (pid:4875)
>[0;37m2013-05-30 06:40:03.703[0m [[32mINFO [0m] Processing by AliasController#index as JSON (pid:4875)
>[0;37m2013-05-30 06:40:03.703[0m [[32mINFO [0m] Parameters: {"domain_id"=>"domx1", "application_id"=>"railsapp"} (pid:4875)
>[0;37m2013-05-30 06:40:03.720[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=authorizations selector={"$query"=>{"token"=>"8e7f6e0cbdfed8d3328c0a2cd411ab1931eec7742824ac17bcc5b25fb509d505"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (10.6726ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.723[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"51a727fd4efd7ff39d000001"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (2.9194ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.725[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com IP=127.0.0.1 SCOPES=session Authenticated (pid:4875)
>[0;37m2013-05-30 06:40:03.733[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"owner_id"=>"51a727fd4efd7ff39d000001", "canonical_namespace"=>"domx1"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (8.3601ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.735[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"domain_id"=>"51a728444efd7ff39d000004", "canonical_name"=>"railsapp"}, "$orderby"=>{:_id=>1}} flags=[] limit=-1 skip=0 batch_size=nil fields=nil (0.8507ms) (pid:4875)
>[0;37m2013-05-30 06:40:03.736[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_ALIAS USER_ID=51a727fd4efd7ff39d000001 LOGIN=xtian+test1@redhat.com APP=railsapp DOMAIN=domx1 APP_UUID=895943249398067907002368 Listing aliases for application railsapp under domain domx1 (pid:4875)
>[0;37m2013-05-30 06:40:03.747[0m [[32mINFO [0m] Completed 200 OK in 44ms (Views: 0.5ms) (pid:4875)
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (2.0652ms)
>  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.7477ms)
>  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.4826ms)
>  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.7608ms)
>  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} (0.4513ms)
>  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.4442ms)
>  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.6609ms)
>  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.7961ms)
>  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.5376ms)
>  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.5929ms)
>[0;37m2013-05-30 06:40:12.540[0m [[0;37mDEBUG[0m] 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:9472)
>[0;37m2013-05-30 06:40:12.540[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8407ms) (pid:9472)
>[0;37m2013-05-30 06:40:12.751[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:9472)
>[0;37m2013-05-30 06:40:12.752[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8385ms) (pid:9472)
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (2.1105ms)
>  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.7546ms)
>  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.5186ms)
>  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} (1.8334ms)
>  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} (0.4306ms)
>  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.4880ms)
>  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.5047ms)
>  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.7377ms)
>  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.5291ms)
>  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.5419ms)
>[0;37m2013-05-30 06:40:57.849[0m [[0;37mDEBUG[0m] 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:10076)
>[0;37m2013-05-30 06:40:57.849[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8612ms) (pid:10076)
>[0;37m2013-05-30 06:40:58.671[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:10076)
>[0;37m2013-05-30 06:40:58.673[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.7873ms) (pid:10076)
>[0;37m2013-05-30 06:40:58.291[0m [[0;37mDEBUG[0m] Dalli::Server#connect localhost:11211 (pid:10076)
>[0;37m2013-05-30 06:40:58.302[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 06:40:58 -0400 (pid:10076)
>[0;37m2013-05-30 06:40:58.476[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:10076)
>[0;37m2013-05-30 06:40:58.750[0m [[32mINFO [0m] Completed 200 OK in 274ms (Views: 9.5ms) (pid:10076)
>[0;37m2013-05-30 06:40:58.758[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-05-30 06:40:58 -0400 (pid:10076)
>[0;37m2013-05-30 06:40:58.764[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:10076)
>[0;37m2013-05-30 06:40:58.783[0m [[32mINFO [0m] Completed 200 OK in 19ms (Views: 9.4ms) (pid:10076)
>  MOPED: 127.0.0.1:27017 COMMAND      database=admin command={:ismaster=>1} (3.3247ms)
>  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} (1.5597ms)
>  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} (1.2558ms)
>  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} (2.9178ms)
>  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} (0.4795ms)
>  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.4518ms)
>  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} (1.7254ms)
>  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} (2.9714ms)
>  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.5319ms)
>  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.6840ms)
>[0;37m2013-05-30 06:41:10.483[0m [[0;37mDEBUG[0m] 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:10101)
>[0;37m2013-05-30 06:41:10.485[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8554ms) (pid:10101)
>[0;37m2013-05-30 06:41:10.223[0m [[0;37mDEBUG[0m] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:unique=>true, :ns=>"openshift_broker_dev.open_shift_counters", :key=>{:name=>1}, :name=>"name_1"}] flags=[] (pid:10101)
>[0;37m2013-05-30 06:41:10.223[0m [[0;37mDEBUG[0m] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (0.8082ms) (pid:10101)

Actions: View

Attachments on bug 967504: 754773 | 754774 | 754779 | 754781