NM fails with 'config-failed' despite successful MM connection
Bjørn Mork
bjorn at mork.no
Wed Jan 4 17:24:33 UTC 2017
Dan Williams <dcbw at redhat.com> writes:
> Could you:
>
> nmcli g log level debug
>
> and grab a trace when NM fails to configure? That'll tell us what's
> happening on the NM side.
Finally, after a frustrating number of successful connection attempts :)
But the result was educating:
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.0973] active-connection[0x563f6678f580]: set device "cdc-wdm0" [0x563f66933c20]
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.0974] device[0x563f66933c20] (cdc-wdm0): add_pending_action (1): 'activation::0x563f6678f580'
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.0975] active-connection[0x563f6678f580]: constructed (NMActRequest, version-id 7)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.0976] auth: call[23]: CheckAuthorization(org.freedesktop.NetworkManager.network-control), subject=unix-process[pid=3985, uid=1000, start=17284820]
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.1015] auth: call[23]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.1017] device[0x563f66933c20] (cdc-wdm0): unmanaged: flags set to [!sleeping,!loopback,!platform-init,!user-explicit,!user-settings=0x0/0x79/managed, set-managed [user-explicit=0x20], reason user-requested)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <info> [1483549874.1025] device (cdc-wdm0): Activation: starting connection 'Telenor ("telenor.smart")' (3fdb56ec-485a-485c-9b05-d9e80a5aa52f)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.1026] device[0x563f66933c20] (cdc-wdm0): activation-stage: schedule activate_stage1_device_prepare,2 (id 5096)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <info> [1483549874.1028] audit: op="connection-activate" uuid="3fdb56ec-485a-485c-9b05-d9e80a5aa52f" name="Telenor ("telenor.smart")" pid=3985 uid=1000 result="success"
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.1030] device[0x563f66933c20] (cdc-wdm0): activation-stage: invoke activate_stage1_device_prepare,2 (id 5096)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <info> [1483549874.1031] device (cdc-wdm0): state change: disconnected -> prepare (reason 'none') [30 40 0]
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.1033] active-connection[0x563f6678f580]: set state activating (was unknown)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.1033] active-connection[0x563f6678f580]: check-master-ready: not signalling (state activating, no master)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.1035] modem-broadband[cdc-wdm0]: launching connection with ip type 'ipv4v6' (try 1)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.1037] device[0x563f66933c20] (cdc-wdm0): activation-stage: complete activate_stage1_device_prepare,2 (id 5096)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4099] platform-linux: link: change 28: user-ipv6ll: set IPv6 address generation mode to none
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4103] platform-linux: do-request-link: 28
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4106] platform-linux: netlink: recvmsg: error message from kernel: No such device (19) for request 331
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4108] platform-linux: netlink: recvmsg: error message from kernel: No such device (19) for request 332
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4116] platform-linux: do-change-link[28]: failure changing link: failure 19 (No such device)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4117] platform: link: setting up '(null)' (28)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4119] platform-linux: link: change 28: flags: set 0x1/0x1 ([up] / [up])
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4123] platform-linux: netlink: recvmsg: error message from kernel: No such device (19) for request 333
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4137] platform-linux: do-request-link: 28
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4143] platform-linux: netlink: recvmsg: error message from kernel: No such device (19) for request 338
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4146] platform-linux: do-change-link[28]: failure changing link: failure 19 (No such device)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4159] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/disable_ipv6' to '1' (current value is '0')
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4166] device[0x563f66933c20] (cdc-wdm0): activation-stage: schedule activate_stage2_device_config,2 (id 5125)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4175] device[0x563f66933c20] (cdc-wdm0): activation-stage: invoke activate_stage2_device_config,2 (id 5125)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <info> [1483549874.4177] device (cdc-wdm0): state change: prepare -> config (reason 'none') [40 50 0]
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4184] device[0x563f66933c20] (cdc-wdm0): bringing up device
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4186] platform: link: setting up '(null)' (28)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4187] platform-linux: link: change 28: flags: set 0x1/0x1 ([up] / [up])
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4190] platform-linux: do-request-link: 28
Jan 4 18:11:14 miraculix kernel: [96770.171542] IPv6: ADDRCONF(NETDEV_UP): wwan0: link is not ready
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4192] platform-linux: netlink: recvmsg: error message from kernel: No such device (19) for request 339
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4195] platform-linux: netlink: recvmsg: error message from kernel: No such device (19) for request 340
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4197] platform-linux: do-change-link[28]: failure changing link: failure 19 (No such device)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <info> [1483549874.4199] device (cdc-wdm0): state change: config -> failed (reason 'config-failed') [50 120 4]
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4206] active-connection[0x563f6678f580]: set state deactivated (was activating)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4209] active-connection[0x563f6678f580]: check-master-ready: not signalling (state deactivated, no master)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4210] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (0): 'activation::0x563f6678f580'
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4220] modem-broadband[cdc-wdm0]: notifying ModemManager about the modem disconnection
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4226] policy: connection 'Telenor ("telenor.smart")' failed to autoconnect; 4 tries left
Jan 4 18:11:14 miraculix NetworkManager[8315]: <warn> [1483549874.4243] device (cdc-wdm0): Activation: failed for connection 'Telenor ("telenor.smart")'
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4244] device[0x563f66933c20] (cdc-wdm0): add_pending_action (1): 'queued state change to disconnected'
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4246] device[0x563f66933c20] (cdc-wdm0): queued state change to disconnected due to none (id 5136)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4246] device[0x563f66933c20] (cdc-wdm0): activation-stage: complete activate_stage2_device_config,2 (id 5125)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4277] device[0x563f66933c20] (cdc-wdm0): running queued state change to disconnected (id 5136)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <info> [1483549874.4278] device (cdc-wdm0): state change: failed -> disconnected (reason 'none') [120 30 0]
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4279] device[0x563f66933c20] (cdc-wdm0): will enable userland IPv6LL
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4329] platform-linux: link: change 28: user-ipv6ll: set IPv6 address generation mode to none
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4331] platform-linux: do-request-link: 28
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4333] platform-linux: netlink: recvmsg: error message from kernel: No such device (19) for request 341
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4335] platform-linux: netlink: recvmsg: error message from kernel: No such device (19) for request 342
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4336] platform-linux: do-change-link[28]: failure changing link: failure 19 (No such device)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4338] device[0x563f66933c20] (cdc-wdm0): failed to enable userspace IPv6LL address handling (not-found)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4340] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wwan0/disable_ipv6': '1'
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4345] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/disable_ipv6' to '0' (current value is '1')
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4346] device[0x563f66933c20] (cdc-wdm0): deactivating device (reason 'none') [0]
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4347] firewall: [0x7f1e6402eb50,remove*:"wwan0"]: firewall zone remove wwan0:default (not running, simulate success)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4347] firewall: [0x7f1e6402eb50,remove*:"wwan0"]: complete: drop request simulating success
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4347] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (1): 'dhcp6' not pending (expected)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4348] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (1): 'autoconf6' not pending (expected)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4349] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/accept_ra' to '0' (current value is '1')
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4351] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/use_tempaddr' to '0' (current value is identical)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4352] modem-broadband[cdc-wdm0]: notifying ModemManager about the modem disconnection
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4354] route-mgr4: 28: sync 0 IPv4 routes
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4354] route-mgr6: 28: sync 0 IPv6 routes
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4355] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:0:+sync]: record:add 0.0.0.0/0 via 0.0.0.0 dev 28 metric 4294967295 mss 0 src unknown (4294967295)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4356] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:0:+sync]: record:add ::/0 via :: dev 28 metric 4294967295 mss 0 src unknown (4294967295)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4356] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:0:+sync]: record:remove 0.0.0.0/0 via 0.0.0.0 dev 28 metric 4294967295 mss 0 src unknown (4294967295)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4357] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:0:+sync]: record:remove ::/0 via :: dev 28 metric 4294967295 mss 0 src unknown (4294967295)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4357] device[0x563f66933c20] (cdc-wdm0): ip4-config: update (commit=1, routes-full-sync=1, new-config=(nil))
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4358] device[0x563f66933c20] (cdc-wdm0): ip6-config: update (commit=1, routes-full-sync=1, new-config=(nil))
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4364] dns-mgr: (update_routing_and_dns): queueing DNS updates (1)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4365] dns-mgr: (update_routing_and_dns): DNS configuration did not change
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4367] dns-mgr: (update_routing_and_dns): no DNS changes to commit (0)
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4367] device[0x563f66933c20] (cdc-wdm0): add_pending_action (2): 'autoactivate'
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4368] active-connection[0x563f6678f580]: disposing
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4376] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (1): 'queued state change to disconnected'
Jan 4 18:11:14 miraculix NetworkManager[8315]: <debug> [1483549874.4391] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (0): 'autoactivate'
Jan 4 18:11:18 miraculix NetworkManager[8315]: <debug> [1483549878.8304] device[0x563f6677e000] (wlan0): add_pending_action (1): 'scan'
Jan 4 18:11:18 miraculix NetworkManager[8315]: <debug> [1483549878.8330] device[0x563f6677e000] (wlan0): remove_pending_action (0): 'scan'
I don't know where it got '28' from. Maybe cached from an earlier
instance of the same device? This is a laptop which I often suspend,
and the modem goes completely away and is rediscovered as a completely
new USB device on every such event.
The 'wwan0' associated with the modem was link number '29' when the
above connection attempt failed:
root at miraculix:/tmp# ip link
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2: eth0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc pfifo_fast state DOWN mode DEFAULT group default qlen 1000
link/ether 54:ee:75:9a:bf:58 brd ff:ff:ff:ff:ff:ff
3: wlan0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DORMANT group default qlen 1000
link/ether 44:85:00:11:26:e6 brd ff:ff:ff:ff:ff:ff
5: tap0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP mode DEFAULT group default qlen 1000
link/ether 86:69:18:2d:bf:ca brd ff:ff:ff:ff:ff:ff
6: tap0.42 at tap0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP mode DEFAULT group default qlen 1000
link/ether 86:69:18:2d:bf:ca brd ff:ff:ff:ff:ff:ff
29: wwan0: <BROADCAST,MULTICAST,NOARP> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
link/ether 9e:20:ec:6f:e5:38 brd ff:ff:ff:ff:ff:ff
After waiting a few minutes it will use '29', and the connection is
successful as expected:
Jan 4 18:20:29 miraculix NetworkManager[8315]: <debug> [1483550429.9456] rdisc-lndp[0x563f6678f440,"wlan0"]: processing libndp events
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0477] active-connection[0x563f6678f340]: set device "cdc-wdm0" [0x563f66933c20]
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0479] device[0x563f66933c20] (cdc-wdm0): add_pending_action (1): 'activation::0x563f6678f340'
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0482] active-connection[0x563f6678f340]: constructed (NMActRequest, version-id 8)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0486] auth: call[24]: CheckAuthorization(org.freedesktop.NetworkManager.network-control), subject=unix-process[pid=3985, uid=1000, start=17284820]
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0573] auth: call[24]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.0587] device (cdc-wdm0): Activation: starting connection 'Telenor ("telenor.smart")' (3fdb56ec-485a-485c-9b05-d9e80a5aa52f)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0588] device[0x563f66933c20] (cdc-wdm0): activation-stage: schedule activate_stage1_device_prepare,2 (id 6907)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.0593] audit: op="connection-activate" uuid="3fdb56ec-485a-485c-9b05-d9e80a5aa52f" name="Telenor ("telenor.smart")" pid=3985 uid=1000 result="success"
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0597] device[0x563f66933c20] (cdc-wdm0): activation-stage: invoke activate_stage1_device_prepare,2 (id 6907)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.0598] device (cdc-wdm0): state change: disconnected -> prepare (reason 'none') [30 40 0]
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0602] active-connection[0x563f6678f340]: set state activating (was unknown)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0603] active-connection[0x563f6678f340]: check-master-ready: not signalling (state activating, no master)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0605] modem-broadband[cdc-wdm0]: launching connection with ip type 'ipv4v6' (try 1)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.0609] device[0x563f66933c20] (cdc-wdm0): activation-stage: complete activate_stage1_device_prepare,2 (id 6907)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3499] platform-linux: link: change 29: user-ipv6ll: set IPv6 address generation mode to none
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3502] platform-linux: do-request-link: 29
Jan 4 18:20:34 miraculix kernel: [97330.086104] 8021q: adding VLAN 0 to HW filter on device wwan0
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3506] platform: signal: link changed: 29: wwan0 <NOARP,DOWN;broadcast,multicast,noarp> mtu 1500 arp 1 wwan? init addrgenmode none addr 9E:20:EC:6F:E5:38 driver cdc_mbim rx:0,0 tx:0,0
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3508] device[0x563f66933c20] (cdc-wdm0): queued link change for ip-ifindex 29
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3511] platform-linux: do-change-link[29]: success changing link: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3513] platform: link: setting up 'wwan0' (29)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3514] platform-linux: link: change 29: flags: set 0x1/0x1 ([up] / [up])
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3528] platform-linux: do-request-link: 29
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3535] platform: signal: link changed: 29: wwan0 <NOARP,UP,LOWER_UP;broadcast,multicast,noarp,up,running,lowerup> mtu 1500 arp 1 wwan? init addrgenmode none addr 9E:20:EC:6F:E5:38 driver cdc_mbim rx:0,0 tx:0,0
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3542] platform-linux: do-change-link[29]: success changing link: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3549] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/disable_ipv6' to '1' (current value is '0')
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3562] device[0x563f66933c20] (cdc-wdm0): activation-stage: schedule activate_stage2_device_config,2 (id 6936)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3575] device[0x563f66933c20] (cdc-wdm0): activation-stage: invoke activate_stage2_device_config,2 (id 6936)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3577] device (cdc-wdm0): state change: prepare -> config (reason 'none') [40 50 0]
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3588] device[0x563f66933c20] (cdc-wdm0): bringing up device
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3590] platform: link: setting up 'wwan0' (29)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3593] platform-linux: link: change 29: flags: set 0x1/0x1 ([up] / [up])
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3596] platform-linux: do-request-link: 29
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3602] platform-linux: do-change-link[29]: success changing link: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3604] device[0x563f66933c20] (cdc-wdm0): Activation: setting firewall zone 'default'
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3606] firewall: [0x7f1e64058a40,change*:"wwan0"]: firewall zone change wwan0:default (not running, simulate success)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3608] device[0x563f66933c20] (cdc-wdm0): activation-stage: complete activate_stage2_device_config,2 (id 6936)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3638] firewall: [0x7f1e64058a40,change*:"wwan0"]: complete: fake success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3640] device[0x563f66933c20] (cdc-wdm0): activation-stage: schedule activate_stage3_ip_config_start,2 (id 6940)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3644] device[0x563f66933c20] (cdc-wdm0): activation-stage: invoke activate_stage3_ip_config_start,2 (id 6940)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3650] device (cdc-wdm0): state change: config -> ip-config (reason 'none') [50 70 0]
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3660] ip4_config_start
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3663] MODEM_IP_METHOD_STATIC
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3665] device[0x563f66933c20] (cdc-wdm0): activation-stage: complete activate_stage3_ip_config_start,2 (id 6940)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3685] modem-broadband[cdc-wdm0]: IPv4 static configuration:
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3687] modem-broadband[cdc-wdm0]: address 10.164.235.15/27
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3688] modem-broadband[cdc-wdm0]: gateway 10.164.235.16
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3689] modem-broadband[cdc-wdm0]: DNS 193.213.112.4
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3692] modem-broadband[cdc-wdm0]: DNS 130.67.15.198
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3694] device[0x563f66933c20] (cdc-wdm0): ip4-config: update (commit=1, routes-full-sync=1, new-config=0x563f66874940)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3695] platform: address: adding or updating IPv4 address: 10.164.235.15/27 lft forever pref forever lifetime 887-0[4294967295,4294967295] dev 29 src unknown
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3729] platform: signal: address 4 added: 10.164.235.15/27 lft forever pref forever lifetime 887-0[4294967295,4294967295] dev 29 flags permanent src kernel
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3731] default-route: resync: schedule on idle
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3732] device[0x563f66933c20] (cdc-wdm0): queued IP4 config change
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3733] platform: signal: route 4 added: 10.164.235.0/27 via 0.0.0.0 dev 29 metric 0 mss 0 src rt-kernel scope link pref-src 10.164.235.15
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3734] platform-linux: do-add-ip4-address[29: 10.164.235.15/27]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3735] route-mgr4: 29: sync 1 IPv4 routes
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3736] platform: route: deleting IPv4 route 10.164.235.0/27, metric=0, ifindex 29 dev wwan0
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3743] platform: signal: route 4 removed: 10.164.235.0/27 via 0.0.0.0 dev 29 metric 0 mss 0 src rt-kernel scope link pref-src 10.164.235.15
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3744] platform-linux: do-delete-ip4-route[29: 10.164.235.0/27 0]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3745] platform: route: adding or updating IPv4 route: 10.164.235.0/27 via 0.0.0.0 dev 29 metric 700 mss 0 src kernel pref-src 10.164.235.15
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3752] platform: signal: route 4 added: 10.164.235.0/27 via 0.0.0.0 dev 29 metric 700 mss 0 src rt-kernel scope link pref-src 10.164.235.15
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3753] platform-linux: do-add-ip4-route[29: 10.164.235.0/27 700]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3759] device[0x563f66933c20] (cdc-wdm0): ip4-config: set IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/15)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3760] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:1:+sync]: record:add 0.0.0.0/0 via 10.164.235.16 dev 29 metric 700 mss 0 src user (700)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3761] default-route: resync: cancelled (6947)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3762] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:1:+sync]: sync:add 0.0.0.0/0 via 10.164.235.16 dev 29 metric 700 mss 0 src user (700)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3762] platform: route: adding or updating IPv4 route: 0.0.0.0/0 via 10.164.235.16 dev 29 metric 700 mss 0 src user
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3770] platform: signal: route 4 added: 0.0.0.0/0 via 10.164.235.16 dev 29 metric 700 mss 0 src rt-static scope global
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3771] platform-linux: do-add-ip4-route[29: 0.0.0.0/0 700]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3772] dns-mgr: (device_ip4_config_changed): queueing DNS updates (1)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3773] dns-mgr: (device_ip4_config_changed): DNS configuration did not change
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3773] dns-mgr: (device_ip4_config_changed): no DNS changes to commit (0)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3773] device[0x563f66933c20] (cdc-wdm0): activation-stage: schedule activate_stage5_ip4_config_commit,2 (id 6950)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3774] modem-broadband[cdc-wdm0]: IPv6 base configuration:
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3774] modem-broadband[cdc-wdm0]: address 2a02:2121:24b:219c:552e:5ebe:be9b:ad24/64
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3775] modem-broadband[cdc-wdm0]: gateway 2a02:2121:24b:219c:90ba:4e2d:c50b:be82
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3775] modem-broadband[cdc-wdm0]: DNS 2001:4600:4:fff::52
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3775] modem-broadband[cdc-wdm0]: DNS 2001:4600:4:1fff::52
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3776] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/disable_ipv6' to '0' (current value is '1')
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3778] device[0x563f66933c20] (cdc-wdm0): ip6-config: update (commit=1, routes-full-sync=1, new-config=0x563f668821e0)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3780] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wwan0/mtu': '1500'
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3780] platform: address: adding or updating IPv6 address: 2a02:2121:24b:219c:552e:5ebe:be9b:ad24/64 lft forever pref forever lifetime 887-0[4294967295,4294967295] dev 29 src unknown
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3791] platform: signal: route 6 added: 2a02:2121:24b:219c::/64 via :: dev 29 metric 256 mss 0 src rt-kernel
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3792] device[0x563f66933c20] (cdc-wdm0): queued IP6 config change
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3793] platform: signal: address 6 added: 2a02:2121:24b:219c:552e:5ebe:be9b:ad24/64 lft forever pref forever lifetime 887-0[4294967295,4294967295] dev 29 flags permanent src kernel
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3793] default-route: resync: schedule on idle
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3794] platform-linux: do-add-ip6-address[29: 2a02:2121:24b:219c:552e:5ebe:be9b:ad24]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3794] route-mgr6: 29: sync 1 IPv6 routes
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3795] platform: route: adding or updating IPv6 route: 2a02:2121:24b:219c:90ba:4e2d:c50b:be82/128 via :: dev 29 metric 700 mss 0 src user
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3796] platform: signal: route 6 added: 2a02:2121:24b:219c:90ba:4e2d:c50b:be82/128 via :: dev 29 metric 700 mss 0 src rt-static
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3797] platform-linux: do-add-ip6-route[29: 2a02:2121:24b:219c:90ba:4e2d:c50b:be82/128 700]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3804] device[0x563f66933c20] (cdc-wdm0): ip6-config: set IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/15)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3804] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:1:+sync]: record:add ::/0 via 2a02:2121:24b:219c:90ba:4e2d:c50b:be82 dev 29 metric 700 mss 0 src user (700)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3805] default-route: resync: cancelled (6952)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3805] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:1:+sync]: sync:add ::/0 via 2a02:2121:24b:219c:90ba:4e2d:c50b:be82 dev 29 metric 700 mss 0 src user (700)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3806] platform: route: adding or updating IPv6 route: ::/0 via 2a02:2121:24b:219c:90ba:4e2d:c50b:be82 dev 29 metric 700 mss 0 src user
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3807] platform: signal: route 6 added: ::/0 via 2a02:2121:24b:219c:90ba:4e2d:c50b:be82 dev 29 metric 700 mss 0 src rt-static
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3808] platform-linux: do-add-ip6-route[29: ::/0 700]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3809] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3809] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3810] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3810] device[0x563f66933c20] (cdc-wdm0): activation-stage: schedule activate_stage5_ip6_config_commit,10 (id 6953)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3816] device[0x563f66933c20] (cdc-wdm0): activation-stage: invoke activate_stage5_ip4_config_commit,2 (id 6950)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3817] device[0x563f66933c20] (cdc-wdm0): ip4-config: update (commit=1, routes-full-sync=0, new-config=0x563f66789310)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3819] platform: address: adding or updating IPv4 address: 10.164.235.15/27 lft forever pref forever lifetime 887-0[4294967295,4294967295] dev 29 src unknown
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3822] platform-linux: do-add-ip4-address[29: 10.164.235.15/27]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3823] route-mgr4: 29: sync 1 IPv4 routes
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3823] platform: route: adding or updating IPv4 route: 10.164.235.0/27 via 0.0.0.0 dev 29 metric 700 mss 0 src kernel pref-src 10.164.235.15
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3824] platform-linux: do-add-ip4-route[29: 10.164.235.0/27 700]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3825] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (1): 'dhcp4' not pending (expected)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3825] device (cdc-wdm0): state change: ip-config -> ip-check (reason 'none') [70 80 0]
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3830] firewall: [0x563f6694c000,change*:"wwan0"]: firewall zone change wwan0:default (not running, simulate success)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3831] device[0x563f66933c20] (cdc-wdm0): activation-stage: complete activate_stage5_ip4_config_commit,2 (id 6950)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3831] device[0x563f66933c20] (cdc-wdm0): activation-stage: invoke activate_stage5_ip6_config_commit,10 (id 6953)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3832] device[0x563f66933c20] (cdc-wdm0): ip6-config: update (commit=1, routes-full-sync=0, new-config=0x563f66871dd0)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3833] platform: address: adding or updating IPv6 address: 2a02:2121:24b:219c:552e:5ebe:be9b:ad24/64 lft forever pref forever lifetime 887-0[4294967295,4294967295] dev 29 src unknown
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3835] platform-linux: do-add-ip6-address[29: 2a02:2121:24b:219c:552e:5ebe:be9b:ad24]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3835] route-mgr6: 29: sync 1 IPv6 routes
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3836] platform: route: adding or updating IPv6 route: 2a02:2121:24b:219c:90ba:4e2d:c50b:be82/128 via :: dev 29 metric 700 mss 0 src user
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3837] platform-linux: do-add-ip6-route[29: 2a02:2121:24b:219c:90ba:4e2d:c50b:be82/128 700]: success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3838] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (1): 'dhcp6' not pending (expected)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3838] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (1): 'autoconf6' not pending (expected)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3838] device[0x563f66933c20] (cdc-wdm0): activation-stage: complete activate_stage5_ip6_config_commit,10 (id 6953)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3853] device[0x563f66933c20] (cdc-wdm0): ip4-config: update (commit=0, routes-full-sync=0, new-config=0x563f66756c40)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3854] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:1:-sync]: record:update 0.0.0.0/0 via 10.164.235.16 dev 29 metric 700 mss 0 src rt-static scope global (700)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3856] firewall: [0x563f6694c000,change*:"wwan0"]: complete: fake success
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3857] dispatcher: (24) (cdc-wdm0) dispatching action 'pre-up' (with callback)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3857] dispatcher: (24) simulate request; no scripts in /etc/NetworkManager/dispatcher.d/pre-up.d
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3858] device[0x563f66933c20] (cdc-wdm0): ip6-config: update (commit=0, routes-full-sync=0, new-config=0x563f66871dd0)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3860] device[0x563f66933c20] (cdc-wdm0): ip6-config: update IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/15)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3860] default-route: entry[1/dev:0x563f66933c20:cdc-wdm0:1:-sync]: record:update ::/0 via 2a02:2121:24b:219c:90ba:4e2d:c50b:be82 dev 29 metric 700 mss 0 src rt-static (700)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3861] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3862] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3862] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3865] device[0x563f66933c20] (cdc-wdm0): add_pending_action (2): 'queued state change to secondaries'
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3865] device[0x563f66933c20] (cdc-wdm0): queued state change to secondaries due to none (id 6964)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3866] device[0x563f66933c20] (cdc-wdm0): running queued state change to secondaries (id 6964)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3867] device (cdc-wdm0): state change: ip-check -> secondaries (reason 'none') [80 90 0]
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3869] device[0x563f66933c20] (cdc-wdm0): add_pending_action (3): 'queued state change to activated'
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3869] device[0x563f66933c20] (cdc-wdm0): queued state change to activated due to none (id 6967)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3870] device[0x563f66933c20] (cdc-wdm0): device entered SECONDARIES state
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3870] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (2): 'queued state change to secondaries'
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3873] device[0x563f66933c20] (cdc-wdm0): running queued state change to activated (id 6967)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3874] device (cdc-wdm0): state change: secondaries -> activated (reason 'none') [90 100 0]
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3876] active-connection[0x563f6678f340]: set state activated (was activating)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3876] active-connection[0x563f6678f340]: check-master-ready: not signalling (state activated, no master)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3953] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (1): 'activation::0x563f6678f340'
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3956] dns-mgr: (device_state_changed): queueing DNS updates (1)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3957] dns-mgr: (update_routing_and_dns): queueing DNS updates (2)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3957] dns-mgr: (update_routing_and_dns): DNS configuration did not change
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3957] dns-mgr: (update_routing_and_dns): no DNS changes to commit (1)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3958] dns-mgr: (device_state_changed): DNS configuration did not change
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3958] dns-mgr: (device_state_changed): no DNS changes to commit (0)
Jan 4 18:20:34 miraculix NetworkManager[8315]: <info> [1483550434.3958] device (cdc-wdm0): Activation: successful, device activated.
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3958] device[0x563f66933c20] (cdc-wdm0): set metered value 3
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3959] dispatcher: (25) (cdc-wdm0) dispatching action 'up'
Jan 4 18:20:34 miraculix NetworkManager[8315]: <debug> [1483550434.3964] device[0x563f66933c20] (cdc-wdm0): remove_pending_action (0): 'queued state change to activated'
Jan 4 18:20:35 miraculix NetworkManager[8315]: <debug> [1483550435.0680] dispatcher: (25) 01ifupdown succeeded
Jan 4 18:20:35 miraculix NetworkManager[8315]: <debug> [1483550435.0681] dispatcher: (25) ntp succeeded
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.4987] rdisc-lndp[0x563f6678f440,"wlan0"]: processing libndp events
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.4993] rdisc-lndp[0x563f6678f440,"wlan0"]: received router advertisement at 890
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.4997] rdisc[0x563f6678f440,"wlan0"]: complete-address: using an stable-privacy address
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.4999] rdisc[0x563f6678f440,"wlan0"]: router discovery configuration changed [AS]:
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5000] rdisc[0x563f6678f440,"wlan0"]: dhcp-level otherconf
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5002] rdisc[0x563f6678f440,"wlan0"]: gateway fe80::21b:21ff:fea7:98bc pref 2 exp 1490
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5004] rdisc[0x563f6678f440,"wlan0"]: address 2001:4641:0:2:7627:374e:db74:e353 exp 87290
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5005] rdisc[0x563f6678f440,"wlan0"]: route 2001:4641:0:2::/64 via :: pref 0 exp 87290
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5007] rdisc[0x563f6678f440,"wlan0"]: dns_server 2001:4641::1 exp 8090
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5009] device[0x563f6677e000] (wlan0): activation-stage: schedule activate_stage5_ip6_config_commit,10 (id 6981)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5011] rdisc[0x563f6678f440,"wlan0"]: scheduling next now/lifetime check: 600 seconds
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5014] device[0x563f6677e000] (wlan0): activation-stage: invoke activate_stage5_ip6_config_commit,10 (id 6981)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5018] device[0x563f6677e000] (wlan0): ip6-config: update (commit=1, routes-full-sync=0, new-config=0x563f66882720)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5023] platform: address: adding or updating IPv6 address: 2001:4641:0:2:7627:374e:db74:e353/64 lft 86400sec pref 14400sec lifetime 890-0[14400,86400] dev 3 flags noprefixroute src unknown
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5037] platform: signal: address 6 changed: 2001:4641:0:2:7627:374e:db74:e353/64 lft 86400sec pref 14400sec lifetime 890-890[14400,86400] dev 3 flags noprefixroute src kernel
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5039] default-route: resync: schedule on idle
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5043] device[0x563f6677e000] (wlan0): queued IP6 config change
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5046] platform-linux: do-add-ip6-address[3: 2001:4641:0:2:7627:374e:db74:e353]: success
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5048] platform: address: adding or updating IPv6 address: fe80::b0c3:cd67:ca54:e36f/64 lft forever pref forever lifetime 890-0[4294967295,4294967295] dev 3 flags permanent src unknown
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5054] platform-linux: do-add-ip6-address[3: fe80::b0c3:cd67:ca54:e36f]: success
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5056] route-mgr6: 3: sync 2 IPv6 routes
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5058] platform: route: adding or updating IPv6 route: 2001:4641:0:2::/64 via :: dev 3 metric 600 mss 0 src rdisc
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5066] platform-linux: do-add-ip6-route[3: 2001:4641:0:2::/64 600]: success
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5069] platform: route: adding or updating IPv6 route: fe80::21b:21ff:fea7:98bc/128 via :: dev 3 metric 600 mss 0 src user
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5089] platform-linux: do-add-ip6-route[3: fe80::21b:21ff:fea7:98bc/128 600]: success
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5115] device[0x563f6677e000] (wlan0): ip6-config: update IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/14)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5117] default-route: entry[0/dev:0x563f6677e000:wlan0:1:+sync]: record:update ::/0 via fe80::21b:21ff:fea7:98bc dev 3 metric 600 mss 0 src user (600)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5119] default-route: resync: cancelled (6983)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5121] default-route: entry[0/dev:0x563f6677e000:wlan0:1:+sync]: sync:update ::/0 via fe80::21b:21ff:fea7:98bc dev 3 metric 600 mss 0 src user (600 -> 600)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5123] platform: route: adding or updating IPv6 route: ::/0 via fe80::21b:21ff:fea7:98bc dev 3 metric 600 mss 0 src user
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5131] platform-linux: do-add-ip6-route[3: ::/0 600]: success
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5134] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5136] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5137] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5140] device[0x563f6677e000] (wlan0): remove_pending_action (0): 'dhcp6' not pending (expected)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5143] device[0x563f6677e000] (wlan0): remove_pending_action (0): 'autoconf6' not pending (expected)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5145] device[0x563f6677e000] (wlan0): activation-stage: complete activate_stage5_ip6_config_commit,10 (id 6981)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5176] device[0x563f6677e000] (wlan0): ip6-config: update (commit=0, routes-full-sync=0, new-config=0x563f66882720)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5188] device[0x563f6677e000] (wlan0): ip6-config: update IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/14)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5190] default-route: entry[0/dev:0x563f6677e000:wlan0:1:-sync]: record:update ::/0 via fe80::21b:21ff:fea7:98bc dev 3 metric 600 mss 0 src rt-static (600)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5194] dns-mgr: (device_ip6_config_changed): queueing DNS updates (1)
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5196] dns-mgr: (device_ip6_config_changed): DNS configuration did not change
Jan 4 18:20:36 miraculix NetworkManager[8315]: <debug> [1483550436.5198] dns-mgr: (device_ip6_config_changed): no DNS changes to commit (0)
Bjørn
More information about the ModemManager-devel
mailing list