Attachment 862099 Details for Bug 1062526 – wordpress quickstart scale-up failed log

wordpress quickstart scale-up failed log

wordpress.log (text/x-log), 68.67 KB, created by weiwei jiang on 2014-02-12 03:16:26 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: weiwei jiang

Created: 2014-02-12 03:16:26 UTC

Size: 68.67 KB

patch

obsolete

>2014-02-11 22:11:09.273 [INFO ] Started GET "/broker/rest/api" for 209.132.188.8 at 2014-02-11 22:11:09 -0500 (pid:2662)
>2014-02-11 22:11:09.275 [INFO ] Processing by ApiController#show as JSON (pid:2662)
>2014-02-11 22:11:09.276 [DEBUG] API version 1.3 (pid:2662)
>2014-02-11 22:11:09.291 [INFO ] Completed 200 OK in 15ms (Views: 8.3ms) (pid:2662)
>2014-02-11 22:11:09.785 [INFO ] Started GET "/broker/rest/api" for 209.132.188.8 at 2014-02-11 22:11:09 -0500 (pid:2662)
>2014-02-11 22:11:09.787 [INFO ] Processing by ApiController#show as JSON (pid:2662)
>2014-02-11 22:11:09.787 [DEBUG] API version 1.6 (pid:2662)
>2014-02-11 22:11:09.808 [INFO ] Completed 200 OK in 21ms (Views: 15.2ms) (pid:2662)
>2014-02-11 22:11:10.265 [INFO ] Started GET "/broker/rest/domains" for 209.132.188.8 at 2014-02-11 22:11:10 -0500 (pid:2662)
>2014-02-11 22:11:10.268 [INFO ] Processing by DomainsController#index as JSON (pid:2662)
>2014-02-11 22:11:10.268 [DEBUG] API version 1.6 (pid:2662)
>2014-02-11 22:11:10.273 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=52fadfd33a7ae7f987000001 LOGIN=wjiang@redhat.com IP=209.132.188.8 SCOPES=session Authenticated (pid:2662)
>2014-02-11 22:11:10.277 [DEBUG] SUCCESS ACTION=LIST_DOMAIN USER_ID=52fadfd33a7ae7f987000001 LOGIN=wjiang@redhat.com DOMAIN= (pid:2662)
>2014-02-11 22:11:10.285 [INFO ] Completed 200 OK in 17ms (Views: 6.1ms) (pid:2662)
>2014-02-11 22:11:10.744 [INFO ] Started GET "/broker/rest/domains/y/applications/wordpresss?include=cartridges" for 209.132.188.8 at 2014-02-11 22:11:10 -0500 (pid:2662)
>2014-02-11 22:11:10.747 [INFO ] Processing by ApplicationsController#show as JSON (pid:2662)
>2014-02-11 22:11:10.747 [INFO ] Parameters: {"include"=>"cartridges", "domain_id"=>"y", "id"=>"wordpresss"} (pid:2662)
>2014-02-11 22:11:10.747 [DEBUG] API version 1.6 (pid:2662)
>2014-02-11 22:11:10.751 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=52fadfd33a7ae7f987000001 LOGIN=wjiang@redhat.com IP=209.132.188.8 SCOPES=session Authenticated (pid:2662)
>2014-02-11 22:11:10.775 [DEBUG] SUCCESS ACTION=SHOW_APPLICATION USER_ID=52fadfd33a7ae7f987000001 LOGIN=wjiang@redhat.com APP_UUID=52fae1423a7ae70f70000018 DOMAIN=y Application 'wordpresss' found (pid:2662)
>2014-02-11 22:11:10.910 [INFO ] Completed 200 OK in 162ms (Views: 133.3ms) (pid:2662)
>2014-02-11 22:11:11.921 [INFO ] Started PUT "/broker/rest/application/52fae1423a7ae70f70000018/cartridge/php-5.4" for 209.132.188.8 at 2014-02-11 22:11:11 -0500 (pid:2662)
>2014-02-11 22:11:11.927 [INFO ] Processing by EmbCartController#update as JSON (pid:2662)
>2014-02-11 22:11:11.927 [INFO ] Parameters: {"scales_from"=>2, "application_id"=>"52fae1423a7ae70f70000018", "id"=>"php-5.4", "emb_cart"=>{"scales_from"=>2}} (pid:2662)
>2014-02-11 22:11:11.928 [DEBUG] API version 1.6 (pid:2662)
>2014-02-11 22:11:11.932 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=52fadfd33a7ae7f987000001 LOGIN=wjiang@redhat.com IP=209.132.188.8 SCOPES=session Authenticated (pid:2662)
>2014-02-11 22:11:12.435 [DEBUG] Execute SetGroupOverridesOp (pid:2662)
>2014-02-11 22:11:12.486 [DEBUG] Execute InitGearOp comp_specs=[component:web_proxy/haproxy-1.4/52fab1ba8bc5b21b39000012, component:php-5.4/php-5.4/52fab1ba8bc5b21b39000002] gear_id=52fae64f3a7ae70f700000b4 group_instance_id=52fae1423a7ae70f70000024 (pid:2662)
>2014-02-11 22:11:12.932 [DEBUG] Execute ReserveGearUidOp gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:12.969 [DEBUG] DEBUG: rpc_get_fact: fact=active_capacity (pid:2662)
>2014-02-11 22:11:12.100 [DEBUG] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x000000083429e0> (pid:2662)
>2014-02-11 22:11:14.245 [DEBUG] Selecting best fit node: server: ip-10-181-147-25 capacity: 2.2222222222222223 (pid:2662)
>2014-02-11 22:11:14.276 [DEBUG] Execute CreateGearOp gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:14.280 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--with-secret-token"=>"P8z9V0AO8JdtUuaji2VVii19-RL4f2-9sB7J66BYExZveTApekL6f0hoK6V4mpXBAlscYNGfxDIzY5zurL61jt8tdsj600cbInFmt8PRhhtk_gjXf9zu8rwyB2aXqUfj", "--with-initial-deployment-dir"=>true, "--cart-name"=>"openshift-origin-node"}}, ip-10-181-147-25, {'identity' => ip-10-181-147-25}) (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:16.200 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000006b2af38 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-181-147-25", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:16.205 [DEBUG] DEBUG: MCollective Response Time (execute_direct: app-create): 1.926277021s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:16.265 [DEBUG] Execute RegisterDnsOp gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:16.269 [DEBUG] DYNECT Login with path: /REST/Session/ (pid:2662)
>2014-02-11 22:11:16.929 [DEBUG] DYNECT Response: {"status": "success", "data": {"token": "q13tsAhHKzqM4ZA6NvoJ6zjE3poF3wKzJjdjQtSqu6Tz883RYqrSKWLfx245bNc1Brg4DgTi8rStlOMUGjxuAizo9PiXd2PqjS/QZQUXQOjcI0sKHu+yDFVAqlpNDQLBBDZH7RUWaJfJsjHrM02prQ==", "version": "3.5.4"}, "job_id": 695746456, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2662)
>2014-02-11 22:11:16.930 [DEBUG] DYNECT Response data: {"token"=>"q13tsAhHKzqM4ZA6NvoJ6zjE3poF3wKzJjdjQtSqu6Tz883RYqrSKWLfx245bNc1Brg4DgTi8rStlOMUGjxuAizo9PiXd2PqjS/QZQUXQOjcI0sKHu+yDFVAqlpNDQLBBDZH7RUWaJfJsjHrM02prQ==", "version"=>"3.5.4"} (pid:2662)
>2014-02-11 22:11:16.930 [DEBUG] Dynect Response Time (dyn_login): 0.661026913s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:17.135 [DEBUG] Public ip being configured 'ec2-54-211-5-148.compute-1.amazonaws.com' to app '52fae6503a7ae70a66000018' (pid:2662)
>2014-02-11 22:11:17.139 [DEBUG] DYNECT put/post with path: /REST/CNAMERecord/dev.rhcloud.com/52fae6503a7ae70a66000018-y.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-54-211-5-148.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2662)
>2014-02-11 22:11:17.748 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone": "dev.rhcloud.com", "ttl": 60, "fqdn": "52fae6503a7ae70a66000018-y.dev.rhcloud.com", "record_type": "CNAME", "rdata": {"cname": "ec2-54-211-5-148.compute-1.amazonaws.com."}, "record_id": 0}, "job_id": 695746461, "msgs": [{"INFO": "add_node: New node added to zone", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}, {"INFO": "add: Record added", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2662)
>2014-02-11 22:11:17.749 [DEBUG] DYNECT Response data: {"zone"=>"dev.rhcloud.com", "ttl"=>60, "fqdn"=>"52fae6503a7ae70a66000018-y.dev.rhcloud.com", "record_type"=>"CNAME", "rdata"=>{"cname"=>"ec2-54-211-5-148.compute-1.amazonaws.com."}, "record_id"=>0} (pid:2662)
>2014-02-11 22:11:17.749 [DEBUG] Dynect Response Time (dyn_put_post): 0.735722089s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:17.749 [DEBUG] DYNECT put/post with path: /REST/Zone/dev.rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2662)
>2014-02-11 22:11:18.663 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "day", "serial": 2014255496, "zone": "dev.rhcloud.com"}, "job_id": 695746465, "msgs": [{"INFO": "publish: dev.rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2662)
>2014-02-11 22:11:18.664 [DEBUG] DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"day", "serial"=>2014255496, "zone"=>"dev.rhcloud.com"} (pid:2662)
>2014-02-11 22:11:18.664 [DEBUG] Dynect Response Time (dyn_put_post): 0.914718587s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:18.664 [DEBUG] DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2662)
>2014-02-11 22:11:19.109 [DEBUG] DYNECT Response: {"status": "success", "data": {}, "job_id": 695746480, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2662)
>2014-02-11 22:11:19.109 [DEBUG] DYNECT Response data: {} (pid:2662)
>2014-02-11 22:11:19.109 [DEBUG] Dynect Response Time (dyn_delete): 0.444938518s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:19.137 [DEBUG] Execute UpdateAppConfigOp gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:19.146 [DEBUG] Execute AddCompOp comp_spec=component:php-5.4/php-5.4/52fab1ba8bc5b21b39000002 gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:19.150 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"php-5.4", :action=>"configure", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat"}}, ip-10-181-147-25, {'identity' => ip-10-181-147-25}) (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:25.511 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000067459e0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-181-147-25", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"CONFIGURATION (CHANNEL PEAR.PHP.NET):\n=====================================\nAuto-discover new Channels     auto_discover    <not set>\nDefault Channel                default_channel  pear.php.net\nHTTP Proxy Server Address      http_proxy       <not set>\nPEAR server [DEPRECATED]       master_server    <not set>\nDefault Channel Mirror         preferred_mirror <not set>\nRemote Configuration File      remote_config    <not set>\nPEAR executables directory     bin_dir          /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear\nPEAR documentation directory   doc_dir          /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/docs\nPHP extension directory        ext_dir          /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/ext\nPEAR metadata directory        metadata_dir     <not set>\nPEAR directory                 php_dir          /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/php\nPEAR Installer cache directory cache_dir        /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/cache\nPEAR configuration file        cfg_dir          /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/cfg\ndirectory\nPEAR data directory            data_dir         /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/data\nPEAR Installer download        download_dir     /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/download\ndirectory\nPHP CLI/CGI binary             php_bin          <not set>\nphp.ini location               php_ini          <not set>\n--program-prefix passed to     php_prefix       <not set>\nPHP's ./configure\n--program-suffix passed to     php_suffix       <not set>\nPHP's ./configure\nPEAR Installer temp directory  temp_dir         /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/temp\nPEAR test directory            test_dir         /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/tests\nPEAR www files directory       www_dir          /var/lib/openshift/52fae6503a7ae70a66000018/php/phplib/pear/pear/www\nCache TimeToLive               cache_ttl        <not set>\nPreferred Package State        preferred_state  <not set>\nUnix file mask                 umask            <not set>\nDebug Log Level                verbose          <not set>\nPEAR password (for             password         <not set>\nmaintainers)\nSignature Handling Program     sig_bin          <not set>\nSignature Key Directory        sig_keydir       <not set>\nSignature Key Id               sig_keyid        <not set>\nPackage Signature Type         sig_type         <not set>\nPEAR username (for             username         <not set>\nmaintainers)\nUser Configuration File        Filename         /var/lib/openshift/52fae6503a7ae70a66000018/.pearrc\nSystem Configuration File      Filename         #no#system#config#\nSuccessfully created default configuration file \"/var/lib/openshift/52fae6503a7ae70a66000018/.pearrc\"\nconfig-set succeeded\nconfig-set succeeded\n", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:25.518 [DEBUG] DEBUG: MCollective Response Time (execute_direct: configure): 6.369374204s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:25.896 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>{"op_id"=>"52fae64f3a7ae70f700000b9"}, :gear=>"52fae6503a7ae70a66000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-batch-add", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--with-ssh-keys"=>[{"key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAqXf/NcbVgX6Yx/x7odAM2+0aevdjMrAqol2/CI9LW3CJcabD39PFil9mktuPogoHmpGqdtnzLs0fmVgOv9kKPpWiVpazDcnSWpnqGUvMiPxJhgGaKwa74OKxyTtkIC5xAIhifxGvGHFVgJjFpmlKPG8SKyljK5OaErl4C4P84bJg2HgwMLAsK4HUcQt/TYkcI6dovm/UyAuny0i59/dbzoHs1gMaAmtcjMSfyQvOHNH1bqzqXsGm3p9TOK35pPVbY9TqqMSazIIFL3X+bV1s/KveetRBVy0AvFpyTLmk5IhoIOmmn/6n5B4ePmMUD3zT9edjyE8ltuvEzH9WJk2yIw==", "type"=>"ssh-rsa", "comment"=>"application-52fae1423a7ae70f70000018", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAqXf/NcbVgX6Yx/x7odAM2+0aevdjMrAqol2/CI9LW3CJcabD39PFil9mktuPogoHmpGqdtnzLs0fmVgOv9kKPpWiVpazDcnSWpnqGUvMiPxJhgGaKwa74OKxyTtkIC5xAIhifxGvGHFVgJjFpmlKPG8SKyljK5OaErl4C4P84bJg2HgwMLAsK4HUcQt/TYkcI6dovm/UyAuny0i59/dbzoHs1gMaAmtcjMSfyQvOHNH1bqzqXsGm3p9TOK35pPVbY9TqqMSazIIFL3X+bV1s/KveetRBVy0AvFpyTLmk5IhoIOmmn/6n5B4ePmMUD3zT9edjyE8ltuvEzH9WJk2yIw=="}, {"key"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDPqZYRXFIuqt8F/qbK3i+6veNNHrknWSf8QcEhUnGDKijBSr723SziCXRylP3PTuHcN5C+pH/NgRSi1uCS93cfTf0brsDLkNXSk3krYt3JfRqjrXdcACZQ2+3KvaEQ8glgfU87Fb3Hf8izxh5qBRd1r1edzYy97ArjlrQNmi05Eyhe3Wh2GfHlyKdRjKTHLHW+pCXOGJPMszaMJp6lbyJf6f8e9etl1/HDUYrWUCpQeGAGJxCXTdW2drMsgbaWDpZcLV3mmP2UJn65InYls4WdwkTbdStvFUOLvyP05SpVis2QWqSM5Pe5inHUzQ0a8mt5rhseZooPrMOqJFIJQSLP", "type"=>"ssh-rsa", "comment"=>"52fadfd33a7ae7f987000001-default", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDPqZYRXFIuqt8F/qbK3i+6veNNHrknWSf8QcEhUnGDKijBSr723SziCXRylP3PTuHcN5C+pH/NgRSi1uCS93cfTf0brsDLkNXSk3krYt3JfRqjrXdcACZQ2+3KvaEQ8glgfU87Fb3Hf8izxh5qBRd1r1edzYy97ArjlrQNmi05Eyhe3Wh2GfHlyKdRjKTHLHW+pCXOGJPMszaMJp6lbyJf6f8e9etl1/HDUYrWUCpQeGAGJxCXTdW2drMsgbaWDpZcLV3mmP2UJn65InYls4WdwkTbdStvFUOLvyP05SpVis2QWqSM5Pe5inHUzQ0a8mt5rhseZooPrMOqJFIJQSLP"}]}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"52fae64f3a7ae70f700000b9"}, :gear=>"52fae6503a7ae70a66000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"update-configuration", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--with-config"=>{"auto_deploy"=>true, "deployment_branch"=>"master", "keep_deployments"=>1, "deployment_type"=>"git"}}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-181-147-25  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:25.907 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 384ms  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:25.994 [DEBUG] Execute PatchUserEnvVarsOp (pid:2662)
>2014-02-11 22:11:26.448 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"user-var-add", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--with-gears"=>"50abf7f8939011e3b06822000ab59319@ip-10-181-147-25;52fae6503a7ae70a66000018@ip-10-181-147-25", "--cart-name"=>"openshift-origin-node"}}, ip-10-181-147-25, {'identity' => ip-10-181-147-25}) (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:26.155 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007d35408 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-181-147-25", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:26.158 [DEBUG] DEBUG: MCollective Response Time (execute_direct: user-var-add): 0.157988667s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:26.163 [DEBUG] Execute ExposePortOp comp_spec=component:php-5.4/php-5.4/52fab1ba8bc5b21b39000002 gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:26.937 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>{"expose-ports"=>"52fae1433a7ae70f7000003e", "op_id"=>"52fae64f3a7ae70f700000bc"}, :gear=>"52fae6503a7ae70a66000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"expose-port", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat"}}, :result_stdout=>"NOTIFY_ENDPOINT_CREATE: {\"cartridge_name\":\"php-5.4\",\"external_address\":\"54.211.5.148\",\"external_port\":38031,\"internal_address\":\"127.1.244.1\",\"internal_port\":8080,\"protocols\":[\"http\",\"ws\"],\"description\":null,\"type\":[\"web_framework\"],\"mappings\":[{\"frontend\":\"\",\"backend\":\"\"},{\"frontend\":\"/health\",\"backend\":\"\"}]}\n", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-181-147-25  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:26.942 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 776ms  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:27.709 [DEBUG] Execute ExecuteConnectionsOp (pid:2662)
>2014-02-11 22:11:27.150 [DEBUG] Running publishers (pid:2662)
>2014-02-11 22:11:27.819 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"52fae65f3a7ae70a66000019", :gear=>"52fae1423a7ae70f70000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"web_proxy", "--cart-name"=>"haproxy-1.4", "--with-software-version"=>"1.4", "--cartridge-vendor"=>"redhat", "--hook-name"=>"publish-haproxy-status-url", "--connection-type"=>"NET_TCP:haproxy-status-info", "--input-args"=>"wordpresss y 52fae1423a7ae70f70000018"}}, :result_stdout=>"http://wordpresss-y.dev.rhcloud.com/haproxy-status/\n", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>"52fae65f3a7ae70a6600001a", :gear=>"50abf7f8939011e3b06822000ab59319", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"50abf7f8939011e3b06822000ab59319", "--with-container-name"=>"50abf7f8939011e3b06822000ab59319", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"mysql-5.5", "--cart-name"=>"mysql-5.5", "--with-software-version"=>"5.5", "--cartridge-vendor"=>"redhat", "--hook-name"=>"publish-db-connection-info", "--connection-type"=>"ENV:NET_TCP:db:connection-info", "--input-args"=>"50abf7f8939011e3b06822000ab59319 y 50abf7f8939011e3b06822000ab59319"}}, :result_stdout=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-181-147-25  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:27.822 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 670ms  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:27.822 [DEBUG] Running subscribers (pid:2662)
>2014-02-11 22:11:27.823 [DEBUG] Output of publisher - '{"52fae65f3a7ae70a66000019"=>["'52fae1423a7ae70f70000018'='http://wordpresss-y.dev.rhcloud.com/haproxy-status/\n'"], "52fae65f3a7ae70a6600001a"=>{"50abf7f8939011e3b06822000ab59319"=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n"}}' (pid:2662)
>2014-02-11 22:11:27.825 [DEBUG] Output of publisher - '{"52fae65f3a7ae70a66000019"=>["'52fae1423a7ae70f70000018'='http://wordpresss-y.dev.rhcloud.com/haproxy-status/\n'"], "52fae65f3a7ae70a6600001a"=>{"50abf7f8939011e3b06822000ab59319"=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n"}}' (pid:2662)
>2014-02-11 22:11:28.277 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"", :gear=>"52fae1423a7ae70f70000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"web_proxy", "--cart-name"=>"haproxy-1.4", "--with-software-version"=>"1.4", "--cartridge-vendor"=>"redhat", "--hook-name"=>"set-haproxy-status-url", "--publishing-cart-name"=>"haproxy-1.4", "--connection-type"=>"NET_TCP:haproxy-status-info", "--input-args"=>"wordpresss y 52fae1423a7ae70f70000018 \\'52fae1423a7ae70f70000018\\'\\=\\'http://wordpresss-y.dev.rhcloud.com/haproxy-status/'\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>"", :gear=>"52fae1423a7ae70f70000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat", "--hook-name"=>"set-env", "--publishing-cart-name"=>"mysql-5.5", "--connection-type"=>"ENV:NET_TCP:db:connection-info", "--input-args"=>["wordpresss", "y", "52fae1423a7ae70f70000018", {"50abf7f8939011e3b06822000ab59319"=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n"}]}}, :result_stdout=>"Set environment variables successfully", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>"", :gear=>"52fae6503a7ae70a66000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat", "--hook-name"=>"set-env", "--publishing-cart-name"=>"mysql-5.5", "--connection-type"=>"ENV:NET_TCP:db:connection-info", "--input-args"=>["52fae6503a7ae70a66000018", "y", "52fae6503a7ae70a66000018", {"50abf7f8939011e3b06822000ab59319"=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n"}]}}, :result_stdout=>"Set environment variables successfully", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-181-147-25  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:28.280 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 455ms  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:28.280 [DEBUG] Connections done (pid:2662)
>2014-02-11 22:11:28.308 [DEBUG] Execute UpdateClusterOp (pid:2662)
>2014-02-11 22:11:28.681 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"update-cluster", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--sync-new-gears"=>true, "--proxy-gears"=>"52fae1423a7ae70f70000018,wordpresss,y,ec2-54-211-5-148.compute-1.amazonaws.com", "--web-gears"=>"52fae1423a7ae70f70000018,wordpresss,y,ec2-54-211-5-148.compute-1.amazonaws.com,38041 52fae6503a7ae70a66000018,52fae6503a7ae70a66000018,y,ec2-54-211-5-148.compute-1.amazonaws.com,38031", "--cart-name"=>"openshift-origin-node"}}, ip-10-181-147-25, {'identity' => ip-10-181-147-25}) (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:33.117 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000074433b8 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-181-147-25", :statuscode=>1, :statusmsg=>"cartridge_do_action failed 1. Output Activation of new gears failed: 52fae6503a7ae70a66000018: Error activating gear: CLIENT_ERROR: Failed to execute: 'control start' for /var/lib/openshift/52fae6503a7ae70a66000018/php", :data=>{:time=>nil, :output=>"Activation of new gears failed: 52fae6503a7ae70a66000018: Error activating gear: CLIENT_ERROR: Failed to execute: 'control start' for /var/lib/openshift/52fae6503a7ae70a66000018/php", :exitcode=>1, :addtl_params=>nil}}>] (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:33.122 [DEBUG] DEBUG: MCollective Response Time (execute_direct: update-cluster): 4.548582865s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:33.123 [DEBUG] DEBUG: server results: Activation of new gears failed: 52fae6503a7ae70a66000018: Error activating gear: CLIENT_ERROR: Failed to execute: 'control start' for /var/lib/openshift/52fae6503a7ae70a66000018/php (pid:2662)
>2014-02-11 22:11:33.124 [ERROR] An invalid exit code (1) was returned from the server ip-10-181-147-25.  This indicates an unexpected problem during the execution of your request. (pid:2662)
>2014-02-11 22:11:33.124 [ERROR] /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.20.3/lib/openshift/mcollective_application_container_proxy.rb:2724:in `parse_result'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.20.3/lib/openshift/mcollective_application_container_proxy.rb:1763:in `update_cluster'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/gear.rb:285:in `method_missing'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:1311:in `update_cluster'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/relations/proxy.rb:143:in `method_missing'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/pending_ops_models/update_cluster_op.rb:4:in `execute'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/pending_app_op_group.rb:103:in `block in execute'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/pending_app_op_group.rb:93:in `each'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/pending_app_op_group.rb:93:in `execute'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:1595:in `run_jobs'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:921:in `block in update_component_limits'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:1665:in `run_in_application_lock'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:916:in `update_component_limits'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/controllers/emb_cart_controller.rb:156:in `update'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:18:in `block in process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:492:in `block in _run__3030963173005817431__process_action__2571425975118214851__callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_514'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/lib/openshift/controller/action_log.rb:80:in `set_logged_request'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_514'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:403:in `_run__3030963173005817431__process_action__2571425975118214851__callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
>/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
>/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `block in call'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__2333000388537093858__call__524814346147448166__callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'
>/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/rack/request_handler.rb:97:in `process_request'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/abstract_request_handler.rb:521:in `accept_and_process_next_request'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/utils.rb:470:in `safe_fork'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'
>/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/helper-scripts/passenger-spawn-server:102:in `<main>' (pid:2662)
>2014-02-11 22:11:33.125 [ERROR] Encountered error during execute 'An invalid exit code (1) was returned from the server ip-10-181-147-25.  This indicates an unexpected problem during the execution of your request.' (pid:2662)
>2014-02-11 22:11:33.125 [ERROR] Encountered error during execute 'An invalid exit code (1) was returned from the server ip-10-181-147-25.  This indicates an unexpected problem during the execution of your request.' (pid:2662)
>2014-02-11 22:11:33.126 [DEBUG] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.20.3/lib/openshift/mcollective_application_container_proxy.rb:2724:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.20.3/lib/openshift/mcollective_application_container_proxy.rb:1763:in `update_cluster'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/gear.rb:285:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:1311:in `update_cluster'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/relations/proxy.rb:143:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/pending_ops_models/update_cluster_op.rb:4:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/pending_app_op_group.rb:103:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/pending_app_op_group.rb:93:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/pending_app_op_group.rb:93:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:1595:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:921:in `block in update_component_limits'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:1665:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/models/application.rb:916:in `update_component_limits'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/app/controllers/emb_cart_controller.rb:156:in `update'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:18:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:492:in `block in _run__3030963173005817431__process_action__2571425975118214851__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_514'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.20.3/lib/openshift/controller/action_log.rb:80:in `set_logged_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_514'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:403:in `_run__3030963173005817431__process_action__2571425975118214851__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__2333000388537093858__call__524814346147448166__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/rack/request_handler.rb:97:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/abstract_request_handler.rb:521:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/utils.rb:470:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.21/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:2662)
>2014-02-11 22:11:33.178 [DEBUG] Rollback UpdateClusterOp (pid:2662)
>2014-02-11 22:11:33.186 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"update-cluster", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--rollback"=>true, "--cart-name"=>"openshift-origin-node"}}, ip-10-181-147-25, {'identity' => ip-10-181-147-25}) (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:55.271 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007c95728 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-181-147-25", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:55.276 [DEBUG] DEBUG: MCollective Response Time (execute_direct: update-cluster): 22.091224233s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:55.325 [DEBUG] Rollback ExecuteConnectionsOp (pid:2662)
>2014-02-11 22:11:55.328 [DEBUG] Running publishers (pid:2662)
>2014-02-11 22:11:56.218 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"52fae67b3a7ae70a6600001b", :gear=>"52fae1423a7ae70f70000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"web_proxy", "--cart-name"=>"haproxy-1.4", "--with-software-version"=>"1.4", "--cartridge-vendor"=>"redhat", "--hook-name"=>"publish-haproxy-status-url", "--connection-type"=>"NET_TCP:haproxy-status-info", "--input-args"=>"wordpresss y 52fae1423a7ae70f70000018"}}, :result_stdout=>"http://wordpresss-y.dev.rhcloud.com/haproxy-status/\n", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>"52fae67b3a7ae70a6600001c", :gear=>"50abf7f8939011e3b06822000ab59319", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"50abf7f8939011e3b06822000ab59319", "--with-container-name"=>"50abf7f8939011e3b06822000ab59319", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"mysql-5.5", "--cart-name"=>"mysql-5.5", "--with-software-version"=>"5.5", "--cartridge-vendor"=>"redhat", "--hook-name"=>"publish-db-connection-info", "--connection-type"=>"ENV:NET_TCP:db:connection-info", "--input-args"=>"50abf7f8939011e3b06822000ab59319 y 50abf7f8939011e3b06822000ab59319"}}, :result_stdout=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-181-147-25  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:56.245 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 694ms  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:56.248 [DEBUG] Running subscribers (pid:2662)
>2014-02-11 22:11:56.257 [DEBUG] Output of publisher - '{"52fae67b3a7ae70a6600001b"=>["'52fae1423a7ae70f70000018'='http://wordpresss-y.dev.rhcloud.com/haproxy-status/\n'"], "52fae67b3a7ae70a6600001c"=>{"50abf7f8939011e3b06822000ab59319"=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n"}}' (pid:2662)
>2014-02-11 22:11:56.269 [DEBUG] Output of publisher - '{"52fae67b3a7ae70a6600001b"=>["'52fae1423a7ae70f70000018'='http://wordpresss-y.dev.rhcloud.com/haproxy-status/\n'"], "52fae67b3a7ae70a6600001c"=>{"50abf7f8939011e3b06822000ab59319"=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n"}}' (pid:2662)
>2014-02-11 22:11:56.480 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"", :gear=>"52fae1423a7ae70f70000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"web_proxy", "--cart-name"=>"haproxy-1.4", "--with-software-version"=>"1.4", "--cartridge-vendor"=>"redhat", "--hook-name"=>"set-haproxy-status-url", "--publishing-cart-name"=>"haproxy-1.4", "--connection-type"=>"NET_TCP:haproxy-status-info", "--input-args"=>"wordpresss y 52fae1423a7ae70f70000018 \\'52fae1423a7ae70f70000018\\'\\=\\'http://wordpresss-y.dev.rhcloud.com/haproxy-status/'\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>"", :gear=>"52fae1423a7ae70f70000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae1423a7ae70f70000018", "--with-container-name"=>"wordpresss", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat", "--hook-name"=>"set-env", "--publishing-cart-name"=>"mysql-5.5", "--connection-type"=>"ENV:NET_TCP:db:connection-info", "--input-args"=>["wordpresss", "y", "52fae1423a7ae70f70000018", {"50abf7f8939011e3b06822000ab59319"=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n"}]}}, :result_stdout=>"Set environment variables successfully", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>"", :gear=>"52fae6503a7ae70a66000018", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat", "--hook-name"=>"set-env", "--publishing-cart-name"=>"mysql-5.5", "--connection-type"=>"ENV:NET_TCP:db:connection-info", "--input-args"=>["52fae6503a7ae70a66000018", "y", "52fae6503a7ae70a66000018", {"50abf7f8939011e3b06822000ab59319"=>"OPENSHIFT_MYSQL_DB_GEAR_UUID=50abf7f8939011e3b06822000ab59319\nOPENSHIFT_MYSQL_DB_GEAR_DNS=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_USERNAME=admin2bgtcmN\nOPENSHIFT_MYSQL_DB_PASSWORD=UP-JTzDp-dKh\nOPENSHIFT_MYSQL_DB_HOST=50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com\nOPENSHIFT_MYSQL_DB_PORT=38036\nOPENSHIFT_MYSQL_DB_URL=mysql://admin2bgtcmN:UP-JTzDp-dKh@50abf7f8939011e3b06822000ab59319-y.dev.rhcloud.com:38036/\n"}]}}, :result_stdout=>"Set environment variables successfully", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-181-147-25  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:56.483 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 455ms  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:56.483 [DEBUG] Connections done (pid:2662)
>2014-02-11 22:11:56.589 [DEBUG] Rollback SetGroupOverridesOp (pid:2662)
>2014-02-11 22:11:56.592 [DEBUG] Rollback PatchUserEnvVarsOp (pid:2662)
>2014-02-11 22:11:56.595 [DEBUG] Rollback ExposePortOp comp_spec=component:php-5.4/php-5.4/52fab1ba8bc5b21b39000002 gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:56.595 [DEBUG] Parallel rollback not implemented: ExposePortOp (pid:2662)
>2014-02-11 22:11:56.607 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 12ms  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:56.648 [DEBUG] Rollback UpdateAppConfigOp gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:56.648 [DEBUG] Parallel rollback not implemented: UpdateAppConfigOp (pid:2662)
>2014-02-11 22:11:56.649 [DEBUG] Rollback AddCompOp comp_spec=component:php-5.4/php-5.4/52fab1ba8bc5b21b39000002 gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:56.650 [DEBUG] DEBUG: Deconfiguring cartridge 'php-5.4' in application 'wordpresss' on node 'ip-10-181-147-25' (pid:2662)
>2014-02-11 22:11:56.651 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"php-5.4", :action=>"deconfigure", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat"}}, ip-10-181-147-25, {'identity' => ip-10-181-147-25}) (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:58.692 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000006c43690 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-181-147-25", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:58.744 [DEBUG] DEBUG: MCollective Response Time (execute_direct: deconfigure): 1.424128072s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:58.102 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 23ms  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:58.148 [DEBUG] Rollback RegisterDnsOp gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:11:58.149 [DEBUG] DYNECT Login with path: /REST/Session/ (pid:2662)
>2014-02-11 22:11:58.799 [DEBUG] DYNECT Response: {"status": "success", "data": {"token": "Zvuv0sH4l05o/Ab1PVdJZfYW1RGvFpeSmNAXqnpu2c6q/zbDQRC8PFZtKXrUYj2RF3rnmPURC6aorCg72A2NJH5N43uH2V59ZbOj4d2VbchsY/mPNp8wAYjaCa6vksCgHWTJzXAhWvS8dM87HPfC7g==", "version": "3.5.4"}, "job_id": 695746987, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2662)
>2014-02-11 22:11:58.800 [DEBUG] DYNECT Response data: {"token"=>"Zvuv0sH4l05o/Ab1PVdJZfYW1RGvFpeSmNAXqnpu2c6q/zbDQRC8PFZtKXrUYj2RF3rnmPURC6aorCg72A2NJH5N43uH2V59ZbOj4d2VbchsY/mPNp8wAYjaCa6vksCgHWTJzXAhWvS8dM87HPfC7g==", "version"=>"3.5.4"} (pid:2662)
>2014-02-11 22:11:58.800 [DEBUG] Dynect Response Time (dyn_login): 0.651002803s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:58.800 [DEBUG] DYNECT delete with path: /REST/CNAMERecord/dev.rhcloud.com/52fae6503a7ae70a66000018-y.dev.rhcloud.com/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2662)
>2014-02-11 22:11:59.320 [DEBUG] DYNECT Response: {"status": "success", "data": {}, "job_id": 695746996, "msgs": [{"INFO": "delete: 1 records deleted", "SOURCE": "API-B", "ERR_CD": null, "LVL": "INFO"}]} (pid:2662)
>2014-02-11 22:11:59.320 [DEBUG] DYNECT Response data: {} (pid:2662)
>2014-02-11 22:11:59.320 [DEBUG] Dynect Response Time (dyn_delete): 0.519724113s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:11:59.320 [DEBUG] DYNECT put/post with path: /REST/Zone/dev.rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2662)
>2014-02-11 22:12:00.282 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "day", "serial": 2014255505, "zone": "dev.rhcloud.com"}, "job_id": 695747004, "msgs": [{"INFO": "publish: dev.rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2662)
>2014-02-11 22:12:00.282 [DEBUG] DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"day", "serial"=>2014255505, "zone"=>"dev.rhcloud.com"} (pid:2662)
>2014-02-11 22:12:00.282 [DEBUG] Dynect Response Time (dyn_put_post): 0.962112989s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:12:00.283 [DEBUG] DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2662)
>2014-02-11 22:12:00.723 [DEBUG] DYNECT Response: {"status": "success", "data": {}, "job_id": 695747017, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2662)
>2014-02-11 22:12:00.724 [DEBUG] DYNECT Response data: {} (pid:2662)
>2014-02-11 22:12:00.724 [DEBUG] Dynect Response Time (dyn_delete): 0.44120659s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:12:00.751 [DEBUG] Rollback CreateGearOp gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:12:00.757 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"52fae1423a7ae70f70000018", "--with-app-name"=>"wordpresss", "--with-container-uuid"=>"52fae6503a7ae70a66000018", "--with-container-name"=>"52fae6503a7ae70a66000018", "--with-namespace"=>"y", "--with-request-id"=>"b58ea5e884559e46535397f8e6503b91", "--cart-name"=>"openshift-origin-node"}}, ip-10-181-147-25, {'identity' => ip-10-181-147-25}) (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:12:04.162 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000008314658 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-181-147-25", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:12:04.164 [DEBUG] DEBUG: MCollective Response Time (execute_direct: app-destroy): 3.411782233s  (Request ID: b58ea5e884559e46535397f8e6503b91) (pid:2662)
>2014-02-11 22:12:04.412 [DEBUG] Rollback ReserveGearUidOp gear_id=52fae64f3a7ae70f700000b4 (pid:2662)
>2014-02-11 22:12:04.468 [DEBUG] Rollback InitGearOp comp_specs=[component:web_proxy/haproxy-1.4/52fab1ba8bc5b21b39000012, component:php-5.4/php-5.4/52fab1ba8bc5b21b39000002] gear_id=52fae64f3a7ae70f700000b4 group_instance_id=52fae1423a7ae70f70000024 (pid:2662)
>2014-02-11 22:12:04.545 [DEBUG] FAILURE ACTION=UPDATE_APP_CARTRIDGE USER_ID=52fadfd33a7ae7f987000001 LOGIN=wjiang@redhat.com APP_UUID=52fae1423a7ae70f70000018 DOMAIN=y Unable to complete the requested operation due to: An invalid exit code (1) was returned from the server ip-10-181-147-25.  This indicates an unexpected problem during the execution of your request. Activation of new gears failed: 52fae6503a7ae70a66000018: Error activating gear: CLIENT_ERROR: Failed to execute: 'control start' for /var/lib/openshift/52fae6503a7ae70a66000018/php, Unable to complete the requested operation due to: An invalid exit code (1) was returned from the server ip-10-181-147-25.  This indicates an unexpected problem during the execution of your request.
>Reference ID: b58ea5e884559e46535397f8e6503b91 (pid:2662)
>2014-02-11 22:12:04.548 [INFO ] Completed 500 Internal Server Error in 52621ms (Views: 0.8ms) (pid:2662)
>

Actions: View

Attachments on bug 1062526: 862099