Ubuntu

dhcp timeout during wifi connection

Asked by Ravi

$ lsb_release -a
No LSB modules are available.
Distributor ID: Ubuntu
Description: Ubuntu 20.04.2 LTS
Release: 20.04
Codename: focal

$ sudo hwinfo --netcard
[sudo] password for ravi:
14: PCI 200.0: 0282 WLAN controller
  [Created at pci.386]
  Unique ID: qru8.kOSB13gZvM0
  Parent ID: aYFK.XZwpGIabNM5
  SysFS ID: /devices/pci0000:00/0000:00:02.3/0000:02:00.0
  SysFS BusID: 0000:02:00.0
  Hardware Class: network
  Model: "Realtek RTL8723BE PCIe Wireless Network Adapter"
  Vendor: pci 0x10ec "Realtek Semiconductor Co., Ltd."
  Device: pci 0xb723 "RTL8723BE PCIe Wireless Network Adapter"
  SubVendor: pci 0x103c "Hewlett-Packard Company"
  SubDevice: pci 0x804c
  Driver: "rtl8723be"
  Driver Modules: "rtl8723be"
  Device File: wlp2s0
  Features: WLAN
  I/O Ports: 0x2000-0x2fff (rw)
  Memory Range: 0xd0b00000-0xd0b03fff (rw,non-prefetchable)
  IRQ: 41 (778740 events)
  HW Address: 94:53:30:4d:84:27
  Permanent HW Address: 94:53:30:4d:84:27
  Link detected: yes
  WLAN channels: 1 2 3 4 5 6 7 8 9 10 11 12 13
  WLAN frequencies: 2.412 2.417 2.422 2.427 2.432 2.437 2.442 2.447 2.452 2.457 2.462 2.467 2.472
  WLAN encryption modes: WEP40 WEP104 TKIP CCMP
  WLAN authentication modes: open sharedkey wpa-psk wpa-eap
  Module Alias: "pci:v000010ECd0000B723sv0000103Csd0000804Cbc02sc80i00"
  Driver Info #0:
    Driver Status: rtl8723be is active
    Driver Activation Cmd: "modprobe rtl8723be"
  Config Status: cfg=new, avail=yes, need=no, active=unknown
  Attached to: #16 (PCI bridge)

18: PCI 100.0: 0200 Ethernet controller
  [Created at pci.386]
  Unique ID: lkKU.ZcueftKbNdB
  Parent ID: jDmU.XZwpGIabNM5
  SysFS ID: /devices/pci0000:00/0000:00:02.2/0000:01:00.0
  SysFS BusID: 0000:01:00.0
  Hardware Class: network
  Model: "Realtek RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller"
  Vendor: pci 0x10ec "Realtek Semiconductor Co., Ltd."
  Device: pci 0x8168 "RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller"
  SubVendor: pci 0x103c "Hewlett-Packard Company"
  SubDevice: pci 0x80ef
  Revision: 0x15
  Driver: "r8169"
  Driver Modules: "r8169"
  Device File: enp1s0
  I/O Ports: 0x3000-0x3fff (rw)
  Memory Range: 0xd0c04000-0xd0c04fff (rw,non-prefetchable)
  Memory Range: 0xd0c00000-0xd0c03fff (rw,non-prefetchable)
  IRQ: 36 (no events)
  HW Address: 98:e7:f4:5f:35:25
  Permanent HW Address: 98:e7:f4:5f:35:25
  Link detected: no
  Module Alias: "pci:v000010ECd00008168sv0000103Csd000080EFbc02sc00i00"
  Driver Info #0:
    Driver Status: r8169 is active
    Driver Activation Cmd: "modprobe r8169"
  Config Status: cfg=new, avail=yes, need=no, active=unknown
  Attached to: #29 (PCI bridge)

$ cat /etc/modprobe.d/rtl8723be.conf
options rtl8723be ant_sel=1 fwlps=0

Access point has no WPA enabled.

