Attachment 890403 Details for Bug 1091897 – broker_log_during_app_creation

broker_log_during_app_creation

broker.log (text/plain), 20.65 KB, created by Meng Bo on 2014-04-28 09:59:36 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Meng Bo

Created: 2014-04-28 09:59:36 UTC

Size: 20.65 KB

patch

obsolete

>2014-04-28 09:28:50.334 [INFO ] Started GET "/broker/rest/api" for 203.114.244.84 at 2014-04-28 09:28:50 -0400 (pid:134)
>2014-04-28 09:28:50.335 [INFO ] Processing by ApiController#show as JSON (pid:134)
>2014-04-28 09:28:50.335 [DEBUG] API version 1.3 (pid:134)
>2014-04-28 09:28:50.352 [INFO ] Completed 200 OK in 16ms (Views: 4.8ms) (pid:134)
>2014-04-28 09:28:50.949 [INFO ] Started GET "/broker/rest/api" for 203.114.244.84 at 2014-04-28 09:28:50 -0400 (pid:134)
>2014-04-28 09:28:50.950 [INFO ] Processing by ApiController#show as JSON (pid:134)
>2014-04-28 09:28:50.950 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:28:50.971 [INFO ] Completed 200 OK in 21ms (Views: 17.7ms) (pid:134)
>2014-04-28 09:28:51.310 [INFO ] Started GET "/broker/rest/domains" for 203.114.244.84 at 2014-04-28 09:28:51 -0400 (pid:134)
>2014-04-28 09:28:51.311 [INFO ] Processing by DomainsController#index as JSON (pid:134)
>2014-04-28 09:28:51.311 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:28:51.312 [INFO ] Filter chain halted as :authenticate_user! rendered or redirected (pid:134)
>2014-04-28 09:28:51.312 [INFO ] Completed 401 Unauthorized in 1ms (pid:134)
>2014-04-28 09:28:51.618 [INFO ] Started GET "/broker/rest/domains" for 203.114.244.84 at 2014-04-28 09:28:51 -0400 (pid:134)
>2014-04-28 09:28:51.619 [INFO ] Processing by DomainsController#index as JSON (pid:134)
>2014-04-28 09:28:51.619 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:28:51.622 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com IP=203.114.244.84 SCOPES=session Authenticated (pid:134)
>2014-04-28 09:28:51.626 [DEBUG] SUCCESS ACTION=LIST_DOMAIN USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com DOMAIN= (pid:134)
>2014-04-28 09:28:51.638 [INFO ] Completed 200 OK in 20ms (Views: 12.0ms) (pid:134)
>2014-04-28 09:28:51.960 [INFO ] Started GET "/broker/rest/cartridges" for 203.114.244.84 at 2014-04-28 09:28:51 -0400 (pid:134)
>2014-04-28 09:28:51.961 [INFO ] Processing by CartridgesController#index as JSON (pid:134)
>2014-04-28 09:28:51.961 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:28:52.106 [DEBUG] SUCCESS ACTION=LIST_CARTRIDGE USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com DOMAIN= Listing cartridges (pid:134)
>2014-04-28 09:28:52.138 [INFO ] Completed 200 OK in 177ms (Views: 30.7ms) (pid:134)
>2014-04-28 09:28:52.793 [INFO ] Started POST "/broker/rest/domain/bmengdev1/applications" for 203.114.244.84 at 2014-04-28 09:28:52 -0400 (pid:134)
>2014-04-28 09:28:52.803 [INFO ] Processing by ApplicationsController#create as JSON (pid:134)
>2014-04-28 09:28:52.803 [INFO ] Parameters: {"name"=>"php543", "cartridges"=>["php-5.4"], "domain_id"=>"bmengdev1", "application"=>{"name"=>"php543"}} (pid:134)
>2014-04-28 09:28:52.804 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:28:52.804 [INFO ] Filter chain halted as :authenticate_user! rendered or redirected (pid:134)
>2014-04-28 09:28:52.805 [INFO ] Completed 401 Unauthorized in 1ms (pid:134)
>2014-04-28 09:28:53.948 [INFO ] Started POST "/broker/rest/domain/bmengdev1/applications" for 203.114.244.84 at 2014-04-28 09:28:53 -0400 (pid:134)
>2014-04-28 09:28:53.966 [INFO ] Processing by ApplicationsController#create as JSON (pid:134)
>2014-04-28 09:28:53.966 [INFO ] Parameters: {"name"=>"php543", "cartridges"=>["php-5.4"], "domain_id"=>"bmengdev1", "application"=>{"name"=>"php543"}} (pid:134)
>2014-04-28 09:28:53.969 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:28:53.986 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com IP=203.114.244.84 SCOPES=session Authenticated (pid:134)
>2014-04-28 09:28:53.133 [DEBUG] Pre-Executing ops... (pid:134)
>2014-04-28 09:28:53.133 [DEBUG] Pre-Execute InitGearOp comp_specs=[component:php-5.4/php-5.4/535ccb8d450091146c000022] gear_id=535e579564f6e1b63800009a group_instance_id=535e579564f6e1b63800009c (pid:134)
>2014-04-28 09:28:53.196 [DEBUG] Execute NotifyAppCreateOp (pid:134)
>2014-04-28 09:28:53.228 [DEBUG] Execute ReserveGearUidOp gear_id=535e579564f6e1b63800009a (pid:134)
>2014-04-28 09:28:53.230 [DEBUG] DEBUG: rpc_get_fact: fact=active_capacity (pid:134)
>2014-04-28 09:28:53.247 [DEBUG] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x00000008159a20> (pid:134)
>2014-04-28 09:28:55.398 [DEBUG] Selecting best fit node: server: ip-10-179-157-98 capacity: 3.3333333333333335 (pid:134)
>2014-04-28 09:28:55.431 [DEBUG] Execute CreateGearOp gear_id=535e579564f6e1b63800009a (pid:134)
>2014-04-28 09:28:55.446 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"535e579564f6e1b63800009a", "--with-app-name"=>"php543", "--with-expose-ports"=>false, "--with-container-uuid"=>"535e579564f6e1b63800009a", "--with-container-name"=>"php543", "--with-namespace"=>"bmengdev1", "--with-request-id"=>"0aad1876345c132e04bc7dc6a37af606", "--with-secret-token"=>"5vjI-mHPQP0MUJnuetdYhe5WtsF9oDfkyqe_dVTQa7LX_bZ47wGKTnoJt67T7fH_Zuo2TK062gwSabHXLRaUA3RJwIsdI1bk5ip8btKsO4pSWKokEt2CJlyZ3R0OG4oy", "--with-initial-deployment-dir"=>true, "--cart-name"=>"openshift-origin-node"}}, ip-10-179-157-98, {'identity' => ip-10-179-157-98}) (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:28:57.159 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007af2628 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-179-157-98", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:28:57.161 [DEBUG] DEBUG: MCollective Response Time (execute_direct: app-create): 1.728468092s  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:28:57.197 [DEBUG] Execute RegisterDnsOp gear_id=535e579564f6e1b63800009a (pid:134)
>2014-04-28 09:28:57.198 [DEBUG] DYNECT Login with path: /REST/Session/ (pid:134)
>2014-04-28 09:28:57.781 [DEBUG] DYNECT Response: {"status": "success", "data": {"token": "OZR0Ck3kgb8wtTchUM83tKwu2XQQa4Rm1/HGW/fNvZhrZgHxoMwso2eZ7KGdok8aBviXLRKfCHSRAcfHn9CcJmgGQR0u/Tl60QOo6e64e/GU724kxCGba+KQpTbDrdqnQx43WIjk8QNqUKtVRKu0Wg==", "version": "3.5.6"}, "job_id": 844388972, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:134)
>2014-04-28 09:28:57.781 [DEBUG] DYNECT Response data: {"token"=>"OZR0Ck3kgb8wtTchUM83tKwu2XQQa4Rm1/HGW/fNvZhrZgHxoMwso2eZ7KGdok8aBviXLRKfCHSRAcfHn9CcJmgGQR0u/Tl60QOo6e64e/GU724kxCGba+KQpTbDrdqnQx43WIjk8QNqUKtVRKu0Wg==", "version"=>"3.5.6"} (pid:134)
>2014-04-28 09:28:57.781 [DEBUG] Dynect Response Time (dyn_login): 0.582536452s  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:28:57.872 [DEBUG] Public ip being configured 'ip-10-179-157-98.ec2.internal' to app 'php543' (pid:134)
>2014-04-28 09:28:57.873 [DEBUG] DYNECT put/post with path: /REST/CNAMERecord/dev.rhcloud.com/php543-bmengdev1.dev.rhcloud.com/ json data: {"rdata":{"cname":"ip-10-179-157-98.ec2.internal"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:134)
>2014-04-28 09:28:58.434 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone": "dev.rhcloud.com", "ttl": 60, "fqdn": "php543-bmengdev1.dev.rhcloud.com", "record_type": "CNAME", "rdata": {"cname": "ip-10-179-157-98.ec2.internal."}, "record_id": 0}, "job_id": 844388985, "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:134)
>2014-04-28 09:28:58.434 [DEBUG] DYNECT Response data: {"zone"=>"dev.rhcloud.com", "ttl"=>60, "fqdn"=>"php543-bmengdev1.dev.rhcloud.com", "record_type"=>"CNAME", "rdata"=>{"cname"=>"ip-10-179-157-98.ec2.internal."}, "record_id"=>0} (pid:134)
>2014-04-28 09:28:58.434 [DEBUG] Dynect Response Time (dyn_put_post): 0.561268961s  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:28:58.434 [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:134)
>2014-04-28 09:28:59.632 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "increment", "serial": 69685, "zone": "dev.rhcloud.com"}, "job_id": 844388999, "msgs": [{"INFO": "publish: dev.rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:134)
>2014-04-28 09:28:59.634 [DEBUG] DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"increment", "serial"=>69685, "zone"=>"dev.rhcloud.com"} (pid:134)
>2014-04-28 09:28:59.634 [DEBUG] Dynect Response Time (dyn_put_post): 0.628924597s  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:28:59.636 [DEBUG] DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:134)
>2014-04-28 09:29:02.318 [DEBUG] DYNECT Response: {"status": "success", "data": {}, "job_id": 844389028, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:134)
>2014-04-28 09:29:02.318 [DEBUG] DYNECT Response data: {} (pid:134)
>2014-04-28 09:29:02.318 [DEBUG] Dynect Response Time (dyn_delete): 3.254666402s  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:02.351 [DEBUG] Execute UpdateAppConfigOp gear_id=535e579564f6e1b63800009a (pid:134)
>2014-04-28 09:29:02.384 [DEBUG] Execute AddBrokerAuthKeyOp gear_id=535e579564f6e1b63800009a (pid:134)
>2014-04-28 09:29:02.399 [DEBUG] Execute AddCompOp comp_spec=component:php-5.4/php-5.4/535ccb8d450091146c000022 gear_id=535e579564f6e1b63800009a (pid:134)
>2014-04-28 09:29:02.438 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"php-5.4", :action=>"configure", :args=>{"--with-app-uuid"=>"535e579564f6e1b63800009a", "--with-app-name"=>"php543", "--with-expose-ports"=>false, "--with-container-uuid"=>"535e579564f6e1b63800009a", "--with-container-name"=>"php543", "--with-namespace"=>"bmengdev1", "--with-request-id"=>"0aad1876345c132e04bc7dc6a37af606", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat"}}, ip-10-179-157-98, {'identity' => ip-10-179-157-98}) (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:07.578 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000005bbdeb0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-179-157-98", :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/535e579564f6e1b63800009a/php/phplib/pear/pear\nPEAR documentation directory   doc_dir          /var/lib/openshift/535e579564f6e1b63800009a/php/phplib/pear/pear/docs\nPHP extension directory        ext_dir          /var/lib/openshift/535e579564f6e1b63800009a/php/phplib/pear/pear/ext\nPEAR metadata directory        metadata_dir     <not set>\nPEAR directory                 php_dir          /var/lib/openshift/535e579564f6e1b63800009a/php/phplib/pear/pear/php\nPEAR Installer cache directory cache_dir        /var/lib/openshift/535e579564f6e1b63800009a/php/phplib/pear/pear/cache\nPEAR configuration file        cfg_dir          /var/lib/openshift/535e579564f6e1b63800009a/php/phplib/pear/pear/cfg\ndirectory\nPEAR data directory            data_dir         /var/lib/openshift/535e579564f6e1b63800009a/php/phplib/pear/pear/data\nPEAR Installer download        download_dir     /var/lib/openshift/535e579564f6e1b63800009a/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/535e579564f6e1b63800009a/php/phplib/pear/pear/temp\nPEAR test directory            test_dir         /var/lib/openshift/535e579564f6e1b63800009a/php/phplib/pear/pear/tests\nPEAR www files directory       www_dir          /var/lib/openshift/535e579564f6e1b63800009a/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/535e579564f6e1b63800009a/.pearrc\nSystem Configuration File      Filename         #no#system#config#\nSuccessfully created default configuration file \"/var/lib/openshift/535e579564f6e1b63800009a/.pearrc\"\nconfig-set succeeded\nconfig-set succeeded\n", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:07.579 [DEBUG] DEBUG: MCollective Response Time (execute_direct: configure): 5.178328295s  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:07.686 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>{"op_id"=>"535e579564f6e1b6380000a4"}, :gear=>"535e579564f6e1b63800009a", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-batch-add", :args=>{"--with-app-uuid"=>"535e579564f6e1b63800009a", "--with-app-name"=>"php543", "--with-expose-ports"=>false, "--with-container-uuid"=>"535e579564f6e1b63800009a", "--with-container-name"=>"php543", "--with-namespace"=>"bmengdev1", "--with-request-id"=>"0aad1876345c132e04bc7dc6a37af606", "--with-ssh-keys"=>[{"key"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQCvk6aI6mJplA2BqfLaf6XheT26VQvvVhToSWGEl09+tZ/pVLBiZf+tDX0duGj5y+ATlMKAZ/urahO4nliQ1Kjs8ZO/xHYiIrZPm9jTgLEOd5c/wQh8Nof4E/FVqQEpcqq3ZhIk3a0bODE5nHfYPUphWjgLawFqVr0eoEbXUSc4QZ8Pubet2km6/sGtaEusF4ThERBq9jLjA5q4Gt/856lCa1SXPKCBtMBRLvMt4H7RYWwLG7ahzzuxygVNUVrIdLzW+WEWWJdI5SSpBXCDsJbPwBxmJhRqkyNNzPbq5u8DY7g2eoAthtDS3wE3rWKFFNFMTScK9ANwvh3Tn+DEwBuX", "type"=>"ssh-rsa", "comment"=>"535e4f2964f6e1b638000033-default", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQCvk6aI6mJplA2BqfLaf6XheT26VQvvVhToSWGEl09+tZ/pVLBiZf+tDX0duGj5y+ATlMKAZ/urahO4nliQ1Kjs8ZO/xHYiIrZPm9jTgLEOd5c/wQh8Nof4E/FVqQEpcqq3ZhIk3a0bODE5nHfYPUphWjgLawFqVr0eoEbXUSc4QZ8Pubet2km6/sGtaEusF4ThERBq9jLjA5q4Gt/856lCa1SXPKCBtMBRLvMt4H7RYWwLG7ahzzuxygVNUVrIdLzW+WEWWJdI5SSpBXCDsJbPwBxmJhRqkyNNzPbq5u8DY7g2eoAthtDS3wE3rWKFFNFMTScK9ANwvh3Tn+DEwBuX"}]}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"535e579564f6e1b6380000a4"}, :gear=>"535e579564f6e1b63800009a", :job=>{:cartridge=>"openshift-origin-node", :action=>"update-configuration", :args=>{"--with-app-uuid"=>"535e579564f6e1b63800009a", "--with-app-name"=>"php543", "--with-expose-ports"=>false, "--with-container-uuid"=>"535e579564f6e1b63800009a", "--with-container-name"=>"php543", "--with-namespace"=>"bmengdev1", "--with-request-id"=>"0aad1876345c132e04bc7dc6a37af606", "--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}, {:tag=>{"op_id"=>"535e579564f6e1b6380000a5"}, :gear=>"535e579564f6e1b63800009a", :job=>{:cartridge=>"openshift-origin-node", :action=>"broker-auth-key-add", :args=>{"--with-app-uuid"=>"535e579564f6e1b63800009a", "--with-app-name"=>"php543", "--with-expose-ports"=>false, "--with-container-uuid"=>"535e579564f6e1b63800009a", "--with-container-name"=>"php543", "--with-namespace"=>"bmengdev1", "--with-request-id"=>"0aad1876345c132e04bc7dc6a37af606", "--with-iv"=>"r+ixGu67cAval3q3bI8MjC9YosVjRgi5pijGmBcZWEJ2QBQ/iATx4661P796\nwdcxBEMNO/L/W7Q3PRYnqJhaKVBfTo7ePGNhe0T2FbFbcNpO542ocKEVyq+J\nkP4S+6BbzgwAI6LihMzu0NkRIrVvbgfCCmstWngJOE9jFmF8nExZleXD5ERA\nJavl7b4j4pqjhz44yXMkq9boa6abuNgroBAQRuDbQ9/5D17mfLXie9eC97Iq\n5mshCzLirufZ57yQhDFq7EZf1VQx/8HqPm3zVvRktXcdUPPdSb4IEBK7yVMP\nRZUgtOYDfJ4zr8MY7xDf0gkEB1US8aE1iZqxi/fmtg==\n", "--with-token"=>"YDzN6Gk/DlpsDNq1CDycCd2exdcKac1k3G4Y5vRyNXOR3ZFNL6k4c+x1euwT\naXZbkMhdt65uWgMF6oqF7i4mPFL/dPfwI3+4q1g9tqAy/lc=\n"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-179-157-98  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:07.690 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 109ms  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:07.742 [DEBUG] Execute ExecuteConnectionsOp (pid:134)
>2014-04-28 09:29:07.768 [DEBUG] Execute PostConfigureCompOp comp_spec=component:php-5.4/php-5.4/535ccb8d450091146c000022 gear_id=535e579564f6e1b63800009a (pid:134)
>2014-04-28 09:29:07.783 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"php-5.4", :action=>"post-configure", :args=>{"--with-app-uuid"=>"535e579564f6e1b63800009a", "--with-app-name"=>"php543", "--with-expose-ports"=>false, "--with-container-uuid"=>"535e579564f6e1b63800009a", "--with-container-name"=>"php543", "--with-namespace"=>"bmengdev1", "--with-request-id"=>"0aad1876345c132e04bc7dc6a37af606", "--component-name"=>"php-5.4", "--cart-name"=>"php-5.4", "--with-software-version"=>"5.4", "--cartridge-vendor"=>"redhat"}}, ip-10-179-157-98, {'identity' => ip-10-179-157-98}) (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:09.379 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007dcce20 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-179-157-98", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"Starting PHP 5.4 cartridge (Apache+mod_php)\nApplication directory \"/\" selected as DocumentRoot\n", :exitcode=>0, :addtl_params=>{:deployments=>[{:id=>"b8921a6a", :ref=>"master", :sha1=>"496a767", :force_clean_build=>nil, :hot_deploy=>nil, :created_at=>1398691736.468, :activations=>[1398691748.3697078]}]}}}>] (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:09.381 [DEBUG] DEBUG: MCollective Response Time (execute_direct: post-configure): 1.611263994s  (Request ID: 0aad1876345c132e04bc7dc6a37af606) (pid:134)
>2014-04-28 09:29:09.384 [DEBUG] Execute TrackUsageOp gear_id=535e579564f6e1b63800009a (pid:134)
>2014-04-28 09:29:09.591 [DEBUG] SUCCESS ACTION=ADD_APPLICATION USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com APP_UUID=535e579564f6e1b63800009a DOMAIN=bmengdev1 Application php543 was created. (pid:134)
>2014-04-28 09:29:09.599 [INFO ] Completed 201 Created in 16503ms (Views: 7.5ms) (pid:134)
>2014-04-28 09:29:11.138 [INFO ] Started GET "/broker/rest/user" for 203.114.244.84 at 2014-04-28 09:29:11 -0400 (pid:134)
>2014-04-28 09:29:11.139 [INFO ] Processing by UserExtController#show as JSON (pid:134)
>2014-04-28 09:29:11.140 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:29:11.140 [INFO ] Filter chain halted as :authenticate_user! rendered or redirected (pid:134)
>2014-04-28 09:29:11.140 [INFO ] Completed 401 Unauthorized in 1ms (pid:134)
>2014-04-28 09:29:11.426 [INFO ] Started GET "/broker/rest/user" for 203.114.244.84 at 2014-04-28 09:29:11 -0400 (pid:134)
>2014-04-28 09:29:11.427 [INFO ] Processing by UserExtController#show as JSON (pid:134)
>2014-04-28 09:29:11.427 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:29:11.429 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com IP=203.114.244.84 SCOPES=session Authenticated (pid:134)
>2014-04-28 09:29:11.430 [DEBUG] SUCCESS ACTION=LIST_USER USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com DOMAIN= (pid:134)
>2014-04-28 09:29:11.432 [INFO ] Completed 200 OK in 5ms (Views: 1.0ms) (pid:134)
>2014-04-28 09:29:11.723 [INFO ] Started GET "/broker/rest/user/keys" for 203.114.244.84 at 2014-04-28 09:29:11 -0400 (pid:134)
>2014-04-28 09:29:11.724 [INFO ] Processing by KeysController#index as JSON (pid:134)
>2014-04-28 09:29:11.724 [DEBUG] API version 1.6 (pid:134)
>2014-04-28 09:29:11.726 [DEBUG] SUCCESS ACTION=AUTHENTICATE USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com IP=203.114.244.84 SCOPES=session Authenticated (pid:134)
>2014-04-28 09:29:11.727 [DEBUG] SUCCESS ACTION=LIST_KEY USER_ID=535e4f2964f6e1b638000033 LOGIN=bmeng+1@redhat.com DOMAIN= Found 1 ssh keys (pid:134)
>2014-04-28 09:29:11.729 [INFO ] Completed 200 OK in 5ms (Views: 1.0ms) (pid:134)

Actions: View

Attachments on bug 1091897: 890403