You are not logged in.

#1 2015-07-14 21:48:36

hilltownboy
Member
From: Massachusetts
Registered: 2013-08-15
Posts: 100

Wifi connection not found

Wifi connection not found, yet the service seems to be enabled and functioning:

* netctl-auto@wlp16s0.service - Automatic wireless network connection using netctl profiles
   Loaded: loaded (/usr/lib/systemd/system/netctl-auto@.service; enabled; vendor preset: disabled)
   Active: active (running) since Tue 2015-07-14 16:47:31 EDT; 22min ago
     Docs: man:netctl.special(7)
  Process: 277 ExecStart=/usr/bin/netctl-auto start %I (code=exited, status=0/SUCCESS)
   CGroup: /system.slice/system-netctl\x2dauto.slice/netctl-auto@wlp16s0.service
           `-318 wpa_supplicant -B -P /run/wpa_supplicant_wlp16s0.pid -i wlp16s0 -D nl80211,wext -c/run/network/wpa_supplicant_wlp16s0.conf -W

Jul 14 16:47:28 arch systemd[1]: Starting Automatic wireless network connection using netctl profiles...
Jul 14 16:47:30 arch netctl-auto[277]: Included profile 'WIRELESS'
Jul 14 16:47:31 arch wpa_actiond[320]: Starting wpa_actiond session for interface 'wlp16s0'
Jul 14 16:47:31 arch systemd[1]: Started Automatic wireless network connection using netctl profiles.

Journalctl last 55 lines of boot seem pertinent:

Jul 14 16:47:30 arch kernel: iwl4965 0000:10:00.0: RF_KILL bit toggled to disable radio.
Jul 14 16:47:30 arch kernel: iwl4965 0000:10:00.0: RF_KILL bit toggled to enable radio.
Jul 14 16:47:30 arch kernel: iwl4965 0000:10:00.0: On demand firmware reload
Jul 14 16:47:30 arch kernel: iwl4965 0000:10:00.0: RF_KILL bit toggled to disable radio.
Jul 14 16:47:30 arch kernel: ieee80211 phy0: Hardware restart was requested
Jul 14 16:47:30 arch netctl-auto[277]: Included profile 'WIRELESS'
Jul 14 16:47:30 arch dhcpcd[279]: enp0s25: waiting for carrier
Jul 14 16:47:30 arch kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
Jul 14 16:47:30 arch lightdm[292]: ** (lightdm:292): WARNING **: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jul 14 16:47:30 arch systemd[1]: Started Light Display Manager.
Jul 14 16:47:31 arch wpa_actiond[320]: Starting wpa_actiond session for interface 'wlp16s0'
Jul 14 16:47:31 arch systemd[1]: Started Automatic wireless network connection using netctl profiles.
Jul 14 16:47:34 arch lightdm[329]: pam_succeed_if(lightdm-autologin:auth): requirement "user ingroup autologin" was met by user "norman"
Jul 14 16:47:35 arch lightdm[292]: ** (process:329): WARNING **: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jul 14 16:47:35 arch lightdm[329]: pam_unix(lightdm-autologin:session): session opened for user norman by (uid=0)
Jul 14 16:47:35 arch systemd[1]: Created slice user-1000.slice.
Jul 14 16:47:35 arch systemd[1]: Starting user-1000.slice.
Jul 14 16:47:35 arch systemd[1]: Starting User Manager for UID 1000...
Jul 14 16:47:35 arch systemd-logind[278]: New session c1 of user norman.
Jul 14 16:47:35 arch systemd[1]: Started Session c1 of user norman.
Jul 14 16:47:35 arch systemd[1]: Starting Session c1 of user norman.
Jul 14 16:47:35 arch systemd[335]: pam_unix(systemd-user:session): session opened for user norman by (uid=0)
Jul 14 16:47:35 arch systemd[335]: Reached target Sockets.
Jul 14 16:47:35 arch systemd[335]: Starting Sockets.
Jul 14 16:47:35 arch systemd[335]: Reached target Timers.
Jul 14 16:47:36 arch wpa_actiond[320]: Terminating wpa_actiond session for interface 'wlp16s0'
Jul 14 16:47:35 arch systemd[335]: Starting Timers.
Jul 14 16:47:35 arch systemd[335]: Reached target Paths.
Jul 14 16:47:35 arch systemd[335]: Starting Paths.
Jul 14 16:47:35 arch systemd[335]: Reached target Basic System.
Jul 14 16:47:35 arch systemd[335]: Starting Basic System.
Jul 14 16:47:35 arch systemd[335]: Reached target Default.
Jul 14 16:47:35 arch systemd[335]: Startup finished in 110ms.
Jul 14 16:47:35 arch systemd[1]: Started User Manager for UID 1000.
Jul 14 16:47:35 arch systemd[335]: Starting Default.
Jul 14 16:47:51 arch kernel: CE: hpet increased min_delta_ns to 20115 nsec
Jul 14 16:48:00 arch dhcpcd[279]: timed out
Jul 14 16:48:00 arch dhcpcd[279]: dhcpcd exited
Jul 14 16:48:00 arch systemd[1]: dhcpcd@enp0s25.service: Control process exited, code=exited status=1
Jul 14 16:48:00 arch systemd[1]: Failed to start dhcpcd on enp0s25.
Jul 14 16:48:00 arch systemd[1]: dhcpcd@enp0s25.service: Unit entered failed state.
Jul 14 16:48:00 arch systemd[1]: dhcpcd@enp0s25.service: Failed with result 'exit-code'.
Jul 14 16:48:00 arch systemd[1]: Reached target Network.
Jul 14 16:48:00 arch systemd[1]: Starting Network.
Jul 14 16:48:00 arch systemd[1]: Reached target Multi-User System.
Jul 14 16:48:00 arch systemd[1]: Starting Multi-User System.
Jul 14 16:48:00 arch systemd[1]: Reached target Graphical Interface.
Jul 14 16:48:00 arch systemd[1]: Startup finished in 5.336s (kernel) + 46.257s (userspace) = 51.593s.
Jul 14 16:48:00 arch systemd[1]: Starting Graphical Interface.
Jul 14 16:48:38 arch kernel: CE: hpet increased min_delta_ns to 30172 nsec
Jul 14 16:51:33 arch kernel: hrtimer: interrupt took 13471198 ns
Jul 14 16:55:37 arch kernel: CE: hpet increased min_delta_ns to 45258 nsec
Jul 14 17:01:50 arch kernel: perf interrupt took too long (2548 > 2495), lowering kernel.perf_event_max_sample_rate to 50100
Jul 14 17:02:20 arch systemd[1]: Starting Cleanup of Temporary Directories...
Jul 14 17:02:21 arch systemd[1]: Started Cleanup of Temporary Directories.

One line mentions netctl-auto@wlp16s0 including the appropriate wireless access, but then dhcpcd tries to connect wired service.
Is rfkill killing iwl4965, which is required?
Does terminationg wpa_actiond session stop the connection process?  If so, what is making it terminate?

Sometimes on booting the connection is established but usually not (it always connected until recently).  Debian wheezy which is in another partition on the same computer always connects.

Help appreciated.

Last edited by hilltownboy (2015-07-14 21:49:47)

Offline

#2 2015-07-15 07:20:09

Head_on_a_Stick
Member
From: London
Registered: 2014-02-20
Posts: 7,732
Website

Re: Wifi connection not found

Is your interface always called "wlp16s0"?

On one of my laptops, I did have a problem with the predictable naming system not being applied consistently and the networking would then fail if the old (wlan) nomenclature was being used by the system.

When the connection fails, look at the output of:

ip l
dmesg|egrep 'wlp|wlan'

Offline

#3 2015-07-15 14:28:24

hilltownboy
Member
From: Massachusetts
Registered: 2013-08-15
Posts: 100

Re: Wifi connection not found

Thanks, Head_on_a_Stick.  Interface for wifi is always wlp16s0.  Connection did not fail this morning so your test output showed everything normal.

The problem seems to be RF_KILL.  The following excerpt from journalctl on today's successful connection shows (2nd line) "toggled to enable radio", after which the netctl-auto connection proceeds satisfactorily, without any termination of wpa_actiond session.
In the unsuccessful attempt "radio" is disabled (4th line) immediately after the firmware reload.

I don't know how to cope with RF_KILL disabling "radio".  Isn't that a hardware problem?

Jul 15 09:47:52 arch kernel: iwl4965 0000:10:00.0: RF_KILL bit toggled to disable radio.
Jul 15 09:47:52 arch kernel: iwl4965 0000:10:00.0: RF_KILL bit toggled to enable radio.
Jul 15 09:47:52 arch kernel: iwl4965 0000:10:00.0: On demand firmware reload
Jul 15 09:47:52 arch kernel: ieee80211 phy0: Hardware restart was requested
Jul 15 09:47:52 arch systemd[1]: Started Rotate log files.
Jul 15 09:47:52 arch netctl-auto[290]: Included profile 'WIRELESS'
Jul 15 09:47:52 arch kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
Jul 15 09:47:52 arch dhcpcd[281]: enp0s25: waiting for carrier
Jul 15 09:47:53 arch systemd[1]: Started Verify integrity of password and group files.
Jul 15 09:47:53 arch kernel: IPv6: ADDRCONF(NETDEV_UP): wlp16s0: link is not ready
Jul 15 09:47:53 arch lightdm[303]: ** (lightdm:303): WARNING **: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jul 15 09:47:53 arch systemd[1]: Started Light Display Manager.
Jul 15 09:47:54 arch systemd[1]: Started Automatic wireless network connection using netctl profiles.
Jul 15 09:47:54 arch wpa_actiond[336]: Starting wpa_actiond session for interface 'wlp16s0'
Jul 15 09:47:55 arch kernel: wlp16s0: authenticate with f8:1a:67:d3:6f:aa
Jul 15 09:47:55 arch kernel: wlp16s0: send auth to f8:1a:67:d3:6f:aa (try 1/3)
Jul 15 09:47:55 arch kernel: wlp16s0: authenticated
Jul 15 09:47:55 arch kernel: wlp16s0: waiting for beacon from f8:1a:67:d3:6f:aa
Jul 15 09:47:55 arch kernel: wlp16s0: associate with f8:1a:67:d3:6f:aa (try 1/3)
Jul 15 09:47:55 arch kernel: wlp16s0: RX AssocResp from f8:1a:67:d3:6f:aa (capab=0x421 status=0 aid=1)
Jul 15 09:47:55 arch wpa_actiond[336]: Interface 'wlp16s0' connected to network 'WIRELESS'
Jul 15 09:47:55 arch kernel: wlp16s0: associated
Jul 15 09:47:55 arch kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp16s0: link becomes ready
Jul 15 09:47:55 arch kernel: cfg80211: Calling CRDA to update world regulatory domain
Jul 15 09:47:56 arch dhcpcd[344]: DUID 00:01:00:01:1d:2b:5d:43:00:1b:38:ba:55:64
Jul 15 09:47:56 arch dhcpcd[344]: wlp16s0: IAID e0:20:c5:69
Jul 15 09:47:57 arch dhcpcd[344]: wlp16s0: soliciting a DHCP lease
Jul 15 09:47:57 arch dhcpcd[344]: wlp16s0: offered 192.168.0.103 from 192.168.0.1
Jul 15 09:47:57 arch dhcpcd[344]: wlp16s0: leased 192.168.0.103 for 7200 seconds
Jul 15 09:47:57 arch dhcpcd[344]: wlp16s0: adding route to 192.168.0.0/24
Jul 15 09:47:57 arch dhcpcd[344]: wlp16s0: adding default route via 192.168.0.1
Jul 15 09:48:00 arch dhcpcd[344]: forked to background, child pid 387
Jul 15 09:48:02 arch lightdm[392]: pam_succeed_if(lightdm-autologin:auth): requirement "user ingroup autologin" was met by user "norman"
Jul 15 09:48:02 arch lightdm[303]: ** (process:392): WARNING **: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jul 15 09:48:02 arch lightdm[392]: pam_unix(lightdm-autologin:session): session opened for user norman by (uid=0)
Jul 15 09:48:02 arch systemd[1]: Created slice user-1000.slice.
Jul 15 09:48:02 arch systemd[1]: Starting user-1000.slice.
Jul 15 09:48:02 arch systemd[1]: Starting User Manager for UID 1000...
Jul 15 09:48:02 arch systemd-logind[284]: New session c1 of user norman.
Jul 15 09:48:02 arch systemd[1]: Started Session c1 of user norman.
Jul 15 09:48:02 arch systemd[1]: Starting Session c1 of user norman.
Jul 15 09:48:02 arch systemd[1]: Started Update man-db cache.
Jul 15 09:48:02 arch systemd[397]: pam_unix(systemd-user:session): session opened for user norman by (uid=0)
Jul 15 09:48:02 arch systemd[397]: Reached target Paths.
Jul 15 09:48:02 arch systemd[397]: Starting Paths.
Jul 15 09:48:02 arch systemd[397]: Reached target Sockets.
Jul 15 09:48:02 arch systemd[397]: Starting Sockets.
Jul 15 09:48:02 arch systemd[397]: Reached target Timers.
Jul 15 09:48:02 arch systemd[397]: Starting Timers.
Jul 15 09:48:02 arch systemd[397]: Reached target Basic System.
Jul 15 09:48:02 arch systemd[397]: Starting Basic System.
Jul 15 09:48:02 arch systemd[397]: Reached target Default.
Jul 15 09:48:02 arch systemd[397]: Startup finished in 119ms.
Jul 15 09:48:02 arch systemd[397]: Starting Default.
Jul 15 09:48:02 arch systemd[1]: Started User Manager for UID 1000.
Jul 15 09:48:22 arch dhcpcd[281]: timed out
Jul 15 09:48:22 arch dhcpcd[281]: dhcpcd exited
Jul 15 09:48:22 arch systemd[1]: dhcpcd@enp0s25.service: Control process exited, code=exited status=1
Jul 15 09:48:22 arch systemd[1]: Failed to start dhcpcd on enp0s25.
Jul 15 09:48:22 arch systemd[1]: dhcpcd@enp0s25.service: Unit entered failed state.
Jul 15 09:48:22 arch systemd[1]: dhcpcd@enp0s25.service: Failed with result 'exit-code'.
Jul 15 09:48:22 arch systemd[1]: Reached target Multi-User System.
Jul 15 09:48:22 arch systemd[1]: Starting Multi-User System.
Jul 15 09:48:22 arch systemd[1]: Reached target Graphical Interface.
Jul 15 09:48:22 arch systemd[1]: Starting Graphical Interface.
Jul 15 09:48:22 arch systemd[1]: Reached target Network.
Jul 15 09:48:22 arch systemd[1]: Startup finished in 5.285s (kernel) + 46.349s (userspace) = 51.635s.
Jul 15 09:48:22 arch systemd[1]: Starting Network.
Jul 15 09:50:55 arch kernel: hrtimer: interrupt took 14002480 ns

Offline

Board footer

Powered by FluxBB