Hi Jason,
<snip>
> iwd[4842]: src/station.c:station_connect_cb() 8, result: 0
> iwd[4842]: src/station.c:station_connect_ok()
So the interesting thing is that we connect successfully, so the issue is
actually in netconfig somewhere. Can you tell me which version you had before
that worked? Was it 1.17 or earlier?
> iwd[4842]: src/netconfig.c:netconfig_ipv6_select_and_install() IPV6 configuration
disabled
> iwd[4842]: src/wiphy.c:wiphy_radio_work_done() Work item 2 done
> iwd[4842]: src/netdev.c:netdev_link_notify() event 16 on ifindex 8
> iwd[4842]: src/netdev.c:netdev_mlme_notify() MLME notification Frame TX Status(60)
> iwd[4842]: src/station.c:station_early_neighbor_report_cb() ifindex: 8, error:
-110(Connection timed out)
>
> This is the point where connection is stuck and iwctl reports 'connecting'
indefinitely despite being authenticated already.
Ok, can you get us a log with IWD_DHCP_DEBUG set as well? See 'man 7 iwd.debug'
Regards,
-Denis
Here you have. For some reason is not getting an IP:
iwd[11413]: src/netdev.c:netdev_mlme_notify() MLME notification New Station(19)
kernel: wlan0: send auth to xx:xx:xx:xx:xx:xx (try 1/3)
kernel: wlan0: send auth to xx:xx:xx:xx:xx:xx (try 2/3)
iwd[11413]: src/netdev.c:netdev_mlme_notify() MLME notification Authenticate(37)
iwd[11413]: src/netdev.c:netdev_authenticate_event()
kernel: wlan0: authenticated
kernel: wlan0: associate with xx:xx:xx:xx:xx:xx (try 1/3)
kernel: wlan0: RX AssocResp from xx:xx:xx:xx:xx:xx (capab=0x1411 status=0 aid=1)
iwd[11413]: src/netdev.c:netdev_unicast_notify() Unicast notification 129
iwd[11413]: src/netdev.c:netdev_control_port_frame_event()
kernel: wlan0: associated
iwd[11413]: src/netdev.c:netdev_link_notify() event 16 on ifindex 6
iwd[11413]: src/netdev.c:netdev_mlme_notify() MLME notification Associate(38)
iwd[11413]: src/netdev.c:netdev_associate_event()
iwd[11413]: src/netdev.c:netdev_link_notify() event 16 on ifindex 6
iwd[11413]: src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
iwd[11413]: src/netdev.c:netdev_connect_event()
iwd[11413]: src/eapol.c:eapol_handle_ptk_1_of_4() ifindex=6
iwd[11413]: src/netdev.c:netdev_link_notify() event 16 on ifindex 6
iwd[11413]: src/netdev.c:netdev_link_notify() event 16 on ifindex 6
iwd[11413]: src/netdev.c:netdev_mlme_notify() MLME notification Control Port TX
Status(139)
iwd[11413]: src/netdev.c:netdev_unicast_notify() Unicast notification 129
iwd[11413]: src/netdev.c:netdev_control_port_frame_event()
iwd[11413]: src/eapol.c:eapol_handle_ptk_3_of_4() ifindex=6
iwd[11413]: src/netdev.c:netdev_set_gtk() 6
iwd[11413]: src/station.c:station_handshake_event() Setting keys
iwd[11413]: src/netdev.c:netdev_set_tk() 6
iwd[11413]: src/netdev.c:netdev_set_rekey_offload() 6
iwd[11413]: src/netdev.c:netdev_mlme_notify() MLME notification Control Port TX
Status(139)
iwd[11413]: src/netdev.c:netdev_mlme_notify() MLME notification Notify CQM(64)
iwd[11413]: src/station.c:station_connect_cb() 6, result: 0
iwd[11413]: src/station.c:station_connect_ok()
iwd[11413]: [DHCPv4] dhcp_client_send_discover:346
iwd[11413]: [DHCPv4] l_dhcp_client_start:1174 Entering state: DHCP_STATE_SELECTING
iwd[11413]: src/netconfig.c:netconfig_ipv6_select_and_install() IPV6 configuration
disabled
iwd[11413]: src/wiphy.c:wiphy_radio_work_done() Work item 2 done
iwd[11413]: src/netdev.c:netdev_link_notify() event 16 on ifindex 6
iwd[11413]: src/netdev.c:netdev_mlme_notify() MLME notification Frame TX Status(60)
iwd[11413]: [DHCPv4] dhcp_client_timeout_resend:526
iwd[11413]: [DHCPv4] dhcp_client_send_discover:346
iwd[11413]: src/station.c:station_early_neighbor_report_cb() ifindex: 6, error:
-110(Connection timed out)
iwd[11413]: [DHCPv4] dhcp_client_timeout_resend:526
iwd[11413]: [DHCPv4] dhcp_client_send_discover:346
iwd[11413]: [DHCPv4] dhcp_client_timeout_resend:526
iwd[11413]: [DHCPv4] dhcp_client_send_discover:346
I think is relevant to the bug that I'm using this unit to start iwd.service:
# /usr/lib/systemd/system/iwd.service
[Unit]
Description=Wireless service
After=network-pre.target
Before=network.target
Wants=network.target
[Service]
Type=dbus
BusName=net.connman.iwd
ExecStart=/usr/lib/iwd/iwd
NotifyAccess=main
LimitNPROC=1
Restart=on-failure
CapabilityBoundingSet=CAP_NET_ADMIN CAP_NET_RAW CAP_NET_BIND_SERVICE
PrivateTmp=true
NoNewPrivileges=true
DevicePolicy=closed
DeviceAllow=/dev/rfkill rw
ProtectHome=yes
ProtectSystem=strict
ProtectControlGroups=yes
ProtectKernelModules=yes
ConfigurationDirectory=iwd
StateDirectory=iwd
StateDirectoryMode=0700
[Install]
WantedBy=multi-user.target
# /etc/systemd/system/iwd.service.d/override.conf
[Service]
RuntimeDirectory=resolvconf
ReadWritePaths=/etc/resolv.conf
I remeber adding the override part few months ago due to another bug [1]. For some reason,
forcing a disconnect/connect with iwctl doesn't hit the same problem.
# Generated by resolvconf
nameserver 127.0.0.1
options trust-ad edns0
[1]
https://bugs.archlinux.org/task/67069