$ iwconfig wlp2s0
wlp2s0 IEEE 802.11 ESSID:"Starbucks WiFi"
          Mode:Managed Frequency:2.462 GHz Access Point: 18:64:72:6E:26:30
          Bit Rate=72.2 Mb/s Tx-Power=20 dBm
          Retry short limit:7 RTS thr=2347 B Fragment thr:off
          Power Management:off
          Link Quality=70/70 Signal level=-38 dBm
          Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0
          Tx excessive retries:0 Invalid misc:0 Missed beacon:0

$ nmcli dev wifi connect 'Starbucks WiFi'
Error: Connection activation failed: (5) IP configuration could not be reserved (no available address, timeout, etc.).

Related messages in /var/log/syslog which shows dhcp timeout:
Jul 27 19:48:06 voyager systemd[1]: Started Network Manager Script Dispatcher Service.
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7275] policy: auto-activating connection 'Starbucks WiFi' (8e7f11bb-b588-4aa6-8a6d-b9da4968342c)
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7287] device (wlp2s0): Activation: starting connection 'Starbucks WiFi' (8e7f11bb-b588-4aa6-8a6d-b9da4968342c)
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7290] device (wlp2s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7298] manager: NetworkManager state is now CONNECTING
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7307] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7314] device (wlp2s0): Activation: (wifi) connection 'Starbucks WiFi' requires no security. No secrets needed.
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7316] Config: added 'ssid' value 'Starbucks WiFi'
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7316] Config: added 'scan_ssid' value '1'
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7316] Config: added 'bgscan' value 'simple:30:-65:300'
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7316] Config: added 'key_mgmt' value 'NONE'
Jul 27 19:48:07 voyager kernel: [ 8691.973890] wlp2s0: authenticate with 18:64:72:6e:26:30
Jul 27 19:48:07 voyager wpa_supplicant[909]: wlp2s0: SME: Trying to authenticate with 18:64:72:6e:26:30 (SSID='Starbucks WiFi' freq=2462 MHz)
Jul 27 19:48:07 voyager kernel: [ 8691.984104] wlp2s0: send auth to 18:64:72:6e:26:30 (try 1/3)
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7660] device (wlp2s0): supplicant interface state: disconnected -> authenticating
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.7661] device (p2p-dev-wlp2s0): supplicant management interface state: disconnected -> authenticating
Jul 27 19:48:07 voyager kernel: [ 8692.019327] wlp2s0: authenticated
Jul 27 19:48:07 voyager wpa_supplicant[909]: wlp2s0: Trying to associate with 18:64:72:6e:26:30 (SSID='Starbucks WiFi' freq=2462 MHz)
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.8024] device (wlp2s0): supplicant interface state: authenticating -> associating
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.8025] device (p2p-dev-wlp2s0): supplicant management interface state: authenticating -> associating
Jul 27 19:48:07 voyager kernel: [ 8692.021307] wlp2s0: associate with 18:64:72:6e:26:30 (try 1/3)
Jul 27 19:48:07 voyager kernel: [ 8692.051270] wlp2s0: RX AssocResp from 18:64:72:6e:26:30 (capab=0x421 status=0 aid=1)
Jul 27 19:48:07 voyager kernel: [ 8692.051466] wlp2s0: associated
Jul 27 19:48:07 voyager wpa_supplicant[909]: wlp2s0: Associated with 18:64:72:6e:26:30
Jul 27 19:48:07 voyager wpa_supplicant[909]: wlp2s0: CTRL-EVENT-CONNECTED - Connection to 18:64:72:6e:26:30 completed [id=0 id_str=]
Jul 27 19:48:07 voyager wpa_supplicant[909]: bgscan simple: Failed to enable signal strength monitoring
Jul 27 19:48:07 voyager wpa_supplicant[909]: wlp2s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jul 27 19:48:07 voyager wpa_supplicant[909]: wlp2s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.8395] device (wlp2s0): supplicant interface state: associating -> completed
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.8396] device (wlp2s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "Starbucks WiFi"
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.8397] device (p2p-dev-wlp2s0): supplicant management interface state: associating -> completed
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.8402] device (wlp2s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jul 27 19:48:07 voyager NetworkManager[872]: <info> [1627429687.8414] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
Jul 27 19:48:07 voyager avahi-daemon[866]: Joining mDNS multicast group on interface wlp2s0.IPv6 with address fe80::f917:d397:8310:9ed1.
Jul 27 19:48:07 voyager avahi-daemon[866]: New relevant interface wlp2s0.IPv6 for mDNS.
Jul 27 19:48:07 voyager avahi-daemon[866]: Registering new address record for fe80::f917:d397:8310:9ed1 on wlp2s0.*.
Jul 27 19:48:18 voyager systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Jul 27 19:48:52 voyager NetworkManager[872]: <warn> [1627429732.6792] dhcp4 (wlp2s0): request timed out
Jul 27 19:48:52 voyager NetworkManager[872]: <info> [1627429732.6794] dhcp4 (wlp2s0): state changed unknown -> timeout
Jul 27 19:48:52 voyager NetworkManager[872]: <info> [1627429732.6795] device (wlp2s0): state change: ip-config -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed')
Jul 27 19:48:52 voyager NetworkManager[872]: <info> [1627429732.6815] manager: NetworkManager state is now CONNECTED_LOCAL
Jul 27 19:48:52 voyager NetworkManager[872]: <warn> [1627429732.6859] device (wlp2s0): Activation: failed for connection 'Starbucks WiFi'
Jul 27 19:48:52 voyager NetworkManager[872]: <info> [1627429732.6869] device (wlp2s0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Jul 27 19:48:52 voyager avahi-daemon[866]: Withdrawing address record for fe80::f917:d397:8310:9ed1 on wlp2s0.
Jul 27 19:48:52 voyager avahi-daemon[866]: Leaving mDNS multicast group on interface wlp2s0.IPv6 with address fe80::f917:d397:8310:9ed1.
Jul 27 19:48:52 voyager avahi-daemon[866]: Interface wlp2s0.IPv6 no longer relevant for mDNS.
Jul 27 19:48:52 voyager NetworkManager[872]: <info> [1627429732.7102] dhcp4 (wlp2s0): canceled DHCP transaction
Jul 27 19:48:52 voyager NetworkManager[872]: <info> [1627429732.7103] dhcp4 (wlp2s0): state changed timeout -> done
Jul 27 19:48:52 voyager kernel: [ 8736.930353] wlp2s0: deauthenticating from 18:64:72:6e:26:30 by local choice (Reason: 3=DEAUTH_LEAVING)
Jul 27 19:48:52 voyager dbus-daemon[871]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.15' (uid=0 pid=872 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jul 27 19:48:52 voyager wpa_supplicant[909]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=18:64:72:6e:26:30 reason=3 locally_generated=1
Jul 27 19:48:52 voyager systemd[1]: Starting Network Manager Script Dispatcher Service...
Jul 27 19:48:52 voyager wpa_supplicant[909]: wlp2s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Jul 27 19:48:52 voyager NetworkManager[872]: <warn> [1627429732.7312] sup-iface[0x55905ed16200,wlp2s0]: connection disconnected (reason -3)
Jul 27 19:48:52 voyager NetworkManager[872]: <info> [1627429732.7314] device (wlp2s0): supplicant interface state: completed -> disconnected
Jul 27 19:48:52 voyager NetworkManager[872]: <info> [1627429732.7314] device (p2p-dev-wlp2s0): supplicant management interface state: completed -> disconnected
Jul 27 19:48:52 voyager dbus-daemon[871]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jul 27 19:48:52 voyager systemd[1]: Started Network Manager Script Dispatcher Service.
Jul 27 19:49:02 voyager systemd[1]: NetworkManager-dispatcher.service: Succeeded.

Wifi connectivity to same access point works fine from my cell phone.

Question information

Language:
English Edit question
Status:
Expired
For:
Ubuntu Edit question
Assignee:
No assignee Edit question
Last query:
Last reply:
Revision history for this message
Launchpad Janitor (janitor) said : 		#1

This question was expired because it remained in the 'Open' state without activity for the last 15 days.