Attachment 704092 Details for Bug 916918 – development.log

development.log

file_916918.txt (text/plain), 9.25 KB, created by Rony Gong 🔥 on 2013-03-01 09:23:22 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rony Gong 🔥

Created: 2013-03-01 09:23:22 UTC

Size: 9.25 KB

patch

obsolete

>2013-03-01 03:27:33.823 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (1.4703ms) (pid:8726)
>2013-03-01 03:27:33.868 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=system.indexes documents=[{:ns=>"openshift_broker_dev.locks", :key=>{:user_id=>1}, :name=>"user_id_1"}] flags=[] (pid:8726)
>2013-03-01 03:27:33.868 [DEBUG] COMMAND      database=openshift_broker_dev command={:getlasterror=>1, :safe=>true} (2.4245ms) (pid:8726)
>2013-03-01 03:27:34.587 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"group_instances.gears.uuid"=>"513051ace5a26827c60001ff"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (3.6621ms) (pid:8726)
>2013-03-01 03:27:34.595 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=domains selector={"$query"=>{"_id"=>"513048fbe5a26827c6000005"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (2.0249ms) (pid:8726)
>2013-03-01 03:27:34.605 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=cloud_users selector={"$query"=>{"_id"=>"513048e8e5a26827c6000001"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 batch_size=nil fields=nil (2.9469ms) (pid:8726)
>2013-03-01 03:27:34.609 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"513048e8e5a26827c6000001", "$or"=>[{"app_ids.513051ace5a26827c60001ff"=>{"$exists"=>false}}, {"app_ids.513051ace5a26827c60001ff"=>{"$lt"=>1362126454}}]}, :new=>true, :update=>{"$set"=>{"app_ids.513051ace5a26827c60001ff"=>1362127054}}} (1.3826ms) (pid:8726)
>2013-03-01 03:27:35.823 [DEBUG] DEBUG: Source district uuid: 513048a7e5a26865ad000001 (pid:8726)
>2013-03-01 03:27:35.825 [DEBUG] DEBUG: Destination district uuid: 513048a7e5a26865ad000001 (pid:8726)
>2013-03-01 03:27:35.825 [DEBUG] DEBUG: District unchanged keeping uid (pid:8726)
>2013-03-01 03:27:36.170 [DEBUG] DEBUG: Getting existing app 'redmine' status before moving (pid:8726)
>2013-03-01 03:27:36.184 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000005aa03c0> (pid:8726)
>2013-03-01 03:27:36.184 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"ruby-1.8", :action=>"status", :args=>{"--with-app-uuid"=>"513051ace5a26827c60001ff", "--with-app-name"=>"redmine", "--with-container-uuid"=>"513051ace5a26827c60001ff", "--with-container-name"=>"redmine", "--with-namespace"=>"qgong1", "--with-uid"=>1016, "--with-request-id"=>nil, "--cart-name"=>"ruby-1.8"}}, ip-10-147-168-174, {'identity' => ip-10-147-168-174}) (Request ID: ) (pid:8726)
>2013-03-01 03:27:37.458 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000005a81100 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-147-168-174", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"CLIENT_RESULT: Application 'redmine' is either stopped or inaccessible\nATTR: status=ALREADY_STOPPED\nATTR: quota_blocks=1048576\nATTR: quota_files=40000\n", :exitcode=>0}}>] (Request ID: ) (pid:8726)
>2013-03-01 03:27:37.460 [DEBUG] DEBUG: MCollective Response Time (execute_direct: status): 1.282138065s  (Request ID: ) (pid:8726)
>2013-03-01 03:27:37.461 [DEBUG] DEBUG: Gear component 'ruby-1.8' was stopped (pid:8726)
>2013-03-01 03:27:37.516 [DEBUG] DEBUG: Creating new account for gear 'redmine' on ip-10-147-194-239 (pid:8726)
>2013-03-01 03:27:37.517 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000005da8590> (pid:8726)
>2013-03-01 03:27:37.520 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"513051ace5a26827c60001ff", "--with-app-name"=>"redmine", "--with-container-uuid"=>"513051ace5a26827c60001ff", "--with-container-name"=>"redmine", "--with-quota-blocks"=>"1048576", "--with-quota-files"=>"40000", "--with-namespace"=>"qgong1", "--with-uid"=>1016, "--with-request-id"=>nil, "--cart-name"=>"openshift-origin-node"}}, ip-10-147-194-239, {'identity' => ip-10-147-194-239}) (Request ID: ) (pid:8726)
>2013-03-01 03:27:40.770 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000005a100b8 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-147-194-239", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (Request ID: ) (pid:8726)
>2013-03-01 03:27:40.773 [DEBUG] DEBUG: MCollective Response Time (execute_direct: app-create): 3.256395548s  (Request ID: ) (pid:8726)
>2013-03-01 03:27:40.776 [DEBUG] DEBUG: Moving content for app 'redmine', gear 'redmine' to ip-10-147-194-239 (pid:8726)
>2013-03-01 03:27:54.791 [DEBUG] Agent pid 8896
>unset SSH_AUTH_SOCK;
>unset SSH_AGENT_PID;
>echo Agent pid 8896 killed; (pid:8726)
>2013-03-01 03:27:54.791 [DEBUG] DEBUG: Moving system components for app 'redmine', gear 'redmine' to ip-10-147-194-239 (pid:8726)
>2013-03-01 03:27:56.134 [DEBUG] Agent pid 8971
>unset SSH_AUTH_SOCK;
>unset SSH_AGENT_PID;
>echo Agent pid 8971 killed; (pid:8726)
>2013-03-01 03:27:56.140 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x000000053a9440> (pid:8726)
>2013-03-01 03:27:56.140 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"frontend-backup", :args=>{"--with-container-uuid"=>"513051ace5a26827c60001ff", "--with-container-name"=>"redmine", "--with-namespace"=>"qgong1", "--cart-name"=>"openshift-origin-node"}}, ip-10-147-168-174, {'identity' => ip-10-147-168-174}) (Request ID: ) (pid:8726)
>2013-03-01 03:27:56.244 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000005b94bc8 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-147-168-174", :statuscode=>4, :statusmsg=>"Input action did not pass validation: value should match (?-mix:\\A(app-create|app-destroy|env-var-add|env-var-remove|broker-auth-key-add|broker-auth-key-remove|authorized-ssh-key-add|authorized-ssh-key-remove|ssl-cert-add|ssl-cert-remove|configure|deconfigure|update-namespace|tidy|deploy-httpd-proxy|remove-httpd-proxy|restart-httpd-proxy|move|pre-move|post-move|info|post-install|post-remove|pre-install|reload|restart|start|status|stop|force-stop|add-alias|remove-alias|threaddump|cartridge-list|expose-port|system-messages|connector-execute|get-quota|set-quota)\\Z)", :data=>{:time=>nil, :output=>nil, :exitcode=>nil}}>] (Request ID: ) (pid:8726)
>2013-03-01 03:27:56.246 [DEBUG] DEBUG: MCollective Response Time (execute_direct: frontend-backup): 0.111708413s  (Request ID: ) (pid:8726)
>2013-03-01 03:27:56.247 [DEBUG] DEBUG: Moving failed.  Rolling back gear 'redmine' 'redmine' with remove-httpd-proxy on 'ip-10-147-194-239' (pid:8726)
>2013-03-01 03:27:56.263 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000005bd8800> (pid:8726)
>2013-03-01 03:27:56.265 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"ruby-1.8", :action=>"remove-httpd-proxy", :args=>{"--with-app-uuid"=>"513051ace5a26827c60001ff", "--with-app-name"=>"redmine", "--with-container-uuid"=>"513051ace5a26827c60001ff", "--with-container-name"=>"redmine", "--with-namespace"=>"qgong1", "--with-uid"=>1016, "--with-request-id"=>nil, "--cart-name"=>"ruby-1.8"}}, ip-10-147-194-239, {'identity' => ip-10-147-194-239}) (Request ID: ) (pid:8726)
>2013-03-01 03:27:58.587 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000005dfb1c8 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-147-194-239", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (Request ID: ) (pid:8726)
>2013-03-01 03:27:58.613 [DEBUG] DEBUG: MCollective Response Time (execute_direct: remove-httpd-proxy): 1.798884392s  (Request ID: ) (pid:8726)
>2013-03-01 03:27:58.694 [DEBUG] DEBUG: Moving failed.  Rolling back gear 'redmine' in 'redmine' with destroy on 'ip-10-147-194-239' (pid:8726)
>2013-03-01 03:27:58.746 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x0000000617ef20> (pid:8726)
>2013-03-01 03:27:58.748 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"513051ace5a26827c60001ff", "--with-app-name"=>"redmine", "--with-container-uuid"=>"513051ace5a26827c60001ff", "--with-container-name"=>"redmine", "--with-namespace"=>"qgong1", "--with-uid"=>1016, "--with-request-id"=>nil, "--skip-hooks"=>true, "--cart-name"=>"openshift-origin-node"}}, ip-10-147-194-239, {'identity' => ip-10-147-194-239}) (Request ID: ) (pid:8726)
>2013-03-01 03:28:06.193 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000006200638 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-147-194-239", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0}}>] (Request ID: ) (pid:8726)
>2013-03-01 03:28:06.201 [DEBUG] DEBUG: MCollective Response Time (execute_direct: app-destroy): 8.131177621s  (Request ID: ) (pid:8726)
>2013-03-01 03:28:06.251 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"513048e8e5a26827c6000001", "locked"=>false, "app_ids.513051ace5a26827c60001ff"=>{"$exists"=>true}}, :new=>true, :update=>{"$unset"=>{"app_ids.513051ace5a26827c60001ff"=>""}}} (11.3497ms) (pid:8726)

Actions: View

Attachments on bug 916918: 704092 | 704765