wireguard-windows: Tunnel shown as active but never completed handshake
Max Schulze
max.schulze at online.de
Sun May 16 12:07:13 UTC 2021
I am puzzled that the wireguard management UI shows "active", even
though the tunnel is not established.
I know for sure the peer is reachable via ping, but does not have
wireguard running. I am reproducing an issue where the peer went down
but the tunnel on the client kept being displayed as "active".
Wouldn't it be better to show 'tunnel up - handshake in progress' or
something similar and only switch to 'active' once there has been an
handshake?
Can I provide more debug info?
Note: the wintun errors are there even when it works - I might report a
separate Issue for them...
Best regards,
max
C:\Windows\system32>wg
interface: <>
public key: QhK<scrambled>0=
private key: (hidden)
listening port: 65170
peer: 9npne1<scrambled>CDg=
endpoint: 192.168.3.184:51820
allowed ips: 192.168.223.0/24
transfer: 0 B received, 10.12 KiB sent
persistent keepalive: every 20 seconds
2021-05-15 18:34:53.501802: [MGR] [Wintun] WintunEnumAdapters: Failed to
create adapter 2276 data
2021-05-16 13:48:04.114000: [TUN] [<>] Starting WireGuard/0.3.14
(Windows 6.1.7601; amd64)
2021-05-16 13:48:04.114000: [TUN] [<>] Watching network interfaces
2021-05-16 13:48:04.114000: [TUN] [<>] Resolving DNS names
2021-05-16 13:48:04.114000: [TUN] [<>] Creating Wintun interface
2021-05-16 13:48:04.114000: [TUN] [<>] [Wintun]
GetNetCfgInstanceIdFromDevInfo: Opening adapter 2276 device registry key
failed: Der angeforderte Geräteregistrierungsschlüssel ist nicht
vorhanden. (Code 0xE0000204)
2021-05-16 13:48:04.114000: [TUN] [<>] [Wintun] CreateAdapter: Creating
adapter
2021-05-16 13:48:04.198000: [TUN] [<>] [Wintun] SelectDriver: Using
existing driver 0.11
2021-05-16 13:48:04.660000: [TUN] [<>] Using Wintun/0.11
2021-05-16 13:48:04.660000: [TUN] [<>] Enabling firewall rules
2021-05-16 13:48:04.670000: [TUN] [<>] Dropping privileges
2021-05-16 13:48:04.670000: [TUN] [<>] Creating interface instance
2021-05-16 13:48:04.680000: [TUN] [<>] Setting interface configuration
2021-05-16 13:48:04.680000: [TUN] [<>] UAPI: Updating private key
2021-05-16 13:48:04.680000: [TUN] [<>] UAPI: Removing all peers
2021-05-16 13:48:04.680000: [TUN] [<>] peer(9npn…+CDg) - UAPI: Created
2021-05-16 13:48:04.680000: [TUN] [<>] peer(9npn…+CDg) - UAPI: Updating
endpoint
2021-05-16 13:48:04.680000: [TUN] [<>] peer(9npn…+CDg) - UAPI: Updating
persistent keepalive interval
2021-05-16 13:48:04.680000: [TUN] [<>] peer(9npn…+CDg) - UAPI: Removing
all allowedips
2021-05-16 13:48:04.680000: [TUN] [<>] peer(9npn…+CDg) - UAPI: Adding
allowedip
2021-05-16 13:48:04.680000: [TUN] [<>] Bringing peers up
2021-05-16 13:48:04.681000: [TUN] [<>] UDP bind has been updated
2021-05-16 13:48:04.681000: [TUN] [<>] peer(9npn…+CDg) - Starting
2021-05-16 13:48:04.681000: [TUN] [<>] peer(9npn…+CDg) - Sending
keepalive packet
2021-05-16 13:48:04.681000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:04.681000: [TUN] [<>] peer(9npn…+CDg) - Routine:
sequential receiver - started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: encryption worker 1 -
started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: decryption worker 1 -
started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: handshake worker 1 - started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: encryption worker 2 -
started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: decryption worker 2 -
started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: handshake worker 2 - started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: encryption worker 3 -
started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: decryption worker 3 -
started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: handshake worker 3 - started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: encryption worker 4 -
started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: decryption worker 4 -
started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: handshake worker 4 - started
2021-05-16 13:48:04.681000: [TUN] [<>] Routine: TUN reader - started
2021-05-16 13:48:04.687000: [TUN] [<>] Routine: event worker - started
2021-05-16 13:48:04.687000: [TUN] [<>] Interface state was Down,
requested Up, now Up
2021-05-16 13:48:04.687000: [TUN] [<>] Monitoring default v4 routes
2021-05-16 13:48:04.687000: [TUN] [<>] Binding v4 socket to interface 11
(blackhole=false)
2021-05-16 13:48:04.689000: [TUN] [<>] Routine: receive incoming v6 -
started
2021-05-16 13:48:04.689000: [TUN] [<>] Routine: receive incoming v4 -
started
2021-05-16 13:48:04.689000: [TUN] [<>] peer(9npn…+CDg) - Routine:
sequential sender - started
2021-05-16 13:48:04.691000: [TUN] [<>] Setting device v4 addresses
2021-05-16 13:48:04.752000: [TUN] [<>] Monitoring default v6 routes
2021-05-16 13:48:04.752000: [TUN] [<>] Binding v6 socket to interface 0
(blackhole=false)
2021-05-16 13:48:04.752000: [TUN] [<>] Setting device v6 addresses
2021-05-16 13:48:04.822000: [TUN] [<>] Listening for UAPI requests
2021-05-16 13:48:04.822000: [TUN] [<>] Startup complete
2021-05-16 13:48:09.957000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:48:09.957000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:13.991000: [MGR] [Wintun] CreateAdapterData: Opening
adapter 2276 device registry key failed: Der angeforderte
Geräteregistrierungsschlüssel ist nicht vorhanden. (Code 0xE0000204)
2021-05-16 13:48:13.991000: [MGR] [Wintun] WintunEnumAdapters: Failed to
create adapter 2276 data
2021-05-16 13:48:15.126000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:48:15.126000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:20.407000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:48:20.407000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:25.584000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:48:25.584000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:30.842000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:48:30.842000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:36.044000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:41.132000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:48:41.132000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:46.324000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:51.464000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:48:51.464000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:48:56.605000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:48:56.605000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:01.854000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:06.975000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:12.056000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:49:12.056000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:17.165000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:49:17.165000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:22.242000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:49:22.242000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:27.340000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:49:27.340000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:32.355000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 3)
2021-05-16 13:49:32.355000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:37.450000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:49:37.450000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:42.531000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:49:42.531000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:47.840000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:49:47.840000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:53.065000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:49:53.065000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:49:58.275000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:03.341000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:50:03.341000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:08.626000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:50:08.626000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:13.946000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 3)
2021-05-16 13:50:13.946000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:19.156000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 4)
2021-05-16 13:50:19.156000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:24.287000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 5)
2021-05-16 13:50:24.287000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:29.350000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 6)
2021-05-16 13:50:29.350000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:34.640000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 7)
2021-05-16 13:50:34.640000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:39.912000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 8)
2021-05-16 13:50:39.912000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:45.153000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 9)
2021-05-16 13:50:45.153000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:50.245000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 10)
2021-05-16 13:50:50.245000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:50:55.309000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 11)
2021-05-16 13:50:55.309000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:00.553000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 12)
2021-05-16 13:51:00.553000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:05.714000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 13)
2021-05-16 13:51:05.714000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:10.835000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 14)
2021-05-16 13:51:10.835000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:15.996000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 15)
2021-05-16 13:51:15.996000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:21.221000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 16)
2021-05-16 13:51:21.221000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:26.417000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 17)
2021-05-16 13:51:26.417000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:31.673000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:51:31.673000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:36.739000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 3)
2021-05-16 13:51:36.739000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:41.796000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 4)
2021-05-16 13:51:41.796000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:46.865000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:51:46.865000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:51.969000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 3)
2021-05-16 13:51:51.969000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:51:57.275000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 4)
2021-05-16 13:51:57.275000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:02.465000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 5)
2021-05-16 13:52:02.465000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:07.777000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 6)
2021-05-16 13:52:07.777000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:12.994000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 7)
2021-05-16 13:52:12.994000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:18.219000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 8)
2021-05-16 13:52:18.219000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:23.398000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 9)
2021-05-16 13:52:23.398000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:28.674000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 10)
2021-05-16 13:52:28.674000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:33.856000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 11)
2021-05-16 13:52:33.856000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:39.128000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 12)
2021-05-16 13:52:39.128000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:44.265000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 13)
2021-05-16 13:52:44.265000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:49.490000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 14)
2021-05-16 13:52:49.490000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:54.761000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 15)
2021-05-16 13:52:54.761000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:52:59.766000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
2021-05-16 13:53:04.937000: [TUN] [<>] peer(9npn…+CDg) - Handshake did
not complete after 5 seconds, retrying (try 2)
2021-05-16 13:53:04.937000: [TUN] [<>] peer(9npn…+CDg) - Sending
handshake initiation
More information about the WireGuard
mailing list