cf push failed and the cc.log shows that

cloud-controller.log

{"timestamp":1381885621.9957068,"message":"request: sid=11 response='{:app_id=>\"0a4f783d-465a-4b54-b58d-66132d46e018\", :task_id=>\"b9e4240e2cb092044f4c7326f7e1543c\", :properties=>{:services=>[], :buildpack=>nil, :resources=>{:memory=>64, :disk=>1024, :fds=>16384}, :environment=>[], :meta=>{\"command\"=>\"ruby env.rb\", \"console\"=>true}}, :download_uri=>\"http://zxsfhgjg:[email protected]:8181/staging/apps/0a4f783d-465a-4b54-b58d-66132d46e018\", :upload_uri=>\"http://zxsfhgjg:[email protected]:8181/staging/droplets/0a4f783d-465a-4b54-b58d-66132d46e018/upload\", :buildpack_cache_download_uri=>\"http://zxsfhgjg:[email protected]:8181/staging/buildpack_cache/0a4f783d-465a-4b54-b58d-66132d46e018/download\", :buildpack_cache_upload_uri=>\"http://zxsfhgjg:[email protected]:8181/staging/buildpack_cache/0a4f783d-465a-4b54-b58d-66132d46e018/upload\", :async=>false}'","log_level":"info","source":"MultiResponseMessageBusRequest","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":25,"method":"request"}
{"timestamp":1381885621.995998,"message":"timeout_request: sid=11 timeout=480","log_level":"info","source":"MultiResponseMessageBusRequest","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":55,"method":"timeout_request"}
{"timestamp":1381885622.0869346,"message":"Token received from the UAA nil","log_level":"info","source":"cc.api","data":{"request_guid":"7e10f48f-ed65-4d67-bf77-b2222ae00960"},"thread_id":13659480,"fiber_id":70164632613880,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller.rb","lineno":71,"method":"decode_token"}
{"timestamp":1381885622.1489801,"message":"dispatch VCAP::CloudController::Staging get /staging/apps/:guid","log_level":"debug","source":"cc.api","data":{"request_guid":"7e10f48f-ed65-4d67-bf77-b2222ae00960"},"thread_id":13659480,"fiber_id":70164632613880,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/routes.rb","lineno":13,"method":"block in define_route"}
{"timestamp":1381885622.1496627,"message":"parse_params: {}","log_level":"debug","source":"cc.api","data":{"request_guid":"7e10f48f-ed65-4d67-bf77-b2222ae00960"},"thread_id":13659480,"fiber_id":70164632613880,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/base.rb","lineno":57,"method":"parse_params"}
{"timestamp":1381885622.1503813,"message":"dispatch: download_app","log_level":"debug","source":"cc.api","data":{"request_guid":"7e10f48f-ed65-4d67-bf77-b2222ae00960"},"thread_id":13659480,"fiber_id":70164632613880,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/base.rb","lineno":94,"method":"dispatch"}
{"timestamp":1381885622.145065,"message":"Token received from the UAA nil","log_level":"info","source":"cc.api","data":{"request_guid":"4df90592-2706-4a54-a426-e475508443f7"},"thread_id":28453740,"fiber_id":34098180,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller.rb","lineno":71,"method":"decode_token"}
{"timestamp":1381885622.158459,"message":"dispatch VCAP::CloudController::Staging get /staging/buildpack_cache/:guid/download","log_level":"debug","source":"cc.api","data":{"request_guid":"4df90592-2706-4a54-a426-e475508443f7"},"thread_id":28453740,"fiber_id":34098180,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/routes.rb","lineno":13,"method":"block in define_route"}
{"timestamp":1381885622.157513,"message":"(0.004777s) SELECT * FROM apps WHERE ((not_deleted = 't') AND (guid = '0a4f783d-465a-4b54-b58d-66132d46e018')) LIMIT 1","log_level":"debug2","source":"cc.db","data":{"request_guid":"7e10f48f-ed65-4d67-bf77-b2222ae00960"},"thread_id":13659480,"fiber_id":70164632613880,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381885622.1600475,"message":"guid: 0a4f783d-465a-4b54-b58d-66132d46e018 package_path: /tmp/cc-app-packages/0a/4f/0a4f783d-465a-4b54-b58d-66132d46e018","log_level":"debug","source":"cc.api","data":{"request_guid":"7e10f48f-ed65-4d67-bf77-b2222ae00960"},"thread_id":13659480,"fiber_id":70164632613880,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/api/staging.rb","lineno":275,"method":"download_app"}
{"timestamp":1381885622.1607409,"message":"send_file /tmp/cc-app-packages/0a/4f/0a4f783d-465a-4b54-b58d-66132d46e018","log_level":"debug","source":"cc.api","data":{"request_guid":"7e10f48f-ed65-4d67-bf77-b2222ae00960"},"thread_id":13659480,"fiber_id":70164632613880,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/api/staging.rb","lineno":287,"method":"download_app"}
{"timestamp":1381885622.15885,"message":"parse_params: {}","log_level":"debug","source":"cc.api","data":{"request_guid":"4df90592-2706-4a54-a426-e475508443f7"},"thread_id":28453740,"fiber_id":34098180,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/base.rb","lineno":57,"method":"parse_params"}
{"timestamp":1381885622.224158,"message":"dispatch: download_buildpack_cache","log_level":"debug","source":"cc.api","data":{"request_guid":"4df90592-2706-4a54-a426-e475508443f7"},"thread_id":28453740,"fiber_id":34098180,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/base.rb","lineno":94,"method":"dispatch"}
{"timestamp":1381885622.226726,"message":"(0.001654s) SELECT * FROM apps WHERE ((not_deleted = 't') AND (guid = '0a4f783d-465a-4b54-b58d-66132d46e018')) LIMIT 1","log_level":"debug2","source":"cc.db","data":{"request_guid":"4df90592-2706-4a54-a426-e475508443f7"},"thread_id":28453740,"fiber_id":34098180,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381885622.230591,"message":"guid: 0a4f783d-465a-4b54-b58d-66132d46e018 droplet_path ","log_level":"debug","source":"cc.api","data":{"request_guid":"4df90592-2706-4a54-a426-e475508443f7"},"thread_id":28453740,"fiber_id":34098180,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/api/staging.rb","lineno":329,"method":"download"}
{"timestamp":1381885622.2309008,"message":"could not find droplet for 0a4f783d-465a-4b54-b58d-66132d46e018","log_level":"error","source":"cc.api","data":{"request_guid":"4df90592-2706-4a54-a426-e475508443f7"},"thread_id":28453740,"fiber_id":34098180,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/api/staging.rb","lineno":332,"method":"download"}
{"timestamp":1381885622.231484,"message":"Request failed with response code: 400 error code: 170001 error: Staging error: droplet not found for 0a4f783d-465a-4b54-b58d-66132d46e018","log_level":"debug","source":"cc.api","data":{"request_guid":"4df90592-2706-4a54-a426-e475508443f7"},"thread_id":28453740,"fiber_id":34098180,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/sinatra/vcap.rb","lineno":63,"method":"block in registered"}
{"timestamp":1381886102.0033169,"message":"unsubscribe: sid=11","log_level":"info","source":"MultiResponseMessageBusRequest","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":65,"method":"unsubscribe"}
{"timestamp":1381886102.008127,"message":"(0.001702s) SELECT COUNT(*) AS 'count' FROM apps WHERE ((not_deleted = 't') AND (not_deleted = 't') AND (space_id = 1) AND (name = 'rubss') AND (id != 2)) LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381886102.0098677,"message":"(0.000639s) SELECT sum((memory * instances)) FROM apps WHERE ((not_deleted = 't') AND (apps.space_id IN (1))) LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381886102.0119693,"message":"(0.001571s) SELECT * FROM apps WHERE ((not_deleted = 't') AND (guid = '0a4f783d-465a-4b54-b58d-66132d46e018')) LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381886102.0123482,"message":"(0.000052s) BEGIN","log_level":"debug2","source":"cc.db","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381886102.02935,"message":"(0.016081s) UPDATE apps SET guid = '0a4f783d-465a-4b54-b58d-66132d46e018', created_at = '2013-10-16 09:06:55.895481+0800', updated_at = '2013-10-16 09:15:02.012631+0800', name = 'rubss', production = 'f', environment_json = '{}', memory = 64, instances = 1, file_descriptors = 16384, disk_quota = 1024, state = 'STARTED', package_state = 'FAILED', package_hash = '07f481e96ef802428cc7a9a365a50959b9e32c40', droplet_hash = NULL, version = 'aec190ed-052c-4bdd-86ef-a730dee5fbe5', metadata = '{\"command\":\"ruby env.rb\",\"console\":true}', buildpack = NULL, space_id = 1, stack_id = 1, detected_buildpack = NULL, staging_task_id = 'b9e4240e2cb092044f4c7326f7e1543c', kill_after_multiple_restarts = 'f', deleted_at = NULL, not_deleted = 't' WHERE ((not_deleted = 't') AND (id = 2))","log_level":"debug2","source":"cc.db","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381886102.0323954,"message":"(0.002675s) COMMIT","log_level":"debug2","source":"cc.db","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381886102.0340915,"message":"exception handling first response #, backtrace: /vagrant/cloud_controller_ng/lib/cloud_controller/app_stager_task.rb:150:in check_staging_error!'\n/vagrant/cloud_controller_ng/lib/cloud_controller/app_stager_task.rb:92:inhandle_first_response'\n/vagrant/cloud_controller_ng/lib/cloud_controller/app_stager_task.rb:62:in block (2 levels) in stage'\n/vagrant/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:48:incall'\n/vagrant/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:48:in trigger_on_response'\n/vagrant/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:59:inblock in timeout_request'\n/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/eventmachine-1.0.3/lib/eventmachine.rb:187:in call'\n/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/eventmachine-1.0.3/lib/eventmachine.rb:187:inrun_machine'\n/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/eventmachine-1.0.3/lib/eventmachine.rb:187:in run'\n/vagrant/cloud_controller_ng/lib/cloud_controller/runner.rb:92:inrun!'\n/vagrant/cloud_controller_ng/bin/cloud_controller:11:in <main>'","log_level":"error","source":"cc.app_stager","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/app_stager_task.rb","lineno":103,"method":"rescue in handle_first_response"}
{"timestamp":1381886102.0360663,"message":"(0.001141s) SELECT * FROMappsWHERE ((not_deleted= 't') AND (id= 2)) LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":13733880,"fiber_id":33069800,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381886102.0372305,"message":"Request failed with response code: 400 error code: 170001 error: Staging error: failed to stage application:\n","log_level":"debug","source":"cc.api","data":{"request_guid":"ddbd44af-522b-43b0-aa00-dc233b2f76f8"},"thread_id":13703460,"fiber_id":70164800831200,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/sinatra/vcap.rb","lineno":63,"method":"block in registered"}
{"timestamp":1381887767.2149117,"message":"Token received from the UAA nil","log_level":"info","source":"cc.api","data":{"request_guid":"6bbf00a9-199a-4e64-b910-608a886f0510"},"thread_id":31012600,"fiber_id":70164801100540,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller.rb","lineno":71,"method":"decode_token"}
{"timestamp":1381887767.215963,"message":"dispatch VCAP::CloudController::Staging get /staging/buildpack_cache/:guid/download","log_level":"debug","source":"cc.api","data":{"request_guid":"6bbf00a9-199a-4e64-b910-608a886f0510"},"thread_id":31012600,"fiber_id":70164801100540,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/routes.rb","lineno":13,"method":"block in define_route"}
{"timestamp":1381887767.216248,"message":"parse_params: {}","log_level":"debug","source":"cc.api","data":{"request_guid":"6bbf00a9-199a-4e64-b910-608a886f0510"},"thread_id":31012600,"fiber_id":70164801100540,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/base.rb","lineno":57,"method":"parse_params"}
{"timestamp":1381887767.2165,"message":"dispatch: download_buildpack_cache","log_level":"debug","source":"cc.api","data":{"request_guid":"6bbf00a9-199a-4e64-b910-608a886f0510"},"thread_id":31012600,"fiber_id":70164801100540,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/rest_controller/base.rb","lineno":94,"method":"dispatch"}
{"timestamp":1381887767.22069,"message":"(0.003333s) SELECT * FROMappsWHERE ((not_deleted= 't') AND (guid` = '0a4f783d-465a-4b54-b58d-66132d46e018')) LIMIT 1","log_level":"debug2","source":"cc.db","data":{"request_guid":"6bbf00a9-199a-4e64-b910-608a886f0510"},"thread_id":31012600,"fiber_id":70164801100540,"process_id":27247,"file":"/home/vagrant/.rbenv/versions/1.9.3-p392/lib/ruby/gems/1.9.1/gems/sequel-3.45.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1381887767.2214656,"message":"guid: 0a4f783d-465a-4b54-b58d-66132d46e018 droplet_path ","log_level":"debug","source":"cc.api","data":{"request_guid":"6bbf00a9-199a-4e64-b910-608a886f0510"},"thread_id":31012600,"fiber_id":70164801100540,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/api/staging.rb","lineno":329,"method":"download"}
{"timestamp":1381887767.2217045,"message":"could not find droplet for 0a4f783d-465a-4b54-b58d-66132d46e018","log_level":"error","source":"cc.api","data":{"request_guid":"6bbf00a9-199a-4e64-b910-608a886f0510"},"thread_id":31012600,"fiber_id":70164801100540,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller/api/staging.rb","lineno":332,"method":"download"}
{"timestamp":1381887767.2220852,"message":"Request failed with response code: 400 error code: 170001 error: Staging error: droplet not found for 0a4f783d-465a-4b54-b58d-66132d46e018","log_level":"debug","source":"cc.api","data":{"request_guid":"6bbf00a9-199a-4e64-b910-608a886f0510"},"thread_id":31012600,"fiber_id":70164801100540,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/sinatra/vcap.rb","lineno":63,"method":"block in registered"}
{"timestamp":1381887767.458728,"message":"Token received from the UAA nil","log_level":"info","source":"cc.api","data":{"request_guid":"50a335a5-5836-43c1-bf1a-09bc85167365"},"thread_id":30353800,"fiber_id":70164700262000,"process_id":27247,"file":"/vagrant/cloud_controller_ng/lib/cloud_controller.rb","lineno":71,"method":"decode_token"}