Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Bugs related to state change in #1492 #1561

Closed
2 tasks done
vsychov opened this issue Sep 26, 2023 · 30 comments
Closed
2 tasks done

Bugs related to state change in #1492 #1561

vsychov opened this issue Sep 26, 2023 · 30 comments
Labels
bug Something isn't working well described ❤️ Issues that are well described and researched, making it easier for maintainers.
Milestone

Comments

@vsychov
Copy link
Contributor

vsychov commented Sep 26, 2023

Bug description

Hello there,

After #1492 was merged, I noticed the following two issues, which may be related, so I'm posting this as a single problem:

The offers exit node flag takes quite some time to appear after route approvals, and nodes status displayed as "offline" (and this is main problem), I tested this with the following configuration:

  • 3 Linux clients, each connected as follows: tailscale up --auth-key XXXX --advertise-exit-node --login-server=https://headscale-test.example.com/ --advertise-routes=10.0.0.0/8 --accept-routes --accept-dns --ssh --shields-up=false (one user and one reusable auth-key)
  • 1 macOS client, authorized through OIDC
  • Version 1.50.0 of tailscale for all clients, but the same problem is present on 1.48.2

I connected all clients, confirmed all routes, and at 2023-09-26T10:32:26Z got the following result of the headscale route list command:

ID | Node                  | Prefix     | Advertised | Enabled | Primary
7  | tmp-tailscale-fra1-01 | 0.0.0.0/0  | true       | true    | -
8  | tmp-tailscale-fra1-01 | ::/0       | true       | true    | -
9  | tmp-tailscale-fra1-01 | 10.0.0.0/8 | true       | true    | true
10 | tmp-tailscale-fra1-02 | 0.0.0.0/0  | true       | true    | -
11 | tmp-tailscale-fra1-02 | ::/0       | true       | true    | -
12 | tmp-tailscale-fra1-02 | 10.0.0.0/8 | true       | true    | false
13 | tmp-tailscale-fra1-03 | 10.0.0.0/8 | true       | true    | false
14 | tmp-tailscale-fra1-03 | 0.0.0.0/0  | true       | true    | -
15 | tmp-tailscale-fra1-03 | ::/0       | true       | true    | -

At 2023-09-26T10:32:45Z, I ran the command /Applications/Tailscale.app/Contents/MacOS/Tailscale status on macOS, and saw the following result (tmp-tailscale-fra1-01 aleady displayed as offline):

100.64.0.2      macbook-pro-test   macos-user macOS   -
100.64.0.1      tmp-tailscale-fra1-01..example.com test-user    linux   offline
100.64.0.3      tmp-tailscale-fra1-02..example.com test-user    linux   -
100.64.0.4      tmp-tailscale-fra1-03..example.com test-user    linux   -

At 2023-09-26T10:33:15Z, the node tmp-tailscale-fra1-02 displayed as offline, and still, no one was offering an exit-node:

100.64.0.2      macbook-pro-test   macos-user macOS   -
100.64.0.1      tmp-tailscale-fra1-01..example.com test-user    linux   offline
100.64.0.3      tmp-tailscale-fra1-02..example.com test-user    linux   offline
100.64.0.4      tmp-tailscale-fra1-03..example.com test-user    linux   -

At 2023-09-26T10:34:39Z, the node tmp-tailscale-fra1-03 started to be displayed as offering an exit-node (and I noticed, that its hostname changed):

100.64.0.2      macbook-pro-test   macos-user macOS   -
100.64.0.1      tmp-tailscale-fra1-01..example.com test-user    linux   offline
100.64.0.3      tmp-tailscale-fra1-02..example.com test-user    linux   offline
100.64.0.4      tmp-tailscale-fra1-03.test-user.example.com test-user    linux   idle; offers exit node

The situation remained the same until 2023-09-26T10:39:50Z when I stopped the test, here is the result of headscale nodes list at that time:

ID | Hostname              | Name                  | MachineKey | NodeKey | User                 | IP addresses                  | Ephemeral | Last seen           | Expiration          | Online  | Expired
2  | macbook-pro-test    | macbook-pro-test    | [qRLmI]    | [SWCGb] | macos-user | 100.64.0.2, fd7a:115c:a1e0::2 | false     | 2023-09-26 10:29:02 | 2023-09-27 06:09:08 | offline | no
6  | tmp-tailscale-fra1-02 | tmp-tailscale-fra1-02 | [CeATM]    | [Mp30q] | test-user            | 100.64.0.3, fd7a:115c:a1e0::3 | false     | 2023-09-26 10:31:43 | 0001-01-01 00:00:00 | offline | no
7  | tmp-tailscale-fra1-03 | tmp-tailscale-fra1-03 | [scFl7]    | [mDZF9] | test-user            | 100.64.0.4, fd7a:115c:a1e0::4 | false     | 2023-09-26 10:38:53 | 0001-01-01 00:00:00 | online  | no
5  | tmp-tailscale-fra1-01 | tmp-tailscale-fra1-01 | [McL18]    | [8fPhC] | test-user            | 100.64.0.1, fd7a:115c:a1e0::1 | false     | 2023-09-26 10:31:26 | 0001-01-01 00:00:00 | offline | no

All 3 Linux clients are on the same network, have the same connectivity with headscale (the command works the same on all machines, example with tmp-tailscale-fra1-03):

root@tmp-tailscale-fra1-03:~# tailscale debug ts2021 --host headscale-test.example.com --verbose
10:25:55.986071 HTTP proxy env: (none)
10:25:55.986327 tshttpproxy.ProxyFromEnvironment = (<nil>, <nil>)
10:25:55.986524 Fetching keys from https://headscale-test.example.com/key?v=74 ...
10:25:56.047203 got public key: mkey:0de89f7f32d82bab3587d8087b74be91937a547a1d59acd7baec6d6e3bdb3e14
10:25:56.049571 Dial("tcp", "SOME_IP_2:80") ...
10:25:56.057721 Dial("tcp", "SOME_IP_2:80") = SOME_IP_1:34648 / SOME_IP_2:80
10:25:56.067858 controlhttp.Dial = 0xc000032580, <nil>
10:25:56.068067 did noise handshake
10:25:56.068170 final underlying conn: SOME_IP_1:34648 / SOME_IP_2:80

with version 0.22.3 it's works well.

Also there is headscale logs, that shows, that cominnication with nodes displayed as "offline" is was going.

Environment

  • OS:
  • Headscale version: main (01b85e5)
  • Tailscale version: 1.50.0
  • Headscale is behind a (reverse) proxy
  • Headscale runs in a container
@vsychov vsychov added the bug Something isn't working label Sep 26, 2023
@vsychov
Copy link
Contributor Author

vsychov commented Sep 26, 2023

Headscale logs

