Development discussion of WireGuard
 help / color / mirror / Atom feed
* wireguard-windows: Tunnel shown as active but never completed handshake
@ 2021-05-16 12:07 Max Schulze
  0 siblings, 0 replies; only message in thread
From: Max Schulze @ 2021-05-16 12:07 UTC (permalink / raw)
  To: wireguard

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


^ permalink raw reply	[flat|nested] only message in thread

only message in thread, other threads:[~2021-05-17 15:21 UTC | newest]

Thread overview: (only message) (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2021-05-16 12:07 wireguard-windows: Tunnel shown as active but never completed handshake Max Schulze

Development discussion of WireGuard

This inbox may be cloned and mirrored by anyone:

	git clone --mirror http://inbox.vuxu.org/wireguard/0 wireguard/git/0.git

	# If you have public-inbox 1.1+ installed, you may
	# initialize and index your mirror using the following commands:
	public-inbox-init -V2 wireguard wireguard/ http://inbox.vuxu.org/wireguard \
		wireguard@lists.zx2c4.com
	public-inbox-index wireguard

Example config snippet for mirrors.
Newsgroup available over NNTP:
	nntp://inbox.vuxu.org/vuxu.archive.wireguard


AGPL code for this site: git clone https://public-inbox.org/public-inbox.git