failed to start a container with docker

[lxm]

follow the doc "Run it with docker"

when i start a container, the container will fail.
errorlog:

compute dockerd[25828]: time="2017-12-22T11:49:56.871309975+08:00" level=warning msg="failed to retrieve /usr/local/bin/runv version: unknown output format: runv version 1.0.0, commit: \n"

my docker version info
Containers: 9
Running: 0
Paused: 0
Stopped: 9
Images: 42
Server Version: 17.12.0-ce-rc3
Storage Driver: devicemapper
Pool Name: docker-8:3-269506593-pool
Pool Blocksize: 65.54kB
Base Device Size: 10.74GB
Backing Filesystem: xfs
Udev Sync Supported: true
Data file: /dev/loop0
Metadata file: /dev/loop1
Data loop file: /var/lib/docker/devicemapper/devicemapper/data
Metadata loop file: /var/lib/docker/devicemapper/devicemapper/metadata
Data Space Used: 4.419GB
Data Space Total: 107.4GB
Data Space Available: 103GB
Metadata Space Used: 6.058MB
Metadata Space Total: 2.147GB
Metadata Space Available: 2.141GB
Thin Pool Minimum Free Space: 10.74GB
Deferred Removal Enabled: true
Deferred Deletion Enabled: true
Deferred Deleted Device Count: 0
Library Version: 1.02.140-RHEL7 (2017-05-03)
Logging Driver: json-file
Cgroup Driver: cgroupfs
Plugins:
Volume: local
Network: bridge host kuryr macvlan null overlay
Log: awslogs fluentd gcplogs gelf journald json-file logentries splunk syslog
Swarm: inactive
Runtimes: runc runv
Default Runtime: runv

[laijs]

It because the new version of docker depends on the format of the output of the runtime --version.
Although either this behavior nor the format is documented in the OCI. We are going to use the same format too. Thanks for the report.

[laijs]

@lxm see #658

[lxm]

still hava trouble with staring a container
version error disapper，but the containter still get into stopped status immediately after docker run xxx command

if use docker run -it busybox /bin/sh will have a error:
set winsize failed, rpc error: code = Unavailable desc = all SubConns are in TransientFailure

the full journalctl log

Dec 22 17:25:10 compute kernel: XFS (dm-1): Mounting V5 Filesystem
Dec 22 17:25:10 compute kernel: XFS (dm-1): Ending clean mount
Dec 22 17:25:10 compute kernel: XFS (dm-1): Unmounting Filesystem
Dec 22 17:25:10 compute kernel: XFS (dm-1): Mounting V5 Filesystem
Dec 22 17:25:10 compute kernel: XFS (dm-1): Ending clean mount
Dec 22 17:25:10 compute kernel: XFS (dm-1): Unmounting Filesystem
Dec 22 17:25:10 compute kernel: XFS (dm-1): Mounting V5 Filesystem
Dec 22 17:25:10 compute kernel: XFS (dm-1): Ending clean mount
Dec 22 17:25:10 compute kernel: docker0: port 1(veth1f64f0e) entered blocking state
Dec 22 17:25:10 compute kernel: docker0: port 1(veth1f64f0e) entered disabled state
Dec 22 17:25:10 compute kernel: device veth1f64f0e entered promiscuous mode
Dec 22 17:25:10 compute kernel: IPv6: ADDRCONF(NETDEV_UP): veth1f64f0e: link is not ready
Dec 22 17:25:10 compute NetworkManager[982]: [1513934710.4118] manager: (vethfcd6f4f): new Veth device (/org/freedesktop/NetworkManager/Devices/314)
Dec 22 17:25:10 compute NetworkManager[982]: [1513934710.4141] manager: (veth1f64f0e): new Veth device (/org/freedesktop/NetworkManager/Devices/315)
Dec 22 17:25:10 compute dockerd[9347]: time="2017-12-22T17:25:10.445182601+08:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/create type="*events.ContainerCreate"
Dec 22 17:25:10 compute dockerd[9347]: time="2017-12-22T17:25:10+08:00" level=info msg="shim docker-containerd-shim started" address="/containerd-shim/moby/23e03516d0afd11a59b088531f47bd4f78469f5dc64acde77305d08cef7f8c6d/shim.sock" debug=false module="containerd/tasks" pid=10308
Dec 22 17:25:10 compute kvm[10335]: 3 guests now active
Dec 22 17:25:12 compute kernel: kvm [10333]: vcpu0 unhandled rdmsr: 0x1c9
Dec 22 17:25:12 compute kernel: kvm [10333]: vcpu0 unhandled rdmsr: 0x1a6
Dec 22 17:25:12 compute kernel: kvm [10333]: vcpu0 unhandled rdmsr: 0x1a7
Dec 22 17:25:12 compute kernel: kvm [10333]: vcpu0 unhandled rdmsr: 0x3f6
Dec 22 17:25:12 compute kernel: kvm [10333]: vcpu0 unhandled rdmsr: 0x34
Dec 22 17:25:12 compute kernel: kvm [10333]: vcpu0 unhandled rdmsr: 0x606
Dec 22 17:25:12 compute dockerd[9347]: time="2017-12-22T17:25:12.953807121+08:00" level=warning msg="failed to retrieve /usr/local/bin/runv version: unknown output format: runv version 1.0.0, commit: v1.0.0-97-g26a9c72\n"
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13.003981825+08:00" level=warning msg="failed to retrieve /usr/local/bin/runv version: unknown output format: runv version 1.0.0, commit: v1.0.0-97-g26a9c72\n"
Dec 22 17:25:13 compute kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth1f64f0e: link becomes ready
Dec 22 17:25:13 compute kernel: docker0: port 1(veth1f64f0e) entered blocking state
Dec 22 17:25:13 compute kernel: docker0: port 1(veth1f64f0e) entered forwarding state
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.2111] device (veth1f64f0e): link connected
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.2112] device (docker0): link connected
Dec 22 17:25:13 compute kernel: runv0: port 1(vm-scfGpXbAp0) entered blocking state
Dec 22 17:25:13 compute kernel: runv0: port 1(vm-scfGpXbAp0) entered disabled state
Dec 22 17:25:13 compute kernel: device vm-scfGpXbAp0 entered promiscuous mode
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.2261] manager: (vm-scfGpXbAp0): new Tun device (/org/freedesktop/NetworkManager/Devices/316)
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.2274] device (vm-scfGpXbAp0): state change: unmanaged -> unavailable (reason 'connection-assumed') [10 20 41]
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.2282] device (vm-scfGpXbAp0): state change: unavailable -> disconnected (reason 'none') [20 30 0]
Dec 22 17:25:13 compute kernel: device vm-scfGpXbAp0 left promiscuous mode
Dec 22 17:25:13 compute kernel: runv0: port 1(vm-scfGpXbAp0) entered disabled state
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.3245] device (vm-scfGpXbAp0): state change: disconnected -> unmanaged (reason 'unmanaged') [30 10 3]
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.3246] device (vm-scfGpXbAp0): released from master device runv0
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13.337350374+08:00" level=warning msg="unknown container" container=23e03516d0afd11a59b088531f47bd4f78469f5dc64acde77305d08cef7f8c6d module=libcontainerd namespace=moby-plugins
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13.428083203+08:00" level=warning msg="unknown container" container=23e03516d0afd11a59b088531f47bd4f78469f5dc64acde77305d08cef7f8c6d module=libcontainerd namespace=moby-plugins
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13.452337905+08:00" level=error msg="Handler for POST /v1.35/containers/23e03516d0afd11a59b088531f47bd4f78469f5dc64acde77305d08cef7f8c6d/resize returned error: cannot resize a stopped container: unknown"
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13.456245175+08:00" level=warning msg="unknown container" container=23e03516d0afd11a59b088531f47bd4f78469f5dc64acde77305d08cef7f8c6d module=libcontainerd namespace=moby-plugins
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13+08:00" level=info msg="shim reaped" id=23e03516d0afd11a59b088531f47bd4f78469f5dc64acde77305d08cef7f8c6d module="containerd/tasks"
Dec 22 17:25:13 compute kvm[10460]: 2 guests now active
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13.648341510+08:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13.648368960+08:00" level=info msg="ignoring event" module=libcontainerd namespace=moby-plugins topic=/tasks/delete type="*events.TaskDelete"
Dec 22 17:25:13 compute kernel: docker0: port 1(veth1f64f0e) entered disabled state
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.6952] manager: (vethfcd6f4f): new Veth device (/org/freedesktop/NetworkManager/Devices/317)
Dec 22 17:25:13 compute kernel: docker0: port 1(veth1f64f0e) entered disabled state
Dec 22 17:25:13 compute kernel: device veth1f64f0e left promiscuous mode
Dec 22 17:25:13 compute kernel: docker0: port 1(veth1f64f0e) entered disabled state
Dec 22 17:25:13 compute NetworkManager[982]: [1513934713.7220] device (veth1f64f0e): released from master device docker0
Dec 22 17:25:13 compute kernel: XFS (dm-1): Unmounting Filesystem
Dec 22 17:25:13 compute dockerd[9347]: time="2017-12-22T17:25:13.939064003+08:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/delete type="*events.ContainerDelete"