2023-09-26T10:30:46Z INF unary dur=3.564289 md={":authority":"/var/run/headscale/headscale.sock","authorization":"Bearer 1TwrG2b3xg.RTzm67ZAwinZwL7F8s7exAHr-bgvjoGKfcimdcnau5k","content-type":"application/grpc","grpcgateway-accept":"application/json","grpcgateway-accept-language":"ru,en;q=0.9","grpcgateway-authorization":"Bearer 1TwrG2b3xg.RTzm67ZAwinZwL7F8s7exAHr-bgvjoGKfcimdcnau5k","grpcgateway-referer":"https://headscale-test.example.com/web/users.html","grpcgateway-user-agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36","user-agent":"grpc-go/1.54.0","x-forwarded-for":"2a01:4f8:c012:98a1:4000::2, 10.61.19.4","x-forwarded-host":"headscale-test.example.com"} method=ListPreAuthKeys req={"user":"test-user"} service=headscale.v1.HeadscaleService
2023-09-26T10:31:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF Successfully authenticated via AuthKey ips="100.64.0.1, fd7a:115c:a1e0::1" node=tmp-tailscale-fra1-01 noise=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:79 > Received endpoint update endpoints=[] node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=true readOnly=false stream=false
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.9161
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.54268
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:79 > Received endpoint update endpoints=["SOME_IP_6:41641","10.19.0.7:41641","10.114.0.4:41641"] node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=true readOnly=false stream=false
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.90094
2023-09-26T10:31:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF Successfully authenticated via AuthKey ips="100.64.0.3, fd7a:115c:a1e0::3" node=tmp-tailscale-fra1-02 noise=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:79 > Received endpoint update endpoints=[] node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=true readOnly=false stream=false
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.7588
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.51507
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.565959
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.170731
2023-09-26T10:31:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:79 > Received endpoint update endpoints=["SOME_IP_4:41641","10.19.0.5:41641","10.114.0.2:41641"] node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=true readOnly=false stream=false
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.65416
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.99611
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:79 > Received endpoint update endpoints=["SOME_IP_4:41641","10.19.0.5:41641","10.114.0.2:41641"] node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=true readOnly=false stream=false
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.06476
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.70738
2023-09-26T10:31:44Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF Successfully authenticated via AuthKey ips="100.64.0.4, fd7a:115c:a1e0::4" node=tmp-tailscale-fra1-03 noise=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:79 > Received endpoint update endpoints=[] node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=true readOnly=false stream=false
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.620351
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.548391
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.86478
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.73166
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.75554
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.10565
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:79 > Received endpoint update endpoints=["SOME_IP_5:41641","10.19.0.6:41641","10.114.0.3:41641"] node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=true readOnly=false stream=false
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.00193
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.67812
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.15207
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:79 > Received endpoint update endpoints=["SOME_IP_5:41641","10.19.0.6:41641","10.114.0.3:41641"] node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=true readOnly=false stream=false
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.90793
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.45008
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.91174
2023-09-26T10:31:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:31:59Z INF unary dur=3.08444 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=GetRoutes req={} service=headscale.v1.HeadscaleService
2023-09-26T10:32:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF unary dur=15.114319 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"7"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true timeSpent=1.23756
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.27698
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.9452
2023-09-26T10:32:07Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF unary dur=13.624249 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"8"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.12517
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=2.01943
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true timeSpent=0.93926
2023-09-26T10:32:08Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF unary dur=11.801359 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"9"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true timeSpent=2.08784
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.63943
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.724679
2023-09-26T10:32:10Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF unary dur=12.759089 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"10"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.00864
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.66158
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true timeSpent=1.03664
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:12Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF unary dur=13.268199 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"11"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.56972
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.81885
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true timeSpent=0.95121
2023-09-26T10:32:13Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF unary dur=7.943639 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"12"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.9575
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true timeSpent=1.61645
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.67004
2023-09-26T10:32:15Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF unary dur=13.474909 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"13"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.94827
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.37802
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.84038
2023-09-26T10:32:17Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF unary dur=11.046579 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"14"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.84525
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.30437
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.66417
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:19Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF unary dur=13.589619 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=EnableRoute req={"routeId":"15"} service=headscale.v1.HeadscaleService
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.11535
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.66543
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.37244
2023-09-26T10:32:20Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:26Z INF unary dur=2.38532 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=GetRoutes req={} service=headscale.v1.HeadscaleService
2023-09-26T10:32:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-01 prefix=10.0.0.0/8
2023-09-26T10:32:28Z INF found new primary route new_node=tmp-tailscale-fra1-02 old_node=tmp-tailscale-fra1-01 prefix=10.0.0.0/8
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true timeSpent=0.56437
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.35632
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.50105
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=2.26454
2023-09-26T10:32:28Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-02 prefix=10.0.0.0/8
2023-09-26T10:32:48Z INF found new primary route new_node=tmp-tailscale-fra1-03 old_node=tmp-tailscale-fra1-02 prefix=10.0.0.0/8
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.72655
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true timeSpent=1.101489
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=2.062459
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.52378
2023-09-26T10:32:48Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:32:53Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:32:53Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:32:58Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:32:58Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:03Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:03Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:08Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:08Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:13Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:13Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:18Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:18Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:23Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:23Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:28Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:28Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:33Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:33Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:38Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:38Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:43Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:43Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:48Z INF node offline, finding a new primary subnet node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:48Z WRN no alternative primary route found node=tmp-tailscale-fra1-03 prefix=10.0.0.0/8
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > The client has closed the connection node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.47176
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.084091
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=2.69637
2023-09-26T10:33:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:21Z INF unary dur=2.606729 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=GetRoutes req={} service=headscale.v1.HeadscaleService
2023-09-26T10:34:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:27Z INF unary dur=5.15441 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=ListNodes req={} service=headscale.v1.HeadscaleService
2023-09-26T10:34:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > The client has closed the connection node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.603329
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.857789
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.046969
2023-09-26T10:34:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > The client has closed the connection node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.760371
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.07742
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.663069
2023-09-26T10:35:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:44Z INF unary dur=4.7204 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=ListNodes req={} service=headscale.v1.HeadscaleService
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > The client has closed the connection node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.26327
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.86441
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.96402
2023-09-26T10:36:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > The client has closed the connection node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.77852
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.56398
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.81825
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:37:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:18Z INF unary dur=5.21342 md={":authority":"/var/run/headscale/headscale.sock","content-type":"application/grpc","user-agent":"grpc-go/1.54.0"} method=ListNodes req={} service=headscale.v1.HeadscaleService
2023-09-26T10:38:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > The client has closed the connection node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending initial map node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-03 node_key=983645f619c4e5281753e8cec3e90c3a4da96758c4d1004e47df15ea702c3d7a noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Received update node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Sending PeerChanged MapResponse node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true timeSpent=0.95507
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.86308
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:339 > update sent node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true timeSpent=1.22468
2023-09-26T10:38:53Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:39:02Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=MacBook-Pro-Test node_key=4960866dfbc111c3b5040bc72ef2148fdc6811687bb0e21315d70c69e2cfb549 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:39:26Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-01 node_key=f1f3e10b5368c692505c1c772d4791b48fde25d048ad1ab93a6f4dbec4316b61 noise=true omitPeers=false readOnly=false stream=true
2023-09-26T10:39:43Z INF go/src/headscale/hscontrol/poll.go:33 > Waiting for update on stream channel node=tmp-tailscale-fra1-02 node_key=329df4abd9540ecebb818bfc99bd8127ead3b916f9e537fff9e4d5f10137dd11 noise=true omitPeers=false readOnly=false stream=true

