[Release 1.20] Improve flannel logging

[manuelbuil]

Signed-off-by: Manuel Buil [email protected]

Backport: #4550
Issue: #4617

[dereknola]

Don't think this file should be included in the PR

[manuelbuil]

upss!! thanks!

[VestigeJ]

Validated

Commit=1e1fb73ee998bf03828247941278289304423907
$ k3s -v

k3s version v1.20.13+k3s-1e1fb73e (1e1fb73e)
go version go1.15.15

$ echo $K3S_DEBUG
true

$ sudo chmod 0644 /etc/rancher/k3s/k3s.yaml
$ kubectl get nodes

NAME               STATUS   ROLES                  AGE   VERSION
ip-172-31-24-147   Ready    control-plane,master   103s   v1.20.13+k3s-1e1fb73e

$ sudo journalctl -u k3s.service | grep flannel

Dec 08 19:09:36 ip-172-31-24-147 k3s[15578]: time="2021-12-08T19:09:36.361260429Z" level=info msg="Starting flannel with backend vxlan"
Dec 08 19:09:45 ip-172-31-24-147 k3s[15578]: I1208 19:09:45.783298   15578 flannel.go:93] Determining IP address of default interface
Dec 08 19:09:46 ip-172-31-24-147 k3s[15578]: I1208 19:09:46.814833   15578 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 08 19:09:46 ip-172-31-24-147 k3s[15578]: I1208 19:09:46.814973   15578 flannel.go:82] Running backend.
Dec 08 19:10:01 ip-172-31-24-147 k3s[16495]: time="2021-12-08T19:10:01.757733671Z" level=info msg="Starting flannel with backend vxlan"
Dec 08 19:10:01 ip-172-31-24-147 k3s[16495]: I1208 19:10:01.761597   16495 flannel.go:93] Determining IP address of default interface
Dec 08 19:10:02 ip-172-31-24-147 k3s[16495]: I1208 19:10:02.772799   16495 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 08 19:10:02 ip-172-31-24-147 k3s[16495]: I1208 19:10:02.772822   16495 flannel.go:82] Running backend.
Dec 08 21:15:06 ip-172-31-24-147 k3s[493]: time="2021-12-08T21:15:06.916915322Z" level=info msg="Starting flannel with backend vxlan"
Dec 08 21:15:06 ip-172-31-24-147 k3s[493]: I1208 21:15:06.919148     493 flannel.go:93] Determining IP address of default interface
Dec 08 21:15:08 ip-172-31-24-147 k3s[493]: I1208 21:15:08.037963     493 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 08 21:15:08 ip-172-31-24-147 k3s[493]: I1208 21:15:08.037987     493 flannel.go:82] Running backend.
Dec 08 21:23:59 ip-172-31-24-147 k3s[493]: W1208 21:23:59.915888     493 reflector.go:436] github.com/flannel-io/flannel/subnet/kube/kube.go:379: watch of *v1.Node ended with: an error on the server ("unable to decode an event from the watch stream: context canceled") has prevented the request from succeeding
Dec 08 22:56:37 ip-172-31-24-147 k3s[19021]: time="2021-12-08T22:56:37.341439948Z" level=info msg="Starting flannel with backend vxlan"
Dec 08 22:56:37 ip-172-31-24-147 k3s[19021]: I1208 22:56:37.343512   19021 flannel.go:93] Determining IP address of default interface
Dec 08 22:56:38 ip-172-31-24-147 k3s[19021]: I1208 22:56:38.363006   19021 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 08 22:56:38 ip-172-31-24-147 k3s[19021]: I1208 22:56:38.363143   19021 flannel.go:82] Running backend.
Dec 09 00:10:02 ip-172-31-24-147 k3s[30719]: time="2021-12-09T00:10:02Z" level=info msg="Starting flannel with backend vxlan"
Dec 09 00:10:11 ip-172-31-24-147 k3s[30719]: I1209 00:10:11.948945   30719 flannel.go:93] Determining IP address of default interface
Dec 09 00:10:12 ip-172-31-24-147 k3s[30719]: I1209 00:10:12.988051   30719 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 09 00:10:12 ip-172-31-24-147 k3s[30719]: I1209 00:10:12.988072   30719 flannel.go:82] Running backend.
Dec 09 00:11:17 ip-172-31-24-147 k3s[33100]: time="2021-12-09T00:11:17Z" level=info msg="Starting flannel with backend vxlan"
Dec 09 00:11:17 ip-172-31-24-147 k3s[33100]: I1209 00:11:17.533493   33100 flannel.go:93] Determining IP address of default interface
Dec 09 00:11:18 ip-172-31-24-147 k3s[33100]: I1209 00:11:18.542670   33100 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 09 00:11:18 ip-172-31-24-147 k3s[33100]: I1209 00:11:18.542694   33100 flannel.go:82] Running backend.
Dec 09 00:11:25 ip-172-31-24-147 k3s[33320]: time="2021-12-09T00:11:25Z" level=info msg="Starting flannel with backend vxlan"
Dec 09 00:11:25 ip-172-31-24-147 k3s[33320]: I1209 00:11:25.691528   33320 flannel.go:93] Determining IP address of default interface
Dec 09 00:11:26 ip-172-31-24-147 k3s[33320]: I1209 00:11:26.707929   33320 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 09 00:11:26 ip-172-31-24-147 k3s[33320]: I1209 00:11:26.707953   33320 flannel.go:82] Running backend.
Dec 09 00:11:35 ip-172-31-24-147 k3s[33523]: time="2021-12-09T00:11:35Z" level=info msg="Starting flannel with backend vxlan"
Dec 09 00:11:35 ip-172-31-24-147 k3s[33523]: I1209 00:11:35.078412   33523 flannel.go:93] Determining IP address of default interface
Dec 09 00:11:36 ip-172-31-24-147 k3s[33523]: I1209 00:11:36.094705   33523 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 09 00:11:36 ip-172-31-24-147 k3s[33523]: I1209 00:11:36.094866   33523 flannel.go:82] Running backend.
Dec 09 00:24:38 ip-172-31-24-147 k3s[33523]: W1209 00:24:38.234192   33523 reflector.go:441] github.com/flannel-io/flannel/subnet/kube/kube.go:379: watch of *v1.Node ended with: an error on the server ("unable to decode an event from the watch stream: context canceled") has prevented the request from succeeding
Dec 09 01:05:32 ip-172-31-24-147 k3s[44153]: time="2021-12-09T01:05:32.628067392Z" level=info msg="Starting flannel with backend vxlan"
Dec 09 01:05:41 ip-172-31-24-147 k3s[44153]: I1209 01:05:41.842794   44153 flannel.go:93] Determining IP address of default interface
Dec 09 01:05:42 ip-172-31-24-147 k3s[44153]: I1209 01:05:42.951574   44153 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 09 01:05:42 ip-172-31-24-147 k3s[44153]: I1209 01:05:42.951609   44153 flannel.go:82] Running backend.
Dec 09 01:09:47 ip-172-31-24-147 k3s[47028]: time="2021-12-09T01:09:47.577086708Z" level=info msg="Starting flannel with backend vxlan"
Dec 09 01:09:47 ip-172-31-24-147 k3s[47028]: I1209 01:09:47.632018   47028 flannel.go:93] Determining IP address of default interface
Dec 09 01:09:48 ip-172-31-24-147 k3s[47028]: I1209 01:09:48.639347   47028 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 09 01:09:48 ip-172-31-24-147 k3s[47028]: I1209 01:09:48.639371   47028 flannel.go:82] Running backend.
Dec 09 01:22:23 ip-172-31-24-147 k3s[47028]: W1209 01:22:23.040721   47028 reflector.go:436] github.com/flannel-io/flannel/subnet/kube/kube.go:379: watch of *v1.Node ended with: an error on the server ("unable to decode an event from the watch stream: context canceled") has prevented the request from succeeding
Dec 09 01:22:58 ip-172-31-24-147 k3s[50095]: time="2021-12-09T01:22:58.780176079Z" level=info msg="Starting flannel with backend vxlan"
Dec 09 01:23:14 ip-172-31-24-147 k3s[50095]: I1209 01:23:14.856773   50095 flannel.go:92] Determining IP address of default interface
Dec 09 01:23:14 ip-172-31-24-147 k3s[50095]: I1209 01:23:14.857124   50095 flannel.go:105] Using interface with name eth0 and address 172.31.24.147
Dec 09 01:23:15 ip-172-31-24-147 k3s[50095]: I1209 01:23:15.881627   50095 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 09 01:23:15 ip-172-31-24-147 k3s[50095]: I1209 01:23:15.881645   50095 flannel.go:82] Running backend.
Dec 09 01:27:00 ip-172-31-24-147 k3s[50095]: time="2021-12-09T01:27:00.146600746Z" level=fatal msg="flannel exited: <nil>"
Dec 09 01:27:02 ip-172-31-24-147 k3s[52127]: time="2021-12-09T01:27:02.335731121Z" level=info msg="Starting flannel with backend vxlan"
Dec 09 01:27:02 ip-172-31-24-147 k3s[52127]: I1209 01:27:02.396317   52127 flannel.go:92] Determining IP address of default interface
Dec 09 01:27:02 ip-172-31-24-147 k3s[52127]: I1209 01:27:02.396761   52127 flannel.go:105] Using interface with name eth0 and address 172.31.24.147
Dec 09 01:27:03 ip-172-31-24-147 k3s[52127]: I1209 01:27:03.410588   52127 flannel.go:78] Wrote subnet file to /run/flannel/subnet.env
Dec 09 01:27:03 ip-172-31-24-147 k3s[52127]: I1209 01:27:03.410611   52127 flannel.go:82] Running backend.