F3307 device is not getting marked as ID_MM_CANDIDATE by udev



I have an Ericsson F3307 device which is showing up in udev, but is not marked as ID_MM_CANDIDATE. �Of course this messes up modem manager because it sets the device property to ttyACM0 instead of usb0.

�As shown below, other devices (eth0, etc) are getting marked. �Is this a problem that people have seen before?�Maybe it is a problem with my version of udev? 146?

-Jason


Script started on Tue Feb 22 11:05:01 2011
chronos@localhost: chronos@localhost / $ udevadm monitor
monitor will print the received events for:
UDEV - the event which udev sends out after rule processing
KERNEL - the kernel uevent

KERNEL[1298401513.279997] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1 (usb)
KERNEL[1298401513.284460] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0 (usb)
KERNEL[1298401513.284749] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.1 (usb)
KERNEL[1298401513.293330] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.1/tty/ttyACM0 (tty)
KERNEL[1298401513.302218] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.2 (usb)
KERNEL[1298401513.302646] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.3 (usb)
KERNEL[1298401513.309346] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.3/tty/ttyACM1 (tty)
KERNEL[1298401513.317190] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.4 (usb)
KERNEL[1298401513.317535] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.5 (usb)
KERNEL[1298401513.317890] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.6 (usb)
KERNEL[1298401513.320070] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.6/net/usb0 (net)
KERNEL[1298401513.320595] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.7 (usb)
KERNEL[1298401513.322251] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.8 (usb)
KERNEL[1298401513.322861] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.9 (usb)
KERNEL[1298401513.329335] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.9/tty/ttyACM2 (tty)
KERNEL[1298401513.337435] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.10 (usb)
UDEV �[1298401513.370095] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1 (usb)
UDEV �[1298401513.698720] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.4 (usb)
UDEV �[1298401513.700325] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.1 (usb)
UDEV �[1298401513.706221] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.6 (usb)
UDEV �[1298401513.721672] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.7 (usb)
UDEV �[1298401513.723010] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0 (usb)
UDEV �[1298401513.724578] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.9 (usb)
UDEV �[1298401513.736541] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.2 (usb)
UDEV �[1298401513.743250] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.5 (usb)
UDEV �[1298401513.744928] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.10 (usb)
UDEV �[1298401513.746188] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.8 (usb)
UDEV �[1298401513.750125] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.3 (usb)
UDEV �[1298401513.758499] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.1/tty/ttyACM0 (tty)
UDEV �[1298401513.765074] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.6/net/usb0 (net)
UDEV �[1298401513.771410] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.9/tty/ttyACM2 (tty)
UDEV �[1298401513.777619] add � � �/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.3/tty/ttyACM1 (tty)

chronos@localhost:/ chronos@localhost / $ udevadm info --export | grep _C 10 usb0
E: UDEV_LOG=3
E: DEVPATH=/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.6
E: DEVTYPE=usb_interface
E: DRIVER=cdc_ether
E: DEVICE=/proc/bus/usb/002/006
E: PRODUCT=bdb/1909/0
E: TYPE=2/0/0
E: INTERFACE=2/10/0
E: MODALIAS=usb:v0BDBp1909d0000dc02dsc00dp00ic02isc0Aip00

P: /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.6/net/usb0
E: UDEV_LOG=3
E: DEVPATH=/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.6/net/usb0
E: INTERFACE=usb0
E: IFINDEX=13

P: /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.7
E: UDEV_LOG=3
E: DEVPATH=/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.7
E: DEVTYPE=usb_interface
E: DRIVER=cdc_ether
E: DEVICE=/proc/bus/usb/002/006
E: PRODUCT=bdb/1909/0
E: TYPE=2/0/0

chronos@localhost:/ chronos@localhost / $ udevadm info --export | grep _C 10 eth0
P: /devices/pci0000:00/0000:00:1c.5/0000:09:00.0
E: UDEV_LOG=3
E: DEVPATH=/devices/pci0000:00/0000:00:1c.5/0000:09:00.0
E: DRIVER=tg3
E: PCI_CLASS=20000
E: PCI_ID=14E4:1680
E: PCI_SUBSYS_ID=1028:0432
E: PCI_SLOT_NAME=0000:09:00.0
E: MODALIAS=pci:v000014E4d00001680sv00001028sd00000432bc02sc00i00