@vsychov
Copy link
Contributor Author

vsychov commented Sep 26, 2023

@kradalby

@kradalby kradalby added the well described ❤️ Issues that are well described and researched, making it easier for maintainers. label Sep 26, 2023
@kradalby
Copy link
Collaborator

@vsychov Thanks for the awesome and comprehensive writeup, I truly appreciate it.

I will try to get to this shortly, I have some theories, but need some time to sit down with it.

@kradalby
Copy link
Collaborator

kradalby commented Dec 9, 2023

I've merged a bunch of fixes in #1564, please give it a go and come back to me.

@kradalby kradalby added this to the v0.23.0 milestone Dec 10, 2023
@kradalby
Copy link
Collaborator

0.23.0-alpha2 addresses a series of issues with node synchronisation, online status and subnet routers, please test this release and report back if the issue still persist.

@kradalby
Copy link
Collaborator

@almereyda
Copy link

almereyda commented Feb 15, 2024

To note, the tag of the container image moved from 0.23.0-alpha3 to v0.23.0-alpha4 by introducing the v in the beginning.

We also had to change the command that is run in the container from headscale serve to serve.

Also the syntax from database configuration in config.yaml changed slightly in 94b30ab #1700.

This also comes with a new parameter automatically_add_embedded_derp_region: true one wants to copy. I'm regularily running diff config/config.yaml config-example.yaml on a fresh download of the example to find eventual, sic, differences.

Although the image possibly misses a directory at /var/run/headscale/, since the daemon cannot bind the gRPC socket to the expected location, due to no such file or directory. We are solving this by mapping an empty directory at the location.

All of these are probably worth noting as breaking changes, as they will possibly prohibit a seamless upgrade path.

@TotoTheDragon
Copy link
Contributor

To note, the tag of the container image moved from 0.23.0-alpha3 to v0.23.0-alpha4 by introducing the v in the beginning.

We also had to change the command that is run in the container from headscale serve to serve.

Also the syntax from database configuration in config.yaml changed slightly in 94b30ab #1700.

This also comes with a new parameter automatically_add_embedded_derp_region: true one wants to copy. I'm regularily running diff config/config.yaml config-example.yaml on a fresh download of the example to find eventual, sic, differences.

Although the image possibly misses a directory at /var/run/headscale/, since the daemon cannot bind the gRPC socket to the expected location, due to no such file or directory. We are solving this by mapping an empty directory at the location.

All of these are probably worth noting as breaking changes, as they will possibly prohibit a seamless upgrade path.

Made an issue regarding config migrations for you #1758

Regarding the docker part, that is of course not officially supported and not well documented. If you feel it is a big issue as of now, feel free to create an issue (and possibly PR).

@kradalby
Copy link
Collaborator

Could you please test if this is still the case with https://github.com/juanfont/headscale/releases/tag/v0.23.0-alpha5 ?

@almereyda
Copy link

Thank you, very well.

Upgrading alpha4 to alpha5 (in a container) has Headscale end up in a startup crash loop:

2024-02-19T16:29:12Z FTL home/runner/work/headscale/headscale/cmd/headscale/cli/root.go:54 > Failed to get headscale configuration error="netip.ParsePrefix(\"\"): no '/'"

