Attachment 915794 Details for Bug 1023520 – Comment

Comment

comment_6459024.txt (text/plain), 121.02 KB, created by Dan McPherson on 2013-10-25 19:56:09 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Dan McPherson

Created: 2013-10-25 19:56:09 UTC

Size: 121.02 KB

patch

obsolete

>mco for problem 2:
>
>I, [2013-10-25T05:18:40.849726 #4896]  INFO -- : openshift.rb:94:in `cartridge_do_action' cartridge_do_action validation = openshift-origin-node update-cluster {"--with-app-uuid"=>"526a372803ef6497f100007f", "--with-app-name"=>"app1", "--with-container-uuid"=>"526a372803ef6497f100007f", "--with-container-name"=>"app1", "--with-namespace"=>"imp1lv", "--with-uid"=>6453, "--with-request-id"=>"a049a9f5417724b51786152344781089", "--rollback"=>true, "--cart-name"=>"openshift-origin-node"}
>I, [2013-10-25T05:18:40.850932 #4896]  INFO -- : openshift.rb:135:in `execute_action' Executing action [update-cluster] using method oo_update_cluster with args [{"--with-app-uuid"=>"526a372803ef6497f100007f", "--with-app-name"=>"app1", "--with-container-uuid"=>"526a372803ef6497f100007f", "--with-container-name"=>"app1", "--with-namespace"=>"imp1lv", "--with-uid"=>6453, "--with-request-id"=>"a049a9f5417724b51786152344781089", "--rollback"=>true, "--cart-name"=>"openshift-origin-node"}]
>E, [2013-10-25T05:18:40.919560 #4896] ERROR -- : openshift.rb:313:in `rescue in with_container_from_args' undefined method `each_value' for nil:NilClass
>E, [2013-10-25T05:18:40.919810 #4896] ERROR -- : openshift.rb:314:in `rescue in with_container_from_args' /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:1135:in `generate_update_cluster_control_args'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:1265:in `update_cluster'
>/opt/rh/ruby193/root/usr/libexec/mcollective/mcollective/agent/openshift.rb:1001:in `block in oo_update_cluster'
>/opt/rh/ruby193/root/usr/libexec/mcollective/mcollective/agent/openshift.rb:302:in `with_container_from_args'
>/opt/rh/ruby193/root/usr/libexec/mcollective/mcollective/agent/openshift.rb:1000:in `oo_update_cluster'
>/opt/rh/ruby193/root/usr/libexec/mcollective/mcollective/agent/openshift.rb:140:in `execute_action'
>(eval):3:in `block in execute_action_with_newrelic_transaction_trace'
>/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/instrumentation/controller_instrumentation.rb:318:in `perform_action_with_newrelic_trace'
>(eval):2:in `execute_action_with_newrelic_transaction_trace'
>/opt/rh/ruby193/root/usr/libexec/mcollective/mcollective/agent/openshift.rb:105:in `cartridge_do_action'
>/opt/rh/ruby193/root/usr/share/ruby/mcollective/rpc/agent.rb:86:in `handlemsg'
>/opt/rh/ruby193/root/usr/share/ruby/mcollective/agents.rb:126:in `block (2 levels) in dispatch'
>/opt/rh/ruby193/root/usr/share/ruby/timeout.rb:69:in `timeout'
>/opt/rh/ruby193/root/usr/share/ruby/mcollective/agents.rb:125:in `block in dispatch'
>I, [2013-10-25T05:18:40.919990 #4896]  INFO -- : openshift.rb:151:in `execute_action' Finished executing action [update-cluster] (1)
>I, [2013-10-25T05:18:41.123778 #4896]  INFO -- : openshift.rb:115:in `cartridge_do_action' cartridge_do_action failed (1)
>------
>undefined method `each_value' for nil:NilClass
>------)
>I, [2013-10-25T05:18:41.770311 #4896]  INFO -- : openshift.rb:93:in `cartridge_do_action' cartridge_do_action call / action: cartridge_do, agent=openshift, data={:cartridge=>"haproxy-1.4",
> :action=>"deconfigure",
> :args=>
>  {"--with-app-uuid"=>"526a372803ef6497f100007f",
>   "--with-app-name"=>"app1",
>   "--with-container-uuid"=>"526a372803ef6497f100007f",
>   "--with-container-name"=>"app1",
>   "--with-namespace"=>"imp1lv",
>   "--with-uid"=>6453,
>
>
>
>
>
>platform.log
>
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1620       0 1048576             276       0   40000        
>
>October 25 05:18:12 INFO Shell command 'restorecon /var/lib/openshift/526a37296cec0ecb9f000075//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:12 INFO Shell command 'quota --always-resolve -w 526a37296cec0ecb9f000075' ran. rc=0 out=Disk quotas for user 526a37296cec0ecb9f000075 (uid 1253): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01     884       0 1048576             265       0   40000        
>
>October 25 05:18:12 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a37326cec0ee51a000164/php/versions/shared/configuration/etc/php.ini.erb > /var/lib/openshift/526a37326cec0ee51a000164/php/versions/shared/configuration/etc/php.ini\""' ran. rc=0 out=
>October 25 05:18:12 INFO Shell command 'restorecon /var/lib/openshift/526a372803ef6497f100007f//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:13 INFO Shell command 'restorecon /var/lib/openshift/526a372803ef6497f100007f//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:13 INFO Shell command 'restorecon /var/lib/openshift/526a37296cec0ecb9f000075//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:13 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a37326cec0ee51a000164/php/versions/shared/configuration/etc/conf.d/openshift.conf.erb > /var/lib/openshift/526a37326cec0ee51a000164/php/versions/shared/configuration/etc/conf.d/openshift.conf\""' ran. rc=0 out=
>October 25 05:18:13 INFO Shell command 'restorecon /var/lib/openshift/526a372803ef6497f100007f//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:14 INFO Shell command 'restorecon /var/lib/openshift/526a37296cec0ecb9f000075//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:14 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a37326cec0ee51a000164/php/versions/shared/configuration/etc/conf.d/performance.conf.erb > /var/lib/openshift/526a37326cec0ee51a000164/php/versions/shared/configuration/etc/conf.d/performance.conf\""' ran. rc=0 out=
>October 25 05:18:14 INFO Running install for 526a37326cec0ee51a000164/php
>October 25 05:18:14 INFO Creating cartridge directory 526a372803ef6497f100007f/haproxy
>October 25 05:18:15 INFO Shell command '/bin/cp -ad /var/lib/openshift/.cartridge_repository/redhat-haproxy/0.0.7/README.md /var/lib/openshift/.cartridge_repository/redhat-haproxy/0.0.7/COPYRIGHT /var/lib/openshift/.cartridge_repository/redhat-haproxy/0.0.7/bin /var/lib/openshift/.cartridge_repository/redhat-haproxy/0.0.7/versions /var/lib/openshift/.cartridge_repository/redhat-haproxy/0.0.7/hooks /var/lib/openshift/.cartridge_repository/redhat-haproxy/0.0.7/env /var/lib/openshift/.cartridge_repository/redhat-haproxy/0.0.7/LICENSE /var/lib/openshift/.cartridge_repository/redhat-haproxy/0.0.7/metadata /var/lib/openshift/526a372803ef6497f100007f/haproxy' ran. rc=0 out=
>October 25 05:18:15 INFO Created cartridge directory 526a372803ef6497f100007f/haproxy
>October 25 05:18:15 INFO Creating 2 private endpoints for 526a372803ef6497f100007f/haproxy
>October 25 05:18:15 INFO IPs already allocated for 8080 in gear 526a372803ef6497f100007f: ["127.12.154.129", "127.12.154.129"]
>October 25 05:18:15 INFO Created private endpoint for cart haproxy in gear 526a372803ef6497f100007f: [OPENSHIFT_HAPROXY_IP=127.12.154.130, OPENSHIFT_HAPROXY_PORT=8080]
>October 25 05:18:15 INFO IPs already allocated for 8080 in gear 526a372803ef6497f100007f: ["127.12.154.129", "127.12.154.129", "127.12.154.130"]
>October 25 05:18:15 INFO Created private endpoint for cart haproxy in gear 526a372803ef6497f100007f: [OPENSHIFT_HAPROXY_STATUS_IP=127.12.154.131, OPENSHIFT_HAPROXY_STATUS_PORT=8080]
>October 25 05:18:16 INFO Shell command '/sbin/runuser -s /bin/sh 526a37296cec0ecb9f000075 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c231' /bin/sh -c \"tar c . | tar xO | sha1sum | cut -f 1 -d ' '\""' ran. rc=0 out=8633be69a3ca7abf319146ddcc44955f0e74b3d4
>
>October 25 05:18:16 INFO Shell command '/usr/sbin/lsof -i @127.12.154.130:8080 -i @127.12.154.131:8080' ran. rc=1 out=
>October 25 05:18:16 INFO Running setup for 526a372803ef6497f100007f/haproxy
>October 25 05:18:16 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"/var/lib/openshift/526a37326cec0ee51a000164/php/bin/install --version 5.3\""' ran. rc=0 out=CONFIGURATION (CHANNEL PEAR.PHP.NET):
>=====================================
>Auto-discover new Channels     auto_discover    <not set>
>Default Channel                default_channel  pear.php.net
>HTTP Proxy Server Address      http_proxy       <not set>
>PEAR server [DEPRECATED]       master_server    <not set>
>Default Channel Mirror         preferred_mirror <not set>
>Remote Configuration File      remote_config    <not set>
>PEAR executables directory     bin_dir          /var/lib/openshift/526a37326cec0ee51a000164/php/phplib/pear/pear
>PEAR documentation directory   doc_dir          /var/lib/openshift/526a37326cec0ee51a000164/php/phplib/pear/pear/docs
>--
>.openshift/markers/
>.openshift/markers/.gitkeep
>public/
>public/.gitkeep
>tmp/
>tmp/.gitkeep
>
>sent 9906 bytes  received 363 bytes  20538.00 bytes/sec
>total size is 8567  speedup is 0.83
>
>October 25 05:18:17 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a372803ef6497f100007f/haproxy/env/OPENSHIFT_HAPROXY_LOG_DIR.erb > /var/lib/openshift/526a372803ef6497f100007f/haproxy/env/OPENSHIFT_HAPROXY_LOG_DIR\""' ran. rc=0 out=
>October 25 05:18:17 INFO Shell command '\
>set -xe;
>git init;
>git config user.email "builder@example.com";
>git config user.name "Template builder";
>git config core.logAllRefUpdates true;
>git add -f .;
>git commit -a -m "Creating template";
>' ran. rc=0 out=Initialized empty Git repository in /var/lib/openshift/526a37326cec0ee51a000164/git/template/.git/
>[master (root-commit) 722a0fe] Creating template
>--
> create mode 100644 misc/.gitkeep
> create mode 100644 php/health_check.php
> create mode 100644 php/index.php
>
>October 25 05:18:18 INFO Shell command '/sbin/runuser -s /bin/sh 526a37296cec0ecb9f000075 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c231' /bin/sh -c \"/usr/bin/rsync -av --delete /var/lib/openshift/526a37296cec0ecb9f000075/app-root/runtime/dependencies/ /var/lib/openshift/526a37296cec0ecb9f000075/app-deployments/2013-10-25_05-17-35.518/dependencies/\""' ran. rc=0 out=sending incremental file list
>./
>
>sent 82 bytes  received 15 bytes  194.00 bytes/sec
>total size is 0  speedup is 0.00
>
>October 25 05:18:18 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a372803ef6497f100007f/haproxy/env/OPENSHIFT_HAPROXY_PATH_ELEMENT.erb > /var/lib/openshift/526a372803ef6497f100007f/haproxy/env/OPENSHIFT_HAPROXY_PATH_ELEMENT\""' ran. rc=0 out=
>October 25 05:18:19 INFO Shell command '/sbin/runuser -s /bin/sh 526a37296cec0ecb9f000075 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c231' /bin/sh -c \"/usr/bin/rsync -av --delete /var/lib/openshift/526a37296cec0ecb9f000075/app-root/runtime/build-dependencies/ /var/lib/openshift/526a37296cec0ecb9f000075/app-deployments/2013-10-25_05-17-35.518/build-dependencies/\""' ran. rc=0 out=sending incremental file list
>./
>
>sent 82 bytes  received 15 bytes  194.00 bytes/sec
>total size is 0  speedup is 0.00
>
>October 25 05:18:19 INFO Shell command '\
>set -xe;
>git clone --bare --no-hardlinks template chkexsrv2.git;
>GIT_DIR=./chkexsrv2.git git config core.logAllRefUpdates true;
>GIT_DIR=./chkexsrv2.git git repack;
>' ran. rc=0 out=Cloning into bare repository 'chkexsrv2.git'...
>done.
>
>October 25 05:18:19 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/setup --version 1.4\""' ran. rc=0 out=
>October 25 05:18:19 INFO Ran /var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/setup --version 1.4 for 526a372803ef6497f100007f/haproxy
>
>October 25 05:18:19 INFO Processing ERB templates for haproxy
>October 25 05:18:19 INFO Shell command '/sbin/runuser -s /bin/sh 526a37296cec0ecb9f000075 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c231' /bin/sh -c \"
>set -xe;
>git rev-parse --short master
>\""' ran. rc=0 out=f85544a
>
>October 25 05:18:21 INFO Shell command 'find objects -type f 2>/dev/null | wc -l' ran. rc=0 out=21
>
>October 25 05:18:21 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"
>--
>shopt -s dotglob;
>if [ "$(find objects -type f 2>/dev/null | wc -l)" -eq "0" ]; then
>  exit 0;
>fi
>git archive --format=tar master | (cd /var/lib/openshift/526a37326cec0ee51a000164/app-root/runtime/repo && tar --warning=no-timestamp -xf -);
>\""' ran. rc=0 out=
>October 25 05:18:21 INFO Connecting frontend mapping for 526a37326cec0ee51a000164/php: [] => [127.12.190.129:8080] with options: {"websocket"=>true, "protocols"=>["http", "ws"]}
>October 25 05:18:21 INFO 526a37296cec0ecb9f000075 start against 'ruby'
>October 25 05:18:21 INFO Shell command '/usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-101d5pz/new.db' ran. rc=0 out=
>October 25 05:18:22 INFO Connecting frontend mapping for 526a37326cec0ee51a000164/php: [/health] => [127.12.190.129:8080] with options: {"health"=>true, "protocols"=>["http", "ws"]}
>October 25 05:18:22 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a372803ef6497f100007f/haproxy/conf/haproxy.cfg.erb > /var/lib/openshift/526a372803ef6497f100007f/haproxy/conf/haproxy.cfg\""' ran. rc=0 out=
>October 25 05:18:22 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a372803ef6497f100007f/haproxy/versions/1.4/configuration/haproxy.cfg.erb > /var/lib/openshift/526a372803ef6497f100007f/haproxy/versions/1.4/configuration/haproxy.cfg\""' ran. rc=0 out=
>October 25 05:18:22 INFO Running install for 526a372803ef6497f100007f/haproxy
>October 25 05:18:23 INFO Shell command '/usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-hwj1vq/new.db' ran. rc=0 out=
>October 25 05:18:23 INFO Connecting frontend mapping for 526a372803ef6497f100007f/haproxy: [] => [127.12.154.130:8080] with options: {"target_update"=>true, "connections"=>-1, "protocols"=>["http", "ws"]}
>October 25 05:18:23 INFO configure output: CONFIGURATION (CHANNEL PEAR.PHP.NET):
>=====================================
>Auto-discover new Channels     auto_discover    <not set>
>Default Channel                default_channel  pear.php.net
>HTTP Proxy Server Address      http_proxy       <not set>
>PEAR server [DEPRECATED]       master_server    <not set>
>Default Channel Mirror         preferred_mirror <not set>
>Remote Configuration File      remote_config    <not set>
>PEAR executables directory     bin_dir          /var/lib/openshift/526a37326cec0ee51a000164/php/phplib/pear/pear
>PEAR documentation directory   doc_dir          /var/lib/openshift/526a37326cec0ee51a000164/php/phplib/pear/pear/docs
>--
>
>Warning: date(): It is not safe to rely on the system's timezone settings. You are *required* to use the date.timezone setting or the date_default_timezone_set() function. In case you used any of those methods and you are still getting this warning, you most likely misspelled the timezone identifier. We selected 'America/New_York' for 'EDT/-4.0/DST' instead in Registry.php on line 1012
>config-set succeeded
>config-set succeeded
>
>October 25 05:18:23 INFO Shell command 'quota --always-resolve -w 526a37326cec0ee51a000164' ran. rc=0 out=Disk quotas for user 526a37326cec0ee51a000164 (uid 6525): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01     724       0 1048576             173       0   40000        
>
>October 25 05:18:23 INFO Shell command '/usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-97f03l/new.db' ran. rc=0 out=
>October 25 05:18:24 INFO Connecting frontend mapping for 526a372803ef6497f100007f/haproxy: [/health] => [127.12.154.130:8080/configuration/health] with options: {"file"=>true, "protocols"=>["http", "ws"]}
>October 25 05:18:24 INFO Shell command 'restorecon /var/lib/openshift/526a37326cec0ee51a000164//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:24 INFO Shell command 'quota --always-resolve -w 526a37326cec0ee51a000164' ran. rc=0 out=Disk quotas for user 526a37326cec0ee51a000164 (uid 6525): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01     724       0 1048576             173       0   40000        
>
>October 25 05:18:24 INFO Shell command '/usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-uzxfgk/new.db' ran. rc=0 out=
>October 25 05:18:24 INFO Shell command 'restorecon /var/lib/openshift/526a37326cec0ee51a000164//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:24 INFO Connecting frontend mapping for 526a372803ef6497f100007f/haproxy: [/haproxy-status] => [127.12.154.131:8080/] with options: {"protocols"=>["http"]}
>October 25 05:18:25 INFO Shell command '/usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-wca4lt/new.db' ran. rc=0 out=
>October 25 05:18:25 INFO Connecting frontend mapping for 526a372803ef6497f100007f/haproxy: [/health] => [127.12.154.131:8080] with options: {"health"=>true, "protocols"=>["http"]}
>October 25 05:18:25 INFO Shell command '/usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-1w8ju4f/new.db' ran. rc=0 out=
>October 25 05:18:25 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"tar c . | tar xO | sha1sum | cut -f 1 -d ' '\""' ran. rc=0 out=8633be69a3ca7abf319146ddcc44955f0e74b3d4
>
>October 25 05:18:26 INFO configure output: 
>October 25 05:18:26 INFO Shell command 'quota --always-resolve -w 526a372803ef6497f100007f' ran. rc=0 out=Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1688       0 1048576             294       0   40000        
>
>October 25 05:18:26 INFO Shell command 'find objects -type f 2>/dev/null | wc -l' ran. rc=0 out=42
>
>October 25 05:18:26 INFO 526a372803ef6497f100007f start against 'haproxy'
>October 25 05:18:26 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"/usr/bin/rsync -av --delete /var/lib/openshift/526a37326cec0ee51a000164/app-root/runtime/repo/ /var/lib/openshift/526a37326cec0ee51a000164/app-deployments/2013-10-25_05-17-44.137/repo/\""' ran. rc=0 out=sending incremental file list
>./
>README.md
>deplist.txt
>.openshift/
>.openshift/action_hooks/
>.openshift/action_hooks/README.md
>.openshift/cron/
>.openshift/cron/README.cron
>.openshift/cron/daily/
>--
>October 25 05:18:28 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"/usr/bin/rsync -av --delete /var/lib/openshift/526a37326cec0ee51a000164/app-root/runtime/build-dependencies/ /var/lib/openshift/526a37326cec0ee51a000164/app-deployments/2013-10-25_05-17-44.137/build-dependencies/\""' ran. rc=0 out=sending incremental file list
>
>sent 79 bytes  received 12 bytes  182.00 bytes/sec
>total size is 0  speedup is 0.00
>
>October 25 05:18:29 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"
>set -xe;
>git rev-parse --short master
>\""' ran. rc=0 out=722a0fe
>
>October 25 05:18:30 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"set -e; /var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/control start \""' ran. rc=0 out=HAProxy instance is started
>
>October 25 05:18:31 INFO Running post_install for 526a372803ef6497f100007f/haproxy
>October 25 05:18:31 INFO post-configure output: HAProxy instance is started
>
>October 25 05:18:31 INFO Shell command 'find objects -type f 2>/dev/null | wc -l' ran. rc=0 out=23
>
>October 25 05:18:31 INFO Shell command 'quota --always-resolve -w 526a372803ef6497f100007f' ran. rc=0 out=Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1712       0 1048576             301       0   40000        
>
>October 25 05:18:31 INFO Shell command '/sbin/runuser -s /bin/sh 526a37296cec0ecb9f000075 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c231' /bin/sh -c \"set -e; /var/lib/openshift/526a37296cec0ecb9f000075/ruby/bin/control start \""' ran. rc=0 out=Starting Ruby cartridge
>
>October 25 05:18:31 INFO Running post_install for 526a37296cec0ecb9f000075/ruby
>October 25 05:18:31 INFO post-configure output: Starting Ruby cartridge
>
>October 25 05:18:31 INFO Shell command 'quota --always-resolve -w 526a37296cec0ecb9f000075' ran. rc=0 out=Disk quotas for user 526a37296cec0ecb9f000075 (uid 1253): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>--
>October 25 05:18:32 INFO Shell command 'quota --always-resolve -w 526a37296cec0ecb9f000075' ran. rc=0 out=Disk quotas for user 526a37296cec0ecb9f000075 (uid 1253): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1032       0 1048576             314       0   40000        
>
>October 25 05:18:33 INFO Shell command 'restorecon /var/lib/openshift/526a37296cec0ecb9f000075//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:33 INFO Shell command 'oo-iptables-port-proxy addproxy 65296 "127.12.154.129:8080"' ran. rc=0 out=-I rhc-app-comm 1 -d 127.12.154.129 -p tcp --dport 8080 -j ACCEPT -m comment --comment 65296
>-I rhc-app-comm 1 -d 127.12.154.129 -m conntrack --ctstate NEW -m tcp -p tcp --dport 8080 -j ACCEPT -m comment --comment 65296
>-A OUTPUT -d 10.83.71.235/32 -m tcp -p tcp --dport 65296 -j DNAT --to-destination 127.12.154.129:8080
>-A PREROUTING -d 10.83.71.235/32 -m tcp -p tcp --dport 65296 -j DNAT --to-destination 127.12.154.129:8080
>
>October 25 05:18:34 INFO Created public endpoint for cart jbossews in gear 526a372803ef6497f100007f: [OPENSHIFT_JBOSSEWS_HTTP_PROXY_PORT=65296]
>October 25 05:18:34 INFO Shell command 'restorecon /var/lib/openshift/526a37296cec0ecb9f000075//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:34 INFO Shell command 'quota --always-resolve -w 526a372803ef6497f100007f' ran. rc=0 out=Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1712       0 1048576             301       0   40000        
>
>October 25 05:18:34 INFO Shell command 'oo-iptables-port-proxy showproxy 65296 | awk '{ print $2 }'' ran. rc=0 out=127.12.154.129:8080
>
>October 25 05:18:34 INFO Shell command '/sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"set -e; /var/lib/openshift/526a37326cec0ee51a000164/php/bin/control start \""' ran. rc=0 out=Starting PHP cartridge
>
>October 25 05:18:34 INFO Running post_install for 526a37326cec0ee51a000164/php
>October 25 05:18:34 INFO post-configure output: Starting PHP cartridge
>
>--
>October 25 05:18:35 INFO Shell command 'restorecon /var/lib/openshift/526a37326cec0ee51a000164//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:35 INFO Shell command 'quota --always-resolve -w 526a37326cec0ee51a000164' ran. rc=0 out=Disk quotas for user 526a37326cec0ee51a000164 (uid 6525): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1008       0 1048576             244       0   40000        
>
>October 25 05:18:36 INFO Shell command 'oo-iptables-port-proxy addproxy 65297 "127.12.154.130:8080"' ran. rc=0 out=-I rhc-app-comm 1 -d 127.12.154.130 -p tcp --dport 8080 -j ACCEPT -m comment --comment 65297
>-I rhc-app-comm 1 -d 127.12.154.130 -m conntrack --ctstate NEW -m tcp -p tcp --dport 8080 -j ACCEPT -m comment --comment 65297
>-A OUTPUT -d 10.83.71.235/32 -m tcp -p tcp --dport 65297 -j DNAT --to-destination 127.12.154.130:8080
>-A PREROUTING -d 10.83.71.235/32 -m tcp -p tcp --dport 65297 -j DNAT --to-destination 127.12.154.130:8080
>
>October 25 05:18:36 INFO Created public endpoint for cart haproxy in gear 526a372803ef6497f100007f: [OPENSHIFT_HAPROXY_PROXY_PORT=65297]
>October 25 05:18:36 INFO Shell command 'restorecon /var/lib/openshift/526a37326cec0ee51a000164//.ssh/authorized_keys' ran. rc=0 out=
>October 25 05:18:36 INFO Backing up 526a372803ef6497f100007fgear registry
>October 25 05:18:36 INFO Retrieving 526a372803ef6497f100007f gear registry entries prior to this update
>October 25 05:18:36 INFO Clearing 526a372803ef6497f100007f gear registry
>October 25 05:18:36 INFO Adding gear registry 526a372803ef6497f100007f new web entry: {:type=>:web, :uuid=>"526a372803ef6497f100007f", :namespace=>"imp1lv", :dns=>"app1-imp1lv.int.rhcloud.com", :proxy_hostname=>"ex-std-node1.int.rhcloud.com", :proxy_port=>"65296"}
>October 25 05:18:36 INFO Adding gear registry 526a372803ef6497f100007f new proxy entry: {:type=>:proxy, :uuid=>"526a372803ef6497f100007f", :namespace=>"imp1lv", :dns=>"app1-imp1lv.int.rhcloud.com", :proxy_hostname=>"ex-std-node1.int.rhcloud.com", :proxy_port=>0}
>October 25 05:18:36 INFO Saving gear registry 526a372803ef6497f100007f
>October 25 05:18:36 INFO Retrieving updated gear registry 526a372803ef6497f100007f entries
>October 25 05:18:36 INFO 526a372803ef6497f100007f update-cluster against 'haproxy'
>October 25 05:18:37 INFO Shell command 'useradd -u 4913                     -d /var/lib/openshift/526a376903ef64c70c000208                     -s /usr/bin/oo-trap-user                     -c 'OpenShift guest'                     -m                     -k /etc/openshift/skel                     526a376903ef64c70c000208 -G ""' ran. rc=0 out=
>October 25 05:18:38 INFO Shell command 'ip link show dev eth0' ran. rc=0 out=2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc htb state UP qlen 1000
>    link/ether 12:31:3d:1b:38:1d brd ff:ff:ff:ff:ff:ff
>
>October 25 05:18:38 INFO Shell command 'tc -s class show dev eth0 classid 1:1331' ran. rc=0 out=
>October 25 05:18:38 INFO Shell command 'cat /tmp/tc-batch20131025-4896-nyds52 | tc -force -batch' ran. rc=0 out=
>October 25 05:18:39 INFO Shell command 'quota --always-resolve -w 526a376903ef64c70c000208' ran. rc=0 out=Disk quotas for user 526a376903ef64c70c000208 (uid 4913): none
>
>October 25 05:18:40 INFO Shell command 'setquota --always-resolve -u 526a376903ef64c70c000208 0 1048576 0 40000 -a /var/lib/openshift' ran. rc=0 out=
>October 25 05:18:40 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"set -e; /var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/control update-cluster app1-imp1lv.int.rhcloud.com\|ex-std-node1.int.rhcloud.com:65296\""' ran. rc=1 out=
>October 25 05:18:40 INFO Shell command 'quota --always-resolve -w 526a372803ef6497f100007f' ran. rc=0 out=Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1716       0 1048576             303       0   40000        
>
>October 25 05:18:40 INFO Restoring 526a372803ef6497f100007f gear registry from backup
>October 25 05:18:41 INFO Shell command 'quota --always-resolve -w 526a372803ef6497f100007f' ran. rc=0 out=Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1716       0 1048576             303       0   40000        
>
>October 25 05:18:41 INFO Shell command 'oo-iptables-port-proxy removeproxy 57596 57597 57598 57599 57600' ran. rc=0 out=
>October 25 05:18:41 INFO Deleting private endpoints for 526a372803ef6497f100007f/haproxy
>October 25 05:18:41 INFO Deleted private endpoints for 526a372803ef6497f100007f/haproxy
>October 25 05:18:41 INFO 526a372803ef6497f100007f stop against 'haproxy'
>October 25 05:18:42 INFO Shell command '/sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/usr/bin/ssh-keygen -N '' -f /var/lib/openshift/526a376903ef64c70c000208/.openshift_ssh/id_rsa\""' ran. rc=0 out=Generating public/private rsa key pair.
>Your identification has been saved in /var/lib/openshift/526a376903ef64c70c000208/.openshift_ssh/id_rsa.
>Your public key has been saved in /var/lib/openshift/526a376903ef64c70c000208/.openshift_ssh/id_rsa.pub.
>The key fingerprint is:
>6a:fd:6e:62:99:f7:32:86:4e:f0:b7:73:82:38:ab:53 526a376903ef64c70c000208@ex-std-node1.int.rhcloud.com
>The key's randomart image is:
>+--[ RSA 2048]----+
>|                 |
>|                 |
>|                 |
>--
>|      E=         |
>|     .o.+=.      |
>|    ..o.BoO..    |
>|    .o.=.*+B.    |
>+-----------------+
>
>October 25 05:18:42 INFO Shell command 'quota --always-resolve -w 526a376903ef64c70c000208' ran. rc=0 out=Disk quotas for user 526a376903ef64c70c000208 (uid 4913): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01      76       0 1048576              25       0   40000        
>
>October 25 05:18:43 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"set -e; /var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/control stop \""' ran. rc=0 out=HAProxy instance is stopped
>
>October 25 05:18:43 INFO Disconnecting frontend mapping for 526a372803ef6497f100007f/haproxy: ["", "/health", "/haproxy-status", "/health"]
>October 25 05:18:43 INFO Shell command '/usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-tsl6s/new.db' ran. rc=0 out=
>October 25 05:18:44 INFO Deleting cartridge directory for 526a372803ef6497f100007f/haproxy
>October 25 05:18:44 INFO Deleted cartridge directory for 526a372803ef6497f100007f/haproxy
>October 25 05:18:44 INFO Shell command 'quota --always-resolve -w 526a372803ef6497f100007f' ran. rc=0 out=Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1640       0 1048576             282       0   40000        
>
>October 25 05:18:44 INFO Deleting private endpoints for 526a372803ef6497f100007f/jbossews
>October 25 05:18:44 INFO Deleted private endpoints for 526a372803ef6497f100007f/jbossews
>October 25 05:18:44 INFO 526a372803ef6497f100007f stop against 'jbossews'
>October 25 05:18:45 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"set -e; /var/lib/openshift/526a372803ef6497f100007f/jbossews/bin/control stop \""' ran. rc=0 out=
>October 25 05:18:45 INFO Creating cartridge directory 526a376903ef64c70c000208/php
>October 25 05:18:46 INFO Shell command '/bin/cp -ad /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/README.md /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/COPYRIGHT /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/bin /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/versions /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/env /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/LICENSE /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/metadata /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/openshift-origin-cartridge-php.spec /var/lib/openshift/526a376903ef64c70c000208/php' ran. rc=0 out=
>October 25 05:18:46 INFO Cartridge php recorded as primary within gear 526a376903ef64c70c000208
>October 25 05:18:46 INFO Created cartridge directory 526a376903ef64c70c000208/php
>October 25 05:18:46 INFO Shell command '/sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/var/lib/openshift/526a372803ef6497f100007f/jbossews/bin/teardown\""' ran. rc=0 out=
>October 25 05:18:46 INFO Creating 1 private endpoints for 526a376903ef64c70c000208/php
>October 25 05:18:46 INFO Ran teardown for 526a372803ef6497f100007f/jbossews
>October 25 05:18:46 INFO Disconnecting frontend mapping for 526a372803ef6497f100007f/jbossews: ["", "/health"]
>October 25 05:18:46 INFO IPs already allocated for 8080 in gear 526a376903ef64c70c000208: []
>October 25 05:18:46 INFO Created private endpoint for cart php in gear 526a376903ef64c70c000208: [OPENSHIFT_PHP_IP=127.9.152.129, OPENSHIFT_PHP_PORT=8080]
>October 25 05:18:47 INFO Deleting cartridge directory for 526a372803ef6497f100007f/jbossews
>October 25 05:18:47 INFO Deleted cartridge directory for 526a372803ef6497f100007f/jbossews
>October 25 05:18:47 INFO Shell command 'quota --always-resolve -w 526a372803ef6497f100007f' ran. rc=0 out=Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01     896       0 1048576             183       0   40000        
>
>October 25 05:18:47 INFO Shell command '/usr/sbin/lsof -i @127.9.152.129:8080' ran. rc=1 out=
>October 25 05:18:47 INFO Running setup for 526a376903ef64c70c000208/php
>October 25 05:18:48 INFO Shell command '/sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a376903ef64c70c000208/php/env/OPENSHIFT_PHP_LOG_DIR.erb > /var/lib/openshift/526a376903ef64c70c000208/php/env/OPENSHIFT_PHP_LOG_DIR\""' ran. rc=0 out=
>October 25 05:18:48 INFO Shell command 'quota --always-resolve -w 526a37036cec0e2da700006b' ran. rc=0 out=Disk quotas for user 526a37036cec0e2da700006b (uid 2238): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    2180       0 1048576             381       0   40000        
>
>--
>-D rhc-app-comm -d 127.12.154.129 -m conntrack --ctstate NEW -m tcp -p tcp --dport 8080 -j ACCEPT -m comment --comment 65296
>-D OUTPUT -d 10.83.71.235/32 -m tcp -p tcp --dport 65296 -j DNAT --to-destination 127.12.154.129:8080
>-D PREROUTING -d 10.83.71.235/32 -m tcp -p tcp --dport 65296 -j DNAT --to-destination 127.12.154.129:8080
>-D rhc-app-comm -d 127.12.154.130 -p tcp --dport 8080 -j ACCEPT -m comment --comment 65297
>-D rhc-app-comm -d 127.12.154.130 -m conntrack --ctstate NEW -m tcp -p tcp --dport 8080 -j ACCEPT -m comment --comment 65297
>-D OUTPUT -d 10.83.71.235/32 -m tcp -p tcp --dport 65297 -j DNAT --to-destination 127.12.154.130:8080
>-D PREROUTING -d 10.83.71.235/32 -m tcp -p tcp --dport 65297 -j DNAT --to-destination 127.12.154.130:8080
>
>October 25 05:18:53 INFO Shell command '/sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a376903ef64c70c000208/php/versions/shared/configuration/etc/conf.d/performance.conf.erb > /var/lib/openshift/526a376903ef64c70c000208/php/versions/shared/configuration/etc/conf.d/performance.conf\""' ran. rc=0 out=
>October 25 05:18:53 INFO Running install for 526a376903ef64c70c000208/php
>October 25 05:18:54 INFO Shell command 'userdel --remove -f "526a372803ef6497f100007f"' ran. rc=0 out=
>October 25 05:18:55 INFO Shell command 'setquota --always-resolve -u 526a372803ef6497f100007f 0 0 0 0 -a /var/lib/openshift' ran. rc=1 out=
>October 25 05:18:55 INFO Shell command '/sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/var/lib/openshift/526a376903ef64c70c000208/php/bin/install --version 5.3\""' ran. rc=0 out=CONFIGURATION (CHANNEL PEAR.PHP.NET):
>=====================================
>Auto-discover new Channels     auto_discover    <not set>
>Default Channel                default_channel  pear.php.net
>HTTP Proxy Server Address      http_proxy       <not set>
>PEAR server [DEPRECATED]       master_server    <not set>
>Default Channel Mirror         preferred_mirror <not set>
>Remote Configuration File      remote_config    <not set>
>PEAR executables directory     bin_dir          /var/lib/openshift/526a376903ef64c70c000208/php/phplib/pear/pear
>PEAR documentation directory   doc_dir          /var/lib/openshift/526a376903ef64c70c000208/php/phplib/pear/pear/docs
>
>
>
>
>platform-trace.log:
>
>=====================================
>Auto-discover new Channels     auto_discover    <not set>
>Default Channel                default_channel  pear.php.net
>HTTP Proxy Server Address      http_proxy       <not set>
>PEAR server [DEPRECATED]       master_server    <not set>
>Default Channel Mirror         preferred_mirror <not set>
>Remote Configuration File      remote_config    <not set>
>PEAR executables directory     bin_dir          /var/lib/openshift/526a37326cec0ee51a000164/php/phplib/pear/pear
>--
>PHP   5. PEAR_Registry->_lock() /usr/share/pear/PEAR/Registry.php:1590
>PHP   6. PEAR_Registry->_assertStateDir() /usr/share/pear/PEAR/Registry.php:823
>PHP   7. PEAR_Registry->_initializeDirs() /usr/share/pear/PEAR/Registry.php:410
>PHP   8. PEAR_Registry->_initializeChannelDirs() /usr/share/pear/PEAR/Registry.php:307
>PHP   9. PEAR_Registry->_addChannel() /usr/share/pear/PEAR/Registry.php:282
>PHP  10. date() /usr/share/pear/PEAR/Registry.php:1012
>
>October 25 05:18:16 INFO oo_spawn buffer(11/) config-set succeeded
>config-set succeeded
>
>October 25 05:18:16 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a372803ef6497f100007f/haproxy/env/OPENSHIFT_HAPROXY_LOG_DIR.erb > /var/lib/openshift/526a372803ef6497f100007f/haproxy/env/OPENSHIFT_HAPROXY_LOG_DIR\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/", :out=>#<IO:fd 19>, :err=>#<IO:fd 16>}
>October 25 05:18:16 INFO oo_spawn running /bin/cp -ad /var/lib/openshift/526a37326cec0ee51a000164/php/usr/template /var/lib/openshift/526a37326cec0ee51a000164/git: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 21>, :err=>#<IO:fd 11>}
>October 25 05:18:17 INFO oo_spawn buffer(17/) sending incremental file list
>./
>
>October 25 05:18:17 INFO oo_spawn buffer(17/) README.md
>
>October 25 05:18:17 INFO oo_spawn buffer(17/) config.ru
>.openshift/
>
>October 25 05:18:17 INFO oo_spawn buffer(17/) .openshift/action_hooks/
>--
>October 25 05:18:17 INFO oo_spawn buffer(12/) Initialized empty Git repository in /var/lib/openshift/526a37326cec0ee51a000164/git/template/.git/
>
>October 25 05:18:17 INFO oo_spawn buffer(6/) + git config user.name 'Template builder'
>
>October 25 05:18:17 INFO oo_spawn buffer(6/) + git config core.logAllRefUpdates true
>
>October 25 05:18:17 INFO oo_spawn buffer(6/) + git add -f .
>
>October 25 05:18:17 INFO oo_spawn buffer(6/) + git commit -a -m 'Creating template'
>
>October 25 05:18:17 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a372803ef6497f100007f/haproxy/env/OPENSHIFT_HAPROXY_PATH_ELEMENT.erb > /var/lib/openshift/526a372803ef6497f100007f/haproxy/env/OPENSHIFT_HAPROXY_PATH_ELEMENT\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/", :out=>#<IO:fd 16>, :err=>#<IO:fd 14>}
>October 25 05:18:17 INFO oo_spawn buffer(12/) [master (root-commit) 722a0fe] Creating template
> 18 files changed, 226 insertions(+)
> create mode 100644 .openshift/action_hooks/README.md
> create mode 100644 .openshift/cron/README.cron
> create mode 100644 .openshift/cron/daily/.gitignore
> create mode 100644 .openshift/cron/hourly/.gitignore
> create mode 100644 .openshift/cron/minutely/.gitignore
> create mode 100644 .openshift/cron/monthly/.gitignore
> create mode 100644 .openshift/cron/weekly/README
> create mode 100644 .openshift/cron/weekly/chrono.dat
>--
>
>October 25 05:18:18 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a37296cec0ecb9f000075 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c231' /bin/sh -c \"/usr/bin/rsync -av --delete /var/lib/openshift/526a37296cec0ecb9f000075/app-root/runtime/build-dependencies/ /var/lib/openshift/526a37296cec0ecb9f000075/app-deployments/2013-10-25_05-17-35.518/build-dependencies/\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 16>, :err=>#<IO:fd 12>}
>October 25 05:18:18 INFO oo_spawn buffer(14/) Cloning into bare repository 'chkexsrv2.git'...
>done.
>
>October 25 05:18:18 INFO oo_spawn buffer(6/) + GIT_DIR=./chkexsrv2.git
>+ git config core.logAllRefUpdates true
>+ GIT_DIR=./chkexsrv2.git
>+ git repack
>
>October 25 05:18:18 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/setup --version 1.4\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/haproxy", :out=>#<IO:fd 16>, :err=>#<IO:fd 12>}
>October 25 05:18:18 INFO oo_spawn buffer(13/) sending incremental file list
>
>October 25 05:18:18 INFO oo_spawn buffer(13/) ./
>
>October 25 05:18:19 INFO oo_spawn buffer(13/) 
>sent 82 bytes  received 15 bytes  194.00 bytes/sec
>total size is 0  speedup is 0.00
>
>October 25 05:18:19 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a37296cec0ecb9f000075 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c231' /bin/sh -c \"
>set -xe;
>--
>
>October 25 05:18:19 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"
>set -xe;
>shopt -s dotglob;
>if [ "$(find objects -type f 2>/dev/null | wc -l)" -eq "0" ]; then
>  exit 0;
>fi
>git archive --format=tar master | (cd /var/lib/openshift/526a37326cec0ee51a000164/app-root/runtime/repo && tar --warning=no-timestamp -xf -);
>\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a37326cec0ee51a000164/git/chkexsrv2.git", :out=>#<IO:fd 13>, :err=>#<IO:fd 11>}
>October 25 05:18:20 INFO oo_spawn running find objects -type f 2>/dev/null | wc -l: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a37296cec0ecb9f000075/git/r8.git", :out=>#<IO:fd 15>, :err=>#<IO:fd 13>}
>October 25 05:18:20 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a372803ef6497f100007f/haproxy/conf/haproxy.cfg.erb > /var/lib/openshift/526a372803ef6497f100007f/haproxy/conf/haproxy.cfg\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/", :out=>#<IO:fd 16>, :err=>#<IO:fd 13>}
>October 25 05:18:21 INFO oo_spawn buffer(14/) 21
>
>October 25 05:18:21 INFO oo_spawn buffer(6/) + shopt -s dotglob
>+ '[' 23 -eq 0 ']'
>+ git archive --format=tar master
>+ cd /var/lib/openshift/526a37326cec0ee51a000164/app-root/runtime/repo
>+ tar --warning=no-timestamp -xf -
>
>October 25 05:18:21 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a37296cec0ecb9f000075 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c231' /bin/sh -c \"set -e; /var/lib/openshift/526a37296cec0ecb9f000075/ruby/bin/control start \"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a37296cec0ecb9f000075/ruby", :out=>#<IO:fd 16>, :err=>#<IO:fd 13>}
>October 25 05:18:21 INFO oo_spawn running /usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-101d5pz/new.db: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 17>, :err=>#<IO:fd 13>}
>October 25 05:18:22 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a372803ef6497f100007f/haproxy/versions/1.4/configuration/haproxy.cfg.erb > /var/lib/openshift/526a372803ef6497f100007f/haproxy/versions/1.4/configuration/haproxy.cfg\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/", :out=>#<IO:fd 16>, :err=>#<IO:fd 13>}
>October 25 05:18:22 INFO oo_spawn buffer(14/) Starting Ruby cartridge
>
>October 25 05:18:22 INFO oo_spawn running /usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-hwj1vq/new.db: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 17>, :err=>#<IO:fd 13>}
>October 25 05:18:23 INFO oo_spawn running quota --always-resolve -w 526a37326cec0ee51a000164: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:23 INFO oo_spawn buffer(13/) Disk quotas for user 526a37326cec0ee51a000164 (uid 6525): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01     724       0 1048576             173       0   40000        
>
>October 25 05:18:23 INFO oo_spawn running /usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-97f03l/new.db: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 13>, :err=>#<IO:fd 11>}
>October 25 05:18:23 INFO oo_spawn running restorecon /var/lib/openshift/526a37326cec0ee51a000164//.ssh/authorized_keys: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 17>, :err=>#<IO:fd 15>}
>--
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01     724       0 1048576             173       0   40000        
>
>October 25 05:18:24 INFO oo_spawn running restorecon /var/lib/openshift/526a37326cec0ee51a000164//.ssh/authorized_keys: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 17>, :err=>#<IO:fd 13>}
>October 25 05:18:24 INFO oo_spawn running /usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-wca4lt/new.db: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 13>, :err=>#<IO:fd 11>}
>October 25 05:18:25 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"tar c . | tar xO | sha1sum | cut -f 1 -d ' '\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a37326cec0ee51a000164/app-deployments/by-id/9706dabb", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:25 INFO oo_spawn running /usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-1w8ju4f/new.db: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 16>, :err=>#<IO:fd 12>}
>October 25 05:18:25 INFO oo_spawn buffer(13/) 8633be69a3ca7abf319146ddcc44955f0e74b3d4
>
>October 25 05:18:25 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"/usr/bin/rsync -av --delete /var/lib/openshift/526a37326cec0ee51a000164/app-root/runtime/repo/ /var/lib/openshift/526a37326cec0ee51a000164/app-deployments/2013-10-25_05-17-44.137/repo/\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:26 INFO oo_spawn running quota --always-resolve -w 526a372803ef6497f100007f: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 8>}
>October 25 05:18:26 INFO oo_spawn buffer(12/) Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1688       0 1048576             294       0   40000        
>
>October 25 05:18:26 INFO oo_spawn running find objects -type f 2>/dev/null | wc -l: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/git/app1.git", :out=>#<IO:fd 15>, :err=>#<IO:fd 8>}
>October 25 05:18:26 INFO oo_spawn buffer(13/) sending incremental file list
>
>October 25 05:18:26 INFO oo_spawn buffer(13/) ./
>
>October 25 05:18:26 INFO oo_spawn buffer(13/) README.md
>
>October 25 05:18:26 INFO oo_spawn buffer(13/) deplist.txt
>
>October 25 05:18:26 INFO oo_spawn buffer(12/) 42
>
>--
>October 25 05:18:26 INFO oo_spawn buffer(13/) libs/
>libs/.gitkeep
>
>October 25 05:18:26 INFO oo_spawn buffer(13/) misc/
>misc/.gitkeep
>
>October 25 05:18:26 INFO oo_spawn buffer(13/) php/
>php/health_check.php
>php/index.php
>
>October 25 05:18:26 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"set -e; /var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/control start \"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/haproxy", :out=>#<IO:fd 15>, :err=>#<IO:fd 8>}
>October 25 05:18:26 INFO oo_spawn buffer(13/) 
>sent 9700 bytes  received 405 bytes  20210.00 bytes/sec
>total size is 8220  speedup is 0.81
>
>October 25 05:18:26 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"/usr/bin/rsync -av --delete /var/lib/openshift/526a37326cec0ee51a000164/app-root/runtime/dependencies/ /var/lib/openshift/526a37326cec0ee51a000164/app-deployments/2013-10-25_05-17-44.137/dependencies/\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 11>}
>October 25 05:18:27 INFO oo_spawn buffer(13/) sending incremental file list
>
>October 25 05:18:27 INFO oo_spawn buffer(13/) ./
>
>October 25 05:18:27 INFO oo_spawn buffer(13/) php/
>--
>set -xe;
>git rev-parse --short master
>\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a37326cec0ee51a000164/git/chkexsrv2.git", :out=>#<IO:fd 15>, :err=>#<IO:fd 11>}
>October 25 05:18:29 INFO oo_spawn buffer(8/) + git rev-parse --short master
>
>October 25 05:18:29 INFO oo_spawn buffer(13/) 722a0fe
>
>October 25 05:18:30 INFO oo_spawn running find objects -type f 2>/dev/null | wc -l: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a37326cec0ee51a000164/git/chkexsrv2.git", :out=>#<IO:fd 15>, :err=>#<IO:fd 11>}
>October 25 05:18:30 INFO oo_spawn buffer(12/) HAProxy instance is started
>
>October 25 05:18:31 INFO oo_spawn running quota --always-resolve -w 526a372803ef6497f100007f: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 11>}
>October 25 05:18:31 INFO oo_spawn buffer(13/) 23
>
>October 25 05:18:31 INFO oo_spawn buffer(12/) Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1712       0 1048576             301       0   40000        
>
>October 25 05:18:31 INFO oo_spawn running quota --always-resolve -w 526a37296cec0ecb9f000075: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 12>}
>October 25 05:18:31 INFO oo_spawn buffer(13/) Disk quotas for user 526a37296cec0ecb9f000075 (uid 1253): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1032       0 1048576             314       0   40000        
>
>October 25 05:18:31 INFO oo_spawn running restorecon /var/lib/openshift/526a37296cec0ecb9f000075//.ssh/authorized_keys: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:31 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a37326cec0ee51a000164 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c408' /bin/sh -c \"set -e; /var/lib/openshift/526a37326cec0ee51a000164/php/bin/control start \"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a37326cec0ee51a000164/php", :out=>#<IO:fd 16>, :err=>#<IO:fd 14>}
>--
>October 25 05:18:32 INFO oo_spawn running restorecon /var/lib/openshift/526a37296cec0ecb9f000075//.ssh/authorized_keys: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 17>, :err=>#<IO:fd 13>}
>October 25 05:18:32 INFO oo_spawn buffer(14/) -I rhc-app-comm 1 -d 127.12.154.129 -p tcp --dport 8080 -j ACCEPT -m comment --comment 65296
>
>October 25 05:18:32 INFO oo_spawn buffer(14/) -I rhc-app-comm 1 -d 127.12.154.129 -m conntrack --ctstate NEW -m tcp -p tcp --dport 8080 -j ACCEPT -m comment --comment 65296
>
>October 25 05:18:33 INFO oo_spawn buffer(14/) -A OUTPUT -d 10.83.71.235/32 -m tcp -p tcp --dport 65296 -j DNAT --to-destination 127.12.154.129:8080
>
>October 25 05:18:33 INFO oo_spawn running restorecon /var/lib/openshift/526a37296cec0ecb9f000075//.ssh/authorized_keys: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 17>, :err=>#<IO:fd 13>}
>October 25 05:18:33 INFO oo_spawn buffer(14/) -A PREROUTING -d 10.83.71.235/32 -m tcp -p tcp --dport 65296 -j DNAT --to-destination 127.12.154.129:8080
>
>October 25 05:18:34 INFO oo_spawn running quota --always-resolve -w 526a372803ef6497f100007f: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 16>, :err=>#<IO:fd 11>}
>October 25 05:18:34 INFO oo_spawn buffer(14/) Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1712       0 1048576             301       0   40000        
>
>October 25 05:18:34 INFO oo_spawn running oo-iptables-port-proxy showproxy 65296 | awk '{ print $2 }': {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 8>}
>October 25 05:18:34 INFO oo_spawn buffer(11/) 127.12.154.129:8080
>
>October 25 05:18:34 INFO oo_spawn running oo-iptables-port-proxy showproxy 65297 | awk '{ print $2 }': {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 8>}
>October 25 05:18:34 INFO oo_spawn running quota --always-resolve -w 526a37326cec0ee51a000164: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 12>}
>October 25 05:18:35 INFO oo_spawn running oo-iptables-port-proxy addproxy 65297 "127.12.154.130:8080": {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 11>}
>October 25 05:18:35 INFO oo_spawn buffer(13/) Disk quotas for user 526a37326cec0ee51a000164 (uid 6525): 
>--
>
>October 25 05:18:35 INFO oo_spawn buffer(12/) -A PREROUTING -d 10.83.71.235/32 -m tcp -p tcp --dport 65297 -j DNAT --to-destination 127.12.154.130:8080
>
>October 25 05:18:35 INFO oo_spawn running quota --always-resolve -w 526a37326cec0ee51a000164: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 11>}
>October 25 05:18:35 INFO oo_spawn buffer(13/) Disk quotas for user 526a37326cec0ee51a000164 (uid 6525): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1008       0 1048576             244       0   40000        
>
>October 25 05:18:35 INFO oo_spawn running restorecon /var/lib/openshift/526a37326cec0ee51a000164//.ssh/authorized_keys: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 13>, :err=>#<IO:fd 11>}
>October 25 05:18:36 INFO oo_spawn running useradd -u 4913                     -d /var/lib/openshift/526a376903ef64c70c000208                     -s /usr/bin/oo-trap-user                     -c 'OpenShift guest'                     -m                     -k /etc/openshift/skel                     526a376903ef64c70c000208 -G "": {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 13>}
>October 25 05:18:36 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"set -e; /var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/control update-cluster app1-imp1lv.int.rhcloud.com\|ex-std-node1.int.rhcloud.com:65296\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/haproxy", :out=>#<IO:fd 17>, :err=>#<IO:fd 15>}
>October 25 05:18:37 INFO oo_spawn running ip link show dev eth0: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :chdir=>"/", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:38 INFO oo_spawn buffer(14/) 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc htb state UP qlen 1000
>    link/ether 12:31:3d:1b:38:1d brd ff:ff:ff:ff:ff:ff
>
>October 25 05:18:38 INFO oo_spawn running tc -s class show dev eth0 classid 1:1331: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :chdir=>"/", :out=>#<IO:fd 18>, :err=>#<IO:fd 15>}
>October 25 05:18:38 INFO oo_spawn running cat /tmp/tc-batch20131025-4896-nyds52 | tc -force -batch: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :chdir=>"/", :out=>#<IO:fd 18>, :err=>#<IO:fd 15>}
>October 25 05:18:39 INFO oo_spawn running quota --always-resolve -w 526a376903ef64c70c000208: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:39 INFO oo_spawn buffer(14/) Disk quotas for user 526a376903ef64c70c000208 (uid 4913): none
>
>October 25 05:18:39 INFO oo_spawn running setquota --always-resolve -u 526a376903ef64c70c000208 0 1048576 0 40000 -a /var/lib/openshift: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:40 INFO oo_spawn running oo-iptables-port-proxy removeproxy 57596 57597 57598 57599 57600: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:40 INFO oo_spawn buffer(13/) /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `initialize': Permission denied - /var/lib/openshift/526a372803ef6497f100007f/jbossews/metadata/manifest.yml (Errno::EACCES)
>	from /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `open'
>	from /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `safe_load_file'
>	from /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-common-1.16.2/lib/openshift-origin-common/models/manifest.rb:217:in `initialize'
>	from /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:168:in `new'
>	from /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:168:in `get_cartridge_from_directory'
>	from /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:94:in `primary_cartridge'
>	from /usr/lib/openshift/cartridge_sdk/ruby/sdk.rb:26:in `primary_cartridge'
>	from /usr/lib/openshift/cartridge_sdk/ruby/sdk.rb:30:in `primary_cartridge_manifest'
>	from -e:1:in `<main>'
>
>October 25 05:18:40 INFO oo_spawn running quota --always-resolve -w 526a372803ef6497f100007f: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 16>, :err=>#<IO:fd 13>}
>October 25 05:18:40 INFO oo_spawn buffer(15/) Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1716       0 1048576             303       0   40000        
>
>October 25 05:18:40 INFO oo_spawn running quota --always-resolve -w 526a372803ef6497f100007f: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 16>, :err=>#<IO:fd 13>}
>October 25 05:18:41 INFO oo_spawn buffer(15/) Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1716       0 1048576             303       0   40000        
>
>October 25 05:18:41 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/usr/bin/ssh-keygen -N '' -f /var/lib/openshift/526a376903ef64c70c000208/.openshift_ssh/id_rsa\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a376903ef64c70c000208", :out=>#<IO:fd 14>, :err=>#<IO:fd 12>}
>October 25 05:18:41 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"set -e; /var/lib/openshift/526a372803ef6497f100007f/haproxy/bin/control stop \"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/haproxy", :out=>#<IO:fd 16>, :err=>#<IO:fd 14>}
>October 25 05:18:42 INFO oo_spawn buffer(13/) Generating public/private rsa key pair.
>Your identification has been saved in /var/lib/openshift/526a376903ef64c70c000208/.openshift_ssh/id_rsa.
>Your public key has been saved in /var/lib/openshift/526a376903ef64c70c000208/.openshift_ssh/id_rsa.pub.
>The key fingerprint is:
>6a:fd:6e:62:99:f7:32:86:4e:f0:b7:73:82:38:ab:53 526a376903ef64c70c000208@ex-std-node1.int.rhcloud.com
>The key's randomart image is:
>+--[ RSA 2048]----+
>|                 |
>|                 |
>|                 |
>--
>+-----------------+
>
>October 25 05:18:42 INFO oo_spawn running quota --always-resolve -w 526a376903ef64c70c000208: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 13>, :err=>#<IO:fd 8>}
>October 25 05:18:42 INFO oo_spawn buffer(11/) Disk quotas for user 526a376903ef64c70c000208 (uid 4913): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01      76       0 1048576              25       0   40000        
>
>October 25 05:18:43 INFO oo_spawn buffer(15/) HAProxy instance is stopped
>
>October 25 05:18:43 INFO oo_spawn running /usr/sbin/httxt2dbm -f DB -i /etc/httpd/conf.d/openshift/nodes.txt -o /etc/httpd/conf.d/openshift/nodes.db-20131025-4896-tsl6s/new.db: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 13>, :err=>#<IO:fd 11>}
>October 25 05:18:44 INFO oo_spawn running quota --always-resolve -w 526a372803ef6497f100007f: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 05:18:44 INFO oo_spawn buffer(11/) Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    1640       0 1048576             282       0   40000        
>
>October 25 05:18:44 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"set -e; /var/lib/openshift/526a372803ef6497f100007f/jbossews/bin/control stop \"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/jbossews", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 05:18:45 INFO oo_spawn buffer(6/) The jbossews cartridge is already stopped
>
>October 25 05:18:45 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a372803ef6497f100007f -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c6,c336' /bin/sh -c \"/var/lib/openshift/526a372803ef6497f100007f/jbossews/bin/teardown\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a372803ef6497f100007f/jbossews", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 05:18:45 INFO oo_spawn running /bin/cp -ad /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/README.md /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/COPYRIGHT /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/bin /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/versions /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/env /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/LICENSE /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/metadata /var/lib/openshift/.cartridge_repository/redhat-php/0.0.7/openshift-origin-cartridge-php.spec /var/lib/openshift/526a376903ef64c70c000208/php: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 12>}
>October 25 05:18:46 INFO oo_spawn running /usr/sbin/lsof -i @127.9.152.129:8080: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 12>}
>October 25 05:18:47 INFO oo_spawn running quota --always-resolve -w 526a372803ef6497f100007f: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 11>}
>October 25 05:18:47 INFO oo_spawn buffer(12/) Disk quotas for user 526a372803ef6497f100007f (uid 6453): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01     896       0 1048576             183       0   40000        
>
>October 25 05:18:47 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a376903ef64c70c000208/php/env/OPENSHIFT_PHP_LOG_DIR.erb > /var/lib/openshift/526a376903ef64c70c000208/php/env/OPENSHIFT_PHP_LOG_DIR\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a376903ef64c70c000208/", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 05:18:48 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/var/lib/openshift/526a376903ef64c70c000208/php/bin/setup --version 5.3\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a376903ef64c70c000208/php", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 05:18:48 INFO oo_spawn running quota --always-resolve -w 526a37036cec0e2da700006b: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 14>, :err=>#<IO:fd 12>}
>October 25 05:18:48 INFO oo_spawn buffer(13/) Disk quotas for user 526a37036cec0e2da700006b (uid 2238): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01    2180       0 1048576             381       0   40000        
>
>--
>October 25 05:18:52 INFO oo_spawn buffer(13/) -D rhc-app-comm -d 127.12.154.130 -p tcp --dport 8080 -j ACCEPT -m comment --comment 65297
>
>October 25 05:18:52 INFO oo_spawn buffer(13/) -D rhc-app-comm -d 127.12.154.130 -m conntrack --ctstate NEW -m tcp -p tcp --dport 8080 -j ACCEPT -m comment --comment 65297
>
>October 25 05:18:52 INFO oo_spawn buffer(13/) -D OUTPUT -d 10.83.71.235/32 -m tcp -p tcp --dport 65297 -j DNAT --to-destination 127.12.154.130:8080
>
>October 25 05:18:52 INFO oo_spawn buffer(13/) -D PREROUTING -d 10.83.71.235/32 -m tcp -p tcp --dport 65297 -j DNAT --to-destination 127.12.154.130:8080
>
>October 25 05:18:53 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/usr/bin/oo-erb -S 2 -- /var/lib/openshift/526a376903ef64c70c000208/php/versions/shared/configuration/etc/conf.d/performance.conf.erb > /var/lib/openshift/526a376903ef64c70c000208/php/versions/shared/configuration/etc/conf.d/performance.conf\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a376903ef64c70c000208/", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:53 INFO oo_spawn running /sbin/runuser -s /bin/sh 526a376903ef64c70c000208 -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c4,c831' /bin/sh -c \"/var/lib/openshift/526a376903ef64c70c000208/php/bin/install --version 5.3\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/526a376903ef64c70c000208/php", :out=>#<IO:fd 15>, :err=>#<IO:fd 13>}
>October 25 05:18:54 INFO oo_spawn running userdel --remove -f "526a372803ef6497f100007f": {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:54 INFO oo_spawn buffer(14/) CONFIGURATION (CHANNEL PEAR.PHP.NET):
>
>October 25 05:18:54 INFO oo_spawn buffer(14/) =====================================
>
>October 25 05:18:54 INFO oo_spawn buffer(14/) Auto-discover new Channels     auto_discover    <not set>
>
>October 25 05:18:54 INFO oo_spawn buffer(14/) Default Channel                default_channel  pear.php.net
>
>October 25 05:18:54 INFO oo_spawn buffer(14/) HTTP Proxy Server Address      http_proxy       <not set>
>
>--
>PHP   4. PEAR_Registry->channelExists() /usr/share/pear/PEAR/Command/Config.php:237
>PHP   5. PEAR_Registry->_lock() /usr/share/pear/PEAR/Registry.php:1590
>PHP   6. PEAR_Registry->_assertStateDir() /usr/share/pear/PEAR/Registry.php:823
>PHP   7. PEAR_Registry->_initializeDirs() /usr/share/pear/PEAR/Registry.php:410
>PHP   8. PEAR_Registry->_initializeChannelDirs() /usr/share/pear/PEAR/Registry.php:307
>PHP   9. PEAR_Registry->_addChannel() /usr/share/pear/PEAR/Registry.php:282
>PHP  10. date() /usr/share/pear/PEAR/Registry.php:1012
>
>October 25 05:18:55 INFO oo_spawn buffer(14/) config-set succeeded
>
>October 25 05:18:55 INFO oo_spawn running setquota --always-resolve -u 526a372803ef6497f100007f 0 0 0 0 -a /var/lib/openshift: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 15>, :err=>#<IO:fd 12>}
>October 25 05:18:55 INFO oo_spawn buffer(6/) setquota: 
>October 25 05:18:55 INFO oo_spawn buffer(6/) user 526a372803ef6497f100007f does not exist.
>
>October 25 05:18:55 INFO oo_spawn running /bin/cp -ad /var/lib/openshift/526a376903ef64c70c000208/php/usr/template /var/lib/openshift/526a376903ef64c70c000208/git: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 05:18:55 INFO oo_spawn running \
>set -xe;
>git init;
>git config user.email "builder@example.com";
>git config user.name "Template builder";
>git config core.logAllRefUpdates true;
>git add -f .;
>git commit -a -m "Creating template";
>
>
>
>
>
>production.log
>
>2013-10-25 05:18:40.449 [INFO ] Started POST "/broker/rest/domain/nyfixj/application/ulcnofer/cartridge/postgresql-8.4/events" for 209.132.181.86 at 2013-10-25 05:18:40 -0400 (pid:13479)
>2013-10-25 05:18:40.535 [INFO ] Processing by EmbCartEventsController#create as JSON (pid:13479)
>2013-10-25 05:18:40.541 [INFO ] Parameters: {"event"=>"stop", "domain_id"=>"nyfixj", "application_id"=>"ulcnofer", "cartridge_id"=>"postgresql-8.4", "emb_cart_event"=>{"event"=>"stop"}} (pid:13479)
>2013-10-25 05:18:40.541 [DEBUG] API version 1.5 (pid:13479)
>2013-10-25 05:18:40.560 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007eedf20 @agent="openshift", @action="cartridge_do", @results={:sender=>"ex-std-node1.int.rhcloud.com", :statuscode=>1, :statusmsg=>"cartridge_do_action failed 1. Output Failed to execute: 'control update-cluster' for /var/lib/openshift/526a372803ef6497f100007f/haproxy\n\n/opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `initialize': Permission denied - /var/lib/openshift/526a372803ef6497f100007f/jbossews/metadata/manifest.yml (Errno::EACCES)\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `open'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `safe_load_file'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-common-1.16.2/lib/openshift-origin-common/models/manifest.rb:217:in `initialize'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:168:in `new'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:168:in `get_cartridge_from_directory'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:94:in `primary_cartridge'\n\tfrom /usr/lib/openshift/cartridge_sdk/ruby/sdk.rb:26:in `primary_cartridge'\n\tfrom /usr/lib/openshift/cartridge_sdk/ruby/sdk.rb:30:in `primary_cartridge_manifest'\n\tfrom -e:1:in `<main>'\n", :data=>{:time=>nil, :output=>"Failed to execute: 'control update-cluster' for /var/lib/openshift/526a372803ef6497f100007f/haproxy\n\n/opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `initialize': Permission denied - /var/lib/openshift/526a372803ef6497f100007f/jbossews/metadata/manifest.yml (Errno::EACCES)\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `open'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `safe_load_file'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-common-1.16.2/lib/openshift-origin-common/models/manifest.rb:217:in `initialize'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:168:in `new'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:168:in `get_cartridge_from_directory'\n\tfrom /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:94:in `primary_cartridge'\n\tfrom /usr/lib/openshift/cartridge_sdk/ruby/sdk.rb:26:in `primary_cartridge'\n\tfrom /usr/lib/openshift/cartridge_sdk/ruby/sdk.rb:30:in `primary_cartridge_manifest'\n\tfrom -e:1:in `<main>'\n", :exitcode=>1, :addtl_params=>nil}}>] (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: MCollective Response Time (execute_direct: update-cluster): 3.991620897s  (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: server results: Failed to execute: 'control update-cluster' for /var/lib/openshift/526a372803ef6497f100007f/haproxy (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: server results: (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: server results: /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `initialize': Permission denied - /var/lib/openshift/526a372803ef6497f100007f/jbossews/metadata/manifest.yml (Errno::EACCES) (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: server results: 	from /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `open' (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: server results: 	from /opt/rh/ruby193/root/usr/share/gems/gems/safe_yaml-0.9.1/lib/safe_yaml.rb:181:in `safe_load_file' (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: server results: 	from /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-common-1.16.2/lib/openshift-origin-common/models/manifest.rb:217:in `initialize' (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: server results: 	from /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:168:in `new' (pid:17909)
>2013-10-25 05:18:40.561 [DEBUG] DEBUG: server results: 	from /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:168:in `get_cartridge_from_directory' (pid:17909)
>2013-10-25 05:18:40.562 [DEBUG] DEBUG: server results: 	from /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:94:in `primary_cartridge' (pid:17909)
>2013-10-25 05:18:40.562 [DEBUG] DEBUG: server results: 	from /usr/lib/openshift/cartridge_sdk/ruby/sdk.rb:26:in `primary_cartridge' (pid:17909)
>2013-10-25 05:18:40.562 [DEBUG] DEBUG: server results: 	from /usr/lib/openshift/cartridge_sdk/ruby/sdk.rb:30:in `primary_cartridge_manifest' (pid:17909)
>2013-10-25 05:18:40.562 [DEBUG] DEBUG: server results: 	from -e:1:in `<main>' (pid:17909)
>2013-10-25 05:18:40.562 [ERROR] Node execution failure (invalid exit code from node). (pid:17909)
>2013-10-25 05:18:40.563 [ERROR] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.16.2/lib/openshift/mcollective_application_container_proxy.rb:2718:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.16.2/lib/openshift/mcollective_application_container_proxy.rb:1738:in `update_cluster'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:518:in `block in update_cluster_with_trace_Custom_OpenShift_MCollectiveApplicationContainerProxy_update_cluster'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:268:in `trace_execution_scoped'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:513:in `update_cluster_with_trace_Custom_OpenShift_MCollectiveApplicationContainerProxy_update_cluster'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/gear.rb:267:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:1255: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.16.4/app/models/pending_ops/update_cluster_op.rb:4:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/pending_app_op_group.rb:75:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/pending_app_op_group.rb:64:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/pending_app_op_group.rb:64:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:1538:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:660:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:1584:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:653:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:251:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/controllers/applications_controller.rb:147:in `create'", "/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:481:in `block in _run__1936774781886859751__process_action__90401671728794907__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_418'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/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_418'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:403:in `_run__1936774781886859751__process_action__90401671728794907__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/newrelic_rpm-3.6.6.147/lib/new_relic/agent/instrumentation/rails3/action_controller.rb:38:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/instrumentation/controller_instrumentation.rb:318:in `perform_action_with_newrelic_trace'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/instrumentation/rails3/action_controller.rb:37: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/newrelic_rpm-3.6.6.147/lib/new_relic/rack/error_collector.rb:43:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/rack/agent_hooks.rb:22:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/rack/browser_monitoring.rb:16: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__4244122412704982935__call__3184952806216346838__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:17909)
>2013-10-25 05:18:40.563 [ERROR] Node execution failure (invalid exit code from node). (pid:17909)
>2013-10-25 05:18:40.563 [DEBUG] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.16.2/lib/openshift/mcollective_application_container_proxy.rb:2718:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.16.2/lib/openshift/mcollective_application_container_proxy.rb:1738:in `update_cluster'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:518:in `block in update_cluster_with_trace_Custom_OpenShift_MCollectiveApplicationContainerProxy_update_cluster'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:268:in `trace_execution_scoped'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:513:in `update_cluster_with_trace_Custom_OpenShift_MCollectiveApplicationContainerProxy_update_cluster'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/gear.rb:267:in `method_missing'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:1255: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.16.4/app/models/pending_ops/update_cluster_op.rb:4:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/pending_app_op_group.rb:75:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/pending_app_op_group.rb:64:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/pending_app_op_group.rb:64:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:1538:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:660:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:1584:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:653:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/models/application.rb:251:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/app/controllers/applications_controller.rb:147:in `create'", "/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:481:in `block in _run__1936774781886859751__process_action__90401671728794907__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_418'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.16.4/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_418'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:403:in `_run__1936774781886859751__process_action__90401671728794907__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/newrelic_rpm-3.6.6.147/lib/new_relic/agent/instrumentation/rails3/action_controller.rb:38:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/instrumentation/controller_instrumentation.rb:318:in `perform_action_with_newrelic_trace'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/instrumentation/rails3/action_controller.rb:37: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/newrelic_rpm-3.6.6.147/lib/new_relic/rack/error_collector.rb:43:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/rack/agent_hooks.rb:22:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/rack/browser_monitoring.rb:16: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__4244122412704982935__call__3184952806216346838__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:17909)
>2013-10-25 05:18:40.748 [DEBUG] Rollback UpdateClusterOp (pid:17909)
>2013-10-25 05:18:40.752 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"update-cluster", :args=>{"--with-app-uuid"=>"526a372803ef6497f100007f", "--with-app-name"=>"app1", "--with-container-uuid"=>"526a372803ef6497f100007f", "--with-container-name"=>"app1", "--with-namespace"=>"imp1lv", "--with-uid"=>6453, "--with-request-id"=>"a049a9f5417724b51786152344781089", "--rollback"=>true, "--cart-name"=>"openshift-origin-node"}}, ex-std-node1.int.rhcloud.com, {'identity' => ex-std-node1.int.rhcloud.com}) (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:41.549 [DEBUG] Response from Streamline took (/wapps/streamline/login.html): 512.420365 ms (pid:13479)
>2013-10-25 05:18:41.551 [DEBUG] POST Response code = 200 (pid:13479)
>2013-10-25 05:18:41.552 [DEBUG] Current login = jizhao+2@redhat.com / authenticated for jizhao+2@redhat.com (pid:13479)
>2013-10-25 05:18:41.603 [INFO ] Started GET "/broker/rest/domains" for 203.114.244.88 at 2013-10-25 05:18:41 -0400 (pid:13657)
>2013-10-25 05:18:41.622 [INFO ] Processing by DomainsController#index as JSON (pid:13657)
>2013-10-25 05:18:41.625 [DEBUG] API version 1.5 (pid:13657)
>2013-10-25 05:18:41.108 [DEBUG] Executing StopFeatureOpGroup: #<StopFeatureOpGroup _id: 526a377103ef642f040004f4, _type: "StopFeatureOpGroup", created_at: 2013-10-25 09:18:41 UTC, updated_at: 2013-10-25 09:18:41 UTC, parent_op_id: nil, num_gears_added: 0, num_gears_removed: 0, num_gears_created: 0, num_gears_destroyed: 0, num_gears_rolled_back: 0, user_agent: nil, feature: "postgresql-8.4", force: false> (pid:13479)
>2013-10-25 05:18:41.122 [DEBUG] Execute StopCompOp (pid:13479)
>2013-10-25 05:18:41.143 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007802058 @agent="openshift", @action="cartridge_do", @results={:sender=>"ex-std-node1.int.rhcloud.com", :statuscode=>1, :statusmsg=>"cartridge_do_action failed 1. Output undefined method `each_value' for nil:NilClass", :data=>{:time=>nil, :output=>"undefined method `each_value' for nil:NilClass", :exitcode=>1, :addtl_params=>nil}}>] (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:41.144 [DEBUG] DEBUG: MCollective Response Time (execute_direct: update-cluster): 0.393195234s  (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>--
>2013-10-25 05:18:41.287 [DEBUG] Rollback AddBrokerAuthKeyOp (pid:17909)
>2013-10-25 05:18:41.287 [DEBUG] Parallel rollback not implemented: AddBrokerAuthKeyOp (pid:17909)
>2013-10-25 05:18:41.287 [DEBUG] Rollback ExposePortOp (pid:17909)
>2013-10-25 05:18:41.287 [DEBUG] Parallel rollback not implemented: ExposePortOp (pid:17909)
>2013-10-25 05:18:41.287 [DEBUG] Rollback ExposePortOp (pid:17909)
>2013-10-25 05:18:41.287 [DEBUG] Parallel rollback not implemented: ExposePortOp (pid:17909)
>2013-10-25 05:18:41.380 [DEBUG] Rollback PostConfigureCompOp (pid:17909)
>2013-10-25 05:18:41.380 [DEBUG] Rollback not implemented: PostConfigureCompOp (pid:17909)
>2013-10-25 05:18:41.568 [DEBUG] Rollback AddCompOp (pid:17909)
>2013-10-25 05:18:41.570 [DEBUG] DEBUG: Deconfiguring cartridge 'haproxy-1.4' in application 'app1' on node 'ex-std-node1.int.rhcloud.com' (pid:17909)
>2013-10-25 05:18:41.570 [DEBUG] DEBUG: Sending to Nurture:application: app_uuid='526a372803ef6497f100007f' action='deconfigure' (pid:17909)
>2013-10-25 05:18:41.614 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"haproxy-1.4", :action=>"deconfigure", :args=>{"--with-app-uuid"=>"526a372803ef6497f100007f", "--with-app-name"=>"app1", "--with-container-uuid"=>"526a372803ef6497f100007f", "--with-container-name"=>"app1", "--with-namespace"=>"imp1lv", "--with-uid"=>6453, "--with-request-id"=>"a049a9f5417724b51786152344781089", "--cart-name"=>"haproxy-1.4", "--component-name"=>"web_proxy", "--with-software-version"=>"1.4", "--cartridge-vendor"=>"redhat"}}, ex-std-node1.int.rhcloud.com, {'identity' => ex-std-node1.int.rhcloud.com}) (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:42.625 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"", :gear=>"526a34fb6cec0e48070003c4", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"526a34fb6cec0e48070003c4", "--with-app-name"=>"zwzrrdvd", "--with-container-uuid"=>"526a34fb6cec0e48070003c4", "--with-container-name"=>"zwzrrdvd", "--with-namespace"=>"smpcjh", "--with-uid"=>1174, "--with-request-id"=>"517c7fa6af804ad7f2a50a0d3c73d01b", "--cart-name"=>"php-5.3", "--component-name"=>"php-5.3", "--with-software-version"=>"5.3", "--cartridge-vendor"=>"redhat", "--hook-name"=>"set-env", "--publishing-cart-name"=>"postgresql-9.2", "--connection-type"=>"ENV:NET_TCP:db:connection-info", "--input-args"=>["zwzrrdvd", "smpcjh", "526a34fb6cec0e48070003c4", {"526a356a6cec0ecb9f00002f"=>"OPENSHIFT_POSTGRESQL_DB_GEAR_UUID=526a356a6cec0ecb9f00002f\nOPENSHIFT_POSTGRESQL_DB_GEAR_DNS=526a356a6cec0ecb9f00002f-smpcjh.int.rhcloud.com\nOPENSHIFT_POSTGRESQL_DB_USERNAME=admincryj4ct\nOPENSHIFT_POSTGRESQL_DB_PASSWORD=w2Ybu8GPjPWb\nOPENSHIFT_POSTGRESQL_DB_HOST=526a356a6cec0ecb9f00002f-smpcjh.int.rhcloud.com\nOPENSHIFT_POSTGRESQL_DB_PORT=36581\nOPENSHIFT_POSTGRESQL_DB_URL=postgresql://admincryj4ct:w2Ybu8GPjPWb@526a356a6cec0ecb9f00002f-smpcjh.int.rhcloud.com:36581/\nPGHOST=\nPGPORT=36581\nPGUSER=admincryj4ct\nPGPASSWORD=w2Ybu8GPjPWb\nPGDATABASE=zwzrrdvd\nOPENSHIFT_POSTGRESQL_VERSION=9.2\n"}]}}, :result_stdout=>"Set environment variables successfully", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>"", :gear=>"526a34fb6cec0e48070003c4", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--with-app-uuid"=>"526a34fb6cec0e48070003c4", "--with-app-name"=>"zwzrrdvd", "--with-container-uuid"=>"526a34fb6cec0e48070003c4", "--with-container-name"=>"zwzrrdvd", "--with-namespace"=>"smpcjh", "--with-uid"=>1174, "--with-request-id"=>"517c7fa6af804ad7f2a50a0d3c73d01b", "--cart-name"=>"haproxy-1.4", "--component-name"=>"web_proxy", "--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"=>"zwzrrdvd smpcjh 526a34fb6cec0e48070003c4 \\'526a34fb6cec0e48070003c4\\'\\=\\'http://zwzrrdvd-smpcjh.int.rhcloud.com/haproxy-status/'\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ex-std-node3.int.rhcloud.com  (Request ID: 517c7fa6af804ad7f2a50a0d3c73d01b) (pid:26137)
>2013-10-25 05:18:42.626 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 3.115336529s  (Request ID: 517c7fa6af804ad7f2a50a0d3c73d01b) (pid:26137)
>2013-10-25 05:18:42.626 [DEBUG] Connections done (pid:26137)
>2013-10-25 05:18:42.661 [DEBUG] Executing UpdateAppConfigOpGroup: #<UpdateAppConfigOpGroup _id: 526a375903ef640916000409, _type: "UpdateAppConfigOpGroup", created_at: 2013-10-25 09:18:17 UTC, updated_at: 2013-10-25 09:18:17 UTC, parent_op_id: nil, num_gears_added: 0, num_gears_removed: 0, num_gears_created: 0, num_gears_destroyed: 0, num_gears_rolled_back: 0, user_agent: nil, add_keys_attrs: [], remove_keys_attrs: [{"_id"=>"526a366803ef64c70c0001e0", "_type"=>"ApplicationSshKey", "component_id"=>"526a360603ef64c70c0001c7", "content"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAqSHRWpwpw1L3o/+YwL7pAECs4TR+STiyF2EEO9mRoZMB+m+jcyfSajz8UU7um5Dwmuhsw+z9mZojHTRm+OCvcM76zDWfCD2raUOUmnBw20wf28lJbzb7U6WBJ83ZbQgGjppKPP+GWQQz+lFyZco70wPdY95n3SefETWHlanhvVQnBdv7CJ5j7CZdgiyjYzZ/gdZ3bO3L9ubLsSHJITcNc9MzN8cd0jk+6yN1m3xP4fDu1fllPGDX0VTL04xMorULsRtZpYPFBrdQIAY4/qWJUdmidVT79E1KW8+vXeYWH8O8qKDw7wyqDKF3sXZRUlLwjlo0plSylFYROeiLfI8xqw==", "name"=>"application-526a360603ef64c70c0001c7", "type"=>"ssh-rsa"}], add_env_vars: [], remove_env_vars: [], config: {}> (pid:26137)
>2013-10-25 05:18:42.673 [DEBUG] Execute UpdateAppConfigOp (pid:26137)
>2013-10-25 05:18:42.692 [DEBUG] Execute UpdateAppConfigOp (pid:26137)
>2013-10-25 05:18:42.804 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000080561f0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ex-std-node1.int.rhcloud.com", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"APP_SSH_KEY_ADD: 526a376903ef64c70c000208 AAAAB3NzaC1yc2EAAAABIwAAAQEAtI4RHzSrsOr4BLryKVUv8Sw2/CrWFkLhMEsrhc8p7BsHPMdmVRM4DfTUrtVnOohPrwO0DPAwxMdR5wjrJ84lWXwBMILOnasRdp+XsjCZoXO6SGMF3dvy+56eIKgb1aU0fkSL61y9IHMeNU3PJBTIb/cptqHR260gp+nYHsoy0o4VJqnv2651fAvHBIVcJwjzwK7irTXc6OE+Ys92SlBP4Azj2IjUIFTIUpQdHun8vf0vOtywfEXKLBO2YV1fPvhuf+xOFXvVm7/GAn9Ps1aM7h8qUn3z6+vwDrTXz/G/W0ixLnDvVX7t5P70af8Ml9MICREfVL8uUvutbF1qGulVsQ== 526a376903ef64c70c000208@ex-std-node1.int.rhcloud.com\n\n", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 273e72b7b42a780c3fa643fd419d018c) (pid:2384)
>2013-10-25 05:18:42.805 [DEBUG] DEBUG: MCollective Response Time (execute_direct: app-create): 6.424966295s  (Request ID: 273e72b7b42a780c3fa643fd419d018c) (pid:2384)
>2013-10-25 05:18:42.988 [DEBUG] Execute TrackUsageOp (pid:2384)
>2013-10-25 05:18:43.124 [DEBUG] Execute RegisterDnsOp (pid:2384)
>--
>2013-10-25 05:18:44.154 [DEBUG] DYNECT Response: {"status": "success", "data": {"token": "/wljcWPY/qYUi+ORjr3j9J7jJ8VlJPo0OdMX60F1msIH3Z80L7FnOnEIxuvSBpRG4RV7u7Q4PdSPvRqzp1uG+Z+INePg0Kwy95yYXinqQp4iNbJGUD80DHBCAMcxid3iMxvIVhwdOnvg+u9HRdVtrQzem0hebSAZ", "version": "3.5.3"}, "job_id": 537093368, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:14861)
>2013-10-25 05:18:44.155 [DEBUG] DYNECT Response data: {"token"=>"/wljcWPY/qYUi+ORjr3j9J7jJ8VlJPo0OdMX60F1msIH3Z80L7FnOnEIxuvSBpRG4RV7u7Q4PdSPvRqzp1uG+Z+INePg0Kwy95yYXinqQp4iNbJGUD80DHBCAMcxid3iMxvIVhwdOnvg+u9HRdVtrQzem0hebSAZ", "version"=>"3.5.3"} (pid:14861)
>2013-10-25 05:18:44.155 [DEBUG] Dynect Response Time (dyn_login): 0.626144606s  (Request ID: dfe4a9a1d6cb656d224219a056f56cbf) (pid:14861)
>2013-10-25 05:18:44.277 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007f56818 @agent="openshift", @action="cartridge_do", @results={:sender=>"ex-std-node1.int.rhcloud.com", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:44.278 [DEBUG] DEBUG: MCollective Response Time (execute_direct: deconfigure): 2.66880978s  (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:44.317 [DEBUG] Public ip being configured 'ex-std-node3.int.rhcloud.com' to app '526a376803ef6402a4000188' (pid:14861)
>2013-10-25 05:18:44.318 [DEBUG] DYNECT put/post with path: /REST/CNAMERecord/int.rhcloud.com/526a376803ef6402a4000188-epzyzd.int.rhcloud.com/ json data: {"rdata":{"cname":"ex-std-node3.int.rhcloud.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:14861)
>2013-10-25 05:18:44.350 [DEBUG] Rollback NewCompOp (pid:17909)
>2013-10-25 05:18:44.411 [DEBUG] Rollback AddCompOp (pid:17909)
>2013-10-25 05:18:44.413 [DEBUG] DEBUG: Deconfiguring cartridge 'jbossews-2.0' in application 'app1' on node 'ex-std-node1.int.rhcloud.com' (pid:17909)
>2013-10-25 05:18:44.414 [DEBUG] DEBUG: Sending to Nurture:application: app_uuid='526a372803ef6497f100007f' action='deconfigure' (pid:17909)
>2013-10-25 05:18:44.472 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone": "int.rhcloud.com", "ttl": 60, "fqdn": "526a376903ef64c70c000208-tr0iyp.int.rhcloud.com", "record_type": "CNAME", "rdata": {"cname": "ex-std-node1.int.rhcloud.com."}, "record_id": 0}, "job_id": 537093375, "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:2384)
>2013-10-25 05:18:44.472 [DEBUG] DYNECT Response data: {"zone"=>"int.rhcloud.com", "ttl"=>60, "fqdn"=>"526a376903ef64c70c000208-tr0iyp.int.rhcloud.com", "record_type"=>"CNAME", "rdata"=>{"cname"=>"ex-std-node1.int.rhcloud.com."}, "record_id"=>0} (pid:2384)
>2013-10-25 05:18:44.472 [DEBUG] Dynect Response Time (dyn_put_post): 0.725787801s  (Request ID: 273e72b7b42a780c3fa643fd419d018c) (pid:2384)
>2013-10-25 05:18:44.472 [DEBUG] DYNECT put/post with path: /REST/Zone/int.rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2384)
>2013-10-25 05:18:44.476 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"jbossews-2.0", :action=>"deconfigure", :args=>{"--with-app-uuid"=>"526a372803ef6497f100007f", "--with-app-name"=>"app1", "--with-container-uuid"=>"526a372803ef6497f100007f", "--with-container-name"=>"app1", "--with-namespace"=>"imp1lv", "--with-uid"=>6453, "--with-request-id"=>"a049a9f5417724b51786152344781089", "--cart-name"=>"jbossews-2.0", "--component-name"=>"jbossews-2.0", "--with-software-version"=>"2.0", "--cartridge-vendor"=>"redhat"}}, ex-std-node1.int.rhcloud.com, {'identity' => ex-std-node1.int.rhcloud.com}) (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:44.972 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone": "int.rhcloud.com", "ttl": 60, "fqdn": "526a376803ef6402a4000188-epzyzd.int.rhcloud.com", "record_type": "CNAME", "rdata": {"cname": "ex-std-node3.int.rhcloud.com."}, "record_id": 0}, "job_id": 537093387, "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:14861)
>2013-10-25 05:18:44.972 [DEBUG] DYNECT Response data: {"zone"=>"int.rhcloud.com", "ttl"=>60, "fqdn"=>"526a376803ef6402a4000188-epzyzd.int.rhcloud.com", "record_type"=>"CNAME", "rdata"=>{"cname"=>"ex-std-node3.int.rhcloud.com."}, "record_id"=>0} (pid:14861)
>2013-10-25 05:18:44.972 [DEBUG] Dynect Response Time (dyn_put_post): 0.654701843s  (Request ID: dfe4a9a1d6cb656d224219a056f56cbf) (pid:14861)
>2013-10-25 05:18:44.973 [DEBUG] DYNECT put/post with path: /REST/Zone/int.rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:14861)
>2013-10-25 05:18:45.216 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "day", "serial": 2013220281, "zone": "int.rhcloud.com"}, "job_id": 537093392, "msgs": [{"INFO": "publish: int.rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2384)
>2013-10-25 05:18:45.217 [DEBUG] DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"day", "serial"=>2013220281, "zone"=>"int.rhcloud.com"} (pid:2384)
>2013-10-25 05:18:45.217 [DEBUG] Dynect Response Time (dyn_put_post): 0.744405807s  (Request ID: 273e72b7b42a780c3fa643fd419d018c) (pid:2384)
>2013-10-25 05:18:45.217 [DEBUG] DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:2384)
>2013-10-25 05:18:45.646 [DEBUG] DYNECT Response: {"status": "success", "data": {}, "job_id": 537093399, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:2384)
>2013-10-25 05:18:45.650 [DEBUG] DYNECT Response data: {} (pid:2384)
>--
>2013-10-25 05:18:49.162 [INFO ] Completed 200 OK in 31ms (Views: 12.0ms) (pid:26137)
>2013-10-25 05:18:49.317 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "day", "serial": 2013220283, "zone": "int.rhcloud.com"}, "job_id": 537093435, "msgs": [{"INFO": "publish: int.rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:17909)
>2013-10-25 05:18:49.318 [DEBUG] DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"day", "serial"=>2013220283, "zone"=>"int.rhcloud.com"} (pid:17909)
>2013-10-25 05:18:49.318 [DEBUG] Dynect Response Time (dyn_put_post): 0.689349915s  (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:49.318 [DEBUG] DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:17909)
>2013-10-25 05:18:49.751 [DEBUG] DYNECT Response: {"status": "success", "data": {}, "job_id": 537093442, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:17909)
>2013-10-25 05:18:49.751 [DEBUG] DYNECT Response data: {} (pid:17909)
>2013-10-25 05:18:49.751 [DEBUG] Dynect Response Time (dyn_delete): 0.433122311s  (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:49.762 [DEBUG] Rollback NewCompOp (pid:17909)
>2013-10-25 05:18:49.811 [DEBUG] Rollback CreateGearOp (pid:17909)
>2013-10-25 05:18:49.814 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"526a372803ef6497f100007f", "--with-app-name"=>"app1", "--with-container-uuid"=>"526a372803ef6497f100007f", "--with-container-name"=>"app1", "--with-namespace"=>"imp1lv", "--with-uid"=>6453, "--with-request-id"=>"a049a9f5417724b51786152344781089", "--cart-name"=>"openshift-origin-node"}}, ex-std-node1.int.rhcloud.com, {'identity' => ex-std-node1.int.rhcloud.com}) (Request ID: a049a9f5417724b51786152344781089) (pid:17909)
>2013-10-25 05:18:51.627 [INFO ] Started POST "/broker/rest/user/authorizations" for 203.114.244.88 at 2013-10-25 05:18:51 -0400 (pid:26137)
>2013-10-25 05:18:51.631 [INFO ] Processing by AuthorizationsController#create as JSON (pid:26137)
>2013-10-25 05:18:51.631 [INFO ] Parameters: {"scope"=>"session", "note"=>"RHC/1.16.3 (from localhost.localdomain on x86_64-linux)", "reuse"=>true, "authorization"=>{"note"=>"RHC/1.16.3 (from localhost.localdomain on x86_64-linux)"}} (pid:26137)
>2013-10-25 05:18:51.631 [DEBUG] API version 1.5 (pid:26137)
>2013-10-25 05:18:52.114 [DEBUG] Response from Streamline took (/wapps/streamline/login.html): 481.81787599999996 ms (pid:26137)
>2013-10-25 05:18:52.114 [DEBUG] POST Response code = 200 (pid:26137)
>2013-10-25 05:18:52.114 [DEBUG] Current login = wsun+2@redhat.com / authenticated for wsun+2@redhat.com (pid:26137)
>2013-10-25 05:18:52.128 [INFO ] Completed 201 Created in 497ms (Views: 1.4ms) (pid:26137)
>2013-10-25 05:18:52.477 [INFO ] Started GET "/broker/rest/api" for 209.132.181.86 at 2013-10-25 05:18:52 -0400 (pid:26137)
>2013-10-25 05:18:52.479 [INFO ] Processing by ApiController#show as JSON (pid:26137)

Actions: View

Attachments on bug 1023520: 915793 | 915794 | 915795