Description of problem: This is just to let Fedora kernel engineering to know that cdc-acm.ko seems to be broken on F12 Beta making it impossible, e.g., to use a phone to connect to Internet via USB cable. When connected on F11 no errors in dmesg but now with F12 Beta one gets: usb 1-8: new high speed USB device using ehci_hcd and address 14 usb 1-8: New USB device found, idVendor=0421, idProduct=010e usb 1-8: New USB device strings: Mfr=1, Product=2, SerialNumber=3 usb 1-8: Product: Nokia E75 usb 1-8: Manufacturer: Nokia usb 1-8: SerialNumber: 1234567890 usb 1-8: configuration #1 chosen from 1 choice cdc_acm 1-8:1.1: ttyACM0: USB ACM device usb 1-8: bad CDC descriptors usb 1-8: bad CDC descriptors cdc_phonet: probe of 1-8:1.10 failed with error -22 Version-Release number of selected component (if applicable): kernel-2.6.31.1-56.fc12.i686 Additional info: Probably upstream issue is http://bugzilla.kernel.org/show_bug.cgi?id=14163 . I will try to test few kernel.org kernels to see what is going on here.
I've now tested kernel.org kernels 2.6.28.8, 2.6.29.6, 2.6.30.9, and 2.6.31.3 on F12 Beta and with them no errors are printed in dmesg when connecting the phone. With latest kernel for F12 I see the following errors in syslog when trying to use cdc-acm so this looks like a NetworkManager issue: Oct 26 23:13:16 localhost NetworkManager: <info> Activation (ttyACM0) starting connection 'GSM Default' Oct 26 23:13:16 localhost NetworkManager: <info> (ttyACM0): device state change: 3 -> 4 (reason 0) Oct 26 23:13:16 localhost NetworkManager: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled... Oct 26 23:13:16 localhost NetworkManager: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started... Oct 26 23:13:16 localhost NetworkManager: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete. Oct 26 23:13:16 localhost modem-manager: (ttyACM0) opening serial device... Oct 26 23:13:16 localhost NetworkManager: <WARN> stage1_prepare_done(): GSM modem connection failed: Operation not supported Oct 26 23:13:16 localhost NetworkManager: <info> (ttyACM0): device state change: 4 -> 9 (reason 1) Oct 26 23:13:16 localhost NetworkManager: <info> Marking connection 'GSM Default' invalid. Oct 26 23:13:16 localhost NetworkManager: <info> Activation (ttyACM0) failed. Oct 26 23:13:16 localhost NetworkManager: <info> (ttyACM0): device state change: 9 -> 3 (reason 0) Oct 26 23:13:16 localhost NetworkManager: <info> (ttyACM0): deactivating device (reason: 0). Oct 26 23:13:16 localhost NetworkManager: flush_routes: assertion `iface_idx >= 0' failed Oct 26 23:13:16 localhost NetworkManager: flush_addresses: assertion `iface_idx >= 0' failed Oct 26 23:13:16 localhost modem-manager: (ttyACM0) closing serial device... Oct 26 23:13:16 localhost NetworkManager: <info> Policy set 'System homenet (eth1)' (eth1) as default for routing and DNS.
What phone? Can you stop NM, killall -TERM modem-manager, then as root: modem-manager --debug then start NM, and try to connect like you did before. Paste the MM terminal output into this bug. Thanks!
Nokia E75, this worked with F11. Now from modem-manager: ** Message: Loaded plugin Nokia ** Message: (ttyACM0) opening serial device... ** (modem-manager:2524): DEBUG: (ttyACM0): probe requested by plugin 'Nokia' ** (modem-manager:2524): DEBUG: (ttyACM0): --> 'AT+GCAP<CR>' ** (modem-manager:2524): DEBUG: (ttyACM0): <-- 'AT+GCAP<CR><CR><LF>+GCAP: +CGSM,+DS,+W<CR><LF><CR><LF>OK<CR><LF>' ** Message: (ttyACM0) closing serial device... ** Message: (Nokia): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8 claimed port ttyACM0 ** (modem-manager:2524): DEBUG: Added modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8 ** (modem-manager:2524): DEBUG: Exported modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8 as /org/freedesktop/ModemManager/Modems/0 ** Message: (ttyACM0) opening serial device... ** (modem-manager:2524): DEBUG: (ttyACM0): --> 'ATZ E0 V1 X4 &C1 +CMEE=1<CR>' ** (modem-manager:2524): DEBUG: (ttyACM0): <-- 'ATZ E0 V1 X4 &C1 +CMEE=1<CR><CR><LF>OK<CR><LF>' ** (modem-manager:2524): DEBUG: (ttyACM0): --> 'ATE0 +CMEE=1<CR>' ** (modem-manager:2524): DEBUG: (ttyACM0): <-- 'ATE0 +CMEE=1<CR><CR><LF>OK<CR><LF>' ** (modem-manager:2524): DEBUG: (ttyACM0): --> 'AT+CREG=0<CR>' ** (modem-manager:2524): DEBUG: (ttyACM0): <-- '<CR><LF>OK<CR><LF>' ** (modem-manager:2524): DEBUG: (ttyACM0): --> 'AT+CFUN=1<CR>' ** (modem-manager:2524): DEBUG: (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>' ** (modem-manager:2524): DEBUG: Got failure code 4: Operation not supported ** (modem-manager:2524): DEBUG: (ttyACM0): --> 'AT+CFUN=1<CR>' ** (modem-manager:2524): DEBUG: (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>' ** (modem-manager:2524): DEBUG: Got failure code 4: Operation not supported ** Message: (ttyACM0) closing serial device...
Thanks, that nails it. Fixed upstream by: http://cgit.freedesktop.org/ModemManager/ModemManager/commit/?id=2887a50b232714702c0eb9009c32d9a6b850888b and http://cgit.freedesktop.org/ModemManager/ModemManager/commit/?id=3d86a3e89a18ccd98b9f65b389f70ba19f100004 will probably get into a 0-day update for F12.
This bug appears to have been reported against 'rawhide' during the Fedora 12 development cycle. Changing version to '12'. More information and reason for this action is here: http://fedoraproject.org/wiki/BugZappers/HouseKeeping
ModemManager-0.2-4.20091105.fc12.i686 NetworkManager-0.7.996-7.git20091113.fc12.i686 from updates-testing seem to fix this! Thanks a lot!