search

hyperhq / runv

Hypervisor-based Runtime for OCI
Apache License 2.0
828 stars 129 forks source link

The container or the process init is not found #501

Closed SergeyOvsienko closed 7 years ago

SergeyOvsienko commented 7 years ago

Hi All!

My env

runv -v
runv version 0.8.1

docker -v
Docker version 1.11.0, build 4dc5990

uname -a
Linux runv 4.4.0-31-generic #50~14.04.1-Ubuntu SMP Wed Jul 13 01:07:32 UTC 2016 x86_64 x86_64 x86_64 GNU/Linux

libvirt version: 1.2.2

QEMU emulator version 2.0.0 (Debian 2.0.0+dfsg-2ubuntu1.33), Copyright (c) 2003-2008 Fabrice Bellard

I am do

runv --debug --driver libvirt --kernel /opt/hyperstart/build/kernel --initrd /opt/hyperstart/build/hyper-initrd.img containerd

docker daemon -D -l debug --containerd=/run/runv-containerd/containerd.sock

I run container docker run -ti ubuntu bash After logout vm is dead, but docker ps

CONTAINER ID        IMAGE               COMMAND                  CREATED             STATUS              PORTS               NAMES
d2d6ff99c587        ubuntu              "bash"                   21 minutes ago      Up 21 minutes                           goofy_euclid

root@runv:~/runv# virsh list --all
 Id    Name                           State
----------------------------------------------------

I do docker rm -f d2d6ff99c587

Error response from daemon: Could not kill running container d2d6ff99c587ff52c37443257ce54c39b497d81812ce3b0dd43ff37804cbc67b, cannot remove - Cannot kill container d2d6ff99c587ff52c37443257ce54c39b497d81812ce3b0dd43ff37804cbc67b: rpc error: code = 2 desc = "The container d2d6ff99c587ff52c37443257ce54c39b497d81812ce3b0dd43ff37804cbc67b or the process init is not found"

The same problem with docker stop on live container

gao-feng commented 7 years ago

@SergeyOvsienko Hi,

Can you post the logs of runv? I test this with docker 17.05.0 and runv 0.8.1-14, cannot reproduce this problem.

Thanks!

SergeyOvsienko commented 7 years ago

@gao-feng Hi, docker 17.05.0-ce does not work with runv, only 1.11.0.

sergey@sergey-All-Series:~$ docker run -d nginx
9656bc5fa8f04a51c9667899c9bc4b127b5b2768a05a5e53750f92966a7f9a2b
docker: Error response from daemon: unknown runtime: "docker-runc".

With docker 1.11.0

runv logs

I0522 13:04:10.734872    6809 hypervisor.go:31] SB[vm-FRvhslRDNe] main event loop exiting
I0522 13:04:10.734885    6809 hyperpod.go:523] StopPod successfully
I0522 13:04:10.735820    6809 hyperpod.go:222] listener.dec.Decode NetlinkUpdate: EOF
I0522 13:04:10.831861    6809 hypervisor.go:44] SB[vm-FRvhslRDNe] issue VERSION request for keep-alive test
E0522 13:04:10.831885    6809 json.go:570] SB[vm-FRvhslRDNe] get hyperstart API version error: send ctl channel error, the hyperstart might have closed
W0522 13:04:10.831891    6809 hypervisor.go:47] SB[vm-FRvhslRDNe] keep-alive test end with error: send ctl channel error, the hyperstart might have closed

docker logs

ERRO[0086] Handler for DELETE /v1.23/containers/977fd27a2a83 returned error: Could not kill running container 977fd27a2a836211f42aebb21068a76bdddfb548f15a5270282ae010ce678a24, cannot remove - Cannot kill container 977fd27a2a836211f42aebb21068a76bdddfb548f15a5270282ae010ce678a24: rpc error: code = 2 desc = "The container 977fd27a2a836211f42aebb21068a76bdddfb548f15a5270282ae010ce678a24 or the process init is not found"

After docker stop or rm -f, docker ps 

 docker ps
