Skip to content

Instantly share code, notes, and snippets.

@marius311

marius311/hostA_daemon_log

Created January 8, 2017 13:25
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 marius311/a8c297ff39dfb826f1d1cb8ada20c587 to your computer and use it in GitHub Desktop.
Save marius311/a8c297ff39dfb826f1d1cb8ada20c587 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
hostA_daemon_log
Jan 08 14:06:56 hostA dockerd[8953]: time="2017-01-08T14:06:56.096638774+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:06:56 hostA dockerd[8953]: time="2017-01-08T14:06:56.097295704+01:00" level=debug msg="Calling GET /v1.25/version"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.525640581+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.526276681+01:00" level=debug msg="Calling POST /v1.25/swarm/init"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.526387729+01:00" level=debug msg="form data: {\"AdvertiseAddr\":\"<hostA's public IP>\",\"AutoLockManagers\":false,\"ForceNewCluster\":false,\"ListenAddr\":\"wlp3s0:2377\",\"Spec\":{\"CAConfig\":{},\"Dispatcher\":{},\"EncryptionConfig\":{\"AutoLockManagers\":false},\"Orchestration\":{},\"Raft\":{\"ElectionTick\":0,\"HeartbeatTick\":0},\"TaskDefaults\":{}}}"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.552327066+01:00" level=debug msg="generated CA key and certificate" module=node
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.552436689+01:00" level=debug msg="no node credentials found in: /var/lib/docker/swarm/certificates/swarm-node.crt" error="open /var/lib/docker/swarm/certificates/swarm-node.key: no such file or directory" module="node/tls"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.552696768+01:00" level=debug msg="loaded TLS certificate" module="node/tls" node.id=yq69t3ba47z9hr42caw7pifiy
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.568928016+01:00" level=debug msg="issued new TLS certificate" module="node/tls" node.id=yq69t3ba47z9hr42caw7pifiy node.role=swarm-manager
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.569350956+01:00" level=debug msg="new node credentials generated: /var/lib/docker/swarm/certificates/swarm-node.crt" module="node/tls" node.id=yq69t3ba47z9hr42caw7pifiy node.role=swarm-manager
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.579362507+01:00" level=debug msg="next certificate renewal scheduled" module="node/tls" node.id=yq69t3ba47z9hr42caw7pifiy node.role=swarm-manager time=2017-03-15 20:56:00.000000313 +0100 CET
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.590298630+01:00" level=info msg="Listening for local connections" addr="/var/run/docker/swarm/control.sock" proto=unix
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.590328489+01:00" level=info msg="Listening for connections" addr="192.168.1.47:2377" proto=tcp
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.600214865+01:00" level=info msg="2bd1dc40bcc21a8a became follower at term 0"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.600277822+01:00" level=info msg="newRaft 2bd1dc40bcc21a8a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.600300451+01:00" level=info msg="2bd1dc40bcc21a8a became follower at term 1"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.603668882+01:00" level=info msg="2bd1dc40bcc21a8a is starting a new election at term 1"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.603805683+01:00" level=info msg="2bd1dc40bcc21a8a became candidate at term 2"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.603841072+01:00" level=info msg="2bd1dc40bcc21a8a received vote from 2bd1dc40bcc21a8a at term 2"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.603889979+01:00" level=info msg="2bd1dc40bcc21a8a became leader at term 2"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.603924428+01:00" level=info msg="raft.node: 2bd1dc40bcc21a8a elected leader 2bd1dc40bcc21a8a at term 2"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.617736149+01:00" level=debug msg="RequestPool(GlobalDefault, 10.255.0.0/16, , map[], false)"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.617821206+01:00" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[])"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.619004067+01:00" level=debug msg="Root CA updated successfully" cluster.id=z9mqya47z3lmk62i2zng6110n method="(*Server).updateCluster" module=ca
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.631642688+01:00" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[])"
Jan 08 14:07:24 hostA dockerd[8953]: time="2017-01-08T14:07:24.637418932+01:00" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[])"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.105872011+01:00" level=debug msg="(*Agent).run" module="node/agent"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.513889484+01:00" level=debug msg="(*session).start" module="node/agent"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.525372667+01:00" level=debug msg="node status updated" method="(*Dispatcher).processUpdates" module=dispatcher node.id=yq69t3ba47z9hr42caw7pifiy
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.538824803+01:00" level=debug method="(*session).logSubscriptions" module="node/agent" session.id=2r6kd1umpt6cfct0az8p1sakk
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.538876849+01:00" level=debug method="(*session).watch" module="node/agent" session.id=2r6kd1umpt6cfct0az8p1sakk
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.538954981+01:00" level=debug msg="(*session).listen" module="node/agent" session.id=2r6kd1umpt6cfct0az8p1sakk
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.538980008+01:00" level=debug msg="(*session).heartbeat" module="node/agent" session.id=2r6kd1umpt6cfct0az8p1sakk
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.539914742+01:00" level=debug msg="node registered" method="(*LogBroker).ListenSubscriptions" node=yq69t3ba47z9hr42caw7pifiy
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.540005525+01:00" level=debug method="(*Dispatcher).Assignments" node.id=yq69t3ba47z9hr42caw7pifiy node.session=2r6kd1umpt6cfct0az8p1sakk
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.554427610+01:00" level=info msg="Initializing Libnetwork Agent Listen-Addr=192.168.1.47 Local-addr=192.168.1.47 Adv-addr=<hostA's public IP> Remote-addr ="
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.554518879+01:00" level=debug msg="(*worker).Assign" len(assignments)=0 module="node/agent"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.554575224+01:00" level=debug msg="(*worker).reconcileSecrets" len(removedSecrets)=0 len(updatedSecrets)=0 module="node/agent"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.554609191+01:00" level=debug msg="(*worker).reconcileTaskState" len(removedTasks)=0 len(updatedTasks)=0 module="node/agent"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.560802156+01:00" level=debug msg="agent: registered" module="node/agent"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.561012625+01:00" level=info msg="Initializing Libnetwork Agent Listen-Addr=192.168.1.47 Local-addr=192.168.1.47 Adv-addr=<hostA's public IP> Remote-addr ="
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.561177223+01:00" level=info msg="Gossip cluster hostname hostA-eacc6b718a3a"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.561258474+01:00" level=debug msg="Encryption key 1: bd806"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.561354789+01:00" level=debug msg="Encryption key 2: 3f70d"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.561372997+01:00" level=debug msg="Encryption key 3: 8b689"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.561809572+01:00" level=debug msg="Initial encryption keys: [(key: 7c761, tag: 0xf670) (key: 60f5f, tag: 0xf66f) (key: 406ed, tag: 0xf671)]"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.562012003+01:00" level=debug msg="Initial encryption keys: [(key: 7c761, tag: 0xf670) (key: 60f5f, tag: 0xf66f) (key: 406ed, tag: 0xf671)]"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.562310351+01:00" level=debug msg="Allocating IPv4 pools for network ingress (y4p7ieba8mwxca5hq0b51vj0f)"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.562352493+01:00" level=debug msg="RequestPool(LocalDefault, 10.255.0.0/16, , map[], false)"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.562448654+01:00" level=debug msg="RequestAddress(LocalDefault/10.255.0.0/16, 10.255.0.1, map[RequestAddressType:com.docker.network.gateway])"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.562507021+01:00" level=debug msg="overlay: Received vxlan IDs: 4096"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.562569806+01:00" 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 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.570662270+01:00" level=debug msg="Root CA updated successfully" cluster.id=z9mqya47z3lmk62i2zng6110n method="(*Server).updateCluster" module=ca
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.571628214+01:00" level=debug msg="hostA-eacc6b718a3a: joined network y4p7ieba8mwxca5hq0b51vj0f"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.571680475+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostA-eacc6b718a3a]"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.571722751+01:00" level=debug msg="Calling GET /v1.25/swarm"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.571785995+01:00" level=debug msg="/sbin/iptables, [--wait -t filter -L DOCKER-INGRESS]"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.578093134+01:00" level=debug msg="Calling GET /v1.25/nodes/yq69t3ba47z9hr42caw7pifiy"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.579698668+01:00" 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 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.579785381+01:00" level=info msg="IPv6 enabled; Adding default IPv6 external servers: [nameserver 2001:4860:4860::8888 nameserver 2001:4860:4860::8844]"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.625144577+01:00" level=debug msg="Assigning addresses for endpoint ingress-endpoint's interface on network ingress"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.625174282+01:00" level=debug msg="RequestAddress(LocalDefault/10.255.0.0/16, 10.255.0.3, map[])"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.631699652+01:00" level=debug msg="Assigning addresses for endpoint ingress-endpoint's interface on network ingress"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.631921671+01:00" level=error msg="Could not open netlink handle during vni population for ns /var/run/docker/netns/1-4wtvb9qbli: failed to set into network namespace 12 while creating netlink socket: invalid argument"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.824355885+01:00" level=debug msg="checkEncryption(y4p7ieb, <nil>, 4096, true)"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25+01:00" level=info msg="Firewalld running: false"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.987813355+01:00" level=debug msg="Assigning addresses for endpoint gateway_ingress-sbox's interface on network docker_gwbridge"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.987897565+01:00" level=debug msg="RequestAddress(LocalDefault/172.20.0.0/16, <nil>, map[])"
Jan 08 14:07:25 hostA dockerd[8953]: time="2017-01-08T14:07:25.998050591+01:00" level=debug msg="Assigning addresses for endpoint gateway_ingress-sbox's interface on network docker_gwbridge"
Jan 08 14:07:26 hostA dockerd[8953]: time="2017-01-08T14:07:26.064684836+01:00" level=debug msg="Programming external connectivity on endpoint gateway_ingress-sbox (49319ac8db65b6d7147f8b4fb8e3d52832da9af22dd06583035f1f72ce42d79f)"
Jan 08 14:08:37 hostA dockerd[8953]: time="2017-01-08T14:08:37.670340694+01:00" level=debug msg="new certificate entry added" method=IssueNodeCertificate node.id=d0t6n0ivd1hut6axd60a8lq8o node.role=WORKER
Jan 08 14:08:37 hostA dockerd[8953]: time="2017-01-08T14:08:37.670495994+01:00" level=debug msg="RequestAddress(GlobalDefault/10.255.0.0/16, <nil>, map[])"
Jan 08 14:08:37 hostA dockerd[8953]: time="2017-01-08T14:08:37.682501559+01:00" level=debug msg="certificate issued" method="(*Server).signNodeCert" module=ca node.id=d0t6n0ivd1hut6axd60a8lq8o node.role=WORKER
Jan 08 14:08:37 hostA dockerd[8953]: time="2017-01-08T14:08:37.691111678+01:00" level=debug msg="certificate issued" method="(*Server).signNodeCert" module=ca node.id=d0t6n0ivd1hut6axd60a8lq8o node.role=WORKER
Jan 08 14:08:38 hostA dockerd[8953]: time="2017-01-08T14:08:38.669043308+01:00" level=debug msg="node status updated" method="(*Dispatcher).processUpdates" module=dispatcher node.id=d0t6n0ivd1hut6axd60a8lq8o
Jan 08 14:08:38 hostA dockerd[8953]: time="2017-01-08T14:08:38.794426625+01:00" level=debug msg="node registered" method="(*LogBroker).ListenSubscriptions" node=d0t6n0ivd1hut6axd60a8lq8o
Jan 08 14:08:38 hostA dockerd[8953]: time="2017-01-08T14:08:38.906396509+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51155"
Jan 08 14:08:38 hostA dockerd[8953]: time="2017-01-08T14:08:38.964607991+01:00" level=debug method="(*Dispatcher).Assignments" node.id=d0t6n0ivd1hut6axd60a8lq8o node.session=usfldqueauyy7grkbxrnfsvxc
Jan 08 14:08:40 hostA dockerd[8953]: time="2017-01-08T14:08:40.020054576+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51156"
Jan 08 14:08:40 hostA dockerd[8953]: time="2017-01-08T14:08:40.020439773+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f] with node hostB-667564f8c9ef"
Jan 08 14:08:40 hostA dockerd[8953]: time="2017-01-08T14:08:40.594526693+01:00" level=debug msg="checkEncryption(y4p7ieb, <hostB's public IP>, 4096, false)"
Jan 08 14:08:40 hostA dockerd[8953]: time="2017-01-08T14:08:40.594975382+01:00" level=debug msg="miss notification for dest IP, 10.255.0.4"
Jan 08 14:08:46 hostA dockerd[8953]: time="2017-01-08T14:08:46.768145823+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:08:55 hostA dockerd[8953]: time="2017-01-08T14:08:55.562170271+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:08:55 hostA dockerd[8953]: time="2017-01-08T14:08:55.562252884+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f] with node hostB-667564f8c9ef"
Jan 08 14:08:56 hostA dockerd[8953]: time="2017-01-08T14:08:56.000565331+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51157"
Jan 08 14:08:56 hostA dockerd[8953]: time="2017-01-08T14:08:56.006502168+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 318.834944ms"
Jan 08 14:09:09 hostA dockerd[8953]: time="2017-01-08T14:09:08.999447012+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51158"
Jan 08 14:09:09 hostA dockerd[8953]: time="2017-01-08T14:09:08.999982147+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f] with node hostB-667564f8c9ef"
Jan 08 14:09:10 hostA dockerd[8953]: time="2017-01-08T14:09:10.858797073+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51159"
Jan 08 14:09:17 hostA dockerd[8953]: time="2017-01-08T14:09:17.087970101+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:09:25 hostA dockerd[8953]: time="2017-01-08T14:09:25.302328603+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:09:25 hostA dockerd[8953]: time="2017-01-08T14:09:25.304606054+01:00" level=debug msg="Calling POST /v1.25/networks/create"
Jan 08 14:09:25 hostA dockerd[8953]: time="2017-01-08T14:09:25.304897866+01:00" level=debug msg="form data: {\"Attachable\":false,\"CheckDuplicate\":true,\"Driver\":\"overlay\",\"EnableIPv6\":false,\"IPAM\":{\"Config\":[],\"Driver\":\"default\",\"Options\":{}},\"Internal\":false,\"Labels\":{},\"Name\":\"testnet\",\"Options\":{}}"
Jan 08 14:09:25 hostA dockerd[8953]: time="2017-01-08T14:09:25.311783840+01:00" level=debug msg="RequestPool(GlobalDefault, , , map[], false)"
Jan 08 14:09:25 hostA dockerd[8953]: time="2017-01-08T14:09:25.313465331+01:00" level=debug msg="RequestAddress(GlobalDefault/10.0.0.0/24, <nil>, map[])"
Jan 08 14:09:30 hostA dockerd[8953]: time="2017-01-08T14:09:30.160301571+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:09:30 hostA dockerd[8953]: time="2017-01-08T14:09:30.161216749+01:00" level=debug msg="Calling POST /v1.25/services/create"
Jan 08 14:09:30 hostA dockerd[8953]: time="2017-01-08T14:09:30.161341423+01:00" level=debug msg="form data: {\"EndpointSpec\":{},\"Mode\":{\"Replicated\":{\"Replicas\":2}},\"Name\":\"test\",\"Networks\":[{\"Target\":\"testnet\"}],\"TaskTemplate\":{\"ContainerSpec\":{\"Args\":[\"sleep\",\"infinity\"],\"DNSConfig\":{},\"Image\":\"debian:jessie\"},\"ForceUpdate\":0,\"Networks\":[{\"Target\":\"testnet\"}],\"Placement\":{},\"Resources\":{\"Limits\":{},\"Reservations\":{}},\"RestartPolicy\":{}},\"UpdateConfig\":{\"FailureAction\":\"pause\",\"MaxFailureRatio\":0,\"Parallelism\":1}}"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.527591803+01:00" level=debug msg="pinning image debian:jessie by digest: debian:jessie@sha256:f7062cf040f67f0c26ff46b3b44fe036c29468a7e69d8170f37c57f2eec1261b"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.536907335+01:00" level=debug msg="RequestAddress(GlobalDefault/10.0.0.0/24, <nil>, map[])"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.536948622+01:00" level=debug msg="Service ebsmt49zgu58dflah572vic0y was scaled up from 0 to 2 instances"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.544446344+01:00" level=debug msg="RequestAddress(GlobalDefault/10.0.0.0/24, <nil>, map[])"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.544570111+01:00" level=debug msg="RequestAddress(GlobalDefault/10.0.0.0/24, <nil>, map[])"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.598811409+01:00" level=debug msg="assigning to node d0t6n0ivd1hut6axd60a8lq8o" task.id=r3o9t7c2zjwqhk7xhthz9qvtt
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.598938115+01:00" level=debug msg="assigning to node yq69t3ba47z9hr42caw7pifiy" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.703871253+01:00" level=debug msg="(*worker).Update" len(assignments)=1 module="node/agent"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.703905901+01:00" level=debug msg="(*worker).reconcileSecrets" len(removedSecrets)=0 len(updatedSecrets)=0 module="node/agent"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.703922255+01:00" level=debug msg="(*worker).reconcileTaskState" len(removedTasks)=0 len(updatedTasks)=1 module="node/agent"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.703943066+01:00" level=debug msg=assigned module="node/agent" task.desiredstate=RUNNING task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.704068914+01:00" level=debug msg="state changed" module="node/agent" state.desired=RUNNING state.transition="ASSIGNED->ACCEPTED" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.704230222+01:00" level=debug msg="(*Agent).UpdateTaskStatus" module="node/agent" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.704356192+01:00" level=debug msg="state changed" module="node/agent/taskmanager" state.desired=RUNNING state.transition="ASSIGNED->ACCEPTED" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.723117878+01:00" level=debug msg="task status reported" module="node/agent"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.723193707+01:00" level=debug msg="(*Agent).UpdateTaskStatus" module="node/agent" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.724226808+01:00" level=debug msg="task status reported" module="node/agent"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.733427006+01:00" level=debug msg="state changed" module="node/agent/taskmanager" state.desired=RUNNING state.transition="ACCEPTED->PREPARING" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.733761148+01:00" level=debug msg="(*Agent).UpdateTaskStatus" module="node/agent" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.734760752+01:00" level=debug msg="task status reported" module="node/agent"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.740148373+01:00" level=debug msg="Allocating IPv4 pools for network testnet (i5xj0641j4m20dcn7utb5pkp0)"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.740219421+01:00" level=debug msg="RequestPool(LocalDefault, 10.0.0.0/24, , map[], false)"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.740301337+01:00" level=debug msg="RequestAddress(LocalDefault/10.0.0.0/24, 10.0.0.1, map[RequestAddressType:com.docker.network.gateway])"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.740356577+01:00" level=debug msg="overlay: Received vxlan IDs: 4097"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.740402460+01:00" 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 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.750369748+01:00" level=debug msg="hostA-eacc6b718a3a: joined network i5xj0641j4m20dcn7utb5pkp0"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.750390259+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostA-eacc6b718a3a]"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.750437842+01:00" level=debug msg="/sbin/iptables, [--wait -t filter -L DOCKER-INGRESS]"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.759445814+01:00" level=debug msg="task status updated" method="(*Dispatcher).processUpdates" module=dispatcher state.transition="ASSIGNED->PREPARING" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.806436793+01:00" level=debug msg="container mounted via layerStore: /var/lib/docker/aufs/mnt/7cd0a349c6ca56cc2f7d1d6e6a077bb86c1f64169ef7d792f8781cbc16977ce8"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.857171192+01:00" level=debug msg="Creating service config in agent for t = &Task{ID:kemzuu2bmdjl6bt5oqokomwh4,Meta:Meta{Version:Version{Index:22,},CreatedAt:&docker_swarmkit_v1.Timestamp{Seconds:1483880971,Nanos:540857195,},UpdatedAt:&docker_swarmkit_v1.Timestamp{Seconds:1483880971,Nanos:599466396,},},Spec:TaskSpec{Runtime:&TaskSpec_Container{Container:&ContainerSpec{Image:debian:jessie@sha256:f7062cf040f67f0c26ff46b3b44fe036c29468a7e69d8170f37c57f2eec1261b,Labels:map[string]string{},Command:[],Args:[sleep infinity],Env:[],Dir:,User:,Mounts:[],StopGracePeriod:nil,PullOptions:nil,Groups:[],Secrets:[],TTY:false,Hostname:,DNSConfig:&ContainerSpec_DNSConfig{Nameservers:[],Search:[],Options:[],},Healthcheck:nil,Hosts:[],OpenStdin:false,},},Resources:&ResourceRequirements{Limits:&Resources{NanoCPUs:0,MemoryBytes:0,},Reservations:&Resources{NanoCPUs:0,MemoryBytes:0,},},Restart:&RestartPolicy{Condition:ANY,Delay:nil,MaxAttempts:0,Window:nil,},Placement:&Placement{Constraints:[],},LogDriver:nil,Networks:[&NetworkAttachmentConfig{Target:i5xj0641j4m20dcn7utb5pkp0,Aliases:[],Addresses:[],}],ForceUpdate:0,},ServiceID:ebsmt49zgu58dflah572vic0y,Slot:2,NodeID:yq69t3ba47z9hr42caw7pifiy,Annotations:Annotations{Name:,Labels:map[string]string{},},ServiceAnnotations:Annotations{Name:test,Labels:map[string]string{},},Status:TaskStatus{Timestamp:&docker_swarmkit_v1.Timestamp{Seconds:1483880971,Nanos:598972467,},State:ASSIGNED,Message:scheduler assigned task to node,Err:,RuntimeStatus:<nil>,PortStatus:nil,},DesiredState:RUNNING,Networks:[&NetworkAttachment{Network:&Network{ID:i5xj0641j4m20dcn7utb5pkp0,Meta:Meta{Version:Version{Index:17,},CreatedAt:&docker_swarmkit_v1.Timestamp{Seconds:1483880965,Nanos:306703988,},UpdatedAt:&docker_swarmkit_v1.Timestamp{Seconds:1483880965,Nanos:313626419,},},Spec:NetworkSpec{Annotations:Annotations{Name:testnet,Labels:map[string]string{},},DriverConfig:&Driver{Name:overlay,Options:map[string]string{},},Ipv6Enabled:false,Internal:false,IPAM:&IPAMOptions{Driver:&Driver{Name:defaul
Jan 08 14:09:31 hostA dockerd[8953]: t,Options:map[string]string{},},Configs:[],},Attachable:false,},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.4/24],Aliases:[],}],Endpoint:&Endpoint{Spec:&EndpointSpec{Mode:VIP,Ports:[],},Ports:[],VirtualIPs:[&Endpoint_VirtualIP{NetworkID:i5xj0641j4m20dcn7utb5pkp0,Addr:10.0.0.2/24,}],},LogDriver:nil,}"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.857464509+01:00" level=debug msg="state changed" module="node/agent/taskmanager" state.desired=RUNNING state.transition="PREPARING->READY" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.857841554+01:00" level=debug msg="(*Agent).UpdateTaskStatus" module="node/agent" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.859247989+01:00" level=debug msg="task status reported" module="node/agent"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.864056127+01:00" level=debug msg="state changed" module="node/agent/taskmanager" state.desired=RUNNING state.transition="READY->STARTING" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.864371532+01:00" level=debug msg="(*Agent).UpdateTaskStatus" module="node/agent" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.865539235+01:00" level=debug msg="task status reported" module="node/agent"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.871436392+01:00" level=debug msg="task status updated" method="(*Dispatcher).processUpdates" module=dispatcher state.transition="ASSIGNED->PREPARING" task.id=r3o9t7c2zjwqhk7xhthz9qvtt
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.871654496+01:00" level=debug msg="task status updated" method="(*Dispatcher).processUpdates" module=dispatcher state.transition="PREPARING->STARTING" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.873045925+01:00" level=debug msg="container mounted via layerStore: /var/lib/docker/aufs/mnt/7cd0a349c6ca56cc2f7d1d6e6a077bb86c1f64169ef7d792f8781cbc16977ce8"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.874310823+01:00" level=debug msg="Assigning addresses for endpoint test.2.kemzuu2bmdjl6bt5oqokomwh4's interface on network testnet"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.874377824+01:00" level=debug msg="RequestAddress(LocalDefault/10.0.0.0/24, 10.0.0.4, map[])"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.880973037+01:00" level=debug msg="Assigning addresses for endpoint test.2.kemzuu2bmdjl6bt5oqokomwh4's interface on network testnet"
Jan 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.881872427+01:00" 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 08 14:09:31 hostA dockerd[8953]: time="2017-01-08T14:09:31.881921603+01:00" level=info msg="IPv6 enabled; Adding default IPv6 external servers: [nameserver 2001:4860:4860::8888 nameserver 2001:4860:4860::8844]"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.100126062+01:00" level=debug msg="checkEncryption(i5xj064, <nil>, 4097, true)"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.101756141+01:00" level=debug msg="Assigning addresses for endpoint gateway_25a6179ec2e6's interface on network docker_gwbridge"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.101804657+01:00" level=debug msg="RequestAddress(LocalDefault/172.20.0.0/16, <nil>, map[])"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.117326938+01:00" level=debug msg="Assigning addresses for endpoint gateway_25a6179ec2e6's interface on network docker_gwbridge"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.138990156+01:00" level=debug msg="Programming external connectivity on endpoint gateway_25a6179ec2e6 (a685e662638d05f37f087b37e3201cd148b2f356c2f10b1fe182cc1fb1c4e2a5)"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.157676563+01:00" level=warning msg="Your kernel does not support swap memory limit."
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.157785655+01:00" level=warning msg="Your kernel does not support cgroup rt period"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.157822444+01:00" level=warning msg="Your kernel does not support cgroup rt runtime"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32+01:00" level=info msg="Firewalld running: false"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.393455329+01:00" level=debug msg="sandbox set key processing took 150.846466ms for container 25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.482628183+01:00" level=debug msg="libcontainerd: received containerd event: &types.Event{Type:\"start-container\", Id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\", Status:0x0, Pid:\"\", Timestamp:(*timestamp.Timestamp)(0xc4248f7a90)}"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.501776896+01:00" level=debug msg="libcontainerd: event unhandled: type:\"start-container\" id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\" timestamp:<seconds:1483880972 nanos:482283930 > "
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.511674253+01:00" level=debug msg="Creating service for vip 10.0.0.2 fwMark 256 ingressPorts []*libnetwork.PortConfig(nil)"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32+01:00" level=info msg="Firewalld running: false"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.567035636+01:00" level=debug msg="state changed" module="node/agent/taskmanager" state.desired=RUNNING state.transition="STARTING->RUNNING" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.567221281+01:00" level=debug msg="(*Agent).UpdateTaskStatus" module="node/agent" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.567795555+01:00" level=debug msg="task status reported" module="node/agent"
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.575274636+01:00" level=debug msg="waiting on events" module="node/agent/taskmanager" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:32 hostA dockerd[8953]: time="2017-01-08T14:09:32.576911826+01:00" level=debug msg="task status updated" method="(*Dispatcher).processUpdates" module=dispatcher state.transition="STARTING->RUNNING" task.id=kemzuu2bmdjl6bt5oqokomwh4
Jan 08 14:09:39 hostA dockerd[8953]: time="2017-01-08T14:09:39.112092163+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51167"
Jan 08 14:09:39 hostA dockerd[8953]: time="2017-01-08T14:09:39.505152793+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:09:41 hostA dockerd[8953]: time="2017-01-08T14:09:41.159277828+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51168"
Jan 08 14:09:41 hostA dockerd[8953]: time="2017-01-08T14:09:41.508046590+01:00" level=debug msg="task status updated" method="(*Dispatcher).processUpdates" module=dispatcher state.transition="PREPARING->READY" task.id=r3o9t7c2zjwqhk7xhthz9qvtt
Jan 08 14:09:41 hostA dockerd[8953]: time="2017-01-08T14:09:41.593914210+01:00" level=debug msg="checkEncryption(i5xj064, <hostB's public IP>, 4097, false)"
Jan 08 14:09:41 hostA dockerd[8953]: time="2017-01-08T14:09:41.594215852+01:00" level=debug msg="miss notification for dest IP, 10.0.0.3"
Jan 08 14:09:41 hostA dockerd[8953]: time="2017-01-08T14:09:41.613725737+01:00" level=debug msg="task status updated" method="(*Dispatcher).processUpdates" module=dispatcher state.transition="READY->STARTING" task.id=r3o9t7c2zjwqhk7xhthz9qvtt
Jan 08 14:09:41 hostA dockerd[8953]: time="2017-01-08T14:09:41.723166093+01:00" level=debug msg="task status updated" method="(*Dispatcher).processUpdates" module=dispatcher state.transition="STARTING->RUNNING" task.id=r3o9t7c2zjwqhk7xhthz9qvtt
Jan 08 14:09:47 hostA dockerd[8953]: time="2017-01-08T14:09:47.501062193+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:09:55 hostA dockerd[8953]: time="2017-01-08T14:09:55.562251832+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:09:55 hostA dockerd[8953]: time="2017-01-08T14:09:55.562342726+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:09:56 hostA dockerd[8953]: time="2017-01-08T14:09:56.111715471+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51169"
Jan 08 14:09:56 hostA dockerd[8953]: time="2017-01-08T14:09:56.118653514+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 322.944816ms"
Jan 08 14:10:09 hostA dockerd[8953]: time="2017-01-08T14:10:09.107471514+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51170"
Jan 08 14:10:09 hostA dockerd[8953]: time="2017-01-08T14:10:09.108142282+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:10:11 hostA dockerd[8953]: time="2017-01-08T14:10:11.564391815+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51172"
Jan 08 14:10:11 hostA dockerd[8953]: time="2017-01-08T14:10:11.565559939+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51171"
Jan 08 14:10:17 hostA dockerd[8953]: time="2017-01-08T14:10:17.822798060+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:10:23 hostA dockerd[8953]: time="2017-01-08T14:10:23.063570665+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:10:23 hostA dockerd[8953]: time="2017-01-08T14:10:23.064153954+01:00" level=debug msg="Calling GET /v1.25/version"
Jan 08 14:10:25 hostA dockerd[8953]: time="2017-01-08T14:10:25.561936288+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:10:25 hostA dockerd[8953]: time="2017-01-08T14:10:25.561981848+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:10:26 hostA dockerd[8953]: time="2017-01-08T14:10:26.048448660+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51173"
Jan 08 14:10:26 hostA dockerd[8953]: time="2017-01-08T14:10:26.050070665+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 338.114752ms"
Jan 08 14:10:27 hostA dockerd[8953]: time="2017-01-08T14:10:27.611367799+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:10:27 hostA dockerd[8953]: time="2017-01-08T14:10:27.612458180+01:00" level=debug msg="Calling GET /v1.25/containers/json"
Jan 08 14:10:30 hostA dockerd[8953]: time="2017-01-08T14:10:30.644100371+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:10:30 hostA dockerd[8953]: time="2017-01-08T14:10:30.644888583+01:00" level=debug msg="Calling GET /v1.25/version"
Jan 08 14:10:32 hostA dockerd[8953]: time="2017-01-08T14:10:32.076816661+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:10:32 hostA dockerd[8953]: time="2017-01-08T14:10:32.077840733+01:00" level=debug msg="Calling GET /v1.25/containers/25/json"
Jan 08 14:10:39 hostA dockerd[8953]: time="2017-01-08T14:10:39.007584349+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51174"
Jan 08 14:10:39 hostA dockerd[8953]: time="2017-01-08T14:10:39.008371429+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.101127066+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.102422807+01:00" level=debug msg="Calling POST /v1.25/containers/25/exec"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.102633902+01:00" level=debug msg="form data: {\"AttachStderr\":true,\"AttachStdin\":true,\"AttachStdout\":true,\"Cmd\":[\"ping\",\"10.0.0.3\"],\"Detach\":false,\"DetachKeys\":\"\",\"Env\":null,\"Privileged\":false,\"Tty\":true,\"User\":\"\"}"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.104227006+01:00" level=debug msg="Calling POST /v1.25/exec/9be0b89fd82ee92e06bb645dce0ab233f8901501ba77a86fdc47335f9a959206/start"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.104505978+01:00" level=debug msg="form data: {\"AttachStderr\":true,\"AttachStdin\":true,\"AttachStdout\":true,\"Cmd\":[\"ping\",\"10.0.0.3\"],\"Detach\":false,\"DetachKeys\":\"\",\"Env\":null,\"Privileged\":false,\"Tty\":true,\"User\":\"\"}"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.104823652+01:00" level=debug msg="starting exec command 9be0b89fd82ee92e06bb645dce0ab233f8901501ba77a86fdc47335f9a959206 in container 25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.105176986+01:00" level=debug msg="attach: stdout: begin"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.105230214+01:00" level=debug msg="attach: stdin: begin"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.105853308+01:00" level=debug msg="Calling POST /v1.25/exec/9be0b89fd82ee92e06bb645dce0ab233f8901501ba77a86fdc47335f9a959206/resize?h=41&w=132"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.187379024+01:00" level=debug msg="libcontainerd: received containerd event: &types.Event{Type:\"start-process\", Id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\", Status:0x0, Pid:\"9be0b89fd82ee92e06bb645dce0ab233f8901501ba77a86fdc47335f9a959206\", Timestamp:(*timestamp.Timestamp)(0xc42399a3d0)}"
Jan 08 14:10:40 hostA dockerd[8953]: time="2017-01-08T14:10:40.187913728+01:00" level=debug msg="libcontainerd: event unhandled: type:\"start-process\" id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\" pid:\"9be0b89fd82ee92e06bb645dce0ab233f8901501ba77a86fdc47335f9a959206\" timestamp:<seconds:1483881040 nanos:187151843 > "
Jan 08 14:10:41 hostA dockerd[8953]: time="2017-01-08T14:10:41.976900657+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51175"
Jan 08 14:10:48 hostA dockerd[8953]: time="2017-01-08T14:10:48.060129622+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:10:55 hostA dockerd[8953]: time="2017-01-08T14:10:55.562204108+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:10:55 hostA dockerd[8953]: time="2017-01-08T14:10:55.562285201+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:10:56 hostA dockerd[8953]: time="2017-01-08T14:10:56.005646548+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51176"
Jan 08 14:10:56 hostA dockerd[8953]: time="2017-01-08T14:10:56.006661839+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 307.783431ms"
Jan 08 14:11:08 hostA dockerd[8953]: time="2017-01-08T14:11:08.914413680+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51177"
Jan 08 14:11:08 hostA dockerd[8953]: time="2017-01-08T14:11:08.920205378+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:11:12 hostA dockerd[8953]: time="2017-01-08T14:11:12.300602143+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51178"
Jan 08 14:11:18 hostA dockerd[8953]: time="2017-01-08T14:11:18.290820633+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:11:25 hostA dockerd[8953]: time="2017-01-08T14:11:25.561938662+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:11:25 hostA dockerd[8953]: time="2017-01-08T14:11:25.561973988+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:11:26 hostA dockerd[8953]: time="2017-01-08T14:11:26.015788030+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51179"
Jan 08 14:11:26 hostA dockerd[8953]: time="2017-01-08T14:11:26.027447063+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 322.905591ms"
Jan 08 14:11:39 hostA dockerd[8953]: time="2017-01-08T14:11:39.013790657+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51180"
Jan 08 14:11:39 hostA dockerd[8953]: time="2017-01-08T14:11:39.014413039+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:11:42 hostA dockerd[8953]: time="2017-01-08T14:11:42.700469265+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51181"
Jan 08 14:11:48 hostA dockerd[8953]: time="2017-01-08T14:11:48.639189774+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:11:50 hostA dockerd[8953]: time="2017-01-08T14:11:50.412561508+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51182"
Jan 08 14:11:55 hostA dockerd[8953]: time="2017-01-08T14:11:55.562498613+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:11:55 hostA dockerd[8953]: time="2017-01-08T14:11:55.562571730+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:11:56 hostA dockerd[8953]: time="2017-01-08T14:11:56.114425225+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51183"
Jan 08 14:11:56 hostA dockerd[8953]: time="2017-01-08T14:11:56.115318107+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 409.871218ms"
Jan 08 14:12:08 hostA dockerd[8953]: time="2017-01-08T14:12:08.920629733+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51184"
Jan 08 14:12:08 hostA dockerd[8953]: time="2017-01-08T14:12:08.927185454+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:12:13 hostA dockerd[8953]: time="2017-01-08T14:12:13.112759151+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51185"
Jan 08 14:12:19 hostA dockerd[8953]: time="2017-01-08T14:12:19.051865420+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:12:25 hostA dockerd[8953]: time="2017-01-08T14:12:25.561905258+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:12:25 hostA dockerd[8953]: time="2017-01-08T14:12:25.561932785+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:12:26 hostA dockerd[8953]: time="2017-01-08T14:12:26.014775278+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51186"
Jan 08 14:12:26 hostA dockerd[8953]: time="2017-01-08T14:12:26.015929202+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 302.00294ms"
Jan 08 14:12:39 hostA dockerd[8953]: time="2017-01-08T14:12:39.019726683+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51187"
Jan 08 14:12:39 hostA dockerd[8953]: time="2017-01-08T14:12:39.020737616+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:12:43 hostA dockerd[8953]: time="2017-01-08T14:12:43.343767016+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51188"
Jan 08 14:12:49 hostA dockerd[8953]: time="2017-01-08T14:12:49.367710876+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:12:55 hostA dockerd[8953]: time="2017-01-08T14:12:55.562543055+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:12:55 hostA dockerd[8953]: time="2017-01-08T14:12:55.562629709+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:12:55 hostA dockerd[8953]: time="2017-01-08T14:12:55.915866893+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51189"
Jan 08 14:12:55 hostA dockerd[8953]: time="2017-01-08T14:12:55.921963460+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 242.452417ms"
Jan 08 14:13:09 hostA dockerd[8953]: time="2017-01-08T14:13:09.024278189+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51190"
Jan 08 14:13:09 hostA dockerd[8953]: time="2017-01-08T14:13:09.025181697+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:13:13 hostA dockerd[8953]: time="2017-01-08T14:13:13.578430666+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51191"
Jan 08 14:13:19 hostA dockerd[8953]: time="2017-01-08T14:13:19.774764982+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:13:25 hostA dockerd[8953]: time="2017-01-08T14:13:25.561879473+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:13:25 hostA dockerd[8953]: time="2017-01-08T14:13:25.561911569+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:13:26 hostA dockerd[8953]: time="2017-01-08T14:13:26.021198458+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51192"
Jan 08 14:13:26 hostA dockerd[8953]: time="2017-01-08T14:13:26.022043697+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 344.463564ms"
Jan 08 14:13:38 hostA dockerd[8953]: time="2017-01-08T14:13:38.916043208+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51193"
Jan 08 14:13:38 hostA dockerd[8953]: time="2017-01-08T14:13:38.923435621+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:13:43 hostA dockerd[8953]: time="2017-01-08T14:13:43.941715853+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51194"
Jan 08 14:13:50 hostA dockerd[8953]: time="2017-01-08T14:13:50.188114601+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:13:55 hostA dockerd[8953]: time="2017-01-08T14:13:55.562576498+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:13:55 hostA dockerd[8953]: time="2017-01-08T14:13:55.562660551+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:13:56 hostA dockerd[8953]: time="2017-01-08T14:13:56.126852978+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51195"
Jan 08 14:13:56 hostA dockerd[8953]: time="2017-01-08T14:13:56.127828242+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 409.793577ms"
Jan 08 14:14:08 hostA dockerd[8953]: time="2017-01-08T14:14:08.912441994+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51196"
Jan 08 14:14:08 hostA dockerd[8953]: time="2017-01-08T14:14:08.917989872+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:14:14 hostA dockerd[8953]: time="2017-01-08T14:14:14.162147051+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51197"
Jan 08 14:14:20 hostA dockerd[8953]: time="2017-01-08T14:14:20.502188648+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:14:25 hostA dockerd[8953]: time="2017-01-08T14:14:25.561896986+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:14:25 hostA dockerd[8953]: time="2017-01-08T14:14:25.561926337+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:14:26 hostA dockerd[8953]: time="2017-01-08T14:14:26.159072863+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51198"
Jan 08 14:14:26 hostA dockerd[8953]: time="2017-01-08T14:14:26.160101517+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 314.085365ms"
Jan 08 14:14:38 hostA dockerd[8953]: time="2017-01-08T14:14:38.942717317+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51199"
Jan 08 14:14:38 hostA dockerd[8953]: time="2017-01-08T14:14:38.948457781+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:14:44 hostA dockerd[8953]: time="2017-01-08T14:14:44.488621772+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51200"
Jan 08 14:14:50 hostA dockerd[8953]: time="2017-01-08T14:14:50.724552446+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:14:55 hostA dockerd[8953]: time="2017-01-08T14:14:55.562487015+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:14:55 hostA dockerd[8953]: time="2017-01-08T14:14:55.562584803+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:14:55 hostA dockerd[8953]: time="2017-01-08T14:14:55.902293753+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51201"
Jan 08 14:14:55 hostA dockerd[8953]: time="2017-01-08T14:14:55.903124894+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 231.23337ms"
Jan 08 14:15:09 hostA dockerd[8953]: time="2017-01-08T14:15:09.035816433+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51202"
Jan 08 14:15:09 hostA dockerd[8953]: time="2017-01-08T14:15:09.036615296+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:15:14 hostA dockerd[8953]: time="2017-01-08T14:15:14.770085330+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51203"
Jan 08 14:15:21 hostA dockerd[8953]: time="2017-01-08T14:15:21.118778871+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:15:21 hostA dockerd[8953]: time="2017-01-08T14:15:21.477188946+01:00" level=debug msg="containerd: process exited" id=25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59 pid=9be0b89fd82ee92e06bb645dce0ab233f8901501ba77a86fdc47335f9a959206 status=1 systemPid=9824
Jan 08 14:15:21 hostA dockerd[8953]: time="2017-01-08T14:15:21.478188093+01:00" level=debug msg="libcontainerd: received containerd event: &types.Event{Type:\"exit\", Id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\", Status:0x1, Pid:\"9be0b89fd82ee92e06bb645dce0ab233f8901501ba77a86fdc47335f9a959206\", Timestamp:(*timestamp.Timestamp)(0xc4220b4220)}"
Jan 08 14:15:21 hostA dockerd[8953]: time="2017-01-08T14:15:21.478551804+01:00" level=debug msg="attach: stdout: end"
Jan 08 14:15:21 hostA dockerd[8953]: time="2017-01-08T14:15:21.478633201+01:00" level=debug msg="attach: stdin: end"
Jan 08 14:15:21 hostA dockerd[8953]: time="2017-01-08T14:15:21.478806951+01:00" level=debug msg="Closing buffered stdin pipe"
Jan 08 14:15:21 hostA dockerd[8953]: time="2017-01-08T14:15:21.479493165+01:00" level=debug msg="Calling GET /v1.25/exec/9be0b89fd82ee92e06bb645dce0ab233f8901501ba77a86fdc47335f9a959206/json"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.475874455+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.476496346+01:00" level=debug msg="Calling POST /v1.25/containers/25/exec"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.476576706+01:00" level=debug msg="form data: {\"AttachStderr\":true,\"AttachStdin\":false,\"AttachStdout\":true,\"Cmd\":[\"ping\",\"10.0.0.3\"],\"Detach\":false,\"DetachKeys\":\"\",\"Env\":null,\"Privileged\":false,\"Tty\":true,\"User\":\"\"}"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.477055762+01:00" level=debug msg="Calling POST /v1.25/exec/950f9ff07072357002c1eda13969d53fdb592ede1517c907041cd362a917dd12/start"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.477181811+01:00" level=debug msg="form data: {\"AttachStderr\":true,\"AttachStdin\":false,\"AttachStdout\":true,\"Cmd\":[\"ping\",\"10.0.0.3\"],\"Detach\":false,\"DetachKeys\":\"\",\"Env\":null,\"Privileged\":false,\"Tty\":true,\"User\":\"\"}"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.477306508+01:00" level=debug msg="starting exec command 950f9ff07072357002c1eda13969d53fdb592ede1517c907041cd362a917dd12 in container 25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.477390635+01:00" level=debug msg="attach: stdout: begin"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.477563394+01:00" level=debug msg="Calling POST /v1.25/exec/950f9ff07072357002c1eda13969d53fdb592ede1517c907041cd362a917dd12/resize?h=41&w=132"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.556693832+01:00" level=debug msg="libcontainerd: received containerd event: &types.Event{Type:\"start-process\", Id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\", Status:0x0, Pid:\"950f9ff07072357002c1eda13969d53fdb592ede1517c907041cd362a917dd12\", Timestamp:(*timestamp.Timestamp)(0xc4213e24a0)}"
Jan 08 14:15:23 hostA dockerd[8953]: time="2017-01-08T14:15:23.556777907+01:00" level=debug msg="libcontainerd: event unhandled: type:\"start-process\" id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\" pid:\"950f9ff07072357002c1eda13969d53fdb592ede1517c907041cd362a917dd12\" timestamp:<seconds:1483881323 nanos:556477921 > "
Jan 08 14:15:25 hostA dockerd[8953]: time="2017-01-08T14:15:25.561966160+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:15:25 hostA dockerd[8953]: time="2017-01-08T14:15:25.562000781+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [i5xj0641j4m20dcn7utb5pkp0 y4p7ieba8mwxca5hq0b51vj0f] with node hostB-667564f8c9ef"
Jan 08 14:15:25 hostA dockerd[8953]: time="2017-01-08T14:15:25.894228524+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51204"
Jan 08 14:15:25 hostA dockerd[8953]: time="2017-01-08T14:15:25.900557394+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 229.510861ms"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.566979403+01:00" level=debug msg="Calling GET /_ping"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.570673959+01:00" level=debug msg="Calling POST /v1.25/containers/25/exec"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.570959247+01:00" level=debug msg="form data: {\"AttachStderr\":true,\"AttachStdin\":false,\"AttachStdout\":true,\"Cmd\":[\"ping\",\"10.0.0.3\"],\"Detach\":false,\"DetachKeys\":\"\",\"Env\":null,\"Privileged\":false,\"Tty\":false,\"User\":\"\"}"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.572399887+01:00" level=debug msg="Calling POST /v1.25/exec/5316fa3c0796ee3d3a23ce1951aba20477d7cd4d64ee3f463ef48812e98a3640/start"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.572690196+01:00" level=debug msg="form data: {\"AttachStderr\":true,\"AttachStdin\":false,\"AttachStdout\":true,\"Cmd\":[\"ping\",\"10.0.0.3\"],\"Detach\":false,\"DetachKeys\":\"\",\"Env\":null,\"Privileged\":false,\"Tty\":false,\"User\":\"\"}"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.572934037+01:00" level=debug msg="starting exec command 5316fa3c0796ee3d3a23ce1951aba20477d7cd4d64ee3f463ef48812e98a3640 in container 25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.573295806+01:00" level=debug msg="attach: stderr: begin"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.573475063+01:00" level=debug msg="attach: stdout: begin"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.670953206+01:00" level=debug msg="libcontainerd: received containerd event: &types.Event{Type:\"start-process\", Id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\", Status:0x0, Pid:\"5316fa3c0796ee3d3a23ce1951aba20477d7cd4d64ee3f463ef48812e98a3640\", Timestamp:(*timestamp.Timestamp)(0xc424dcf270)}"
Jan 08 14:15:26 hostA dockerd[8953]: time="2017-01-08T14:15:26.671038133+01:00" level=debug msg="libcontainerd: event unhandled: type:\"start-process\" id:\"25a6179ec2e6fa768e1b506c55ec1763421c8f94d9750c137eeb54ff98d1af59\" pid:\"5316fa3c0796ee3d3a23ce1951aba20477d7cd4d64ee3f463ef48812e98a3640\" timestamp:<seconds:1483881326 nanos:670693778 > "
Jan 08 14:15:38 hostA dockerd[8953]: time="2017-01-08T14:15:38.936411711+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51205"
Jan 08 14:15:38 hostA dockerd[8953]: time="2017-01-08T14:15:38.937122941+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:15:45 hostA dockerd[8953]: time="2017-01-08T14:15:45.183445431+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51206"
Jan 08 14:15:51 hostA dockerd[8953]: time="2017-01-08T14:15:51.531781136+01:00" level=debug msg="memberlist: Initiating push/pull sync with: <hostB's public IP>:7946"
Jan 08 14:15:55 hostA dockerd[8953]: time="2017-01-08T14:15:55.562119575+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating bulk sync with nodes [hostB-667564f8c9ef]"
Jan 08 14:15:55 hostA dockerd[8953]: time="2017-01-08T14:15:55.562222795+01:00" level=debug msg="hostA-eacc6b718a3a: Initiating unsolicited bulk sync for networks [y4p7ieba8mwxca5hq0b51vj0f i5xj0641j4m20dcn7utb5pkp0] with node hostB-667564f8c9ef"
Jan 08 14:15:55 hostA dockerd[8953]: time="2017-01-08T14:15:55.935178214+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51207"
Jan 08 14:15:55 hostA dockerd[8953]: time="2017-01-08T14:15:55.935986249+01:00" level=debug msg="hostA-eacc6b718a3a: Bulk sync to node hostB-667564f8c9ef took 256.270152ms"
Jan 08 14:16:08 hostA dockerd[8953]: time="2017-01-08T14:16:08.940046548+01:00" level=debug msg="memberlist: TCP connection from=<hostB's public IP>:51208"
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment