[Pkg-utopia-maintainers] Bug#620355: /usr/sbin/NetworkManager: NetworkManager frequently crashes with SIGSEGV

Thiemo Nagel thiemo.nagel at googlemail.com
Fri Apr 1 10:58:59 UTC 2011


Package: network-manager
Version: 0.8.1-6+squeeze1
Severity: important
File: /usr/sbin/NetworkManager

Hello,

I'm seeing frequent (about once per day) crashes of NetworkManager.
syslog of the last crash is attached.  It seems to me that this specific crash
has happened while switching from mobile network to wifi, but I have a feeling
crashes have happened at other occasions as well.

I've got syslog records back to Feb 28, but the first occurrence of the issue is Mar 15.
On Mar 11, I had switched to Linux 2.6.32-31 while I had been running 2.6.32-30 before.
I guess it's time to reboot into 2.6.32-32...

BTW:  Where can one find the backtrace that syslog says is generated?

Kind regards,
Thiemo

P.S.:  Just to give you an impression on the frequency of the crashes:

syslog.1:Apr  1 00:03:11 eee NetworkManager[31814]: <warn> caught signal 11. Generating backtrace...
syslog.2.gz:Mar 31 11:17:09 eee NetworkManager[16337]: <warn> caught signal 11. Generating backtrace...
syslog.3.gz:Mar 30 00:32:38 eee NetworkManager[32643]: <warn> caught signal 11. Generating backtrace...
syslog.3.gz:Mar 30 09:58:10 eee NetworkManager[12187]: <warn> caught signal 11. Generating backtrace...
syslog.4.gz:Mar 29 12:16:41 eee NetworkManager[21858]: <warn> caught signal 11. Generating backtrace...
syslog.6.gz:Mar 26 16:22:27 eee NetworkManager[19069]: <warn> caught signal 11. Generating backtrace...
syslog.8.gz:Mar 24 11:26:03 eee NetworkManager[8933]: <warn> caught signal 11. Generating backtrace...
syslog.8.gz:Mar 24 12:40:52 eee NetworkManager[27011]: <warn> caught signal 11. Generating backtrace...
syslog.9.gz:Mar 21 22:49:55 eee NetworkManager[28548]: <warn> caught signal 11. Generating backtrace...
syslog.9.gz:Mar 22 09:49:26 eee NetworkManager[6563]: <warn> caught signal 11. Generating backtrace...
syslog.11.gz:Mar 17 11:29:30 eee NetworkManager[16556]: <warn> caught signal 11. Generating backtrace...
syslog.11.gz:Mar 17 15:23:21 eee NetworkManager[1631]: <warn> caught signal 11. Generating backtrace...
syslog.12.gz:Mar 15 13:34:30 eee NetworkManager[11412]: <warn> caught signal 11. Generating backtrace...


-- System Information:
Debian Release: 6.0.1
  APT prefers squeeze-updates
  APT policy: (990, 'squeeze-updates'), (990, 'proposed-updates'), (990, 'stable'), (450, 'testing-proposed-updates'), (450, 'testing'), (300, 'unstable'), (150, 'experimental')
Architecture: i386 (i686)

