search

cloudfoundry-attic / bosh-init

bosh-init is a tool used to create and update the Director VM
Apache License 2.0
31 stars 33 forks source link

Stuck in creating VM #42

Closed LaynePeng closed 9 years ago

LaynePeng commented 9 years ago

Hi,

My environment is OpenStack. And I use bosh-init to deploy the micro-bosh, then use the micro-bosh to deploy releases. Everything fine until today.

However, I suddenly met the problem of cannot create VM . Actually, the VM has been created in OpenStack, but it seems cannot communicated with BOSH director. Then I SSH to the VM create, find the following logs:

2015-08-26_15:41:35.51067 [settingsService] 2015/08/26 15:41:35 ERROR - Failed reading settings from file Opening file /var/vcap/bosh/settings.json: open /var/vcap/bosh/settings.json: no such file or directory
cppforlife commented 9 years ago

That may not be the root problem. Please search for 'Starting agent' in the agent log and include here few surrounding lines.

Sent from my iPhone

On Aug 26, 2015, at 8:48 AM, Layne Peng notifications@github.com wrote:

Hi,

My environment is OpenStack. And I use bosh-init to deploy the micro-bosh, then use the micro-bosh to deploy releases. Everything fine until today.

However, I suddenly met the problem of cannot create VM . Actually, the VM has been created in OpenStack, but it seems cannot communicated with BOSH director. Then I SSH to the VM create, find the following logs:

2015-08-26_15:41:35.51067 [settingsService] 2015/08/26 15:41:35 ERROR - Failed reading settings from file Opening file /var/vcap/bosh/settings.json: open /var/vcap/bosh/settings.json: no such file or directory

— Reply to this email directly or view it on GitHub.

LaynePeng commented 9 years ago

Hi, thanks for quick response. I just check the Starting part, but found nothing...

2015-08-26_16:27:21.09091 [main] 2015/08/26 16:27:21 DEBUG - Starting agent
2015-08-26_16:27:21.09152 [File System] 2015/08/26 16:27:21 DEBUG - Reading file /var/vcap/bosh/agent.json
2015-08-26_16:27:21.09744 [File System] 2015/08/26 16:27:21 DEBUG - Read content
2015-08-26_16:27:21.09745 ********************
2015-08-26_16:27:21.09746 {
2015-08-26_16:27:21.09746   "Platform": {
2015-08-26_16:27:21.09746     "Linux": {
2015-08-26_16:27:21.09747       "CreatePartitionIfNoEphemeralDisk": true,
2015-08-26_16:27:21.09747       "DevicePathResolutionType": "virtio"
2015-08-26_16:27:21.09747     }
2015-08-26_16:27:21.09748   },
2015-08-26_16:27:21.09748   "Infrastructure": {
2015-08-26_16:27:21.09748     "NetworkingType": "dhcp",
2015-08-26_16:27:21.09748
2015-08-26_16:27:21.09749     "Settings": {
2015-08-26_16:27:21.09749       "Sources": [
2015-08-26_16:27:21.09749         {
2015-08-26_16:27:21.09749           "Type": "ConfigDrive",
2015-08-26_16:27:21.09750           "DiskPaths": [
2015-08-26_16:27:21.09750             "/dev/disk/by-label/CONFIG-2",
2015-08-26_16:27:21.09750             "/dev/disk/by-label/config-2"
2015-08-26_16:27:21.09751           ],
2015-08-26_16:27:21.09751           "MetaDataPath": "ec2/latest/meta-data.json",
2015-08-26_16:27:21.09751           "UserDataPath": "ec2/latest/user-data"
2015-08-26_16:27:21.09752         },
2015-08-26_16:27:21.09752         {
2015-08-26_16:27:21.09752           "Type": "HTTP",
2015-08-26_16:27:21.09752           "URI": "http://169.254.169.254"
2015-08-26_16:27:21.09753         }
2015-08-26_16:27:21.09753       ],
2015-08-26_16:27:21.09753
2015-08-26_16:27:21.09754       "UseServerName": true,
2015-08-26_16:27:21.09754       "UseRegistry": true
2015-08-26_16:27:21.09754     }
2015-08-26_16:27:21.09754   }
2015-08-26_16:27:21.09755 }
2015-08-26_16:27:21.09755
2015-08-26_16:27:21.09755 ********************
2015-08-26_16:27:21.09756 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Running command: bosh-agent-rc
2015-08-26_16:27:21.10418 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Stdout:
2015-08-26_16:27:21.10419 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Stderr:
2015-08-26_16:27:21.10419 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Successful: true (0)
2015-08-26_16:27:21.10420 [ConfigDriveMetadataService] 2015/08/26 16:27:21 DEBUG - Loading config drive metadata service
2015-08-26_16:27:21.10420 [File System] 2015/08/26 16:27:21 DEBUG - Checking if file exists /dev/disk/by-label/CONFIG-2
2015-08-26_16:27:21.10420 [ConfigDriveMetadataService] 2015/08/26 16:27:21 WARN - Failed to load config from /dev/disk/by-label/CONFIG-2 - Reading files on config drive: Failed to get file contents, disk path '/dev/disk/by-label/CONFIG-2' does not exist

...

2015-08-26_16:27:21.16761 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Successful: false (1)
2015-08-26_16:27:21.16767 [UbuntuNetManager] 2015/08/26 16:27:21 ERROR - Ignoring failure calling 'pkill dhclient': Running command: 'pkill dhclient', stdout: '', stderr: '': exit status 1
2015-08-26_16:27:21.16769 [File System] 2015/08/26 16:27:21 DEBUG - Glob '/sys/class/net/*'
2015-08-26_16:27:21.17028 [File System] 2015/08/26 16:27:21 DEBUG - Checking if file exists /sys/class/net/eth0/device
2015-08-26_16:27:21.17034 [File System] 2015/08/26 16:27:21 DEBUG - Reading file /sys/class/net/eth0/address
ghost commented 9 years ago

Are you using config-drive or http metadata?

On Wed, Aug 26, 2015 at 9:36 AM, Layne Peng notifications@github.com wrote:

Hi, thanks for quick response. I just check the Starting part, but found nothing...

2015-08-26_16:27:21.09091 [main] 2015/08/26 16:27:21 DEBUG - Starting agent 2015-08-26_16:27:21.09152 [File System] 2015/08/26 16:27:21 DEBUG - Reading file /var/vcap/bosh/agent.json 2015-08-26_16:27:21.09744 [File System] 2015/08/26 16:27:21 DEBUG - Read content 2015-08-26_16:27:21.09745 **** 2015-08-26_16:27:21.09746 { 2015-08-26_16:27:21.09746 "Platform": { 2015-08-26_16:27:21.09746 "Linux": { 2015-08-26_16:27:21.09747 "CreatePartitionIfNoEphemeralDisk": true, 2015-08-26_16:27:21.09747 "DevicePathResolutionType": "virtio" 2015-08-26_16:27:21.09747 } 2015-08-26_16:27:21.09748 }, 2015-08-26_16:27:21.09748 "Infrastructure": { 2015-08-26_16:27:21.09748 "NetworkingType": "dhcp", 2015-08-26_16:27:21.09748 2015-08-26_16:27:21.09749 "Settings": { 2015-08-26_16:27:21.09749 "Sources": [ 2015-08-26_16:27:21.09749 { 2015-08-26_16:27:21.09749 "Type": "ConfigDrive", 2015-08-26_16:27:21.09750 "DiskPaths": [ 2015-08-26_16:27:21.09750 "/dev/disk/by-label/CONFIG-2", 2015-08-26_16:27:21.09750 "/dev/disk/by-label/config-2" 2015-08-26_16:27:21.09751 ], 2015-08-26_16:27:21.09751 "MetaDataPath": "ec2/latest/meta-data.json", 2015-08-26_16:27:21.09751 "UserDataPath": "ec2/latest/user-data" 2015-08-26_16:27:21.09752 }, 2015-08-26_16:27:21.09752 { 2015-08-26_16:27:21.09752 "Type": "HTTP", 2015-08-26_16:27:21.09752 "URI": "http://169.254.169.254" 2015-08-26_16:27:21.09753 } 2015-08-26_16:27:21.09753 ], 2015-08-26_16:27:21.09753 2015-08-26_16:27:21.09754 "UseServerName": true, 2015-08-26_16:27:21.09754 "UseRegistry": true 2015-08-26_16:27:21.09754 } 2015-08-26_16:27:21.09754 } 2015-08-26_16:27:21.09755 } 2015-08-26_16:27:21.09755 2015-08-26_16:27:21.09755 **** 2015-08-26_16:27:21.09756 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Running command: bosh-agent-rc 2015-08-26_16:27:21.10418 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Stdout: 2015-08-26_16:27:21.10419 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Stderr: 2015-08-26_16:27:21.10419 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Successful: true (0) 2015-08-26_16:27:21.10420 [ConfigDriveMetadataService] 2015/08/26 16:27:21 DEBUG - Loading config drive metadata service 2015-08-26_16:27:21.10420 [File System] 2015/08/26 16:27:21 DEBUG - Checking if file exists /dev/disk/by-label/CONFIG-2 2015-08-26_16:27:21.10420 [ConfigDriveMetadataService] 2015/08/26 16:27:21 WARN - Failed to load config from /dev/disk/by-label/CONFIG-2 - Reading files on config drive: Failed to get file contents, disk path '/dev/disk/by-label/CONFIG-2' does not exist

