Skip to content

Instantly share code, notes, and snippets.

@pingles
Last active December 1, 2016 16:20
Show Gist options
  • Save pingles/49a96cc97042c3060b1111880e67fb0e to your computer and use it in GitHub Desktop.
Save pingles/49a96cc97042c3060b1111880e67fb0e to your computer and use it in GitHub Desktop.
# /etc/cni/net.d/10-canal.conf
{
"name": "canal",
"type": "flannel",
"delegate": {
"type": "calico",
"etcd_endpoints": "...",
"log_level": "info",
"policy": {
"type": "k8s",
"k8s_api_root": "https://100.64.0.1:443",
"k8s_auth_token": "..."
},
"kubernetes": {
"kubeconfig": "/etc/cni/net.d/calico-kubeconfig"
}
}
}
Waiting for etcd connection...
No IP provided. Using detected IP: 172.20.12.114
CALICO_NETWORKING is false - disable BIRD and Confd
Calico node started successfully
2016-12-01 15:13:17,347 [INFO][23/2] calico.common 309: Logging initialized
2016-12-01 15:13:17,348 [INFO][23/2] calico.felix.config 486: Parameter InterfacePrefix (Interface name prefix) has value 'cali' read from Global etcd configuration
2016-12-01 15:13:17,348 [INFO][23/2] calico.felix.config 486: Parameter LogSeveritySys (Log severity for logging to syslog) has value 'ERROR' read from None
2016-12-01 15:13:17,348 [INFO][23/2] calico.felix.config 486: Parameter IgnoreLooseRPF (If set to true, Felix will ignore the kernel's RPF check setting. If set to false, Felix will abort if the RPF setting is not 'strict'. Should only be set to true if workloads are incapable of spoofing their source IP. (For example, unprivileged containers.)) has value False read from None
2016-12-01 15:13:17,348 [INFO][23/2] calico.felix.config 486: Parameter EtcdAddr (Address and port for etcd) has value '' read from Environment variable FELIX_ETCDADDR
2016-12-01 15:13:17,349 [INFO][23/2] calico.felix.config 486: Parameter ReportingIntervalSecs (Status reporting interval in seconds) has value 0 read from Global etcd configuration
2016-12-01 15:13:17,349 [INFO][23/2] calico.felix.config 486: Parameter IptablesMarkMask (Mask that Felix selects its IPTables Mark bits from. Should be a 32 bit hexadecimal number with at least 8 bits set, none of which clash with any other mark bits in use on the system.) has value 4278190080 read from None
2016-12-01 15:13:17,349 [INFO][23/2] calico.felix.config 486: Parameter EtcdDriverLogFilePath (Path to log file for etcd driver) has value '/var/log/calico/felix-etcd.log' read from None
2016-12-01 15:13:17,349 [INFO][23/2] calico.felix.config 486: Parameter EtcdCaFile (Path to etcd CA certificate file) has value '' read from Environment variable FELIX_ETCDCAFILE
2016-12-01 15:13:17,349 [INFO][23/2] calico.felix.config 486: Parameter IpInIpEnabled (IP-in-IP device support enabled) has value False read from Global etcd configuration
2016-12-01 15:13:17,349 [INFO][23/2] calico.felix.config 486: Parameter LogSeverityFile (Log severity for logging to file) has value 'None' read from Configuration file /etc/calico/felix.cfg
2016-12-01 15:13:17,350 [INFO][23/2] calico.felix.config 486: Parameter StartupCleanupDelay (Delay before cleanup starts) has value 30 read from None
2016-12-01 15:13:17,350 [INFO][23/2] calico.felix.config 486: Parameter MetadataAddr (Metadata IP address or hostname) has value 'None' read from Configuration file /etc/calico/felix.cfg
2016-12-01 15:13:17,350 [INFO][23/2] calico.felix.config 486: Parameter PeriodicResyncInterval (How often to do cleanups, seconds) has value 3600 read from None
2016-12-01 15:13:17,350 [INFO][23/2] calico.felix.config 486: Parameter IpInIpMtu (MTU to set on the IP-in-IP device) has value 1440 read from None
2016-12-01 15:13:17,350 [INFO][23/2] calico.felix.config 486: Parameter FelixHostname (Felix compute host hostname) has value 'ip-172-20-12-114' read from Environment variable FELIX_FELIXHOSTNAME
2016-12-01 15:13:17,351 [INFO][23/2] calico.felix.config 486: Parameter IptablesRefreshInterval (How often to refresh iptables state, in seconds) has value 60 read from None
2016-12-01 15:13:17,351 [INFO][23/2] calico.felix.config 486: Parameter HostInterfacePollInterval (How often (in seconds) to poll for updates to host endpoint IP addresses, or 0 to disable.) has value 10 read from None
2016-12-01 15:13:17,351 [INFO][23/2] calico.felix.config 486: Parameter PrometheusMetricsPort (Port on which to export Prometheus metrics.) has value 9091 read from None
2016-12-01 15:13:17,351 [INFO][23/2] calico.felix.config 486: Parameter LogSeverityScreen (Log severity for logging to screen) has value 'info' read from Global etcd configuration
2016-12-01 15:13:17,351 [INFO][23/2] calico.felix.config 486: Parameter DefaultEndpointToHostAction (Action to take for packets that arrive froman endpoint to the host.) has value 'RETURN' read from Host specific etcd configuration
2016-12-01 15:13:17,351 [INFO][23/2] calico.felix.config 486: Parameter IpInIpTunnelAddr (IPv4 address to set on the IP-in-IP device) has value 'none' read from None
2016-12-01 15:13:17,352 [INFO][23/2] calico.felix.config 486: Parameter IptablesGeneratorPlugin (Which IptablesGenerator Plugin to use.) has value 'default' read from None
2016-12-01 15:13:17,352 [INFO][23/2] calico.felix.config 486: Parameter ReportingTTLSecs (Status report time to live in seconds) has value 90 read from None
2016-12-01 15:13:17,352 [INFO][23/2] calico.felix.config 486: Parameter EtcdKeyFile (Path to etcd key file) has value '' read from Environment variable FELIX_ETCDKEYFILE
2016-12-01 15:13:17,352 [INFO][23/2] calico.felix.config 486: Parameter FailsafeOutboundHostPorts (Comma-separated list of numeric TCP ports to allowtraffic to from all host endpoints. Useful to avoid accidentally cutting off, for example, access to etcd. The default value allows connectivity to etcd's default ports 2379,2380,4001 and 7001.) has value [2379, 2380, 4001, 7001] read from None
2016-12-01 15:13:17,353 [INFO][23/2] calico.felix.config 486: Parameter EndpointReportingDelaySecs (Minimum delay between per-endpoint status reports) has value 1 read from None
2016-12-01 15:13:17,353 [INFO][23/2] calico.felix.config 486: Parameter ClusterGUID (Unique cluster identifier) has value '5c25f860f8b246ddba756dfdd2f731d9' read from Global etcd configuration
2016-12-01 15:13:17,353 [INFO][23/2] calico.felix.config 486: Parameter EtcdCertFile (Path to etcd certificate file) has value '' read from Environment variable FELIX_ETCDCERTFILE
2016-12-01 15:13:17,353 [INFO][23/2] calico.felix.config 486: Parameter EtcdScheme (Protocol type for http or https) has value '' read from Environment variable FELIX_ETCDSCHEME
2016-12-01 15:13:17,353 [INFO][23/2] calico.felix.config 486: Parameter PrometheusMetricsEnabled (Whether to enable prometheus metrics.) has value False read from None
2016-12-01 15:13:17,353 [INFO][23/2] calico.felix.config 486: Parameter FailsafeInboundHostPorts (Comma-separated list of numeric TCP ports to open for all configured host endpoints. Useful to avoid accidentally cutting off (for example ssh) connectivity via incorrect policy rules. The default value opens the ssh port, 22.) has value [22] read from None
2016-12-01 15:13:17,354 [INFO][23/2] calico.felix.config 486: Parameter EtcdDriverPrometheusMetricsPort (Port on which to export Prometheus metrics from the etcd driver process.) has value 9092 read from None
2016-12-01 15:13:17,354 [INFO][23/2] calico.felix.config 486: Parameter EndpointReportingEnabled (Whether Felix should report per-endpoint status into etcd) has value False read from None
2016-12-01 15:13:17,354 [INFO][23/2] calico.felix.config 486: Parameter UsageReportingEnabled (If set to true, periodically report cluster version, hostname, size and guid to projectcalico.org. Receive version deprecation / security warnings.) has value True read from None
2016-12-01 15:13:17,354 [INFO][23/2] calico.felix.config 486: Parameter EtcdEndpoints (Comma separated list of etcd endpoints, of the form scheme://address:port. For example "https://1.2.3.4:2379,https://1.2.3.5:2379". This option overrides EtcdScheme and EtcdAddr.) has value 'http://etcd.eu-west-1.kube.usw.co:2379' read from Environment variable FELIX_ETCDENDPOINTS
2016-12-01 15:13:17,354 [INFO][23/2] calico.felix.config 486: Parameter MaxIpsetSize (Maximum size of the ipsets that Felix uses to represent profile tag memberships. Should be set to a value larger than the expected number of IP addresses using a single tag.) has value 1048576 read from None
2016-12-01 15:13:17,355 [INFO][23/2] calico.felix.config 486: Parameter LogFilePath (Path to log file) has value 'None' read from Configuration file /etc/calico/felix.cfg
2016-12-01 15:13:17,355 [INFO][23/2] calico.felix.config 486: Parameter MetadataPort (Metadata Port) has value 8775 read from None
2016-12-01 15:13:17,355 [INFO][23/2] calico.felix.config 486: Parameter DropActionOverride (Override for the action taken when a packet would normally be dropped by Calico's firewall rules. This setting is useful when prototyping policy. Note: if the policy is set to 'ACCEPT' or 'LOG-and-ACCEPT'; Calico's security is disabled! One of 'DROP', 'ACCEPT', 'LOG-and-DROP', 'LOG-and-ACCEPT'.) has value 'DROP' read from None
2016-12-01 15:13:17,355 [INFO][30/139694112152240] calico.common 309: Logging initialized
2016-12-01 15:13:17,355 [INFO][23/3] calico.felix.fetcd 194: Interval is 0, status reporting disabled.
2016-12-01 15:13:17,355 [INFO][30/139694112152240] calico.etcddriver.driver 278: Received config from Felix: {'prom_port': None, 'sev_syslog': 40, 'sev_screen': 20, 'log_file': '/var/log/calico/felix-etcd.log', 'type': 'conf', 'sev_file': None}
2016-12-01 15:13:17,356 [INFO][23/4] calico.felix.futils 567: Checking for iptables
2016-12-01 15:13:17,357 [INFO][23/5] calico.felix.fetcd 166: Config loaded, resync interval 3600.
2016-12-01 15:13:17,359 [INFO][23/4] calico.felix.futils 575: iptables version: iptables v1.6.0
2016-12-01 15:13:17,359 [INFO][23/4] calico.felix.futils 577: Checking for iptables-save
2016-12-01 15:13:17,362 [INFO][30/139694112062128] calico.etcddriver.driver 374: Felix sent us the config, continuing.
2016-12-01 15:13:17,362 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: resync
2016-12-01 15:13:17,363 [INFO][30/139694112062128] calico.etcddriver.driver 453: Loading snapshot headers...
2016-12-01 15:13:17,364 [INFO][30/139694112062128] calico.etcddriver.driver 465: Got snapshot headers, snapshot index is 1692594; starting watcher...
2016-12-01 15:13:17,365 [INFO][30/139694004488880] calico.etcddriver.driver 811: Watcher thread started with next index 1692595
2016-12-01 15:13:17,365 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:13:17,366 [INFO][30/139694112062128] calico.etcddriver.hwm 94: Started tracking deletions
2016-12-01 15:13:17,370 [INFO][30/139694112062128] calico.etcddriver.hwm 105: Stopped tracking deletions
2016-12-01 15:13:17,370 [INFO][30/139694112062128] calico.etcddriver.driver 616: First resync: skipping deletion scan
2016-12-01 15:13:17,370 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: in-sync
2016-12-01 15:13:17,370 [INFO][30/139694112062128] calico.etcddriver.driver 604: In sync, now processing events only...
2016-12-01 15:13:17,370 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to resync
2016-12-01 15:13:17,371 [INFO][23/4] calico.felix.futils 585: Checking for iptables-restore
2016-12-01 15:13:17,372 [INFO][23/4] calico.felix.futils 593: Checking for ipset
2016-12-01 15:13:17,375 [INFO][23/4] calico.felix.futils 601: ipset version: ipset v6.29, protocol version: 6
2016-12-01 15:13:17,375 [INFO][23/4] calico.felix.futils 603: Checking for conntrack
2016-12-01 15:13:17,377 [INFO][23/4] calico.felix.futils 611: conntrack version: conntrack v1.4.3 (conntrack-tools)
2016-12-01 15:13:17,378 [INFO][23/4] calico.felix.felix 84: Main greenlet: Configuration loaded, starting remaining actors...
2016-12-01 15:13:17,378 [INFO][23/4] calico.felix.actor 176: IptablesUpdater(v4-filter) created.
2016-12-01 15:13:17,378 [INFO][23/4] calico.felix.fiptables 186: Periodic iptables refresh enabled, starting resync greenlet
2016-12-01 15:13:17,378 [INFO][23/4] calico.felix.actor 176: IptablesUpdater(v4-nat) created.
2016-12-01 15:13:17,379 [INFO][23/4] calico.felix.fiptables 186: Periodic iptables refresh enabled, starting resync greenlet
2016-12-01 15:13:17,379 [INFO][23/4] calico.felix.actor 176: IpsetManager(IPv4) created.
2016-12-01 15:13:17,379 [INFO][23/4] calico.felix.actor 176: MasqueradeManager(IPv4) created.
2016-12-01 15:13:17,379 [INFO][23/4] calico.felix.actor 176: RulesManager(v4) created.
2016-12-01 15:13:17,380 [INFO][23/4] calico.felix.actor 176: WorkloadDispatchChains(v4) created.
2016-12-01 15:13:17,380 [INFO][23/4] calico.felix.actor 176: HostEndpointDispatchChains(v4) created.
2016-12-01 15:13:17,380 [INFO][23/4] calico.felix.actor 176: FloatingIPManager(v4) created.
2016-12-01 15:13:17,380 [INFO][23/4] calico.felix.actor 176: EndpointManager(IPv4) created.
2016-12-01 15:13:17,407 [INFO][23/4] calico.felix.actor 176: IptablesUpdater(v6-raw) created.
2016-12-01 15:13:17,408 [INFO][23/4] calico.felix.fiptables 186: Periodic iptables refresh enabled, starting resync greenlet
2016-12-01 15:13:17,408 [INFO][23/4] calico.felix.actor 176: IptablesUpdater(v6-filter) created.
2016-12-01 15:13:17,409 [INFO][23/4] calico.felix.fiptables 186: Periodic iptables refresh enabled, starting resync greenlet
2016-12-01 15:13:17,409 [INFO][23/4] calico.felix.actor 176: IptablesUpdater(v6-nat) created.
2016-12-01 15:13:17,409 [INFO][23/4] calico.felix.fiptables 186: Periodic iptables refresh enabled, starting resync greenlet
2016-12-01 15:13:17,409 [INFO][23/4] calico.felix.actor 176: IpsetManager(IPv6) created.
2016-12-01 15:13:17,410 [INFO][23/4] calico.felix.actor 176: RulesManager(v6) created.
2016-12-01 15:13:17,410 [INFO][23/4] calico.felix.actor 176: WorkloadDispatchChains(v6) created.
2016-12-01 15:13:17,410 [INFO][23/4] calico.felix.actor 176: HostEndpointDispatchChains(v6) created.
2016-12-01 15:13:17,410 [INFO][23/4] calico.felix.actor 176: FloatingIPManager(v6) created.
2016-12-01 15:13:17,411 [INFO][23/4] calico.felix.actor 176: EndpointManager(IPv6) created.
2016-12-01 15:13:17,411 [INFO][23/4] calico.felix.actor 176: CleanupManager created.
2016-12-01 15:13:17,411 [INFO][23/4] calico.felix.actor 176: InterfaceWatcher created.
2016-12-01 15:13:17,411 [INFO][23/4] calico.felix.felix 198: Starting actors.
2016-12-01 15:13:17,411 [INFO][23/4] calico.felix.actor 235: Starting IpsetActor<None,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,412 [INFO][23/4] calico.felix.actor 235: Starting IptablesUpdater<v4-filter,queue_len=1,live=False,msg=None>
2016-12-01 15:13:17,412 [INFO][23/4] calico.felix.actor 235: Starting IptablesUpdater<v4-nat,queue_len=1,live=False,msg=None>
2016-12-01 15:13:17,412 [INFO][23/4] calico.felix.actor 235: Starting IpsetManager<IPv4,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,412 [INFO][23/4] calico.felix.actor 235: Starting MasqueradeManager<IPv4,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,413 [INFO][23/4] calico.felix.actor 235: Starting RulesManager<v4,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,413 [INFO][23/4] calico.felix.actor 235: Starting WorkloadDispatchChains<ipv4,entries=0>
2016-12-01 15:13:17,413 [INFO][23/4] calico.felix.actor 235: Starting HostEndpointDispatchChains<ipv4,entries=0>
2016-12-01 15:13:17,413 [INFO][23/4] calico.felix.actor 235: Starting EndpointManager<IPv4,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,414 [INFO][23/4] calico.felix.actor 235: Starting FloatingIPManager<v4,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,414 [INFO][23/4] calico.felix.actor 235: Starting IptablesUpdater<v6-raw,queue_len=1,live=False,msg=None>
2016-12-01 15:13:17,414 [INFO][23/4] calico.felix.actor 235: Starting IptablesUpdater<v6-filter,queue_len=1,live=False,msg=None>
2016-12-01 15:13:17,414 [INFO][23/4] calico.felix.actor 235: Starting IptablesUpdater<v6-nat,queue_len=1,live=False,msg=None>
2016-12-01 15:13:17,415 [INFO][23/4] calico.felix.actor 235: Starting IpsetManager<IPv6,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,415 [INFO][23/4] calico.felix.actor 235: Starting RulesManager<v6,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,415 [INFO][23/4] calico.felix.actor 235: Starting WorkloadDispatchChains<ipv6,entries=0>
2016-12-01 15:13:17,415 [INFO][23/4] calico.felix.actor 235: Starting HostEndpointDispatchChains<ipv6,entries=0>
2016-12-01 15:13:17,415 [INFO][23/4] calico.felix.actor 235: Starting EndpointManager<IPv6,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,416 [INFO][23/4] calico.felix.actor 235: Starting FloatingIPManager<v6,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,416 [INFO][23/4] calico.felix.actor 235: Starting CleanupManager<None,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,416 [INFO][23/4] calico.felix.actor 235: Starting InterfaceWatcher<None,queue_len=0,live=False,msg=None>
2016-12-01 15:13:17,416 [INFO][23/4] calico.felix.frules 223: Running conntrack command to force load of nf_conntrack_netlink module.
2016-12-01 15:13:17,417 [INFO][23/6] calico.felix.actor 247: Main loop for actor IpsetActor<None,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,418 [INFO][23/7] calico.felix.actor 247: Main loop for actor IptablesUpdater<v4-filter,queue_len=1,live=True,msg=None> started.
2016-12-01 15:13:17,420 [INFO][23/8] calico.felix.actor 247: Main loop for actor IptablesUpdater<v4-nat,queue_len=1,live=True,msg=None> started.
2016-12-01 15:13:17,426 [INFO][23/9] calico.felix.actor 247: Main loop for actor IpsetManager<IPv4,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,426 [INFO][23/10] calico.felix.actor 247: Main loop for actor MasqueradeManager<IPv4,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,427 [INFO][23/11] calico.felix.actor 247: Main loop for actor RulesManager<v4,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,427 [INFO][23/12] calico.felix.actor 247: Main loop for actor WorkloadDispatchChains<ipv4,entries=0> started.
2016-12-01 15:13:17,427 [INFO][23/13] calico.felix.actor 247: Main loop for actor HostEndpointDispatchChains<ipv4,entries=0> started.
2016-12-01 15:13:17,428 [INFO][23/14] calico.felix.actor 247: Main loop for actor EndpointManager<IPv4,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,428 [INFO][23/14] calico.felix.endpoint 108: Endpoint manager started, spawning interface poll worker.
2016-12-01 15:13:17,428 [INFO][23/15] calico.felix.actor 247: Main loop for actor FloatingIPManager<v4,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,433 [INFO][23/16] calico.felix.actor 247: Main loop for actor IptablesUpdater<v6-raw,queue_len=1,live=True,msg=None> started.
2016-12-01 15:13:17,437 [INFO][23/17] calico.felix.actor 247: Main loop for actor IptablesUpdater<v6-filter,queue_len=1,live=True,msg=None> started.
2016-12-01 15:13:17,442 [INFO][23/18] calico.felix.actor 247: Main loop for actor IptablesUpdater<v6-nat,queue_len=1,live=True,msg=None> started.
2016-12-01 15:13:17,448 [INFO][23/19] calico.felix.actor 247: Main loop for actor IpsetManager<IPv6,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,449 [INFO][23/20] calico.felix.actor 247: Main loop for actor RulesManager<v6,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,450 [INFO][23/21] calico.felix.actor 247: Main loop for actor WorkloadDispatchChains<ipv6,entries=0> started.
2016-12-01 15:13:17,451 [INFO][23/22] calico.felix.actor 247: Main loop for actor HostEndpointDispatchChains<ipv6,entries=0> started.
2016-12-01 15:13:17,452 [INFO][23/23] calico.felix.actor 247: Main loop for actor EndpointManager<IPv6,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,452 [INFO][23/23] calico.felix.endpoint 108: Endpoint manager started, spawning interface poll worker.
2016-12-01 15:13:17,453 [INFO][23/24] calico.felix.actor 247: Main loop for actor FloatingIPManager<v6,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,453 [INFO][23/25] calico.felix.actor 247: Main loop for actor CleanupManager<None,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,454 [INFO][23/26] calico.felix.actor 247: Main loop for actor InterfaceWatcher<None,queue_len=0,live=True,msg=None> started.
2016-12-01 15:13:17,462 [INFO][23/4] calico.felix.felix 211: Installing global rules.
2016-12-01 15:13:17,464 [INFO][23/17] calico.felix.fiptables 529: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,464 [INFO][23/16] calico.felix.fiptables 529: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,465 [INFO][23/8] calico.felix.fiptables 529: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,465 [INFO][23/7] calico.felix.fiptables 529: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,466 [INFO][23/14] calico.felix.endpoint 446: Interface lo now has IPs set([IPAddress('127.0.0.1')])
2016-12-01 15:13:17,466 [INFO][23/14] calico.felix.endpoint 446: Interface docker0 now has IPs set([IPAddress('172.17.0.1')])
2016-12-01 15:13:17,466 [INFO][23/14] calico.felix.endpoint 446: Interface eth0 now has IPs set([IPAddress('172.20.12.114')])
2016-12-01 15:13:17,467 [INFO][23/23] calico.felix.endpoint 446: Interface lo now has IPs set([IPAddress('::1')])
2016-12-01 15:13:17,467 [INFO][23/23] calico.felix.endpoint 446: Interface docker0 now has IPs set([IPAddress('fe80::42:9ff:fe9f:4c59')])
2016-12-01 15:13:17,467 [INFO][23/23] calico.felix.endpoint 446: Interface eth0 now has IPs set([IPAddress('fe80::8fd:20ff:fe9f:f3d1')])
2016-12-01 15:13:17,468 [INFO][23/18] calico.felix.fiptables 529: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,468 [INFO][23/4] calico.felix.frules 280: IPIP disabled or no tunnel address set: removing MASQUERADE rule.
2016-12-01 15:13:17,469 [INFO][23/7] calico.felix.fiptables 275: Storing missing chain override for felix-FROM-HOST-IF
2016-12-01 15:13:17,470 [INFO][23/7] calico.felix.fiptables 275: Storing missing chain override for felix-TO-HOST-IF
2016-12-01 15:13:17,470 [INFO][23/7] calico.felix.fiptables 529: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,470 [INFO][23/8] calico.felix.fiptables 352: Removing rule 'POSTROUTING --out-interface tunl0 -m addrtype ! --src-type LOCAL --limit-iface-out -m addrtype --src-type LOCAL -j MASQUERADE'
2016-12-01 15:13:17,471 [INFO][23/8] calico.felix.fiptables 367: Ensuring rule is not present 'POSTROUTING --out-interface tunl0 -m addrtype ! --src-type LOCAL --limit-iface-out -m addrtype --src-type LOCAL -j MASQUERADE'
2016-12-01 15:13:17,472 [INFO][23/8] calico.felix.fiptables 383: Removal of rule 'POSTROUTING --out-interface tunl0 -m addrtype ! --src-type LOCAL --limit-iface-out -m addrtype --src-type LOCAL -j MASQUERADE' rejected; not present?
2016-12-01 15:13:17,472 [INFO][23/8] calico.felix.fiptables 529: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,479 [INFO][23/8] calico.felix.fiptables 248: iptables update to chains ['felix-PREROUTING', 'felix-POSTROUTING', 'felix-FIP-DNAT', 'felix-FIP-SNAT']
2016-12-01 15:13:17,481 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,483 [INFO][23/8] calico.felix.fiptables 298: Inserting rule 'PREROUTING --jump felix-PREROUTING'
2016-12-01 15:13:17,484 [INFO][23/8] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'PREROUTING --jump felix-PREROUTING' to top of chain.
2016-12-01 15:13:17,485 [INFO][23/8] calico.felix.fiptables 328: Didn't find any existing instance of rule 'PREROUTING --jump felix-PREROUTING', inserting it instead.
2016-12-01 15:13:17,486 [INFO][23/8] calico.felix.fiptables 529: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,492 [INFO][23/8] calico.felix.fiptables 298: Inserting rule 'POSTROUTING --jump felix-POSTROUTING'
2016-12-01 15:13:17,492 [INFO][23/8] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'POSTROUTING --jump felix-POSTROUTING' to top of chain.
2016-12-01 15:13:17,493 [INFO][23/8] calico.felix.fiptables 328: Didn't find any existing instance of rule 'POSTROUTING --jump felix-POSTROUTING', inserting it instead.
2016-12-01 15:13:17,495 [INFO][23/8] calico.felix.fiptables 529: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,495 [INFO][23/4] calico.felix.plugins.fiptgenerator 290: Default endpoint->host action set to RETURN, felix will apply per-endpoint policy to packets in the INPUT chain.
2016-12-01 15:13:17,497 [INFO][23/7] calico.felix.fiptables 248: iptables update to chains ['felix-OUTPUT', 'felix-FAILSAFE-OUT', 'felix-INPUT', 'felix-FORWARD', 'felix-FAILSAFE-IN']
2016-12-01 15:13:17,499 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,507 [INFO][23/7] calico.felix.fiptables 298: Inserting rule 'INPUT --jump felix-INPUT'
2016-12-01 15:13:17,507 [INFO][23/7] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'INPUT --jump felix-INPUT' to top of chain.
2016-12-01 15:13:17,508 [INFO][23/7] calico.felix.fiptables 328: Didn't find any existing instance of rule 'INPUT --jump felix-INPUT', inserting it instead.
2016-12-01 15:13:17,509 [INFO][23/7] calico.felix.fiptables 529: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,511 [INFO][23/7] calico.felix.fiptables 298: Inserting rule 'OUTPUT --jump felix-OUTPUT'
2016-12-01 15:13:17,511 [INFO][23/7] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'OUTPUT --jump felix-OUTPUT' to top of chain.
2016-12-01 15:13:17,512 [INFO][23/7] calico.felix.fiptables 328: Didn't find any existing instance of rule 'OUTPUT --jump felix-OUTPUT', inserting it instead.
2016-12-01 15:13:17,514 [INFO][23/7] calico.felix.fiptables 529: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,520 [INFO][23/7] calico.felix.fiptables 298: Inserting rule 'FORWARD --jump felix-FORWARD'
2016-12-01 15:13:17,521 [INFO][23/7] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'FORWARD --jump felix-FORWARD' to top of chain.
2016-12-01 15:13:17,522 [INFO][23/7] calico.felix.fiptables 328: Didn't find any existing instance of rule 'FORWARD --jump felix-FORWARD', inserting it instead.
2016-12-01 15:13:17,523 [INFO][23/7] calico.felix.fiptables 529: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,526 [INFO][23/17] calico.felix.fiptables 275: Storing missing chain override for felix-FROM-HOST-IF
2016-12-01 15:13:17,526 [INFO][23/17] calico.felix.fiptables 275: Storing missing chain override for felix-TO-HOST-IF
2016-12-01 15:13:17,527 [INFO][23/17] calico.felix.fiptables 529: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,527 [INFO][23/16] calico.felix.fiptables 248: iptables update to chains ['felix-PREROUTING']
2016-12-01 15:13:17,528 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,536 [INFO][23/16] calico.felix.fiptables 298: Inserting rule 'PREROUTING --in-interface cali+ --match rpfilter --invert --jump felix-PREROUTING'
2016-12-01 15:13:17,536 [INFO][23/16] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'PREROUTING --in-interface cali+ --match rpfilter --invert --jump felix-PREROUTING' to top of chain.
2016-12-01 15:13:17,537 [INFO][23/16] calico.felix.fiptables 328: Didn't find any existing instance of rule 'PREROUTING --in-interface cali+ --match rpfilter --invert --jump felix-PREROUTING', inserting it instead.
2016-12-01 15:13:17,541 [INFO][23/16] calico.felix.fiptables 529: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,543 [INFO][23/18] calico.felix.fiptables 248: iptables update to chains ['felix-PREROUTING', 'felix-POSTROUTING', 'felix-FIP-DNAT', 'felix-FIP-SNAT']
2016-12-01 15:13:17,544 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,552 [INFO][23/18] calico.felix.fiptables 298: Inserting rule 'PREROUTING --jump felix-PREROUTING'
2016-12-01 15:13:17,552 [INFO][23/18] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'PREROUTING --jump felix-PREROUTING' to top of chain.
2016-12-01 15:13:17,554 [INFO][23/18] calico.felix.fiptables 328: Didn't find any existing instance of rule 'PREROUTING --jump felix-PREROUTING', inserting it instead.
2016-12-01 15:13:17,555 [INFO][23/18] calico.felix.fiptables 529: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,557 [INFO][23/18] calico.felix.fiptables 298: Inserting rule 'POSTROUTING --jump felix-POSTROUTING'
2016-12-01 15:13:17,557 [INFO][23/18] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'POSTROUTING --jump felix-POSTROUTING' to top of chain.
2016-12-01 15:13:17,558 [INFO][23/18] calico.felix.fiptables 328: Didn't find any existing instance of rule 'POSTROUTING --jump felix-POSTROUTING', inserting it instead.
2016-12-01 15:13:17,559 [INFO][23/18] calico.felix.fiptables 529: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,560 [INFO][23/4] calico.felix.plugins.fiptgenerator 290: Default endpoint->host action set to RETURN, felix will apply per-endpoint policy to packets in the INPUT chain.
2016-12-01 15:13:17,561 [INFO][23/17] calico.felix.fiptables 248: iptables update to chains ['felix-OUTPUT', 'felix-FAILSAFE-OUT', 'felix-INPUT', 'felix-FORWARD', 'felix-FAILSAFE-IN']
2016-12-01 15:13:17,564 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,571 [INFO][23/17] calico.felix.fiptables 298: Inserting rule 'INPUT --jump felix-INPUT'
2016-12-01 15:13:17,571 [INFO][23/17] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'INPUT --jump felix-INPUT' to top of chain.
2016-12-01 15:13:17,572 [INFO][23/17] calico.felix.fiptables 328: Didn't find any existing instance of rule 'INPUT --jump felix-INPUT', inserting it instead.
2016-12-01 15:13:17,574 [INFO][23/17] calico.felix.fiptables 529: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,575 [INFO][23/17] calico.felix.fiptables 298: Inserting rule 'OUTPUT --jump felix-OUTPUT'
2016-12-01 15:13:17,576 [INFO][23/17] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'OUTPUT --jump felix-OUTPUT' to top of chain.
2016-12-01 15:13:17,577 [INFO][23/17] calico.felix.fiptables 328: Didn't find any existing instance of rule 'OUTPUT --jump felix-OUTPUT', inserting it instead.
2016-12-01 15:13:17,578 [INFO][23/17] calico.felix.fiptables 529: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,585 [INFO][23/17] calico.felix.fiptables 298: Inserting rule 'FORWARD --jump felix-FORWARD'
2016-12-01 15:13:17,585 [INFO][23/17] calico.felix.fiptables 319: Attempting to move any existing instance of rule 'FORWARD --jump felix-FORWARD' to top of chain.
2016-12-01 15:13:17,587 [INFO][23/17] calico.felix.fiptables 328: Didn't find any existing instance of rule 'FORWARD --jump felix-FORWARD', inserting it instead.
2016-12-01 15:13:17,588 [INFO][23/17] calico.felix.fiptables 529: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,589 [INFO][23/4] calico.felix.felix 226: Starting polling for interface and etcd updates.
2016-12-01 15:13:17,589 [INFO][23/4] calico.felix.felix 246: All top-level actors started, waiting on failures...
2016-12-01 15:13:17,591 [INFO][23/2] calico.felix.splitter 165: IPAM pool 192.168.0.0-16 updated
2016-12-01 15:13:17,592 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to in-sync
2016-12-01 15:13:17,595 [INFO][23/27] calico.felix.fetcd 366: Started usage report thread. Usage report interval: 86400, pre-jitter: 9.1176629329
2016-12-01 15:13:17,595 [INFO][23/27] calico.felix.fetcd 381: usage report is enabled
2016-12-01 15:13:17,595 [INFO][23/27] calico.felix.futils 648: report_usage_and_get_warnings calico_version=1.4.0, hostname=ip-172-20-12-114, guid=5c25f860f8b246ddba756dfdd2f731d9, size=2, cluster_type=NA
2016-12-01 15:13:17,598 [INFO][23/10] calico.felix.masq 66: IPAM pool 192.168.0.0-16 updated: {'cidr': '192.168.0.0/16'}
2016-12-01 15:13:17,598 [INFO][23/10] calico.felix.masq 73: Marked as dirty, looking for masq-enabled pools
2016-12-01 15:13:17,598 [INFO][23/10] calico.felix.masq 92: No masquerade-enabled pools present. Removing rules and ipsets.
2016-12-01 15:13:17,598 [INFO][23/9] calico.felix.ipsets 176: Datamodel now in sync, uncorking updates to TagIpsets
2016-12-01 15:13:17,599 [INFO][23/11] calico.felix.profilerules 85: RulesManager<v4,queue_len=0,live=True,msg=M6e9febe4b49707e9 (on_datamodel_in_sync)>: datamodel now in sync, unblocking profile startup
2016-12-01 15:13:17,599 [INFO][23/14] calico.felix.endpoint 238: EndpointManager<IPv4,queue_len=0,live=True,msg=M6e9febe4b49707ea (on_datamodel_in_sync)>: First time we've been in-sync with the datamodel,sending snapshot to DispatchChains and FIPManager.
2016-12-01 15:13:17,599 [INFO][23/19] calico.felix.ipsets 176: Datamodel now in sync, uncorking updates to TagIpsets
2016-12-01 15:13:17,600 [INFO][23/20] calico.felix.profilerules 85: RulesManager<v6,queue_len=0,live=True,msg=M6e9febe4b49707ec (on_datamodel_in_sync)>: datamodel now in sync, unblocking profile startup
2016-12-01 15:13:17,600 [INFO][23/23] calico.felix.endpoint 238: EndpointManager<IPv6,queue_len=0,live=True,msg=M6e9febe4b49707ed (on_datamodel_in_sync)>: First time we've been in-sync with the datamodel,sending snapshot to DispatchChains and FIPManager.
2016-12-01 15:13:17,601 [INFO][23/25] calico.felix.splitter 192: No cleanup scheduled, scheduling one.
2016-12-01 15:13:17,602 [INFO][23/8] calico.felix.fiptables 352: Removing rule 'POSTROUTING --match set --match-set felix-masq-ipam-pools src --match set ! --match-set felix-all-ipam-pools dst --jump MASQUERADE'
2016-12-01 15:13:17,602 [INFO][23/8] calico.felix.fiptables 367: Ensuring rule is not present 'POSTROUTING --match set --match-set felix-masq-ipam-pools src --match set ! --match-set felix-all-ipam-pools dst --jump MASQUERADE'
2016-12-01 15:13:17,603 [INFO][23/12] calico.felix.dispatch 68: Applying dispatch chains snapshot.
2016-12-01 15:13:17,603 [INFO][23/12] calico.felix.dispatch 76: Datamodel in sync, unblocking dispatch chain updates
2016-12-01 15:13:17,604 [INFO][23/12] calico.felix.dispatch 275: WorkloadDispatchChains<ipv4,entries=0> Updating dispatch chain, num entries: 0
2016-12-01 15:13:17,604 [INFO][23/13] calico.felix.dispatch 68: Applying dispatch chains snapshot.
2016-12-01 15:13:17,604 [INFO][23/13] calico.felix.dispatch 76: Datamodel in sync, unblocking dispatch chain updates
2016-12-01 15:13:17,604 [INFO][23/13] calico.felix.dispatch 275: HostEndpointDispatchChains<ipv4,entries=0> Updating dispatch chain, num entries: 0
2016-12-01 15:13:17,605 [INFO][23/15] calico.felix.fipmanager 46: Applying floating IP NAT map snapshot.
2016-12-01 15:13:17,605 [INFO][23/21] calico.felix.dispatch 68: Applying dispatch chains snapshot.
2016-12-01 15:13:17,605 [INFO][23/21] calico.felix.dispatch 76: Datamodel in sync, unblocking dispatch chain updates
2016-12-01 15:13:17,605 [INFO][23/21] calico.felix.dispatch 275: WorkloadDispatchChains<ipv6,entries=0> Updating dispatch chain, num entries: 0
2016-12-01 15:13:17,606 [INFO][23/22] calico.felix.dispatch 68: Applying dispatch chains snapshot.
2016-12-01 15:13:17,606 [INFO][23/22] calico.felix.dispatch 76: Datamodel in sync, unblocking dispatch chain updates
2016-12-01 15:13:17,606 [INFO][23/22] calico.felix.dispatch 275: HostEndpointDispatchChains<ipv6,entries=0> Updating dispatch chain, num entries: 0
2016-12-01 15:13:17,606 [INFO][23/24] calico.felix.fipmanager 46: Applying floating IP NAT map snapshot.
2016-12-01 15:13:17,608 [INFO][23/7] calico.felix.fiptables 248: iptables update to chains ['felix-FROM-ENDPOINT', 'felix-TO-ENDPOINT']
2016-12-01 15:13:17,608 [INFO][23/7] calico.felix.fiptables 407: Deleting chains set([])
2016-12-01 15:13:17,609 [INFO][23/7] calico.felix.fiptables 248: iptables update to chains ['felix-FROM-HOST-IF', 'felix-TO-HOST-IF']
2016-12-01 15:13:17,609 [INFO][23/7] calico.felix.fiptables 407: Deleting chains set([])
2016-12-01 15:13:17,610 [INFO][23/17] calico.felix.fiptables 248: iptables update to chains ['felix-FROM-ENDPOINT', 'felix-TO-ENDPOINT']
2016-12-01 15:13:17,610 [INFO][23/17] calico.felix.fiptables 407: Deleting chains set([])
2016-12-01 15:13:17,611 [INFO][23/17] calico.felix.fiptables 248: iptables update to chains ['felix-FROM-HOST-IF', 'felix-TO-HOST-IF']
2016-12-01 15:13:17,613 [INFO][23/17] calico.felix.fiptables 407: Deleting chains set([])
2016-12-01 15:13:17,614 [INFO][23/18] calico.felix.fiptables 248: iptables update to chains ['felix-FIP-DNAT', 'felix-FIP-SNAT']
2016-12-01 15:13:17,616 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,617 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,617 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,620 [INFO][23/8] calico.felix.fiptables 389: Removal of rule 'POSTROUTING --match set --match-set felix-masq-ipam-pools src --match set ! --match-set felix-all-ipam-pools dst --jump MASQUERADE' failed due to missing pre-requisite; rule must not be present.
2016-12-01 15:13:17,620 [INFO][23/8] calico.felix.fiptables 529: IptablesUpdater<v4-nat,queue_len=1,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:17,621 [INFO][23/8] calico.felix.fiptables 248: iptables update to chains ['felix-FIP-DNAT', 'felix-FIP-SNAT']
2016-12-01 15:13:17,623 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:13:17,642 [INFO][23/10] calico.felix.masq 104: Finished refreshing ipsets
2016-12-01 15:13:19,105 [INFO][23/27] calico.felix.futils 670: usage_report status=200, reply={"result": "ok"}
2016-12-01 15:13:19,106 [INFO][23/27] calico.felix.fetcd 387: Usage report interval: 86400, sleep-time: 83304.044432
2016-12-01 15:13:47,603 [INFO][23/25] calico.felix.splitter 201: Triggering a cleanup of orphaned ipsets/chains
2016-12-01 15:13:47,605 [INFO][23/7] calico.felix.fiptables 422: Cleaning up left-over iptables state.
2016-12-01 15:13:47,608 [INFO][23/7] calico.felix.fiptables 438: Graceful restart window finished, stubbing out chains: set([])
2016-12-01 15:13:47,612 [INFO][23/7] calico.felix.fiptables 470: Cleanup finished, deleted 0 chains, failed to delete these chains: set([])
2016-12-01 15:13:47,615 [INFO][23/7] calico.felix.fiptables 529: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:47,617 [INFO][23/8] calico.felix.fiptables 422: Cleaning up left-over iptables state.
2016-12-01 15:13:47,621 [INFO][23/8] calico.felix.fiptables 438: Graceful restart window finished, stubbing out chains: set([])
2016-12-01 15:13:47,624 [INFO][23/8] calico.felix.fiptables 470: Cleanup finished, deleted 0 chains, failed to delete these chains: set([])
2016-12-01 15:13:47,627 [INFO][23/8] calico.felix.fiptables 529: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:47,629 [INFO][23/17] calico.felix.fiptables 422: Cleaning up left-over iptables state.
2016-12-01 15:13:47,632 [INFO][23/17] calico.felix.fiptables 438: Graceful restart window finished, stubbing out chains: set([])
2016-12-01 15:13:47,636 [INFO][23/17] calico.felix.fiptables 470: Cleanup finished, deleted 0 chains, failed to delete these chains: set([])
2016-12-01 15:13:47,639 [INFO][23/17] calico.felix.fiptables 529: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> no updates in this batch.
2016-12-01 15:13:47,639 [INFO][23/25] calico.felix.splitter 208: iptables cleanup complete, moving on to ipsets
2016-12-01 15:13:47,641 [INFO][23/9] calico.felix.ipsets 185: Cleaning up left-over ipsets.
2016-12-01 15:13:47,643 [INFO][23/19] calico.felix.ipsets 185: Cleaning up left-over ipsets.
2016-12-01 15:13:47,644 [INFO][23/25] calico.felix.splitter 216: Invoking cleanup_complete for plugin iptables_generator
2016-12-01 15:13:47,752 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 16 in 30.4s (0.526/s)
2016-12-01 15:13:47,753 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 16 in 30.4s (0.526/s)
2016-12-01 15:13:47,753 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 31 in 30.4s (1.020/s)
2016-12-01 15:13:48,226 [INFO][30/139694004488880] calico.etcddriver.driver 945: STAT: Watcher etcd response time: 21 in 30.8s (0.681/s) min=0.772ms mean=1.111ms max=6.078ms
2016-12-01 15:13:48,227 [INFO][30/139694004488880] calico.etcddriver.driver 945: STAT: Watcher processing time: 20 in 30.8s (0.648/s) min=1.059ms mean=466.571ms max=5454.882ms
2016-12-01 15:13:48,227 [INFO][30/139694004488880] calico.etcddriver.driver 948: STAT: Watcher queue length: 1
2016-12-01 15:13:48,269 [INFO][23/2] calico.felix.splitter 107: Data for tier default updated
2016-12-01 15:13:48,269 [INFO][23/14] calico.felix.endpoint 161: Sequence of profile tiers changed, refreshing all endpoints
2016-12-01 15:13:48,270 [INFO][23/23] calico.felix.endpoint 161: Sequence of profile tiers changed, refreshing all endpoints
2016-12-01 15:13:48,272 [INFO][23/2] calico.felix.splitter 76: Profile update: default/k8s-policy-no-match
2016-12-01 15:13:48,272 [INFO][23/2] calico.felix.splitter 118: Selector for profile default/k8s-policy-no-match updated
2016-12-01 15:13:48,272 [INFO][23/2] calico.felix.splitter 87: Tags for profile k8s_ns.default updated
2016-12-01 15:13:48,273 [INFO][23/2] calico.felix.splitter 97: Profile k8s_ns.default labels updated
2016-12-01 15:13:48,273 [INFO][23/11] calico.felix.profilerules 92: Rules for profile default/k8s-policy-no-match updated.
2016-12-01 15:13:48,274 [INFO][23/20] calico.felix.profilerules 92: Rules for profile default/k8s-policy-no-match updated.
2016-12-01 15:13:48,274 [INFO][23/9] calico.felix.ipsets 227: Tags for profile k8s_ns.default updated
2016-12-01 15:13:48,274 [INFO][23/19] calico.felix.ipsets 227: Tags for profile k8s_ns.default updated
2016-12-01 15:13:48,275 [INFO][23/2] calico.felix.splitter 76: Profile update: k8s_ns.default
2016-12-01 15:13:48,276 [INFO][23/2] calico.felix.splitter 87: Tags for profile k8s_ns.kube-system updated
2016-12-01 15:13:48,280 [INFO][23/2] calico.felix.splitter 97: Profile k8s_ns.kube-system labels updated
2016-12-01 15:13:48,283 [INFO][23/11] calico.felix.profilerules 92: Rules for profile k8s_ns.default updated.
2016-12-01 15:13:48,283 [INFO][23/20] calico.felix.profilerules 92: Rules for profile k8s_ns.default updated.
2016-12-01 15:13:48,284 [INFO][23/2] calico.felix.splitter 76: Profile update: k8s_ns.kube-system
2016-12-01 15:13:48,285 [INFO][23/11] calico.felix.profilerules 92: Rules for profile k8s_ns.kube-system updated.
2016-12-01 15:13:48,285 [INFO][23/20] calico.felix.profilerules 92: Rules for profile k8s_ns.kube-system updated.
2016-12-01 15:13:48,323 [INFO][23/9] calico.felix.ipsets 227: Tags for profile k8s_ns.kube-system updated
2016-12-01 15:13:48,323 [INFO][23/19] calico.felix.ipsets 227: Tags for profile k8s_ns.kube-system updated
2016-12-01 15:14:18,290 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.5s (0.000/s)
2016-12-01 15:14:18,290 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 8 in 30.5s (0.262/s)
2016-12-01 15:14:18,290 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 8 in 30.5s (0.262/s)
2016-12-01 15:14:19,483 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:14:19,485 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:14:19,485 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:14:20,544 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:14:20,545 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:14:20,546 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:14:20,629 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:14:20,631 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:14:20,631 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:14:28,471 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:14:28,473 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:14:28,474 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:14:29,029 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:14:29,031 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:14:29,031 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:14:49,298 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:14:49,298 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:14:49,298 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:15:18,369 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:15:20,306 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:15:20,306 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:15:20,306 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:15:20,813 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:15:20,815 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:15:20,816 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:15:23,704 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:15:23,706 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:15:23,706 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:15:28,677 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:15:28,678 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:15:28,678 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:15:33,754 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:15:33,756 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:15:33,756 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:15:40,799 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:15:40,801 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:15:40,801 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:15:49,151 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:15:49,152 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:15:49,155 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:15:49,156 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:15:49,157 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:15:48,368 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:15:48,374 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:15:48,394 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:15:48,399 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:15:49,350 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:15:50,314 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.8s (0.000/s)
2016-12-01 15:15:50,314 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.8s (0.000/s)
2016-12-01 15:15:50,314 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.8s (0.000/s)
2016-12-01 15:15:56,701 [INFO][23/14] calico.felix.endpoint 446: Interface cni0 now has IPs set([IPAddress('192.168.85.1')])
2016-12-01 15:15:56,703 [INFO][23/23] calico.felix.endpoint 446: Interface cni0 now has IPs set([IPAddress('fe80::8447:ccff:fe9b:b83e')])
2016-12-01 15:15:56,703 [INFO][23/23] calico.felix.endpoint 446: Interface vethdf692c5d@docker0 now has IPs set([IPAddress('fe80::57:feff:fe52:8712')])
2016-12-01 15:16:21,321 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:16:21,322 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:16:21,322 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:16:24,428 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:16:24,429 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:16:24,429 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:16:26,373 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:16:26,376 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:16:26,376 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:16:31,211 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:16:31,212 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:16:31,213 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:16:39,513 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:16:39,519 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:16:39,520 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:16:43,092 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:16:43,094 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:16:43,094 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:16:47,610 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:16:52,329 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:16:52,330 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:16:52,330 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:17:23,337 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:17:23,338 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:17:23,338 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:17:26,293 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:17:26,295 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:17:26,295 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:17:31,600 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:17:31,602 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:17:31,602 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:17:35,113 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:17:35,116 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:17:35,116 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:17:43,646 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:17:43,647 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:17:43,648 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:17:51,460 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:17:51,463 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:17:51,464 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:17:54,345 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:17:54,346 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:17:54,346 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:18:17,662 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:18:25,353 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:18:25,353 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:18:25,353 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:18:26,472 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:18:26,474 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:18:26,474 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:18:37,236 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:18:37,238 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:18:37,238 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:18:41,817 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:18:41,819 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:18:41,819 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:18:52,029 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:18:52,031 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:18:52,031 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:18:56,361 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:18:56,361 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:18:56,361 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:19:01,378 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:19:01,380 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:19:01,380 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:19:18,489 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:19:18,493 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:19:18,493 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:19:18,508 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:19:18,509 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:19:18,603 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:19:18,606 [INFO][23/26] calico.felix.splitter 130: Interface vethdf692c5d state changed
2016-12-01 15:19:18,607 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:19:26,368 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:19:26,368 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:19:26,368 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:19:26,752 [INFO][23/23] calico.felix.endpoint 446: Interface vethdf692c5d@docker0 now has IPs None
2016-12-01 15:19:26,760 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:19:26,762 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:19:26,762 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:19:43,101 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:19:43,103 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:19:43,103 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:19:45,797 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:19:45,799 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:19:45,799 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:19:47,738 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:19:56,375 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:19:56,375 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:19:56,376 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:20:02,765 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:20:02,767 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:20:02,767 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:20:02,939 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:20:02,940 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:20:02,941 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:20:26,382 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:20:26,382 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:20:26,383 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:20:29,243 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:20:29,245 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:20:29,245 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:20:46,457 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:20:46,459 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:20:46,459 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:20:52,004 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:20:52,005 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:20:52,006 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:20:56,390 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:20:56,390 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:20:56,390 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:21:07,208 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:21:07,210 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:21:07,210 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:21:11,574 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:21:11,575 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:21:11,576 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:21:17,782 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:21:27,397 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:21:27,397 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:21:27,397 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:21:31,153 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:21:31,155 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:21:31,155 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:21:54,936 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:21:54,938 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:21:54,938 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:21:58,404 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:21:58,404 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:21:58,404 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:22:02,614 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:22:02,616 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:22:02,616 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:22:04,550 [INFO][30/139694004488880] calico.etcddriver.driver 945: STAT: Watcher etcd response time: 8 in 496.9s (0.016/s) min=0.788ms mean=5.251ms max=35.676ms
2016-12-01 15:22:04,550 [INFO][30/139694004488880] calico.etcddriver.driver 945: STAT: Watcher processing time: 13 in 496.9s (0.026/s) min=1.178ms mean=36274.131ms max=90043.204ms
2016-12-01 15:22:04,550 [INFO][30/139694004488880] calico.etcddriver.driver 948: STAT: Watcher queue length: 1
2016-12-01 15:22:04,570 [INFO][23/2] calico.felix.splitter 87: Tags for profile k8s_ns.policy-example updated
2016-12-01 15:22:04,570 [INFO][23/9] calico.felix.ipsets 227: Tags for profile k8s_ns.policy-example updated
2016-12-01 15:22:04,571 [INFO][23/19] calico.felix.ipsets 227: Tags for profile k8s_ns.policy-example updated
2016-12-01 15:22:04,571 [INFO][23/2] calico.felix.splitter 97: Profile k8s_ns.policy-example labels updated
2016-12-01 15:22:04,571 [INFO][23/2] calico.felix.splitter 76: Profile update: k8s_ns.policy-example
2016-12-01 15:22:04,573 [INFO][23/11] calico.felix.profilerules 92: Rules for profile k8s_ns.policy-example updated.
2016-12-01 15:22:04,573 [INFO][23/20] calico.felix.profilerules 92: Rules for profile k8s_ns.policy-example updated.
2016-12-01 15:22:05,051 [INFO][23/26] calico.felix.splitter 130: Interface vetha01e108a state changed
2016-12-01 15:22:05,052 [INFO][23/26] calico.felix.splitter 130: Interface vetha01e108a state changed
2016-12-01 15:22:05,052 [INFO][23/26] calico.felix.splitter 130: Interface vetha01e108a state changed
2016-12-01 15:22:05,060 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:22:05,066 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:22:05,067 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:22:05,068 [INFO][23/26] calico.felix.splitter 130: Interface cni0 state changed
2016-12-01 15:22:06,789 [INFO][23/23] calico.felix.endpoint 446: Interface vetha01e108a@docker0 now has IPs set([IPAddress('fe80::b824:93ff:fe33:5b7d')])
2016-12-01 15:22:18,433 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:22:18,435 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:22:18,435 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:22:20,721 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:22:20,722 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:22:20,723 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:22:28,576 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.2s (0.000/s)
2016-12-01 15:22:28,576 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 3 in 30.2s (0.099/s)
2016-12-01 15:22:28,576 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 3 in 30.2s (0.099/s)
2016-12-01 15:22:40,484 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:22:40,486 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:22:40,486 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:22:41,103 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:22:41,104 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:22:41,104 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:22:46,799 [INFO][23/23] calico.felix.endpoint 446: Interface veth516d9e8f@docker0 now has IPs set([IPAddress('fe80::703b:80ff:fef2:a476')])
2016-12-01 15:22:58,583 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:22:58,583 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:22:58,583 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:23:00,911 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:23:00,913 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:23:00,914 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:23:04,319 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:23:04,321 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:23:04,321 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:23:25,043 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:23:25,045 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:23:25,045 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:23:28,590 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:23:28,590 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:23:28,590 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:23:30,557 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:23:30,559 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:23:30,559 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:23:34,624 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:23:46,100 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:23:46,102 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:23:46,102 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:23:58,597 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:23:58,597 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:23:58,598 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:24:08,544 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:24:08,546 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:24:08,546 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:24:09,706 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:24:09,708 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:24:09,708 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:24:28,604 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:24:28,605 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:24:28,605 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:24:33,944 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:24:33,946 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:24:33,947 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:24:37,436 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:24:37,438 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:24:37,438 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:24:49,381 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:24:49,383 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:24:49,383 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:24:58,611 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:24:58,612 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:24:58,612 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:25:04,657 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:25:12,697 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:25:12,699 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:25:12,700 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:25:18,291 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:25:18,293 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:25:18,293 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:25:28,619 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:25:28,619 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:25:28,619 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:25:38,858 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:25:38,860 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:25:38,860 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:25:41,750 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:25:41,752 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:25:41,752 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:25:52,361 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:25:52,401 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:25:52,402 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:25:52,403 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:25:52,403 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:25:52,451 [INFO][23/26] calico.felix.splitter 130: Interface veth516d9e8f state changed
2016-12-01 15:25:53,704 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:25:53,705 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:25:53,706 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:25:56,844 [INFO][23/23] calico.felix.endpoint 446: Interface veth516d9e8f@docker0 now has IPs None
2016-12-01 15:25:58,626 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:25:58,626 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:25:58,626 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:26:20,115 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:26:20,118 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:26:20,118 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:26:26,056 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:26:26,057 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:26:26,058 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:26:28,633 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:26:28,633 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:26:28,633 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:26:34,753 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:26:41,476 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:26:41,478 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:26:41,478 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:26:52,413 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:26:52,416 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:26:52,416 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:26:54,658 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:26:54,659 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:26:54,660 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:26:58,640 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:26:58,640 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:26:58,640 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:27:28,203 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:27:28,205 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:27:28,205 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:27:28,647 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:27:28,647 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:27:28,647 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:27:32,491 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:27:32,492 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:27:32,493 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:27:42,720 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:27:42,722 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:27:42,722 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:27:54,788 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:27:54,789 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:27:54,790 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:27:58,654 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:27:58,654 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:27:58,654 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:28:02,114 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:28:02,116 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:28:02,116 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:28:04,870 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:28:28,661 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:28:28,661 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:28:28,661 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:28:38,463 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:28:38,466 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:28:38,466 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:28:40,349 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:28:40,351 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:28:40,351 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:28:43,273 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:28:43,274 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:28:43,275 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:28:58,668 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:28:58,669 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:28:58,669 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:29:06,669 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:29:06,671 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:29:06,671 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:29:09,228 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:29:09,231 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:29:09,231 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:29:28,676 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:29:28,676 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:29:28,676 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:29:34,992 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:29:44,718 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:29:44,720 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:29:44,720 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:29:50,873 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:29:50,875 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:29:50,875 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:29:53,457 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:29:53,458 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:29:53,459 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:29:58,683 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:29:58,683 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:29:58,683 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:30:14,365 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:30:14,367 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:30:14,367 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:30:19,797 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:30:19,800 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:30:19,800 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:30:28,690 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:30:28,690 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:30:28,690 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:30:54,845 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:30:54,847 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:30:54,848 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:30:57,905 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:30:57,907 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:30:57,907 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:30:59,697 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:30:59,697 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:30:59,697 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:31:01,702 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:31:01,704 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:31:01,704 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:31:05,040 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:31:05,079 [WARNING][30/139694004488880] calico.etcddriver.driver 849: etcd watch returned bad HTTP status topoll on index 1693754: 400
2016-12-01 15:31:05,079 [ERROR][30/139694004488880] calico.etcddriver.driver 891: Error from etcd for index 1693754: {u'errorCode': 401, u'index': 1694821, u'message': u'The event in requested index is outdated and cleared', u'cause': u'the requested history has been cleared [1693822/1693754]'}; triggering a resync.
2016-12-01 15:31:05,079 [INFO][30/139694004488880] calico.etcddriver.driver 960: STAT: Final watcher etcd response time: 2 in 540.5s (0.004/s) min=0.868ms mean=1.026ms max=1.183ms
2016-12-01 15:31:05,079 [INFO][30/139694004488880] calico.etcddriver.driver 960: STAT: Final watcher processing time: 9 in 540.5s (0.017/s) min=2.256ms mean=65220.415ms max=90084.410ms
2016-12-01 15:31:05,080 [INFO][30/139694004488880] calico.etcddriver.driver 963: Watcher thread finished. Signalled to resync thread. Was at index 1693754. Queue length is 1.
2016-12-01 15:31:05,113 [WARNING][30/139694112062128] calico.etcddriver.driver 328: Watcher died; resyncing.
2016-12-01 15:31:05,114 [INFO][30/139694112062128] calico.etcddriver.driver 713: Watcher was running before, stopping it
2016-12-01 15:31:05,114 [INFO][30/139694112062128] calico.etcddriver.driver 295: Stop event not set, starting new resync...
2016-12-01 15:31:05,114 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: wait-for-ready
2016-12-01 15:31:05,114 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to wait-for-ready
2016-12-01 15:31:05,116 [INFO][30/139694112062128] calico.etcddriver.driver 393: Ready flag set to true
2016-12-01 15:31:05,116 [INFO][30/139694112062128] calico.etcddriver.driver 407: Pre-loading config.
2016-12-01 15:31:05,118 [INFO][30/139694112062128] calico.etcddriver.driver 418: Sent config message to Felix.
2016-12-01 15:31:05,118 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: resync
2016-12-01 15:31:05,118 [INFO][23/2] calico.felix.fetcd 535: Config loaded by driver:
Global: {'LogSeverityScreen': 'info', 'IpInIpEnabled': 'false', 'LogSeverityFile': 'none', 'InterfacePrefix': 'cali', 'ReportingIntervalSecs': '0', 'ClusterGUID': '5c25f860f8b246ddba756dfdd2f731d9', 'LogFilePath': 'none'}
Per-host: {'marker': 'created', 'DefaultEndpointToHostAction': 'RETURN'}
2016-12-01 15:31:05,118 [INFO][30/139694112062128] calico.etcddriver.driver 453: Loading snapshot headers...
2016-12-01 15:31:05,118 [INFO][23/2] calico.felix.fetcd 540: Checking configuration for changes...
2016-12-01 15:31:05,118 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to resync
2016-12-01 15:31:05,119 [INFO][30/139694112062128] calico.etcddriver.driver 465: Got snapshot headers, snapshot index is 1694821; starting watcher...
2016-12-01 15:31:05,120 [INFO][30/139694004488880] calico.etcddriver.driver 811: Watcher thread started with next index 1694822
2016-12-01 15:31:05,120 [INFO][30/139694112062128] calico.etcddriver.hwm 94: Started tracking deletions
2016-12-01 15:31:05,121 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:31:05,131 [INFO][30/139694112062128] calico.etcddriver.hwm 105: Stopped tracking deletions
2016-12-01 15:31:05,131 [INFO][30/139694112062128] calico.etcddriver.driver 620: Scanning for deletions
2016-12-01 15:31:05,131 [INFO][30/139694112062128] calico.etcddriver.hwm 167: Removing keys that are older than 1694821
2016-12-01 15:31:05,131 [INFO][30/139694112062128] calico.etcddriver.hwm 178: Deleted 0 old keys
2016-12-01 15:31:05,131 [INFO][30/139694112062128] calico.etcddriver.driver 626: Found 0 deleted keys
2016-12-01 15:31:05,131 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: in-sync
2016-12-01 15:31:05,132 [INFO][30/139694112062128] calico.etcddriver.driver 604: In sync, now processing events only...
2016-12-01 15:31:05,132 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to in-sync
2016-12-01 15:31:20,292 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:31:20,294 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:31:20,294 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:31:21,412 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:31:21,413 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:31:21,413 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:31:35,139 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 39 in 30.0s (1.299/s)
2016-12-01 15:31:35,139 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:31:35,139 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:32:00,936 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:32:00,937 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:32:00,937 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:32:01,713 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:32:01,715 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:32:01,716 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:32:03,007 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:32:03,009 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:32:03,009 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:32:06,147 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:32:06,147 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:32:06,147 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:32:22,777 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:32:22,780 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:32:22,780 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:32:27,795 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:32:27,797 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:32:27,797 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:32:35,203 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:32:37,154 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:32:37,154 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:32:37,154 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:33:04,843 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:33:04,845 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:33:04,845 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:33:08,161 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:33:08,161 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:33:08,161 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:33:10,025 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:33:10,029 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:33:10,030 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:33:12,608 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:33:12,611 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:33:12,611 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:33:33,599 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:33:33,601 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:33:33,602 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:33:39,169 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:33:39,169 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:33:39,169 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:33:39,465 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:33:39,466 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:33:39,466 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:34:05,310 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:34:05,857 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:34:05,859 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:34:05,859 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:34:10,176 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:34:10,176 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:34:10,177 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:34:11,523 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:34:11,525 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:34:11,525 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:34:19,186 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:34:19,188 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:34:19,188 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:34:33,655 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:34:33,657 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:34:33,658 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:34:41,183 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:34:41,183 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:34:41,183 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:34:46,774 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:34:46,776 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:34:46,776 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:35:10,710 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:35:10,712 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:35:10,712 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:35:12,190 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:35:12,190 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:35:12,190 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:35:15,423 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:35:15,426 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:35:15,426 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:35:20,146 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:35:20,149 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:35:20,149 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:35:34,766 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:35:34,768 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:35:34,768 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:35:35,412 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:35:43,196 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:35:43,197 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:35:43,197 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:35:57,012 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:35:57,013 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:35:57,013 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:36:12,210 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:36:12,212 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:36:12,212 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:36:14,203 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:36:14,204 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:36:14,204 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:36:16,214 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:36:16,216 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:36:16,216 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:36:23,557 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:36:23,559 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:36:23,559 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:36:36,970 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:36:36,972 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:36:36,972 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:36:45,210 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:36:45,211 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:36:45,211 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:37:02,953 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:37:02,955 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:37:02,955 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:37:05,459 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:37:12,575 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:37:12,577 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:37:12,577 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:37:16,217 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:37:16,217 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:37:16,217 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:37:20,103 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:37:20,105 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:37:20,106 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:37:33,822 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:37:33,824 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:37:33,824 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:37:44,296 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:37:44,298 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:37:44,298 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:37:47,224 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:37:47,224 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:37:47,224 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:38:09,343 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:38:09,345 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:38:09,345 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:38:17,325 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:38:17,326 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:38:17,326 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:38:18,230 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:38:18,230 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:38:18,231 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:38:29,824 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:38:29,825 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:38:29,826 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:38:34,434 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:38:34,436 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:38:34,437 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:38:35,540 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:38:46,039 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:38:46,041 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:38:46,041 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:38:49,237 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:38:49,237 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:38:49,237 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:39:16,647 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:39:16,648 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:39:16,648 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:39:20,244 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:39:20,244 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:39:20,244 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:39:29,076 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:39:29,077 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:39:29,078 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:39:32,681 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:39:32,683 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:39:32,683 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:39:40,824 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:39:40,826 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:39:40,827 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:39:49,982 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:39:49,984 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:39:49,984 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:39:51,250 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:39:51,251 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:39:51,251 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:40:05,656 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:40:05,695 [WARNING][30/139694004488880] calico.etcddriver.driver 849: etcd watch returned bad HTTP status topoll on index 1694822: 400
2016-12-01 15:40:05,695 [ERROR][30/139694004488880] calico.etcddriver.driver 891: Error from etcd for index 1694822: {u'errorCode': 401, u'index': 1695843, u'message': u'The event in requested index is outdated and cleared', u'cause': u'the requested history has been cleared [1694844/1694822]'}; triggering a resync.
2016-12-01 15:40:05,696 [INFO][30/139694004488880] calico.etcddriver.driver 960: STAT: Final watcher etcd response time: 0 in 540.4s (0.000/s) min=0.000ms mean=0.000ms max=0.000ms
2016-12-01 15:40:05,696 [INFO][30/139694004488880] calico.etcddriver.driver 960: STAT: Final watcher processing time: 6 in 540.4s (0.011/s) min=89988.027ms mean=90037.984ms max=90053.809ms
2016-12-01 15:40:05,696 [INFO][30/139694004488880] calico.etcddriver.driver 963: Watcher thread finished. Signalled to resync thread. Was at index 1694822. Queue length is 1.
2016-12-01 15:40:05,719 [WARNING][30/139694112062128] calico.etcddriver.driver 328: Watcher died; resyncing.
2016-12-01 15:40:05,719 [INFO][30/139694112062128] calico.etcddriver.driver 713: Watcher was running before, stopping it
2016-12-01 15:40:05,719 [INFO][30/139694112062128] calico.etcddriver.driver 295: Stop event not set, starting new resync...
2016-12-01 15:40:05,719 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: wait-for-ready
2016-12-01 15:40:05,719 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to wait-for-ready
2016-12-01 15:40:05,721 [INFO][30/139694112062128] calico.etcddriver.driver 393: Ready flag set to true
2016-12-01 15:40:05,721 [INFO][30/139694112062128] calico.etcddriver.driver 407: Pre-loading config.
2016-12-01 15:40:05,723 [INFO][30/139694112062128] calico.etcddriver.driver 418: Sent config message to Felix.
2016-12-01 15:40:05,723 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: resync
2016-12-01 15:40:05,723 [INFO][23/2] calico.felix.fetcd 535: Config loaded by driver:
Global: {'InterfacePrefix': 'cali', 'IpInIpEnabled': 'false', 'LogSeverityFile': 'none', 'LogSeverityScreen': 'info', 'ReportingIntervalSecs': '0', 'ClusterGUID': '5c25f860f8b246ddba756dfdd2f731d9', 'LogFilePath': 'none'}
Per-host: {'marker': 'created', 'DefaultEndpointToHostAction': 'RETURN'}
2016-12-01 15:40:05,723 [INFO][30/139694112062128] calico.etcddriver.driver 453: Loading snapshot headers...
2016-12-01 15:40:05,723 [INFO][23/2] calico.felix.fetcd 540: Checking configuration for changes...
2016-12-01 15:40:05,723 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to resync
2016-12-01 15:40:05,724 [INFO][30/139694112062128] calico.etcddriver.driver 465: Got snapshot headers, snapshot index is 1695843; starting watcher...
2016-12-01 15:40:05,724 [INFO][30/139694004488880] calico.etcddriver.driver 811: Watcher thread started with next index 1695844
2016-12-01 15:40:05,725 [INFO][30/139694112062128] calico.etcddriver.hwm 94: Started tracking deletions
2016-12-01 15:40:05,725 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:40:05,732 [INFO][30/139694112062128] calico.etcddriver.hwm 105: Stopped tracking deletions
2016-12-01 15:40:05,732 [INFO][30/139694112062128] calico.etcddriver.driver 620: Scanning for deletions
2016-12-01 15:40:05,732 [INFO][30/139694112062128] calico.etcddriver.hwm 167: Removing keys that are older than 1695843
2016-12-01 15:40:05,732 [INFO][30/139694112062128] calico.etcddriver.hwm 178: Deleted 0 old keys
2016-12-01 15:40:05,732 [INFO][30/139694112062128] calico.etcddriver.driver 626: Found 0 deleted keys
2016-12-01 15:40:05,732 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: in-sync
2016-12-01 15:40:05,733 [INFO][30/139694112062128] calico.etcddriver.driver 604: In sync, now processing events only...
2016-12-01 15:40:05,733 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to in-sync
2016-12-01 15:40:17,883 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:40:17,885 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:40:17,885 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:40:31,877 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:40:31,879 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:40:31,879 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:40:35,739 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 39 in 30.0s (1.300/s)
2016-12-01 15:40:35,739 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:40:35,739 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:40:39,188 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:40:39,190 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:40:39,190 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:40:43,344 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:40:43,346 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:40:43,346 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:41:01,916 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:41:01,918 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:41:01,918 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:41:06,745 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:41:06,746 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:41:06,746 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:41:19,112 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:41:19,114 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:41:19,114 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:41:35,612 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:41:35,614 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:41:35,614 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:41:35,808 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:41:37,752 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:41:37,752 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:41:37,752 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:41:45,558 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:41:45,560 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:41:45,560 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:41:53,358 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:41:53,360 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:41:53,360 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:42:08,759 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:42:08,759 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:42:08,759 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:42:13,241 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:42:13,243 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:42:13,243 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:42:29,206 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:42:29,207 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:42:29,208 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:42:36,665 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:42:36,666 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:42:36,666 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:42:39,765 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:42:39,766 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:42:39,766 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:42:51,180 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:42:51,182 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:42:51,182 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:42:56,116 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:42:56,118 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:42:56,118 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:43:05,419 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:43:09,772 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.5s (0.000/s)
2016-12-01 15:43:09,772 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.5s (0.000/s)
2016-12-01 15:43:09,772 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.5s (0.000/s)
2016-12-01 15:43:19,797 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:43:19,799 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:43:19,800 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:43:38,925 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:43:38,927 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:43:38,927 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:43:39,779 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:43:39,779 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:43:39,779 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:43:43,001 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:43:43,002 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:43:43,002 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:43:58,999 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:43:59,002 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:43:59,002 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:44:00,413 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:44:00,415 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:44:00,415 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:44:10,785 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:44:10,786 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:44:10,786 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:44:21,621 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:44:21,623 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:44:21,623 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:44:35,500 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:44:40,792 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:44:40,792 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:44:40,792 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:44:47,608 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:44:47,610 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:44:47,610 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:44:49,109 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:44:49,111 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:44:49,111 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:45:09,281 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:45:09,283 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:45:09,283 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:45:10,798 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:45:10,798 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:45:10,798 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:45:11,819 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:45:11,821 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:45:11,821 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:45:26,190 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:45:26,193 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:45:26,193 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:45:40,804 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:45:40,804 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:45:40,804 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:45:51,288 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:45:51,290 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:45:51,290 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:45:54,021 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:45:54,022 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:45:54,022 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:46:05,596 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:46:11,810 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:46:11,811 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:46:11,811 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:46:14,387 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:46:14,388 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:46:14,389 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:46:20,959 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:46:20,962 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:46:20,962 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:46:36,296 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:46:36,299 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:46:36,299 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:46:42,817 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:46:42,817 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 31.0s (0.000/s)
2016-12-01 15:46:42,817 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 31.0s (0.000/s)
2016-12-01 15:46:57,377 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:46:57,378 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:46:57,379 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:47:02,528 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:47:02,531 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:47:02,532 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:47:12,823 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:47:12,823 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:47:12,823 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:47:17,173 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:47:17,175 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:47:17,175 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:47:30,406 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:47:30,408 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:47:30,409 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:47:35,704 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:47:40,465 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:47:40,467 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:47:40,467 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:47:42,829 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:47:42,829 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:47:42,829 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:48:03,902 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:48:03,904 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:48:03,904 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:48:08,973 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:48:08,975 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:48:08,975 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:48:12,835 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:48:12,835 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:48:12,836 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:48:21,062 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:48:21,064 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:48:21,064 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:48:37,659 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:48:37,661 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:48:37,662 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:48:42,841 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:48:42,841 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:48:42,842 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:48:49,921 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:48:49,923 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:48:49,924 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:49:05,554 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:49:05,556 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:49:05,556 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:49:05,799 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:49:05,814 [WARNING][30/139694004488880] calico.etcddriver.driver 849: etcd watch returned bad HTTP status topoll on index 1695844: 400
2016-12-01 15:49:05,814 [ERROR][30/139694004488880] calico.etcddriver.driver 891: Error from etcd for index 1695844: {u'errorCode': 401, u'index': 1696868, u'message': u'The event in requested index is outdated and cleared', u'cause': u'the requested history has been cleared [1695869/1695844]'}; triggering a resync.
2016-12-01 15:49:05,814 [INFO][30/139694004488880] calico.etcddriver.driver 960: STAT: Final watcher etcd response time: 0 in 540.5s (0.000/s) min=0.000ms mean=0.000ms max=0.000ms
2016-12-01 15:49:05,814 [INFO][30/139694004488880] calico.etcddriver.driver 960: STAT: Final watcher processing time: 6 in 540.5s (0.011/s) min=90010.145ms mean=90060.215ms max=90094.971ms
2016-12-01 15:49:05,814 [INFO][30/139694004488880] calico.etcddriver.driver 963: Watcher thread finished. Signalled to resync thread. Was at index 1695844. Queue length is 1.
2016-12-01 15:49:05,846 [WARNING][30/139694112062128] calico.etcddriver.driver 328: Watcher died; resyncing.
2016-12-01 15:49:05,846 [INFO][30/139694112062128] calico.etcddriver.driver 713: Watcher was running before, stopping it
2016-12-01 15:49:05,846 [INFO][30/139694112062128] calico.etcddriver.driver 295: Stop event not set, starting new resync...
2016-12-01 15:49:05,846 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: wait-for-ready
2016-12-01 15:49:05,846 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to wait-for-ready
2016-12-01 15:49:05,848 [INFO][30/139694112062128] calico.etcddriver.driver 393: Ready flag set to true
2016-12-01 15:49:05,848 [INFO][30/139694112062128] calico.etcddriver.driver 407: Pre-loading config.
2016-12-01 15:49:05,850 [INFO][30/139694112062128] calico.etcddriver.driver 418: Sent config message to Felix.
2016-12-01 15:49:05,850 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: resync
2016-12-01 15:49:05,850 [INFO][23/2] calico.felix.fetcd 535: Config loaded by driver:
Global: {'InterfacePrefix': 'cali', 'IpInIpEnabled': 'false', 'LogSeverityFile': 'none', 'LogSeverityScreen': 'info', 'ReportingIntervalSecs': '0', 'LogFilePath': 'none', 'ClusterGUID': '5c25f860f8b246ddba756dfdd2f731d9'}
Per-host: {'marker': 'created', 'DefaultEndpointToHostAction': 'RETURN'}
2016-12-01 15:49:05,850 [INFO][30/139694112062128] calico.etcddriver.driver 453: Loading snapshot headers...
2016-12-01 15:49:05,850 [INFO][23/2] calico.felix.fetcd 540: Checking configuration for changes...
2016-12-01 15:49:05,851 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to resync
2016-12-01 15:49:05,851 [INFO][30/139694112062128] calico.etcddriver.driver 465: Got snapshot headers, snapshot index is 1696868; starting watcher...
2016-12-01 15:49:05,852 [INFO][30/139694112062128] calico.etcddriver.hwm 94: Started tracking deletions
2016-12-01 15:49:05,852 [INFO][30/139694004488880] calico.etcddriver.driver 811: Watcher thread started with next index 1696869
2016-12-01 15:49:05,857 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:49:05,860 [INFO][30/139694112062128] calico.etcddriver.hwm 105: Stopped tracking deletions
2016-12-01 15:49:05,860 [INFO][30/139694112062128] calico.etcddriver.driver 620: Scanning for deletions
2016-12-01 15:49:05,861 [INFO][30/139694112062128] calico.etcddriver.hwm 167: Removing keys that are older than 1696868
2016-12-01 15:49:05,861 [INFO][30/139694112062128] calico.etcddriver.hwm 178: Deleted 0 old keys
2016-12-01 15:49:05,861 [INFO][30/139694112062128] calico.etcddriver.driver 626: Found 0 deleted keys
2016-12-01 15:49:05,861 [INFO][30/139694112062128] calico.etcddriver.driver 766: Sending status to Felix: in-sync
2016-12-01 15:49:05,861 [INFO][23/2] calico.felix.fetcd 594: etcd driver status changed to in-sync
2016-12-01 15:49:05,862 [INFO][30/139694112062128] calico.etcddriver.driver 604: In sync, now processing events only...
2016-12-01 15:49:17,149 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:49:17,151 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:49:17,151 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:49:21,415 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:49:21,417 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:49:21,417 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:49:35,867 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 39 in 30.0s (1.299/s)
2016-12-01 15:49:35,867 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:49:35,867 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:49:45,566 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:49:45,568 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:49:45,569 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:49:52,696 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:49:52,698 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:49:52,698 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:50:05,873 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:50:05,873 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:50:05,874 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:50:09,284 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:50:09,285 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:50:09,286 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:50:28,279 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:50:28,281 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:50:28,281 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:50:28,845 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:50:28,846 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:50:28,846 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:50:35,879 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:50:35,880 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:50:35,880 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:50:35,927 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:50:45,832 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:50:45,834 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:50:45,834 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:50:54,911 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:50:54,913 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:50:54,913 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:51:05,886 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:51:05,886 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:51:05,886 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:51:10,540 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:51:10,541 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:51:10,541 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:51:34,892 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:51:34,894 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:51:34,894 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:51:35,893 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:51:35,893 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:51:35,893 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:51:40,126 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:51:40,128 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:51:40,128 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:51:55,319 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:51:55,321 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:51:55,321 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:52:00,305 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:52:00,307 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:52:00,307 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:52:05,899 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:52:05,899 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:52:05,899 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:52:05,963 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
2016-12-01 15:52:19,262 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:52:19,263 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:52:19,263 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:52:35,905 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:52:35,905 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:52:35,905 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:52:40,667 [INFO][23/18] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:52:40,669 [INFO][23/18] calico.felix.fiptables 532: IptablesUpdater<v6-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:52:40,669 [INFO][23/18] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:52:44,676 [INFO][23/8] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:52:44,677 [INFO][23/8] calico.felix.fiptables 532: IptablesUpdater<v4-nat,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:52:44,678 [INFO][23/8] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:53:02,774 [INFO][23/7] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:53:02,776 [INFO][23/7] calico.felix.fiptables 532: IptablesUpdater<v4-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:53:02,777 [INFO][23/7] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:53:03,244 [INFO][23/17] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:53:03,246 [INFO][23/17] calico.felix.fiptables 532: IptablesUpdater<v6-filter,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:53:03,246 [INFO][23/17] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:53:05,911 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:53:05,912 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:53:05,912 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:53:22,047 [INFO][23/16] calico.felix.fiptables 513: Refreshing all our chains
2016-12-01 15:53:22,048 [INFO][23/16] calico.felix.fiptables 532: IptablesUpdater<v6-raw,queue_len=0,live=True,msg=None> Successfully processed iptables updates.
2016-12-01 15:53:22,049 [INFO][23/16] calico.felix.fiptables 569: Transaction included a refresh, re-applying our inserts and deletions.
2016-12-01 15:53:35,918 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread snapshot keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:53:35,918 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread event keys processed: 0 in 30.0s (0.000/s)
2016-12-01 15:53:35,918 [INFO][30/139694112062128] calico.etcddriver.driver 789: STAT: Resync thread felix updates sent: 0 in 30.0s (0.000/s)
2016-12-01 15:53:36,023 [INFO][30/139694004488880] calico.etcddriver.driver 823: No HTTP pool, creating one...
:felix-FIP-DNAT - [0:0]
:felix-FIP-SNAT - [0:0]
:felix-POSTROUTING - [0:0]
:felix-PREROUTING - [0:0]
-A PREROUTING -j felix-PREROUTING
-A POSTROUTING -j felix-POSTROUTING
-A felix-POSTROUTING -j felix-FIP-SNAT
-A felix-PREROUTING -j felix-FIP-DNAT
:felix-FAILSAFE-IN - [0:0]
:felix-FAILSAFE-OUT - [0:0]
:felix-FORWARD - [0:0]
:felix-FROM-ENDPOINT - [0:0]
:felix-FROM-HOST-IF - [0:0]
:felix-INPUT - [0:0]
:felix-OUTPUT - [0:0]
:felix-TO-ENDPOINT - [0:0]
:felix-TO-HOST-IF - [0:0]
-A INPUT -j felix-INPUT
-A FORWARD -j felix-FORWARD
-A OUTPUT -j felix-OUTPUT
-A felix-FAILSAFE-IN -p tcp -m tcp --dport 22 -j ACCEPT
-A felix-FAILSAFE-OUT -p tcp -m tcp --dport 2379 -j ACCEPT
-A felix-FAILSAFE-OUT -p tcp -m tcp --dport 2380 -j ACCEPT
-A felix-FAILSAFE-OUT -p tcp -m tcp --dport 4001 -j ACCEPT
-A felix-FAILSAFE-OUT -p tcp -m tcp --dport 7001 -j ACCEPT
-A felix-FORWARD -i cali+ -m conntrack --ctstate INVALID -j DROP
-A felix-FORWARD -o cali+ -m conntrack --ctstate INVALID -j DROP
-A felix-FORWARD -i cali+ -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT
-A felix-FORWARD -o cali+ -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT
-A felix-FORWARD -i cali+ -j felix-FROM-ENDPOINT
-A felix-FORWARD -o cali+ -j felix-TO-ENDPOINT
-A felix-FORWARD -i cali+ -j ACCEPT
-A felix-FORWARD -o cali+ -j ACCEPT
-A felix-FROM-ENDPOINT -m comment --comment "From unknown endpoint" -j DROP
-A felix-FROM-HOST-IF -m comment --comment "Unknown interface, return" -j RETURN
-A felix-INPUT -m conntrack --ctstate INVALID -j DROP
-A felix-INPUT -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT
-A felix-INPUT ! -i cali+ -g felix-FROM-HOST-IF
-A felix-INPUT -p udp -m udp --sport 68 --dport 67 -j ACCEPT
-A felix-INPUT -p udp -m udp --dport 53 -j ACCEPT
-A felix-INPUT -j felix-FROM-ENDPOINT
-A felix-OUTPUT -m conntrack --ctstate INVALID -j DROP
-A felix-OUTPUT -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT
-A felix-OUTPUT ! -o cali+ -g felix-TO-HOST-IF
-A felix-TO-ENDPOINT -m comment --comment "To unknown endpoint" -j DROP
-A felix-TO-HOST-IF -m comment --comment "Unknown interface, return" -j RETURN
:felix-FIP-DNAT - [0:0]
:felix-FIP-SNAT - [0:0]
:felix-POSTROUTING - [0:0]
:felix-PREROUTING - [0:0]
-A PREROUTING -j felix-PREROUTING
-A POSTROUTING -j felix-POSTROUTING
-A felix-POSTROUTING -j felix-FIP-SNAT
-A felix-PREROUTING -j felix-FIP-DNAT
:felix-FAILSAFE-IN - [0:0]
:felix-FAILSAFE-OUT - [0:0]
:felix-FORWARD - [0:0]
:felix-FROM-ENDPOINT - [0:0]
:felix-FROM-HOST-IF - [0:0]
:felix-INPUT - [0:0]
:felix-OUTPUT - [0:0]
:felix-TO-ENDPOINT - [0:0]
:felix-TO-HOST-IF - [0:0]
-A INPUT -j felix-INPUT
-A FORWARD -j felix-FORWARD
-A OUTPUT -j felix-OUTPUT
-A felix-FAILSAFE-IN -p tcp -m tcp --dport 22 -j ACCEPT
-A felix-FAILSAFE-OUT -p tcp -m tcp --dport 2379 -j ACCEPT
-A felix-FAILSAFE-OUT -p tcp -m tcp --dport 2380 -j ACCEPT
-A felix-FAILSAFE-OUT -p tcp -m tcp --dport 4001 -j ACCEPT
-A felix-FAILSAFE-OUT -p tcp -m tcp --dport 7001 -j ACCEPT
-A felix-FORWARD -i cali+ -m conntrack --ctstate INVALID -j DROP
-A felix-FORWARD -o cali+ -m conntrack --ctstate INVALID -j DROP
-A felix-FORWARD -i cali+ -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT
-A felix-FORWARD -o cali+ -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT
-A felix-FORWARD -i cali+ -j felix-FROM-ENDPOINT
-A felix-FORWARD -o cali+ -j felix-TO-ENDPOINT
-A felix-FORWARD -i cali+ -j ACCEPT
-A felix-FORWARD -o cali+ -j ACCEPT
-A felix-FROM-ENDPOINT -m comment --comment "From unknown endpoint" -j DROP
-A felix-FROM-HOST-IF -m comment --comment "Unknown interface, return" -j RETURN
-A felix-INPUT -m conntrack --ctstate INVALID -j DROP
-A felix-INPUT -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT
-A felix-INPUT ! -i cali+ -g felix-FROM-HOST-IF
-A felix-INPUT -p udp -m udp --sport 68 --dport 67 -j ACCEPT
-A felix-INPUT -p udp -m udp --dport 53 -j ACCEPT
-A felix-INPUT -j felix-FROM-ENDPOINT
-A felix-OUTPUT -m conntrack --ctstate INVALID -j DROP
-A felix-OUTPUT -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT
-A felix-OUTPUT ! -o cali+ -g felix-TO-HOST-IF
-A felix-TO-ENDPOINT -m comment --comment "To unknown endpoint" -j DROP
-A felix-TO-HOST-IF -m comment --comment "Unknown interface, return" -j RETURN
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment