|
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 |