RE: 3G Modem disconnect goes to unavailable rather than disconnected



I'm now running 0.5.999 as downloaded from https://launchpad.net/ubuntu/quantal/amd64/modemmanager however am still seeing the same problem. Logs below:

Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  ModemManager (version 0.5.999) starting...
Aug 28 18:19:26 hostname-1 kernel: [   11.273040] type=1400 audit(1346174366.085:11): apparmor="STATUS" operation="profile_load" name="/usr/lib/cups/backend/cups-pdf" pid=1073 comm="apparmor_parser"
Aug 28 18:19:26 hostname-1 kernel: [   11.331770] input: PS/2 Generic Mouse as /devices/platform/i8042/serio1/input/input12
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'ZTE'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'AnyData'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Option'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Ericsson MBM'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Option High-Speed'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Gobi'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'SimTech'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Novatel'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Linktop'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Longcheer'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Huawei'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'X22X'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Samsung'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Wavecom'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Nokia'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'MotoC'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Sierra'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Cinterion'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Iridium'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Loaded plugin 'Generic'
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  Successfully loaded 20 plugins
Aug 28 18:19:26 hostname-1 modem-manager[1065]: <info>  (ttyS4) opening serial port...
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> NetworkManager (version 0.9.4.0) is starting...
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> DNS: loaded plugin dnsmasq
Aug 28 18:19:26 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.PolicyKit1' (using servicehelper)
Aug 28 18:19:26 hostname-1 polkitd[1099]: started daemon version 0.104 using authority implementation `local' version `0.104'
Aug 28 18:19:26 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: init!
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: update_system_hostname
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPluginIfupdown: management mode: unmanaged
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:19.0/net/eth0, iface: eth0)
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:19.0/net/eth0, iface: eth0): no ifupdown configuration found.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.3/0000:44:00.0/net/wlan0, iface: wlan0)
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.3/0000:44:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo)
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: end _init.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> Loaded plugin ifupdown: (C) 2008 Canonical Ltd.  To report bugs please use the NetworkManager mailing list.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    Ifupdown: get unmanaged devices count: 0
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: (10842928) ... get_connections.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: (10842928) ... get_connections (managed=false): return empty list.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing Auto DAWES ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection 'Auto DAWES'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing Auto BTHomeHub2-NMGC ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection 'Auto BTHomeHub2-NMGC'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing Auto Triton Desire ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection 'Auto Triton Desire'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing Char Manor AP5 ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection 'Char Manor AP5'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing Auto USR9106 ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection 'Auto USR9106'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing Auto DAWES-e326a404-a017-4168-9cbf-eab8d256f5a6 ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection 'Auto DAWES'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing Auto Portable Hotspot ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection 'Auto Portable Hotspot'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing Auto Triton ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection 'Auto Triton'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile: parsing 3 Internet ... 
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    keyfile:     read connection '3 Internet'
Aug 28 18:19:26 hostname-1 NetworkManager[1093]:    Ifupdown: get unmanaged devices count: 0
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> modem-manager is now available
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> monitoring kernel firmware directory '/lib/firmware'.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> found WiFi radio killswitch rfkill0 (at /sys/devices/pci0000:00/0000:00:1c.3/0000:44:00.0/ieee80211/phy0/rfkill0) (driver (unknown))
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> found WiFi radio killswitch rfkill1 (at /sys/devices/platform/hp-wmi/rfkill/rfkill1) (driver hp-wmi)
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> WiFi disabled by radio killswitch; enabled by state file
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> WWAN enabled by radio killswitch; enabled by state file
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> WiMAX enabled by radio killswitch; enabled by state file
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> Networking is enabled by state file
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <warn> failed to allocate link cache: (-10) Operation not supported
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (eth0): carrier is OFF
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (eth0): new Ethernet device (driver: 'e1000e' ifindex: 2)
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (eth0): now managed
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (eth0): bringing up device.
Aug 28 18:19:26 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.ColorManager' (using servicehelper)
Aug 28 18:19:26 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.ColorManager'
Aug 28 18:19:26 hostname-1 kernel: [   11.522666] e1000e 0000:00:19.0: irq 45 for MSI/MSI-X
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (eth0): preparing device.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (eth0): deactivating device (reason 'managed') [2]
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:19.0/net/eth0
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (wlan0): using nl80211 for WiFi device control
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (wlan0): new 802.11 WiFi device (driver: 'iwlwifi' ifindex: 3)
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/1
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (wlan0): now managed
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (wlan0): bringing up device.
Aug 28 18:19:26 hostname-1 NetworkManager[1093]: <info> (wlan0): deactivating device (reason 'managed') [2]
Aug 28 18:19:26 hostname-1 kernel: [   11.582432] e1000e 0000:00:19.0: irq 45 for MSI/MSI-X
Aug 28 18:19:26 hostname-1 kernel: [   11.583182] ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug 28 18:19:26 hostname-1 kernel: [   11.583510] ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug 28 18:19:26 hostname-1 kernel: [   11.585320] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug 28 18:19:26 hostname-1 cron[1195]: (CRON) INFO (pidfile fd = 3)
Aug 28 18:19:26 hostname-1 anacron[1230]: Anacron 2.3 started on 2012-08-28
Aug 28 18:19:26 hostname-1 acpid: starting up with proc fs
Aug 28 18:19:26 hostname-1 cron[1234]: (CRON) STARTUP (fork ok)
Aug 28 18:19:26 hostname-1 anacron[1230]: Normal exit (0 jobs run)
Aug 28 18:19:26 hostname-1 cron[1234]: (CRON) INFO (Running @reboot jobs)
Aug 28 18:19:26 hostname-1 acpid: 35 rules loaded
Aug 28 18:19:26 hostname-1 acpid: waiting for events: event logging is off
Aug 28 18:19:26 hostname-1 kernel: [   11.762054] pcmcia_socket pcmcia_socket0: pccard: PCMCIA card inserted into slot 0
Aug 28 18:19:26 hostname-1 kernel: [   11.762060] pcmcia_socket pcmcia_socket0: cs: memory probe 0xd3110000-0xd31fffff: excluding 0xd31fe000-0xd320bfff
Aug 28 18:19:26 hostname-1 kernel: [   11.768149] pcmcia 0.0: pcmcia: registering new device pcmcia0.0 (IRQ: 22)
Aug 28 18:19:26 hostname-1 kernel: [   11.833067] vboxdrv: Found 4 processor cores.
Aug 28 18:19:26 hostname-1 kernel: [   11.833333] vboxdrv: fAsync=0 offMin=0x118 offMax=0x3838
Aug 28 18:19:26 hostname-1 kernel: [   11.833408] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'.
Aug 28 18:19:26 hostname-1 kernel: [   11.833410] vboxdrv: Successfully loaded version 4.1.12_Ubuntu (interface 0x00190000).
Aug 28 18:19:26 hostname-1 kernel: [   11.835259] scsi6 : pata_pcmcia
Aug 28 18:19:26 hostname-1 kernel: [   11.835329] ata7: PATA max PIO0 cmd 0x2100 ctl 0x210e irq 22
Aug 28 18:19:26 hostname-1 acpid: client connected from 1347[0:0]
Aug 28 18:19:26 hostname-1 acpid: 1 client rule loaded
Aug 28 18:19:26 hostname-1 kernel: [   11.897809] HDMI status: Codec=0 Pin=5 Presence_Detect=0 ELD_Valid=0
Aug 28 18:19:26 hostname-1 kernel: [   11.921776] HDMI status: Codec=1 Pin=5 Presence_Detect=0 ELD_Valid=0
Aug 28 18:19:26 hostname-1 kernel: [   11.945766] HDMI status: Codec=2 Pin=5 Presence_Detect=0 ELD_Valid=0
Aug 28 18:19:26 hostname-1 kernel: [   11.969672] HDMI status: Codec=3 Pin=5 Presence_Detect=0 ELD_Valid=0
Aug 28 18:19:26 hostname-1 kernel: [   11.969858] input: HDA NVidia HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input13
Aug 28 18:19:26 hostname-1 kernel: [   11.970108] input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input14
Aug 28 18:19:26 hostname-1 kernel: [   11.970313] input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input15
Aug 28 18:19:26 hostname-1 kernel: [   11.970499] input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input16
Aug 28 18:19:26 hostname-1 kernel: [   12.150165] vboxpci: IOMMU not found (not registered)
Aug 28 18:19:27 hostname-1 kernel: [   12.193986] psmouse serio4: synaptics: Touchpad model: 1, fw: 7.2, id: 0x1c0b1, caps: 0xd04733/0xa40000/0xa0000
Aug 28 18:19:27 hostname-1 kernel: [   12.238952] input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio4/input/input17
Aug 28 18:19:27 hostname-1 anacron[1500]: Anacron 2.3 started on 2012-08-28
Aug 28 18:19:27 hostname-1 anacron[1500]: Normal exit (0 jobs run)
Aug 28 18:19:28 hostname-1 kernel: [   13.680246] Adding 963864k swap on /dev/mapper/cryptswap1.  Priority:-1 extents:1 across:963864k SS
Aug 28 18:19:28 hostname-1 udev-configure-printer: add /devices/pnp0/00:09/printer/lp0
Aug 28 18:19:28 hostname-1 udev-configure-printer: Failed to get parent
Aug 28 18:19:28 hostname-1 udev-configure-printer: add /module/lp
Aug 28 18:19:28 hostname-1 udev-configure-printer: Failed to get parent
Aug 28 18:19:28 hostname-1 acpid: client connected from 1347[0:0]
Aug 28 18:19:28 hostname-1 acpid: 1 client rule loaded
Aug 28 18:19:29 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.Accounts' (using servicehelper)
Aug 28 18:19:29 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.Accounts'
Aug 28 18:19:29 hostname-1 accounts-daemon[1659]: started daemon version 0.6.15
Aug 28 18:19:29 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.ConsoleKit' (using servicehelper)
Aug 28 18:19:29 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.ConsoleKit'
Aug 28 18:19:29 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.UPower' (using servicehelper)
Aug 28 18:19:30 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.UPower'
Aug 28 18:19:30 hostname-1 anacron[1845]: Anacron 2.3 started on 2012-08-28
Aug 28 18:19:30 hostname-1 anacron[1845]: Normal exit (0 jobs run)
Aug 28 18:19:31 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.RealtimeKit1' (using servicehelper)
Aug 28 18:19:31 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Successfully called chroot.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Successfully dropped privileges.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Successfully limited resources.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Running.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Canary thread running.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Watchdog thread running.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Successfully made thread 1981 of process 1981 (n/a) owned by '104' high priority at nice level -11.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Supervising 1 threads of 1 processes of 1 users.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Successfully made thread 1988 of process 1981 (n/a) owned by '104' RT at priority 5.
Aug 28 18:19:31 hostname-1 rtkit-daemon[1983]: Supervising 2 threads of 1 processes of 1 users.
Aug 28 18:19:32 hostname-1 rtkit-daemon[1983]: Successfully made thread 1989 of process 1981 (n/a) owned by '104' RT at priority 5.
Aug 28 18:19:32 hostname-1 rtkit-daemon[1983]: Supervising 3 threads of 1 processes of 1 users.
Aug 28 18:19:32 hostname-1 rtkit-daemon[1983]: Successfully made thread 1990 of process 1981 (n/a) owned by '104' RT at priority 5.
Aug 28 18:19:32 hostname-1 rtkit-daemon[1983]: Supervising 4 threads of 1 processes of 1 users.
Aug 28 18:19:32 hostname-1 rtkit-daemon[1983]: Successfully made thread 1993 of process 1993 (n/a) owned by '104' high priority at nice level -11.
Aug 28 18:19:32 hostname-1 rtkit-daemon[1983]: Supervising 5 threads of 2 processes of 1 users.
Aug 28 18:19:32 hostname-1 pulseaudio[1993]: [pulseaudio] pid.c: Daemon already running.
Aug 28 18:19:37 hostname-1 lightdm: pam_ecryptfs: Passphrase file wrapped
Aug 28 18:19:37 hostname-1 modem-manager[1065]: <info>  (ttyS4) closing serial port...
Aug 28 18:19:37 hostname-1 modem-manager[1065]: <info>  (ttyS4) serial port closed
Aug 28 18:19:37 hostname-1 modem-manager[1065]: <info>  (ttyS4) opening serial port...
Aug 28 18:19:39 hostname-1 rtkit-daemon[1983]: Successfully made thread 2139 of process 2139 (n/a) owned by '1000' high priority at nice level -11.
Aug 28 18:19:39 hostname-1 rtkit-daemon[1983]: Supervising 5 threads of 2 processes of 2 users.
Aug 28 18:19:39 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.UDisks' (using servicehelper)
Aug 28 18:19:39 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.UDisks'
Aug 28 18:19:39 hostname-1 rtkit-daemon[1983]: Successfully made thread 2186 of process 2139 (n/a) owned by '1000' RT at priority 5.
Aug 28 18:19:39 hostname-1 rtkit-daemon[1983]: Supervising 6 threads of 2 processes of 2 users.
Aug 28 18:19:39 hostname-1 kernel: [   25.055277] usb 2-1.3: new high-speed USB device number 3 using ehci_hcd
Aug 28 18:19:39 hostname-1 rtkit-daemon[1983]: Successfully made thread 2189 of process 2139 (n/a) owned by '1000' RT at priority 5.
Aug 28 18:19:39 hostname-1 rtkit-daemon[1983]: Supervising 7 threads of 2 processes of 2 users.
Aug 28 18:19:40 hostname-1 mtp-probe: checking bus 2, device 3: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.3"
Aug 28 18:19:40 hostname-1 mtp-probe: bus: 2, device: 3 was not an MTP device
Aug 28 18:19:40 hostname-1 rtkit-daemon[1983]: Successfully made thread 2192 of process 2139 (n/a) owned by '1000' RT at priority 5.
Aug 28 18:19:40 hostname-1 rtkit-daemon[1983]: Supervising 8 threads of 2 processes of 2 users.
Aug 28 18:19:40 hostname-1 kernel: [   25.178886] Initializing USB Mass Storage driver...
Aug 28 18:19:40 hostname-1 kernel: [   25.179094] scsi7 : usb-storage 2-1.3:1.0
Aug 28 18:19:40 hostname-1 kernel: [   25.180337] scsi8 : usb-storage 2-1.3:1.1
Aug 28 18:19:40 hostname-1 kernel: [   25.180434] usbcore: registered new interface driver usb-storage
Aug 28 18:19:40 hostname-1 kernel: [   25.180436] USB Mass Storage support registered.
Aug 28 18:19:41 hostname-1 kernel: [   26.180822] scsi 7:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Aug 28 18:19:41 hostname-1 kernel: [   26.184933] scsi 8:0:0:0: Direct-Access     HUAWEI   MMC Storage      2.31 PQ: 0 ANSI: 2
Aug 28 18:19:41 hostname-1 kernel: [   26.192911] sr1: scsi-1 drive
Aug 28 18:19:41 hostname-1 kernel: [   26.193043] sr 7:0:0:0: Attached scsi CD-ROM sr1
Aug 28 18:19:41 hostname-1 kernel: [   26.193136] sr 7:0:0:0: Attached scsi generic sg2 type 5
Aug 28 18:19:41 hostname-1 kernel: [   26.193515] sd 8:0:0:0: Attached scsi generic sg3 type 0
Aug 28 18:19:41 hostname-1 kernel: [   26.204265] sd 8:0:0:0: [sdb] Attached SCSI removable disk
Aug 28 18:19:41 hostname-1 usb_modeswitch: switching device 12d1:1446 on 002/003
Aug 28 18:19:41 hostname-1 kernel: [   26.253906] usb 2-1.3: USB disconnect, device number 3
Aug 28 18:19:41 hostname-1 kernel: [   26.254287] scsi 8:0:0:0: killing request
Aug 28 18:19:43 hostname-1 modem-manager[1065]: <info>  (ttyS4) closing serial port...
Aug 28 18:19:43 hostname-1 modem-manager[1065]: <info>  (ttyS4) serial port closed
Aug 28 18:19:43 hostname-1 modem-manager[1065]: <info>  (ttyS4) opening serial port...
Aug 28 18:19:47 hostname-1 kernel: [   32.722042] usb 2-1.3: new high-speed USB device number 4 using ehci_hcd
Aug 28 18:19:47 hostname-1 mtp-probe: checking bus 2, device 4: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.3"
Aug 28 18:19:47 hostname-1 kernel: [   32.831787] scsi12 : usb-storage 2-1.3:1.3
Aug 28 18:19:47 hostname-1 kernel: [   32.832851] scsi13 : usb-storage 2-1.3:1.4
Aug 28 18:19:47 hostname-1 mtp-probe: bus: 2, device: 4 was not an MTP device
Aug 28 18:19:47 hostname-1 kernel: [   32.858976] usbcore: registered new interface driver usbserial
Aug 28 18:19:47 hostname-1 kernel: [   32.859005] USB Serial support registered for generic
Aug 28 18:19:47 hostname-1 kernel: [   32.859094] usbcore: registered new interface driver usbserial_generic
Aug 28 18:19:47 hostname-1 kernel: [   32.859099] usbserial: USB Serial Driver core
Aug 28 18:19:47 hostname-1 kernel: [   32.862516] USB Serial support registered for GSM modem (1-port)
Aug 28 18:19:47 hostname-1 kernel: [   32.862686] option 2-1.3:1.0: GSM modem (1-port) converter detected
Aug 28 18:19:47 hostname-1 kernel: [   32.862891] usb 2-1.3: GSM modem (1-port) converter now attached to ttyUSB0
Aug 28 18:19:47 hostname-1 kernel: [   32.862917] option 2-1.3:1.1: GSM modem (1-port) converter detected
Aug 28 18:19:47 hostname-1 kernel: [   32.863068] usb 2-1.3: GSM modem (1-port) converter now attached to ttyUSB1
Aug 28 18:19:47 hostname-1 kernel: [   32.863091] option 2-1.3:1.2: GSM modem (1-port) converter detected
Aug 28 18:19:47 hostname-1 kernel: [   32.863240] usb 2-1.3: GSM modem (1-port) converter now attached to ttyUSB2
Aug 28 18:19:47 hostname-1 kernel: [   32.863313] usbcore: registered new interface driver option
Aug 28 18:19:47 hostname-1 kernel: [   32.863317] option: v0.7.2:USB Driver for GSM modems
Aug 28 18:19:47 hostname-1 modem-manager[1065]: <info>  (ttyUSB0) opening serial port...
Aug 28 18:19:47 hostname-1 modem-manager[1065]: <warn>  (ttyUSB0): port attributes not fully set
Aug 28 18:19:48 hostname-1 usb_modeswitch: switched to 12d1:1001 on 002/003
Aug 28 18:19:48 hostname-1 kernel: [   33.831650] scsi 12:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Aug 28 18:19:48 hostname-1 kernel: [   33.834259] scsi 13:0:0:0: Direct-Access     HUAWEI   MMC Storage      2.31 PQ: 0 ANSI: 2
Aug 28 18:19:48 hostname-1 kernel: [   33.845981] sr1: scsi-1 drive
Aug 28 18:19:48 hostname-1 kernel: [   33.846116] sr 12:0:0:0: Attached scsi CD-ROM sr1
Aug 28 18:19:48 hostname-1 kernel: [   33.846371] sr 12:0:0:0: Attached scsi generic sg2 type 5
Aug 28 18:19:48 hostname-1 kernel: [   33.846703] sd 13:0:0:0: Attached scsi generic sg3 type 0
Aug 28 18:19:48 hostname-1 kernel: [   33.856218] sd 13:0:0:0: [sdb] Attached SCSI removable disk
Aug 28 18:19:49 hostname-1 usb_modeswitch[2217]: usb_modeswitch: switched to 12d1:1001 on 2/4
Aug 28 18:19:52 hostname-1 modem-manager[1065]: <info>  (ttyUSB0) closing serial port...
Aug 28 18:19:52 hostname-1 modem-manager[1065]: <info>  (ttyUSB0) serial port closed
Aug 28 18:19:52 hostname-1 modem-manager[1065]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.3 claimed port ttyUSB0
Aug 28 18:19:53 hostname-1 modem-manager[1065]: <info>  (ttyUSB2) opening serial port...
Aug 28 18:19:53 hostname-1 modem-manager[1065]: <warn>  (ttyUSB2): port attributes not fully set
Aug 28 18:19:53 hostname-1 modem-manager[1065]: <info>  (ttyUSB1) opening serial port...
Aug 28 18:19:53 hostname-1 modem-manager[1065]: <warn>  (ttyUSB1): port attributes not fully set
Aug 28 18:19:54 hostname-1 goa[2538]: goa-daemon version 3.4.0 starting [main.c:112, main()]
Aug 28 18:19:55 hostname-1 modem-manager[1065]: <info>  (ttyUSB2) closing serial port...
Aug 28 18:19:55 hostname-1 modem-manager[1065]: <info>  (ttyUSB2) serial port closed
Aug 28 18:19:55 hostname-1 modem-manager[1065]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.3 claimed port ttyUSB2
Aug 28 18:19:56 hostname-1 modem-manager[1065]: <info>  (ttyS4) closing serial port...
Aug 28 18:19:56 hostname-1 modem-manager[1065]: <info>  (ttyS4) serial port closed
Aug 28 18:19:56 hostname-1 modem-manager[1065]: <info>  (ttyS4) opening serial port...
Aug 28 18:20:02 hostname-1 modem-manager[1065]: <info>  (ttyS4) closing serial port...
Aug 28 18:20:02 hostname-1 modem-manager[1065]: <info>  (ttyS4) serial port closed
Aug 28 18:20:06 hostname-1 modem-manager[1065]: <info>  (ttyUSB1) closing serial port...
Aug 28 18:20:06 hostname-1 modem-manager[1065]: <info>  (ttyUSB1) serial port closed
Aug 28 18:20:06 hostname-1 modem-manager[1065]: <info>  (ttyUSB1) opening serial port...
Aug 28 18:20:09 hostname-1 modem-manager[1065]: <info>  (ttyUSB1) closing serial port...
Aug 28 18:20:09 hostname-1 modem-manager[1065]: <info>  (ttyUSB1) serial port closed
Aug 28 18:20:09 hostname-1 modem-manager[1065]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.3 claimed port ttyUSB1
Aug 28 18:20:09 hostname-1 modem-manager[1065]: <info>  (ttyUSB2) opening serial port...
Aug 28 18:20:09 hostname-1 modem-manager[1065]: <warn>  (ttyUSB2): port attributes not fully set
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: <warn> (ttyUSB0): failed to look up interface index
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/2
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): now managed
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Aug 28 18:20:10 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Aug 28 18:20:10 hostname-1 modem-manager[1065]: <info>  (ttyUSB2) closing serial port...
Aug 28 18:20:10 hostname-1 modem-manager[1065]: <info>  (ttyUSB2) serial port closed
Aug 28 18:20:27 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) starting connection '3 Internet'
Aug 28 18:20:27 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Aug 28 18:20:27 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Aug 28 18:20:27 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Aug 28 18:20:27 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <info>  (ttyUSB2) opening serial port...
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <warn>  (ttyUSB2): port attributes not fully set
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <info>  (ttyUSB2): using text mode for SMS
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> enabled)
Aug 28 18:20:27 hostname-1 NetworkManager[1093]: <info> WWAN now enabled by management service
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabled -> registered)
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <info>  (ttyUSB0) opening serial port...
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <warn>  (ttyUSB0): port attributes not fully set
Aug 28 18:20:27 hostname-1 modem-manager[1065]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (registered -> connecting)
Aug 28 18:20:28 hostname-1 modem-manager[1065]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (connecting -> connected)
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> starting PPP connection
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> pppd started with pid 2624
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Aug 28 18:20:28 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Aug 28 18:20:28 hostname-1 pppd[2624]: Plugin /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
Aug 28 18:20:28 hostname-1 pppd[2624]: pppd 2.4.5 started by root, uid 0
Aug 28 18:20:28 hostname-1 pppd[2624]: Using interface ppp0
Aug 28 18:20:28 hostname-1 pppd[2624]: Connect: ppp0 <--> /dev/ttyUSB0
Aug 28 18:20:28 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Aug 28 18:20:28 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Aug 28 18:20:28 hostname-1 pppd[2624]: CHAP authentication succeeded
Aug 28 18:20:28 hostname-1 pppd[2624]: CHAP authentication succeeded
Aug 28 18:20:28 hostname-1 kernel: [   73.128254] PPP BSD Compression module registered
Aug 28 18:20:28 hostname-1 kernel: [   73.130609] PPP Deflate Compression module registered
Aug 28 18:20:29 hostname-1 pppd[2624]: Could not determine remote IP address: defaulting to 12.12.12.12
Aug 28 18:20:29 hostname-1 pppd[2624]: local  IP address 11.11.11.11
Aug 28 18:20:29 hostname-1 pppd[2624]: remote IP address 12.12.12.12
Aug 28 18:20:29 hostname-1 pppd[2624]: primary   DNS address 217.171.132.1
Aug 28 18:20:29 hostname-1 pppd[2624]: secondary DNS address 217.171.132.1
Aug 28 18:20:29 hostname-1 NetworkManager[1093]: <info> PPP manager(IP Config Get) reply received.
Aug 28 18:20:29 hostname-1 NetworkManager[1093]: nm_ip4_config_add_nameserver: assertion `nameserver != s' failed
Aug 28 18:20:29 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Aug 28 18:20:29 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Aug 28 18:20:30 hostname-1 NetworkManager[1093]: <info> DNS: starting dnsmasq...
Aug 28 18:20:30 hostname-1 NetworkManager[1093]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Aug 28 18:20:30 hostname-1 dnsmasq[2677]: started, version 2.59 cache disabled
Aug 28 18:20:30 hostname-1 dnsmasq[2677]: compile time options: IPv6 GNU-getopt DBus i18n DHCP TFTP conntrack IDN
Aug 28 18:20:30 hostname-1 dnsmasq[2677]: using nameserver 217.171.132.1#53
Aug 28 18:20:32 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Aug 28 18:20:32 hostname-1 NetworkManager[1093]: <info> Policy set '3 Internet' (ppp0) as default for IPv4 routing and DNS.
Aug 28 18:20:32 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) successful, device activated.
Aug 28 18:20:32 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Aug 28 18:20:32 hostname-1 NetworkManager[1093]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Aug 28 18:20:32 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 28 18:20:34 hostname-1 kernel: [   79.707883] audit_printk_skb: 54 callbacks suppressed
Aug 28 18:20:34 hostname-1 kernel: [   79.707886] type=1701 audit(1346174434.637:30): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=2880 comm="chrome" reason="seccomp" sig=0 syscall=2 compat=0 ip=0x7f2361553eb0 code=0x50002
Aug 28 18:20:34 hostname-1 kernel: [   79.707903] type=1701 audit(1346174434.637:31): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=2880 comm="chrome" reason="seccomp" sig=0 syscall=2 compat=0 ip=0x7f2361553eb0 code=0x50002
Aug 28 18:20:34 hostname-1 kernel: [   79.717194] type=1701 audit(1346174434.649:32): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=2886 comm="chrome" reason="seccomp" sig=0 syscall=59 compat=0 ip=0x7f236152d427 code=0x50002
Aug 28 18:20:42 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Aug 28 18:20:42 hostname-1 AptDaemon: INFO: Initializing daemon
Aug 28 18:20:42 hostname-1 AptDaemon.PackageKit: INFO: Initializing PackageKit compat layer
Aug 28 18:20:42 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Aug 28 18:20:42 hostname-1 AptDaemon.PackageKit: INFO: Initializing PackageKit transaction
Aug 28 18:20:42 hostname-1 AptDaemon.Worker: INFO: Simulating trans: /org/debian/apt/transaction/9e24adbf539c40c2a67f3f957faccab3
Aug 28 18:20:42 hostname-1 AptDaemon.Worker: INFO: Processing transaction /org/debian/apt/transaction/9e24adbf539c40c2a67f3f957faccab3
Aug 28 18:20:43 hostname-1 AptDaemon.PackageKit: INFO: Get updates()
Aug 28 18:20:43 hostname-1 ntpdate[2851]: adjust time server 91.189.94.4 offset -0.137143 sec
Aug 28 18:20:44 hostname-1 AptDaemon.Worker: INFO: Finished transaction /org/debian/apt/transaction/9e24adbf539c40c2a67f3f957faccab3
Aug 28 18:26:43 hostname-1 AptDaemon: INFO: Quitting due to inactivity
Aug 28 18:26:43 hostname-1 AptDaemon: INFO: Quitting was requested
Aug 28 18:27:15 hostname-1 sudo: pam_ecryptfs: pam_sm_authenticate: /home/tristan is already mounted
Aug 28 18:27:15 hostname-1 modem-manager[1065]: <info>  [mm-manager.c:1041] impl_manager_set_logging(): logging: level 'debug'
Aug 28 18:27:16 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:5<CR><LF>'
Aug 28 18:27:17 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:00000196,00000000,00000068,000000000005538E,000000000020D866,000AFC80,00107AC0<CR><LF>'
Aug 28 18:27:17 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 406 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:19 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:5<CR><LF>'
Aug 28 18:27:19 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:00000198,0000001A,0000001A,00000000000553C2,000000000020D89A,000AFC80,00107AC0<CR><LF>'
Aug 28 18:27:19 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 408 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:21 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:0000019A,00000000,00000000,00000000000553C2,000000000020D89A,000AFC80,00107AC0<CR><LF>'
Aug 28 18:27:21 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 410 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:22 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:0<CR><LF>'
Aug 28 18:27:23 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:0000019C,00000000,00000000,00000000000553C2,000000000020D89A,000AFC80,00107AC0<CR><LF>'
Aug 28 18:27:23 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 412 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:25 hostname-1 NetworkManager[1093]: <info> logging: level 'DEBUG' domains 'HW,RFKILL,ETHER,WIFI,BT,MB,DHCP4,DHCP6,PPP,IP4,IP6,AUTOIP4,DNS,VPN,SHARING,SUPPLICANT,AGENTS,SETTINGS,SUSPEND,CORE,DEVICE,OLPC,WIMAX,INFINIBAND,FIREWALL'
Aug 28 18:27:25 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:0<CR><LF>'
Aug 28 18:27:25 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:0000019E,00000000,00000000,00000000000553C2,000000000020D89A,000AFC80,00107AC0<CR><LF>'
Aug 28 18:27:25 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 414 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:27 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:000001A0,00000000,00000000,00000000000553C2,000000000020D89A,000AFC80,00107AC0<CR><LF>'
Aug 28 18:27:27 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 416 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:27 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^MODE:5,4<CR><LF>'
Aug 28 18:27:27 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:696] handle_mode_change(): Access Technology: 5
Aug 28 18:27:27 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:27:27 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,3,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:28 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^BOOT:30768362,0,0,0,87<CR><LF>'
Aug 28 18:27:28 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:0<CR><LF>'
Aug 28 18:27:29 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:000001A2,00000000,00000000,00000000000553C2,000000000020D89A,000AFC80,00107AC0<CR><LF>'
Aug 28 18:27:29 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 418 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:31 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:0<CR><LF>'
Aug 28 18:27:31 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:000001A4,00000000,00000000,00000000000553C2,000000000020D89A,000AFC80,00107AC0<CR><LF>'
Aug 28 18:27:31 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 420 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:33 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^DSFLOWRPT:000001A6,00000000,00000000,00000000000553C2,000000000020D89A,00000000,00000000<CR><LF>'
Aug 28 18:27:33 hostname-1 modem-manager[1065]: <debug> [mm-modem-huawei-gsm.c:712] handle_status_change(): Duration: 422 Up: 0 Kbps Down: 0 Kbps Total: 340 Total: 2102#012
Aug 28 18:27:34 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:0<CR><LF>'
Aug 28 18:27:34 hostname-1 pppd[2624]: LCP terminated by peer
Aug 28 18:27:34 hostname-1 pppd[2624]: Connect time 7.1 minutes.
Aug 28 18:27:34 hostname-1 pppd[2624]: Sent 352002 bytes, received 2152602 bytes.
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.889072] [nm-netlink-monitor.c:163] link_msg_handler(): netlink link message: iface idx 4 flags 0x1090
Aug 28 18:27:34 hostname-1 pppd[2624]: Modem hangup
Aug 28 18:27:34 hostname-1 pppd[2624]: Connection terminated.
Aug 28 18:27:34 hostname-1 avahi-daemon[1059]: Withdrawing workstation service for ppp0.
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.898506] [nm-netlink-monitor.c:163] link_msg_handler(): netlink link message: iface idx 4 flags 0x1090
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): device state change: activated -> failed (reason 'ip-config-unavailable') [100 120 5]
Aug 28 18:27:34 hostname-1 modem-manager[1065]: <debug> [mm-serial-port.c:697] data_available(): (ttyUSB0) unexpected port hangup!
Aug 28 18:27:34 hostname-1 modem-manager[1065]: <debug> [mm-serial-port.c:908] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
Aug 28 18:27:34 hostname-1 modem-manager[1065]: <info>  [mm-serial-port.c:923] mm_serial_port_close(): (ttyUSB0) closing serial port...
Aug 28 18:27:34 hostname-1 modem-manager[1065]: <debug> [mm-port.c:181] mm_port_set_connected(): (ttyUSB0): port now disconnected
Aug 28 18:27:34 hostname-1 modem-manager[1065]: <info>  [mm-serial-port.c:944] mm_serial_port_close(): (ttyUSB0) serial port closed
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <warn> Activation (ttyUSB0) failed.
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.916596] [nm-device.c:4406] nm_device_queue_state(): (ttyUSB0): queued state change to disconnected (id 146)
Aug 28 18:27:34 hostname-1 modem-manager[1065]: <info>  [mm-modem.c:759] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (connected -> disconnecting)
Aug 28 18:27:34 hostname-1 NetworkManager[1093]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Aug 28 18:27:34 hostname-1 modem-manager[1065]: <info>  [mm-modem.c:759] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (disconnecting -> connected)
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.918252] [nm-udev-manager.c:476] handle_uevent(): UDEV event: action 'remove' subsys 'net' device 'ppp0'
Aug 28 18:27:34 hostname-1 modem-manager[1065]: mm_serial_port_close: assertion `priv->open_count > 0' failed
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): now unmanaged
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): device state change: failed -> unmanaged (reason 'removed') [120 10 36]
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.918386] [nm-device.c:2891] queued_state_clear(): (ttyUSB0): clearing queued state transition (id 146)
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): deactivating device (reason 'removed') [36]
Aug 28 18:27:34 hostname-1 dbus[1007]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.918432] [nm-firewall-manager.c:157] nm_firewall_manager_remove_from_zone(): (ppp0) firewall zone remove skipped (not running)
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <warn> could not read ppp stats: No such device
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Aug 28 18:27:34 hostname-1 dbus[1007]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.937468] [nm-dns-manager.c:709] update_dns(): DNS: updating plugin dnsmasq
Aug 28 18:27:34 hostname-1 dnsmasq[2677]: exiting on receipt of SIGTERM
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.937593] [nm-dns-dnsmasq.c:337] update(): dnsmasq local caching DNS configuration:
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.937602] [nm-dns-dnsmasq.c:338] update(): 
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> DNS: starting dnsmasq...
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.937648] [nm-dns-plugin.c:173] nm_dns_plugin_child_spawn(): DNS: command line: /usr/sbin/dnsmasq --no-resolv --keep-in-foreground --no-hosts --bind-interfaces --pid-file=/var/run/sendsigs.omit.d/network-manager.dnsmasq.pid --listen-address=127.0.0.1 --conf-file=/var/run/nm-dns-dnsmasq.conf --cache-size=0 --proxy-dnssec
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <debug> [1346174854.939410] [nm-dns-plugin.c:182] nm_dns_plugin_child_spawn(): dnsmasq started with pid 3133
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Aug 28 18:27:34 hostname-1 dnsmasq[3133]: started, version 2.59 cache disabled
Aug 28 18:27:34 hostname-1 dnsmasq[3133]: compile time options: IPv6 GNU-getopt DBus i18n DHCP TFTP conntrack IDN
Aug 28 18:27:34 hostname-1 dnsmasq[3133]: warning: no upstream servers configured
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): cleaning up...
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> (ttyUSB0): taking down device.
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Aug 28 18:27:34 hostname-1 NetworkManager[1093]: <info> disconnect failed: (32) The serial port is not open.
Aug 28 18:27:36 hostname-1 NetworkManager[1093]: <debug> [1346174856.971415] [nm-ppp-manager.c:1054] ensure_killed(): waiting for pppd pid 2624 to exit
Aug 28 18:27:36 hostname-1 NetworkManager[1093]: <debug> [1346174856.972150] [nm-ppp-manager.c:1056] ensure_killed(): pppd pid 2624 cleaned up
Aug 28 18:27:36 hostname-1 NetworkManager[1093]: <debug> [1346174856.972183] [nm-dns-plugin.c:208] ensure_killed(): waiting for dnsmasq pid 2677 to exit
Aug 28 18:27:36 hostname-1 NetworkManager[1093]: <debug> [1346174856.972250] [nm-dns-plugin.c:210] ensure_killed(): dnsmasq pid 2677 cleaned up
Aug 28 18:27:37 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:9<CR><LF>'
Aug 28 18:27:43 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:4<CR><LF>'
Aug 28 18:27:46 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 0,4D0,9477<CR><LF><CR><LF>+CGREG: 1,4D0,9477<CR><LF>'
Aug 28 18:27:46 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): CS registration state changed: 0
Aug 28 18:27:46 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:4<CR><LF>'
Aug 28 18:27:48 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SRVST:1<CR><LF>'
Aug 28 18:27:48 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 2,00,0<CR><LF><CR><LF>+CGREG: 2,00,0<CR><LF>'
Aug 28 18:27:48 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): CS registration state changed: 2
Aug 28 18:27:48 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): PS registration state changed: 2
Aug 28 18:27:49 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:4<CR><LF>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SRVST:2<CR><LF>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 0,478,47BC<CR><LF><CR><LF>+CGREG: 1,478,47BC<CR><LF>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): CS registration state changed: 0
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): PS registration state changed: 1
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+COPS=3,0;+COPS?<CR>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,0,"3 UK",2<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+COPS=3,2;+COPS?<CR>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,2,"23420",2<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+CSQ<CR>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CSQ: 4,99<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:27:50 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,2,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:52 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:4<CR><LF>'
Aug 28 18:27:52 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 1,00,0<CR><LF><CR><LF>+CGREG: 1,00,0<CR><LF>'
Aug 28 18:27:52 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): CS registration state changed: 1
Aug 28 18:27:52 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+COPS=3,0;+COPS?<CR>'
Aug 28 18:27:52 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,0,"3 UK",2<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:52 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+COPS=3,2;+COPS?<CR>'
Aug 28 18:27:53 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,2,"23420",2<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:53 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+CSQ<CR>'
Aug 28 18:27:53 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CSQ: 4,99<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:53 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:27:53 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,3,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:55 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:4<CR><LF>'
Aug 28 18:27:57 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:27:57 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,3,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:27:58 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^BOOT:30768362,0,0,0,87<CR><LF>'
Aug 28 18:27:58 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:13<CR><LF>'
Aug 28 18:28:01 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:6<CR><LF>'
Aug 28 18:28:02 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SRVST:1<CR><LF>'
Aug 28 18:28:02 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 2,00,0<CR><LF><CR><LF>+CGREG: 2,00,0<CR><LF>'
Aug 28 18:28:02 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): CS registration state changed: 2
Aug 28 18:28:02 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): PS registration state changed: 2
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:17<CR><LF>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SRVST:2<CR><LF>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 0,478,47BC<CR><LF><CR><LF>+CGREG: 1,478,47BC<CR><LF>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): CS registration state changed: 0
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): PS registration state changed: 1
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+COPS=3,0;+COPS?<CR>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,0,"3 UK",2<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+COPS=3,2;+COPS?<CR>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,2,"23420",2<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+CSQ<CR>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CSQ: 18,99<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:28:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,2,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 1,478,47BC<CR><LF><CR><LF>+CGREG: 1,478,47BC<CR><LF>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-generic-gsm.c:3192] mm_generic_gsm_set_reg_status(): CS registration state changed: 1
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+COPS=3,0;+COPS?<CR>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,0,"3 UK",2<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+COPS=3,2;+COPS?<CR>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,2,"23420",2<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT+CSQ<CR>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>+CSQ: 18,99<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:28:06 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,3,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:07 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:17<CR><LF>'
Aug 28 18:28:13 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:13<CR><LF>'
Aug 28 18:28:16 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:13<CR><LF>'
Aug 28 18:28:19 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:7<CR><LF>'
Aug 28 18:28:22 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:10<CR><LF>'
Aug 28 18:28:25 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:10<CR><LF>'
Aug 28 18:28:27 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:28:27 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,3,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:28 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^BOOT:30768362,0,0,0,87<CR><LF>'
Aug 28 18:28:28 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:15<CR><LF>'
Aug 28 18:28:52  modem-manager[1065]: last message repeated 4 times
Aug 28 18:28:52 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:19<CR><LF>'
Aug 28 18:28:55 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:19<CR><LF>'
Aug 28 18:28:57 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:28:57 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,3,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:28:58 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^BOOT:30768362,0,0,0,87<CR><LF>'
Aug 28 18:28:58 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:19<CR><LF>'
Aug 28 18:29:01 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:15<CR><LF>'
Aug 28 18:29:04 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:25<CR><LF>'
Aug 28 18:29:07 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:22<CR><LF>'
Aug 28 18:29:10 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:12<CR><LF>'
Aug 28 18:29:13 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:24<CR><LF>'
Aug 28 18:29:16 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:24<CR><LF>'
Aug 28 18:29:19 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:19<CR><LF>'
Aug 28 18:29:22 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:19<CR><LF>'
Aug 28 18:29:25 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:16<CR><LF>'
Aug 28 18:29:27 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): --> 'AT^SYSINFO<CR>'
Aug 28 18:29:27 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^SYSINFO:2,3,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
Aug 28 18:29:28 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^BOOT:30768362,0,0,0,87<CR><LF>'
Aug 28 18:29:28 hostname-1 modem-manager[1065]: <debug> [mm-at-serial-port.c:334] debug_log(): (ttyUSB2): <-- '<CR><LF>^RSSI:16<CR><LF>'


