2019-08-28 16:14:42.796 [INFO][64] daemon.go 285: Successfully loaded configuration. GOMAXPROCS=6 buildDate="" config=&config.Config{UseInternalDataplaneDriver:true, DataplaneDriver:"calico-iptables-plugin", DatastoreType:"etcdv3", FelixHostname:"cmp002", EtcdAddr:"127.0.0.1:2379", EtcdScheme:"http", EtcdKeyFile:"/var/lib/etcd/etcd-client.key", EtcdCertFile:"/var/lib/etcd/etcd-client.crt", EtcdCaFile:"/var/lib/etcd/ca.pem", EtcdEndpoints:[]string{"https://172.16.10.36:4001/"}, TyphaAddr:"", TyphaK8sServiceName:"", TyphaK8sNamespace:"kube-system", TyphaReadTimeout:30000000000, TyphaWriteTimeout:10000000000, TyphaKeyFile:"", TyphaCertFile:"", TyphaCAFile:"", TyphaCN:"", TyphaURISAN:"", Ipv6Support:true, IgnoreLooseRPF:false, RouteRefreshInterval:90000000000, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckIntervalSecs:1000000000, IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeoutSecs:0, IptablesLockProbeIntervalMillis:50000000, IpsetsRefreshInterval:10000000000, MaxIpsetSize:1048576, PolicySyncPathPrefix:"", NetlinkTimeoutSecs:10000000000, MetadataAddr:"", MetadataPort:8775, InterfacePrefix:"cali", InterfaceExclude:"kube-ipvs0", ChainInsertMode:"insert", DefaultEndpointToHostAction:"RETURN", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", LogPrefix:"calico-packet", LogFilePath:"", LogSeverityFile:"", LogSeverityScreen:"INFO", LogSeveritySys:"", IpInIpEnabled:false, IpInIpMtu:1440, IpInIpTunnelAddr:net.IP(nil), ReportingIntervalSecs:0, ReportingTTLSecs:90000000000, EndpointReportingEnabled:false, EndpointReportingDelaySecs:1000000000, IptablesMarkMask:0xffff0000, DisableConntrackInvalidCheck:false, HealthEnabled:false, HealthPort:9099, HealthHost:"localhost", PrometheusMetricsEnabled:false, PrometheusMetricsPort:9091, PrometheusGoMetricsEnabled:true, PrometheusProcessMetricsEnabled:true, FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"tcp", Port:0x16}, config.ProtoPort{Protocol:"udp", Port:0x44}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"udp", Port:0x35}, config.ProtoPort{Protocol:"udp", Port:0x43}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}, UsageReportingEnabled:true, UsageReportingInitialDelaySecs:300000000000, UsageReportingIntervalSecs:86400000000000, ClusterGUID:"236357e2b4f74b22a557c3c0ddeb3a84", ClusterType:"k8s,bgp", CalicoVersion:"v3.3.2", ExternalNodesCIDRList:[]string(nil), DebugMemoryProfilePath:"", DebugCPUProfilePath:"/tmp/felix-cpu-<timestamp>.pprof", DebugDisableLogDropping:false, DebugSimulateCalcGraphHangAfter:0, DebugSimulateDataplaneHangAfter:0, sourceToRawConfig:map[config.Source]map[string]string{0x1:map[string]string{"ClusterGUID":"236357e2b4f74b22a557c3c0ddeb3a84", "ClusterType":"k8s,bgp", "CalicoVersion":"v3.3.2", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0"}, 0x2:map[string]string{"DefaultEndpointToHostAction":"Return"}, 0x4:map[string]string{"etcdendpoints":"https://172.16.10.36:4001", "etcdcafile":"/var/lib/etcd/ca.pem", "felixhostname":"cmp002", "etcdcertfile":"/var/lib/etcd/etcd-client.crt", "etcdkeyfile":"/var/lib/etcd/etcd-client.key"}, 0x3:map[string]string{"LogSeverityFile":"None", "LogSeveritySys":"None", "MetadataAddr":"None", "LogFilePath":"None"}}, rawValues:map[string]string{"EtcdEndpoints":"https://172.16.10.36:4001", "LogSeveritySys":"None", "MetadataAddr":"None", "LogSeverityScreen":"Info", "EtcdKeyFile":"/var/lib/etcd/etcd-client.key", "EtcdCaFile":"/var/lib/etcd/ca.pem", "LogFilePath":"None", "DefaultEndpointToHostAction":"Return", "ClusterGUID":"236357e2b4f74b22a557c3c0ddeb3a84", "EtcdCertFile":"/var/lib/etcd/etcd-client.crt", "LogSeverityFile":"None", "CalicoVersion":"v3.3.2", "FelixHostname":"cmp002", "ClusterType":"k8s,bgp", "ReportingIntervalSecs":"0"}, Err:error(nil)} gitCommit="1edd26adb1cfe5c6ff437467d429608428fbd1fe" version="v3.3.2"
2019-08-28 16:14:42.797 [INFO][64] driver.go 43: Using internal (linux) dataplane driver.
2019-08-28 16:14:42.797 [INFO][64] driver.go 85: Calculated iptables mark bits acceptMark=0x10000 endpointMark=0xfff00000 endpointMarkNonCali=0x100000 passMark=0x20000 scratch0Mark=0x40000 scratch1Mark=0x80000
2019-08-28 16:14:42.797 [INFO][64] int_dataplane.go 212: Creating internal dataplane driver. config=intdataplane.Config{IPv6Enabled:true, RuleRendererOverride:rules.RuleRenderer(nil), IPIPMTU:1440, IgnoreLooseRPF:false, MaxIPSetSize:1048576, IPSetsRefreshInterval:10000000000, RouteRefreshInterval:90000000000, IptablesRefreshInterval:90000000000, IptablesPostWriteCheckInterval:1000000000, IptablesInsertMode:"insert", IptablesLockFilePath:"/run/xtables.lock", IptablesLockTimeout:0, IptablesLockProbeInterval:50000000, NetlinkTimeout:10000000000, RulesConfig:rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc42016c230), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc42016c370), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x100000, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, IPIPEnabled:false, IPIPTunnelAddress:net.IP(nil), IptablesLogPrefix:"calico-packet", EndpointToHostAction:"RETURN", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"tcp", Port:0x16}, config.ProtoPort{Protocol:"udp", Port:0x44}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"udp", Port:0x35}, config.ProtoPort{Protocol:"udp", Port:0x43}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}}, IfaceMonitorConfig:ifacemonitor.Config{InterfaceExcludes:[]string{"kube-ipvs0"}}, StatusReportingInterval:0, ConfigChangedRestartCallback:(func())(0x1398480), PostInSyncCallback:(func())(0x137f060), HealthAggregator:(*health.HealthAggregator)(0xc4201206a0), DebugSimulateDataplaneHangAfter:0, ExternalNodesCidrs:[]string(nil)}
2019-08-28 16:14:42.797 [INFO][64] rule_defs.go 283: Creating rule renderer. config=rules.Config{IPSetConfigV4:(*ipsets.IPVersionConfig)(0xc42016c230), IPSetConfigV6:(*ipsets.IPVersionConfig)(0xc42016c370), WorkloadIfacePrefixes:[]string{"cali"}, IptablesMarkAccept:0x10000, IptablesMarkPass:0x20000, IptablesMarkScratch0:0x40000, IptablesMarkScratch1:0x80000, IptablesMarkEndpoint:0xfff00000, IptablesMarkNonCaliEndpoint:0x100000, KubeNodePortRanges:[]numorstring.Port{numorstring.Port{MinPort:0x7530, MaxPort:0x7fff, PortName:""}}, KubeIPVSSupportEnabled:false, OpenStackMetadataIP:net.IP(nil), OpenStackMetadataPort:0x2247, OpenStackSpecialCasesEnabled:false, IPIPEnabled:false, IPIPTunnelAddress:net.IP(nil), IptablesLogPrefix:"calico-packet", EndpointToHostAction:"RETURN", IptablesFilterAllowAction:"ACCEPT", IptablesMangleAllowAction:"ACCEPT", FailsafeInboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"tcp", Port:0x16}, config.ProtoPort{Protocol:"udp", Port:0x44}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, FailsafeOutboundHostPorts:[]config.ProtoPort{config.ProtoPort{Protocol:"udp", Port:0x35}, config.ProtoPort{Protocol:"udp", Port:0x43}, config.ProtoPort{Protocol:"tcp", Port:0xb3}, config.ProtoPort{Protocol:"tcp", Port:0x94b}, config.ProtoPort{Protocol:"tcp", Port:0x94c}, config.ProtoPort{Protocol:"tcp", Port:0x1a0a}, config.ProtoPort{Protocol:"tcp", Port:0x1a0b}}, DisableConntrackInvalid:false, NATPortRange:numorstring.Port{MinPort:0x0, MaxPort:0x0, PortName:""}}
2019-08-28 16:14:42.797 [INFO][64] rule_defs.go 296: Workload to host packets will be returned to INPUT chain.
2019-08-28 16:14:42.797 [INFO][64] rule_defs.go 307: filter table allowed packets will be accepted immediately.
2019-08-28 16:14:42.798 [INFO][64] rule_defs.go 315: mangle table allowed packets will be accepted immediately.
2019-08-28 16:14:42.799 [INFO][64] feature_detect.go 108: Updating detected iptables features features=iptables.Features{SNATFullyRandom:true, MASQFullyRandom:true, RestoreSupportsLock:true} iptablesVersion=1.6.2 kernelVersion=4.15.0
2019-08-28 16:14:42.801 [INFO][64] route_table.go 133: Calculated interface name regexp regex="^cali.*"
2019-08-28 16:14:42.801 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="all-ipam-pools" setType="hash:net"
2019-08-28 16:14:42.801 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="masq-ipam-pools" setType="hash:net"
2019-08-28 16:14:42.801 [INFO][64] route_table.go 133: Calculated interface name regexp regex="^cali.*"
2019-08-28 16:14:42.801 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="all-ipam-pools" setType="hash:net"
2019-08-28 16:14:42.801 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="masq-ipam-pools" setType="hash:net"
2019-08-28 16:14:42.801 [INFO][64] int_dataplane.go 424: Registering to report health.
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="raw"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="raw"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="raw"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="raw"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x6 table="raw"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x6 table="raw"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x6 table="raw"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x6 table="raw"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-FORWARD" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-INPUT" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-wl-to-host" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-FORWARD" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-INPUT" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-wl-to-host" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.802 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-out" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.802 [INFO][64] int_dataplane.go 569: IPIP disabled. Not starting tunnel update thread.
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="nat"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x4 table="nat"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x4 table="nat"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x6 table="nat"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-POSTROUTING" ipVersion=0x6 table="nat"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-OUTPUT" ipVersion=0x6 table="nat"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x4 table="mangle"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x4 table="mangle"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-failsafe-in" ipVersion=0x6 table="mangle"
2019-08-28 16:14:42.803 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-PREROUTING" ipVersion=0x6 table="mangle"
2019-08-28 16:14:42.803 [INFO][64] daemon.go 299: Connect to the dataplane driver.
2019-08-28 16:14:42.803 [INFO][64] daemon.go 371: Created Syncer syncer=&watchersyncer.watcherSyncer{status:0x0, watcherCaches:[]*watchersyncer.watcherCache{(*watchersyncer.watcherCache)(0xc4204a7180), (*watchersyncer.watcherCache)(0xc4204a7200), (*watchersyncer.watcherCache)(0xc4204a7280), (*watchersyncer.watcherCache)(0xc4204a7300), (*watchersyncer.watcherCache)(0xc4204a7380), (*watchersyncer.watcherCache)(0xc4204a7400), (*watchersyncer.watcherCache)(0xc4204a7480), (*watchersyncer.watcherCache)(0xc4204a7500), (*watchersyncer.watcherCache)(0xc4204a7580), (*watchersyncer.watcherCache)(0xc4204a7600)}, results:(chan interface {})(0xc42016f1a0), numSynced:0, callbacks:(*calc.SyncerCallbacksDecoupler)(0xc42000c4d0), wgwc:(*sync.WaitGroup)(nil), wgws:(*sync.WaitGroup)(nil), cancel:(context.CancelFunc)(nil)}
2019-08-28 16:14:42.803 [INFO][64] calc_graph.go 96: Creating calculation graph, filtered to hostname cmp002
2019-08-28 16:14:42.803 [INFO][64] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x12a2a30)
2019-08-28 16:14:42.803 [INFO][64] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x12a2a30)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x12a2af0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x12a2af0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x12a2920)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x12a2920)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x12a2920)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.ProfileRulesKey: (dispatcher.UpdateHandler)(0x12a2920)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x12a2920)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x12a2920)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.ProfileTagsKey: (dispatcher.UpdateHandler)(0x120eed0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x120eed0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x120eed0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x120eed0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.NetworkSetKey: (dispatcher.UpdateHandler)(0x120eed0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.PolicyKey: (dispatcher.UpdateHandler)(0x12a2ce0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x12a2ce0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x12a2ce0)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x12a2c20)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.IPPoolKey: (dispatcher.UpdateHandler)(0x12a2c20)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.GlobalConfigKey: (dispatcher.UpdateHandler)(0x12a2b60)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x12a2b60)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.ReadyFlagKey: (dispatcher.UpdateHandler)(0x12a2b60)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.ProfileLabelsKey: (dispatcher.UpdateHandler)(0x12a2e10)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostIPKey: (dispatcher.UpdateHandler)(0x12a2e80)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.WorkloadEndpointKey: (dispatcher.UpdateHandler)(0x12a2e80)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostEndpointKey: (dispatcher.UpdateHandler)(0x12a2e80)
2019-08-28 16:14:42.804 [INFO][64] dispatcher.go 68: Registering listener for type model.HostConfigKey: (dispatcher.UpdateHandler)(0x12a2e80)
2019-08-28 16:14:42.805 [INFO][64] int_dataplane.go 594: Started internal iptables dataplane driver loop
2019-08-28 16:14:42.805 [INFO][64] int_dataplane.go 604: Will refresh IP sets on timer interval=1m30s
2019-08-28 16:14:42.805 [INFO][64] int_dataplane.go 614: Will refresh routes on timer interval=1m30s
2019-08-28 16:14:42.805 [INFO][64] int_dataplane.go 981: Started internal status report thread
2019-08-28 16:14:42.805 [INFO][64] int_dataplane.go 983: Process status reports disabled
2019-08-28 16:14:42.805 [INFO][64] iface_monitor.go 88: Interface monitoring thread started.
2019-08-28 16:14:42.804 [INFO][64] daemon.go 439: Starting the datastore Syncer
2019-08-28 16:14:42.805 [INFO][64] watchersyncer.go 89: Start called
2019-08-28 16:14:42.805 [INFO][64] watchersyncer.go 127: Sending status update Status=wait-for-ready
2019-08-28 16:14:42.805 [INFO][64] watchersyncer.go 147: Starting main event processing loop
2019-08-28 16:14:42.806 [INFO][64] iface_monitor.go 95: Subscribed to netlink updates.
2019-08-28 16:14:42.805 [INFO][64] async_calc_graph.go 229: Starting AsyncCalcGraph
2019-08-28 16:14:42.806 [INFO][64] daemon.go 454: Started the processing graph
2019-08-28 16:14:42.806 [INFO][64] daemon.go 813: Reading from dataplane driver pipe...
2019-08-28 16:14:42.806 [INFO][64] async_calc_graph.go 134: AsyncCalcGraph running
2019-08-28 16:14:42.809 [INFO][64] int_dataplane.go 471: Linux interface state changed. ifaceName="lo" state="up"
2019-08-28 16:14:42.806 [INFO][64] int_dataplane.go 630: Received *proto.ConfigUpdate update from calculation graph msg=config:<key:"CalicoVersion" value:"v3.3.2" > config:<key:"ClusterGUID" value:"236357e2b4f74b22a557c3c0ddeb3a84" > config:<key:"ClusterType" value:"k8s,bgp" > config:<key:"DefaultEndpointToHostAction" value:"Return" > config:<key:"EtcdCaFile" value:"/var/lib/etcd/ca.pem" > config:<key:"EtcdCertFile" value:"/var/lib/etcd/etcd-client.crt" > config:<key:"EtcdEndpoints" value:"https://172.16.10.36:4001" > config:<key:"EtcdKeyFile" value:"/var/lib/etcd/etcd-client.key" > config:<key:"FelixHostname" value:"cmp002" > 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" > 
2019-08-28 16:14:42.810 [INFO][64] int_dataplane.go 645: Received interface update msg=&intdataplane.ifaceUpdate{Name:"lo", State:"up"}
2019-08-28 16:14:42.810 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{"::1":set.empty{}, "127.0.0.1":set.empty{}} ifaceName="lo"
2019-08-28 16:14:42.811 [INFO][64] int_dataplane.go 471: Linux interface state changed. ifaceName="ens3" state="up"
2019-08-28 16:14:42.811 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{"192.168.11.37":set.empty{}, "fe80::5054:ff:fe65:64bf":set.empty{}} ifaceName="ens3"
2019-08-28 16:14:42.811 [INFO][64] int_dataplane.go 471: Linux interface state changed. ifaceName="ens4" state="up"
2019-08-28 16:14:42.811 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{"fe80::5054:ff:fe42:ce21":set.empty{}} ifaceName="ens4"
2019-08-28 16:14:42.812 [INFO][64] int_dataplane.go 471: Linux interface state changed. ifaceName="ens5" state="up"
2019-08-28 16:14:42.812 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{"fe80::5054:ff:feaa:6b6b":set.empty{}} ifaceName="ens5"
2019-08-28 16:14:42.812 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="ens6"
2019-08-28 16:14:42.812 [INFO][64] int_dataplane.go 471: Linux interface state changed. ifaceName="br-mgmt" state="up"
2019-08-28 16:14:42.812 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{"172.16.10.56":set.empty{}, "fe80::5054:ff:fe42:ce21":set.empty{}} ifaceName="br-mgmt"
2019-08-28 16:14:42.812 [INFO][64] int_dataplane.go 471: Linux interface state changed. ifaceName="ens5.1000" state="up"
2019-08-28 16:14:42.812 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.1":set.empty{}, "::1":set.empty{}}}
2019-08-28 16:14:42.812 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{"fe80::5054:ff:feaa:6b6b":set.empty{}} ifaceName="ens5.1000"
2019-08-28 16:14:42.813 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.1":set.empty{}, "::1":set.empty{}}}
2019-08-28 16:14:42.813 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.813 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"lo", Addrs:set.mapSet{"127.0.0.1":set.empty{}, "::1":set.empty{}}}
2019-08-28 16:14:42.813 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.813 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"ens3", Addrs:set.mapSet{"192.168.11.37":set.empty{}, "fe80::5054:ff:fe65:64bf":set.empty{}}}
2019-08-28 16:14:42.813 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens3", Addrs:set.mapSet{"192.168.11.37":set.empty{}, "fe80::5054:ff:fe65:64bf":set.empty{}}}
2019-08-28 16:14:42.813 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.813 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens3", Addrs:set.mapSet{"192.168.11.37":set.empty{}, "fe80::5054:ff:fe65:64bf":set.empty{}}}
2019-08-28 16:14:42.814 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.814 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"ens4", Addrs:set.mapSet{"fe80::5054:ff:fe42:ce21":set.empty{}}}
2019-08-28 16:14:42.814 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens4", Addrs:set.mapSet{"fe80::5054:ff:fe42:ce21":set.empty{}}}
2019-08-28 16:14:42.814 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.814 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens4", Addrs:set.mapSet{"fe80::5054:ff:fe42:ce21":set.empty{}}}
2019-08-28 16:14:42.814 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.814 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"ens5", Addrs:set.mapSet{"fe80::5054:ff:feaa:6b6b":set.empty{}}}
2019-08-28 16:14:42.814 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens5", Addrs:set.mapSet{"fe80::5054:ff:feaa:6b6b":set.empty{}}}
2019-08-28 16:14:42.814 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.814 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens5", Addrs:set.mapSet{"fe80::5054:ff:feaa:6b6b":set.empty{}}}
2019-08-28 16:14:42.815 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.815 [INFO][64] daemon.go 546: No driver process to monitor
2019-08-28 16:14:42.815 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"ens6", Addrs:set.mapSet{}}
2019-08-28 16:14:42.815 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens6", Addrs:set.mapSet{}}
2019-08-28 16:14:42.815 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.815 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/workloadendpoints"
2019-08-28 16:14:42.815 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens6", Addrs:set.mapSet{}}
2019-08-28 16:14:42.815 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/networkpolicies"
2019-08-28 16:14:42.816 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.816 [INFO][64] watchersyncer.go 127: Sending status update Status=resync
2019-08-28 16:14:42.816 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/hostendpoints"
2019-08-28 16:14:42.816 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.815 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.816 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"br-mgmt", Addrs:set.mapSet{"172.16.10.56":set.empty{}, "fe80::5054:ff:fe42:ce21":set.empty{}}}
2019-08-28 16:14:42.816 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.816 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/clusterinformations"
2019-08-28 16:14:42.816 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.816 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br-mgmt", Addrs:set.mapSet{"172.16.10.56":set.empty{}, "fe80::5054:ff:fe42:ce21":set.empty{}}}
2019-08-28 16:14:42.816 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/globalnetworkpolicies"
2019-08-28 16:14:42.816 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/nodes"
2019-08-28 16:14:42.816 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/profiles"
2019-08-28 16:14:42.816 [INFO][64] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterGUID) 236357e2b4f74b22a557c3c0ddeb3a84 6 0s} 1}
2019-08-28 16:14:42.816 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.816 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.817 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.817 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"br-mgmt", Addrs:set.mapSet{"172.16.10.56":set.empty{}, "fe80::5054:ff:fe42:ce21":set.empty{}}}
2019-08-28 16:14:42.817 [INFO][64] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ClusterType) k8s,bgp 6 0s} 1}
2019-08-28 16:14:42.817 [INFO][64] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=CalicoVersion) v3.3.2 6 0s} 1}
2019-08-28 16:14:42.817 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.817 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.817 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/globalnetworksets"
2019-08-28 16:14:42.817 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/ippools"
2019-08-28 16:14:42.817 [INFO][64] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=LogSeverityScreen) Info 7 0s} 1}
2019-08-28 16:14:42.817 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"ens5.1000", Addrs:set.mapSet{"fe80::5054:ff:feaa:6b6b":set.empty{}}}
2019-08-28 16:14:42.817 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.817 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.817 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens5.1000", Addrs:set.mapSet{"fe80::5054:ff:feaa:6b6b":set.empty{}}}
2019-08-28 16:14:42.817 [INFO][64] watchercache.go 271: Sending synced update ListRoot="/calico/resources/v3/projectcalico.org/felixconfigurations"
2019-08-28 16:14:42.817 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.817 [INFO][64] config_batcher.go 74: Global config update: {{GlobalFelixConfig(name=ReportingIntervalSecs) 0 7 0s} 1}
2019-08-28 16:14:42.817 [INFO][64] watchersyncer.go 209: Received InSync event from one of the watcher caches
2019-08-28 16:14:42.817 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"ens5.1000", Addrs:set.mapSet{"fe80::5054:ff:feaa:6b6b":set.empty{}}}
2019-08-28 16:14:42.817 [INFO][64] watchersyncer.go 221: All watchers have sync'd data - sending data and final sync
2019-08-28 16:14:42.818 [INFO][64] watchersyncer.go 127: Sending status update Status=in-sync
2019-08-28 16:14:42.818 [INFO][64] ipsets.go 119: Queueing IP set for creation family="inet6" setID="this-host" setType="hash:ip"
2019-08-28 16:14:42.818 [INFO][64] config_batcher.go 61: Host config update for this host: {{HostConfig(node=cmp002,name=DefaultEndpointToHostAction) Return 10 0s} 1}
2019-08-28 16:14:42.818 [INFO][64] int_dataplane.go 645: Received interface update msg=&intdataplane.ifaceUpdate{Name:"ens3", State:"up"}
2019-08-28 16:14:42.818 [INFO][64] int_dataplane.go 645: Received interface update msg=&intdataplane.ifaceUpdate{Name:"ens4", State:"up"}
2019-08-28 16:14:42.818 [INFO][64] config_batcher.go 102: Datamodel in sync, flushing config update
2019-08-28 16:14:42.818 [INFO][64] int_dataplane.go 645: Received interface update msg=&intdataplane.ifaceUpdate{Name:"ens5", State:"up"}
2019-08-28 16:14:42.818 [INFO][64] int_dataplane.go 645: Received interface update msg=&intdataplane.ifaceUpdate{Name:"br-mgmt", State:"up"}
2019-08-28 16:14:42.818 [INFO][64] config_batcher.go 112: Sending config update global: map[CalicoVersion:v3.3.2 LogSeverityScreen:Info ReportingIntervalSecs:0 ClusterGUID:236357e2b4f74b22a557c3c0ddeb3a84 ClusterType:k8s,bgp], host: map[DefaultEndpointToHostAction:Return].
2019-08-28 16:14:42.818 [INFO][64] int_dataplane.go 645: Received interface update msg=&intdataplane.ifaceUpdate{Name:"ens5.1000", State:"up"}
2019-08-28 16:14:42.818 [INFO][64] async_calc_graph.go 160: First time we've been in sync
2019-08-28 16:14:42.818 [INFO][64] usagerep.go 83: Waiting before first check-in delay=5m0.991s
2019-08-28 16:14:42.818 [INFO][64] event_sequencer.go 210: Possible config update. global=map[string]string{"ClusterGUID":"236357e2b4f74b22a557c3c0ddeb3a84", "ClusterType":"k8s,bgp", "CalicoVersion":"v3.3.2", "LogSeverityScreen":"Info", "ReportingIntervalSecs":"0"} host=map[string]string{"DefaultEndpointToHostAction":"Return"}
2019-08-28 16:14:42.818 [INFO][64] config_params.go 217: Merging in config from datastore (global): map[CalicoVersion:v3.3.2 LogSeverityScreen:Info ReportingIntervalSecs:0 ClusterGUID:236357e2b4f74b22a557c3c0ddeb3a84 ClusterType:k8s,bgp]
2019-08-28 16:14:42.818 [INFO][64] config_params.go 302: Parsing value for EtcdCertFile: /var/lib/etcd/etcd-client.crt (from environment variable)
2019-08-28 16:14:42.818 [INFO][64] param_types.go 220: Looking for required file path="/var/lib/etcd/etcd-client.crt"
2019-08-28 16:14:42.818 [INFO][64] config_params.go 338: Parsed value for EtcdCertFile: /var/lib/etcd/etcd-client.crt (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for EtcdKeyFile: /var/lib/etcd/etcd-client.key (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] param_types.go 220: Looking for required file path="/var/lib/etcd/etcd-client.key"
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for EtcdKeyFile: /var/lib/etcd/etcd-client.key (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for EtcdEndpoints: https://172.16.10.36:4001 (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for EtcdEndpoints: [https://172.16.10.36:4001/] (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for EtcdCaFile: /var/lib/etcd/ca.pem (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] param_types.go 220: Looking for required file path="/var/lib/etcd/ca.pem"
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for EtcdCaFile: /var/lib/etcd/ca.pem (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for FelixHostname: cmp002 (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for FelixHostname: cmp002 (from environment variable)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for MetadataAddr: None (from config file)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 319: Value set to 'none', replacing with zero-value: "".
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for MetadataAddr:  (from config file)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for LogFilePath: None (from config file)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 319: Value set to 'none', replacing with zero-value: "".
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for LogFilePath:  (from config file)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for LogSeverityFile: None (from config file)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 319: Value set to 'none', replacing with zero-value: "".
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for LogSeverityFile:  (from config file)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for LogSeveritySys: None (from config file)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 319: Value set to 'none', replacing with zero-value: "".
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for LogSeveritySys:  (from config file)
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for DefaultEndpointToHostAction: Return (from datastore (per-host))
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for DefaultEndpointToHostAction: RETURN (from datastore (per-host))
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for CalicoVersion: v3.3.2 (from datastore (global))
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for CalicoVersion: v3.3.2 (from datastore (global))
2019-08-28 16:14:42.819 [INFO][64] config_params.go 302: Parsing value for LogSeverityScreen: Info (from datastore (global))
2019-08-28 16:14:42.819 [INFO][64] config_params.go 338: Parsed value for LogSeverityScreen: INFO (from datastore (global))
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for ClusterGUID: 236357e2b4f74b22a557c3c0ddeb3a84 (from datastore (global))
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for ClusterGUID: 236357e2b4f74b22a557c3c0ddeb3a84 (from datastore (global))
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for ClusterType: k8s,bgp (from datastore (global))
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for ClusterType: k8s,bgp (from datastore (global))
2019-08-28 16:14:42.820 [INFO][64] config_params.go 217: Merging in config from datastore (per-host): map[DefaultEndpointToHostAction:Return]
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for FelixHostname: cmp002 (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for FelixHostname: cmp002 (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for EtcdCertFile: /var/lib/etcd/etcd-client.crt (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] param_types.go 220: Looking for required file path="/var/lib/etcd/etcd-client.crt"
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for EtcdCertFile: /var/lib/etcd/etcd-client.crt (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for EtcdKeyFile: /var/lib/etcd/etcd-client.key (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] param_types.go 220: Looking for required file path="/var/lib/etcd/etcd-client.key"
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for EtcdKeyFile: /var/lib/etcd/etcd-client.key (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for EtcdEndpoints: https://172.16.10.36:4001 (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for EtcdEndpoints: [https://172.16.10.36:4001/] (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for EtcdCaFile: /var/lib/etcd/ca.pem (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] param_types.go 220: Looking for required file path="/var/lib/etcd/ca.pem"
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for EtcdCaFile: /var/lib/etcd/ca.pem (from environment variable)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for MetadataAddr: None (from config file)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 319: Value set to 'none', replacing with zero-value: "".
2019-08-28 16:14:42.820 [INFO][64] config_params.go 338: Parsed value for MetadataAddr:  (from config file)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 302: Parsing value for LogFilePath: None (from config file)
2019-08-28 16:14:42.820 [INFO][64] config_params.go 319: Value set to 'none', replacing with zero-value: "".
2019-08-28 16:14:42.821 [INFO][64] config_params.go 338: Parsed value for LogFilePath:  (from config file)
2019-08-28 16:14:42.821 [INFO][64] config_params.go 302: Parsing value for LogSeverityFile: None (from config file)
2019-08-28 16:14:42.821 [INFO][64] config_params.go 319: Value set to 'none', replacing with zero-value: "".
2019-08-28 16:14:42.821 [INFO][64] config_params.go 338: Parsed value for LogSeverityFile:  (from config file)
2019-08-28 16:14:42.821 [INFO][64] config_params.go 302: Parsing value for LogSeveritySys: None (from config file)
2019-08-28 16:14:42.821 [INFO][64] config_params.go 319: Value set to 'none', replacing with zero-value: "".
2019-08-28 16:14:42.821 [INFO][64] config_params.go 338: Parsed value for LogSeveritySys:  (from config file)
2019-08-28 16:14:42.821 [INFO][64] config_params.go 302: Parsing value for DefaultEndpointToHostAction: Return (from datastore (per-host))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 338: Parsed value for DefaultEndpointToHostAction: RETURN (from datastore (per-host))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 302: Parsing value for ClusterGUID: 236357e2b4f74b22a557c3c0ddeb3a84 (from datastore (global))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 338: Parsed value for ClusterGUID: 236357e2b4f74b22a557c3c0ddeb3a84 (from datastore (global))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 302: Parsing value for ClusterType: k8s,bgp (from datastore (global))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 338: Parsed value for ClusterType: k8s,bgp (from datastore (global))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 302: Parsing value for CalicoVersion: v3.3.2 (from datastore (global))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 338: Parsed value for CalicoVersion: v3.3.2 (from datastore (global))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 302: Parsing value for LogSeverityScreen: Info (from datastore (global))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 338: Parsed value for LogSeverityScreen: INFO (from datastore (global))
2019-08-28 16:14:42.821 [INFO][64] config_params.go 302: Parsing value for ReportingIntervalSecs: 0 (from datastore (global))
2019-08-28 16:14:42.822 [INFO][64] config_params.go 338: Parsed value for ReportingIntervalSecs: 0s (from datastore (global))
2019-08-28 16:14:42.822 [INFO][64] async_calc_graph.go 209: First flush after becoming in sync, sending InSync message.
2019-08-28 16:14:42.822 [INFO][64] daemon.go 892: Datastore now in sync.
2019-08-28 16:14:42.822 [INFO][64] daemon.go 894: Datastore in sync for first time, sending message to status reporter.
2019-08-28 16:14:42.822 [INFO][64] int_dataplane.go 630: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"cmp001" ipv4_addr:"172.16.10.55" 
2019-08-28 16:14:42.822 [INFO][64] int_dataplane.go 630: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"cmp002" ipv4_addr:"172.16.10.56" 
2019-08-28 16:14:42.822 [INFO][64] int_dataplane.go 630: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"192.168.0.0-16" pool:<cidr:"192.168.0.0/16" masquerade:true > 
2019-08-28 16:14:42.822 [INFO][64] int_dataplane.go 630: Received *proto.IPAMPoolUpdate update from calculation graph msg=id:"fdef:bb2e:a633::-48" pool:<cidr:"fdef:bb2e:a633::/48" > 
2019-08-28 16:14:42.822 [INFO][64] int_dataplane.go 630: Received *proto.InSync update from calculation graph msg=
2019-08-28 16:14:42.822 [INFO][64] int_dataplane.go 638: Datastore in sync, flushing the dataplane for the first time... timeSinceStart=144.618377ms
2019-08-28 16:14:42.822 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:42.822 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.822 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-hep-forward" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-hep-forward" ipVersion=0x4 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="mangle"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x4 table="raw"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x4 table="raw"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fip-dnat" ipVersion=0x4 table="nat"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fip-snat" ipVersion=0x4 table="nat"
2019-08-28 16:14:42.823 [INFO][64] masq_mgr.go 144: IPAM pools updated, refreshing iptables rule ipVersion=0x4
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-nat-outgoing" ipVersion=0x4 table="nat"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-hep-forward" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-hep-forward" ipVersion=0x6 table="filter"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x6 table="mangle"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-host-endpoint" ipVersion=0x6 table="raw"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-host-endpoint" ipVersion=0x6 table="raw"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fip-dnat" ipVersion=0x6 table="nat"
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fip-snat" ipVersion=0x6 table="nat"
2019-08-28 16:14:42.823 [INFO][64] masq_mgr.go 144: IPAM pools updated, refreshing iptables rule ipVersion=0x6
2019-08-28 16:14:42.823 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-nat-outgoing" ipVersion=0x6 table="nat"
2019-08-28 16:14:42.824 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:14:42.824 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:14:42.824 [INFO][64] route_table.go 199: Trying to connect to netlink
2019-08-28 16:14:42.824 [INFO][64] route_table.go 199: Trying to connect to netlink
2019-08-28 16:14:42.835 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=11.801993ms
2019-08-28 16:14:42.836 [INFO][64] ipsets.go 697: Doing full IP set rewrite family="inet" numMembersInPendingReplace=1 setID="masq-ipam-pools"
2019-08-28 16:14:42.836 [INFO][64] ipsets.go 697: Doing full IP set rewrite family="inet" numMembersInPendingReplace=3 setID="this-host"
2019-08-28 16:14:42.836 [INFO][64] ipsets.go 697: Doing full IP set rewrite family="inet" numMembersInPendingReplace=1 setID="all-ipam-pools"
2019-08-28 16:14:42.838 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=14.529364ms
2019-08-28 16:14:42.839 [INFO][64] ipsets.go 697: Doing full IP set rewrite family="inet6" numMembersInPendingReplace=1 setID="all-ipam-pools"
2019-08-28 16:14:42.839 [INFO][64] ipsets.go 697: Doing full IP set rewrite family="inet6" numMembersInPendingReplace=0 setID="masq-ipam-pools"
2019-08-28 16:14:42.839 [INFO][64] ipsets.go 697: Doing full IP set rewrite family="inet6" numMembersInPendingReplace=4 setID="this-host"
2019-08-28 16:14:42.974 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:14:42.974 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:14:42.976 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:14:42.983 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:14:42.984 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:14:42.989 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:14:42.990 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:14:42.992 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:14:43.436 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=613.289557
2019-08-28 16:14:43.436 [INFO][64] int_dataplane.go 771: Completed first update to dataplane. secsSinceStart=0.758119988
2019-08-28 16:14:43.947 [INFO][64] int_dataplane.go 630: Received *proto.HostMetadataUpdate update from calculation graph msg=hostname:"ctl01" ipv4_addr:"172.16.10.36" 
2019-08-28 16:14:43.947 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:43.948 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=0.172708
2019-08-28 16:14:44.040 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:44.040 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:14:44.041 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:14:44.043 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.871349
2019-08-28 16:14:44.059 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:44.060 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:14:44.060 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:14:44.061 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.906158
2019-08-28 16:14:44.245 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:44.251 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:14:44.252 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:14:44.253 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:14:44.255 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:14:44.257 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=11.30424
2019-08-28 16:14:44.264 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:44.264 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:14:44.265 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:14:44.267 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.539802
2019-08-28 16:14:44.287 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:44.287 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:14:44.288 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:14:44.290 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.89696
2019-08-28 16:14:44.432 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:44.432 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:14:44.434 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:14:44.436 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.3691060000000004
2019-08-28 16:14:44.439 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:44.439 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:14:44.440 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:14:44.442 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.857412
2019-08-28 16:14:45.043 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:45.043 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:14:45.047 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:14:45.049 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.806089
2019-08-28 16:14:45.062 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:45.062 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:14:45.063 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:14:45.064 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.814883
2019-08-28 16:14:45.245 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:45.245 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:14:45.246 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:14:45.247 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.651879
2019-08-28 16:14:45.252 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:45.252 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:14:45.253 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:14:45.254 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.668714
2019-08-28 16:14:45.261 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:45.262 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:14:45.262 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:14:45.264 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.7657950000000002
2019-08-28 16:14:45.287 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:45.287 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:14:45.288 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:14:45.289 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.3202320000000003
2019-08-28 16:14:45.433 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:45.434 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:14:45.435 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:14:45.436 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.533244
2019-08-28 16:14:45.438 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:45.438 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:14:45.439 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:14:45.440 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.252807
2019-08-28 16:14:47.042 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:47.042 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:14:47.044 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:14:47.045 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.9092800000000003
2019-08-28 16:14:47.060 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:47.060 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:14:47.061 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:14:47.064 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.020527
2019-08-28 16:14:47.246 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:47.246 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:14:47.247 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:14:47.249 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.9473670000000003
2019-08-28 16:14:47.252 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:47.252 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:14:47.253 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:14:47.254 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.304834
2019-08-28 16:14:47.261 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:47.261 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:14:47.262 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:14:47.263 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.292245
2019-08-28 16:14:47.286 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:47.286 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:14:47.287 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:14:47.288 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.053208
2019-08-28 16:14:47.433 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:47.433 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:14:47.434 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:14:47.438 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.255801
2019-08-28 16:14:47.440 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:47.440 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:14:47.441 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:14:47.442 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.591628
2019-08-28 16:14:51.042 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:51.043 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:14:51.044 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:14:51.045 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.382833
2019-08-28 16:14:51.059 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:51.059 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:14:51.060 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:14:51.061 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.924734
2019-08-28 16:14:51.245 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:51.245 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:14:51.246 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:14:51.248 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.5655769999999998
2019-08-28 16:14:51.252 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:51.252 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:14:51.253 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:14:51.256 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.650417
2019-08-28 16:14:51.262 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:51.263 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:14:51.263 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:14:51.265 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.5528809999999997
2019-08-28 16:14:51.287 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:51.287 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:14:51.287 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:14:51.289 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.979491
2019-08-28 16:14:51.433 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:51.434 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:14:51.435 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:14:51.437 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.543049
2019-08-28 16:14:51.439 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:51.439 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:14:51.440 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:14:51.442 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.103012
2019-08-28 16:14:53.347 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:53.347 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:14:53.347 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:14:53.348 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:14:53.348 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:14:53.349 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.181972ms
2019-08-28 16:14:53.349 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.21534ms
2019-08-28 16:14:53.349 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.595733
2019-08-28 16:14:59.040 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:59.040 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:14:59.041 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:14:59.043 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.6295260000000003
2019-08-28 16:14:59.059 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:59.060 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:14:59.060 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:14:59.061 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.920568
2019-08-28 16:14:59.245 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:59.245 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:14:59.247 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:14:59.249 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.828808
2019-08-28 16:14:59.253 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:59.253 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:14:59.254 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:14:59.256 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.370428
2019-08-28 16:14:59.261 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:59.261 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:14:59.262 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:14:59.263 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.084845
2019-08-28 16:14:59.286 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:59.286 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:14:59.287 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:14:59.288 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.889868
2019-08-28 16:14:59.433 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:59.433 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:14:59.435 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:14:59.437 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.208324
2019-08-28 16:14:59.439 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:14:59.440 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:14:59.441 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:14:59.444 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.313318
2019-08-28 16:15:03.979 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:03.980 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:15:03.980 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:15:03.980 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:15:03.980 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:15:03.982 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.887292ms
2019-08-28 16:15:03.982 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.815189ms
2019-08-28 16:15:03.982 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.713974
2019-08-28 16:15:14.506 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:14.506 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:15:14.506 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:15:14.506 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:15:14.506 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:15:14.510 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=3.490446ms
2019-08-28 16:15:14.510 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=3.64452ms
2019-08-28 16:15:14.511 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.9712380000000005
2019-08-28 16:15:15.041 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:15.041 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:15:15.043 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:15:15.047 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.810142
2019-08-28 16:15:15.063 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:15.063 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:15:15.065 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:15:15.066 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.491247
2019-08-28 16:15:15.246 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:15.247 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:15:15.248 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:15:15.251 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.072049000000001
2019-08-28 16:15:15.253 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:15.254 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:15:15.255 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:15:15.258 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.409381000000001
2019-08-28 16:15:15.263 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:15.263 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:15:15.265 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:15:15.267 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.406325
2019-08-28 16:15:15.288 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:15.288 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:15:15.290 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:15:15.293 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.991609
2019-08-28 16:15:15.436 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:15.436 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:15:15.436 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:15:15.438 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:15:15.440 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:15:15.442 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.383722
2019-08-28 16:15:24.692 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:24.692 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:15:24.692 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:15:24.692 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:15:24.692 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:15:24.694 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.08134ms
2019-08-28 16:15:24.694 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.058656ms
2019-08-28 16:15:24.695 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.852101
2019-08-28 16:15:35.650 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:35.650 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:15:35.650 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:15:35.650 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:15:35.650 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:15:35.652 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.905097ms
2019-08-28 16:15:35.652 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.849135ms
2019-08-28 16:15:35.652 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.641582
2019-08-28 16:15:46.622 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:46.622 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:15:46.622 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:15:46.622 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:15:46.622 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:15:46.624 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.540637ms
2019-08-28 16:15:46.624 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.963939ms
2019-08-28 16:15:46.625 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.769402
2019-08-28 16:15:47.040 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:47.040 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:15:47.042 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:15:47.044 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.367344
2019-08-28 16:15:47.060 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:47.061 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:15:47.062 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:15:47.064 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.2643120000000003
2019-08-28 16:15:47.246 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:47.246 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:15:47.248 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:15:47.249 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.902822
2019-08-28 16:15:47.252 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:47.253 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:15:47.254 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:15:47.256 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.376737
2019-08-28 16:15:47.262 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:47.262 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:15:47.264 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:15:47.266 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.359893
2019-08-28 16:15:47.288 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:47.288 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:15:47.289 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:15:47.290 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.47314
2019-08-28 16:15:47.434 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:47.434 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:15:47.436 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:15:47.439 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.588522
2019-08-28 16:15:47.440 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:47.440 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:15:47.442 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:15:47.444 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.654563
2019-08-28 16:15:57.079 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:15:57.079 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:15:57.079 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:15:57.079 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:15:57.079 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:15:57.081 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.003516ms
2019-08-28 16:15:57.081 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.646582ms
2019-08-28 16:15:57.081 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.35113
2019-08-28 16:16:07.547 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:07.547 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:16:07.547 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:16:07.548 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:16:07.548 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:16:07.549 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.453559ms
2019-08-28 16:16:07.549 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.140536ms
2019-08-28 16:16:07.549 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.077929
2019-08-28 16:16:13.986 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:13.986 [INFO][64] route_table.go 189: Queueing a resync of routing table. ipVersion=0x4
2019-08-28 16:16:13.986 [INFO][64] route_table.go 189: Queueing a resync of routing table. ipVersion=0x6
2019-08-28 16:16:13.990 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.912324
2019-08-28 16:16:17.901 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:17.901 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:16:17.901 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:16:17.901 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:16:17.901 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:16:17.902 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.118365ms
2019-08-28 16:16:17.902 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.191475ms
2019-08-28 16:16:17.903 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.71975
2019-08-28 16:16:28.109 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:28.109 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:16:28.109 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:16:28.109 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:16:28.110 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:16:28.111 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=923.818µs
2019-08-28 16:16:28.111 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.531098ms
2019-08-28 16:16:28.111 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.808695
2019-08-28 16:16:38.192 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:38.192 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:16:38.192 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:16:38.192 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:16:38.192 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:16:38.194 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.691233ms
2019-08-28 16:16:38.194 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.617172ms
2019-08-28 16:16:38.194 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.2388030000000003
2019-08-28 16:16:48.396 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:48.396 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:16:48.396 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:16:48.396 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:16:48.396 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:16:48.398 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.852689ms
2019-08-28 16:16:48.398 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.437365ms
2019-08-28 16:16:48.399 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.0389470000000003
2019-08-28 16:16:51.040 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:51.040 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:16:51.042 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:16:51.046 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.633568
2019-08-28 16:16:51.062 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:51.063 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:16:51.064 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:16:51.065 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.560886
2019-08-28 16:16:51.246 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:51.246 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:16:51.248 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:16:51.249 [WARNING][64] table.go 508: Detected out-of-sync inserts, marking for resync actualRuleIDs=[]string{"", "O3lYWMrLQYEMJtB5"} chainName="POSTROUTING" expectedRuleIDs=[]string{"O3lYWMrLQYEMJtB5", ""} ipVersion=0x4 table="nat"
2019-08-28 16:16:51.251 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.094461
2019-08-28 16:16:51.254 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:51.255 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:16:51.256 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:16:51.257 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.898223
2019-08-28 16:16:51.262 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:51.262 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:16:51.263 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:16:51.265 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.8352350000000004
2019-08-28 16:16:51.287 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:51.287 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:16:51.288 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:16:51.290 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.2092359999999998
2019-08-28 16:16:51.434 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:51.435 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:16:51.437 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:16:51.439 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.704504
2019-08-28 16:16:51.440 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:51.440 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:16:51.442 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:16:51.445 [WARNING][64] table.go 508: Detected out-of-sync inserts, marking for resync actualRuleIDs=[]string{"", "Cz_u1IQiXIMmKD4c"} chainName="INPUT" expectedRuleIDs=[]string{"Cz_u1IQiXIMmKD4c", ""} ipVersion=0x4 table="filter"
2019-08-28 16:16:51.445 [WARNING][64] table.go 508: Detected out-of-sync inserts, marking for resync actualRuleIDs=[]string{"", "tVnHkvAo15HuiPy0"} chainName="OUTPUT" expectedRuleIDs=[]string{"tVnHkvAo15HuiPy0", ""} ipVersion=0x4 table="filter"
2019-08-28 16:16:51.447 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=7.107729999999999
2019-08-28 16:16:52.258 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:52.258 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:16:52.260 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:16:52.261 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.907019
2019-08-28 16:16:52.450 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:52.451 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:16:52.452 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:16:52.454 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.38443
2019-08-28 16:16:53.254 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:53.254 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:16:53.256 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:16:53.257 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.9132730000000002
2019-08-28 16:16:53.450 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:53.451 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:16:53.453 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:16:53.454 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.4650250000000002
2019-08-28 16:16:55.254 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:55.255 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:16:55.257 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:16:55.259 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.3832059999999995
2019-08-28 16:16:55.452 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:55.452 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:16:55.453 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:16:55.458 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.047701
2019-08-28 16:16:59.257 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:59.257 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:16:59.260 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:16:59.263 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.6180390000000004
2019-08-28 16:16:59.308 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:59.308 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:16:59.308 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:16:59.308 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:16:59.308 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:16:59.311 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.292019ms
2019-08-28 16:16:59.312 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=3.111934ms
2019-08-28 16:16:59.312 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.7776419999999997
2019-08-28 16:16:59.448 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:16:59.448 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:16:59.450 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:16:59.452 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.526372
2019-08-28 16:17:07.255 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:07.257 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:17:07.258 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:17:07.260 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.418741000000001
2019-08-28 16:17:07.452 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:07.452 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:07.453 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:07.455 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.708826
2019-08-28 16:17:09.877 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:09.877 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:17:09.877 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:17:09.877 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:17:09.878 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:17:09.880 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.462528ms
2019-08-28 16:17:09.882 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=4.314103ms
2019-08-28 16:17:09.882 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.825098
2019-08-28 16:17:20.553 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:20.553 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:17:20.553 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:17:20.557 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:17:20.557 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:17:20.559 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.28975ms
2019-08-28 16:17:20.559 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.125752ms
2019-08-28 16:17:20.559 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.271752
2019-08-28 16:17:23.251 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:23.251 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:17:23.253 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:17:23.255 [WARNING][64] table.go 508: Detected out-of-sync inserts, marking for resync actualRuleIDs=[]string{"", "6gwbT8clXdHdC1b1"} chainName="PREROUTING" expectedRuleIDs=[]string{"6gwbT8clXdHdC1b1", ""} ipVersion=0x4 table="nat"
2019-08-28 16:17:23.255 [WARNING][64] table.go 508: Detected out-of-sync inserts, marking for resync actualRuleIDs=[]string{"", "tVnHkvAo15HuiPy0"} chainName="OUTPUT" expectedRuleIDs=[]string{"tVnHkvAo15HuiPy0", ""} ipVersion=0x4 table="nat"
2019-08-28 16:17:23.257 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.106196000000001
2019-08-28 16:17:23.453 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:23.454 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:23.456 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:23.458 [WARNING][64] table.go 508: Detected out-of-sync inserts, marking for resync actualRuleIDs=[]string{"", "Cz_u1IQiXIMmKD4c", ""} chainName="INPUT" expectedRuleIDs=[]string{"Cz_u1IQiXIMmKD4c", "", ""} ipVersion=0x4 table="filter"
2019-08-28 16:17:23.458 [WARNING][64] table.go 508: Detected out-of-sync inserts, marking for resync actualRuleIDs=[]string{"", "wUHhoiAYhphO9Mso"} chainName="FORWARD" expectedRuleIDs=[]string{"wUHhoiAYhphO9Mso", ""} ipVersion=0x4 table="filter"
2019-08-28 16:17:23.458 [WARNING][64] table.go 508: Detected out-of-sync inserts, marking for resync actualRuleIDs=[]string{"", "tVnHkvAo15HuiPy0", ""} chainName="OUTPUT" expectedRuleIDs=[]string{"tVnHkvAo15HuiPy0", "", ""} ipVersion=0x4 table="filter"
2019-08-28 16:17:23.459 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.860455
2019-08-28 16:17:24.263 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:24.264 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:17:24.265 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:17:24.268 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.415506
2019-08-28 16:17:24.464 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:24.464 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:24.466 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:24.469 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.761616
2019-08-28 16:17:25.262 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:25.262 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:17:25.264 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:17:25.267 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.1759520000000006
2019-08-28 16:17:25.465 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:25.465 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:25.466 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:25.468 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.4342460000000004
2019-08-28 16:17:27.261 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:27.261 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:17:27.263 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:17:27.267 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.916062
2019-08-28 16:17:27.465 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:27.465 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:27.466 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:27.468 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.654141
2019-08-28 16:17:30.067 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali98126f39d4b"
2019-08-28 16:17:30.067 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali98126f39d4b", Addrs:set.mapSet{}}
2019-08-28 16:17:30.067 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali98126f39d4b", Addrs:set.mapSet{}}
2019-08-28 16:17:30.067 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali98126f39d4b", Addrs:set.mapSet{}}
2019-08-28 16:17:30.067 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.067 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=0.224612
2019-08-28 16:17:30.068 [INFO][64] int_dataplane.go 471: Linux interface state changed. ifaceName="cali98126f39d4b" state="up"
2019-08-28 16:17:30.069 [INFO][64] int_dataplane.go 645: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali98126f39d4b", State:"up"}
2019-08-28 16:17:30.069 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.069 [INFO][64] endpoint_mgr.go 253: Workload interface came up, marking for reconfiguration. ifaceName="cali98126f39d4b"
2019-08-28 16:17:30.069 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali98126f39d4b"
2019-08-28 16:17:30.069 [INFO][64] endpoint_mgr.go 253: Workload interface came up, marking for reconfiguration. ifaceName="cali98126f39d4b"
2019-08-28 16:17:30.069 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali98126f39d4b"
2019-08-28 16:17:30.070 [INFO][64] route_table.go 442: Syncing routes: found unexpected route; ignoring due to grace period. dest=192.168.112.64/32 ifaceName="cali98126f39d4b" ipVersion=0x4
2019-08-28 16:17:30.070 [INFO][64] route_table.go 298: Interface in cleanup grace period, will retry after. ifaceName="cali98126f39d4b" ipVersion=0x4
2019-08-28 16:17:30.070 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=0.927963
2019-08-28 16:17:30.070 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}} ifaceName="cali98126f39d4b"
2019-08-28 16:17:30.070 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali98126f39d4b", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2019-08-28 16:17:30.070 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali98126f39d4b", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2019-08-28 16:17:30.070 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali98126f39d4b", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2019-08-28 16:17:30.070 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.070 [INFO][64] route_table.go 442: Syncing routes: found unexpected route; ignoring due to grace period. dest=192.168.112.64/32 ifaceName="cali98126f39d4b" ipVersion=0x4
2019-08-28 16:17:30.071 [INFO][64] route_table.go 298: Interface in cleanup grace period, will retry after. ifaceName="cali98126f39d4b" ipVersion=0x4
2019-08-28 16:17:30.071 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=0.6852950000000001
2019-08-28 16:17:30.093 [INFO][64] calc_graph.go 361: Local endpoint updated id=WorkloadEndpoint(node=cmp002, orchestrator=k8s, workload=netchecker/netchecker-agent-pk6cn, name=eth0)
2019-08-28 16:17:30.093 [INFO][64] int_dataplane.go 630: Received *proto.ActiveProfileUpdate update from calculation graph msg=id:<name:"calico-k8s-network" > profile:<inbound_rules:<action:"allow" rule_id:"YLdFWiauLlZiqqnX" > outbound_rules:<action:"allow" rule_id:"s12JEevpe1A9nAkJ" > > 
2019-08-28 16:17:30.094 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-pri-calico-k8s-network" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.094 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="chain update" table="filter"
2019-08-28 16:17:30.094 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-pro-calico-k8s-network" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.094 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-pri-calico-k8s-network" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.094 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="chain update" table="filter"
2019-08-28 16:17:30.094 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-pro-calico-k8s-network" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.095 [INFO][64] int_dataplane.go 630: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"netchecker/netchecker-agent-pk6cn" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali98126f39d4b" mac:"0a:14:72:d7:7c:93" profile_ids:"calico-k8s-network" ipv4_nets:"192.168.112.64/32" > 
2019-08-28 16:17:30.095 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.095 [INFO][64] endpoint_mgr.go 408: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:30.095 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-tw-cali98126f39d4b" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.095 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fw-cali98126f39d4b" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.095 [INFO][64] endpoint_mgr.go 435: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:30.095 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.095 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.095 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali98126f39d4b"
2019-08-28 16:17:30.096 [INFO][64] endpoint_mgr.go 350: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:30.096 [INFO][64] status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:30.096 [INFO][64] endpoint_mgr.go 408: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:30.096 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-tw-cali98126f39d4b" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.096 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fw-cali98126f39d4b" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.096 [INFO][64] endpoint_mgr.go 435: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:30.096 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.096 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.096 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali98126f39d4b"
2019-08-28 16:17:30.096 [INFO][64] endpoint_mgr.go 350: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:30.096 [INFO][64] status_combiner.go 58: Storing endpoint status update ipVersion=0x6 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:30.097 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:30.099 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:30.154 [INFO][64] status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"} ipVersion=0x4 status="up"
2019-08-28 16:17:30.154 [INFO][64] status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"} status="up"
2019-08-28 16:17:30.154 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=59.097831000000006
2019-08-28 16:17:30.316 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{} ifaceName="cali93deac07437"
2019-08-28 16:17:30.316 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali93deac07437", Addrs:set.mapSet{}}
2019-08-28 16:17:30.317 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali93deac07437", Addrs:set.mapSet{}}
2019-08-28 16:17:30.317 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali93deac07437", Addrs:set.mapSet{}}
2019-08-28 16:17:30.317 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.317 [INFO][64] int_dataplane.go 471: Linux interface state changed. ifaceName="cali93deac07437" state="up"
2019-08-28 16:17:30.317 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=0.209847
2019-08-28 16:17:30.317 [INFO][64] int_dataplane.go 645: Received interface update msg=&intdataplane.ifaceUpdate{Name:"cali93deac07437", State:"up"}
2019-08-28 16:17:30.317 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.317 [INFO][64] endpoint_mgr.go 253: Workload interface came up, marking for reconfiguration. ifaceName="cali93deac07437"
2019-08-28 16:17:30.317 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali93deac07437"
2019-08-28 16:17:30.317 [INFO][64] int_dataplane.go 504: Linux interface addrs changed. addrs=set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}} ifaceName="cali93deac07437"
2019-08-28 16:17:30.318 [INFO][64] endpoint_mgr.go 253: Workload interface came up, marking for reconfiguration. ifaceName="cali93deac07437"
2019-08-28 16:17:30.318 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali93deac07437"
2019-08-28 16:17:30.320 [INFO][64] route_table.go 442: Syncing routes: found unexpected route; ignoring due to grace period. dest=192.168.112.65/32 ifaceName="cali93deac07437" ipVersion=0x4
2019-08-28 16:17:30.320 [INFO][64] route_table.go 298: Interface in cleanup grace period, will retry after. ifaceName="cali93deac07437" ipVersion=0x4
2019-08-28 16:17:30.321 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.800854
2019-08-28 16:17:30.321 [INFO][64] int_dataplane.go 660: Received interface addresses update msg=&intdataplane.ifaceAddrsUpdate{Name:"cali93deac07437", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2019-08-28 16:17:30.321 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali93deac07437", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2019-08-28 16:17:30.321 [INFO][64] hostip_mgr.go 84: Interface addrs changed. update=&intdataplane.ifaceAddrsUpdate{Name:"cali93deac07437", Addrs:set.mapSet{"fe80::ecee:eeff:feee:eeee":set.empty{}}}
2019-08-28 16:17:30.321 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.322 [INFO][64] route_table.go 442: Syncing routes: found unexpected route; ignoring due to grace period. dest=192.168.112.65/32 ifaceName="cali93deac07437" ipVersion=0x4
2019-08-28 16:17:30.322 [INFO][64] route_table.go 298: Interface in cleanup grace period, will retry after. ifaceName="cali93deac07437" ipVersion=0x4
2019-08-28 16:17:30.322 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=0.423284
2019-08-28 16:17:30.341 [INFO][64] calc_graph.go 361: Local endpoint updated id=WorkloadEndpoint(node=cmp002, orchestrator=k8s, workload=kube-system/coredns-7f8f94c97b-lwlss, name=eth0)
2019-08-28 16:17:30.341 [INFO][64] int_dataplane.go 630: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kube-system/coredns-7f8f94c97b-lwlss" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali93deac07437" mac:"f6:de:37:b7:16:68" profile_ids:"calico-k8s-network" ipv4_nets:"192.168.112.65/32" > 
2019-08-28 16:17:30.341 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.341 [INFO][64] endpoint_mgr.go 408: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:30.341 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-tw-cali93deac07437" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.341 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="chain update" table="filter"
2019-08-28 16:17:30.341 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fw-cali93deac07437" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.341 [INFO][64] endpoint_mgr.go 435: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:30.341 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.341 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x4 table="filter"
2019-08-28 16:17:30.341 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali93deac07437"
2019-08-28 16:17:30.342 [INFO][64] endpoint_mgr.go 350: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:30.342 [INFO][64] status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:30.342 [INFO][64] endpoint_mgr.go 408: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:30.342 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-tw-cali93deac07437" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.342 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="chain update" table="filter"
2019-08-28 16:17:30.342 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fw-cali93deac07437" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.342 [INFO][64] endpoint_mgr.go 435: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:30.342 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-from-wl-dispatch" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.342 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-to-wl-dispatch" ipVersion=0x6 table="filter"
2019-08-28 16:17:30.342 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali93deac07437"
2019-08-28 16:17:30.342 [INFO][64] endpoint_mgr.go 350: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:30.342 [INFO][64] status_combiner.go 58: Storing endpoint status update ipVersion=0x6 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:30.346 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:30.347 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:30.351 [INFO][64] status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"} ipVersion=0x6 status="up"
2019-08-28 16:17:30.352 [INFO][64] status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"} status="up"
2019-08-28 16:17:30.352 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=10.654378000000001
2019-08-28 16:17:30.870 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:30.870 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:17:30.870 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:17:30.870 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:17:30.870 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:17:30.872 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.685243ms
2019-08-28 16:17:30.872 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.925955ms
2019-08-28 16:17:30.872 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.4769900000000002
2019-08-28 16:17:31.258 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:31.258 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:17:31.261 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:17:31.264 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.832302
2019-08-28 16:17:31.356 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:31.356 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:31.356 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:17:31.358 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:31.360 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:31.362 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.12783
2019-08-28 16:17:31.951 [INFO][64] iface_monitor.go 176: Netlink address update. addr="fe80::ecee:eeff:feee:eeee" exists=true ifIndex=9
2019-08-28 16:17:31.983 [INFO][64] iface_monitor.go 176: Netlink address update. addr="fe80::ecee:eeff:feee:eeee" exists=true ifIndex=8
2019-08-28 16:17:32.356 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:32.356 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:17:32.356 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:32.357 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:32.360 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:32.362 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.990243
2019-08-28 16:17:34.356 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:34.356 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:34.356 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:17:34.357 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:34.358 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:34.361 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.402451
2019-08-28 16:17:38.355 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:38.355 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:17:38.355 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:38.356 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:38.357 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:38.359 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.966632
2019-08-28 16:17:39.261 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:39.262 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:17:39.264 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:17:39.268 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.1239110000000005
2019-08-28 16:17:41.189 [INFO][64] calc_graph.go 361: Local endpoint updated id=WorkloadEndpoint(node=cmp002, orchestrator=k8s, workload=kube-system/coredns-7f8f94c97b-lwlss, name=eth0)
2019-08-28 16:17:41.189 [INFO][64] int_dataplane.go 630: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"kube-system/coredns-7f8f94c97b-lwlss" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali93deac07437" mac:"f6:de:37:b7:16:68" profile_ids:"calico-k8s-network" ipv4_nets:"192.168.112.65/32" > 
2019-08-28 16:17:41.189 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:41.190 [INFO][64] endpoint_mgr.go 408: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:41.190 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-tw-cali93deac07437" ipVersion=0x4 table="filter"
2019-08-28 16:17:41.190 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="chain update" table="filter"
2019-08-28 16:17:41.190 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fw-cali93deac07437" ipVersion=0x4 table="filter"
2019-08-28 16:17:41.190 [INFO][64] endpoint_mgr.go 435: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:41.190 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali93deac07437"
2019-08-28 16:17:41.190 [INFO][64] endpoint_mgr.go 350: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:41.190 [INFO][64] status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:41.191 [INFO][64] endpoint_mgr.go 408: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:41.191 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-tw-cali93deac07437" ipVersion=0x6 table="filter"
2019-08-28 16:17:41.191 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="chain update" table="filter"
2019-08-28 16:17:41.191 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fw-cali93deac07437" ipVersion=0x6 table="filter"
2019-08-28 16:17:41.191 [INFO][64] endpoint_mgr.go 435: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:41.194 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali93deac07437"
2019-08-28 16:17:41.195 [INFO][64] endpoint_mgr.go 350: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:41.195 [INFO][64] status_combiner.go 58: Storing endpoint status update ipVersion=0x6 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"}
2019-08-28 16:17:41.197 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:41.199 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:41.201 [INFO][64] status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"} ipVersion=0x4 status="up"
2019-08-28 16:17:41.201 [INFO][64] status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"kube-system/coredns-7f8f94c97b-lwlss", EndpointId:"eth0"} status="up"
2019-08-28 16:17:41.201 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=11.222999000000002
2019-08-28 16:17:41.287 [INFO][64] calc_graph.go 361: Local endpoint updated id=WorkloadEndpoint(node=cmp002, orchestrator=k8s, workload=netchecker/netchecker-agent-pk6cn, name=eth0)
2019-08-28 16:17:41.287 [INFO][64] int_dataplane.go 630: Received *proto.WorkloadEndpointUpdate update from calculation graph msg=id:<orchestrator_id:"k8s" workload_id:"netchecker/netchecker-agent-pk6cn" endpoint_id:"eth0" > endpoint:<state:"active" name:"cali98126f39d4b" mac:"0a:14:72:d7:7c:93" profile_ids:"calico-k8s-network" ipv4_nets:"192.168.112.64/32" > 
2019-08-28 16:17:41.287 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:41.287 [INFO][64] endpoint_mgr.go 408: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:41.288 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-tw-cali98126f39d4b" ipVersion=0x4 table="filter"
2019-08-28 16:17:41.288 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="chain update" table="filter"
2019-08-28 16:17:41.288 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fw-cali98126f39d4b" ipVersion=0x4 table="filter"
2019-08-28 16:17:41.288 [INFO][64] endpoint_mgr.go 435: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:41.288 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali98126f39d4b"
2019-08-28 16:17:41.288 [INFO][64] endpoint_mgr.go 350: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:41.288 [INFO][64] status_combiner.go 58: Storing endpoint status update ipVersion=0x4 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:41.288 [INFO][64] endpoint_mgr.go 408: Updating per-endpoint chains. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:41.288 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-tw-cali98126f39d4b" ipVersion=0x6 table="filter"
2019-08-28 16:17:41.289 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="chain update" table="filter"
2019-08-28 16:17:41.289 [INFO][64] table.go 417: Queueing update of chain. chainName="cali-fw-cali98126f39d4b" ipVersion=0x6 table="filter"
2019-08-28 16:17:41.289 [INFO][64] endpoint_mgr.go 435: Updating endpoint routes. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:41.289 [INFO][64] endpoint_mgr.go 811: Applying /proc/sys configuration to interface. ifaceName="cali98126f39d4b"
2019-08-28 16:17:41.289 [INFO][64] endpoint_mgr.go 350: Re-evaluated workload endpoint status adminUp=true failed=false known=true operUp=true status="up" workloadEndpointID=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:41.289 [INFO][64] status_combiner.go 58: Storing endpoint status update ipVersion=0x6 status="up" workload=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"}
2019-08-28 16:17:41.291 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:41.293 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:41.294 [INFO][64] status_combiner.go 81: Endpoint up for at least one IP version id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"} ipVersion=0x4 status="up"
2019-08-28 16:17:41.294 [INFO][64] status_combiner.go 98: Reporting combined status. id=proto.WorkloadEndpointID{OrchestratorId:"k8s", WorkloadId:"netchecker/netchecker-agent-pk6cn", EndpointId:"eth0"} status="up"
2019-08-28 16:17:41.294 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.838801999999999
2019-08-28 16:17:41.686 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:41.686 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:17:41.686 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:17:41.686 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:17:41.686 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:17:41.688 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.507106ms
2019-08-28 16:17:41.688 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.109612ms
2019-08-28 16:17:41.689 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.5556039999999998
2019-08-28 16:17:46.350 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:46.350 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:17:46.351 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:17:46.353 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.3717119999999996
2019-08-28 16:17:46.355 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:46.355 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:17:46.358 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:17:46.360 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.805742
2019-08-28 16:17:51.795 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:51.795 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:17:51.795 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:17:51.795 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:17:51.795 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:17:51.798 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.647165ms
2019-08-28 16:17:51.798 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.720798ms
2019-08-28 16:17:51.798 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.491163
2019-08-28 16:17:52.734 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:52.734 [INFO][64] route_table.go 189: Queueing a resync of routing table. ipVersion=0x4
2019-08-28 16:17:52.734 [INFO][64] route_table.go 189: Queueing a resync of routing table. ipVersion=0x6
2019-08-28 16:17:52.738 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.512818
2019-08-28 16:17:55.261 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:17:55.261 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:17:55.264 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:17:55.268 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.718252
2019-08-28 16:18:02.356 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:02.357 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:18:02.357 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:18:02.360 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:18:02.364 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:18:02.367 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=10.769147
2019-08-28 16:18:02.518 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:02.519 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:18:02.519 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:18:02.519 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:18:02.519 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:18:02.521 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.02844ms
2019-08-28 16:18:02.522 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=3.153035ms
2019-08-28 16:18:02.523 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.9989429999999997
2019-08-28 16:18:13.205 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:13.206 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:18:13.206 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:18:13.206 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:18:13.206 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:18:13.208 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.147043ms
2019-08-28 16:18:13.209 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.506578ms
2019-08-28 16:18:13.209 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.328047
2019-08-28 16:18:21.042 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:21.042 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="refresh timer" table="raw"
2019-08-28 16:18:21.044 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:18:21.046 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.803406
2019-08-28 16:18:21.068 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:21.068 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="refresh timer" table="nat"
2019-08-28 16:18:21.069 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:18:21.071 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.4095929999999997
2019-08-28 16:18:21.259 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:21.260 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="refresh timer" table="raw"
2019-08-28 16:18:21.261 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:18:21.263 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.9401490000000003
2019-08-28 16:18:21.292 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:21.292 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="refresh timer" table="mangle"
2019-08-28 16:18:21.294 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:18:21.297 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.605008
2019-08-28 16:18:21.441 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:21.442 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="refresh timer" table="mangle"
2019-08-28 16:18:21.443 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:18:21.444 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.2561
2019-08-28 16:18:23.859 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:23.860 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:18:23.860 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:18:23.860 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:18:23.860 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:18:23.862 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.19064ms
2019-08-28 16:18:23.863 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.167228ms
2019-08-28 16:18:23.863 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.661227
2019-08-28 16:18:27.257 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:27.258 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:18:27.259 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:18:27.261 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.43323
2019-08-28 16:18:33.863 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:33.863 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:18:33.863 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:18:33.863 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:18:33.864 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:18:33.866 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.752295ms
2019-08-28 16:18:33.866 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.498598ms
2019-08-28 16:18:33.867 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.500736
2019-08-28 16:18:34.350 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:34.350 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:18:34.353 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:18:34.356 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.257792
2019-08-28 16:18:34.358 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:34.358 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:18:34.359 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:18:34.362 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.9267399999999997
2019-08-28 16:18:44.807 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:44.807 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:18:44.807 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:18:44.807 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:18:44.807 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:18:44.810 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.698488ms
2019-08-28 16:18:44.810 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=3.21179ms
2019-08-28 16:18:44.811 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.907551
2019-08-28 16:18:55.412 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:55.412 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:18:55.412 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:18:55.413 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:18:55.413 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:18:55.414 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.235667ms
2019-08-28 16:18:55.414 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.016083ms
2019-08-28 16:18:55.414 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.655753
2019-08-28 16:18:59.040 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:59.040 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="raw"
2019-08-28 16:18:59.041 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="raw"
2019-08-28 16:18:59.043 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.7207049999999997
2019-08-28 16:18:59.060 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:59.060 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="nat"
2019-08-28 16:18:59.061 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="nat"
2019-08-28 16:18:59.062 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.442265
2019-08-28 16:18:59.252 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:59.252 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="raw"
2019-08-28 16:18:59.253 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="raw"
2019-08-28 16:18:59.254 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.418421
2019-08-28 16:18:59.286 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:59.287 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="mangle"
2019-08-28 16:18:59.287 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="mangle"
2019-08-28 16:18:59.289 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.216519
2019-08-28 16:18:59.433 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:18:59.434 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="mangle"
2019-08-28 16:18:59.437 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="mangle"
2019-08-28 16:18:59.439 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=5.765887
2019-08-28 16:19:05.808 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:05.809 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:19:05.809 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:19:05.809 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:19:05.809 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:19:05.811 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.661604ms
2019-08-28 16:19:05.812 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.828039ms
2019-08-28 16:19:05.812 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.216269
2019-08-28 16:19:15.963 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:15.963 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:19:15.963 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:19:15.963 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:19:15.963 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:19:15.964 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.385555ms
2019-08-28 16:19:15.965 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.85319ms
2019-08-28 16:19:15.965 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.5939180000000004
2019-08-28 16:19:26.394 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:26.394 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:19:26.394 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:19:26.394 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:19:26.394 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:19:26.395 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.412069ms
2019-08-28 16:19:26.396 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.838467ms
2019-08-28 16:19:26.396 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.1550800000000003
2019-08-28 16:19:26.832 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:26.832 [INFO][64] route_table.go 189: Queueing a resync of routing table. ipVersion=0x4
2019-08-28 16:19:26.832 [INFO][64] route_table.go 189: Queueing a resync of routing table. ipVersion=0x6
2019-08-28 16:19:26.835 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.3925829999999997
2019-08-28 16:19:31.261 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:31.261 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="nat"
2019-08-28 16:19:31.264 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="nat"
2019-08-28 16:19:31.269 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=7.878094000000001
2019-08-28 16:19:36.660 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:36.661 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:19:36.661 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:19:36.661 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:19:36.661 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:19:36.662 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.419038ms
2019-08-28 16:19:36.663 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.407484ms
2019-08-28 16:19:36.663 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.915448
2019-08-28 16:19:38.351 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:38.352 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x4 reason="post update" table="filter"
2019-08-28 16:19:38.352 [INFO][64] table.go 740: Invalidating dataplane cache ipVersion=0x6 reason="post update" table="filter"
2019-08-28 16:19:38.354 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x4 table="filter"
2019-08-28 16:19:38.355 [INFO][64] table.go 460: Loading current iptables state and checking it is correct. ipVersion=0x6 table="filter"
2019-08-28 16:19:38.358 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=6.650878
2019-08-28 16:19:43.809 [INFO][64] usagerep.go 106: Initial delay complete, doing first report
2019-08-28 16:19:43.810 [INFO][64] usagerep.go 185: Reporting cluster usage/checking for deprecation warnings. alpEnabled=false calicoVersion="v3.3.2" clusterGUID="236357e2b4f74b22a557c3c0ddeb3a84" clusterType="k8s,bgp" gitRevision="1edd26adb1cfe5c6ff437467d429608428fbd1fe" stats=calc.StatsUpdate{NumHosts:3, NumWorkloadEndpoints:6, NumHostEndpoints:0, NumPolicies:0, NumProfiles:5, NumALPPolicies:0} version="v3.3.2"
2019-08-28 16:19:46.805 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:46.806 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:19:46.806 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:19:46.806 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:19:46.806 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:19:46.809 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.124863ms
2019-08-28 16:19:46.809 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=2.24251ms
2019-08-28 16:19:46.809 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=3.145938
2019-08-28 16:19:48.811 [INFO][64] usagerep.go 151: Failed to report usage/get deprecation warnings. error=Get https://usage.projectcalico.org/UsageCheck/calicoVersionCheck?alp=false&alp_policies=0&cal_ver=v3.3.2&guid=236357e2b4f74b22a557c3c0ddeb3a84&heps=0&policies=0&profiles=5&rev=1edd26adb1cfe5c6ff437467d429608428fbd1fe&size=3&type=k8s%2Cbgp&version=v3.3.2&weps=6: net/http: request canceled (Client.Timeout exceeded while awaiting headers)
2019-08-28 16:19:48.811 [INFO][64] usagerep.go 108: First report done, starting ticker
2019-08-28 16:19:57.341 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:19:57.341 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:19:57.341 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:19:57.341 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:19:57.341 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:19:57.343 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.168797ms
2019-08-28 16:19:57.343 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.39808ms
2019-08-28 16:19:57.343 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=1.6915310000000001
2019-08-28 16:20:08.153 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:20:08.153 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:20:08.153 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:20:08.153 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:20:08.153 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:20:08.155 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=2.076159ms
2019-08-28 16:20:08.156 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=3.383127ms
2019-08-28 16:20:08.157 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=4.065883
2019-08-28 16:20:18.403 [INFO][64] int_dataplane.go 751: Applying dataplane updates
2019-08-28 16:20:18.403 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet"
2019-08-28 16:20:18.403 [INFO][64] ipsets.go 223: Asked to resync with the dataplane on next update. family="inet6"
2019-08-28 16:20:18.404 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet"
2019-08-28 16:20:18.404 [INFO][64] ipsets.go 254: Resyncing ipsets with dataplane. family="inet6"
2019-08-28 16:20:18.405 [INFO][64] ipsets.go 304: Finished resync family="inet6" numInconsistenciesFound=0 resyncDuration=1.382009ms
2019-08-28 16:20:18.406 [INFO][64] ipsets.go 304: Finished resync family="inet" numInconsistenciesFound=0 resyncDuration=1.996666ms
2019-08-28 16:20:18.406 [INFO][64] int_dataplane.go 765: Finished applying updates to dataplane. msecToApply=2.4132670000000003
