search

docker / machine

Machine management for a container-centric world
https://docs.docker.com/machine/
Apache License 2.0
6.63k stars 1.97k forks source link

Constant vmsvc warnings in console of machine #1396

Open fgimian opened 9 years ago

fgimian commented 9 years ago

Hey there folks, love docker-machine, thank you :smile:

Just a little annoyance, I'm getting constant warnings in the console from vmsvc:

screen shot 2015-06-21 at 9 59 03 am

Cheers Fotis

nathanleclaire commented 9 years ago

Hi @fgimian, could you give a little more detail about how to reproduce this issue please?

fgimian commented 9 years ago

Sure @nathanleclaire, thanks for your help.

Firstly, my system details are:

We start by installing docker and docker-machine using homebrew:

> brew install docker docker-machine
==> Downloading https://homebrew.bintray.com/bottles/docker-1.7.0.yosemite.bottle.tar.gz
Already downloaded: /Library/Caches/Homebrew/docker-1.7.0.yosemite.bottle.tar.gz
==> Pouring docker-1.7.0.yosemite.bottle.tar.gz
==> Caveats

Bash completion has been installed to:
  /usr/local/etc/bash_completion.d

zsh completion has been installed to:
  /usr/local/share/zsh/site-functions
==> Summary
🍺  /usr/local/Cellar/docker/1.7.0: 9 files, 7.4M
==> Downloading https://homebrew.bintray.com/bottles/docker-machine-0.3.0.yosemite.bottle.1.tar.gz
Already downloaded: /Library/Caches/Homebrew/docker-machine-0.3.0.yosemite.bottle.1.tar.gz
==> Pouring docker-machine-0.3.0.yosemite.bottle.1.tar.gz
🍺  /usr/local/Cellar/docker-machine/0.3.0: 5 files, 16M

Next up, create the machine:

> docker-machine create -d vmwarefusion dev
Creating CA: /Users/fots/.docker/machine/certs/ca.pem
Creating client certificate: /Users/fots/.docker/machine/certs/cert.pem
Image cache does not exist, creating it at /Users/fots/.docker/machine/cache...
No default boot2docker iso found locally, downloading the latest release...
Downloading https://github.com/boot2docker/boot2docker/releases/download/v1.7.0/boot2docker.iso to /Users/fots/.docker/machine/cache/boot2docker.iso...
Creating SSH key...
Creating VM...
Starting dev...
Waiting for VM to come online...
To see how to connect Docker to this machine, run: docker-machine env dev

And now simply open VMware Fusion and check out the console window after a few minutes :smile:

screen shot 2015-07-07 at 7 21 00 pm

Also note the other errors during startup, these are all related to the same vmtoolsd daemon which is described further below.

This just continues on constantly from that point onwards:

screen shot 2015-07-07 at 7 30 07 pm

It seems to relate to openvm-tools:

root@dev:~# ps -ef | grep vmtools
root      1866     1  0 09:19 ?        00:00:00 /usr/local/bin/vmtoolsd
root      2236  2197  0 09:26 pts/1    00:00:00 grep vmtools

If I kill this process, the errors disappear.

There's a related issue on the boot2docker issues list which you may check out here: https://github.com/boot2docker/boot2docker/issues/464

Any help would be greatly appreciated, would love to see the console nice and clean on this :smile:

Cheers Fotis

fgimian commented 9 years ago

Here's an strace when the error happens in case it's helpful:

root@dev:~# strace -p 2239 2>&1 | egrep -v "^times|^poll"
Process 2239 attached
restart_syscall(<... resuming interrupted call ...>) = 0
stat("/etc/vmware-tools/tools.conf", 0x7ffde6139600) = -1 ENOENT (No such file or directory)
getrlimit(RLIMIT_CORE, {rlim_cur=RLIM64_INFINITY, rlim_max=RLIM64_INFINITY}) = 0
setrlimit(RLIMIT_CORE, {rlim_cur=RLIM64_INFINITY, rlim_max=RLIM64_INFINITY}) = 0
stat("/etc/vmware-tools/tools.conf", 0x7ffde6139600) = -1 ENOENT (No such file or directory)
getrlimit(RLIMIT_CORE, {rlim_cur=RLIM64_INFINITY, rlim_max=RLIM64_INFINITY}) = 0
setrlimit(RLIMIT_CORE, {rlim_cur=RLIM64_INFINITY, rlim_max=RLIM64_INFINITY}) = 0
open("/etc/mtab", O_RDONLY|O_CLOEXEC)   = 6
open("/proc/devices", O_RDONLY)         = 7
fstat(7, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d34a000
read(7, "Character devices:\n  1 mem\n  4 /"..., 1024) = 502
read(7, "", 1024)                       = 0
close(7)                                = 0
munmap(0x7fe44d34a000, 4096)            = 0
fstat(6, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d34a000
read(6, "tmpfs / tmpfs rw,relatime,size=9"..., 1024) = 1024
stat("/dev/sda1", {st_mode=S_IFBLK|0660, st_rdev=makedev(8, 1), ...}) = 0
read(6, "s /Users vmhgfs rw,relatime 0 0\n"..., 1024) = 170
stat("/dev/sda1", {st_mode=S_IFBLK|0660, st_rdev=makedev(8, 1), ...}) = 0
read(6, "", 1024)                       = 0
close(6)                                = 0
munmap(0x7fe44d34a000, 4096)            = 0
statfs("/mnt/sda1", {f_type="EXT2_SUPER_MAGIC", f_bsize=4096, f_blocks=4766133, f_bfree=4750762, f_bavail=4502890, f_files=1220608, f_ffree=1220537, f_fsid={542762188, 623317981}, f_namelen=255, f_frsize=4096}) = 0
geteuid()                               = 0
statfs("/mnt/sda1/var/lib/docker/aufs", {f_type="EXT2_SUPER_MAGIC", f_bsize=4096, f_blocks=4766133, f_bfree=4750762, f_bavail=4502890, f_files=1220608, f_ffree=1220537, f_fsid={542762188, 623317981}, f_namelen=255, f_frsize=4096}) = 0
geteuid()                               = 0
uname({sys="Linux", node="dev", ...})   = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
open("/proc/net/dev", O_RDONLY)         = 7
ioctl(6, SIOCGIFCONF, {120, {{"lo", {AF_INET, inet_addr("127.0.0.1")}}, {"eth0", {AF_INET, inet_addr("172.16.36.129")}}, {"docker0", {AF_INET, inet_addr("172.17.42.1")}}}}) = 0
fstat(7, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d34a000
read(7, "Inter-|   Receive               "..., 1024) = 693
ioctl(6, SIOCGIFFLAGS, {ifr_name="eth0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
ioctl(6, SIOCGIFMTU, {ifr_name="eth0", ifr_mtu=1500}) = 0
ioctl(6, SIOCGIFADDR, {ifr_name="eth0", ifr_addr={AF_INET, inet_addr("172.16.36.129")}}) = 0
ioctl(6, SIOCGIFNETMASK, {ifr_name="eth0", ifr_netmask={AF_INET, inet_addr("255.255.255.0")}}) = 0
ioctl(6, SIOCGIFHWADDR, {ifr_name="eth0", ifr_hwaddr=00:0c:29:b3:e8:9b}) = 0
open("/proc/net/if_inet6", O_RDONLY)    = 8
fstat(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d349000
read(8, "00000000000000000000000000000001"..., 1024) = 162
read(8, "", 1024)                       = 0
close(8)                                = 0
munmap(0x7fe44d349000, 4096)            = 0
ioctl(6, SIOCGIFFLAGS, {ifr_name="docker0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
ioctl(6, SIOCGIFMTU, {ifr_name="docker0", ifr_mtu=1500}) = 0
ioctl(6, SIOCGIFADDR, {ifr_name="docker0", ifr_addr={AF_INET, inet_addr("172.17.42.1")}}) = 0
ioctl(6, SIOCGIFNETMASK, {ifr_name="docker0", ifr_netmask={AF_INET, inet_addr("255.255.0.0")}}) = 0
ioctl(6, SIOCGIFHWADDR, {ifr_name="docker0", ifr_hwaddr=e6:50:90:c2:a7:1a}) = 0
open("/proc/net/if_inet6", O_RDONLY)    = 8
fstat(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d349000
read(8, "00000000000000000000000000000001"..., 1024) = 162
read(8, "", 1024)                       = 0
close(8)                                = 0
munmap(0x7fe44d349000, 4096)            = 0
ioctl(6, SIOCGIFFLAGS, {ifr_name="dummy0", ifr_flags=IFF_BROADCAST|IFF_NOARP}) = 0
ioctl(6, SIOCGIFMTU, {ifr_name="dummy0", ifr_mtu=1500}) = 0
ioctl(6, SIOCGIFADDR, {ifr_name="dummy0", ???}) = -1 EADDRNOTAVAIL (Cannot assign requested address)
ioctl(6, SIOCGIFHWADDR, {ifr_name="dummy0", ifr_hwaddr=8a:15:56:f9:94:33}) = 0
open("/proc/net/if_inet6", O_RDONLY)    = 8
fstat(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d349000
read(8, "00000000000000000000000000000001"..., 1024) = 162
read(8, "", 1024)                       = 0
close(8)                                = 0
munmap(0x7fe44d349000, 4096)            = 0
ioctl(6, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
ioctl(6, SIOCGIFMTU, {ifr_name="lo", ifr_mtu=65536}) = 0
ioctl(6, SIOCGIFADDR, {ifr_name="lo", ifr_addr={AF_INET, inet_addr("127.0.0.1")}}) = 0
ioctl(6, SIOCGIFNETMASK, {ifr_name="lo", ifr_netmask={AF_INET, inet_addr("255.0.0.0")}}) = 0
open("/proc/net/if_inet6", O_RDONLY)    = 8
fstat(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d349000
read(8, "00000000000000000000000000000001"..., 1024) = 162
read(8, "", 1024)                       = 0
close(8)                                = 0
munmap(0x7fe44d349000, 4096)            = 0
read(7, "", 1024)                       = 0
close(7)                                = 0
munmap(0x7fe44d34a000, 4096)            = 0
close(6)                                = 0
open("/etc/resolv.conf", O_RDONLY|O_CLOEXEC) = 6
fstat(6, {st_mode=S_IFREG|0664, st_size=42, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d34a000
read(6, "search localdomain\nnameserver 17"..., 4096) = 42
read(6, "", 4096)                       = 0
close(6)                                = 0
munmap(0x7fe44d34a000, 4096)            = 0
uname({sys="Linux", node="dev", ...})   = 0
access("/proc/net/route", F_OK)         = 0
open("/proc/net/route", O_RDONLY)       = 6
fstat(6, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
fcntl(6, F_GETFL)                       = 0x8000 (flags O_RDONLY|O_LARGEFILE)
read(6, "Iface\tDestination\tGateway \tFlags"..., 1024) = 640
read(6, "", 1024)                       = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFINDEX, {ifr_name="eth0", ifr_index=3}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=3, ifr_name="eth0"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="eth0", ifr_hwaddr=00:0c:29:b3:e8:9b}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFINDEX, {ifr_name="lo", ifr_index=1}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="lo", ifr_hwaddr=00:00:00:00:00:00}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFINDEX, {ifr_name="eth0", ifr_index=3}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=3, ifr_name="eth0"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="eth0", ifr_hwaddr=00:0c:29:b3:e8:9b}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFINDEX, {ifr_name="docker0", ifr_index=4}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=4, ifr_name="docker0"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="docker0", ifr_hwaddr=e6:50:90:c2:a7:1a}) = 0
close(6)                                = 0
access("/proc/net/ipv6_route", F_OK)    = 0
open("/proc/net/ipv6_route", O_RDONLY)  = 6
fstat(6, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
fcntl(6, F_GETFL)                       = 0x8000 (flags O_RDONLY|O_LARGEFILE)
read(6, "fe800000000000000000000000000000"..., 1024) = 1024
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="eth0", ifr_index=3}) = 0
close(7)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="docker0", ifr_index=4}) = 0
close(7)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="lo", ifr_index=1}) = 0
close(7)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="lo", ifr_index=1}) = 0
close(7)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="lo", ifr_index=1}) = 0
close(7)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="lo", ifr_index=1}) = 0
close(7)                                = 0
read(6, "0000000 00000001     eth0\nff0000"..., 1024) = 326
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="eth0", ifr_index=3}) = 0
close(7)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="docker0", ifr_index=4}) = 0
close(7)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
ioctl(7, SIOCGIFINDEX, {ifr_name="lo", ifr_index=1}) = 0
close(7)                                = 0
read(6, "", 1024)                       = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=3, ifr_name="eth0"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="eth0", ifr_hwaddr=00:0c:29:b3:e8:9b}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=4, ifr_name="docker0"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="docker0", ifr_hwaddr=e6:50:90:c2:a7:1a}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="lo", ifr_hwaddr=00:00:00:00:00:00}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="lo", ifr_hwaddr=00:00:00:00:00:00}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="lo", ifr_hwaddr=00:00:00:00:00:00}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=3, ifr_name="eth0"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="eth0", ifr_hwaddr=00:0c:29:b3:e8:9b}) = 0
close(6)                                = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
ioctl(6, SIOCGIFNAME, {ifr_index=4, ifr_name="docker0"}) = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
ioctl(6, SIOCGIFHWADDR, {ifr_name="docker0", ifr_hwaddr=e6:50:90:c2:a7:1a}) = 0
close(6)                                = 0
open("/proc/uptime", O_RDONLY)          = 6
fstat(6, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d34a000
read(6, "1081.82 1005.74\n", 1024)      = 16
close(6)                                = 0
munmap(0x7fe44d34a000, 4096)            = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 6
connect(6, {sa_family=AF_LOCAL, sun_path="/dev/log"}, 110) = -1 ENOENT (No such file or directory)
close(6)                                = 0
open("/dev/console", O_WRONLY|O_NOCTTY) = 6
fstat(6, {st_mode=S_IFCHR|0600, st_rdev=makedev(5, 1), ...}) = 0
ioctl(6, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, {B38400 opost -isig -icanon -echo ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d34a000
lseek(6, 0, SEEK_CUR)                   = -1 ESPIPE (Illegal seek)
write(6, "vmsvc[2239]: [ warning] [guestin"..., 59) = 59
write(6, "\r\n", 2)                     = 2
munmap(0x7fe44d34a000, 4096)            = 0
close(6)                                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
open("/proc/net/dev", O_RDONLY)         = 7
ioctl(6, SIOCGIFCONF, {120, {{"lo", {AF_INET, inet_addr("127.0.0.1")}}, {"eth0", {AF_INET, inet_addr("172.16.36.129")}}, {"docker0", {AF_INET, inet_addr("172.17.42.1")}}}}) = 0
fstat(7, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d34a000
read(7, "Inter-|   Receive               "..., 1024) = 693
ioctl(6, SIOCGIFFLAGS, {ifr_name="eth0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
ioctl(6, SIOCGIFMTU, {ifr_name="eth0", ifr_mtu=1500}) = 0
ioctl(6, SIOCGIFADDR, {ifr_name="eth0", ifr_addr={AF_INET, inet_addr("172.16.36.129")}}) = 0
ioctl(6, SIOCGIFNETMASK, {ifr_name="eth0", ifr_netmask={AF_INET, inet_addr("255.255.255.0")}}) = 0
ioctl(6, SIOCGIFHWADDR, {ifr_name="eth0", ifr_hwaddr=00:0c:29:b3:e8:9b}) = 0
open("/proc/net/if_inet6", O_RDONLY)    = 8
fstat(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe44d349000
read(8, "00000000000000000000000000000001"..., 1024) = 162
read(8, "", 1024)                       = 0
close(8)                                = 0
munmap(0x7fe44d349000, 4096)            = 0
close(7)                                = 0
munmap(0x7fe44d34a000, 4096)            = 0
close(6)                                = 0
stat("/etc/vmware-tools/tools.conf", 0x7ffde6139600) = -1 ENOENT (No such file or directory)
getrlimit(RLIMIT_CORE, {rlim_cur=RLIM64_INFINITY, rlim_max=RLIM64_INFINITY}) = 0
setrlimit(RLIMIT_CORE, {rlim_cur=RLIM64_INFINITY, rlim_max=RLIM64_INFINITY}) = 0
^C
root@dev:~#
fgimian commented 9 years ago

OK, I started getting into this. After enabling syslog on this box, I can clearly see all errors which are created by vmtoolsd.

Jul  7 09:51:53 dev user.warn vmsvc[2863]: [ warning] [vmsvc] SOCKET failed to create socket, error 97: Address family not supported by protocol
Jul  7 09:51:53 dev user.warn vmsvc[2863]: [ warning] [vmsvc] SimpleSock: Couldn't get VMCI socket family info.
Jul  7 09:51:53 dev user.warn vmsvc[2863]: [ warning] [vmtoolsd] Opening plugin 'libdeployPkgPlugin.so' failed: libmspack.so.0: cannot open shared object file: No such file or directory.
Jul  7 09:52:23 dev user.warn vmsvc[2863]: [ warning] [guestinfo] Failed to get vmstats.
Jul  7 09:52:53 dev user.warn vmsvc[2863]: [ warning] [guestinfo] Failed to get vmstats.

I raised the debug level of vmtoolsd but sadly it didn't really provide any further useful info:

root@dev:~# cat /var/log/vmtoolsd.log 
[Jul 07 10:07:21.989] [   debug] [vmtoolsd] CmdLine: "/usr/local/bin/vmtoolsd"
[Jul 07 10:07:21.989] [ message] [vmtoolsd] Tools Version: 9.10.0.43888 (build-2476743)
[Jul 07 10:07:22.001] [ warning] [vmtoolsd] Opening plugin 'libdeployPkgPlugin.so' failed: libmspack.so.0: cannot open shared object file: No such file or directory.
[Jul 07 10:07:22.001] [   debug] [vmtoolsd] Plugin 'hgfsServer' initialized.
[Jul 07 10:07:22.001] [   debug] [vmtoolsd] Plugin 'vix' initialized.
[Jul 07 10:07:22.001] [   debug] [vmtoolsd] Plugin 'guestInfo' initialized.
[Jul 07 10:07:22.001] [   debug] [vmtoolsd] Plugin 'powerops' initialized.
[Jul 07 10:07:22.001] [   debug] [vmtoolsd] Plugin 'timeSync' initialized.
[Jul 07 10:07:22.001] [   debug] [vmtoolsd] Plugin 'vmbackup' initialized.
[Jul 07 10:07:22.004] [   debug] [vmtoolsd] Setting option 'synctime' to '0'.
[Jul 07 10:07:22.004] [   debug] [vmtoolsd] Setting option 'copypaste' to '1'.
[Jul 07 10:07:22.004] [   debug] [vmtoolsd] Setting option 'autohide' to '0'.
[Jul 07 10:07:22.004] [   debug] [vmtoolsd] Setting option 'broadcastIP' to '1'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'enableDnD' to '1'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'synctime.period' to '0'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'time.synchronize.tools.enable' to '1'.
[Jul 07 10:07:22.008] [   debug] [vmtoolsd] Setting option 'time.synchronize.tools.startup' to '1'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'toolScripts.afterPowerOn' to '1'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'toolScripts.beforePowerOff' to '1'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'toolScripts.afterResume' to '1'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'toolScripts.beforeSuspend' to '1'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'time.synchronize.tools.slewCorrection' to '1'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'time.synchronize.tools.percentCorrection' to '0'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'mapRootHgfsShare' to '0'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'linkRootHgfsShare' to '0'.
[Jul 07 10:07:22.007] [   debug] [vmtoolsd] Setting option 'enableMessageBusTunnel' to '0'.
[Jul 07 10:07:52.060] [   debug] [vmtoolsd] Setting option 'broadcastIP' to '1'.
[Jul 07 10:08:22.119] [   debug] [vmtoolsd] Setting option 'broadcastIP' to '1'.
[Jul 07 10:08:52.077] [   debug] [vmtoolsd] Setting option 'broadcastIP' to '1'.
[Jul 07 10:09:22.116] [   debug] [vmtoolsd] Setting option 'broadcastIP' to '1'.

Any ideas? Fotis

nathanleclaire commented 9 years ago

cc @frapposelli

frapposelli commented 9 years ago

This is expected, boot2docker doesn't carry vmstat (used to gather guest metrics to report back to the hypervisor). That is why vmtoolsd complains.

slevine commented 9 years ago

Any guidance on how to turn down/up the log level to mute it?

akurach commented 8 years ago

https://communities.vmware.com/message/2261082#2261082