gistfile1.txt

D, [2015-08-05 09:47:25 #15977] [] DEBUG -- DirectorJobRunner: Lock renewal thread exiting
D, [2015-08-05 09:47:25 #15977] [task:76] DEBUG -- DirectorJobRunner: Deleted lock: lock:deployment:cf
I, [2015-08-05 09:47:25 #15977] [task:76]  INFO -- DirectorJobRunner: sending update deployment error event
D, [2015-08-05 09:47:25 #15977] [task:76] DEBUG -- DirectorJobRunner: SENT: hm.director.alert {"id":"4c2cf180-9c2a-4821-bd75-298be815ebea","severity":3,"title":"director - error during update deployment","summary":"Error during update deployment for 'cf' against Director '56f4541a-ebef-45dd-9276-9df6705c9b6d': #<Bosh::Clouds::CloudError: Volume `5ff1c17d-d1ad-4fe3-ae69-af06a92c51f2' state is error, expected available>","created_at":1438768045}
E, [2015-08-05 09:47:25 #15977] [task:76] ERROR -- DirectorJobRunner: Volume `5ff1c17d-d1ad-4fe3-ae69-af06a92c51f2' state is error, expected available
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_cpi-1.0000.0/lib/cloud/external_cpi.rb:119:in `handle_error'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_cpi-1.0000.0/lib/cloud/external_cpi.rb:89:in `invoke_cpi_method'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_cpi-1.0000.0/lib/cloud/external_cpi.rb:57:in `create_disk'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/instance_updater.rb:333:in `block in create_disk'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/sequel-3.43.0/lib/sequel/database/query.rb:338:in `_transaction'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/sequel-3.43.0/lib/sequel/database/query.rb:300:in `block in transaction'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/sequel-3.43.0/lib/sequel/database/connecting.rb:236:in `block in synchronize'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/sequel-3.43.0/lib/sequel/connection_pool/threaded.rb:104:in `hold'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/sequel-3.43.0/lib/sequel/database/connecting.rb:236:in `synchronize'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/sequel-3.43.0/lib/sequel/database/query.rb:293:in `transaction'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/instance_updater.rb:332:in `create_disk'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/instance_updater.rb:262:in `update_persistent_disk'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/instance_updater.rb:61:in `block in update_steps'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/instance_updater.rb:85:in `call'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/instance_updater.rb:85:in `block in update'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/instance_updater.rb:84:in `each'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/instance_updater.rb:84:in `update'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/job_updater.rb:74:in `block (2 levels) in update_canary_instance'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.0000.0/lib/common/thread_formatter.rb:49:in `with_thread_name'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/job_updater.rb:72:in `block in update_canary_instance'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/event_log.rb:97:in `call'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/event_log.rb:97:in `advance_and_track'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/job_updater.rb:71:in `update_canary_instance'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh-director-1.0000.0/lib/bosh/director/job_updater.rb:65:in `block (2 levels) in update_canaries'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.0000.0/lib/common/thread_pool.rb:77:in `call'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.0000.0/lib/common/thread_pool.rb:77:in `block (2 levels) in create_thread'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.0000.0/lib/common/thread_pool.rb:63:in `loop'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/bosh_common-1.0000.0/lib/common/thread_pool.rb:63:in `block in create_thread'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:in `call'
/var/vcap/packages/director/gem_home/ruby/2.1.0/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:in `block in create_with_logging_context'
D, [2015-08-05 09:47:25 #15977] [task:76] DEBUG -- DirectorJobRunner: (0.000243s) SELECT NULL
D, [2015-08-05 09:47:25 #15977] [task:76] DEBUG -- DirectorJobRunner: (0.000078s) BEGIN
D, [2015-08-05 09:47:25 #15977] [task:76] DEBUG -- DirectorJobRunner: (0.001229s) UPDATE "tasks" SET "state" = 'error', "timestamp" = '2015-08-05 09:47:25.923558+0000', "description" = 'create deployment', "result" = 'Volume `5ff1c17d-d1ad-4fe3-ae69-af06a92c51f2'' state is error, expected available', "output" = '/var/vcap/store/director/tasks/76', "checkpoint_time" = '2015-08-05 09:47:12.522277+0000', "type" = 'update_deployment', "username" = 'admin' WHERE ("id" = 76)
D, [2015-08-05 09:47:25 #15977] [task:76] DEBUG -- DirectorJobRunner: (0.021597s) COMMIT
I, [2015-08-05 09:47:25 #15977] []  INFO -- DirectorJobRunner: Task took 8 minutes 43.92486816500002 seconds to process.

Task 76 error