[Pkg-utopia-maintainers] Bug#707186: network-manager: wireless reconnection doesn't work without stoping NM an killing nm-applet
Christoph Anton Mitterer
calestyo at scientia.net
Tue Sep 30 00:50:00 UTC 2014
retitle 707186 network-manager: wireless reconnection doesn't work without restarting NM
stop
Hey.
Sorry for not having provided further information (debug logs), bug I
somehow got distracted back then and forgot about this.
Even though it seems that there is no goal to properly integrate NM into
Debian, the requested information shall be found here (at the very end)
now:
On Wed, 08 May 2013 04:16:41 +0200 Michael Biebl <biebl at debian.org>
wrote:
>As a first advice, if you want to manage your wireless interface with
>NM, I would recommed *against* using /e/n/i and managed=true.
>ifupdown will still manage your wlan0-foo interface and having two
>network management systems handling the same interface is not going to
>work out well.
Well I still stick to the point that Debian's native (and working)
network management tool is ifupdown, which also means that it's
configuration is the native configuration which other packages/systems
shall integrate into.
Even when not using managed-mode, NM has far too many issues and bugs
which makes it unusable as a general replacement for ifupdown - and no
I'm not saying ifupdown is perfect (the syntax of (/e/n/interfaces is
actually ugly), and no, nothing of this criticism is agains you
personally.
That NM isn't Debian's default/native network management configuration
system, seems to be backed by the majority of the community and
especially the tech-ctte.
And as long as this hasn't changed (and it seems it won't in the
foreseeable future) I consider NM merely a GUI framework, that should
read the real native config files from the respective systems (ifupdown,
vpnc, strongswan, etc.), allow connections to be managed via GUI from
the desktop and programs to be informed about connectivity.
But the real work and especially place for configuration are still the
respective native tools - cause while NM breaks something with basically
every update (even on minor versions), these tools always work.
>What kind of desktop environment are you using?
Back then it was GNOME Classic, nowadays it's Cinnamon.
>Do you have proper PolicyKit permissions?
I would guess so, cause how could connecting the first time work, if
there weren't sufficient permissions.
>Is you desktop session marked as active and
>local? (ck-list-sessions)
Well since ck is deprecated now and since I'm run on systemd I took the
logind equivalent information:
$ loginctl session-status 6
6 - calestyo (1000)
Since: Tue 2014-09-30 01:55:41 CEST; 42min ago
Leader: 27514 (gdm-session-wor)
Seat: seat0; vc7
Display: :0
Service: gdm-password; type x11; class user
State: active
Unit: session-6.scope
So I guess yes.
I shall perhaps add, that with cinnamon, killing the nm-applet is no
longer necessary (actually nm-applet isn't used AFAICS(!?).
But restarting NM itself is still necessary in order to "re-connect"
after the first time a WiFi connection has been disconnected.
>As for debugging, see [1]. Please attach a debug log.
***************************************************************************************
Here NM has already started and is running, but no connection has been made yet
***************************************************************************************
NetworkManager[31344]: <debug> [1412036408.304814] [nm-supplicant-interface.c:747] iface_check_netreply_cb(): Supplicant supports network credentials requests
NetworkManager[31344]: <info> (wlan0): supplicant interface state: starting -> ready
NetworkManager[31344]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
NetworkManager[31344]: <warn> Trying to remove a non-existant call id.
NetworkManager[31344]: <info> (wlan0): supplicant interface state: ready -> scanning
NetworkManager[31344]: <info> (wlan0) supports 5 scan SSIDs
NetworkManager[31344]: <warn> Could not get scan request result: Scan request rejected
NetworkManager[31344]: <warn> Could not get scan request result: Scan request rejected
NetworkManager[31344]: <debug> [1412036410.781096] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
NetworkManager[31344]: <info> (wlan0): supplicant interface state: scanning -> inactive
***************************************************************************************
Now I select my WPA-PSK connection in the NM applet
***************************************************************************************
NetworkManager[31344]: <debug> [1412036433.820618] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
NetworkManager[31344]: <info> Activation (wlan0) starting connection 'Ifupdown (wlan0-scientia.net)'
NetworkManager[31344]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[31344]: <info> NetworkManager state is now CONNECTING
NetworkManager[31344]: <debug> [1412036434.134688] [nm-manager.c:4242] policy_activating_device_changed(): ActivatingConnection now Ifupdown (wlan0-scientia.net)
NetworkManager[31344]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[31344]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[31344]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[31344]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[31344]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[31344]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[31344]: <info> Activation (wlan0/wireless): connection 'Ifupdown (wlan0-scientia.net)' has security, and secrets exist. No new secrets needed.
NetworkManager[31344]: <info> Config: added 'ssid' value 'scientia.net'
NetworkManager[31344]: <info> Config: added 'scan_ssid' value '1'
NetworkManager[31344]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
NetworkManager[31344]: <info> Config: added 'psk' value '<omitted>'
NetworkManager[31344]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[31344]: <info> Config: set interface ap_scan to 1
NetworkManager[31344]: <info> (wlan0): supplicant interface state: inactive -> authenticating
NetworkManager[31344]: <info> (wlan0): supplicant interface state: authenticating -> associating
NetworkManager[31344]: <debug> [1412036434.322409] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x11003
NetworkManager[31344]: <debug> [1412036434.322484] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x11003
NetworkManager[31344]: <debug> [1412036434.322541] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x11003
NetworkManager[31344]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
NetworkManager[31344]: <debug> [1412036434.366708] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x11043
NetworkManager[31344]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
NetworkManager[31344]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'scientia.net'.
NetworkManager[31344]: <debug> [1412036434.371906] [nm-device.c:3069] nm_device_activate_schedule_stage3_ip_config_start(): Activation (wlan0) setting firewall zone 'default'
NetworkManager[31344]: <debug> [1412036434.371925] [nm-firewall-manager.c:112] nm_firewall_manager_add_or_change_zone(): (wlan0) firewall zone add/change skipped (not running)
NetworkManager[31344]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager[31344]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager[31344]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
NetworkManager[31344]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
NetworkManager[31344]: <debug> [1412036434.372573] [nm-dhcp-dhclient.c:627] dhclient_start(): running: /sbin/dhclient -d -sf /usr/lib/NetworkManager/nm-dhcp-client.action -pf /var/run/dhclient-wlan0.pid -lf /var/lib/NetworkManager/dhclient-9375b2e6-428a-71f6-32f7-4e37e36f0bdd-wlan0.lease -cf /var/lib/NetworkManager/dhclient-wlan0.conf wlan0
NetworkManager[31344]: <info> dhclient started with pid 31397
NetworkManager[31344]: <debug> [1412036434.374037] [NetworkManagerUtils.c:583] nm_utils_do_sysctl(): sysctl: setting '/proc/sys/net/ipv6/conf/wlan0/accept_ra' to '2'
NetworkManager[31344]: <info> Activation (wlan0) Beginning IP6 addrconf.
NetworkManager[31344]: <debug> [1412036434.374080] [NetworkManagerUtils.c:583] nm_utils_do_sysctl(): sysctl: setting '/proc/sys/net/ipv6/conf/wlan0/disable_ipv6' to '1'
NetworkManager[31344]: <debug> [1412036434.374179] [NetworkManagerUtils.c:583] nm_utils_do_sysctl(): sysctl: setting '/proc/sys/net/ipv6/conf/wlan0/disable_ipv6' to '0'
NetworkManager[31344]: <debug> [1412036434.374260] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x0) (state/target 'unconfigured'/'got-address')
NetworkManager[31344]: <debug> [1412036434.374273] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.374280] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.374287] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.374364] [NetworkManagerUtils.c:583] nm_utils_do_sysctl(): sysctl: setting '/proc/sys/net/ipv6/conf/wlan0/use_tempaddr' to '0'
NetworkManager[31344]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager[31344]: <debug> [1412036434.374415] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 25
NetworkManager[31344]: <debug> [1412036434.374452] [nm-ip6-manager.c:926] process_route_change(): (wlan0): route cache size: 5 -> 4:
NetworkManager[31344]: <debug> [1412036434.374471] [nm-ip6-manager.c:855] dump_route_change(): (wlan0) lost route: fe80::/64 via none
NetworkManager[31344]: <debug> [1412036434.374481] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x0) (state/target 'unconfigured'/'got-address')
NetworkManager[31344]: <debug> [1412036434.374500] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.374655] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.374803] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.375063] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 25
NetworkManager[31344]: <debug> [1412036434.375097] [nm-ip6-manager.c:926] process_route_change(): (wlan0): route cache size: 4 -> 3:
NetworkManager[31344]: <debug> [1412036434.375280] [nm-ip6-manager.c:855] dump_route_change(): (wlan0) lost route: ff00::/8 via none
NetworkManager[31344]: <debug> [1412036434.375473] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x0) (state/target 'unconfigured'/'got-address')
NetworkManager[31344]: <debug> [1412036434.375705] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.375926] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.376138] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.376497] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 21
NetworkManager[31344]: <debug> [1412036434.376575] [nm-ip6-manager.c:885] process_address_change(): (wlan0): address cache size: 2 -> 2:
NetworkManager[31344]: <debug> [1412036434.376791] [nm-ip6-manager.c:835] dump_address_change(): (wlan0) lost address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036434.377172] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036434.377199] [nm-ip6-manager.c:926] process_route_change(): (wlan0): route cache size: 3 -> 4:
NetworkManager[31344]: <debug> [1412036434.377392] [nm-ip6-manager.c:855] dump_route_change(): (wlan0) new route: ff00::/8 via none
NetworkManager[31344]: <debug> [1412036434.377531] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x0) (state/target 'unconfigured'/'got-address')
NetworkManager[31344]: <debug> [1412036434.377665] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.377804] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.377932] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.378201] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036434.378225] [nm-ip6-manager.c:926] process_route_change(): (wlan0): route cache size: 4 -> 5:
NetworkManager[31344]: <debug> [1412036434.378349] [nm-ip6-manager.c:855] dump_route_change(): (wlan0) new route: fe80::/64 via none
NetworkManager[31344]: <debug> [1412036434.378479] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x0) (state/target 'unconfigured'/'got-address')
NetworkManager[31344]: <debug> [1412036434.378611] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state unconfigured)
Internet Systems Consortium DHCP Client 4.3.1
NetworkManager[31344]: <debug> [1412036434.378884] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state unconfigured)
Copyright 2004-2014 Internet Systems Consortium.
NetworkManager[31344]: <debug> [1412036434.379157] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state unconfigured)
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/
NetworkManager[31344]: <debug> [1412036434.379583] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036434.379849] [nm-ip6-manager.c:1302] process_newlink(): ((none)): ignoring unknown or completed device
NetworkManager[31344]: <debug> [1412036434.379987] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 1 flags 0x10049
NetworkManager[31344]: <debug> [1412036434.380122] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036434.380251] [nm-ip6-manager.c:1302] process_newlink(): ((none)): ignoring unknown or completed device
NetworkManager[31344]: <debug> [1412036434.380628] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 2 flags 0x1003
NetworkManager[31344]: <debug> [1412036434.380781] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036434.380924] [nm-ip6-manager.c:415] device_set_ra_flags(): (wlan0) IP6 device ra_flags: 0x00000000 () -> 0x80000080 (O)
NetworkManager[31344]: <debug> [1412036434.381049] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x80000080) (state/target 'unconfigured'/'got-address')
NetworkManager[31344]: <debug> [1412036434.381181] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.381314] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.381448] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036434.381584] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x11043
NetworkManager[31344]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
NetworkManager[31344]: <debug> [1412036434.383523] [nm-device.c:1945] dhcp4_state_changed(): (wlan0): new DHCPv4 client state 1
Listening on LPF/wlan0/60:67:20:09:9a:a4
Sending on LPF/wlan0/60:67:20:09:9a:a4
Sending on Socket/fallback
DHCPREQUEST on wlan0 to 255.255.255.255 port 67
NetworkManager[31344]: <debug> [1412036434.416420] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036435.260557] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036435.260647] [nm-ip6-manager.c:1302] process_newlink(): ((none)): ignoring unknown or completed device
NetworkManager[31344]: <debug> [1412036435.260710] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 1 flags 0x10049
NetworkManager[31344]: <debug> [1412036435.260790] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036435.260852] [nm-ip6-manager.c:1302] process_newlink(): ((none)): ignoring unknown or completed device
NetworkManager[31344]: <debug> [1412036435.260891] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 2 flags 0x1003
NetworkManager[31344]: <debug> [1412036435.260915] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036435.260931] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x80000080) (state/target 'unconfigured'/'got-address')
NetworkManager[31344]: <debug> [1412036435.260946] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036435.260960] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036435.260970] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036435.260993] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x11043
NetworkManager[31344]: <debug> [1412036435.675777] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 20
NetworkManager[31344]: <debug> [1412036435.675941] [nm-ip6-manager.c:885] process_address_change(): (wlan0): address cache size: 2 -> 3:
NetworkManager[31344]: <debug> [1412036435.676061] [nm-ip6-manager.c:835] dump_address_change(): (wlan0) new address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036435.676119] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x80000080) (state/target 'unconfigured'/'got-address')
NetworkManager[31344]: <debug> [1412036435.676196] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state unconfigured)
NetworkManager[31344]: <debug> [1412036435.676248] [nm-ip6-manager.c:697] check_addresses(): (wlan0): netlink address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036435.676290] [nm-ip6-manager.c:369] device_set_state(): (wlan0) IP6 device state: unconfigured -> got-link-local
NetworkManager[31344]: <debug> [1412036435.676315] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.676334] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.676429] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036435.676483] [nm-ip6-manager.c:926] process_route_change(): (unknown): route cache size: 5 -> 6:
NetworkManager[31344]: <debug> [1412036435.676515] [nm-ip6-manager.c:855] dump_route_change(): (unknown) new route: fe80::6267:20ff:fe09:9aa4 via none
NetworkManager[31344]: <debug> [1412036435.678872] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036435.678988] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036435.679014] [nm-ip6-manager.c:926] process_route_change(): (wlan0): route cache size: 6 -> 7:
NetworkManager[31344]: <debug> [1412036435.679031] [nm-ip6-manager.c:855] dump_route_change(): (wlan0) new route: none via fe80::3631:c4ff:fe42:e53e
NetworkManager[31344]: <debug> [1412036435.679050] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x80000080) (state/target 'got-link-local'/'got-address')
NetworkManager[31344]: <debug> [1412036435.679066] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.679087] [nm-ip6-manager.c:697] check_addresses(): (wlan0): netlink address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036435.679200] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.679526] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.679934] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036435.680249] [nm-ip6-manager.c:926] process_route_change(): (wlan0): route cache size: 7 -> 8:
NetworkManager[31344]: <debug> [1412036435.680573] [nm-ip6-manager.c:855] dump_route_change(): (wlan0) new route: 2001:a60:146e:5c01::/64 via none
NetworkManager[31344]: <debug> [1412036435.680910] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x80000080) (state/target 'got-link-local'/'got-address')
NetworkManager[31344]: <debug> [1412036435.681255] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.681590] [nm-ip6-manager.c:697] check_addresses(): (wlan0): netlink address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036435.681920] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.682265] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.682687] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 52
NetworkManager[31344]: <debug> [1412036435.683012] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036435.683313] [nm-ip6-manager.c:926] process_route_change(): (wlan0): route cache size: 8 -> 9:
NetworkManager[31344]: <debug> [1412036435.683663] [nm-ip6-manager.c:855] dump_route_change(): (wlan0) new route: fd00::/64 via none
NetworkManager[31344]: <debug> [1412036435.684015] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x80000080) (state/target 'got-link-local'/'got-address')
NetworkManager[31344]: <debug> [1412036435.684354] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.684701] [nm-ip6-manager.c:697] check_addresses(): (wlan0): netlink address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036435.685040] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.685420] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.685838] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 52
NetworkManager[31344]: <debug> [1412036435.686162] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 68
NetworkManager[31344]: <debug> [1412036435.686460] [nm-ip6-manager.c:1210] process_nduseropt(): processing netlink nduseropt message
NetworkManager[31344]: <debug> [1412036435.686784] [nm-ip6-manager.c:1028] process_nduseropt_rdnss(): (wlan0): found RA-provided nameserver fd00::3631:c4ff:fe42:e53e (expires in 7200 seconds)
NetworkManager[31344]: <debug> [1412036435.687078] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x80000080) (state/target 'got-link-local'/'got-address')
NetworkManager[31344]: <debug> [1412036435.687388] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.687735] [nm-ip6-manager.c:697] check_addresses(): (wlan0): netlink address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036435.688002] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036435.688269] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state got-link-local)
NetworkManager[31344]: <debug> [1412036436.260315] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036436.260378] [nm-ip6-manager.c:1302] process_newlink(): ((none)): ignoring unknown or completed device
NetworkManager[31344]: <debug> [1412036436.260415] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 1 flags 0x10049
NetworkManager[31344]: <debug> [1412036436.260449] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036436.260475] [nm-ip6-manager.c:1302] process_newlink(): ((none)): ignoring unknown or completed device
NetworkManager[31344]: <debug> [1412036436.260514] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 2 flags 0x1003
NetworkManager[31344]: <debug> [1412036436.260566] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
NetworkManager[31344]: <debug> [1412036436.260611] [nm-ip6-manager.c:415] device_set_ra_flags(): (wlan0) IP6 device ra_flags: 0x80000080 (O) -> 0x800000b0 (SRO)
NetworkManager[31344]: <debug> [1412036436.260637] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x800000B0) (state/target 'got-link-local'/'got-address')
NetworkManager[31344]: <debug> [1412036436.260653] [nm-ip6-manager.c:369] device_set_state(): (wlan0) IP6 device state: got-link-local -> got-ra
NetworkManager[31344]: <debug> [1412036436.260681] [nm-ip6-manager.c:739] check_ra_flags(): router advertisement requests parallel DHCPv6
NetworkManager[31344]: <debug> [1412036436.260690] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state got-ra)
NetworkManager[31344]: <debug> [1412036436.260701] [nm-ip6-manager.c:697] check_addresses(): (wlan0): netlink address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036436.261043] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state got-ra)
NetworkManager[31344]: <debug> [1412036436.261550] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state got-ra)
NetworkManager[31344]: <debug> [1412036436.262034] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x11043
NetworkManager[31344]: <debug> [1412036436.819783] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 20
NetworkManager[31344]: <debug> [1412036436.819927] [nm-ip6-manager.c:885] process_address_change(): (wlan0): address cache size: 3 -> 4:
NetworkManager[31344]: <debug> [1412036436.820011] [nm-ip6-manager.c:835] dump_address_change(): (wlan0) new address: 2001:a60:146e:5c01:6267:20ff:fe09:9aa4/
NetworkManager[31344]: <debug> [1412036436.820075] [nm-ip6-manager.c:807] nm_ip6_device_sync_from_netlink(): (wlan0): syncing with netlink (ra_flags 0x800000B0) (state/target 'got-ra'/'got-address')
NetworkManager[31344]: <debug> [1412036436.820127] [nm-ip6-manager.c:739] check_ra_flags(): router advertisement requests parallel DHCPv6
NetworkManager[31344]: <debug> [1412036436.820168] [nm-ip6-manager.c:743] check_ra_flags(): (wlan0): router advertisement checked (state got-ra)
NetworkManager[31344]: <debug> [1412036436.820220] [nm-ip6-manager.c:697] check_addresses(): (wlan0): netlink address: fe80::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036436.820266] [nm-ip6-manager.c:697] check_addresses(): (wlan0): netlink address: 2001:a60:146e:5c01:6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036436.820324] [nm-ip6-manager.c:369] device_set_state(): (wlan0) IP6 device state: got-ra -> got-address
NetworkManager[31344]: <debug> [1412036436.820370] [nm-ip6-manager.c:719] check_addresses(): (wlan0): addresses checked (state got-address)
NetworkManager[31344]: <debug> [1412036436.820429] [nm-ip6-manager.c:766] check_addrconf_complete(): (wlan0): reached target state or Managed-mode requested (state 'got-address') (dhcp opts 0x1)
NetworkManager[31344]: <debug> [1412036436.820499] [nm-ip6-manager.c:798] check_addrconf_complete(): (wlan0): dhcp_opts checked (state got-address)
NetworkManager[31344]: <debug> [1412036436.820636] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036436.820697] [nm-ip6-manager.c:926] process_route_change(): (unknown): route cache size: 9 -> 10:
NetworkManager[31344]: <debug> [1412036436.821069] [nm-ip6-manager.c:855] dump_route_change(): (unknown) new route: 2001:a60:146e:5c01:6267:20ff:fe09:9aa4 via none
NetworkManager[31344]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) starting DHCPv6 as requested by IPv6 router...
NetworkManager[31344]: <debug> [1412036436.821980] [nm-dhcp-dhclient.c:728] get_duid(): Looking for DHCPv6 DUID in '/var/lib/NetworkManager/dhclient6-9375b2e6-428a-71f6-32f7-4e37e36f0bdd-wlan0.lease'.
NetworkManager[31344]: <debug> [1412036436.822065] [nm-dhcp-client.c:481] nm_dhcp_client_start_ip6(): (wlan0): DHCPv6 DUID is '00:04:f0:38:89:b5:96:da:08:42:1c:80:6f:b2:f7:67:b6:21'
NetworkManager[31344]: <info> Activation (wlan0) Beginning DHCPv6 transaction (timeout in 45 seconds)
NetworkManager[31344]: <debug> [1412036436.822958] [nm-dhcp-dhclient.c:627] dhclient_start(): running: /sbin/dhclient -d -6 -S -sf /usr/lib/NetworkManager/nm-dhcp-client.action -pf /var/run/dhclient6-wlan0.pid -lf /var/lib/NetworkManager/dhclient6-9375b2e6-428a-71f6-32f7-4e37e36f0bdd-wlan0.lease -cf /var/lib/NetworkManager/dhclient6-wlan0.conf wlan0
NetworkManager[31344]: <info> dhclient started with pid 31399
Internet Systems Consortium DHCP Client 4.3.1
Copyright 2004-2014 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/
Listening on Socket/wlan0
Sending on Socket/wlan0
PRC: Requesting information (INIT).
XMT: Forming Info-Request, 0 ms elapsed.
XMT: Info-Request on wlan0, interval 970ms.
NetworkManager[31344]: <debug> [1412036437.244876] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036437.247441] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036437.251812] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036437.455719] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 20
NetworkManager[31344]: <debug> [1412036437.455815] [nm-ip6-manager.c:885] process_address_change(): (wlan0): address cache size: 5 -> 5:
NetworkManager[31344]: <debug> [1412036437.455836] [nm-ip6-manager.c:835] dump_address_change(): (wlan0) new address: fd00::6267:20ff:fe09:9aa4/64
NetworkManager[31344]: <debug> [1412036437.455911] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
NetworkManager[31344]: <debug> [1412036437.455938] [nm-ip6-manager.c:926] process_route_change(): (unknown): route cache size: 12 -> 13:
NetworkManager[31344]: <debug> [1412036437.455954] [nm-ip6-manager.c:855] dump_route_change(): (unknown) new route: fd00::6267:20ff:fe09:9aa4 via none
NetworkManager[31344]: <debug> [1412036438.61963] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
XMT: Forming Info-Request, 970 ms elapsed.
XMT: Info-Request on wlan0, interval 1930ms.
NetworkManager[31344]: <debug> [1412036439.299803] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
XMT: Forming Info-Request, 2900 ms elapsed.
XMT: Info-Request on wlan0, interval 3970ms.
NetworkManager[31344]: <debug> [1412036440.195703] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 24
DHCPREQUEST on wlan0 to 255.255.255.255 port 67
DHCPACK from 192.168.0.1
NetworkManager[31344]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
NetworkManager[31344]: <debug> [1412036442.173636] [nm-device.c:1945] dhcp4_state_changed(): (wlan0): new DHCPv4 client state 8
NetworkManager[31344]: <info> address 192.168.0.100
NetworkManager[31344]: <info> prefix 24 (255.255.255.0)
NetworkManager[31344]: <info> gateway 192.168.0.1
NetworkManager[31344]: <info> nameserver '192.168.0.1'
NetworkManager[31344]: <info> domain name 'fritz.box'
NetworkManager[31344]: <info> domain search 'scientia.net.'
NetworkManager[31344]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
NetworkManager[31344]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
NetworkManager[31344]: <debug> [1412036442.175980] [nm-system.c:194] sync_addresses(): (wlan0): syncing addresses (family 2)
NetworkManager[31344]: <debug> [1412036442.176020] [nm-system.c:280] sync_addresses(): (wlan0): adding address '192.168.0.100/24'
bound to 192.168.0.100 -- renewal in 1293329 seconds.
NetworkManager[31344]: <debug> [1412036443.176547] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET (2) addr 192.168.0.0/24
NetworkManager[31344]: <debug> [1412036443.176911] [nm-dns-manager.c:995] nm_dns_manager_begin_updates(): (device_ip4_config_changed): queueing DNS updates (1)
NetworkManager[31344]: <debug> [1412036443.176978] [nm-dns-manager.c:1013] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
NetworkManager[31344]: <debug> [1412036443.177005] [nm-dns-manager.c:1017] nm_dns_manager_end_updates(): (device_ip4_config_changed): no DNS changes to commit (0)
NetworkManager[31344]: <info> (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
NetworkManager[31344]: <debug> [1412036443.177134] [nm-device.c:5261] nm_device_queue_state(): (wlan0): queued state change to activated (id 209)
NetworkManager[31344]: <debug> [1412036443.177180] [nm-manager.c:4242] policy_activating_device_changed(): ActivatingConnection now (none)
NetworkManager[31344]: <debug> [1412036443.177206] [nm-device.c:5191] nm_device_state_changed(): (wlan0): device entered SECONDARIES state
NetworkManager[31344]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
NetworkManager[31344]: <debug> [1412036443.177274] [nm-device.c:5219] queued_set_state(): (wlan0): running queued state change to activated (id 209)
NetworkManager[31344]: <info> (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0]
NetworkManager[31344]: <debug> [1412036443.190290] [nm-manager.c:4150] connectivity_changed(): connectivity checking indicates FULL
NetworkManager[31344]: <info> NetworkManager state is now CONNECTED_GLOBAL
NetworkManager[31344]: <debug> [1412036443.190541] [nm-dns-manager.c:995] nm_dns_manager_begin_updates(): (device_state_changed): queueing DNS updates (1)
NetworkManager[31344]: <debug> [1412036443.190557] [nm-dns-manager.c:995] nm_dns_manager_begin_updates(): (update_routing_and_dns): queueing DNS updates (2)
NetworkManager[31344]: <info> Policy set 'Ifupdown (wlan0-scientia.net)' (wlan0) as default for IPv4 routing and DNS.
NetworkManager[31344]: <debug> [1412036443.190675] [nm-manager.c:4209] policy_default_device_changed(): PrimaryConnection now Ifupdown (wlan0-scientia.net)
NetworkManager[31344]: <debug> [1412036443.190715] [nm-dns-manager.c:1013] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration changed
NetworkManager[31344]: <debug> [1412036443.190731] [nm-dns-manager.c:1017] nm_dns_manager_end_updates(): (update_routing_and_dns): no DNS changes to commit (1)
NetworkManager[31344]: <debug> [1412036443.190746] [nm-dns-manager.c:1013] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration changed
NetworkManager[31344]: <debug> [1412036443.190760] [nm-dns-manager.c:1022] nm_dns_manager_end_updates(): (device_state_changed): committing DNS changes (0)
NetworkManager[31344]: <debug> [1412036443.190772] [nm-dns-manager.c:591] update_dns(): updating resolv.conf
NetworkManager[31344]: <info> Writing DNS information to /sbin/resolvconf
NetworkManager[31344]: <debug> [1412036443.262306] [nm-device-wifi.c:518] get_active_ap(): (wlan0): active BSSID: 34:31:c4:42:e5:41
NetworkManager[31344]: <debug> [1412036443.262394] [nm-device-wifi.c:528] get_active_ap(): (wlan0): active SSID: 'scientia.net'
NetworkManager[31344]: <debug> [1412036443.262470] [nm-device-wifi.c:538] get_active_ap(): Pass #1
NetworkManager[31344]: <debug> [1412036443.262488] [nm-device-wifi.c:554] get_active_ap(): AP: 'scientia.net' 34:31:c4:42:e5:40
NetworkManager[31344]: <debug> [1412036443.262501] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.262509] [nm-device-wifi.c:554] get_active_ap(): AP: 'Nanas' 08:96:d7:03:80:2f
NetworkManager[31344]: <debug> [1412036443.262515] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.262523] [nm-device-wifi.c:554] get_active_ap(): AP: 'KD WLAN Hotspot+' 0c:47:3d:3e:ce:9c
NetworkManager[31344]: <debug> [1412036443.262530] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.262537] [nm-device-wifi.c:554] get_active_ap(): AP: 'dlink' 1c:7e:e5:00:68:88
NetworkManager[31344]: <debug> [1412036443.262544] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.262550] [nm-device-wifi.c:554] get_active_ap(): AP: 'HITRON-9EE0' 78:8d:f7:73:9e:e8
NetworkManager[31344]: <debug> [1412036443.262557] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.262704] [nm-device-wifi.c:554] get_active_ap(): AP: 'NETGEAR' c0:3f:0e:c8:f1:0a
NetworkManager[31344]: <debug> [1412036443.262893] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.263080] [nm-device-wifi.c:554] get_active_ap(): AP: 'WLAN-GJDSWW' a4:99:47:7c:46:87
NetworkManager[31344]: <debug> [1412036443.263278] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.263454] [nm-device-wifi.c:554] get_active_ap(): AP: 'Igel' c0:4a:00:38:e1:8d
NetworkManager[31344]: <debug> [1412036443.263620] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.263782] [nm-device-wifi.c:554] get_active_ap(): AP: 'o2-WLAN24' 9c:80:df:98:ec:f4
NetworkManager[31344]: <debug> [1412036443.263950] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.264120] [nm-device-wifi.c:554] get_active_ap(): AP: 'o2-WLAN60' 00:1c:28:a7:8a:a7
NetworkManager[31344]: <debug> [1412036443.264285] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.264455] [nm-device-wifi.c:554] get_active_ap(): AP: 'HITRON-EF40' 68:b6:fc:26:ef:48
NetworkManager[31344]: <debug> [1412036443.264620] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.264791] [nm-device-wifi.c:554] get_active_ap(): AP: 'FRITZ!Box 7362 SL' 34:31:c4:3c:5f:08
NetworkManager[31344]: <debug> [1412036443.264954] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.265124] [nm-device-wifi.c:554] get_active_ap(): AP: 'Rollespiel' 08:96:d7:17:8e:90
NetworkManager[31344]: <debug> [1412036443.265288] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.265459] [nm-device-wifi.c:554] get_active_ap(): AP: 'ALICE-WLAN16' 00:1c:28:74:38:cf
NetworkManager[31344]: <debug> [1412036443.265621] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.265788] [nm-device-wifi.c:554] get_active_ap(): AP: 'ALICE-WLAN29' 88:25:2c:97:da:52
NetworkManager[31344]: <debug> [1412036443.265952] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.266120] [nm-device-wifi.c:554] get_active_ap(): AP: 'KDG-2B4DE' 34:31:c4:0e:02:14
NetworkManager[31344]: <debug> [1412036443.266286] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.266453] [nm-device-wifi.c:554] get_active_ap(): AP: 'HITRON-CE90' 0c:47:3d:3e:ce:98
NetworkManager[31344]: <debug> [1412036443.266619] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.266805] [nm-device-wifi.c:554] get_active_ap(): AP: 'ALICE-WLAN20' 74:31:70:0a:55:c1
NetworkManager[31344]: <debug> [1412036443.266969] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.267122] [nm-device-wifi.c:554] get_active_ap(): AP: 'o2-WLAN95' 88:03:55:f9:02:83
NetworkManager[31344]: <debug> [1412036443.267285] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.267454] [nm-device-wifi.c:554] get_active_ap(): AP: 'EasyBox-2A6626' 74:31:70:2a:66:e0
NetworkManager[31344]: <debug> [1412036443.267640] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.267782] [nm-device-wifi.c:554] get_active_ap(): AP: 'WLAN-2B6092' 88:25:2c:2b:60:a8
NetworkManager[31344]: <debug> [1412036443.267920] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.268064] [nm-device-wifi.c:554] get_active_ap(): AP: 'Quagan Fuuuu' 88:03:55:7b:d8:db
NetworkManager[31344]: <debug> [1412036443.268218] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.268390] [nm-device-wifi.c:554] get_active_ap(): AP: 'Quagan Fuuuu' 88:03:55:7b:d8:d9
NetworkManager[31344]: <debug> [1412036443.268557] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.268725] [nm-device-wifi.c:554] get_active_ap(): AP: 'o2-WLAN08' 88:03:55:6b:29:5c
NetworkManager[31344]: <debug> [1412036443.268890] [nm-device-wifi.c:562] get_active_ap(): BSSID mismatch
NetworkManager[31344]: <debug> [1412036443.269059] [nm-device-wifi.c:554] get_active_ap(): AP: 'scientia.net' 34:31:c4:42:e5:41
NetworkManager[31344]: <debug> [1412036443.269232] [nm-device-wifi.c:594] get_active_ap(): matched
NetworkManager[31344]: <info> (wlan0): roamed from BSSID 34:31:C4:42:E5:40 (scientia.net) to 34:31:C4:42:E5:41 (scientia.net)
NetworkManager[31344]: <info> Activation (wlan0) successful, device activated.
XMT: Forming Info-Request, 6870 ms elapsed.
XMT: Info-Request on wlan0, interval 8120ms.
NetworkManager[31344]: <debug> [1412036444.260241] [nm-device-wifi.c:518] get_active_ap(): (wlan0): active BSSID: 34:31:c4:42:e5:41
NetworkManager[31344]: <debug> [1412036444.260548] [nm-device-wifi.c:528] get_active_ap(): (wlan0): active SSID: 'scientia.net'
NetworkManager[31344]: <debug> [1412036444.260906] [nm-device-wifi.c:538] get_active_ap(): Pass #1
NetworkManager[31344]: <debug> [1412036444.260985] [nm-device-wifi.c:554] get_active_ap(): AP: 'scientia.net' 34:31:c4:42:e5:41
NetworkManager[31344]: <debug> [1412036444.261038] [nm-device-wifi.c:594] get_active_ap(): matched
***************************************************************************************
At this point the connection to the WiFi Router has been successfully established and
networking/internet works.
***************************************************************************************
***************************************************************************************
Next I close the connection, here by simply disabling WiFi in the NM Applet
***************************************************************************************
NetworkManager[31344]: <debug> [1412036450.263643] [nm-device-wifi.c:518] get_active_ap(): (wlan0): active BSSID: 34:31:c4:42:e5:41
NetworkManager[31344]: <debug> [1412036450.263738] [nm-device-wifi.c:528] get_active_ap(): (wlan0): active SSID: 'scientia.net'
NetworkManager[31344]: <debug> [1412036450.263796] [nm-device-wifi.c:538] get_active_ap(): Pass #1
NetworkManager[31344]: <debug> [1412036450.263808] [nm-device-wifi.c:554] get_active_ap(): AP: 'scientia.net' 34:31:c4:42:e5:41
NetworkManager[31344]: <debug> [1412036450.263819] [nm-device-wifi.c:594] get_active_ap(): matched
XMT: Forming Info-Request, 15000 ms elapsed.
XMT: Info-Request on wlan0, interval 15460ms.
NetworkManager[31344]: <debug> [1412036456.262808] [nm-device-wifi.c:518] get_active_ap(): (wlan0): active BSSID: 34:31:c4:42:e5:41
NetworkManager[31344]: <debug> [1412036456.262959] [nm-device-wifi.c:528] get_active_ap(): (wlan0): active SSID: 'scientia.net'
NetworkManager[31344]: <debug> [1412036456.263085] [nm-device-wifi.c:538] get_active_ap(): Pass #1
NetworkManager[31344]: <debug> [1412036456.263109] [nm-device-wifi.c:554] get_active_ap(): AP: 'scientia.net' 34:31:c4:42:e5:41
NetworkManager[31344]: <debug> [1412036456.263141] [nm-device-wifi.c:594] get_active_ap(): matched
NetworkManager[31344]: <debug> [1412036457.769867] [nm-manager.c:4751] manager_radio_user_toggled(): (WiFi): setting radio disabled by user
NetworkManager[31344]: <debug> [1412036457.778784] [nm-manager.c:1609] manager_update_radio_enabled(): (wlan0): setting radio disabled
NetworkManager[31344]: <debug> [1412036457.778833] [nm-device-wifi.c:3580] set_enabled(): (wlan0): device now disabled
NetworkManager[31344]: <info> (wlan0): device state change: activated -> unavailable (reason 'none') [100 20 0]
NetworkManager[31344]: <info> (wlan0): deactivating device (reason 'none') [0]
NetworkManager[31344]: <debug> [1412036457.778931] [nm-firewall-manager.c:166] nm_firewall_manager_remove_from_zone(): (wlan0) firewall zone remove skipped (not running)
NetworkManager[31344]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 31397
NetworkManager[31344]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 31399
NetworkManager[31344]: <debug> [1412036458.180164] [NetworkManagerUtils.c:583] nm_utils_do_sysctl(): sysctl: setting '/proc/sys/net/ipv6/conf/wlan0/disable_ipv6' to '0'
NetworkManager[31344]: <debug> [1412036458.180242] [NetworkManagerUtils.c:583] nm_utils_do_sysctl(): sysctl: setting '/proc/sys/net/ipv6/conf/wlan0/accept_ra' to '0'
NetworkManager[31344]: <debug> [1412036458.180267] [NetworkManagerUtils.c:583] nm_utils_do_sysctl(): sysctl: setting '/proc/sys/net/ipv6/conf/wlan0/use_tempaddr' to '0'
NetworkManager[31344]: <debug> [1412036458.180395] [nm-device-wifi.c:854] _set_hw_addr(): (wlan0): no MAC address change needed
NetworkManager[31344]: <debug> [1412036458.180525] [nm-system.c:1404] nm_system_iface_flush_routes(): (wlan0): flushing routes ifindex 3 family UNSPEC (0)
NetworkManager[31344]: <debug> [1412036458.207018] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET (2) addr 0.0.0.0/0
NetworkManager[31344]: <debug> [1412036458.207052] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.207102] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET (2) addr 192.168.0.0/24
NetworkManager[31344]: <debug> [1412036458.207119] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.207151] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET (2) addr 127.0.0.0/32
NetworkManager[31344]: <debug> [1412036458.207170] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET (2) addr 127.0.0.0/8
NetworkManager[31344]: <debug> [1412036458.207185] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET (2) addr 127.0.0.1/32
NetworkManager[31344]: <debug> [1412036458.207202] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET (2) addr 127.255.255.255/32
NetworkManager[31344]: <debug> [1412036458.207219] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET (2) addr 192.168.0.0/32
NetworkManager[31344]: <debug> [1412036458.207232] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.207266] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET (2) addr 192.168.0.100/32
NetworkManager[31344]: <debug> [1412036458.207284] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.207319] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET (2) addr 192.168.0.255/32
NetworkManager[31344]: <debug> [1412036458.207560] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.207939] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET6 (10) addr ::1/128
NetworkManager[31344]: <debug> [1412036458.208242] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr 2001:a60:146e:5c01::/64
NetworkManager[31344]: <debug> [1412036458.208578] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.208827] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr 2a03:2880:2020:6f09:face:b00c:0:1/128
NetworkManager[31344]: <debug> [1412036458.208975] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.209159] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr 2a03:2880:2020:7f00:face:b00c:0:1/128
NetworkManager[31344]: <debug> [1412036458.209248] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.209416] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr 2a03:2880:2130:4f00:face:b00c:0:1/128
NetworkManager[31344]: <debug> [1412036458.209544] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.209731] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr 2a03:2880:f01c:301:face:b00c:0:1/128
NetworkManager[31344]: <debug> [1412036458.209843] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.210006] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr fd00::/64
NetworkManager[31344]: <debug> [1412036458.210133] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.210296] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr fe80::3631:c4ff:fe42:e53e/128
NetworkManager[31344]: <debug> [1412036458.210425] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr fe80::/64
NetworkManager[31344]: <debug> [1412036458.210569] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr 0:0:2100::300:0/0
NetworkManager[31344]: <debug> [1412036458.210711] [nm-system.c:1368] delete_one_route(): deleting route
NetworkManager[31344]: <debug> [1412036458.210912] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET6 (10) addr ::1/128
NetworkManager[31344]: <debug> [1412036458.211022] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET6 (10) addr 2001:a60:146e:5c01:6267:20ff:fe09:9aa4/128
NetworkManager[31344]: <debug> [1412036458.211153] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET6 (10) addr fd00::6267:20ff:fe09:9aa4/128
NetworkManager[31344]: <debug> [1412036458.211296] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET6 (10) addr fe80::6267:20ff:fe09:9aa4/128
NetworkManager[31344]: <debug> [1412036458.211439] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr ff02::1/128
NetworkManager[31344]: <debug> [1412036458.211594] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr ff02::1:2/128
NetworkManager[31344]: <debug> [1412036458.211759] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr ff02::1:ff09:9aa4/128
NetworkManager[31344]: <debug> [1412036458.211907] [nm-netlink-utils.c:356] dump_route(): route idx 3 family INET6 (10) addr ff00::/8
NetworkManager[31344]: <debug> [1412036458.212057] [nm-netlink-utils.c:356] dump_route(): route idx 1 family INET6 (10) addr 0:0:4100::/0
NetworkManager[31344]: <debug> [1412036458.212363] [nm-system.c:194] sync_addresses(): (wlan0): syncing addresses (family 0)
NetworkManager[31344]: <debug> [1412036458.212403] [nm-system.c:247] sync_addresses(): (wlan0): removing address '192.168.0.100/24'
NetworkManager[31344]: <debug> [1412036458.212602] [nm-system.c:247] sync_addresses(): (wlan0): removing address 'fd00::6267:20ff:fe09:9aa4/64'
NetworkManager[31344]: <debug> [1412036458.212763] [nm-system.c:247] sync_addresses(): (wlan0): removing address '2001:a60:146e:5c01:6267:20ff:fe09:9aa4/64'
NetworkManager[31344]: <debug> [1412036458.212941] [nm-system.c:230] sync_addresses(): (wlan0): ignoring IPv6 link-local address
NetworkManager[31344]: <debug> [1412036458.213126] [nm-dns-manager.c:995] nm_dns_manager_begin_updates(): (device_ip4_config_changed): queueing DNS updates (1)
NetworkManager[31344]: <debug> [1412036458.213274] [nm-manager.c:4209] policy_default_device_changed(): PrimaryConnection now (none)
NetworkManager[31344]: <debug> [1412036458.213446] [nm-dns-manager.c:1013] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration changed
NetworkManager[31344]: <debug> [1412036458.213595] [nm-dns-manager.c:1022] nm_dns_manager_end_updates(): (device_ip4_config_changed): committing DNS changes (0)
NetworkManager[31344]: <debug> [1412036458.213762] [nm-dns-manager.c:591] update_dns(): updating resolv.conf
NetworkManager[31344]: <info> Removing DNS information from /sbin/resolvconf
NetworkManager[31344]: <debug> [1412036458.277513] [nm-manager.c:4150] connectivity_changed(): connectivity checking indicates NONE
NetworkManager[31344]: <info> NetworkManager state is now DISCONNECTED
NetworkManager[31344]: <debug> [1412036458.277635] [nm-dns-manager.c:995] nm_dns_manager_begin_updates(): (update_routing_and_dns): queueing DNS updates (1)
NetworkManager[31344]: <debug> [1412036458.277668] [nm-dns-manager.c:1013] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
NetworkManager[31344]: <debug> [1412036458.277683] [nm-dns-manager.c:1017] nm_dns_manager_end_updates(): (update_routing_and_dns): no DNS changes to commit (0)
NetworkManager[31344]: <debug> [1412036458.278948] [nm-device-wifi.c:1366] is_available(): (wlan0): not available because not enabled
NetworkManager[31344]: <debug> [1412036458.278972] [nm-device.c:5154] nm_device_state_changed(): (wlan0): device not yet available for transition to DISCONNECTED
NetworkManager[31344]: <info> (wlan0): taking down device.
NetworkManager[31344]: <info> WiFi hardware radio set disabled
NetworkManager[31344]: <debug> [1412036458.284527] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
NetworkManager[31344]: <debug> [1412036458.284707] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
NetworkManager[31344]: <debug> [1412036458.284762] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1043
NetworkManager[31344]: <debug> [1412036458.285362] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1002
NetworkManager[31344]: <debug> [1412036458.312463] [nm-udev-manager.c:568] handle_uevent(): UDEV event: action 'change' subsys 'rfkill' device 'rfkill0'
NetworkManager[31344]: <debug> [1412036458.312589] [nm-udev-manager.c:216] recheck_killswitches(): WiFi rfkill switch rfkill0 state now 0/1
NetworkManager[31344]: <debug> [1412036458.312607] [nm-udev-manager.c:243] recheck_killswitches(): WiFi rfkill state now 'soft-blocked'
NetworkManager[31344]: <debug> [1412036458.312623] [nm-manager.c:1757] manager_rfkill_update_one_type(): WiFi hw-enabled 1 sw-enabled 0
NetworkManager[31344]: <info> WiFi now disabled by radio killswitch
***************************************************************************************
At this point I try to re-connect (again by clicking on the WPA-PSK connection in the
NM applet
***************************************************************************************
NetworkManager[31344]: <debug> [1412036466.21271] [nm-manager.c:4751] manager_radio_user_toggled(): (WiFi): setting radio enabled by user
NetworkManager[31344]: <debug> [1412036466.30355] [nm-manager.c:1609] manager_update_radio_enabled(): (wlan0): setting radio enabled
NetworkManager[31344]: <debug> [1412036466.30406] [nm-device-wifi.c:3580] set_enabled(): (wlan0): device now enabled
NetworkManager[31344]: <info> (wlan0): bringing up device.
NetworkManager[31344]: <debug> [1412036466.30708] [nm-device-wifi.c:3598] set_enabled(): (wlan0): enable blocked by failure to bring device up
NetworkManager[31344]: <info> WiFi hardware radio set enabled
NetworkManager[31344]: <debug> [1412036466.77933] [nm-udev-manager.c:568] handle_uevent(): UDEV event: action 'change' subsys 'rfkill' device 'rfkill0'
NetworkManager[31344]: <debug> [1412036466.78109] [nm-udev-manager.c:216] recheck_killswitches(): WiFi rfkill switch rfkill0 state now 1/0
NetworkManager[31344]: <debug> [1412036466.78144] [nm-udev-manager.c:243] recheck_killswitches(): WiFi rfkill state now 'unblocked'
NetworkManager[31344]: <debug> [1412036466.78171] [nm-manager.c:1757] manager_rfkill_update_one_type(): WiFi hw-enabled 1 sw-enabled 1
NetworkManager[31344]: <info> WiFi now enabled by radio killswitch
NetworkManager[31344]: <debug> [1412036466.78223] [nm-manager.c:1609] manager_update_radio_enabled(): (wlan0): setting radio enabled
NetworkManager[31344]: <debug> [1412036466.78239] [nm-device-wifi.c:3580] set_enabled(): (wlan0): device now enabled
NetworkManager[31344]: <info> (wlan0): bringing up device.
NetworkManager[31344]: <debug> [1412036466.171040] [nm-supplicant-manager.c:90] nm_supplicant_manager_iface_get(): (wlan0): creating new supplicant interface
NetworkManager[31344]: <debug> [1412036466.171145] [nm-supplicant-interface.c:1022] interface_add(): (wlan0): adding interface to supplicant
NetworkManager[31344]: <debug> [1412036466.171279] [nm-device-wifi.c:3615] set_enabled(): (wlan0): enable waiting on supplicant state
NetworkManager[31344]: <debug> [1412036466.171353] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
NetworkManager[31344]: <debug> [1412036466.237305] [nm-supplicant-interface.c:849] interface_add_done(): (wlan0): interface added to supplicant
NetworkManager[31344]: <info> (wlan0) supports 5 scan SSIDs
NetworkManager[31344]: <debug> [1412036466.238137] [nm-supplicant-interface.c:747] iface_check_netreply_cb(): Supplicant supports network credentials requests
NetworkManager[31344]: <info> (wlan0): supplicant interface state: starting -> ready
NetworkManager[31344]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
NetworkManager[31344]: <warn> Trying to remove a non-existant call id.
NetworkManager[31344]: <info> (wlan0): supplicant interface state: ready -> disconnected
NetworkManager[31344]: <info> (wlan0) supports 5 scan SSIDs
NetworkManager[31344]: <warn> Could not get scan request result: Scan request rejected
NetworkManager[31344]: <debug> [1412036468.850564] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
NetworkManager[31344]: <info> (wlan0): supplicant interface state: disconnected -> inactive
NetworkManager[31344]: <info> Activation (wlan0) starting connection 'Ifupdown (wlan0-scientia.net)'
NetworkManager[31344]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[31344]: <info> NetworkManager state is now CONNECTING
NetworkManager[31344]: <debug> [1412036471.337295] [nm-manager.c:4242] policy_activating_device_changed(): ActivatingConnection now Ifupdown (wlan0-scientia.net)
NetworkManager[31344]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[31344]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[31344]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[31344]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[31344]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[31344]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[31344]: <info> Activation (wlan0/wireless): access point 'Ifupdown (wlan0-scientia.net)' has security, but secrets are required.
NetworkManager[31344]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
NetworkManager[31344]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
***************************************************************************************
here it waits "forever", with no connection being established anymore
***************************************************************************************
Cheers,
Chris.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/x-pkcs7-signature
Size: 5313 bytes
Desc: not available
URL: <http://lists.alioth.debian.org/pipermail/pkg-utopia-maintainers/attachments/20140930/8de3ed9a/attachment-0001.bin>
More information about the Pkg-utopia-maintainers
mailing list