P: /devices/pci0000:00/0000:00:1c.5/0000:09:00.0/net/eth0
E: UDEV_LOG=3
E: DEVPATH=/devices/pci0000:00/0000:00:1c.5/0000:09:00.0/net/eth0
E: INTERFACE= [01;31m [Keth0 [m [K
E: IFINDEX=6
E: ID_BUS=pci
E: ID_VENDOR_ID=0x14e4
E: ID_MODEL_ID=0x1680
E: ID_MM_CANDIDATE=1

P: /devices/pci0000:00/0000:00:1c.5/pci_bus/0000:09
E: UDEV_LOG=3
E: DEVPATH=/devices/pci0000:00/0000:00:1c.5/pci_bus/0000:09

2011-02-17T09:22:58.251045-08:00 localhost kernel: [ � 99.250047] usb 2-4: new high speed USB device using ehci_hcd and address 5
2011-02-17T09:22:58.367209-08:00 localhost kernel: [ � 99.366603] usb 2-4: New USB device found, idVendor=0bdb, idProduct=1909
2011-02-17T09:22:58.367234-08:00 localhost kernel: [ � 99.366609] usb 2-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
2011-02-17T09:22:58.367242-08:00 localhost kernel: [ � 99.366613] usb 2-4: Product: F3307
2011-02-17T09:22:58.367251-08:00 localhost kernel: [ � 99.366616] usb 2-4: Manufacturer: �F3307
2011-02-17T09:22:58.367258-08:00 localhost kernel: [ � 99.366619] usb 2-4: SerialNumber: 8444311594054030
2011-02-17T09:22:58.367265-08:00 localhost kernel: [ � 99.366751] usb 2-4: configuration #1 chosen from 2 choices
2011-02-17T09:22:58.372046-08:00 localhost kernel: [ � 99.371620] cdc_acm 2-4:1.1: ttyACM0: USB ACM device
2011-02-17T09:22:58.388046-08:00 localhost kernel: [ � 99.387083] cdc_acm 2-4:1.3: ttyACM1: USB ACM device
2011-02-17T09:22:58.406049-08:00 localhost kernel: [ � 99.405255] cdc_acm 2-4:1.9: ttyACM2: USB ACM device
2011-02-17T09:22:58.498952-08:00 localhost flimflamd[221]: usb1 {create} index 9 type 1 <ETHER>
2011-02-17T09:22:58.499102-08:00 localhost kernel: [ � 99.498828] usb1: register 'cdc_ether' at usb-0000:00:1d.7-4, CDC Ethernet Device, 02:80:37:ec:02:00
2011-02-17T09:22:58.502120-08:00 localhost kernel: [ � 99.501141] usbcore: registered new interface driver cdc_ether
2011-02-17T09:22:58.812836-08:00 localhost modem-manager[2274]: <info> (ttyACM0) opening serial port...
2011-02-17T09:22:58.834410-08:00 localhost modem-manager[2274]: <info> (ttyACM2) opening serial port...
2011-02-17T09:22:58.858410-08:00 localhost modem-manager[2274]: <info> (ttyACM1) opening serial port...
2011-02-17T09:22:58.882584-08:00 localhost modem-manager[2274]: <warn> plugin 'Ericsson MBM' claimed to support net/usb1 but couldn't: (0) Could not get modem product ID.
2011-02-17T09:23:03.803153-08:00 localhost modem-manager[2274]: <info> (ttyACM2) closing serial port...
2011-02-17T09:23:03.812640-08:00 localhost modem-manager[2274]: <info> (ttyACM2) serial port closed
2011-02-17T09:23:03.813277-08:00 localhost modem-manager[2274]: <info> (ttyACM1) closing serial port...
2011-02-17T09:23:03.820529-08:00 localhost modem-manager[2274]: <info> (ttyACM1) serial port closed
2011-02-17T09:23:03.822603-08:00 localhost modem-manager[2274]: <info> (ttyACM2) opening serial port...
2011-02-17T09:23:03.830852-08:00 localhost modem-manager[2274]: <info> (Ericsson MBM): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyACM2
2011-02-17T09:23:03.831571-08:00 localhost modem-manager[2274]: <info> (Ericsson MBM): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyACM1
2011-02-17T09:23:07.800369-08:00 localhost modem-manager[2274]: <info> (ttyACM0) closing serial port...
2011-02-17T09:23:07.808786-08:00 localhost modem-manager[2274]: <info> (ttyACM0) serial port closed
2011-02-17T09:23:07.809589-08:00 localhostOn Tue, Feb 22, 2011 at 1:02 PM, Jason Glasgow <jglasgow google com> wrote:
Jonas,

Thanks for the input.

I'm now looking at the F3307. �lsmod indicates that cdc_ether has been loaded, and ifconfig -a shows a usb1 network interface. �Yet, still the device property of the modem as reported by modem-manager is ttyACM0. (See attached).

I also see a warning from modem-manager that could be related:

2011-02-22T08:34:53.085836-08:00 localhost modem-manager[2501]: <warn> plugin 'Ericsson MBM' claimed to support net/usb0 but couldn't: (0) Could not get modem product ID.

I have also attached a file with debug messages.

In terms of priority, we are trying to make sure that the F3307 works in the next two weeks, though ideally this week. �I will be spending time looking at the issue. �Any help you can provide is appreciated -- but I understand that I didn't give you any lead time.

-Jason


On Tue, Feb 22, 2011 at 4:42 AM, Jonas Sj�st <jonas sjoquist ericsson com> wrote:
Jason,

We need some more time to be able to test and look at the current code, we are currently �somehow loaded with finishing a delivery.

I'll do my best to find some time so we can test and look into the latest code, do you have any deadline when you must have this ready?

Your expectation is correct, the device name should be usb[x]/wwan[x]. The ttyACM[x] is the name of the modem port. For the F3307, it could be that the VID/PID is missing in flimflam, for F55 you also need the cdc_ncm driver.

Thanks,
Jonas

________________________________

From: Jason Glasgow [mailto:jglasgow google com]
Sent: den 17 februari 2011 18:34
To: Jonas Sj�st
Cc: Elly Jones; Carl Nordbeck; Torgny Johansson
Subject: Trouble with F5521 and F3307


Jonas,

I know we skipped out Monday meeting this week, but it seems like interest in the Ericsson modems is picking up again, and to ensure that things are moving in the right direction I started testing our F3307 and F5521 modules again. �We are now actively working on the GSM support in Chrome OS and it would be great to see the Ericsson modems fully supported.

I ran into some problems though, as highlighted below.

* � � � A warning from modem manager about net/usb1. �This then causes our connection manager (flimflam) to fail because ttyACM2 is not the network device (usb1).
* � � � I am unable to enable the device (manually)
* � � � Power cycling the device does eventually allow me to enable the device and get status output from it. �The device registers on the network, but it still has the wrong "device": ttyACM0 not usb1

We are now running very close to the upstream modem manager. �Do you know are the F3307 and F5521 working for you on Chrome OS? �Do you have any hints to offer in debugging this problem? �Is my expectation that the device should be "usb1" not "ttyACM0" correct?

Thanks,
Jason�modem-manager[2274]: <info> (Ericsson MBM): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyACM0
2011-02-17T09:23:43.015898-08:00 localhost modem-manager[2274]: <info> (ttyACM2) closing serial port...
2011-02-17T09:23:43.025157-08:00 localhost modem-manager[2274]: <info> (ttyACM2) serial port closed
2011-02-17T09:23:43.028450-08:00 localhost flimflamd[221]: connman_inet_ifindex: SIOCGIFINDEX(ttyACM2): No such device
2011-02-17T09:23:43.028484-08:00 localhost flimflamd[221]: plugins/modemmgr.c:modem_create_device_task() Cannot create modem device

2011-02-17T09:24:14.278960-08:00 localhost modem-manager[2274]: <info> (ttyACM2) opening serial port...
2011-02-17T09:24:14.287937-08:00 localhost modem-manager[2274]: <info> Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
2011-02-17T09:24:20.000747-08:00 localhost modem-manager[2274]: <warn> timed out waiting for EMRDY response.
2011-02-17T09:24:23.004436-08:00 localhost modem-manager[2274]: <info> Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> disabled)
2011-02-17T09:24:23.004464-08:00 localhost modem-manager[2274]: <info> (ttyACM2) closing serial port...
2011-02-17T09:24:23.013918-08:00 localhost modem-manager[2274]: <info> (ttyACM2) serial port closed
2011-02-17T09:25:40.156525-08:00 localhost modem-manager[2274]: <info> (ttyACM2) opening serial port...
2011-02-17T09:25:40.164838-08:00 localhost modem-manager[2274]: <info> Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
2011-02-17T09:25:45.005663-08:00 localhost modem-manager[2274]: <warn> timed out waiting for EMRDY response.
2011-02-17T09:25:48.004313-08:00 localhost modem-manager[2274]: <info> Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> disabled)
2011-02-17T09:25:48.004342-08:00 localhost modem-manager[2274]: <info> (ttyACM2) closing serial port...
2011-02-17T09:25:48.015064-08:00 localhost modem-manager[2274]: <info> (ttyACM2) serial port closed

Modem /org/freedesktop/ModemManager/Modems/1 (manager org.freedesktop.ModemManager):
�GetStatus:
� �operator_code: 310410
� �registration_status: 1
� �operator_name: AT&T
� �signal_quality: 40
�GetInfo:
� �Manufacturer: Ericsson
� �Modem: Ericsson Mobile Platform
� �Version: 2010-09-21 10:38 Off.Bld CRH1090155_47_R1A028 ACCESS_EXPRESS_CARD_CHW
�Props:
� �Device: ttyACM0
� �DeviceIdentifier: b685a520417abd8cb823111fa77e558885712bb6
� �MasterDevice: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4
� �Driver: cdc_acm
� �Type: 1
� �Enabled: true
� �EquipmentIdentifier: 004401700406917
� �UnlockRetries: 0
� �IpMethod: 2
� �State: 60
� �SimIdentifier: 0ee41cea6d016388a2db4a02e2f320c6b5f16b39
� �SupportedBands: 0
� �SupportedModes: 0
� �AllowedMode: 0
� �AccessTechnology: 6
�GSM:
� �IMSI: 310410321922484
� �Registration:
� � �Status: 1
� � �OperatorCode: 310410
� � �OperatorName: AT&T
� �SignalQuality: 40






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