Attachment 646193 Details for Bug 872026 – newest developement.log

newest developement.log

file_872026.txt (text/plain), 10.31 KB, created by Rony Gong 🔥 on 2012-11-16 06:16:35 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rony Gong 🔥

Created: 2012-11-16 06:16:35 UTC

Size: 10.31 KB

patch

obsolete

>2012-11-16 01:13:25.777 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000004621570 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-90-246-99", :statuscode=>0, :statusmsg=>"OK", :data=>{:exitcode=>0, :output=>"CLIENT_RESULT: Total Accesses: 0\nCLIENT_RESULT: Total kBytes: 0\nCLIENT_RESULT: Uptime: 664\nCLIENT_RESULT: ReqPerSec: 0\nCLIENT_RESULT: BytesPerSec: 0\nCLIENT_RESULT: BusyWorkers: 1\nCLIENT_RESULT: IdleWorkers: 0\nCLIENT_RESULT: Scoreboard: W....\nATTR: quota_blocks=1048576\nATTR: quota_files=40000\n"}}>] (pid:8122)
>2012-11-16 01:13:25.779 [DEBUG] DEBUG: Cartridge command zend-5.6::status exitcode = 0 (pid:8122)
>2012-11-16 01:13:25.779 [DEBUG] DEBUG: Gear component 'zend-5.6' was running (pid:8122)
>2012-11-16 01:13:25.780 [DEBUG] DEBUG: Stopping existing app cartridge 'zend-5.6' before moving (pid:8122)
>2012-11-16 01:13:25.781 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x0000000479c918> (pid:8122)
>2012-11-16 01:13:25.782 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"zend-5.6", :action=>"stop", :args=>"'qzend' 'qgong5' '0d780ac934634b0aa76e1a50c1b7fba7'"}, ip-10-90-246-99, {'identity' => ip-10-90-246-99}) (pid:8122)
>2012-11-16 01:13:30.506 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000004a58440 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-90-246-99", :statuscode=>0, :statusmsg=>"OK", :data=>{:exitcode=>0, :output=>"Stopping 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"}}>] (pid:8122)
>2012-11-16 01:13:30.509 [DEBUG] DEBUG: Cartridge command zend-5.6::stop exitcode = 0 (pid:8122)
>2012-11-16 01:13:30.509 [DEBUG] DEBUG: Force stopping existing app cartridge 'zend-5.6' before moving (pid:8122)
>2012-11-16 01:13:30.511 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x000000048cd9b8> (pid:8122)
>2012-11-16 01:13:30.511 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"zend-5.6", :action=>"force-stop", :args=>"'qzend' 'qgong5' '0d780ac934634b0aa76e1a50c1b7fba7'"}, ip-10-90-246-99, {'identity' => ip-10-90-246-99}) (pid:8122)
>2012-11-16 01:13:30.695 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000047bc290 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-90-246-99", :statuscode=>0, :statusmsg=>"OK", :data=>{:exitcode=>0, :output=>""}}>] (pid:8122)
>2012-11-16 01:13:30.696 [DEBUG] DEBUG: Cartridge command zend-5.6::force-stop exitcode = 0 (pid:8122)
>2012-11-16 01:13:30.706 [DEBUG] reserve_district_uid(602b2f3a24a140b68d0c28d4407a422a) (pid:8122)
>2012-11-16 01:13:30.714 [DEBUG] DEBUG: Reserved uid '1004' on district: '602b2f3a24a140b68d0c28d4407a422a' (pid:8122)
>2012-11-16 01:13:30.714 [DEBUG] DEBUG: Creating new account for gear 'qzend' on domU-12-31-38-04-7A-E7 (pid:8122)
>2012-11-16 01:13:30.716 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x000000049d76d8> (pid:8122)
>2012-11-16 01:13:30.716 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"0d780ac934634b0aa76e1a50c1b7fba7", "--with-app-name"=>"qzend", "--with-container-uuid"=>"0d780ac934634b0aa76e1a50c1b7fba7", "--with-container-name"=>"qzend", "--with-quota-blocks"=>"1048576", "--with-quota-files"=>"40000", "--with-namespace"=>"qgong5", "--with-uid"=>1004}}, domU-12-31-38-04-7A-E7, {'identity' => domU-12-31-38-04-7A-E7}) (pid:8122)
>2012-11-16 01:13:31.986 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000000d848c0 @agent="openshift", @action="cartridge_do", @results={:sender=>"domU-12-31-38-04-7A-E7", :statuscode=>0, :statusmsg=>"OK", :data=>{:exitcode=>0, :output=>""}}>] (pid:8122)
>2012-11-16 01:13:31.989 [DEBUG] DEBUG: Moving content for app 'qzend', gear 'qzend' to domU-12-31-38-04-7A-E7 (pid:8122)
>2012-11-16 01:13:34.222 [DEBUG] Agent pid 8550
>unset SSH_AUTH_SOCK;
>unset SSH_AGENT_PID;
>echo Agent pid 8550 killed; (pid:8122)
>2012-11-16 01:13:34.222 [DEBUG] DEBUG: Performing cartridge level move for 'zend-5.6' on domU-12-31-38-04-7A-E7 (pid:8122)
>2012-11-16 01:13:34.226 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x0000000114b170> (pid:8122)
>2012-11-16 01:13:34.226 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"zend-5.6", :action=>"move", :args=>"'qzend' 'qgong5' '0d780ac934634b0aa76e1a50c1b7fba7'"}, domU-12-31-38-04-7A-E7, {'identity' => domU-12-31-38-04-7A-E7}) (pid:8122)
>2012-11-16 01:13:36.576 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000002cae818 @agent="openshift", @action="cartridge_do", @results={:sender=>"domU-12-31-38-04-7A-E7", :statuscode=>0, :statusmsg=>"OK", :data=>{:exitcode=>0, :output=>"Nothing found in .openshift/config/* to redeploy\nFILES_DIR=/usr/libexec/openshift/cartridges/zend-5.6/files/user-files CART_DIR=/var/lib/openshift/0d780ac934634b0aa76e1a50c1b7fba7//zend-5.6 USER_ID=1004 GROUP_ID=1004 IP_ADDRESS=127.1.246.1\nConfiguring zend server on move\n"}}>] (pid:8122)
>2012-11-16 01:13:36.578 [DEBUG] DEBUG: Cartridge command zend-5.6::move exitcode = 0 (pid:8122)
>2012-11-16 01:13:36.578 [DEBUG] DEBUG: Starting cartridge 'zend-5.6' in 'qzend' after move on domU-12-31-38-04-7A-E7 (pid:8122)
>2012-11-16 01:13:36.582 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000002d4eb60> (pid:8122)
>2012-11-16 01:13:36.583 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"zend-5.6", :action=>"start", :args=>"'qzend' 'qgong5' '0d780ac934634b0aa76e1a50c1b7fba7'"}, domU-12-31-38-04-7A-E7, {'identity' => domU-12-31-38-04-7A-E7}) (pid:8122)
>2012-11-16 01:13:40.242 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000003c70d78 @agent="openshift", @action="cartridge_do", @results={:sender=>"domU-12-31-38-04-7A-E7", :statuscode=>1, :statusmsg=>"cartridge_do_action failed 121. Output Starting Deployment \e[32m[OK]\e[0m\n[16.11.2012 01:13:39 SYSTEM] watchdog for zdd is running. \n[16.11.2012 01:13:39 SYSTEM] zdd is running. \nStarting Zend Server Monitor node \e[32m[OK]\e[0m\n[16.11.2012 01:13:40 SYSTEM] watchdog for monitor is running. \n[16.11.2012 01:13:40 SYSTEM] monitor is running. \n(13)Permission denied: make_sock: could not bind to address 127.0.250.129:16083\nno listening sockets available, shutting down\nUnable to open logs\nFailed to start zend-5.6\n", :data=>{:exitcode=>121, :output=>"Starting Deployment \e[32m[OK]\e[0m\n[16.11.2012 01:13:39 SYSTEM] watchdog for zdd is running. \n[16.11.2012 01:13:39 SYSTEM] zdd is running. \nStarting Zend Server Monitor node \e[32m[OK]\e[0m\n[16.11.2012 01:13:40 SYSTEM] watchdog for monitor is running. \n[16.11.2012 01:13:40 SYSTEM] monitor is running. \n(13)Permission denied: make_sock: could not bind to address 127.0.250.129:16083\nno listening sockets available, shutting down\nUnable to open logs\nFailed to start zend-5.6\n"}}>] (pid:8122)
>2012-11-16 01:13:40.244 [DEBUG] DEBUG: Cartridge command zend-5.6::start exitcode = 121 (pid:8122)
>2012-11-16 01:13:40.245 [DEBUG] DEBUG: Moving failed.  Rolling back gear 'qzend' 'qzend' with remove-httpd-proxy on 'domU-12-31-38-04-7A-E7' (pid:8122)
>2012-11-16 01:13:40.248 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x00000003c0b248> (pid:8122)
>2012-11-16 01:13:40.248 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"zend-5.6", :action=>"remove-httpd-proxy", :args=>"'qzend' 'qgong5' '0d780ac934634b0aa76e1a50c1b7fba7'"}, domU-12-31-38-04-7A-E7, {'identity' => domU-12-31-38-04-7A-E7}) (pid:8122)
>2012-11-16 01:13:40.345 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000042bb520 @agent="openshift", @action="cartridge_do", @results={:sender=>"domU-12-31-38-04-7A-E7", :statuscode=>0, :statusmsg=>"OK", :data=>{:exitcode=>0, :output=>""}}>] (pid:8122)
>2012-11-16 01:13:40.346 [DEBUG] DEBUG: Cartridge command zend-5.6::remove-httpd-proxy exitcode = 0 (pid:8122)
>2012-11-16 01:13:40.347 [DEBUG] DEBUG: Moving failed.  Rolling back gear 'qzend' in 'qzend' with destroy on 'domU-12-31-38-04-7A-E7' (pid:8122)
>2012-11-16 01:13:40.349 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x0000000417d208> (pid:8122)
>2012-11-16 01:13:40.350 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"0d780ac934634b0aa76e1a50c1b7fba7", "--with-app-name"=>"qzend", "--with-container-uuid"=>"0d780ac934634b0aa76e1a50c1b7fba7", "--with-container-name"=>"qzend", "--with-namespace"=>"qgong5", "--skip-hooks"=>true}}, domU-12-31-38-04-7A-E7, {'identity' => domU-12-31-38-04-7A-E7}) (pid:8122)
>2012-11-16 01:13:41.758 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000003bd5530 @agent="openshift", @action="cartridge_do", @results={:sender=>"domU-12-31-38-04-7A-E7", :statuscode=>0, :statusmsg=>"OK", :data=>{:exitcode=>0, :output=>""}}>] (pid:8122)
>2012-11-16 01:13:41.826 [DEBUG] unreserve_district_uid(602b2f3a24a140b68d0c28d4407a422a) (pid:8122)
>2012-11-16 01:13:41.836 [DEBUG] DEBUG: rpc_exec_direct: rpc_client=#<MCollective::RPC::Client:0x0000000471aa30> (pid:8122)
>2012-11-16 01:13:41.836 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"zend-5.6", :action=>"start", :args=>"'qzend' 'qgong5' '0d780ac934634b0aa76e1a50c1b7fba7'"}, ip-10-90-246-99, {'identity' => ip-10-90-246-99}) (pid:8122)
>2012-11-16 01:13:49.394 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000043ce458 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-90-246-99", :statuscode=>0, :statusmsg=>"OK", :data=>{:exitcode=>0, :output=>"Starting Deployment \e[32m[OK]\e[0m\n[16.11.2012 01:13:43 SYSTEM] watchdog for zdd is running. \n[16.11.2012 01:13:43 SYSTEM] zdd is running. \nStarting Zend Server Monitor node \e[32m[OK]\e[0m\n[16.11.2012 01:13:44 SYSTEM] watchdog for monitor is running. \n[16.11.2012 01:13:44 SYSTEM] monitor is running. \nStarting JobQueue \e[32m[OK]\e[0m\n[16.11.2012 01:13:47 SYSTEM] watchdog for jqd is running. \n[16.11.2012 01:13:47 SYSTEM] jqd is running. \nspawn-fcgi: child spawned successfully: PID: 8884\nStarting Zend Server GUI [Lighttpd] \e[32m[OK]\e[0m\n[16.11.2012 01:13:49 SYSTEM] watchdog for lighttpd is running. \n[16.11.2012 01:13:49 SYSTEM] lighttpd is running. \n"}}>] (pid:8122)
>2012-11-16 01:13:49.396 [DEBUG] DEBUG: Cartridge command zend-5.6::start exitcode = 0 (pid:8122)

Actions: View

Attachments on bug 872026: 636427 | 646193