[laijs]

I can't find the relevant log from it.
Could you remove /var/log/hyper/ and test again, and show all the log in it?

[lxm]

still not working, i have some infos in /var/log/hyper, are these infos useful?

==> runv.INFO <==
Log file created at: 2017/12/23 01:07:41
Running on machine: compute
Binary: Built with gc go1.8.3 for linux/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I1223 01:07:41.626391 31986 hypervisor.go:60] VM vm-HvwqEoAACE trying to reload with serialized data: {"PersistVersion":20171210,"Id":"vm-HvwqEoAACE","Paused":false,"DriverInfo":{"hypervisor":"qemu","log":{"name":"/var/log/hyper/qemu/vm-HvwqEoAAC.log","offset":0},"pid":31883,"qmpSock":"/var/run/hyper/vm-HvwqEoAACE/qmp.sock"},"SandboxConfig":{"Hostname":"d76522947bd3","Dns":null,"Neighbors":null,"DnsOptions":null,"DnsSearch":null},"HwStat":{"PciAddr":6,"ScsiId":0,"AttachId":0,"GuestCid":0},"VolumeList":[{"Name":"d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4","Filename":"d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4","Format":"vfs","Fstype":"dir","DeviceName":"","ScsiId":0,"ContainerIds":["d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4"],"IsRootVol":true,"MontPoints":null}],"NetworkList":[{"Id":"330","Index":0,"PciAddr":5,"HostDevice":"vm-HvwqEoAAC0","DeviceName":"eth0","NewName":"eth0","IpAddr":"172.16.91.2/24","Mac":"02:42:ac:10:5b:02","Mtu":1500}],"PortList":[],"Containers":{"d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4":{"Id":"d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4","Name":"d76522947bd3","Image":"","Labels":{},"StopSignal":"","RootVolume":{"Name":"d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4","Source":"d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4","Format":"vfs","Fstype":"dir","Options":null,"DockerVolume":false,"ReadOnly":false},"RootPath":"rootfs","UGI":null,"Volumes":null,"Initialize":false,"OciSpec":{"ociVersion":"1.0.0","process":{"user":{"uid":0,"gid":0,"additionalGids":[10]},"args":["ping","baidu.com"],"env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","HOSTNAME=d76522947bd3"],"cwd":"/","capabilities":{"bounding":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"effective":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"inheritable":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"permitted":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"]},"oomScoreAdj":0},"root":{"path":""},"hostname":"d76522947bd3","hooks":{"prestart":[{"path":"/usr/bin/dockerd","args":["libnetwork-setkey","d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4","6d0a909bff3779c6db0268df447a187cd6f58eb30b819bcb3f574237cadb9fcf"]}]},"linux":{"sysctl":{"vm.overcommit_memory":"1"},"resources":{"devices":[{"allow":false,"access":"rwm"},{"allow":true,"type":"c","major":1,"minor":5,"access":"rwm"},{"allow":true,"type":"c","major":1,"minor":3,"access":"rwm"},{"allow":true,"type":"c","major":1,"minor":9,"access":"rwm"},{"allow":true,"type":"c","major":1,"minor":8,"access":"rwm"},{"allow":true,"type":"c","major":5,"minor":0,"access":"rwm"},{"allow":true,"type":"c","major":5,"minor":1,"access":"rwm"},{"allow":false,"type":"c","major":10,"minor":229,"access":"rwm"}],"memory":{},"cpu":{"shares":0},"pids":{"limit":0},"blockIO":{"weight":0}},"cgroupsPath":"/docker/d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4","namespaces":[{"type":"mount"},{"type":"network"},{"type":"uts"},{"type":"pid"},{"type":"ipc"}],"seccomp":{"defaultAction":"SCMP_ACT_ERRNO","architectures":["SCMP_ARCH_X86_64","SCMP_ARCH_X86","SCMP_ARCH_X32"],"syscalls":[{"names":["accept"],"action":"SCMP_ACT_ALLOW"},{"names":["accept4"],"action":"SCMP_ACT_ALLOW"},{"names":["access"],"action":"SCMP_ACT_ALLOW"},{"names":["adjtimex"],"action":"SCMP_ACT_ALLOW"},{"names":["alarm"],"action":"SCMP_ACT_ALLOW"},{"names":["bind"],"action":"SCMP_ACT_ALLOW"},{"names":["brk"],"action":"SCMP_ACT_ALLOW"},{"names":["capget"],"action":"SCMP_ACT_ALLOW"},{"names":["capset"],"action":"SCMP_ACT_ALLOW"},{"names":["chdir"],"action":"SCMP_ACT_ALLOW"},{"names":["chmod"],"action":"SCMP_ACT_ALLOW"},{"names":["chown"],"action":"SCMP_ACT_ALLOW"},{"names":["chown32"],"action":"SCMP_ACT_ALLOW"},{"names":["clock_getres"],"action":"SCMP_ACT_ALLOW"},{"names":["clock_gettime"],"action":"SCMP_ACT_ALLOW"},{"names":["clock_nanosleep"],"action":"SCMP_ACT_ALLOW"},{"names":["close"],"action":"SCMP_ACT_ALLOW"},{"names":["connect"],"action":"SCMP_ACT_ALLOW"},{"names":["copy_file_range"],"action":"SCMP_ACT_ALLOW"},{"names":["creat"],"action":"SCMP_ACT_ALLOW"},{"names":["dup"],"action":"SCMP_ACT_ALLOW"},{"names":["dup2"],"action":"SCMP_ACT_ALLOW"},{"names":["dup3"],"action":"SCMP_ACT_ALLOW"},{"names":["epoll_create"],"action":"SCMP_ACT_ALLOW"},{"names":["epoll_create1"],"action":"SCMP_ACT_ALLOW"},{"names":["epoll_ctl"],"action":"SCMP_ACT_ALLOW"},{"names":["epoll_ctl_old"],"action":"SCMP_ACT_ALLOW"},{"names":["epoll_pwait"],"action":"SCMP_ACT_ALLOW"},{"names":["epoll_wait"],"action":"SCMP_ACT_ALLOW"},{"names":["epoll_wait_old"],"action":"SCMP_ACT_ALLOW"},{"names":["eventfd"],"action":"SCMP_ACT_ALLOW"},{"names":["eventfd2"],"action":"SCMP_ACT_ALLOW"},{"names":["execve"],"action":"SCMP_ACT_ALLOW"},{"names":["execveat"],"action":"SCMP_ACT_ALLOW"},{"names":["exit"],"action":"SCMP_ACT_ALLOW"},{"names":["exit_group"],"action":"SCMP_ACT_ALLOW"},{"names":["faccessat"],"action":"SCMP_ACT_ALLOW"},{"names":["fadvise64"],"action":"SCMP_ACT_ALLOW"},{"names":["fadvise64_64"],"action":"SCMP_ACT_ALLOW"},{"names":["fallocate"],"action":"SCMP_ACT_ALLOW"},{"names":["fanotify_mark"],"action":"SCMP_ACT_ALLOW"},{"names":["fchdir"],"action":"SCMP_ACT_ALLOW"},{"names":["fchmod"],"action":"SCMP_ACT_ALLOW"},{"names":["fchmodat"],"action":"SCMP_ACT_ALLOW"},{"names":["fchown"],"action":"SCMP_ACT_ALLOW"},{"names":["fchown32"],"action":"SCMP_ACT_ALLOW"},{"names":["fchownat"],"action":"SCMP_ACT_ALLOW"},{"names":["fcntl"],"action":"SCMP_ACT_ALLOW"},{"names":["fcntl64"],"action":"SCMP_ACT_ALLOW"},{"names":["fdatasync"],"action":"SCMP_ACT_ALLOW"},{"names":["fgetxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["flistxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["flock"],"action":"SCMP_ACT_ALLOW"},{"names":["fork"],"action":"SCMP_ACT_ALLOW"},{"names":["fremovexattr"],"action":"SCMP_ACT_ALLOW"},{"names":["fsetxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["fstat"],"action":"SCMP_ACT_ALLOW"},{"names":["fstat64"],"action":"SCMP_ACT_ALLOW"},{"names":["fstatat64"],"action":"SCMP_ACT_ALLOW"},{"names":["fstatfs"],"action":"SCMP_ACT_ALLOW"},{"names":["fstatfs64"],"action":"SCMP_ACT_ALLOW"},{"names":["fsync"],"action":"SCMP_ACT_ALLOW"},{"names":["ftruncate"],"action":"SCMP_ACT_ALLOW"},{"names":["ftruncate64"],"action":"SCMP_ACT_ALLOW"},{"names":["futex"],"action":"SCMP_ACT_ALLOW"},{"names":["futimesat"],"action":"SCMP_ACT_ALLOW"},{"names":["getcpu"],"action":"SCMP_ACT_ALLOW"},{"names":["getcwd"],"action":"SCMP_ACT_ALLOW"},{"names":["getdents"],"action":"SCMP_ACT_ALLOW"},{"names":["getdents64"],"action":"SCMP_ACT_ALLOW"},{"names":["getegid"],"action":"SCMP_ACT_ALLOW"},{"names":["getegid32"],"action":"SCMP_ACT_ALLOW"},{"names":["geteuid"],"action":"SCMP_ACT_ALLOW"},{"names":["geteuid32"],"action":"SCMP_ACT_ALLOW"},{"names":["getgid"],"action":"SCMP_ACT_ALLOW"},{"names":["getgid32"],"action":"SCMP_ACT_ALLOW"},{"names":["getgroups"],"action":"SCMP_ACT_ALLOW"},{"names":["getgroups32"],"action":"SCMP_ACT_ALLOW"},{"names":["getitimer"],"action":"SCMP_ACT_ALLOW"},{"names":["getpeername"],"action":"SCMP_ACT_ALLOW"},{"names":["getpgid"],"action":"SCMP_ACT_ALLOW"},{"names":["getpgrp"],"action":"SCMP_ACT_ALLOW"},{"names":["getpid"],"action":"SCMP_ACT_ALLOW"},{"names":["getppid"],"action":"SCMP_ACT_ALLOW"},{"names":["getpriority"],"action":"SCMP_ACT_ALLOW"},{"names":["getrandom"],"action":"SCMP_ACT_ALLOW"},{"names":["getresgid"],"action":"SCMP_ACT_ALLOW"},{"names":["getresgid32"],"action":"SCMP_ACT_ALLOW"},{"names":["getresuid"],"action":"SCMP_ACT_ALLOW"},{"names":["getresuid32"],"action":"SCMP_ACT_ALLOW"},{"names":["getrlimit"],"action":"SCMP_ACT_ALLOW"},{"names":["get_robust_list"],"action":"SCMP_ACT_ALLOW"},{"names":["getrusage"],"action":"SCMP_ACT_ALLOW"},{"names":["getsid"],"action":"SCMP_ACT_ALLOW"},{"names":["getsockname"],"action":"SCMP_ACT_ALLOW"},{"names":["getsockopt"],"action":"SCMP_ACT_ALLOW"},{"names":["get_thread_area"],"action":"SCMP_ACT_ALLOW"},{"names":["gettid"],"action":"SCMP_ACT_ALLOW"},{"names":["gettimeofday"],"action":"SCMP_ACT_ALLOW"},{"names":["getuid"],"action":"SCMP_ACT_ALLOW"},{"names":["getuid32"],"action":"SCMP_ACT_ALLOW"},{"names":["getxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["inotify_add_watch"],"action":"SCMP_ACT_ALLOW"},{"names":["inotify_init"],"action":"SCMP_ACT_ALLOW"},{"names":["inotify_init1"],"action":"SCMP_ACT_ALLOW"},{"names":["inotify_rm_watch"],"action":"SCMP_ACT_ALLOW"},{"names":["io_cancel"],"action":"SCMP_ACT_ALLOW"},{"names":["ioctl"],"action":"SCMP_ACT_ALLOW"},{"names":["io_destroy"],"action":"SCMP_ACT_ALLOW"},{"names":["io_getevents"],"action":"SCMP_ACT_ALLOW"},{"names":["ioprio_get"],"action":"SCMP_ACT_ALLOW"},{"names":["ioprio_set"],"action":"SCMP_ACT_ALLOW"},{"names":["io_setup"],"action":"SCMP_ACT_ALLOW"},{"names":["io_submit"],"action":"SCMP_ACT_ALLOW"},{"names":["ipc"],"action":"SCMP_ACT_ALLOW"},{"names":["kill"],"action":"SCMP_ACT_ALLOW"},{"names":["lchown"],"action":"SCMP_ACT_ALLOW"},{"names":["lchown32"],"action":"SCMP_ACT_ALLOW"},{"names":["lgetxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["link"],"action":"SCMP_ACT_ALLOW"},{"names":["linkat"],"action":"SCMP_ACT_ALLOW"},{"names":["listen"],"action":"SCMP_ACT_ALLOW"},{"names":["listxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["llistxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["_llseek"],"action":"SCMP_ACT_ALLOW"},{"names":["lremovexattr"],"action":"SCMP_ACT_ALLOW"},{"names":["lseek"],"action":"SCMP_ACT_ALLOW"},{"names":["lsetxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["lstat"],"action":"SCMP_ACT_ALLOW"},{"names":["lstat64"],"action":"SCMP_ACT_ALLOW"},{"names":["madvise"],"action":"SCMP_ACT_ALLOW"},{"names":["memfd_create"],"action":"SCMP_ACT_ALLOW"},{"names":["mincore"],"action":"SCMP_ACT_ALLOW"},{"names":["mkdir"],"action":"SCMP_ACT_ALLOW"},{"names":["mkdirat"],"action":"SCMP_ACT_ALLOW"},{"names":["mknod"],"action":"SCMP_ACT_ALLOW"},{"names":["mknodat"],"action":"SCMP_ACT_ALLOW"},{"names":["mlock"],"action":"SCMP_ACT_ALLOW"},{"names":["mlock2"],"action":"SCMP_ACT_ALLOW"},{"names":["mlockall"],"action":"SCMP_ACT_ALLOW"},{"names":["mmap"],"action":"SCMP_ACT_ALLOW"},{"names":["mmap2"],"action":"SCMP_ACT_ALLOW"},{"names":["mprotect"],"action":"SCMP_ACT_ALLOW"},{"names":["mq_getsetattr"],"action":"SCMP_ACT_ALLOW"},{"names":["mq_notify"],"action":"SCMP_ACT_ALLOW"},{"names":["mq_open"],"action":"SCMP_ACT_ALLOW"},{"names":["mq_timedreceive"],"action":"SCMP_ACT_ALLOW"},{"names":["mq_timedsend"],"action":"SCMP_ACT_ALLOW"},{"names":["mq_unlink"],"action":"SCMP_ACT_ALLOW"},{"names":["mremap"],"action":"SCMP_ACT_ALLOW"},{"names":["msgctl"],"action":"SCMP_ACT_ALLOW"},{"names":["msgget"],"action":"SCMP_ACT_ALLOW"},{"names":["msgrcv"],"action":"SCMP_ACT_ALLOW"},{"names":["msgsnd"],"action":"SCMP_ACT_ALLOW"},{"names":["msync"],"action":"SCMP_ACT_ALLOW"},{"names":["munlock"],"action":"SCMP_ACT_ALLOW"},{"names":["munlockall"],"action":"SCMP_ACT_ALLOW"},{"names":["munmap"],"action":"SCMP_ACT_ALLOW"},{"names":["nanosleep"],"action":"SCMP_ACT_ALLOW"},{"names":["newfstatat"],"action":"SCMP_ACT_ALLOW"},{"names":["_newselect"],"action":"SCMP_ACT_ALLOW"},{"names":["open"],"action":"SCMP_ACT_ALLOW"},{"names":["openat"],"action":"SCMP_ACT_ALLOW"},{"names":["pause"],"action":"SCMP_ACT_ALLOW"},{"names":["pipe"],"action":"SCMP_ACT_ALLOW"},{"names":["pipe2"],"action":"SCMP_ACT_ALLOW"},{"names":["poll"],"action":"SCMP_ACT_ALLOW"},{"names":["ppoll"],"action":"SCMP_ACT_ALLOW"},{"names":["prctl"],"action":"SCMP_ACT_ALLOW"},{"names":["pread64"],"action":"SCMP_ACT_ALLOW"},{"names":["preadv"],"action":"SCMP_ACT_ALLOW"},{"names":["preadv2"],"action":"SCMP_ACT_ALLOW"},{"names":["prlimit64"],"action":"SCMP_ACT_ALLOW"},{"names":["pselect6"],"action":"SCMP_ACT_ALLOW"},{"names":["pwrite64"],"action":"SCMP_ACT_ALLOW"},{"names":["pwritev"],"action":"SCMP_ACT_ALLOW"},{"names":["pwritev2"],"action":"SCMP_ACT_ALLOW"},{"names":["read"],"action":"SCMP_ACT_ALLOW"},{"names":["readahead"],"action":"SCMP_ACT_ALLOW"},{"names":["readlink"],"action":"SCMP_ACT_ALLOW"},{"names":["readlinkat"],"action":"SCMP_ACT_ALLOW"},{"names":["readv"],"action":"SCMP_ACT_ALLOW"},{"names":["recv"],"action":"SCMP_ACT_ALLOW"},{"names":["recvfrom"],"action":"SCMP_ACT_ALLOW"},{"names":["recvmmsg"],"action":"SCMP_ACT_ALLOW"},{"names":["recvmsg"],"action":"SCMP_ACT_ALLOW"},{"names":["remap_file_pages"],"action":"SCMP_ACT_ALLOW"},{"names":["removexattr"],"action":"SCMP_ACT_ALLOW"},{"names":["rename"],"action":"SCMP_ACT_ALLOW"},{"names":["renameat"],"action":"SCMP_ACT_ALLOW"},{"names":["renameat2"],"action":"SCMP_ACT_ALLOW"},{"names":["restart_syscall"],"action":"SCMP_ACT_ALLOW"},{"names":["rmdir"],"action":"SCMP_ACT_ALLOW"},{"names":["rt_sigaction"],"action":"SCMP_ACT_ALLOW"},{"names":["rt_sigpending"],"action":"SCMP_ACT_ALLOW"},{"names":["rt_sigprocmask"],"action":"SCMP_ACT_ALLOW"},{"names":["rt_sigqueueinfo"],"action":"SCMP_ACT_ALLOW"},{"names":["rt_sigreturn"],"action":"SCMP_ACT_ALLOW"},{"names":["rt_sigsuspend"],"action":"SCMP_ACT_ALLOW"},{"names":["rt_sigtimedwait"],"action":"SCMP_ACT_ALLOW"},{"names":["rt_tgsigqueueinfo"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_getaffinity"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_getattr"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_getparam"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_get_priority_max"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_get_priority_min"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_getscheduler"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_rr_get_interval"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_setaffinity"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_setattr"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_setparam"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_setscheduler"],"action":"SCMP_ACT_ALLOW"},{"names":["sched_yield"],"action":"SCMP_ACT_ALLOW"},{"names":["seccomp"],"action":"SCMP_ACT_ALLOW"},{"names":["select"],"action":"SCMP_ACT_ALLOW"},{"names":["semctl"],"action":"SCMP_ACT_ALLOW"},{"names":["semget"],"action":"SCMP_ACT_ALLOW"},{"names":["semop"],"action":"SCMP_ACT_ALLOW"},{"names":["semtimedop"],"action":"SCMP_ACT_ALLOW"},{"names":["send"],"action":"SCMP_ACT_ALLOW"},{"names":["sendfile"],"action":"SCMP_ACT_ALLOW"},{"names":["sendfile64"],"action":"SCMP_ACT_ALLOW"},{"names":["sendmmsg"],"action":"SCMP_ACT_ALLOW"},{"names":["sendmsg"],"action":"SCMP_ACT_ALLOW"},{"names":["sendto"],"action":"SCMP_ACT_ALLOW"},{"names":["setfsgid"],"action":"SCMP_ACT_ALLOW"},{"names":["setfsgid32"],"action":"SCMP_ACT_ALLOW"},{"names":["setfsuid"],"action":"SCMP_ACT_ALLOW"},{"names":["setfsuid32"],"action":"SCMP_ACT_ALLOW"},{"names":["setgid"],"action":"SCMP_ACT_ALLOW"},{"names":["setgid32"],"action":"SCMP_ACT_ALLOW"},{"names":["setgroups"],"action":"SCMP_ACT_ALLOW"},{"names":["setgroups32"],"action":"SCMP_ACT_ALLOW"},{"names":["setitimer"],"action":"SCMP_ACT_ALLOW"},{"names":["setpgid"],"action":"SCMP_ACT_ALLOW"},{"names":["setpriority"],"action":"SCMP_ACT_ALLOW"},{"names":["setregid"],"action":"SCMP_ACT_ALLOW"},{"names":["setregid32"],"action":"SCMP_ACT_ALLOW"},{"names":["setresgid"],"action":"SCMP_ACT_ALLOW"},{"names":["setresgid32"],"action":"SCMP_ACT_ALLOW"},{"names":["setresuid"],"action":"SCMP_ACT_ALLOW"},{"names":["setresuid32"],"action":"SCMP_ACT_ALLOW"},{"names":["setreuid"],"action":"SCMP_ACT_ALLOW"},{"names":["setreuid32"],"action":"SCMP_ACT_ALLOW"},{"names":["setrlimit"],"action":"SCMP_ACT_ALLOW"},{"names":["set_robust_list"],"action":"SCMP_ACT_ALLOW"},{"names":["setsid"],"action":"SCMP_ACT_ALLOW"},{"names":["setsockopt"],"action":"SCMP_ACT_ALLOW"},{"names":["set_thread_area"],"action":"SCMP_ACT_ALLOW"},{"names":["set_tid_address"],"action":"SCMP_ACT_ALLOW"},{"names":["setuid"],"action":"SCMP_ACT_ALLOW"},{"names":["setuid32"],"action":"SCMP_ACT_ALLOW"},{"names":["setxattr"],"action":"SCMP_ACT_ALLOW"},{"names":["shmat"],"action":"SCMP_ACT_ALLOW"},{"names":["shmctl"],"action":"SCMP_ACT_ALLOW"},{"names":["shmdt"],"action":"SCMP_ACT_ALLOW"},{"names":["shmget"],"action":"SCMP_ACT_ALLOW"},{"names":["shutdown"],"action":"SCMP_ACT_ALLOW"},{"names":["sigaltstack"],"action":"SCMP_ACT_ALLOW"},{"names":["signalfd"],"action":"SCMP_ACT_ALLOW"},{"names":["signalfd4"],"action":"SCMP_ACT_ALLOW"},{"names":["sigreturn"],"action":"SCMP_ACT_ALLOW"},{"names":["socket"],"action":"SCMP_ACT_ALLOW"},{"names":["socketcall"],"action":"SCMP_ACT_ALLOW"},{"names":["socketpair"],"action":"SCMP_ACT_ALLOW"},{"names":["splice"],"action":"SCMP_ACT_ALLOW"},{"names":["stat"],"action":"SCMP_ACT_ALLOW"},{"names":["stat64"],"action":"SCMP_ACT_ALLOW"},{"names":["statfs"],"action":"SCMP_ACT_ALLOW"},{"names":["statfs64"],"action":"SCMP_ACT_ALLOW"},{"names":["symlink"],"action":"SCMP_ACT_ALLOW"},{"names":["symlinkat"],"action":"SCMP_ACT_ALLOW"},{"names":["sync"],"action":"SCMP_ACT_ALLOW"},{"names":["sync_file_range"],"action":"SCMP_ACT_ALLOW"},{"names":["syncfs"],"action":"SCMP_ACT_ALLOW"},{"names":["sysinfo"],"action":"SCMP_ACT_ALLOW"},{"names":["syslog"],"action":"SCMP_ACT_ALLOW"},{"names":["tee"],"action":"SCMP_ACT_ALLOW"},{"names":["tgkill"],"action":"SCMP_ACT_ALLOW"},{"names":["time"],"action":"SCMP_ACT_ALLOW"},{"names":["timer_create"],"action":"SCMP_ACT_ALLOW"},{"names":["timer_delete"],"action":"SCMP_ACT_ALLOW"},{"names":["timerfd_create"],"action":"SCMP_ACT_ALLOW"},{"names":["timerfd_gettime"],"action":"SCMP_ACT_ALLOW"},{"names":["timerfd_settime"],"action":"SCMP_ACT_ALLOW"},{"names":["timer_getoverrun"],"action":"SCMP_ACT_ALLOW"},{"names":["timer_gettime"],"action":"SCMP_ACT_ALLOW"},{"names":["timer_settime"],"action":"SCMP_ACT_ALLOW"},{"names":["times"],"action":"SCMP_ACT_ALLOW"},{"names":["tkill"],"action":"SCMP_ACT_ALLOW"},{"names":["truncate"],"action":"SCMP_ACT_ALLOW"},{"names":["truncate64"],"action":"SCMP_ACT_ALLOW"},{"names":["ugetrlimit"],"action":"SCMP_ACT_ALLOW"},{"names":["umask"],"action":"SCMP_ACT_ALLOW"},{"names":["uname"],"action":"SCMP_ACT_ALLOW"},{"names":["unlink"],"action":"SCMP_ACT_ALLOW"},{"names":["unlinkat"],"action":"SCMP_ACT_ALLOW"},{"names":["utime"],"action":"SCMP_ACT_ALLOW"},{"names":["utimensat"],"action":"SCMP_ACT_ALLOW"},{"names":["utimes"],"action":"SCMP_ACT_ALLOW"},{"names":["vfork"],"action":"SCMP_ACT_ALLOW"},{"names":["vmsplice"],"action":"SCMP_ACT_ALLOW"},{"names":["wait4"],"action":"SCMP_ACT_ALLOW"},{"names":["waitid"],"action":"SCMP_ACT_ALLOW"},{"names":["waitpid"],"action":"SCMP_ACT_ALLOW"},{"names":["write"],"action":"SCMP_ACT_ALLOW"},{"names":["writev"],"action":"SCMP_ACT_ALLOW"},{"names":["personality"],"action":"SCMP_ACT_ALLOW","args":[{"index":0,"value":0,"op":"SCMP_CMP_EQ"}]},{"names":["personality"],"action":"SCMP_ACT_ALLOW","args":[{"index":0,"value":8,"op":"SCMP_CMP_EQ"}]},{"names":["personality"],"action":"SCMP_ACT_ALLOW","args":[{"index":0,"value":131072,"op":"SCMP_CMP_EQ"}]},{"names":["personality"],"action":"SCMP_ACT_ALLOW","args":[{"index":0,"value":131080,"op":"SCMP_CMP_EQ"}]},{"names":["personality"],"action":"SCMP_ACT_ALLOW","args":[{"index":0,"value":4294967295,"op":"SCMP_CMP_EQ"}]},{"names":["arch_prctl"],"action":"SCMP_ACT_ALLOW"},{"names":["modify_ldt"],"action":"SCMP_ACT_ALLOW"},{"names":["clone"],"action":"SCMP_ACT_ALLOW","args":[{"index":0,"value":2080505856,"op":"SCMP_CMP_MASKED_EQ"}]},{"names":["chroot"],"action":"SCMP_ACT_ALLOW"}]},"maskedPaths":["/proc/kcore","/proc/latency_stats","/proc/timer_list","/proc/timer_stats","/proc/sched_debug","/proc/scsi","/sys/firmware"],"readonlyPaths":["/proc/asound","/proc/bus","/proc/fs","/proc/irq","/proc/sys","/proc/sysrq-trigger"]}}}}}
I1223 01:07:41.628360 31986 hypervisor.go:69] VM vm-HvwqEoAACE trying to reload with deserialized pinfo: &hypervisor.PersistInfo{PersistVersion:20171210, Id:"vm-HvwqEoAACE", Paused:false, DriverInfo:map[string]interface {}{"qmpSock":"/var/run/hyper/vm-HvwqEoAACE/qmp.sock", "hypervisor":"qemu", "log":map[string]interface {}{"name":"/var/log/hyper/qemu/vm-HvwqEoAAC.log", "offset":0}, "pid":31883}, SandboxConfig:(*api.SandboxConfig)(0xc42008cd20), HwStat:(*hypervisor.VmHwStatus)(0xc4201fba60), VolumeList:[]*hypervisor.PersistVolumeInfo{(*hypervisor.PersistVolumeInfo)(0xc4200107e0)}, NetworkList:[]*hypervisor.PersistNetworkInfo{(*hypervisor.PersistNetworkInfo)(0xc4200a2c00)}, PortList:[]*api.PortDescription{}, Containers:map[string]*api.ContainerDescription{"d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4":(*api.ContainerDescription)(0xc42026a000)}}
I1223 01:07:41.628491 31986 sandbox.go:224] newHyperstart() on socket: /var/run/hyper/vm-HvwqEoAACE/kata-agent.sock
I1223 01:07:41.758373 31986 disk.go:167] SB[vm-HvwqEoAACE] container d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4 unwait disk d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4
I1223 01:07:41.758433 31986 context.go:385] SB[vm-HvwqEoAACE] remove container d76522947bd3ba9230a5e6341b3dc1cf265504cf8807e9a55e2289d4762708d4
I1223 01:07:41.764719 31986 context.go:214] SB[vm-HvwqEoAACE] state change from RUNNING to 'TERMINATING'
I1223 01:07:41.772730 31986 vm_states.go:153] SB[vm-HvwqEoAACE] POD destroyed
I1223 01:07:41.772754 31986 vm_states.go:167] SB[vm-HvwqEoAACE] poweroff vm based on command:
I1223 01:07:41.772799 31986 qemu_process.go:93] kill Qemu... 31883
I1223 01:07:41.772843 31986 context.go:190] SB[vm-HvwqEoAACE] VmContext Close()
E1223 01:07:41.772913 31986 qmp_handler.go:141] QMP exit as got error: read unix @->/var/run/hyper/vm-HvwqEoAACE/qmp.sock: use of closed network connection
I1223 01:07:41.773497 31986 hypervisor.go:30] SB[vm-HvwqEoAACE] main event loop exiting
I1223 01:07:41.772935 31986 sandbox.go:157] StopPod successfully
I1223 01:07:41.773544 31986 vm_states.go:167] SB[vm-HvwqEoAACE] poweroff vm based on command: vm.Kill()

