server # [   19.361671] dockerd[945]: time="2023-08-28T06:42:29.387357329Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
server # [   19.363453] dockerd[945]: time="2023-08-28T06:42:29.389501736Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
server # [   19.364575] dockerd[945]: time="2023-08-28T06:42:29.389573533Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
server # [   19.365652] dockerd[945]: time="2023-08-28T06:42:29.389608733Z" level=info msg="containerd successfully booted in 1.091698s"
server # [   22.016978] dockerd[888]: time="2023-08-28T06:42:32.042690619Z" level=info msg="parsed scheme: \"unix\"" module=grpc
server # [   22.018213] dockerd[888]: time="2023-08-28T06:42:32.044552308Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
server # [   22.020081] dockerd[888]: time="2023-08-28T06:42:32.045750225Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock  <nil> 0 <nil>}] <nil> <nil>}" module=grpc
server # [   22.022103] dockerd[888]: time="2023-08-28T06:42:32.045777882Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
server # [   22.452566] dockerd[888]: time="2023-08-28T06:42:32.478849404Z" level=info msg="parsed scheme: \"unix\"" module=grpc
server # [   22.454478] dockerd[888]: time="2023-08-28T06:42:32.480787639Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
server # [   22.457729] dockerd[888]: time="2023-08-28T06:42:32.482811360Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock  <nil> 0 <nil>}] <nil> <nil>}" module=grpc
server # [   22.459309] dockerd[888]: time="2023-08-28T06:42:32.482830636Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
server # [   22.566618] systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport3969011572-merged.mount: Deactivated successfully.
server # [   28.949656] dockerd[888]: time="2023-08-28T06:42:38.975029504Z" level=info msg="Loading containers: start."
server # [   30.719468] Initializing XFRM netlink socket
server # [   30.728796] dockerd[888]: time="2023-08-28T06:42:40.754832244Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address"
server # [   30.735378] (udev-worker)[1069]: Network interface NamePolicy= disabled on kernel command line.
server # [   31.432990] dockerd[888]: time="2023-08-28T06:42:41.458971737Z" level=info msg="Loading containers: done."
server # [   32.987359] systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3389590629-merged.mount: Deactivated successfully.
server # [   33.283061] dockerd[888]: time="2023-08-28T06:42:43.307992772Z" level=info msg="Docker daemon" commit=v20.10.25 graphdriver(s)=overlay2 version=20.10.25
server # [   33.297661] dockerd[888]: time="2023-08-28T06:42:43.323761535Z" level=info msg="Daemon has completed initialization"
server # [   39.048156] systemd[1]: Started Docker Application Container Engine.
server # [   39.050857] systemd[1]: Starting docker-nginx.service...
server # [   39.357126] dockerd[888]: time="2023-08-28T06:42:49.375474532Z" level=info msg="API listen on /run/docker.sock"
server # [   39.361693] dockerd[888]: time="2023-08-28T06:42:49.388028641Z" level=info msg="API listen on /run/docker.sock"
server # [   48.213916] docker-nginx-pre-start[1258]: Error: No such container: nginx
server # [  100.113143] docker-nginx-pre-start[1300]: Loaded image: nginx-container:latest
server # [  100.205883] systemd[1]: Started docker-nginx.service.
server # [  100.207389] systemd[1]: Reached target Multi-User System.
server # [  100.207928] systemd[1]: Startup finished in 4.914s (kernel) + 1min 35.290s (userspace) = 1min 40.205s.
server # [  100.439704] systemd[1]: var-lib-docker-overlay2-be8f9acdf43a900009e845403362a481f07b3e3ea77adc42f4c413f1c716be47\x2dinit-merged.mount: Deactivated successfully.
(finished: waiting for unit docker-nginx.service, in 101.20 seconds)
server: waiting for TCP port 8181 on localhost
server # [  101.618863] systemd[1]: var-lib-docker-overlay2-be8f9acdf43a900009e845403362a481f07b3e3ea77adc42f4c413f1c716be47-merged.mount: Deactivated successfully.
server # [  102.242507] docker0: port 1(veth38edc67) entered blocking state
server # [  102.243332] docker0: port 1(veth38edc67) entered disabled state
server # [  102.244754] device veth38edc67 entered promiscuous mode
server # [  102.183354] (udev-worker)[1833]: Network interface NamePolicy= disabled on kernel command line.
server # [  102.184789] (udev-worker)[1834]: Network interface NamePolicy= disabled on kernel command line.
server # [  102.259150] dhcpcd[679]: veth38edc67: waiting for carrier
server # [  102.371346] IPv6: ADDRCONF(NETDEV_CHANGE): veth6b79e23: link becomes ready
server # [  102.371841] IPv6: ADDRCONF(NETDEV_CHANGE): veth38edc67: link becomes ready
server # [  102.372300] docker0: port 1(veth38edc67) entered blocking state
server # [  102.372697] docker0: port 1(veth38edc67) entered forwarding state
server # [  102.373134] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
server # [  102.300508] dhcpcd[679]: veth6b79e23: waiting for carrier
server # [  102.301507] dhcpcd[679]: veth6b79e23: carrier acquired
server # [  102.309532] dhcpcd[679]: veth6b79e23: IAID 35:e3:cf:39
server # [  102.310265] dhcpcd[679]: veth6b79e23: adding address fe80::f0b8:35ff:fee3:cf39
server # [  102.311507] dhcpcd[679]: veth38edc67: carrier acquired
server # [  102.319609] dhcpcd[679]: veth38edc67: IAID 8b:b4:aa:d0
server # [  102.320722] dhcpcd[679]: veth38edc67: adding address fe80::388e:8bff:feb4:aad0
server # [  102.381501] dhcpcd[679]: veth6b79e23: soliciting a DHCP lease
server # [  102.435966] dhcpcd[679]: veth38edc67: soliciting an IPv6 router
server # [  102.850724] dhcpcd[679]: veth6b79e23: soliciting an IPv6 router
server # Connection to localhost (::1) 8181 port [tcp/intermapper] succeeded!
(finished: waiting for TCP port 8181 on localhost, in 3.11 seconds)
server: must succeed: cntr attach nginx true
server # [  104.230339] dhcpcd[679]: veth38edc67: soliciting a DHCP lease
server # [  104.937308] dockerd[945]: time="2023-08-28T06:43:54.916958707Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
server # [  104.938709] dockerd[945]: time="2023-08-28T06:43:54.941244476Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
server # [  104.940077] dockerd[945]: time="2023-08-28T06:43:54.941272412Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
server # [  104.941482] dockerd[945]: time="2023-08-28T06:43:54.945011714Z" level=info msg="starting signal loop" namespace=moby path=/run/docker/containerd/daemon/io.containerd.runtime.v2.task/moby/80aae10d2e7d9d736fac3a342fc48d3d85f9dbf589992e15663decef2178ad92 pid=1894 runtime=io.containerd.runc.v2
server # [  105.113856] systemd[1]: run-docker-runtime\x2drunc-moby-80aae10d2e7d9d736fac3a342fc48d3d85f9dbf589992e15663decef2178ad92-runc.imufhb.mount: Deactivated successfully.
server # [  105.243837] systemd[1]: Started libcontainer container 80aae10d2e7d9d736fac3a342fc48d3d85f9dbf589992e15663decef2178ad92.
server # [  105.431905] dhcpcd[679]: veth6b79e23: carrier lost
server # [  105.512226] docker0: port 1(veth38edc67) entered disabled state
server # [  105.516360] eth0: renamed from veth6b79e23
server # [  105.519721] docker0: port 1(veth38edc67) entered blocking state
server # [  105.520156] docker0: port 1(veth38edc67) entered forwarding state
server # [  105.482987] dhcpcd[679]: veth6b79e23: deleting address fe80::f0b8:35ff:fee3:cf39
server # [  105.526110] dhcpcd[679]: veth6b79e23: removing interface
server # [  105.569073] dhcpcd[679]: veth38edc67: carrier lost
server # [  105.596238] dhcpcd[679]: veth38edc67: deleting address fe80::388e:8bff:feb4:aad0
server # [  105.635360] dhcpcd[679]: veth38edc67: carrier acquired
server # [  105.644211] dhcpcd[679]: veth38edc67: IAID 8b:b4:aa:d0
server # [  105.644805] dhcpcd[679]: veth38edc67: adding address fe80::388e:8bff:feb4:aad0
server # [  105.793351] 80aae10d2e7d[888]: 2023/08/28 06:43:55 [notice] 1#1: using the "epoll" event method
server # [  105.794528] docker-nginx-start[1816]: 2023/08/28 06:43:55 [notice] 1#1: using the "epoll" event method
server # [  105.795679] 80aae10d2e7d[888]: 2023/08/28 06:43:55 [notice] 1#1: nginx/1.24.0
server # [  105.796503] docker-nginx-start[1816]: 2023/08/28 06:43:55 [notice] 1#1: nginx/1.24.0
server # [  105.799847] docker-nginx-start[1816]: 2023/08/28 06:43:55 [notice] 1#1: built by gcc 12.3.0 (GCC)
server # [  105.801374] docker-nginx-start[1816]: 2023/08/28 06:43:55 [notice] 1#1: OS: Linux 6.1.47
server # [  105.802860] docker-nginx-start[1816]: 2023/08/28 06:43:55 [notice] 1#1: getrlimit(RLIMIT_NOFILE): 1048576:1048576
server # [  105.804584] docker-nginx-start[1816]: 2023/08/28 06:43:55 [notice] 1#1: start worker processes
server # [  105.806142] docker-nginx-start[1816]: 2023/08/28 06:43:55 [notice] 1#1: start worker process 6
server # [  105.807730] 80aae10d2e7d[888]: 2023/08/28 06:43:55 [notice] 1#1: built by gcc 12.3.0 (GCC)
server # [  105.808790] 80aae10d2e7d[888]: 2023/08/28 06:43:55 [notice] 1#1: OS: Linux 6.1.47
server # [  105.809626] 80aae10d2e7d[888]: 2023/08/28 06:43:55 [notice] 1#1: getrlimit(RLIMIT_NOFILE): 1048576:1048576
server # [  105.810609] 80aae10d2e7d[888]: 2023/08/28 06:43:55 [notice] 1#1: start worker processes
server # [  105.811498] 80aae10d2e7d[888]: 2023/08/28 06:43:55 [notice] 1#1: start worker process 6
(finished: must succeed: cntr attach nginx true, in 2.52 seconds)
(finished: run the VM test script, in 108.27 seconds)
server # [  106.365463] dhcpcd[679]: veth38edc67: soliciting an IPv6 router
test script finished in 108.46s
cleanup
kill machine (pid 8)
server # qemu-kvm: terminating on signal 15 from pid 6 (/nix/store/wlxpsdzfvdanfzh704qmgyzb42qvy4fr-python3-3.10.12/bin/python3.10)
(finished: cleanup, in 0.07 seconds)
kill vlan (pid 7)