Skip to content

Instantly share code, notes, and snippets.

@pbering
Created January 3, 2019 13:12
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save pbering/bae7d2cb2d5503f62e8e230058e44917 to your computer and use it in GitHub Desktop.
Save pbering/bae7d2cb2d5503f62e8e230058e44917 to your computer and use it in GitHub Desktop.
docker log with debug enabled from linux host
-- Logs begin at Thu 2019-01-03 11:33:57 UTC, end at Thu 2019-01-03 11:45:56 UTC. --
Jan 03 11:36:29 dc2node-nvm0 systemd[1]: Starting Docker Application Container Engine...
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.139064323Z" level=info msg="systemd-resolved is running, so using resolvconf: /run/systemd/resolve/resolv.conf"
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.225100467Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.225381975Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.225666683Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///run/containerd/containerd.sock 0 <nil>}]" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.225734985Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.225886089Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4201675d0, CONNECTING" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.225695384Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.226640110Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.226601609Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4201675d0, READY" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.227152025Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///run/containerd/containerd.sock 0 <nil>}]" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.227372931Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.227590937Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4201678c0, CONNECTING" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.228905175Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4201678c0, READY" module=grpc
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.691322710Z" level=info msg="Graph migration to content-addressability took 0.00 seconds"
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.691772823Z" level=warning msg="Your kernel does not support swap memory limit"
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.691875326Z" level=warning msg="Your kernel does not support cgroup rt period"
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.691893826Z" level=warning msg="Your kernel does not support cgroup rt runtime"
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.691910927Z" level=warning msg="Your kernel does not support cgroup blkio weight"
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.691925927Z" level=warning msg="Your kernel does not support cgroup blkio weight_device"
Jan 03 11:36:29 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:29.693146362Z" level=info msg="Loading containers: start."
Jan 03 11:36:30 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:30.759942009Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.18.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Jan 03 11:36:31 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:31.005291491Z" level=info msg="Loading containers: done."
Jan 03 11:36:31 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:31.333605970Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2
Jan 03 11:36:31 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:31.334002681Z" level=info msg="Docker daemon" commit=4d60db4 graphdriver(s)=overlay2 version=18.09.0
Jan 03 11:36:31 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:31.334208187Z" level=info msg="Daemon has completed initialization"
Jan 03 11:36:32 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:32.118273237Z" level=info msg="API listen on /var/run/docker.sock"
Jan 03 11:36:32 dc2node-nvm0 systemd[1]: Started Docker Application Container Engine.
Jan 03 11:36:35 dc2node-nvm0 systemd[1]: Stopping Docker Application Container Engine...
Jan 03 11:36:35 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:35.859682149Z" level=info msg="Processing signal 'terminated'"
Jan 03 11:36:35 dc2node-nvm0 dockerd[4183]: time="2019-01-03T11:36:35.864543484Z" level=info msg="stopping event stream following graceful shutdown" error="<nil>" module=libcontainerd namespace=moby
Jan 03 11:36:35 dc2node-nvm0 systemd[1]: Stopped Docker Application Container Engine.
Jan 03 11:36:35 dc2node-nvm0 systemd[1]: Starting Docker Application Container Engine...
Jan 03 11:36:35 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:35.983593385Z" level=debug msg="Listener created for HTTP on unix (/var/run/docker.sock)"
Jan 03 11:36:35 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:35.991533106Z" level=info msg="systemd-resolved is running, so using resolvconf: /run/systemd/resolve/resolv.conf"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.036906260Z" level=debug msg="Golang's threads limit set to 28170"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.079135225Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.079785443Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.080006549Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///run/containerd/containerd.sock 0 <nil>}]" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.080048450Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.080118352Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc42078f6d0, CONNECTING" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.080615666Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc42078f6d0, READY" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.195580539Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.195631240Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.195902148Z" level=debug msg="Using default logging driver json-file"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.195964649Z" level=debug msg="[graphdriver] priority list: [btrfs zfs overlay2 aufs overlay devicemapper vfs]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.196028351Z" level=debug msg="zfs command is not available: exec: \"zfs\": executable file not found in $PATH" storage-driver=zfs
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.196297759Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///run/containerd/containerd.sock 0 <nil>}]" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.196510565Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.196773272Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc42078f9b0, CONNECTING" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.197611695Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc42078f9b0, READY" module=grpc
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.198390416Z" level=debug msg="processing event stream" module=libcontainerd namespace=plugins.moby
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.227876130Z" level=debug msg="backingFs=extfs, projectQuotaSupported=false" storage-driver=overlay2
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.227926332Z" level=debug msg="Initialized graph driver overlay2"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.445445035Z" level=info msg="Graph migration to content-addressability took 0.00 seconds"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.446106653Z" level=warning msg="Your kernel does not support swap memory limit"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.446190256Z" level=warning msg="Your kernel does not support cgroup rt period"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.446209456Z" level=warning msg="Your kernel does not support cgroup rt runtime"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.446236457Z" level=warning msg="Your kernel does not support cgroup blkio weight"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.446251057Z" level=warning msg="Your kernel does not support cgroup blkio weight_device"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.447009678Z" level=debug msg="Max Concurrent Downloads: 3"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.447038779Z" level=debug msg="Max Concurrent Uploads: 5"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.447064080Z" level=info msg="Loading containers: start."
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.447326687Z" level=debug msg="Option Experimental: false"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.447373988Z" level=debug msg="Option DefaultDriver: bridge"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.447387989Z" level=debug msg="Option DefaultNetwork: bridge"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.447469091Z" level=debug msg="Network Control Plane MTU: 1500"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.447864802Z" level=debug msg="processing event stream" module=libcontainerd namespace=moby
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.470480626Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-ISOLATION]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.471618857Z" level=debug msg="/sbin/iptables, [--wait -t nat -D PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.472899793Z" level=debug msg="/sbin/iptables, [--wait -t nat -D OUTPUT -m addrtype --dst-type LOCAL ! --dst 127.0.0.0/8 -j DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.474222129Z" level=debug msg="/sbin/iptables, [--wait -t nat -D OUTPUT -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.480157993Z" level=debug msg="/sbin/iptables, [--wait -t nat -D PREROUTING]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.481351626Z" level=debug msg="/sbin/iptables, [--wait -t nat -D OUTPUT]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.483249778Z" level=debug msg="/sbin/iptables, [--wait -t nat -F DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.484454212Z" level=debug msg="/sbin/iptables, [--wait -t nat -X DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.486938480Z" level=debug msg="/sbin/iptables, [--wait -t filter -F DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.488310218Z" level=debug msg="/sbin/iptables, [--wait -t filter -X DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.490858588Z" level=debug msg="/sbin/iptables, [--wait -t filter -F DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.492102823Z" level=debug msg="/sbin/iptables, [--wait -t filter -X DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.494401286Z" level=debug msg="/sbin/iptables, [--wait -t filter -F DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.496211336Z" level=debug msg="/sbin/iptables, [--wait -t filter -X DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.498988813Z" level=debug msg="/sbin/iptables, [--wait -t filter -F DOCKER-ISOLATION]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.500167545Z" level=debug msg="/sbin/iptables, [--wait -t filter -X DOCKER-ISOLATION]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.501435680Z" level=debug msg="/sbin/iptables, [--wait -t nat -n -L DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.502535511Z" level=debug msg="/sbin/iptables, [--wait -t nat -N DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.503662442Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.504856175Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.505951705Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.507074636Z" level=debug msg="/sbin/iptables, [--wait -t filter -N DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.508257869Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-1 -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.513637217Z" level=debug msg="/sbin/iptables, [--wait -A DOCKER-ISOLATION-STAGE-1 -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.514912952Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-2 -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.518462250Z" level=debug msg="/sbin/iptables, [--wait -A DOCKER-ISOLATION-STAGE-2 -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.653151168Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.654719811Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-USER -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.656014547Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.657266581Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.658578618Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.719096488Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.720637830Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-USER -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.721848464Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.722975095Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.724153627Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.730387599Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.18.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.730456101Z" level=debug msg="Allocating IPv4 pools for network bridge (513b699b1c6e72fe423ab08625d4f72c4f6d4625f80770d15ff95e114518eb21)"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.730477902Z" level=debug msg="RequestPool(LocalDefault, 172.18.0.0/16, , map[], false)"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.734330208Z" level=debug msg="RequestAddress(LocalDefault/172.18.0.0/16, 172.18.0.1, map[RequestAddressType:com.docker.network.gateway])"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.734390110Z" level=debug msg="Request address PoolID:172.18.0.0/16 App: ipam/default/data, ID: LocalDefault/172.18.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65534, Sequence: (0x80000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:172.18.0.1 "
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.734852223Z" level=debug msg="/sbin/iptables, [--wait -t nat -C POSTROUTING -s 172.18.0.0/16 ! -o docker0 -j MASQUERADE]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.736124458Z" level=debug msg="/sbin/iptables, [--wait -t nat -C DOCKER -i docker0 -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.737326091Z" level=debug msg="/sbin/iptables, [--wait -t nat -I DOCKER -i docker0 -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.742029321Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -i docker0 -o docker0 -j DROP]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.743323256Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker0 -o docker0 -j ACCEPT]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.744707295Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker0 ! -o docker0 -j ACCEPT]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.745933328Z" level=debug msg="/sbin/iptables, [--wait -t nat -C PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.747347568Z" level=debug msg="/sbin/iptables, [--wait -t nat -A PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.748897410Z" level=debug msg="/sbin/iptables, [--wait -t nat -C OUTPUT -m addrtype --dst-type LOCAL -j DOCKER ! --dst 127.0.0.0/8]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.750366351Z" level=debug msg="/sbin/iptables, [--wait -t nat -A OUTPUT -m addrtype --dst-type LOCAL -j DOCKER ! --dst 127.0.0.0/8]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.751673787Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -j DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.752872320Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -j DOCKER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.757554149Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.758944288Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.760433729Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.761696464Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.762948998Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.764466740Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-1 -i docker0 ! -o docker0 -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.765748075Z" level=debug msg="/sbin/iptables, [--wait -t filter -I DOCKER-ISOLATION-STAGE-1 -i docker0 ! -o docker0 -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.770955419Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-2 -o docker0 -j DROP]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.772283156Z" level=debug msg="/sbin/iptables, [--wait -t filter -I DOCKER-ISOLATION-STAGE-2 -o docker0 -j DROP]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.842905505Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.844543050Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-USER -j RETURN]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.845744283Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.847049619Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.848373256Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-USER]"
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.853594400Z" level=info msg="Loading containers: done."
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.943624785Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.944131199Z" level=info msg="Docker daemon" commit=4d60db4 graphdriver(s)=overlay2 version=18.09.0
Jan 03 11:36:36 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:36.951182393Z" level=info msg="Daemon has completed initialization"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.164314254Z" level=debug msg="Registering routers"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.164904571Z" level=debug msg="Registering GET, /containers/{name:.*}/checkpoints"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.165206079Z" level=debug msg="Registering POST, /containers/{name:.*}/checkpoints"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.165338983Z" level=debug msg="Registering DELETE, /containers/{name}/checkpoints/{checkpoint}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.165496187Z" level=debug msg="Registering HEAD, /containers/{name:.*}/archive"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.165614690Z" level=debug msg="Registering GET, /containers/json"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.165693092Z" level=debug msg="Registering GET, /containers/{name:.*}/export"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.165785795Z" level=debug msg="Registering GET, /containers/{name:.*}/changes"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.165890198Z" level=debug msg="Registering GET, /containers/{name:.*}/json"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.165985200Z" level=debug msg="Registering GET, /containers/{name:.*}/top"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166086503Z" level=debug msg="Registering GET, /containers/{name:.*}/logs"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166232207Z" level=debug msg="Registering GET, /containers/{name:.*}/stats"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166339310Z" level=debug msg="Registering GET, /containers/{name:.*}/attach/ws"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166456713Z" level=debug msg="Registering GET, /exec/{id:.*}/json"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166575316Z" level=debug msg="Registering GET, /containers/{name:.*}/archive"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166676819Z" level=debug msg="Registering POST, /containers/create"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166749821Z" level=debug msg="Registering POST, /containers/{name:.*}/kill"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166843824Z" level=debug msg="Registering POST, /containers/{name:.*}/pause"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.166940527Z" level=debug msg="Registering POST, /containers/{name:.*}/unpause"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167035729Z" level=debug msg="Registering POST, /containers/{name:.*}/restart"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167131932Z" level=debug msg="Registering POST, /containers/{name:.*}/start"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167240435Z" level=debug msg="Registering POST, /containers/{name:.*}/stop"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167332437Z" level=debug msg="Registering POST, /containers/{name:.*}/wait"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167442140Z" level=debug msg="Registering POST, /containers/{name:.*}/resize"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167531243Z" level=debug msg="Registering POST, /containers/{name:.*}/attach"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167627045Z" level=debug msg="Registering POST, /containers/{name:.*}/copy"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167743749Z" level=debug msg="Registering POST, /containers/{name:.*}/exec"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167837951Z" level=debug msg="Registering POST, /exec/{name:.*}/start"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.167928854Z" level=debug msg="Registering POST, /exec/{name:.*}/resize"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168016556Z" level=debug msg="Registering POST, /containers/{name:.*}/rename"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168113659Z" level=debug msg="Registering POST, /containers/{name:.*}/update"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168201461Z" level=debug msg="Registering POST, /containers/prune"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168267963Z" level=debug msg="Registering POST, /commit"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168325065Z" level=debug msg="Registering PUT, /containers/{name:.*}/archive"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168424267Z" level=debug msg="Registering DELETE, /containers/{name:.*}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168506470Z" level=debug msg="Registering GET, /images/json"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168571471Z" level=debug msg="Registering GET, /images/search"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168638073Z" level=debug msg="Registering GET, /images/get"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168699975Z" level=debug msg="Registering GET, /images/{name:.*}/get"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168786277Z" level=debug msg="Registering GET, /images/{name:.*}/history"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168892480Z" level=debug msg="Registering GET, /images/{name:.*}/json"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.168985083Z" level=debug msg="Registering POST, /images/load"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169040484Z" level=debug msg="Registering POST, /images/create"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169100586Z" level=debug msg="Registering POST, /images/{name:.*}/push"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169183388Z" level=debug msg="Registering POST, /images/{name:.*}/tag"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169269290Z" level=debug msg="Registering POST, /images/prune"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169346793Z" level=debug msg="Registering DELETE, /images/{name:.*}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169433095Z" level=debug msg="Registering OPTIONS, /{anyroute:.*}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169526398Z" level=debug msg="Registering GET, /_ping"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169587499Z" level=debug msg="Registering GET, /events"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.169640001Z" level=debug msg="Registering GET, /info"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.170669929Z" level=debug msg="Registering GET, /version"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.170767332Z" level=debug msg="Registering GET, /system/df"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.170840634Z" level=debug msg="Registering POST, /auth"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.170897735Z" level=debug msg="Registering GET, /volumes"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.170958737Z" level=debug msg="Registering GET, /volumes/{name:.*}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171048039Z" level=debug msg="Registering POST, /volumes/create"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171127342Z" level=debug msg="Registering POST, /volumes/prune"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171192643Z" level=debug msg="Registering DELETE, /volumes/{name:.*}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171278446Z" level=debug msg="Registering POST, /build"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171337147Z" level=debug msg="Registering POST, /build/prune"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171396649Z" level=debug msg="Registering POST, /build/cancel"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171462951Z" level=debug msg="Registering POST, /session"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171514152Z" level=debug msg="Registering POST, /swarm/init"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.171565154Z" level=debug msg="Registering POST, /swarm/join"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.175521262Z" level=debug msg="Registering POST, /swarm/leave"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.175596964Z" level=debug msg="Registering GET, /swarm"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.175658366Z" level=debug msg="Registering GET, /swarm/unlockkey"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.175745668Z" level=debug msg="Registering POST, /swarm/update"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.175819970Z" level=debug msg="Registering POST, /swarm/unlock"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.175879972Z" level=debug msg="Registering GET, /services"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.175934774Z" level=debug msg="Registering GET, /services/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176038876Z" level=debug msg="Registering POST, /services/create"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176104078Z" level=debug msg="Registering POST, /services/{id}/update"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176216181Z" level=debug msg="Registering DELETE, /services/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176299384Z" level=debug msg="Registering GET, /services/{id}/logs"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176386186Z" level=debug msg="Registering GET, /nodes"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176445388Z" level=debug msg="Registering GET, /nodes/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176516190Z" level=debug msg="Registering DELETE, /nodes/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176596492Z" level=debug msg="Registering POST, /nodes/{id}/update"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176679894Z" level=debug msg="Registering GET, /tasks"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176737296Z" level=debug msg="Registering GET, /tasks/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176817898Z" level=debug msg="Registering GET, /tasks/{id}/logs"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176908000Z" level=debug msg="Registering GET, /secrets"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.176963002Z" level=debug msg="Registering POST, /secrets/create"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177021303Z" level=debug msg="Registering DELETE, /secrets/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177103206Z" level=debug msg="Registering GET, /secrets/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177198508Z" level=debug msg="Registering POST, /secrets/{id}/update"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177287311Z" level=debug msg="Registering GET, /configs"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177352613Z" level=debug msg="Registering POST, /configs/create"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177415014Z" level=debug msg="Registering DELETE, /configs/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177503117Z" level=debug msg="Registering GET, /configs/{id}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177599919Z" level=debug msg="Registering POST, /configs/{id}/update"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177702422Z" level=debug msg="Registering GET, /plugins"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177773124Z" level=debug msg="Registering GET, /plugins/{name:.*}/json"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177867027Z" level=debug msg="Registering GET, /plugins/privileges"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.177937429Z" level=debug msg="Registering DELETE, /plugins/{name:.*}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178023231Z" level=debug msg="Registering POST, /plugins/{name:.*}/enable"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178130734Z" level=debug msg="Registering POST, /plugins/{name:.*}/disable"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178235637Z" level=debug msg="Registering POST, /plugins/pull"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178305539Z" level=debug msg="Registering POST, /plugins/{name:.*}/push"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178397041Z" level=debug msg="Registering POST, /plugins/{name:.*}/upgrade"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178499844Z" level=debug msg="Registering POST, /plugins/{name:.*}/set"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178603447Z" level=debug msg="Registering POST, /plugins/create"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178668949Z" level=debug msg="Registering GET, /distribution/{name:.*}/json"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178765751Z" level=debug msg="Registering GET, /networks"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178839853Z" level=debug msg="Registering GET, /networks/"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178896455Z" level=debug msg="Registering GET, /networks/{id:.+}"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.178985357Z" level=debug msg="Registering POST, /networks/create"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.179059559Z" level=debug msg="Registering POST, /networks/{id:.*}/connect"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.179168562Z" level=debug msg="Registering POST, /networks/{id:.*}/disconnect"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.179278965Z" level=debug msg="Registering POST, /networks/prune"
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.179347467Z" level=debug msg="Registering DELETE, /networks/{id:.*}"
Jan 03 11:36:37 dc2node-nvm0 systemd[1]: Started Docker Application Container Engine.
Jan 03 11:36:37 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:36:37.185782944Z" level=info msg="API listen on /var/run/docker.sock"
Jan 03 11:40:51 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:40:51.031035233Z" level=info msg="Processing signal 'terminated'"
Jan 03 11:40:51 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:40:51.031194235Z" level=debug msg="daemon configured with a 15 seconds minimum shutdown timeout"
Jan 03 11:40:51 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:40:51.031231036Z" level=debug msg="start clean shutdown of all containers with a 15 seconds timeout..."
Jan 03 11:40:51 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:40:51.031676841Z" level=debug msg="Unix socket /run/docker/libnetwork/3864b503f9e3b3aa69c9d38a844ff6757aa3d4c492ad62aa7aba60cf4b219ebd.sock doesn't exist. cannot accept client connections"
Jan 03 11:40:51 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:40:51.031835944Z" level=debug msg="Cleaning up old mountid : start."
Jan 03 11:40:51 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:40:51.032172948Z" level=debug msg="Cleaning up old mountid : done."
Jan 03 11:40:51 dc2node-nvm0 dockerd[4381]: time="2019-01-03T11:40:51.032332350Z" level=debug msg="Clean shutdown succeeded"
Jan 03 11:40:51 dc2node-nvm0 systemd[1]: Stopping Docker Application Container Engine...
Jan 03 11:40:51 dc2node-nvm0 systemd[1]: Stopped Docker Application Container Engine.
-- Reboot --
Jan 03 11:41:20 dc2node-nvm0 systemd[1]: Starting Docker Application Container Engine...
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.359210900Z" level=debug msg="Listener created for HTTP on unix (/var/run/docker.sock)"
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.367967900Z" level=info msg="systemd-resolved is running, so using resolvconf: /run/systemd/resolve/resolv.conf"
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.508617500Z" level=debug msg="Golang's threads limit set to 28170"
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.632319600Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.632611200Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.633268900Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///run/containerd/containerd.sock 0 <nil>}]" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.633331100Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.633418500Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4205f96d0, CONNECTING" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.633928200Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4205f96d0, READY" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.703553900Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.703608900Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.703991200Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{unix:///run/containerd/containerd.sock 0 <nil>}]" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.704186000Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.704268800Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4205f99c0, CONNECTING" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.705070500Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc4205f99c0, READY" module=grpc
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.705191900Z" level=debug msg="processing event stream" module=libcontainerd namespace=plugins.moby
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.712790500Z" level=debug msg="Using default logging driver json-file"
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.803130100Z" level=debug msg="[graphdriver] priority list: [btrfs zfs overlay2 aufs overlay devicemapper vfs]"
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.804791700Z" level=debug msg="backingFs=extfs, projectQuotaSupported=false" storage-driver=overlay2
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.804832300Z" level=info msg="[graphdriver] using prior storage driver: overlay2"
Jan 03 11:41:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:32.804852400Z" level=debug msg="Initialized graph driver overlay2"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.047053100Z" level=info msg="Graph migration to content-addressability took 0.00 seconds"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.047440800Z" level=warning msg="Your kernel does not support swap memory limit"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.047527900Z" level=warning msg="Your kernel does not support cgroup rt period"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.047545600Z" level=warning msg="Your kernel does not support cgroup rt runtime"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.047562400Z" level=warning msg="Your kernel does not support cgroup blkio weight"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.047577800Z" level=warning msg="Your kernel does not support cgroup blkio weight_device"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.049319400Z" level=debug msg="Max Concurrent Downloads: 3"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.049344800Z" level=debug msg="Max Concurrent Uploads: 5"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.049594000Z" level=info msg="Loading containers: start."
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.049942300Z" level=debug msg="processing event stream" module=libcontainerd namespace=moby
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.055111700Z" level=debug msg="Option Experimental: false"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.055149800Z" level=debug msg="Option DefaultDriver: bridge"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.055167600Z" level=debug msg="Option DefaultNetwork: bridge"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.055179100Z" level=debug msg="Network Control Plane MTU: 1500"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.176737500Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-ISOLATION]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.178468800Z" level=debug msg="/sbin/iptables, [--wait -t nat -D PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.242914600Z" level=debug msg="/sbin/iptables, [--wait -t nat -D OUTPUT -m addrtype --dst-type LOCAL ! --dst 127.0.0.0/8 -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.244449800Z" level=debug msg="/sbin/iptables, [--wait -t nat -D OUTPUT -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.245826600Z" level=debug msg="/sbin/iptables, [--wait -t nat -D PREROUTING]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.247152200Z" level=debug msg="/sbin/iptables, [--wait -t nat -D OUTPUT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.248492000Z" level=debug msg="/sbin/iptables, [--wait -t nat -F DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.249685300Z" level=debug msg="/sbin/iptables, [--wait -t nat -X DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.250860100Z" level=debug msg="/sbin/iptables, [--wait -t filter -F DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.251982100Z" level=debug msg="/sbin/iptables, [--wait -t filter -X DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.258113400Z" level=debug msg="/sbin/iptables, [--wait -t filter -F DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.259193100Z" level=debug msg="/sbin/iptables, [--wait -t filter -X DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.260376600Z" level=debug msg="/sbin/iptables, [--wait -t filter -F DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.261523400Z" level=debug msg="/sbin/iptables, [--wait -t filter -X DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.263258600Z" level=debug msg="/sbin/iptables, [--wait -t filter -F DOCKER-ISOLATION]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.264301900Z" level=debug msg="/sbin/iptables, [--wait -t filter -X DOCKER-ISOLATION]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.265405400Z" level=debug msg="/sbin/iptables, [--wait -t nat -n -L DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.266464300Z" level=debug msg="/sbin/iptables, [--wait -t nat -N DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.271417700Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.272550400Z" level=debug msg="/sbin/iptables, [--wait -t filter -N DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.273669800Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.274715500Z" level=debug msg="/sbin/iptables, [--wait -t filter -N DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.279056700Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.280191300Z" level=debug msg="/sbin/iptables, [--wait -t filter -N DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.281332500Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-1 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.282566600Z" level=debug msg="/sbin/iptables, [--wait -A DOCKER-ISOLATION-STAGE-1 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.283716200Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-2 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.284910500Z" level=debug msg="/sbin/iptables, [--wait -A DOCKER-ISOLATION-STAGE-2 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.286378400Z" level=debug msg="/sbin/iptables, [--wait -t filter -P FORWARD DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.330379700Z" level=debug msg="Did not find any interface with name docker0: Link not found"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.338512800Z" level=debug msg="Setting bridge mac address to 02:42:a7:fd:87:a4"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.340411900Z" level=debug msg="Assigning address to bridge interface docker0: 172.18.0.1/16"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.340797700Z" level=debug msg="/sbin/iptables, [--wait -t nat -C POSTROUTING -s 172.18.0.0/16 ! -o docker0 -j MASQUERADE]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.342471300Z" level=debug msg="/sbin/iptables, [--wait -t nat -I POSTROUTING -s 172.18.0.0/16 ! -o docker0 -j MASQUERADE]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.362693800Z" level=debug msg="/sbin/iptables, [--wait -t nat -C DOCKER -i docker0 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.370432100Z" level=debug msg="/sbin/iptables, [--wait -t nat -I DOCKER -i docker0 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.371742000Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -i docker0 -o docker0 -j DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.373497900Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker0 -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.378851100Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -i docker0 -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.380011600Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker0 ! -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.381258700Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -i docker0 ! -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.382414100Z" level=debug msg="/sbin/iptables, [--wait -t nat -C PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.383762200Z" level=debug msg="/sbin/iptables, [--wait -t nat -A PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.385075000Z" level=debug msg="/sbin/iptables, [--wait -t nat -C OUTPUT -m addrtype --dst-type LOCAL -j DOCKER ! --dst 127.0.0.0/8]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.390437000Z" level=debug msg="/sbin/iptables, [--wait -t nat -A OUTPUT -m addrtype --dst-type LOCAL -j DOCKER ! --dst 127.0.0.0/8]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.391686300Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.392809000Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -o docker0 -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.393947400Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.395283800Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.400480200Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.401743900Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.402974400Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-1 -i docker0 ! -o docker0 -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.404270500Z" level=debug msg="/sbin/iptables, [--wait -t filter -I DOCKER-ISOLATION-STAGE-1 -i docker0 ! -o docker0 -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.405460700Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-2 -o docker0 -j DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.406691200Z" level=debug msg="/sbin/iptables, [--wait -t filter -I DOCKER-ISOLATION-STAGE-2 -o docker0 -j DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.408009400Z" level=debug msg="Network (513b699) restored"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.413729400Z" level=debug msg="Allocating IPv4 pools for network bridge (513b699b1c6e72fe423ab08625d4f72c4f6d4625f80770d15ff95e114518eb21)"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.413900000Z" level=debug msg="RequestPool(LocalDefault, 172.18.0.0/16, , map[], false)"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.414076300Z" level=debug msg="RequestAddress(LocalDefault/172.18.0.0/16, 172.18.0.1, map[RequestAddressType:com.docker.network.gateway])"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.414250400Z" level=debug msg="Request address PoolID:172.18.0.0/16 App: ipam/default/data, ID: LocalDefault/172.18.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65534, Sequence: (0x80000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:172.18.0.1 "
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.437998300Z" level=debug msg="/sbin/iptables, [--wait -t nat -C POSTROUTING -s 172.18.0.0/16 ! -o docker0 -j MASQUERADE]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.439365900Z" level=debug msg="/sbin/iptables, [--wait -t nat -D POSTROUTING -s 172.18.0.0/16 ! -o docker0 -j MASQUERADE]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.440607900Z" level=debug msg="/sbin/iptables, [--wait -t nat -C DOCKER -i docker0 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.441842400Z" level=debug msg="/sbin/iptables, [--wait -t nat -D DOCKER -i docker0 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.443073900Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker0 -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.444177400Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -i docker0 -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.445420200Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker0 ! -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.446608600Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -i docker0 ! -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.447820900Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.448978000Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.450809800Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -o docker0 -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.451963200Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.453216100Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.457672400Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.459158100Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-1 -i docker0 ! -o docker0 -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.460310700Z" level=debug msg="/sbin/iptables, [--wait -t filter -D DOCKER-ISOLATION-STAGE-1 -i docker0 ! -o docker0 -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.465405700Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-2 -o docker0 -j DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.466782500Z" level=debug msg="/sbin/iptables, [--wait -t filter -D DOCKER-ISOLATION-STAGE-2 -o docker0 -j DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.479217500Z" level=debug msg="releasing IPv4 pools from network bridge (513b699b1c6e72fe423ab08625d4f72c4f6d4625f80770d15ff95e114518eb21)"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.479261400Z" level=debug msg="ReleaseAddress(LocalDefault/172.18.0.0/16, 172.18.0.1)"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.479305300Z" level=debug msg="Released address PoolID:LocalDefault/172.18.0.0/16, Address:172.18.0.1 Sequence:App: ipam/default/data, ID: LocalDefault/172.18.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.479349400Z" level=debug msg="ReleasePool(LocalDefault/172.18.0.0/16)"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.493920500Z" level=debug msg="cleanupServiceDiscovery for network:513b699b1c6e72fe423ab08625d4f72c4f6d4625f80770d15ff95e114518eb21"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.539767700Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.18.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.539851300Z" level=debug msg="Allocating IPv4 pools for network bridge (37331a25bc84ed04086c42415b5bc990eea13ec073895322a0695335c382ffb0)"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.539879600Z" level=debug msg="RequestPool(LocalDefault, 172.18.0.0/16, , map[], false)"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.540010800Z" level=debug msg="RequestAddress(LocalDefault/172.18.0.0/16, 172.18.0.1, map[RequestAddressType:com.docker.network.gateway])"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.541312700Z" level=debug msg="Request address PoolID:172.18.0.0/16 App: ipam/default/data, ID: LocalDefault/172.18.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65534, Sequence: (0x80000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:172.18.0.1 "
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.541913900Z" level=debug msg="/sbin/iptables, [--wait -t nat -C POSTROUTING -s 172.18.0.0/16 ! -o docker0 -j MASQUERADE]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.554632300Z" level=debug msg="/sbin/iptables, [--wait -t nat -I POSTROUTING -s 172.18.0.0/16 ! -o docker0 -j MASQUERADE]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.556058100Z" level=debug msg="/sbin/iptables, [--wait -t nat -C DOCKER -i docker0 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.557354400Z" level=debug msg="/sbin/iptables, [--wait -t nat -I DOCKER -i docker0 -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.558557800Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -i docker0 -o docker0 -j DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.563940200Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker0 -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.565390000Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -i docker0 -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.566763700Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker0 ! -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.568157700Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -i docker0 ! -o docker0 -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.569350200Z" level=debug msg="/sbin/iptables, [--wait -t nat -C PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.570635900Z" level=debug msg="/sbin/iptables, [--wait -t nat -C PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.576023100Z" level=debug msg="/sbin/iptables, [--wait -t nat -C OUTPUT -m addrtype --dst-type LOCAL -j DOCKER ! --dst 127.0.0.0/8]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.577390700Z" level=debug msg="/sbin/iptables, [--wait -t nat -C OUTPUT -m addrtype --dst-type LOCAL -j DOCKER ! --dst 127.0.0.0/8]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.578839900Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.580018700Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -o docker0 -j DOCKER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.581206300Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.582561800Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -o docker0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.583873200Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.589006000Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.590271200Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.591711700Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-1 -i docker0 ! -o docker0 -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.592974900Z" level=debug msg="/sbin/iptables, [--wait -t filter -I DOCKER-ISOLATION-STAGE-1 -i docker0 ! -o docker0 -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.594433800Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-2 -o docker0 -j DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.595713900Z" level=debug msg="/sbin/iptables, [--wait -t filter -I DOCKER-ISOLATION-STAGE-2 -o docker0 -j DROP]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.631293500Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-USER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.632457300Z" level=debug msg="/sbin/iptables, [--wait -t filter -N DOCKER-USER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.633723800Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-USER -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.635104900Z" level=debug msg="/sbin/iptables, [--wait -A DOCKER-USER -j RETURN]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.636379200Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-USER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.638061400Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-USER]"
Jan 03 11:41:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:33.639311800Z" level=info msg="Loading containers: done."
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.154260650Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.154642055Z" level=info msg="Docker daemon" commit=4d60db4 graphdriver(s)=overlay2 version=18.09.0
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.322026237Z" level=info msg="Daemon has completed initialization"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.666625445Z" level=debug msg="Registering routers"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.666689246Z" level=debug msg="Registering GET, /containers/{name:.*}/checkpoints"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.667088452Z" level=debug msg="Registering POST, /containers/{name:.*}/checkpoints"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.667213154Z" level=debug msg="Registering DELETE, /containers/{name}/checkpoints/{checkpoint}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.667521959Z" level=debug msg="Registering HEAD, /containers/{name:.*}/archive"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.667711161Z" level=debug msg="Registering GET, /containers/json"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.667814963Z" level=debug msg="Registering GET, /containers/{name:.*}/export"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.667997566Z" level=debug msg="Registering GET, /containers/{name:.*}/changes"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.668234669Z" level=debug msg="Registering GET, /containers/{name:.*}/json"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.668414872Z" level=debug msg="Registering GET, /containers/{name:.*}/top"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.668598875Z" level=debug msg="Registering GET, /containers/{name:.*}/logs"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.668997880Z" level=debug msg="Registering GET, /containers/{name:.*}/stats"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.669186283Z" level=debug msg="Registering GET, /containers/{name:.*}/attach/ws"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.669366186Z" level=debug msg="Registering GET, /exec/{id:.*}/json"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.669538288Z" level=debug msg="Registering GET, /containers/{name:.*}/archive"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.669713491Z" level=debug msg="Registering POST, /containers/create"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.669874193Z" level=debug msg="Registering POST, /containers/{name:.*}/kill"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.670065896Z" level=debug msg="Registering POST, /containers/{name:.*}/pause"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.670261699Z" level=debug msg="Registering POST, /containers/{name:.*}/unpause"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.670440302Z" level=debug msg="Registering POST, /containers/{name:.*}/restart"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.670623605Z" level=debug msg="Registering POST, /containers/{name:.*}/start"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.670796707Z" level=debug msg="Registering POST, /containers/{name:.*}/stop"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.670969010Z" level=debug msg="Registering POST, /containers/{name:.*}/wait"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.671153812Z" level=debug msg="Registering POST, /containers/{name:.*}/resize"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.671330015Z" level=debug msg="Registering POST, /containers/{name:.*}/attach"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.671508318Z" level=debug msg="Registering POST, /containers/{name:.*}/copy"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.671689620Z" level=debug msg="Registering POST, /containers/{name:.*}/exec"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.671903624Z" level=debug msg="Registering POST, /exec/{name:.*}/start"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672024525Z" level=debug msg="Registering POST, /exec/{name:.*}/resize"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672111927Z" level=debug msg="Registering POST, /containers/{name:.*}/rename"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672217928Z" level=debug msg="Registering POST, /containers/{name:.*}/update"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672312530Z" level=debug msg="Registering POST, /containers/prune"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672398431Z" level=debug msg="Registering POST, /commit"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672458032Z" level=debug msg="Registering PUT, /containers/{name:.*}/archive"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672563233Z" level=debug msg="Registering DELETE, /containers/{name:.*}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672664135Z" level=debug msg="Registering GET, /images/json"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672739436Z" level=debug msg="Registering GET, /images/search"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672813537Z" level=debug msg="Registering GET, /images/get"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672881338Z" level=debug msg="Registering GET, /images/{name:.*}/get"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.672971839Z" level=debug msg="Registering GET, /images/{name:.*}/history"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.673079741Z" level=debug msg="Registering GET, /images/{name:.*}/json"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.673179242Z" level=debug msg="Registering POST, /images/load"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.673236943Z" level=debug msg="Registering POST, /images/create"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.673310444Z" level=debug msg="Registering POST, /images/{name:.*}/push"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.673477047Z" level=debug msg="Registering POST, /images/{name:.*}/tag"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.674035955Z" level=debug msg="Registering POST, /images/prune"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.674256058Z" level=debug msg="Registering DELETE, /images/{name:.*}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.674812967Z" level=debug msg="Registering OPTIONS, /{anyroute:.*}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.674978469Z" level=debug msg="Registering GET, /_ping"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.675121971Z" level=debug msg="Registering GET, /events"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.675270273Z" level=debug msg="Registering GET, /info"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.675393975Z" level=debug msg="Registering GET, /version"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.675538077Z" level=debug msg="Registering GET, /system/df"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.675677079Z" level=debug msg="Registering POST, /auth"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.675803681Z" level=debug msg="Registering GET, /volumes"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.675945483Z" level=debug msg="Registering GET, /volumes/{name:.*}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.676103386Z" level=debug msg="Registering POST, /volumes/create"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.676250088Z" level=debug msg="Registering POST, /volumes/prune"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.676394990Z" level=debug msg="Registering DELETE, /volumes/{name:.*}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.676581393Z" level=debug msg="Registering POST, /build"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.676729495Z" level=debug msg="Registering POST, /build/prune"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.676862597Z" level=debug msg="Registering POST, /build/cancel"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.677018799Z" level=debug msg="Registering POST, /session"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.677154401Z" level=debug msg="Registering POST, /swarm/init"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.677293703Z" level=debug msg="Registering POST, /swarm/join"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.677445206Z" level=debug msg="Registering POST, /swarm/leave"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.681487366Z" level=debug msg="Registering GET, /swarm"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.681643068Z" level=debug msg="Registering GET, /swarm/unlockkey"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.681840271Z" level=debug msg="Registering POST, /swarm/update"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.681993473Z" level=debug msg="Registering POST, /swarm/unlock"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.682121675Z" level=debug msg="Registering GET, /services"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.682265977Z" level=debug msg="Registering GET, /services/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.682430080Z" level=debug msg="Registering POST, /services/create"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.682578082Z" level=debug msg="Registering POST, /services/{id}/update"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.682761984Z" level=debug msg="Registering DELETE, /services/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.682925587Z" level=debug msg="Registering GET, /services/{id}/logs"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.683114090Z" level=debug msg="Registering GET, /nodes"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.683232491Z" level=debug msg="Registering GET, /nodes/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.683379394Z" level=debug msg="Registering DELETE, /nodes/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.683542196Z" level=debug msg="Registering POST, /nodes/{id}/update"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.683715499Z" level=debug msg="Registering GET, /tasks"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.683861101Z" level=debug msg="Registering GET, /tasks/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.684018003Z" level=debug msg="Registering GET, /tasks/{id}/logs"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.684211606Z" level=debug msg="Registering GET, /secrets"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.684340708Z" level=debug msg="Registering POST, /secrets/create"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.684645612Z" level=debug msg="Registering DELETE, /secrets/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.685100819Z" level=debug msg="Registering GET, /secrets/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.685269022Z" level=debug msg="Registering POST, /secrets/{id}/update"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.685454924Z" level=debug msg="Registering GET, /configs"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.685603827Z" level=debug msg="Registering POST, /configs/create"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.685731729Z" level=debug msg="Registering DELETE, /configs/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.685889431Z" level=debug msg="Registering GET, /configs/{id}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.686059833Z" level=debug msg="Registering POST, /configs/{id}/update"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.686262336Z" level=debug msg="Registering GET, /plugins"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.686402338Z" level=debug msg="Registering GET, /plugins/{name:.*}/json"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.686575441Z" level=debug msg="Registering GET, /plugins/privileges"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.686732443Z" level=debug msg="Registering DELETE, /plugins/{name:.*}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.686915646Z" level=debug msg="Registering POST, /plugins/{name:.*}/enable"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.687097749Z" level=debug msg="Registering POST, /plugins/{name:.*}/disable"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.687272451Z" level=debug msg="Registering POST, /plugins/pull"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.687420454Z" level=debug msg="Registering POST, /plugins/{name:.*}/push"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.687589456Z" level=debug msg="Registering POST, /plugins/{name:.*}/upgrade"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.691695917Z" level=debug msg="Registering POST, /plugins/{name:.*}/set"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.691902020Z" level=debug msg="Registering POST, /plugins/create"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.692049722Z" level=debug msg="Registering GET, /distribution/{name:.*}/json"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.692220625Z" level=debug msg="Registering GET, /networks"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.692314726Z" level=debug msg="Registering GET, /networks/"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.692437728Z" level=debug msg="Registering GET, /networks/{id:.+}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.692671831Z" level=debug msg="Registering POST, /networks/create"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.692808333Z" level=debug msg="Registering POST, /networks/{id:.*}/connect"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.692929535Z" level=debug msg="Registering POST, /networks/{id:.*}/disconnect"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.693128838Z" level=debug msg="Registering POST, /networks/prune"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.693284140Z" level=debug msg="Registering DELETE, /networks/{id:.*}"
Jan 03 11:41:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:41:36.693775448Z" level=info msg="API listen on /var/run/docker.sock"
Jan 03 11:41:36 dc2node-nvm0 systemd[1]: Started Docker Application Container Engine.
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.446475024Z" level=debug msg="Calling GET /_ping"
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.447237156Z" level=debug msg="Calling GET /_ping"
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.448320501Z" level=debug msg="Calling POST /v1.39/swarm/init"
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.448815322Z" level=debug msg="form data: {\"AdvertiseAddr\":\"\",\"AutoLockManagers\":false,\"Availability\":\"\",\"DataPathAddr\":\"\",\"DefaultAddrPool\":null,\"ForceNewCluster\":false,\"ListenAddr\":\"0.0.0.0:2377\",\"Spec\":{\"CAConfig\":{},\"Dispatcher\":{},\"EncryptionConfig\":{\"AutoLockManagers\":false},\"Labels\":null,\"Orchestration\":{},\"Raft\":{\"ElectionTick\":0,\"HeartbeatTick\":0},\"TaskDefaults\":{}},\"SubnetSize\":24}"
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.549795016Z" level=debug msg="generated CA key and certificate" module=node
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.549875620Z" level=debug msg="no node credentials found in: /datadisk/swarm/certificates/swarm-node.crt" error="open /datadisk/swarm/certificates/swarm-node.key: no such file or directory" module=node
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.586832855Z" level=debug msg="issued new TLS certificate" module=node/tls node.id=6m8os6plt3f23t24ngxegaqgx node.role=swarm-manager
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.587522883Z" level=debug msg="new node credentials generated: /datadisk/swarm/certificates/swarm-node.crt" module=node/tls node.id=6m8os6plt3f23t24ngxegaqgx node.role=swarm-manager
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.606094455Z" level=debug msg="next certificate renewal scheduled for 1704h23m3.393923346s from now" fields.time="2019-03-15 12:06:00.0000004 +0000 UTC m=+6135871.587551501" module=node/tls node.id=6m8os6plt3f23t24ngxegaqgx node.role=swarm-manager
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.624823433Z" level=info msg="parsed scheme: \"\"" module=grpc
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.624892736Z" level=info msg="scheme \"\" not registered, fallback to default scheme" module=grpc
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.624957738Z" level=info msg="ccResolverWrapper: sending new addresses to cc: [{/var/run/docker/swarm/control.sock 0 <nil>}]" module=grpc
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.624978639Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.625024641Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc420920790, CONNECTING" module=grpc
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.628146471Z" level=info msg="Listening for connections" addr="[::]:2377" module=node node.id=6m8os6plt3f23t24ngxegaqgx proto=tcp
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.628227474Z" level=info msg="Listening for local connections" addr=/var/run/docker/swarm/control.sock module=node node.id=6m8os6plt3f23t24ngxegaqgx proto=unix
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.630889885Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc420920790, READY" module=grpc
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.724494173Z" level=info msg="5e74dde9fc5bfdf5 became follower at term 0" module=raft node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.724549975Z" level=info msg="newRaft 5e74dde9fc5bfdf5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]" module=raft node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.724577576Z" level=info msg="5e74dde9fc5bfdf5 became follower at term 1" module=raft node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.730948041Z" level=info msg="5e74dde9fc5bfdf5 is starting a new election at term 1" module=raft node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.731025744Z" level=info msg="5e74dde9fc5bfdf5 became candidate at term 2" module=raft node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.731052545Z" level=info msg="5e74dde9fc5bfdf5 received MsgVoteResp from 5e74dde9fc5bfdf5 at term 2" module=raft node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.731073546Z" level=info msg="5e74dde9fc5bfdf5 became leader at term 2" module=raft node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.731087547Z" level=info msg="raft.node: 5e74dde9fc5bfdf5 elected leader 5e74dde9fc5bfdf5 at term 2" module=raft node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.737335506Z" level=info msg="Creating default ingress network" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.744062186Z" level=info msg="leadership changed from not yet part of a raft cluster to 6m8os6plt3f23t24ngxegaqgx" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.755152846Z" level=debug msg="RequestPool(GlobalDefault, 10.255.0.0/16, , map[], false)"
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.755224949Z" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[RequestAddressType:com.docker.network.gateway com.docker.network.ipam.serial:true])"
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.755257951Z" level=debug msg="Request address PoolID:10.255.0.0/16 App: ipam/default/data, ID: GlobalDefault/10.255.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65534, Sequence: (0x80000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:true PrefAddress:<nil> "
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.755377856Z" level=info msg="dispatcher starting" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.782344576Z" level=debug msg="Root CA set successfully" cluster.id=jkw54ivjwqbmm6tmkk4h0p04u method="(*Server).UpdateRootCA" module=ca node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.793035820Z" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[com.docker.network.ipam.serial:true])"
Jan 03 11:42:56 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:56.793102523Z" level=debug msg="Request address PoolID:10.255.0.0/16 App: ipam/default/data, ID: GlobalDefault/10.255.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:2 Serial:true PrefAddress:<nil> "
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.132554290Z" level=debug msg="(*Agent).run" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.171104550Z" level=info msg="manager selected by agent for new session: { }" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.171263657Z" level=info msg="waiting 0s before registering session" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.171313659Z" level=debug msg="(*session).start" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.188401651Z" level=debug msg="node status updated" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.193920874Z" level=info msg="worker 6m8os6plt3f23t24ngxegaqgx was successfully registered" method="(*Dispatcher).register"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.209954023Z" level=debug method="(*session).logSubscriptions" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.210061427Z" level=debug msg="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.210608649Z" level=debug method="(*session).watch" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.210747255Z" level=debug msg="(*session).listen" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.210900061Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.211225674Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.592035008s" method="(*Dispatcher).Heartbeat"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.211360680Z" level=debug msg="node registered" method="(*LogBroker).ListenSubscriptions" node=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.211475084Z" level=debug method="(*Dispatcher).Assignments" node.id=6m8os6plt3f23t24ngxegaqgx node.session=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.211678793Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.592035008s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.228711582Z" level=debug msg="(*worker).Assign" len(assignments)=0 module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.228755984Z" level=debug msg="(*worker).reconcileSecrets" len(removedSecrets)=0 len(updatedSecrets)=0 module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.228776485Z" level=debug msg="(*worker).reconcileConfigs" len(removedConfigs)=0 len(updatedConfigs)=0 module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.228791585Z" level=debug msg="(*worker).reconcileTaskState" len(removedTasks)=0 len(updatedTasks)=0 module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.229163700Z" level=info msg="Initializing Libnetwork Agent Listen-Addr=0.0.0.0 Local-addr=172.17.1.10 Adv-addr=172.17.1.10 Data-addr= Remote-addr-list=[] MTU=1500"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.229275505Z" level=debug msg="Control plane MTU: 1500 will initialize NetworkDB with: 1400"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.229337407Z" level=info msg="New memberlist node - Node:dc2node-nvm0 will use memberlist nodeID:b9e1cbdaa68e with config:&{NodeID:b9e1cbdaa68e Hostname:dc2node-nvm0 BindAddr:0.0.0.0 AdvertiseAddr:172.17.1.10 BindPort:0 Keys:[[59 173 192 237 181 175 61 57 189 154 63 226 12 57 34 126] [129 164 207 138 44 139 208 90 105 0 236 22 205 165 161 172] [177 190 227 97 13 116 102 246 94 161 178 112 107 28 56 179]] PacketBufferSize:1400 reapEntryInterval:1800000000000 reapNetworkInterval:1825000000000 StatsPrintPeriod:5m0s HealthPrintPeriod:1m0s}"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.229405510Z" level=debug msg="Encryption key 1: 3badc"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.229418911Z" level=debug msg="Encryption key 2: 81a4c"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.229428711Z" level=debug msg="Encryption key 3: b1bee"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.241147385Z" level=debug msg="agent: registered" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.244450419Z" level=info msg="Node b9e1cbdaa68e/172.17.1.10, joined gossip cluster"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.244729230Z" level=info msg="Node b9e1cbdaa68e/172.17.1.10, added to nodes list"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.245188349Z" level=debug msg="Initial encryption keys: [(key: 21e89, tag: 0x4352) (key: c5165, tag: 0x4351) (key: 35044, tag: 0x4353)]"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.245754972Z" level=debug msg="Initial encryption keys: [(key: 21e89, tag: 0x4352) (key: c5165, tag: 0x4351) (key: 35044, tag: 0x4353)]"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.246283793Z" level=debug msg="Allocating IPv4 pools for network ingress (1c7lonmv59ig920zwpi5vzn0x)"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.246478601Z" level=debug msg="RequestPool(LocalDefault, 10.255.0.0/16, , map[], false)"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.246706610Z" level=debug msg="RequestAddress(LocalDefault/10.255.0.0/16, 10.255.0.1, map[RequestAddressType:com.docker.network.gateway])"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.246918819Z" level=debug msg="Request address PoolID:10.255.0.0/16 App: ipam/default/data, ID: LocalDefault/10.255.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65534, Sequence: (0x80000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:10.255.0.1 "
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.247218331Z" level=debug msg="updated kernel parameter net.ipv4.neigh.default.gc_thresh3 = 65536 (was 1024)"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.247459441Z" level=debug msg="updated kernel parameter net.ipv4.neigh.default.gc_thresh1 = 8192 (was 128)"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.247705751Z" level=debug msg="updated kernel parameter net.ipv4.neigh.default.gc_thresh2 = 49152 (was 512)"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.247883958Z" level=debug msg="overlay: Received vxlan IDs: 4096"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.248084066Z" level=debug msg="/sbin/iptables, [--wait -t mangle -C OUTPUT -p udp --dport 4789 -m u32 --u32 0>>22&0x3C@12&0xFFFFFF00=1048576 -j MARK --set-mark 13681891]"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.277025638Z" level=debug msg="Calling GET /v1.39/nodes/6m8os6plt3f23t24ngxegaqgx"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.278953916Z" level=debug msg="Calling GET /v1.39/swarm"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.343109112Z" level=debug msg="/sbin/iptables, [--wait -t filter -C INPUT -m policy --dir in --pol ipsec -p udp --dport 4789 -m u32 --u32 0>>22&0x3C@12&0xFFFFFF00=1048576 -j ACCEPT]"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.345741419Z" level=debug msg="/sbin/iptables, [--wait -t filter -C INPUT -p udp --dport 4789 -m u32 --u32 0>>22&0x3C@12&0xFFFFFF00=1048576 -j DROP]"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.347909307Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): joined network 1c7lonmv59ig920zwpi5vzn0x"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.380662332Z" level=info msg="No non-localhost DNS nameservers are left in resolv.conf. Using default external servers: [nameserver 8.8.8.8 nameserver 8.8.4.4]"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.380716235Z" level=info msg="IPv6 enabled; Adding default IPv6 external servers: [nameserver 2001:4860:4860::8888 nameserver 2001:4860:4860::8844]"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.581825875Z" level=error msg="error reading the kernel parameter net.ipv4.vs.expire_nodest_conn" error="open /proc/sys/net/ipv4/vs/expire_nodest_conn: no such file or directory"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.593934365Z" level=debug msg="Assigning addresses for endpoint ingress-endpoint's interface on network ingress"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.593985567Z" level=debug msg="RequestAddress(LocalDefault/10.255.0.0/16, 10.255.0.2, map[])"
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.594022268Z" level=debug msg="Request address PoolID:10.255.0.0/16 App: ipam/default/data, ID: LocalDefault/10.255.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:10.255.0.2 "
Jan 03 11:42:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:57.630365939Z" level=debug msg="Assigning addresses for endpoint ingress-endpoint's interface on network ingress"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.070836301Z" level=debug msg="checkEncryption(1c7lonm, <nil>, 4096, true)"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.619645879Z" level=debug msg="Allocating IPv4 pools for network docker_gwbridge (ebf9ca9341335de76ac2027dfe9aa2cf53cf9620b85ccd46f0b7ecb225b89e17)"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.619704882Z" level=debug msg="RequestPool(LocalDefault, , , map[], false)"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.619910790Z" level=debug msg="RequestPool(LocalDefault, , , map[], false)"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.620045895Z" level=debug msg="ReleasePool(LocalDefault/172.17.0.0/16)"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.620076896Z" level=debug msg="RequestAddress(LocalDefault/172.19.0.0/16, <nil>, map[RequestAddressType:com.docker.network.gateway])"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.620104398Z" level=debug msg="Request address PoolID:172.19.0.0/16 App: ipam/default/data, ID: LocalDefault/172.19.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65534, Sequence: (0x80000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:<nil> "
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.620181101Z" level=debug msg="Did not find any interface with name docker_gwbridge: Link not found"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.621289145Z" level=debug msg="Setting bridge mac address to 02:42:2d:67:01:25"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.621372248Z" level=debug msg="Assigning address to bridge interface docker_gwbridge: 172.19.0.1/16"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.621474153Z" level=debug msg="/sbin/iptables, [--wait -t nat -C POSTROUTING -s 172.19.0.0/16 ! -o docker_gwbridge -j MASQUERADE]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.623020815Z" level=debug msg="/sbin/iptables, [--wait -t nat -I POSTROUTING -s 172.19.0.0/16 ! -o docker_gwbridge -j MASQUERADE]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.624408570Z" level=debug msg="/sbin/iptables, [--wait -t nat -C DOCKER -i docker_gwbridge -j RETURN]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.626524555Z" level=debug msg="/sbin/iptables, [--wait -t nat -I DOCKER -i docker_gwbridge -j RETURN]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.627938212Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -i docker_gwbridge -o docker_gwbridge -j ACCEPT]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.629309367Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker_gwbridge -o docker_gwbridge -j DROP]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.635845529Z" level=debug msg="/sbin/iptables, [--wait -A FORWARD -i docker_gwbridge -o docker_gwbridge -j DROP]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.636970974Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -i docker_gwbridge ! -o docker_gwbridge -j ACCEPT]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.638100019Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -i docker_gwbridge ! -o docker_gwbridge -j ACCEPT]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.639490975Z" level=debug msg="/sbin/iptables, [--wait -t nat -C PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.645858030Z" level=debug msg="/sbin/iptables, [--wait -t nat -C PREROUTING -m addrtype --dst-type LOCAL -j DOCKER]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.647583299Z" level=debug msg="/sbin/iptables, [--wait -t nat -C OUTPUT -m addrtype --dst-type LOCAL -j DOCKER ! --dst 127.0.0.0/8]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.648810048Z" level=debug msg="/sbin/iptables, [--wait -t nat -C OUTPUT -m addrtype --dst-type LOCAL -j DOCKER ! --dst 127.0.0.0/8]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.654004957Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker_gwbridge -j DOCKER]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.655385612Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -o docker_gwbridge -j DOCKER]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.656534258Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -o docker_gwbridge -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.657944815Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -o docker_gwbridge -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.659305069Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.660442915Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.661637463Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-ISOLATION-STAGE-1]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.666728667Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-1 -i docker_gwbridge ! -o docker_gwbridge -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.667928715Z" level=debug msg="/sbin/iptables, [--wait -t filter -I DOCKER-ISOLATION-STAGE-1 -i docker_gwbridge ! -o docker_gwbridge -j DOCKER-ISOLATION-STAGE-2]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.669114962Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-ISOLATION-STAGE-2 -o docker_gwbridge -j DROP]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.670483217Z" level=debug msg="/sbin/iptables, [--wait -t filter -I DOCKER-ISOLATION-STAGE-2 -o docker_gwbridge -j DROP]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.841012053Z" level=debug msg="/sbin/iptables, [--wait -t filter -nL DOCKER-INGRESS]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.842463912Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-USER]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.844396989Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-USER -j RETURN]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.845557336Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-USER]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.846774184Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-USER]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.848675361Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-USER]"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.850095218Z" level=debug msg="Assigning addresses for endpoint gateway_ingress-sbox's interface on network docker_gwbridge"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.850145820Z" level=debug msg="RequestAddress(LocalDefault/172.19.0.0/16, <nil>, map[])"
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.850206222Z" level=debug msg="Request address PoolID:172.19.0.0/16 App: ipam/default/data, ID: LocalDefault/172.19.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:2 Serial:false PrefAddress:<nil> "
Jan 03 11:42:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:58.891556880Z" level=debug msg="Assigning addresses for endpoint gateway_ingress-sbox's interface on network docker_gwbridge"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.015038370Z" level=debug msg="Programming external connectivity on endpoint gateway_ingress-sbox (b9adcbe26fd4e68fecf1843cc170d1f5661eb5ba558e00e9365ccf097b5f97b9)"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.033672289Z" level=debug msg="EnableService ingress-sbox START"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.033719990Z" level=debug msg="addServiceInfoToCluster START for 9c53d63eaa37c916c4918505ffbaeedc8f7451d008e1a22bc57663fd83558c51"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.033775490Z" level=debug msg="addContainerNameResolution 9c53d63eaa37c916c4918505ffbaeedc8f7451d008e1a22bc57663fd83558c51 ingress-endpoint"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.033835891Z" level=debug msg="addServiceInfoToCluster END for 9c53d63eaa37c916c4918505ffbaeedc8f7451d008e1a22bc57663fd83558c51"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.033858391Z" level=debug msg="EnableService ingress-sbox DONE"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.033882492Z" level=debug msg="/sbin/iptables, [--wait -t filter -nL DOCKER-INGRESS]"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.035466310Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-USER]"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.036715925Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-USER -j RETURN]"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.037964340Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-USER]"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.039179655Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-USER]"
Jan 03 11:42:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:42:59.040532771Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-USER]"
Jan 03 11:43:01 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:01.803975099Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:01 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:01.804485005Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.734415017s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:01 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:01.804636407Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.734415017s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:06 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:06.539357860Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:06 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:06.539777965Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.980446687s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:06 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:06.539941367Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.980446687s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.040351165Z" level=debug msg="new certificate entry added" method=IssueNodeCertificate node.id=ych43rb08jsey2s7n4p738t12 node.role=WORKER
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.040483767Z" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[com.docker.network.ipam.serial:true])"
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.041465879Z" level=debug msg="Request address PoolID:10.255.0.0/16 App: ipam/default/data, ID: GlobalDefault/10.255.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65532, Sequence: (0xe0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:3 Serial:true PrefAddress:<nil> "
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.047015445Z" level=debug msg="certificate issued" method="(*Server).signNodeCert" module=ca node.id=ych43rb08jsey2s7n4p738t12 node.role=WORKER
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.218497100Z" level=debug msg="node status updated" method="(*Dispatcher).processUpdates" module=dispatcher node.id=ych43rb08jsey2s7n4p738t12
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.238120835Z" level=info msg="worker ych43rb08jsey2s7n4p738t12 was successfully registered" method="(*Dispatcher).register"
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.247632649Z" level=debug method="(*Dispatcher).Assignments" node.id=ych43rb08jsey2s7n4p738t12 node.session=r09hoaoyhfaag3baekzvtjgqv
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.248577660Z" level=debug msg="node registered" method="(*LogBroker).ListenSubscriptions" node=ych43rb08jsey2s7n4p738t12
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.250031778Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:49955}, expect next heartbeat in 5.152402522s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.317542786Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:49958"
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.318229595Z" level=info msg="Node 7c9aa543fb4f/172.17.1.20, joined gossip cluster"
Jan 03 11:43:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:10.318291895Z" level=info msg="Node 7c9aa543fb4f/172.17.1.20, added to nodes list"
Jan 03 11:43:11 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:11.520672197Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:11 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:11.521669209Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.584262082s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:11 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:11.521979113Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.584262082s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:12 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:12.745566759Z" level=debug msg="memberlist: Failed ping: 7c9aa543fb4f (timeout reached)"
Jan 03 11:43:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:13.245324337Z" level=info msg="memberlist: Suspect 7c9aa543fb4f has failed, no acks received"
Jan 03 11:43:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:13.644104506Z" level=debug msg="node status updated" method="(*Dispatcher).processUpdates" module=dispatcher node.id=ych43rb08jsey2s7n4p738t12
Jan 03 11:43:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:13.674627571Z" level=debug msg="error handling streaming rpc" error="context canceled" rpc=/docker.swarmkit.v1.LogBroker/ListenSubscriptions
Jan 03 11:43:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:13.674637872Z" level=debug msg="error handling streaming rpc" error="context canceled" rpc=/docker.swarmkit.v1.Dispatcher/Session
Jan 03 11:43:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:13.674983776Z" level=debug msg="error handling streaming rpc" error="context canceled" rpc=/docker.swarmkit.v1.Dispatcher/Assignments
Jan 03 11:43:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:13.675774985Z" level=debug msg="node registered" method="(*LogBroker).ListenSubscriptions" node=ych43rb08jsey2s7n4p738t12
Jan 03 11:43:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:13.675988388Z" level=debug method="(*Dispatcher).Assignments" node.id=ych43rb08jsey2s7n4p738t12 node.session=r09hoaoyhfaag3baekzvtjgqv
Jan 03 11:43:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:13.677013100Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.949622568s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:16 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:16.106521430Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:16 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:16.106973936Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.625820095s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:16 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:16.107406341Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.625820095s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:17 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:17.251959202Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50067"
Jan 03 11:43:17 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:17.252166204Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:43:17 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:17.526720580Z" level=debug msg="checkEncryption(1c7lonm, 172.17.1.20, 4096, false)"
Jan 03 11:43:17 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:17.526748680Z" level=debug msg="handleEpTableEvent ADD 5cc1b3a2e8111634b06ee132129a6bcbd7a5d9f3dc76650fe6cdf3328cae6a80 R:{ingress-endpoint <nil> 10.255.0.3 [] [] [] false}"
Jan 03 11:43:17 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:17.527163685Z" level=debug msg="addContainerNameResolution 5cc1b3a2e8111634b06ee132129a6bcbd7a5d9f3dc76650fe6cdf3328cae6a80 ingress-endpoint"
Jan 03 11:43:17 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:17.527399688Z" level=debug msg="Neighbor entry added for IP:10.255.0.3, mac:00:15:5d:0e:fc:a6 on ifc:vx-001000-1c7lo"
Jan 03 11:43:17 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:17.527535389Z" level=debug msg="Neighbor entry added for IP:172.17.1.20, mac:00:15:5d:0e:fc:a6 on ifc:vx-001000-1c7lo"
Jan 03 11:43:18 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:18.629557331Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.889986421s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:20 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:20.733479397Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:20 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:20.733888302Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.233055s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:20 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:20.734025703Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.233055s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:23 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:23.522389773Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.427534999s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:25 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:25.967482414Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:25 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:25.967848818Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.883171471s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:25 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:25.967996320Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.883171471s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:27.245419774Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync with node 7c9aa543fb4f"
Jan 03 11:43:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:27.246105182Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating unsolicited bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:43:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:27.251088041Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50073"
Jan 03 11:43:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:27.251780749Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Bulk sync to node 7c9aa543fb4f took 4.292851ms"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.093475929Z" level=debug msg="Calling GET /_ping"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.106946988Z" level=debug msg="Calling GET /v1.39/info"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.151846820Z" level=debug msg="Calling GET /v1.39/services?filters=%7B%22label%22%3A%7B%22com.docker.stack.namespace%3Doperations%22%3Atrue%7D%7D"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.154719954Z" level=debug msg="Calling GET /v1.39/networks?filters=%7B%22label%22%3A%7B%22com.docker.stack.namespace%3Doperations%22%3Atrue%7D%7D"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.156913180Z" level=debug msg="Calling POST /v1.39/networks/create"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.157196184Z" level=debug msg="form data: {\"Attachable\":false,\"CheckDuplicate\":false,\"ConfigFrom\":null,\"ConfigOnly\":false,\"Driver\":\"overlay\",\"EnableIPv6\":false,\"IPAM\":null,\"Ingress\":false,\"Internal\":false,\"Labels\":{\"com.docker.stack.namespace\":\"operations\"},\"Name\":\"operations_default\",\"Options\":null,\"Scope\":\"\"}"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.166618495Z" level=debug msg="RequestPool(GlobalDefault, , , map[], false)"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.167377204Z" level=debug msg="RequestAddress(GlobalDefault/10.0.0.0/24, <nil>, map[RequestAddressType:com.docker.network.gateway com.docker.network.ipam.serial:true])"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.167436405Z" level=debug msg="Request address PoolID:10.0.0.0/24 App: ipam/default/data, ID: GlobalDefault/10.0.0.0/24, DBIndex: 0x0, Bits: 256, Unselected: 254, Sequence: (0x80000000, 1)->(0x0, 6)->(0x1, 1)->end Curr:0 Serial:true PrefAddress:<nil> "
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.167769709Z" level=debug msg="Calling GET /v1.39/services?filters=%7B%22label%22%3A%7B%22com.docker.stack.namespace%3Doperations%22%3Atrue%7D%7D"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.168975323Z" level=debug msg="Calling GET /v1.39/distribution/nginx:latest/json"
Jan 03 11:43:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:28.952897712Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.719585502s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.751683572Z" level=debug msg="Calling POST /v1.39/services/create"
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.752233478Z" level=debug msg="form data: {\"EndpointSpec\":{\"Ports\":[{\"Protocol\":\"tcp\",\"PublishMode\":\"ingress\",\"PublishedPort\":9000,\"TargetPort\":80}]},\"Labels\":{\"com.docker.stack.image\":\"nginx\",\"com.docker.stack.namespace\":\"operations\"},\"Mode\":{\"Replicated\":{}},\"Name\":\"operations_test0\",\"TaskTemplate\":{\"ContainerSpec\":{\"Image\":\"nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c\",\"Labels\":{\"com.docker.stack.namespace\":\"operations\"},\"Privileges\":{\"CredentialSpec\":null,\"SELinuxContext\":null}},\"ForceUpdate\":0,\"Networks\":[{\"Aliases\":[\"test0\"],\"Target\":\"operations_default\"}],\"Placement\":{\"Constraints\":[\"node.platform.os == linux\"],\"Platforms\":[{\"Architecture\":\"amd64\",\"OS\":\"linux\"},{\"OS\":\"linux\"},{\"Architecture\":\"arm64\",\"OS\":\"linux\"},{\"Architecture\":\"386\",\"OS\":\"linux\"},{\"Architecture\":\"ppc64le\",\"OS\":\"linux\"},{\"Architecture\":\"s390x\",\"OS\":\"linux\"}]},\"Resources\":{}}}"
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.752878086Z" level=debug msg="error handling rpc" error="rpc error: code = NotFound desc = network operations_default not found" rpc=/docker.swarmkit.v1.Control/GetNetwork
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.769044677Z" level=debug msg="Service i4xb93n87eutyub1jov9uxccn was scaled up from 0 to 1 instances" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.769075078Z" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[com.docker.network.ipam.serial:true])"
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.769361081Z" level=debug msg="Request address PoolID:10.255.0.0/16 App: ipam/default/data, ID: GlobalDefault/10.255.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65531, Sequence: (0xf0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:4 Serial:true PrefAddress:<nil> "
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.769395182Z" level=debug msg="RequestAddress(GlobalDefault/10.0.0.0/24, <nil>, map[com.docker.network.ipam.serial:true])"
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.769418882Z" level=debug msg="Request address PoolID:10.0.0.0/24 App: ipam/default/data, ID: GlobalDefault/10.0.0.0/24, DBIndex: 0x0, Bits: 256, Unselected: 253, Sequence: (0xc0000000, 1)->(0x0, 6)->(0x1, 1)->end Curr:2 Serial:true PrefAddress:<nil> "
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.780558114Z" level=debug msg="task y9tlqb5lm3k2vtsp794ls7nee was marked pending allocation" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.780831917Z" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[com.docker.network.ipam.serial:true])"
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.781023719Z" level=debug msg="Request address PoolID:10.255.0.0/16 App: ipam/default/data, ID: GlobalDefault/10.255.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65530, Sequence: (0xf8000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:5 Serial:true PrefAddress:<nil> "
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.781076420Z" level=debug msg="RequestAddress(GlobalDefault/10.0.0.0/24, <nil>, map[com.docker.network.ipam.serial:true])"
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.781112420Z" level=debug msg="Request address PoolID:10.0.0.0/24 App: ipam/default/data, ID: GlobalDefault/10.0.0.0/24, DBIndex: 0x0, Bits: 256, Unselected: 252, Sequence: (0xe0000000, 1)->(0x0, 6)->(0x1, 1)->end Curr:3 Serial:true PrefAddress:<nil> "
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.781143821Z" level=debug msg="allocated task y9tlqb5lm3k2vtsp794ls7nee, state update PENDING" method="(*Allocator).allocateTask" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.781295823Z" level=debug msg="committed allocated task y9tlqb5lm3k2vtsp794ls7nee, state update {2019-01-03T11:43:29.781136521Z PENDING pending task scheduling <nil> nil (timestamp: nil Timestamp)}" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.796161699Z" level=debug msg="Task y9tlqb5lm3k2vtsp794ls7nee is already in allocated state PENDING" method="(*Allocator).doTaskAlloc" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.846327893Z" level=debug msg="assigning to node 6m8os6plt3f23t24ngxegaqgx" module=node node.id=6m8os6plt3f23t24ngxegaqgx task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.852665968Z" level=debug msg="RequestAddress(GlobalDefault/10.0.0.0/24, <nil>, map[com.docker.network.ipam.serial:true])"
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.852744869Z" level=debug msg="Request address PoolID:10.0.0.0/24 App: ipam/default/data, ID: GlobalDefault/10.0.0.0/24, DBIndex: 0x0, Bits: 256, Unselected: 251, Sequence: (0xf0000000, 1)->(0x0, 6)->(0x1, 1)->end Curr:4 Serial:true PrefAddress:<nil> "
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.858587338Z" level=debug msg="Task y9tlqb5lm3k2vtsp794ls7nee is already in allocated state ASSIGNED" method="(*Allocator).doTaskAlloc" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.953111257Z" level=debug msg="(*worker).Update" len(assignments)=1 module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.953164658Z" level=debug msg="(*worker).reconcileSecrets" len(removedSecrets)=0 len(updatedSecrets)=0 module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.953184558Z" level=debug msg="(*worker).reconcileConfigs" len(removedConfigs)=0 len(updatedConfigs)=0 module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.953199858Z" level=debug msg="(*worker).reconcileTaskState" len(removedTasks)=0 len(updatedTasks)=1 module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.953220958Z" level=debug msg=assigned module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx task.desiredstate=RUNNING task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.953410361Z" level=debug msg="state changed" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx service.id=i4xb93n87eutyub1jov9uxccn state.desired=RUNNING state.transition="ASSIGNED->ACCEPTED" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.953716764Z" level=debug msg="(*Agent).UpdateTaskStatus" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:29.953868066Z" level=debug msg="state changed" module=node/agent/taskmanager node.id=6m8os6plt3f23t24ngxegaqgx service.id=i4xb93n87eutyub1jov9uxccn state.desired=RUNNING state.transition="ASSIGNED->ACCEPTED" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.014874189Z" level=debug msg="task status reported" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.014918289Z" level=debug msg="(*Agent).UpdateTaskStatus" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.015178992Z" level=debug msg="task status reported" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.041745407Z" level=debug msg="state changed" module=node/agent/taskmanager node.id=6m8os6plt3f23t24ngxegaqgx service.id=i4xb93n87eutyub1jov9uxccn state.desired=RUNNING state.transition="ACCEPTED->PREPARING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.041964909Z" level=debug msg="(*Agent).UpdateTaskStatus" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.042512216Z" level=debug msg="task status reported" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.077082225Z" level=debug msg="Allocating IPv4 pools for network operations_default (j2cjebtq7yi152vevs2ahe150)"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.077143125Z" level=debug msg="RequestPool(LocalDefault, 10.0.0.0/24, , map[], false)"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.077187326Z" level=debug msg="RequestAddress(LocalDefault/10.0.0.0/24, 10.0.0.1, map[RequestAddressType:com.docker.network.gateway])"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.077216926Z" level=debug msg="Request address PoolID:10.0.0.0/24 App: ipam/default/data, ID: LocalDefault/10.0.0.0/24, DBIndex: 0x0, Bits: 256, Unselected: 254, Sequence: (0x80000000, 1)->(0x0, 6)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:10.0.0.1 "
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.077291427Z" level=debug msg="overlay: Received vxlan IDs: 4097"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.077321328Z" level=debug msg="/sbin/iptables, [--wait -t mangle -C OUTPUT -p udp --dport 4789 -m u32 --u32 0>>22&0x3C@12&0xFFFFFF00=1048832 -j MARK --set-mark 13681891]"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.079922558Z" level=debug msg="/sbin/iptables, [--wait -t filter -C INPUT -m policy --dir in --pol ipsec -p udp --dport 4789 -m u32 --u32 0>>22&0x3C@12&0xFFFFFF00=1048832 -j ACCEPT]"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.081850881Z" level=debug msg="/sbin/iptables, [--wait -t filter -C INPUT -p udp --dport 4789 -m u32 --u32 0>>22&0x3C@12&0xFFFFFF00=1048832 -j DROP]"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.083802104Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): joined network j2cjebtq7yi152vevs2ahe150"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.085480324Z" level=info msg="No non-localhost DNS nameservers are left in resolv.conf. Using default external servers: [nameserver 8.8.8.8 nameserver 8.8.4.4]"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.085519625Z" level=info msg="IPv6 enabled; Adding default IPv6 external servers: [nameserver 2001:4860:4860::8888 nameserver 2001:4860:4860::8844]"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.108443496Z" level=debug msg="state for task y9tlqb5lm3k2vtsp794ls7nee updated to PREPARING" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx state.transition="ASSIGNED->PREPARING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.108833101Z" level=debug msg="dispatcher committed status update to store" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx state.transition="ASSIGNED->PREPARING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.127118317Z" level=debug msg="Task y9tlqb5lm3k2vtsp794ls7nee is already in allocated state PREPARING" method="(*Allocator).doTaskAlloc" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.287733518Z" level=error msg="error reading the kernel parameter net.ipv4.vs.expire_nodest_conn" error="open /proc/sys/net/ipv4/vs/expire_nodest_conn: no such file or directory"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.326454476Z" level=debug msg="Assigning addresses for endpoint operations_default-endpoint's interface on network operations_default"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.326518477Z" level=debug msg="RequestAddress(LocalDefault/10.0.0.0/24, 10.0.0.4, map[])"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.326563677Z" level=debug msg="Request address PoolID:10.0.0.0/24 App: ipam/default/data, ID: LocalDefault/10.0.0.0/24, DBIndex: 0x0, Bits: 256, Unselected: 253, Sequence: (0xc0000000, 1)->(0x0, 6)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:10.0.0.4 "
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.339920935Z" level=debug msg="Assigning addresses for endpoint operations_default-endpoint's interface on network operations_default"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.774886686Z" level=debug msg="checkEncryption(j2cjebt, <nil>, 4097, true)"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.851350792Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.851717696Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.574736828s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:30 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:30.851891298Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.574736828s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.201159379Z" level=debug msg="EnableService lb-operations_default START"
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.201207380Z" level=debug msg="EnableService lb-operations_default DONE"
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.201232080Z" level=debug msg="/sbin/iptables, [--wait -t filter -nL DOCKER-INGRESS]"
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.202566398Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-USER]"
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.203732314Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-USER -j RETURN]"
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.204939831Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-USER]"
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.206128747Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-USER]"
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.207411364Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-USER]"
Jan 03 11:43:31 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:31.208923785Z" level=debug msg="Trying to pull nginx from https://registry-1.docker.io v2"
Jan 03 11:43:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:32.283879036Z" level=debug msg="Pulling ref from V2 registry: nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c"
Jan 03 11:43:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:32.283988937Z" level=debug msg="docker.io/library/nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c resolved to a manifestList object with 6 entries; looking for a unknown/amd64 match"
Jan 03 11:43:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:32.284016537Z" level=debug msg="found match for linux/amd64 with media type application/vnd.docker.distribution.manifest.v2+json, digest sha256:e2847e35d4e0e2d459a7696538cbfea42ea2d3b8a1ee8329ba7e68694950afd3"
Jan 03 11:43:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:32.284810248Z" level=debug msg="pull in progress"
Jan 03 11:43:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:32.666109080Z" level=debug msg="pulling blob \"sha256:177e7ef0df6987e0c5738a1fb5aba98b6b6e7a5fef992e481977dbb5ba3f91be\""
Jan 03 11:43:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:32.666155681Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Pulling fs layer"
Jan 03 11:43:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:32.666758389Z" level=debug msg="pulling blob \"sha256:ea57c53235dfe1ae1db219ca7cda6210c8f875367bcb892fdc6d86c047174f3d\""
Jan 03 11:43:32 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:32.667181095Z" level=debug msg="pulling blob \"sha256:bbdb1fbd4a86c36dbc219ff18eba7a332d7a41a4101731874da06a708d4db2f9\""
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.214830707Z" level=debug msg="pull in progress" current=204 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Downloading total=204
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.214968709Z" level=debug msg="Downloaded bbdb1fbd4a86 to tempfile /datadisk/tmp/GetImageBlob265137621"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.215083711Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Verifying Checksum"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.215200412Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Download complete"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.217777748Z" level=debug msg="memberlist: Initiating push/pull sync with: 172.17.1.20:7946"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.260622235Z" level=debug msg="pull in progress" current=228503 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Downloading total=22492916
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.383819424Z" level=debug msg="pull in progress" current=4356857 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Downloading total=22229779
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.720716143Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.948944111s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.859461646Z" level=debug msg="Downloaded ea57c53235df to tempfile /datadisk/tmp/GetImageBlob991298766"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.859884851Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Verifying Checksum"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.859995553Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Download complete"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.872795628Z" level=debug msg="Downloaded 177e7ef0df69 to tempfile /datadisk/tmp/GetImageBlob112991851"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.872923230Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Verifying Checksum"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.872996331Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Download complete"
Jan 03 11:43:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:33.888472843Z" level=debug msg="pull in progress" current=229376 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Extracting total=22492916
Jan 03 11:43:34 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:34.054216015Z" level=debug msg="Applying tar in /datadisk/overlay2/82310da243a964991f5c9e784fbe6e0bfc2d7f31463fdec810aeab0440ffcfef/diff" storage-driver=overlay2
Jan 03 11:43:34 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:34.384569241Z" level=debug msg="pull in progress" current=2981888 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Extracting total=22492916
Jan 03 11:43:35 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:35.396733903Z" level=debug msg="pull in progress" current=16285696 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Extracting total=22492916
Jan 03 11:43:35 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:35.426825815Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:35 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:35.428739841Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.831347701s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:35 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:35.428895744Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.831347701s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:36.461216870Z" level=debug msg="pull in progress" current=21790720 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Extracting total=22492916
Jan 03 11:43:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:36.803716155Z" level=debug msg="Applied tar sha256:7b4e562e58dcb7fbe1e27bb274f0ff8bfeb2fd965203380436e159df9f218900 to 82310da243a964991f5c9e784fbe6e0bfc2d7f31463fdec810aeab0440ffcfef, size: 55274211"
Jan 03 11:43:36 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:36.964055948Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Pull complete"
Jan 03 11:43:37 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:37.031219567Z" level=debug msg="Applying tar in /datadisk/overlay2/ca9b031da0dbb1722434c66c7f05480fd2bcd5c01bb11c1ef4f897ee858866ba/diff" storage-driver=overlay2
Jan 03 11:43:37 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:37.160451933Z" level=debug msg="pull in progress" current=229376 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Extracting total=22229779
Jan 03 11:43:37 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:37.473390910Z" level=debug msg="pull in progress" current=5275648 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Extracting total=22229779
Jan 03 11:43:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:38.537513149Z" level=debug msg="pull in progress" current=20185088 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Extracting total=22229779
Jan 03 11:43:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:38.697070728Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.225413314s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:38.786071943Z" level=debug msg="Applied tar sha256:c9c2a36960802924221f5b8fab90ed09b5900b346129979da9488810d8669e06 to ca9b031da0dbb1722434c66c7f05480fd2bcd5c01bb11c1ef4f897ee858866ba, size: 53900110"
Jan 03 11:43:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:39.126622693Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Pull complete"
Jan 03 11:43:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:39.169770482Z" level=debug msg="pull in progress" current=204 image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status=Extracting total=204
Jan 03 11:43:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:39.170471991Z" level=debug msg="Applying tar in /datadisk/overlay2/effad92c2ed8c5bb4773dab03ba3b1c0e8b28e9213f7a8973c4b06fbbd122132/diff" storage-driver=overlay2
Jan 03 11:43:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:39.294568785Z" level=debug msg="Applied tar sha256:b7efe781401dfe8d05a9e4c920dd3cd430593a483c442831a14413e2738cd968 to effad92c2ed8c5bb4773dab03ba3b1c0e8b28e9213f7a8973c4b06fbbd122132, size: 22"
Jan 03 11:43:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:39.429932932Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Pull complete"
Jan 03 11:43:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:39.518646943Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Digest: sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c"
Jan 03 11:43:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:39.598968939Z" level=debug msg="pull in progress" image="nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c" status="Status: Downloaded newer image for nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.260436563Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.260794368Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.994695566s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.261220573Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.994695566s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.322594710Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50074"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.322871514Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.708787976Z" level=debug msg="container mounted via layerStore: &{/datadisk/overlay2/569eb302e0e4848db32463edfc9084f3a37f40707a243f4eb930ae5643dae146/merged 0x556ea7746440 0x556ea7746440}"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.834957497Z" level=debug msg="Creating service config in agent for t = &Task{ID:y9tlqb5lm3k2vtsp794ls7nee,Meta:Meta{Version:Version{Index:22,},CreatedAt:2019-01-03T11:43:29.769310581Z,UpdatedAt:2019-01-03T11:43:29.846657897Z,},Spec:TaskSpec{Runtime:&TaskSpec_Container{Container:&ContainerSpec{Image:nginx:latest@sha256:b543f6d0983fbc25b9874e22f4fe257a567111da96fd1d8f1b44315f1236398c,Labels:map[string]string{com.docker.stack.namespace: operations,},Command:[],Args:[],Env:[],Dir:,User:,Mounts:[],StopGracePeriod:(duration: nil google_protobuf1.Duration),PullOptions:nil,Groups:[],Secrets:[],TTY:false,Hostname:,DNSConfig:nil,Healthcheck:nil,Hosts:[],OpenStdin:false,ReadOnly:false,StopSignal:,Configs:[],Privileges:&Privileges{CredentialSpec:nil,SELinuxContext:nil,},Init:nil,Isolation:ISOLATION_DEFAULT,PidsLimit:0,Sysctls:map[string]string{},},},Resources:&ResourceRequirements{Limits:nil,Reservations:nil,},Restart:nil,Placement:&Placement{Constraints:[node.platform.os == linux],Preferences:[],Platforms:[&Platform{Architecture:amd64,OS:linux,} &Platform{Architecture:,OS:linux,} &Platform{Architecture:arm64,OS:linux,} &Platform{Architecture:386,OS:linux,} &Platform{Architecture:ppc64le,OS:linux,} &Platform{Architecture:s390x,OS:linux,}],},LogDriver:nil,Networks:[&NetworkAttachmentConfig{Target:j2cjebtq7yi152vevs2ahe150,Aliases:[test0],Addresses:[],DriverAttachmentOpts:map[string]string{},}],ForceUpdate:0,ResourceReferences:[],},ServiceID:i4xb93n87eutyub1jov9uxccn,Slot:1,NodeID:6m8os6plt3f23t24ngxegaqgx,Annotations:Annotations{Name:,Labels:map[string]string{},Indices:[],},ServiceAnnotations:Annotations{Name:operations_test0,Labels:map[string]string{com.docker.stack.image: nginx,com.docker.stack.namespace: operations,},Indices:[],},Status:TaskStatus{Timestamp:2019-01-03T11:43:29.846522495Z,State:ASSIGNED,Message:scheduler assigned task to node,Err:,RuntimeStatus:<nil>,PortStatus:nil,AppliedBy:,AppliedAt:(timestamp: nil google_protobuf.Timestamp),},DesiredState:RUNNING,Networks:[&NetworkAttachment{Network:&Network{ID:1c7lonmv59ig920zwpi5vzn0x,Meta:Meta{Version:Version{Index:5,},CreatedAt:2019-01-03T11:42:56.737400809Z,UpdatedAt:2019-01-03T11:42:56.762289643Z,},Spec:NetworkSpec{Annotations:Annotations{Name:ingress,Labels:map[string]string{},Indices:[],},DriverConfig:&Driver{Name:,Options:map[string]string{},},Ipv6Enabled:false,Internal:false,IPAM:&IPAMOptions{Driver:&Driver{Name:,Options:map[string]string{},},Configs:[&IPAMConfig{Family:UNKNOWN,Subnet:10.255.0.0/16,Range:,Gateway:10.255.0.1,Reserved:map[string]string{},}],},Attachable:false,Ingress:true,ConfigFrom:<nil>,},DriverState:&Driver{Name:overlay,Options:map[string]string{com.docker.network.driver.overlay.vxlanid_list: 4096,},},IPAM:&IPAMOptions{Driver:&Driver{Name:default,Options:map[string]string{},},Configs:[&IPAMConfig{Family:UNKNOWN,Subnet:10.255.0.0/16,Range:,Gateway:10.255.0.1,Reserved:map[string]string{},}],},},Addresses:[10.255.0.5/16],Aliases:[],DriverAttachmentOpts:map[string]string{},} &NetworkAttachment{Network:&Network{ID:j2cjebtq7yi152vevs2ahe150,Meta:Meta{Version:Version{Index:17,},CreatedAt:2019-01-03T11:43:28.161255332Z,UpdatedAt:2019-01-03T11:43:28.167951611Z,},Spec:NetworkSpec{Annotations:Annotations{Name:operations_default,Labels:map[string]string{com.docker.stack.namespace: operations,},Indices:[],},DriverConfig:&Driver{Name:overlay,Options:map[string]string{},},Ipv6Enabled:false,Internal:false,IPAM:nil,Attachable:false,Ingress:false,ConfigFrom:<nil>,},DriverState:&Driver{Name:overlay,Options:map[string]string{com.docker.network.driver.overlay.vxlanid_list: 4097,},},IPAM:&IPAMOptions{Driver:&Driver{Name:default,Options:map[string]string{},},Configs:[&IPAMConfig{Family:IPV4,Subnet:10.0.0.0/24,Range:,Gateway:10.0.0.1,Reserved:map[string]string{},}],},},Addresses:[10.0.0.3/24],Aliases:[test0],DriverAttachmentOpts:map[string]string{},}],Endpoint:&Endpoint{Spec:&EndpointSpec{Mode:VIP,Ports:[&PortConfig{Name:,Protocol:TCP,TargetPort:80,PublishedPort:9000,PublishMode:INGRESS,}],},Ports:[&PortConfig{Name:,Protocol:TCP,TargetPort:80,PublishedPort:9000,PublishMode:INGRESS,}],VirtualIPs:[&Endpoint_VirtualIP{NetworkID:1c7lonmv59ig920zwpi5vzn0x,Addr:10.255.0.4/16,} &Endpoint_VirtualIP{NetworkID:j2cjebtq7yi152vevs2ahe150,Addr:10.0.0.2/24,}],},LogDriver:nil,SpecVersion:&Version{Index:0,},AssignedGenericResources:[],}"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.835168000Z" level=debug msg="state changed" module=node/agent/taskmanager node.id=6m8os6plt3f23t24ngxegaqgx service.id=i4xb93n87eutyub1jov9uxccn state.desired=RUNNING state.transition="PREPARING->READY" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.835368402Z" level=debug msg="(*Agent).UpdateTaskStatus" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.836832922Z" level=debug msg="task status reported" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.848368080Z" level=debug msg="state for task y9tlqb5lm3k2vtsp794ls7nee updated to READY" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx state.transition="PREPARING->READY" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.848521682Z" level=debug msg="dispatcher committed status update to store" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx state.transition="PREPARING->READY" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.851211018Z" level=debug msg="state changed" module=node/agent/taskmanager node.id=6m8os6plt3f23t24ngxegaqgx service.id=i4xb93n87eutyub1jov9uxccn state.desired=RUNNING state.transition="READY->STARTING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.855861382Z" level=debug msg="Task y9tlqb5lm3k2vtsp794ls7nee is already in allocated state READY" method="(*Allocator).doTaskAlloc" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.856275687Z" level=debug msg="(*Agent).UpdateTaskStatus" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.856771794Z" level=debug msg="task status reported" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.877896182Z" level=debug msg="container mounted via layerStore: &{/datadisk/overlay2/569eb302e0e4848db32463edfc9084f3a37f40707a243f4eb930ae5643dae146/merged 0x556ea7746440 0x556ea7746440}"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.878466590Z" level=debug msg="Assigning addresses for endpoint operations_test0.1.y9tlqb5lm3k2vtsp794ls7nee's interface on network ingress"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.878624392Z" level=debug msg="RequestAddress(LocalDefault/10.255.0.0/16, 10.255.0.5, map[])"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.878757694Z" level=debug msg="Request address PoolID:10.255.0.0/16 App: ipam/default/data, ID: LocalDefault/10.255.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65532, Sequence: (0xe0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:10.255.0.5 "
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.890561155Z" level=debug msg="Assigning addresses for endpoint operations_test0.1.y9tlqb5lm3k2vtsp794ls7nee's interface on network ingress"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.956572155Z" level=debug msg="state for task y9tlqb5lm3k2vtsp794ls7nee updated to STARTING" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx state.transition="READY->STARTING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.956754157Z" level=debug msg="dispatcher committed status update to store" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx state.transition="READY->STARTING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.959507795Z" level=debug msg="checkEncryption(1c7lonm, <nil>, 4096, true)"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.960456508Z" level=debug msg="Assigning addresses for endpoint gateway_1e947d31f763's interface on network docker_gwbridge"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.960492208Z" level=debug msg="RequestAddress(LocalDefault/172.19.0.0/16, <nil>, map[])"
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.960522409Z" level=debug msg="Request address PoolID:172.19.0.0/16 App: ipam/default/data, ID: LocalDefault/172.19.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65532, Sequence: (0xe0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:3 Serial:false PrefAddress:<nil> "
Jan 03 11:43:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:40.969231928Z" level=debug msg="Task y9tlqb5lm3k2vtsp794ls7nee is already in allocated state STARTING" method="(*Allocator).doTaskAlloc" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:41 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:41.071947628Z" level=debug msg="Assigning addresses for endpoint gateway_1e947d31f763's interface on network docker_gwbridge"
Jan 03 11:43:41 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:41.098437189Z" level=debug msg="Programming external connectivity on endpoint gateway_1e947d31f763 (ea438cbd0c89572d8fd20f890f7b7d18c0dbab61167400768994b20709db4dfe)"
Jan 03 11:43:41 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:41.111231863Z" level=debug msg="Assigning addresses for endpoint operations_test0.1.y9tlqb5lm3k2vtsp794ls7nee's interface on network operations_default"
Jan 03 11:43:41 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:41.111288564Z" level=debug msg="RequestAddress(LocalDefault/10.0.0.0/24, 10.0.0.3, map[])"
Jan 03 11:43:41 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:41.111323065Z" level=debug msg="Request address PoolID:10.0.0.0/24 App: ipam/default/data, ID: LocalDefault/10.0.0.0/24, DBIndex: 0x0, Bits: 256, Unselected: 252, Sequence: (0xc8000000, 1)->(0x0, 6)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:10.0.0.3 "
Jan 03 11:43:41 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:41.124810449Z" level=debug msg="Assigning addresses for endpoint operations_test0.1.y9tlqb5lm3k2vtsp794ls7nee's interface on network operations_default"
Jan 03 11:43:41 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:41.194792303Z" level=debug msg="checkEncryption(j2cjebt, <nil>, 4097, true)"
Jan 03 11:43:41 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:41.216130993Z" level=debug msg="bundle dir created" bundle=/var/run/docker/containerd/204a52cacc594aee9ec6edbf3e941ddd372a26feeee801a5d6ef70d050a4bf7a module=libcontainerd namespace=moby root=/datadisk/overlay2/569eb302e0e4848db32463edfc9084f3a37f40707a243f4eb930ae5643dae146/merged
Jan 03 11:43:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:43.328978694Z" level=debug msg="sandbox set key processing took 779.973502ms for container 204a52cacc594aee9ec6edbf3e941ddd372a26feeee801a5d6ef70d050a4bf7a"
Jan 03 11:43:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:43.450752027Z" level=debug msg=event module=libcontainerd namespace=moby topic=/tasks/create
Jan 03 11:43:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:43.545980982Z" level=debug msg=event module=libcontainerd namespace=moby topic=/tasks/start
Jan 03 11:43:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:43.632346011Z" level=debug msg="EnableService 204a52cacc594aee9ec6edbf3e941ddd372a26feeee801a5d6ef70d050a4bf7a START"
Jan 03 11:43:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:43.632403111Z" level=debug msg="addServiceInfoToCluster START for operations_test0 60210f26fa0f36e854d443827011a12851e917bb7f199d836714baeec85e9bbb"
Jan 03 11:43:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:43.632477812Z" level=debug msg="addServiceBinding from addServiceInfoToCluster START for operations_test0 60210f26fa0f36e854d443827011a12851e917bb7f199d836714baeec85e9bbb p:0xc421edd780 nid:1c7lonmv59ig920zwpi5vzn0x skey:{i4xb93n87eutyub1jov9uxccn 9000:80/TCP}"
Jan 03 11:43:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:43.925604283Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.812705977s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.855145448Z" level=debug msg="/sbin/iptables, [--wait -t nat -nL DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.856625569Z" level=debug msg="/sbin/iptables, [--wait -t filter -nL DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.857845287Z" level=debug msg="/sbin/iptables, [--wait -t nat -N DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.860342722Z" level=debug msg="/sbin/iptables, [--wait -N DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.862341951Z" level=debug msg="/sbin/iptables, [--wait -t nat -C DOCKER-INGRESS -j RETURN]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.863686070Z" level=debug msg="/sbin/iptables, [--wait -t nat -A DOCKER-INGRESS -j RETURN]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.864997189Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-INGRESS -j RETURN]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.866233706Z" level=debug msg="/sbin/iptables, [--wait -A DOCKER-INGRESS -j RETURN]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.867525125Z" level=debug msg="/sbin/iptables, [--wait -t nat -C OUTPUT -m addrtype --dst-type LOCAL -j DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.868950945Z" level=debug msg="/sbin/iptables, [--wait -t nat -I OUTPUT -m addrtype --dst-type LOCAL -j DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.870360765Z" level=debug msg="/sbin/iptables, [--wait -t nat -C PREROUTING -m addrtype --dst-type LOCAL -j DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.871728485Z" level=debug msg="/sbin/iptables, [--wait -t nat -I PREROUTING -m addrtype --dst-type LOCAL -j DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.876943459Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.878282678Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.879517496Z" level=debug msg="/sbin/iptables, [--wait -t filter -n -L DOCKER-USER]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.881236621Z" level=debug msg="/sbin/iptables, [--wait -t filter -C DOCKER-USER -j RETURN]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.882584940Z" level=debug msg="/sbin/iptables, [--wait -t filter -C FORWARD -j DOCKER-USER]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.884606769Z" level=debug msg="/sbin/iptables, [--wait -D FORWARD -j DOCKER-USER]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.885820586Z" level=debug msg="/sbin/iptables, [--wait -I FORWARD -j DOCKER-USER]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.887267907Z" level=debug msg="/sbin/iptables, [--wait -t nat -C POSTROUTING -m addrtype --src-type LOCAL -o docker_gwbridge -j MASQUERADE]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.888591326Z" level=debug msg="/sbin/iptables, [--wait -t nat -I POSTROUTING -m addrtype --src-type LOCAL -o docker_gwbridge -j MASQUERADE]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.890115847Z" level=debug msg="/sbin/iptables, [--wait -t nat -nL DOCKER-INGRESS]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.891562668Z" level=debug msg="/sbin/iptables, [--wait -t nat -I DOCKER-INGRESS -p tcp --dport 9000 -j DNAT --to-destination 172.19.0.2:9000]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.893231992Z" level=debug msg="/sbin/iptables, [--wait -I DOCKER-INGRESS -m state -p tcp --sport 9000 --state ESTABLISHED,RELATED -j ACCEPT]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.896671941Z" level=debug msg="/sbin/iptables, [--wait -I DOCKER-INGRESS -p tcp --dport 9000 -j ACCEPT]"
Jan 03 11:43:44 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:44.898385665Z" level=debug msg="Creating service for vip 10.255.0.4 fwMark 256 ingressPorts libnetwork.portConfigs{&libnetwork.PortConfig{Name: \"\",\nProtocol: 0,\nTargetPort: 0x50,\nPublishedPort: 0x2328,\n}} in sbox ingress (ingress)"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.111724009Z" level=debug msg="addEndpointNameResolution 60210f26fa0f36e854d443827011a12851e917bb7f199d836714baeec85e9bbb operations_test0 add_service:true sAliases:[] tAliases:[204a52cacc59]"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.111787710Z" level=debug msg="addContainerNameResolution 60210f26fa0f36e854d443827011a12851e917bb7f199d836714baeec85e9bbb operations_test0.1.y9tlqb5lm3k2vtsp794ls7nee"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.111807410Z" level=debug msg="addServiceBinding from addServiceInfoToCluster END for operations_test0 60210f26fa0f36e854d443827011a12851e917bb7f199d836714baeec85e9bbb"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.111856811Z" level=debug msg="addServiceInfoToCluster END for operations_test0 60210f26fa0f36e854d443827011a12851e917bb7f199d836714baeec85e9bbb"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.111878311Z" level=debug msg="addServiceInfoToCluster START for operations_test0 7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.111921912Z" level=debug msg="addServiceBinding from addServiceInfoToCluster START for operations_test0 7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 p:0xc4222bfa00 nid:j2cjebtq7yi152vevs2ahe150 skey:{i4xb93n87eutyub1jov9uxccn }"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.112171116Z" level=debug msg="Creating service for vip 10.0.0.2 fwMark 257 ingressPorts libnetwork.portConfigs(nil) in sbox lb_j2cj (lb-oper)"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.256174970Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.256525875Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.769679652s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.256659676Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.769679652s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313502887Z" level=debug msg="addEndpointNameResolution 7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 operations_test0 add_service:true sAliases:[test0] tAliases:[204a52cacc59]"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313569488Z" level=debug msg="addContainerNameResolution 7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 operations_test0.1.y9tlqb5lm3k2vtsp794ls7nee"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313595889Z" level=debug msg="7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 (j2cjebt).addSvcRecords(operations_test0.1.y9tlqb5lm3k2vtsp794ls7nee, 10.0.0.3, <nil>, true) addServiceBinding sid:7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313635689Z" level=debug msg="7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 (j2cjebt).addSvcRecords(204a52cacc59, 10.0.0.3, <nil>, true) addServiceBinding sid:7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313661789Z" level=debug msg="7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 (j2cjebt).addSvcRecords(tasks.operations_test0, 10.0.0.3, <nil>, false) addServiceBinding sid:i4xb93n87eutyub1jov9uxccn"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313683390Z" level=debug msg="7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 (j2cjebt).addSvcRecords(tasks.test0, 10.0.0.3, <nil>, false) addServiceBinding sid:i4xb93n87eutyub1jov9uxccn"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313702390Z" level=debug msg="7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 (j2cjebt).addSvcRecords(operations_test0, 10.0.0.2, <nil>, false) addServiceBinding sid:i4xb93n87eutyub1jov9uxccn"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313721290Z" level=debug msg="7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888 (j2cjebt).addSvcRecords(test0, 10.0.0.2, <nil>, false) addServiceBinding sid:i4xb93n87eutyub1jov9uxccn"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313739491Z" level=debug msg="addServiceBinding from addServiceInfoToCluster END for operations_test0 7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313786391Z" level=debug msg="addServiceInfoToCluster END for operations_test0 7847d35525eac6f8b5e98bcd17f9a45f54e5d37d661898726b6453f4d0874888"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.313802291Z" level=debug msg="EnableService 204a52cacc594aee9ec6edbf3e941ddd372a26feeee801a5d6ef70d050a4bf7a DONE"
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.314012094Z" level=debug msg="state changed" module=node/agent/taskmanager node.id=6m8os6plt3f23t24ngxegaqgx service.id=i4xb93n87eutyub1jov9uxccn state.desired=RUNNING state.transition="STARTING->RUNNING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.314211997Z" level=debug msg="(*Agent).UpdateTaskStatus" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.314669604Z" level=debug msg="task status reported" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.326699675Z" level=debug msg="waiting on events" module=node/agent/taskmanager node.id=6m8os6plt3f23t24ngxegaqgx service.id=i4xb93n87eutyub1jov9uxccn task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.383907191Z" level=debug msg="state for task y9tlqb5lm3k2vtsp794ls7nee updated to RUNNING" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx state.transition="STARTING->RUNNING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.384085294Z" level=debug msg="dispatcher committed status update to store" method="(*Dispatcher).processUpdates" module=dispatcher node.id=6m8os6plt3f23t24ngxegaqgx state.transition="STARTING->RUNNING" task.id=y9tlqb5lm3k2vtsp794ls7nee
Jan 03 11:43:45 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:45.404557486Z" level=debug msg="Task y9tlqb5lm3k2vtsp794ls7nee is already in allocated state RUNNING" method="(*Allocator).doTaskAlloc" module=node node.id=6m8os6plt3f23t24ngxegaqgx
Jan 03 11:43:48 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:48.743132706Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.163290175s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:49 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:49.172564120Z" level=debug msg="Calling GET /_ping"
Jan 03 11:43:49 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:49.173901239Z" level=debug msg="Calling GET /_ping"
Jan 03 11:43:49 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:49.174575848Z" level=debug msg="Calling GET /v1.39/services"
Jan 03 11:43:49 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:49.176241671Z" level=debug msg="Calling GET /v1.39/tasks?filters=%7B%22service%22%3A%7B%22i4xb93n87eutyub1jov9uxccn%22%3Atrue%7D%7D"
Jan 03 11:43:49 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:49.179253712Z" level=debug msg="Calling GET /v1.39/nodes"
Jan 03 11:43:50 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:50.026555777Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:50 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:50.026933982Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.938822772s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:50 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:50.027067384Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.938822772s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:53 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:53.908909919Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.511829929s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:54 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:54.966143918Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:54 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:54.966555423Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.822901569s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:54 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:54.966693725Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.822901569s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:57.245144528Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync with node 7c9aa543fb4f"
Jan 03 11:43:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:57.245234930Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating unsolicited bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:43:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:57.253207739Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50076"
Jan 03 11:43:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:57.253700346Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Bulk sync to node 7c9aa543fb4f took 6.830493ms"
Jan 03 11:43:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:58.427733002Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.868495018s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:59.789863813Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:43:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:59.790266019Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.196210502s" method="(*Dispatcher).Heartbeat"
Jan 03 11:43:59 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:43:59.790398821Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.196210502s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:03 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:03.227804898Z" level=debug msg="memberlist: Initiating push/pull sync with: 172.17.1.20:7946"
Jan 03 11:44:03 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:03.309350409Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.565273274s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:04 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:04.986824039Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:04 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:04.987214845Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.779053017s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:04 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:04.987354347Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.779053017s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:07 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:07.879214634Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.425721619s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:09 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:09.244405149Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50077"
Jan 03 11:44:09 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:09.766620127Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:09 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:09.767018832Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.865134747s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:09 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:09.767355737Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.865134747s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:10.321640146Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50078"
Jan 03 11:44:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:10.321881649Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:44:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:13.307519142Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.770686908s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:14 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:14.632754442Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:14 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:14.633109346Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.836018802s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:14 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:14.633235548Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.836018802s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:18 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:18.089434045Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.533506643s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:19 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:19.469475615Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:19 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:19.470722031Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.428330279s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:19 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:19.472336853Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.428330279s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:22 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:22.640361411Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.015800693s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:24 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:24.900963917Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:24 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:24.901800829Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.541425422s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:24 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:24.902188634Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.541425422s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:27.245221189Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync with node 7c9aa543fb4f"
Jan 03 11:44:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:27.245325090Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating unsolicited bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:44:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:27.249900852Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50079"
Jan 03 11:44:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:27.250208156Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Bulk sync to node 7c9aa543fb4f took 2.822838ms"
Jan 03 11:44:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:27.662552768Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.342443968s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:29.444026364Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:29.444377069Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.599628323s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:29 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:29.444507670Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.599628323s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:33.014273659Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.601090066s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:33.232151159Z" level=debug msg="memberlist: Initiating push/pull sync with: 172.17.1.20:7946"
Jan 03 11:44:34 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:34.044338267Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:34 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:34.044713972Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.529327502s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:34 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:34.044842074Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.529327502s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:37 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:37.626825858Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.266719665s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:38.574409625Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:38.574778830Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.051285241s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:38.575186335Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.051285241s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:39.248771163Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50080"
Jan 03 11:44:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:40.327714254Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50081"
Jan 03 11:44:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:40.328549665Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:44:42 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:42.896616535Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.64438875s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:43.626736380Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:43.627123785Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.274342393s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:43.627266287Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.274342393s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:47 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:47.546483676Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.209111999s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:48 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:48.901828417Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:48 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:48.902308624Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.884950192s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:48 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:48.902452425Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.884950192s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:52 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:52.758468784Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.358141378s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:53 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:53.787615981Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:53 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:53.788018686Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.629682074s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:53 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:53.788154688Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.629682074s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:57.245170550Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync with node 7c9aa543fb4f"
Jan 03 11:44:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:57.245252251Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating unsolicited bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:44:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:57.249462206Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50082"
Jan 03 11:44:57 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:57.250071714Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Bulk sync to node 7c9aa543fb4f took 3.455845ms"
Jan 03 11:44:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:58.120391591Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.092546579s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:58.418061180Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:44:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:58.418428585Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.75313726s" method="(*Dispatcher).Heartbeat"
Jan 03 11:44:58 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:44:58.418573587Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.75313726s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:03 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:03.171906583Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:03 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:03.172254388Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.105504319s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:03 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:03.172382189Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.105504319s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:03 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:03.222652744Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.931688156s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:03 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:03.237394436Z" level=debug msg="memberlist: Initiating push/pull sync with: 172.17.1.20:7946"
Jan 03 11:45:08 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:08.158256390Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.074168187s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:08 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:08.278122145Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:08 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:08.278430048Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.950623294s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:08 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:08.278787453Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.950623294s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:09 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:09.253859198Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50083"
Jan 03 11:45:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:10.328063518Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50084"
Jan 03 11:45:10 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:10.328467123Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:45:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:13.229777466Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:13.230200571Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.194300754s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:13.230355273Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.194300754s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:13 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:13.240663507Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.04194073s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:18 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:18.291489078Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.47540842s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:18 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:18.424900096Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:18 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:18.425263400Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.881354887s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:18 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:18.425407602Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.881354887s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:23 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:23.307017062Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:23 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:23.307417567Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.433437531s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:23 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:23.307571269Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.433437531s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:23 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:23.769250493Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.98727197s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:27.245327337Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync with node 7c9aa543fb4f"
Jan 03 11:45:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:27.245381437Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating unsolicited bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:45:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:27.249335088Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50085"
Jan 03 11:45:27 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:27.249761294Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Bulk sync to node 7c9aa543fb4f took 3.100239ms"
Jan 03 11:45:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:28.741247073Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:28.741589377Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.890667327s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:28.741800280Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.890667327s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:28 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:28.759854710Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.422864987s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:33.244124753Z" level=debug msg="memberlist: Initiating push/pull sync with: 172.17.1.20:7946"
Jan 03 11:45:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:33.632741505Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:33.633108410Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.97652636s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:33 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:33.633329012Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.97652636s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:34 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:34.185592048Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.500897213s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:38.610213015Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:38.610691421Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.226101015s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:38.610842123Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.226101015s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:38 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:38.690286732Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 4.505777611s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:39 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:39.257339531Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50086"
Jan 03 11:45:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:40.326022189Z" level=debug msg="memberlist: Stream connection from=172.17.1.20:50087"
Jan 03 11:45:40 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:40.326303293Z" level=debug msg="dc2node-nvm0(b9e1cbdaa68e): Initiating bulk sync for networks [1c7lonmv59ig920zwpi5vzn0x] with node 7c9aa543fb4f"
Jan 03 11:45:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:43.206891989Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.12169041s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:43.837154265Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:43.837575970Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 5.420534371s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:43 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:43.837931574Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 5.420534371s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:48 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:48.331581439Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.481605736s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:49 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:49.258954232Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:49 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:49.259497539Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.654980479s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:49 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:49.259750042Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.654980479s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:53 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:53.815335276Z" level=debug msg="received heartbeat from worker {[swarm-worker] jkw54ivjwqbmm6tmkk4h0p04u ych43rb08jsey2s7n4p738t12 <nil> 172.17.1.20:50065}, expect next heartbeat in 5.397524795s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:53 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:53.915217431Z" level=debug msg="sending heartbeat to manager { } with timeout 5s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Jan 03 11:45:53 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:53.915749938Z" level=debug msg="received heartbeat from worker {[swarm-manager] jkw54ivjwqbmm6tmkk4h0p04u 6m8os6plt3f23t24ngxegaqgx <nil> 172.17.1.10:2377}, expect next heartbeat in 4.658302243s" method="(*Dispatcher).Heartbeat"
Jan 03 11:45:53 dc2node-nvm0 dockerd[1007]: time="2019-01-03T11:45:53.916108243Z" level=debug msg="heartbeat successful to manager { }, next heartbeat period: 4.658302243s" method="(*session).heartbeat" module=node/agent node.id=6m8os6plt3f23t24ngxegaqgx session.id=ojotyyzmxscxm1wd6e3tnsa63 sessionID=ojotyyzmxscxm1wd6e3tnsa63
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment