Re: NM 0.7 on Fedora 8 with 3G USB Modems

["Peter Robinson" <pbrobinson gmail com>]

> > Been reading through the list for details of the 3G modem support in
> > Fedora8/NM. It seems like its almost there, and from my playing it
> > seems like I've got it almost working :)
> >
> > Its one of the seemingly infamous Huawei E220's and a bit of a search
> > I got it ready to go, and added the problems I had to the RH BZ ticket
> > here https://bugzilla.redhat.com/show_bug.cgi?id=253096 (I added a
> > blacklist to the /etc/modprobe.d dir for the USB device).
>
> Is that all sorted out now?  I seem to recall there was a bunch of
> traffic on the E220 on the Linux Kernel Mailing List with patches to
> make it work better.  Whether those patches have been incorporated into
> the Fedora kernels already might make a difference.

According to the BZ bug it should be fixed in the kernel that I'm
running but its not. The kernel (and other stuff) is:
kernel-2.6.23.9-85.fc8
NetworkManager-0.7.0-0.6.6.svn3138.fc8
dhclient-3.0.6-12.fc8

> > I then added the .fdi file mentioned on the list earlier in the month
> > and restarted hal. NM now detects the modem but wouldn't connect. I
> > first discovered that the NM ppp plugin in the F8 rpm was looking for
> > /usr/lib64/NetworkManager/nm-pppd-plugin.so where as it was located in
> > /usr/lib64. A quick symlink fixed that.
>
> Which version of NM are you currently using?  The output of 'rpm -qv
> NetworkManager' is what you want here.  There's been a few changes
> recently in the plugin path of the ppp plugin.

NetworkManager-0.7.0-0.6.6.svn3138.fc8

> > I've attached the relevent portion of /var/log/messages which includes
> > a crash from NM as well. It looks like its almost successful but
> > there's a selinux message although the system is configured for
> > permissive (I think it might be a log).
>
> I didn't see an attachment, can you resend?

Attached.

Cheers,
Pete