[laijs]

there are several log files for one container. It seems the error message is not in runv.INFO. Cloud you also post the other files, please.

[lxm]

[root@compute hyper]# cat runv.WARNING
Log file created at: 2017/12/23 01:07:41
Running on machine: compute
Binary: Built with gc go1.8.3 for linux/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
E1223 01:07:41.772913 31986 qmp_handler.go:141] QMP exit as got error: read unix @->/var/run/hyper/vm-HvwqEoAACE/qmp.sock: use of closed network connection

[root@compute hyper]# cat runv.ERROR
Log file created at: 2017/12/23 01:07:41
Running on machine: compute
Binary: Built with gc go1.8.3 for linux/amd64
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
E1223 01:07:41.772913 31986 qmp_handler.go:141] QMP exit as got error: read unix @->/var/run/hyper/vm-HvwqEoAACE/qmp.sock: use of closed network connection

[lxm]

Is there any progress？

[laijs]

I can't find anything from these files.
I'm wondering why the other logs were missing...

[laijs]

hello, could you please post all the logs?

[lxm]

that's all log in "/var/log/hyper/". any more info on where i can find?

[jack6liu]

Same issue here with docker-ce 17.12 on ubuntu 16.04.3.
Version of runv is v1.0.0.
After reinstall docker-ce 17.06, it's working well.

