Attachment 668328 Details for Bug 889949 – development log on fork_ami_refctr1_372

development log on fork_ami_refctr1_372

development_1224.log (text/x-log), 74.07 KB, created by joycezhang on 2012-12-24 06:18:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: joycezhang

Created: 2012-12-24 06:18:02 UTC

Size: 74.07 KB

patch

obsolete

>2012-12-24 00:37:46.700 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"", :gear=>"50d7ea0049629bf8730003aa", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7ea0049629bf8730003aa", "--cart-name"=>"haproxy-1.4", "--hook-name"=>"set-proxy", "--input-args"=>"phpscaletest wsunsunn 50d7ea0049629bf8730003aa \\'50d7ea0049629bf8730003aa\\'\\=\\'phpscaletest-wsunsunn.dev.rhcloud.com\\|10.46.183.31:35591'\n'\\'"}}, :result_stdout=>"Mon Dec 24 00:37:34 EST 2012: Conditionally reloading HAProxy service \n", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7ea0049629bf8730003aa", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7ea0049629bf8730003aa", "--cart-name"=>"haproxy-1.4", "--hook-name"=>"set-gear-endpoints", "--input-args"=>"phpscaletest wsunsunn 50d7ea0049629bf8730003aa \\'50d7ea0049629bf8730003aa\\'\\=\\'50d7ea0049629bf8730003aa@10.46.183.31:php-5.3\\;phpscaletest-wsunsunn.dev.rhcloud.com'\n'\\'"}}, :result_stdout=>"Mon Dec 24 00:37:35 EST 2012: Conditionally reloading HAProxy service \nERROR: there is already one or more instance(s) of the program running\n", :result_stderr=>"", :result_exit_code=>0}], exitcode: 0, from: ip-10-46-183-31 (pid:29688)
>2012-12-24 00:49:42.135 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"", :gear=>"50d7d52c49629bf873000184", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7d52c49629bf873000184", "--cart-name"=>"perl-5.10", "--hook-name"=>"set-db-connection-info", "--input-args"=>"ccypl1 dd 50d7d52c49629bf873000184 \\'50d7ecd649629bf873000411\\'\\=\\'OPENSHIFT_MYSQL_DB_GEAR_UUID\\=50d7ecd649629bf873000411\\;\\ OPENSHIFT_MYSQL_DB_GEAR_DNS\\=50d7ecd649-dd.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_USERNAME\\=admin\\;\\ OPENSHIFT_MYSQL_DB_PASSWORD\\=hEgTYJ87-Yuc\\;\\ OPENSHIFT_MYSQL_DB_HOST\\=50d7ecd649-dd.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_PORT\\=35626\\;\\ OPENSHIFT_MYSQL_DB_URL\\=mysql://admin:hEgTYJ87-Yuc@50d7ecd649-dd.dev.rhcloud.com:35626/\\;\\ '\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7d52c49629bf873000184", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7d52c49629bf873000184", "--cart-name"=>"haproxy-1.4", "--hook-name"=>"set-proxy", "--input-args"=>"ccypl1 dd 50d7d52c49629bf873000184 \\'50d7d52c49629bf873000184\\'\\=\\'ccypl1-dd.dev.rhcloud.com\\|10.46.183.31:35596'\n'\\'"}}, :result_stdout=>"Mon Dec 24 00:49:32 EST 2012: Conditionally reloading HAProxy service \nERROR: there is already one or more instance(s) of the program running\n", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7d52c49629bf873000184", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7d52c49629bf873000184", "--cart-name"=>"haproxy-1.4", "--hook-name"=>"set-gear-endpoints", "--input-args"=>"ccypl1 dd 50d7d52c49629bf873000184 \\'50d7d52c49629bf873000184\\'\\=\\'50d7d52c49629bf873000184@10.46.183.31:perl-5.10\\;ccypl1-dd.dev.rhcloud.com'\n'\\'"}}, :result_stdout=>"Mon Dec 24 00:49:31 EST 2012: Conditionally reloading HAProxy service \n", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7d52c49629bf873000184", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7d52c49629bf873000184", "--cart-name"=>"haproxy-1.4", "--hook-name"=>"set-db-connection-info", "--input-args"=>"ccypl1 dd 50d7d52c49629bf873000184 \\'50d7ecd649629bf873000411\\'\\=\\'OPENSHIFT_MYSQL_DB_GEAR_UUID\\=50d7ecd649629bf873000411\\;\\ OPENSHIFT_MYSQL_DB_GEAR_DNS\\=50d7ecd649-dd.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_USERNAME\\=admin\\;\\ OPENSHIFT_MYSQL_DB_PASSWORD\\=hEgTYJ87-Yuc\\;\\ OPENSHIFT_MYSQL_DB_HOST\\=50d7ecd649-dd.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_PORT\\=35626\\;\\ OPENSHIFT_MYSQL_DB_URL\\=mysql://admin:hEgTYJ87-Yuc@50d7ecd649-dd.dev.rhcloud.com:35626/\\;\\ '\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0}], exitcode: 0, from: ip-10-46-183-31 (pid:29688)
>2012-12-24 00:50:37.125 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>"", :gear=>"50d7e72549629bf87300030b", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7e72549629bf87300030b", "--cart-name"=>"php-5.3", "--hook-name"=>"set-db-connection-info", "--input-args"=>"php1s fk372t1 50d7e72549629bf87300030b \\'50d7ed0a49629bf87300042d\\'\\=\\'OPENSHIFT_MYSQL_DB_GEAR_UUID\\=50d7ed0a49629bf87300042d\\;\\ OPENSHIFT_MYSQL_DB_GEAR_DNS\\=50d7ed0a49-fk372t1.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_USERNAME\\=admin\\;\\ OPENSHIFT_MYSQL_DB_PASSWORD\\=4ybwDVXVaGcD\\;\\ OPENSHIFT_MYSQL_DB_HOST\\=50d7ed0a49-fk372t1.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_PORT\\=35546\\;\\ OPENSHIFT_MYSQL_DB_URL\\=mysql://admin:4ybwDVXVaGcD@50d7ed0a49-fk372t1.dev.rhcloud.com:35546/\\;\\ '\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7e84249629bf873000349", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7e84249629bf873000349", "--cart-name"=>"php-5.3", "--hook-name"=>"set-db-connection-info", "--input-args"=>"50d7e84249 fk372t1 50d7e84249629bf873000349 \\'50d7ed0a49629bf87300042d\\'\\=\\'OPENSHIFT_MYSQL_DB_GEAR_UUID\\=50d7ed0a49629bf87300042d\\;\\ OPENSHIFT_MYSQL_DB_GEAR_DNS\\=50d7ed0a49-fk372t1.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_USERNAME\\=admin\\;\\ OPENSHIFT_MYSQL_DB_PASSWORD\\=4ybwDVXVaGcD\\;\\ OPENSHIFT_MYSQL_DB_HOST\\=50d7ed0a49-fk372t1.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_PORT\\=35546\\;\\ OPENSHIFT_MYSQL_DB_URL\\=mysql://admin:4ybwDVXVaGcD@50d7ed0a49-fk372t1.dev.rhcloud.com:35546/\\;\\ '\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7e9aa49629bf873000398", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7e9aa49629bf873000398", "--cart-name"=>"php-5.3", "--hook-name"=>"set-db-connection-info", "--input-args"=>"50d7e9aa49 fk372t1 50d7e9aa49629bf873000398 \\'50d7ed0a49629bf87300042d\\'\\=\\'OPENSHIFT_MYSQL_DB_GEAR_UUID\\=50d7ed0a49629bf87300042d\\;\\ OPENSHIFT_MYSQL_DB_GEAR_DNS\\=50d7ed0a49-fk372t1.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_USERNAME\\=admin\\;\\ OPENSHIFT_MYSQL_DB_PASSWORD\\=4ybwDVXVaGcD\\;\\ OPENSHIFT_MYSQL_DB_HOST\\=50d7ed0a49-fk372t1.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_PORT\\=35546\\;\\ OPENSHIFT_MYSQL_DB_URL\\=mysql://admin:4ybwDVXVaGcD@50d7ed0a49-fk372t1.dev.rhcloud.com:35546/\\;\\ '\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7e72549629bf87300030b", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7e72549629bf87300030b", "--cart-name"=>"haproxy-1.4", "--hook-name"=>"set-proxy", "--input-args"=>"php1s fk372t1 50d7e72549629bf87300030b \\'50d7e72549629bf87300030b\\'\\=\\'php1s-fk372t1.dev.rhcloud.com\\|10.46.183.31:35601'\n'\\'\\ \\'50d7e84249629bf873000349\\'\\=\\'50d7e84249-fk372t1.dev.rhcloud.com\\|10.46.183.31:35606'\n'\\'\\ \\'50d7e9aa49629bf873000398\\'\\=\\'50d7e9aa49-fk372t1.dev.rhcloud.com\\|10.46.183.31:35586'\n'\\'"}}, :result_stdout=>"Mon Dec 24 00:50:28 EST 2012: Conditionally reloading HAProxy service \nERROR: there is already one or more instance(s) of the program running\n", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7e72549629bf87300030b", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7e72549629bf87300030b", "--cart-name"=>"haproxy-1.4", "--hook-name"=>"set-gear-endpoints", "--input-args"=>"php1s fk372t1 50d7e72549629bf87300030b \\'50d7e72549629bf87300030b\\'\\=\\'50d7e72549629bf87300030b@10.46.183.31:php-5.3\\;php1s-fk372t1.dev.rhcloud.com'\n'\\'\\ \\'50d7e84249629bf873000349\\'\\=\\'50d7e84249629bf873000349@10.46.183.31:php-5.3\\;50d7e84249-fk372t1.dev.rhcloud.com'\n'\\'\\ \\'50d7e9aa49629bf873000398\\'\\=\\'50d7e9aa49629bf873000398@10.46.183.31:php-5.3\\;50d7e9aa49-fk372t1.dev.rhcloud.com'\n'\\'"}}, :result_stdout=>"Mon Dec 24 00:50:27 EST 2012: Conditionally reloading HAProxy service \n", :result_stderr=>"", :result_exit_code=>0}, {:tag=>"", :gear=>"50d7e72549629bf87300030b", :job=>{:cartridge=>"openshift-origin-node", :action=>"connector-execute", :args=>{"--gear-uuid"=>"50d7e72549629bf87300030b", "--cart-name"=>"haproxy-1.4", "--hook-name"=>"set-db-connection-info", "--input-args"=>"php1s fk372t1 50d7e72549629bf87300030b \\'50d7ed0a49629bf87300042d\\'\\=\\'OPENSHIFT_MYSQL_DB_GEAR_UUID\\=50d7ed0a49629bf87300042d\\;\\ OPENSHIFT_MYSQL_DB_GEAR_DNS\\=50d7ed0a49-fk372t1.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_USERNAME\\=admin\\;\\ OPENSHIFT_MYSQL_DB_PASSWORD\\=4ybwDVXVaGcD\\;\\ OPENSHIFT_MYSQL_DB_HOST\\=50d7ed0a49-fk372t1.dev.rhcloud.com\\;\\ OPENSHIFT_MYSQL_DB_PORT\\=35546\\;\\ OPENSHIFT_MYSQL_DB_URL\\=mysql://admin:4ybwDVXVaGcD@50d7ed0a49-fk372t1.dev.rhcloud.com:35546/\\;\\ '\n'\\'"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0}], exitcode: 0, from: ip-10-46-183-31 (pid:29688)
>2012-12-24 00:51:33.434 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000038a2de0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-46-183-31", :statuscode=>1, :statusmsg=>"cartridge_do_action failed 129. Output ERROR: unable to create user account(9): useradd -u 520 -d /var/lib/openshift/50d7ed5f49629bf873000457/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 50d7ed5f49629bf873000457 stdout: useradd: user '50d7ed5f49629bf873000457' already exists\n stderr: useradd: user '50d7ed5f49629bf873000457' already exists\n", :data=>{:time=>nil, :output=>"ERROR: unable to create user account(9): useradd -u 520 -d /var/lib/openshift/50d7ed5f49629bf873000457/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 50d7ed5f49629bf873000457 stdout: useradd: user '50d7ed5f49629bf873000457' already exists\n stderr: useradd: user '50d7ed5f49629bf873000457' already exists\n", :exitcode=>129}}>] (pid:29688)
>2012-12-24 00:51:33.436 [DEBUG] DEBUG: server results: ERROR: unable to create user account(9): useradd -u 520 -d /var/lib/openshift/50d7ed5f49629bf873000457/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 50d7ed5f49629bf873000457 stdout: useradd: user '50d7ed5f49629bf873000457' already exists (pid:29688)
>2012-12-24 00:51:33.436 [ERROR] Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:29688)
>2012-12-24 00:51:33.437 [ERROR] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:1131:in `parse_result'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:189:in `block in create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:178:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.3.1/lib/openshift/mcollective_application_container_proxy.rb:178:in `create'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/gear.rb:70:in `create_gear'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:170:in `block in execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/pending_app_op_group.rb:128:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:905:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:319:in `block in add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:937:in `run_in_application_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:316:in `add_features'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/models/application.rb:91:in `create_app'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.3.2/app/controllers/applications_controller.rb:96: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:458:in `_run__3372508005978997221__process_action__4390825379209607702__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.5/lib/rack/mongoid/middleware/identity_map.rb:33: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__2781603490877969806__call__4394155244277305052__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.12/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:514:in `accept_and_process_next_request'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:79:in `block in spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/utils.rb:479:in `safe_fork'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/rack/application_spawner.rb:64:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:264:in `spawn_rack_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:137:in `spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'", "/opt/rh/ruby193/root/usr/share/gems/gems/passenger-3.0.12/helper-scripts/passenger-spawn-server:102:in `<main>'"] (pid:29688)
>2012-12-24 00:51:33.464 [ERROR] Node execution failure (invalid exit code from node).  If the problem persists please contact Red Hat support. (pid:29688)
>[root@ip-10-46-183-31 log]# cat development.log |grep 50d7ed5f49629bf873000457
>2012-12-24 00:51:27.956 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=applications documents=[{"_id"=>"50d7ed5f49629bf873000457", "domain_requires"=>[], "group_overrides"=>[], "user_ids"=>[], "aliases"=>[], "component_start_order"=>[], "component_stop_order"=>[], "component_configure_order"=>[], "default_gear_size"=>nil, "init_git_url"=>nil, "name"=>"jbosas6", "scalable"=>true, "domain_id"=>"50d7ca1e49629bb120000046", "updated_at"=>2012-12-24 05:51:27 UTC, "created_at"=>2012-12-24 05:51:27 UTC}] flags=[] (3.6035ms) (pid:29688)
>2012-12-24 00:51:27.958 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7ca1e49629bb120000044", "app_ids"=>{"$nin"=>["50d7ed5f49629bf873000457"]}}, :new=>true, :update=>{"$push"=>{:app_ids=>"50d7ed5f49629bf873000457"}}} (1.2891ms) (pid:29688)
>2012-12-24 00:51:27.965 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$push"=>{"pending_op_groups"=>{"_id"=>"50d7ed5f49629bf873000458", "num_gears_added"=>0, "num_gears_removed"=>0, "num_gears_created"=>0, "num_gears_destroyed"=>0, "num_gears_rolled_back"=>0, "op_type"=>:add_features, "args"=>{"features"=>["jbossews-1.0", "web_proxy"], "group_overrides"=>[], "init_git_url"=>nil}, "parent_op_id"=>nil, "updated_at"=>2012-12-24 05:51:27 UTC, "created_at"=>2012-12-24 05:51:27 UTC}}} flags=[] (4.5998ms) (pid:29688)
>2012-12-24 00:51:27.967 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.9518ms) (pid:29688)
>2012-12-24 00:51:28.108 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7ca1e49629bb120000044", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x000000062c65e0>"=>1356328288}], "app_ids"=>{"$in"=>["50d7ed5f49629bf873000457"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1356328888}}} (0.8924ms) (pid:29688)
>2012-12-24 00:51:28.168 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458"} update={"$pushAll"=>{"pending_op_groups.0.pending_ops"=>[{"_id"=>"50d7ed5f49629bf87300045b", "state"=>:init, "retry_count"=>0, "saved_values"=>{"current_group_overrides"=>[]}, "op_type"=>:set_group_overrides, "args"=>{"group_overrides"=>[]}}, {"_id"=>"50d7ed5f49629bf87300045d", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_size"=>nil, "additional_filesystem_gb"=>0}}, {"_id"=>"50d7ed6049629bf873000460", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457"}, "prereq"=>["50d7ed6049629bf87300045f"], "retry_rollback_op"=>"50d7ed6049629bf87300045f"}, {"_id"=>"50d7ed6049629bf87300045e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["50d7ed5f49629bf87300045d"]}, {"_id"=>"50d7ed6049629bf87300046c", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"jinzhang+3", "gear_ref"=>"50d7ed5f49629bf873000457", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>nil}, "prereq"=>["50d7ed6049629bf87300045e"]}, {"_id"=>"50d7ed6049629bf87300045f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457"}, "prereq"=>["50d7ed6049629bf87300045e"]}, {"_id"=>"50d7ed6049629bf873000461", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457"}, "prereq"=>["50d7ed6049629bf873000460"]}, {"_id"=>"50d7ed6049629bf87300046d", "state"=>:init, "retry_count"=>0, "saved_values"=>{"additional_filesystem_gb"=>0}, "op_type"=>:set_gear_additional_filesystem_gb, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457", "additional_filesystem_gb"=>0}, "prereq"=>["50d7ed6049629bf87300045e"]}, {"_id"=>"50d7ed6049629bf87300046e", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"50d7cb0f49629bb120000075", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDKVBuS3VaBuwmKj9extkJmhdIvZH5rmcEw1KpeqgRWDhw8GFT0n4l2M1D92vaCTM4Yp1aelQb3V+UxHF4yIdR3Vs36M3f2zjfgOBA50QfmZmVJgvPm/KFqxEcr5y/JrV/mEmQhgTSeFU7caYupIdNgTSQU7L5Ny971tRD05m0uB3ISUXyaU3idEt0ZqnRpUA9zWXLjPR2YsOnfhF6cnrIQ4HUkVGpEFhXCoWcYeDM75NBZWzw017deIvTofoyL9X3s6CMq+onQJ5Wq5O/2Kbi+hXatB5YKfST3bvFbb5/SBWFo9jdnFoON3GkPi7rEmHxcu61Ykmbt96CSj0cb8hVx", "name"=>"default", "type"=>"ssh-rsa"}], "add_env_vars"=>[], "group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457"}, "prereq"=>["50d7ed6049629bf873000461"]}, {"_id"=>"50d7ed6049629bf873000462", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "comp_spec"=>{"comp"=>"jbossews-1.0", "cart"=>"jbossews-1.0"}}, "prereq"=>["50d7ed5f49629bf87300045d"]}, {"_id"=>"50d7ed6049629bf873000463", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457", "comp_spec"=>{"comp"=>"jbossews-1.0", "cart"=>"jbossews-1.0"}, "init_git_url"=>nil}, "prereq"=>["50d7ed6049629bf873000462", "50d7ed6049629bf873000461"]}, {"_id"=>"50d7ed6049629bf87300046f", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:expose_port, "args"=>{"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457", "comp_spec"=>{"comp"=>"jbossews-1.0", "cart"=>"jbossews-1.0"}}, "prereq"=>["50d7ed6049629bf873000463", "50d7ed6049629bf873000461"]}, {"_id"=>"50d7ed6049629bf873000465", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_group_instance, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_size"=>nil, "additional_filesystem_gb"=>0}}, {"_id"=>"50d7ed6049629bf873000468", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:create_gear, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457"}, "prereq"=>["50d7ed6049629bf873000467"], "retry_rollback_op"=>"50d7ed6049629bf873000467"}, {"_id"=>"50d7ed6049629bf873000466", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:init_gear, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true}, "prereq"=>["50d7ed6049629bf873000465"]}, {"_id"=>"50d7ed6049629bf873000470", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:track_usage, "args"=>{"login"=>"jinzhang+3", "gear_ref"=>"50d7ed5f49629bf873000457", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>nil}, "prereq"=>["50d7ed6049629bf873000466"]}, {"_id"=>"50d7ed6049629bf873000467", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:reserve_uid, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457"}, "prereq"=>["50d7ed6049629bf873000466"]}, {"_id"=>"50d7ed6049629bf873000469", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:register_dns, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457"}, "prereq"=>["50d7ed6049629bf873000468"]}, {"_id"=>"50d7ed6049629bf873000471", "state"=>:init, "retry_count"=>0, "saved_values"=>{"additional_filesystem_gb"=>0}, "op_type"=>:set_gear_additional_filesystem_gb, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457", "additional_filesystem_gb"=>0}, "prereq"=>["50d7ed6049629bf873000466"]}, {"_id"=>"50d7ed6049629bf873000472", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:update_configuration, "args"=>{"add_keys_attrs"=>[{"_id"=>"50d7cb0f49629bb120000075", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDKVBuS3VaBuwmKj9extkJmhdIvZH5rmcEw1KpeqgRWDhw8GFT0n4l2M1D92vaCTM4Yp1aelQb3V+UxHF4yIdR3Vs36M3f2zjfgOBA50QfmZmVJgvPm/KFqxEcr5y/JrV/mEmQhgTSeFU7caYupIdNgTSQU7L5Ny971tRD05m0uB3ISUXyaU3idEt0ZqnRpUA9zWXLjPR2YsOnfhF6cnrIQ4HUkVGpEFhXCoWcYeDM75NBZWzw017deIvTofoyL9X3s6CMq+onQJ5Wq5O/2Kbi+hXatB5YKfST3bvFbb5/SBWFo9jdnFoON3GkPi7rEmHxcu61Ykmbt96CSj0cb8hVx", "name"=>"default", "type"=>"ssh-rsa"}], "add_env_vars"=>[], "group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457"}, "prereq"=>["50d7ed6049629bf873000469"]}, {"_id"=>"50d7ed6049629bf87300046a", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:new_component, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "comp_spec"=>{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}}, "prereq"=>["50d7ed6049629bf873000465", "50d7ed6049629bf873000463"]}, {"_id"=>"50d7ed6049629bf87300046b", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:add_component, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457", "comp_spec"=>{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}, "init_git_url"=>nil}, "prereq"=>["50d7ed6049629bf87300046a", "50d7ed6049629bf873000469", "50d7ed6049629bf873000463"]}, {"_id"=>"50d7ed6049629bf873000473", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:expose_port, "args"=>{"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457", "comp_spec"=>{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}}, "prereq"=>["50d7ed6049629bf87300046b", "50d7ed6049629bf873000469"]}, {"_id"=>"50d7ed6049629bf873000474", "state"=>:init, "retry_count"=>0, "saved_values"=>{:current_connections=>[]}, "op_type"=>:set_connections, "args"=>{"connections"=>[{"from_comp_inst"=>{"cart"=>"jbossews-1.0", "comp"=>"jbossews-1.0"}, "to_comp_inst"=>{"cart"=>"haproxy-1.4", "comp"=>"web_proxy"}, "from_connector_name"=>"publish-http-url", "to_connector_name"=>"set-proxy", "connection_type"=>"NET_TCP:httpd-proxy-info"}, {"from_comp_inst"=>{"cart"=>"jbossews-1.0", "comp"=>"jbossews-1.0"}, "to_comp_inst"=>{"cart"=>"haproxy-1.4", "comp"=>"web_proxy"}, "from_connector_name"=>"publish-gear-endpoint", "to_connector_name"=>"set-gear-endpoints", "connection_type"=>"NET_TCP:gear-endpoint-info"}]}, "prereq"=>["50d7ed5f49629bf87300045b", "50d7ed5f49629bf87300045d", "50d7ed6049629bf873000460", "50d7ed6049629bf87300045e", "50d7ed6049629bf87300046c", "50d7ed6049629bf87300045f", "50d7ed6049629bf873000461", "50d7ed6049629bf87300046d", "50d7ed6049629bf87300046e", "50d7ed6049629bf873000462", "50d7ed6049629bf873000463", "50d7ed6049629bf87300046f", "50d7ed6049629bf873000465", "50d7ed6049629bf873000468", "50d7ed6049629bf873000466", "50d7ed6049629bf873000470", "50d7ed6049629bf873000467", "50d7ed6049629bf873000469", "50d7ed6049629bf873000471", "50d7ed6049629bf873000472", "50d7ed6049629bf87300046a", "50d7ed6049629bf87300046b", "50d7ed6049629bf873000473"]}, {"_id"=>"50d7ed6049629bf873000475", "state"=>:init, "retry_count"=>0, "saved_values"=>[], "op_type"=>:execute_connections, "prereq"=>["50d7ed6049629bf873000474"]}]}} flags=[] (30.0729ms) (pid:29688)
>2012-12-24 00:51:28.204 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458"} update={"$set"=>{"pending_op_groups.0.num_gears_added"=>2, "pending_op_groups.0.updated_at"=>2012-12-24 05:51:28 UTC}} flags=[] (2.4567ms) (pid:29688)
>2012-12-24 00:51:28.226 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7ca1e49629bb120000044", "locked"=>true, "app_ids"=>{"$in"=>["50d7ed5f49629bf873000457"]}}, :new=>false, :update=>{"$set"=>{"locked"=>false}}} (3.9823ms) (pid:29688)
>2012-12-24 00:51:28.228 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000460, _type: nil, op_type: :create_gear, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457"}, prereq: ["50d7ed6049629bf87300045f"], retry_count: 0, retry_rollback_op: "50d7ed6049629bf87300045f", saved_values: []> (pid:29688)
>2012-12-24 00:51:28.228 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf87300045e, _type: nil, op_type: :init_gear, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true}, prereq: ["50d7ed5f49629bf87300045d"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.228 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf87300046c, _type: nil, op_type: :track_usage, state: :init, args: {"login"=>"jinzhang+3", "gear_ref"=>"50d7ed5f49629bf873000457", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>nil}, prereq: ["50d7ed6049629bf87300045e"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.228 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf87300045f, _type: nil, op_type: :reserve_uid, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457"}, prereq: ["50d7ed6049629bf87300045e"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.229 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000461, _type: nil, op_type: :register_dns, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457"}, prereq: ["50d7ed6049629bf873000460"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.229 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf87300046d, _type: nil, op_type: :set_gear_additional_filesystem_gb, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457", "additional_filesystem_gb"=>0}, prereq: ["50d7ed6049629bf87300045e"], retry_count: 0, retry_rollback_op: nil, saved_values: {"additional_filesystem_gb"=>0}> (pid:29688)
>2012-12-24 00:51:28.229 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf87300046e, _type: nil, op_type: :update_configuration, state: :init, args: {"add_keys_attrs"=>[{"_id"=>"50d7cb0f49629bb120000075", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDKVBuS3VaBuwmKj9extkJmhdIvZH5rmcEw1KpeqgRWDhw8GFT0n4l2M1D92vaCTM4Yp1aelQb3V+UxHF4yIdR3Vs36M3f2zjfgOBA50QfmZmVJgvPm/KFqxEcr5y/JrV/mEmQhgTSeFU7caYupIdNgTSQU7L5Ny971tRD05m0uB3ISUXyaU3idEt0ZqnRpUA9zWXLjPR2YsOnfhF6cnrIQ4HUkVGpEFhXCoWcYeDM75NBZWzw017deIvTofoyL9X3s6CMq+onQJ5Wq5O/2Kbi+hXatB5YKfST3bvFbb5/SBWFo9jdnFoON3GkPi7rEmHxcu61Ykmbt96CSj0cb8hVx", "name"=>"default", "type"=>"ssh-rsa"}], "add_env_vars"=>[], "group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457"}, prereq: ["50d7ed6049629bf873000461"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.229 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000463, _type: nil, op_type: :add_component, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457", "comp_spec"=>{"comp"=>"jbossews-1.0", "cart"=>"jbossews-1.0"}, "init_git_url"=>nil}, prereq: ["50d7ed6049629bf873000462", "50d7ed6049629bf873000461"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.230 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf87300046f, _type: nil, op_type: :expose_port, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf873000459", "gear_id"=>"50d7ed5f49629bf873000457", "comp_spec"=>{"comp"=>"jbossews-1.0", "cart"=>"jbossews-1.0"}}, prereq: ["50d7ed6049629bf873000463", "50d7ed6049629bf873000461"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.230 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000468, _type: nil, op_type: :create_gear, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457"}, prereq: ["50d7ed6049629bf873000467"], retry_count: 0, retry_rollback_op: "50d7ed6049629bf873000467", saved_values: []> (pid:29688)
>2012-12-24 00:51:28.230 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000466, _type: nil, op_type: :init_gear, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true}, prereq: ["50d7ed6049629bf873000465"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.232 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000470, _type: nil, op_type: :track_usage, state: :init, args: {"login"=>"jinzhang+3", "gear_ref"=>"50d7ed5f49629bf873000457", "event"=>"begin", "usage_type"=>"GEAR_USAGE", "gear_size"=>nil}, prereq: ["50d7ed6049629bf873000466"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.232 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000467, _type: nil, op_type: :reserve_uid, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457"}, prereq: ["50d7ed6049629bf873000466"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.232 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000469, _type: nil, op_type: :register_dns, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457"}, prereq: ["50d7ed6049629bf873000468"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.232 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000471, _type: nil, op_type: :set_gear_additional_filesystem_gb, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457", "additional_filesystem_gb"=>0}, prereq: ["50d7ed6049629bf873000466"], retry_count: 0, retry_rollback_op: nil, saved_values: {"additional_filesystem_gb"=>0}> (pid:29688)
>2012-12-24 00:51:28.232 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000472, _type: nil, op_type: :update_configuration, state: :init, args: {"add_keys_attrs"=>[{"_id"=>"50d7cb0f49629bb120000075", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDKVBuS3VaBuwmKj9extkJmhdIvZH5rmcEw1KpeqgRWDhw8GFT0n4l2M1D92vaCTM4Yp1aelQb3V+UxHF4yIdR3Vs36M3f2zjfgOBA50QfmZmVJgvPm/KFqxEcr5y/JrV/mEmQhgTSeFU7caYupIdNgTSQU7L5Ny971tRD05m0uB3ISUXyaU3idEt0ZqnRpUA9zWXLjPR2YsOnfhF6cnrIQ4HUkVGpEFhXCoWcYeDM75NBZWzw017deIvTofoyL9X3s6CMq+onQJ5Wq5O/2Kbi+hXatB5YKfST3bvFbb5/SBWFo9jdnFoON3GkPi7rEmHxcu61Ykmbt96CSj0cb8hVx", "name"=>"default", "type"=>"ssh-rsa"}], "add_env_vars"=>[], "group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457"}, prereq: ["50d7ed6049629bf873000469"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.236 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf87300046b, _type: nil, op_type: :add_component, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457", "comp_spec"=>{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}, "init_git_url"=>nil}, prereq: ["50d7ed6049629bf87300046a", "50d7ed6049629bf873000469", "50d7ed6049629bf873000463"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.236 [DEBUG] #<PendingAppOp _id: 50d7ed6049629bf873000473, _type: nil, op_type: :expose_port, state: :init, args: {"group_instance_id"=>"50d7ed5f49629bf87300045a", "gear_id"=>"50d7ed5f49629bf873000457", "comp_spec"=>{"comp"=>"web_proxy", "cart"=>"haproxy-1.4"}}, prereq: ["50d7ed6049629bf87300046b", "50d7ed6049629bf873000469"], retry_count: 0, retry_rollback_op: nil, saved_values: []> (pid:29688)
>2012-12-24 00:51:28.242 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (3.5384ms) (pid:29688)
>2012-12-24 00:51:28.322 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed5f49629bf87300045b"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:completed}} flags=[] (1.2691ms) (pid:29688)
>2012-12-24 00:51:28.327 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$push"=>{"group_instances"=>{"_id"=>"50d7ed5f49629bf873000459", "gear_size"=>"small", "addtl_fs_gb"=>0}}} flags=[] (1.8530ms) (pid:29688)
>2012-12-24 00:51:28.334 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed5f49629bf87300045d"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:completed}} flags=[] (0.6292ms) (pid:29688)
>2012-12-24 00:51:28.336 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$push"=>{"group_instances"=>{"_id"=>"50d7ed5f49629bf87300045a", "gear_size"=>"small", "addtl_fs_gb"=>0}}} flags=[] (0.6075ms) (pid:29688)
>2012-12-24 00:51:28.340 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000465"} update={"$set"=>{"pending_op_groups.0.pending_ops.12.state"=>:completed}} flags=[] (0.5391ms) (pid:29688)
>2012-12-24 00:51:28.345 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (3.8579ms) (pid:29688)
>2012-12-24 00:51:28.377 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf873000459"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true, "name"=>"jbosas6"}}} flags=[] (1.6005ms) (pid:29688)
>2012-12-24 00:51:28.408 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf87300045e"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:completed}} flags=[] (25.1868ms) (pid:29688)
>2012-12-24 00:51:28.410 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$push"=>{"component_instances"=>{"_id"=>"50d7ed6049629bf873000479", "component_properties"=>{}, "cartridge_name"=>"jbossews-1.0", "component_name"=>"jbossews-1.0", "group_instance_id"=>"50d7ed5f49629bf873000459"}}} flags=[] (0.3445ms) (pid:29688)
>2012-12-24 00:51:28.411 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000462"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:completed}} flags=[] (0.4685ms) (pid:29688)
>2012-12-24 00:51:28.412 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf87300045a"} update={"$push"=>{"group_instances.1.gears"=>{"_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true, "name"=>"jbosas6"}}} flags=[] (0.3371ms) (pid:29688)
>2012-12-24 00:51:28.416 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000466"} update={"$set"=>{"pending_op_groups.0.pending_ops.14.state"=>:completed}} flags=[] (0.4873ms) (pid:29688)
>2012-12-24 00:51:28.418 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.7347ms) (pid:29688)
>2012-12-24 00:51:28.432 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage_records documents=[{"_id"=>"50d7ed6049629bf87300047b", "event"=>"begin", "time"=>2012-12-24 05:51:28 UTC, "gear_id"=>"50d7ed5f49629bf873000457", "usage_type"=>"GEAR_USAGE", "login"=>"jinzhang+3", "updated_at"=>2012-12-24 05:51:28 UTC, "created_at"=>2012-12-24 05:51:28 UTC}] flags=[] (0.5393ms) (pid:29688)
>2012-12-24 00:51:28.436 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage documents=[{"_id"=>"50d7ed6049629bf87300047c", "login"=>"jinzhang+3", "gear_id"=>"50d7ed5f49629bf873000457", "begin_time"=>2012-12-24 05:51:28 UTC, "usage_type"=>"GEAR_USAGE", "updated_at"=>2012-12-24 05:51:28 UTC, "created_at"=>2012-12-24 05:51:28 UTC}] flags=[] (2.5804ms) (pid:29688)
>2012-12-24 00:51:28.437 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf87300046c"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:completed}} flags=[] (0.5527ms) (pid:29688)
>2012-12-24 00:51:29.577 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf873000459", "group_instances.0.gears._id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>"ip-10-46-183-31"}} flags=[] (0.7946ms) (pid:29688)
>2012-12-24 00:51:29.694 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf873000459", "group_instances.0.gears._id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (0.6521ms) (pid:29688)
>2012-12-24 00:51:29.696 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf87300045f"} update={"$set"=>{"pending_op_groups.0.pending_ops.5.state"=>:completed}} flags=[] (2.2409ms) (pid:29688)
>2012-12-24 00:51:29.711 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage_records documents=[{"_id"=>"50d7ed6149629bf87300047d", "event"=>"begin", "time"=>2012-12-24 05:51:29 UTC, "gear_id"=>"50d7ed5f49629bf873000457", "usage_type"=>"GEAR_USAGE", "login"=>"jinzhang+3", "updated_at"=>2012-12-24 05:51:29 UTC, "created_at"=>2012-12-24 05:51:29 UTC}] flags=[] (6.2163ms) (pid:29688)
>2012-12-24 00:51:29.746 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=usage documents=[{"_id"=>"50d7ed6149629bf87300047e", "login"=>"jinzhang+3", "gear_id"=>"50d7ed5f49629bf873000457", "begin_time"=>2012-12-24 05:51:29 UTC, "usage_type"=>"GEAR_USAGE", "updated_at"=>2012-12-24 05:51:29 UTC, "created_at"=>2012-12-24 05:51:29 UTC}] flags=[] (2.3227ms) (pid:29688)
>2012-12-24 00:51:29.748 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000470"} update={"$set"=>{"pending_op_groups.0.pending_ops.15.state"=>:completed}} flags=[] (0.8261ms) (pid:29688)
>2012-12-24 00:51:30.785 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf87300045a", "group_instances.1.gears._id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_instances.1.gears.0.server_identity"=>"ip-10-46-183-31"}} flags=[] (0.7145ms) (pid:29688)
>2012-12-24 00:51:30.855 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf87300045a", "group_instances.1.gears._id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_instances.1.gears.0.uid"=>nil}} flags=[] (0.7362ms) (pid:29688)
>2012-12-24 00:51:30.857 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000467"} update={"$set"=>{"pending_op_groups.0.pending_ops.16.state"=>:completed}} flags=[] (1.6332ms) (pid:29688)
>2012-12-24 00:51:30.859 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf87300046d"} update={"$set"=>{"pending_op_groups.0.pending_ops.7.state"=>:completed}} flags=[] (0.4864ms) (pid:29688)
>2012-12-24 00:51:30.859 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000471"} update={"$set"=>{"pending_op_groups.0.pending_ops.18.state"=>:completed}} flags=[] (0.4332ms) (pid:29688)
>2012-12-24 00:51:30.867 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (2.3625ms) (pid:29688)
>2012-12-24 00:51:30.880 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:33.220 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458"} update={"$inc"=>{"pending_op_groups.0.num_gears_created"=>1}} flags=[] (6.2675ms) (pid:29688)
>2012-12-24 00:51:33.230 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000460"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:completed}} flags=[] (0.1888ms) (pid:29688)
>2012-12-24 00:51:33.348 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:33.434 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000038a2de0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-46-183-31", :statuscode=>1, :statusmsg=>"cartridge_do_action failed 129. Output ERROR: unable to create user account(9): useradd -u 520 -d /var/lib/openshift/50d7ed5f49629bf873000457/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 50d7ed5f49629bf873000457 stdout: useradd: user '50d7ed5f49629bf873000457' already exists\n stderr: useradd: user '50d7ed5f49629bf873000457' already exists\n", :data=>{:time=>nil, :output=>"ERROR: unable to create user account(9): useradd -u 520 -d /var/lib/openshift/50d7ed5f49629bf873000457/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 50d7ed5f49629bf873000457 stdout: useradd: user '50d7ed5f49629bf873000457' already exists\n stderr: useradd: user '50d7ed5f49629bf873000457' already exists\n", :exitcode=>129}}>] (pid:29688)
>2012-12-24 00:51:33.436 [DEBUG] DEBUG: server results: ERROR: unable to create user account(9): useradd -u 520 -d /var/lib/openshift/50d7ed5f49629bf873000457/ -s /usr/bin/oo-trap-user -c 'OpenShift guest' -m -k /etc/openshift/skel 50d7ed5f49629bf873000457 stdout: useradd: user '50d7ed5f49629bf873000457' already exists (pid:29688)
>2012-12-24 00:51:33.436 [DEBUG] DEBUG: server results:  stderr: useradd: user '50d7ed5f49629bf873000457' already exists (pid:29688)
>2012-12-24 00:51:33.469 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (2.9418ms) (pid:29688)
>2012-12-24 00:51:33.480 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_overrides"=>nil, "updated_at"=>2012-12-24 05:51:33 UTC}} flags=[] (0.4764ms) (pid:29688)
>2012-12-24 00:51:33.482 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed5f49629bf87300045b"} update={"$set"=>{"pending_op_groups.0.pending_ops.0.state"=>:rolledback}} flags=[] (0.5286ms) (pid:29688)
>2012-12-24 00:51:33.486 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:36.909 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458"} update={"$inc"=>{"pending_op_groups.0.num_gears_rolled_back"=>1}} flags=[] (1.2696ms) (pid:29688)
>2012-12-24 00:51:36.910 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000460"} update={"$set"=>{"pending_op_groups.0.pending_ops.2.state"=>:rolledback}} flags=[] (0.9978ms) (pid:29688)
>2012-12-24 00:51:36.912 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage_records selector={"$query"=>{"login"=>"jinzhang+3", "gear_id"=>"50d7ed5f49629bf873000457", "event"=>"begin", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.7517ms) (pid:29688)
>2012-12-24 00:51:36.915 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage selector={"$query"=>{"gear_id"=>"50d7ed5f49629bf873000457", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{"begin_time"=>-1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.5280ms) (pid:29688)
>2012-12-24 00:51:36.917 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf87300046c"} update={"$set"=>{"pending_op_groups.0.pending_ops.4.state"=>:rolledback}} flags=[] (0.4599ms) (pid:29688)
>2012-12-24 00:51:36.919 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$pull"=>{"component_instances"=>{"_id"=>"50d7ed6049629bf873000479"}}} flags=[] (0.2468ms) (pid:29688)
>2012-12-24 00:51:36.920 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000462"} update={"$set"=>{"pending_op_groups.0.pending_ops.9.state"=>:rolledback}} flags=[] (0.4075ms) (pid:29688)
>2012-12-24 00:51:36.921 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage_records selector={"$query"=>{"login"=>"jinzhang+3", "gear_id"=>"50d7ed5f49629bf873000457", "event"=>"begin", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.6273ms) (pid:29688)
>2012-12-24 00:51:36.923 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=usage selector={"$query"=>{"gear_id"=>"50d7ed5f49629bf873000457", "usage_type"=>"GEAR_USAGE"}, "$orderby"=>{"begin_time"=>-1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.4220ms) (pid:29688)
>2012-12-24 00:51:36.924 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000470"} update={"$set"=>{"pending_op_groups.0.pending_ops.15.state"=>:rolledback}} flags=[] (0.5074ms) (pid:29688)
>2012-12-24 00:51:36.995 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf87300045a", "group_instances.1.gears._id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_instances.1.gears.0.server_identity"=>nil}} flags=[] (0.6411ms) (pid:29688)
>2012-12-24 00:51:36.996 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf87300045a", "group_instances.1.gears._id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_instances.1.gears.0.uid"=>nil}} flags=[] (1.2724ms) (pid:29688)
>2012-12-24 00:51:36.997 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000467"} update={"$set"=>{"pending_op_groups.0.pending_ops.16.state"=>:rolledback}} flags=[] (0.4516ms) (pid:29688)
>2012-12-24 00:51:37.801 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"updated_at"=>2012-12-24 05:51:37 UTC, "pending_op_groups.0.pending_ops.7.state"=>:rolledback, "pending_op_groups.0.pending_ops.18.state"=>:rolledback}} flags=[] (3.0878ms) (pid:29688)
>2012-12-24 00:51:37.116 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (2.5628ms) (pid:29688)
>2012-12-24 00:51:37.125 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf873000459", "group_instances.0.gears._id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_instances.0.gears.0.server_identity"=>nil}} flags=[] (0.8271ms) (pid:29688)
>2012-12-24 00:51:37.126 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf873000459", "group_instances.0.gears._id"=>"50d7ed5f49629bf873000457"} update={"$set"=>{"group_instances.0.gears.0.uid"=>nil}} flags=[] (0.3097ms) (pid:29688)
>2012-12-24 00:51:37.127 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf87300045f"} update={"$set"=>{"pending_op_groups.0.pending_ops.5.state"=>:rolledback}} flags=[] (0.4001ms) (pid:29688)
>2012-12-24 00:51:37.130 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf87300045a"} update={"$pull"=>{"group_instances.1.gears"=>{"_id"=>"50d7ed5f49629bf873000457"}}} flags=[] (1.6003ms) (pid:29688)
>2012-12-24 00:51:37.133 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000466"} update={"$set"=>{"pending_op_groups.0.pending_ops.14.state"=>:rolledback}} flags=[] (0.4926ms) (pid:29688)
>2012-12-24 00:51:37.136 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.7533ms) (pid:29688)
>2012-12-24 00:51:37.140 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf873000459"} update={"$pull"=>{"group_instances.0.gears"=>{"_id"=>"50d7ed5f49629bf873000457"}}} flags=[] (0.4280ms) (pid:29688)
>2012-12-24 00:51:37.143 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf87300045e"} update={"$set"=>{"pending_op_groups.0.pending_ops.3.state"=>:rolledback}} flags=[] (0.4761ms) (pid:29688)
>2012-12-24 00:51:37.144 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$pull"=>{"group_instances"=>{"_id"=>"50d7ed5f49629bf87300045a"}}} flags=[] (0.3176ms) (pid:29688)
>2012-12-24 00:51:37.145 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed6049629bf873000465"} update={"$set"=>{"pending_op_groups.0.pending_ops.12.state"=>:rolledback}} flags=[] (0.4454ms) (pid:29688)
>2012-12-24 00:51:37.147 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={:_id=>"50d7ed5f49629bf873000457"} flags=[:slave_ok] limit=-1 skip=0 fields=nil (1.5426ms) (pid:29688)
>2012-12-24 00:51:37.151 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$pull"=>{"group_instances"=>{"_id"=>"50d7ed5f49629bf873000459"}}} flags=[] (0.2573ms) (pid:29688)
>2012-12-24 00:51:37.151 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "pending_op_groups._id"=>"50d7ed5f49629bf873000458", "pending_op_groups.0.pending_ops._id"=>"50d7ed5f49629bf87300045d"} update={"$set"=>{"pending_op_groups.0.pending_ops.1.state"=>:rolledback}} flags=[] (0.4408ms) (pid:29688)
>2012-12-24 00:51:37.154 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7ca1e49629bb120000044", "$or"=>[{"locked"=>false}, {"#<Origin::Key:0x00000006564590>"=>1356328297}], "app_ids"=>{"$in"=>["50d7ed5f49629bf873000457"]}}, :new=>true, :update=>{"$set"=>{:locked=>true, :timeout=>1356328897}}} (0.5972ms) (pid:29688)
>2012-12-24 00:51:37.162 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7ca1e49629bb120000044", "locked"=>true, "app_ids"=>{"$in"=>["50d7ed5f49629bf873000457"]}}, :new=>false, :update=>{"$set"=>{"locked"=>false}}} (0.4909ms) (pid:29688)
>2012-12-24 00:51:37.163 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457"} update={"$pull"=>{"pending_op_groups"=>{"_id"=>"50d7ed5f49629bf873000458"}}} flags=[] (0.2556ms) (pid:29688)
>2012-12-24 00:51:37.164 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:findAndModify=>"locks", :query=>{"user_id"=>"50d7ca1e49629bb120000044", "locked"=>false, "app_ids"=>{"$in"=>["50d7ed5f49629bf873000457"]}}, :new=>false, :update=>{"$pop"=>{:app_ids=>"50d7ed5f49629bf873000457"}}} (0.4625ms) (pid:29688)
>[root@ip-10-46-183-31 log]# cat development.log |grep jbosas6
>2012-12-24 00:51:27.920 [INFO ] Parameters: {"cartridge"=>"jbossews-1.0", "name"=>"jbosas6", "scale"=>"true", "domain_id"=>"fork372test3"} (pid:29688)
>2012-12-24 00:51:27.944 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"applications", :query=>{"domain_id"=>"50d7ca1e49629bb120000046", "name"=>"jbosas6"}} (0.5035ms) (pid:29688)
>2012-12-24 00:51:27.956 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=applications documents=[{"_id"=>"50d7ed5f49629bf873000457", "domain_requires"=>[], "group_overrides"=>[], "user_ids"=>[], "aliases"=>[], "component_start_order"=>[], "component_stop_order"=>[], "component_configure_order"=>[], "default_gear_size"=>nil, "init_git_url"=>nil, "name"=>"jbosas6", "scalable"=>true, "domain_id"=>"50d7ca1e49629bb120000046", "updated_at"=>2012-12-24 05:51:27 UTC, "created_at"=>2012-12-24 05:51:27 UTC}] flags=[] (3.6035ms) (pid:29688)
>2012-12-24 00:51:28.377 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf873000459"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true, "name"=>"jbosas6"}}} flags=[] (1.6005ms) (pid:29688)
>2012-12-24 00:51:28.412 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf87300045a"} update={"$push"=>{"group_instances.1.gears"=>{"_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true, "name"=>"jbosas6"}}} flags=[] (0.3371ms) (pid:29688)
>2012-12-24 00:51:30.880 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:33.348 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:33.486 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:38.178 [DEBUG] [REQ_ID=7cd4addc734d47d0ba9169b3f534b360] ACTION=ADD_APPLICATION Application jbosas6 was created., TODO (pid:29688)
>2012-12-24 01:02:46.345 [INFO ] Started GET "/broker/rest/domains/fork372test3/applications/jbosas6/cartridges" for 203.114.244.88 at 2012-12-24 01:02:46 -0500 (pid:29688)
>2012-12-24 01:02:46.348 [INFO ] Parameters: {"domain_id"=>"fork372test3", "application_id"=>"jbosas6"} (pid:29688)
>2012-12-24 01:02:46.353 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"domain_id"=>"50d7ca1e49629bb120000046", "name"=>"jbosas6"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5765ms) (pid:29688)
>[root@ip-10-46-183-31 log]# clear
>
>[root@ip-10-46-183-31 log]# cat development.log |grep jbosas6
>2012-12-24 00:51:27.920 [INFO ] Parameters: {"cartridge"=>"jbossews-1.0", "name"=>"jbosas6", "scale"=>"true", "domain_id"=>"fork372test3"} (pid:29688)
>2012-12-24 00:51:27.944 [DEBUG] MOPED: 127.0.0.1:27017 COMMAND      database=openshift_broker_dev command={:count=>"applications", :query=>{"domain_id"=>"50d7ca1e49629bb120000046", "name"=>"jbosas6"}} (0.5035ms) (pid:29688)
>2012-12-24 00:51:27.956 [DEBUG] MOPED: 127.0.0.1:27017 INSERT       database=openshift_broker_dev collection=applications documents=[{"_id"=>"50d7ed5f49629bf873000457", "domain_requires"=>[], "group_overrides"=>[], "user_ids"=>[], "aliases"=>[], "component_start_order"=>[], "component_stop_order"=>[], "component_configure_order"=>[], "default_gear_size"=>nil, "init_git_url"=>nil, "name"=>"jbosas6", "scalable"=>true, "domain_id"=>"50d7ca1e49629bb120000046", "updated_at"=>2012-12-24 05:51:27 UTC, "created_at"=>2012-12-24 05:51:27 UTC}] flags=[] (3.6035ms) (pid:29688)
>2012-12-24 00:51:28.377 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf873000459"} update={"$push"=>{"group_instances.0.gears"=>{"_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true, "name"=>"jbosas6"}}} flags=[] (1.6005ms) (pid:29688)
>2012-12-24 00:51:28.412 [DEBUG] MOPED: 127.0.0.1:27017 UPDATE       database=openshift_broker_dev collection=applications selector={"_id"=>"50d7ed5f49629bf873000457", "group_instances._id"=>"50d7ed5f49629bf87300045a"} update={"$push"=>{"group_instances.1.gears"=>{"_id"=>"50d7ed5f49629bf873000457", "host_singletons"=>true, "app_dns"=>true, "name"=>"jbosas6"}}} flags=[] (0.3371ms) (pid:29688)
>2012-12-24 00:51:30.880 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:33.348 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:33.486 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"50d7ed5f49629bf873000457", "--with-app-name"=>"jbosas6", "--with-container-uuid"=>"50d7ed5f49629bf873000457", "--with-container-name"=>"jbosas6", "--with-namespace"=>"fork372test3"}}, ip-10-46-183-31, {'identity' => ip-10-46-183-31}) (pid:29688)
>2012-12-24 00:51:38.178 [DEBUG] [REQ_ID=7cd4addc734d47d0ba9169b3f534b360] ACTION=ADD_APPLICATION Application jbosas6 was created., TODO (pid:29688)
>2012-12-24 01:02:46.345 [INFO ] Started GET "/broker/rest/domains/fork372test3/applications/jbosas6/cartridges" for 203.114.244.88 at 2012-12-24 01:02:46 -0500 (pid:29688)
>2012-12-24 01:02:46.348 [INFO ] Parameters: {"domain_id"=>"fork372test3", "application_id"=>"jbosas6"} (pid:29688)
>2012-12-24 01:02:46.353 [DEBUG] MOPED: 127.0.0.1:27017 QUERY        database=openshift_broker_dev collection=applications selector={"$query"=>{"domain_id"=>"50d7ca1e49629bb120000046", "name"=>"jbosas6"}, "$orderby"=>{:_id=>1}} flags=[:slave_ok] limit=-1 skip=0 fields=nil (0.5765ms) (pid:29688)

Actions: View

Attachments on bug 889949: 668328