...

2015-08-26_16:27:21.16761 [Cmd Runner] 2015/08/26 16:27:21 DEBUG - Successful: false (1) 2015-08-26_16:27:21.16767 [UbuntuNetManager] 2015/08/26 16:27:21 ERROR - Ignoring failure calling 'pkill dhclient': Running command: 'pkill dhclient', stdout: '', stderr: '': exit status 1 2015-08-26_16:27:21.16769 [File System] 2015/08/26 16:27:21 DEBUG - Glob '/sys/class/net/*' 2015-08-26_16:27:21.17028 [File System] 2015/08/26 16:27:21 DEBUG - Checking if file exists /sys/class/net/eth0/device 2015-08-26_16:27:21.17034 [File System] 2015/08/26 16:27:21 DEBUG - Reading file /sys/class/net/eth0/address

— Reply to this email directly or view it on GitHub https://github.com/cloudfoundry/bosh-init/issues/42#issuecomment-135098441 .

LaynePeng commented 9 years ago

Do you mean the OpenStack metadata service? I am not using it in this BOSH release. But we enable it in our OpenStack environment.

ghost commented 9 years ago

I meant is your CPI configured to use config-drive or http metadata. Can you paste in more debug output? It seems that agent does not see config-drive (Reading files on config drive: Failed to get file contents, disk path '/dev/disk/by-label/CONFIG-2' does not exist) and will try to look at "http://169.254.169.254" next. That probably fails too.

Btw which version of director and stemcells you are using?

On Wed, Aug 26, 2015 at 9:45 AM, Layne Peng notifications@github.com wrote:

Do you mean the OpenStack metadata service? I am not using it in this BOSH release. But we enable it in our OpenStack environment.

— Reply to this email directly or view it on GitHub https://github.com/cloudfoundry/bosh-init/issues/42#issuecomment-135104510 .

LaynePeng commented 9 years ago

BOSH director version: 1.3026.0 Stellcell: bosh-openstack-kvm-ubuntu-trusty-go_agent/3029

I didn't see my CPI configured to use config-drive, the following is my CPI config:

