runv icon indicating copy to clipboard operation
runv copied to clipboard

failed to start a container with docker

Open lxm opened this issue 7 years ago • 17 comments

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

lxm avatar Dec 22 '17 03:12 lxm

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 avatar Dec 22 '17 06:12 laijs

@lxm see #658

laijs avatar Dec 22 '17 06:12 laijs

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"

lxm avatar Dec 22 '17 09:12 lxm

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?

laijs avatar Dec 22 '17 10:12 laijs

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()

lxm avatar Dec 22 '17 17:12 lxm

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.

laijs avatar Dec 23 '17 13:12 laijs

[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 avatar Dec 23 '17 15:12 lxm

Is there any progress?

lxm avatar Dec 26 '17 10:12 lxm

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

laijs avatar Dec 27 '17 00:12 laijs

hello, could you please post all the logs?

laijs avatar Jan 31 '18 12:01 laijs

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

lxm avatar Feb 05 '18 01:02 lxm

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"

jack6liu avatar Feb 22 '18 08:02 jack6liu

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"

telala avatar Apr 25 '18 07:04 telala

@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.

gnawux avatar Apr 25 '18 08:04 gnawux

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 : (

telala avatar Apr 25 '18 08:04 telala

@bergwolf are you working on investigating this?

gnawux avatar Apr 25 '18 08:04 gnawux

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.

bergwolf avatar Apr 25 '18 09:04 bergwolf