Dec 20 14:09:55 localhost kernel: usb 3-2: new full speed USB device using uhci_hcd and address 11
Dec 20 14:09:55 localhost kernel: usb 3-2: configuration #1 chosen from 1 choice
Dec 20 14:09:55 localhost kernel: usbserial_generic 3-2:1.0: GSM modem (1-port) converter detected
Dec 20 14:09:55 localhost kernel: option_start_huawei: HUAWEI E220 setup failed (1)
Dec 20 14:09:55 localhost kernel: usb 3-2: GSM modem (1-port) converter now attached to ttyUSB0
Dec 20 14:09:55 localhost kernel: usb 3-2: USB disconnect, address 11
Dec 20 14:09:55 localhost kernel: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Dec 20 14:09:55 localhost kernel: usbserial_generic 3-2:1.0: device disconnected
Dec 20 14:09:55 localhost kernel: usb 3-2: new full speed USB device using uhci_hcd and address 12
Dec 20 14:09:56 localhost kernel: usb 3-2: configuration #1 chosen from 1 choice
Dec 20 14:09:56 localhost kernel: usbserial_generic 3-2:1.0: GSM modem (1-port) converter detected
Dec 20 14:09:56 localhost kernel: option_start_huawei: HUAWEI E220 setup failed (1)
Dec 20 14:09:56 localhost kernel: usb 3-2: GSM modem (1-port) converter now attached to ttyUSB0
Dec 20 14:09:56 localhost kernel: usbserial_generic 3-2:1.1: GSM modem (1-port) converter detected
Dec 20 14:09:56 localhost kernel: option_start_huawei: HUAWEI E220 setup failed (1)
Dec 20 14:09:56 localhost kernel: usb 3-2: GSM modem (1-port) converter now attached to ttyUSB1
Dec 20 14:09:56 localhost kernel: usbserial_generic 3-2:1.2: GSM modem (1-port) converter detected
Dec 20 14:09:56 localhost kernel: option_start_huawei: HUAWEI E220 setup failed (1)
Dec 20 14:09:56 localhost kernel: usb 3-2: GSM modem (1-port) converter now attached to ttyUSB2
Dec 20 14:09:56 localhost NetworkManager: <info>  ttyUSB0: Device is fully-supported using driver 'usbserial_generic'.
Dec 20 14:09:56 localhost NetworkManager: <info>  (ttyUSB0): exporting device as /org/freedesktop/Hal/devices/usb_device_12d1_1003_noserial_1_if0_serial_usb_0
Dec 20 14:09:56 localhost NetworkManager: <info>  Now managing UMTS (GSM) device 'ttyUSB0'.
Dec 20 14:09:56 localhost NetworkManager: <info>  Bringing up device ttyUSB0
Dec 20 14:10:04 localhost NetworkManager: <info>  Deactivating device ttyUSB0.
Dec 20 14:10:34 localhost NetworkManager: <info>  User request for activation of ttyUSB0.
Dec 20 14:10:34 localhost NetworkManager: <info>  Activating device ttyUSB0
Dec 20 14:10:34 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Dec 20 14:10:34 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Dec 20 14:10:34 localhost NetworkManager: Invalid bits (0). Valid values are 5, 6, 7, 8.
Dec 20 14:10:34 localhost NetworkManager: Invalid parity (
Dec 20 14:10:34 localhost NetworkManager: Invalid stop bits (0). Valid values are 1 and 2)
Dec 20 14:10:35 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Dec 20 14:10:35 localhost NetworkManager: <info>  Registered on Home network
Dec 20 14:10:35 localhost NetworkManager: <info>  Associated with network: 
Dec 20 14:10:35 localhost NetworkManager: <info>  Connected, Woo!
Dec 20 14:10:35 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Dec 20 14:10:35 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Dec 20 14:10:35 localhost NetworkManager: <info>  Bringing up device ttyUSB0
Dec 20 14:10:35 localhost NetworkManager: <info>  Starting pppd connection
Dec 20 14:10:35 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Dec 20 14:10:35 localhost pppd[1635]: Plugin /usr/lib64/NetworkManager/nm-pppd-plugin.so loaded.
Dec 20 14:10:35 localhost pppd[1635]: pppd 2.4.4 started by root, uid 0
Dec 20 14:10:35 localhost pppd[1635]: Using interface ppp0
Dec 20 14:10:35 localhost pppd[1635]: Connect: ppp0 <--> /dev/ttyUSB0
Dec 20 14:10:35 localhost kernel: PPP Deflate Compression module registered
Dec 20 14:10:37 localhost setroubleshoot: #012    SELinux is preventing /usr/sbin/pppd (pppd_t) "write" to <Unknown> (system_dbusd_var_run_t).#012     For complete SELinux messages. run sealert -l e803d664-cd78-4b45-bce0-c014d2a81b1a
Dec 20 14:10:37 localhost setroubleshoot: #012    SELinux is preventing /usr/sbin/pppd (pppd_t) "read write" to <Unknown> (var_run_t).#012     For complete SELinux messages. run sealert -l 14834a05-ddcb-477a-a2b0-a544360633be
Dec 20 14:10:37 localhost setroubleshoot: #012    SELinux is preventing /usr/sbin/pppd (pppd_t) "lock" to /var/run/pppd2.tdb (var_run_t).#012     For complete SELinux messages. run sealert -l 834b55bf-ed04-463a-9d43-e39021553119
Dec 20 14:10:37 localhost pppd[1635]: Modem hangup
Dec 20 14:10:37 localhost pppd[1635]: Connection terminated.
Dec 20 14:10:38 localhost pppd[1635]: Exit.
Dec 20 14:10:38 localhost NetworkManager: pppd exited with error: A modem hung up the phone
Dec 20 14:11:15 localhost NetworkManager: <info>  User request for activation of ttyUSB0.
Dec 20 14:11:15 localhost NetworkManager: <info>  Activating device ttyUSB0
Dec 20 14:11:15 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Dec 20 14:11:15 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Dec 20 14:11:15 localhost NetworkManager: <WARN>  nm_serial_device_open(): Can not control device 'ttyUSB0'
Dec 20 14:11:15 localhost NetworkManager: <info>  Marking connection 'Auto GSM dialup connection' invalid.
Dec 20 14:11:15 localhost NetworkManager: <info>  Activation (ttyUSB0) failed.
Dec 20 14:11:15 localhost NetworkManager: <info>  Deactivating device ttyUSB0.
Dec 20 14:11:15 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Dec 20 14:12:31 localhost NetworkManager: <info>  User request for activation of ttyUSB0.
Dec 20 14:12:31 localhost NetworkManager: <info>  Activating device ttyUSB0
Dec 20 14:12:31 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Dec 20 14:12:31 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Dec 20 14:12:31 localhost NetworkManager: <WARN>  nm_serial_device_open(): Can not control device 'ttyUSB0'
Dec 20 14:12:31 localhost NetworkManager: <info>  Marking connection 'Auto GSM dialup connection' invalid.
Dec 20 14:12:31 localhost NetworkManager: <info>  Activation (ttyUSB0) failed.
Dec 20 14:12:31 localhost NetworkManager: <info>  Deactivating device ttyUSB0.
Dec 20 14:12:31 localhost NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Dec 20 14:12:31 localhost NetworkManager: <WARN>  nm_signal_handler(): Caught signal 11.  Generating backtrace...
Dec 20 14:12:31 localhost NetworkManager: ******************* START **********************************
Dec 20 14:12:32 localhost NetworkManager: #033[?1034h(no debugging symbols found)
Dec 20 14:12:32 localhost NetworkManager: Using host libthread_db library "/lib64/libthread_db.so.1".
Dec 20 14:12:32 localhost NetworkManager: (no debugging symbols found)
Dec 20 14:12:32 localhost NetworkManager:last message repeated 3 times
Dec 20 14:12:32 localhost NetworkManager: [Thread debugging using libthread_db enabled]
Dec 20 14:12:32 localhost NetworkManager: [New Thread 46912496249328 (LWP 689)]
Dec 20 14:12:32 localhost NetworkManager: [New Thread 1084229968 (LWP 1636)]
Dec 20 14:12:33 localhost NetworkManager: 0x00000034d140e0bf in waitpid () from /lib64/libpthread.so.0
Dec 20 14:12:33 localhost NetworkManager: #0  0x00000034d140e0bf in waitpid () from /lib64/libpthread.so.0
Dec 20 14:12:33 localhost NetworkManager: #1  0x0000000000422131 in nm_logging_backtrace ()
Dec 20 14:12:33 localhost NetworkManager: #2  0x000000000041eca4 in ?? ()
Dec 20 14:12:33 localhost NetworkManager: #3  <signal handler called>
Dec 20 14:12:33 localhost NetworkManager: #4  0x0000000041fbb000 in ?? ()
Dec 20 14:12:33 localhost NetworkManager: #5  0x0000003e0942eea3 in IA__g_main_context_dispatch (context=0x661f20)
Dec 20 14:12:33 localhost NetworkManager:     at gmain.c:2061
Dec 20 14:12:33 localhost NetworkManager: #6  0x0000003e0943219d in g_main_context_iterate (context=0x661f20, block=1, 
Dec 20 14:12:33 localhost NetworkManager:     dispatch=1, self=<value optimized out>) at gmain.c:2694
Dec 20 14:12:33 localhost NetworkManager: #7  0x0000003e094324aa in IA__g_main_loop_run (loop=0x65e740) at gmain.c:2898
Dec 20 14:12:33 localhost NetworkManager: #8  0x000000000041f3b0 in main ()
Dec 20 14:12:33 localhost NetworkManager: 
Dec 20 14:12:33 localhost NetworkManager: Thread 2 (Thread 1084229968 (LWP 1636)):
Dec 20 14:12:33 localhost NetworkManager: #0  0x00000034d140d34b in read () from /lib64/libpthread.so.0
Dec 20 14:12:33 localhost NetworkManager: No locals.
Dec 20 14:12:33 localhost NetworkManager: #1  0x0000003e0942fde4 in child_watch_helper_thread (
Dec 20 14:12:33 localhost NetworkManager:     data=<value optimized out>) at /usr/include/bits/unistd.h:45
Dec 20 14:12:33 localhost NetworkManager: 
Dec 20 14:12:33 localhost NetworkManager: #011list = (GSList *) 0x34d1409ad0
Dec 20 14:12:33 localhost NetworkManager: #2  0x0000003e09450724 in g_thread_create_proxy (data=0x67acd0)
Dec 20 14:12:33 localhost NetworkManager:     at gthread.c:635
Dec 20 14:12:33 localhost NetworkManager: 
Dec 20 14:12:33 localhost NetworkManager: #3  0x00000034d1406407 in start_thread () from /lib64/libpthread.so.0
Dec 20 14:12:33 localhost NetworkManager: No locals.
Dec 20 14:12:33 localhost NetworkManager: #4  0x00000034d08d4b0d in clone () from /lib64/libc.so.6
Dec 20 14:12:33 localhost NetworkManager: #011fstab_state = {fs_fp = 0x0, fs_buffer = 0x0, fs_mntres = {
Dec 20 14:12:33 localhost NetworkManager:     mnt_fsname = 0x0, mnt_dir = 0x0, mnt_type = 0x0, mnt_opts = 0x0, 
Dec 20 14:12:33 localhost NetworkManager: mnt_passno = 0}, fs_ret = {fs_spec = 0x0, fs_file = 0x0, 
Dec 20 14:12:33 localhost NetworkManager:     fs_vfstype = 0x0, fs_mntops = 0x0, fs_type = 0x0, fs_freq = 0, 
Dec 20 14:12:33 localhost NetworkManager: 
Dec 20 14:12:33 localhost NetworkManager: #011__elf_set___libc_subfreeres_element_fstab_free__ = (
Dec 20 14:12:33 localhost NetworkManager:     const void *) 0x34d090beb0
Dec 20 14:12:33 localhost NetworkManager: Current language:  auto; currently asm
Dec 20 14:12:33 localhost NetworkManager: 
Dec 20 14:12:33 localhost NetworkManager: Thread 1 (Thread 46912496249328 (LWP 689)):
Dec 20 14:12:33 localhost NetworkManager: #0  0x00000034d140e0bf in waitpid () from /lib64/libpthread.so.0
Dec 20 14:12:33 localhost NetworkManager: No locals.
Dec 20 14:12:33 localhost NetworkManager: #1  0x0000000000422131 in nm_logging_backtrace ()
Dec 20 14:12:33 localhost NetworkManager: No symbol table info available.
Dec 20 14:12:33 localhost NetworkManager: #2  0x000000000041eca4 in ?? ()
Dec 20 14:12:33 localhost NetworkManager: No symbol table info available.
Dec 20 14:12:33 localhost NetworkManager: #3  <signal handler called>
Dec 20 14:12:33 localhost NetworkManager: No locals.
Dec 20 14:12:33 localhost NetworkManager: #4  0x0000000041fbb000 in ?? ()
Dec 20 14:12:33 localhost NetworkManager: No symbol table info available.
Dec 20 14:12:33 localhost NetworkManager: #5  0x0000003e0942eea3 in IA__g_main_context_dispatch (context=0x661f20)
Dec 20 14:12:33 localhost NetworkManager:     at gmain.c:2061
Dec 20 14:12:33 localhost NetworkManager: No locals.
Dec 20 14:12:33 localhost NetworkManager: #6  0x0000003e0943219d in g_main_context_iterate (context=0x661f20, block=1, 
Dec 20 14:12:33 localhost NetworkManager:     dispatch=1, self=<value optimized out>) at gmain.c:2694
Dec 20 14:12:33 localhost NetworkManager: #011got_ownership = <value optimized out>
Dec 20 14:12:33 localhost NetworkManager: 
Dec 20 14:12:33 localhost NetworkManager:last message repeated 2 times
Dec 20 14:12:33 localhost NetworkManager: #011nfds = <value optimized out>
Dec 20 14:12:33 localhost NetworkManager: #011allocated_nfds = <value optimized out>
Dec 20 14:12:33 localhost NetworkManager: #011fds = (GPollFD *) 0x66b3f0
Dec 20 14:12:33 localhost NetworkManager: 
Dec 20 14:12:33 localhost NetworkManager: #7  0x0000003e094324aa in IA__g_main_loop_run (loop=0x65e740) at gmain.c:2898
Dec 20 14:12:33 localhost NetworkManager: #011got_ownership = <value optimized out>
Dec 20 14:12:33 localhost NetworkManager: #011self = (GThread *) 0x65a3f0
Dec 20 14:12:33 localhost NetworkManager: 
Dec 20 14:12:33 localhost NetworkManager: #8  0x000000000041f3b0 in main ()
Dec 20 14:12:33 localhost NetworkManager: No symbol table info available.
Dec 20 14:12:33 localhost NetworkManager: Current language:  auto; currently c
Dec 20 14:12:33 localhost NetworkManager: #0  0x00000034d140e0bf in waitpid () from /lib64/libpthread.so.0
Dec 20 14:12:33 localhost NetworkManager: The program is running.  Quit anyway (and detach it)? (y or n) [answered Y; input not from terminal]
Dec 20 14:12:33 localhost NetworkManager: ******************* END **********************************