Skip to content

Instantly share code, notes, and snippets.

@bpmct
Created November 29, 2022 15:43
Show Gist options
  • Save bpmct/a5fd526c52bd35f906ef05b7383a43b9 to your computer and use it in GitHub Desktop.
Save bpmct/a5fd526c52bd35f906ef05b7383a43b9 to your computer and use it in GitHub Desktop.
+ trap waitonexit EXIT
+ mktemp -d -t coder.XXXXXX
+ BINARY_DIR=/tmp/coder.XJUdiE
+ BINARY_NAME=coder
+ BINARY_URL=https://dev.coder.com/bin/coder-linux-amd64
+ cd /tmp/coder.XJUdiE
+ :
+ status=
+ command -v curl
+ curl -fsSL --compressed https://dev.coder.com/bin/coder-linux-amd64 -o coder
+ break
+ chmod +x coder
+ export CODER_AGENT_AUTH=token
+ export CODER_AGENT_URL=https://dev.coder.com/
+ exec ./coder agent
2022-11-29 15:36:44.178 [INFO] <./cli/agent.go:63> workspaceAgent.func1 spawning reaper process
2022-11-29 15:36:44.300 [INFO] <./cli/agent.go:78> workspaceAgent.func1 starting agent {"url": "https://dev.coder.com/", "auth": "token", "version": "v0.12.9-devel+a8f5af12"}
2022-11-29 15:36:44.300 [DEBUG] <./cli/server.go:1183> serveHandler http server listening {"addr": "127.0.0.1:6060", "name": "pprof"}
2022-11-29 15:36:44.300 [INFO] <./agent/agent.go:405> (*agent).init generating host key
2022-11-29 15:36:44.828 [INFO] <./agent/agent.go:138> (*agent).runLoop running loop
2022-11-29 15:36:44.871 [INFO] <./agent/agent.go:177> (*agent).run fetched metadata
2022-11-29 15:36:44.872 [DEBUG] <./agent/agent.go:206> (*agent).run running tailnet with derpmap {"derpmap": {"Regions": {"999": {"EmbeddedRelay": true, "RegionID": 999, "RegionCode": "coder", "RegionName": "Council Bluffs, Iowa", "Nodes": [{"Name": "999stun0", "RegionID": 999, "HostName": "stun.l.google.com", "STUNPort": 19302, "STUNOnly": true}, {"Name": "999b", "RegionID": 999, "HostName": "dev.coder.com", "STUNPort": -1, "DERPPort": 443}]}}}}
2022-11-29 15:36:44.872 [INFO] <./agent/agent.go:377> (*agent).runStartupScript running startup script ...
"script": #!/bin/bash
# home folder can be empty, so copying default bash settings
if [ ! -f ~/.profile ]; then
cp /etc/skel/.profile $HOME
fi
if [ ! -f ~/.bashrc ]; then
cp /etc/skel/.bashrc $HOME
fi
# install and start code-server
curl -fsSL https://code-server.dev/install.sh | sh | tee code-server-install.log
code-server --auth none --port 13337 | tee code-server-install.log &
2022-11-29 15:36:44.872 [DEBUG] <./agent/agent.go:212> (*agent).run creating tailnet
2022-11-29 15:36:44.873 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] using fake (no-op) tun device
2022-11-29 15:36:44.873 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] using fake (no-op) OS network configurator
2022-11-29 15:36:44.873 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] using fake (no-op) DNS configurator
2022-11-29 15:36:44.873 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 dns: using dns.noopManager
2022-11-29 15:36:44.873 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 link state: interfaces.State{defaultRoute=eth0 ifs={eth0:[10.56.5.5/32]} v4=true v6=false}
2022-11-29 15:36:44.873 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: failed to force-set UDP read buffer size to 7340032: operation not permitted
2022-11-29 15:36:44.873 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: failed to force-set UDP write buffer size to 7340032: operation not permitted
2022-11-29 15:36:44.873 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: failed to force-set UDP read buffer size to 7340032: operation not permitted
2022-11-29 15:36:44.874 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: failed to force-set UDP write buffer size to 7340032: operation not permitted
2022-11-29 15:36:44.874 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] couldn't create raw v4 disco listener, using regular listener instead: raw disco listening disabled, SO_MARK unavailable
2022-11-29 15:36:44.874 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] couldn't create raw v6 disco listener, using regular listener instead: raw disco listening disabled, SO_MARK unavailable
2022-11-29 15:36:44.874 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: disco key = d:39bbc8a7f5237940
2022-11-29 15:36:44.874 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 Creating WireGuard device...
2022-11-29 15:36:44.875 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 Bringing WireGuard device up...
2022-11-29 15:36:44.875 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] UDP bind has been updated
2022-11-29 15:36:44.875 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] Routine: receive incoming receiveDERP - started
2022-11-29 15:36:44.875 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] Routine: receive incoming v6 - started
2022-11-29 15:36:44.875 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] Routine: receive incoming v4 - started
2022-11-29 15:36:44.875 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] Interface state was Down, requested Up, now Up
2022-11-29 15:36:44.875 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 Bringing router up...
2022-11-29 15:36:44.876 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] warning: fakeRouter.Up: not implemented.
2022-11-29 15:36:44.876 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 Clearing router settings...
2022-11-29 15:36:44.876 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] warning: fakeRouter.Set: not implemented.
2022-11-29 15:36:44.876 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 Starting link monitor...
2022-11-29 15:36:44.876 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 Engine created.
2022-11-29 15:36:44.876 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: SetPrivateKey called (init)
2022-11-29 15:36:44.877 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] magicsock: got updated network map; 0 peers
2022-11-29 15:36:44.877 [DEBUG] (tailnet.netstack) <./tailnet/conn.go:712> Logger.func1 [v2] netstack: registered IP fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128
2022-11-29 15:36:44.877 [DEBUG] <./agent/agent.go:225> (*agent).run running coordinator
2022-11-29 15:36:44.892 [INFO] <./agent/agent.go:361> (*agent).runCoordinator connected to coordination server
2022-11-29 15:36:44.892 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:36:44.892743Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 0, "derp_latency": null, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": null}}
2022-11-29 15:36:44.897 [DEBUG] (tailnet) <./tailnet/conn.go:352> (*Conn).UpdateNodes adding node {"node": {"id": 2559511147709220916, "as_of": "2022-11-29T15:35:49.787688Z", "key": "nodekey:3f159ae6e58c523444373e0cf5d02f684e6d8d8be02bee8bb40b12181006230c", "disco": "discokey:70a33fb56e751c1e604285fb6dfc568b3be72d375ce26ae5986a81eebc21345a", "preferred_derp": 999, "derp_latency": {"999-v4": 0.00239675}, "addresses": ["fd7a:115c:a1e0:43c5:8eff:2261:b7a:f88/128"], "allowed_ips": ["fd7a:115c:a1e0:43c5:8eff:2261:b7a:f88/128"], "endpoints": ["34.71.26.24:39545", "10.128.0.41:39545"]}}
2022-11-29 15:36:44.897 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] magicsock: got updated network map; 1 peers
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 wgengine: Reconfig: configuring userspace WireGuard config (with 0/1 peers)
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] UAPI: Updating private key
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 wgengine: Reconfig: configuring router
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] warning: fakeRouter.Set: not implemented.
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 wgengine: Reconfig: configuring DNS
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 dns: Set: {DefaultResolvers:[] Routes:{} SearchDomains:[] Hosts:0}
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 "dns: Resolvercfg: {Routes:{} Hosts:0 LocalDomains:[], cloud=\"gcp\"}"
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 dns: OScfg: {Nameservers:[] SearchDomains:[] MatchDomains:[] Hosts:[]}
2022-11-29 15:36:44.898 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] wgengine: Reconfig done
2022-11-29 15:36:44.899 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 netcheck: netcheck: UDP is blocked, trying HTTPS
2022-11-29 15:36:44.900 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 netcheck: [v1] measureAllICMPLatency: listen ip4:icmp 0.0.0.0: socket: operation not permitted
2022-11-29 15:36:44.912 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 netcheck: [v1] report: udp=true v6=false v6os=false mapvarydest=false hair= portmap= v4a=35.232.53.156:57394 derp=999 derpdist=999v4:1ms
2022-11-29 15:36:44.912 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: home is now derp-999 (coder)
2022-11-29 15:36:44.913 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: endpoints changed: 35.232.53.156:57394 (stun), 10.56.5.5:57394 (local)
2022-11-29 15:36:44.913 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: adding connection to derp-999 for home-keep-alive
2022-11-29 15:36:44.913 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: 1 active derp conns: derp-999=cr0s,wr0s
2022-11-29 15:36:44.913 [DEBUG] (tailnet) <./tailnet/conn.go:228> NewConn.func4 netinfo callback {"netinfo": {"MappingVariesByDestIP": false, "HairPinning": null, "WorkingIPv6": false, "OSHasIPv6": false, "WorkingUDP": true, "WorkingICMPv4": false, "UPnP": false, "PMP": false, "PCP": false, "PreferredDERP": 999, "DERPLatency": {"999-v4": 0.001220768}}}
2022-11-29 15:36:44.913 [DEBUG] (tailnet) <./tailnet/conn.go:209> NewConn.func3 wireguard status {"status": "\u0026{AsOf:2022-11-29 15:36:44.913630475 +0000 UTC m=+0.732651632 Peers:[] LocalAddrs:[{Addr:35.232.53.156:57394 Type:stun} {Addr:10.56.5.5:57394 Type:local}] DERPs:1}", "err": null}
2022-11-29 15:36:44.913 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:36:44.913696Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.001220768}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": null}}
2022-11-29 15:36:44.913 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:36:44.913912Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.001220768}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": ["35.232.53.156:57394", "10.56.5.5:57394"]}}
2022-11-29 15:36:44.914 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 derphttp.Client.Connect: connecting to derp-999 (coder)
2022-11-29 15:36:44.914 [DEBUG] (tailnet) <./tailnet/conn.go:209> NewConn.func3 wireguard status {"status": "\u0026{AsOf:2022-11-29 15:36:44.914286033 +0000 UTC m=+0.733307203 Peers:[] LocalAddrs:[{Addr:35.232.53.156:57394 Type:stun} {Addr:10.56.5.5:57394 Type:local}] DERPs:1}", "err": null}
2022-11-29 15:36:44.914 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:36:44.914482Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.001220768}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": ["35.232.53.156:57394", "10.56.5.5:57394"]}}
2022-11-29 15:36:44.917 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 netcheck: [v1] report: udp=true v6=false v6os=false mapvarydest= hair= portmap= v4a=35.232.53.156:57394 derp=999 derpdist=999v4:0s
2022-11-29 15:36:44.918 [DEBUG] (tailnet) <./tailnet/conn.go:228> NewConn.func4 netinfo callback {"netinfo": {"MappingVariesByDestIP": null, "HairPinning": null, "WorkingIPv6": false, "OSHasIPv6": false, "WorkingUDP": true, "WorkingICMPv4": false, "UPnP": false, "PMP": false, "PCP": false, "PreferredDERP": 999, "DERPLatency": {"999-v4": 0.000387845}}}
2022-11-29 15:36:44.918 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:36:44.918114Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.000387845}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": ["35.232.53.156:57394", "10.56.5.5:57394"]}}
2022-11-29 15:36:44.927 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: derp-999 connected; connGen=1
2022-11-29 15:37:09.652 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 netcheck: [v1] report: udp=true v6=false v6os=false mapvarydest= hair= portmap= v4a=35.232.53.156:57394 derp=999 derpdist=999v4:1ms
2022-11-29 15:37:22.965 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 wgengine: idle peer [PxWa5] now active, reconfiguring WireGuard
2022-11-29 15:37:22.965 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 wgengine: Reconfig: configuring userspace WireGuard config (with 1/1 peers)
2022-11-29 15:37:22.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - UAPI: Created
2022-11-29 15:37:22.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - UAPI: Updating endpoint
2022-11-29 15:37:22.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - UAPI: Removing all allowedips
2022-11-29 15:37:22.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - UAPI: Adding allowedip
2022-11-29 15:37:22.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - UAPI: Updating persistent keepalive interval
2022-11-29 15:37:22.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Starting
2022-11-29 15:37:22.966 [DEBUG] (tailnet) <./tailnet/conn.go:209> NewConn.func3 wireguard status {"status": "\u0026{AsOf:2022-11-29 15:37:22.966698003 +0000 UTC m=+38.785719163 Peers:[{TxBytes:0 RxBytes:0 LastHandshake:1970-01-01 00:00:00 +0000 UTC NodeKey:nodekey:3f159ae6e58c523444373e0cf5d02f684e6d8d8be02bee8bb40b12181006230c}] LocalAddrs:[{Addr:35.232.53.156:57394 Type:stun} {Addr:10.56.5.5:57394 Type:local}] DERPs:1}", "err": null}
2022-11-29 15:37:22.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Received handshake initiation
2022-11-29 15:37:22.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending handshake response
2022-11-29 15:37:22.967 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 magicsock: disco: node [PxWa5] d:70a33fb56e751c1e now using 10.128.0.41:39545
2022-11-29 15:37:22.967 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:37:22.967327Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.000387845}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": ["35.232.53.156:57394", "10.56.5.5:57394"]}}
2022-11-29 15:37:22.968 [DEBUG] (tailnet) <./tailnet/conn.go:352> (*Conn).UpdateNodes adding node {"node": {"id": 2559511147709220916, "as_of": "2022-11-29T15:37:22.967916Z", "key": "nodekey:3f159ae6e58c523444373e0cf5d02f684e6d8d8be02bee8bb40b12181006230c", "disco": "discokey:70a33fb56e751c1e604285fb6dfc568b3be72d375ce26ae5986a81eebc21345a", "preferred_derp": 999, "derp_latency": {"999-v4": 0.00239675}, "addresses": ["fd7a:115c:a1e0:43c5:8eff:2261:b7a:f88/128"], "allowed_ips": ["fd7a:115c:a1e0:43c5:8eff:2261:b7a:f88/128"], "endpoints": ["34.71.26.24:39545", "10.128.0.41:39545"]}}
2022-11-29 15:37:22.968 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] magicsock: got updated network map; 1 peers
2022-11-29 15:37:22.969 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 wgengine: Reconfig: configuring userspace WireGuard config (with 1/1 peers)
2022-11-29 15:37:22.969 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - UAPI: Updating persistent keepalive interval
2022-11-29 15:37:22.969 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:37:22.969 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] wgengine: Reconfig done
2022-11-29 15:37:32.369 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 netcheck: [v1] report: udp=true v6=false v6os=false mapvarydest= hair= portmap= v4a=35.232.53.156:57394 derp=999 derpdist=999v4:1ms
2022-11-29 15:37:32.972 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:37:55.118 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 netcheck: [v1] report: udp=true v6=false v6os=false mapvarydest= hair= portmap= v4a=35.232.53.156:57394 derp=999 derpdist=999v4:1ms
2022-11-29 15:37:57.973 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:37:57.973 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:38:17.254 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 netcheck: [v1] report: udp=true v6=false v6os=false mapvarydest= hair= portmap= v4a=35.232.53.156:57394 derp=999 derpdist=999v4:1ms
2022-11-29 15:38:22.974 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:38:22.975 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:38:22.975 [DEBUG] (tailnet) <./tailnet/conn.go:209> NewConn.func3 wireguard status {"status": "\u0026{AsOf:2022-11-29 15:38:22.975492746 +0000 UTC m=+98.794513901 Peers:[{TxBytes:284 RxBytes:340 LastHandshake:2022-11-29 15:37:22.970672829 +0000 UTC NodeKey:nodekey:3f159ae6e58c523444373e0cf5d02f684e6d8d8be02bee8bb40b12181006230c}] LocalAddrs:[{Addr:35.232.53.156:57394 Type:stun} {Addr:10.56.5.5:57394 Type:local}] DERPs:1}", "err": null}
2022-11-29 15:38:22.975 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:38:22.975748Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.000387845}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": ["35.232.53.156:57394", "10.56.5.5:57394"]}}
2022-11-29 15:38:22.976 [DEBUG] (tailnet) <./tailnet/conn.go:352> (*Conn).UpdateNodes adding node {"node": {"id": 2559511147709220916, "as_of": "2022-11-29T15:38:22.97529Z", "key": "nodekey:3f159ae6e58c523444373e0cf5d02f684e6d8d8be02bee8bb40b12181006230c", "disco": "discokey:70a33fb56e751c1e604285fb6dfc568b3be72d375ce26ae5986a81eebc21345a", "preferred_derp": 999, "derp_latency": {"999-v4": 0.00239675}, "addresses": ["fd7a:115c:a1e0:43c5:8eff:2261:b7a:f88/128"], "allowed_ips": ["fd7a:115c:a1e0:43c5:8eff:2261:b7a:f88/128"], "endpoints": ["34.71.26.24:39545", "10.128.0.41:39545"]}}
2022-11-29 15:38:22.976 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] magicsock: got updated network map; 1 peers
2022-11-29 15:38:22.976 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v1] wgengine: Reconfig done
2022-11-29 15:38:47.976 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:38:47.976 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:39:12.977 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:39:12.978 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:39:32.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:39:32.966 [DEBUG] (tailnet) <./tailnet/conn.go:209> NewConn.func3 wireguard status {"status": "\u0026{AsOf:2022-11-29 15:39:32.966027889 +0000 UTC m=+168.785049043 Peers:[{TxBytes:444 RxBytes:564 LastHandshake:2022-11-29 15:37:22.970672829 +0000 UTC NodeKey:nodekey:3f159ae6e58c523444373e0cf5d02f684e6d8d8be02bee8bb40b12181006230c}] LocalAddrs:[{Addr:35.232.53.156:57394 Type:stun} {Addr:10.56.5.5:57394 Type:local}] DERPs:1}", "err": null}
2022-11-29 15:39:32.966 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:39:32.966278Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.000387845}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": ["35.232.53.156:57394", "10.56.5.5:57394"]}}
2022-11-29 15:39:32.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Received handshake initiation
2022-11-29 15:39:32.966 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending handshake response
2022-11-29 15:39:32.968 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:39:57.969 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:39:57.969 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:40:22.970 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:40:22.970 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:40:47.970 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:40:47.972 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:40:47.972 [DEBUG] (tailnet) <./tailnet/conn.go:209> NewConn.func3 wireguard status {"status": "\u0026{AsOf:2022-11-29 15:40:47.972148468 +0000 UTC m=+243.791169646 Peers:[{TxBytes:632 RxBytes:872 LastHandshake:2022-11-29 15:39:32.968685668 +0000 UTC NodeKey:nodekey:3f159ae6e58c523444373e0cf5d02f684e6d8d8be02bee8bb40b12181006230c}] LocalAddrs:[{Addr:35.232.53.156:57394 Type:stun} {Addr:10.56.5.5:57394 Type:local}] DERPs:1}", "err": null}
2022-11-29 15:40:47.972 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:40:47.972613Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.000387845}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": ["35.232.53.156:57394", "10.56.5.5:57394"]}}
2022-11-29 15:41:12.972 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:41:12.973 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:41:37.973 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:41:37.973 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:41:37.974 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Received handshake initiation
2022-11-29 15:41:37.974 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending handshake response
2022-11-29 15:41:37.975 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:42:02.976 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:42:02.976 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
2022-11-29 15:42:02.976 [DEBUG] (tailnet) <./tailnet/conn.go:209> NewConn.func3 wireguard status {"status": "\u0026{AsOf:2022-11-29 15:42:02.976635057 +0000 UTC m=+318.795656217 Peers:[{TxBytes:820 RxBytes:1116 LastHandshake:2022-11-29 15:41:37.975327211 +0000 UTC NodeKey:nodekey:3f159ae6e58c523444373e0cf5d02f684e6d8d8be02bee8bb40b12181006230c}] LocalAddrs:[{Addr:35.232.53.156:57394 Type:stun} {Addr:10.56.5.5:57394 Type:local}] DERPs:1}", "err": null}
2022-11-29 15:42:02.976 [DEBUG] (tailnet) <./tailnet/conn.go:546> (*Conn).sendNode.func1 sending node {"node": {"id": 1457526004339703756, "as_of": "2022-11-29T15:42:02.976959Z", "key": "nodekey:252de0c16a769e44fc2cf86f7045f7429511eb89a2a974ad9a342eb11ed87702", "disco": "discokey:39bbc8a7f52379402c73d7329e826802da3f7f25bfceef0cf1092247eb839709", "preferred_derp": 999, "derp_latency": {"999-v4": 0.000387845}, "addresses": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "allowed_ips": ["fd7a:115c:a1e0:49d6:b259:b7ac:b1b2:48f4/128"], "endpoints": ["35.232.53.156:57394", "10.56.5.5:57394"]}}
2022-11-29 15:42:27.977 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Sending keepalive packet
2022-11-29 15:42:27.977 [DEBUG] (tailnet.wgengine) <./tailnet/conn.go:712> Logger.func1 [v2] [PxWa5] - Receiving keepalive packet
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment