Attachment 636142 Details for Bug 868378 – annotated /var/log/messages

annotated /var/log/messages

nm4.txt (text/plain), 25.81 KB, created by Mads Kiilerich on 2012-10-31 13:53:46 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Mads Kiilerich

Created: 2012-10-31 13:53:46 UTC

Size: 25.81 KB

patch

obsolete

>Inserting pcmcia broadband card without SIM:
>
>Oct 31 14:38:05 user07 kernel: [  846.543099] pcmcia_socket pcmcia_socket0: pccard: PCMCIA card inserted into slot 0
>Oct 31 14:38:05 user07 kernel: [  846.543354] pcmcia 0.0: pcmcia: registering new device pcmcia0.0 (IRQ: 19)
>Oct 31 14:38:05 user07 kernel: [  846.559680] pcmcia_socket pcmcia_socket0: Using replacement CIS
>Oct 31 14:38:05 user07 kernel: [  846.559719] serial_cs 0.0: trying to set up [0x0192:0x0710] (pfc: 0, multi: 1, quirk:   (null))
>Oct 31 14:38:05 user07 kernel: [  846.626766] 0.0: ttyS1 at I/O 0x2f8 (irq = 19) is a 16550A
>Oct 31 14:38:05 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:38:05 user07 modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:38:12 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:38:12 user07 modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:38:12 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:38:12 user07 modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:38:12 user07 modem-manager[610]: <info>  (Generic): GSM modem /sys/devices/pci0000:00/0000:00:1e.0/0000:02:01.0/0.0 claimed port ttyS1
>Oct 31 14:38:12 user07 modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:38:12 user07 dbus-daemon[558]: modem-manager[610]: <info>  (Generic): GSM modem /sys/devices/pci0000:00/0000:00:1e.0/0000:02:01.0/0.0 claimed port ttyS1
>Oct 31 14:38:12 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:38:19 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:38:19 user07 modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:38:19 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:38:19 user07 modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:38:19 user07 NetworkManager[571]: <warn> (ttyS1): failed to look up interface index
>Oct 31 14:38:19 user07 NetworkManager[571]: <info> WWAN now disabled by management service
>Oct 31 14:38:19 user07 NetworkManager[571]: <info> (ttyS1): new GSM/UMTS device (driver: 'serial_cs' ifindex: 0)
>Oct 31 14:38:19 user07 NetworkManager[571]: <info> (ttyS1): exported as /org/freedesktop/NetworkManager/Devices/4
>Oct 31 14:38:19 user07 NetworkManager[571]: <info> (ttyS1): now managed
>Oct 31 14:38:19 user07 NetworkManager[571]: <info> (ttyS1): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>Oct 31 14:38:19 user07 NetworkManager[571]: <info> (ttyS1): deactivating device (reason 'managed') [2]
>Oct 31 14:38:19 user07 NetworkManager[571]: <info> (ttyS1): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
>
>Choosing 'New Mobile Broadband Connection' and creating one:
>
>Oct 31 14:38:54 user07 NetworkManager[571]: <info> Activation (ttyS1) starting connection '3 BredbÃ¥nd (standard)'
>Oct 31 14:38:54 user07 NetworkManager[571]: <info> (ttyS1): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>Oct 31 14:38:54 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) scheduled...
>Oct 31 14:38:54 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) started...
>Oct 31 14:38:54 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:38:54 user07 modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:38:54 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (disabled -> enabling)
>Oct 31 14:38:54 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (disabled -> enabling)
>Oct 31 14:38:54 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) complete.
>Oct 31 14:38:54 user07 dbus-daemon[558]: modem-manager[610]: <warn>  (ttyS1): failed to query SMS mode, assuming text mode
>Oct 31 14:38:54 user07 modem-manager[610]: <warn>  (ttyS1): failed to query SMS mode, assuming text mode
>Oct 31 14:38:54 user07 dbus-daemon[558]: modem-manager[610]: <warn>  (ttyS1): failed to enable USSD notifications.
>Oct 31 14:38:54 user07 modem-manager[610]: <warn>  (ttyS1): failed to enable USSD notifications.
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (enabling -> disabled)
>Oct 31 14:38:55 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (enabling -> disabled)
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:38:55 user07 modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:38:55 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:38:55 user07 NetworkManager[571]: <warn> GSM modem enable failed: (32) Failed to find a usable modem character set
>Oct 31 14:38:55 user07 NetworkManager[571]: <info> (ttyS1): device state change: prepare -> failed (reason 'modem-init-failed') [40 120 28]
>Oct 31 14:38:55 user07 NetworkManager[571]: <warn> Activation (ttyS1) failed.
>Oct 31 14:38:55 user07 NetworkManager[571]: <info> (ttyS1): device state change: failed -> disconnected (reason 'none') [120 30 0]
>Oct 31 14:38:55 user07 NetworkManager[571]: <info> (ttyS1): deactivating device (reason 'none') [0]
>Oct 31 14:38:55 user07 NetworkManager[571]: <info> Policy set 'Auto Ethernet' (em1) as default for IPv4 routing and DNS.
>Oct 31 14:38:55 user07 NetworkManager[571]: <info> Policy set 'Auto Ethernet' (em1) as default for IPv4 routing and DNS.
>Oct 31 14:38:55 user07 NetworkManager[571]: <warn> (ttyS1) firewall zone remove failed: (32) UNKNOWN_INTERFACE: ttyS1
>
>Selecting the connection even though the modem is 'not enabled':
>
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> Activation (ttyS1) starting connection '3 BredbÃ¥nd (standard)'
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> (ttyS1): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) scheduled...
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) started...
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) complete.
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:39:53 user07 modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:39:53 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (disabled -> enabling)
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (disabled -> enabling)
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: <warn>  (ttyS1): failed to query SMS mode, assuming text mode
>Oct 31 14:39:53 user07 modem-manager[610]: <warn>  (ttyS1): failed to query SMS mode, assuming text mode
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: <warn>  (ttyS1): failed to enable USSD notifications.
>Oct 31 14:39:53 user07 modem-manager[610]: <warn>  (ttyS1): failed to enable USSD notifications.
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (enabling -> disabled)
>Oct 31 14:39:53 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (enabling -> disabled)
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:39:53 user07 modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:39:53 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:39:53 user07 NetworkManager[571]: <warn> GSM modem enable failed: (32) Failed to find a usable modem character set
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> (ttyS1): device state change: prepare -> failed (reason 'modem-init-failed') [40 120 28]
>Oct 31 14:39:53 user07 NetworkManager[571]: <warn> Activation (ttyS1) failed.
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> (ttyS1): device state change: failed -> disconnected (reason 'none') [120 30 0]
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> (ttyS1): deactivating device (reason 'none') [0]
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> Policy set 'Auto Ethernet' (em1) as default for IPv4 routing and DNS.
>Oct 31 14:39:53 user07 NetworkManager[571]: <info> Policy set 'Auto Ethernet' (em1) as default for IPv4 routing and DNS.
>Oct 31 14:39:53 user07 NetworkManager[571]: <warn> (ttyS1) firewall zone remove failed: (32) UNKNOWN_INTERFACE: ttyS1
>
>Second time is no worse:
>
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> Activation (ttyS1) starting connection '3 BredbÃ¥nd (standard)'
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> (ttyS1): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) scheduled...
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) started...
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) complete.
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:40:09 user07 modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (disabled -> enabling)
>Oct 31 14:40:09 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (disabled -> enabling)
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: <warn>  (ttyS1): failed to query SMS mode, assuming text mode
>Oct 31 14:40:09 user07 modem-manager[610]: <warn>  (ttyS1): failed to query SMS mode, assuming text mode
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: <warn>  (ttyS1): failed to enable USSD notifications.
>Oct 31 14:40:09 user07 modem-manager[610]: <warn>  (ttyS1): failed to enable USSD notifications.
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (enabling -> disabled)
>Oct 31 14:40:09 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/2: state changed (enabling -> disabled)
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:40:09 user07 modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:40:09 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:40:09 user07 NetworkManager[571]: <warn> GSM modem enable failed: (32) Failed to find a usable modem character set
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> (ttyS1): device state change: prepare -> failed (reason 'modem-init-failed') [40 120 28]
>Oct 31 14:40:09 user07 NetworkManager[571]: <warn> Activation (ttyS1) failed.
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> (ttyS1): device state change: failed -> disconnected (reason 'none') [120 30 0]
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> (ttyS1): deactivating device (reason 'none') [0]
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> Policy set 'Auto Ethernet' (em1) as default for IPv4 routing and DNS.
>Oct 31 14:40:09 user07 NetworkManager[571]: <info> Policy set 'Auto Ethernet' (em1) as default for IPv4 routing and DNS.
>Oct 31 14:40:09 user07 NetworkManager[571]: <warn> (ttyS1) firewall zone remove failed: (32) UNKNOWN_INTERFACE: ttyS1
>
>Eject pcmcia:
>
>Oct 31 14:40:43 user07 kernel: [ 1004.250876] pcmcia_socket pcmcia_socket0: pccard: card ejected from slot 0
>Oct 31 14:40:43 user07 dbus-daemon[558]: modem-manager[610]: <info>  (tty/ttyS1): released by modem /sys/devices/pci0000:00/0000:00:1e.0/0000:02:01.0/0.0
>Oct 31 14:40:43 user07 modem-manager[610]: <info>  (tty/ttyS1): released by modem /sys/devices/pci0000:00/0000:00:1e.0/0000:02:01.0/0.0
>Oct 31 14:40:43 user07 NetworkManager[571]: <info> (ttyS1): now unmanaged
>Oct 31 14:40:43 user07 NetworkManager[571]: <info> (ttyS1): device state change: disconnected -> unmanaged (reason 'removed') [30 10 36]
>Oct 31 14:40:43 user07 NetworkManager[571]: <info> (ttyS1): cleaning up...
>Oct 31 14:40:43 user07 NetworkManager[571]: <info> (ttyS1): taking down device.
>
>Insert pcmcia with another SIM. I haven't been able to make it work ... but
>that is not the main point here as long as I get useful feedback.
>
>Oct 31 14:40:51 user07 kernel: [ 1012.752090] pcmcia_socket pcmcia_socket0: pccard: PCMCIA card inserted into slot 0
>Oct 31 14:40:51 user07 kernel: [ 1012.752346] pcmcia 0.0: pcmcia: registering new device pcmcia0.0 (IRQ: 19)
>Oct 31 14:40:51 user07 kernel: [ 1012.761643] pcmcia_socket pcmcia_socket0: Using replacement CIS
>Oct 31 14:40:51 user07 kernel: [ 1012.761681] serial_cs 0.0: trying to set up [0x0192:0x0710] (pfc: 0, multi: 1, quirk:   (null))
>Oct 31 14:40:51 user07 kernel: [ 1012.824592] 0.0: ttyS1 at I/O 0x2f8 (irq = 19) is a 16550A
>Oct 31 14:40:51 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:40:51 user07 modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:40:57 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:40:57 user07 modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:40:57 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:40:57 user07 modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:40:57 user07 dbus-daemon[558]: modem-manager[610]: <info>  (Generic): GSM modem /sys/devices/pci0000:00/0000:00:1e.0/0000:02:01.0/0.0 claimed port ttyS1
>Oct 31 14:40:57 user07 modem-manager[610]: <info>  (Generic): GSM modem /sys/devices/pci0000:00/0000:00:1e.0/0000:02:01.0/0.0 claimed port ttyS1
>Oct 31 14:40:57 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:40:57 user07 modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:40:58 user07 NetworkManager[571]: <warn> (ttyS1): failed to look up interface index
>Oct 31 14:40:58 user07 NetworkManager[571]: <info> (ttyS1): new GSM/UMTS device (driver: 'serial_cs' ifindex: 0)
>Oct 31 14:40:58 user07 NetworkManager[571]: <info> (ttyS1): exported as /org/freedesktop/NetworkManager/Devices/5
>Oct 31 14:40:58 user07 NetworkManager[571]: <info> (ttyS1): now managed
>Oct 31 14:40:58 user07 NetworkManager[571]: <info> (ttyS1): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>Oct 31 14:40:58 user07 NetworkManager[571]: <info> (ttyS1): deactivating device (reason 'managed') [2]
>Oct 31 14:40:58 user07 NetworkManager[571]: <info> (ttyS1): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
>Oct 31 14:40:59 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:40:59 user07 modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:40:59 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:40:59 user07 modem-manager[610]: <info>  (ttyS1) serial port closed
>
>It is still 'not enabled' but I choose the defined connection (which doesn't match the SIM card):
>
>Oct 31 14:41:30 user07 NetworkManager[571]: <info> Activation (ttyS1) starting connection '3 BredbÃ¥nd (standard)'
>Oct 31 14:41:30 user07 NetworkManager[571]: <info> (ttyS1): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>Oct 31 14:41:30 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) scheduled...
>Oct 31 14:41:30 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) started...
>Oct 31 14:41:30 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 1 of 5 (Device Prepare) complete.
>Oct 31 14:41:30 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:41:30 user07 modem-manager[610]: <info>  (ttyS1) opening serial port...
>Oct 31 14:41:30 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (disabled -> enabling)
>Oct 31 14:41:30 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (disabled -> enabling)
>Oct 31 14:41:30 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1): using PDU mode for SMS
>Oct 31 14:41:30 user07 modem-manager[610]: <info>  (ttyS1): using PDU mode for SMS
>Oct 31 14:41:31 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (enabling -> enabled)
>Oct 31 14:41:31 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (enabling -> enabled)
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> WWAN now enabled by management service
>Oct 31 14:41:31 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (enabled -> registered)
>Oct 31 14:41:31 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (enabled -> registered)
>Oct 31 14:41:31 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (registered -> connecting)
>Oct 31 14:41:31 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (registered -> connecting)
>Oct 31 14:41:31 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (connecting -> connected)
>Oct 31 14:41:31 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (connecting -> connected)
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 2 of 5 (Device Configure) scheduled...
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 2 of 5 (Device Configure) starting...
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> (ttyS1): device state change: prepare -> config (reason 'none') [40 50 0]
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 2 of 5 (Device Configure) successful.
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 2 of 5 (Device Configure) complete.
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 3 of 5 (IP Configure Start) scheduled.
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 3 of 5 (IP Configure Start) started...
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> (ttyS1): device state change: config -> ip-config (reason 'none') [50 70 0]
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> starting PPP connection
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> pppd started with pid 1708
>Oct 31 14:41:31 user07 NetworkManager[571]: <info> Activation (ttyS1) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
>Oct 31 14:41:31 user07 rsyslogd-2177: imuxsock begins to drop messages from pid 571 due to rate-limiting
>Oct 31 14:41:31 user07 pppd[1708]: Plugin /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
>Oct 31 14:41:31 user07 pppd[1708]: pppd 2.4.5 started by root, uid 0
>Oct 31 14:41:31 user07 pppd[1708]: Using interface ppp0
>Oct 31 14:41:31 user07 pppd[1708]: Connect: ppp0 <--> /dev/ttyS1
>Oct 31 14:41:31 user07 pppd[1708]: CHAP authentication succeeded
>Oct 31 14:41:31 user07 pppd[1708]: CHAP authentication succeeded
>Oct 31 14:41:33 user07 pppd[1708]: Modem hangup
>Oct 31 14:41:33 user07 pppd[1708]: Connection terminated.
>Oct 31 14:41:33 user07 modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:41:33 user07 modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:41:33 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (connected -> disconnecting)
>Oct 31 14:41:33 user07 avahi-daemon[506]: Withdrawing workstation service for ppp0.
>Oct 31 14:41:33 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (disconnecting -> connected)
>Oct 31 14:41:33 user07 modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:41:33 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) closing serial port...
>Oct 31 14:41:33 user07 dbus-daemon[558]: modem-manager[610]: <info>  (ttyS1) serial port closed
>Oct 31 14:41:33 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (connected -> disconnecting)
>Oct 31 14:41:33 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (disconnecting -> connected)
>Oct 31 14:41:33 user07 dbus-daemon[558]: modem-manager[610]: mm_serial_port_close: assertion `priv->open_count > 0' failed
>Oct 31 14:41:34 user07 pppd[1708]: Exit.
>
>Nothing popped up, but now it appears as 'enabled'. Signal strength and 'TDC Mobil GSM' is shown (couldn't that be used to choose plan automatically?).
>
>Choosing it again, and the reported message appears:
>
>Oct 31 14:42:52 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (connected -> disconnecting)
>Oct 31 14:42:52 user07 modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (disconnecting -> connected)
>Oct 31 14:42:52 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (connected -> disconnecting)
>Oct 31 14:42:52 user07 dbus-daemon[558]: modem-manager[610]: <info>  Modem /org/freedesktop/ModemManager/Modems/3: state changed (disconnecting -> connected)
>

Actions: View

Attachments on bug 868378: 636142