Attachment 606099 Details for Bug 850626 – development.log

development.log

file_850626.txt (text/plain), 14.64 KB, created by Rony Gong 🔥 on 2012-08-22 02:53:41 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rony Gong 🔥

Created: 2012-08-22 02:53:41 UTC

Size: 14.64 KB

patch

obsolete

>[29546] WARN  DEPRECATION WARNING: config.action_view.debug_rjs will be removed in 3.1, from 3.1 onwards you will need to install prototype-rails to continue to use RJS templates . (called from /var/www/stickshift/broker/config/environment.rb:5)
>[29546] DEBUG MongoDataStore.find_by_gear_uuid(5643129f2f4445ba808f4e4a34566017)
>[29546] DEBUG DEBUG: Source district uuid: 8979679e0cbd4951a7e8572876384093
>[29546] DEBUG DEBUG: Destination district uuid: 8979679e0cbd4951a7e8572876384093
>[29546] DEBUG DEBUG: District unchanged keeping uid
>[29546] DEBUG CURRENT SERVER: ip-10-120-178-175
>[29546] DEBUG DEBUG: find_one_impl: current_server: ip-10-120-178-175
>[29546] DEBUG DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x7f556d7cc670>
>[29546] DEBUG DEBUG: rpc_client.custom_request('cartridge_do', {:action=>"cartridge-list", :cartridge=>"stickshift-node", :args=>{"--with-descriptors"=>true, "--porcelain"=>true}}, @id, {'identity' => @id})
>[29546] DEBUG DEBUG: Getting existing app 'q2zend' status before moving
>[29546] DEBUG DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x7f556db198f0>
>[29546] DEBUG DEBUG: rpc_client.custom_request('cartridge_do', {:action=>"status", :cartridge=>"zend-5.6", :args=>"'q2zend' 'qgong' '5643129f2f4445ba808f4e4a34566017'"}, @id, {'identity' => @id})
>[29546] DEBUG DEBUG: [#<MCollective::RPC::Result:0x7f556daeb838 @results={:sender=>"ip-10-120-178-175", :statuscode=>0, :data=>{:output=>"CLIENT_RESULT: Total Accesses: 8\nCLIENT_RESULT: Total kBytes: 17\nCLIENT_RESULT: Uptime: 131\nCLIENT_RESULT: ReqPerSec: .0610687\nCLIENT_RESULT: BytesPerSec: 132.885\nCLIENT_RESULT: BytesPerReq: 2176\nCLIENT_RESULT: BusyWorkers: 1\nCLIENT_RESULT: IdleWorkers: 0\nCLIENT_RESULT: Scoreboard: W....\nATTR: quota_blocks=1048576\nATTR: quota_files=40000\n", :exitcode=>0}, :statusmsg=>"OK"}, @action="cartridge_do", @agent="stickshift">]
>[29546] DEBUG DEBUG: Cartridge command zend-5.6::status exitcode = 0
>[29546] DEBUG DEBUG: Gear component 'zend-5.6' was running
>[29546] DEBUG DEBUG: Stopping existing app cartridge 'zend-5.6' before moving
>[29546] DEBUG DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x7f556dae42b8>
>[29546] DEBUG DEBUG: rpc_client.custom_request('cartridge_do', {:action=>"stop", :cartridge=>"zend-5.6", :args=>"'q2zend' 'qgong' '5643129f2f4445ba808f4e4a34566017'"}, @id, {'identity' => @id})
>[29546] DEBUG DEBUG: [#<MCollective::RPC::Result:0x7f556da9cc10 @results={:sender=>"ip-10-120-178-175", :statuscode=>0, :data=>{:output=>"\nStopping Deployment \e[32m[OK]\e[0m\nStopping Zend Server Monitor node \e[32m[OK]\e[0m\nWaiting for stop to finish\nStopping JobQueue \e[32m[OK]\e[0m\nStopping Zend Server GUI [Lighttpd] \e[32m[OK]\e[0m\nATTR: status=ALREADY_STOPPED\n", :exitcode=>0}, :statusmsg=>"OK"}, @action="cartridge_do", @agent="stickshift">]
>[29546] DEBUG DEBUG: Cartridge command zend-5.6::stop exitcode = 0
>[29546] DEBUG DEBUG: Force stopping existing app cartridge 'zend-5.6' before moving
>[29546] DEBUG DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x7f556da96c20>
>[29546] DEBUG DEBUG: rpc_client.custom_request('cartridge_do', {:action=>"force-stop", :cartridge=>"zend-5.6", :args=>"'q2zend' 'qgong' '5643129f2f4445ba808f4e4a34566017'"}, @id, {'identity' => @id})
>[29546] DEBUG DEBUG: [#<MCollective::RPC::Result:0x7f556da5fa40 @results={:sender=>"ip-10-120-178-175", :statuscode=>0, :data=>{:output=>"", :exitcode=>0}, :statusmsg=>"OK"}, @action="cartridge_do", @agent="stickshift">]
>[29546] DEBUG DEBUG: Cartridge command zend-5.6::force-stop exitcode = 0
>[29546] DEBUG DEBUG: Creating new account for gear 'q2zend' on ip-10-121-5-119
>[29546] DEBUG DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x7f556da53600>
>[29546] DEBUG DEBUG: rpc_client.custom_request('cartridge_do', {:action=>"app-create", :cartridge=>"stickshift-node", :args=>{"--with-uid"=>1006, "--with-namespace"=>"qgong", "--with-quota-files"=>"40000", "--with-container-uuid"=>"5643129f2f4445ba808f4e4a34566017", "--with-app-name"=>"q2zend", "--with-container-name"=>"q2zend", "--with-app-uuid"=>"5643129f2f4445ba808f4e4a34566017", "--with-quota-blocks"=>"1048576"}}, @id, {'identity' => @id})
>[29546] DEBUG DEBUG: [#<MCollective::RPC::Result:0x7f556da21f60 @results={:sender=>"ip-10-121-5-119", :statuscode=>0, :data=>{:output=>"", :exitcode=>0}, :statusmsg=>"OK"}, @action="cartridge_do", @agent="stickshift">]
>[29546] DEBUG DEBUG: Moving content for app 'q2zend', gear 'q2zend' to ip-10-121-5-119
>[29546] DEBUG Agent pid 30152
>unset SSH_AUTH_SOCK;
>unset SSH_AGENT_PID;
>echo Agent pid 30152 killed;
>[29546] DEBUG DEBUG: Performing cartridge level move for 'zend-5.6' on ip-10-121-5-119
>[29546] DEBUG DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x7f556d9c8488>
>[29546] DEBUG DEBUG: rpc_client.custom_request('cartridge_do', {:action=>"move", :cartridge=>"zend-5.6", :args=>"'q2zend' 'qgong' '5643129f2f4445ba808f4e4a34566017'"}, @id, {'identity' => @id})
>[29546] DEBUG DEBUG: [#<MCollective::RPC::Result:0x7f556d998990 @results={:sender=>"ip-10-121-5-119", :statuscode=>0, :data=>{:output=>"Nothing found in .openshift/config/* to redeploy\n", :exitcode=>0}, :statusmsg=>"OK"}, @action="cartridge_do", @agent="stickshift">]
>[29546] DEBUG DEBUG: Cartridge command zend-5.6::move exitcode = 0
>[29546] DEBUG DEBUG: Starting cartridge 'zend-5.6' in 'q2zend' after move on ip-10-121-5-119
>[29546] DEBUG DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x7f556d993670>
>[29546] DEBUG DEBUG: rpc_client.custom_request('cartridge_do', {:action=>"start", :cartridge=>"zend-5.6", :args=>"'q2zend' 'qgong' '5643129f2f4445ba808f4e4a34566017'"}, @id, {'identity' => @id})
>[29546] DEBUG DEBUG: [#<MCollective::RPC::Result:0x7f556d9297e8 @results={:sender=>"ip-10-121-5-119", :statuscode=>0, :data=>{:output=>"\nStarting Deployment \e[32m[OK]\e[0m\n[21.08.2012 22:25:14 SYSTEM] watchdog for zdd is running. \n[21.08.2012 22:25:14 SYSTEM] zdd is running. \nStarting Zend Server Monitor node \e[32m[OK]\e[0m\n[21.08.2012 22:25:15 SYSTEM] watchdog for monitor is running. \n[21.08.2012 22:25:15 SYSTEM] monitor is running. \nStarting JobQueue \e[32m[OK]\e[0m\n[21.08.2012 22:25:17 SYSTEM] watchdog for jqd is running. \n[21.08.2012 22:25:17 SYSTEM] jqd is running. \nspawn-fcgi: child spawned successfully: PID: 30505\nStarting Zend Server GUI [Lighttpd] \e[32m[OK]\e[0m\n[21.08.2012 22:25:19 SYSTEM] watchdog for lighttpd is running. \n[21.08.2012 22:25:19 SYSTEM] lighttpd is running. \n", :exitcode=>0}, :statusmsg=>"OK"}, @action="cartridge_do", @agent="stickshift">]
>[29546] DEBUG DEBUG: Cartridge command zend-5.6::start exitcode = 0
>[29546] DEBUG DEBUG: Fixing DNS and mongo for gear 'q2zend' after move
>[29546] DEBUG DEBUG: Changing server identity of 'q2zend' from 'ip-10-120-178-175' to 'ip-10-121-5-119'
>[29546] DEBUG DEBUG: DYNECT Login with path: /REST/Session/
>[29546] DEBUG DEBUG: DYNECT Response: {"status": "success", "data": {"token": "Zri3ok96IvlNGHYt7W92aFcWtTB7P3BkHRBxarQeBzvDfIj9AJMboXtvRdnURpYHRZjQuVx/6q48d4F48s03+8dzDnnGqfSG9TUNEfKyxI6g5/0mIByamrVgBGIKSsvQNTYjEL5WW+tDdg3ENsCQ9hS9VWrLKEwY", "version": "3.3.5"}, "job_id": 160394248, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]}
>[29546] DEBUG DEBUG: DYNECT Response data: tokenZri3ok96IvlNGHYt7W92aFcWtTB7P3BkHRBxarQeBzvDfIj9AJMboXtvRdnURpYHRZjQuVx/6q48d4F48s03+8dzDnnGqfSG9TUNEfKyxI6g5/0mIByamrVgBGIKSsvQNTYjEL5WW+tDdg3ENsCQ9hS9VWrLKEwYversion3.3.5
>[29546] DEBUG DEBUG: Public ip being modified 'ec2-23-22-43-193.compute-1.amazonaws.com' to app 'q2zend'
>[29546] DEBUG DEBUG: DYNECT put/post with path: /REST/CNAMERecord/rhcloud.com/q2zend-qgong.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-23-22-43-193.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Auth-Token"=>"[hidden]", "Content-Type"=>"application/json"}
>[29546] DEBUG DEBUG: DYNECT Response: {"status": "success", "data": {"zone": "rhcloud.com", "ttl": 60, "fqdn": "q2zend-qgong.dev.rhcloud.com", "record_type": "CNAME", "rdata": {"cname": "ec2-23-22-43-193.compute-1.amazonaws.com."}, "record_id": 0}, "job_id": 160394253, "msgs": [{"INFO": "update: Record updated", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]}
>[29546] DEBUG DEBUG: DYNECT Response data: zonerhcloud.comfqdnq2zend-qgong.dev.rhcloud.comrecord_id0rdatacnameec2-23-22-43-193.compute-1.amazonaws.com.record_typeCNAMEttl60
>[29546] DEBUG DEBUG: DYNECT put/post with path: /REST/Zone/rhcloud.com/ json data: {"publish":"true"} and headers: {"Auth-Token"=>"[hidden]", "Content-Type"=>"application/json"}
>[29546] DEBUG DEBUG: DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "increment", "serial": 1901343, "zone": "rhcloud.com"}, "job_id": 160394258, "msgs": [{"INFO": "publish: rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]}
>[29546] DEBUG DEBUG: DYNECT Response data: zonerhcloud.comserial1901343zone_typePrimaryserial_styleincrement
>[29546] DEBUG DEBUG: DYNECT delete with path: /REST/Session/ and headers: {"Auth-Token"=>"[hidden]", "Content-Type"=>"application/json"}
>[29546] DEBUG DEBUG: DYNECT Response: {"status": "success", "data": {}, "job_id": 160394269, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]}
>[29546] DEBUG DEBUG: DYNECT Response data: 
>[29546] DEBUG MongoDataStore.save(Application, qgong@redhat.com, q2zend, #hidden)
>[29546] DEBUG DEBUG: Deconfiguring old app 'q2zend' on ip-10-120-178-175 after move
>[29546] DEBUG DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x7f556d8729f8>
>[29546] DEBUG DEBUG: rpc_client.custom_request('cartridge_do', {:action=>"app-destroy", :cartridge=>"stickshift-node", :args=>{"--with-namespace"=>"qgong", "--with-container-uuid"=>"5643129f2f4445ba808f4e4a34566017", "--skip-hooks"=>true, "--with-app-name"=>"q2zend", "--with-container-name"=>"q2zend", "--with-app-uuid"=>"5643129f2f4445ba808f4e4a34566017"}}, @id, {'identity' => @id})
>[29546] DEBUG DEBUG: [#<MCollective::RPC::Result:0x7f556d83f9b8 @results={:sender=>"ip-10-120-178-175", :statuscode=>0, :data=>{:output=>"", :exitcode=>0}, :statusmsg=>"OK"}, @action="cartridge_do", @agent="stickshift">]
>[29546] DEBUG Successfully moved 'q2zend' with gear uuid '5643129f2f4445ba808f4e4a34566017' from 'ip-10-120-178-175' to 'ip-10-121-5-119'
>[1961] INFO  Started GET "/broker/rest/api" for 203.114.244.88 at Tue Aug 21 22:26:30 -0400 2012
>[1961] INFO    Processing by BaseController#show as JSON
>[1961] INFO  Completed 200 OK in 21ms (Views: 14.6ms)
>[1961] INFO  Started GET "/broker/rest/api" for 203.114.244.88 at Tue Aug 21 22:26:31 -0400 2012
>[1961] INFO    Processing by BaseController#show as JSON
>[1961] INFO  Completed 200 OK in 20ms (Views: 13.1ms)
>[1961] INFO  Started GET "/broker/rest/domains" for 203.114.244.88 at Tue Aug 21 22:26:32 -0400 2012
>[1961] INFO    Processing by DomainsController#index as JSON
>[1961] DEBUG MongoDataStore.find(CloudUser, qgong@redhat.com, qgong@redhat.com)
>[1961] DEBUG Getting domains for user qgong@redhat.com
>[1961] DEBUG #<Domain:0x7fb2a65103a0>
>[1961] DEBUG [REQ_ID=d945cf351cdd434385285a4e06d92799] ACTION=LIST_DOMAINS 
>[1961] INFO  Completed 200 OK in 107ms (Views: 18.2ms)
>[1961] INFO  Started GET "/broker/rest/domains/qgong/applications" for 203.114.244.88 at Tue Aug 21 22:26:34 -0400 2012
>[1961] INFO    Processing by ApplicationsController#index as JSON
>[1961] INFO    Parameters: {"domain_id"=>"qgong"}
>[1961] DEBUG MongoDataStore.find(CloudUser, qgong@redhat.com, qgong@redhat.com)
>[1961] DEBUG [REQ_ID=fbc27fbbc0b94269b09909cf3cfcdfa9] ACTION=LIST_APPLICATIONS Found 5 applications for domain 'qgong'
>[1961] INFO  Completed 200 OK in 400ms (Views: 260.9ms)
>[1961] INFO  Started GET "/broker/rest/domains/qgong/applications/qjbosseap/cartridges" for 203.114.244.88 at Tue Aug 21 22:26:35 -0400 2012
>[1961] INFO    Processing by EmbCartController#index as JSON
>[1961] INFO    Parameters: {"application_id"=>"qjbosseap", "domain_id"=>"qgong"}
>[1961] DEBUG MongoDataStore.find(CloudUser, qgong@redhat.com, qgong@redhat.com)
>[1961] DEBUG Getting cartridges for application qjbosseap under domain qgong
>[1961] DEBUG [REQ_ID=fcdca7fec77147ef8d3db4b4d7d84842] ACTION=LIST_APP_CARTRIDGES Listing cartridges for application qjbosseap under domain qgong
>[1961] INFO  Completed 200 OK in 105ms (Views: 18.8ms)
>[1961] INFO  Started GET "/broker/rest/domains/qgong/applications/qjboss/cartridges" for 203.114.244.88 at Tue Aug 21 22:26:36 -0400 2012
>[1961] INFO    Processing by EmbCartController#index as JSON
>[1961] INFO    Parameters: {"application_id"=>"qjboss", "domain_id"=>"qgong"}
>[1961] DEBUG MongoDataStore.find(CloudUser, qgong@redhat.com, qgong@redhat.com)
>[1961] DEBUG Getting cartridges for application qjboss under domain qgong
>[1961] DEBUG [REQ_ID=a234400e39ab485ab7ba2a1645d86163] ACTION=LIST_APP_CARTRIDGES Listing cartridges for application qjboss under domain qgong
>[1961] INFO  Completed 200 OK in 209ms (Views: 20.0ms)
>[1961] INFO  Started GET "/broker/rest/domains/qgong/applications/qzend/cartridges" for 203.114.244.88 at Tue Aug 21 22:26:38 -0400 2012
>[1961] INFO    Processing by EmbCartController#index as JSON
>[1961] INFO    Parameters: {"application_id"=>"qzend", "domain_id"=>"qgong"}
>[1961] DEBUG MongoDataStore.find(CloudUser, qgong@redhat.com, qgong@redhat.com)
>[1961] DEBUG Getting cartridges for application qzend under domain qgong
>[1961] DEBUG [REQ_ID=285ffc6dc8244c52be9f25cecffd0821] ACTION=LIST_APP_CARTRIDGES Listing cartridges for application qzend under domain qgong
>[1961] INFO  Completed 200 OK in 109ms (Views: 15.0ms)
>[1961] INFO  Started GET "/broker/rest/domains/qgong/applications/qruby19/cartridges" for 203.114.244.88 at Tue Aug 21 22:26:39 -0400 2012
>[1961] INFO    Processing by EmbCartController#index as JSON
>[1961] INFO    Parameters: {"application_id"=>"qruby19", "domain_id"=>"qgong"}
>[1961] DEBUG MongoDataStore.find(CloudUser, qgong@redhat.com, qgong@redhat.com)
>[1961] DEBUG Getting cartridges for application qruby19 under domain qgong
>[1961] DEBUG [REQ_ID=c3878fb5c0cd4e2baa418f9c52cff7f6] ACTION=LIST_APP_CARTRIDGES Listing cartridges for application qruby19 under domain qgong
>[1961] INFO  Completed 200 OK in 106ms (Views: 18.1ms)
>[1961] INFO  Started GET "/broker/rest/domains/qgong/applications/q2zend/cartridges" for 203.114.244.88 at Tue Aug 21 22:26:40 -0400 2012
>[1961] INFO    Processing by EmbCartController#index as JSON
>[1961] INFO    Parameters: {"application_id"=>"q2zend", "domain_id"=>"qgong"}
>[1961] DEBUG MongoDataStore.find(CloudUser, qgong@redhat.com, qgong@redhat.com)
>[1961] DEBUG Getting cartridges for application q2zend under domain qgong
>[1961] DEBUG [REQ_ID=a195933ab1c24cdca74e0a69a511e3a7] ACTION=LIST_APP_CARTRIDGES Listing cartridges for application q2zend under domain qgong
>[1961] INFO  Completed 200 OK in 83ms (Views: 2.0ms)

Actions: View

Attachments on bug 850626: 606099