[SOLVED] netctl-auto fails on boot, works when started manually

MightyPork · 2015-07-23 06:43:52

I've added some profiles to my netctl, enabled them in netctl-auto, and now it no longer starts automatically.

~ $ sudo systemctl status netctl-auto@wlp2s0
[sudo] password for ondra: 
● netctl-auto@wlp2s0.service - Automatic wireless network connection using netctl profiles
   Loaded: loaded (/usr/lib/systemd/system/netctl-auto@.service; enabled; vendor preset: disabled)
   Active: failed (Result: exit-code) since Čt 2015-07-23 08:36:44 CEST; 23s ago
     Docs: man:netctl.special(7)
  Process: 431 ExecStart=/usr/bin/netctl-auto start %I (code=exited, status=1/FAILURE)

čec 23 08:36:43 x201 systemd[1]: Starting Automatic wireless network connection using netctl profiles...
čec 23 08:36:44 x201 netctl-auto[431]: Could not create the configuration file for interface 'wlp2s0'
čec 23 08:36:44 x201 systemd[1]: netctl-auto@wlp2s0.service: Control process exited, code=exited status=1
čec 23 08:36:44 x201 systemd[1]: Failed to start Automatic wireless network connection using netctl profiles.
čec 23 08:36:44 x201 systemd[1]: netctl-auto@wlp2s0.service: Unit entered failed state.
čec 23 08:36:44 x201 systemd[1]: netctl-auto@wlp2s0.service: Failed with result 'exit-code'.

However, when I then start it manually, it works fine:

~ $ sudo systemctl start netctl-auto@wlp2s0
~ $ sudo systemctl status netctl-auto@wlp2s0
● netctl-auto@wlp2s0.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 Čt 2015-07-23 08:37:45 CEST; 2min 46s ago
     Docs: man:netctl.special(7)

What's wrong here?

The probable troublemaker profile is here:

Description="UPC Wi-Free"
Connection='wireless'
Interface=wlp2s0
Security='wpa-configsection'
IP='dhcp'
TimeoutWPA=30
WPAConfigSection=(
    'ssid="UPC Wi-Free"'
    'key_mgmt=WPA-EAP IEEE8021X'
    'eap=PEAP'
    'phase2="auth=MSCHAPV2"'
    'identity="redacted@example.com"'
    'priority=3'
    'ca_cert="/etc/ssl/certs/LGI_Root_CA.pem"'
    'password="secretsecret"'
    'proactive_key_caching=1'
)

This AP is sometimes present, and refuses to connect - but I have another one with priority 5 that is present and working fine, so that shouldn't be the cause. Maybe I misconfigured something?

Last edited by MightyPork (2015-07-23 10:37:02)

Awebb · 2015-07-23 07:52:30

netctl-auto should not fail, just because an AP is unavailable. Do you have any relevant journalctl or dmesg excerpts to work with?

MightyPork · 2015-07-23 08:21:18

Awebb wrote:

netctl-auto should not fail, just because an AP is unavailable. Do you have any relevant journalctl or dmesg excerpts to work with?

dmesg is devoid of anything related. journalctl contains this section:

čec 22 17:23:49 x201 network[425]: Starting network profile 'wifree'...
čec 22 17:23:50 x201 systemd[1]: Found device 82577LM Gigabit Network Connection.
čec 22 17:23:50 x201 systemd[1]: Started Automatic wired network connection using netctl profiles.
čec 22 17:23:50 x201 systemd[1]: Starting Automatic wired network connection using netctl profiles...
čec 22 17:23:50 x201 ifplugd[482]: ifplugd 0.28 initializing.
čec 22 17:23:50 x201 netctl-auto[424]: Included profile 'bambulka'
čec 22 17:23:50 x201 network[425]: Could not create a wpa config file for interface 'wlp2s0'
čec 22 17:23:50 x201 network[425]: Failed to bring the network up for profile 'wifree'
čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Main process exited, code=exited, status=1/FAILURE
čec 22 17:23:50 x201 systemd[1]: Failed to start UPC Wi-Free.
čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Unit entered failed state.
čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Failed with result 'exit-code'.
čec 22 17:23:50 x201 kernel: usb 4-2: new SuperSpeed USB device number 2 using xhci_hcd

Also this

...skipping...
čec 22 18:01:17 x201 network[444]: Failed to bring the network up for profile 'wifree'
čec 22 18:01:17 x201 systemd[1]: netctl@wifree.service: Main process exited, code=exited, status=1/FAILURE
čec 22 18:01:17 x201 systemd[1]: Failed to start UPC Wi-Free.
čec 22 18:01:17 x201 systemd[1]: netctl@wifree.service: Unit entered failed state.
čec 22 18:01:17 x201 systemd[1]: netctl@wifree.service: Failed with result 'exit-code'.
čec 22 18:01:17 x201 systemd[1]: Starting (Re)store the netctl profile state...
čec 22 18:01:17 x201 kernel: cfg80211: World regulatory domain updated:
čec 22 18:01:17 x201 kernel: cfg80211:  DFS Master region: unset
čec 22 18:01:17 x201 kernel: cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
čec 22 18:01:17 x201 kernel: cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
čec 22 18:01:17 x201 kernel: cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
čec 22 18:01:17 x201 kernel: cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
čec 22 18:01:17 x201 kernel: cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
čec 22 18:01:17 x201 kernel: cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
čec 22 18:01:17 x201 kernel: cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
čec 22 18:01:17 x201 kernel: cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
čec 22 18:01:17 x201 kernel: cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
čec 22 18:01:17 x201 systemd[1]: Started (Re)store the netctl profile state.

Here is one case where it apparently succeeded (but the AP is not accepting the connection, which is OK)

čec 23 01:53:17 x201 netctl-auto[19332]: Included profile 'wifree'
čec 23 01:53:17 x201 kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
čec 23 01:53:17 x201 systemd[1]: Started Automatic wireless network connection using netctl profiles.
čec 23 01:53:17 x201 wpa_actiond[19377]: Starting wpa_actiond session for interface 'wlp2s0'
čec 23 01:53:17 x201 sudo[19319]: pam_unix(sudo:session): session closed for user root
čec 23 01:53:17 x201 polkitd[1193]: Unregistered Authentication Agent for unix-process:19327:264160 (system bus name :1.70, object path /org/freedesktop/PolicyKit1/Authentica
čec 23 01:53:18 x201 kernel: wlp2s0: authenticate with e8:40:f2:1f:c2:02
čec 23 01:53:18 x201 kernel: wlp2s0: send auth to e8:40:f2:1f:c2:02 (try 1/3)
čec 23 01:53:18 x201 kernel: wlp2s0: authenticated
čec 23 01:53:18 x201 kernel: wlp2s0: associate with e8:40:f2:1f:c2:02 (try 1/3)
čec 23 01:53:18 x201 kernel: wlp2s0: RX AssocResp from e8:40:f2:1f:c2:02 (capab=0x411 status=0 aid=2)
čec 23 01:53:18 x201 kernel: wlp2s0: associated
čec 23 01:53:18 x201 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready

I'm not sure if those logs are good for anything... I certainly cant see much from them.

MightyPork · 2015-07-23 09:41:20

Here's another piece of log, captured after reboot.

čec 23 11:35:46 x201 systemd[1]: Starting Automatic wireless network connection using netctl profiles...
-- Subject: Unit netctl-auto@wlp2s0.service has begun start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
čec 23 11:35:46 x201 netctl-auto[386]: Included profile 'mh'
čec 23 11:35:47 x201 netctl-auto[386]: Included profile 'eduroam'
čec 23 11:35:47 x201 netctl-auto[386]: Included profile 'medvjedov'
čec 23 11:35:47 x201 netctl-auto[386]: Included profile 'wifree'
čec 23 11:35:48 x201 systemd[1]: netctl-auto@wlp2s0.service: Control process exited, code=exited status=1
čec 23 11:35:48 x201 systemd[1]: Failed to start Automatic wireless network connection using netctl profiles.
-- Subject: Unit netctl-auto@wlp2s0.service has failed
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit netctl-auto@wlp2s0.service has failed.
-- 
-- The result is failed.
čec 23 11:35:48 x201 systemd[1]: netctl-auto@wlp2s0.service: Unit entered failed state.
čec 23 11:35:48 x201 systemd[1]: netctl-auto@wlp2s0.service: Failed with result 'exit-code'.
...skipping...
čec 23 11:35:26 x201 systemd[1]: Stopping Automatic wireless network connection using netctl profiles...
-- Subject: Unit netctl-auto@wlp2s0.service has begun shutting down
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit netctl-auto@wlp2s0.service has begun shutting down.
čec 23 11:35:26 x201 wpa_actiond[2179]: Interface 'wlp2s0' disconnected from network 'Chlivek'
čec 23 11:35:26 x201 dhcpcd[13366]: sending signal TERM to pid 2256
čec 23 11:35:26 x201 dhcpcd[13366]: waiting for pid 2256 to exit
čec 23 11:35:26 x201 dhcpcd[2256]: received SIGTERM, stopping
čec 23 11:35:26 x201 dhcpcd[2256]: wlp2s0: removing interface
čec 23 11:35:26 x201 dhcpcd[2256]: dhcpcd exited
čec 23 11:35:26 x201 wpa_actiond[2179]: Terminating wpa_actiond session for interface 'wlp2s0'
čec 23 11:35:27 x201 systemd[1]: Stopped Automatic wireless network connection using netctl profiles.
-- Subject: Unit netctl-auto@wlp2s0.service has finished shutting down
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit netctl-auto@wlp2s0.service has finished shutting down.

Awebb · 2015-07-23 09:57:36

čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Main process exited, code=exited, status=1/FAILURE
čec 22 17:23:50 x201 systemd[1]: Failed to start UPC Wi-Free.
čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Unit entered failed state.
čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Failed with result 'exit-code'.

To me, this looks like you still have a manual netctl profile enabled, that tries to come up and fails (because this wifree isn't there). Due to the parallel nature of systemd, it tries to start netctl-auto at the same time (wanted by the same target I assume), which fails, because the network device is busy with netctl@wifree.service.

The fastest way to check this is to find all the service symlinks in /etc/systemd/system.

Head_on_a_Stick · 2015-07-23 09:59:17

Do you have any conflicting .services?

ls -l /etc/systemd/system/multi-user.target.wants

MightyPork · 2015-07-23 10:36:46

Awebb wrote:

čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Main process exited, code=exited, status=1/FAILURE
čec 22 17:23:50 x201 systemd[1]: Failed to start UPC Wi-Free.
čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Unit entered failed state.
čec 22 17:23:50 x201 systemd[1]: netctl@wifree.service: Failed with result 'exit-code'.
To me, this looks like you still have a manual netctl profile enabled, that tries to come up and fails (because this wifree isn't there). Due to the parallel nature of systemd, it tries to start netctl-auto at the same time (wanted by the same target I assume), which fails, because the network device is busy with netctl@wifree.service.
The fastest way to check this is to find all the service symlinks in /etc/systemd/system.

Thanks, that helped!

I somehow had the netctl services alongside netctl-auto. Now I've removed all this mess and it works again. SOLVED

Arch Linux

#1 2015-07-23 06:43:52

[SOLVED] netctl-auto fails on boot, works when started manually

#2 2015-07-23 07:52:30

Re: [SOLVED] netctl-auto fails on boot, works when started manually

#3 2015-07-23 08:21:18

Re: [SOLVED] netctl-auto fails on boot, works when started manually

#4 2015-07-23 09:41:20

Re: [SOLVED] netctl-auto fails on boot, works when started manually

#5 2015-07-23 09:57:36

Re: [SOLVED] netctl-auto fails on boot, works when started manually

#6 2015-07-23 09:59:17

Re: [SOLVED] netctl-auto fails on boot, works when started manually

#7 2015-07-23 10:36:46

Re: [SOLVED] netctl-auto fails on boot, works when started manually

Board footer