Kernel: Linux 2.6.32-5-686 (SMP w/2 CPU cores)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages network-manager depends on:
ii  adduser             3.112+nmu2           add and remove users and groups
ii  dbus                1.2.24-4             simple interprocess messaging syst
ii  isc-dhcp-client     4.1.1-P1-15+squeeze1 ISC DHCP client
ii  libc6               2.11.2-10            Embedded GNU C Library: Shared lib
ii  libdbus-1-3         1.2.24-4             simple interprocess messaging syst
ii  libdbus-glib-1-2    0.88-2.1             simple interprocess messaging syst
ii  libgcrypt11         1.4.5-2              LGPL Crypto library - runtime libr
ii  libglib2.0-0        2.24.2-1             The GLib library of C routines
ii  libgnutls26         2.8.6-1              the GNU TLS library - runtime libr
ii  libgudev-1.0-0      164-3                GObject-based wrapper library for 
ii  libnl1              1.1-6                library for dealing with netlink s
ii  libnm-glib2         0.8.1-6+squeeze1     network management framework (GLib
ii  libnm-util1         0.8.1-6+squeeze1     network management framework (shar
ii  libpolkit-gobject-1 0.96-4               PolicyKit Authorization API
ii  libuuid1            2.17.2-9             Universally Unique ID library
ii  lsb-base            3.2-23.2squeeze1     Linux Standard Base 3.2 init scrip
ii  udev                164-3                /dev/ and hotplug management daemo
ii  wpasupplicant       0.6.10-2.1           client support for WPA and WPA2 (I

Versions of packages network-manager recommends:
ii  dnsmas 2.55-2+b1                         A small caching DNS proxy and DHCP
ii  iptabl 1.4.8-3                           administration tools for packet fi
ii  modemm 0.4+git.20100624t180933.6e79d15-2 D-Bus service for managing modems
ii  policy 0.96-4                            framework for managing administrat
ii  ppp    2.4.5-4                           Point-to-Point Protocol (PPP) - da

Versions of packages network-manager suggests:
ii  avahi-autoipd          0.6.27-2+squeeze1 Avahi IPv4LL network address confi

-- no debconf information
-------------- next part --------------
Apr  1 00:02:59 eee laptop-mode: Laptop mode 
Apr  1 00:02:59 eee laptop-mode: enabled, 
Apr  1 00:02:59 eee laptop-mode: not active [unchanged]
Apr  1 00:03:00 eee laptop-mode: Laptop mode 
Apr  1 00:03:00 eee laptop-mode: enabled, 
Apr  1 00:03:00 eee laptop-mode: not active [unchanged]
Apr  1 00:03:00 eee acpid: client connected from 1889[0:0]
Apr  1 00:03:00 eee acpid: 1 client rule loaded
Apr  1 00:03:03 eee modem-manager: (ttyUSB2) closing serial device...
Apr  1 00:03:03 eee modem-manager: (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-6 claimed port ttyUSB2
Apr  1 00:03:03 eee modem-manager: Exported modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-6 as /org/freedesktop/ModemManager/Modems/72
Apr  1 00:03:03 eee modem-manager: (/org/freedesktop/ModemManager/Modems/72): data port is ttyUSB1
Apr  1 00:03:03 eee NetworkManager[31814]: <warn> (ttyUSB1): failed to look up interface index
Apr  1 00:03:03 eee NetworkManager[31814]: <info> (ttyUSB1): new GSM device (driver: 'option1' ifindex: -1)
Apr  1 00:03:03 eee NetworkManager[31814]: <info> (ttyUSB1): exported as /org/freedesktop/NetworkManager/Devices/5
Apr  1 00:03:03 eee NetworkManager[31814]: <info> (ttyUSB1): now managed
Apr  1 00:03:03 eee NetworkManager[31814]: <info> (ttyUSB1): device state change: 1 -> 2 (reason 2)
Apr  1 00:03:03 eee NetworkManager[31814]: <info> (ttyUSB1): deactivating device (reason: 2).
Apr  1 00:03:03 eee NetworkManager[31814]: <info> (ttyUSB1): device state change: 2 -> 3 (reason 0)
Apr  1 00:03:03 eee NetworkManager[31814]: <info> Activation (ttyUSB1) starting connection 'Auto Mobile Broadband (GSM) connection'
Apr  1 00:03:03 eee NetworkManager[31814]: <info> (ttyUSB1): device state change: 3 -> 4 (reason 0)
Apr  1 00:03:03 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 1 of 5 (Device Prepare) scheduled...
Apr  1 00:03:03 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 1 of 5 (Device Prepare) started...
Apr  1 00:03:03 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 1 of 5 (Device Prepare) complete.
Apr  1 00:03:03 eee modem-manager: (ttyUSB1) opening serial device...
Apr  1 00:03:03 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (disabled -> enabling)
Apr  1 00:03:03 eee modem-manager: (ttyUSB4) opening serial device...
Apr  1 00:03:03 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (enabling -> enabled)
Apr  1 00:03:03 eee modem-manager: CS registration state changed: 1
Apr  1 00:03:03 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (enabled -> registered)
Apr  1 00:03:03 eee modem-manager: PS registration state changed: 1
Apr  1 00:03:03 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (registered -> connecting)
Apr  1 00:03:04 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (connecting -> connected)
Apr  1 00:03:04 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 2 of 5 (Device Configure) scheduled...
Apr  1 00:03:04 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 2 of 5 (Device Configure) starting...
Apr  1 00:03:04 eee NetworkManager[31814]: <info> (ttyUSB1): device state change: 4 -> 5 (reason 0)
Apr  1 00:03:04 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 2 of 5 (Device Configure) successful.
Apr  1 00:03:04 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 3 of 5 (IP Configure Start) scheduled.
Apr  1 00:03:04 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 2 of 5 (Device Configure) complete.
Apr  1 00:03:04 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 3 of 5 (IP Configure Start) started...
Apr  1 00:03:04 eee NetworkManager[31814]: <info> (ttyUSB1): device state change: 5 -> 7 (reason 0)
Apr  1 00:03:04 eee NetworkManager[31814]: <info> starting PPP connection
Apr  1 00:03:04 eee NetworkManager[31814]: <info> pppd started with pid 9144
Apr  1 00:03:04 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 3 of 5 (IP Configure Start) complete.
Apr  1 00:03:04 eee pppd[9144]: Plugin /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
Apr  1 00:03:04 eee pppd[9144]: pppd 2.4.5 started by root, uid 0
Apr  1 00:03:04 eee pppd[9144]: Using interface ppp0
Apr  1 00:03:04 eee pppd[9144]: Connect: ppp0 <--> /dev/ttyUSB1
Apr  1 00:03:04 eee modem-manager: (net/ppp0): could not get port's parent device
Apr  1 00:03:04 eee NetworkManager[31814]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Apr  1 00:03:04 eee NetworkManager[31814]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Apr  1 00:03:04 eee pppd[9144]: CHAP authentication succeeded
Apr  1 00:03:04 eee pppd[9144]: CHAP authentication succeeded
Apr  1 00:03:05 eee modem-manager: Access Technology: 7
Apr  1 00:03:05 eee modem-manager: Access Technology: 8
Apr  1 00:03:06 eee vnstatd[1357]: Interface "ppp0" enabled.
Apr  1 00:03:06 eee pppd[9144]: Could not determine remote IP address: defaulting to 10.64.64.64
Apr  1 00:03:06 eee pppd[9144]: local  IP address 10.75.244.219
Apr  1 00:03:06 eee pppd[9144]: remote IP address 10.64.64.64
Apr  1 00:03:06 eee pppd[9144]: primary   DNS address 193.189.244.225
Apr  1 00:03:06 eee pppd[9144]: secondary DNS address 193.189.244.206
Apr  1 00:03:06 eee NetworkManager[31814]: <info> PPP manager(IP Config Get) reply received.
Apr  1 00:03:06 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 4 of 5 (IP4 Configure Get) scheduled...
Apr  1 00:03:06 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 4 of 5 (IP4 Configure Get) started...
Apr  1 00:03:06 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 5 of 5 (IP Configure Commit) scheduled...
Apr  1 00:03:06 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 4 of 5 (IP4 Configure Get) complete.
Apr  1 00:03:06 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 5 of 5 (IP Configure Commit) started...
Apr  1 00:03:07 eee NetworkManager[31814]: <info> (ttyUSB1): device state change: 7 -> 8 (reason 0)
Apr  1 00:03:07 eee NetworkManager[31814]: <info> Policy set 'Auto Mobile Broadband (GSM) connection' (ppp0) as default for IPv4 routing and DNS.
Apr  1 00:03:07 eee NetworkManager[31814]: <info> Activation (ttyUSB1) successful, device activated.
Apr  1 00:03:08 eee NetworkManager[31814]: <info> Activation (ttyUSB1) Stage 5 of 5 (IP Configure Commit) complete.
Apr  1 00:03:09 eee kernel: [337952.013029] pci 0000:01:00.0: reg 10 64bit mmio: [0x000000-0x00ffff]
Apr  1 00:03:09 eee kernel: [337952.013501] ath5k 0000:01:00.0: enabling device (0000 -> 0002)
Apr  1 00:03:09 eee kernel: [337952.013533] ath5k 0000:01:00.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
Apr  1 00:03:09 eee kernel: [337952.013572] ath5k 0000:01:00.0: setting latency timer to 64
Apr  1 00:03:09 eee kernel: [337952.014768] ath5k 0000:01:00.0: registered as 'phy13'
Apr  1 00:03:10 eee kernel: [337952.529878] ath: EEPROM regdomain: 0x60
Apr  1 00:03:10 eee kernel: [337952.529887] ath: EEPROM indicates we should expect a direct regpair map
Apr  1 00:03:10 eee kernel: [337952.529897] ath: Country alpha2 being used: 00
Apr  1 00:03:10 eee kernel: [337952.529903] ath: Regpair used: 0x60
Apr  1 00:03:10 eee kernel: [337952.541318] phy13: Selected rate control algorithm 'minstrel'
Apr  1 00:03:10 eee kernel: [337952.550580] Registered led device: ath5k-phy13::rx
Apr  1 00:03:10 eee kernel: [337952.553003] Registered led device: ath5k-phy13::tx
Apr  1 00:03:10 eee kernel: [337952.553017] ath5k phy13: Atheros AR2425 chip found (MAC: 0xe2, PHY: 0x70)
Apr  1 00:03:10 eee pppd[9144]: LCP terminated by peer
Apr  1 00:03:10 eee pppd[9144]: Connect time 0.1 minutes.
Apr  1 00:03:10 eee pppd[9144]: Sent 2843 bytes, received 29466 bytes.
Apr  1 00:03:10 eee pppd[9144]: Modem hangup
Apr  1 00:03:10 eee pppd[9144]: Connection terminated.
Apr  1 00:03:10 eee avahi-daemon[1584]: Withdrawing workstation service for ppp0.
Apr  1 00:03:10 eee modem-manager: (ttyUSB1) closing serial device...
Apr  1 00:03:10 eee pppd[9144]: Exit.
Apr  1 00:03:10 eee NetworkManager[31814]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Apr  1 00:03:10 eee NetworkManager[31814]: <info> found WiFi radio killswitch rfkill16 (at /sys/devices/pci0000:00/0000:00:1c.3/0000:01:00.0/ieee80211/phy13/rfkill16) (driver <unknown>)
Apr  1 00:03:10 eee NetworkManager[31814]: <info> (ttyUSB1): device state change: 8 -> 3 (reason 0)
Apr  1 00:03:10 eee NetworkManager[31814]: <info> (ttyUSB1): deactivating device (reason: 0).
Apr  1 00:03:10 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (connected -> disconnecting)
Apr  1 00:03:10 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (disconnecting -> connected)
Apr  1 00:03:10 eee modem-manager: disable_disconnect_done: (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-6): error disconnecting the modem while disabling: (5) The serial port is not open.
Apr  1 00:03:10 eee modem-manager: (ttyUSB4) closing serial device...
Apr  1 00:03:10 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (connected -> disabling)
Apr  1 00:03:10 eee modem-manager: mm_serial_port_close_force: assertion `priv->open_count > 0' failed
Apr  1 00:03:10 eee modem-manager: Modem /org/freedesktop/ModemManager/Modems/72: state changed (disabling -> disabled)
Apr  1 00:03:10 eee NetworkManager[31814]: <warn> could not read ppp stats: No such device
Apr  1 00:03:10 eee NetworkManager[31814]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.3/0000:01:00.0/net/wlan0, iface: wlan0)
Apr  1 00:03:10 eee NetworkManager[31814]:    SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.3/0000:01:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
Apr  1 00:03:10 eee NetworkManager[31814]: <info> (ttyUSB1): now unmanaged
Apr  1 00:03:10 eee NetworkManager[31814]: <info> (ttyUSB1): device state change: 3 -> 1 (reason 36)
Apr  1 00:03:10 eee NetworkManager[31814]: <info> (ttyUSB1): cleaning up...
Apr  1 00:03:10 eee NetworkManager[31814]: <info> (ttyUSB1): taking down device.
Apr  1 00:03:11 eee NetworkManager[31814]: <info> (wlan0): driver supports SSID scans (scan_capa 0x01).
Apr  1 00:03:11 eee NetworkManager[31814]: <info> (wlan0): new 802.11 WiFi device (driver: 'ath5k' ifindex: 80)
Apr  1 00:03:11 eee NetworkManager[31814]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/6
Apr  1 00:03:11 eee NetworkManager[31814]: <info> (wlan0): now managed
Apr  1 00:03:11 eee NetworkManager[31814]: <info> (wlan0): device state change: 1 -> 2 (reason 2)
Apr  1 00:03:11 eee NetworkManager[31814]: <info> (wlan0): bringing up device.
Apr  1 00:03:11 eee kernel: [337953.672703] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr  1 00:03:11 eee NetworkManager[31814]: <info> (wlan0): preparing device.
Apr  1 00:03:11 eee NetworkManager[31814]: <info> (wlan0): deactivating device (reason: 2).
Apr  1 00:03:11 eee NetworkManager[31814]: <warn> caught signal 11. Generating backtrace...
Apr  1 00:03:11 eee vnstatd[1357]: Interface "wlan0" enabled.
Apr  1 00:03:11 eee vnstatd[1357]: Interface "ppp0" disabled.
Apr  1 00:03:11 eee NetworkManager: ******************* START **********************************
Apr  1 00:03:17 eee NetworkManager: [Thread debugging using libthread_db enabled]
Apr  1 00:03:17 eee NetworkManager: [New Thread 0xb70ffb70 (LWP 31828)]
Apr  1 00:03:20 eee NetworkManager: 0xb77ee424 in __kernel_vsyscall ()
Apr  1 00:03:20 eee NetworkManager: ******************* END **********************************


More information about the Pkg-utopia-maintainers mailing list