From: tristanstevens hotmail com
To: aleksander lanedo com
Subject: RE: 3G Modem disconnect goes to unavailable rather than disconnected
Date: Sun, 26 Aug 2012 09:08:12 +0000
CC: networkmanager-list gnome org

Thanks Aleksander - couldn't work out how to find the ModemManager version.

I'll try that next week and see how I get on. Will post back when I have some results (the bug only manifests on my commute when I go in and out of signal).

Thanks,
Tristan

> Date: Fri, 24 Aug 2012 12:15:36 +0200
> From: aleksander lanedo com
> To: tristanstevens hotmail com
> CC: networkmanager-list gnome org
> Subject: Re: 3G Modem disconnect goes to unavailable rather than disconnected
>
>
> > I'm running 0.9.4.0 on Ubuntu 12.04.
> >
> > Logs below with NM and MM in debug mode.
>
>
> Ubuntu 12.04 still has ModemManager 0.5 (with NetworkManager is 0.9.4).
> IIRC there's a ModemManager 0.6 package for the next Ubuntu 12.10; you
> may want to try that instead. Or otherwise compile the 0.6-rc1 yourself:
> http://ftp.gnome.org/pub/gnome/sources/ModemManager/0.5/ModemManager-0.5.995.0.tar.xz
>
> See
> https://mail.gnome.org/archives/networkmanager-list/2012-July/msg00120.html
>
> --
> Aleksander

_______________________________________________ networkmanager-list mailing list networkmanager-list gnome org https://mail.gnome.org/mailman/listinfo/networkmanager-list


[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]