cloud:
  plugin: openstack
  properties:
    openstack:
      auth_url: http://10.10.132.20:5000/v2.0
      username: xxx
      api_key: xxx
      tenant: cloudfoundry
      endpoint_type: publicURL
      state_timeout: 300
      stemcell_public_visibility: false
      boot_from_volume: false
      default_key_name: bosh-kilo
      default_security_groups: ["bosh"]
      wait_resource_poll_interval: 5
      ignore_server_availability_zone: false

    registry:
      endpoint: http://10.0.0.50:25777
      user: admin
      password: admin
      address: 10.0.0.50
      http:
        port: 25777
        user: admin
        password: admin

    agent:
      ntp: [10.32.97.145]

      blobstore:
        provider: dav
        options:
          endpoint: 'http://10.0.0.50:25250'
          user: agent
          password: agent-password
ghost commented 9 years ago

By default openstack CPI assumes it's http metadata unless config_drive prop is specified: https://bosh.io/jobs/director?source=github.com/cloudfoundry/bosh&version=199#p=openstack.config_drive. Ok.

You'll have to provide a bit more output from your agent log, especially what happens after 'disk path '/dev/disk/by-label/CONFIG-2' does not exist'.

On Wed, Aug 26, 2015 at 10:03 AM, Layne Peng notifications@github.com wrote:

BOSH director version: 1.3026.0 Stellcell: bosh-openstack-kvm-ubuntu-trusty-go_agent/3029

I didn't see my CPI configured to use config-drive, the following is my CPI config:

cloud: plugin: openstack properties: openstack: auth_url: http://10.10.132.20:5000/v2.0 username: xxx api_key: xxx tenant: cloudfoundry endpoint_type: publicURL state_timeout: 300 stemcell_public_visibility: false boot_from_volume: false default_key_name: bosh-kilo default_security_groups: ["bosh"] wait_resource_poll_interval: 5 ignore_server_availability_zone: false

registry:
  endpoint: http://10.0.0.50:25777
  user: admin
  password: admin
  address: 10.0.0.50
  http:
    port: 25777
    user: admin
    password: admin

agent:
  ntp: [10.32.97.145]

  blobstore:
    provider: dav
    options:
      endpoint: 'http://10.0.0.50:25250'
      user: agent
      password: agent-password

— Reply to this email directly or view it on GitHub https://github.com/cloudfoundry/bosh-init/issues/42#issuecomment-135109275 .

LaynePeng commented 9 years ago

Thanks. The following is the logs after cannot find CONFIG-2. I really not very familar with this part. :):

