Attachment 878378 Details for Bug 1080381 – development log

development log

development.log (text/plain), 70.07 KB, created by Qixuan Wang on 2014-03-25 10:36:13 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Qixuan Wang

Created: 2014-03-25 10:36:13 UTC

Size: 70.07 KB

patch

obsolete

>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 03:07:31.673[0m [[0;37mDEBUG[0m] Dalli::Server#connect 172.17.42.1:11211 (pid:77)
>[0;37m2014-03-25 03:07:31.677[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2014-03-25 03:07:31 -0400 (pid:77)
>[0;37m2014-03-25 03:07:31.918[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:77)
>[0;37m2014-03-25 03:07:31.919[0m [[0;37mDEBUG[0m] API version 1.6 (pid:77)
>[0;37m2014-03-25 03:07:32.155[0m [[32mINFO [0m] Completed 200 OK in 237ms (Views: 8.9ms) (pid:77)
>[0;37m2014-03-25 03:07:32.159[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2014-03-25 03:07:32 -0400 (pid:77)
>[0;37m2014-03-25 03:07:32.163[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:77)
>[0;37m2014-03-25 03:07:32.163[0m [[0;37mDEBUG[0m] API version 1.6 (pid:77)
>[0;37m2014-03-25 03:07:32.177[0m [[32mINFO [0m] Completed 200 OK in 14ms (Views: 7.9ms) (pid:77)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 03:07:42.386[0m [[0;37mDEBUG[0m] Dalli::Server#connect 172.17.42.1:11212 (pid:95)
>[0;37m2014-03-25 03:07:42.389[0m [[32mINFO [0m] Started GET "/broker/rest/user" for 172.17.42.1 at 2014-03-25 03:07:42 -0400 (pid:95)
>[0;37m2014-03-25 03:07:42.597[0m [[32mINFO [0m] Processing by UserExtController#show as */* (pid:95)
>[0;37m2014-03-25 03:07:42.598[0m [[0;37mDEBUG[0m] API version 1.6 (pid:95)
>[0;37m2014-03-25 03:07:42.616[0m [[0;37mDEBUG[0m] SUCCESS ACTION=CREATE_USER USER_ID= LOGIN= USER=53312b3e87b8533775000001 LOGIN=user_with_multiple_gear_sizes@test.com PROVIDER= Creating user (pid:95)
>[0;37m2014-03-25 03:07:42.617[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=53312b3e87b8533775000001 LOGIN=user_with_multiple_gear_sizes@test.com IP=172.17.42.1 SCOPES=session Authenticated (pid:95)
>[0;37m2014-03-25 03:07:42.618[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=53312b3e87b8533775000001 LOGIN=user_with_multiple_gear_sizes@test.com DOMAIN= (pid:95)
>[0;37m2014-03-25 03:07:42.727[0m [[32mINFO [0m] Completed 200 OK in 130ms (Views: 3.7ms) (pid:95)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 03:08:05.453[0m [[0;37mDEBUG[0m] Dalli::Server#connect 172.17.42.1:11212 (pid:134)
>[0;37m2014-03-25 03:08:05.456[0m [[32mINFO [0m] Started GET "/broker/rest/user" for 172.17.42.1 at 2014-03-25 03:08:05 -0400 (pid:134)
>[0;37m2014-03-25 03:08:05.662[0m [[32mINFO [0m] Processing by UserExtController#show as */* (pid:134)
>[0;37m2014-03-25 03:08:05.663[0m [[0;37mDEBUG[0m] API version 1.6 (pid:134)
>[0;37m2014-03-25 03:08:05.682[0m [[0;37mDEBUG[0m] SUCCESS ACTION=CREATE_USER USER_ID= LOGIN= USER=53312b5587b853ae1a000001 LOGIN=user_with_extra_storage@test.com PROVIDER= Creating user (pid:134)
>[0;37m2014-03-25 03:08:05.682[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=53312b5587b853ae1a000001 LOGIN=user_with_extra_storage@test.com IP=172.17.42.1 SCOPES=session Authenticated (pid:134)
>[0;37m2014-03-25 03:08:05.684[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=53312b5587b853ae1a000001 LOGIN=user_with_extra_storage@test.com DOMAIN= (pid:134)
>[0;37m2014-03-25 03:08:05.796[0m [[32mINFO [0m] Completed 200 OK in 133ms (Views: 3.1ms) (pid:134)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 03:08:19.756[0m [[32mINFO [0m] Started GET "/broker/rest/user" for 172.17.42.1 at 2014-03-25 03:08:19 -0400 (pid:134)
>[0;37m2014-03-25 03:08:19.759[0m [[32mINFO [0m] Processing by UserExtController#show as */* (pid:134)
>[0;37m2014-03-25 03:08:19.759[0m [[0;37mDEBUG[0m] API version 1.6 (pid:134)
>[0;37m2014-03-25 03:08:19.766[0m [[0;37mDEBUG[0m] SUCCESS ACTION=CREATE_USER USER_ID=53312b5587b853ae1a000001 LOGIN=user_with_extra_storage@test.com USER=53312b6387b853ae1a000003 LOGIN=user_with_certificate_capabilities@test.com PROVIDER= Creating user (pid:134)
>[0;37m2014-03-25 03:08:19.766[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=53312b6387b853ae1a000003 LOGIN=user_with_certificate_capabilities@test.com IP=172.17.42.1 SCOPES=session Authenticated (pid:134)
>[0;37m2014-03-25 03:08:19.767[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=53312b6387b853ae1a000003 LOGIN=user_with_certificate_capabilities@test.com DOMAIN= (pid:134)
>[0;37m2014-03-25 03:08:19.769[0m [[32mINFO [0m] Completed 200 OK in 10ms (Views: 1.1ms) (pid:134)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 03:08:46.599[0m [[0;37mDEBUG[0m] DEBUG: find_one_impl: current_server: ip-10-186-10-13 (pid:1)
>[0;37m2014-03-25 03:08:46.617[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"cartridge-list", :args=>{"--porcelain"=>true, "--with-descriptors"=>true, "--cart-name"=>"openshift-origin-node"}}, ip-10-186-10-13, {'identity' => ip-10-186-10-13}) (Request ID: ) (pid:1)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 03:09:17.495[0m [[0;37mDEBUG[0m] Downloading https://raw.github.com/vert-x/openshift-cartridge/master/metadata/manifest.yml... (pid:1)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 03:09:31.405[0m [[0;37mDEBUG[0m] Downloading https://raw.github.com/jboss-datavirtualization/openshift-cartridge-datavirtualization/6.0.0/metadata/manifest.yml... (pid:1)
>[0;37m2014-03-25 03:09:48.658[0m [[32mINFO [0m] Started GET "/broker/rest/cartridges/standalone" for 172.17.42.1 at 2014-03-25 03:09:48 -0400 (pid:134)
>[0;37m2014-03-25 03:09:48.661[0m [[32mINFO [0m] Processing by CartridgesController#index as */* (pid:134)
>[0;37m2014-03-25 03:09:48.661[0m [[32mINFO [0m] Parameters: {"feature"=>"standalone"} (pid:134)
>[0;37m2014-03-25 03:09:48.661[0m [[0;37mDEBUG[0m] API version 1.6 (pid:134)
>[0;37m2014-03-25 03:09:48.671[0m [[0;37mDEBUG[0m] Dalli::Server#connect 172.17.42.1:11211 (pid:134)
>[0;37m2014-03-25 03:09:48.738[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_CARTRIDGE USER_ID=53312b6387b853ae1a000003 LOGIN=user_with_certificate_capabilities@test.com DOMAIN= Searching cartridges (pid:134)
>[0;37m2014-03-25 03:09:48.749[0m [[32mINFO [0m] Completed 200 OK in 88ms (Views: 9.0ms) (pid:134)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 05:47:53.227[0m [[0;37mDEBUG[0m] Dalli::Server#connect 172.17.42.1:11211 (pid:74)
>[0;37m2014-03-25 05:47:53.230[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2014-03-25 05:47:53 -0400 (pid:74)
>[0;37m2014-03-25 05:47:54.709[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:74)
>[0;37m2014-03-25 05:47:54.766[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:47:56.224[0m [[32mINFO [0m] Completed 200 OK in 1516ms (Views: 50.0ms) (pid:74)
>[0;37m2014-03-25 05:47:56.279[0m [[32mINFO [0m] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2014-03-25 05:47:56 -0400 (pid:74)
>[0;37m2014-03-25 05:47:56.282[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:74)
>[0;37m2014-03-25 05:47:56.282[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:47:56.377[0m [[32mINFO [0m] Completed 200 OK in 95ms (Views: 80.6ms) (pid:74)
>[0;37m2014-03-25 05:48:03.550[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:48:03 -0400 (pid:74)
>[0;37m2014-03-25 05:48:03.551[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:74)
>[0;37m2014-03-25 05:48:03.552[0m [[0;37mDEBUG[0m] API version 1.3 (pid:74)
>[0;37m2014-03-25 05:48:03.775[0m [[32mINFO [0m] Completed 200 OK in 224ms (Views: 184.6ms) (pid:74)
>[0;37m2014-03-25 05:48:04.112[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:48:04 -0400 (pid:74)
>[0;37m2014-03-25 05:48:04.113[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:74)
>[0;37m2014-03-25 05:48:04.113[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:04.259[0m [[32mINFO [0m] Completed 200 OK in 146ms (Views: 97.2ms) (pid:74)
>[0;37m2014-03-25 05:48:04.670[0m [[0;37mDEBUG[0m] Dalli::Server#connect 172.17.42.1:11212 (pid:74)
>[0;37m2014-03-25 05:48:04.671[0m [[32mINFO [0m] Started GET "/broker/rest/user" for 203.114.244.88 at 2014-03-25 05:48:04 -0400 (pid:74)
>[0;37m2014-03-25 05:48:04.674[0m [[32mINFO [0m] Processing by UserExtController#show as JSON (pid:74)
>[0;37m2014-03-25 05:48:04.716[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:04.752[0m [[32mINFO [0m] Filter chain halted as :authenticate_user! rendered or redirected (pid:74)
>[0;37m2014-03-25 05:48:04.754[0m [[32mINFO [0m] Completed 401 Unauthorized in 39ms (pid:74)
>[0;37m2014-03-25 05:48:07.719[0m [[32mINFO [0m] Started GET "/broker/rest/user" for 203.114.244.88 at 2014-03-25 05:48:07 -0400 (pid:74)
>[0;37m2014-03-25 05:48:07.722[0m [[32mINFO [0m] Processing by UserExtController#show as JSON (pid:74)
>[0;37m2014-03-25 05:48:07.741[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:08.244[0m [[0;37mDEBUG[0m] SUCCESS ACTION=CREATE_USER USER_ID= LOGIN= USER=533150d787b8539f8b000001 LOGIN=qwang@redhat.com PROVIDER= Creating user (pid:74)
>[0;37m2014-03-25 05:48:08.285[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:74)
>[0;37m2014-03-25 05:48:08.610[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= (pid:74)
>[0;37m2014-03-25 05:48:08.972[0m [[32mINFO [0m] Completed 200 OK in 1248ms (Views: 894.1ms) (pid:74)
>[0;37m2014-03-25 05:48:10.892[0m [[32mINFO [0m] Started POST "/broker/rest/user/authorizations" for 203.114.244.88 at 2014-03-25 05:48:10 -0400 (pid:74)
>[0;37m2014-03-25 05:48:11.351[0m [[32mINFO [0m] Processing by AuthorizationsController#create as JSON (pid:74)
>[0;37m2014-03-25 05:48:11.352[0m [[32mINFO [0m] Parameters: {"scope"=>"session", "reuse"=>true, "note"=>"RHC/1.22.4 (from dhcp-65-16.nay.redhat.com on x86_64-linux)", "authorization"=>{"note"=>"RHC/1.22.4 (from dhcp-65-16.nay.redhat.com on x86_64-linux)"}} (pid:74)
>[0;37m2014-03-25 05:48:11.359[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:11.381[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:74)
>[0;37m2014-03-25 05:48:11.172[0m [[0;37mDEBUG[0m] SUCCESS ACTION=ADD_AUTHORIZATION USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= TOKEN=818102735ecf7f2090fadab785f202621fa2814df1ef943de48c3494ae17441b SCOPE=session EXPIRES=2014-04-24 09:48:11 UTC IP=203.114.244.88 Create authorization (pid:74)
>[0;37m2014-03-25 05:48:11.220[0m [[32mINFO [0m] Completed 201 Created in 185ms (Views: 27.3ms) (pid:74)
>[0;37m2014-03-25 05:48:12.540[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:48:12 -0400 (pid:74)
>[0;37m2014-03-25 05:48:12.542[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:74)
>[0;37m2014-03-25 05:48:12.552[0m [[0;37mDEBUG[0m] API version 1.3 (pid:74)
>[0;37m2014-03-25 05:48:12.621[0m [[32mINFO [0m] Completed 200 OK in 79ms (Views: 61.8ms) (pid:74)
>[0;37m2014-03-25 05:48:12.928[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:48:12 -0400 (pid:74)
>[0;37m2014-03-25 05:48:12.965[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:74)
>[0;37m2014-03-25 05:48:12.965[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:13.566[0m [[32mINFO [0m] Completed 200 OK in 91ms (Views: 71.0ms) (pid:74)
>[0;37m2014-03-25 05:48:13.380[0m [[32mINFO [0m] Started GET "/broker/rest/user" for 203.114.244.88 at 2014-03-25 05:48:13 -0400 (pid:74)
>[0;37m2014-03-25 05:48:13.396[0m [[32mINFO [0m] Processing by UserExtController#show as JSON (pid:74)
>[0;37m2014-03-25 05:48:13.396[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:13.401[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:74)
>[0;37m2014-03-25 05:48:13.402[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= (pid:74)
>[0;37m2014-03-25 05:48:13.429[0m [[32mINFO [0m] Completed 200 OK in 33ms (Views: 1.2ms) (pid:74)
>[0;37m2014-03-25 05:48:13.741[0m [[32mINFO [0m] Started GET "/broker/rest/user/keys" for 203.114.244.88 at 2014-03-25 05:48:13 -0400 (pid:74)
>[0;37m2014-03-25 05:48:13.745[0m [[32mINFO [0m] Processing by KeysController#index as JSON (pid:74)
>[0;37m2014-03-25 05:48:13.746[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:13.805[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:74)
>[0;37m2014-03-25 05:48:13.805[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_KEY USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= Found 0 ssh keys (pid:74)
>[0;37m2014-03-25 05:48:13.810[0m [[32mINFO [0m] Completed 200 OK in 64ms (Views: 0.4ms) (pid:74)
>[0;37m2014-03-25 05:48:16.212[0m [[32mINFO [0m] Started POST "/broker/rest/user/keys" for 203.114.244.88 at 2014-03-25 05:48:16 -0400 (pid:74)
>[0;37m2014-03-25 05:48:16.217[0m [[32mINFO [0m] Processing by KeysController#create as JSON (pid:74)
>[0;37m2014-03-25 05:48:16.218[0m [[32mINFO [0m] Parameters: {"name"=>"default", "type"=>"ssh-rsa", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDM3MQ+zRV8h8DfIlTGqsiLUQaV0tsdGPz1hdu9Hw23+LMSTReu7KSN8PdPRJ6VXLy7l8OnJWb9ln56uiuydmocxc6SDB6I74HYkB7lMBMFe6qsLZkL9/W14YE2VIzg32/R1RDC06wBCx5cXs4Tvn9XKRhtENMDfY/i34z4HW0uDN8VOBZOe+8+glJhRC4KILGiGFfbsk1BTZbyJbZ66m4EI5K8Do+CMI8QjHjCQNHTC/V+dGSKLrNzTN/N7tux5ihebCuGtXpwYAmz+o7IwSdpyTR8T9/+Whp4zNr3q3dlwwNMayyh+OIam7N11rdt4FkCGrV7sS9Dqxztqgw6itLB", "key"=>{"name"=>"default", "type"=>"ssh-rsa", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDM3MQ+zRV8h8DfIlTGqsiLUQaV0tsdGPz1hdu9Hw23+LMSTReu7KSN8PdPRJ6VXLy7l8OnJWb9ln56uiuydmocxc6SDB6I74HYkB7lMBMFe6qsLZkL9/W14YE2VIzg32/R1RDC06wBCx5cXs4Tvn9XKRhtENMDfY/i34z4HW0uDN8VOBZOe+8+glJhRC4KILGiGFfbsk1BTZbyJbZ66m4EI5K8Do+CMI8QjHjCQNHTC/V+dGSKLrNzTN/N7tux5ihebCuGtXpwYAmz+o7IwSdpyTR8T9/+Whp4zNr3q3dlwwNMayyh+OIam7N11rdt4FkCGrV7sS9Dqxztqgw6itLB"}} (pid:74)
>[0;37m2014-03-25 05:48:16.219[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:16.294[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:74)
>[0;37m2014-03-25 05:48:16.294[0m [[0;37mDEBUG[0m] Creating key name:default type:ssh-rsa for user qwang@redhat.com (pid:74)
>[0;37m2014-03-25 05:48:16.397[0m [[0;37mDEBUG[0m] Execute AddSshKeysUserOp (pid:74)
>[0;37m2014-03-25 05:48:16.558[0m [[0;37mDEBUG[0m] SUCCESS ACTION=ADD_KEY USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= IP=203.114.244.88 Created SSH key default (pid:74)
>[0;37m2014-03-25 05:48:16.562[0m [[32mINFO [0m] Completed 201 Created in 345ms (Views: 1.0ms) (pid:74)
>[0;37m2014-03-25 05:48:16.894[0m [[32mINFO [0m] Started GET "/broker/rest/user/keys" for 203.114.244.88 at 2014-03-25 05:48:16 -0400 (pid:74)
>[0;37m2014-03-25 05:48:16.907[0m [[32mINFO [0m] Processing by KeysController#index as JSON (pid:74)
>[0;37m2014-03-25 05:48:16.908[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:16.967[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:74)
>[0;37m2014-03-25 05:48:16.969[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_KEY USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= Found 1 ssh keys (pid:74)
>[0;37m2014-03-25 05:48:16.970[0m [[32mINFO [0m] Completed 200 OK in 63ms (Views: 1.0ms) (pid:74)
>[0;37m2014-03-25 05:48:17.258[0m [[32mINFO [0m] Started GET "/broker/rest/domains" for 203.114.244.88 at 2014-03-25 05:48:17 -0400 (pid:74)
>[0;37m2014-03-25 05:48:17.261[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:74)
>[0;37m2014-03-25 05:48:17.286[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:17.288[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:74)
>[0;37m2014-03-25 05:48:17.292[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= (pid:74)
>[0;37m2014-03-25 05:48:17.318[0m [[32mINFO [0m] Completed 200 OK in 33ms (Views: 24.8ms) (pid:74)
>[0;37m2014-03-25 05:48:19.626[0m [[32mINFO [0m] Started POST "/broker/rest/domains" for 203.114.244.88 at 2014-03-25 05:48:19 -0400 (pid:74)
>[0;37m2014-03-25 05:48:19.658[0m [[32mINFO [0m] Processing by DomainsController#create as JSON (pid:74)
>[0;37m2014-03-25 05:48:19.658[0m [[32mINFO [0m] Parameters: {"id"=>"d", "domain"=>{}} (pid:74)
>[0;37m2014-03-25 05:48:19.665[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:19.785[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:74)
>[0;37m2014-03-25 05:48:19.318[0m [[0;37mDEBUG[0m] SUCCESS ACTION=ADD_DOMAIN USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN=d Created domain with name d (pid:74)
>[0;37m2014-03-25 05:48:19.343[0m [[32mINFO [0m] Completed 201 Created in 277ms (Views: 23.4ms) (pid:74)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 05:48:19.685[0m [[32mINFO [0m] Started GET "/broker/rest/cartridges" for 203.114.244.88 at 2014-03-25 05:48:19 -0400 (pid:74)
>[0;37m2014-03-25 05:48:19.687[0m [[32mINFO [0m] Processing by CartridgesController#index as JSON (pid:74)
>[0;37m2014-03-25 05:48:19.688[0m [[0;37mDEBUG[0m] API version 1.6 (pid:74)
>[0;37m2014-03-25 05:48:19.830[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_CARTRIDGE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= Listing cartridges (pid:74)
>[0;37m2014-03-25 05:48:19.924[0m [[32mINFO [0m] Completed 200 OK in 236ms (Views: 78.1ms) (pid:74)
>[0;37m2014-03-25 05:48:32.739[0m [[0;37mDEBUG[0m] Dalli::Server#connect 172.17.42.1:11211 (pid:97)
>[0;37m2014-03-25 05:48:32.742[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:48:32 -0400 (pid:97)
>[0;37m2014-03-25 05:48:33.562[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:97)
>[0;37m2014-03-25 05:48:33.563[0m [[0;37mDEBUG[0m] API version 1.3 (pid:97)
>[0;37m2014-03-25 05:48:34.390[0m [[32mINFO [0m] Completed 200 OK in 476ms (Views: 29.4ms) (pid:97)
>[0;37m2014-03-25 05:48:34.358[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:48:34 -0400 (pid:97)
>[0;37m2014-03-25 05:48:34.361[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:97)
>[0;37m2014-03-25 05:48:34.364[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:48:34.423[0m [[32mINFO [0m] Completed 200 OK in 59ms (Views: 30.1ms) (pid:97)
>[0;37m2014-03-25 05:48:35.156[0m [[32mINFO [0m] Started GET "/broker/rest/domains" for 203.114.244.88 at 2014-03-25 05:48:35 -0400 (pid:97)
>[0;37m2014-03-25 05:48:35.181[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:97)
>[0;37m2014-03-25 05:48:35.368[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:48:35.679[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:48:35.974[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= (pid:97)
>[0;37m2014-03-25 05:48:35.129[0m [[32mINFO [0m] Completed 200 OK in 112ms (Views: 30.6ms) (pid:97)
>[0;37m2014-03-25 05:48:35.692[0m [[0;37mDEBUG[0m] Dalli::Server#connect 172.17.42.1:11212 (pid:97)
>[0;37m2014-03-25 05:48:35.695[0m [[32mINFO [0m] Started GET "/broker/rest/cartridges" for 203.114.244.88 at 2014-03-25 05:48:35 -0400 (pid:97)
>[0;37m2014-03-25 05:48:35.722[0m [[32mINFO [0m] Processing by CartridgesController#index as JSON (pid:97)
>[0;37m2014-03-25 05:48:35.723[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:48:35.989[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_CARTRIDGE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= Listing cartridges (pid:97)
>[0;37m2014-03-25 05:48:36.797[0m [[32mINFO [0m] Completed 200 OK in 357ms (Views: 64.4ms) (pid:97)
>[0;37m2014-03-25 05:48:37.267[0m [[32mINFO [0m] Started POST "/broker/rest/domain/d/applications" for 203.114.244.88 at 2014-03-25 05:48:37 -0400 (pid:97)
>[0;37m2014-03-25 05:48:37.288[0m [[32mINFO [0m] Processing by ApplicationsController#create as JSON (pid:97)
>[0;37m2014-03-25 05:48:37.288[0m [[32mINFO [0m] Parameters: {"cartridges"=>["perl-5.10"], "name"=>"perl510", "domain_id"=>"d", "application"=>{"name"=>"perl510"}} (pid:97)
>[0;37m2014-03-25 05:48:37.299[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:48:37.301[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:48:37.434[0m [[0;37mDEBUG[0m] Pre-Executing ops... (pid:97)
>[0;37m2014-03-25 05:48:37.434[0m [[0;37mDEBUG[0m] Pre-Execute InitGearOp comp_specs=[component:perl-5.10/perl-5.10/53312b7e9046e662ff000007] gear_id=533150f587b853d763000001 group_instance_id=533150f587b853d763000003 (pid:97)
>[0;37m2014-03-25 05:48:37.854[0m [[0;37mDEBUG[0m] Execute NotifyAppCreateOp (pid:97)
>[0;37m2014-03-25 05:48:37.943[0m [[0;37mDEBUG[0m] Execute ReserveGearUidOp gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:48:37.955[0m [[0;37mDEBUG[0m] DEBUG: rpc_get_fact: fact=active_capacity (pid:97)
>[0;37m2014-03-25 05:48:39.385[0m [[0;37mDEBUG[0m] DEBUG: rpc_exec: rpc_client=#<MCollective::RPC::Client:0x00000006f3e980> (pid:97)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 05:48:42.863[0m [[0;37mDEBUG[0m] Selecting best fit node: server: ip-10-146-248-66 capacity: 0.0 (pid:97)
>[0;37m2014-03-25 05:48:42.317[0m [[0;37mDEBUG[0m] Execute CreateGearOp gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:48:42.359[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-create", :args=>{"--with-app-uuid"=>"533150f587b853d763000001", "--with-app-name"=>"perl510", "--with-expose-ports"=>false, "--with-container-uuid"=>"533150f587b853d763000001", "--with-container-name"=>"perl510", "--with-namespace"=>"d", "--with-request-id"=>"12a7beb2b1a4dd2cc0553c54754d76e3", "--with-secret-token"=>"9O5dVRKMQ_13Fymud6MtsfVwSoPupOMXRXEO5LKjhb9wUW7vkDAlFlnLb4Rs5CNaoFbUs2R7kmlMOiAhOgTVxLWZbTrLCroUTIPcDlBGq2ONpoFO3ByawgBPSjIKsQcg", "--with-initial-deployment-dir"=>true, "--cart-name"=>"openshift-origin-node"}}, ip-10-146-248-66, {'identity' => ip-10-146-248-66}) (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:48:47.113[0m [[0;37mDEBUG[0m] DEBUG: [#<MCollective::RPC::Result:0x00000006ebe0a0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-146-248-66", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:48:47.422[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_direct: app-create): 4.722699953s  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:48:47.111[0m [[0;37mDEBUG[0m] Execute RegisterDnsOp gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:48:47.113[0m [[0;37mDEBUG[0m] DYNECT Login with path: /REST/Session/ (pid:97)
>[0;37m2014-03-25 05:48:49.862[0m [[0;37mDEBUG[0m] DYNECT Response: {"status": "success", "data": {"token": "P8pPJJAf9pubOPAN6xxGz0BFNlqHBqBjtnf+tAvtj94DCXUjVmyyJMkoNyX0sH3T+jx5DODkNbSVQzuRN6A8+st+OULEfGJodWvtp1HORyU3bmKyyJcsUFTILbmh8fn7jwLk5oj5xYsNaNUAsEVFDw==", "version": "3.5.6"}, "job_id": 769031366, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:97)
>[0;37m2014-03-25 05:48:49.878[0m [[0;37mDEBUG[0m] DYNECT Response data: {"token"=>"P8pPJJAf9pubOPAN6xxGz0BFNlqHBqBjtnf+tAvtj94DCXUjVmyyJMkoNyX0sH3T+jx5DODkNbSVQzuRN6A8+st+OULEfGJodWvtp1HORyU3bmKyyJcsUFTILbmh8fn7jwLk5oj5xYsNaNUAsEVFDw==", "version"=>"3.5.6"} (pid:97)
>[0;37m2014-03-25 05:48:49.888[0m [[0;37mDEBUG[0m] Dynect Response Time (dyn_login): 1.895322497s  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:48:49.159[0m [[0;37mDEBUG[0m] Public ip being configured 'ec2-54-221-21-229.compute-1.amazonaws.com' to app 'perl510' (pid:97)
>[0;37m2014-03-25 05:48:49.159[0m [[0;37mDEBUG[0m] DYNECT put/post with path: /REST/CNAMERecord/dev.rhcloud.com/perl510-d.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-54-221-21-229.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:97)
>[0;37m2014-03-25 05:48:49.816[0m [[0;37mDEBUG[0m] Retrying dyn_put_post after exception caught from DNS request: fqdn already in use. Please choose another. (pid:97)
>[0;37m2014-03-25 05:48:49.816[0m [[0;37mDEBUG[0m] DYNECT put/post with path: /REST/CNAMERecord/dev.rhcloud.com/perl510-d.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-54-221-21-229.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:97)
>[0;37m2014-03-25 05:48:50.473[0m [[0;37mDEBUG[0m] Retrying dyn_put_post after exception caught from DNS request: fqdn already in use. Please choose another. (pid:97)
>[0;37m2014-03-25 05:48:50.474[0m [[0;37mDEBUG[0m] DYNECT put/post with path: /REST/CNAMERecord/dev.rhcloud.com/perl510-d.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-54-221-21-229.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:97)
>[0;37m2014-03-25 05:48:51.141[0m [[0;37mDEBUG[0m] DNS entry already exists for perl510-d.  Attempting to modify... (pid:97)
>[0;37m2014-03-25 05:48:51.141[0m [[0;37mDEBUG[0m] Public ip being modified 'ec2-54-221-21-229.compute-1.amazonaws.com' to app 'perl510' (pid:97)
>[0;37m2014-03-25 05:48:51.141[0m [[0;37mDEBUG[0m] DYNECT put/post with path: /REST/CNAMERecord/dev.rhcloud.com/perl510-d.dev.rhcloud.com/ json data: {"rdata":{"cname":"ec2-54-221-21-229.compute-1.amazonaws.com"},"ttl":"60"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:97)
>[0;37m2014-03-25 05:48:51.782[0m [[0;37mDEBUG[0m] DYNECT Response: {"status": "success", "data": {"zone": "dev.rhcloud.com", "ttl": 60, "fqdn": "perl510-d.dev.rhcloud.com", "record_type": "CNAME", "rdata": {"cname": "ec2-54-221-21-229.compute-1.amazonaws.com."}, "record_id": 0}, "job_id": 769031447, "msgs": [{"INFO": "update: Record updated", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:97)
>[0;37m2014-03-25 05:48:51.782[0m [[0;37mDEBUG[0m] DYNECT Response data: {"zone"=>"dev.rhcloud.com", "ttl"=>60, "fqdn"=>"perl510-d.dev.rhcloud.com", "record_type"=>"CNAME", "rdata"=>{"cname"=>"ec2-54-221-21-229.compute-1.amazonaws.com."}, "record_id"=>0} (pid:97)
>[0;37m2014-03-25 05:48:51.782[0m [[0;37mDEBUG[0m] Dynect Response Time (dyn_put_post): 0.641083357s  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:48:51.782[0m [[0;37mDEBUG[0m] DYNECT put/post with path: /REST/Zone/dev.rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:97)
>[0;37m2014-03-25 05:48:52.868[0m [[0;37mDEBUG[0m] DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "day", "serial": 2014538065, "zone": "dev.rhcloud.com"}, "job_id": 769031463, "msgs": [{"INFO": "publish: dev.rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:97)
>[0;37m2014-03-25 05:48:52.868[0m [[0;37mDEBUG[0m] DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"day", "serial"=>2014538065, "zone"=>"dev.rhcloud.com"} (pid:97)
>[0;37m2014-03-25 05:48:52.868[0m [[0;37mDEBUG[0m] Dynect Response Time (dyn_put_post): 1.085955818s  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:48:52.868[0m [[0;37mDEBUG[0m] DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:97)
>[0;37m2014-03-25 05:48:53.361[0m [[0;37mDEBUG[0m] DYNECT Response: {"status": "success", "data": {}, "job_id": 769031483, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:97)
>[0;37m2014-03-25 05:48:53.361[0m [[0;37mDEBUG[0m] DYNECT Response data: {} (pid:97)
>[0;37m2014-03-25 05:48:53.361[0m [[0;37mDEBUG[0m] Dynect Response Time (dyn_delete): 0.492537915s  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:48:53.455[0m [[0;37mDEBUG[0m] Execute UpdateAppConfigOp gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:48:53.484[0m [[0;37mDEBUG[0m] Execute AddBrokerAuthKeyOp gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:48:53.533[0m [[0;37mDEBUG[0m] Execute AddCompOp comp_spec=component:perl-5.10/perl-5.10/53312b7e9046e662ff000007 gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:48:53.582[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"perl-5.10", :action=>"configure", :args=>{"--with-app-uuid"=>"533150f587b853d763000001", "--with-app-name"=>"perl510", "--with-expose-ports"=>false, "--with-container-uuid"=>"533150f587b853d763000001", "--with-container-name"=>"perl510", "--with-namespace"=>"d", "--with-request-id"=>"12a7beb2b1a4dd2cc0553c54754d76e3", "--component-name"=>"perl-5.10", "--cart-name"=>"perl-5.10", "--with-software-version"=>"5.10", "--cartridge-vendor"=>"redhat"}}, ip-10-146-248-66, {'identity' => ip-10-146-248-66}) (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:49:00.557[0m [[0;37mDEBUG[0m] DEBUG: [#<MCollective::RPC::Result:0x000000016f9fe0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-146-248-66", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:49:00.567[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_direct: configure): 7.033608851s  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:49:01.341[0m [[0;37mDEBUG[0m] DEBUG: Output of parallel execute: [{:tag=>{"op_id"=>"533150f587b853d76300000b"}, :gear=>"533150f587b853d763000001", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-batch-add", :args=>{"--with-app-uuid"=>"533150f587b853d763000001", "--with-app-name"=>"perl510", "--with-expose-ports"=>false, "--with-container-uuid"=>"533150f587b853d763000001", "--with-container-name"=>"perl510", "--with-namespace"=>"d", "--with-request-id"=>"12a7beb2b1a4dd2cc0553c54754d76e3", "--with-ssh-keys"=>[{"key"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDM3MQ+zRV8h8DfIlTGqsiLUQaV0tsdGPz1hdu9Hw23+LMSTReu7KSN8PdPRJ6VXLy7l8OnJWb9ln56uiuydmocxc6SDB6I74HYkB7lMBMFe6qsLZkL9/W14YE2VIzg32/R1RDC06wBCx5cXs4Tvn9XKRhtENMDfY/i34z4HW0uDN8VOBZOe+8+glJhRC4KILGiGFfbsk1BTZbyJbZ66m4EI5K8Do+CMI8QjHjCQNHTC/V+dGSKLrNzTN/N7tux5ihebCuGtXpwYAmz+o7IwSdpyTR8T9/+Whp4zNr3q3dlwwNMayyh+OIam7N11rdt4FkCGrV7sS9Dqxztqgw6itLB", "type"=>"ssh-rsa", "comment"=>"533150d787b8539f8b000001-default", "content"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDM3MQ+zRV8h8DfIlTGqsiLUQaV0tsdGPz1hdu9Hw23+LMSTReu7KSN8PdPRJ6VXLy7l8OnJWb9ln56uiuydmocxc6SDB6I74HYkB7lMBMFe6qsLZkL9/W14YE2VIzg32/R1RDC06wBCx5cXs4Tvn9XKRhtENMDfY/i34z4HW0uDN8VOBZOe+8+glJhRC4KILGiGFfbsk1BTZbyJbZ66m4EI5K8Do+CMI8QjHjCQNHTC/V+dGSKLrNzTN/N7tux5ihebCuGtXpwYAmz+o7IwSdpyTR8T9/+Whp4zNr3q3dlwwNMayyh+OIam7N11rdt4FkCGrV7sS9Dqxztqgw6itLB"}]}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"533150f587b853d76300000b"}, :gear=>"533150f587b853d763000001", :job=>{:cartridge=>"openshift-origin-node", :action=>"update-configuration", :args=>{"--with-app-uuid"=>"533150f587b853d763000001", "--with-app-name"=>"perl510", "--with-expose-ports"=>false, "--with-container-uuid"=>"533150f587b853d763000001", "--with-container-name"=>"perl510", "--with-namespace"=>"d", "--with-request-id"=>"12a7beb2b1a4dd2cc0553c54754d76e3", "--with-config"=>{"auto_deploy"=>true, "deployment_branch"=>"master", "keep_deployments"=>1, "deployment_type"=>"git"}}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"533150f587b853d76300000c"}, :gear=>"533150f587b853d763000001", :job=>{:cartridge=>"openshift-origin-node", :action=>"broker-auth-key-add", :args=>{"--with-app-uuid"=>"533150f587b853d763000001", "--with-app-name"=>"perl510", "--with-expose-ports"=>false, "--with-container-uuid"=>"533150f587b853d763000001", "--with-container-name"=>"perl510", "--with-namespace"=>"d", "--with-request-id"=>"12a7beb2b1a4dd2cc0553c54754d76e3", "--with-iv"=>"X3a01lGDiEzptEkNsP9OPonO7bcjyLY1B0D5iud0gluWuj9dpJ+S3iCXlgP8\n+v2Z//IisLlj04w7KsoFHBTi02Yww2Y5pmv9ozRHYXzsmxChFp4kMm9vQEdU\nfIrwkq1dbVujOlr8YHzdjIDf9SLXO18Roqd4pvu0oND+t2UX2/8halr+ffm/\nL9j/KyRuTvSzy6wdbTW+Qprq/QHNvoRjv4Eug9/kHwmI9lGMPzFJmirIjzP3\nW7S95tz3/c9gKgzFnzmZDAEP6j4PdfTky8WVVGZYb1QZb03EkewpLDTRFpDd\nl5v8dMEb1kNkZWKbX/Bk35RzXTsO4KgnsHVEmvuIXA==\n", "--with-token"=>"mMrenS38r9ZD3GToCLNjgMj3bzFayJV0i8rj3sSrfqHQ6l0bPfZ1nyPF49YF\nkVx5nS7BW+ti9aWf4zuH6Uwg9zidRAVRY+a0V5gCUJei4to=\n"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-146-248-66  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:49:01.373[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_parallel): 801ms  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:49:01.670[0m [[0;37mDEBUG[0m] Execute ExecuteConnectionsOp (pid:97)
>[0;37m2014-03-25 05:49:01.769[0m [[0;37mDEBUG[0m] Execute PostConfigureCompOp comp_spec=component:perl-5.10/perl-5.10/53312b7e9046e662ff000007 gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:49:01.905[0m [[0;37mDEBUG[0m] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"perl-5.10", :action=>"post-configure", :args=>{"--with-app-uuid"=>"533150f587b853d763000001", "--with-app-name"=>"perl510", "--with-expose-ports"=>false, "--with-container-uuid"=>"533150f587b853d763000001", "--with-container-name"=>"perl510", "--with-namespace"=>"d", "--with-request-id"=>"12a7beb2b1a4dd2cc0553c54754d76e3", "--component-name"=>"perl-5.10", "--cart-name"=>"perl-5.10", "--with-software-version"=>"5.10", "--cartridge-vendor"=>"redhat"}}, ip-10-146-248-66, {'identity' => ip-10-146-248-66}) (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>Development configuration for openshift-origin-admin-console not found. Using production configuration.
>[0;37m2014-03-25 05:49:25.975[0m [[0;37mDEBUG[0m] DEBUG: [#<MCollective::RPC::Result:0x00000006fa0cc0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ip-10-146-248-66", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"Starting Perl cartridge\nApplication directory \"/\" selected as DocumentRoot\n", :exitcode=>0, :addtl_params=>{:deployments=>[{:id=>"ddb6a359", :ref=>"master", :sha1=>"988f401", :force_clean_build=>nil, :hot_deploy=>nil, :created_at=>1395740925.1260002, :activations=>[1395740945.1036835]}]}}}>] (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:49:25.975[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_direct: post-configure): 24.1476549s  (Request ID: 12a7beb2b1a4dd2cc0553c54754d76e3) (pid:97)
>[0;37m2014-03-25 05:49:25.993[0m [[0;37mDEBUG[0m] Execute TrackUsageOp gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:49:26.785[0m [[0;37mDEBUG[0m] SUCCESS ACTION=ADD_APPLICATION USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com APP_UUID=533150f587b853d763000001 DOMAIN=d Application perl510 was created. (pid:97)
>[0;37m2014-03-25 05:49:26.964[0m [[32mINFO [0m] Completed 201 Created in 48808ms (Views: 16.0ms) (pid:97)
>[0;37m2014-03-25 05:49:27.644[0m [[32mINFO [0m] Started GET "/broker/rest/user" for 203.114.244.88 at 2014-03-25 05:49:27 -0400 (pid:97)
>[0;37m2014-03-25 05:49:27.775[0m [[32mINFO [0m] Processing by UserExtController#show as JSON (pid:97)
>[0;37m2014-03-25 05:49:27.775[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:49:27.786[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:49:27.787[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_USER USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= (pid:97)
>[0;37m2014-03-25 05:49:27.790[0m [[32mINFO [0m] Completed 200 OK in 15ms (Views: 1.2ms) (pid:97)
>[0;37m2014-03-25 05:49:28.894[0m [[32mINFO [0m] Started GET "/broker/rest/user/keys" for 203.114.244.88 at 2014-03-25 05:49:28 -0400 (pid:97)
>[0;37m2014-03-25 05:49:28.921[0m [[32mINFO [0m] Processing by KeysController#index as JSON (pid:97)
>[0;37m2014-03-25 05:49:28.927[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:49:28.953[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:49:28.966[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_KEY USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= Found 1 ssh keys (pid:97)
>[0;37m2014-03-25 05:49:28.107[0m [[32mINFO [0m] Completed 200 OK in 15ms (Views: 9.2ms) (pid:97)
>[0;37m2014-03-25 05:50:51.104[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:50:51 -0400 (pid:97)
>[0;37m2014-03-25 05:50:51.105[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:97)
>[0;37m2014-03-25 05:50:51.105[0m [[0;37mDEBUG[0m] API version 1.3 (pid:97)
>[0;37m2014-03-25 05:50:51.126[0m [[32mINFO [0m] Completed 200 OK in 21ms (Views: 4.8ms) (pid:97)
>[0;37m2014-03-25 05:50:51.452[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:50:51 -0400 (pid:97)
>[0;37m2014-03-25 05:50:51.453[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:97)
>[0;37m2014-03-25 05:50:51.453[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:50:51.474[0m [[32mINFO [0m] Completed 200 OK in 21ms (Views: 17.6ms) (pid:97)
>[0;37m2014-03-25 05:50:52.419[0m [[32mINFO [0m] Started GET "/broker/rest/domains" for 203.114.244.88 at 2014-03-25 05:50:52 -0400 (pid:97)
>[0;37m2014-03-25 05:50:52.421[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:97)
>[0;37m2014-03-25 05:50:52.421[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:50:52.425[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:50:52.437[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= (pid:97)
>[0;37m2014-03-25 05:50:52.442[0m [[32mINFO [0m] Completed 200 OK in 21ms (Views: 4.1ms) (pid:97)
>[0;37m2014-03-25 05:50:53.341[0m [[32mINFO [0m] Started GET "/broker/rest/domains/d/applications/perl510" for 203.114.244.88 at 2014-03-25 05:50:53 -0400 (pid:97)
>[0;37m2014-03-25 05:50:53.397[0m [[32mINFO [0m] Processing by ApplicationsController#show as JSON (pid:97)
>[0;37m2014-03-25 05:50:53.398[0m [[32mINFO [0m] Parameters: {"domain_id"=>"d", "id"=>"perl510"} (pid:97)
>[0;37m2014-03-25 05:50:53.509[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:50:53.535[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:50:53.692[0m [[0;37mDEBUG[0m] SUCCESS ACTION=SHOW_APPLICATION USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com APP_UUID=533150f587b853d763000001 DOMAIN=d Application 'perl510' found (pid:97)
>[0;37m2014-03-25 05:50:53.901[0m [[32mINFO [0m] Completed 200 OK in 50ms (Views: 19.8ms) (pid:97)
>[0;37m2014-03-25 05:50:53.690[0m [[32mINFO [0m] Started POST "/broker/rest/application/533150f587b853d763000001/events" for 203.114.244.88 at 2014-03-25 05:50:53 -0400 (pid:97)
>[0;37m2014-03-25 05:50:53.694[0m [[32mINFO [0m] Processing by AppEventsController#create as JSON (pid:97)
>[0;37m2014-03-25 05:50:53.694[0m [[32mINFO [0m] Parameters: {"event"=>"force-stop", "application_id"=>"533150f587b853d763000001", "app_event"=>{"event"=>"force-stop"}} (pid:97)
>[0;37m2014-03-25 05:50:53.694[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:50:53.697[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:50:53.731[0m [[0;37mDEBUG[0m] Execute StopCompOp comp_spec=component:perl-5.10/perl-5.10/ gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:50:54.698[0m [[0;37mDEBUG[0m] DEBUG: Output of parallel execute: [{:tag=>{"op_id"=>"5331517d87b853d763000016"}, :gear=>"533150f587b853d763000001", :job=>{:cartridge=>"openshift-origin-node", :action=>"force-stop", :args=>{"--with-app-uuid"=>"533150f587b853d763000001", "--with-app-name"=>"perl510", "--with-expose-ports"=>false, "--with-container-uuid"=>"533150f587b853d763000001", "--with-container-name"=>"perl510", "--with-namespace"=>"d", "--with-request-id"=>"8c61091ca8d2154492007f95b4672e0d", "--component-name"=>"perl-5.10", "--cart-name"=>"perl-5.10", "--with-software-version"=>"5.10", "--cartridge-vendor"=>"redhat"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-146-248-66  (Request ID: 8c61091ca8d2154492007f95b4672e0d) (pid:97)
>[0;37m2014-03-25 05:50:54.699[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_parallel): 966ms  (Request ID: 8c61091ca8d2154492007f95b4672e0d) (pid:97)
>[0;37m2014-03-25 05:50:54.762[0m [[0;37mDEBUG[0m] SUCCESS ACTION=FORCE_STOP_APPLICATION USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com APP_UUID=533150f587b853d763000001 DOMAIN=d Application perl510 has forcefully stopped (pid:97)
>[0;37m2014-03-25 05:50:54.878[0m [[32mINFO [0m] Completed 200 OK in 1184ms (Views: 114.8ms) (pid:97)
>[0;37m2014-03-25 05:52:03.198[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:52:03 -0400 (pid:97)
>[0;37m2014-03-25 05:52:03.210[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:97)
>[0;37m2014-03-25 05:52:03.210[0m [[0;37mDEBUG[0m] API version 1.3 (pid:97)
>[0;37m2014-03-25 05:52:03.243[0m [[32mINFO [0m] Completed 200 OK in 33ms (Views: 29.4ms) (pid:97)
>[0;37m2014-03-25 05:52:03.551[0m [[32mINFO [0m] Started GET "/broker/rest/api" for 203.114.244.88 at 2014-03-25 05:52:03 -0400 (pid:97)
>[0;37m2014-03-25 05:52:03.553[0m [[32mINFO [0m] Processing by ApiController#show as JSON (pid:97)
>[0;37m2014-03-25 05:52:03.553[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:52:03.612[0m [[32mINFO [0m] Completed 200 OK in 60ms (Views: 31.2ms) (pid:97)
>[0;37m2014-03-25 05:52:04.208[0m [[32mINFO [0m] Started GET "/broker/rest/domains" for 203.114.244.88 at 2014-03-25 05:52:04 -0400 (pid:97)
>[0;37m2014-03-25 05:52:04.209[0m [[32mINFO [0m] Processing by DomainsController#index as JSON (pid:97)
>[0;37m2014-03-25 05:52:04.209[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:52:04.251[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:52:04.255[0m [[0;37mDEBUG[0m] SUCCESS ACTION=LIST_DOMAIN USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com DOMAIN= (pid:97)
>[0;37m2014-03-25 05:52:04.271[0m [[32mINFO [0m] Completed 200 OK in 62ms (Views: 14.5ms) (pid:97)
>[0;37m2014-03-25 05:52:04.856[0m [[32mINFO [0m] Started GET "/broker/rest/domains/d/applications/perl510" for 203.114.244.88 at 2014-03-25 05:52:04 -0400 (pid:97)
>[0;37m2014-03-25 05:52:04.874[0m [[32mINFO [0m] Processing by ApplicationsController#show as JSON (pid:97)
>[0;37m2014-03-25 05:52:04.874[0m [[32mINFO [0m] Parameters: {"domain_id"=>"d", "id"=>"perl510"} (pid:97)
>[0;37m2014-03-25 05:52:04.874[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:52:04.893[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:52:04.947[0m [[0;37mDEBUG[0m] SUCCESS ACTION=SHOW_APPLICATION USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com APP_UUID=533150f587b853d763000001 DOMAIN=d Application 'perl510' found (pid:97)
>[0;37m2014-03-25 05:52:04.994[0m [[32mINFO [0m] Completed 200 OK in 120ms (Views: 18.3ms) (pid:97)
>[0;37m2014-03-25 05:52:05.319[0m [[32mINFO [0m] Started POST "/broker/rest/application/533150f587b853d763000001/events" for 203.114.244.88 at 2014-03-25 05:52:05 -0400 (pid:97)
>[0;37m2014-03-25 05:52:05.322[0m [[32mINFO [0m] Processing by AppEventsController#create as JSON (pid:97)
>[0;37m2014-03-25 05:52:05.324[0m [[32mINFO [0m] Parameters: {"event"=>"restart", "application_id"=>"533150f587b853d763000001", "app_event"=>{"event"=>"restart"}} (pid:97)
>[0;37m2014-03-25 05:52:05.324[0m [[0;37mDEBUG[0m] API version 1.6 (pid:97)
>[0;37m2014-03-25 05:52:05.340[0m [[0;37mDEBUG[0m] SUCCESS ACTION=AUTHENTICATE USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com IP=203.114.244.88 SCOPES=session Authenticated (pid:97)
>[0;37m2014-03-25 05:52:05.444[0m [[0;37mDEBUG[0m] Execute RestartCompOp comp_spec=component:perl-5.10/perl-5.10/53312b7e9046e662ff000007 gear_id=533150f587b853d763000001 (pid:97)
>[0;37m2014-03-25 05:52:06.524[0m [[0;37mDEBUG[0m] DEBUG: Output of parallel execute: [{:tag=>{"op_id"=>"533151c587b853d763000019"}, :gear=>"533150f587b853d763000001", :job=>{:cartridge=>"openshift-origin-node", :action=>"restart", :args=>{"--with-app-uuid"=>"533150f587b853d763000001", "--with-app-name"=>"perl510", "--with-expose-ports"=>false, "--with-container-uuid"=>"533150f587b853d763000001", "--with-container-name"=>"perl510", "--with-namespace"=>"d", "--with-request-id"=>"a871f2b873ca3405d68b291e7738273f", "--component-name"=>"perl-5.10", "--cart-name"=>"perl-5.10", "--with-software-version"=>"5.10", "--cartridge-vendor"=>"redhat", "--all"=>false, "--parallel_concurrency_ratio"=>0.5}}, :result_stdout=>"CLIENT_ERROR: Failed to execute: 'control restart' for /var/lib/openshift/533150f587b853d763000001/perl\nRestarting Perl cartridge\n\n/var/lib/openshift/533150f587b853d763000001/perl/bin/control: line 46: kill: (4301) - No such process\n", :result_stderr=>"", :result_exit_code=>1, :result_addtl_params=>nil}], exitcode: 0, from: ip-10-146-248-66  (Request ID: a871f2b873ca3405d68b291e7738273f) (pid:97)
>[0;37m2014-03-25 05:52:06.525[0m [[0;37mDEBUG[0m] DEBUG: MCollective Response Time (execute_parallel): 1079ms  (Request ID: a871f2b873ca3405d68b291e7738273f) (pid:97)
>[0;37m2014-03-25 05:52:06.525[0m [[0;37mDEBUG[0m] DEBUG: server results: Restarting Perl cartridge (pid:97)
>[0;37m2014-03-25 05:52:06.525[0m [[0;37mDEBUG[0m] DEBUG: server results: (pid:97)
>[0;37m2014-03-25 05:52:06.525[0m [[0;37mDEBUG[0m] DEBUG: server results: /var/lib/openshift/533150f587b853d763000001/perl/bin/control: line 46: kill: (4301) - No such process (pid:97)
>[0;37m2014-03-25 05:52:06.544[0m [[31mERROR[0m] Failed to execute: 'control restart' for /var/lib/openshift/533150f587b853d763000001/perl (pid:97)
>[0;37m2014-03-25 05:52:06.544[0m [[31mERROR[0m] /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/pending_app_op_group.rb:137:in `execute'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/application.rb:1687:in `run_jobs'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/application.rb:1150:in `block in restart'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/lock.rb:62:in `run_in_app_lock'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/application.rb:1141:in `restart'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/controllers/app_events_controller.rb:69: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:492:in `block in _run__1036214511544273037__process_action__2251990439460692997__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_564'
>/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/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_564'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:403:in `_run__1036214511544273037__process_action__2251990439460692997__callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
>/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
>/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `block in call'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'
>/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'
>/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'
>/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
>/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__462025206399411768__call__301513175508247046__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:97)
>[0;37m2014-03-25 05:52:06.544[0m [[31mERROR[0m] Encountered error during execute 'Failed to execute: 'control restart' for /var/lib/openshift/533150f587b853d763000001/perl
>' (pid:97)
>[0;37m2014-03-25 05:52:06.545[0m [[0;37mDEBUG[0m] ["/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/pending_app_op_group.rb:137:in `execute'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/application.rb:1687:in `run_jobs'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/application.rb:1150:in `block in restart'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/lock.rb:62:in `run_in_app_lock'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/models/application.rb:1141:in `restart'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/app/controllers/app_events_controller.rb:69: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:492:in `block in _run__1036214511544273037__process_action__2251990439460692997__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_564'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-controller-1.22.5/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_564'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:403:in `_run__1036214511544273037__process_action__2251990439460692997__callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'", "/opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongo_mapper-0.11.1/lib/mongo_mapper/middleware/identity_map.rb:10:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/mongoid/unit_of_work.rb:39:in `unit_of_work'", "/opt/rh/ruby193/root/usr/share/gems/gems/mongoid-3.0.21/lib/rack/mongoid/middleware/identity_map.rb:34:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'", "/opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'", "/opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__462025206399411768__call__301513175508247046__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:97)
>[0;37m2014-03-25 05:52:06.562[0m [[0;37mDEBUG[0m] SUCCESS ACTION=RESTART_APPLICATION USER_ID=533150d787b8539f8b000001 LOGIN=qwang@redhat.com APP_UUID=533150f587b853d763000001 DOMAIN=d Failed to execute: 'control restart' for /var/lib/openshift/533150f587b853d763000001/perl Restarting Perl cartridge
>
>/var/lib/openshift/533150f587b853d763000001/perl/bin/control: line 46: kill: (4301) - No such process
>, Failed to execute: 'control restart' for /var/lib/openshift/533150f587b853d763000001/perl (pid:97)
>[0;37m2014-03-25 05:52:06.563[0m [[32mINFO [0m] Completed 422 Unprocessable Entity in 1239ms (Views: 0.5ms) (pid:97)

Actions: View

Attachments on bug 1080381: 878378 | 878379 | 878380