$ bosh upload stemcell ./bosh-stemcell-2751-aws-xen-ubuntu-trusty-go_agent.tgz
Verifying stemcell...
File exists and readable OK
Verifying tarball...
Read tarball OK
Manifest exists OK
Stemcell image file OK
Stemcell properties OK
Stemcell info
-------------
Name: bosh-aws-xen-ubuntu-trusty-go_agent
Version: 2751
Checking if stemcell already exists...
No
Uploading stemcell...
bosh-stemcell: 100% |oooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooo| 445.9MB 30.5MB/s Time: 00:00:14
Director task 2
Started update stemcell
Started update stemcell > Extracting stemcell archive. Done (00:00:07)
Started update stemcell > Verifying stemcell manifest. Done (00:00:00)
Started update stemcell > Checking if this stemcell already exists. Done (00:00:00)
Started update stemcell > Uploading stemcell bosh-aws-xen-ubuntu-trusty-go_agent/2751 to the cloud. Failed: execution expired (00:00:46)
Error 100: execution expired
Task 2 error
For a more detailed error report, run: bosh task 2 --debug
$ bosh task 2 --debug
Director task 2
# Logfile created on 2014-11-04 05:52:16 +0000 by logger.rb/44203
I, [2014-11-04T05:52:16.900239 #2883] INFO -- : Director Version : 1.2751.0
I, [2014-11-04T05:52:16.900308 #2883] INFO -- : Enqueuing task: 2
I, [2014-11-04T05:52:17.481533 #3566] [0x3ffeaee95330] INFO -- : Looking for task with task id 2
D, [2014-11-04T05:52:17.483363 #3566] [0x3ffeaee95330] DEBUG -- : (0.000761s) SELECT * FROM "tasks" WHERE "id" = 2
I, [2014-11-04T05:52:17.488317 #3566] [0x3ffeaee95330] INFO -- : Starting task: 2
I, [2014-11-04T05:52:17.488487 #3566] [task:2] INFO -- : Creating job
D, [2014-11-04T05:52:24.156394 #3566] [task:2] DEBUG -- : (0.000633s) SELECT * FROM "tasks" WHERE "id" = 2
I, [2014-11-04T05:52:24.157221 #3566] [task:2] INFO -- : Performing task: 2
D, [2014-11-04T05:52:24.158203 #3566] [task:2] DEBUG -- : (0.000118s) BEGIN
D, [2014-11-04T05:52:24.160204 #3566] [task:2] DEBUG -- : (0.000398s) UPDATE "tasks" SET "state" = 'processing', "timestamp" = '2014-11-04 05:52:24.157516+0000', "description" = 'create stemcell', "result" = NULL, "output" = '/var/vcap/store/director/tasks/2', "user_id" = NULL, "checkpoint_time" = '2014-11-04 05:52:24.157626+0000', "type" = 'update_stemcell' WHERE ("id" = 2)
D, [2014-11-04T05:52:24.162314 #3566] [task:2] DEBUG -- : (0.001908s) COMMIT
I, [2014-11-04T05:52:24.162479 #3566] [task:2] INFO -- : Processing update stemcell
I, [2014-11-04T05:52:24.163179 #3566] [task:2] INFO -- : Extracting stemcell archive
I, [2014-11-04T05:52:31.204011 #3566] [task:2] INFO -- : Verifying stemcell manifest
I, [2014-11-04T05:52:31.206431 #3566] [task:2] INFO -- : Found stemcell image `bosh-aws-xen-ubuntu-trusty-go_agent/2751', cloud properties are {"name"=>"bosh-aws-xen-ubuntu-trusty-go_agent", "version"=>"2751", "infrastructure"=>"aws", "architecture"=>"x86_64", "root_device_name"=>"/dev/sda1"}
I, [2014-11-04T05:52:31.206510 #3566] [task:2] INFO -- : Verifying stemcell image
I, [2014-11-04T05:52:31.206804 #3566] [task:2] INFO -- : Checking if this stemcell already exists
D, [2014-11-04T05:52:31.209085 #3566] [task:2] DEBUG -- : (0.000674s) SELECT * FROM "stemcells" WHERE (("name" = 'bosh-aws-xen-ubuntu-trusty-go_agent') AND ("version" = '2751')) LIMIT 1
I, [2014-11-04T05:52:31.209817 #3566] [task:2] INFO -- : Uploading stemcell bosh-aws-xen-ubuntu-trusty-go_agent/2751 to the cloud
D, [2014-11-04T05:52:54.162865 #3566] [task:2-checkpoint] DEBUG -- : (0.000235s) BEGIN
D, [2014-11-04T05:52:54.164467 #3566] [task:2-checkpoint] DEBUG -- : (0.000460s) UPDATE "tasks" SET "checkpoint_time" = '2014-11-04 05:52:54.160786+0000' WHERE ("id" = 2)
D, [2014-11-04T05:52:54.166803 #3566] [task:2-checkpoint] DEBUG -- : (0.002143s) COMMIT
I, [2014-11-04T05:53:17.138357 #3566] [create_disk(2048, i-86ad6e4b)] INFO -- : [AWS EC2 200 45.919706 2 retries] describe_instances(:instance_ids=>["i-86ad6e4b"]) Net::OpenTimeout execution expired
E, [2014-11-04T05:53:17.138586 #3566] [create_stemcell(/var/vcap/data/tmp/director/stemcell20141104-3566-xkxtd1/image...)] ERROR -- : execution expired (Net::OpenTimeout)
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:879:in `initialize'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:879:in `open'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:879:in `block in connect'
/var/vcap/packages/ruby/lib/ruby/2.1.0/timeout.rb:91:in `block in timeout'
/var/vcap/packages/ruby/lib/ruby/2.1.0/timeout.rb:101:in `call'
/var/vcap/packages/ruby/lib/ruby/2.1.0/timeout.rb:101:in `timeout'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:878:in `connect'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:863:in `do_start'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:858:in `start'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/http/connection_pool.rb:321:in `start_session'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/http/connection_pool.rb:125:in `session_for'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/http/net_http_handler.rb:55:in `handle'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:253:in `block in make_sync_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:289:in `retry_server_errors'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:249:in `make_sync_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:511:in `block (2 levels) in client_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:391:in `log_client_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:477:in `block in client_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:373:in `return_or_raise'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:476:in `client_request'
(eval):3:in `describe_instances'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/ec2/resource.rb:72:in `describe_call'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/ec2/instance.rb:787:in `get_resource'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/resource.rb:235:in `block (2 levels) in define_attribute_getter'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/cacheable.rb:63:in `retrieve_attribute'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/ec2/resource.rb:66:in `retrieve_attribute'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/resource.rb:235:in `block in define_attribute_getter'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/ec2/instance.rb:527:in `availability_zone'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/availability_zone_selector.rb:19:in `select_availability_zone'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/cloud.rb:179:in `block in create_disk'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.2751.0/lib/common/thread_formatter.rb:46:in `with_thread_name'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/cloud.rb:166:in `create_disk'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/cloud.rb:422:in `block in create_stemcell'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.2751.0/lib/common/thread_formatter.rb:46:in `with_thread_name'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/cloud.rb:410:in `create_stemcell'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/update_stemcell.rb:84:in `block in perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/base_job.rb:48:in `block in track_and_log'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/event_log.rb:83:in `call'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/event_log.rb:83:in `advance_and_track'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/event_log.rb:36:in `track'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/base_job.rb:46:in `track_and_log'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/update_stemcell.rb:83:in `perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/job_runner.rb:104:in `perform_job'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/job_runner.rb:31:in `block in run'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.2751.0/lib/common/thread_formatter.rb:46:in `with_thread_name'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/job_runner.rb:31:in `run'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/base_job.rb:10:in `perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/job.rb:227:in `perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/worker.rb:250:in `perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/worker.rb:189:in `block in work'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/worker.rb:166:in `loop'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/worker.rb:166:in `work'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/bin/bosh-director-worker:84:in `<top (required)>'
/var/vcap/packages/director/bin/bosh-director-worker:16:in `load'
/var/vcap/packages/director/bin/bosh-director-worker:16:in `<main>'
E, [2014-11-04T05:53:17.257104 #3566] [task:2] ERROR -- : execution expired
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:879:in `initialize'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:879:in `open'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:879:in `block in connect'
/var/vcap/packages/ruby/lib/ruby/2.1.0/timeout.rb:91:in `block in timeout'
/var/vcap/packages/ruby/lib/ruby/2.1.0/timeout.rb:101:in `call'
/var/vcap/packages/ruby/lib/ruby/2.1.0/timeout.rb:101:in `timeout'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:878:in `connect'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:863:in `do_start'
/var/vcap/packages/ruby/lib/ruby/2.1.0/net/http.rb:858:in `start'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/http/connection_pool.rb:321:in `start_session'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/http/connection_pool.rb:125:in `session_for'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/http/net_http_handler.rb:55:in `handle'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:253:in `block in make_sync_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:289:in `retry_server_errors'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:249:in `make_sync_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:511:in `block (2 levels) in client_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:391:in `log_client_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:477:in `block in client_request'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:373:in `return_or_raise'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/client.rb:476:in `client_request'
(eval):3:in `describe_instances'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/ec2/resource.rb:72:in `describe_call'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/ec2/instance.rb:787:in `get_resource'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/resource.rb:235:in `block (2 levels) in define_attribute_getter'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/cacheable.rb:63:in `retrieve_attribute'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/ec2/resource.rb:66:in `retrieve_attribute'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/core/resource.rb:235:in `block in define_attribute_getter'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/aws-sdk-1.44.0/lib/aws/ec2/instance.rb:527:in `availability_zone'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/availability_zone_selector.rb:19:in `select_availability_zone'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/cloud.rb:179:in `block in create_disk'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.2751.0/lib/common/thread_formatter.rb:46:in `with_thread_name'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/cloud.rb:166:in `create_disk'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/cloud.rb:422:in `block in create_stemcell'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.2751.0/lib/common/thread_formatter.rb:46:in `with_thread_name'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_aws_cpi-1.2751.0/lib/cloud/aws/cloud.rb:410:in `create_stemcell'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/update_stemcell.rb:84:in `block in perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/base_job.rb:48:in `block in track_and_log'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/event_log.rb:83:in `call'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/event_log.rb:83:in `advance_and_track'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/event_log.rb:36:in `track'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/base_job.rb:46:in `track_and_log'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/update_stemcell.rb:83:in `perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/job_runner.rb:104:in `perform_job'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/job_runner.rb:31:in `block in run'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.2751.0/lib/common/thread_formatter.rb:46:in `with_thread_name'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/job_runner.rb:31:in `run'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/lib/bosh/director/jobs/base_job.rb:10:in `perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/job.rb:227:in `perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/worker.rb:250:in `perform'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/worker.rb:189:in `block in work'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/worker.rb:166:in `loop'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/resque-1.25.2/lib/resque/worker.rb:166:in `work'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.2751.0/bin/bosh-director-worker:84:in `<top (required)>'
/var/vcap/packages/director/bin/bosh-director-worker:16:in `load'
/var/vcap/packages/director/bin/bosh-director-worker:16:in `<main>'
D, [2014-11-04T05:53:17.258093 #3566] [task:2] DEBUG -- : (0.000242s) BEGIN
D, [2014-11-04T05:53:17.259634 #3566] [task:2] DEBUG -- : (0.000548s) UPDATE "tasks" SET "state" = 'error', "timestamp" = '2014-11-04 05:53:17.257439+0000', "description" = 'create stemcell', "result" = 'execution expired', "output" = '/var/vcap/store/director/tasks/2', "user_id" = NULL, "checkpoint_time" = '2014-11-04 05:52:54.160786+0000', "type" = 'update_stemcell' WHERE ("id" = 2)
D, [2014-11-04T05:53:17.261713 #3566] [task:2] DEBUG -- : (0.001925s) COMMIT
I, [2014-11-04T05:53:17.262174 #3566] [0x3ffeaee95330] INFO -- : Task took 59.773423658 seconds to process.
Task 2 error
Type | Protocol | Port Range | Destination | |
---|---|---|---|---|
All traffic | All | All |
To unsubscribe from this group and stop receiving emails from it, send an email to bosh-users+...@cloudfoundry.org.