gistfile1.txt

{"timestamp":1456847530.5810566,"message":"request: sid=11 response='{:app_id=>\"a760f148-4b00-4f43-aa3d-e19d5fc3d4d0\", :stack=>\"cflinuxfs2\", :task_id=>\"d165e1acf2384cdcaa1b7142067755b2\", :properties=>{:services=>[], :resources=>{:memory=>1024, :disk=>1024, :fds=>16384}, :environment=>[\"CF_STACK=cflinuxfs2\"], :meta=>{\"console\"=>false}}, :download_uri=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/staging/apps/a760f148-4b00-4f43-aa3d-e19d5fc3d4d0\", :upload_uri=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/staging/droplets/a760f148-4b00-4f43-aa3d-e19d5fc3d4d0/upload\", :buildpack_cache_download_uri=>nil, :buildpack_cache_upload_uri=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/staging/buildpack_cache/a760f148-4b00-4f43-aa3d-e19d5fc3d4d0/upload\", :start_message=>{:droplet=>\"a760f148-4b00-4f43-aa3d-e19d5fc3d4d0\", :name=>\"my-php-app\", :stack=>\"cflinuxfs2\", :uris=>[\"pyptest.mydomain.cf\"], :prod=>false, :sha1=>nil, :executableFile=>\"deprecated\", :executableUri=>nil, :version=>\"3d9cc568-5095-4caa-b005-0b9240c7b3f8\", :services=>[], :limits=>{:mem=>1024, :disk=>1024, :fds=>16384}, :env=>[\"CF_PROCESS_TYPE=web\"], :cc_partition=>\"default\", :console=>false, :debug=>nil, :start_command=>nil, :health_check_timeout=>nil, :vcap_application=>{:limits=>{:mem=>1024, :disk=>1024, :fds=>16384}, :application_id=>\"a760f148-4b00-4f43-aa3d-e19d5fc3d4d0\", :application_version=>\"3d9cc568-5095-4caa-b005-0b9240c7b3f8\", :application_name=>\"my-php-app\", :application_uris=>[\"pyptest.mydomain.cf\"], :version=>\"3d9cc568-5095-4caa-b005-0b9240c7b3f8\", :name=>\"my-php-app\", :space_name=>\"main\", :space_id=>\"bc05b595-1f97-445c-9dc9-d7d13318c272\", :uris=>[\"pyptest.mydomain.cf\"], :users=>nil}, :index=>0, :egress_network_rules=>[{\"destination\"=>\"0.0.0.0/0\", \"ports\"=>\"53\", \"protocol\"=>\"tcp\"}, {\"destination\"=>\"0.0.0.0/0\", \"ports\"=>\"53\", \"protocol\"=>\"udp\"}, {\"destination\"=>\"0.0.0.0-9.255.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"11.0.0.0-169.253.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"169.255.0.0-172.15.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"172.32.0.0-192.167.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"192.169.0.0-255.255.255.255\", \"protocol\"=>\"all\"}]}, :admin_buildpacks=>[{:key=>\"38ef2252-0d78-45ef-a795-ef35ca086306_d84ffdf7306e272d1a09877bb00df9cce1583401\", :url=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/v2/buildpacks/38ef2252-0d78-45ef-a795-ef35ca086306/download\"}, {:key=>\"1e47fabf-b490-47de-86c2-f54ca61a0b7a_3ddf6855baec15fb97cd486312615ce82a354dba\", :url=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/v2/buildpacks/1e47fabf-b490-47de-86c2-f54ca61a0b7a/download\"}, {:key=>\"3568a209-4283-4b27-a6d7-1ff59d6dd14c_712fe85a99fe8ea5fcf55e6d96b648ebb2acd66b\", :url=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/v2/buildpacks/3568a209-4283-4b27-a6d7-1ff59d6dd14c/download\"}, {:key=>\"af66547f-08e7-4878-a84e-856387606303_0940c49346411e30af59b60ed6213a8047374b9b\", :url=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/v2/buildpacks/af66547f-08e7-4878-a84e-856387606303/download\"}, {:key=>\"3ab4eceb-35fd-4e75-8259-910b66025be7_35ff4ffbde64e185bf221008e1d3aacb81db7d5e\", :url=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/v2/buildpacks/3ab4eceb-35fd-4e75-8259-910b66025be7/download\"}, {:key=>\"8b658fa8-7b2a-4070-a84c-c1be42db0d5e_d77d7634925ecb6bb5130bf659dfb7a4f44a1054\", :url=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/v2/buildpacks/8b658fa8-7b2a-4070-a84c-c1be42db0d5e/download\"}, {:key=>\"4139a5ff-d33e-4b57-bd8d-44e4cc91251c_4e7dbfdd1804fa0c052fb19dc5e349c3852180ca\", :url=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/v2/buildpacks/4139a5ff-d33e-4b57-bd8d-44e4cc91251c/download\"}, {:key=>\"37b108a9-4b49-4959-8c04-a0a71d8cd32f_3e62e3882495cf16686078e7209bf28d3f9d60f5\", :url=>\"http://STAGING_UPLOAD_USER:STAGING_UPLOAD_PASSWORD@cloud-controller-ng.service.cf.internal:9022/v2/buildpacks/37b108a9-4b49-4959-8c04-a0a71d8cd32f/download\"}], :egress_network_rules=>[{\"destination\"=>\"0.0.0.0-9.255.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"11.0.0.0-169.253.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"169.255.0.0-172.15.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"172.32.0.0-192.167.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"192.169.0.0-255.255.255.255\", \"protocol\"=>\"all\"}, {\"destination\"=>\"0.0.0.0/0\", \"ports\"=>\"53\", \"protocol\"=>\"tcp\"}, {\"destination\"=>\"0.0.0.0/0\", \"ports\"=>\"53\", \"protocol\"=>\"udp\"}]}'","log_level":"debug","source":"MultiResponseMessageBusRequest","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":25,"method":"request"}
{"timestamp":1456847530.581567,"message":"timeout_request: sid=11 timeout=900","log_level":"info","source":"MultiResponseMessageBusRequest","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":55,"method":"timeout_request"}
{"timestamp":1456847530.7400074,"message":"handle_received_response: sid=11 response='{\"task_id\"=>\"d165e1acf2384cdcaa1b7142067755b2\", \"task_streaming_log_url\"=>\"https://1730c0ac4e7b4bf68d747f40803639ac.mydomain.cf/staging_tasks/d165e1acf2384cdcaa1b7142067755b2/file_path?hmac=f6d2b101ba74e2930a16afe276d861d07c2441dd55c5370e9f1960e325ab5bc5a4afab10408efa154ec69985905e58db3363aa6ea1aec2d5835dd3ce13891309&path=%2Ftmp%2Fstaged%2Flogs%2Fstaging_task.log&timestamp=1456847530\", \"error\"=>\"Error downloading: Response status: unknown, Error: unable to resolve server address\"}'","log_level":"debug","source":"MultiResponseMessageBusRequest","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":39,"method":"handle_received_response"}
{"timestamp":1456847530.7404122,"message":"timeout_request: sid=11 timeout=900","log_level":"info","source":"MultiResponseMessageBusRequest","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":55,"method":"timeout_request"}
{"timestamp":1456847530.7407522,"message":"staging.first-response","log_level":"info","source":"cc.app_stager","data":{"app_guid":"a760f148-4b00-4f43-aa3d-e19d5fc3d4d0","response":{"task_id":"d165e1acf2384cdcaa1b7142067755b2","task_streaming_log_url":"https://1730c0ac4e7b4bf68d747f40803639ac.mydomain.cf/staging_tasks/d165e1acf2384cdcaa1b7142067755b2/file_path?hmac=f6d2b101ba74e2930a16afe276d861d07c2441dd55c5370e9f1960e325ab5bc5a4afab10408efa154ec69985905e58db3363aa6ea1aec2d5835dd3ce13891309&path=%2Ftmp%2Fstaged%2Flogs%2Fstaging_task.log&timestamp=1456847530","error":"Error downloading: Response status: unknown, Error: unable to resolve server address"},"error":null},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb","lineno":48,"method":"block (2 levels) in stage"}
{"timestamp":1456847530.7485552,"message":"(0.006995s) SELECT * FROM \"apps\" WHERE (\"id\" = 7) LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7519732,"message":"(0.000960s) SELECT * FROM \"spaces\" WHERE \"id\" = 2","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7542257,"message":"(0.000982s) SELECT count(*) AS \"count\" FROM \"apps\" WHERE ((\"space_id\" = 2) AND (\"name\" = 'my-php-app') AND (\"id\" != 7)) LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.757566,"message":"(0.000819s) SELECT * FROM \"organizations\" WHERE \"id\" = 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7597945,"message":"(0.001344s) SELECT * FROM \"quota_definitions\" WHERE \"id\" = 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.762004,"message":"(0.001189s) SELECT * FROM \"apps\" WHERE (\"guid\" = 'a760f148-4b00-4f43-aa3d-e19d5fc3d4d0') LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.764828,"message":"(0.001178s) SELECT * FROM \"apps\" WHERE (\"guid\" = 'a760f148-4b00-4f43-aa3d-e19d5fc3d4d0') LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7668967,"message":"(0.001070s) SELECT * FROM \"spaces\" WHERE (\"spaces\".\"organization_id\" = 1)","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7689123,"message":"(0.000929s) SELECT sum((\"memory\" * \"instances\")) AS \"sum\" FROM \"apps\" WHERE ((\"apps\".\"space_id\" IN (2)) AND (\"state\" = 'STARTED')) LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7716637,"message":"(0.000779s) BEGIN","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7728496,"message":"(0.000736s) SELECT * FROM \"stacks\" WHERE \"id\" = 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.774959,"message":"(0.001008s) UPDATE \"apps\" SET \"guid\" = 'a760f148-4b00-4f43-aa3d-e19d5fc3d4d0', \"created_at\" = '2016-03-01 15:51:43.314518+0000', \"updated_at\" = CURRENT_TIMESTAMP, \"name\" = 'my-php-app', \"production\" = false, \"memory\" = 1024, \"instances\" = 1, \"file_descriptors\" = 16384, \"disk_quota\" = 1024, \"state\" = 'STARTED', \"package_state\" = 'FAILED', \"package_hash\" = 'e679175df56a38b721231fdbfd961f93414bf594', \"droplet_hash\" = NULL, \"version\" = '3d9cc568-5095-4caa-b005-0b9240c7b3f8', \"metadata\" = '{\"console\":false}', \"buildpack\" = NULL, \"space_id\" = 2, \"stack_id\" = 1, \"detected_buildpack\" = NULL, \"staging_task_id\" = 'd165e1acf2384cdcaa1b7142067755b2', \"deleted_at\" = NULL, \"not_deleted\" = true, \"salt\" = 'b98c8d4c', \"encrypted_environment_json\" = 't3I+FS121r82e3QBRptcvg==', \"admin_buildpack_id\" = NULL, \"health_check_timeout\" = NULL, \"detected_buildpack_guid\" = NULL, \"detected_buildpack_name\" = NULL, \"staging_failed_reason\" = 'StagingError', \"diego\" = false, \"docker_image\" = NULL, \"package_updated_at\" = '2016-03-01 15:52:04.940750+0000', \"app_guid\" = NULL, \"package_pending_since\" = NULL, \"type\" = 'web', \"health_check_type\" = 'port', \"command\" = NULL, \"enable_ssh\" = true, \"encrypted_docker_credentials_json\" = 'JOCcpOg6ZeWtiWhfo4WWZw==', \"docker_salt\" = '8b2a9f7c', \"staging_failed_description\" = 'Staging error: staging failed', \"ports\" = NULL WHERE (\"id\" = 7)","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7771502,"message":"(0.001681s) COMMIT","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7791538,"message":"exception handling first response from stager with id 0-2e3ff20f95b1469584b22a95f1f9b3f4 response: #<VCAP::Errors::ApiError: VCAP::Errors::ApiError>, backtrace: /var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:112:in `check_staging_error!'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:75:in `handle_first_response'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:49:in `block (2 levels) in stage'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:48:in `call'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:48:in `trigger_on_response'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:43:in `handle_received_response'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:22:in `block in request'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:88:in `yield'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:88:in `run_handler'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:41:in `block (2 levels) in request'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:104:in `yield'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:104:in `process_message'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:40:in `block in request'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:415:in `call'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:415:in `block in request'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:515:in `call'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:515:in `on_msg'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:576:in `receive_data'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/eventmachine-1.0.9.1/lib/eventmachine.rb:193:in `run_machine'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/eventmachine-1.0.9.1/lib/eventmachine.rb:193:in `run'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/runner.rb:87:in `run!'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/bin/cloud_controller:8:in `<main>'","log_level":"error","source":"cc.app_stager","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb","lineno":79,"method":"rescue in handle_first_response"}
{"timestamp":1456847530.7796047,"message":"handle_received_response: sid=11 response='{\"task_id\"=>\"d165e1acf2384cdcaa1b7142067755b2\", \"detected_buildpack\"=>nil, \"buildpack_key\"=>nil, \"droplet_sha1\"=>nil, \"detected_start_command\"=>nil, \"procfile\"=>nil, \"error\"=>\"Error downloading: Response status: unknown, Error: unable to resolve server address\"}'","log_level":"debug","source":"MultiResponseMessageBusRequest","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":39,"method":"handle_received_response"}
{"timestamp":1456847530.7798505,"message":"staging.second-response","log_level":"info","source":"cc.app_stager","data":{"app_guid":"a760f148-4b00-4f43-aa3d-e19d5fc3d4d0","response":{"task_id":"d165e1acf2384cdcaa1b7142067755b2","detected_buildpack":null,"buildpack_key":null,"droplet_sha1":null,"detected_start_command":null,"procfile":null,"error":"Error downloading: Response status: unknown, Error: unable to resolve server address"},"error":null},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb","lineno":56,"method":"block (2 levels) in stage"}
{"timestamp":1456847530.780126,"message":"unsubscribe: sid=11","log_level":"info","source":"MultiResponseMessageBusRequest","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb","lineno":65,"method":"unsubscribe"}
{"timestamp":1456847530.7810159,"message":"Request failed: 400: {\"code\"=>170001, \"description\"=>\"Staging error: failed to stage application:\\nError downloading: Response status: unknown, Error: unable to resolve server address\\n\", \"error_code\"=>\"CF-StagingError\", \"backtrace\"=>[\"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:112:in `check_staging_error!'\", \"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:75:in `handle_first_response'\", \"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:49:in `block (2 levels) in stage'\", \"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:48:in `call'\", \"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:48:in `trigger_on_response'\", \"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:43:in `handle_received_response'\", \"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:22:in `block in request'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:88:in `yield'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:88:in `run_handler'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:41:in `block (2 levels) in request'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:104:in `yield'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:104:in `process_message'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:40:in `block in request'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:415:in `call'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:415:in `block in request'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:515:in `call'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:515:in `on_msg'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:576:in `receive_data'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/eventmachine-1.0.9.1/lib/eventmachine.rb:193:in `run_machine'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/eventmachine-1.0.9.1/lib/eventmachine.rb:193:in `run'\", \"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/runner.rb:87:in `run!'\", \"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/bin/cloud_controller:8:in `<main>'\"]}","log_level":"info","source":"cc.api","data":{"request_guid":"7a4a48a7-015d-4b26-443a-4c1f5d3f7005::b18202f9-e778-4593-b5e0-10c9660d9c82"},"thread_id":47050550355220,"fiber_id":47050548706040,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/lib/sinatra/vcap.rb","lineno":51,"method":"block in registered"}
{"timestamp":1456847530.7815988,"message":"(0.000967s) SELECT * FROM \"apps\" WHERE (\"id\" = 7) LIMIT 1","log_level":"debug2","source":"cc.db","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/sequel-4.29.0/lib/sequel/database/logging.rb","lineno":70,"method":"block in log_each"}
{"timestamp":1456847530.7828329,"message":"Completed 400 vcap-request-id: 7a4a48a7-015d-4b26-443a-4c1f5d3f7005::b18202f9-e778-4593-b5e0-10c9660d9c82","log_level":"info","source":"cc.api","data":{},"thread_id":47050550355220,"fiber_id":47050548706040,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/middleware/request_logs.rb","lineno":23,"method":"call"}
{"timestamp":1456847530.783637,"message":"Encountered error on stager with id 0-2e3ff20f95b1469584b22a95f1f9b3f4: VCAP::Errors::ApiError\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:152:in `ensure_staging_is_current!'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:85:in `handle_second_response'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb:57:in `block (2 levels) in stage'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:48:in `call'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:48:in `trigger_on_response'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:43:in `handle_received_response'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/multi_response_message_bus_request.rb:22:in `block in request'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:88:in `yield'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:88:in `run_handler'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:41:in `block (2 levels) in request'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:104:in `yield'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:104:in `process_message'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/cf-message-bus-0.3.4/lib/cf_message_bus/message_bus.rb:40:in `block in request'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:415:in `call'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:415:in `block in request'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:515:in `call'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:515:in `on_msg'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/nats-0.5.1/lib/nats/client.rb:576:in `receive_data'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/eventmachine-1.0.9.1/lib/eventmachine.rb:193:in `run_machine'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/eventmachine-1.0.9.1/lib/eventmachine.rb:193:in `run'\n/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/runner.rb:87:in `run!'\n/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/bin/cloud_controller:8:in `<main>'","log_level":"error","source":"cc.app_stager","data":{},"thread_id":47050499455380,"fiber_id":47050527749180,"process_id":11295,"file":"/var/vcap/data/packages/cloud_controller_ng/c8eeafa6c5997e670ad5e8376280c721d6f593c4.1-70e377f704082bd8d03553902f7d08ac437de6ea/cloud_controller_ng/lib/cloud_controller/dea/app_stager_task.rb","lineno":90,"method":"rescue in handle_second_response"}
{"timestamp":1456847530.7839677,"message":"Statsd: cc.requests.outstanding:-1|c\ncc.requests.completed:1|c\ncc.http_status.4XX:1|c","log_level":"debug","source":"statsd.client","data":{},"thread_id":47050550355220,"fiber_id":47050548706040,"process_id":11295,"file":"/var/vcap/packages/cloud_controller_ng/cloud_controller_ng/vendor/bundle/ruby/2.2.0/gems/statsd-ruby-1.2.1/lib/statsd.rb","lineno":254,"method":"send_to_socket"}