Only found some log from /var/log/syslog. Hope it's useful.

Feb 22 16:05:31 xenial2 systemd-udevd[2814]: Could not generate persistent MAC address for veth9db5bd8: No such file or directory
Feb 22 16:05:31 xenial2 kernel: [  249.344852] device veth9db5bd8 entered promiscuous mode
Feb 22 16:05:31 xenial2 systemd-udevd[2812]: Could not generate persistent MAC address for veth05758a1: No such file or directory
Feb 22 16:05:31 xenial2 kernel: [  249.350663] IPv6: ADDRCONF(NETDEV_UP): veth9db5bd8: link is not ready
Feb 22 16:05:31 xenial2 dockerd[1244]: time="2018-02-22T16:05:31.811120519+08:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/create type="*events.ContainerCreate"
Feb 22 16:05:31 xenial2 dockerd[1244]: time="2018-02-22T16:05:31+08:00" level=info msg="shim docker-containerd-shim started" address="/containerd-shim/moby/d4a133d5c9a5f0acb915a33c8a097f21506bc46ffb9df3da50b415dcc43d2019/shim.sock" debug=false module="containerd/tasks" pid=2842
Feb 22 16:05:37 xenial2 kernel: [  254.603649] eth0: renamed from veth05758a1
Feb 22 16:05:37 xenial2 kernel: [  254.603877] IPv6: ADDRCONF(NETDEV_CHANGE): veth9db5bd8: link becomes ready
Feb 22 16:05:37 xenial2 kernel: [  254.603901] docker0: port 1(veth9db5bd8) entered forwarding state
Feb 22 16:05:37 xenial2 kernel: [  254.603921] docker0: port 1(veth9db5bd8) entered forwarding state
Feb 22 16:05:37 xenial2 kernel: [  254.627276] device vm-TJKCwawXZo0 entered promiscuous mode
Feb 22 16:05:37 xenial2 systemd-udevd[2953]: Could not generate persistent MAC address for vm-TJKCwawXZo0: No such file or directory
Feb 22 16:05:37 xenial2 kernel: [  254.998265] device vm-TJKCwawXZo0 left promiscuous mode
Feb 22 16:05:37 xenial2 kernel: [  254.998278] runv0: port 1(vm-TJKCwawXZo0) entered disabled state
Feb 22 16:05:37 xenial2 dockerd[1244]: time="2018-02-22T16:05:37.476302864+08:00" level=warning msg="unknown container" container=d4a133d5c9a5f0acb915a33c8a097f21506bc46ffb9df3da50b415dcc43d2019 module=libcontainerd namespace=plugins.moby
Feb 22 16:05:37 xenial2 dockerd[1244]: time="2018-02-22T16:05:37.687707492+08:00" level=warning msg="unknown container" container=d4a133d5c9a5f0acb915a33c8a097f21506bc46ffb9df3da50b415dcc43d2019 module=libcontainerd namespace=plugins.moby
Feb 22 16:05:37 xenial2 dockerd[1244]: time="2018-02-22T16:05:37.824702647+08:00" level=warning msg="unknown container" container=d4a133d5c9a5f0acb915a33c8a097f21506bc46ffb9df3da50b415dcc43d2019 module=libcontainerd namespace=plugins.moby
Feb 22 16:05:38 xenial2 dockerd[1244]: time="2018-02-22T16:05:38+08:00" level=info msg="shim reaped" id=d4a133d5c9a5f0acb915a33c8a097f21506bc46ffb9df3da50b415dcc43d2019 module="containerd/tasks"
Feb 22 16:05:38 xenial2 dockerd[1244]: time="2018-02-22T16:05:38.044660685+08:00" level=info msg="ignoring event" module=libcontainerd namespace=plugins.moby topic=/tasks/delete type="*events.TaskDelete"
Feb 22 16:05:38 xenial2 dockerd[1244]: time="2018-02-22T16:05:38.045004023+08:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Feb 22 16:05:38 xenial2 kernel: [  255.603961] docker0: port 1(veth9db5bd8) entered disabled state
Feb 22 16:05:38 xenial2 kernel: [  255.604082] veth05758a1: renamed from eth0
Feb 22 16:05:38 xenial2 kernel: [  255.613464] docker0: port 1(veth9db5bd8) entered disabled state
Feb 22 16:05:38 xenial2 kernel: [  255.614062] device veth9db5bd8 left promiscuous mode
Feb 22 16:05:38 xenial2 kernel: [  255.614065] docker0: port 1(veth9db5bd8) entered disabled state
Feb 22 16:05:38 xenial2 dockerd[1244]: time="2018-02-22T16:05:38.081568805+08:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/containers/delete type="*events.ContainerDelete"

