calico的日志

master的日志

2022-05-21 07:37:05.515 [INFO][8] startup/startup.go 396: Early log level set to info
2022-05-21 07:37:05.518 [INFO][8] startup/utils.go 126: Using NODENAME environment for node name k8smec
2022-05-21 07:37:05.519 [INFO][8] startup/utils.go 138: Determined node name: k8smec
2022-05-21 07:37:05.519 [INFO][8] startup/startup.go 98: Starting node k8smec with version v3.21.2
2022-05-21 07:37:05.528 [INFO][8] startup/startup.go 401: Checking datastore connection
2022-05-21 07:37:05.541 [INFO][8] startup/startup.go 425: Datastore connection verified
2022-05-21 07:37:05.541 [INFO][8] startup/startup.go 108: Datastore is ready
2022-05-21 07:37:05.563 [INFO][8] startup/customresource.go 102: Error getting resource Key=GlobalFelixConfig(name=CalicoVersion) Name="calicoversion" Resource="GlobalFelixConfigs" error=the server could not find the requested resource (get GlobalFelixConfigs.crd.projectcalico.org calicoversion)
2022-05-21 07:37:05.600 [INFO][8] startup/startup.go 727: Using autodetected IPv4 address 192.168.10.139/24 on matching interface enp2s0
2022-05-21 07:37:05.600 [INFO][8] startup/startup.go 790: No AS number configured on node resource, using global value
2022-05-21 07:37:05.605 [INFO][8] startup/startup.go 839: found v6= in the kubeadm config map
2022-05-21 07:37:05.625 [INFO][8] startup/startup.go 651: CALICO_IPV4POOL_NAT_OUTGOING is true (defaulted) through environment variable
2022-05-21 07:37:05.625 [INFO][8] startup/startup.go 992: Ensure default IPv4 pool is created. IPIP mode: Always, VXLAN mode: Never
2022-05-21 07:37:05.676 [INFO][8] startup/startup.go 1002: Created default IPv4 pool (10.100.0.0/16) with NAT outgoing true. IPIP mode: Always, VXLAN mode: Never
2022-05-21 07:37:05.676 [INFO][8] startup/startup.go 645: FELIX_IPV6SUPPORT is false through environment variable
2022-05-21 07:37:05.722 [INFO][8] startup/startup.go 208: Using node name: k8smec
2022-05-21 07:37:05.722 [INFO][8] startup/utils.go 190: Setting NetworkUnavailable to false
2022-05-21 07:37:05.831 [INFO][17] tunnel-ip-allocator/allocateip.go 279: Assign a new tunnel address type="ipipTunnelAddress"
2022-05-21 07:37:05.831 [INFO][17] tunnel-ip-allocator/allocateip.go 355: Release any old tunnel addresses IP="" type="ipipTunnelAddress"
2022-05-21 07:37:05.832 [INFO][17] tunnel-ip-allocator/allocateip.go 366: Assign new tunnel address IP="" type="ipipTunnelAddress"
2022-05-21 07:37:05.832 [INFO][17] tunnel-ip-allocator/ipam.go 103: Auto-assign 1 ipv4, 0 ipv6 addrs for host 'k8smec'
2022-05-21 07:37:05.834 [INFO][17] tunnel-ip-allocator/ipam.go 656: Looking up existing affinities for host handle="ipip-tunnel-addr-k8smec" host="k8smec"
2022-05-21 07:37:05.837 [INFO][17] tunnel-ip-allocator/ipam.go 368: Looking up existing affinities for host host="k8smec"
2022-05-21 07:37:05.863 [INFO][17] tunnel-ip-allocator/ipam.go 517: Ran out of existing affine blocks for host host="k8smec"
2022-05-21 07:37:05.864 [INFO][17] tunnel-ip-allocator/ipam.go 534: Tried all affine blocks. Looking for an affine block with space, or a new unclaimed block host="k8smec"
2022-05-21 07:37:05.866 [INFO][17] tunnel-ip-allocator/ipam_block_reader_writer.go 153: Found free block: 10.100.207.64/26
2022-05-21 07:37:05.866 [INFO][17] tunnel-ip-allocator/ipam.go 546: Found unclaimed block host="k8smec" subnet=10.100.207.64/26
2022-05-21 07:37:05.866 [INFO][17] tunnel-ip-allocator/ipam_block_reader_writer.go 170: Trying to create affinity in pending state host="k8smec" subnet=10.100.207.64/26
2022-05-21 07:37:05.869 [INFO][17] tunnel-ip-allocator/ipam_block_reader_writer.go 200: Successfully created pending affinity for block host="k8smec" subnet=10.100.207.64/26
2022-05-21 07:37:05.869 [INFO][17] tunnel-ip-allocator/ipam.go 151: Attempting to load block cidr=10.100.207.64/26 host="k8smec"
2022-05-21 07:37:05.871 [INFO][17] tunnel-ip-allocator/ipam.go 156: The referenced block doesn't exist, trying to create it cidr=10.100.207.64/26 host="k8smec"
2022-05-21 07:37:05.872 [INFO][17] tunnel-ip-allocator/ipam.go 163: Wrote affinity as pending cidr=10.100.207.64/26 host="k8smec"
2022-05-21 07:37:05.873 [INFO][17] tunnel-ip-allocator/ipam.go 172: Attempting to claim the block cidr=10.100.207.64/26 host="k8smec"
2022-05-21 07:37:05.873 [INFO][17] tunnel-ip-allocator/ipam_block_reader_writer.go 222: Attempting to create a new block host="k8smec" subnet=10.100.207.64/26
2022-05-21 07:37:05.891 [INFO][17] tunnel-ip-allocator/ipam_block_reader_writer.go 263: Successfully created block
2022-05-21 07:37:05.891 [INFO][17] tunnel-ip-allocator/ipam_block_reader_writer.go 274: Confirming affinity host="k8smec" subnet=10.100.207.64/26
2022-05-21 07:37:05.893 [INFO][17] tunnel-ip-allocator/ipam_block_reader_writer.go 289: Successfully confirmed affinity host="k8smec" subnet=10.100.207.64/26
2022-05-21 07:37:05.893 [INFO][17] tunnel-ip-allocator/ipam.go 581: Block '10.100.207.64/26' has 64 free ips which is more than 1 ips required. host="k8smec" subnet=10.100.207.64/26
2022-05-21 07:37:05.893 [INFO][17] tunnel-ip-allocator/ipam.go 1153: Attempting to assign 1 addresses from block block=10.100.207.64/26 handle="ipip-tunnel-addr-k8smec" host="k8smec"
2022-05-21 07:37:05.901 [INFO][17] tunnel-ip-allocator/ipam.go 1630: Creating new handle: ipip-tunnel-addr-k8smec
2022-05-21 07:37:05.904 [INFO][17] tunnel-ip-allocator/ipam.go 1176: Writing block in order to claim IPs block=10.100.207.64/26 handle="ipip-tunnel-addr-k8smec" host="k8smec"
2022-05-21 07:37:05.909 [INFO][17] tunnel-ip-allocator/ipam.go 1189: Successfully claimed IPs: [10.100.207.64/26] block=10.100.207.64/26 handle="ipip-tunnel-addr-k8smec" host="k8smec"
2022-05-21 07:37:05.909 [INFO][17] tunnel-ip-allocator/ipam.go 843: Auto-assigned 1 out of 1 IPv4s: [10.100.207.64/26] handle="ipip-tunnel-addr-k8smec" host="k8smec"
2022-05-21 07:37:05.919 [INFO][17] tunnel-ip-allocator/allocateip.go 455: Assigned tunnel address to node IP="10.100.207.64" type="ipipTunnelAddress"
Calico node started successfully
bird: Unable to open configuration file /etc/calico/confd/config/bird.cfg: No such file or directory
bird: Unable to open configuration file /etc/calico/confd/config/bird6.cfg: No such file or directory
2022-05-21 07:37:07.122 [INFO][55] status-reporter/startup.go 396: Early log level set to info
2022-05-21 07:37:07.123 [INFO][55] status-reporter/watchersyncer.go 89: Start called
2022-05-21 07:37:07.123 [INFO][55] status-reporter/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:07.123 [INFO][55] status-reporter/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:07.123 [INFO][55] status-reporter/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/caliconodestatuses"
2022-05-21 07:37:07.222 [INFO][58] tunnel-ip-allocator/watchersyncer.go 89: Start called
2022-05-21 07:37:07.230 [INFO][58] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:07.230 [INFO][58] tunnel-ip-allocator/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:07.230 [INFO][58] tunnel-ip-allocator/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/nodes/k8smec"
2022-05-21 07:37:07.230 [INFO][58] tunnel-ip-allocator/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:07.233 [INFO][53] cni-config-monitor/token_watch.go 40: Watching contents for changes. directory="/var/run/secrets/kubernetes.io/serviceaccount/"
2022-05-21 07:37:07.239 [INFO][58] tunnel-ip-allocator/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:07.239 [INFO][58] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:07.239 [INFO][58] tunnel-ip-allocator/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.240 [INFO][58] tunnel-ip-allocator/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes/k8smec"
2022-05-21 07:37:07.241 [INFO][58] tunnel-ip-allocator/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.241 [INFO][58] tunnel-ip-allocator/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:07.241 [INFO][58] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:07.243 [INFO][55] status-reporter/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/caliconodestatuses"
2022-05-21 07:37:07.243 [INFO][55] status-reporter/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.243 [INFO][55] status-reporter/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:07.243 [INFO][55] status-reporter/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:07.243 [INFO][55] status-reporter/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:07.250 [INFO][54] confd/config.go 81: Skipping confd config file.
2022-05-21 07:37:07.251 [INFO][54] confd/run.go 17: Starting calico-confd
2022-05-21 07:37:07.270 [INFO][60] monitor-addresses/startup.go 396: Early log level set to info
W0521 07:37:07.270636      54 client_config.go:615] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
2022-05-21 07:37:07.271 [INFO][60] monitor-addresses/utils.go 126: Using NODENAME environment for node name k8smec
2022-05-21 07:37:07.271 [INFO][60] monitor-addresses/utils.go 138: Determined node name: k8smec
2022-05-21 07:37:07.271 [INFO][54] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:07.271 [INFO][54] confd/client.go 1215: Updated with new cluster IP CIDRs: []
2022-05-21 07:37:07.271 [INFO][54] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:07.271 [INFO][54] confd/client.go 1206: Updated with new external IP CIDRs: []
2022-05-21 07:37:07.271 [INFO][54] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:07.271 [INFO][54] confd/client.go 1225: Updated with new Loadbalancer IP CIDRs: []
2022-05-21 07:37:07.271 [INFO][54] confd/watchersyncer.go 89: Start called
2022-05-21 07:37:07.271 [INFO][54] confd/client.go 378: Source SourceRouteGenerator readiness changed, ready=true
2022-05-21 07:37:07.271 [INFO][54] confd/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:07.271 [INFO][54] confd/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:07.271 [INFO][54] confd/watchercache.go 175: Full resync is required ListRoot="/calico/ipam/v2/host/k8smec"
2022-05-21 07:37:07.272 [INFO][54] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:07.272 [INFO][54] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:07.272 [INFO][54] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:07.272 [INFO][54] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/bgppeers"
2022-05-21 07:37:07.275 [INFO][54] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgppeers"
2022-05-21 07:37:07.276 [INFO][54] confd/watchercache.go 273: Sending synced update ListRoot="/calico/ipam/v2/host/k8smec"
2022-05-21 07:37:07.276 [INFO][54] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:07.276 [INFO][54] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.276 [INFO][54] confd/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:07.276 [INFO][54] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.276 [INFO][54] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.276 [INFO][54] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:07.276 [INFO][54] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.276 [INFO][54] confd/client.go 900: Recompute BGP peerings: HostBGPConfig(node=k8sai; name=ip_addr_v4) updated; HostBGPConfig(node=k8sai; name=ip_addr_v6) updated; HostBGPConfig(node=k8sai; name=network_v4) updated; HostBGPConfig(node=k8sai; name=rr_cluster_id) updated; k8sai updated; HostBGPConfig(node=k8smec; name=ip_addr_v4) updated; HostBGPConfig(node=k8smec; name=ip_addr_v6) updated; HostBGPConfig(node=k8smec; name=network_v4) updated; HostBGPConfig(node=k8smec; name=rr_cluster_id) updated; k8smec updated; HostBGPConfig(node=node1; name=ip_addr_v4) updated; HostBGPConfig(node=node1; name=ip_addr_v6) updated; HostBGPConfig(node=node1; name=network_v4) updated; HostBGPConfig(node=node1; name=rr_cluster_id) updated; node1 updated
2022-05-21 07:37:07.276 [INFO][54] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:07.276 [INFO][54] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.276 [INFO][54] confd/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:07.276 [INFO][54] confd/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:07.276 [INFO][54] confd/client.go 378: Source SourceSyncer readiness changed, ready=true
2022-05-21 07:37:07.276 [INFO][54] confd/client.go 398: Data is now syncd, can start rendering templates
2022-05-21 07:37:07.284 [ERROR][54] confd/resource.go 305: Error from checkcmd "bird6 -p -c /etc/calico/confd/config/.bird6.cfg411114436": "bird: /etc/calico/confd/config/.bird6.cfg411114436:6:1 Unable to open included file /etc/calico/confd/config/bird6_aggr.cfg: No such file or directory\n"
2022-05-21 07:37:07.284 [INFO][54] confd/resource.go 239: Check failed, but file does not yet exist - create anyway
2022-05-21 07:37:07.284 [INFO][54] confd/resource.go 277: Target config /etc/calico/confd/config/bird_aggr.cfg has been updated
2022-05-21 07:37:07.284 [INFO][54] confd/resource.go 277: Target config /etc/calico/confd/config/bird_ipam.cfg has been updated
2022-05-21 07:37:07.286 [INFO][54] confd/resource.go 277: Target config /etc/calico/confd/config/bird6.cfg has been updated
2022-05-21 07:37:07.289 [INFO][54] confd/resource.go 277: Target config /etc/calico/confd/config/bird6_aggr.cfg has been updated
2022-05-21 07:37:07.289 [INFO][54] confd/resource.go 277: Target config /etc/calico/confd/config/bird6_ipam.cfg has been updated
2022-05-21 07:37:07.295 [INFO][54] confd/resource.go 277: Target config /etc/calico/confd/config/bird.cfg has been updated
2022-05-21 07:37:07.308 [INFO][58] tunnel-ip-allocator/allocateip.go 303: Current address is still valid, do nothing currentAddr="10.100.207.64" type="ipipTunnelAddress"
2022-05-21 07:37:07.308 [INFO][56] felix/daemon.go 358: Successfully loaded configuration. GOMAXPROCS=4 builddate="2021-12-06T03:23:22+0000" config=&config.Config{UseInternalDataplaneDriver:true, DataplaneDriver:"calico-iptables-plugin", WireguardEnabled:false, WireguardListeningPort:51820, WireguardRoutingRulePriority:99, WireguardInterfaceName:"wireguard.cali", WireguardMTU:0, WireguardHostEncryptionEnabled:false, BPFEnabled:false, BPFDisableUnprivileged:true, BPFLogLevel:"off", BPFDataIfacePattern:(*regexp.Regexp)(0xc0005c8000), BPFConnectTimeLoadBalancingEnabled:true, BPFExternalServiceMode:"tunnel", BPFKubeProxyIptablesCleanupEnabled:true, BPFKubeProxyMinSyncPeriod:1000000000, BPFKubeProxyEndpointSlicesEnabled:false, BPFExtToServiceConnmark:0, BPFPSNATPorts:numorstring.Port{MinPort:0x4e20, MaxPort:0x752f, PortName:""}, DebugBPFCgroupV2:"", DebugBPFMapRepinEnabled:false, DatastoreType:"kubernetes", FelixHostname:"k8smec", EtcdAddr:"127.0.0.1:2379", EtcdScheme:"http", EtcdKeyFile:"", EtcdCertFile:"", EtcdCaFile:"", EtcdEndpoints:[]string(nil), TyphaAddr:"", TyphaK8sServiceName:"", TyphaK8sNamespace:"kube-system", TyphaReadTimeout:30000000000, TyphaWriteTimeout:10000000000, TyphaKeyFile:"", TyphaCertFile:"", TyphaCAFile:"", TyphaCN:"", TyphaURISAN:"", Ipv6Support:false, IptablesBackend:"auto", RouteRefreshInterval:90000000000, InterfaceRefreshInterval:90000000000, DeviceRouteSourceAddress:net.IP(nil), DeviceRouteProtocol:3, RemoveExternalRoutes:true, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckIntervalSecs:1000000000, IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeoutSecs:0, IptablesLockProbeIntervalMillis:50000000, FeatureDetectOverride:map[string]string(nil), IpsetsRefreshInterval:10000000000, MaxIpsetSize:1048576, XDPRefreshInterval:90000000000, PolicySyncPathPrefix:"", NetlinkTimeoutSecs:10000000000, MetadataAddr:"", MetadataPort:8775, OpenstackRegion:"", InterfacePrefix:"cali", InterfaceExclude:[]*regexp.Regexp{(*regexp.Regexp)(0xc0005c8280)}, ChainInsertMode:"insert", DefaultEndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", LogPrefix:"calico-packet", LogFilePath:"", LogSeverityFile:"", LogSeverityScreen:"INFO", LogSeveritySys:"", VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, VXLANMTU:0, IPv4VXLANTunnelAddr:net.IP(nil), VXLANTunnelMACAddr:"", IpInIpEnabled:true, IpInIpMtu:0, IpInIpTunnelAddr:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xcf, 0x40}, AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, AWSSrcDstCheck:"DoNothing", ServiceLoopPrevention:"Drop", ReportingIntervalSecs:0, ReportingTTLSecs:90000000000, EndpointReportingEnabled:false, EndpointReportingDelaySecs:1000000000, IptablesMarkMask:0xffff0000, DisableConntrackInvalidCheck:false, HealthEnabled:true, HealthPort:9099, HealthHost:"localhost", PrometheusMetricsEnabled:false, PrometheusMetricsHost:"", PrometheusMetricsPort:9091, PrometheusGoMetricsEnabled:true, PrometheusProcessMetricsEnabled:true, PrometheusWireGuardMetricsEnabled:true, FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", 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:"8f9356338eec420688d4692f5ba4d190", ClusterType:"k8s,bgp,kubeadm,kdd", CalicoVersion:"v3.21.2", ExternalNodesCIDRList:[]string(nil), DebugMemoryProfilePath:"", DebugCPUProfilePath:"/tmp/felix-cpu-<timestamp>.pprof", DebugDisableLogDropping:false, DebugSimulateCalcGraphHangAfter:0, DebugSimulateDataplaneHangAfter:0, DebugPanicAfter:0, DebugSimulateDataRace:false, RouteSource:"CalicoIPAM", RouteTableRange:idalloc.IndexRange{Min:1, Max:250}, IptablesNATOutgoingInterfaceFilter:"", SidecarAccelerationEnabled:false, XDPEnabled:true, GenericXDPEnabled:false, Variant:"Calico", MTUIfacePattern:(*regexp.Regexp)(0xc0005c8960), internalOverrides:map[string]string{}, sourceToRawConfig:map[config.Source]map[string]string{0x1:map[string]string{"CalicoVersion":"v3.21.2", "ClusterGUID":"8f9356338eec420688d4692f5ba4d190", "ClusterType":"k8s,bgp,kubeadm,kdd", "IpInIpEnabled":"true", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0"}, 0x2:map[string]string{"IpInIpTunnelAddr":"10.100.207.64"}, 0x3:map[string]string{"LogFilePath":"None", "LogSeverityFile":"None", "LogSeveritySys":"None", "MetadataAddr":"None"}, 0x4:map[string]string{"datastoretype":"kubernetes", "defaultendpointtohostaction":"ACCEPT", "felixhostname":"k8smec", "healthenabled":"true", "ipinipmtu":"0", "ipv6support":"false", "vxlanmtu":"0", "wireguardmtu":"0"}}, rawValues:map[string]string{"CalicoVersion":"v3.21.2", "ClusterGUID":"8f9356338eec420688d4692f5ba4d190", "ClusterType":"k8s,bgp,kubeadm,kdd", "DatastoreType":"kubernetes", "DefaultEndpointToHostAction":"ACCEPT", "FelixHostname":"k8smec", "HealthEnabled":"true", "IpInIpEnabled":"true", "IpInIpMtu":"0", "IpInIpTunnelAddr":"10.100.207.64", "Ipv6Support":"false", "LogFilePath":"None", "LogSeverityFile":"None", "LogSeverityScreen":"Info", "LogSeveritySys":"None", "MetadataAddr":"None", "ReportingIntervalSecs":"0", "VXLANMTU":"0", "WireguardMTU":"0"}, Err:error(nil), loadClientConfigFromEnvironment:(func() (*apiconfig.CalicoAPIConfig, error))(0x1597ec0), useNodeResourceUpdates:false} gitcommit="6411b17928e58c88815ab7eb8b335187508d4987" version="v3.21.2"
2022-05-21 07:37:07.314 [INFO][56] felix/driver.go 69: Using internal (linux) dataplane driver.
2022-05-21 07:37:07.316 [INFO][56] felix/driver.go 154: Calculated iptables mark bits acceptMark=0x10000 endpointMark=0xfff00000 endpointMarkNonCali=0x0 passMark=0x20000 scratch0Mark=0x40000 scratch1Mark=0x80000
2022-05-21 07:37:07.316 [INFO][56] felix/int_dataplane.go 307: Creating internal dataplane driver. config=intdataplane.Config{Hostname:"k8smec", IPv6Enabled:false, RuleRendererOverride:rules.RuleRenderer(nil), IPIPMTU:0, VXLANMTU:0, VXLANPort:4789, MaxIPSetSize:1048576, IptablesBackend:"auto", IPSetsRefreshInterval:10000000000, RouteRefreshInterval:90000000000, DeviceRouteSourceAddress:net.IP(nil), DeviceRouteProtocol:3, RemoveExternalRoutes:true, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckInterval:1000000000, IptablesInsertMode:"insert", IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeout:0, IptablesLockProbeInterval:50000000, XDPRefreshInterval:90000000000, Wireguard:wireguard.Config{Enabled:false, ListeningPort:51820, FirewallMark:0, RoutingRulePriority:99, RoutingTableIndex:1, InterfaceName:"wireguard.cali", MTU:0, RouteSource:"CalicoIPAM", EncryptHostTraffic:false}, NetlinkTimeout:10000000000, RulesConfig:rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc00059c280), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc00059c3c0), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x0, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:true, IPIPTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xcf, 0x40}, VXLANTunnelAddress:net.IP(nil), AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, WireguardEnabled:false, WireguardInterfaceName:"wireguard.cali", WireguardIptablesMark:0x0, WireguardListeningPort:51820, WireguardEncryptHostTraffic:false, RouteSource:"CalicoIPAM", IptablesLogPrefix:"calico-packet", EndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil), BPFEnabled:false, ServiceLoopPrevention:"Drop"}, IfaceMonitorConfig:ifacemonitor.Config{InterfaceExcludes:[]*regexp.Regexp{(*regexp.Regexp)(0xc0005c8280)}, ResyncInterval:90000000000}, StatusReportingInterval:0, ConfigChangedRestartCallback:(func())(0x28b7b80), FatalErrorRestartCallback:(func(error))(0x28b7c40), PostInSyncCallback:(func())(0x289f340), HealthAggregator:(*health.HealthAggregator)(0xc00047dec0), RouteTableManager:(*idalloc.IndexAllocator)(0xc000010008), DebugSimulateDataplaneHangAfter:0, ExternalNodesCidrs:[]string(nil), BPFEnabled:false, BPFDisableUnprivileged:true, BPFKubeProxyIptablesCleanupEnabled:true, BPFLogLevel:"off", BPFExtToServiceConnmark:0, BPFDataIfacePattern:(*regexp.Regexp)(0xc0005c8000), XDPEnabled:true, XDPAllowGeneric:false, BPFConntrackTimeouts:conntrack.Timeouts{CreationGracePeriod:10000000000, TCPPreEstablished:20000000000, TCPEstablished:3600000000000, TCPFinsSeen:30000000000, TCPResetSeen:40000000000, UDPLastSeen:60000000000, GenericIPLastSeen:600000000000, ICMPLastSeen:5000000000}, BPFCgroupV2:"", BPFConnTimeLBEnabled:true, BPFMapRepin:false, BPFNodePortDSREnabled:false, BPFPSNATPorts:numorstring.Port{MinPort:0x4e20, MaxPort:0x752f, PortName:""}, KubeProxyMinSyncPeriod:1000000000, KubeProxyEndpointSlicesEnabled:false, SidecarAccelerationEnabled:false, LookPathOverride:(func(string) (string, error))(nil), KubeClientSet:(*kubernetes.Clientset)(0xc00014a6e0), FeatureDetectOverrides:map[string]string(nil), hostMTU:0, MTUIfacePattern:(*regexp.Regexp)(0xc0005c8960), RouteSource:"CalicoIPAM", KubernetesProvider:0x0}
2022-05-21 07:37:07.317 [INFO][56] felix/rule_defs.go 358: Creating rule renderer. config=rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc00059c280), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc00059c3c0), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x0, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:true, IPIPTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xcf, 0x40}, VXLANTunnelAddress:net.IP(nil), AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, WireguardEnabled:false, WireguardInterfaceName:"wireguard.cali", WireguardIptablesMark:0x0, WireguardListeningPort:51820, WireguardEncryptHostTraffic:false, RouteSource:"CalicoIPAM", IptablesLogPrefix:"calico-packet", EndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil), BPFEnabled:false, ServiceLoopPrevention:"Drop"}
2022-05-21 07:37:07.317 [INFO][56] felix/rule_defs.go 368: Workload to host packets will be accepted.
2022-05-21 07:37:07.318 [INFO][56] felix/rule_defs.go 382: filter table allowed packets will be accepted immediately.
2022-05-21 07:37:07.318 [INFO][56] felix/rule_defs.go 390: mangle table allowed packets will be accepted immediately.
2022-05-21 07:37:07.318 [INFO][56] felix/rule_defs.go 398: Packets to unknown service IPs will be dropped
2022-05-21 07:37:07.319 [INFO][56] felix/int_dataplane.go 932: Determined pod MTU mtu=1480
2022-05-21 07:37:07.320 [INFO][56] felix/iface_monitor.go 74: configured to periodically rescan interfaces. interval=1m30s
2022-05-21 07:37:07.320 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"ip6tables-legacy-save", "ip6tables-save"} command="ip6tables-legacy-save" ipVersion=0x6 saveOrRestore="save"
2022-05-21 07:37:07.320 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:07.464 [INFO][56] felix/feature_detect.go 153: Updating detected iptables features features=iptables.Features{SNATFullyRandom:true, MASQFullyRandom:true, RestoreSupportsLock:true, ChecksumOffloadBroken:true} iptablesVersion=1.8.4 kernelVersion=4.15.0-142
2022-05-21 07:37:07.464 [INFO][56] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:07.465 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:07.465 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:07.465 [INFO][56] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-|-A POSTROUTING .* felix-masq-ipam-pools .*|-A POSTROUTING -o tunl0 -m addrtype ! --src-type LOCAL --limit-iface-out -m addrtype --src-type LOCAL -j MASQUERADE"
2022-05-21 07:37:07.465 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:07.465 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:07.465 [INFO][56] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:07.465 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:07.465 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:07.465 [INFO][56] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:07.466 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:07.466 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:07.466 [WARNING][56] felix/int_dataplane.go 459: Can't enable XDP acceleration. error=kernel is too old (have: 4.15.0-142 but want at least: 4.16.0)
2022-05-21 07:37:07.545 [INFO][56] felix/connecttime.go 51: Running bpftool to look up programs attached to cgroup args=[]string{"bpftool", "-j", "-p", "cgroup", "show", "/run/calico/cgroup"}
2022-05-21 07:37:07.550 [INFO][56] felix/route_table.go 266: Calculated interface name regexp ifaceRegex="^cali.*" ipVersion=0x4 tableIndex=0
2022-05-21 07:37:07.550 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="all-ipam-pools" setType="hash:net"
2022-05-21 07:37:07.550 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="masq-ipam-pools" setType="hash:net"
2022-05-21 07:37:07.550 [INFO][56] felix/route_table.go 266: Calculated interface name regexp ifaceRegex="^wireguard.cali$" ipVersion=0x4 tableIndex=1
2022-05-21 07:37:07.551 [INFO][56] felix/int_dataplane.go 837: Registering to report health.
2022-05-21 07:37:07.639 [INFO][56] felix/int_dataplane.go 1682: attempted to modprobe nf_conntrack_proto_sctp error=exit status 1 output=""
2022-05-21 07:37:07.639 [INFO][56] felix/int_dataplane.go 1684: Making sure IPv4 forwarding is enabled.
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="raw"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="raw"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="raw"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-wireguard-incoming-mark" ipVersion=0x4 table="raw"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="raw"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-FORWARD" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-hep-forward"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-wl-dispatch"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-wl-dispatch"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-hep-forward"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-cidr-block"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-INPUT" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-wl-to-host"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-wl-to-host" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-FORWARD"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-INPUT"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="nat"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-fip-dnat"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="nat"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-fip-snat"
2022-05-21 07:37:07.639 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-nat-outgoing"
2022-05-21 07:37:07.640 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="nat"
2022-05-21 07:37:07.641 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:07.641 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-POSTROUTING"
2022-05-21 07:37:07.641 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:07.642 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="mangle"
2022-05-21 07:37:07.642 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="mangle"
2022-05-21 07:37:07.642 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="mangle"
2022-05-21 07:37:07.642 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:07.642 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="mangle"
2022-05-21 07:37:07.642 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:07.642 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:07.642 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-POSTROUTING"
2022-05-21 07:37:07.642 [INFO][56] felix/int_dataplane.go 1121: IPIP enabled, starting thread to keep tunnel configuration in sync.
2022-05-21 07:37:07.642 [INFO][56] felix/ipip_mgr.go 84: IPIP thread started.
2022-05-21 07:37:07.642 [INFO][56] felix/daemon.go 397: Connect to the dataplane driver.
2022-05-21 07:37:07.642 [INFO][56] felix/int_dataplane.go 1460: Started internal iptables dataplane driver loop
2022-05-21 07:37:07.642 [INFO][56] felix/int_dataplane.go 1470: Will refresh IP sets on timer interval=1m30s
2022-05-21 07:37:07.642 [INFO][56] felix/int_dataplane.go 1480: Will refresh routes on timer interval=1m30s
2022-05-21 07:37:07.642 [INFO][56] felix/int_dataplane.go 1913: Started internal status report thread
2022-05-21 07:37:07.642 [INFO][56] felix/int_dataplane.go 1915: Process status reports disabled
2022-05-21 07:37:07.642 [INFO][56] felix/daemon.go 475: using resource updates where applicable
2022-05-21 07:37:07.642 [INFO][56] felix/iface_monitor.go 100: Interface monitoring thread started.
2022-05-21 07:37:07.642 [INFO][56] felix/daemon.go 478: Created Syncer syncer=&watchersyncer.watcherSyncer{status:0x0, watcherCaches:[]*watchersyncer.watcherCache{(*watchersyncer.watcherCache)(0xc00054b700), (*watchersyncer.watcherCache)(0xc00054b800), (*watchersyncer.watcherCache)(0xc00054b900), (*watchersyncer.watcherCache)(0xc00054bb00), (*watchersyncer.watcherCache)(0xc00054bc00), (*watchersyncer.watcherCache)(0xc00054bd00), (*watchersyncer.watcherCache)(0xc00054be00), (*watchersyncer.watcherCache)(0xc00054bf00), (*watchersyncer.watcherCache)(0xc0006ac000), (*watchersyncer.watcherCache)(0xc0006ac080), (*watchersyncer.watcherCache)(0xc0006ac100), (*watchersyncer.watcherCache)(0xc0006ac180), (*watchersyncer.watcherCache)(0xc0006ac200), (*watchersyncer.watcherCache)(0xc0006ac280), (*watchersyncer.watcherCache)(0xc0006ac300)}, results:(chan interface {})(0xc000295b00), numSynced:0, callbacks:(*calc.SyncerCallbacksDecoupler)(0xc0001ae4a0), wgwc:(*sync.WaitGroup)(nil), wgws:(*sync.WaitGroup)(nil), cancel:(context.CancelFunc)(nil)}
2022-05-21 07:37:07.643 [INFO][56] felix/iface_monitor.go 118: Subscribed to netlink updates.
2022-05-21 07:37:07.643 [INFO][56] felix/daemon.go 482: Starting the datastore Syncer
2022-05-21 07:37:07.643 [INFO][56] felix/watchersyncer.go 89: Start called
2022-05-21 07:37:07.643 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=1 ifaceName="lo" state="up"
2022-05-21 07:37:07.643 [INFO][56] felix/calc_graph.go 115: Creating calculation graph, filtered to hostname k8smec
2022-05-21 07:37:07.644 [INFO][56] felix/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9c9a0)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"lo", State:"up", Index:1}
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9c9a0)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9caa0)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9caa0)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileRulesKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}, "::1":set.empty{}} ifaceName="lo"
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ResourceKey: (dispatcher.UpdateHandler)(0x1a61360)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="enp1s0"
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.NetworkSetKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=3 ifaceName="enp2s0" state="up"
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x1a9d080)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9d080)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9d080)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}, "::1":set.empty{}}}
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x1a9cc40)
2022-05-21 07:37:07.644 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}, "::1":set.empty{}}}
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.IPPoolKey: (dispatcher.UpdateHandler)(0x1a9cc40)
2022-05-21 07:37:07.644 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WireguardKey: (dispatcher.UpdateHandler)(0x1a9cc40)
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ResourceKey: (dispatcher.UpdateHandler)(0x1a9cc40)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"192.168.10.139":set.empty{}, "fe80::74eb:4b91:f002:4c08":set.empty{}} ifaceName="enp2s0"
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.GlobalConfigKey: (dispatcher.UpdateHandler)(0x1a9cb40)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"enp1s0", Addrs:set.mapSet{}}
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x1a9cb40)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=4 ifaceName="br-1fbbcfe37496" state="up"
2022-05-21 07:37:07.644 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"enp1s0", Addrs:set.mapSet{}}
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ReadyFlagKey: (dispatcher.UpdateHandler)(0x1a9cb40)
2022-05-21 07:37:07.644 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x1a9d220)
2022-05-21 07:37:07.644 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"enp2s0", Addrs:set.mapSet{"192.168.10.139":set.empty{}, "fe80::74eb:4b91:f002:4c08":set.empty{}}}
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x1a9d2c0)
2022-05-21 07:37:07.644 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"enp2s0", Addrs:set.mapSet{"192.168.10.139":set.empty{}, "fe80::74eb:4b91:f002:4c08":set.empty{}}}
2022-05-21 07:37:07.645 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.644 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9d2c0)
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"enp2s0", State:"up", Index:3}
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"172.18.0.1":set.empty{}, "fe80::42:d8ff:fe5f:241c":set.empty{}} ifaceName="br-1fbbcfe37496"
2022-05-21 07:37:07.645 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9d2c0)
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br-1fbbcfe37496", State:"up", Index:4}
2022-05-21 07:37:07.645 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x1a9d2c0)
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br-1fbbcfe37496", Addrs:set.mapSet{"172.18.0.1":set.empty{}, "fe80::42:d8ff:fe5f:241c":set.empty{}}}
2022-05-21 07:37:07.645 [INFO][56] felix/async_calc_graph.go 255: Starting AsyncCalcGraph
2022-05-21 07:37:07.645 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br-1fbbcfe37496", Addrs:set.mapSet{"172.18.0.1":set.empty{}, "fe80::42:d8ff:fe5f:241c":set.empty{}}}
2022-05-21 07:37:07.645 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"172.17.0.1":set.empty{}, "fe80::42:13ff:fef8:8067":set.empty{}} ifaceName="docker0"
2022-05-21 07:37:07.645 [INFO][56] felix/daemon.go 589: Started the processing graph
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=7 ifaceName="veth87c990e" state="up"
2022-05-21 07:37:07.645 [INFO][56] felix/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:07.645 [INFO][56] felix/async_calc_graph.go 137: AsyncCalcGraph running
2022-05-21 07:37:07.645 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/ipam/v2/assignment/"
2022-05-21 07:37:07.645 [INFO][56] felix/daemon.go 940: Reading from dataplane driver pipe...
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"fe80::94d4:45ff:fe6d:7841":set.empty{}} ifaceName="veth87c990e"
2022-05-21 07:37:07.645 [INFO][56] felix/daemon.go 659: No driver process to monitor
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=8 ifaceName="vmnet1" state="up"
2022-05-21 07:37:07.645 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/clusterinformations"
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"172.16.202.1":set.empty{}, "fe80::250:56ff:fec0:1":set.empty{}} ifaceName="vmnet1"
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=9 ifaceName="vmnet8" state="up"
2022-05-21 07:37:07.645 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/felixconfigurations"
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"172.16.91.1":set.empty{}, "fe80::250:56ff:fec0:8":set.empty{}} ifaceName="vmnet8"
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=12 ifaceName="tunl0" state="up"
2022-05-21 07:37:07.645 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/globalnetworkpolicies"
2022-05-21 07:37:07.645 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"10.100.207.64":set.empty{}} ifaceName="tunl0"
2022-05-21 07:37:07.645 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/globalnetworksets"
2022-05-21 07:37:07.646 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"12.1.1.1":set.empty{}, "fe80::43e:1b80:d268:6804":set.empty{}} ifaceName="tun0"
2022-05-21 07:37:07.646 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"docker0", Addrs:set.mapSet{"172.17.0.1":set.empty{}, "fe80::42:13ff:fef8:8067":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"docker0", Addrs:set.mapSet{"172.17.0.1":set.empty{}, "fe80::42:13ff:fef8:8067":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.646 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"veth87c990e", Addrs:set.mapSet{"fe80::94d4:45ff:fe6d:7841":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"veth87c990e", Addrs:set.mapSet{"fe80::94d4:45ff:fe6d:7841":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.646 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"vmnet1", Addrs:set.mapSet{"172.16.202.1":set.empty{}, "fe80::250:56ff:fec0:1":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"vmnet1", Addrs:set.mapSet{"172.16.202.1":set.empty{}, "fe80::250:56ff:fec0:1":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.646 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"vmnet8", Addrs:set.mapSet{"172.16.91.1":set.empty{}, "fe80::250:56ff:fec0:8":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"vmnet8", Addrs:set.mapSet{"172.16.91.1":set.empty{}, "fe80::250:56ff:fec0:8":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.646 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tunl0", Addrs:set.mapSet{"10.100.207.64":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tunl0", Addrs:set.mapSet{"10.100.207.64":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.646 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tun0", Addrs:set.mapSet{"12.1.1.1":set.empty{}, "fe80::43e:1b80:d268:6804":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tun0", Addrs:set.mapSet{"12.1.1.1":set.empty{}, "fe80::43e:1b80:d268:6804":set.empty{}}}
2022-05-21 07:37:07.646 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:07.646 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ConfigUpdate update from calculation graph msg=config:<key:"CalicoVersion" value:"v3.21.2" > config:<key:"ClusterGUID" value:"8f9356338eec420688d4692f5ba4d190" > config:<key:"ClusterType" value:"k8s,bgp,kubeadm,kdd" > config:<key:"DatastoreType" value:"kubernetes" > config:<key:"DefaultEndpointToHostAction" value:"ACCEPT" > config:<key:"FelixHostname" value:"k8smec" > config:<key:"HealthEnabled" value:"true" > config:<key:"IpInIpEnabled" value:"true" > config:<key:"IpInIpMtu" value:"0" > config:<key:"IpInIpTunnelAddr" value:"10.100.207.64" > config:<key:"Ipv6Support" value:"false" > config:<key:"LogFilePath" value:"None" > config:<key:"LogSeverityFile" value:"None" > config:<key:"LogSeverityScreen" value:"Info" > config:<key:"LogSeveritySys" value:"None" > config:<key:"MetadataAddr" value:"None" > config:<key:"ReportingIntervalSecs" value:"0" > config:<key:"VXLANMTU" value:"0" > config:<key:"WireguardMTU" value:"0" > 
2022-05-21 07:37:07.647 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"veth87c990e", State:"up", Index:7}
2022-05-21 07:37:07.647 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"vmnet1", State:"up", Index:8}
2022-05-21 07:37:07.647 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"vmnet8", State:"up", Index:9}
2022-05-21 07:37:07.647 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tunl0", State:"up", Index:12}
2022-05-21 07:37:07.648 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/felixconfigurations"
2022-05-21 07:37:07.648 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/networksets"
2022-05-21 07:37:07.649 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:07.649 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/profiles"
2022-05-21 07:37:07.649 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/workloadendpoints"
2022-05-21 07:37:07.649 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/networkpolicies"
2022-05-21 07:37:07.650 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/networksets"
2022-05-21 07:37:07.651 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/hostendpoints"
2022-05-21 07:37:07.651 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:07.652 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/kubernetesnetworkpolicies"
2022-05-21 07:37:07.658 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/kubernetesendpointslices"
2022-05-21 07:37:07.658 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:07.658 [INFO][56] felix/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:07.658 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.658 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.658 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.658 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=LogSeverityScreen) Info 2570258 <nil> 0s} 1}
2022-05-21 07:37:07.661 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/networkpolicies"
2022-05-21 07:37:07.661 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.645 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:07.665 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/kubernetesendpointslices"
2022-05-21 07:37:07.665 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.666 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/ipam/v2/assignment/"
2022-05-21 07:37:07.667 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:07.667 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/clusterinformations"
2022-05-21 07:37:07.667 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/globalnetworkpolicies"
2022-05-21 07:37:07.667 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/kubernetesnetworkpolicies"
2022-05-21 07:37:07.667 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.667 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.667 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.667 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.667 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.668 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=IpInIpEnabled) true 2570258 <nil> 0s} 1}
2022-05-21 07:37:07.668 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ReportingIntervalSecs) 0 2570258 <nil> 0s} 1}
2022-05-21 07:37:07.669 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:07.669 [INFO][56] felix/config_batcher.go 61: Host config update for this host: {{HostConfig(node=k8smec,name=IpInIpTunnelAddr) 10.100.207.64 2570270 <nil> 0s} 1}
2022-05-21 07:37:07.669 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterGUID) 8f9356338eec420688d4692f5ba4d190 2570257 <nil> 0s} 1}
2022-05-21 07:37:07.669 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.669 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterType) k8s,bgp,kubeadm,kdd 2570257 <nil> 0s} 1}
2022-05-21 07:37:07.669 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=CalicoVersion) v3.21.2 2570257 <nil> 0s} 1}
2022-05-21 07:37:07.669 [INFO][56] felix/int_dataplane.go 1506: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"k8sai" ipv4_addr:"192.168.10.11" 
2022-05-21 07:37:07.670 [INFO][56] felix/int_dataplane.go 1506: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"k8smec" ipv4_addr:"192.168.10.139" 
2022-05-21 07:37:07.670 [INFO][56] felix/int_dataplane.go 1506: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"node1" ipv4_addr:"192.168.10.201" 
2022-05-21 07:37:07.675 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/workloadendpoints"
2022-05-21 07:37:07.676 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.676 [INFO][56] felix/int_dataplane.go 1506: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"10.100.0.0-16" pool:<cidr:"10.100.0.0/16" masquerade:true > 
2022-05-21 07:37:07.686 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/profiles"
2022-05-21 07:37:07.686 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.687 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/globalnetworksets"
2022-05-21 07:37:07.687 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.692 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/hostendpoints"
2022-05-21 07:37:07.692 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:07.692 [INFO][56] felix/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:07.692 [INFO][56] felix/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:07.692 [INFO][56] felix/config_batcher.go 102: Datamodel in sync, flushing config update
2022-05-21 07:37:07.692 [INFO][56] felix/config_batcher.go 112: Sending config update global: map[CalicoVersion:v3.21.2 ClusterGUID:8f9356338eec420688d4692f5ba4d190 ClusterType:k8s,bgp,kubeadm,kdd IpInIpEnabled:true LogSeverityScreen:Info ReportingIntervalSecs:0], host: map[IpInIpTunnelAddr:10.100.207.64].
2022-05-21 07:37:07.692 [INFO][56] felix/async_calc_graph.go 166: First time we've been in sync
2022-05-21 07:37:07.692 [INFO][56] felix/health.go 133: Health of component changed lastReport=health.HealthReport{Live:true, Ready:false} name="async_calc_graph" newReport=&health.HealthReport{Live:true, Ready:true}
2022-05-21 07:37:07.692 [INFO][56] felix/event_sequencer.go 234: Possible config update. global=map[string]string{"CalicoVersion":"v3.21.2", "ClusterGUID":"8f9356338eec420688d4692f5ba4d190", "ClusterType":"k8s,bgp,kubeadm,kdd", "IpInIpEnabled":"true", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0"} host=map[string]string{"IpInIpTunnelAddr":"10.100.207.64"}
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 396: Merging in config from datastore (global): map[CalicoVersion:v3.21.2 ClusterGUID:8f9356338eec420688d4692f5ba4d190 ClusterType:k8s,bgp,kubeadm,kdd IpInIpEnabled:true LogSeverityScreen:Info ReportingIntervalSecs:0]
2022-05-21 07:37:07.692 [INFO][56] felix/usagerep.go 91: Waiting before first check-in delay=5m1.583s
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpMtu: 0 (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpMtu: 0 (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for FelixHostname: k8smec (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for FelixHostname: k8smec (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for VXLANMTU: 0 (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for VXLANMTU: 0 (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for HealthEnabled: true (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for HealthEnabled: true (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for DatastoreType: kubernetes (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for DatastoreType: kubernetes (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for Ipv6Support: false (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for Ipv6Support: false (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for WireguardMTU: 0 (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for WireguardMTU: 0 (from environment variable)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for LogSeveritySys: None (from config file)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 539: Parsed value for LogSeveritySys:  (from config file)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 503: Parsing value for MetadataAddr: None (from config file)
2022-05-21 07:37:07.692 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for MetadataAddr:  (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for LogFilePath: None (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for LogFilePath:  (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for LogSeverityFile: None (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for LogSeverityFile:  (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpTunnelAddr: 10.100.207.64 (from datastore (per-host))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpTunnelAddr: 10.100.207.64 (from datastore (per-host))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for CalicoVersion: v3.21.2 (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for CalicoVersion: v3.21.2 (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for LogSeverityScreen: Info (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for LogSeverityScreen: INFO (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpEnabled: true (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpEnabled: true (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for ClusterGUID: 8f9356338eec420688d4692f5ba4d190 (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for ClusterGUID: 8f9356338eec420688d4692f5ba4d190 (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for ClusterType: k8s,bgp,kubeadm,kdd (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for ClusterType: k8s,bgp,kubeadm,kdd (from datastore (global))
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 396: Merging in config from datastore (per-host): map[IpInIpTunnelAddr:10.100.207.64]
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for VXLANMTU: 0 (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for VXLANMTU: 0 (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for HealthEnabled: true (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for HealthEnabled: true (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for DatastoreType: kubernetes (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for DatastoreType: kubernetes (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for Ipv6Support: false (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for Ipv6Support: false (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for WireguardMTU: 0 (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for WireguardMTU: 0 (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpMtu: 0 (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpMtu: 0 (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for FelixHostname: k8smec (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for FelixHostname: k8smec (from environment variable)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for LogSeverityFile: None (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for LogSeverityFile:  (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for LogSeveritySys: None (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for LogSeveritySys:  (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for MetadataAddr: None (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 539: Parsed value for MetadataAddr:  (from config file)
2022-05-21 07:37:07.693 [INFO][56] felix/config_params.go 503: Parsing value for LogFilePath: None (from config file)
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 539: Parsed value for LogFilePath:  (from config file)
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpTunnelAddr: 10.100.207.64 (from datastore (per-host))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpTunnelAddr: 10.100.207.64 (from datastore (per-host))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 503: Parsing value for CalicoVersion: v3.21.2 (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 539: Parsed value for CalicoVersion: v3.21.2 (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 503: Parsing value for LogSeverityScreen: Info (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 539: Parsed value for LogSeverityScreen: INFO (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpEnabled: true (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpEnabled: true (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 503: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 539: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 503: Parsing value for ClusterGUID: 8f9356338eec420688d4692f5ba4d190 (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 539: Parsed value for ClusterGUID: 8f9356338eec420688d4692f5ba4d190 (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 503: Parsing value for ClusterType: k8s,bgp,kubeadm,kdd (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/config_params.go 539: Parsed value for ClusterType: k8s,bgp,kubeadm,kdd (from datastore (global))
2022-05-21 07:37:07.694 [INFO][56] felix/async_calc_graph.go 220: First flush after becoming in sync, sending InSync message.
2022-05-21 07:37:07.694 [INFO][56] felix/daemon.go 1107: Datastore now in sync.
2022-05-21 07:37:07.694 [INFO][56] felix/daemon.go 1109: Datastore in sync for first time, sending message to status reporter.
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"attachdetach-controller" > labels:<key:"projectcalico.org/name" value:"attachdetach-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"replicaset-controller" > labels:<key:"projectcalico.org/name" value:"replicaset-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"service-controller" > labels:<key:"projectcalico.org/name" value:"service-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"daemon-set-controller" > labels:<key:"projectcalico.org/name" value:"daemon-set-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"deployment-controller" > labels:<key:"projectcalico.org/name" value:"deployment-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"expand-controller" > labels:<key:"projectcalico.org/name" value:"expand-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"horizontal-pod-autoscaler" > labels:<key:"projectcalico.org/name" value:"horizontal-pod-autoscaler" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"statefulset-controller" > labels:<key:"projectcalico.org/name" value:"statefulset-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"clusterrole-aggregation-controller" > labels:<key:"projectcalico.org/name" value:"clusterrole-aggregation-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"kube-proxy" > labels:<key:"projectcalico.org/name" value:"kube-proxy" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"persistent-volume-binder" > labels:<key:"projectcalico.org/name" value:"persistent-volume-binder" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pvc-protection-controller" > labels:<key:"projectcalico.org/name" value:"pvc-protection-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"token-cleaner" > labels:<key:"projectcalico.org/name" value:"token-cleaner" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"metrics-server" > labels:<key:"addonmanager.kubernetes.io/mode" value:"Reconcile" > labels:<key:"kubernetes.io/cluster-service" value:"true" > labels:<key:"projectcalico.org/name" value:"metrics-server" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"namespace-controller" > labels:<key:"projectcalico.org/name" value:"namespace-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pv-protection-controller" > labels:<key:"projectcalico.org/name" value:"pv-protection-controller" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"zmh" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-public" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"calico-node" > labels:<key:"projectcalico.org/name" value:"calico-node" > 
2022-05-21 07:37:07.694 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"generic-garbage-collector" > labels:<key:"projectcalico.org/name" value:"generic-garbage-collector" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"job-controller" > labels:<key:"projectcalico.org/name" value:"job-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"node-controller" > labels:<key:"projectcalico.org/name" value:"node-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"replication-controller" > labels:<key:"projectcalico.org/name" value:"replication-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"service-account-controller" > labels:<key:"projectcalico.org/name" value:"service-account-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"cronjob-controller" > labels:<key:"projectcalico.org/name" value:"cronjob-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"disruption-controller" > labels:<key:"projectcalico.org/name" value:"disruption-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"endpoint-controller" > labels:<key:"projectcalico.org/name" value:"endpoint-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"endpointslicemirroring-controller" > labels:<key:"projectcalico.org/name" value:"endpointslicemirroring-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"default" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"calico-kube-controllers" > labels:<key:"projectcalico.org/name" value:"calico-kube-controllers" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"certificate-controller" > labels:<key:"projectcalico.org/name" value:"certificate-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"coredns" > labels:<key:"projectcalico.org/name" value:"coredns" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"resourcequota-controller" > labels:<key:"projectcalico.org/name" value:"resourcequota-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"ttl-controller" > labels:<key:"projectcalico.org/name" value:"ttl-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-node-lease" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"bootstrap-signer" > labels:<key:"projectcalico.org/name" value:"bootstrap-signer" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"endpointslice-controller" > labels:<key:"projectcalico.org/name" value:"endpointslice-controller" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pod-garbage-collector" > labels:<key:"projectcalico.org/name" value:"pod-garbage-collector" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"zmh" > labels:<key:"projectcalico.org/name" value:"zmh" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-node-lease" > labels:<key:"projectcalico.org/name" value:"kube-node-lease" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-public" > labels:<key:"projectcalico.org/name" value:"kube-public" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-system" > labels:<key:"projectcalico.org/name" value:"kube-system" > 
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1506: Received *proto.InSync update from calculation graph msg=
2022-05-21 07:37:07.695 [INFO][56] felix/int_dataplane.go 1514: Datastore in sync, flushing the dataplane for the first time... timeSinceStart=456.344629ms
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="raw"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="raw"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-hep-forward" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-hep-forward" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="mangle"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="mangle"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-fip-dnat" ipVersion=0x4 table="nat"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-fip-snat" ipVersion=0x4 table="nat"
2022-05-21 07:37:07.695 [INFO][56] felix/masq_mgr.go 145: IPAM pools updated, refreshing iptables rule ipVersion=0x4
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-nat-outgoing" ipVersion=0x4 table="nat"
2022-05-21 07:37:07.695 [INFO][56] felix/ipip_mgr.go 221: All-hosts IP set out-of sync, refreshing it.
2022-05-21 07:37:07.695 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="all-hosts-net" setType="hash:net"
2022-05-21 07:37:07.695 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-cidr-block" ipVersion=0x4 table="filter"
2022-05-21 07:37:07.695 [INFO][56] felix/wireguard.go 1617: Trying to connect to linkClient
2022-05-21 07:37:07.695 [INFO][56] felix/route_table.go 432: Trying to connect to netlink
2022-05-21 07:37:07.696 [INFO][56] felix/route_table.go 432: Trying to connect to netlink
2022-05-21 07:37:07.696 [INFO][56] felix/route_rule.go 176: Trying to connect to netlink
2022-05-21 07:37:07.706 [INFO][56] felix/wireguard.go 570: Public key out of sync or updated ourPublicKey=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=
2022-05-21 07:37:07.728 [INFO][56] felix/ipsets.go 785: Doing full IP set rewrite family="inet" numMembersInPendingReplace=1 setID="masq-ipam-pools"
2022-05-21 07:37:07.728 [INFO][56] felix/ipsets.go 785: Doing full IP set rewrite family="inet" numMembersInPendingReplace=9 setID="this-host"
2022-05-21 07:37:07.728 [INFO][56] felix/ipsets.go 785: Doing full IP set rewrite family="inet" numMembersInPendingReplace=3 setID="all-hosts-net"
2022-05-21 07:37:07.728 [INFO][56] felix/ipsets.go 785: Doing full IP set rewrite family="inet" numMembersInPendingReplace=1 setID="all-ipam-pools"
2022-05-21 07:37:07.821 [INFO][56] felix/int_dataplane.go 1654: Completed first update to dataplane. secsSinceStart=0.582443361
2022-05-21 07:37:07.825 [INFO][56] felix/health.go 133: Health of component changed lastReport=health.HealthReport{Live:true, Ready:false} name="int_dataplane" newReport=&health.HealthReport{Live:true, Ready:true}
bird: device1: Initializing
bird: direct1: Initializing
bird: Mesh_192_168_10_11: Initializing
bird: Mesh_192_168_10_201: 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_192_168_10_11: Starting
bird: Mesh_192_168_10_11: State changed to start
bird: Mesh_192_168_10_201: Starting
bird: Mesh_192_168_10_201: State changed to start
bird: Graceful restart started
bird: Started
bird: device1: State changed to up
bird: direct1: State changed to up
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: Mesh_192_168_10_11: Connected to table master
bird: Mesh_192_168_10_11: State changed to feed
bird: Mesh_192_168_10_11: State changed to up
2022-05-21 07:37:19.353 [INFO][56] felix/health.go 196: Overall health status changed newStatus=&health.HealthReport{Live:true, Ready:true}
bird: Mesh_192_168_10_201: Connected to table master
bird: Mesh_192_168_10_201: State changed to feed
bird: Mesh_192_168_10_201: State changed to up
bird: Graceful restart done
2022-05-21 07:38:07.281 [INFO][60] monitor-addresses/startup.go 727: Using autodetected IPv4 address 192.168.10.139/24 on matching interface enp2s0
2022-05-21 07:38:10.218 [INFO][56] felix/summary.go 100: Summarising 14 dataplane reconciliation loops over 1m2.9s: avg=14ms longest=126ms (resync-filter-v4,resync-ipsets-v4,resync-mangle-v4,resync-nat-v4,resync-raw-v4,resync-routes-v4,resync-routes-v4,resync-rules-v4,update-ipsets-4)
2022-05-21 07:39:07.281 [INFO][60] monitor-addresses/startup.go 727: Using autodetected IPv4 address 192.168.10.139/24 on matching interface enp2s0
2022-05-21 07:39:13.669 [INFO][56] felix/summary.go 100: Summarising 8 dataplane reconciliation loops over 1m3.5s: avg=5ms longest=34ms (resync-filter-v4,resync-mangle-v4,resync-nat-v4,resync-raw-v4)
2022-05-21 07:40:07.282 [INFO][60] monitor-addresses/startup.go 727: Using autodetected IPv4 address 192.168.10.139/24 on matching interface enp2s0
2022-05-21 07:40:17.090 [INFO][56] felix/summary.go 100: Summarising 9 dataplane reconciliation loops over 1m3.4s: avg=3ms longest=13ms (resync-mangle-v4,resync-nat-v4,resync-raw-v4)

node1的日志

2022-05-21 07:37:13.030 [INFO][8] startup/startup.go 396: Early log level set to info
2022-05-21 07:37:13.030 [INFO][8] startup/utils.go 126: Using NODENAME environment for node name node1
2022-05-21 07:37:13.030 [INFO][8] startup/utils.go 138: Determined node name: node1
2022-05-21 07:37:13.030 [INFO][8] startup/startup.go 98: Starting node node1 with version v3.21.2
2022-05-21 07:37:13.032 [INFO][8] startup/startup.go 401: Checking datastore connection
2022-05-21 07:37:13.051 [INFO][8] startup/startup.go 425: Datastore connection verified
2022-05-21 07:37:13.055 [INFO][8] startup/startup.go 108: Datastore is ready
2022-05-21 07:37:13.076 [INFO][8] startup/startup.go 727: Using autodetected IPv4 address 192.168.10.201/24 on matching interface ens33
2022-05-21 07:37:13.076 [INFO][8] startup/startup.go 790: No AS number configured on node resource, using global value
2022-05-21 07:37:13.091 [INFO][8] startup/startup.go 839: found v6= in the kubeadm config map
2022-05-21 07:37:13.098 [INFO][8] startup/startup.go 645: FELIX_IPV6SUPPORT is false through environment variable
2022-05-21 07:37:13.111 [INFO][8] startup/startup.go 208: Using node name: node1
2022-05-21 07:37:13.111 [INFO][8] startup/utils.go 190: Setting NetworkUnavailable to false
2022-05-21 07:37:13.277 [ERROR][15] tunnel-ip-allocator/ipam.go 1740: Error reading block 10.100.166.128/26: resource does not exist: IPAMBlock(10-100-166-128-26) with error: ipamblocks.crd.projectcalico.org "10-100-166-128-26" not found
2022-05-21 07:37:13.278 [INFO][15] tunnel-ip-allocator/allocateip.go 342: Current address is not assigned, assign a new one currentAddr="10.100.166.128" type="ipipTunnelAddress"
2022-05-21 07:37:13.278 [INFO][15] tunnel-ip-allocator/allocateip.go 355: Release any old tunnel addresses IP="10.100.166.128" type="ipipTunnelAddress"
2022-05-21 07:37:13.280 [INFO][15] tunnel-ip-allocator/allocateip.go 366: Assign new tunnel address IP="10.100.166.128" type="ipipTunnelAddress"
2022-05-21 07:37:13.280 [INFO][15] tunnel-ip-allocator/ipam.go 103: Auto-assign 1 ipv4, 0 ipv6 addrs for host 'node1'
2022-05-21 07:37:13.284 [INFO][15] tunnel-ip-allocator/ipam.go 656: Looking up existing affinities for host handle="ipip-tunnel-addr-node1" host="node1"
2022-05-21 07:37:13.295 [INFO][15] tunnel-ip-allocator/ipam.go 368: Looking up existing affinities for host host="node1"
2022-05-21 07:37:13.301 [INFO][15] tunnel-ip-allocator/ipam.go 517: Ran out of existing affine blocks for host host="node1"
2022-05-21 07:37:13.303 [INFO][15] tunnel-ip-allocator/ipam.go 534: Tried all affine blocks. Looking for an affine block with space, or a new unclaimed block host="node1"
2022-05-21 07:37:13.313 [INFO][15] tunnel-ip-allocator/ipam_block_reader_writer.go 153: Found free block: 10.100.166.128/26
2022-05-21 07:37:13.313 [INFO][15] tunnel-ip-allocator/ipam.go 546: Found unclaimed block host="node1" subnet=10.100.166.128/26
2022-05-21 07:37:13.313 [INFO][15] tunnel-ip-allocator/ipam_block_reader_writer.go 170: Trying to create affinity in pending state host="node1" subnet=10.100.166.128/26
2022-05-21 07:37:13.324 [INFO][15] tunnel-ip-allocator/ipam_block_reader_writer.go 200: Successfully created pending affinity for block host="node1" subnet=10.100.166.128/26
2022-05-21 07:37:13.324 [INFO][15] tunnel-ip-allocator/ipam.go 151: Attempting to load block cidr=10.100.166.128/26 host="node1"
2022-05-21 07:37:13.326 [INFO][15] tunnel-ip-allocator/ipam.go 156: The referenced block doesn't exist, trying to create it cidr=10.100.166.128/26 host="node1"
2022-05-21 07:37:13.328 [INFO][15] tunnel-ip-allocator/ipam.go 163: Wrote affinity as pending cidr=10.100.166.128/26 host="node1"
2022-05-21 07:37:13.330 [INFO][15] tunnel-ip-allocator/ipam.go 172: Attempting to claim the block cidr=10.100.166.128/26 host="node1"
2022-05-21 07:37:13.330 [INFO][15] tunnel-ip-allocator/ipam_block_reader_writer.go 222: Attempting to create a new block host="node1" subnet=10.100.166.128/26
2022-05-21 07:37:13.334 [INFO][15] tunnel-ip-allocator/ipam_block_reader_writer.go 263: Successfully created block
2022-05-21 07:37:13.334 [INFO][15] tunnel-ip-allocator/ipam_block_reader_writer.go 274: Confirming affinity host="node1" subnet=10.100.166.128/26
2022-05-21 07:37:13.337 [INFO][15] tunnel-ip-allocator/ipam_block_reader_writer.go 289: Successfully confirmed affinity host="node1" subnet=10.100.166.128/26
2022-05-21 07:37:13.337 [INFO][15] tunnel-ip-allocator/ipam.go 581: Block '10.100.166.128/26' has 64 free ips which is more than 1 ips required. host="node1" subnet=10.100.166.128/26
2022-05-21 07:37:13.337 [INFO][15] tunnel-ip-allocator/ipam.go 1153: Attempting to assign 1 addresses from block block=10.100.166.128/26 handle="ipip-tunnel-addr-node1" host="node1"
2022-05-21 07:37:13.343 [INFO][15] tunnel-ip-allocator/ipam.go 1630: Creating new handle: ipip-tunnel-addr-node1
2022-05-21 07:37:13.346 [INFO][15] tunnel-ip-allocator/ipam.go 1176: Writing block in order to claim IPs block=10.100.166.128/26 handle="ipip-tunnel-addr-node1" host="node1"
2022-05-21 07:37:13.350 [INFO][15] tunnel-ip-allocator/ipam.go 1189: Successfully claimed IPs: [10.100.166.128/26] block=10.100.166.128/26 handle="ipip-tunnel-addr-node1" host="node1"
2022-05-21 07:37:13.350 [INFO][15] tunnel-ip-allocator/ipam.go 843: Auto-assigned 1 out of 1 IPv4s: [10.100.166.128/26] handle="ipip-tunnel-addr-node1" host="node1"
2022-05-21 07:37:13.369 [INFO][15] tunnel-ip-allocator/allocateip.go 455: Assigned tunnel address to node IP="10.100.166.128" type="ipipTunnelAddress"
Calico node started successfully
bird: Unable to open configuration file /etc/calico/confd/config/bird.cfg: No such file or directory
bird: Unable to open configuration file /etc/calico/confd/config/bird6.cfg: No such file or directory
2022-05-21 07:37:14.883 [INFO][57] status-reporter/startup.go 396: Early log level set to info
2022-05-21 07:37:14.904 [INFO][57] status-reporter/watchersyncer.go 89: Start called
2022-05-21 07:37:14.904 [INFO][57] status-reporter/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:14.904 [INFO][57] status-reporter/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/caliconodestatuses"
2022-05-21 07:37:14.916 [INFO][57] status-reporter/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:14.968 [INFO][52] cni-config-monitor/token_watch.go 40: Watching contents for changes. directory="/var/run/secrets/kubernetes.io/serviceaccount/"
2022-05-21 07:37:15.125 [INFO][53] tunnel-ip-allocator/watchersyncer.go 89: Start called
2022-05-21 07:37:15.125 [INFO][53] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:15.125 [INFO][53] tunnel-ip-allocator/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:15.132 [INFO][57] status-reporter/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/caliconodestatuses"
2022-05-21 07:37:15.133 [INFO][57] status-reporter/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:15.133 [INFO][57] status-reporter/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:15.133 [INFO][57] status-reporter/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:15.133 [INFO][57] status-reporter/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:15.177 [INFO][53] tunnel-ip-allocator/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/nodes/node1"
2022-05-21 07:37:15.132 [INFO][53] tunnel-ip-allocator/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:15.278 [INFO][53] tunnel-ip-allocator/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:15.278 [INFO][53] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:15.278 [INFO][53] tunnel-ip-allocator/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:15.361 [INFO][53] tunnel-ip-allocator/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes/node1"
2022-05-21 07:37:15.362 [INFO][53] tunnel-ip-allocator/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:15.362 [INFO][53] tunnel-ip-allocator/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:15.362 [INFO][53] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:15.773 [INFO][53] tunnel-ip-allocator/allocateip.go 303: Current address is still valid, do nothing currentAddr="10.100.166.128" type="ipipTunnelAddress"
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
2022-05-21 07:37:16.544 [INFO][56] felix/daemon.go 358: Successfully loaded configuration. GOMAXPROCS=2 builddate="2021-12-06T03:23:22+0000" config=&config.Config{UseInternalDataplaneDriver:true, DataplaneDriver:"calico-iptables-plugin", WireguardEnabled:false, WireguardListeningPort:51820, WireguardRoutingRulePriority:99, WireguardInterfaceName:"wireguard.cali", WireguardMTU:0, WireguardHostEncryptionEnabled:false, BPFEnabled:false, BPFDisableUnprivileged:true, BPFLogLevel:"off", BPFDataIfacePattern:(*regexp.Regexp)(0xc00062c1e0), BPFConnectTimeLoadBalancingEnabled:true, BPFExternalServiceMode:"tunnel", BPFKubeProxyIptablesCleanupEnabled:true, BPFKubeProxyMinSyncPeriod:1000000000, BPFKubeProxyEndpointSlicesEnabled:false, BPFExtToServiceConnmark:0, BPFPSNATPorts:numorstring.Port{MinPort:0x4e20, MaxPort:0x752f, PortName:""}, DebugBPFCgroupV2:"", DebugBPFMapRepinEnabled:false, DatastoreType:"kubernetes", FelixHostname:"node1", EtcdAddr:"127.0.0.1:2379", EtcdScheme:"http", EtcdKeyFile:"", EtcdCertFile:"", EtcdCaFile:"", EtcdEndpoints:[]string(nil), TyphaAddr:"", TyphaK8sServiceName:"", TyphaK8sNamespace:"kube-system", TyphaReadTimeout:30000000000, TyphaWriteTimeout:10000000000, TyphaKeyFile:"", TyphaCertFile:"", TyphaCAFile:"", TyphaCN:"", TyphaURISAN:"", Ipv6Support:false, IptablesBackend:"auto", RouteRefreshInterval:90000000000, InterfaceRefreshInterval:90000000000, DeviceRouteSourceAddress:net.IP(nil), DeviceRouteProtocol:3, RemoveExternalRoutes:true, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckIntervalSecs:1000000000, IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeoutSecs:0, IptablesLockProbeIntervalMillis:50000000, FeatureDetectOverride:map[string]string(nil), IpsetsRefreshInterval:10000000000, MaxIpsetSize:1048576, XDPRefreshInterval:90000000000, PolicySyncPathPrefix:"", NetlinkTimeoutSecs:10000000000, MetadataAddr:"", MetadataPort:8775, OpenstackRegion:"", InterfacePrefix:"cali", InterfaceExclude:[]*regexp.Regexp{(*regexp.Regexp)(0xc0009560a0)}, ChainInsertMode:"insert", DefaultEndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", LogPrefix:"calico-packet", LogFilePath:"", LogSeverityFile:"", LogSeverityScreen:"INFO", LogSeveritySys:"", VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, VXLANMTU:0, IPv4VXLANTunnelAddr:net.IP(nil), VXLANTunnelMACAddr:"", IpInIpEnabled:true, IpInIpMtu:0, IpInIpTunnelAddr:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xa6, 0x80}, AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, AWSSrcDstCheck:"DoNothing", ServiceLoopPrevention:"Drop", ReportingIntervalSecs:0, ReportingTTLSecs:90000000000, EndpointReportingEnabled:false, EndpointReportingDelaySecs:1000000000, IptablesMarkMask:0xffff0000, DisableConntrackInvalidCheck:false, HealthEnabled:true, HealthPort:9099, HealthHost:"localhost", PrometheusMetricsEnabled:false, PrometheusMetricsHost:"", PrometheusMetricsPort:9091, PrometheusGoMetricsEnabled:true, PrometheusProcessMetricsEnabled:true, PrometheusWireGuardMetricsEnabled:true, FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", 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:"8f9356338eec420688d4692f5ba4d190", ClusterType:"k8s,bgp,kubeadm,kdd", CalicoVersion:"v3.21.2", ExternalNodesCIDRList:[]string(nil), DebugMemoryProfilePath:"", DebugCPUProfilePath:"/tmp/felix-cpu-<timestamp>.pprof", DebugDisableLogDropping:false, DebugSimulateCalcGraphHangAfter:0, DebugSimulateDataplaneHangAfter:0, DebugPanicAfter:0, DebugSimulateDataRace:false, RouteSource:"CalicoIPAM", RouteTableRange:idalloc.IndexRange{Min:1, Max:250}, IptablesNATOutgoingInterfaceFilter:"", SidecarAccelerationEnabled:false, XDPEnabled:true, GenericXDPEnabled:false, Variant:"Calico", MTUIfacePattern:(*regexp.Regexp)(0xc000956460), internalOverrides:map[string]string{}, sourceToRawConfig:map[config.Source]map[string]string{0x1:map[string]string{"CalicoVersion":"v3.21.2", "ClusterGUID":"8f9356338eec420688d4692f5ba4d190", "ClusterType":"k8s,bgp,kubeadm,kdd", "IpInIpEnabled":"true", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0"}, 0x2:map[string]string{"IpInIpTunnelAddr":"10.100.166.128"}, 0x3:map[string]string{"LogFilePath":"None", "LogSeverityFile":"None", "LogSeveritySys":"None", "MetadataAddr":"None"}, 0x4:map[string]string{"datastoretype":"kubernetes", "defaultendpointtohostaction":"ACCEPT", "felixhostname":"node1", "healthenabled":"true", "ipinipmtu":"0", "ipv6support":"false", "vxlanmtu":"0", "wireguardmtu":"0"}}, rawValues:map[string]string{"CalicoVersion":"v3.21.2", "ClusterGUID":"8f9356338eec420688d4692f5ba4d190", "ClusterType":"k8s,bgp,kubeadm,kdd", "DatastoreType":"kubernetes", "DefaultEndpointToHostAction":"ACCEPT", "FelixHostname":"node1", "HealthEnabled":"true", "IpInIpEnabled":"true", "IpInIpMtu":"0", "IpInIpTunnelAddr":"10.100.166.128", "Ipv6Support":"false", "LogFilePath":"None", "LogSeverityFile":"None", "LogSeverityScreen":"Info", "LogSeveritySys":"None", "MetadataAddr":"None", "ReportingIntervalSecs":"0", "VXLANMTU":"0", "WireguardMTU":"0"}, Err:error(nil), loadClientConfigFromEnvironment:(func() (*apiconfig.CalicoAPIConfig, error))(0x1597ec0), useNodeResourceUpdates:false} gitcommit="6411b17928e58c88815ab7eb8b335187508d4987" version="v3.21.2"
2022-05-21 07:37:16.545 [INFO][56] felix/driver.go 69: Using internal (linux) dataplane driver.
2022-05-21 07:37:16.546 [INFO][56] felix/driver.go 154: Calculated iptables mark bits acceptMark=0x10000 endpointMark=0xfff00000 endpointMarkNonCali=0x0 passMark=0x20000 scratch0Mark=0x40000 scratch1Mark=0x80000
2022-05-21 07:37:16.547 [INFO][56] felix/int_dataplane.go 307: Creating internal dataplane driver. config=intdataplane.Config{Hostname:"node1", IPv6Enabled:false, RuleRendererOverride:rules.RuleRenderer(nil), IPIPMTU:0, VXLANMTU:0, VXLANPort:4789, MaxIPSetSize:1048576, IptablesBackend:"auto", IPSetsRefreshInterval:10000000000, RouteRefreshInterval:90000000000, DeviceRouteSourceAddress:net.IP(nil), DeviceRouteProtocol:3, RemoveExternalRoutes:true, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckInterval:1000000000, IptablesInsertMode:"insert", IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeout:0, IptablesLockProbeInterval:50000000, XDPRefreshInterval:90000000000, Wireguard:wireguard.Config{Enabled:false, ListeningPort:51820, FirewallMark:0, RoutingRulePriority:99, RoutingTableIndex:1, InterfaceName:"wireguard.cali", MTU:0, RouteSource:"CalicoIPAM", EncryptHostTraffic:false}, NetlinkTimeout:10000000000, RulesConfig:rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc0003507d0), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc0003508c0), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x0, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:true, IPIPTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xa6, 0x80}, VXLANTunnelAddress:net.IP(nil), AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, WireguardEnabled:false, WireguardInterfaceName:"wireguard.cali", WireguardIptablesMark:0x0, WireguardListeningPort:51820, WireguardEncryptHostTraffic:false, RouteSource:"CalicoIPAM", IptablesLogPrefix:"calico-packet", EndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil), BPFEnabled:false, ServiceLoopPrevention:"Drop"}, IfaceMonitorConfig:ifacemonitor.Config{InterfaceExcludes:[]*regexp.Regexp{(*regexp.Regexp)(0xc0009560a0)}, ResyncInterval:90000000000}, StatusReportingInterval:0, ConfigChangedRestartCallback:(func())(0x28b7b80), FatalErrorRestartCallback:(func(error))(0x28b7c40), PostInSyncCallback:(func())(0x289f340), HealthAggregator:(*health.HealthAggregator)(0xc000634000), RouteTableManager:(*idalloc.IndexAllocator)(0xc000346178), DebugSimulateDataplaneHangAfter:0, ExternalNodesCidrs:[]string(nil), BPFEnabled:false, BPFDisableUnprivileged:true, BPFKubeProxyIptablesCleanupEnabled:true, BPFLogLevel:"off", BPFExtToServiceConnmark:0, BPFDataIfacePattern:(*regexp.Regexp)(0xc00062c1e0), XDPEnabled:true, XDPAllowGeneric:false, BPFConntrackTimeouts:conntrack.Timeouts{CreationGracePeriod:10000000000, TCPPreEstablished:20000000000, TCPEstablished:3600000000000, TCPFinsSeen:30000000000, TCPResetSeen:40000000000, UDPLastSeen:60000000000, GenericIPLastSeen:600000000000, ICMPLastSeen:5000000000}, BPFCgroupV2:"", BPFConnTimeLBEnabled:true, BPFMapRepin:false, BPFNodePortDSREnabled:false, BPFPSNATPorts:numorstring.Port{MinPort:0x4e20, MaxPort:0x752f, PortName:""}, KubeProxyMinSyncPeriod:1000000000, KubeProxyEndpointSlicesEnabled:false, SidecarAccelerationEnabled:false, LookPathOverride:(func(string) (string, error))(nil), KubeClientSet:(*kubernetes.Clientset)(0xc0000d71e0), FeatureDetectOverrides:map[string]string(nil), hostMTU:0, MTUIfacePattern:(*regexp.Regexp)(0xc000956460), RouteSource:"CalicoIPAM", KubernetesProvider:0x0}
2022-05-21 07:37:16.552 [INFO][56] felix/rule_defs.go 358: Creating rule renderer. config=rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc0003507d0), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc0003508c0), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x0, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:true, IPIPTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xa6, 0x80}, VXLANTunnelAddress:net.IP(nil), AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, WireguardEnabled:false, WireguardInterfaceName:"wireguard.cali", WireguardIptablesMark:0x0, WireguardListeningPort:51820, WireguardEncryptHostTraffic:false, RouteSource:"CalicoIPAM", IptablesLogPrefix:"calico-packet", EndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil), BPFEnabled:false, ServiceLoopPrevention:"Drop"}
2022-05-21 07:37:16.554 [INFO][56] felix/rule_defs.go 368: Workload to host packets will be accepted.
2022-05-21 07:37:16.557 [INFO][56] felix/rule_defs.go 382: filter table allowed packets will be accepted immediately.
2022-05-21 07:37:16.557 [INFO][56] felix/rule_defs.go 390: mangle table allowed packets will be accepted immediately.
2022-05-21 07:37:16.557 [INFO][56] felix/rule_defs.go 398: Packets to unknown service IPs will be dropped
2022-05-21 07:37:16.558 [INFO][56] felix/int_dataplane.go 932: Determined pod MTU mtu=1480
2022-05-21 07:37:16.559 [INFO][50] confd/config.go 81: Skipping confd config file.
2022-05-21 07:37:16.559 [INFO][50] confd/run.go 17: Starting calico-confd
2022-05-21 07:37:16.560 [INFO][56] felix/iface_monitor.go 74: configured to periodically rescan interfaces. interval=1m30s
2022-05-21 07:37:16.560 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"ip6tables-legacy-save", "ip6tables-save"} command="ip6tables-legacy-save" ipVersion=0x6 saveOrRestore="save"
2022-05-21 07:37:16.560 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
W0521 07:37:16.575832      50 client_config.go:615] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
2022-05-21 07:37:16.581 [INFO][50] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:16.584 [INFO][50] confd/client.go 1215: Updated with new cluster IP CIDRs: []
2022-05-21 07:37:16.584 [INFO][50] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:16.584 [INFO][50] confd/client.go 1206: Updated with new external IP CIDRs: []
2022-05-21 07:37:16.584 [INFO][50] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:16.584 [INFO][50] confd/client.go 1225: Updated with new Loadbalancer IP CIDRs: []
2022-05-21 07:37:16.584 [INFO][50] confd/watchersyncer.go 89: Start called
2022-05-21 07:37:16.584 [INFO][50] confd/client.go 378: Source SourceRouteGenerator readiness changed, ready=true
2022-05-21 07:37:16.585 [INFO][50] confd/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:16.585 [INFO][50] confd/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:16.585 [INFO][50] confd/watchercache.go 175: Full resync is required ListRoot="/calico/ipam/v2/host/node1"
2022-05-21 07:37:16.589 [INFO][50] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:16.590 [INFO][50] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:16.590 [INFO][50] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:16.591 [INFO][51] monitor-addresses/startup.go 396: Early log level set to info
2022-05-21 07:37:16.593 [INFO][51] monitor-addresses/utils.go 126: Using NODENAME environment for node name node1
2022-05-21 07:37:16.593 [INFO][51] monitor-addresses/utils.go 138: Determined node name: node1
2022-05-21 07:37:16.598 [INFO][50] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/bgppeers"
2022-05-21 07:37:16.621 [INFO][50] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:16.627 [INFO][50] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgppeers"
2022-05-21 07:37:16.627 [INFO][50] confd/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:16.627 [INFO][50] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:16.627 [INFO][50] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:16.634 [INFO][50] confd/watchercache.go 273: Sending synced update ListRoot="/calico/ipam/v2/host/node1"
2022-05-21 07:37:16.634 [INFO][50] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:16.646 [INFO][50] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:16.647 [INFO][50] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:16.648 [INFO][50] confd/client.go 900: Recompute BGP peerings: HostBGPConfig(node=k8sai; name=ip_addr_v4) updated; HostBGPConfig(node=k8sai; name=ip_addr_v6) updated; HostBGPConfig(node=k8sai; name=network_v4) updated; HostBGPConfig(node=k8sai; name=rr_cluster_id) updated; k8sai updated; HostBGPConfig(node=k8smec; name=ip_addr_v4) updated; HostBGPConfig(node=k8smec; name=ip_addr_v6) updated; HostBGPConfig(node=k8smec; name=network_v4) updated; HostBGPConfig(node=k8smec; name=rr_cluster_id) updated; k8smec updated; HostBGPConfig(node=node1; name=ip_addr_v4) updated; HostBGPConfig(node=node1; name=ip_addr_v6) updated; HostBGPConfig(node=node1; name=network_v4) updated; HostBGPConfig(node=node1; name=rr_cluster_id) updated; node1 updated
2022-05-21 07:37:16.658 [INFO][50] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:16.658 [INFO][50] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:16.658 [INFO][50] confd/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:16.658 [INFO][50] confd/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:16.658 [INFO][50] confd/client.go 378: Source SourceSyncer readiness changed, ready=true
2022-05-21 07:37:16.658 [INFO][50] confd/client.go 398: Data is now syncd, can start rendering templates
2022-05-21 07:37:16.686 [INFO][50] confd/resource.go 277: Target config /etc/calico/confd/config/bird6_ipam.cfg has been updated
2022-05-21 07:37:16.686 [INFO][50] confd/resource.go 277: Target config /etc/calico/confd/config/bird_aggr.cfg has been updated
2022-05-21 07:37:16.686 [INFO][50] confd/resource.go 277: Target config /etc/calico/confd/config/bird6_aggr.cfg has been updated
2022-05-21 07:37:16.703 [INFO][50] confd/resource.go 277: Target config /etc/calico/confd/config/bird_ipam.cfg has been updated
2022-05-21 07:37:16.709 [INFO][50] confd/resource.go 277: Target config /etc/calico/confd/config/bird.cfg has been updated
2022-05-21 07:37:16.710 [INFO][56] felix/feature_detect.go 153: Updating detected iptables features features=iptables.Features{SNATFullyRandom:true, MASQFullyRandom:true, RestoreSupportsLock:true, ChecksumOffloadBroken:true} iptablesVersion=1.8.4 kernelVersion=4.15.0-142
2022-05-21 07:37:16.710 [INFO][56] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:16.710 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:16.710 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:16.711 [INFO][56] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-|-A POSTROUTING .* felix-masq-ipam-pools .*|-A POSTROUTING -o tunl0 -m addrtype ! --src-type LOCAL --limit-iface-out -m addrtype --src-type LOCAL -j MASQUERADE"
2022-05-21 07:37:16.711 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:16.711 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:16.711 [INFO][56] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:16.712 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:16.712 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:16.712 [INFO][56] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:16.721 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:16.724 [INFO][56] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:16.724 [WARNING][56] felix/int_dataplane.go 459: Can't enable XDP acceleration. error=kernel is too old (have: 4.15.0-142 but want at least: 4.16.0)
2022-05-21 07:37:16.730 [INFO][50] confd/resource.go 277: Target config /etc/calico/confd/config/bird6.cfg has been updated
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: Reconfiguration requested by SIGHUP
bird: Reconfiguring
bird: device1: Reconfigured
bird: direct1: Reconfigured
bird: Reconfigured
2022-05-21 07:37:16.828 [INFO][56] felix/health.go 196: Overall health status changed newStatus=&health.HealthReport{Live:true, Ready:true}
2022-05-21 07:37:17.015 [INFO][56] felix/connecttime.go 51: Running bpftool to look up programs attached to cgroup args=[]string{"bpftool", "-j", "-p", "cgroup", "show", "/run/calico/cgroup"}
2022-05-21 07:37:17.029 [INFO][56] felix/route_table.go 266: Calculated interface name regexp ifaceRegex="^cali.*" ipVersion=0x4 tableIndex=0
2022-05-21 07:37:17.029 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="all-ipam-pools" setType="hash:net"
2022-05-21 07:37:17.030 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="masq-ipam-pools" setType="hash:net"
2022-05-21 07:37:17.031 [INFO][56] felix/route_table.go 266: Calculated interface name regexp ifaceRegex="^wireguard.cali$" ipVersion=0x4 tableIndex=1
2022-05-21 07:37:17.031 [INFO][56] felix/int_dataplane.go 837: Registering to report health.
2022-05-21 07:37:17.056 [INFO][56] felix/int_dataplane.go 1682: attempted to modprobe nf_conntrack_proto_sctp error=exit status 1 output=""
2022-05-21 07:37:17.056 [INFO][56] felix/int_dataplane.go 1684: Making sure IPv4 forwarding is enabled.
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="raw"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="raw"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="raw"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-wireguard-incoming-mark" ipVersion=0x4 table="raw"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="raw"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-FORWARD" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-hep-forward"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-wl-dispatch"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-wl-dispatch"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-hep-forward"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-cidr-block"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-INPUT" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-wl-to-host"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-wl-to-host" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-FORWARD"
2022-05-21 07:37:17.057 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-INPUT"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="nat"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-fip-dnat"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="nat"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-fip-snat"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-nat-outgoing"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="nat"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-POSTROUTING"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:17.058 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-POSTROUTING"
2022-05-21 07:37:17.058 [INFO][56] felix/int_dataplane.go 1121: IPIP enabled, starting thread to keep tunnel configuration in sync.
2022-05-21 07:37:17.058 [INFO][56] felix/daemon.go 397: Connect to the dataplane driver.
2022-05-21 07:37:17.060 [INFO][56] felix/daemon.go 475: using resource updates where applicable
2022-05-21 07:37:17.060 [INFO][56] felix/daemon.go 478: Created Syncer syncer=&watchersyncer.watcherSyncer{status:0x0, watcherCaches:[]*watchersyncer.watcherCache{(*watchersyncer.watcherCache)(0xc000671000), (*watchersyncer.watcherCache)(0xc000671080), (*watchersyncer.watcherCache)(0xc000671100), (*watchersyncer.watcherCache)(0xc000671180), (*watchersyncer.watcherCache)(0xc000671200), (*watchersyncer.watcherCache)(0xc000671280), (*watchersyncer.watcherCache)(0xc000671300), (*watchersyncer.watcherCache)(0xc000671380), (*watchersyncer.watcherCache)(0xc000671400), (*watchersyncer.watcherCache)(0xc000671480), (*watchersyncer.watcherCache)(0xc000671500), (*watchersyncer.watcherCache)(0xc000671580), (*watchersyncer.watcherCache)(0xc000671600), (*watchersyncer.watcherCache)(0xc000671680), (*watchersyncer.watcherCache)(0xc000671700)}, results:(chan interface {})(0xc0008c5c20), numSynced:0, callbacks:(*calc.SyncerCallbacksDecoupler)(0xc0003463e0), wgwc:(*sync.WaitGroup)(nil), wgws:(*sync.WaitGroup)(nil), cancel:(context.CancelFunc)(nil)}
2022-05-21 07:37:17.060 [INFO][56] felix/daemon.go 482: Starting the datastore Syncer
2022-05-21 07:37:17.061 [INFO][56] felix/watchersyncer.go 89: Start called
2022-05-21 07:37:17.061 [INFO][56] felix/calc_graph.go 115: Creating calculation graph, filtered to hostname node1
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9c9a0)
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9c9a0)
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9caa0)
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9caa0)
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileRulesKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:17.061 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:17.062 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:17.062 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ResourceKey: (dispatcher.UpdateHandler)(0x1a61360)
2022-05-21 07:37:17.093 [INFO][56] felix/int_dataplane.go 1460: Started internal iptables dataplane driver loop
2022-05-21 07:37:17.093 [INFO][56] felix/int_dataplane.go 1470: Will refresh IP sets on timer interval=1m30s
2022-05-21 07:37:17.093 [INFO][56] felix/int_dataplane.go 1480: Will refresh routes on timer interval=1m30s
2022-05-21 07:37:17.062 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:17.099 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:17.100 [INFO][56] felix/ipip_mgr.go 84: IPIP thread started.
2022-05-21 07:37:17.099 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.NetworkSetKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x1a9d080)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9d080)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9d080)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x1a9cc40)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.IPPoolKey: (dispatcher.UpdateHandler)(0x1a9cc40)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WireguardKey: (dispatcher.UpdateHandler)(0x1a9cc40)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ResourceKey: (dispatcher.UpdateHandler)(0x1a9cc40)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.GlobalConfigKey: (dispatcher.UpdateHandler)(0x1a9cb40)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x1a9cb40)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ReadyFlagKey: (dispatcher.UpdateHandler)(0x1a9cb40)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x1a9d220)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x1a9d2c0)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9d2c0)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9d2c0)
2022-05-21 07:37:17.100 [INFO][56] felix/dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x1a9d2c0)
2022-05-21 07:37:17.100 [INFO][56] felix/async_calc_graph.go 255: Starting AsyncCalcGraph
2022-05-21 07:37:17.101 [INFO][56] felix/daemon.go 589: Started the processing graph
2022-05-21 07:37:17.101 [INFO][56] felix/async_calc_graph.go 137: AsyncCalcGraph running
2022-05-21 07:37:17.101 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ConfigUpdate update from calculation graph msg=config:<key:"CalicoVersion" value:"v3.21.2" > config:<key:"ClusterGUID" value:"8f9356338eec420688d4692f5ba4d190" > config:<key:"ClusterType" value:"k8s,bgp,kubeadm,kdd" > config:<key:"DatastoreType" value:"kubernetes" > config:<key:"DefaultEndpointToHostAction" value:"ACCEPT" > config:<key:"FelixHostname" value:"node1" > config:<key:"HealthEnabled" value:"true" > config:<key:"IpInIpEnabled" value:"true" > config:<key:"IpInIpMtu" value:"0" > config:<key:"IpInIpTunnelAddr" value:"10.100.166.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" > config:<key:"VXLANMTU" value:"0" > config:<key:"WireguardMTU" value:"0" > 
2022-05-21 07:37:17.101 [INFO][56] felix/daemon.go 940: Reading from dataplane driver pipe...
2022-05-21 07:37:17.102 [INFO][56] felix/daemon.go 659: No driver process to monitor
2022-05-21 07:37:17.102 [INFO][56] felix/iface_monitor.go 100: Interface monitoring thread started.
2022-05-21 07:37:17.103 [INFO][56] felix/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:17.105 [INFO][56] felix/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:17.105 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/ipam/v2/assignment/"
2022-05-21 07:37:17.105 [INFO][56] felix/iface_monitor.go 118: Subscribed to netlink updates.
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=1 ifaceName="lo" state="up"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}, "::1":set.empty{}} ifaceName="lo"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=2 ifaceName="ens33" state="up"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"192.168.10.201":set.empty{}, "fe80::3755:90ce:4020:4d32":set.empty{}} ifaceName="ens33"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"172.17.0.1":set.empty{}} ifaceName="docker0"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=6 ifaceName="tunl0" state="up"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"10.100.166.128":set.empty{}} ifaceName="tunl0"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=7 ifaceName="cali7f22d95c351" state="up"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}} ifaceName="cali7f22d95c351"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="ifb0"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="ifb1"
2022-05-21 07:37:17.106 [INFO][56] felix/int_dataplane.go 1036: Linux interface state changed. ifIndex=26 ifaceName="cali6d984c0b07f" state="up"
2022-05-21 07:37:17.107 [INFO][56] felix/int_dataplane.go 1071: Linux interface addrs changed. addrs=set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}} ifaceName="cali6d984c0b07f"
2022-05-21 07:37:17.108 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/clusterinformations"
2022-05-21 07:37:17.107 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"lo", State:"up", Index:1}
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"ens33", State:"up", Index:2}
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"tunl0", State:"up", Index:6}
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali7f22d95c351", State:"up", Index:7}
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1521: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali6d984c0b07f", State:"up", Index:26}
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}, "::1":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.0":set.empty{}, "127.0.0.1":set.empty{}, "::1":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"ens33", Addrs:set.mapSet{"192.168.10.201":set.empty{}, "fe80::3755:90ce:4020:4d32":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens33", Addrs:set.mapSet{"192.168.10.201":set.empty{}, "fe80::3755:90ce:4020:4d32":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"docker0", Addrs:set.mapSet{"172.17.0.1":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"docker0", Addrs:set.mapSet{"172.17.0.1":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"tunl0", Addrs:set.mapSet{"10.100.166.128":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"tunl0", Addrs:set.mapSet{"10.100.166.128":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali7f22d95c351", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali7f22d95c351", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2022-05-21 07:37:17.121 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"ifb0", Addrs:set.mapSet{}}
2022-05-21 07:37:17.122 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ifb0", Addrs:set.mapSet{}}
2022-05-21 07:37:17.122 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:17.122 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"ifb1", Addrs:set.mapSet{}}
2022-05-21 07:37:17.122 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ifb1", Addrs:set.mapSet{}}
2022-05-21 07:37:17.122 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2022-05-21 07:37:17.122 [INFO][56] felix/int_dataplane.go 1539: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali6d984c0b07f", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2022-05-21 07:37:17.122 [INFO][56] felix/hostip_mgr.go 85: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali6d984c0b07f", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2022-05-21 07:37:17.122 [INFO][56] felix/int_dataplane.go 1913: Started internal status report thread
2022-05-21 07:37:17.122 [INFO][56] felix/int_dataplane.go 1915: Process status reports disabled
2022-05-21 07:37:17.123 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/felixconfigurations"
2022-05-21 07:37:17.124 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/globalnetworkpolicies"
2022-05-21 07:37:17.124 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/globalnetworksets"
2022-05-21 07:37:17.124 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:17.124 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:17.124 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/profiles"
2022-05-21 07:37:17.125 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/workloadendpoints"
2022-05-21 07:37:17.126 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/networkpolicies"
2022-05-21 07:37:17.129 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/networksets"
2022-05-21 07:37:17.131 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/hostendpoints"
2022-05-21 07:37:17.131 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:17.131 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/kubernetesnetworkpolicies"
2022-05-21 07:37:17.133 [INFO][56] felix/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/kubernetesendpointslices"
2022-05-21 07:37:17.138 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/kubernetesnetworkpolicies"
2022-05-21 07:37:17.141 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.141 [INFO][56] felix/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:17.143 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/ipam/v2/assignment/"
2022-05-21 07:37:17.144 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.175 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=LogSeverityScreen) Info 2570258 <nil> 0s} 1}
2022-05-21 07:37:17.175 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=IpInIpEnabled) true 2570258 <nil> 0s} 1}
2022-05-21 07:37:17.175 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ReportingIntervalSecs) 0 2570258 <nil> 0s} 1}
2022-05-21 07:37:17.176 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/clusterinformations"
2022-05-21 07:37:17.177 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.177 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterGUID) 8f9356338eec420688d4692f5ba4d190 2570257 <nil> 0s} 1}
2022-05-21 07:37:17.177 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterType) k8s,bgp,kubeadm,kdd 2570257 <nil> 0s} 1}
2022-05-21 07:37:17.177 [INFO][56] felix/config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=CalicoVersion) v3.21.2 2570257 <nil> 0s} 1}
2022-05-21 07:37:17.164 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/felixconfigurations"
2022-05-21 07:37:17.177 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.178 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/globalnetworkpolicies"
2022-05-21 07:37:17.178 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.179 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/globalnetworksets"
2022-05-21 07:37:17.179 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.180 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:17.212 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.230 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:17.230 [INFO][56] felix/int_dataplane.go 1506: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"10.100.0.0-16" pool:<cidr:"10.100.0.0/16" masquerade:true > 
2022-05-21 07:37:17.253 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/profiles"
2022-05-21 07:37:17.255 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/networksets"
2022-05-21 07:37:17.256 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.257 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.257 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.259 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:17.261 [INFO][56] felix/config_batcher.go 61: Host config update for this host: {{HostConfig(node=node1,name=IpInIpTunnelAddr) 10.100.166.128 2570310 <nil> 0s} 1}
2022-05-21 07:37:17.262 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.263 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/hostendpoints"
2022-05-21 07:37:17.264 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.279 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/networkpolicies"
2022-05-21 07:37:17.279 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.281 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"calico-kube-controllers" > labels:<key:"projectcalico.org/name" value:"calico-kube-controllers" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"endpointslicemirroring-controller" > labels:<key:"projectcalico.org/name" value:"endpointslicemirroring-controller" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"persistent-volume-binder" > labels:<key:"projectcalico.org/name" value:"persistent-volume-binder" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"token-cleaner" > labels:<key:"projectcalico.org/name" value:"token-cleaner" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"attachdetach-controller" > labels:<key:"projectcalico.org/name" value:"attachdetach-controller" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"coredns" > labels:<key:"projectcalico.org/name" value:"coredns" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"ttl-controller" > labels:<key:"projectcalico.org/name" value:"ttl-controller" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"clusterrole-aggregation-controller" > labels:<key:"projectcalico.org/name" value:"clusterrole-aggregation-controller" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"cronjob-controller" > labels:<key:"projectcalico.org/name" value:"cronjob-controller" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"endpointslice-controller" > labels:<key:"projectcalico.org/name" value:"endpointslice-controller" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"resourcequota-controller" > labels:<key:"projectcalico.org/name" value:"resourcequota-controller" > 
2022-05-21 07:37:17.282 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"namespace-controller" > labels:<key:"projectcalico.org/name" value:"namespace-controller" > 
2022-05-21 07:37:17.283 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"default" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:17.283 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-node-lease" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:17.283 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-public" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:17.283 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"daemon-set-controller" > labels:<key:"projectcalico.org/name" value:"daemon-set-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"metrics-server" > labels:<key:"addonmanager.kubernetes.io/mode" value:"Reconcile" > labels:<key:"kubernetes.io/cluster-service" value:"true" > labels:<key:"projectcalico.org/name" value:"metrics-server" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pvc-protection-controller" > labels:<key:"projectcalico.org/name" value:"pvc-protection-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"replicaset-controller" > labels:<key:"projectcalico.org/name" value:"replicaset-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"certificate-controller" > labels:<key:"projectcalico.org/name" value:"certificate-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"endpoint-controller" > labels:<key:"projectcalico.org/name" value:"endpoint-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"generic-garbage-collector" > labels:<key:"projectcalico.org/name" value:"generic-garbage-collector" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"job-controller" > labels:<key:"projectcalico.org/name" value:"job-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"node-controller" > labels:<key:"projectcalico.org/name" value:"node-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"replication-controller" > labels:<key:"projectcalico.org/name" value:"replication-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"service-controller" > labels:<key:"projectcalico.org/name" value:"service-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"calico-node" > labels:<key:"projectcalico.org/name" value:"calico-node" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"disruption-controller" > labels:<key:"projectcalico.org/name" value:"disruption-controller" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"horizontal-pod-autoscaler" > labels:<key:"projectcalico.org/name" value:"horizontal-pod-autoscaler" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"kube-proxy" > labels:<key:"projectcalico.org/name" value:"kube-proxy" > 
2022-05-21 07:37:17.284 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"zmh" name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"bootstrap-signer" > labels:<key:"projectcalico.org/name" value:"bootstrap-signer" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"deployment-controller" > labels:<key:"projectcalico.org/name" value:"deployment-controller" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pod-garbage-collector" > labels:<key:"projectcalico.org/name" value:"pod-garbage-collector" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"service-account-controller" > labels:<key:"projectcalico.org/name" value:"service-account-controller" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"expand-controller" > labels:<key:"projectcalico.org/name" value:"expand-controller" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"pv-protection-controller" > labels:<key:"projectcalico.org/name" value:"pv-protection-controller" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ServiceAccountUpdate update from calculation graph msg=id:<namespace:"kube-system" name:"statefulset-controller" > labels:<key:"projectcalico.org/name" value:"statefulset-controller" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-public" > labels:<key:"projectcalico.org/name" value:"kube-public" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-system" > labels:<key:"projectcalico.org/name" value:"kube-system" > 
2022-05-21 07:37:17.285 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"zmh" > labels:<key:"projectcalico.org/name" value:"zmh" > 
2022-05-21 07:37:17.286 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"default" > labels:<key:"projectcalico.org/name" value:"default" > 
2022-05-21 07:37:17.286 [INFO][56] felix/int_dataplane.go 1506: Received *proto.NamespaceUpdate update from calculation graph msg=id:<name:"kube-node-lease" > labels:<key:"projectcalico.org/name" value:"kube-node-lease" > 
2022-05-21 07:37:17.286 [INFO][56] felix/int_dataplane.go 1506: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"node1" ipv4_addr:"192.168.10.201" 
2022-05-21 07:37:17.286 [INFO][56] felix/int_dataplane.go 1506: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"k8sai" ipv4_addr:"192.168.10.11" 
2022-05-21 07:37:17.286 [INFO][56] felix/int_dataplane.go 1506: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"k8smec" ipv4_addr:"192.168.10.139" 
2022-05-21 07:37:17.298 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/kubernetesendpointslices"
2022-05-21 07:37:17.298 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.302 [INFO][56] felix/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/workloadendpoints"
2022-05-21 07:37:17.304 [INFO][56] felix/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:17.304 [INFO][56] felix/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:17.304 [INFO][56] felix/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:17.304 [INFO][56] felix/calc_graph.go 445: Local endpoint updated id=WorkloadEndpoint(node=node1, orchestrator=k8s, workload=kube-system/coredns-f9fd979d6-6kcxk, name=eth0)
2022-05-21 07:37:17.305 [INFO][56] felix/calc_graph.go 445: Local endpoint updated id=WorkloadEndpoint(node=node1, orchestrator=k8s, workload=kube-system/metrics-server-v0.5.1-544f94d7bf-4m6w4, name=eth0)
2022-05-21 07:37:17.305 [INFO][56] felix/config_batcher.go 102: Datamodel in sync, flushing config update
2022-05-21 07:37:17.305 [INFO][56] felix/config_batcher.go 112: Sending config update global: map[CalicoVersion:v3.21.2 ClusterGUID:8f9356338eec420688d4692f5ba4d190 ClusterType:k8s,bgp,kubeadm,kdd IpInIpEnabled:true LogSeverityScreen:Info ReportingIntervalSecs:0], host: map[IpInIpTunnelAddr:10.100.166.128].
2022-05-21 07:37:17.305 [INFO][56] felix/async_calc_graph.go 166: First time we've been in sync
2022-05-21 07:37:17.305 [INFO][56] felix/health.go 133: Health of component changed lastReport=health.HealthReport{Live:true, Ready:false} name="async_calc_graph" newReport=&health.HealthReport{Live:true, Ready:true}
2022-05-21 07:37:17.305 [INFO][56] felix/event_sequencer.go 234: Possible config update. global=map[string]string{"CalicoVersion":"v3.21.2", "ClusterGUID":"8f9356338eec420688d4692f5ba4d190", "ClusterType":"k8s,bgp,kubeadm,kdd", "IpInIpEnabled":"true", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0"} host=map[string]string{"IpInIpTunnelAddr":"10.100.166.128"}
2022-05-21 07:37:17.305 [INFO][56] felix/config_params.go 396: Merging in config from datastore (global): map[CalicoVersion:v3.21.2 ClusterGUID:8f9356338eec420688d4692f5ba4d190 ClusterType:k8s,bgp,kubeadm,kdd IpInIpEnabled:true LogSeverityScreen:Info ReportingIntervalSecs:0]
2022-05-21 07:37:17.305 [INFO][56] felix/config_params.go 503: Parsing value for WireguardMTU: 0 (from environment variable)
2022-05-21 07:37:17.305 [INFO][56] felix/config_params.go 539: Parsed value for WireguardMTU: 0 (from environment variable)
2022-05-21 07:37:17.305 [INFO][56] felix/config_params.go 503: Parsing value for FelixHostname: node1 (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for FelixHostname: node1 (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpMtu: 0 (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpMtu: 0 (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for VXLANMTU: 0 (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for VXLANMTU: 0 (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for HealthEnabled: true (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for HealthEnabled: true (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for Ipv6Support: false (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for Ipv6Support: false (from environment variable)
2022-05-21 07:37:17.305 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"kns.kube-system" > profile:<inbound_rules:<action:"allow" rule_id:"YlN-KJA93B7Fe2ih" > outbound_rules:<action:"allow" rule_id:"GUM3x2IgYhIM5cBY" > > 
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for DatastoreType: kubernetes (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for DatastoreType: kubernetes (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for MetadataAddr: None (from config file)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:17.306 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pri-kns.kube-system" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.306 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pro-kns.kube-system" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.306 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pro-kns.kube-system" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.306 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"ksa.kube-system.coredns" > profile:<> 
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for MetadataAddr:  (from config file)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for LogFilePath: None (from config file)
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:17.306 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pri-_u2Tn2rSoAPffvE7JO6" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for LogFilePath:  (from config file)
2022-05-21 07:37:17.306 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pro-_u2Tn2rSoAPffvE7JO6" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 503: Parsing value for LogSeverityFile: None (from config file)
2022-05-21 07:37:17.306 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pro-_u2Tn2rSoAPffvE7JO6" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:17.306 [INFO][56] felix/config_params.go 539: Parsed value for LogSeverityFile:  (from config file)
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for LogSeveritySys: None (from config file)
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for LogSeveritySys:  (from config file)
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpTunnelAddr: 10.100.166.128 (from datastore (per-host))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpTunnelAddr: 10.100.166.128 (from datastore (per-host))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for ClusterGUID: 8f9356338eec420688d4692f5ba4d190 (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for ClusterGUID: 8f9356338eec420688d4692f5ba4d190 (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for ClusterType: k8s,bgp,kubeadm,kdd (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for ClusterType: k8s,bgp,kubeadm,kdd (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for CalicoVersion: v3.21.2 (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for CalicoVersion: v3.21.2 (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for LogSeverityScreen: Info (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/int_dataplane.go 1506: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"ksa.kube-system.metrics-server" > profile:<> 
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for LogSeverityScreen: INFO (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpEnabled: true (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pri-_CVSZITRyIpEmH8AB6H" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpEnabled: true (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pro-_CVSZITRyIpEmH8AB6H" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-pro-_CVSZITRyIpEmH8AB6H" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 396: Merging in config from datastore (per-host): map[IpInIpTunnelAddr:10.100.166.128]
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for VXLANMTU: 0 (from environment variable)
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for VXLANMTU: 0 (from environment variable)
2022-05-21 07:37:17.307 [INFO][56] felix/usagerep.go 91: Waiting before first check-in delay=5m2.114s
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for HealthEnabled: true (from environment variable)
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 539: Parsed value for HealthEnabled: true (from environment variable)
2022-05-21 07:37:17.307 [INFO][56] felix/config_params.go 503: Parsing value for Ipv6Support: false (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 539: Parsed value for Ipv6Support: false (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 503: Parsing value for DatastoreType: kubernetes (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 539: Parsed value for DatastoreType: kubernetes (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 503: Parsing value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 539: Parsed value for DefaultEndpointToHostAction: ACCEPT (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 503: Parsing value for WireguardMTU: 0 (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 539: Parsed value for WireguardMTU: 0 (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 503: Parsing value for FelixHostname: node1 (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 539: Parsed value for FelixHostname: node1 (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpMtu: 0 (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpMtu: 0 (from environment variable)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 503: Parsing value for MetadataAddr: None (from config file)
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:17.308 [INFO][56] felix/config_params.go 539: Parsed value for MetadataAddr:  (from config file)
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for LogFilePath: None (from config file)
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for LogFilePath:  (from config file)
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for LogSeverityFile: None (from config file)
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for LogSeverityFile:  (from config file)
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for LogSeveritySys: None (from config file)
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 520: Value set to 'none', replacing with zero-value: "".
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for LogSeveritySys:  (from config file)
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpTunnelAddr: 10.100.166.128 (from datastore (per-host))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpTunnelAddr: 10.100.166.128 (from datastore (per-host))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for LogSeverityScreen: Info (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for LogSeverityScreen: INFO (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for IpInIpEnabled: true (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for IpInIpEnabled: true (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for ClusterGUID: 8f9356338eec420688d4692f5ba4d190 (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for ClusterGUID: 8f9356338eec420688d4692f5ba4d190 (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for ClusterType: k8s,bgp,kubeadm,kdd (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for ClusterType: k8s,bgp,kubeadm,kdd (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 503: Parsing value for CalicoVersion: v3.21.2 (from datastore (global))
2022-05-21 07:37:17.309 [INFO][56] felix/config_params.go 539: Parsed value for CalicoVersion: v3.21.2 (from datastore (global))
2022-05-21 07:37:17.311 [INFO][56] felix/async_calc_graph.go 220: First flush after becoming in sync, sending InSync message.
2022-05-21 07:37:17.311 [INFO][56] felix/daemon.go 1107: Datastore now in sync.
2022-05-21 07:37:17.312 [INFO][56] felix/daemon.go 1109: Datastore in sync for first time, sending message to status reporter.
2022-05-21 07:37:17.312 [INFO][56] felix/int_dataplane.go 1506: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kube-system/coredns-f9fd979d6-6kcxk" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali6d984c0b07f" profile_ids:"kns.kube-system" profile_ids:"ksa.kube-system.coredns" ipv4_nets:"10.100.166.138/32" > 
2022-05-21 07:37:17.312 [INFO][56] felix/int_dataplane.go 1506: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kube-system/metrics-server-v0.5.1-544f94d7bf-4m6w4" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali7f22d95c351" profile_ids:"kns.kube-system" profile_ids:"ksa.kube-system.metrics-server" ipv4_nets:"10.100.166.129/32" > 
2022-05-21 07:37:17.312 [INFO][56] felix/int_dataplane.go 1506: Received *proto.InSync update from calculation graph msg=
2022-05-21 07:37:17.312 [INFO][56] felix/int_dataplane.go 1514: Datastore in sync, flushing the dataplane for the first time... timeSinceStart=2.123663684s
2022-05-21 07:37:17.312 [INFO][56] felix/endpoint_mgr.go 366: Workload interface came up, marking for reconfiguration. ifaceName="cali7f22d95c351"
2022-05-21 07:37:17.312 [INFO][56] felix/endpoint_mgr.go 366: Workload interface came up, marking for reconfiguration. ifaceName="cali6d984c0b07f"
2022-05-21 07:37:17.312 [INFO][56] felix/endpoint_mgr.go 584: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-f9fd979d6-6kcxk", EndpointId:"eth0"}
2022-05-21 07:37:17.312 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-tw-cali6d984c0b07f" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.312 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-pri-kns.kube-system"
2022-05-21 07:37:17.312 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-pri-_u2Tn2rSoAPffvE7JO6"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-fw-cali6d984c0b07f" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-pro-kns.kube-system"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-pro-_u2Tn2rSoAPffvE7JO6"
2022-05-21 07:37:17.313 [INFO][56] felix/endpoint_mgr.go 615: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-f9fd979d6-6kcxk", EndpointId:"eth0"}
2022-05-21 07:37:17.313 [INFO][56] felix/endpoint_mgr.go 584: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-v0.5.1-544f94d7bf-4m6w4", EndpointId:"eth0"}
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-tw-cali7f22d95c351" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-pri-_CVSZITRyIpEmH8AB6H"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-fw-cali7f22d95c351" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-pro-_CVSZITRyIpEmH8AB6H"
2022-05-21 07:37:17.313 [INFO][56] felix/endpoint_mgr.go 615: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-v0.5.1-544f94d7bf-4m6w4", EndpointId:"eth0"}
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-fw-cali6d984c0b07f"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-fw-cali7f22d95c351"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-tw-cali6d984c0b07f"
2022-05-21 07:37:17.313 [INFO][56] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-tw-cali7f22d95c351"
2022-05-21 07:37:17.313 [INFO][56] felix/endpoint_mgr.go 1179: Applying /proc/sys configuration to interface. ifaceName="cali7f22d95c351"
2022-05-21 07:37:17.328 [INFO][56] felix/endpoint_mgr.go 1179: Applying /proc/sys configuration to interface. ifaceName="cali6d984c0b07f"
2022-05-21 07:37:17.328 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="raw"
2022-05-21 07:37:17.328 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="raw"
2022-05-21 07:37:17.328 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.328 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.328 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-hep-forward" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.328 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-hep-forward" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.328 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.328 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="mangle"
2022-05-21 07:37:17.329 [INFO][56] felix/endpoint_mgr.go 477: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-f9fd979d6-6kcxk", EndpointId:"eth0"}
2022-05-21 07:37:17.329 [INFO][56] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-f9fd979d6-6kcxk", EndpointId:"eth0"}
2022-05-21 07:37:17.330 [INFO][56] felix/endpoint_mgr.go 477: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-v0.5.1-544f94d7bf-4m6w4", EndpointId:"eth0"}
2022-05-21 07:37:17.330 [INFO][56] felix/status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-v0.5.1-544f94d7bf-4m6w4", EndpointId:"eth0"}
2022-05-21 07:37:17.330 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-fip-dnat" ipVersion=0x4 table="nat"
2022-05-21 07:37:17.330 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-fip-snat" ipVersion=0x4 table="nat"
2022-05-21 07:37:17.330 [INFO][56] felix/masq_mgr.go 145: IPAM pools updated, refreshing iptables rule ipVersion=0x4
2022-05-21 07:37:17.330 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-nat-outgoing" ipVersion=0x4 table="nat"
2022-05-21 07:37:17.330 [INFO][56] felix/ipip_mgr.go 221: All-hosts IP set out-of sync, refreshing it.
2022-05-21 07:37:17.330 [INFO][56] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="all-hosts-net" setType="hash:net"
2022-05-21 07:37:17.330 [INFO][56] felix/table.go 506: Queueing update of chain. chainName="cali-cidr-block" ipVersion=0x4 table="filter"
2022-05-21 07:37:17.330 [INFO][56] felix/wireguard.go 1617: Trying to connect to linkClient
2022-05-21 07:37:17.330 [INFO][56] felix/route_table.go 432: Trying to connect to netlink
2022-05-21 07:37:17.331 [INFO][56] felix/route_table.go 432: Trying to connect to netlink
2022-05-21 07:37:17.336 [INFO][56] felix/route_rule.go 176: Trying to connect to netlink
2022-05-21 07:37:17.337 [INFO][56] felix/wireguard.go 570: Public key out of sync or updated ourPublicKey=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=
2022-05-21 07:37:17.344 [INFO][56] felix/ipsets.go 785: Doing full IP set rewrite family="inet" numMembersInPendingReplace=5 setID="this-host"
2022-05-21 07:37:17.344 [INFO][56] felix/ipsets.go 785: Doing full IP set rewrite family="inet" numMembersInPendingReplace=3 setID="all-hosts-net"
2022-05-21 07:37:17.344 [INFO][56] felix/ipsets.go 785: Doing full IP set rewrite family="inet" numMembersInPendingReplace=1 setID="all-ipam-pools"
2022-05-21 07:37:17.344 [INFO][56] felix/ipsets.go 785: Doing full IP set rewrite family="inet" numMembersInPendingReplace=1 setID="masq-ipam-pools"
2022-05-21 07:37:17.430 [INFO][56] felix/status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-v0.5.1-544f94d7bf-4m6w4", EndpointId:"eth0"} ipVersion=0x4 status="up"
2022-05-21 07:37:17.430 [INFO][56] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/metrics-server-v0.5.1-544f94d7bf-4m6w4", EndpointId:"eth0"} status="up"
2022-05-21 07:37:17.430 [INFO][56] felix/status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-f9fd979d6-6kcxk", EndpointId:"eth0"} ipVersion=0x4 status="up"
2022-05-21 07:37:17.430 [INFO][56] felix/status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-f9fd979d6-6kcxk", EndpointId:"eth0"} status="up"
2022-05-21 07:37:17.430 [INFO][56] felix/int_dataplane.go 1654: Completed first update to dataplane. secsSinceStart=2.241712293
2022-05-21 07:37:17.434 [INFO][56] felix/health.go 133: Health of component changed lastReport=health.HealthReport{Live:true, Ready:false} name="int_dataplane" newReport=&health.HealthReport{Live:true, Ready:true}
bird: device1: Initializing
bird: direct1: Initializing
bird: Mesh_192_168_10_11: Initializing
bird: Mesh_192_168_10_139: 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_192_168_10_11: Starting
bird: Mesh_192_168_10_11: State changed to start
bird: Mesh_192_168_10_139: Starting
bird: Mesh_192_168_10_139: State changed to start
bird: Graceful restart started
bird: Started
bird: device1: State changed to up
bird: direct1: State changed to up
bird: Mesh_192_168_10_139: Connected to table master
bird: Mesh_192_168_10_139: State changed to feed
bird: Mesh_192_168_10_139: State changed to up
bird: Mesh_192_168_10_11: Connected to table master
bird: Mesh_192_168_10_11: State changed to feed
bird: Mesh_192_168_10_11: State changed to up
bird: Graceful restart done
2022-05-21 07:38:16.632 [INFO][51] monitor-addresses/startup.go 727: Using autodetected IPv4 address 192.168.10.201/24 on matching interface ens33
2022-05-21 07:38:20.718 [INFO][56] felix/summary.go 100: Summarising 15 dataplane reconciliation loops over 1m4.2s: avg=14ms longest=118ms (resync-filter-v4,resync-ipsets-v4,resync-mangle-v4,resync-nat-v4,resync-raw-v4,resync-routes-v4,resync-routes-v4,resync-rules-v4,update-ipsets-4)
2022-05-21 07:39:16.633 [INFO][51] monitor-addresses/startup.go 727: Using autodetected IPv4 address 192.168.10.201/24 on matching interface ens33
2022-05-21 07:39:23.346 [INFO][56] felix/summary.go 100: Summarising 9 dataplane reconciliation loops over 1m2.6s: avg=2ms longest=7ms (resync-filter-v4,resync-raw-v4)
2022-05-21 07:40:16.635 [INFO][51] monitor-addresses/startup.go 727: Using autodetected IPv4 address 192.168.10.201/24 on matching interface ens33
2022-05-21 07:40:24.633 [INFO][56] felix/summary.go 100: Summarising 8 dataplane reconciliation loops over 1m1.3s: avg=3ms longest=7ms (resync-filter-v4,resync-raw-v4)
2022-05-21 07:41:16.637 [INFO][51] monitor-addresses/startup.go 727: Using autodetected IPv4 address 192.168.10.201/24 on matching interface ens33

node2的日志

2022-05-21 07:37:12.896 [INFO][9] startup/startup.go 396: Early log level set to info
2022-05-21 07:37:12.896 [INFO][9] startup/utils.go 126: Using NODENAME environment for node name k8sai
2022-05-21 07:37:12.896 [INFO][9] startup/utils.go 138: Determined node name: k8sai
2022-05-21 07:37:12.896 [INFO][9] startup/startup.go 98: Starting node k8sai with version v3.21.2
2022-05-21 07:37:12.899 [INFO][9] startup/startup.go 401: Checking datastore connection
2022-05-21 07:37:12.906 [INFO][9] startup/startup.go 425: Datastore connection verified
2022-05-21 07:37:12.907 [INFO][9] startup/startup.go 108: Datastore is ready
2022-05-21 07:37:12.947 [INFO][9] startup/startup.go 727: Using autodetected IPv4 address 192.168.10.11/24 on matching interface ens33
2022-05-21 07:37:12.947 [INFO][9] startup/startup.go 790: No AS number configured on node resource, using global value
2022-05-21 07:37:12.964 [INFO][9] startup/startup.go 839: found v6= in the kubeadm config map
2022-05-21 07:37:12.978 [INFO][9] startup/startup.go 645: FELIX_IPV6SUPPORT is false through environment variable
2022-05-21 07:37:13.008 [INFO][9] startup/startup.go 208: Using node name: k8sai
2022-05-21 07:37:13.008 [INFO][9] startup/utils.go 190: Setting NetworkUnavailable to false
2022-05-21 07:37:13.231 [ERROR][18] tunnel-ip-allocator/ipam.go 1740: Error reading block 10.100.193.192/26: resource does not exist: IPAMBlock(10-100-193-192-26) with error: ipamblocks.crd.projectcalico.org "10-100-193-192-26" not found
2022-05-21 07:37:13.232 [INFO][18] tunnel-ip-allocator/allocateip.go 342: Current address is not assigned, assign a new one currentAddr="10.100.193.192" type="ipipTunnelAddress"
2022-05-21 07:37:13.232 [INFO][18] tunnel-ip-allocator/allocateip.go 355: Release any old tunnel addresses IP="10.100.193.192" type="ipipTunnelAddress"
2022-05-21 07:37:13.234 [INFO][18] tunnel-ip-allocator/allocateip.go 366: Assign new tunnel address IP="10.100.193.192" type="ipipTunnelAddress"
2022-05-21 07:37:13.234 [INFO][18] tunnel-ip-allocator/ipam.go 103: Auto-assign 1 ipv4, 0 ipv6 addrs for host 'k8sai'
2022-05-21 07:37:13.237 [INFO][18] tunnel-ip-allocator/ipam.go 656: Looking up existing affinities for host handle="ipip-tunnel-addr-k8sai" host="k8sai"
2022-05-21 07:37:13.250 [INFO][18] tunnel-ip-allocator/ipam.go 368: Looking up existing affinities for host host="k8sai"
2022-05-21 07:37:13.254 [INFO][18] tunnel-ip-allocator/ipam.go 517: Ran out of existing affine blocks for host host="k8sai"
2022-05-21 07:37:13.256 [INFO][18] tunnel-ip-allocator/ipam.go 534: Tried all affine blocks. Looking for an affine block with space, or a new unclaimed block host="k8sai"
2022-05-21 07:37:13.259 [INFO][18] tunnel-ip-allocator/ipam_block_reader_writer.go 153: Found free block: 10.100.193.192/26
2022-05-21 07:37:13.259 [INFO][18] tunnel-ip-allocator/ipam.go 546: Found unclaimed block host="k8sai" subnet=10.100.193.192/26
2022-05-21 07:37:13.259 [INFO][18] tunnel-ip-allocator/ipam_block_reader_writer.go 170: Trying to create affinity in pending state host="k8sai" subnet=10.100.193.192/26
2022-05-21 07:37:13.263 [INFO][18] tunnel-ip-allocator/ipam_block_reader_writer.go 200: Successfully created pending affinity for block host="k8sai" subnet=10.100.193.192/26
2022-05-21 07:37:13.263 [INFO][18] tunnel-ip-allocator/ipam.go 151: Attempting to load block cidr=10.100.193.192/26 host="k8sai"
2022-05-21 07:37:13.270 [INFO][18] tunnel-ip-allocator/ipam.go 156: The referenced block doesn't exist, trying to create it cidr=10.100.193.192/26 host="k8sai"
2022-05-21 07:37:13.279 [INFO][18] tunnel-ip-allocator/ipam.go 163: Wrote affinity as pending cidr=10.100.193.192/26 host="k8sai"
2022-05-21 07:37:13.280 [INFO][18] tunnel-ip-allocator/ipam.go 172: Attempting to claim the block cidr=10.100.193.192/26 host="k8sai"
2022-05-21 07:37:13.280 [INFO][18] tunnel-ip-allocator/ipam_block_reader_writer.go 222: Attempting to create a new block host="k8sai" subnet=10.100.193.192/26
2022-05-21 07:37:13.285 [INFO][18] tunnel-ip-allocator/ipam_block_reader_writer.go 263: Successfully created block
2022-05-21 07:37:13.286 [INFO][18] tunnel-ip-allocator/ipam_block_reader_writer.go 274: Confirming affinity host="k8sai" subnet=10.100.193.192/26
2022-05-21 07:37:13.289 [INFO][18] tunnel-ip-allocator/ipam_block_reader_writer.go 289: Successfully confirmed affinity host="k8sai" subnet=10.100.193.192/26
2022-05-21 07:37:13.289 [INFO][18] tunnel-ip-allocator/ipam.go 581: Block '10.100.193.192/26' has 64 free ips which is more than 1 ips required. host="k8sai" subnet=10.100.193.192/26
2022-05-21 07:37:13.289 [INFO][18] tunnel-ip-allocator/ipam.go 1153: Attempting to assign 1 addresses from block block=10.100.193.192/26 handle="ipip-tunnel-addr-k8sai" host="k8sai"
2022-05-21 07:37:13.296 [INFO][18] tunnel-ip-allocator/ipam.go 1630: Creating new handle: ipip-tunnel-addr-k8sai
2022-05-21 07:37:13.304 [INFO][18] tunnel-ip-allocator/ipam.go 1176: Writing block in order to claim IPs block=10.100.193.192/26 handle="ipip-tunnel-addr-k8sai" host="k8sai"
2022-05-21 07:37:13.309 [INFO][18] tunnel-ip-allocator/ipam.go 1189: Successfully claimed IPs: [10.100.193.192/26] block=10.100.193.192/26 handle="ipip-tunnel-addr-k8sai" host="k8sai"
2022-05-21 07:37:13.309 [INFO][18] tunnel-ip-allocator/ipam.go 843: Auto-assigned 1 out of 1 IPv4s: [10.100.193.192/26] handle="ipip-tunnel-addr-k8sai" host="k8sai"
2022-05-21 07:37:13.332 [INFO][18] tunnel-ip-allocator/allocateip.go 455: Assigned tunnel address to node IP="10.100.193.192" 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
2022-05-21 07:37:14.690 [INFO][53] monitor-addresses/startup.go 396: Early log level set to info
2022-05-21 07:37:14.692 [INFO][53] monitor-addresses/utils.go 126: Using NODENAME environment for node name k8sai
2022-05-21 07:37:14.692 [INFO][53] monitor-addresses/utils.go 138: Determined node name: k8sai
2022-05-21 07:37:14.712 [INFO][65] status-reporter/startup.go 396: Early log level set to info
2022-05-21 07:37:14.714 [INFO][65] status-reporter/watchersyncer.go 89: Start called
2022-05-21 07:37:14.714 [INFO][65] status-reporter/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:14.714 [INFO][65] status-reporter/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:14.714 [INFO][65] status-reporter/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/caliconodestatuses"
2022-05-21 07:37:14.736 [INFO][57] confd/config.go 81: Skipping confd config file.
2022-05-21 07:37:14.736 [INFO][57] confd/run.go 17: Starting calico-confd
2022-05-21 07:37:14.759 [INFO][59] cni-config-monitor/token_watch.go 40: Watching contents for changes. directory="/var/run/secrets/kubernetes.io/serviceaccount/"
W0521 07:37:14.762948      57 client_config.go:615] Neither --kubeconfig nor --master was specified.  Using the inClusterConfig.  This might not work.
2022-05-21 07:37:14.766 [INFO][65] status-reporter/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/caliconodestatuses"
2022-05-21 07:37:14.766 [INFO][65] status-reporter/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:14.767 [INFO][65] status-reporter/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:14.767 [INFO][65] status-reporter/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:14.767 [INFO][65] status-reporter/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:14.767 [INFO][57] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:14.769 [INFO][57] confd/client.go 1215: Updated with new cluster IP CIDRs: []
2022-05-21 07:37:14.770 [INFO][57] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:14.770 [INFO][57] confd/client.go 1206: Updated with new external IP CIDRs: []
2022-05-21 07:37:14.770 [INFO][57] confd/client.go 1270: Advertise global service ranges from this node
2022-05-21 07:37:14.770 [INFO][57] confd/client.go 1225: Updated with new Loadbalancer IP CIDRs: []
2022-05-21 07:37:14.770 [INFO][57] confd/watchersyncer.go 89: Start called
2022-05-21 07:37:14.770 [INFO][57] confd/client.go 378: Source SourceRouteGenerator readiness changed, ready=true
2022-05-21 07:37:14.770 [INFO][57] confd/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:14.770 [INFO][57] confd/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:14.770 [INFO][57] confd/watchercache.go 175: Full resync is required ListRoot="/calico/ipam/v2/host/k8sai"
2022-05-21 07:37:14.774 [INFO][57] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:14.776 [INFO][57] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:14.776 [INFO][57] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:14.777 [INFO][57] confd/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/bgppeers"
2022-05-21 07:37:14.781 [INFO][57] confd/watchercache.go 273: Sending synced update ListRoot="/calico/ipam/v2/host/k8sai"
2022-05-21 07:37:14.782 [INFO][57] confd/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:14.782 [INFO][57] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:14.784 [INFO][57] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:14.790 [INFO][57] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:14.794 [INFO][64] tunnel-ip-allocator/watchersyncer.go 89: Start called
2022-05-21 07:37:14.795 [INFO][64] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:14.795 [INFO][64] tunnel-ip-allocator/watchersyncer.go 147: Starting main event processing loop
2022-05-21 07:37:14.795 [INFO][64] tunnel-ip-allocator/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/nodes/k8sai"
2022-05-21 07:37:14.795 [INFO][64] tunnel-ip-allocator/watchercache.go 175: Full resync is required ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:14.797 [INFO][57] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgpconfigurations"
2022-05-21 07:37:14.798 [INFO][57] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:14.800 [INFO][57] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/bgppeers"
2022-05-21 07:37:14.806 [INFO][57] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:14.809 [INFO][57] confd/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2022-05-21 07:37:14.811 [INFO][57] confd/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:14.811 [INFO][57] confd/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:14.811 [INFO][57] confd/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:14.820 [INFO][57] confd/client.go 900: Recompute BGP peerings: HostBGPConfig(node=k8sai; name=ip_addr_v4) updated; HostBGPConfig(node=k8sai; name=ip_addr_v6) updated; HostBGPConfig(node=k8sai; name=network_v4) updated; HostBGPConfig(node=k8sai; name=rr_cluster_id) updated; k8sai updated; HostBGPConfig(node=k8smec; name=ip_addr_v4) updated; HostBGPConfig(node=k8smec; name=ip_addr_v6) updated; HostBGPConfig(node=k8smec; name=network_v4) updated; HostBGPConfig(node=k8smec; name=rr_cluster_id) updated; k8smec updated; HostBGPConfig(node=node1; name=ip_addr_v4) updated; HostBGPConfig(node=node1; name=ip_addr_v6) updated; HostBGPConfig(node=node1; name=network_v4) updated; HostBGPConfig(node=node1; name=rr_cluster_id) updated; node1 updated
2022-05-21 07:37:14.822 [INFO][57] confd/client.go 378: Source SourceSyncer readiness changed, ready=true
2022-05-21 07:37:14.822 [INFO][57] confd/client.go 398: Data is now syncd, can start rendering templates
2022-05-21 07:37:14.823 [INFO][64] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=resync
2022-05-21 07:37:14.823 [INFO][64] tunnel-ip-allocator/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes/k8sai"
2022-05-21 07:37:14.823 [INFO][64] tunnel-ip-allocator/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:14.825 [INFO][64] tunnel-ip-allocator/watchercache.go 273: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2022-05-21 07:37:14.825 [INFO][64] tunnel-ip-allocator/watchersyncer.go 209: Received InSync event from one of the watcher caches
2022-05-21 07:37:14.825 [INFO][64] tunnel-ip-allocator/watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2022-05-21 07:37:14.825 [INFO][64] tunnel-ip-allocator/watchersyncer.go 127: Sending status update Status=in-sync
2022-05-21 07:37:14.873 [INFO][57] confd/resource.go 277: Target config /etc/calico/confd/config/bird_ipam.cfg has been updated
2022-05-21 07:37:14.896 [INFO][57] confd/resource.go 277: Target config /etc/calico/confd/config/bird6_ipam.cfg has been updated
2022-05-21 07:37:14.901 [INFO][57] confd/resource.go 277: Target config /etc/calico/confd/config/bird6.cfg has been updated
2022-05-21 07:37:14.901 [INFO][57] confd/resource.go 277: Target config /etc/calico/confd/config/bird_aggr.cfg has been updated
2022-05-21 07:37:14.903 [INFO][57] confd/resource.go 277: Target config /etc/calico/confd/config/bird6_aggr.cfg has been updated
2022-05-21 07:37:14.904 [INFO][57] confd/resource.go 277: Target config /etc/calico/confd/config/bird.cfg has been updated
2022-05-21 07:37:14.907 [INFO][64] tunnel-ip-allocator/allocateip.go 303: Current address is still valid, do nothing currentAddr="10.100.193.192" type="ipipTunnelAddress"
2022-05-21 07:37:14.909 [INFO][55] felix/daemon.go 358: Successfully loaded configuration. GOMAXPROCS=4 builddate="2021-12-06T03:23:22+0000" config=&config.Config{UseInternalDataplaneDriver:true, DataplaneDriver:"calico-iptables-plugin", WireguardEnabled:false, WireguardListeningPort:51820, WireguardRoutingRulePriority:99, WireguardInterfaceName:"wireguard.cali", WireguardMTU:0, WireguardHostEncryptionEnabled:false, BPFEnabled:false, BPFDisableUnprivileged:true, BPFLogLevel:"off", BPFDataIfacePattern:(*regexp.Regexp)(0xc000039a40), BPFConnectTimeLoadBalancingEnabled:true, BPFExternalServiceMode:"tunnel", BPFKubeProxyIptablesCleanupEnabled:true, BPFKubeProxyMinSyncPeriod:1000000000, BPFKubeProxyEndpointSlicesEnabled:false, BPFExtToServiceConnmark:0, BPFPSNATPorts:numorstring.Port{MinPort:0x4e20, MaxPort:0x752f, PortName:""}, DebugBPFCgroupV2:"", DebugBPFMapRepinEnabled:false, DatastoreType:"kubernetes", FelixHostname:"k8sai", EtcdAddr:"127.0.0.1:2379", EtcdScheme:"http", EtcdKeyFile:"", EtcdCertFile:"", EtcdCaFile:"", EtcdEndpoints:[]string(nil), TyphaAddr:"", TyphaK8sServiceName:"", TyphaK8sNamespace:"kube-system", TyphaReadTimeout:30000000000, TyphaWriteTimeout:10000000000, TyphaKeyFile:"", TyphaCertFile:"", TyphaCAFile:"", TyphaCN:"", TyphaURISAN:"", Ipv6Support:false, IptablesBackend:"auto", RouteRefreshInterval:90000000000, InterfaceRefreshInterval:90000000000, DeviceRouteSourceAddress:net.IP(nil), DeviceRouteProtocol:3, RemoveExternalRoutes:true, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckIntervalSecs:1000000000, IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeoutSecs:0, IptablesLockProbeIntervalMillis:50000000, FeatureDetectOverride:map[string]string(nil), IpsetsRefreshInterval:10000000000, MaxIpsetSize:1048576, XDPRefreshInterval:90000000000, PolicySyncPathPrefix:"", NetlinkTimeoutSecs:10000000000, MetadataAddr:"", MetadataPort:8775, OpenstackRegion:"", InterfacePrefix:"cali", InterfaceExclude:[]*regexp.Regexp{(*regexp.Regexp)(0xc000039b80)}, ChainInsertMode:"insert", DefaultEndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", LogPrefix:"calico-packet", LogFilePath:"", LogSeverityFile:"", LogSeverityScreen:"INFO", LogSeveritySys:"", VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, VXLANMTU:0, IPv4VXLANTunnelAddr:net.IP(nil), VXLANTunnelMACAddr:"", IpInIpEnabled:true, IpInIpMtu:0, IpInIpTunnelAddr:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xc1, 0xc0}, AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, AWSSrcDstCheck:"DoNothing", ServiceLoopPrevention:"Drop", ReportingIntervalSecs:0, ReportingTTLSecs:90000000000, EndpointReportingEnabled:false, EndpointReportingDelaySecs:1000000000, IptablesMarkMask:0xffff0000, DisableConntrackInvalidCheck:false, HealthEnabled:true, HealthPort:9099, HealthHost:"localhost", PrometheusMetricsEnabled:false, PrometheusMetricsHost:"", PrometheusMetricsPort:9091, PrometheusGoMetricsEnabled:true, PrometheusProcessMetricsEnabled:true, PrometheusWireGuardMetricsEnabled:true, FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", 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:"8f9356338eec420688d4692f5ba4d190", ClusterType:"k8s,bgp,kubeadm,kdd", CalicoVersion:"v3.21.2", ExternalNodesCIDRList:[]string(nil), DebugMemoryProfilePath:"", DebugCPUProfilePath:"/tmp/felix-cpu-<timestamp>.pprof", DebugDisableLogDropping:false, DebugSimulateCalcGraphHangAfter:0, DebugSimulateDataplaneHangAfter:0, DebugPanicAfter:0, DebugSimulateDataRace:false, RouteSource:"CalicoIPAM", RouteTableRange:idalloc.IndexRange{Min:1, Max:250}, IptablesNATOutgoingInterfaceFilter:"", SidecarAccelerationEnabled:false, XDPEnabled:true, GenericXDPEnabled:false, Variant:"Calico", MTUIfacePattern:(*regexp.Regexp)(0xc000039f40), internalOverrides:map[string]string{}, sourceToRawConfig:map[config.Source]map[string]string{0x1:map[string]string{"CalicoVersion":"v3.21.2", "ClusterGUID":"8f9356338eec420688d4692f5ba4d190", "ClusterType":"k8s,bgp,kubeadm,kdd", "IpInIpEnabled":"true", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0"}, 0x2:map[string]string{"IpInIpTunnelAddr":"10.100.193.192"}, 0x3:map[string]string{"LogFilePath":"None", "LogSeverityFile":"None", "LogSeveritySys":"None", "MetadataAddr":"None"}, 0x4:map[string]string{"datastoretype":"kubernetes", "defaultendpointtohostaction":"ACCEPT", "felixhostname":"k8sai", "healthenabled":"true", "ipinipmtu":"0", "ipv6support":"false", "vxlanmtu":"0", "wireguardmtu":"0"}}, rawValues:map[string]string{"CalicoVersion":"v3.21.2", "ClusterGUID":"8f9356338eec420688d4692f5ba4d190", "ClusterType":"k8s,bgp,kubeadm,kdd", "DatastoreType":"kubernetes", "DefaultEndpointToHostAction":"ACCEPT", "FelixHostname":"k8sai", "HealthEnabled":"true", "IpInIpEnabled":"true", "IpInIpMtu":"0", "IpInIpTunnelAddr":"10.100.193.192", "Ipv6Support":"false", "LogFilePath":"None", "LogSeverityFile":"None", "LogSeverityScreen":"Info", "LogSeveritySys":"None", "MetadataAddr":"None", "ReportingIntervalSecs":"0", "VXLANMTU":"0", "WireguardMTU":"0"}, Err:error(nil), loadClientConfigFromEnvironment:(func() (*apiconfig.CalicoAPIConfig, error))(0x1597ec0), useNodeResourceUpdates:false} gitcommit="6411b17928e58c88815ab7eb8b335187508d4987" version="v3.21.2"
2022-05-21 07:37:14.909 [INFO][55] felix/driver.go 69: Using internal (linux) dataplane driver.
2022-05-21 07:37:14.911 [INFO][55] felix/driver.go 154: Calculated iptables mark bits acceptMark=0x10000 endpointMark=0xfff00000 endpointMarkNonCali=0x0 passMark=0x20000 scratch0Mark=0x40000 scratch1Mark=0x80000
2022-05-21 07:37:14.911 [INFO][55] felix/int_dataplane.go 307: Creating internal dataplane driver. config=intdataplane.Config{Hostname:"k8sai", IPv6Enabled:false, RuleRendererOverride:rules.RuleRenderer(nil), IPIPMTU:0, VXLANMTU:0, VXLANPort:4789, MaxIPSetSize:1048576, IptablesBackend:"auto", IPSetsRefreshInterval:10000000000, RouteRefreshInterval:90000000000, DeviceRouteSourceAddress:net.IP(nil), DeviceRouteProtocol:3, RemoveExternalRoutes:true, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckInterval:1000000000, IptablesInsertMode:"insert", IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeout:0, IptablesLockProbeInterval:50000000, XDPRefreshInterval:90000000000, Wireguard:wireguard.Config{Enabled:false, ListeningPort:51820, FirewallMark:0, RoutingRulePriority:99, RoutingTableIndex:1, InterfaceName:"wireguard.cali", MTU:0, RouteSource:"CalicoIPAM", EncryptHostTraffic:false}, NetlinkTimeout:10000000000, RulesConfig:rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc00082a410), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc00082a500), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x0, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:true, IPIPTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xc1, 0xc0}, VXLANTunnelAddress:net.IP(nil), AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, WireguardEnabled:false, WireguardInterfaceName:"wireguard.cali", WireguardIptablesMark:0x0, WireguardListeningPort:51820, WireguardEncryptHostTraffic:false, RouteSource:"CalicoIPAM", IptablesLogPrefix:"calico-packet", EndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil), BPFEnabled:false, ServiceLoopPrevention:"Drop"}, IfaceMonitorConfig:ifacemonitor.Config{InterfaceExcludes:[]*regexp.Regexp{(*regexp.Regexp)(0xc000039b80)}, ResyncInterval:90000000000}, StatusReportingInterval:0, ConfigChangedRestartCallback:(func())(0x28b7b80), FatalErrorRestartCallback:(func(error))(0x28b7c40), PostInSyncCallback:(func())(0x289f340), HealthAggregator:(*health.HealthAggregator)(0xc000349950), RouteTableManager:(*idalloc.IndexAllocator)(0xc000286020), DebugSimulateDataplaneHangAfter:0, ExternalNodesCidrs:[]string(nil), BPFEnabled:false, BPFDisableUnprivileged:true, BPFKubeProxyIptablesCleanupEnabled:true, BPFLogLevel:"off", BPFExtToServiceConnmark:0, BPFDataIfacePattern:(*regexp.Regexp)(0xc000039a40), XDPEnabled:true, XDPAllowGeneric:false, BPFConntrackTimeouts:conntrack.Timeouts{CreationGracePeriod:10000000000, TCPPreEstablished:20000000000, TCPEstablished:3600000000000, TCPFinsSeen:30000000000, TCPResetSeen:40000000000, UDPLastSeen:60000000000, GenericIPLastSeen:600000000000, ICMPLastSeen:5000000000}, BPFCgroupV2:"", BPFConnTimeLBEnabled:true, BPFMapRepin:false, BPFNodePortDSREnabled:false, BPFPSNATPorts:numorstring.Port{MinPort:0x4e20, MaxPort:0x752f, PortName:""}, KubeProxyMinSyncPeriod:1000000000, KubeProxyEndpointSlicesEnabled:false, SidecarAccelerationEnabled:false, LookPathOverride:(func(string) (string, error))(nil), KubeClientSet:(*kubernetes.Clientset)(0xc0008e82c0), FeatureDetectOverrides:map[string]string(nil), hostMTU:0, MTUIfacePattern:(*regexp.Regexp)(0xc000039f40), RouteSource:"CalicoIPAM", KubernetesProvider:0x0}
2022-05-21 07:37:14.912 [INFO][55] felix/rule_defs.go 358: Creating rule renderer. config=rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc00082a410), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc00082a500), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x0, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, VXLANEnabled:false, VXLANPort:4789, VXLANVNI:4096, IPIPEnabled:true, IPIPTunnelAddress:net.IP{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xff, 0xff, 0xa, 0x64, 0xc1, 0xc0}, VXLANTunnelAddress:net.IP(nil), AllowVXLANPacketsFromWorkloads:false, AllowIPIPPacketsFromWorkloads:false, WireguardEnabled:false, WireguardInterfaceName:"wireguard.cali", WireguardIptablesMark:0x0, WireguardListeningPort:51820, WireguardEncryptHostTraffic:false, RouteSource:"CalicoIPAM", IptablesLogPrefix:"calico-packet", EndpointToHostAction:"ACCEPT", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"tcp", Port:0x16}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x44}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Net:"", Protocol:"udp", Port:0x35}, config.ProtoPort{Net:"", Protocol:"udp", Port:0x43}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0xb3}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x94c}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1561}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x192b}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Net:"", Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, IptablesNATOutgoingInterfaceFilter:"", NATOutgoingAddress:net.IP(nil), BPFEnabled:false, ServiceLoopPrevention:"Drop"}
2022-05-21 07:37:14.914 [INFO][55] felix/rule_defs.go 368: Workload to host packets will be accepted.
2022-05-21 07:37:14.915 [INFO][55] felix/rule_defs.go 382: filter table allowed packets will be accepted immediately.
2022-05-21 07:37:14.915 [INFO][55] felix/rule_defs.go 390: mangle table allowed packets will be accepted immediately.
2022-05-21 07:37:14.915 [INFO][55] felix/rule_defs.go 398: Packets to unknown service IPs will be dropped
2022-05-21 07:37:14.919 [INFO][55] felix/int_dataplane.go 932: Determined pod MTU mtu=1480
2022-05-21 07:37:14.920 [INFO][55] felix/iface_monitor.go 74: configured to periodically rescan interfaces. interval=1m30s
2022-05-21 07:37:14.920 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"ip6tables-legacy-save", "ip6tables-save"} command="ip6tables-legacy-save" ipVersion=0x6 saveOrRestore="save"
2022-05-21 07:37:14.920 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:15.087 [INFO][55] felix/feature_detect.go 153: Updating detected iptables features features=iptables.Features{SNATFullyRandom:true, MASQFullyRandom:true, RestoreSupportsLock:true, ChecksumOffloadBroken:true} iptablesVersion=1.8.4 kernelVersion=4.15.0-142
2022-05-21 07:37:15.094 [INFO][55] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:15.094 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:15.095 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:15.095 [INFO][55] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-|-A POSTROUTING .* felix-masq-ipam-pools .*|-A POSTROUTING -o tunl0 -m addrtype ! --src-type LOCAL --limit-iface-out -m addrtype --src-type LOCAL -j MASQUERADE"
2022-05-21 07:37:15.095 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:15.095 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:15.096 [INFO][55] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:15.096 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:15.096 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:15.096 [INFO][55] felix/table.go 334: Calculated old-insert detection regex. pattern="(?:-j|--jump) cali-|(?:-j|--jump) califw-|(?:-j|--jump) calitw-|(?:-j|--jump) califh-|(?:-j|--jump) calith-|(?:-j|--jump) calipi-|(?:-j|--jump) calipo-|(?:-j|--jump) felix-"
2022-05-21 07:37:15.097 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-restore", "iptables-restore"} command="iptables-legacy-restore" ipVersion=0x4 saveOrRestore="restore"
2022-05-21 07:37:15.097 [INFO][55] felix/feature_detect.go 275: Looked up iptables command backendMode="legacy" candidates=[]string{"iptables-legacy-save", "iptables-save"} command="iptables-legacy-save" ipVersion=0x4 saveOrRestore="save"
2022-05-21 07:37:15.108 [WARNING][55] felix/int_dataplane.go 459: Can't enable XDP acceleration. error=kernel is too old (have: 4.15.0-142 but want at least: 4.16.0)
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
2022-05-21 07:37:16.736 [INFO][55] felix/connecttime.go 51: Running bpftool to look up programs attached to cgroup args=[]string{"bpftool", "-j", "-p", "cgroup", "show", "/run/calico/cgroup"}
bird: device1: Initializing
bird: direct1: Initializing
bird: Mesh_192_168_10_139: Initializing
bird: Mesh_192_168_10_201: 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_192_168_10_139: Starting
bird: Mesh_192_168_10_139: State changed to start
bird: Mesh_192_168_10_201: Starting
bird: Mesh_192_168_10_201: State changed to start
bird: Graceful restart started
bird: Started
bird: device1: State changed to up
bird: direct1: State changed to up
2022-05-21 07:37:16.803 [INFO][55] felix/route_table.go 266: Calculated interface name regexp ifaceRegex="^cali.*" ipVersion=0x4 tableIndex=0
2022-05-21 07:37:16.808 [INFO][55] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="all-ipam-pools" setType="hash:net"
2022-05-21 07:37:16.808 [INFO][55] felix/ipsets.go 130: Queueing IP set for creation family="inet" setID="masq-ipam-pools" setType="hash:net"
2022-05-21 07:37:16.808 [INFO][55] felix/route_table.go 266: Calculated interface name regexp ifaceRegex="^wireguard.cali$" ipVersion=0x4 tableIndex=1
2022-05-21 07:37:16.809 [INFO][55] felix/int_dataplane.go 837: Registering to report health.
2022-05-21 07:37:16.841 [INFO][55] felix/int_dataplane.go 1682: attempted to modprobe nf_conntrack_proto_sctp error=exit status 1 output=""
2022-05-21 07:37:16.842 [INFO][55] felix/int_dataplane.go 1684: Making sure IPv4 forwarding is enabled.
2022-05-21 07:37:16.842 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="raw"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="raw"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="raw"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-wireguard-incoming-mark" ipVersion=0x4 table="raw"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="raw"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-FORWARD" ipVersion=0x4 table="filter"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-hep-forward"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-wl-dispatch"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-wl-dispatch"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-hep-forward"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-cidr-block"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-INPUT" ipVersion=0x4 table="filter"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-wl-to-host"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-wl-to-host" ipVersion=0x4 table="filter"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="filter"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="filter"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="filter"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-FORWARD"
2022-05-21 07:37:16.843 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-INPUT"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="nat"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-fip-dnat"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="nat"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-fip-snat"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-nat-outgoing"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="nat"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-POSTROUTING"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-OUTPUT"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="mangle"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="mangle"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="mangle"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-from-host-endpoint"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 506: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="mangle"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-to-host-endpoint"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-PREROUTING"
2022-05-21 07:37:16.844 [INFO][55] felix/table.go 580: Chain became referenced, marking it for programming chainName="cali-POSTROUTING"
2022-05-21 07:37:16.844 [INFO][55] felix/int_dataplane.go 1121: IPIP enabled, starting thread to keep tunnel configuration in sync.
2022-05-21 07:37:16.844 [INFO][55] felix/daemon.go 397: Connect to the dataplane driver.
2022-05-21 07:37:16.844 [INFO][55] felix/int_dataplane.go 1460: Started internal iptables dataplane driver loop
2022-05-21 07:37:16.844 [INFO][55] felix/int_dataplane.go 1470: Will refresh IP sets on timer interval=1m30s
2022-05-21 07:37:16.844 [INFO][55] felix/int_dataplane.go 1480: Will refresh routes on timer interval=1m30s
2022-05-21 07:37:16.844 [INFO][55] felix/ipip_mgr.go 84: IPIP thread started.
2022-05-21 07:37:16.844 [INFO][55] felix/daemon.go 475: using resource updates where applicable
2022-05-21 07:37:16.844 [INFO][55] felix/iface_monitor.go 100: Interface monitoring thread started.
2022-05-21 07:37:16.844 [INFO][55] felix/daemon.go 478: Created Syncer syncer=&watchersyncer.watcherSyncer{status:0x0, watcherCaches:[]*watchersyncer.watcherCache{(*watchersyncer.watcherCache)(0xc000755600), (*watchersyncer.watcherCache)(0xc000755680), (*watchersyncer.watcherCache)(0xc000755700), (*watchersyncer.watcherCache)(0xc000755780), (*watchersyncer.watcherCache)(0xc000755800), (*watchersyncer.watcherCache)(0xc000755880), (*watchersyncer.watcherCache)(0xc000755900), (*watchersyncer.watcherCache)(0xc000755980), (*watchersyncer.watcherCache)(0xc000755a00), (*watchersyncer.watcherCache)(0xc000755a80), (*watchersyncer.watcherCache)(0xc000755b00), (*watchersyncer.watcherCache)(0xc000755b80), (*watchersyncer.watcherCache)(0xc000755c00), (*watchersyncer.watcherCache)(0xc000755d00), (*watchersyncer.watcherCache)(0xc000755e00)}, results:(chan interface {})(0xc000a46360), numSynced:0, callbacks:(*calc.SyncerCallbacksDecoupler)(0xc000196078), wgwc:(*sync.WaitGroup)(nil), wgws:(*sync.WaitGroup)(nil), cancel:(context.CancelFunc)(nil)}
2022-05-21 07:37:16.845 [INFO][55] felix/daemon.go 482: Starting the datastore Syncer
2022-05-21 07:37:16.845 [INFO][55] felix/int_dataplane.go 1913: Started internal status report thread
2022-05-21 07:37:16.845 [INFO][55] felix/int_dataplane.go 1915: Process status reports disabled
2022-05-21 07:37:16.845 [INFO][55] felix/watchersyncer.go 89: Start called
2022-05-21 07:37:16.845 [INFO][55] felix/calc_graph.go 115: Creating calculation graph, filtered to hostname k8sai
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9c9a0)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9c9a0)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9caa0)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9caa0)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:16.845 [INFO][55] felix/watchersyncer.go 127: Sending status update Status=wait-for-ready
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.ProfileRulesKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x1a9c820)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.ResourceKey: (dispatcher.UpdateHandler)(0x1a61360)
2022-05-21 07:37:16.845 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:16.846 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:16.846 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:16.846 [INFO][55] felix/dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x19ddc60)
2022-05-21 07:37:16.846 [INFO][55] felix/dispatch