calico: calico on k3s: the calico-node is running but not ready with no obvious errors
Expected Behavior
This is an initial install based on: https://docs.projectcalico.org/getting-started/kubernetes/k3s/quickstart calico-node should transition from ready 0 -> 1
Current Behavior
calico-node initializes but never is ready, no obvious error messages calico-node description
Name: calico-node-vnxhs
Namespace: calico-system
Priority: 1000000000
Priority Class Name: calico-priority
Node: nc1/172.17.0.32
Start Time: Tue, 12 Jan 2021 09:12:34 +0000
Labels: controller-revision-hash=58d5bb989f
k8s-app=calico-node
pod-template-generation=1
Annotations: hash.operator.tigera.io/cni-config: 8cdc037baa5a288802a5754d2ab6c74d9b8b3ada
hash.operator.tigera.io/node-cert: e29af7cd10f55bae4338b7ea9f6ae8811e0fde3a
hash.operator.tigera.io/typha-ca: b30f9f864fbcefb76603d7651b55c7d7cd8bfd35
Status: Running
IP: 172.17.0.32
IPs:
IP: 172.17.0.32
Controlled By: DaemonSet/calico-node
Init Containers:
flexvol-driver:
Container ID: containerd://ff0f929789be52479cd3b9fbbe9799477c3a018f5753edb15365cef0f2cebfe0
Image: docker.io/calico/pod2daemon-flexvol:v3.17.1
Image ID: docker.io/calico/pod2daemon-flexvol@sha256:48f277d41c35dae051d7dd6f0ec8f64ac7ee6650e27102a41b0203a0c2ce6c6b
Port: <none>
Host Port: <none>
State: Terminated
Reason: Completed
Exit Code: 0
Started: Tue, 12 Jan 2021 09:12:38 +0000
Finished: Tue, 12 Jan 2021 09:12:38 +0000
Ready: True
Restart Count: 0
Environment: <none>
Mounts:
/host/driver from flexvol-driver-host (rw)
/var/run/secrets/kubernetes.io/serviceaccount from calico-node-token-7s9rf (ro)
install-cni:
Container ID: containerd://aaacbbbde7383bb0f3f996902899508f6ccba199aa5b5e49655068a949a9d725
Image: docker.io/calico/cni:v3.17.1
Image ID: docker.io/calico/cni@sha256:3dc2506632843491864ce73a6e73d5bba7d0dc25ec0df00c1baa91d17549b068
Port: <none>
Host Port: <none>
Command:
/opt/cni/bin/install
State: Terminated
Reason: Completed
Exit Code: 0
Started: Tue, 12 Jan 2021 09:12:43 +0000
Finished: Tue, 12 Jan 2021 09:12:45 +0000
Ready: True
Restart Count: 0
Environment:
CNI_CONF_NAME: 10-calico.conflist
SLEEP: false
CNI_NET_DIR: /etc/cni/net.d
CNI_NETWORK_CONFIG: <set to the key 'config' of config map 'cni-config'> Optional: false
KUBERNETES_SERVICE_HOST: 10.43.0.1
KUBERNETES_SERVICE_PORT: 443
Mounts:
/host/etc/cni/net.d from cni-net-dir (rw)
/host/opt/cni/bin from cni-bin-dir (rw)
/var/run/secrets/kubernetes.io/serviceaccount from calico-node-token-7s9rf (ro)
Containers:
calico-node:
Container ID: containerd://99ba9b3756de5e0f022ce6f0a7e3d98ce7b46a71bd12eb2be1dc7ddd31a28ef6
Image: docker.io/calico/node:v3.17.1
Image ID: docker.io/calico/node@sha256:25e0b0495c0df3a7a06b6f9e92203c53e5b56c143ac1c885885ee84bf86285ff
Port: <none>
Host Port: <none>
State: Running
Started: Tue, 12 Jan 2021 09:12:54 +0000
Ready: False
Restart Count: 0
Liveness: http-get http://localhost:9099/liveness delay=0s timeout=1s period=10s #success=1 #failure=3
Readiness: exec [/bin/calico-node -bird-ready -felix-ready] delay=0s timeout=1s period=10s #success=1 #failure=3
Environment:
DATASTORE_TYPE: kubernetes
WAIT_FOR_DATASTORE: true
CLUSTER_TYPE: k8s,operator,bgp
CALICO_DISABLE_FILE_LOGGING: true
FELIX_DEFAULTENDPOINTTOHOSTACTION: ACCEPT
FELIX_HEALTHENABLED: true
NODENAME: (v1:spec.nodeName)
NAMESPACE: calico-system (v1:metadata.namespace)
FELIX_TYPHAK8SNAMESPACE: calico-system
FELIX_TYPHAK8SSERVICENAME: calico-typha
FELIX_TYPHACAFILE: /typha-ca/caBundle
FELIX_TYPHACERTFILE: /felix-certs/cert.crt
FELIX_TYPHAKEYFILE: /felix-certs/key.key
FELIX_TYPHACN: <set to the key 'common-name' in secret 'typha-certs'> Optional: true
FELIX_TYPHAURISAN: <set to the key 'uri-san' in secret 'typha-certs'> Optional: true
CALICO_IPV4POOL_CIDR: 20.28.0.0/16
CALICO_IPV4POOL_VXLAN: CrossSubnet
CALICO_IPV4POOL_BLOCK_SIZE: 26
CALICO_IPV4POOL_NODE_SELECTOR: all()
CALICO_NETWORKING_BACKEND: bird
IP: autodetect
IP_AUTODETECTION_METHOD: first-found
IP6: none
FELIX_IPV6SUPPORT: false
FELIX_IPTABLESBACKEND: auto
KUBERNETES_SERVICE_HOST: 10.43.0.1
KUBERNETES_SERVICE_PORT: 443
Mounts:
/felix-certs from felix-certs (ro)
/lib/modules from lib-modules (ro)
/run/xtables.lock from xtables-lock (rw)
/typha-ca from typha-ca (ro)
/var/lib/calico from var-lib-calico (rw)
/var/log/calico/cni from cni-log-dir (ro)
/var/run/calico from var-run-calico (rw)
/var/run/nodeagent from policysync (rw)
/var/run/secrets/kubernetes.io/serviceaccount from calico-node-token-7s9rf (ro)
Conditions:
Type Status
Initialized True
Ready False
ContainersReady False
PodScheduled True
Volumes:
lib-modules:
Type: HostPath (bare host directory volume)
Path: /lib/modules
HostPathType:
var-run-calico:
Type: HostPath (bare host directory volume)
Path: /var/run/calico
HostPathType:
var-lib-calico:
Type: HostPath (bare host directory volume)
Path: /var/lib/calico
HostPathType:
xtables-lock:
Type: HostPath (bare host directory volume)
Path: /run/xtables.lock
HostPathType: FileOrCreate
policysync:
Type: HostPath (bare host directory volume)
Path: /var/run/nodeagent
HostPathType: DirectoryOrCreate
typha-ca:
Type: ConfigMap (a volume populated by a ConfigMap)
Name: typha-ca
Optional: false
felix-certs:
Type: Secret (a volume populated by a Secret)
SecretName: node-certs
Optional: false
cni-bin-dir:
Type: HostPath (bare host directory volume)
Path: /opt/cni/bin
HostPathType:
cni-net-dir:
Type: HostPath (bare host directory volume)
Path: /etc/cni/net.d
HostPathType:
cni-log-dir:
Type: HostPath (bare host directory volume)
Path: /var/log/calico/cni
HostPathType:
flexvol-driver-host:
Type: HostPath (bare host directory volume)
Path: /usr/libexec/kubernetes/kubelet-plugins/volume/exec/nodeagent~uds
HostPathType: DirectoryOrCreate
calico-node-token-7s9rf:
Type: Secret (a volume populated by a Secret)
SecretName: calico-node-token-7s9rf
Optional: false
QoS Class: BestEffort
Node-Selectors: kubernetes.io/os=linux
Tolerations: :NoSchedule
:NoExecute
CriticalAddonsOnly
node.kubernetes.io/disk-pressure:NoSchedule
node.kubernetes.io/memory-pressure:NoSchedule
node.kubernetes.io/network-unavailable:NoSchedule
node.kubernetes.io/not-ready:NoExecute
node.kubernetes.io/pid-pressure:NoSchedule
node.kubernetes.io/unreachable:NoExecute
node.kubernetes.io/unschedulable:NoSchedule
Events:
Type Reason Age From Message
---- ------ ---- ---- -------
Normal Scheduled <unknown> default-scheduler Successfully assigned calico-system/calico-node-vnxhs to nc1
Normal Pulling 14m kubelet, nc1 Pulling image "docker.io/calico/pod2daemon-flexvol:v3.17.1"
Normal Pulled 14m kubelet, nc1 Successfully pulled image "docker.io/calico/pod2daemon-flexvol:v3.17.1"
Normal Created 14m kubelet, nc1 Created container flexvol-driver
Normal Started 14m kubelet, nc1 Started container flexvol-driver
Normal Pulling 14m kubelet, nc1 Pulling image "docker.io/calico/cni:v3.17.1"
Normal Pulled 14m kubelet, nc1 Successfully pulled image "docker.io/calico/cni:v3.17.1"
Normal Created 14m kubelet, nc1 Created container install-cni
Normal Started 14m kubelet, nc1 Started container install-cni
Normal Pulling 14m kubelet, nc1 Pulling image "docker.io/calico/node:v3.17.1"
Normal Pulled 14m kubelet, nc1 Successfully pulled image "docker.io/calico/node:v3.17.1"
Normal Started 14m kubelet, nc1 Started container calico-node
Normal Created 14m kubelet, nc1 Created container calico-node
Warning Unhealthy 13m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "229add0d8ae2ddb83a80a94bf3cfb320f37d7dee97848c38f7aad5d64383de61": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 13m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "a397bfeb73a4638a055a6c6c8f12438466e51f0ceb0984cbe1d818fefe626301": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 13m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "4fcd3f771c0f2979c870716476aec6d5416b3afe57529792c390c792bd4ea57f": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 13m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "2c6a19447f1148331471444a8ebe8a61dc0686434dceca190e5b6bb32e5be13a": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 13m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "dff5e5407c6e3ad217dcbacb2befe155458e58a3162a83476eb1012e5ee0377d": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 13m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "52c26e79c2777f97e4c4b610976f4626a0776793244fe1de009bc9a00bee5982": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 12m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "862a7c43f6b27b24d4e2197bf058e40050afc87ff3e96798a9d7d844d19aca67": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 12m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "884b3e77753b3e0b899f64dcdaadd2d7323ea1348490b334f05841ddb66823df": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 12m kubelet, nc1 Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "7e2ab935618cebc97106f83fde8fc1119925df6f08118e42f40cbf07d35b0c66": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
Warning Unhealthy 4m21s (x49 over 12m) kubelet, nc1 (combined from similar events): Readiness probe errored: rpc error: code = Unknown desc = failed to exec in container: failed to start exec "1d02350cbf55a42b169ab5f25085c8f8e04379ec9eb8237f4d5581a6998a559a": OCI runtime exec failed: exec failed: container_linux.go:349: starting container process caused "exec: \"/bin/calico-node\": stat /bin/calico-node: no such file or directory": unknown
calico-node log
2021-01-12 09:12:54.956 [INFO][9] startup/startup.go 379: Early log level set to info
2021-01-12 09:12:54.956 [INFO][9] startup/startup.go 395: Using NODENAME environment for node name
2021-01-12 09:12:54.956 [INFO][9] startup/startup.go 407: Determined node name: nc1
2021-01-12 09:12:54.958 [INFO][9] startup/startup.go 439: Checking datastore connection
2021-01-12 09:12:54.977 [INFO][9] startup/startup.go 463: Datastore connection verified
2021-01-12 09:12:54.977 [INFO][9] startup/startup.go 112: Datastore is ready
2021-01-12 09:12:54.983 [INFO][9] startup/customresource.go 101: Error getting resource Key=GlobalFelixConfig(name=CalicoVersion) Name="calicoversion" Resource="GlobalFelixConfigs" error=the server could not find the requested resource (get GlobalFelixConfigs.crd.projectcalico.org calicoversion)
2021-01-12 09:12:54.992 [INFO][9] startup/startup.go 505: Initialize BGP data
2021-01-12 09:12:55.003 [INFO][9] startup/startup.go 759: Using autodetected IPv4 address on interface eth1.403: 172.27.40.11/24
2021-01-12 09:12:55.004 [INFO][9] startup/startup.go 576: Node IPv4 changed, will check for conflicts
2021-01-12 09:12:55.008 [INFO][9] startup/startup.go 836: No AS number configured on node resource, using global value
2021-01-12 09:12:55.008 [INFO][9] startup/startup.go 184: Setting NetworkUnavailable to False
2021-01-12 09:12:55.029 [INFO][9] startup/startup.go 697: CALICO_IPV4POOL_NAT_OUTGOING is true (defaulted) through environment variable
2021-01-12 09:12:55.029 [INFO][9] startup/startup.go 1037: Ensure default IPv4 pool is created. IPIP mode: Never, VXLAN mode: CrossSubnet
2021-01-12 09:12:55.051 [INFO][9] startup/startup.go 1047: Created default IPv4 pool (20.28.0.0/16) with NAT outgoing true. IPIP mode: Never, VXLAN mode: CrossSubnet
2021-01-12 09:12:55.051 [INFO][9] startup/startup.go 691: FELIX_IPV6SUPPORT is false through environment variable
2021-01-12 09:12:55.067 [INFO][9] startup/startup.go 217: Using node name: nc1
2021-01-12 09:12:55.152 [INFO][21] tunnel-ip-allocator/ipam.go 1325: Releasing all IPs with handle 'wireguard-tunnel-addr-nc1'
2021-01-12 09:12:55.165 [INFO][21] tunnel-ip-allocator/ipam.go 1325: Releasing all IPs with handle 'ipip-tunnel-addr-nc1'
2021-01-12 09:12:55.179 [INFO][21] tunnel-ip-allocator/allocateip.go 267: Assign a new tunnel address type="vxlanTunnelAddress"
2021-01-12 09:12:55.179 [INFO][21] tunnel-ip-allocator/allocateip.go 343: Release any old tunnel addresses IP="" type="vxlanTunnelAddress"
2021-01-12 09:12:55.179 [INFO][21] tunnel-ip-allocator/ipam.go 1325: Releasing all IPs with handle 'vxlan-tunnel-addr-nc1'
2021-01-12 09:12:55.182 [INFO][21] tunnel-ip-allocator/allocateip.go 354: Assign new tunnel address IP="" type="vxlanTunnelAddress"
2021-01-12 09:12:55.182 [INFO][21] tunnel-ip-allocator/ipam.go 92: Auto-assign 1 ipv4, 0 ipv6 addrs for host 'nc1'
2021-01-12 09:12:55.182 [INFO][21] tunnel-ip-allocator/ipam.go 548: Looking up existing affinities for host handle="vxlan-tunnel-addr-nc1" host="nc1"
2021-01-12 09:12:55.187 [INFO][21] tunnel-ip-allocator/ipam.go 346: Looking up existing affinities for host host="nc1"
2021-01-12 09:12:55.193 [INFO][21] tunnel-ip-allocator/ipam.go 460: Ran out of existing affine blocks for host host="nc1"
2021-01-12 09:12:55.195 [INFO][21] tunnel-ip-allocator/ipam.go 475: No more affine blocks, but need to claim more block -- allocate another block host="nc1"
2021-01-12 09:12:55.195 [INFO][21] tunnel-ip-allocator/ipam.go 478: Looking for an unclaimed block host="nc1"
2021-01-12 09:12:55.199 [INFO][21] tunnel-ip-allocator/ipam_block_reader_writer.go 124: Found free block: 20.28.116.64/26
2021-01-12 09:12:55.199 [INFO][21] tunnel-ip-allocator/ipam.go 490: Found unclaimed block host="nc1" subnet=20.28.116.64/26
2021-01-12 09:12:55.199 [INFO][21] tunnel-ip-allocator/ipam_block_reader_writer.go 137: Trying to create affinity in pending state host="nc1" subnet=20.28.116.64/26
2021-01-12 09:12:55.205 [INFO][21] tunnel-ip-allocator/ipam_block_reader_writer.go 167: Successfully created pending affinity for block host="nc1" subnet=20.28.116.64/26
2021-01-12 09:12:55.205 [INFO][21] tunnel-ip-allocator/ipam.go 140: Attempting to load block cidr=20.28.116.64/26 host="nc1"
2021-01-12 09:12:55.208 [INFO][21] tunnel-ip-allocator/ipam.go 145: The referenced block doesn't exist, trying to create it cidr=20.28.116.64/26 host="nc1"
2021-01-12 09:12:55.213 [INFO][21] tunnel-ip-allocator/ipam.go 152: Wrote affinity as pending cidr=20.28.116.64/26 host="nc1"
2021-01-12 09:12:55.215 [INFO][21] tunnel-ip-allocator/ipam.go 161: Attempting to claim the block cidr=20.28.116.64/26 host="nc1"
2021-01-12 09:12:55.215 [INFO][21] tunnel-ip-allocator/ipam_block_reader_writer.go 189: Attempting to create a new block host="nc1" subnet=20.28.116.64/26
2021-01-12 09:12:55.221 [INFO][21] tunnel-ip-allocator/ipam_block_reader_writer.go 230: Successfully created block
2021-01-12 09:12:55.221 [INFO][21] tunnel-ip-allocator/ipam_block_reader_writer.go 241: Confirming affinity host="nc1" subnet=20.28.116.64/26
2021-01-12 09:12:55.225 [INFO][21] tunnel-ip-allocator/ipam_block_reader_writer.go 256: Successfully confirmed affinity host="nc1" subnet=20.28.116.64/26
2021-01-12 09:12:55.225 [INFO][21] tunnel-ip-allocator/ipam.go 524: Block '20.28.116.64/26' has 64 free ips which is more than 1 ips required. host="nc1" subnet=20.28.116.64/26
2021-01-12 09:12:55.225 [INFO][21] tunnel-ip-allocator/ipam.go 947: Attempting to assign 1 addresses from block block=20.28.116.64/26 handle="vxlan-tunnel-addr-nc1" host="nc1"
2021-01-12 09:12:55.228 [INFO][21] tunnel-ip-allocator/ipam.go 1424: Creating new handle: vxlan-tunnel-addr-nc1
2021-01-12 09:12:55.232 [INFO][21] tunnel-ip-allocator/ipam.go 970: Writing block in order to claim IPs block=20.28.116.64/26 handle="vxlan-tunnel-addr-nc1" host="nc1"
2021-01-12 09:12:55.239 [INFO][21] tunnel-ip-allocator/ipam.go 983: Successfully claimed IPs: [20.28.116.64/26] block=20.28.116.64/26 handle="vxlan-tunnel-addr-nc1" host="nc1"
2021-01-12 09:12:55.239 [INFO][21] tunnel-ip-allocator/ipam.go 706: Auto-assigned 1 out of 1 IPv4s: [20.28.116.64/26] handle="vxlan-tunnel-addr-nc1" host="nc1"
2021-01-12 09:12:55.252 [INFO][21] tunnel-ip-allocator/allocateip.go 441: Assigned tunnel address to node IP="20.28.116.64" type="vxlanTunnelAddress"
Calico node started successfully
bird: Unable to open configuration file /etc/calico/confd/config/bird6.cfg: No such file or directory
bird: Unable to open configuration file /etc/calico/confd/config/bird.cfg: No such file or directory
2021-01-12 09:12:56.345 [INFO][56] tunnel-ip-allocator/config.go 60: Found FELIX_TYPHAK8SSERVICENAME=calico-typha
2021-01-12 09:12:56.345 [INFO][56] tunnel-ip-allocator/config.go 60: Found FELIX_TYPHAK8SNAMESPACE=calico-system
2021-01-12 09:12:56.345 [INFO][56] tunnel-ip-allocator/config.go 60: Found FELIX_TYPHAKEYFILE=/felix-certs/key.key
2021-01-12 09:12:56.345 [INFO][56] tunnel-ip-allocator/config.go 60: Found FELIX_TYPHACERTFILE=/felix-certs/cert.crt
2021-01-12 09:12:56.345 [INFO][56] tunnel-ip-allocator/config.go 60: Found FELIX_TYPHACAFILE=/typha-ca/caBundle
2021-01-12 09:12:56.345 [INFO][56] tunnel-ip-allocator/config.go 60: Found FELIX_TYPHACN=typha-server
2021-01-12 09:12:56.354 [INFO][55] confd/config.go 60: Found FELIX_TYPHAK8SSERVICENAME=calico-typha
2021-01-12 09:12:56.354 [INFO][55] confd/config.go 60: Found FELIX_TYPHAK8SNAMESPACE=calico-system
2021-01-12 09:12:56.354 [INFO][55] confd/config.go 60: Found FELIX_TYPHAKEYFILE=/felix-certs/key.key
2021-01-12 09:12:56.354 [INFO][55] confd/config.go 60: Found FELIX_TYPHACERTFILE=/felix-certs/cert.crt
2021-01-12 09:12:56.354 [INFO][55] confd/config.go 60: Found FELIX_TYPHACAFILE=/typha-ca/caBundle
2021-01-12 09:12:56.355 [INFO][55] confd/config.go 60: Found FELIX_TYPHACN=typha-server
2021-01-12 09:12:56.355 [INFO][55] confd/config.go 81: Skipping confd config file.
2021-01-12 09:12:56.355 [INFO][55] confd/run.go 17: Starting calico-confd
2021-01-12 09:12:56.371 [INFO][57] monitor-addresses/startup.go 395: Using NODENAME environment for node name
2021-01-12 09:12:56.371 [INFO][57] monitor-addresses/startup.go 407: Determined node name: nc1
2021-01-12 09:12:56.378 [INFO][56] tunnel-ip-allocator/discovery.go 162: Found ready Typha addresses. addrs=[]string{"172.17.0.32:5473"}
2021-01-12 09:12:56.378 [INFO][56] tunnel-ip-allocator/discovery.go 165: Chose Typha to connect to. choice="172.17.0.32:5473"
2021-01-12 09:12:56.378 [INFO][56] tunnel-ip-allocator/startsyncerclient.go 56: Connecting to Typha. addr="172.17.0.32:5473"
2021-01-12 09:12:56.378 [INFO][56] tunnel-ip-allocator/sync_client.go 71: requiringTLS=true
2021-01-12 09:12:56.378 [INFO][56] tunnel-ip-allocator/sync_client.go 200: Starting Typha client
2021-01-12 09:12:56.378 [INFO][56] tunnel-ip-allocator/sync_client.go 71: requiringTLS=true
2021-01-12 09:12:56.379 [INFO][56] tunnel-ip-allocator/tlsutils.go 39: Make certificate verifier requiredCN="typha-server" requiredURISAN="" roots=&x509.CertPool{bySubjectKeyId:map[string][]int{"\xf7\x8bVN\x8f\xae\x8dQ\xe9ˤ\xa8y\x17\xf6\xe4\x1cA34":[]int{0}}, byName:map[string][]int{"0,1*0(\x06\x03U\x04\x03\f!tigera-operator-signer@1610442752":[]int{0}}, certs:[]*x509.Certificate{(*x509.Certificate)(0xc0005fb180)}}
2021-01-12 09:12:56.379 [INFO][56] tunnel-ip-allocator/sync_client.go 251: Connecting to Typha. address="172.17.0.32:5473" connID=0x0 type="tunnel-ip-allocation"
2021-01-12 09:12:56.385 [INFO][56] tunnel-ip-allocator/tlsutils.go 46: Verify certificate chain signing address="172.17.0.32:5473" connID=0x0 type="tunnel-ip-allocation"
W0112 09:12:56.386054 55 client_config.go:543] Neither --kubeconfig nor --master was specified. Using the inClusterConfig. This might not work.
2021-01-12 09:12:56.387 [INFO][55] confd/client.go 1168: Updated with new cluster IP CIDRs: []
2021-01-12 09:12:56.387 [INFO][55] confd/client.go 1159: Updated with new external IP CIDRs: []
2021-01-12 09:12:56.393 [INFO][56] tunnel-ip-allocator/sync_client.go 266: Connected to Typha. address="172.17.0.32:5473" connID=0x0 type="tunnel-ip-allocation"
2021-01-12 09:12:56.393 [INFO][56] tunnel-ip-allocator/sync_client.go 300: Started Typha client main loop address="172.17.0.32:5473" connID=0x0 type="tunnel-ip-allocation"
2021-01-12 09:12:56.394 [INFO][56] tunnel-ip-allocator/sync_client.go 357: Server hello message received address="172.17.0.32:5473" connID=0x0 serverVersion="v3.17.1" type="tunnel-ip-allocation"
2021-01-12 09:12:56.395 [INFO][56] tunnel-ip-allocator/sync_client.go 328: Status update from Typha. address="172.17.0.32:5473" connID=0x0 newStatus=in-sync type="tunnel-ip-allocation"
2021-01-12 09:12:56.396 [INFO][55] confd/discovery.go 162: Found ready Typha addresses. addrs=[]string{"172.17.0.32:5473"}
2021-01-12 09:12:56.396 [INFO][55] confd/discovery.go 165: Chose Typha to connect to. choice="172.17.0.32:5473"
2021-01-12 09:12:56.396 [INFO][55] confd/startsyncerclient.go 56: Connecting to Typha. addr="172.17.0.32:5473"
2021-01-12 09:12:56.396 [INFO][55] confd/sync_client.go 71: requiringTLS=true
2021-01-12 09:12:56.397 [INFO][55] confd/sync_client.go 200: Starting Typha client
2021-01-12 09:12:56.397 [INFO][55] confd/sync_client.go 71: requiringTLS=true
2021-01-12 09:12:56.397 [INFO][55] confd/tlsutils.go 39: Make certificate verifier requiredCN="typha-server" requiredURISAN="" roots=&x509.CertPool{bySubjectKeyId:map[string][]int{"\xf7\x8bVN\x8f\xae\x8dQ\xe9ˤ\xa8y\x17\xf6\xe4\x1cA34":[]int{0}}, byName:map[string][]int{"0,1*0(\x06\x03U\x04\x03\f!tigera-operator-signer@1610442752":[]int{0}}, certs:[]*x509.Certificate{(*x509.Certificate)(0xc000670580)}}
2021-01-12 09:12:56.398 [INFO][55] confd/sync_client.go 251: Connecting to Typha. address="172.17.0.32:5473" connID=0x0 type="bgp"
2021-01-12 09:12:56.403 [INFO][55] confd/tlsutils.go 46: Verify certificate chain signing address="172.17.0.32:5473" connID=0x0 type="bgp"
2021-01-12 09:12:56.408 [INFO][56] tunnel-ip-allocator/ipam.go 1325: Releasing all IPs with handle 'wireguard-tunnel-addr-nc1'
2021-01-12 09:12:56.412 [INFO][55] confd/sync_client.go 266: Connected to Typha. address="172.17.0.32:5473" connID=0x0 type="bgp"
2021-01-12 09:12:56.412 [INFO][55] confd/client.go 351: Source SourceRouteGenerator readiness changed, ready=true
2021-01-12 09:12:56.412 [INFO][55] confd/sync_client.go 300: Started Typha client main loop address="172.17.0.32:5473" connID=0x0 type="bgp"
2021-01-12 09:12:56.414 [INFO][55] confd/sync_client.go 357: Server hello message received address="172.17.0.32:5473" connID=0x0 serverVersion="v3.17.1" type="bgp"
2021-01-12 09:12:56.415 [INFO][55] confd/sync_client.go 328: Status update from Typha. address="172.17.0.32:5473" connID=0x0 newStatus=in-sync type="bgp"
2021-01-12 09:12:56.416 [INFO][55] confd/client.go 877: Recompute BGP peerings: HostBGPConfig(node=nc1; name=ip_addr_v4) updated; HostBGPConfig(node=nc1; name=ip_addr_v6) updated; HostBGPConfig(node=nc1; name=network_v4) updated; HostBGPConfig(node=nc1; name=rr_cluster_id) updated; nc1 updated
2021-01-12 09:12:56.416 [INFO][55] confd/client.go 351: Source SourceSyncer readiness changed, ready=true
2021-01-12 09:12:56.416 [INFO][55] confd/client.go 371: Data is now syncd, can start rendering templates
2021-01-12 09:12:56.429 [INFO][55] confd/resource.go 277: Target config /etc/calico/confd/config/bird_aggr.cfg has been updated
2021-01-12 09:12:56.429 [INFO][56] tunnel-ip-allocator/ipam.go 1325: Releasing all IPs with handle 'ipip-tunnel-addr-nc1'
2021-01-12 09:12:56.430 [INFO][55] confd/resource.go 277: Target config /etc/calico/confd/config/bird_ipam.cfg has been updated
2021-01-12 09:12:56.431 [INFO][55] confd/resource.go 277: Target config /etc/calico/confd/config/bird6_aggr.cfg has been updated
2021-01-12 09:12:56.433 [INFO][55] confd/resource.go 277: Target config /etc/calico/confd/config/bird6_ipam.cfg has been updated
2021-01-12 09:12:56.436 [INFO][55] confd/resource.go 277: Target config /etc/calico/confd/config/bird.cfg has been updated
2021-01-12 09:12:56.437 [INFO][55] confd/resource.go 277: Target config /etc/calico/confd/config/bird6.cfg has been updated
2021-01-12 09:12:56.450 [INFO][60] felix/daemon.go 357: Successfully loaded configuration. GOMAXPROCS=8 builddate="345226a3c1809f6981fd279d81c475d53f894caf" config=&config.Config{UseInternalDataplaneDriver:true, DataplaneDriver:"calico-iptables-plugin", WireguardEnabled:false, WireguardListeningPort:51820, WireguardRoutingRulePriority:99, WireguardInterfaceName:"wireguard.cali", WireguardMTU:0, BPFEnabled:false, BPFDisableUnprivileged:true, BPFLogLevel:"off", BPFDataIfacePattern:(*regexp.Regexp)(0xc0005c1900), BPFConnectTimeLoadBalancingEnabled:true, BPFExternalServiceMode:"tunnel", BPFKubeProxyIptablesCleanupEnabled:true, BPFKubeProxyMinSyncPeriod:1000000000, BPFKubeProxyEndpointSlicesEnabled:false, DebugBPFCgroupV2:"", DebugBPFMapRepinEnabled:true, DatastoreType:"kubernetes", FelixHostname:"nc1", EtcdAddr:"127.0.0.1:2379", EtcdScheme:"http", EtcdKeyFile:"", EtcdCertFile:"", EtcdCaFile:"", EtcdEndpoints:[]string(nil), TyphaAddr:"", TyphaK8sServiceName:"calico-typha", TyphaK8sNamespace:"calico-system", TyphaReadTimeout:30000000000, TyphaWriteTimeout:10000000000, TyphaKeyFile:"/felix-certs/key.key", TyphaCertFile:"/felix-certs/cert.crt", TyphaCAFile:"/typha-ca/caBundle", TyphaCN:"typha-server", TyphaURISAN:"", Ipv6Support:false, IptablesBackend:"auto", RouteRefreshInterval:90000000000, InterfaceRefreshInterval:90000000000, DeviceRouteSourceAddress:net.IP(nil), DeviceRouteProtocol:3, RemoveExternalRoutes:true, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckIntervalSecs:1000000000, IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeoutSecs:0, IptablesLockProbeIntervalMillis:50000000, FeatureDetectOverride:map[string]string(nil), IpsetsRefreshInterval:10000000000, MaxIpsetSize:1048576, XDPRefreshInterval:90000000000, PolicySyncPathPrefix:"", NetlinkTimeoutSecs:10000000000, MetadataAddr:"", MetadataPort:8775, OpenstackRegion:"", InterfacePrefix:"cali", InterfaceExclude:[]*regexp.Regexp{(*regexp.Regexp)(0xc0005c1b80)}, ChainInsertMode:"insert", DefaultEndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", LogPrefix:"calico-packet", LogFilePath:"", LogSeverityFile:"", LogSeverityScreen:"INFO", LogSeveritySys:"", VXLANEnabled:true, VXLANPort:4789, VXLANVNI:4096, VXLANMTU:0, IPv4VXLANTunnelAddr:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0x14, 0x1c, 0x74, 0x40}, VXLANTunnelMACAddr:"", IpInIpEnabled:false, IpInIpMtu:0, IpInIpTunnelAddr:net.IP(nil), AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, AWSSrcDstCheck:"DoNothing", ServiceLoopPrevention:"Drop", ReportingIntervalSecs:0, ReportingTTLSecs:90000000000, EndpointReportingEnabled:false, EndpointReportingDelaySecs:1000000000, IptablesMarkMask:0xffff0000, DisableConntrackInvalidCheck:false, HealthEnabled:true, HealthPort:9099, HealthHost:"localhost", PrometheusMetricsEnabled:false, PrometheusMetricsHost:"", PrometheusMetricsPort:9091, PrometheusGoMetricsEnabled:true, PrometheusProcessMetricsEnabled:true, FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"tcp", Port:0x16}, config.ProtoPort{Protocol:"udp", Port:0x44}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1561}, config.ProtoPort{Protocol:"tcp", Port:0x192b}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"udp", Port:0x35}, config.ProtoPort{Protocol:"udp", Port:0x43}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1561}, config.ProtoPort{Protocol:"tcp", Port:0x192b}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, NATOutgoingAddress:net.IP(nil), UsageReportingEnabled:true, UsageReportingInitialDelaySecs:300000000000, UsageReportingIntervalSecs:86400000000000, ClusterGUID:"6c70b42e3f974469ac2efe1bfa298127", ClusterType:"typha,kdd,k8s,operator,bgp", CalicoVersion:"v3.17.1", ExternalNodesCIDRList:[]string(nil), DebugMemoryProfilePath:"", DebugCPUProfilePath:"/tmp/felix-cpu-<timestamp>.pprof", DebugDisableLogDropping:false, DebugSimulateCalcGraphHangAfter:0, DebugSimulateDataplaneHangAfter:0, DebugPanicAfter:0, DebugSimulateDataRace:false, RouteSource:"CalicoIPAM", RouteTableRange:idalloc.IndexRange{Min:1, Max:250}, IptablesNATOutgoingInterfaceFilter:"", SidecarAccelerationEnabled:false, XDPEnabled:true, GenericXDPEnabled:false, Variant:"Calico", MTUIfacePattern:(*regexp.Regexp)(0xc0005c1f40), internalOverrides:map[string]string{}, sourceToRawConfig:map[config.Source]map[string]string{0x1:map[string]string{"CalicoVersion":"v3.17.1", "ClusterGUID":"6c70b42e3f974469ac2efe1bfa298127", "ClusterType":"typha,kdd,k8s,operator,bgp", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0", "VXLANEnabled":"true"}, 0x2:map[string]string{"IPv4VXLANTunnelAddr":"20.28.116.64"}, 0x3:map[string]string{"LogFilePath":"None", "LogSeverityFile":"None", "LogSeveritySys":"None", "MetadataAddr":"None"}, 0x4:map[string]string{"datastoretype":"kubernetes", "defaultendpointtohostaction":"ACCEPT", "felixhostname":"nc1", "healthenabled":"true", "iptablesbackend":"auto", "ipv6support":"false", "typhacafile":"/typha-ca/caBundle", "typhacertfile":"/felix-certs/cert.crt", "typhacn":"typha-server", "typhak8snamespace":"calico-system", "typhak8sservicename":"calico-typha", "typhakeyfile":"/felix-certs/key.key"}}, rawValues:map[string]string{"CalicoVersion":"v3.17.1", "ClusterGUID":"6c70b42e3f974469ac2efe1bfa298127", "ClusterType":"typha,kdd,k8s,operator,bgp", "DatastoreType":"kubernetes", "DefaultEndpointToHostAction":"ACCEPT", "FelixHostname":"nc1", "HealthEnabled":"true", "IPv4VXLANTunnelAddr":"20.28.116.64", "IptablesBackend":"auto", "Ipv6Support":"false", "LogFilePath":"None", "LogSeverityFile":"None", "LogSeverityScreen":"Info", "LogSeveritySys":"None", "MetadataAddr":"None", "ReportingIntervalSecs":"0", "TyphaCAFile":"/typha-ca/caBundle", "TyphaCN":"typha-server", "TyphaCertFile":"/felix-certs/cert.crt", "TyphaK8sNamespace":"calico-system", "TyphaK8sServiceName":"calico-typha", "TyphaKeyFile":"/felix-certs/key.key", "VXLANEnabled":"true"}, Err:error(nil), loadClientConfigFromEnvironment:(func() (*apiconfig.CalicoAPIConfig, error))(0x12f9640), useNodeResourceUpdates:false} gitcommit="2020-12-10T23:50:58+0000" version="v3.17.1"
2021-01-12 09:12:56.451 [INFO][60] felix/driver.go 59: Using internal (linux) dataplane driver.
2021-01-12 09:12:56.451 [INFO][60] felix/driver.go 147: Calculated iptables mark bits acceptMark=0x10000 endpointMark=0xfff00000 endpointMarkNonCali=0x0 passMark=0x20000 scratch0Mark=0x40000 scratch1Mark=0x80000
2021-01-12 09:12:56.452 [INFO][56] tunnel-ip-allocator/allocateip.go 291: Current address is still valid, do nothing currentAddr="20.28.116.64" type="vxlanTunnelAddress"
2021-01-12 09:12:56.451 [INFO][60] felix/int_dataplane.go 282: Creating internal dataplane driver. config=intdataplane.Config{Hostname:"nc1", IPv6Enabled:false, RuleRendererOverride:rules.RuleRenderer(nil), IPIPMTU:0, VXLANMTU:0, MaxIPSetSize:1048576, IptablesBackend:"auto", IPSetsRefreshInterval:10000000000, RouteRefreshInterval:90000000000, DeviceRouteSourceAddress:net.IP(nil), DeviceRouteProtocol:3, RemoveExternalRoutes:true, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckInterval:1000000000, IptablesInsertMode:"insert", IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeout:0, IptablesLockProbeInterval:50000000, XDPRefreshInterval:90000000000, Wireguard:wireguard.Config{Enabled:false, ListeningPort:51820, FirewallMark:0, RoutingRulePriority:99, RoutingTableIndex:1, InterfaceName:"wireguard.cali", MTU:0}, NetlinkTimeout:10000000000, RulesConfig:rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc0006d4690), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc0006d4780), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x0, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:true, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:false, IPIPTunnelAddress:net.IP(nil), VXLANTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0x14, 0x1c, 0x74, 0x40}, AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, WireguardEnabled:false, WireguardInterfaceName:"wireguard.cali", IptablesLogPrefix:"calico-packet", EndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"tcp", Port:0x16}, config.ProtoPort{Protocol:"udp", Port:0x44}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1561}, config.ProtoPort{Protocol:"tcp", Port:0x192b}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"udp", Port:0x35}, config.ProtoPort{Protocol:"udp", Port:0x43}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1561}, config.ProtoPort{Protocol:"tcp", Port:0x192b}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil), BPFEnabled:false, ServiceLoopPrevention:"Drop"}, IfaceMonitorConfig:ifacemonitor.Config{InterfaceExcludes:[]*regexp.Regexp{(*regexp.Regexp)(0xc0005c1b80)}, ResyncInterval:90000000000}, StatusReportingInterval:0, ConfigChangedRestartCallback:(func())(0x1e17000), PostInSyncCallback:(func())(0x1dfe400), HealthAggregator:(*health.HealthAggregator)(0xc00020cb70), RouteTableManager:(*idalloc.IndexAllocator)(0xc000010fb8), DebugSimulateDataplaneHangAfter:0, ExternalNodesCidrs:[]string(nil), BPFEnabled:false, BPFDisableUnprivileged:true, BPFKubeProxyIptablesCleanupEnabled:true, BPFLogLevel:"off", BPFDataIfacePattern:(*regexp.Regexp)(0xc0005c1900), XDPEnabled:true, XDPAllowGeneric:false, BPFConntrackTimeouts:conntrack.Timeouts{CreationGracePeriod:10000000000, TCPPreEstablished:20000000000, TCPEstablished:3600000000000, TCPFinsSeen:30000000000, TCPResetSeen:40000000000, UDPLastSeen:60000000000, ICMPLastSeen:5000000000}, BPFCgroupV2:"", BPFConnTimeLBEnabled:true, BPFMapRepin:true, BPFNodePortDSREnabled:false, KubeProxyMinSyncPeriod:1000000000, KubeProxyEndpointSlicesEnabled:false, SidecarAccelerationEnabled:false, LookPathOverride:(func(string) (string, error))(nil), KubeClientSet:(*kubernetes.Clientset)(0xc000159080), FeatureDetectOverrides:map[string]string(nil), hostMTU:0, MTUIfacePattern:(*regexp.Regexp)(0xc0005c1f40)}
2021-01-12 09:12:56.452 [INFO][60] felix/rule_defs.go 338: Creating rule renderer. config=rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc0006d4690), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc0006d4780), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x0, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:true, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:false, IPIPTunnelAddress:net.IP(nil), VXLANTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0x14, 0x1c, 0x74, 0x40}, AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, WireguardEnabled:false, WireguardInterfaceName:"wireguard.cali", IptablesLogPrefix:"calico-packet", EndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"tcp", Port:0x16}, config.ProtoPort{Protocol:"udp", Port:0x44}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1561}, config.ProtoPort{Protocol:"tcp", Port:0x192b}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"udp", Port:0x35}, config.ProtoPort{Protocol:"udp", Port:0x43}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1561}, config.ProtoPort{Protocol:"tcp", Port:0x192b}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil), BPFEnabled:false, ServiceLoopPrevention:"Drop"}
2021-01-12 09:12:56.452 [INFO][60] felix/rule_defs.go 348: Workload to host packets will be accepted.
2021-01-12 09:12:56.452 [INFO][60] felix/rule_defs.go 362: filter table allowed packets will be accepted immediately.
2021-01-12 09:12:56.452 [INFO][60] felix/rule_defs.go 370: mangle table allowed packets will be accepted immediately.
2021-01-12 09:12:56.452 [INFO][60] felix/rule_defs.go 378: Packets to unknown service IPs will be dropped
2021-01-12 09:12:56.454 [INFO][60] felix/int_dataplane.go 868: Determined pod MTU mtu=1450
2021-01-12 09:12:56.454 [INFO][60] felix/iface_monitor.go 72: configured to periodically rescan interfaces. interval=1m30s
2021-01-12 09:12:56.454 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"ip6tables-legacy-save", "ip6tables-save"} command="ip6tables-legacy-save" ipVersion=0x6 saveOrRestore="save"
2021-01-12 09:12:56.454 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-01-12 09:12:56.462 [INFO][60] felix/feature_detect.go 137: Updating detected iptables features features=iptables.Features{SNATFullyRandom:true, MASQFullyRandom:true, RestoreSupportsLock:true} iptablesVersion=1.8.2 kernelVersion=4.19.78
2021-01-12 09:12:56.463 [INFO][60] felix/table.go 329: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2021-01-12 09:12:56.463 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2021-01-12 09:12:56.463 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-01-12 09:12:56.463 [INFO][60] felix/table.go 329: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-|-A POSTROUTING .* felix-masq-ipam-pools .*|-A POSTROUTING -o tunl0 -m addrtype ! --src-type LOCAL --limit-iface-out -m addrtype --src-type LOCAL -j MASQUERADE"
2021-01-12 09:12:56.463 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2021-01-12 09:12:56.463 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-01-12 09:12:56.463 [INFO][60] felix/table.go 329: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2021-01-12 09:12:56.463 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2021-01-12 09:12:56.464 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-01-12 09:12:56.464 [INFO][60] felix/table.go 329: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2021-01-12 09:12:56.464 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2021-01-12 09:12:56.464 [INFO][60] felix/feature_detect.go 259: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-01-12 09:12:56.464 [INFO][60] felix/route_table.go 241: Calculated interface name regexp regex="^vxlan.calico$"
2021-01-12 09:12:56.464 [INFO][60] felix/vxlan_mgr.go 340: VXLAN tunnel device thread started. mtu=1450
2021-01-12 09:12:56.464 [WARNING][60] felix/int_dataplane.go 448: Can't enable XDP acceleration. error=/sys/fs/bpf is not mounted
2021-01-12 09:12:56.465 [INFO][60] felix/connecttime.go 46: Running bpftool to look up programs attached to cgroup args=[]string{"bpftool", "-j", "-p", "cgroup", "show", "/run/calico/cgroup"}
2021-01-12 09:12:56.467 [INFO][60] felix/connecttime.go 49: Failed to list BPF programs. Assuming not supported/nothing to clean up. error=exit status 255 output="[]\n"
2021-01-12 09:12:56.467 [INFO][60] felix/int_dataplane.go 517: Failed to remove BPF connect-time load balancer, ignoring. error=exit status 255
2021-01-12 09:12:56.468 [INFO][60] felix/cleanup.go 37: Failed to list BPF maps, assuming there's nothing to clean up. error=exit status 255
2021-01-12 09:12:56.468 [INFO][60] felix/route_table.go 241: Calculated interface name regexp regex="^cali.*"
2021-01-12 09:12:56.468 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="all-ipam-pools" setType="hash:net"
2021-01-12 09:12:56.468 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="masq-ipam-pools" setType="hash:net"
2021-01-12 09:12:56.468 [INFO][60] felix/route_table.go 241: Calculated interface name regexp regex="^wireguard.cali$"
2021-01-12 09:12:56.468 [INFO][60] felix/int_dataplane.go 772: Registering to report health.
2021-01-12 09:12:56.470 [INFO][60] felix/int_dataplane.go 1500: attempted to modprobe nf_conntrack_proto_sctp error=exit status 1 output=""
2021-01-12 09:12:56.470 [INFO][60] felix/int_dataplane.go 1502: Making sure IPv4 forwarding is enabled.
2021-01-12 09:12:56.472 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="raw"
2021-01-12 09:12:56.472 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="raw"
2021-01-12 09:12:56.472 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="raw"
2021-01-12 09:12:56.472 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2021-01-12 09:12:56.473 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="raw"
2021-01-12 09:12:56.473 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2021-01-12 09:12:56.473 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2021-01-12 09:12:56.473 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2021-01-12 09:12:56.473 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-FORWARD" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.473 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-from-hep-forward"
2021-01-12 09:12:56.473 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-from-wl-dispatch"
2021-01-12 09:12:56.473 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-to-wl-dispatch"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-to-hep-forward"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-cidr-block"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-INPUT" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-wl-to-host"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-wl-to-host" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-FORWARD"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-INPUT"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="nat"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-fip-dnat"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="nat"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-fip-snat"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-nat-outgoing"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="nat"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-POSTROUTING"
2021-01-12 09:12:56.474 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2021-01-12 09:12:56.475 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="mangle"
2021-01-12 09:12:56.475 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="mangle"
2021-01-12 09:12:56.475 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2021-01-12 09:12:56.475 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2021-01-12 09:12:56.475 [INFO][60] felix/int_dataplane.go 1033: IPIP disabled. Not starting tunnel update thread.
2021-01-12 09:12:56.475 [INFO][60] felix/int_dataplane.go 1279: Started internal iptables dataplane driver loop
2021-01-12 09:12:56.475 [INFO][60] felix/daemon.go 385: Connect to the dataplane driver.
2021-01-12 09:12:56.475 [INFO][60] felix/iface_monitor.go 97: Interface monitoring thread started.
2021-01-12 09:12:56.475 [INFO][60] felix/int_dataplane.go 1289: Will refresh IP sets on timer interval=1m30s
2021-01-12 09:12:56.475 [INFO][60] felix/int_dataplane.go 1715: Started internal status report thread
2021-01-12 09:12:56.475 [INFO][60] felix/iface_monitor.go 107: Subscribed to netlink updates.
2021-01-12 09:12:56.475 [INFO][60] felix/int_dataplane.go 1299: Will refresh routes on timer interval=1m30s
2021-01-12 09:12:56.475 [INFO][60] felix/int_dataplane.go 1717: Process status reports disabled
2021-01-12 09:12:56.475 [INFO][60] felix/daemon.go 441: Connecting to Typha. addr="172.17.0.32:5473"
2021-01-12 09:12:56.475 [INFO][60] felix/sync_client.go 71: requiringTLS=true
2021-01-12 09:12:56.475 [INFO][60] felix/daemon.go 466: Created Syncer syncer=<nil>
2021-01-12 09:12:56.475 [INFO][60] felix/daemon.go 473: Starting the Typha connection
2021-01-12 09:12:56.475 [INFO][60] felix/sync_client.go 200: Starting Typha client
2021-01-12 09:12:56.475 [INFO][60] felix/sync_client.go 71: requiringTLS=true
2021-01-12 09:12:56.476 [INFO][60] felix/tlsutils.go 39: Make certificate verifier requiredCN="typha-server" requiredURISAN="" roots=&x509.CertPool{bySubjectKeyId:map[string][]int{"\xf7\x8bVN\x8f\xae\x8dQ\xe9ˤ\xa8y\x17\xf6\xe4\x1cA34":[]int{0}}, byName:map[string][]int{"0,1*0(\x06\x03U\x04\x03\f!tigera-operator-signer@1610442752":[]int{0}}, certs:[]*x509.Certificate{(*x509.Certificate)(0xc0007d0680)}}
2021-01-12 09:12:56.476 [INFO][60] felix/sync_client.go 251: Connecting to Typha. address="172.17.0.32:5473" connID=0x0 type=""
2021-01-12 09:12:56.478 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=1 ifaceName="lo" state="up"
2021-01-12 09:12:56.478 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"lo", State:"up", Index:1}
2021-01-12 09:12:56.480 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}} ifaceName="lo"
2021-01-12 09:12:56.481 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=2 ifaceName="eth0" state="up"
2021-01-12 09:12:56.481 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}}}
2021-01-12 09:12:56.481 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}}}
2021-01-12 09:12:56.481 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.481 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth0", State:"up", Index:2}
2021-01-12 09:12:56.482 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth0"
2021-01-12 09:12:56.482 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=3 ifaceName="eth1" state="up"
2021-01-12 09:12:56.482 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth0", Addrs:set.mapSet{}}
2021-01-12 09:12:56.482 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth0", Addrs:set.mapSet{}}
2021-01-12 09:12:56.482 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.482 [INFO][60] felix/tlsutils.go 46: Verify certificate chain signing address="172.17.0.32:5473" connID=0x0 type=""
2021-01-12 09:12:56.482 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1", State:"up", Index:3}
2021-01-12 09:12:56.483 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1"
2021-01-12 09:12:56.483 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=4 ifaceName="bond0" state="up"
2021-01-12 09:12:56.484 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1", Addrs:set.mapSet{}}
2021-01-12 09:12:56.484 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1", Addrs:set.mapSet{}}
2021-01-12 09:12:56.484 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.485 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"bond0", State:"up", Index:4}
2021-01-12 09:12:56.485 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.17.0.16":set.empty{}, "172.17.0.20":set.empty{}, "172.17.0.32":set.empty{}} ifaceName="bond0"
2021-01-12 09:12:56.485 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=5 ifaceName="brGXData" state="up"
2021-01-12 09:12:56.486 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"bond0", Addrs:set.mapSet{"172.17.0.16":set.empty{}, "172.17.0.20":set.empty{}, "172.17.0.32":set.empty{}}}
2021-01-12 09:12:56.486 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"bond0", Addrs:set.mapSet{"172.17.0.16":set.empty{}, "172.17.0.20":set.empty{}, "172.17.0.32":set.empty{}}}
2021-01-12 09:12:56.486 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.486 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"brGXData", State:"up", Index:5}
2021-01-12 09:12:56.487 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="brGXData"
2021-01-12 09:12:56.487 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=6 ifaceName="eth1.31" state="up"
2021-01-12 09:12:56.488 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"brGXData", Addrs:set.mapSet{}}
2021-01-12 09:12:56.488 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"brGXData", Addrs:set.mapSet{}}
2021-01-12 09:12:56.488 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.488 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.31", State:"up", Index:6}
2021-01-12 09:12:56.488 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.31"
2021-01-12 09:12:56.488 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=7 ifaceName="brGXMgmt" state="up"
2021-01-12 09:12:56.488 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.31", Addrs:set.mapSet{}}
2021-01-12 09:12:56.488 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.31", Addrs:set.mapSet{}}
2021-01-12 09:12:56.489 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.489 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"brGXMgmt", State:"up", Index:7}
2021-01-12 09:12:56.489 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.29.55.10":set.empty{}} ifaceName="brGXMgmt"
2021-01-12 09:12:56.490 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=8 ifaceName="eth1.400" state="up"
2021-01-12 09:12:56.490 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"brGXMgmt", Addrs:set.mapSet{"172.29.55.10":set.empty{}}}
2021-01-12 09:12:56.490 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"brGXMgmt", Addrs:set.mapSet{"172.29.55.10":set.empty{}}}
2021-01-12 09:12:56.490 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.490 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.400", State:"up", Index:8}
2021-01-12 09:12:56.491 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.400"
2021-01-12 09:12:56.491 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=9 ifaceName="br3_400" state="up"
2021-01-12 09:12:56.492 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.400", Addrs:set.mapSet{}}
2021-01-12 09:12:56.492 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.400", Addrs:set.mapSet{}}
2021-01-12 09:12:56.493 [INFO][60] felix/sync_client.go 266: Connected to Typha. address="172.17.0.32:5473" connID=0x0 type=""
2021-01-12 09:12:56.493 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.493 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_400", State:"up", Index:9}
2021-01-12 09:12:56.493 [INFO][60] felix/sync_client.go 300: Started Typha client main loop address="172.17.0.32:5473" connID=0x0 type=""
2021-01-12 09:12:56.493 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.27.0.11":set.empty{}} ifaceName="br3_400"
2021-01-12 09:12:56.493 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=10 ifaceName="eth1.4086" state="up"
2021-01-12 09:12:56.493 [INFO][60] felix/sync_client.go 357: Server hello message received address="172.17.0.32:5473" connID=0x0 serverVersion="v3.17.1" type=""
2021-01-12 09:12:56.493 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_400", Addrs:set.mapSet{"172.27.0.11":set.empty{}}}
2021-01-12 09:12:56.494 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_400", Addrs:set.mapSet{"172.27.0.11":set.empty{}}}
2021-01-12 09:12:56.494 [INFO][60] felix/calc_graph.go 114: Creating calculation graph, filtered to hostname nc1
2021-01-12 09:12:56.494 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.494 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1761f20)
2021-01-12 09:12:56.494 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1761f20)
2021-01-12 09:12:56.494 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4086", State:"up", Index:10}
2021-01-12 09:12:56.494 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1762020)
2021-01-12 09:12:56.494 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1762020)
2021-01-12 09:12:56.495 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1761dc0)
2021-01-12 09:12:56.495 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1761dc0)
2021-01-12 09:12:56.495 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x1761dc0)
2021-01-12 09:12:56.495 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ProfileRulesKey: (dispatcher.UpdateHandler)(0x1761dc0)
2021-01-12 09:12:56.495 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x1761dc0)
2021-01-12 09:12:56.495 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.4086"
2021-01-12 09:12:56.495 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x1761dc0)
2021-01-12 09:12:56.495 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=11 ifaceName="br3_4086" state="up"
2021-01-12 09:12:56.495 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x16a1fe0)
2021-01-12 09:12:56.495 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x16a1fe0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x16a1fe0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x16a1fe0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.NetworkSetKey: (dispatcher.UpdateHandler)(0x16a1fe0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x17625e0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x17625e0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x17625e0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x17621c0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.IPPoolKey: (dispatcher.UpdateHandler)(0x17621c0)
2021-01-12 09:12:56.496 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.WireguardKey: (dispatcher.UpdateHandler)(0x17621c0)
2021-01-12 09:12:56.497 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ResourceKey: (dispatcher.UpdateHandler)(0x17621c0)
2021-01-12 09:12:56.497 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.19.241.17":set.empty{}} ifaceName="br3_4086"
2021-01-12 09:12:56.497 [INFO][60] felix/l3_route_resolver.go 142: Creating L3 route resolver
2021-01-12 09:12:56.497 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=12 ifaceName="eth1.4087" state="up"
2021-01-12 09:12:56.497 [INFO][60] felix/l3_route_resolver.go 161: Registering L3 route resolver (node resources on)
2021-01-12 09:12:56.497 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4086", Addrs:set.mapSet{}}
2021-01-12 09:12:56.497 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4086", Addrs:set.mapSet{}}
2021-01-12 09:12:56.497 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ResourceKey: (dispatcher.UpdateHandler)(0x17622c0)
2021-01-12 09:12:56.497 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.IPPoolKey: (dispatcher.UpdateHandler)(0x1762400)
2021-01-12 09:12:56.497 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.497 [INFO][60] felix/l3_route_resolver.go 172: Registering for L3 route updates routeSource="CalicoIPAM"
2021-01-12 09:12:56.498 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.BlockKey: (dispatcher.UpdateHandler)(0x1762540)
2021-01-12 09:12:56.498 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x17624a0)
2021-01-12 09:12:56.497 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_4086", Addrs:set.mapSet{"172.19.241.17":set.empty{}}}
2021-01-12 09:12:56.498 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ResourceKey: (dispatcher.UpdateHandler)(0x1762960)
2021-01-12 09:12:56.498 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_4086", Addrs:set.mapSet{"172.19.241.17":set.empty{}}}
2021-01-12 09:12:56.498 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.498 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x1762aa0)
2021-01-12 09:12:56.498 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_4086", State:"up", Index:11}
2021-01-12 09:12:56.498 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.GlobalConfigKey: (dispatcher.UpdateHandler)(0x17620c0)
2021-01-12 09:12:56.498 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4087", State:"up", Index:12}
2021-01-12 09:12:56.498 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x17620c0)
2021-01-12 09:12:56.499 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ReadyFlagKey: (dispatcher.UpdateHandler)(0x17620c0)
2021-01-12 09:12:56.499 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.4087"
2021-01-12 09:12:56.499 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x1762760)
2021-01-12 09:12:56.499 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=13 ifaceName="br3_4087" state="up"
2021-01-12 09:12:56.499 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x1762800)
2021-01-12 09:12:56.499 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1762800)
2021-01-12 09:12:56.499 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1762800)
2021-01-12 09:12:56.499 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4087", Addrs:set.mapSet{}}
2021-01-12 09:12:56.500 [INFO][60] felix/dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x1762800)
2021-01-12 09:12:56.500 [INFO][60] felix/async_calc_graph.go 255: Starting AsyncCalcGraph
2021-01-12 09:12:56.500 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4087", Addrs:set.mapSet{}}
2021-01-12 09:12:56.500 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.500 [INFO][60] felix/daemon.go 577: Started the processing graph
2021-01-12 09:12:56.500 [INFO][60] felix/async_calc_graph.go 137: AsyncCalcGraph running
2021-01-12 09:12:56.500 [INFO][60] felix/daemon.go 955: Reading from dataplane driver pipe...
2021-01-12 09:12:56.500 [INFO][60] felix/daemon.go 674: No driver process to monitor
2021-01-12 09:12:56.500 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.19.241.1":set.empty{}} ifaceName="br3_4087"
2021-01-12 09:12:56.500 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ConfigUpdate update from calculation graph msg=config:<key:"CalicoVersion" value:"v3.17.1" > config:<key:"ClusterGUID" value:"6c70b42e3f974469ac2efe1bfa298127" > config:<key:"ClusterType" value:"typha,kdd,k8s,operator,bgp" > config:<key:"DatastoreType" value:"kubernetes" > config:<key:"DefaultEndpointToHostAction" value:"ACCEPT" > config:<key:"FelixHostname" value:"nc1" > config:<key:"HealthEnabled" value:"true" > config:<key:"IPv4VXLANTunnelAddr" value:"20.28.116.64" > config:<key:"IptablesBackend" value:"auto" > config:<key:"Ipv6Support" value:"false" > config:<key:"LogFilePath" value:"None" > config:<key:"LogSeverityFile" value:"None" > config:<key:"LogSeverityScreen" value:"Info" > config:<key:"LogSeveritySys" value:"None" > config:<key:"MetadataAddr" value:"None" > config:<key:"ReportingIntervalSecs" value:"0" > config:<key:"TyphaCAFile" value:"/typha-ca/caBundle" > config:<key:"TyphaCN" value:"typha-server" > config:<key:"TyphaCertFile" value:"/felix-certs/cert.crt" > config:<key:"TyphaK8sNamespace" value:"calico-system" > config:<key:"TyphaK8sServiceName" value:"calico-typha" > config:<key:"TyphaKeyFile" value:"/felix-certs/key.key" > config:<key:"VXLANEnabled" value:"true" >
2021-01-12 09:12:56.501 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=14 ifaceName="eth1.4088" state="up"
2021-01-12 09:12:56.501 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_4087", State:"up", Index:13}
2021-01-12 09:12:56.501 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4088", State:"up", Index:14}
2021-01-12 09:12:56.501 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_4087", Addrs:set.mapSet{"172.19.241.1":set.empty{}}}
2021-01-12 09:12:56.501 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_4087", Addrs:set.mapSet{"172.19.241.1":set.empty{}}}
2021-01-12 09:12:56.502 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.502 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.4088"
2021-01-12 09:12:56.502 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=15 ifaceName="br3_4088" state="up"
2021-01-12 09:12:56.503 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4088", Addrs:set.mapSet{}}
2021-01-12 09:12:56.504 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4088", Addrs:set.mapSet{}}
2021-01-12 09:12:56.504 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.504 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_4088", State:"up", Index:15}
2021-01-12 09:12:56.505 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.19.254.1":set.empty{}} ifaceName="br3_4088"
2021-01-12 09:12:56.505 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=16 ifaceName="eth1.4089" state="up"
2021-01-12 09:12:56.505 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_4088", Addrs:set.mapSet{"172.19.254.1":set.empty{}}}
2021-01-12 09:12:56.505 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_4088", Addrs:set.mapSet{"172.19.254.1":set.empty{}}}
2021-01-12 09:12:56.505 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.505 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4089", State:"up", Index:16}
2021-01-12 09:12:56.506 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.4089"
2021-01-12 09:12:56.506 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=17 ifaceName="br3_4089" state="up"
2021-01-12 09:12:56.506 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4089", Addrs:set.mapSet{}}
2021-01-12 09:12:56.506 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4089", Addrs:set.mapSet{}}
2021-01-12 09:12:56.506 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.506 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_4089", State:"up", Index:17}
2021-01-12 09:12:56.508 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.19.253.1":set.empty{}} ifaceName="br3_4089"
2021-01-12 09:12:56.508 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=18 ifaceName="eth1.4090" state="up"
2021-01-12 09:12:56.509 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_4089", Addrs:set.mapSet{"172.19.253.1":set.empty{}}}
2021-01-12 09:12:56.509 [INFO][60] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=LogSeverityScreen) Info 1140 <nil> 0s} 1}
2021-01-12 09:12:56.509 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_4089", Addrs:set.mapSet{"172.19.253.1":set.empty{}}}
2021-01-12 09:12:56.509 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.4090"
2021-01-12 09:12:56.510 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.510 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=19 ifaceName="br3_4090" state="up"
2021-01-12 09:12:56.510 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4090", Addrs:set.mapSet{}}
2021-01-12 09:12:56.510 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4090", Addrs:set.mapSet{}}
2021-01-12 09:12:56.510 [INFO][60] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterGUID) 6c70b42e3f974469ac2efe1bfa298127 1099 <nil> 0s} 1}
2021-01-12 09:12:56.510 [INFO][60] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterType) typha,kdd,k8s,operator,bgp 1139 <nil> 0s} 1}
2021-01-12 09:12:56.510 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.510 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4090", State:"up", Index:18}
2021-01-12 09:12:56.511 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_4090", State:"up", Index:19}
2021-01-12 09:12:56.511 [INFO][60] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=CalicoVersion) v3.17.1 1139 <nil> 0s} 1}
2021-01-12 09:12:56.511 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.19.240.1":set.empty{}} ifaceName="br3_4090"
2021-01-12 09:12:56.512 [INFO][60] felix/sync_client.go 328: Status update from Typha. address="172.17.0.32:5473" connID=0x0 newStatus=in-sync type=""
2021-01-12 09:12:56.512 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=20 ifaceName="eth1.4091" state="up"
2021-01-12 09:12:56.512 [INFO][60] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=VXLANEnabled) true 1137 <nil> 0s} 1}
2021-01-12 09:12:56.512 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_4090", Addrs:set.mapSet{"172.19.240.1":set.empty{}}}
2021-01-12 09:12:56.512 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_4090", Addrs:set.mapSet{"172.19.240.1":set.empty{}}}
2021-01-12 09:12:56.513 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.513 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4091", State:"up", Index:20}
2021-01-12 09:12:56.513 [INFO][60] felix/vxlan_resolver.go 247: Missing vxlan tunnel address for node, cannot send VTEP yet node="nc1"
2021-01-12 09:12:56.513 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.4091"
2021-01-12 09:12:56.513 [INFO][60] felix/l3_route_resolver.go 532: Pool is active newType=VXLAN oldType=NONE
2021-01-12 09:12:56.513 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=21 ifaceName="br3_4091" state="up"
2021-01-12 09:12:56.513 [INFO][60] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ReportingIntervalSecs) 0 1140 <nil> 0s} 1}
2021-01-12 09:12:56.513 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4091", Addrs:set.mapSet{}}
2021-01-12 09:12:56.513 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4091", Addrs:set.mapSet{}}
2021-01-12 09:12:56.515 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.515 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.19.252.1":set.empty{}} ifaceName="br3_4091"
2021-01-12 09:12:56.515 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=22 ifaceName="eth1.4092" state="up"
2021-01-12 09:12:56.515 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_4091", State:"up", Index:21}
2021-01-12 09:12:56.515 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4092", State:"up", Index:22}
2021-01-12 09:12:56.515 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_4091", Addrs:set.mapSet{"172.19.252.1":set.empty{}}}
2021-01-12 09:12:56.515 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_4091", Addrs:set.mapSet{"172.19.252.1":set.empty{}}}
2021-01-12 09:12:56.513 [INFO][60] felix/config_batcher.go 61: Host config update for this host: {{HostConfig(node=nc1,name=IPv4VXLANTunnelAddr) 20.28.116.64 1147 <nil> 0s} 1}
2021-01-12 09:12:56.516 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.516 [INFO][60] felix/calc_graph.go 413: Local endpoint updated id=WorkloadEndpoint(node=nc1, orchestrator=k8s, workload=kube-system/metrics-server-7566d596c8-nsxk6, name=eth0)
2021-01-12 09:12:56.516 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.4092"
2021-01-12 09:12:56.517 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4092", Addrs:set.mapSet{}}
2021-01-12 09:12:56.517 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=23 ifaceName="br3_4092" state="up"
2021-01-12 09:12:56.517 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4092", Addrs:set.mapSet{}}
2021-01-12 09:12:56.517 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.518 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_4092", State:"up", Index:23}
2021-01-12 09:12:56.518 [INFO][60] felix/config_batcher.go 102: Datamodel in sync, flushing config update
2021-01-12 09:12:56.518 [INFO][60] felix/config_batcher.go 112: Sending config update global: map[CalicoVersion:v3.17.1 ClusterGUID:6c70b42e3f974469ac2efe1bfa298127 ClusterType:typha,kdd,k8s,operator,bgp LogSeverityScreen:Info ReportingIntervalSecs:0 VXLANEnabled:true], host: map[IPv4VXLANTunnelAddr:20.28.116.64].
2021-01-12 09:12:56.518 [INFO][60] felix/usagerep.go 91: Waiting before first check-in delay=5m0.023s
2021-01-12 09:12:56.518 [INFO][60] felix/async_calc_graph.go 166: First time we've been in sync
2021-01-12 09:12:56.518 [INFO][60] felix/health.go 133: Health of component changed lastReport=health.HealthReport{Live:true, Ready:false} name="async_calc_graph" newReport=&health.HealthReport{Live:true, Ready:true}
2021-01-12 09:12:56.518 [INFO][60] felix/event_sequencer.go 234: Possible config update. global=map[string]string{"CalicoVersion":"v3.17.1", "ClusterGUID":"6c70b42e3f974469ac2efe1bfa298127", "ClusterType":"typha,kdd,k8s,operator,bgp", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0", "VXLANEnabled":"true"} host=map[string]string{"IPv4VXLANTunnelAddr":"20.28.116.64"}
2021-01-12 09:12:56.519 [INFO][60] felix/config_params.go 341: Merging in config from datastore (global): map[CalicoVersion:v3.17.1 ClusterGUID:6c70b42e3f974469ac2efe1bfa298127 ClusterType:typha,kdd,k8s,operator,bgp LogSeverityScreen:Info ReportingIntervalSecs:0 VXLANEnabled:true]
2021-01-12 09:12:56.519 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.19.248.1":set.empty{}} ifaceName="br3_4092"
2021-01-12 09:12:56.519 [INFO][60] felix/config_params.go 430: Parsing value for TyphaCertFile: /felix-certs/cert.crt (from environment variable)
2021-01-12 09:12:56.519 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=24 ifaceName="eth1.4093" state="up"
2021-01-12 09:12:56.519 [INFO][60] felix/param_types.go 279: Looking for required file path="/felix-certs/cert.crt"
2021-01-12 09:12:56.519 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_4092", Addrs:set.mapSet{"172.19.248.1":set.empty{}}}
2021-01-12 09:12:56.519 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_4092", Addrs:set.mapSet{"172.19.248.1":set.empty{}}}
2021-01-12 09:12:56.519 [INFO][60] felix/config_params.go 466: Parsed value for TyphaCertFile: /felix-certs/cert.crt (from environment variable)
2021-01-12 09:12:56.519 [INFO][60] felix/config_params.go 430: Parsing value for DatastoreType: kubernetes (from environment variable)
2021-01-12 09:12:56.519 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.519 [INFO][60] felix/config_params.go 466: Parsed value for DatastoreType: kubernetes (from environment variable)
2021-01-12 09:12:56.520 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4093", State:"up", Index:24}
2021-01-12 09:12:56.520 [INFO][60] felix/config_params.go 430: Parsing value for IptablesBackend: auto (from environment variable)
2021-01-12 09:12:56.520 [INFO][60] felix/config_params.go 466: Parsed value for IptablesBackend: auto (from environment variable)
2021-01-12 09:12:56.520 [INFO][60] felix/config_params.go 430: Parsing value for TyphaKeyFile: /felix-certs/key.key (from environment variable)
2021-01-12 09:12:56.520 [INFO][60] felix/param_types.go 279: Looking for required file path="/felix-certs/key.key"
2021-01-12 09:12:56.520 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="eth1.4093"
2021-01-12 09:12:56.520 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=25 ifaceName="br3_4093" state="up"
2021-01-12 09:12:56.520 [INFO][60] felix/config_params.go 466: Parsed value for TyphaKeyFile: /felix-certs/key.key (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 430: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4093", Addrs:set.mapSet{}}
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 466: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4093", Addrs:set.mapSet{}}
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 430: Parsing value for HealthEnabled: true (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 466: Parsed value for HealthEnabled: true (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 430: Parsing value for TyphaK8sServiceName: calico-typha (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br3_4093", State:"up", Index:25}
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 466: Parsed value for TyphaK8sServiceName: calico-typha (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 430: Parsing value for TyphaCN: typha-server (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 466: Parsed value for TyphaCN: typha-server (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.19.255.1":set.empty{}} ifaceName="br3_4093"
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 430: Parsing value for Ipv6Support: false (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=26 ifaceName="eth1.4094" state="up"
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 466: Parsed value for Ipv6Support: false (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 430: Parsing value for FelixHostname: nc1 (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 466: Parsed value for FelixHostname: nc1 (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/config_params.go 430: Parsing value for TyphaCAFile: /typha-ca/caBundle (from environment variable)
2021-01-12 09:12:56.521 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br3_4093", Addrs:set.mapSet{"172.19.255.1":set.empty{}}}
2021-01-12 09:12:56.522 [INFO][60] felix/param_types.go 279: Looking for required file path="/typha-ca/caBundle"
2021-01-12 09:12:56.522 [INFO][60] felix/config_params.go 466: Parsed value for TyphaCAFile: /typha-ca/caBundle (from environment variable)
2021-01-12 09:12:56.522 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br3_4093", Addrs:set.mapSet{"172.19.255.1":set.empty{}}}
2021-01-12 09:12:56.522 [INFO][60] felix/config_params.go 430: Parsing value for TyphaK8sNamespace: calico-system (from environment variable)
2021-01-12 09:12:56.522 [INFO][60] felix/config_params.go 466: Parsed value for TyphaK8sNamespace: calico-system (from environment variable)
2021-01-12 09:12:56.522 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.522 [INFO][60] felix/config_params.go 430: Parsing value for LogSeveritySys: None (from config file)
2021-01-12 09:12:56.522 [INFO][60] felix/config_params.go 447: Value set to 'none', replacing with zero-value: "".
2021-01-12 09:12:56.522 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.4094", State:"up", Index:26}
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 466: Parsed value for LogSeveritySys: (from config file)
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 430: Parsing value for MetadataAddr: None (from config file)
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 447: Value set to 'none', replacing with zero-value: "".
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 466: Parsed value for MetadataAddr: (from config file)
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 430: Parsing value for LogFilePath: None (from config file)
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 447: Value set to 'none', replacing with zero-value: "".
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 466: Parsed value for LogFilePath: (from config file)
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 430: Parsing value for LogSeverityFile: None (from config file)
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 447: Value set to 'none', replacing with zero-value: "".
2021-01-12 09:12:56.523 [INFO][60] felix/config_params.go 466: Parsed value for LogSeverityFile: (from config file)
2021-01-12 09:12:56.524 [INFO][60] felix/config_params.go 430: Parsing value for IPv4VXLANTunnelAddr: 20.28.116.64 (from datastore (per-host))
2021-01-12 09:12:56.524 [INFO][60] felix/config_params.go 466: Parsed value for IPv4VXLANTunnelAddr: 20.28.116.64 (from datastore (per-host))
2021-01-12 09:12:56.524 [INFO][60] felix/config_params.go 430: Parsing value for CalicoVersion: v3.17.1 (from datastore (global))
2021-01-12 09:12:56.524 [INFO][60] felix/config_params.go 466: Parsed value for CalicoVersion: v3.17.1 (from datastore (global))
2021-01-12 09:12:56.524 [INFO][60] felix/config_params.go 430: Parsing value for VXLANEnabled: true (from datastore (global))
2021-01-12 09:12:56.524 [INFO][60] felix/config_params.go 466: Parsed value for VXLANEnabled: true (from datastore (global))
2021-01-12 09:12:56.524 [INFO][60] felix/config_params.go 430: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2021-01-12 09:12:56.524 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.29.15.155":set.empty{}, "172.29.15.158":set.empty{}} ifaceName="eth1.4094"
2021-01-12 09:12:56.524 [INFO][60] felix/config_params.go 466: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2021-01-12 09:12:56.525 [INFO][60] felix/config_params.go 430: Parsing value for LogSeverityScreen: Info (from datastore (global))
2021-01-12 09:12:56.525 [INFO][60] felix/config_params.go 466: Parsed value for LogSeverityScreen: INFO (from datastore (global))
2021-01-12 09:12:56.525 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4094", Addrs:set.mapSet{"172.29.15.155":set.empty{}, "172.29.15.158":set.empty{}}}
2021-01-12 09:12:56.525 [INFO][60] felix/config_params.go 430: Parsing value for ClusterGUID: 6c70b42e3f974469ac2efe1bfa298127 (from datastore (global))
2021-01-12 09:12:56.525 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=27 ifaceName="brMgmt" state="up"
2021-01-12 09:12:56.525 [INFO][60] felix/config_params.go 466: Parsed value for ClusterGUID: 6c70b42e3f974469ac2efe1bfa298127 (from datastore (global))
2021-01-12 09:12:56.525 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.4094", Addrs:set.mapSet{"172.29.15.155":set.empty{}, "172.29.15.158":set.empty{}}}
2021-01-12 09:12:56.525 [INFO][60] felix/config_params.go 430: Parsing value for ClusterType: typha,kdd,k8s,operator,bgp (from datastore (global))
2021-01-12 09:12:56.525 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.525 [INFO][60] felix/config_params.go 466: Parsed value for ClusterType: typha,kdd,k8s,operator,bgp (from datastore (global))
2021-01-12 09:12:56.525 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"brMgmt", State:"up", Index:27}
2021-01-12 09:12:56.525 [INFO][60] felix/config_params.go 341: Merging in config from datastore (per-host): map[IPv4VXLANTunnelAddr:20.28.116.64]
2021-01-12 09:12:56.526 [INFO][60] felix/config_params.go 430: Parsing value for TyphaCN: typha-server (from environment variable)
2021-01-12 09:12:56.526 [INFO][60] felix/config_params.go 466: Parsed value for TyphaCN: typha-server (from environment variable)
2021-01-12 09:12:56.526 [INFO][60] felix/config_params.go 430: Parsing value for Ipv6Support: false (from environment variable)
2021-01-12 09:12:56.526 [INFO][60] felix/config_params.go 466: Parsed value for Ipv6Support: false (from environment variable)
2021-01-12 09:12:56.526 [INFO][60] felix/config_params.go 430: Parsing value for FelixHostname: nc1 (from environment variable)
2021-01-12 09:12:56.526 [INFO][60] felix/config_params.go 466: Parsed value for FelixHostname: nc1 (from environment variable)
2021-01-12 09:12:56.526 [INFO][60] felix/config_params.go 430: Parsing value for TyphaCAFile: /typha-ca/caBundle (from environment variable)
2021-01-12 09:12:56.526 [INFO][60] felix/param_types.go 279: Looking for required file path="/typha-ca/caBundle"
2021-01-12 09:12:56.526 [INFO][60] felix/config_params.go 466: Parsed value for TyphaCAFile: /typha-ca/caBundle (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 430: Parsing value for TyphaK8sNamespace: calico-system (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 466: Parsed value for TyphaK8sNamespace: calico-system (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 430: Parsing value for HealthEnabled: true (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.20.128.2":set.empty{}} ifaceName="brMgmt"
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 466: Parsed value for HealthEnabled: true (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 430: Parsing value for TyphaK8sServiceName: calico-typha (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=28 ifaceName="eth1.401" state="up"
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 466: Parsed value for TyphaK8sServiceName: calico-typha (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 430: Parsing value for DatastoreType: kubernetes (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 466: Parsed value for DatastoreType: kubernetes (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"brMgmt", Addrs:set.mapSet{"172.20.128.2":set.empty{}}}
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 430: Parsing value for IptablesBackend: auto (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"brMgmt", Addrs:set.mapSet{"172.20.128.2":set.empty{}}}
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 466: Parsed value for IptablesBackend: auto (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 430: Parsing value for TyphaKeyFile: /felix-certs/key.key (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.527 [INFO][60] felix/param_types.go 279: Looking for required file path="/felix-certs/key.key"
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 466: Parsed value for TyphaKeyFile: /felix-certs/key.key (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.401", State:"up", Index:28}
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 430: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 466: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 430: Parsing value for TyphaCertFile: /felix-certs/cert.crt (from environment variable)
2021-01-12 09:12:56.527 [INFO][60] felix/param_types.go 279: Looking for required file path="/felix-certs/cert.crt"
2021-01-12 09:12:56.527 [INFO][60] felix/config_params.go 466: Parsed value for TyphaCertFile: /felix-certs/cert.crt (from environment variable)
2021-01-12 09:12:56.528 [INFO][60] felix/config_params.go 430: Parsing value for LogSeveritySys: None (from config file)
2021-01-12 09:12:56.528 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.22.100.2":set.empty{}} ifaceName="eth1.401"
2021-01-12 09:12:56.528 [INFO][60] felix/config_params.go 447: Value set to 'none', replacing with zero-value: "".
2021-01-12 09:12:56.528 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=29 ifaceName="eth1.70" state="up"
2021-01-12 09:12:56.528 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.401", Addrs:set.mapSet{"172.22.100.2":set.empty{}}}
2021-01-12 09:12:56.528 [INFO][60] felix/config_params.go 466: Parsed value for LogSeveritySys: (from config file)
2021-01-12 09:12:56.528 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.401", Addrs:set.mapSet{"172.22.100.2":set.empty{}}}
2021-01-12 09:12:56.528 [INFO][60] felix/config_params.go 430: Parsing value for MetadataAddr: None (from config file)
2021-01-12 09:12:56.528 [INFO][60] felix/config_params.go 447: Value set to 'none', replacing with zero-value: "".
2021-01-12 09:12:56.528 [INFO][60] felix/config_params.go 466: Parsed value for MetadataAddr: (from config file)
2021-01-12 09:12:56.528 [INFO][60] felix/config_params.go 430: Parsing value for LogFilePath: None (from config file)
2021-01-12 09:12:56.528 [INFO][60] felix/config_params.go 447: Value set to 'none', replacing with zero-value: "".
2021-01-12 09:12:56.528 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 466: Parsed value for LogFilePath: (from config file)
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 430: Parsing value for LogSeverityFile: None (from config file)
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 447: Value set to 'none', replacing with zero-value: "".
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 466: Parsed value for LogSeverityFile: (from config file)
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 430: Parsing value for IPv4VXLANTunnelAddr: 20.28.116.64 (from datastore (per-host))
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 466: Parsed value for IPv4VXLANTunnelAddr: 20.28.116.64 (from datastore (per-host))
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 430: Parsing value for CalicoVersion: v3.17.1 (from datastore (global))
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 466: Parsed value for CalicoVersion: v3.17.1 (from datastore (global))
2021-01-12 09:12:56.529 [INFO][60] felix/config_params.go 430: Parsing value for VXLANEnabled: true (from datastore (global))
2021-01-12 09:12:56.530 [INFO][60] felix/config_params.go 466: Parsed value for VXLANEnabled: true (from datastore (global))
2021-01-12 09:12:56.529 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.70", State:"up", Index:29}
2021-01-12 09:12:56.530 [INFO][60] felix/config_params.go 430: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2021-01-12 09:12:56.530 [INFO][60] felix/config_params.go 466: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2021-01-12 09:12:56.530 [INFO][60] felix/config_params.go 430: Parsing value for LogSeverityScreen: Info (from datastore (global))
2021-01-12 09:12:56.530 [INFO][60] felix/config_params.go 466: Parsed value for LogSeverityScreen: INFO (from datastore (global))
2021-01-12 09:12:56.530 [INFO][60] felix/config_params.go 430: Parsing value for ClusterGUID: 6c70b42e3f974469ac2efe1bfa298127 (from datastore (global))
2021-01-12 09:12:56.530 [INFO][60] felix/config_params.go 466: Parsed value for ClusterGUID: 6c70b42e3f974469ac2efe1bfa298127 (from datastore (global))
2021-01-12 09:12:56.531 [INFO][60] felix/config_params.go 430: Parsing value for ClusterType: typha,kdd,k8s,operator,bgp (from datastore (global))
2021-01-12 09:12:56.531 [INFO][60] felix/config_params.go 466: Parsed value for ClusterType: typha,kdd,k8s,operator,bgp (from datastore (global))
2021-01-12 09:12:56.531 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.16.32.16":set.empty{}} ifaceName="eth1.70"
2021-01-12 09:12:56.531 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=30 ifaceName="eth1.15" state="up"
2021-01-12 09:12:56.531 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.70", Addrs:set.mapSet{"172.16.32.16":set.empty{}}}
2021-01-12 09:12:56.531 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.70", Addrs:set.mapSet{"172.16.32.16":set.empty{}}}
2021-01-12 09:12:56.532 [INFO][60] felix/async_calc_graph.go 220: First flush after becoming in sync, sending InSync message.
2021-01-12 09:12:56.532 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.532 [INFO][60] felix/daemon.go 1122: Datastore now in sync.
2021-01-12 09:12:56.532 [INFO][60] felix/daemon.go 1124: Datastore in sync for first time, sending message to status reporter.
2021-01-12 09:12:56.532 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"kns.kube-system" > profile:<inbound_rules:<action:"allow" rule_id:"YlN-KJA93B7Fe2ih" > outbound_rules:<action:"allow" rule_id:"GUM3x2IgYhIM5cBY" > >
2021-01-12 09:12:56.533 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pri-kns.kube-system" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.533 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pro-kns.kube-system" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.17.254.1":set.empty{}} ifaceName="eth1.15"
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"ksa.kube-system.metrics-server" > profile:<>
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=31 ifaceName="eth1.38" state="up"
2021-01-12 09:12:56.533 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pri-_CVSZITRyIpEmH8AB6H" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.533 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pro-_CVSZITRyIpEmH8AB6H" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 1325: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kube-system/metrics-server-7566d596c8-nsxk6" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali11cc79557a4" profile_ids:"kns.kube-system" profile_ids:"ksa.kube-system.metrics-server" ipv4_nets:"20.28.116.65/32" >
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"resourcequota-controller" > labels:<key:"projectcalico.org/name" value:"resourcequota-controller" >
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"metrics-server" > labels:<key:"objectset.rio.cattle.io/hash" value:"e10e245e13e46a725c9dddd4f9eb239f147774fd" > labels:<key:"projectcalico.org/name" value:"metrics-server" >
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"tigera-operator" name:"tigera-operator" > labels:<key:"projectcalico.org/name" value:"tigera-operator" >
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"coredns" > labels:<key:"objectset.rio.cattle.io/hash" value:"bce283298811743a0386ab510f2f67ef74240c57" > labels:<key:"projectcalico.org/name" value:"coredns" >
2021-01-12 09:12:56.533 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"deployment-controller" > labels:<key:"projectcalico.org/name" value:"deployment-controller" >
2021-01-12 09:12:56.534 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-node-lease" name:"default" > labels:<key:"projectcalico.org/name" value:"default" >
2021-01-12 09:12:56.534 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"disruption-controller" > labels:<key:"projectcalico.org/name" value:"disruption-controller" >
2021-01-12 09:12:56.534 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"192.168.15.6":set.empty{}} ifaceName="eth1.38"
2021-01-12 09:12:56.534 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=32 ifaceName="eth1.403" state="up"
2021-01-12 09:12:56.534 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"calico-system" name:"default" > labels:<key:"projectcalico.org/name" value:"default" >
2021-01-12 09:12:56.534 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"horizontal-pod-autoscaler" > labels:<key:"projectcalico.org/name" value:"horizontal-pod-autoscaler" >
2021-01-12 09:12:56.535 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"calico-system" name:"calico-node" > labels:<key:"projectcalico.org/name" value:"calico-node" >
2021-01-12 09:12:56.536 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"172.27.40.11":set.empty{}} ifaceName="eth1.403"
2021-01-12 09:12:56.536 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"certificate-controller" > labels:<key:"projectcalico.org/name" value:"certificate-controller" >
2021-01-12 09:12:56.536 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=33 ifaceName="tap0" state="up"
2021-01-12 09:12:56.536 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"namespace-controller" > labels:<key:"projectcalico.org/name" value:"namespace-controller" >
2021-01-12 09:12:56.536 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"endpointslice-controller" > labels:<key:"projectcalico.org/name" value:"endpointslice-controller" >
2021-01-12 09:12:56.536 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"replicaset-controller" > labels:<key:"projectcalico.org/name" value:"replicaset-controller" >
2021-01-12 09:12:56.537 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pvc-protection-controller" > labels:<key:"projectcalico.org/name" value:"pvc-protection-controller" >
2021-01-12 09:12:56.537 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"default" name:"default" > labels:<key:"projectcalico.org/name" value:"default" >
2021-01-12 09:12:56.537 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"tigera-operator" name:"default" > labels:<key:"projectcalico.org/name" value:"default" >
2021-01-12 09:12:56.537 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"job-controller" > labels:<key:"projectcalico.org/name" value:"job-controller" >
2021-01-12 09:12:56.537 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap0"
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=34 ifaceName="tap1" state="up"
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"service-account-controller" > labels:<key:"projectcalico.org/name" value:"service-account-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"generic-garbage-collector" > labels:<key:"projectcalico.org/name" value:"generic-garbage-collector" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"persistent-volume-binder" > labels:<key:"projectcalico.org/name" value:"persistent-volume-binder" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"statefulset-controller" > labels:<key:"projectcalico.org/name" value:"statefulset-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"cronjob-controller" > labels:<key:"projectcalico.org/name" value:"cronjob-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"node-controller" > labels:<key:"projectcalico.org/name" value:"node-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"replication-controller" > labels:<key:"projectcalico.org/name" value:"replication-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"service-controller" > labels:<key:"projectcalico.org/name" value:"service-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-public" name:"default" > labels:<key:"projectcalico.org/name" value:"default" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pv-protection-controller" > labels:<key:"projectcalico.org/name" value:"pv-protection-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap1"
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=35 ifaceName="tap2" state="up"
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"clusterrole-aggregation-controller" > labels:<key:"projectcalico.org/name" value:"clusterrole-aggregation-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"endpoint-controller" > labels:<key:"projectcalico.org/name" value:"endpoint-controller" >
2021-01-12 09:12:56.538 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pod-garbage-collector" > labels:<key:"projectcalico.org/name" value:"pod-garbage-collector" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"expand-controller" > labels:<key:"projectcalico.org/name" value:"expand-controller" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"calico-system" name:"calico-kube-controllers" > labels:<key:"projectcalico.org/name" value:"calico-kube-controllers" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"daemon-set-controller" > labels:<key:"projectcalico.org/name" value:"daemon-set-controller" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"local-path-provisioner-service-account" > labels:<key:"objectset.rio.cattle.io/hash" value:"183f35c65ffbc3064603f43f1580d8c68a2dabd4" > labels:<key:"projectcalico.org/name" value:"local-path-provisioner-service-account" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap2"
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"attachdetach-controller" > labels:<key:"projectcalico.org/name" value:"attachdetach-controller" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=36 ifaceName="tap3" state="up"
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"calico-system" name:"calico-typha" > labels:<key:"projectcalico.org/name" value:"calico-typha" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"ttl-controller" > labels:<key:"projectcalico.org/name" value:"ttl-controller" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"default" > labels:<key:"projectcalico.org/name" value:"default" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-system" > labels:<key:"projectcalico.org/name" value:"kube-system" >
2021-01-12 09:12:56.539 [INFO][60] felix/int_dataplane.go 1325: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-node-lease" > labels:<key:"projectcalico.org/name" value:"kube-node-lease" >
2021-01-12 09:12:56.540 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap3"
2021-01-12 09:12:56.540 [INFO][60] felix/int_dataplane.go 1325: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"calico-system" > labels:<key:"name" value:"calico-system" > labels:<key:"projectcalico.org/name" value:"calico-system" >
2021-01-12 09:12:56.540 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=37 ifaceName="tap4" state="up"
2021-01-12 09:12:56.540 [INFO][60] felix/int_dataplane.go 1325: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"tigera-operator" > labels:<key:"name" value:"tigera-operator" > labels:<key:"projectcalico.org/name" value:"tigera-operator" >
2021-01-12 09:12:56.540 [INFO][60] felix/int_dataplane.go 1325: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-public" > labels:<key:"projectcalico.org/name" value:"kube-public" >
2021-01-12 09:12:56.540 [INFO][60] felix/int_dataplane.go 1325: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"default" > labels:<key:"projectcalico.org/name" value:"default" >
2021-01-12 09:12:56.540 [INFO][60] felix/int_dataplane.go 1325: Received *proto.VXLANTunnelEndpointUpdate update from calculation graph msg=node:"nc1" mac:"66:14:b1:49:a0:bb" ipv4_addr:"20.28.116.64" parent_device_ip:"172.27.40.11"
2021-01-12 09:12:56.540 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=ip_pool_type:VXLAN dst:"20.28.0.0/16" nat_outgoing:true
2021-01-12 09:12:56.541 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=type:LOCAL_WORKLOAD ip_pool_type:VXLAN dst:"20.28.116.65/32" dst_node_name:"nc1" dst_node_ip:"172.27.40.11" same_subnet:true nat_outgoing:true local_workload:true
2021-01-12 09:12:56.541 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=type:LOCAL_WORKLOAD ip_pool_type:VXLAN dst:"20.28.116.64/26" dst_node_name:"nc1" dst_node_ip:"172.27.40.11" same_subnet:true nat_outgoing:true
2021-01-12 09:12:56.541 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap4"
2021-01-12 09:12:56.541 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=type:LOCAL_HOST dst:"172.17.0.32/32" dst_node_name:"nc1" dst_node_ip:"172.27.40.11"
2021-01-12 09:12:56.541 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=38 ifaceName="tap5" state="up"
2021-01-12 09:12:56.542 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=type:LOCAL_TUNNEL ip_pool_type:VXLAN dst:"20.28.116.64/32" dst_node_name:"nc1" dst_node_ip:"172.27.40.11" same_subnet:true nat_outgoing:true tunnel_type:<vxlan:true >
2021-01-12 09:12:56.542 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=type:LOCAL_HOST dst:"172.27.40.11/32" dst_node_name:"nc1" dst_node_ip:"172.27.40.11"
2021-01-12 09:12:56.542 [INFO][60] felix/int_dataplane.go 1325: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"nc1" ipv4_addr:"172.27.40.11"
2021-01-12 09:12:56.542 [INFO][60] felix/int_dataplane.go 1325: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"20.28.0.0-16" pool:<cidr:"20.28.0.0/16" masquerade:true >
2021-01-12 09:12:56.543 [INFO][60] felix/int_dataplane.go 1325: Received *proto.InSync update from calculation graph msg=
2021-01-12 09:12:56.544 [INFO][60] felix/int_dataplane.go 1333: Datastore in sync, flushing the dataplane for the first time... timeSinceStart=169.991911ms
2021-01-12 09:12:56.544 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:56.544 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="all-vxlan-net" setType="hash:net"
2021-01-12 09:12:56.545 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap5"
2021-01-12 09:12:56.545 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=39 ifaceName="tap6" state="up"
2021-01-12 09:12:56.545 [INFO][60] felix/endpoint_mgr.go 561: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"}
2021-01-12 09:12:56.545 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-tw-cali11cc79557a4" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.545 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pri-kns.kube-system"
2021-01-12 09:12:56.545 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pri-_CVSZITRyIpEmH8AB6H"
2021-01-12 09:12:56.545 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-fw-cali11cc79557a4" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.545 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pro-kns.kube-system"
2021-01-12 09:12:56.545 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pro-_CVSZITRyIpEmH8AB6H"
2021-01-12 09:12:56.546 [INFO][60] felix/endpoint_mgr.go 592: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"}
2021-01-12 09:12:56.546 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap6"
2021-01-12 09:12:56.546 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=40 ifaceName="tap7" state="up"
2021-01-12 09:12:56.546 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.546 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-fw-cali11cc79557a4"
2021-01-12 09:12:56.546 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.546 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-tw-cali11cc79557a4"
2021-01-12 09:12:56.546 [INFO][60] felix/endpoint_mgr.go 1072: Skipping configuration of interface because it is oper down. ifaceName="cali11cc79557a4"
2021-01-12 09:12:56.546 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.546 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap7"
2021-01-12 09:12:56.546 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.546 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=41 ifaceName="tap8" state="up"
2021-01-12 09:12:56.546 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-from-hep-forward" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.547 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-to-hep-forward" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.547 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="mangle"
2021-01-12 09:12:56.547 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="raw"
2021-01-12 09:12:56.547 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="raw"
2021-01-12 09:12:56.547 [INFO][60] felix/endpoint_mgr.go 454: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=false status="down" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"}
2021-01-12 09:12:56.547 [INFO][60] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="down" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"}
2021-01-12 09:12:56.547 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-fip-dnat" ipVersion=0x4 table="nat"
2021-01-12 09:12:56.547 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-fip-snat" ipVersion=0x4 table="nat"
2021-01-12 09:12:56.547 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap8"
2021-01-12 09:12:56.547 [INFO][60] felix/masq_mgr.go 144: IPAM pools updated, refreshing iptables rule ipVersion=0x4
2021-01-12 09:12:56.547 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=42 ifaceName="tap9" state="up"
2021-01-12 09:12:56.547 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-nat-outgoing" ipVersion=0x4 table="nat"
2021-01-12 09:12:56.547 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-cidr-block" ipVersion=0x4 table="filter"
2021-01-12 09:12:56.548 [INFO][60] felix/wireguard.go 1593: Trying to connect to linkClient
2021-01-12 09:12:56.548 [INFO][60] felix/route_table.go 408: Trying to connect to netlink
2021-01-12 09:12:56.548 [INFO][60] felix/route_table.go 408: Trying to connect to netlink
2021-01-12 09:12:56.549 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap9"
2021-01-12 09:12:56.549 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:12:56.549 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=43 ifaceName="tap10" state="up"
2021-01-12 09:12:56.550 [INFO][60] felix/route_table.go 408: Trying to connect to netlink
2021-01-12 09:12:56.550 [INFO][60] felix/route_rule.go 182: Trying to connect to netlink
2021-01-12 09:12:56.551 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap10"
2021-01-12 09:12:56.551 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=44 ifaceName="tap11" state="up"
2021-01-12 09:12:56.553 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=3.984815ms
2021-01-12 09:12:56.553 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.553 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.553 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.553 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.554 [INFO][60] felix/ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=1 setID="all-ipam-pools"
2021-01-12 09:12:56.554 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="tap11"
2021-01-12 09:12:56.554 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=51 ifaceName="cali11cc79557a4" state="up"
2021-01-12 09:12:56.554 [INFO][60] felix/ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=1 setID="masq-ipam-pools"
2021-01-12 09:12:56.555 [INFO][60] felix/ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=20 setID="this-host"
2021-01-12 09:12:56.556 [INFO][60] felix/wireguard.go 558: Public key out of sync or updated ourPublicKey=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=
2021-01-12 09:12:56.556 [INFO][60] felix/ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=0 setID="all-vxlan-net"
2021-01-12 09:12:56.556 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali11cc79557a4"
2021-01-12 09:12:56.583 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:12:56.584 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:12:56.587 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:12:56.592 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:12:56.697 [INFO][60] felix/status_combiner.go 78: Endpoint down for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"} ipVersion=0x4 status="down"
2021-01-12 09:12:56.697 [INFO][60] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"} status="down"
2021-01-12 09:12:56.697 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=152.64042899999998
2021-01-12 09:12:56.697 [INFO][60] felix/int_dataplane.go 1473: Completed first update to dataplane. secsSinceStart=0.322922518
2021-01-12 09:12:56.700 [INFO][60] felix/health.go 133: Health of component changed lastReport=health.HealthReport{Live:true, Ready:false} name="int_dataplane" newReport=&health.HealthReport{Live:true, Ready:true}
2021-01-12 09:12:56.700 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.15", Addrs:set.mapSet{"172.17.254.1":set.empty{}}}
2021-01-12 09:12:56.700 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.15", Addrs:set.mapSet{"172.17.254.1":set.empty{}}}
2021-01-12 09:12:56.700 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.701 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.38", Addrs:set.mapSet{"192.168.15.6":set.empty{}}}
2021-01-12 09:12:56.701 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.38", Addrs:set.mapSet{"192.168.15.6":set.empty{}}}
2021-01-12 09:12:56.701 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.701 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"eth1.403", Addrs:set.mapSet{"172.27.40.11":set.empty{}}}
2021-01-12 09:12:56.701 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"eth1.403", Addrs:set.mapSet{"172.27.40.11":set.empty{}}}
2021-01-12 09:12:56.701 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.701 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap0", Addrs:set.mapSet{}}
2021-01-12 09:12:56.701 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap0", Addrs:set.mapSet{}}
2021-01-12 09:12:56.701 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.701 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap1", Addrs:set.mapSet{}}
2021-01-12 09:12:56.701 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap1", Addrs:set.mapSet{}}
2021-01-12 09:12:56.701 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.701 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap2", Addrs:set.mapSet{}}
2021-01-12 09:12:56.701 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap2", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.702 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap3", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap3", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.702 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap4", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap4", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.702 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap5", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap5", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.702 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap6", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap6", Addrs:set.mapSet{}}
2021-01-12 09:12:56.702 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.702 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap7", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap7", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.703 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap8", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap8", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.703 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap9", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap9", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.703 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap10", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap10", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.703 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tap11", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tap11", Addrs:set.mapSet{}}
2021-01-12 09:12:56.703 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:56.704 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali11cc79557a4", Addrs:set.mapSet{}}
2021-01-12 09:12:56.704 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali11cc79557a4", Addrs:set.mapSet{}}
2021-01-12 09:12:56.704 [INFO][60] felix/int_dataplane.go 1482: Dataplane updates throttled
2021-01-12 09:12:56.704 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:56.704 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.704 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.704 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.704 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.704 [INFO][60] felix/ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=23 setID="this-host"
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=13.987919
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.15", State:"up", Index:30}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.38", State:"up", Index:31}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"eth1.403", State:"up", Index:32}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap0", State:"up", Index:33}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap1", State:"up", Index:34}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap2", State:"up", Index:35}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap3", State:"up", Index:36}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap4", State:"up", Index:37}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap5", State:"up", Index:38}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap6", State:"up", Index:39}
2021-01-12 09:12:56.718 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap7", State:"up", Index:40}
2021-01-12 09:12:56.719 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap8", State:"up", Index:41}
2021-01-12 09:12:56.719 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap9", State:"up", Index:42}
2021-01-12 09:12:56.719 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap10", State:"up", Index:43}
2021-01-12 09:12:56.719 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tap11", State:"up", Index:44}
2021-01-12 09:12:56.719 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali11cc79557a4", State:"up", Index:51}
2021-01-12 09:12:56.786 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:56.786 [INFO][60] felix/endpoint_mgr.go 353: Workload interface came up, marking for reconfiguration. ifaceName="cali11cc79557a4"
2021-01-12 09:12:56.786 [INFO][60] felix/endpoint_mgr.go 395: Workload interface state changed; marking for status update. ifaceName="cali11cc79557a4"
2021-01-12 09:12:56.787 [INFO][60] felix/endpoint_mgr.go 1089: Applying /proc/sys configuration to interface. ifaceName="cali11cc79557a4"
2021-01-12 09:12:56.787 [INFO][60] felix/endpoint_mgr.go 454: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"}
2021-01-12 09:12:56.787 [INFO][60] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"}
2021-01-12 09:12:56.787 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.788 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.788 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.789 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.789 [INFO][60] felix/status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"} ipVersion=0x4 status="up"
2021-01-12 09:12:56.789 [INFO][60] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-7566d596c8-nsxk6", EndpointId:"eth0"} status="up"
2021-01-12 09:12:56.789 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.633984
2021-01-12 09:12:56.888 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:56.888 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.888 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.888 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.888 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.888 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.394322
2021-01-12 09:12:56.994 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:56.994 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.994 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.994 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.994 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:56.994 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.485036
2021-01-12 09:12:57.096 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:57.097 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.097 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.097 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.097 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.097 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.380375
2021-01-12 09:12:57.206 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:57.207 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.207 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.207 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.207 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.207 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.5915710000000001
2021-01-12 09:12:57.310 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:57.310 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.310 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.310 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.310 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.310 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.518916
2021-01-12 09:12:57.410 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:57.411 [INFO][60] felix/route_table.go 1085: Failed to access interface because it doesn't exist. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.411 [INFO][60] felix/route_table.go 1153: Failed to get interface; it's down/gone. error=Link not found ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.411 [ERROR][60] felix/route_table.go 920: Failed to get link attributes error=interface not present ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.411 [INFO][60] felix/route_table.go 527: Interface missing, will retry if it appears. ifaceName="vxlan.calico" ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:12:57.411 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.616689
2021-01-12 09:12:57.469 [INFO][60] felix/route_table.go 241: Calculated interface name regexp regex="^eth1.403$"
2021-01-12 09:12:57.474 [INFO][60] felix/vxlan_mgr.go 425: Failed to get VXLAN tunnel device, assuming it isn't present error=Link not found
2021-01-12 09:12:57.475 [INFO][60] felix/vxlan_mgr.go 514: Assigning address to VXLAN device address=20.28.116.64/32
2021-01-12 09:12:57.475 [INFO][60] felix/vxlan_mgr.go 370: VXLAN tunnel device configured
2021-01-12 09:12:57.514 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:57.520 [INFO][60] felix/vxlan_mgr.go 329: VXLAN Manager completed deferred work
2021-01-12 09:12:57.521 [INFO][60] felix/route_table.go 408: Trying to connect to netlink
2021-01-12 09:12:57.523 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=8.539754
2021-01-12 09:12:57.576 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"20.28.116.64":set.empty{}} ifaceName="vxlan.calico"
2021-01-12 09:12:57.576 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=52 ifaceName="vxlan.calico" state="up"
2021-01-12 09:12:57.576 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"vxlan.calico", Addrs:set.mapSet{"20.28.116.64":set.empty{}}}
2021-01-12 09:12:57.576 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"vxlan.calico", Addrs:set.mapSet{"20.28.116.64":set.empty{}}}
2021-01-12 09:12:57.576 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:57.576 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"vxlan.calico", State:"up", Index:52}
2021-01-12 09:12:57.577 [INFO][60] felix/iface_monitor.go 187: Netlink address update. addr="20.28.116.64" exists=true ifIndex=52
2021-01-12 09:12:57.624 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:57.626 [INFO][60] felix/ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=24 setID="this-host"
2021-01-12 09:12:57.639 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:12:57.639 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:12:57.640 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:12:57.641 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:12:57.645 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=21.56116
2021-01-12 09:12:57.734 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:57.734 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:12:57.734 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:12:57.736 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:12:57.738 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:12:57.741 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=7.243788
2021-01-12 09:12:57.826 [INFO][60] felix/iface_monitor.go 187: Netlink address update. addr="fe80::ecee:eeff:feee:eeee" exists=true ifIndex=51
2021-01-12 09:12:57.826 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}} ifaceName="cali11cc79557a4"
2021-01-12 09:12:57.826 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali11cc79557a4", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2021-01-12 09:12:57.826 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali11cc79557a4", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2021-01-12 09:12:57.837 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:57.837 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.24943600000000002
bird: device1: Initializing
bird: direct1: Initializing
bird: device1: Starting
bird: device1: Initializing
bird: direct1: Initializing
bird: device1: Starting
bird: device1: Connected to table master
bird: device1: State changed to feed
bird: direct1: Starting
bird: direct1: Connected to table master
bird: direct1: State changed to feed
bird: Graceful restart started
bird: Graceful restart done
bird: Started
bird: device1: State changed to up
bird: direct1: State changed to up
bird: device1: Connected to table master
bird: device1: State changed to feed
bird: direct1: Starting
bird: direct1: Connected to table master
bird: direct1: State changed to feed
bird: Graceful restart started
bird: Graceful restart done
bird: Started
bird: device1: State changed to up
bird: direct1: State changed to up
2021-01-12 09:12:58.599 [INFO][60] felix/int_dataplane.go 1450: Dataplane updates no longer throttled
2021-01-12 09:12:58.599 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:58.599 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:12:58.601 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:12:58.604 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=4.516211
2021-01-12 09:12:58.607 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:58.607 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:12:58.609 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:12:58.612 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=4.995447
2021-01-12 09:12:58.654 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:58.654 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:12:58.658 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:12:58.660 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.713286
2021-01-12 09:12:58.702 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:58.702 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:12:58.704 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:12:58.706 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.832053
2021-01-12 09:12:58.990 [INFO][60] felix/calc_graph.go 413: Local endpoint updated id=WorkloadEndpoint(node=nc1, orchestrator=k8s, workload=calico-system/calico-kube-controllers-6896fd456b-gfqcv, name=eth0)
2021-01-12 09:12:58.990 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"ksa.calico-system.calico-kube-controllers" > profile:<>
2021-01-12 09:12:58.991 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pri-_nzzjLvInId1gPHmQz_" ipVersion=0x4 table="filter"
2021-01-12 09:12:58.991 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="chain update" table="filter"
2021-01-12 09:12:58.991 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pro-_nzzjLvInId1gPHmQz_" ipVersion=0x4 table="filter"
2021-01-12 09:12:58.991 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"kns.calico-system" > profile:<inbound_rules:<action:"allow" rule_id:"fw2fB0QZr1DPiXMg" > outbound_rules:<action:"allow" rule_id:"D66fMttEtc8Iyw4Z" > >
2021-01-12 09:12:58.991 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pri-kns.calico-system" ipVersion=0x4 table="filter"
2021-01-12 09:12:58.991 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pro-kns.calico-system" ipVersion=0x4 table="filter"
2021-01-12 09:12:58.992 [INFO][60] felix/int_dataplane.go 1325: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"calico-system/calico-kube-controllers-6896fd456b-gfqcv" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali6beed33b626" profile_ids:"kns.calico-system" profile_ids:"ksa.calico-system.calico-kube-controllers" ipv4_nets:"20.28.116.66/32" >
2021-01-12 09:12:58.992 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=type:LOCAL_WORKLOAD ip_pool_type:VXLAN dst:"20.28.116.66/32" dst_node_name:"nc1" dst_node_ip:"172.27.40.11" same_subnet:true nat_outgoing:true local_workload:true
2021-01-12 09:12:58.992 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:58.992 [INFO][60] felix/endpoint_mgr.go 561: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"}
2021-01-12 09:12:58.993 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-tw-cali6beed33b626" ipVersion=0x4 table="filter"
2021-01-12 09:12:58.993 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pri-kns.calico-system"
2021-01-12 09:12:58.993 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pri-_nzzjLvInId1gPHmQz_"
2021-01-12 09:12:58.994 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-fw-cali6beed33b626" ipVersion=0x4 table="filter"
2021-01-12 09:12:58.994 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pro-kns.calico-system"
2021-01-12 09:12:58.994 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pro-_nzzjLvInId1gPHmQz_"
2021-01-12 09:12:58.994 [INFO][60] felix/endpoint_mgr.go 592: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"}
2021-01-12 09:12:58.994 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2021-01-12 09:12:58.994 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-fw-cali6beed33b626"
2021-01-12 09:12:58.994 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2021-01-12 09:12:58.994 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-tw-cali6beed33b626"
2021-01-12 09:12:58.994 [INFO][60] felix/endpoint_mgr.go 1072: Skipping configuration of interface because it is oper down. ifaceName="cali6beed33b626"
2021-01-12 09:12:58.994 [INFO][60] felix/endpoint_mgr.go 454: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=false status="down" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"}
2021-01-12 09:12:58.994 [INFO][60] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="down" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"}
2021-01-12 09:12:58.994 [INFO][60] felix/route_table.go 408: Trying to connect to netlink
2021-01-12 09:12:58.996 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:12:59.003 [INFO][60] felix/status_combiner.go 78: Endpoint down for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"} ipVersion=0x4 status="down"
2021-01-12 09:12:59.003 [INFO][60] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"} status="down"
2021-01-12 09:12:59.003 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=10.476869
2021-01-12 09:12:59.042 [INFO][60] felix/iface_monitor.go 187: Netlink address update. addr="fe80::6414:b1ff:fe49:a0bb" exists=true ifIndex=52
2021-01-12 09:12:59.042 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"20.28.116.64":set.empty{}, "fe80::6414:b1ff:fe49:a0bb":set.empty{}} ifaceName="vxlan.calico"
2021-01-12 09:12:59.042 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"vxlan.calico", Addrs:set.mapSet{"20.28.116.64":set.empty{}, "fe80::6414:b1ff:fe49:a0bb":set.empty{}}}
2021-01-12 09:12:59.042 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"vxlan.calico", Addrs:set.mapSet{"20.28.116.64":set.empty{}, "fe80::6414:b1ff:fe49:a0bb":set.empty{}}}
2021-01-12 09:12:59.042 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:12:59.042 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:59.045 [INFO][60] felix/ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=24 setID="this-host"
2021-01-12 09:12:59.055 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=12.943848000000001
2021-01-12 09:12:59.081 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali6beed33b626"
2021-01-12 09:12:59.081 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali6beed33b626", Addrs:set.mapSet{}}
2021-01-12 09:12:59.081 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali6beed33b626", Addrs:set.mapSet{}}
2021-01-12 09:12:59.081 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:59.081 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.22375499999999998
2021-01-12 09:12:59.082 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=53 ifaceName="cali6beed33b626" state="up"
2021-01-12 09:12:59.082 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali6beed33b626", State:"up", Index:53}
2021-01-12 09:12:59.082 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:12:59.082 [INFO][60] felix/endpoint_mgr.go 353: Workload interface came up, marking for reconfiguration. ifaceName="cali6beed33b626"
2021-01-12 09:12:59.082 [INFO][60] felix/endpoint_mgr.go 395: Workload interface state changed; marking for status update. ifaceName="cali6beed33b626"
2021-01-12 09:12:59.082 [INFO][60] felix/endpoint_mgr.go 1089: Applying /proc/sys configuration to interface. ifaceName="cali6beed33b626"
2021-01-12 09:12:59.083 [INFO][60] felix/endpoint_mgr.go 454: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"}
2021-01-12 09:12:59.083 [INFO][60] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"}
2021-01-12 09:12:59.084 [INFO][60] felix/status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"} ipVersion=0x4 status="up"
2021-01-12 09:12:59.084 [INFO][60] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"calico-system/calico-kube-controllers-6896fd456b-gfqcv", EndpointId:"eth0"} status="up"
2021-01-12 09:12:59.084 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=1.9487020000000002
2021-01-12 09:13:00.003 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:00.003 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:00.005 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:00.009 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.66046
2021-01-12 09:13:00.130 [INFO][60] felix/iface_monitor.go 187: Netlink address update. addr="fe80::ecee:eeff:feee:eeee" exists=true ifIndex=53
2021-01-12 09:13:00.130 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}} ifaceName="cali6beed33b626"
2021-01-12 09:13:00.130 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali6beed33b626", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2021-01-12 09:13:00.130 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali6beed33b626", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2021-01-12 09:13:00.130 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:00.130 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.16891799999999998
2021-01-12 09:13:00.599 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:00.599 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:13:00.601 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:13:00.604 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=4.757281000000001
2021-01-12 09:13:00.607 [INFO][60] felix/health.go 196: Overall health status changed newStatus=&health.HealthReport{Live:true, Ready:true}
2021-01-12 09:13:00.654 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:00.654 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:13:00.656 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:13:00.658 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=4.257300000000001
2021-01-12 09:13:00.701 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:00.701 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:13:00.702 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:13:00.705 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.840152
2021-01-12 09:13:01.006 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:01.007 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:01.008 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:01.013 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.673719
2021-01-12 09:13:03.008 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:03.009 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:03.010 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:03.014 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.574748
2021-01-12 09:13:04.604 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:04.605 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:13:04.606 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:13:04.609 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=4.654584
2021-01-12 09:13:04.654 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:04.654 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:13:04.656 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:13:04.658 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=4.43866
2021-01-12 09:13:04.701 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:04.701 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:13:04.707 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:13:04.710 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=8.777396
2021-01-12 09:13:06.019 [INFO][60] felix/calc_graph.go 413: Local endpoint updated id=WorkloadEndpoint(node=nc1, orchestrator=k8s, workload=kube-system/coredns-8655855d6-tjn2l, name=eth0)
2021-01-12 09:13:06.019 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"ksa.kube-system.coredns" > profile:<>
2021-01-12 09:13:06.019 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pri-_u2Tn2rSoAPffvE7JO6" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.019 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="chain update" table="filter"
2021-01-12 09:13:06.020 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pro-_u2Tn2rSoAPffvE7JO6" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.020 [INFO][60] felix/int_dataplane.go 1325: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kube-system/coredns-8655855d6-tjn2l" endpoint_id:"eth0" > endpoint:<state:"active" name:"calicc1d21c9713" profile_ids:"kns.kube-system" profile_ids:"ksa.kube-system.coredns" ipv4_nets:"20.28.116.67/32" >
2021-01-12 09:13:06.020 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=type:LOCAL_WORKLOAD ip_pool_type:VXLAN dst:"20.28.116.67/32" dst_node_name:"nc1" dst_node_ip:"172.27.40.11" same_subnet:true nat_outgoing:true local_workload:true
2021-01-12 09:13:06.020 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:06.020 [INFO][60] felix/endpoint_mgr.go 561: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"}
2021-01-12 09:13:06.020 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-tw-calicc1d21c9713" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.020 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pri-_u2Tn2rSoAPffvE7JO6"
2021-01-12 09:13:06.021 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-fw-calicc1d21c9713" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.021 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pro-_u2Tn2rSoAPffvE7JO6"
2021-01-12 09:13:06.021 [INFO][60] felix/endpoint_mgr.go 592: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"}
2021-01-12 09:13:06.021 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.021 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-fw-calicc1d21c9713"
2021-01-12 09:13:06.021 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.021 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-tw-calicc1d21c9713"
2021-01-12 09:13:06.021 [INFO][60] felix/endpoint_mgr.go 1072: Skipping configuration of interface because it is oper down. ifaceName="calicc1d21c9713"
2021-01-12 09:13:06.021 [INFO][60] felix/endpoint_mgr.go 454: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=false status="down" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"}
2021-01-12 09:13:06.022 [INFO][60] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="down" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"}
2021-01-12 09:13:06.022 [INFO][60] felix/route_table.go 408: Trying to connect to netlink
2021-01-12 09:13:06.026 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:06.038 [INFO][60] felix/status_combiner.go 78: Endpoint down for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"} ipVersion=0x4 status="down"
2021-01-12 09:13:06.038 [INFO][60] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"} status="down"
2021-01-12 09:13:06.038 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=18.135313
2021-01-12 09:13:06.062 [INFO][60] felix/calc_graph.go 413: Local endpoint updated id=WorkloadEndpoint(node=nc1, orchestrator=k8s, workload=kube-system/local-path-provisioner-6d59f47c7-vpfwj, name=eth0)
2021-01-12 09:13:06.062 [INFO][60] felix/int_dataplane.go 1325: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"ksa.kube-system.local-path-provisioner-service-account" > profile:<>
2021-01-12 09:13:06.062 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pri-_HayIXLB85hzHkIhWER" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.063 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="chain update" table="filter"
2021-01-12 09:13:06.063 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-pro-_HayIXLB85hzHkIhWER" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.063 [INFO][60] felix/int_dataplane.go 1325: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kube-system/local-path-provisioner-6d59f47c7-vpfwj" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali50d724d0bd7" profile_ids:"kns.kube-system" profile_ids:"ksa.kube-system.local-path-provisioner-service-account" ipv4_nets:"20.28.116.68/32" >
2021-01-12 09:13:06.063 [INFO][60] felix/int_dataplane.go 1325: Received *proto.RouteUpdate update from calculation graph msg=type:LOCAL_WORKLOAD ip_pool_type:VXLAN dst:"20.28.116.68/32" dst_node_name:"nc1" dst_node_ip:"172.27.40.11" same_subnet:true nat_outgoing:true local_workload:true
2021-01-12 09:13:06.063 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:06.063 [INFO][60] felix/endpoint_mgr.go 561: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"}
2021-01-12 09:13:06.063 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-tw-cali50d724d0bd7" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.063 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pri-_HayIXLB85hzHkIhWER"
2021-01-12 09:13:06.063 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-fw-cali50d724d0bd7" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.063 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-pro-_HayIXLB85hzHkIhWER"
2021-01-12 09:13:06.063 [INFO][60] felix/endpoint_mgr.go 592: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"}
2021-01-12 09:13:06.064 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.064 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-fw-cali50d724d0bd7"
2021-01-12 09:13:06.064 [INFO][60] felix/table.go 500: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2021-01-12 09:13:06.064 [INFO][60] felix/table.go 574: Chain became referenced, marking it for programming chainName="cali-tw-cali50d724d0bd7"
2021-01-12 09:13:06.064 [INFO][60] felix/endpoint_mgr.go 1072: Skipping configuration of interface because it is oper down. ifaceName="cali50d724d0bd7"
2021-01-12 09:13:06.064 [INFO][60] felix/endpoint_mgr.go 454: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=false status="down" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"}
2021-01-12 09:13:06.064 [INFO][60] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="down" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"}
2021-01-12 09:13:06.065 [INFO][60] felix/route_table.go 408: Trying to connect to netlink
2021-01-12 09:13:06.070 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:06.080 [INFO][60] felix/status_combiner.go 78: Endpoint down for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"} ipVersion=0x4 status="down"
2021-01-12 09:13:06.080 [INFO][60] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"} status="down"
2021-01-12 09:13:06.080 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=16.814217
2021-01-12 09:13:06.105 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="calicc1d21c9713"
2021-01-12 09:13:06.105 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"calicc1d21c9713", Addrs:set.mapSet{}}
2021-01-12 09:13:06.105 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"calicc1d21c9713", Addrs:set.mapSet{}}
2021-01-12 09:13:06.105 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:06.106 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.203322
2021-01-12 09:13:06.107 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=54 ifaceName="calicc1d21c9713" state="up"
2021-01-12 09:13:06.108 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"calicc1d21c9713", State:"up", Index:54}
2021-01-12 09:13:06.108 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:06.108 [INFO][60] felix/endpoint_mgr.go 353: Workload interface came up, marking for reconfiguration. ifaceName="calicc1d21c9713"
2021-01-12 09:13:06.108 [INFO][60] felix/endpoint_mgr.go 395: Workload interface state changed; marking for status update. ifaceName="calicc1d21c9713"
2021-01-12 09:13:06.109 [INFO][60] felix/endpoint_mgr.go 1089: Applying /proc/sys configuration to interface. ifaceName="calicc1d21c9713"
2021-01-12 09:13:06.109 [INFO][60] felix/endpoint_mgr.go 454: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"}
2021-01-12 09:13:06.109 [INFO][60] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"}
2021-01-12 09:13:06.110 [INFO][60] felix/status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"} ipVersion=0x4 status="up"
2021-01-12 09:13:06.110 [INFO][60] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-8655855d6-tjn2l", EndpointId:"eth0"} status="up"
2021-01-12 09:13:06.110 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=1.882498
2021-01-12 09:13:06.151 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali50d724d0bd7"
2021-01-12 09:13:06.151 [INFO][60] felix/int_dataplane.go 942: Linux interface state changed. ifIndex=55 ifaceName="cali50d724d0bd7" state="up"
2021-01-12 09:13:06.151 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali50d724d0bd7", Addrs:set.mapSet{}}
2021-01-12 09:13:06.151 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali50d724d0bd7", Addrs:set.mapSet{}}
2021-01-12 09:13:06.151 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:06.151 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.26601800000000003
2021-01-12 09:13:06.152 [INFO][60] felix/int_dataplane.go 1340: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali50d724d0bd7", State:"up", Index:55}
2021-01-12 09:13:06.152 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:06.152 [INFO][60] felix/endpoint_mgr.go 353: Workload interface came up, marking for reconfiguration. ifaceName="cali50d724d0bd7"
2021-01-12 09:13:06.152 [INFO][60] felix/endpoint_mgr.go 395: Workload interface state changed; marking for status update. ifaceName="cali50d724d0bd7"
2021-01-12 09:13:06.152 [INFO][60] felix/endpoint_mgr.go 1089: Applying /proc/sys configuration to interface. ifaceName="cali50d724d0bd7"
2021-01-12 09:13:06.153 [INFO][60] felix/endpoint_mgr.go 454: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"}
2021-01-12 09:13:06.153 [INFO][60] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"}
2021-01-12 09:13:06.154 [INFO][60] felix/status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"} ipVersion=0x4 status="up"
2021-01-12 09:13:06.155 [INFO][60] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/local-path-provisioner-6d59f47c7-vpfwj", EndpointId:"eth0"} status="up"
2021-01-12 09:13:06.155 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.859932
2021-01-12 09:13:06.607 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:06.607 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:13:06.608 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:13:06.610 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.890075ms
2021-01-12 09:13:06.610 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.467495
2021-01-12 09:13:07.080 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:07.080 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:07.082 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:07.087 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=7.238559
2021-01-12 09:13:07.554 [INFO][60] felix/iface_monitor.go 187: Netlink address update. addr="fe80::ecee:eeff:feee:eeee" exists=true ifIndex=55
2021-01-12 09:13:07.554 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}} ifaceName="cali50d724d0bd7"
2021-01-12 09:13:07.554 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali50d724d0bd7", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2021-01-12 09:13:07.554 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali50d724d0bd7", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2021-01-12 09:13:07.554 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:07.554 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.25044
2021-01-12 09:13:07.874 [INFO][60] felix/iface_monitor.go 187: Netlink address update. addr="fe80::ecee:eeff:feee:eeee" exists=true ifIndex=54
2021-01-12 09:13:07.874 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}} ifaceName="calicc1d21c9713"
2021-01-12 09:13:07.874 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"calicc1d21c9713", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2021-01-12 09:13:07.874 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"calicc1d21c9713", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2021-01-12 09:13:07.874 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:07.875 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.242904
2021-01-12 09:13:08.080 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:08.080 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:08.082 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:08.087 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=7.300778
2021-01-12 09:13:10.087 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:10.087 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:10.091 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:10.096 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=9.147357999999999
2021-01-12 09:13:12.608 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:12.608 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:13:12.610 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:13:12.615 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=6.571425
2021-01-12 09:13:12.656 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:12.656 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:13:12.657 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:13:12.659 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.536496
2021-01-12 09:13:12.700 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:12.700 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:13:12.702 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:13:12.703 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.3574949999999997
2021-01-12 09:13:14.083 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:14.083 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:14.087 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:14.092 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=8.564852
2021-01-12 09:13:16.962 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:16.962 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:13:16.962 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:13:16.964 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.930915ms
2021-01-12 09:13:16.964 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.485943
2021-01-12 09:13:22.080 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:22.080 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:22.082 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:22.086 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=6.267407
2021-01-12 09:13:27.904 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:27.905 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:13:27.905 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:13:27.906 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.547224ms
2021-01-12 09:13:27.907 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=1.961389
2021-01-12 09:13:28.599 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:28.599 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:13:28.601 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:13:28.605 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.8685220000000005
2021-01-12 09:13:28.655 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:28.656 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:13:28.659 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:13:28.661 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.732122
2021-01-12 09:13:28.700 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:28.700 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:13:28.702 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:13:28.704 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.9291480000000005
2021-01-12 09:13:38.083 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:38.084 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:13:38.086 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:13:38.090 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=6.410311
2021-01-12 09:13:38.822 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:38.823 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:13:38.823 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:13:38.825 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.873027ms
2021-01-12 09:13:38.825 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.2451149999999997
2021-01-12 09:13:48.998 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:48.998 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:13:48.998 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:13:49.000 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.875746ms
2021-01-12 09:13:49.000 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.388089
2021-01-12 09:13:56.409 [INFO][57] monitor-addresses/startup.go 759: Using autodetected IPv4 address on interface eth1.403: 172.27.40.11/24
2021-01-12 09:13:59.102 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:13:59.103 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:13:59.103 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:13:59.105 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.899001ms
2021-01-12 09:13:59.106 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.21556
2021-01-12 09:14:00.599 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:00.599 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:14:00.601 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:14:00.605 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=6.264124000000001
2021-01-12 09:14:00.655 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:00.656 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:14:00.657 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:14:00.659 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=4.025167
2021-01-12 09:14:00.700 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:00.701 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:14:00.702 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:14:00.705 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.9643819999999996
2021-01-12 09:14:09.668 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:09.668 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:14:09.668 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:14:09.670 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.584475ms
2021-01-12 09:14:09.670 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.0773010000000003
2021-01-12 09:14:10.080 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:10.080 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:14:10.081 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:14:10.085 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.471356999999999
2021-01-12 09:14:19.940 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:19.940 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:14:19.940 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:14:19.942 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.667366ms
2021-01-12 09:14:19.942 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.094823
2021-01-12 09:14:26.505 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali11cc79557a4"
2021-01-12 09:14:26.505 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali11cc79557a4", Addrs:set.mapSet{}}
2021-01-12 09:14:26.505 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali11cc79557a4", Addrs:set.mapSet{}}
2021-01-12 09:14:26.505 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:26.506 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.46857099999999996
2021-01-12 09:14:26.506 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{"20.28.116.64":set.empty{}} ifaceName="vxlan.calico"
2021-01-12 09:14:26.507 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"vxlan.calico", Addrs:set.mapSet{"20.28.116.64":set.empty{}}}
2021-01-12 09:14:26.507 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"vxlan.calico", Addrs:set.mapSet{"20.28.116.64":set.empty{}}}
2021-01-12 09:14:26.508 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali6beed33b626"
2021-01-12 09:14:26.508 [INFO][60] felix/ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-01-12 09:14:26.509 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali6beed33b626", Addrs:set.mapSet{}}
2021-01-12 09:14:26.509 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali6beed33b626", Addrs:set.mapSet{}}
2021-01-12 09:14:26.509 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:26.510 [INFO][60] felix/ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=24 setID="this-host"
2021-01-12 09:14:26.510 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="calicc1d21c9713"
2021-01-12 09:14:26.511 [INFO][60] felix/int_dataplane.go 977: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali50d724d0bd7"
2021-01-12 09:14:26.520 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=10.516744
2021-01-12 09:14:26.520 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"calicc1d21c9713", Addrs:set.mapSet{}}
2021-01-12 09:14:26.520 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"calicc1d21c9713", Addrs:set.mapSet{}}
2021-01-12 09:14:26.520 [INFO][60] felix/int_dataplane.go 1358: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali50d724d0bd7", Addrs:set.mapSet{}}
2021-01-12 09:14:26.520 [INFO][60] felix/hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali50d724d0bd7", Addrs:set.mapSet{}}
2021-01-12 09:14:26.520 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:26.521 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=0.277618
2021-01-12 09:14:30.826 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:30.827 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:14:30.827 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:14:30.828 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.63091ms
2021-01-12 09:14:30.829 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.1508410000000002
2021-01-12 09:14:31.465 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:31.465 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:14:31.465 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^eth1.403$" ipVersion=0x4
2021-01-12 09:14:31.465 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^cali.*" ipVersion=0x4
2021-01-12 09:14:31.465 [INFO][60] felix/wireguard.go 534: Queueing a resync of wireguard configuration
2021-01-12 09:14:31.465 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^wireguard.cali$" ipVersion=0x4
2021-01-12 09:14:31.465 [INFO][60] felix/route_rule.go 172: Queueing a resync of routing rules. ipVersion=4
2021-01-12 09:14:31.471 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=6.4941189999999995
2021-01-12 09:14:41.183 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:41.183 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:14:41.183 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:14:41.185 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.646026ms
2021-01-12 09:14:41.185 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.016867
2021-01-12 09:14:51.488 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:14:51.488 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:14:51.489 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:14:51.490 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.612324ms
2021-01-12 09:14:51.490 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.0118810000000003
2021-01-12 09:14:56.417 [INFO][57] monitor-addresses/startup.go 759: Using autodetected IPv4 address on interface eth1.403: 172.27.40.11/24
2021-01-12 09:15:01.814 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:01.814 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:15:01.815 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:15:01.816 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.803835ms
2021-01-12 09:15:01.817 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.271943
2021-01-12 09:15:04.599 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:04.599 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:15:04.601 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:15:04.605 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.857302
2021-01-12 09:15:04.655 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:04.655 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:15:04.657 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:15:04.659 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.7496869999999998
2021-01-12 09:15:04.700 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:04.700 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:15:04.704 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:15:04.706 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.81405
2021-01-12 09:15:12.084 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:12.084 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:15:12.084 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:15:12.086 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.040865ms
2021-01-12 09:15:12.086 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.6457479999999998
2021-01-12 09:15:14.080 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:14.080 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:15:14.082 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:15:14.086 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.839944
2021-01-12 09:15:22.918 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:22.918 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:15:22.918 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:15:22.920 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.661274ms
2021-01-12 09:15:22.920 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.0915090000000003
2021-01-12 09:15:33.771 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:33.771 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:15:33.771 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:15:33.773 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.651818ms
2021-01-12 09:15:33.773 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.0836729999999997
2021-01-12 09:15:44.352 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:44.352 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:15:44.352 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:15:44.354 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.925403ms
2021-01-12 09:15:44.354 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.388235
2021-01-12 09:15:54.830 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:15:54.830 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:15:54.830 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:15:54.832 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.545648ms
2021-01-12 09:15:54.832 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=1.9068049999999999
2021-01-12 09:15:56.428 [INFO][57] monitor-addresses/startup.go 759: Using autodetected IPv4 address on interface eth1.403: 172.27.40.11/24
2021-01-12 09:16:01.562 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:01.562 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:16:01.562 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^eth1.403$" ipVersion=0x4
2021-01-12 09:16:01.562 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^cali.*" ipVersion=0x4
2021-01-12 09:16:01.562 [INFO][60] felix/wireguard.go 534: Queueing a resync of wireguard configuration
2021-01-12 09:16:01.562 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^wireguard.cali$" ipVersion=0x4
2021-01-12 09:16:01.562 [INFO][60] felix/route_rule.go 172: Queueing a resync of routing rules. ipVersion=4
2021-01-12 09:16:01.569 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=7.132738
2021-01-12 09:16:05.427 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:05.427 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:16:05.427 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:16:05.429 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.905335ms
2021-01-12 09:16:05.430 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.365029
2021-01-12 09:16:16.256 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:16.257 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:16:16.257 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:16:16.259 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.875083ms
2021-01-12 09:16:16.259 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.3304229999999997
2021-01-12 09:16:26.672 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:26.672 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:16:26.672 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:16:26.674 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.652541ms
2021-01-12 09:16:26.674 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.076647
2021-01-12 09:16:34.601 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:34.601 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="refresh timer" table="nat"
2021-01-12 09:16:34.603 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:16:34.606 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.150570999999999
2021-01-12 09:16:34.662 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:34.662 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="refresh timer" table="raw"
2021-01-12 09:16:34.664 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:16:34.666 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.494391
2021-01-12 09:16:34.708 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:34.708 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="refresh timer" table="mangle"
2021-01-12 09:16:34.709 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:16:34.712 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=3.9544970000000004
2021-01-12 09:16:36.752 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:36.752 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:16:36.752 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:16:36.754 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.511574ms
2021-01-12 09:16:36.754 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=1.890765
2021-01-12 09:16:44.082 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:44.082 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="refresh timer" table="filter"
2021-01-12 09:16:44.088 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:16:44.094 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=12.087512
2021-01-12 09:16:47.355 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:47.355 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:16:47.355 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:16:47.357 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.763349ms
2021-01-12 09:16:47.357 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.264671
2021-01-12 09:16:56.436 [INFO][57] monitor-addresses/startup.go 759: Using autodetected IPv4 address on interface eth1.403: 172.27.40.11/24
2021-01-12 09:16:57.804 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:16:57.804 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:16:57.804 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:16:57.806 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.546954ms
2021-01-12 09:16:57.806 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=1.920082
2021-01-12 09:17:08.108 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:08.109 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:17:08.109 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:17:08.110 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.797001ms
2021-01-12 09:17:08.111 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.177929
2021-01-12 09:17:12.599 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:12.599 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-01-12 09:17:12.601 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-01-12 09:17:12.605 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.9804319999999995
2021-01-12 09:17:12.655 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:12.655 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-01-12 09:17:12.657 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-01-12 09:17:12.661 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.773174
2021-01-12 09:17:12.702 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:12.702 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-01-12 09:17:12.704 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-01-12 09:17:12.707 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=4.164161
2021-01-12 09:17:18.620 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:18.620 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:17:18.620 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:17:18.621 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.52652ms
2021-01-12 09:17:18.622 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=1.91644
2021-01-12 09:17:22.080 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:22.080 [INFO][60] felix/table.go 934: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-01-12 09:17:22.081 [INFO][60] felix/table.go 596: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-01-12 09:17:22.086 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=5.705118000000001
2021-01-12 09:17:28.897 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:28.897 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:17:28.897 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:17:28.899 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.619536ms
2021-01-12 09:17:28.899 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.025199
2021-01-12 09:17:31.856 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:31.856 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^vxlan.calico$" ipVersion=0x4
2021-01-12 09:17:31.856 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^eth1.403$" ipVersion=0x4
2021-01-12 09:17:31.856 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^cali.*" ipVersion=0x4
2021-01-12 09:17:31.856 [INFO][60] felix/wireguard.go 534: Queueing a resync of wireguard configuration
2021-01-12 09:17:31.856 [INFO][60] felix/route_table.go 398: Queueing a resync of routing table. ifaceRegex="^wireguard.cali$" ipVersion=0x4
2021-01-12 09:17:31.856 [INFO][60] felix/route_rule.go 172: Queueing a resync of routing rules. ipVersion=4
2021-01-12 09:17:31.864 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=7.746464
2021-01-12 09:17:39.312 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:39.312 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:17:39.312 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:17:39.314 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.660841ms
2021-01-12 09:17:39.314 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=1.991269
2021-01-12 09:17:49.349 [INFO][60] felix/int_dataplane.go 1453: Applying dataplane updates
2021-01-12 09:17:49.349 [INFO][60] felix/ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-01-12 09:17:49.349 [INFO][60] felix/ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-01-12 09:17:49.351 [INFO][60] felix/ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.65357ms
2021-01-12 09:17:49.351 [INFO][60] felix/int_dataplane.go 1467: Finished applying updates to dataplane. msecToApply=2.079501
2021-01-12 09:17:56.446 [INFO][57] monitor-addresses/startup.go 759: Using autodetected IPv4 address on interface eth1.403: 172.27.40.11/24
2021-01-12 09:17:56.541 [INFO][60] felix/usagerep.go 115: Initial delay complete, doing first report
2021-01-12 09:17:56.541 [INFO][60] felix/usagerep.go 205: Reporting cluster usage/checking for deprecation warnings. alpEnabled=false calicoVersion="v3.17.1" clusterGUID="6c70b42e3f974469ac2efe1bfa298127" clusterType="typha,kdd,k8s,operator,bgp" gitRevision="2020-12-10T23:50:58+0000" kubernetesVersion="v1.18.4+k3s1" stats=calc.StatsUpdate{NumHosts:1, NumWorkloadEndpoints:4, NumHostEndpoints:0, NumPolicies:0, NumProfiles:46, NumALPPolicies:0} version="v3.17.1"
2021-01-12 09:17:57.268 [INFO][60] felix/usagerep.go 117: First report done, starting ticker
Status:
NAMESPACE↑ NAME READY RESTARTS STATUS IP NODE AGE │
│ calico-system calico-kube-controllers-6896fd456b-gfqcv 0/1 0 Running 20.28.116.66 nc1 34m │
│ calico-system calico-node-vnxhs 0/1 0 Running 172.17.0.32 nc1 34m │
│ calico-system calico-typha-76dc5bbdb8-7t2z6 1/1 0 Running 172.17.0.32 nc1 34m │
│ kube-system coredns-8655855d6-tjn2l 1/1 0 Running 20.28.116.67 nc1 48m │
│ kube-system local-path-provisioner-6d59f47c7-vpfwj 1/1 0 Running 20.28.116.68 nc1 48m │
│ kube-system metrics-server-7566d596c8-nsxk6 1/1 0 Running 20.28.116.65 nc1 48m │
│ tigera-operator tigera-operator-587f6cb54d-ndpzt 1/1 0 Running 172.17.0.32 nc1 43m │
Your Environment
- Calico version
- k3s
- Fedora 30
- Link to your project (optional):
About this issue
- Original URL
- State: closed
- Created 3 years ago
- Comments: 34 (15 by maintainers)
Hi @frozenprocess, I spent quite sometime last night figuring it out and got it to work. It turns out the setup I was using was not working because it is in an initramfs. Initially when setting up k3s, I’ve been using a chroot to combat the pivot_root’s inability to switch root when on an initramfs. In fact I’ve been doing it this way for some time and thought pods were working fine such as Alpine etc., but the calico-node and your suggestions proved otherwise.
The odd thing is that it could break out of that root and see the host’s “/” directory within the calico container and other containers, how that’s possible I’m not sure of.
I changed the containerd config.toml to use NoPivotRoot, apparently a newish config for the runtime io.containerd.runc.v2. Not only does calico now work, but I don’t need to have a chroot. Though this has other security concerns that I’ll need to work through.
Really appreciate your help in debugging this!
Ok I don’t even know how this setup is partially working with how many items are missing…
This is a huge help major thanks!! I’ll update the kernel configs and try again:
That looks like the bin directory of my host