2015-08-26_17:05:24.89950 [File System] 2015/08/26 17:05:24 DEBUG - Checking if file exists /dev/disk/by-label/CONFIG-2
2015-08-26_17:05:24.89974 [ConfigDriveMetadataService] 2015/08/26 17:05:24 WARN - Failed to load config from /dev/disk/by-label/CONFIG-2 - Reading files on config drive: Failed to get file contents, disk path '/dev/disk/by-label/CONFIG-2' does not exist
2015-08-26_17:05:24.89988 [File System] 2015/08/26 17:05:24 DEBUG - Checking if file exists /dev/disk/by-label/config-2
2015-08-26_17:05:24.90005 [ConfigDriveMetadataService] 2015/08/26 17:05:24 WARN - Failed to load config from /dev/disk/by-label/config-2 - Reading files on config drive: Failed to get file contents, disk path '/dev/disk/by-label/config-2' does not exist
2015-08-26_17:05:24.90023 [File System] 2015/08/26 17:05:24 DEBUG - Glob '/sys/class/net/*'
2015-08-26_17:05:24.90048 [File System] 2015/08/26 17:05:24 DEBUG - Checking if file exists /sys/class/net/eth0/device
2015-08-26_17:05:24.90067 [File System] 2015/08/26 17:05:24 DEBUG - Reading file /sys/class/net/eth0/address
2015-08-26_17:05:24.90085 [File System] 2015/08/26 17:05:24 DEBUG - Read content
2015-08-26_17:05:24.90095 ********************
2015-08-26_17:05:24.90105 fa:16:3e:9c:64:32
2015-08-26_17:05:24.90115
2015-08-26_17:05:24.90126 ********************
2015-08-26_17:05:24.90138 [File System] 2015/08/26 17:05:24 DEBUG - Checking if file exists /sys/class/net/lo/device
2015-08-26_17:05:24.90160 [Cmd Runner] 2015/08/26 17:05:24 DEBUG - Running command: ifup --no-act eth0
2015-08-26_17:05:24.90348 [Cmd Runner] 2015/08/26 17:05:24 DEBUG - Stdout:
2015-08-26_17:05:24.90361 [Cmd Runner] 2015/08/26 17:05:24 DEBUG - Stderr: ifup: interface eth0 already configured
2015-08-26_17:05:24.90374 [Cmd Runner] 2015/08/26 17:05:24 DEBUG - Successful: true (0)
2015-08-26_17:05:25.08499 [File System] 2015/08/26 17:05:25 DEBUG - Getting HomeDir for vcap
2015-08-26_17:05:25.08835 [File System] 2015/08/26 17:05:25 DEBUG - HomeDir is /home/vcap
2015-08-26_17:05:25.08860 [File System] 2015/08/26 17:05:25 DEBUG - Making dir /home/vcap/.ssh with perm 448
2015-08-26_17:05:25.08874 [File System] 2015/08/26 17:05:25 DEBUG - Chown /home/vcap/.ssh to user vcap
2015-08-26_17:05:25.09651 [File System] 2015/08/26 17:05:25 DEBUG - Writing /home/vcap/.ssh/authorized_keys
2015-08-26_17:05:25.09668 [File System] 2015/08/26 17:05:25 DEBUG - Making dir /home/vcap/.ssh with perm 511
2015-08-26_17:05:25.09696 [File System] 2015/08/26 17:05:25 DEBUG - Write content
2015-08-26_17:05:25.09709 ********************
2015-08-26_17:05:25.09724 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDXwaZwGXtUAxIe8vvC/J18Ij/LTxf356YTWP16JE1+fGBZrKMOBnZmmNmCjXGOBuRgOUEur6gCAKu47EMca2aOah5QCfgLX+/65aC2jdaHBuRfKsBxfodK7z4D53m7HEwJMRGIij6dDw5kAPrAuPmWwfnaqYJ4lpfUyIy/DTPywpDzA/hm/YgP8kQoACyly7PuuJGzAhSbp4SC+Y/ZaiIM2AasKhU4aw14m9m2F6gg9LW/4lINCWf5TeutGZpLOmHfHlZ6vQiW6rciqQexSORVtOOF4d/D0FhhNgqjfAkZbhxa4Fak6snt0b+z1T59g7JBD5OKdcmQ6kGmoNl3kKaF Generated-by-Nova
2015-08-26_17:05:25.09734
2015-08-26_17:05:25.09745 ********************
2015-08-26_17:05:25.09778 [File System] 2015/08/26 17:05:25 DEBUG - Chown /home/vcap/.ssh/authorized_keys to user vcap
2015-08-26_17:05:25.10576 [File System] 2015/08/26 17:05:25 DEBUG - Chmod /home/vcap/.ssh/authorized_keys to 384
2015-08-26_17:05:25.10590 [settingsService] 2015/08/26 17:05:25 DEBUG - Loading settings from fetcher
2015-08-26_17:05:25.10602 [File System] 2015/08/26 17:05:25 DEBUG - Glob '/sys/class/net/*'
2015-08-26_17:05:25.10615 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/eth0/device
2015-08-26_17:05:25.10626 [File System] 2015/08/26 17:05:25 DEBUG - Reading file /sys/class/net/eth0/address
2015-08-26_17:05:25.10646 [File System] 2015/08/26 17:05:25 DEBUG - Read content
2015-08-26_17:05:25.10663 ********************
2015-08-26_17:05:25.10675 fa:16:3e:9c:64:32
2015-08-26_17:05:25.10683
2015-08-26_17:05:25.10691 ********************
2015-08-26_17:05:25.10700 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/lo/device
2015-08-26_17:05:25.10714 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Running command: ifup --no-act eth0
2015-08-26_17:05:25.10858 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stdout:
2015-08-26_17:05:25.10874 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stderr: ifup: interface eth0 already configured
2015-08-26_17:05:25.10889 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Successful: true (0)
2015-08-26_17:05:25.18208 [registryProvider] 2015/08/26 17:05:25 DEBUG - Using http registry at http://10.0.0.50:25777
2015-08-26_17:05:25.18229 [File System] 2015/08/26 17:05:25 DEBUG - Glob '/sys/class/net/*'
2015-08-26_17:05:25.18244 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/eth0/device
2015-08-26_17:05:25.18258 [File System] 2015/08/26 17:05:25 DEBUG - Reading file /sys/class/net/eth0/address
2015-08-26_17:05:25.18270 [File System] 2015/08/26 17:05:25 DEBUG - Read content
2015-08-26_17:05:25.18282 ********************
2015-08-26_17:05:25.18294 fa:16:3e:9c:64:32
2015-08-26_17:05:25.18312
2015-08-26_17:05:25.18327 ********************
2015-08-26_17:05:25.18343 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/lo/device
2015-08-26_17:05:25.18354 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Running command: ifup --no-act eth0
2015-08-26_17:05:25.18540 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stdout:
2015-08-26_17:05:25.18552 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stderr: ifup: interface eth0 already configured
2015-08-26_17:05:25.18565 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Successful: true (0)
2015-08-26_17:05:25.25554 [File System] 2015/08/26 17:05:25 DEBUG - Glob '/sys/class/net/*'
2015-08-26_17:05:25.25592 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/eth0/device
2015-08-26_17:05:25.25619 [File System] 2015/08/26 17:05:25 DEBUG - Reading file /sys/class/net/eth0/address
2015-08-26_17:05:25.25644 [File System] 2015/08/26 17:05:25 DEBUG - Read content
2015-08-26_17:05:25.25665 ********************
2015-08-26_17:05:25.25676 fa:16:3e:9c:64:32
2015-08-26_17:05:25.25685
2015-08-26_17:05:25.25694 ********************
2015-08-26_17:05:25.25717 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/lo/device
2015-08-26_17:05:25.25748 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Running command: ifup --no-act eth0
2015-08-26_17:05:25.25972 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stdout:
2015-08-26_17:05:25.25985 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stderr: ifup: interface eth0 already configured
2015-08-26_17:05:25.25994 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Successful: true (0)
2015-08-26_17:05:25.65541 [settingsService] 2015/08/26 17:05:25 ERROR - Failed loading settings via fetcher: Unmarshalling settings wrapper: invalid character 'I' looking for beginning of value
2015-08-26_17:05:25.65554 [File System] 2015/08/26 17:05:25 DEBUG - Reading file /var/vcap/bosh/settings.json
ghost commented 9 years ago

Ah openstack CPI uses registry. Following error: 'Failed loading settings via fetcher: Unmarshalling settings wrapper: invalid character 'I' looking for beginning of value' indicates that it's failing to get settings from the registry. You should be able to look at the logs on the Director in /var/vcap/sys/log/registry/ and find a stacktrace or an error.

On Wed, Aug 26, 2015 at 10:23 AM, Layne Peng notifications@github.com wrote:

Thanks. The following is the logs after cannot find CONFIG-2. I really not very familar with this part. :):

2015-08-26_17:05:24.89950 [File System] 2015/08/26 17:05:24 DEBUG - Checking if file exists /dev/disk/by-label/CONFIG-2 2015-08-26_17:05:24.89974 [ConfigDriveMetadataService] 2015/08/26 17:05:24 WARN - Failed to load config from /dev/disk/by-label/CONFIG-2 - Reading files on config drive: Failed to get file contents, disk path '/dev/disk/by-label/CONFIG-2' does not exist 2015-08-26_17:05:24.89988 [File System] 2015/08/26 17:05:24 DEBUG - Checking if file exists /dev/disk/by-label/config-2 2015-08-26_17:05:24.90005 [ConfigDriveMetadataService] 2015/08/26 17:05:24 WARN - Failed to load config from /dev/disk/by-label/config-2 - Reading files on config drive: Failed to get file contents, disk path '/dev/disk/by-label/config-2' does not exist 2015-08-2617:05:24.90023 [File System] 2015/08/26 17:05:24 DEBUG - Glob '/sys/class/net/' 2015-08-26_17:05:24.90048 [File System] 2015/08/26 17:05:24 DEBUG - Checking if file exists /sys/class/net/eth0/device 2015-08-26_17:05:24.90067 [File System] 2015/08/26 17:05:24 DEBUG - Reading file /sys/class/net/eth0/address 2015-08-26_17:05:24.90085 [File System] 2015/08/26 17:05:24 DEBUG - Read content 2015-08-2617:05:24.90095 *** 2015-08-26_17:05:24.90105 fa:16:3e:9c:64:32 2015-08-26_17:05:24.90115 2015-08-26_17:05:24.90126 **** 2015-08-26_17:05:24.90138 [File System] 2015/08/26 17:05:24 DEBUG - Checking if file exists /sys/class/net/lo/device 2015-08-26_17:05:24.90160 [Cmd Runner] 2015/08/26 17:05:24 DEBUG - Running command: ifup --no-act eth0 2015-08-26_17:05:24.90348 [Cmd Runner] 2015/08/26 17:05:24 DEBUG - Stdout: 2015-08-26_17:05:24.90361 [Cmd Runner] 2015/08/26 17:05:24 DEBUG - Stderr: ifup: interface eth0 already configured 2015-08-26_17:05:24.90374 [Cmd Runner] 2015/08/26 17:05:24 DEBUG - Successful: true (0) 2015-08-26_17:05:25.08499 [File System] 2015/08/26 17:05:25 DEBUG - Getting HomeDir for vcap 2015-08-26_17:05:25.08835 [File System] 2015/08/26 17:05:25 DEBUG - HomeDir is /home/vcap 2015-08-26_17:05:25.08860 [File System] 2015/08/26 17:05:25 DEBUG - Making dir /home/vcap/.ssh with perm 448 2015-08-26_17:05:25.08874 [File System] 2015/08/26 17:05:25 DEBUG - Chown /home/vcap/.ssh to user vcap 2015-08-26_17:05:25.09651 [File System] 2015/08/26 17:05:25 DEBUG - Writing /home/vcap/.ssh/authorized_keys 2015-08-26_17:05:25.09668 [File System] 2015/08/26 17:05:25 DEBUG - Making dir /home/vcap/.ssh with perm 511 2015-08-26_17:05:25.09696 [File System] 2015/08/26 17:05:25 DEBUG - Write content 2015-08-26_17:05:25.09709 **** 2015-08-26_17:05:25.09724 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDXwaZwGXtUAxIe8vvC/J18Ij/LTxf356YTWP16JE1+fGBZrKMOBnZmmNmCjXGOBuRgOUEur6gCAKu47EMca2aOah5QCfgLX+/65aC2jdaHBuRfKsBxfodK7z4D53m7HEwJMRGIij6dDw5kAPrAuPmWwfnaqYJ4lpfUyIy/DTPywpDzA/hm/YgP8kQoACyly7PuuJGzAhSbp4SC+Y/ZaiIM2AasKhU4aw14m9m2F6gg9LW/4lINCWf5TeutGZpLOmHfHlZ6vQiW6rciqQexSORVtOOF4d/D0FhhNgqjfAkZbhxa4Fak6snt0b+z1T59g7JBD5OKdcmQ6kGmoNl3kKaF Generated-by-Nova 2015-08-26_17:05:25.09734 2015-08-26_17:05:25.09745 **** 2015-08-26_17:05:25.09778 [File System] 2015/08/26 17:05:25 DEBUG - Chown /home/vcap/.ssh/authorized_keys to user vcap 2015-08-26_17:05:25.10576 [File System] 2015/08/26 17:05:25 DEBUG - Chmod /home/vcap/.ssh/authorized_keys to 384 2015-08-26_17:05:25.10590 [settingsService] 2015/08/26 17:05:25 DEBUG - Loading settings from fetcher 2015-08-2617:05:25.10602 [File System] 2015/08/26 17:05:25 DEBUG - Glob '/sys/class/net/' 2015-08-26_17:05:25.10615 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/eth0/device 2015-08-26_17:05:25.10626 [File System] 2015/08/26 17:05:25 DEBUG - Reading file /sys/class/net/eth0/address 2015-08-26_17:05:25.10646 [File System] 2015/08/26 17:05:25 DEBUG - Read content 2015-08-2617:05:25.10663 *** 2015-08-26_17:05:25.10675 fa:16:3e:9c:64:32 2015-08-26_17:05:25.10683 2015-08-26_17:05:25.10691 **** 2015-08-26_17:05:25.10700 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/lo/device 2015-08-26_17:05:25.10714 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Running command: ifup --no-act eth0 2015-08-26_17:05:25.10858 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stdout: 2015-08-26_17:05:25.10874 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stderr: ifup: interface eth0 already configured 2015-08-26_17:05:25.10889 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Successful: true (0) 2015-08-26_17:05:25.18208 [registryProvider] 2015/08/26 17:05:25 DEBUG - Using http registry at http://10.0.0.50:25777 2015-08-2617:05:25.18229 [File System] 2015/08/26 17:05:25 DEBUG - Glob '/sys/class/net/' 2015-08-26_17:05:25.18244 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/eth0/device 2015-08-26_17:05:25.18258 [File System] 2015/08/26 17:05:25 DEBUG - Reading file /sys/class/net/eth0/address 2015-08-26_17:05:25.18270 [File System] 2015/08/26 17:05:25 DEBUG - Read content 2015-08-2617:05:25.18282 *** 2015-08-26_17:05:25.18294 fa:16:3e:9c:64:32 2015-08-26_17:05:25.18312 2015-08-26_17:05:25.18327 **** 2015-08-26_17:05:25.18343 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/lo/device 2015-08-26_17:05:25.18354 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Running command: ifup --no-act eth0 2015-08-26_17:05:25.18540 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stdout: 2015-08-26_17:05:25.18552 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stderr: ifup: interface eth0 already configured 2015-08-26_17:05:25.18565 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Successful: true (0) 2015-08-2617:05:25.25554 [File System] 2015/08/26 17:05:25 DEBUG - Glob '/sys/class/net/' 2015-08-26_17:05:25.25592 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/eth0/device 2015-08-26_17:05:25.25619 [File System] 2015/08/26 17:05:25 DEBUG - Reading file /sys/class/net/eth0/address 2015-08-26_17:05:25.25644 [File System] 2015/08/26 17:05:25 DEBUG - Read content 2015-08-2617:05:25.25665 *** 2015-08-26_17:05:25.25676 fa:16:3e:9c:64:32 2015-08-26_17:05:25.25685 2015-08-26_17:05:25.25694 **** 2015-08-26_17:05:25.25717 [File System] 2015/08/26 17:05:25 DEBUG - Checking if file exists /sys/class/net/lo/device 2015-08-26_17:05:25.25748 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Running command: ifup --no-act eth0 2015-08-26_17:05:25.25972 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stdout: 2015-08-26_17:05:25.25985 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Stderr: ifup: interface eth0 already configured 2015-08-26_17:05:25.25994 [Cmd Runner] 2015/08/26 17:05:25 DEBUG - Successful: true (0) 2015-08-26_17:05:25.65541 [settingsService] 2015/08/26 17:05:25 ERROR - Failed loading settings via fetcher: Unmarshalling settings wrapper: invalid character 'I' looking for beginning of value 2015-08-26_17:05:25.65554 [File System] 2015/08/26 17:05:25 DEBUG - Reading file /var/vcap/bosh/settings.json

— Reply to this email directly or view it on GitHub https://github.com/cloudfoundry/bosh-init/issues/42#issuecomment-135114292 .

LaynePeng commented 9 years ago

Oh! Good! Thank you very much for I finally find the problem. I change the tenant I provision releases, but only change the CPI configuration, but forget the registry configuration!! That is the problem!!

Thank you again for your hint!