This is alleviated with changing the name of the configuration key ip_prefixes to prefixes and changing it into a dictionary with separate keys for v6 and v4, as the diff tells.

< ip_prefixes:                                                                                                                                     
<   - fd7a:115c:a1e0::/48                                                                                                                          
<   - 100.64.0.0/10                                                                                                                                
---
> prefixes:
>   v6: fd7a:115c:a1e0::/48
>   v4: 100.64.0.0/10

It will be good to include this information in the migration guide as well.

If there is interest, I can take look at https://github.com/juanfont/headscale/blob/main/docs/running-headscale-container.md again and prepare a PR including the current changes.

And because we fixed the broken migration manually, we cannot run the new migration, as the column to be deleted does not exist right now #1748.

2024-02-19T16:39:43Z FTL Migration failed: ERROR: column "last_successful_update" of relation "nodes" does not exist (SQLSTATE 42703) error="ERROR: column \"last_successful_update\" of relation \"nodes\" does not exist (SQLSTATE 42703)"

Running alter table nodes add column last_successful_update timestamp; allowed to apply the migration in our special case and alpha5 is now running as expected.

@kradalby
Copy link
Collaborator

Could you please try the newest alpha (https://github.com/juanfont/headscale/releases/tag/v0.23.0-alpha6) and report back?

@vsychov
Copy link
Contributor Author

vsychov commented Apr 18, 2024

Thanks @kradalby , I'll make tests today or tomorrow

@vsychov
Copy link
Contributor Author

vsychov commented Apr 20, 2024

Hello @kradalby , I tried testing it on the alpha-8 revision but couldn't manage it due to an issue with ACL. Here's the ACL file:

"acls":
- "action": "accept"
  "dst":
  - "*:*"
  "src":
  - "group:full-access"
"groups":
  "group:full-access":
  - "user.example.com"
"ssh":
- "action": "accept"
  "dst":
  - "*"
  "src":
  - "group:full-access"
  "users":
  - "root"

I created two nodes:

  1. tmp-tailscale-1-ams3-do
  2. tmp-tailscale-2-ams3-do

Then, I connected them to headscale using a pre-auth key for the user user.example.com:

tailscale up --auth-key XXXX --advertise-exit-node --login-server=https://headscale-test.example.com --advertise-routes=10.110.0.0/16 --shields-up=false

Node details:

ID | Hostname                | Name                    | MachineKey | NodeKey | User                 | IP addresses | Ephemeral | Last seen           | Expiration          | Connected | Expired
1  | tmp-tailscale-1-ams3-do | tmp-tailscale-1-ams3-do | [uvObE]    | [JSAmz] | user.example.com     | ,            | false     | 2024-04-20 09:06:14 | 0001-01-01 00:00:00 | online    | no
2  | tmp-tailscale-2-ams3-do | tmp-tailscale-2-ams3-do | [MvLkM]    | [nIo1T] | user.example.com     | ,            | false     | 2024-04-20 09:10:54 | 0001-01-01 00:00:00 | online    | no

However, both nodes are invisible to each other:

root@tmp-tailscale-2-ams3-do:~# tailscale status
tmp-tailscale-2-ams3-do user.example.com linux   idle; offers exit node
root@tmp-tailscale-1-ams3-do:~# tailscale status
tmp-tailscale-1-ams3-do user.example.com linux   idle; offers exit node

Additionally, I noticed that there are no IP addresses in headscale node ls, which might also be incorrect.

@kradalby
Copy link
Collaborator

kradalby commented Apr 29, 2024

Yes the IPs is strange I suspect they might be the case. Is this sqlite or postgres? do you have the config?

Edit: the config might not have the new IP prefix syntax?

@vsychov
Copy link
Contributor Author

vsychov commented Apr 29, 2024

@kradalby, you are right! There were missing prefixes in configuration, probably it's also a validation bug. I'll try testing with the new config.

@kradalby
Copy link
Collaborator

Yea, we need to throw an error if there are no prefixes!

@kradalby
Copy link
Collaborator

https://github.com/juanfont/headscale/releases/tag/v0.23.0-alpha10 was also released to address a couple of regressions in the ACL.

@kradalby
Copy link
Collaborator

and addressed no prefix issue in #1918

@kradalby
Copy link
Collaborator

kradalby commented May 1, 2024

@vsychov let me know when you have had a time to give this a go, if these issues are resolved, I will tag a beta release after resolving one other issue.

@vsychov
Copy link
Contributor Author

vsychov commented May 2, 2024

@kradalby , I was just about to write that everything was fine, but it seems something went wrong. I deployed a test environment consisting of 3 machines and one headscale control server.

I left it running for about 3 days, and for the first 24 hours, it worked super stably. Subnet routers failed over very quickly, there were no problems with nodes going offline, and so on. Everything seemed just fine. But literally just before writing here, I decided to do a retest and found that both subnet routers became Primary == false.

ID | Node                       | Prefix     | Advertised | Enabled | Primary
3  | tmp-tailscale-test-02-ams3 | 0.0.0.0/0  | true       | true    | -
1  | tmp-tailscale-test-02-ams3 | ::/0       | true       | true    | -
2  | tmp-tailscale-test-02-ams3 | 10.0.0.0/8 | true       | true    | false
5  | tmp-tailscale-test-ams3    | 0.0.0.0/0  | true       | true    | -
6  | tmp-tailscale-test-ams3    | ::/0       | true       | true    | -
4  | tmp-tailscale-test-ams3    | 10.0.0.0/8 | true       | true    | false

Consequently, from the clients, the subnet 10.0.0.0/8 became inaccessible.

➜  ~ /Applications/Tailscale.app/Contents/MacOS/Tailscale ping 10.110.0.9
2024/05/02 11:16:35 no matching peer

Testing was performed on version 0.23.0-alpha8.

If I can provide any additional information that would help identify the cause of this behavior, let me know, and I'll try to get it (perhaps logs would be useful or something else).

@kradalby
Copy link
Collaborator

kradalby commented May 2, 2024

@vsychov do you have the log of the machine? that would be helpful.

If you cannot share it, it would be useful to see if you see a lot log lines with rejected in them.

@vsychov
Copy link
Contributor Author

vsychov commented May 2, 2024

@kradalby, which machine's log? Where was headscale run? Or machine with tailscale?

@kradalby
Copy link
Collaborator

kradalby commented May 2, 2024

Headscale please

@vsychov
Copy link
Contributor Author

vsychov commented May 2, 2024

I noticed that it was at the 'INFO' level, so there are no lines with rejected. I'll switch it to trace level and rerun the tests. Hopefully, I can reproduce it again. However, there's a full log available; perhaps it contains something useful. Additionally, would it make sense to downgrade logs like 'Sending Changed MapResponse' or 'received stream update' to debug level from info?
headscale.log

@vsychov
Copy link
Contributor Author

vsychov commented May 2, 2024

I've switched the logs to trace mode, and I suggest waiting a few more days in an attempt to reproduce the issue. Meanwhile, as far as I can see, headscale doesn't seem to perform route reselection upon restart, or it seems better to do it once in a while, for example, every 10 seconds (the time could be configurable), by going through all routes and finding those without any 'Primary' nodes, and forcibly performing reselections. This way, it seems possible to protect against some failures when routes are chosen based on events (as I understand it, that's how it's currently done).

@vsychov
Copy link
Contributor Author

vsychov commented May 8, 2024

I'm writing an update on the testing results. Since I started running headscale with logs in trace mode, I still haven't been able to reproduce this issue. It seems to be a very rare case, and everything else looks very stable at the moment.

I'll continue to keep the headscale test instance running in the hope that I can reproduce this situation and gather more logs. Perhaps I'll increase the size of the test infrastructure, add more clients, and try to reproduce the problem in that scenario. However, I still believe that a better solution would be to write a background goroutine that monitors all routes and, in case a route doesn't have an Primary node, selects one. I can try to submit a PR with this approach if you approve @kradalby .

@kradalby
Copy link
Collaborator

I've fixed up some of the things that could cause a deadlock in https://github.com/juanfont/headscale/releases/tag/v0.23.0-alpha12, I will leave this open for you to test, and hopefully we can close this.

@almereyda

This comment was marked as abuse.

@juanfont
Copy link
Owner

We reckon this is now fixed, after a significant redesign of the state machine.

Can you open a new issue should this be present in the new release?

@vsychov
Copy link
Contributor Author

vsychov commented Jul 22, 2024

I completed all my tests, and am not able to reproduce this issue anymore. Thanks for your great job!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working well described ❤️ Issues that are well described and researched, making it easier for maintainers.
Projects
None yet
Development

No branches or pull requests

5 participants