[telala]

got the same error while using runv:1.0.0 with docker-ce-18.03 in centos 7:
set winsize failed, rpc error: code = Unavailable desc = all SubConns are in TransientFailure
/var/log/messages:
time="2018-04-25T23:47:46+08:00" level=warning msg="could not use snapshotter btrfs in metadata plugin" error="path /data/docker-image/containerd/daemon/io.containerd.snapshotter.v1.btrfs must be a btrfs filesystem to be used with the btrfs snapshotter" module="containerd/io.containerd.metadata.v1.bolt"

time="2018-04-25T23:47:46.720831433+08:00" level=warning msg="failed to retrieve runv version: unknown output format: runv version 1.0.0\nspec: 1.0.1\n"

[gnawux]

@telala could you try docker 17.12 or 17.06 instead? docker will change some configurations randomly, which is not standardized yet, as a result, it may break runtimes other than runc.

[telala]

I used to use docker ce 17.09 and it is ok with runv.
Recently I updated docker ce to 18.03 for I had to use nvidia docker2 which need docker ce 18.03.
Then runv broke : (

[gnawux]

@bergwolf are you working on investigating this?

[bergwolf]

I think it's the same as #677. docker has changed its network configuration since certain release. I need some time to investigate what was changed though. moby stopped tagging releases after v17.03.2-ce, so it's hard to tell from moby's code change.