search

projectcalico / calico

Cloud native networking and network security
https://docs.tigera.io/calico/latest/about/
Apache License 2.0
6.03k stars 1.34k forks source link

Liveness probe failed: Get http://127.0.0.1:9099/liveness: net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers) #4372

Closed NadamHL closed 3 years ago

NadamHL commented 3 years ago 
k8s：v1.15.2
calico: v3.8.2
kernel: 3.10.0-327.el7.x86_64
centos: 7.2

When the server joins the cluster and runs the calico node node, it will affect the port communication of this host. The telnet 127.0.0.1 port is unavailable, and the telnet network port (the host's own network port ip address) port is connected. When this node is deleted, it returns to normal. For this reason, calico node is always no ready

 telnet本地端口，127.0.0.1不通，本机网口地址是通的

image

NadamHL commented 3 years ago

kubectl logs calico-node-fh8fr -n kube-system 

2021-02-06 07:39:55.165 [INFO][8] startup.go 256: Early log level set to info
2021-02-06 07:39:55.166 [INFO][8] startup.go 274: Using stored node name from /var/lib/calico/nodename
2021-02-06 07:39:55.166 [INFO][8] startup.go 284: Determined node name: b0108011
2021-02-06 07:39:55.178 [INFO][8] startup.go 97: Skipping datastore connection test
2021-02-06 07:39:55.182 [INFO][8] reachaddr.go 46: Auto-detected address by connecting to remote Destination="172.16.69.220" IP=172.22.108.11
2021-02-06 07:39:55.183 [INFO][8] reachaddr.go 55: Checking interface CIDRs Name="tunl0"
2021-02-06 07:39:55.183 [INFO][8] reachaddr.go 55: Checking interface CIDRs Name="docker0"
2021-02-06 07:39:55.183 [INFO][8] reachaddr.go 57: Checking CIDR CIDR="172.30.60.1/24"
2021-02-06 07:39:55.183 [INFO][8] reachaddr.go 55: Checking interface CIDRs Name="bond0"
2021-02-06 07:39:55.183 [INFO][8] reachaddr.go 57: Checking CIDR CIDR="172.22.108.11/24"
2021-02-06 07:39:55.183 [INFO][8] reachaddr.go 59: Found matching interface CIDR CIDR="172.22.108.11/24"
2021-02-06 07:39:55.183 [INFO][8] startup.go 610: Using autodetected IPv4 address 172.22.108.11/24, detected by connecting to 172.16.69.220
2021-02-06 07:39:55.184 [INFO][8] startup.go 647: No AS number configured on node resource, using global value
2021-02-06 07:39:55.184 [INFO][8] startup.go 149: Setting NetworkUnavailable to False
2021-02-06 07:39:55.203 [ERROR][8] startup.go 152: Unable to set NetworkUnavailable to False error=nodes "b0108011" not found
2021-02-06 07:39:55.211 [INFO][8] startup.go 530: FELIX_IPV6SUPPORT is false through environment variable
2021-02-06 07:39:55.220 [INFO][8] startup.go 181: Using node name: b0108011
2021-02-06 07:39:55.301 [INFO][34] ipam.go 87: Auto-assign 1 ipv4, 0 ipv6 addrs for host 'b0108011'
2021-02-06 07:39:55.307 [INFO][34] ipam.go 313: Looking up existing affinities for host handle="ipip-tunnel-addr-b0108011" host="b0108011"
2021-02-06 07:39:55.310 [INFO][34] ipam.go 371: Ran out of existing affine blocks for host handle="ipip-tunnel-addr-b0108011" host="b0108011"
2021-02-06 07:39:55.313 [INFO][34] ipam.go 436: No more affine blocks, but need to allocate 1 more addresses - allocate another block handle="ipip-tunnel-addr-b0108011" host="b0108011"
2021-02-06 07:39:55.313 [INFO][34] ipam.go 440: Looking for an unclaimed block handle="ipip-tunnel-addr-b0108011" host="b0108011"
2021-02-06 07:39:55.325 [INFO][34] ipam_block_reader_writer.go 114: Found free block: 172.33.1.128/26
2021-02-06 07:39:55.325 [INFO][34] ipam.go 452: Found unclaimed block host="b0108011" subnet=172.33.1.128/26
2021-02-06 07:39:55.325 [INFO][34] ipam_block_reader_writer.go 130: Trying to create affinity in pending state host="b0108011" subnet=172.33.1.128/26
2021-02-06 07:39:55.328 [INFO][34] ipam_block_reader_writer.go 160: Successfully created pending affinity for block host="b0108011" subnet=172.33.1.128/26
2021-02-06 07:39:55.329 [INFO][34] ipam.go 135: Attempting to load block cidr=172.33.1.128/26 host="b0108011"
2021-02-06 07:39:55.331 [INFO][34] ipam.go 140: The referenced block doesn't exist, trying to create it cidr=172.33.1.128/26 host="b0108011"
2021-02-06 07:39:55.334 [INFO][34] ipam.go 147: Wrote affinity as pending cidr=172.33.1.128/26 host="b0108011"
2021-02-06 07:39:55.337 [INFO][34] ipam.go 156: Attempting to claim the block cidr=172.33.1.128/26 host="b0108011"
2021-02-06 07:39:55.337 [INFO][34] ipam_block_reader_writer.go 183: Attempting to create a new block host="b0108011" subnet=172.33.1.128/26
2021-02-06 07:39:55.341 [INFO][34] ipam_block_reader_writer.go 224: Successfully created block
2021-02-06 07:39:55.341 [INFO][34] ipam_block_reader_writer.go 235: Confirming affinity host="b0108011" subnet=172.33.1.128/26
2021-02-06 07:39:55.345 [INFO][34] ipam_block_reader_writer.go 250: Successfully confirmed affinity host="b0108011" subnet=172.33.1.128/26
2021-02-06 07:39:55.345 [INFO][34] ipam.go 484: Claimed new block &{BlockKey(cidr=172.33.1.128/26) 0xc000771180 89574210 <nil> 0s} - assigning 1 addresses host="b0108011" subnet=172.33.1.128/26
2021-02-06 07:39:55.345 [INFO][34] ipam.go 804: Attempting to assign 1 addresses from block block=172.33.1.128/26 handle="ipip-tunnel-addr-b0108011" host="b0108011"
2021-02-06 07:39:55.351 [INFO][34] ipam.go 827: Writing block in order to claim IPs block=172.33.1.128/26 handle="ipip-tunnel-addr-b0108011" host="b0108011"
2021-02-06 07:39:55.355 [INFO][34] ipam.go 840: Successfully claimed IPs: [172.33.1.128/26] block=172.33.1.128/26 handle="ipip-tunnel-addr-b0108011" host="b0108011"
2021-02-06 07:39:55.355 [INFO][34] ipam.go 569: Auto-assigned 1 out of 1 IPv4s: [172.33.1.128/26] handle="ipip-tunnel-addr-b0108011" host="b0108011"
2021-02-06 07:39:55.362 [INFO][34] allocateip.go 206: Set tunnel address IP="172.33.1.128/26" type="ipipTunnelAddress"
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-02-06 07:39:56.452 [INFO][77] logutils.go 82: Early screen log level set to info
2021-02-06 07:39:56.453 [INFO][77] daemon.go 138: Felix starting up GOMAXPROCS=24 buildDate="" gitCommit="a161671c667ac33d85736944e9aa069995498911" version="v3.8.2"
2021-02-06 07:39:56.453 [INFO][77] daemon.go 156: Loading configuration...
2021-02-06 07:39:56.453 [INFO][75] config.go 105: Skipping confd config file.
2021-02-06 07:39:56.453 [INFO][75] run.go 17: Starting calico-confd
2021-02-06 07:39:56.456 [INFO][77] env_var_loader.go 40: Found felix environment variable: "ipv6support"="false"
2021-02-06 07:39:56.456 [INFO][77] env_var_loader.go 40: Found felix environment variable: "felixhostname"="b0108011"
2021-02-06 07:39:56.456 [INFO][77] env_var_loader.go 40: Found felix environment variable: "etcdcertfile"="/calico-secrets/etcd-cert"
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "logseverityscreen"="info"
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "etcdscheme"=""
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "defaultendpointtohostaction"="ACCEPT"
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "etcdaddr"=""
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "etcdkeyfile"="/calico-secrets/etcd-key"
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "healthenabled"="true"
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "ipinipmtu"="1440"
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "etcdendpoints"="https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379"
2021-02-06 07:39:56.457 [INFO][77] env_var_loader.go 40: Found felix environment variable: "etcdcafile"="/calico-secrets/etcd-ca"
2021-02-06 07:39:56.457 [INFO][77] daemon.go 179: Loading config file: /etc/calico/felix.cfg
2021-02-06 07:39:56.457 [INFO][77] config_params.go 243: Merging in config from environment variable: map[ipv6support:false etcdcertfile:/calico-secrets/etcd-cert etcdscheme: etcdendpoints:https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379 etcdcafile:/calico-secrets/etcd-ca ipinipmtu:1440 felixhostname:b0108011 logseverityscreen:info defaultendpointtohostaction:ACCEPT etcdaddr: etcdkeyfile:/calico-secrets/etcd-key healthenabled:true]
2021-02-06 07:39:56.458 [INFO][77] config_params.go 252: Ignoring empty configuration parameter. Use value 'none' if your intention is to explicitly disable the default value. name="etcdaddr" source=environment variable
2021-02-06 07:39:56.458 [INFO][77] config_params.go 252: Ignoring empty configuration parameter. Use value 'none' if your intention is to explicitly disable the default value. name="etcdscheme" source=environment variable
2021-02-06 07:39:56.458 [INFO][77] config_params.go 324: Parsing value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 360: Parsed value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 324: Parsing value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-key"
2021-02-06 07:39:56.458 [INFO][77] config_params.go 360: Parsed value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 324: Parsing value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 360: Parsed value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 324: Parsing value for EtcdEndpoints: https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379 (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 360: Parsed value for EtcdEndpoints: [https://172.22.108.11:2379/ https://172.22.108.12:2379/ https://10.100.106.15:2379/] (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] config_params.go 324: Parsing value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.458 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-ca"
2021-02-06 07:39:56.459 [INFO][77] config_params.go 360: Parsed value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 324: Parsing value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-cert"
2021-02-06 07:39:56.459 [INFO][77] config_params.go 360: Parsed value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 324: Parsing value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 360: Parsed value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 324: Parsing value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 360: Parsed value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: info (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: INFO (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 243: Merging in config from config file: map[MetadataAddr:None LogFilePath:None LogSeverityFile:None LogSeveritySys:None]
2021-02-06 07:39:56.459 [INFO][77] config_params.go 324: Parsing value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 360: Parsed value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 324: Parsing value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 360: Parsed value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: info (from environment variable)
2021-02-06 07:39:56.459 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: INFO (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 324: Parsing value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-ca"
2021-02-06 07:39:56.460 [INFO][77] config_params.go 360: Parsed value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 324: Parsing value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-cert"
2021-02-06 07:39:56.460 [INFO][77] config_params.go 360: Parsed value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 324: Parsing value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-key"
2021-02-06 07:39:56.460 [INFO][77] config_params.go 360: Parsed value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 324: Parsing value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 360: Parsed value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 324: Parsing value for EtcdEndpoints: https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379 (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 360: Parsed value for EtcdEndpoints: [https://172.22.108.11:2379/ https://172.22.108.12:2379/ https://10.100.106.15:2379/] (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 324: Parsing value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 360: Parsed value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.460 [INFO][77] config_params.go 324: Parsing value for MetadataAddr: None (from config file)
2021-02-06 07:39:56.461 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.461 [INFO][77] config_params.go 360: Parsed value for MetadataAddr:  (from config file)
2021-02-06 07:39:56.461 [INFO][77] config_params.go 324: Parsing value for LogFilePath: None (from config file)
2021-02-06 07:39:56.461 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.461 [INFO][77] config_params.go 360: Parsed value for LogFilePath:  (from config file)
2021-02-06 07:39:56.461 [INFO][77] config_params.go 324: Parsing value for LogSeverityFile: None (from config file)
2021-02-06 07:39:56.461 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.461 [INFO][77] config_params.go 360: Parsed value for LogSeverityFile:  (from config file)
2021-02-06 07:39:56.461 [INFO][77] config_params.go 324: Parsing value for LogSeveritySys: None (from config file)
2021-02-06 07:39:56.461 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.461 [INFO][77] config_params.go 360: Parsed value for LogSeveritySys:  (from config file)
2021-02-06 07:39:56.461 [INFO][77] health.go 225: Health enabled.  Starting server. host="localhost" port=9099
2021-02-06 07:39:56.461 [INFO][77] config_params.go 428: Encap disabled, disabling node poll (if KDD is in use).
2021-02-06 07:39:56.461 [INFO][77] daemon.go 211: Connecting to datastore datastore="etcdv3"
2021-02-06 07:39:56.469 [INFO][75] watchersyncer.go 89: Start called
2021-02-06 07:39:56.469 [INFO][75] client.go 186: CALICO_ADVERTISE_CLUSTER_IPS not specified, no cluster ips will be advertised
2021-02-06 07:39:56.469 [INFO][75] watchersyncer.go 127: Sending status update Status=wait-for-ready
2021-02-06 07:39:56.469 [INFO][75] watchersyncer.go 147: Starting main event processing loop
2021-02-06 07:39:56.469 [INFO][75] client.go 330: RouteGenerator has indicated it is in sync
2021-02-06 07:39:56.475 [INFO][75] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2021-02-06 07:39:56.475 [INFO][75] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.475 [INFO][75] watchersyncer.go 127: Sending status update Status=resync
2021-02-06 07:39:56.477 [INFO][75] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgppeers"
2021-02-06 07:39:56.477 [INFO][77] daemon.go 218: Created datastore client
2021-02-06 07:39:56.477 [INFO][75] watchercache.go 289: Sending synced update ListRoot="/calico/ipam/v2/host/b0108011"
2021-02-06 07:39:56.477 [INFO][75] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.477 [INFO][75] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.477 [INFO][75] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2021-02-06 07:39:56.478 [INFO][75] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.478 [INFO][75] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2021-02-06 07:39:56.479 [INFO][75] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.479 [INFO][75] watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2021-02-06 07:39:56.479 [INFO][75] watchersyncer.go 127: Sending status update Status=in-sync
2021-02-06 07:39:56.479 [INFO][75] client.go 327: Calico Syncer has indicated it is in sync
2021-02-06 07:39:56.482 [INFO][75] resource.go 220: Target config /etc/calico/confd/config/bird6_ipam.cfg out of sync
2021-02-06 07:39:56.482 [INFO][77] daemon.go 771: Loaded ready flag kind="ClusterInformation" name="default" ready=true
2021-02-06 07:39:56.485 [INFO][75] resource.go 220: Target config /etc/calico/confd/config/bird6_aggr.cfg out of sync
2021-02-06 07:39:56.486 [INFO][75] resource.go 220: Target config /tmp/tunl-ip out of sync
2021-02-06 07:39:56.485 [INFO][75] resource.go 220: Target config /etc/calico/confd/config/bird_aggr.cfg out of sync
2021-02-06 07:39:56.486 [INFO][75] resource.go 220: Target config /etc/calico/confd/config/bird_ipam.cfg out of sync
2021-02-06 07:39:56.486 [INFO][75] resource.go 220: Target config /etc/calico/confd/config/bird6.cfg out of sync
2021-02-06 07:39:56.488 [INFO][75] resource.go 220: Target config /etc/calico/confd/config/bird.cfg out of sync
2021-02-06 07:39:56.490 [INFO][75] resource.go 260: Target config /etc/calico/confd/config/bird6_ipam.cfg has been updated
2021-02-06 07:39:56.491 [INFO][75] resource.go 260: Target config /etc/calico/confd/config/bird6_aggr.cfg has been updated
2021-02-06 07:39:56.492 [INFO][75] resource.go 260: Target config /etc/calico/confd/config/bird_ipam.cfg has been updated
2021-02-06 07:39:56.493 [INFO][75] resource.go 260: Target config /etc/calico/confd/config/bird_aggr.cfg has been updated
2021-02-06 07:39:56.494 [INFO][77] config_params.go 243: Merging in config from datastore (global): map[ReportingIntervalSecs:0 ClusterGUID:736bd25180004c95ad233605f227cee4 ClusterType:k8s,bgp CalicoVersion:v3.8.2 LogSeverityScreen:Debug IpInIpEnabled:true]
2021-02-06 07:39:56.494 [INFO][77] config_params.go 324: Parsing value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.494 [INFO][77] config_params.go 360: Parsed value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.494 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: info (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: INFO (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] config_params.go 324: Parsing value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-ca"
2021-02-06 07:39:56.495 [INFO][77] config_params.go 360: Parsed value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] config_params.go 324: Parsing value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-cert"
2021-02-06 07:39:56.495 [INFO][77] config_params.go 360: Parsed value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.495 [INFO][75] resource.go 260: Target config /etc/calico/confd/config/bird6.cfg has been updated
2021-02-06 07:39:56.495 [INFO][77] config_params.go 324: Parsing value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] config_params.go 360: Parsed value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] config_params.go 324: Parsing value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.495 [INFO][77] config_params.go 360: Parsed value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.496 [INFO][77] config_params.go 324: Parsing value for EtcdEndpoints: https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379 (from environment variable)
2021-02-06 07:39:56.496 [INFO][77] config_params.go 360: Parsed value for EtcdEndpoints: [https://172.22.108.11:2379/ https://172.22.108.12:2379/ https://10.100.106.15:2379/] (from environment variable)
2021-02-06 07:39:56.496 [INFO][77] config_params.go 324: Parsing value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.496 [INFO][77] config_params.go 360: Parsed value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.496 [INFO][77] config_params.go 324: Parsing value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.496 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-key"
2021-02-06 07:39:56.496 [INFO][77] config_params.go 360: Parsed value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.496 [INFO][77] config_params.go 324: Parsing value for LogSeverityFile: None (from config file)
2021-02-06 07:39:56.496 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.496 [INFO][75] resource.go 260: Target config /etc/calico/confd/config/bird.cfg has been updated
2021-02-06 07:39:56.496 [INFO][77] config_params.go 360: Parsed value for LogSeverityFile:  (from config file)
2021-02-06 07:39:56.496 [INFO][77] config_params.go 324: Parsing value for LogSeveritySys: None (from config file)
2021-02-06 07:39:56.497 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.497 [INFO][77] config_params.go 360: Parsed value for LogSeveritySys:  (from config file)
2021-02-06 07:39:56.497 [INFO][77] config_params.go 324: Parsing value for MetadataAddr: None (from config file)
2021-02-06 07:39:56.497 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.497 [INFO][77] config_params.go 360: Parsed value for MetadataAddr:  (from config file)
2021-02-06 07:39:56.497 [INFO][77] config_params.go 324: Parsing value for LogFilePath: None (from config file)
2021-02-06 07:39:56.497 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.497 [INFO][77] config_params.go 360: Parsed value for LogFilePath:  (from config file)
2021-02-06 07:39:56.497 [INFO][77] config_params.go 324: Parsing value for ClusterGUID: 736bd25180004c95ad233605f227cee4 (from datastore (global))
2021-02-06 07:39:56.497 [INFO][77] config_params.go 360: Parsed value for ClusterGUID: 736bd25180004c95ad233605f227cee4 (from datastore (global))
2021-02-06 07:39:56.497 [INFO][77] config_params.go 324: Parsing value for ClusterType: k8s,bgp (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 360: Parsed value for ClusterType: k8s,bgp (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 324: Parsing value for CalicoVersion: v3.8.2 (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 360: Parsed value for CalicoVersion: v3.8.2 (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: Debug (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: DEBUG (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 363: Skipping config value for LogSeverityScreen from datastore (global); already have a value from environment variable
2021-02-06 07:39:56.498 [INFO][77] config_params.go 324: Parsing value for IpInIpEnabled: true (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 360: Parsed value for IpInIpEnabled: true (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 324: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 360: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2021-02-06 07:39:56.498 [INFO][77] config_params.go 243: Merging in config from datastore (per-host): map[IpInIpTunnelAddr:172.33.1.128 DefaultEndpointToHostAction:Return]
2021-02-06 07:39:56.499 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.499 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.499 [INFO][77] config_params.go 324: Parsing value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.499 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-ca"
2021-02-06 07:39:56.499 [INFO][77] config_params.go 360: Parsed value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.499 [INFO][77] config_params.go 324: Parsing value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.499 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-cert"
2021-02-06 07:39:56.499 [INFO][77] config_params.go 360: Parsed value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.499 [INFO][77] config_params.go 324: Parsing value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.499 [INFO][77] config_params.go 360: Parsed value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.499 [INFO][77] config_params.go 324: Parsing value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 360: Parsed value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: info (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: INFO (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 324: Parsing value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 360: Parsed value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 324: Parsing value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-key"
2021-02-06 07:39:56.500 [INFO][77] config_params.go 360: Parsed value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 324: Parsing value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 360: Parsed value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.500 [INFO][77] config_params.go 324: Parsing value for EtcdEndpoints: https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379 (from environment variable)
2021-02-06 07:39:56.501 [INFO][77] config_params.go 360: Parsed value for EtcdEndpoints: [https://172.22.108.11:2379/ https://172.22.108.12:2379/ https://10.100.106.15:2379/] (from environment variable)
2021-02-06 07:39:56.501 [INFO][77] config_params.go 324: Parsing value for MetadataAddr: None (from config file)
2021-02-06 07:39:56.501 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.501 [INFO][77] config_params.go 360: Parsed value for MetadataAddr:  (from config file)
2021-02-06 07:39:56.501 [INFO][77] config_params.go 324: Parsing value for LogFilePath: None (from config file)
2021-02-06 07:39:56.501 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.501 [INFO][77] config_params.go 360: Parsed value for LogFilePath:  (from config file)
2021-02-06 07:39:56.501 [INFO][77] config_params.go 324: Parsing value for LogSeverityFile: None (from config file)
2021-02-06 07:39:56.501 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.501 [INFO][77] config_params.go 360: Parsed value for LogSeverityFile:  (from config file)
2021-02-06 07:39:56.501 [INFO][77] config_params.go 324: Parsing value for LogSeveritySys: None (from config file)
2021-02-06 07:39:56.502 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.502 [INFO][77] config_params.go 360: Parsed value for LogSeveritySys:  (from config file)
2021-02-06 07:39:56.502 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: Return (from datastore (per-host))
2021-02-06 07:39:56.502 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: RETURN (from datastore (per-host))
2021-02-06 07:39:56.502 [INFO][77] config_params.go 363: Skipping config value for DefaultEndpointToHostAction from datastore (per-host); already have a value from environment variable
2021-02-06 07:39:56.502 [INFO][77] config_params.go 324: Parsing value for IpInIpTunnelAddr: 172.33.1.128 (from datastore (per-host))
2021-02-06 07:39:56.502 [INFO][77] config_params.go 360: Parsed value for IpInIpTunnelAddr: 172.33.1.128 (from datastore (per-host))
2021-02-06 07:39:56.502 [INFO][77] config_params.go 324: Parsing value for CalicoVersion: v3.8.2 (from datastore (global))
2021-02-06 07:39:56.502 [INFO][77] config_params.go 360: Parsed value for CalicoVersion: v3.8.2 (from datastore (global))
2021-02-06 07:39:56.502 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: Debug (from datastore (global))
2021-02-06 07:39:56.502 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: DEBUG (from datastore (global))
2021-02-06 07:39:56.503 [INFO][77] config_params.go 363: Skipping config value for LogSeverityScreen from datastore (global); already have a value from environment variable
2021-02-06 07:39:56.503 [INFO][77] config_params.go 324: Parsing value for IpInIpEnabled: true (from datastore (global))
2021-02-06 07:39:56.503 [INFO][77] config_params.go 360: Parsed value for IpInIpEnabled: true (from datastore (global))
2021-02-06 07:39:56.503 [INFO][77] config_params.go 324: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2021-02-06 07:39:56.503 [INFO][77] config_params.go 360: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2021-02-06 07:39:56.503 [INFO][77] config_params.go 324: Parsing value for ClusterGUID: 736bd25180004c95ad233605f227cee4 (from datastore (global))
2021-02-06 07:39:56.503 [INFO][77] config_params.go 360: Parsed value for ClusterGUID: 736bd25180004c95ad233605f227cee4 (from datastore (global))
2021-02-06 07:39:56.503 [INFO][77] config_params.go 324: Parsing value for ClusterType: k8s,bgp (from datastore (global))
2021-02-06 07:39:56.503 [INFO][77] config_params.go 360: Parsed value for ClusterType: k8s,bgp (from datastore (global))
2021-02-06 07:39:56.519 [INFO][77] health.go 222: Health enabled.  Server is already running. host="localhost" port=9099
2021-02-06 07:39:56.520 [INFO][77] daemon.go 286: Successfully loaded configuration. GOMAXPROCS=24 buildDate="" config=&config.Config{UseInternalDataplaneDriver:true, DataplaneDriver:"calico-iptables-plugin", DatastoreType:"etcdv3", FelixHostname:"b0108011", EtcdAddr:"127.0.0.1:2379", EtcdScheme:"http", EtcdKeyFile:"/calico-secrets/etcd-key", EtcdCertFile:"/calico-secrets/etcd-cert", EtcdCaFile:"/calico-secrets/etcd-ca", EtcdEndpoints:[]string{"https://172.22.108.11:2379/", "https://172.22.108.12:2379/", "https://10.100.106.15:2379/"}, TyphaAddr:"", TyphaK8sServiceName:"", TyphaK8sNamespace:"kube-system", TyphaReadTimeout:30000000000, TyphaWriteTimeout:10000000000, TyphaKeyFile:"", TyphaCertFile:"", TyphaCAFile:"", TyphaCN:"", TyphaURISAN:"", Ipv6Support:false, IgnoreLooseRPF:false, IptablesBackend:"legacy", RouteRefreshInterval:90000000000, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckIntervalSecs:1000000000, IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeoutSecs:0, IptablesLockProbeIntervalMillis:50000000, IpsetsRefreshInterval:10000000000, MaxIpsetSize:1048576, XDPRefreshInterval:90000000000, PolicySyncPathPrefix:"", NetlinkTimeoutSecs:10000000000, MetadataAddr:"", MetadataPort:8775, OpenstackRegion:"", InterfacePrefix:"cali", InterfaceExclude:[]*regexp.Regexp{(*regexp.Regexp)(0xc000263a40)}, ChainInsertMode:"insert", DefaultEndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", LogPrefix:"calico-packet", LogFilePath:"", LogSeverityFile:"", LogSeverityScreen:"INFO", LogSeveritySys:"", VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, VXLANMTU:1410, IPv4VXLANTunnelAddr:net.IP(nil), VXLANTunnelMACAddr:"", IpInIpEnabled:true, IpInIpMtu:1440, IpInIpTunnelAddr:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xac, 0x21, 0x1, 0x80}, ReportingIntervalSecs:0, ReportingTTLSecs:90000000000, EndpointReportingEnabled:false, EndpointReportingDelaySecs:1000000000, IptablesMarkMask:0xffff0000, DisableConntrackInvalidCheck:false, HealthEnabled:true, HealthPort:9099, HealthHost:"localhost", PrometheusMetricsEnabled:false, 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: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: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:"736bd25180004c95ad233605f227cee4", ClusterType:"k8s,bgp", CalicoVersion:"v3.8.2", ExternalNodesCIDRList:[]string(nil), DebugMemoryProfilePath:"", DebugCPUProfilePath:"/tmp/felix-cpu-<timestamp>.pprof", DebugDisableLogDropping:false, DebugSimulateCalcGraphHangAfter:0, DebugSimulateDataplaneHangAfter:0, sourceToRawConfig:map[config.Source]map[string]string{0x4:map[string]string{"etcdendpoints":"https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379", "ipv6support":"false", "etcdkeyfile":"/calico-secrets/etcd-key", "ipinipmtu":"1440", "logseverityscreen":"info", "defaultendpointtohostaction":"ACCEPT", "etcdcafile":"/calico-secrets/etcd-ca", "etcdcertfile":"/calico-secrets/etcd-cert", "healthenabled":"true", "felixhostname":"b0108011"}, 0x3:map[string]string{"MetadataAddr":"None", "LogFilePath":"None", "LogSeverityFile":"None", "LogSeveritySys":"None"}, 0x1:map[string]string{"ClusterGUID":"736bd25180004c95ad233605f227cee4", "ClusterType":"k8s,bgp", "CalicoVersion":"v3.8.2", "LogSeverityScreen":"Debug", "IpInIpEnabled":"true", "ReportingIntervalSecs":"0"}, 0x2:map[string]string{"IpInIpTunnelAddr":"172.33.1.128", "DefaultEndpointToHostAction":"Return"}}, rawValues:map[string]string{"EtcdCaFile":"/calico-secrets/etcd-ca", "Ipv6Support":"false", "EtcdKeyFile":"/calico-secrets/etcd-key", "IpInIpMtu":"1440", "EtcdEndpoints":"https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379", "MetadataAddr":"None", "CalicoVersion":"v3.8.2", "ClusterType":"k8s,bgp", "DefaultEndpointToHostAction":"ACCEPT", "HealthEnabled":"true", "FelixHostname":"b0108011", "LogFilePath":"None", "LogSeverityFile":"None", "LogSeveritySys":"None", "IpInIpTunnelAddr":"172.33.1.128", "ReportingIntervalSecs":"0", "EtcdCertFile":"/calico-secrets/etcd-cert", "LogSeverityScreen":"info", "IpInIpEnabled":"true", "ClusterGUID":"736bd25180004c95ad233605f227cee4"}, Err:error(nil), IptablesNATOutgoingInterfaceFilter:"", SidecarAccelerationEnabled:false, XDPEnabled:false, GenericXDPEnabled:false} gitCommit="a161671c667ac33d85736944e9aa069995498911" version="v3.8.2"
2021-02-06 07:39:56.522 [INFO][77] driver.go 43: Using internal (linux) dataplane driver.
2021-02-06 07:39:56.523 [INFO][77] driver.go 85: Calculated iptables mark bits acceptMark=0x10000 endpointMark=0xfff00000 endpointMarkNonCali=0x100000 passMark=0x20000 scratch0Mark=0x40000 scratch1Mark=0x80000
2021-02-06 07:39:56.523 [INFO][77] int_dataplane.go 238: Creating internal dataplane driver. config=intdataplane.Config{Hostname:"b0108011", IPv6Enabled:false, RuleRendererOverride:rules.RuleRenderer(nil), IPIPMTU:1440, VXLANMTU:1410, IgnoreLooseRPF:false, MaxIPSetSize:1048576, IptablesBackend:"legacy", IPSetsRefreshInterval:10000000000, RouteRefreshInterval:90000000000, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckInterval:1000000000, IptablesInsertMode:"insert", IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeout:0, IptablesLockProbeInterval:50000000, XDPRefreshInterval:90000000000, NetlinkTimeout:10000000000, RulesConfig:rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc0001a66e0), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc0001a6820), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x100000, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:true, IPIPTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xac, 0x21, 0x1, 0x80}, VXLANTunnelAddress:net.IP(nil), 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: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:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil)}, IfaceMonitorConfig:ifacemonitor.Config{InterfaceExcludes:[]*regexp.Regexp{(*regexp.Regexp)(0xc000263a40)}}, StatusReportingInterval:0, ConfigChangedRestartCallback:(func())(0x1464ab0), PostInSyncCallback:(func())(0x144b060), HealthAggregator:(*health.HealthAggregator)(0xc00049e6f0), DebugSimulateDataplaneHangAfter:0, ExternalNodesCidrs:[]string(nil), XDPEnabled:false, XDPAllowGeneric:false, SidecarAccelerationEnabled:false, LookPathOverride:(func(string) (string, error))(nil)}
2021-02-06 07:39:56.524 [INFO][77] rule_defs.go 294: Creating rule renderer. config=rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc0001a66e0), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc0001a6820), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x100000, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:true, IPIPTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xac, 0x21, 0x1, 0x80}, VXLANTunnelAddress:net.IP(nil), 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: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:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil)}
2021-02-06 07:39:56.524 [INFO][77] rule_defs.go 304: Workload to host packets will be accepted.
2021-02-06 07:39:56.524 [INFO][77] rule_defs.go 318: filter table allowed packets will be accepted immediately.
2021-02-06 07:39:56.524 [INFO][77] rule_defs.go 326: mangle table allowed packets will be accepted immediately.
2021-02-06 07:39:56.528 [INFO][77] feature_detect.go 110: Updating detected iptables features features=iptables.Features{SNATFullyRandom:false, MASQFullyRandom:false, RestoreSupportsLock:true} iptablesVersion=1.8.2 kernelVersion=3.10.0
2021-02-06 07:39:56.528 [INFO][77] table.go 444: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2021-02-06 07:39:56.529 [INFO][77] table.go 444: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-02-06 07:39:56.529 [INFO][77] table.go 444: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2021-02-06 07:39:56.530 [INFO][77] table.go 444: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-02-06 07:39:56.530 [INFO][77] table.go 444: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2021-02-06 07:39:56.532 [INFO][77] table.go 444: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-02-06 07:39:56.532 [INFO][77] table.go 444: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2021-02-06 07:39:56.532 [INFO][77] table.go 444: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2021-02-06 07:39:56.533 [INFO][77] route_table.go 158: Calculated interface name regexp regex="^cali.*"
2021-02-06 07:39:56.533 [INFO][77] int_dataplane.go 360: Checking if we need to clean up the VXLAN device
2021-02-06 07:39:56.533 [WARNING][77] int_dataplane.go 362: Failed to query VXLAN device error=Link not found
2021-02-06 07:39:56.533 [INFO][77] int_dataplane.go 386: XDP acceleration disabled.
2021-02-06 07:39:56.534 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="all-ipam-pools" setType="hash:net"
2021-02-06 07:39:56.535 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="masq-ipam-pools" setType="hash:net"
2021-02-06 07:39:56.535 [INFO][77] int_dataplane.go 542: Registering to report health.
2021-02-06 07:39:56.535 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="raw"
2021-02-06 07:39:56.535 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="raw"
2021-02-06 07:39:56.535 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="raw"
2021-02-06 07:39:56.535 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="raw"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-FORWARD" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-INPUT" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-wl-to-host" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.536 [INFO][77] int_dataplane.go 681: IPIP enabled, starting thread to keep tunnel configuration in sync.
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="nat"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="nat"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="nat"
2021-02-06 07:39:56.536 [INFO][77] ipip_mgr.go 85: IPIP thread started.
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="mangle"
2021-02-06 07:39:56.536 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="mangle"
2021-02-06 07:39:56.536 [INFO][77] daemon.go 300: Connect to the dataplane driver.
2021-02-06 07:39:56.537 [INFO][77] int_dataplane.go 1219: Started internal status report thread
2021-02-06 07:39:56.537 [INFO][77] int_dataplane.go 774: Started internal iptables dataplane driver loop
2021-02-06 07:39:56.537 [INFO][77] int_dataplane.go 1221: Process status reports disabled
2021-02-06 07:39:56.537 [INFO][77] iface_monitor.go 89: Interface monitoring thread started.
2021-02-06 07:39:56.537 [INFO][77] daemon.go 372: Created Syncer syncer=&watchersyncer.watcherSyncer{status:0x0, watcherCaches:[]*watchersyncer.watcherCache{(*watchersyncer.watcherCache)(0xc0006ca100), (*watchersyncer.watcherCache)(0xc0006ca180), (*watchersyncer.watcherCache)(0xc0006ca200), (*watchersyncer.watcherCache)(0xc0006ca280), (*watchersyncer.watcherCache)(0xc0006ca300), (*watchersyncer.watcherCache)(0xc0006ca400), (*watchersyncer.watcherCache)(0xc0006ca480), (*watchersyncer.watcherCache)(0xc0006ca580), (*watchersyncer.watcherCache)(0xc0006ca600), (*watchersyncer.watcherCache)(0xc0006ca680), (*watchersyncer.watcherCache)(0xc0006ca700), (*watchersyncer.watcherCache)(0xc0006ca780)}, results:(chan interface {})(0xc0001a4c00), numSynced:0, callbacks:(*calc.SyncerCallbacksDecoupler)(0xc000690030), wgwc:(*sync.WaitGroup)(nil), wgws:(*sync.WaitGroup)(nil), cancel:(context.CancelFunc)(nil)}
2021-02-06 07:39:56.537 [INFO][77] int_dataplane.go 784: Will refresh IP sets on timer interval=1m30s
2021-02-06 07:39:56.537 [INFO][77] iface_monitor.go 96: Subscribed to netlink updates.
2021-02-06 07:39:56.537 [INFO][77] int_dataplane.go 794: Will refresh routes on timer interval=1m30s
2021-02-06 07:39:56.537 [INFO][77] calc_graph.go 110: Creating calculation graph, filtered to hostname b0108011
2021-02-06 07:39:56.537 [INFO][77] ipip_mgr.go 183: Address wasn't present, adding it. addr=172.33.1.128 link="tunl0"
2021-02-06 07:39:56.538 [INFO][77] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x132e4a0)
2021-02-06 07:39:56.538 [INFO][77] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x132e4a0)
2021-02-06 07:39:56.538 [INFO][77] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x132e580)
2021-02-06 07:39:56.538 [INFO][77] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x132e580)
2021-02-06 07:39:56.538 [INFO][77] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x132e370)
2021-02-06 07:39:56.538 [INFO][77] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x132e370)
2021-02-06 07:39:56.538 [INFO][77] dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x132e370)
2021-02-06 07:39:56.538 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="lo" state="up"
2021-02-06 07:39:56.540 [INFO][77] dispatcher.go 68: Registering listener for type model.ProfileRulesKey: (dispatcher.UpdateHandler)(0x132e370)
2021-02-06 07:39:56.540 [INFO][77] dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x132e370)
2021-02-06 07:39:56.540 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"lo", State:"up"}
2021-02-06 07:39:56.540 [INFO][77] dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x132e370)
2021-02-06 07:39:56.541 [INFO][77] dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x1289640)
2021-02-06 07:39:56.541 [INFO][77] dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x1289640)
2021-02-06 07:39:56.541 [INFO][77] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1289640)
2021-02-06 07:39:56.541 [INFO][77] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1289640)
2021-02-06 07:39:56.541 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{"127.0.0.1":set.empty{}} ifaceName="lo"
2021-02-06 07:39:56.541 [INFO][77] dispatcher.go 68: Registering listener for type model.NetworkSetKey: (dispatcher.UpdateHandler)(0x1289640)
2021-02-06 07:39:56.541 [INFO][77] dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x132e7d0)
2021-02-06 07:39:56.541 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="em1" state="up"
2021-02-06 07:39:56.541 [INFO][77] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x132e7d0)
2021-02-06 07:39:56.542 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.1":set.empty{}}}
2021-02-06 07:39:56.542 [INFO][77] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x132e7d0)
2021-02-06 07:39:56.542 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.1":set.empty{}}}
2021-02-06 07:39:56.542 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="em1"
2021-02-06 07:39:56.542 [INFO][77] dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x132e6f0)
2021-02-06 07:39:56.542 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-02-06 07:39:56.543 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="em2" state="up"
2021-02-06 07:39:56.543 [INFO][77] dispatcher.go 68: Registering listener for type model.IPPoolKey: (dispatcher.UpdateHandler)(0x132e6f0)
2021-02-06 07:39:56.544 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"em1", Addrs:set.mapSet{}}
2021-02-06 07:39:56.544 [INFO][77] dispatcher.go 68: Registering listener for type model.GlobalConfigKey: (dispatcher.UpdateHandler)(0x132e610)
2021-02-06 07:39:56.544 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="em2"
2021-02-06 07:39:56.544 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"em1", Addrs:set.mapSet{}}
2021-02-06 07:39:56.544 [INFO][77] dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x132e610)
2021-02-06 07:39:56.544 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="em3" state="up"
2021-02-06 07:39:56.544 [INFO][77] dispatcher.go 68: Registering listener for type model.ReadyFlagKey: (dispatcher.UpdateHandler)(0x132e610)
2021-02-06 07:39:56.544 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-02-06 07:39:56.544 [INFO][77] dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x132e920)
2021-02-06 07:39:56.544 [INFO][77] dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x132e9b0)
2021-02-06 07:39:56.544 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"em2", Addrs:set.mapSet{}}
2021-02-06 07:39:56.545 [INFO][77] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x132e9b0)
2021-02-06 07:39:56.545 [INFO][77] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x132e9b0)
2021-02-06 07:39:56.545 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"em2", Addrs:set.mapSet{}}
2021-02-06 07:39:56.545 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{"43.230.89.82":set.empty{}} ifaceName="em3"
2021-02-06 07:39:56.545 [INFO][77] dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x132e9b0)
2021-02-06 07:39:56.545 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-02-06 07:39:56.545 [INFO][77] daemon.go 440: Starting the datastore Syncer
2021-02-06 07:39:56.545 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="em4" state="up"
2021-02-06 07:39:56.545 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"em3", Addrs:set.mapSet{"43.230.89.82":set.empty{}}}
2021-02-06 07:39:56.545 [INFO][77] watchersyncer.go 89: Start called
2021-02-06 07:39:56.546 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"em3", Addrs:set.mapSet{"43.230.89.82":set.empty{}}}
2021-02-06 07:39:56.546 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{"103.108.2.132":set.empty{}} ifaceName="em4"
2021-02-06 07:39:56.547 [INFO][77] async_calc_graph.go 229: Starting AsyncCalcGraph
2021-02-06 07:39:56.547 [INFO][77] watchersyncer.go 127: Sending status update Status=wait-for-ready
2021-02-06 07:39:56.547 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="bond0" state="up"
2021-02-06 07:39:56.547 [INFO][77] daemon.go 472: Started the processing graph
2021-02-06 07:39:56.547 [INFO][77] watchersyncer.go 147: Starting main event processing loop
2021-02-06 07:39:56.547 [INFO][77] async_calc_graph.go 134: AsyncCalcGraph running
2021-02-06 07:39:56.547 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{"172.22.108.11":set.empty{}} ifaceName="bond0"
2021-02-06 07:39:56.547 [INFO][77] daemon.go 832: Reading from dataplane driver pipe...
2021-02-06 07:39:56.547 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{"172.30.60.1":set.empty{}} ifaceName="docker0"
2021-02-06 07:39:56.547 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="tunl0" state="up"
2021-02-06 07:39:56.547 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-02-06 07:39:56.548 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{"172.33.1.128":set.empty{}} ifaceName="tunl0"
2021-02-06 07:39:56.548 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"em4", Addrs:set.mapSet{"103.108.2.132":set.empty{}}}
2021-02-06 07:39:56.548 [INFO][77] daemon.go 565: No driver process to monitor
2021-02-06 07:39:56.548 [INFO][77] iface_monitor.go 176: Netlink address update. addr="172.33.1.128" exists=true ifIndex=69
2021-02-06 07:39:56.548 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"em4", Addrs:set.mapSet{"103.108.2.132":set.empty{}}}
2021-02-06 07:39:56.550 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-02-06 07:39:56.551 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"bond0", Addrs:set.mapSet{"172.22.108.11":set.empty{}}}
2021-02-06 07:39:56.551 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"bond0", Addrs:set.mapSet{"172.22.108.11":set.empty{}}}
2021-02-06 07:39:56.551 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-02-06 07:39:56.551 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"docker0", Addrs:set.mapSet{"172.30.60.1":set.empty{}}}
2021-02-06 07:39:56.551 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"docker0", Addrs:set.mapSet{"172.30.60.1":set.empty{}}}
2021-02-06 07:39:56.551 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-02-06 07:39:56.551 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tunl0", Addrs:set.mapSet{"172.33.1.128":set.empty{}}}
2021-02-06 07:39:56.551 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tunl0", Addrs:set.mapSet{"172.33.1.128":set.empty{}}}
2021-02-06 07:39:56.552 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2021-02-06 07:39:56.553 [INFO][77] int_dataplane.go 820: Received *proto.ConfigUpdate update from calculation graph msg=config:<key:"CalicoVersion" value:"v3.8.2" > config:<key:"ClusterGUID" value:"736bd25180004c95ad233605f227cee4" > config:<key:"ClusterType" value:"k8s,bgp" > config:<key:"DefaultEndpointToHostAction" value:"ACCEPT" > config:<key:"EtcdCaFile" value:"/calico-secrets/etcd-ca" > config:<key:"EtcdCertFile" value:"/calico-secrets/etcd-cert" > config:<key:"EtcdEndpoints" value:"https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379" > config:<key:"EtcdKeyFile" value:"/calico-secrets/etcd-key" > config:<key:"FelixHostname" value:"b0108011" > config:<key:"HealthEnabled" value:"true" > config:<key:"IpInIpEnabled" value:"true" > config:<key:"IpInIpMtu" value:"1440" > config:<key:"IpInIpTunnelAddr" value:"172.33.1.128" > 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" > 
2021-02-06 07:39:56.553 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"em1", State:"up"}
2021-02-06 07:39:56.554 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"em2", State:"up"}
2021-02-06 07:39:56.554 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"em3", State:"up"}
2021-02-06 07:39:56.554 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"em4", State:"up"}
2021-02-06 07:39:56.554 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"bond0", State:"up"}
2021-02-06 07:39:56.554 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tunl0", State:"up"}
2021-02-06 07:39:56.561 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/networksets"
2021-02-06 07:39:56.561 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.561 [INFO][77] watchersyncer.go 127: Sending status update Status=resync
2021-02-06 07:39:56.562 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/globalnetworksets"
2021-02-06 07:39:56.563 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.563 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/hostendpoints"
2021-02-06 07:39:56.563 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.563 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/networkpolicies"
2021-02-06 07:39:56.563 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.564 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2021-02-06 07:39:56.564 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.566 [INFO][77] config_batcher.go 61: Host config update for this host: {{HostConfig(node=b0108011,name=IpInIpTunnelAddr) 172.33.1.128 89574214 <nil> 0s} 1}
2021-02-06 07:39:56.566 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/clusterinformations"
2021-02-06 07:39:56.567 [INFO][77] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=LogSeverityScreen) Debug 89404644 <nil> 0s} 1}
2021-02-06 07:39:56.567 [INFO][77] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=IpInIpEnabled) true 89404644 <nil> 0s} 1}
2021-02-06 07:39:56.567 [INFO][77] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ReportingIntervalSecs) 0 89404644 <nil> 0s} 1}
2021-02-06 07:39:56.567 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.567 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/globalnetworkpolicies"
2021-02-06 07:39:56.567 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.568 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/felixconfigurations"
2021-02-06 07:39:56.570 [INFO][77] int_dataplane.go 820: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"b0108011" ipv4_addr:"172.22.108.11" 
2021-02-06 07:39:56.570 [INFO][77] int_dataplane.go 820: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"b2106104" ipv4_addr:"10.100.106.104" 
2021-02-06 07:39:56.570 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.570 [INFO][77] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterGUID) 736bd25180004c95ad233605f227cee4 755 <nil> 0s} 1}
2021-02-06 07:39:56.570 [INFO][77] int_dataplane.go 820: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"b2106107" ipv4_addr:"10.100.106.107" 
2021-02-06 07:39:56.571 [INFO][77] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterType) k8s,bgp 755 <nil> 0s} 1}
2021-02-06 07:39:56.571 [INFO][77] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=CalicoVersion) v3.8.2 755 <nil> 0s} 1}
2021-02-06 07:39:56.571 [INFO][77] config_batcher.go 61: Host config update for this host: {{HostConfig(node=b0108011,name=DefaultEndpointToHostAction) Return 89573152 <nil> 0s} 1}
2021-02-06 07:39:56.574 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2021-02-06 07:39:56.574 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.576 [INFO][75] resource.go 260: Target config /tmp/tunl-ip has been updated
2021-02-06 07:39:56.577 [INFO][77] int_dataplane.go 820: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"172.33.2.0-24" pool:<cidr:"172.33.2.0/24" masquerade:true > 
2021-02-06 07:39:56.578 [INFO][77] int_dataplane.go 820: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"172.33.3.0-24" pool:<cidr:"172.33.3.0/24" masquerade:true > 
2021-02-06 07:39:56.578 [INFO][77] int_dataplane.go 820: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"172.33.4.0-24" pool:<cidr:"172.33.4.0/24" masquerade:true > 
2021-02-06 07:39:56.578 [INFO][77] int_dataplane.go 820: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"172.33.5.0-24" pool:<cidr:"172.33.5.0/24" masquerade:true > 
2021-02-06 07:39:56.578 [INFO][77] int_dataplane.go 820: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"172.33.6.0-24" pool:<cidr:"172.33.6.0/24" masquerade:true > 
2021-02-06 07:39:56.580 [INFO][77] int_dataplane.go 820: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"172.33.7.0-24" pool:<cidr:"172.33.7.0/24" masquerade:true > 
2021-02-06 07:39:56.581 [INFO][77] int_dataplane.go 820: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"172.33.9.0-24" pool:<cidr:"172.33.9.0/24" masquerade:true > 
2021-02-06 07:39:56.581 [INFO][77] int_dataplane.go 820: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"172.33.1.0-24" pool:<cidr:"172.33.1.0/24" masquerade:true > 
2021-02-06 07:39:56.586 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/profiles"
2021-02-06 07:39:56.587 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"cattle-system" > labels:<key:"field.cattle.io/projectId" value:"p-sjghg" > 
2021-02-06 07:39:56.588 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.588 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"default" > labels:<key:"field.cattle.io/projectId" value:"p-b6rck" > 
2021-02-06 07:39:56.591 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/ipam/v2/assignment/"
2021-02-06 07:39:56.593 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.597 [INFO][77] int_dataplane.go 820: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"ingress-nginx" name:"ingress-nginx-nginx-ingress-backend" > labels:<key:"app" value:"nginx-ingress" > labels:<key:"chart" value:"nginx-ingress-2.1.1013" > labels:<key:"heritage" value:"Tiller" > labels:<key:"release" value:"ingress-nginx" > 
2021-02-06 07:39:56.597 [INFO][77] int_dataplane.go 820: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"coredns" > labels:<key:"addonmanager.kubernetes.io/mode" value:"Reconcile" > labels:<key:"kubernetes.io/cluster-service" value:"true" > 
2021-02-06 07:39:56.597 [INFO][77] int_dataplane.go 820: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"kubernetes-dashboard" > labels:<key:"k8s-app" value:"kubernetes-dashboard" > 
2021-02-06 07:39:56.597 [INFO][77] int_dataplane.go 820: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kubeapps" name:"kubeapps-internal-apprepository-controller" > labels:<key:"app" value:"kubeapps-internal-apprepository-controller" > labels:<key:"chart" value:"kubeapps-3.0.1" > labels:<key:"heritage" value:"Tiller" > labels:<key:"release" value:"kubeapps" > 
2021-02-06 07:39:56.598 [INFO][77] int_dataplane.go 820: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kubeapps" name:"kubeapps-internal-tiller-proxy" > labels:<key:"app" value:"kubeapps-internal-tiller-proxy" > labels:<key:"chart" value:"kubeapps-3.0.1" > labels:<key:"heritage" value:"Tiller" > labels:<key:"release" value:"kubeapps" > 
2021-02-06 07:39:56.598 [INFO][77] int_dataplane.go 820: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"develop" name:"knotted-hydra-nginx-ingress" > labels:<key:"app" value:"nginx-ingress" > labels:<key:"chart" value:"nginx-ingress-1.6.17" > labels:<key:"heritage" value:"Tiller" > labels:<key:"release" value:"knotted-hydra" > 
2021-02-06 07:39:56.598 [INFO][77] int_dataplane.go 820: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"develop" name:"nginx-ingress" > labels:<key:"app" value:"nginx-ingress" > labels:<key:"chart" value:"nginx-ingress-1.6.17" > labels:<key:"heritage" value:"Tiller" > labels:<key:"release" value:"nginx-ingress" > 
2021-02-06 07:39:56.598 [INFO][77] int_dataplane.go 820: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"ingress-nginx" name:"ingress-nginx-nginx-ingress" > labels:<key:"app" value:"nginx-ingress" > labels:<key:"chart" value:"nginx-ingress-2.1.1013" > labels:<key:"heritage" value:"Tiller" > labels:<key:"release" value:"ingress-nginx" > 
2021-02-06 07:39:56.598 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"ingress-nginx" > labels:<key:"field.cattle.io/projectId" value:"p-kbcgk" > labels:<key:"name" value:"ingress-nginx" > 
2021-02-06 07:39:56.598 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"ingress-nginx-test" > labels:<key:"cattle.io/creator" value:"norman" > labels:<key:"field.cattle.io/projectId" value:"p-z2gv2" > 
2021-02-06 07:39:56.598 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"integration" > labels:<key:"field.cattle.io/projectId" value:"p-kbcgk" > 
2021-02-06 07:39:56.598 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-node-lease" > labels:<key:"field.cattle.io/projectId" value:"p-vqwxt" > 
2021-02-06 07:39:56.599 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"test" > labels:<key:"field.cattle.io/projectId" value:"p-kbcgk" > 
2021-02-06 07:39:56.599 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"develop" > labels:<key:"field.cattle.io/projectId" value:"p-kbcgk" > 
2021-02-06 07:39:56.599 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"helmfiletest" > labels:<key:"cattle.io/creator" value:"norman" > labels:<key:"field.cattle.io/projectId" value:"p-v6cs5" > 
2021-02-06 07:39:56.599 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-public" > labels:<key:"field.cattle.io/projectId" value:"p-vqwxt" > 
2021-02-06 07:39:56.599 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-system" > labels:<key:"field.cattle.io/projectId" value:"p-vqwxt" > 
2021-02-06 07:39:56.599 [INFO][77] int_dataplane.go 820: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"nginx-ingress" > labels:<key:"field.cattle.io/projectId" value:"p-z2gv2" > labels:<key:"name" value:"nginx-ingress" > 
2021-02-06 07:39:56.609 [INFO][77] watchercache.go 289: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/workloadendpoints"
2021-02-06 07:39:56.609 [INFO][77] watchersyncer.go 209: Received InSync event from one of the watcher caches
2021-02-06 07:39:56.609 [INFO][77] watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2021-02-06 07:39:56.609 [INFO][77] watchersyncer.go 127: Sending status update Status=in-sync
2021-02-06 07:39:56.611 [INFO][77] config_batcher.go 102: Datamodel in sync, flushing config update
2021-02-06 07:39:56.611 [INFO][77] config_batcher.go 112: Sending config update global: map[LogSeverityScreen:Debug IpInIpEnabled:true ReportingIntervalSecs:0 ClusterGUID:736bd25180004c95ad233605f227cee4 ClusterType:k8s,bgp CalicoVersion:v3.8.2], host: map[IpInIpTunnelAddr:172.33.1.128 DefaultEndpointToHostAction:Return].
2021-02-06 07:39:56.611 [INFO][77] async_calc_graph.go 160: First time we've been in sync
2021-02-06 07:39:56.612 [INFO][77] event_sequencer.go 222: Possible config update. global=map[string]string{"LogSeverityScreen":"Debug", "IpInIpEnabled":"true", "ReportingIntervalSecs":"0", "ClusterGUID":"736bd25180004c95ad233605f227cee4", "ClusterType":"k8s,bgp", "CalicoVersion":"v3.8.2"} host=map[string]string{"IpInIpTunnelAddr":"172.33.1.128", "DefaultEndpointToHostAction":"Return"}
2021-02-06 07:39:56.613 [INFO][77] usagerep.go 83: Waiting before first check-in delay=5m0.523s
2021-02-06 07:39:56.613 [INFO][77] config_params.go 243: Merging in config from datastore (global): map[ReportingIntervalSecs:0 ClusterGUID:736bd25180004c95ad233605f227cee4 ClusterType:k8s,bgp CalicoVersion:v3.8.2 LogSeverityScreen:Debug IpInIpEnabled:true]
2021-02-06 07:39:56.613 [INFO][77] config_params.go 324: Parsing value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.613 [INFO][77] config_params.go 360: Parsed value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.613 [INFO][77] config_params.go 324: Parsing value for EtcdEndpoints: https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379 (from environment variable)
2021-02-06 07:39:56.613 [INFO][77] config_params.go 360: Parsed value for EtcdEndpoints: [https://172.22.108.11:2379/ https://172.22.108.12:2379/ https://10.100.106.15:2379/] (from environment variable)
2021-02-06 07:39:56.613 [INFO][77] config_params.go 324: Parsing value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.613 [INFO][77] config_params.go 360: Parsed value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.613 [INFO][77] config_params.go 324: Parsing value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.613 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-key"
2021-02-06 07:39:56.614 [INFO][77] config_params.go 360: Parsed value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 324: Parsing value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 360: Parsed value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: info (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: INFO (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 324: Parsing value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-ca"
2021-02-06 07:39:56.614 [INFO][77] config_params.go 360: Parsed value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 324: Parsing value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-cert"
2021-02-06 07:39:56.614 [INFO][77] config_params.go 360: Parsed value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 324: Parsing value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 360: Parsed value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.614 [INFO][77] config_params.go 324: Parsing value for LogSeverityFile: None (from config file)
2021-02-06 07:39:56.615 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.615 [INFO][77] config_params.go 360: Parsed value for LogSeverityFile:  (from config file)
2021-02-06 07:39:56.615 [INFO][77] config_params.go 324: Parsing value for LogSeveritySys: None (from config file)
2021-02-06 07:39:56.615 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.615 [INFO][77] config_params.go 360: Parsed value for LogSeveritySys:  (from config file)
2021-02-06 07:39:56.615 [INFO][77] config_params.go 324: Parsing value for MetadataAddr: None (from config file)
2021-02-06 07:39:56.615 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.615 [INFO][77] config_params.go 360: Parsed value for MetadataAddr:  (from config file)
2021-02-06 07:39:56.615 [INFO][77] config_params.go 324: Parsing value for LogFilePath: None (from config file)
2021-02-06 07:39:56.615 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.615 [INFO][77] config_params.go 360: Parsed value for LogFilePath:  (from config file)
2021-02-06 07:39:56.615 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: Return (from datastore (per-host))
2021-02-06 07:39:56.615 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: RETURN (from datastore (per-host))
2021-02-06 07:39:56.615 [INFO][77] config_params.go 363: Skipping config value for DefaultEndpointToHostAction from datastore (per-host); already have a value from environment variable
2021-02-06 07:39:56.615 [INFO][77] config_params.go 324: Parsing value for IpInIpTunnelAddr: 172.33.1.128 (from datastore (per-host))
2021-02-06 07:39:56.615 [INFO][77] config_params.go 360: Parsed value for IpInIpTunnelAddr: 172.33.1.128 (from datastore (per-host))
2021-02-06 07:39:56.615 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: Debug (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: DEBUG (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 363: Skipping config value for LogSeverityScreen from datastore (global); already have a value from environment variable
2021-02-06 07:39:56.616 [INFO][77] config_params.go 324: Parsing value for IpInIpEnabled: true (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 360: Parsed value for IpInIpEnabled: true (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 324: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 360: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 324: Parsing value for ClusterGUID: 736bd25180004c95ad233605f227cee4 (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 360: Parsed value for ClusterGUID: 736bd25180004c95ad233605f227cee4 (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 324: Parsing value for ClusterType: k8s,bgp (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 360: Parsed value for ClusterType: k8s,bgp (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 324: Parsing value for CalicoVersion: v3.8.2 (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 360: Parsed value for CalicoVersion: v3.8.2 (from datastore (global))
2021-02-06 07:39:56.616 [INFO][77] config_params.go 243: Merging in config from datastore (per-host): map[DefaultEndpointToHostAction:Return IpInIpTunnelAddr:172.33.1.128]
2021-02-06 07:39:56.616 [INFO][77] config_params.go 324: Parsing value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.616 [INFO][77] config_params.go 360: Parsed value for HealthEnabled: true (from environment variable)
2021-02-06 07:39:56.616 [INFO][77] config_params.go 324: Parsing value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 360: Parsed value for FelixHostname: b0108011 (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: info (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: INFO (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 324: Parsing value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-ca"
2021-02-06 07:39:56.617 [INFO][77] config_params.go 360: Parsed value for EtcdCaFile: /calico-secrets/etcd-ca (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 324: Parsing value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-cert"
2021-02-06 07:39:56.617 [INFO][77] config_params.go 360: Parsed value for EtcdCertFile: /calico-secrets/etcd-cert (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 324: Parsing value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] param_types.go 267: Looking for required file path="/calico-secrets/etcd-key"
2021-02-06 07:39:56.617 [INFO][77] config_params.go 360: Parsed value for EtcdKeyFile: /calico-secrets/etcd-key (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 324: Parsing value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 360: Parsed value for IpInIpMtu: 1440 (from environment variable)
2021-02-06 07:39:56.617 [INFO][77] config_params.go 324: Parsing value for EtcdEndpoints: https://172.22.108.11:2379,https://172.22.108.12:2379,https://10.100.106.15:2379 (from environment variable)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 360: Parsed value for EtcdEndpoints: [https://172.22.108.11:2379/ https://172.22.108.12:2379/ https://10.100.106.15:2379/] (from environment variable)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 324: Parsing value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 360: Parsed value for Ipv6Support: false (from environment variable)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 324: Parsing value for MetadataAddr: None (from config file)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.618 [INFO][77] config_params.go 360: Parsed value for MetadataAddr:  (from config file)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 324: Parsing value for LogFilePath: None (from config file)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.618 [INFO][77] config_params.go 360: Parsed value for LogFilePath:  (from config file)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 324: Parsing value for LogSeverityFile: None (from config file)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.618 [INFO][77] config_params.go 360: Parsed value for LogSeverityFile:  (from config file)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 324: Parsing value for LogSeveritySys: None (from config file)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 341: Value set to 'none', replacing with zero-value: "".
2021-02-06 07:39:56.618 [INFO][77] config_params.go 360: Parsed value for LogSeveritySys:  (from config file)
2021-02-06 07:39:56.618 [INFO][77] config_params.go 324: Parsing value for IpInIpTunnelAddr: 172.33.1.128 (from datastore (per-host))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 360: Parsed value for IpInIpTunnelAddr: 172.33.1.128 (from datastore (per-host))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 324: Parsing value for DefaultEndpointToHostAction: Return (from datastore (per-host))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 360: Parsed value for DefaultEndpointToHostAction: RETURN (from datastore (per-host))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 363: Skipping config value for DefaultEndpointToHostAction from datastore (per-host); already have a value from environment variable
2021-02-06 07:39:56.619 [INFO][77] config_params.go 324: Parsing value for CalicoVersion: v3.8.2 (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 360: Parsed value for CalicoVersion: v3.8.2 (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 324: Parsing value for LogSeverityScreen: Debug (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 360: Parsed value for LogSeverityScreen: DEBUG (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 363: Skipping config value for LogSeverityScreen from datastore (global); already have a value from environment variable
2021-02-06 07:39:56.619 [INFO][77] config_params.go 324: Parsing value for IpInIpEnabled: true (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 360: Parsed value for IpInIpEnabled: true (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 324: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 360: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 324: Parsing value for ClusterGUID: 736bd25180004c95ad233605f227cee4 (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 360: Parsed value for ClusterGUID: 736bd25180004c95ad233605f227cee4 (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 324: Parsing value for ClusterType: k8s,bgp (from datastore (global))
2021-02-06 07:39:56.619 [INFO][77] config_params.go 360: Parsed value for ClusterType: k8s,bgp (from datastore (global))
2021-02-06 07:39:56.620 [INFO][77] async_calc_graph.go 209: First flush after becoming in sync, sending InSync message.
2021-02-06 07:39:56.620 [INFO][77] daemon.go 916: Datastore now in sync.
2021-02-06 07:39:56.620 [INFO][77] daemon.go 918: Datastore in sync for first time, sending message to status reporter.
2021-02-06 07:39:56.620 [INFO][77] int_dataplane.go 820: Received *proto.InSync update from calculation graph msg=
2021-02-06 07:39:56.620 [INFO][77] int_dataplane.go 828: Datastore in sync, flushing the dataplane for the first time... timeSinceStart=169.784759ms
2021-02-06 07:39:56.620 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:39:56.620 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.620 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.620 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.620 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.620 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-from-hep-forward" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.621 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-to-hep-forward" ipVersion=0x4 table="filter"
2021-02-06 07:39:56.621 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="mangle"
2021-02-06 07:39:56.621 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="raw"
2021-02-06 07:39:56.621 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="raw"
2021-02-06 07:39:56.621 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-fip-dnat" ipVersion=0x4 table="nat"
2021-02-06 07:39:56.621 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-fip-snat" ipVersion=0x4 table="nat"
2021-02-06 07:39:56.621 [INFO][77] masq_mgr.go 144: IPAM pools updated, refreshing iptables rule ipVersion=0x4
2021-02-06 07:39:56.622 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-nat-outgoing" ipVersion=0x4 table="nat"
2021-02-06 07:39:56.622 [INFO][77] ipip_mgr.go 222: All-hosts IP set out-of sync, refreshing it.
2021-02-06 07:39:56.622 [INFO][77] ipsets.go 119: Queueing IP set for creation family="inet" setID="all-hosts-net" setType="hash:net"
2021-02-06 07:39:56.622 [INFO][77] route_table.go 232: Trying to connect to netlink
2021-02-06 07:39:56.622 [INFO][77] ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-02-06 07:39:56.627 [INFO][77] ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=3.984148ms
2021-02-06 07:39:56.627 [INFO][77] ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=8 setID="all-ipam-pools"
2021-02-06 07:39:56.628 [INFO][77] ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=8 setID="masq-ipam-pools"
2021-02-06 07:39:56.628 [INFO][77] ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=6 setID="this-host"
2021-02-06 07:39:56.628 [INFO][77] ipsets.go 749: Doing full IP set rewrite family="inet" numMembersInPendingReplace=3 setID="all-hosts-net"
2021-02-06 07:39:56.633 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-02-06 07:39:56.636 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-02-06 07:39:56.638 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-02-06 07:39:56.642 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:39:56.667 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=47.203936
2021-02-06 07:39:56.668 [INFO][77] int_dataplane.go 965: Completed first update to dataplane. secsSinceStart=0.217663771
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: Initializing
bird: direct1: Initializing
bird: Mesh_10_100_106_104: Initializing
bird: Mesh_10_100_106_107: 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: Mesh_10_100_106_104: Starting
bird: Mesh_10_100_106_104: State changed to start
bird: Mesh_10_100_106_107: Starting
bird: Mesh_10_100_106_107: State changed to start
bird: Graceful restart started
bird: Started
bird: device1: State changed to up
bird: direct1: State changed to up
2021-02-06 07:39:57.566 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:39:57.566 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-02-06 07:39:57.566 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-02-06 07:39:57.569 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-02-06 07:39:57.572 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-02-06 07:39:57.576 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=9.797201
2021-02-06 07:39:57.666 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:39:57.667 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:39:57.669 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:39:57.679 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=12.044081
2021-02-06 07:39:57.679 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:39:57.679 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-02-06 07:39:57.682 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-02-06 07:39:57.697 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=17.204886
2021-02-06 07:39:58.546 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:39:58.546 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-02-06 07:39:58.546 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-02-06 07:39:58.549 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-02-06 07:39:58.552 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-02-06 07:39:58.557 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=11.326661
2021-02-06 07:39:58.668 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:39:58.668 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:39:58.668 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-02-06 07:39:58.671 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:39:58.674 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-02-06 07:39:58.689 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=20.807804
bird: Mesh_10_100_106_107: Connected to table master
bird: Mesh_10_100_106_107: State changed to wait
bird: Mesh_10_100_106_104: Connected to table master
bird: Mesh_10_100_106_104: State changed to wait
bird: Graceful restart done
bird: Mesh_10_100_106_104: State changed to feed
bird: Mesh_10_100_106_107: State changed to feed
2021-02-06 07:39:59.501 [INFO][77] health.go 178: Overall health status changed lastSummary=&health.HealthReport{Live:true, Ready:true}
bird: Mesh_10_100_106_104: State changed to up
bird: Mesh_10_100_106_107: State changed to up
2021-02-06 07:40:00.549 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:00.549 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-02-06 07:40:00.549 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-02-06 07:40:00.552 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-02-06 07:40:00.555 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-02-06 07:40:00.560 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=10.182547999999999
2021-02-06 07:40:00.667 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:00.667 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-02-06 07:40:00.667 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:00.670 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-02-06 07:40:00.674 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:00.687 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=19.436044
2021-02-06 07:40:04.054 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali7fbc0230cb1"
2021-02-06 07:40:04.055 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali7fbc0230cb1", Addrs:set.mapSet{}}
2021-02-06 07:40:04.055 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali7fbc0230cb1", Addrs:set.mapSet{}}
2021-02-06 07:40:04.055 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:04.057 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=1.320751
2021-02-06 07:40:04.072 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="cali7fbc0230cb1" state="up"
2021-02-06 07:40:04.072 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali7fbc0230cb1", State:"up"}
2021-02-06 07:40:04.072 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:04.072 [INFO][77] endpoint_mgr.go 328: Workload interface came up, marking for reconfiguration. ifaceName="cali7fbc0230cb1"
2021-02-06 07:40:04.072 [INFO][77] endpoint_mgr.go 941: Applying /proc/sys configuration to interface. ifaceName="cali7fbc0230cb1"
2021-02-06 07:40:04.073 [INFO][77] route_table.go 524: Syncing routes: found unexpected route; ignoring due to grace period. dest=172.33.1.129/32 ifaceName="cali7fbc0230cb1" ipVersion=0x4
2021-02-06 07:40:04.073 [INFO][77] route_table.go 349: Interface in cleanup grace period, will retry after. ifaceName="cali7fbc0230cb1" ipVersion=0x4
2021-02-06 07:40:04.073 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=0.992114
2021-02-06 07:40:04.076 [INFO][77] calc_graph.go 392: Local endpoint updated id=WorkloadEndpoint(node=b0108011, orchestrator=k8s, workload=kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc, name=eth0)
2021-02-06 07:40:04.076 [INFO][77] int_dataplane.go 820: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"kns.kubeapps" > profile:<inbound_rules:<action:"allow" rule_id:"eXX0FrflrtxVMhoa" > outbound_rules:<action:"allow" rule_id:"PM7suQJpv4u5UQTp" > > 
2021-02-06 07:40:04.078 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-pri-kns.kubeapps" ipVersion=0x4 table="filter"
2021-02-06 07:40:04.078 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="chain update" table="filter"
2021-02-06 07:40:04.078 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-pro-kns.kubeapps" ipVersion=0x4 table="filter"
2021-02-06 07:40:04.079 [INFO][77] int_dataplane.go 820: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"ksa.kubeapps.default" > profile:<> 
2021-02-06 07:40:04.080 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-pri-_W4Vp61c6z2_4o058P-" ipVersion=0x4 table="filter"
2021-02-06 07:40:04.080 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-pro-_W4Vp61c6z2_4o058P-" ipVersion=0x4 table="filter"
2021-02-06 07:40:04.080 [INFO][77] int_dataplane.go 820: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali7fbc0230cb1" mac:"62:f5:5e:5b:53:a1" profile_ids:"kns.kubeapps" profile_ids:"ksa.kubeapps.default" ipv4_nets:"172.33.1.129/32" > 
2021-02-06 07:40:04.080 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:04.080 [INFO][77] endpoint_mgr.go 490: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:04.081 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-tw-cali7fbc0230cb1" ipVersion=0x4 table="filter"
2021-02-06 07:40:04.081 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-fw-cali7fbc0230cb1" ipVersion=0x4 table="filter"
2021-02-06 07:40:04.081 [INFO][77] endpoint_mgr.go 517: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:04.081 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2021-02-06 07:40:04.081 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2021-02-06 07:40:04.081 [INFO][77] endpoint_mgr.go 941: Applying /proc/sys configuration to interface. ifaceName="cali7fbc0230cb1"
2021-02-06 07:40:04.081 [INFO][77] endpoint_mgr.go 425: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:04.082 [INFO][77] status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:04.084 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:04.098 [INFO][77] status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"} ipVersion=0x4 status="up"
2021-02-06 07:40:04.098 [INFO][77] status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"} status="up"
2021-02-06 07:40:04.098 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=17.986994
2021-02-06 07:40:04.545 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:04.545 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-02-06 07:40:04.545 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-02-06 07:40:04.549 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-02-06 07:40:04.551 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-02-06 07:40:04.556 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=11.016594
2021-02-06 07:40:04.678 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:04.679 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-02-06 07:40:04.681 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-02-06 07:40:04.699 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=20.539558
2021-02-06 07:40:05.119 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:05.119 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:05.122 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:05.131 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=11.962342000000001
2021-02-06 07:40:06.110 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:06.110 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:06.113 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:06.120 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=10.077010000000001
2021-02-06 07:40:06.580 [INFO][77] calc_graph.go 390: Local endpoint deleted id=WorkloadEndpoint(node=b0108011, orchestrator=k8s, workload=kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc, name=eth0)
2021-02-06 07:40:06.581 [INFO][77] int_dataplane.go 820: Received *proto.WorkloadEndpointRemove update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc" endpoint_id:"eth0" > 
2021-02-06 07:40:06.581 [INFO][77] int_dataplane.go 820: Received *proto.ActiveProfileRemove update from calculation graph msg=id:<name:"kns.kubeapps" > 
2021-02-06 07:40:06.581 [INFO][77] table.go 499: Queing deletion of chain. chainName="cali-pri-kns.kubeapps" ipVersion=0x4 table="filter"
2021-02-06 07:40:06.581 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="chain removal" table="filter"
2021-02-06 07:40:06.581 [INFO][77] table.go 499: Queing deletion of chain. chainName="cali-pro-kns.kubeapps" ipVersion=0x4 table="filter"
2021-02-06 07:40:06.581 [INFO][77] int_dataplane.go 820: Received *proto.ActiveProfileRemove update from calculation graph msg=id:<name:"ksa.kubeapps.default" > 
2021-02-06 07:40:06.582 [INFO][77] table.go 499: Queing deletion of chain. chainName="cali-pri-_W4Vp61c6z2_4o058P-" ipVersion=0x4 table="filter"
2021-02-06 07:40:06.582 [INFO][77] table.go 499: Queing deletion of chain. chainName="cali-pro-_W4Vp61c6z2_4o058P-" ipVersion=0x4 table="filter"
2021-02-06 07:40:06.582 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:06.582 [INFO][77] endpoint_mgr.go 570: Workload removed, deleting its chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:06.582 [INFO][77] table.go 499: Queing deletion of chain. chainName="cali-tw-cali7fbc0230cb1" ipVersion=0x4 table="filter"
2021-02-06 07:40:06.582 [INFO][77] table.go 499: Queing deletion of chain. chainName="cali-fw-cali7fbc0230cb1" ipVersion=0x4 table="filter"
2021-02-06 07:40:06.582 [INFO][77] endpoint_mgr.go 579: Workload removed, deleting old state. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:06.582 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2021-02-06 07:40:06.582 [INFO][77] table.go 475: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2021-02-06 07:40:06.582 [INFO][77] endpoint_mgr.go 425: Re-evaluated workload endpoint status adminUp=false failed=false known=false operUp=false status="" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:06.583 [INFO][77] status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:06.583 [INFO][77] route_table.go 524: Syncing routes: found unexpected route; ignoring due to grace period. dest=172.33.1.129/32 ifaceName="cali7fbc0230cb1" ipVersion=0x4
2021-02-06 07:40:06.583 [INFO][77] route_table.go 349: Interface in cleanup grace period, will retry after. ifaceName="cali7fbc0230cb1" ipVersion=0x4
2021-02-06 07:40:06.583 [INFO][77] conntrack.go 78: Removing conntrack flows ip=172.33.1.129
2021-02-06 07:40:06.585 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:06.598 [INFO][77] status_combiner.go 86: Reporting endpoint removed. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kubeapps/apprepo-sync-chartmuseum-1612597200-lbwqc", EndpointId:"eth0"}
2021-02-06 07:40:06.599 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=16.809036
2021-02-06 07:40:06.625 [INFO][77] int_dataplane.go 589: Linux interface state changed. ifaceName="cali7fbc0230cb1" state="down"
2021-02-06 07:40:06.625 [INFO][77] int_dataplane.go 835: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali7fbc0230cb1", State:"down"}
2021-02-06 07:40:06.625 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:06.650 [INFO][77] route_table.go 744: Failed to access interface because it doesn't exist. error=Link not found ifaceName="cali7fbc0230cb1" ipVersion=0x4
2021-02-06 07:40:06.650 [INFO][77] route_table.go 485: Failed to get interface; it's down/gone. error=Link not found ifaceName="cali7fbc0230cb1" ipVersion=0x4
2021-02-06 07:40:06.650 [INFO][77] route_table.go 343: Interface missing, will retry if it appears. ifaceName="cali7fbc0230cb1" ipVersion=0x4
2021-02-06 07:40:06.650 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=24.74217
2021-02-06 07:40:06.651 [INFO][77] int_dataplane.go 622: Linux interface addrs changed. addrs=<nil> ifaceName="cali7fbc0230cb1"
2021-02-06 07:40:06.651 [INFO][77] int_dataplane.go 850: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali7fbc0230cb1", Addrs:set.Set(nil)}
2021-02-06 07:40:06.651 [INFO][77] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali7fbc0230cb1", Addrs:set.Set(nil)}
2021-02-06 07:40:06.651 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:06.651 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=0.13993999999999998
2021-02-06 07:40:06.863 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:06.863 [INFO][77] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-02-06 07:40:06.863 [INFO][77] ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-02-06 07:40:06.866 [INFO][77] ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.897291ms
2021-02-06 07:40:06.867 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=4.3748119999999995
2021-02-06 07:40:07.598 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:07.599 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:07.601 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:07.610 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=11.363510999999999
2021-02-06 07:40:08.609 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:08.610 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:08.612 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:08.620 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=9.993686
2021-02-06 07:40:10.608 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:10.608 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:10.612 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:10.620 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=11.389365999999999
2021-02-06 07:40:12.540 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:12.540 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-02-06 07:40:12.540 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-02-06 07:40:12.543 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-02-06 07:40:12.546 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-02-06 07:40:12.550 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=9.863243
2021-02-06 07:40:12.677 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:12.677 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-02-06 07:40:12.681 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-02-06 07:40:12.700 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=22.866607000000002
2021-02-06 07:40:14.621 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:14.621 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:14.624 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:14.631 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=9.971565
2021-02-06 07:40:17.523 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:17.523 [INFO][77] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-02-06 07:40:17.523 [INFO][77] ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-02-06 07:40:17.526 [INFO][77] ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.688778ms
2021-02-06 07:40:17.526 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=3.1738
2021-02-06 07:40:22.598 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:22.599 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:22.601 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:22.609 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=10.624099
2021-02-06 07:40:28.127 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:28.128 [INFO][77] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-02-06 07:40:28.128 [INFO][77] ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-02-06 07:40:28.130 [INFO][77] ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.680485ms
2021-02-06 07:40:28.131 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=3.110632
2021-02-06 07:40:28.529 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:28.529 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-02-06 07:40:28.532 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-02-06 07:40:28.536 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=7.2941530000000006
2021-02-06 07:40:28.538 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:28.538 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-02-06 07:40:28.540 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-02-06 07:40:28.545 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=7.488647
2021-02-06 07:40:28.675 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:28.675 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-02-06 07:40:28.679 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-02-06 07:40:28.695 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=20.148051
2021-02-06 07:40:38.452 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:38.452 [INFO][77] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-02-06 07:40:38.452 [INFO][77] ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-02-06 07:40:38.455 [INFO][77] ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.811388ms
2021-02-06 07:40:38.455 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=3.329771
2021-02-06 07:40:38.598 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:38.598 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2021-02-06 07:40:38.602 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2021-02-06 07:40:38.611 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=12.292402
2021-02-06 07:40:49.376 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:49.377 [INFO][77] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-02-06 07:40:49.377 [INFO][77] ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-02-06 07:40:49.379 [INFO][77] ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.734321ms
2021-02-06 07:40:49.380 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=3.199418
2021-02-06 07:40:59.717 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:40:59.717 [INFO][77] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2021-02-06 07:40:59.717 [INFO][77] ipsets.go 306: Resyncing ipsets with dataplane. family="inet"
2021-02-06 07:40:59.720 [INFO][77] ipsets.go 356: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.737371ms
2021-02-06 07:40:59.720 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=3.343265
2021-02-06 07:41:00.529 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:41:00.529 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2021-02-06 07:41:00.532 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2021-02-06 07:41:00.536 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=7.488306
2021-02-06 07:41:00.538 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:41:00.538 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2021-02-06 07:41:00.542 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2021-02-06 07:41:00.547 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=8.87169
2021-02-06 07:41:00.676 [INFO][77] int_dataplane.go 945: Applying dataplane updates
2021-02-06 07:41:00.676 [INFO][77] table.go 830: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2021-02-06 07:41:00.679 [INFO][77] table.go 518: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2021-02-06 07:41:00.698 [INFO][77] int_dataplane.go 959: Finished applying updates to dataplane. msecToApply=22.024881999999998
caseydavenport commented 3 years ago

I'm a bit confused - the title of this issue suggests an issue with liveness probes, however the description doesn't claim any issues with liveness probes.

The provided logs look OK to me, with the exception that the calico/node seems to be running with a NODENAME that doesn't match the Kubernetes node.

This is also a very old version of Calico. Could you please try using a modern version to see if it resolves your issues?