CONTAINER ID        IMAGE               COMMAND                  CREATED             STATUS              PORTS               NAMES
977fd27a2a83        nginx               "nginx -g 'daemon off"   2 minutes ago       Up 2 minutes        80/tcp              serene_davinci

but virsh list --all

sudo virsh list --all
[sudo] пароль для sergey: 
 ID    Имя                         Статус
----------------------------------------------------

sergey@sergey-All-Series:~$

After restart docker daemon i can delete containers

SergeyOvsienko commented 7 years ago

Full runv debug log when stop container

I0522 13:33:24.471671   12106 hypervisor.go:44] SB[vm-uuQktKRsaf] issue VERSION request for keep-alive test
I0522 13:33:24.471698   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:0
I0522 13:33:24.471709   12106 json.go:304] SB[vm-uuQktKRsaf] send command 0 to init, payload: 'null'.
I0522 13:33:24.471785   12106 json.go:316] SB[vm-uuQktKRsaf] write 12 to hyperstart.
I0522 13:33:24.472340   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLIN, he 0x61d668, fd 3, 0x61d4e0
I0522 13:33:24.472953   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 14, len 4
I0522 13:33:24.473284   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 3, 0x61d668, event 8197
I0522 13:33:24.473743   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_ctlfd_read: get length 12
I0522 13:33:24.474018   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 14, len 4
I0522 13:33:24.474198   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_ctlmsg_handle, type 0, len 12
I0522 13:33:24.474296   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 9, len 4
I0522 13:33:24.474629   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLOUT, he 0x61d668, fd 3, 0x61d4e0
I0522 13:33:24.474798   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 14, len: 4
I0522 13:33:24.474807   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 14, len: 4
I0522 13:33:24.474823   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 9, len: 4
I0522 13:33:24.474829   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:14
I0522 13:33:24.474835   12106 json.go:262] SB[vm-uuQktKRsaf] get command NEXT: send 12, receive 8
I0522 13:33:24.474839   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:14
I0522 13:33:24.474844   12106 json.go:262] SB[vm-uuQktKRsaf] get command NEXT: send 12, receive 12
I0522 13:33:24.474848   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:9
I0522 13:33:24.474972   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 3, 0x61d668, event 8193
I0522 13:33:28.533056   12106 server.go:89] gRPC handle Signal
I0522 13:33:28.533092   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:24
I0522 13:33:28.533142   12106 json.go:304] SB[vm-uuQktKRsaf] send command 24 to init, payload: '{"container":"6fc93e8d7096dd5be38d31006902b82035bd70681f588c23caf99f5e7b36af5c","process":"init","signal":15}'.
I0522 13:33:28.533167   12106 json.go:316] SB[vm-uuQktKRsaf] write 117 to hyperstart.
I0522 13:33:28.533726   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLIN, he 0x61d668, fd 3, 0x61d4e0
I0522 13:33:28.533839   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 14, len 4
I0522 13:33:28.533965   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 3, 0x61d668, event 8197
I0522 13:33:28.534059   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_ctlfd_read: get length 117
I0522 13:33:28.534156   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 14, len 4
I0522 13:33:28.539174   12106 json.go:431] SB[vm-uuQktKRsaf] tty: read 70 bytes for stream 1
I0522 13:33:28.539174   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 14, len: 4
I0522 13:33:28.539213   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 14, len: 4
I0522 13:33:28.539219   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 9, len: 0
I0522 13:33:28.539224   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:14
I0522 13:33:28.539230   12106 json.go:262] SB[vm-uuQktKRsaf] get command NEXT: send 117, receive 8
I0522 13:33:28.539234   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:14
I0522 13:33:28.539238   12106 json.go:262] SB[vm-uuQktKRsaf] get command NEXT: send 117, receive 117
I0522 13:33:28.539241   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:9
I0522 13:33:28.539302   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_ctlmsg_handle, type 24, len 117
I0522 13:33:28.539312   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 9, len 0
I0522 13:33:28.539318   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLOUT, he 0x61d668, fd 3, 0x61d4e0
I0522 13:33:28.539324   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 3, 0x61d668, event 8193
I0522 13:33:28.539330   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLIN, he 0x146aed8, fd 9, 0x61d5c0
I0522 13:33:28.539336   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] stdout_loop, seq 1
I0522 13:33:28.539498   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] pts_loop: read 70 data
I0522 13:33:28.539505   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8197
I0522 13:33:28.539522   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLOUT, he 0x61d6a8, fd 4, 0x61d4c0
I0522 13:33:28.539603   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8193
I0522 13:33:28.613156   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLIN, he 0x146aed8, fd 9, 0x61d5c0
I0522 13:33:28.613246   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] stdout_loop, seq 1
I0522 13:33:28.613344   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] pts_loop: read 125 data
I0522 13:33:28.613578   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8197
I0522 13:33:28.614882   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLOUT, he 0x61d6a8, fd 4, 0x61d4c0
I0522 13:33:28.614934   12106 json.go:431] SB[vm-uuQktKRsaf] tty: read 125 bytes for stream 1
I0522 13:33:28.615263   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8193
I0522 13:33:28.625652   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLIN, he 0x146aed8, fd 9, 0x61d5c0
I0522 13:33:28.625741   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] stdout_loop, seq 1
I0522 13:33:28.625842   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] pts_loop: read 43 data
I0522 13:33:28.625967   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8197
I0522 13:33:28.626119   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLOUT, he 0x61d6a8, fd 4, 0x61d4c0
I0522 13:33:28.626161   12106 json.go:431] SB[vm-uuQktKRsaf] tty: read 43 bytes for stream 1
I0522 13:33:28.626324   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8193
I0522 13:33:28.628267   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLIN, he 0x146aed8, fd 9, 0x61d5c0
I0522 13:33:28.628347   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] stdout_loop, seq 1
I0522 13:33:28.628590   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] pts_loop: read 65 data
I0522 13:33:28.629429   12106 json.go:431] SB[vm-uuQktKRsaf] tty: read 65 bytes for stream 1
I0522 13:33:28.629494   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8197
I0522 13:33:28.629502   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLOUT, he 0x61d6a8, fd 4, 0x61d4c0
I0522 13:33:28.629506   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8193
I0522 13:33:28.640528   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLHUP or EPOLLERR, he 0x146af18, fd 11, 10
I0522 13:33:28.640543   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] stderr_hup, seq 1, id init
I0522 13:33:28.640548   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] still have 2 user of exec
I0522 13:33:28.640553   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLHUP or EPOLLERR, he 0x146aed8, fd 9, 10
I0522 13:33:28.640557   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] stdout_hup, seq 1, id init
I0522 13:33:28.640561   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] still have 1 user of exec
I0522 13:33:28.640584   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] pid 334 exit normally, status 0
I0522 13:33:28.640734   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_exec_exit exec exit pid 334, seq 1, container 6fc93e8d7096dd5be38d31006902b82035bd70681f588c23caf99f5e7b36af5c
I0522 13:33:28.640765   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] container init process 334
I0522 13:33:28.646911   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] last user of exec exit, release
I0522 13:33:28.647118   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8197
I0522 13:33:28.647848   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 23, len 127
I0522 13:33:28.648113   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 3, 0x61d668, event 8197
I0522 13:33:28.648310   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_release_exec exit code 0
I0522 13:33:28.648568   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_release_exec container init exited automatically, remains 1
I0522 13:33:28.653305   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] create child process pid=343 in the sandbox
I0522 13:33:28.658685   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] umount /
I0522 13:33:28.662296   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper send cleanup container mounts event: normal
I0522 13:33:28.663999   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] pid 342 exit normally, status 0
I0522 13:33:28.664306   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLOUT, he 0x61d6a8, fd 4, 0x61d4c0
I0522 13:33:28.664350   12106 json.go:431] SB[vm-uuQktKRsaf] tty: read 0 bytes for stream 1
I0522 13:33:28.664359   12106 json.go:444] SB[vm-uuQktKRsaf] session 1 closed by peer, close pty
I0522 13:33:28.664396   12106 vm_states.go:153] Close tty
I0522 13:33:28.665865   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 4, 0x61d6a8, event 8193
I0522 13:33:28.666008   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLOUT, he 0x61d668, fd 3, 0x61d4e0
I0522 13:33:28.666059   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 23, len: 127
I0522 13:33:28.666066   12106 json.go:347] SB[vm-uuQktKRsaf] ProcessAsyncEvent: {"container":"6fc93e8d7096dd5be38d31006902b82035bd70681f588c23caf99f5e7b36af5c","process":"init","event":"finished","status":0}
I0522 13:33:28.666161   12106 vm_states.go:55] SB[vm-uuQktKRsaf] Con[6fc93e8d7096dd5be38d31006902b82035bd70681f588c23caf99f5e7b36af5c] container finished, unset iostream pipes
I0522 13:33:28.666179   12106 vm.go:206] SB[vm-uuQktKRsaf] got shut down msg, acked here
I0522 13:33:28.666187   12106 events.go:121] notifySubscribers: {6fc93e8d7096dd5be38d31006902b82035bd70681f588c23caf99f5e7b36af5c exit 2017-05-22 10:33:28.666170932 +0000 UTC init 0}
I0522 13:33:28.666223   12106 events.go:54] write event log: {6fc93e8d7096dd5be38d31006902b82035bd70681f588c23caf99f5e7b36af5c exit 2017-05-22 10:33:28.666170932 +0000 UTC init 0}
I0522 13:33:28.666249   12106 supervisor.go:155] reap container 6fc93e8d7096dd5be38d31006902b82035bd70681f588c23caf99f5e7b36af5c processId init
I0522 13:33:28.667621   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 3, 0x61d668, event 8193
I0522 13:33:28.667633   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] pid 343 exit normally, status 0
I0522 13:33:28.687232   12106 hypervisor.go:23] SB[vm-uuQktKRsaf] main event loop got message 9(COMMAND_SHUTDOWN)
I0522 13:33:28.687246   12106 vm_states.go:270] SB[vm-uuQktKRsaf] got shutdown command, shutting down
I0522 13:33:28.687252   12106 context.go:265] SB[vm-uuQktKRsaf] state change from RUNNING to 'TERMINATING'
I0522 13:33:28.687261   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:4
I0522 13:33:28.687270   12106 json.go:304] SB[vm-uuQktKRsaf] send command 4 to init, payload: 'null'.
I0522 13:33:28.687286   12106 json.go:316] SB[vm-uuQktKRsaf] write 12 to hyperstart.
I0522 13:33:28.687857   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_handle_event event EPOLLIN, he 0x61d668, fd 3, 0x61d4e0
I0522 13:33:28.688048   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 14, len 4
I0522 13:33:28.688213   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_modify_event modify event fd 3, 0x61d668, event 8197
I0522 13:33:28.688313   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_ctlfd_read: get length 12
I0522 13:33:28.688555   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 14, len 4
I0522 13:33:28.688645   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper_ctlmsg_handle, type 4, len 12
I0522 13:33:28.688723   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] get DESTROYPOD message
I0522 13:33:28.688862   12106 vm_console.go:96] SB[vm-uuQktKRsaf] [CNL] hyper ctl append type 9, len 0
I0522 13:33:28.688992   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 14, len: 4
I0522 13:33:28.689001   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 14, len: 4
I0522 13:33:28.689006   12106 json.go:337] SB[vm-uuQktKRsaf] readVmMessage code: 9, len: 0
I0522 13:33:28.689012   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:14
I0522 13:33:28.689017   12106 json.go:262] SB[vm-uuQktKRsaf] get command NEXT: send 12, receive 8
I0522 13:33:28.689021   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:14
I0522 13:33:28.689026   12106 json.go:262] SB[vm-uuQktKRsaf] get command NEXT: send 12, receive 12
I0522 13:33:28.689030   12106 json.go:241] SB[vm-uuQktKRsaf] got cmd:9
I0522 13:33:28.689035   12106 json.go:245] SB[vm-uuQktKRsaf] got response of shutdown command, last round of command to init
I0522 13:33:28.689041   12106 json.go:89] SB[vm-uuQktKRsaf] close jsonBasedHyperstart
I0522 13:33:28.689056   12106 json.go:395] SB[vm-uuQktKRsaf] tty chan closed, quit sent goroutine
I0522 13:33:28.689068   12106 vm_states.go:235] SB[vm-uuQktKRsaf] POD destroyed
I0522 13:33:28.689072   12106 vm_states.go:249] SB[vm-uuQktKRsaf] poweroff vm based on command: 
E0522 13:33:28.689078   12106 json.go:370] read tty data failed
E0522 13:33:28.689246   12106 json.go:427] SB[vm-uuQktKRsaf] tty socket closed, quit the reading goroutine: read unix @->/var/run/hyper/vm-uuQktKRsaf/tty.sock: use of closed network connection
E0522 13:33:28.689259   12106 json.go:137] read init data failed
I0522 13:33:28.735182   12106 vm_console.go:113] Input byte chan closed, close the output string chan
I0522 13:33:28.735217   12106 vm_console.go:98] SB[vm-uuQktKRsaf] console output end
I0522 13:33:28.890579   12106 hypervisor.go:23] SB[vm-uuQktKRsaf] main event loop got message 1(EVENT_VM_KILL)
I0522 13:33:28.890604   12106 vm_states.go:307] SB[vm-uuQktKRsaf] Got VM force killed message, go to cleaning up
I0522 13:33:28.890620   12106 context.go:241] SB[vm-uuQktKRsaf] VmContext Close()
I0522 13:33:28.890643   12106 hypervisor.go:31] SB[vm-uuQktKRsaf] main event loop exiting
I0522 13:33:28.890658   12106 hyperpod.go:523] StopPod successfully
I0522 13:33:28.891650   12106 hyperpod.go:222] listener.dec.Decode NetlinkUpdate: EOF
I0522 13:33:34.474989   12106 hypervisor.go:44] SB[vm-uuQktKRsaf] issue VERSION request for keep-alive test
E0522 13:33:34.475014   12106 json.go:570] SB[vm-uuQktKRsaf] get hyperstart API version error: send ctl channel error, the hyperstart might have closed
W0522 13:33:34.475019   12106 hypervisor.go:47] SB[vm-uuQktKRsaf] keep-alive test end with error: send ctl channel error, the hyperstart might have closed
I0522 13:33:38.539898   12106 server.go:89] gRPC handle Signal
gao-feng commented 7 years ago

Hi,

Looks like docker sends out Signal twice.

I0522 13:33:28.533056   12106 server.go:89] gRPC handle Signal
...
I0522 13:33:38.539898   12106 server.go:89] gRPC handle Signal

Will check this problem. Thanks

gao-feng commented 7 years ago

@SergeyOvsienko Is it necessary for you to run runv-containerd with docker-1.11? This bug is disappeared in docker-17.05 and latest runv, and the latest runv doesn't support old version of docker now. (The oci spec in new version docker is changed, so we have to change it in runv to adjust new version docker).

SergeyOvsienko commented 7 years ago

Earlier I wrote that since docker-17.05 does not work!

docker 17.05.0-ce runv version 0.8.1

runv --debug --driver libvirt --kernel /opt/hyperstart/build/kernel --initrd /opt/hyperstart/build/hyper-initrd.img containerd

docker daemon -D -l debug --containerd=/run/runv-containerd/containerd.sock
sergey@sergey-All-Series:~$ docker run -d nginx
9656bc5fa8f04a51c9667899c9bc4b127b5b2768a05a5e53750f92966a7f9a2b
docker: Error response from daemon: unknown runtime: "docker-runc".
gao-feng commented 7 years ago

@SergeyOvsienko

Sorry for this...

Since the command line is changed, please check this pr to run docker with correct command line and you should use latest runv.

SergeyOvsienko commented 7 years ago

Tnx, everything works as expected!