-
Help with a 3G Modem
Hello everyone
I am working on an embedded system that connects to the Internet using the modem Sony Ericsson MD300 (USB) with a 3G connection. My problem is this: if I plug the modem with the system running, it is recognized, mounted on /dev/ttyACM0 and works perfectly. However, if the system starts with the modem already connected, it is not recognized neither mounted. Can anyone help me on how to even be recognized on startup, or how I can ride it if it is connected when the system starts.
Appreciate the assistance and sorry for the bad English.
-
If your system has usbserial compiled as module usbserial.ko, you could try removing the module, waiting a few seconds, then reinstalling the module. This may force the usb bus to re-read the 3G device and create the ttyACM0 device.
To find out if usbserial is compiled as a module:
Plug in your 3G device and get it working.
Enter:
lsmod | grep usb
and look for:
usbserial 33489 1
and see if usbserial appears in the output as shown (the numbers may be different). If it does, usbserial is a module. If the last number is greater than zero, the module is being used by the 3G device.
Now reboot the system with the 3G device connected, and after the boot, use the lsmod command again and see if the usbserial module is loaded or not, and if it is loaded, is it in-use?
(Last number > 0). Also see if there is a /dev/ttyACM0 device:
ls -l /dev/ttyACM0
If the module is not loaded, go straight to loading it. If the module is loaded, but not in use (last number=0), and no ttyACM0 device exists, the module can removed from memory using one of these commands:
/sbin/modprobe -r usbserial
or, on older systems:
rmmod usbserial
Now wait a few seconds, then load (or reload) the module:
/sbin/modprobe usbserial
Finally, see if the /dev/ttyACM0 device now exists. if it does, your device should now work. To make this happen on every boot, you can add a few lines to your system's /etc/rc.local file to reload the module at the end of each boot. As in:
if [ ! -c /dev/ttyACM0 ]; then
lsmod | grep "usbserial" | grep " 0" > /dev/null
if [ $? -eq 0 ]; then
/sbin/modprobe -r usbserial
sleep 5
/sbin/modprobe usbserial
fi
fi
-
Hi
My experience with usb 3G modems is that they run in two different modes, one for device installation and one for 3G functionality.
Because of this, to use the modem's 3G functionality, mode switching is required. I don't have a great deal of experience with this but googling usb_modeswitch should get you started, it's the first link in the results page (I'd posted a direct link but can't until I've made 15 posts).
Aside from that, there may be tools available that are suited to your purposes. I know that the version of NetworkManager packaged with Ubuntu 10.04 has 3G modem and switching functionality built-in, but my guess is that if you're running an embedded system you're not going to want to be playing with NetworkManager :)
Might be helpful to tell us what Distro you are running too :)
Good luck!
-
That's right, it could be in CD-Rom mode on boot and just doing an 'eject -s /dev/{scd0 or sr0, or...} will put it into tty mode.
-
Hello again
Let's start slowly. First, I used the command lsmod | grep usb (as root) and it did not return anything, with or without the modem, with the modem connected since the boot or not. The distribution that I'm using, to be honest, I do not know what it is. I just know it's not Debian. It is a simplified version that came with the hardware (ARM9 200Mhz with 32 Mb RAM and 64 MB Flash Disk). Neither the directory /sbin quoted above it has. Below is a listing of the / bin directory:
Code:
addgroup crontab ftpd ls pwd telnetd
adduser date gpioctl mkdir rm tip
amgrd delgroup grep mke2fs rmdir touch
bash deluser gunzip mkfs.jffs2 scp true
boa df gzip mknod sed umount
boa_indexer dhcpcd hostname mktemp setuart update
busybox dhrystone inetd more sh usleep
cat discard init mount sleep version
chat dmesg iptables mv snmpd vi
chgrp echo iwconfig netstat sram zcat
chmod egrep iwlist ntpdate sshd
chown erase iwpriv pidof stty
cp false kill ping su
cpu fgrep ln pppd sync
cron ftp login ps tar
-
Well lets find some things about your system. Enter these commands and post the output. Some may not work, that's ok:
This will tell us Linux version and processor family info
uname -a
CPU Specific details:
cat /proc/cpuinfo
This will list system bus devices (maybe):
lspci -v
Do these 4 commands three times, once before connecting the 3G device, once right after connecting the device, and a third time after connecting the device to the Internet:
ps {-e or -ef or whatever gives the most info, try 'ps --help'}
lsusb -v
dmesg
cat /var/log/messages {or wherever messages lives try 'find / -name messages', or 'locate message'}
The differences found in these commands will tells what happens to the system when the device works correctly. Drivers that are loaded, devices created, programs that start, etc. Specifically, we are looking for the changes that concern the 3G device, CD devices, and networking.
Next, reboot the system with the 3G device connected, and after the boot, do the above commands again before either attempting to use, or unplugging the device.
We should be able to determine what is happening when the device works, that is not happening when booting with the device connected. From there, hopefully, we can just force the action that is failing, or not starting to happen on boot.
Now, all that said, these embedded systems are usually trimmed to the bare minimum, so hopefully, the Linux has enough installed commands to determine the source of the problem.
Good luck.
-
Well, let's to the command's results:
uname -a
sh: uname: command not found - :(
cat /proc/cpuinfo
Code:
Processor : ARM920Tid(wb) rev 0 (v4l)
BogoMIPS : 89.70
Features : swp half thumb
CPU implementer : 0x41
CPU architecture: 4T
CPU variant : 0x1
CPU part : 0x920
CPU revision : 0
Cache type : write-back
Cache clean : cp15 c7 ops
Cache lockdown : format A
Cache format : Harvard
I size : 16384
I assoc : 64
I line length : 32
I sets : 8
D size : 16384
D assoc : 64
D line length : 32
D sets : 8
Hardware : Artila M508
Revision : 0000
Serial : 0000000000000000
lspci -v
sh: lspci: command not found
The four commands:
- without the modem:
ps -e:
Code:
PID Uid VmSize Stat Command
1 root 444 S /bin/init
2 root SWN [ksoftirqd/0]
3 root SW< [events/0]
4 root SW< [khelper]
5 root SW< [kthread]
10 root SW< [kblockd/0]
13 root SW< [khubd]
60 root SW [pdflush]
61 root SW [pdflush]
63 root SW< [aio/0]
62 root SW [kswapd0]
678 root SW [mtdblockd]
722 root SWN [jffs2_gcd_mtd4]
747 root SWN [jffs2_gcd_mtd5]
749 root 392 S < /disk/udevd --daemon
767 root 1024 S /bin/sh
768 root 496 S /bin/inetd
769 root 516 S /bin/boa
770 root 648 S /sbin/syslogd -n
771 root 648 S /sbin/klogd -n
776 root 1516 S /bin/sshd -i
777 root 1020 S -sh
778 root 652 R ps -e
lsusb -v
sh: lsusb: command not found
dmesg:
Code:
ep 15 19:10:32 CST 2008
CPU: ARM920Tid(wb) [41129200] revision 0 (ARMv4T)
Machine: Artila M508
Memory policy: ECC disabled, Data cache writeback
Clocks: CPU 179 MHz, master 59 MHz, main 18.432 MHz
On node 0 totalpages: 16384
DMA zone: 16384 pages, LIFO batch:7
Normal zone: 0 pages, LIFO batch:1
HighMem zone: 0 pages, LIFO batch:1
CPU0: D VIVT write-back cache
CPU0: I cache: 16384 bytes, associativity 64, 32 byte lines, 8 sets
CPU0: D cache: 16384 bytes, associativity 64, 32 byte lines, 8 sets
Built 1 zonelists
Kernel command line: mem=64M console=ttyS0,115200 lel=1 initrd=0x20800000,8192000 ramdisk_size=15360 root=/dev/ram0 rw mtdparts=phys_mapped_flash:128k(loader)ro,128k(env)ro,1408k(linux)ro,3072k(ramdisk)ro,-(userdisk)
AT91: 128 gpio irqs in 4 banks
PID hash table entries: 512 (order: 9, 8192 bytes)
Console: colour dummy device 80x30
Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
Memory: 64MB = 64MB total
Memory: 53760KB available (2343K code, 528K data, 104K init)
Calibrating delay loop... 89.70 BogoMIPS (lpj=448512)
Mount-cache hash table entries: 512
CPU: Testing write buffer coherency: ok
checking if image is initramfs...it isn't (no cpio magic); looks like an initrd
Freeing initrd memory: 8000K
NET: Registered protocol family 16
SCSI subsystem initialized
usbcore: registered new driver usbfs
usbcore: registered new driver hub
NetWinder Floating Point Emulator V0.97 (extended precision)
JFFS2 version 2.2. (NAND) (C) 2001-2003 Red Hat, Inc.
Initializing Cryptographic API
Matrix500 RTC driver.
Matrix500 GPIO Driver Ver 1.06 Loaded.
AT91 Watchdog Timer enabled (5 seconds)
ttyS0 at MMIO 0xfefff200 (irq = 1) is a AT91_SERIAL
ttyS1 at MMIO 0xfefc0000 (irq = 6) is a AT91_SERIAL
ttyS2 at MMIO 0xfefc4000 (irq = 7) is a AT91_SERIAL
ttyS3 at MMIO 0xfefc8000 (irq = 8) is a AT91_SERIAL
ttyS4 at MMIO 0xfefcc000 (irq = 9) is a AT91_SERIAL
io scheduler noop registered
io scheduler anticipatory registered
RAMDISK driver initialized: 16 RAM disks of 15360K size 1024 blocksize
PPP generic driver version 2.4.2
PPP BSD Compression module registered
NET: Registered protocol family 24
tun: Universal TUN/TAP device driver, 1.6
tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
Badness in local_bh_enable at kernel/softirq.c:140
Badness in local_bh_enable at kernel/softirq.c:140
Badness in local_bh_enable at kernel/softirq.c:140
eth0: AT91 ethernet at 0xfefbc000 int=24 10-HalfDuplex (00:13:48:00:5d:c0)
eth0: Davicom 9161AE PHY (Copper)
dm9000 Ethernet Driver
Artila:probing 0x01000000 at 0x10000000
phys_mapped_flash: Found 1 x16 devices at 0x0 in 16-bit bank
Intel/Sharp Extended Query Table at 0x010A
Using buffer write method
cfi_cmdset_0001: Erase suspend on write enabled
0: offset=0x0,size=0x20000,blocks=127
1: offset=0xfe0000,size=0x8000,blocks=4
5 cmdlinepart partitions found on MTD device phys_mapped_flash
Artila:using command line partition definition
Creating 5 MTD partitions on "phys_mapped_flash":
0x00000000-0x00020000 : "loader"
0x00020000-0x00040000 : "env"
0x00040000-0x001a0000 : "linux"
0x001a0000-0x004a0000 : "ramdisk"
0x004a0000-0x01000000 : "userdisk"
Artila:probing 0x01000000 at 0x30000000
phys_mapped_flash#2: Found 1 x16 devices at 0x0 in 16-bit bank
Intel/Sharp Extended Query Table at 0x010A
Using buffer write method
cfi_cmdset_0001: Erase suspend on write enabled
0: offset=0x0,size=0x20000,blocks=127
1: offset=0xfe0000,size=0x8000,blocks=4
Artila:using static partition definition
Creating 1 MTD partitions on "phys_mapped_flash#2":
0x00000000-0x01000000 : "FileSystem"
Generic platform RAM MTD, (c) 2004 Simtec Electronics
block2mtd: version $Revision: 1.28 $
at91rm9200-ohci at91rm9200-ohci: starting AT91RM9200 OHCI USB Controller
at91rm9200-ohci at91rm9200-ohci: AT91RM9200 OHCI
at91rm9200-ohci at91rm9200-ohci: new USB bus registered, assigned bus number 1
at91rm9200-ohci at91rm9200-ohci: irq 23, io mem 0x00300000
at91rm9200-ohci at91rm9200-ohci: resetting from state 'reset', control = 0x0
at91rm9200-ohci at91rm9200-ohci: OHCI controller state
at91rm9200-ohci at91rm9200-ohci: OHCI 1.0, NO legacy support registers
at91rm9200-ohci at91rm9200-ohci: control 0x083 HCFS=operational CBSR=3
at91rm9200-ohci at91rm9200-ohci: cmdstatus 0x00000 SOC=0
at91rm9200-ohci at91rm9200-ohci: intrstatus 0x00000044 RHSC SF
at91rm9200-ohci at91rm9200-ohci: intrenable 0x8000001a MIE UE RD WDH
at91rm9200-ohci at91rm9200-ohci: fminterval a7782edf
at91rm9200-ohci at91rm9200-ohci: hcca frame #0005
at91rm9200-ohci at91rm9200-ohci: roothub.a 02000202 POTPGT=2 NPS NDP=2(2)
at91rm9200-ohci at91rm9200-ohci: roothub.b 00000000 PPCM=0000 DR=0000
at91rm9200-ohci at91rm9200-ohci: roothub.status 00008000 DRWE
at91rm9200-ohci at91rm9200-ohci: roothub.portstatus [0] 0x00000100 PPS
at91rm9200-ohci at91rm9200-ohci: roothub.portstatus [1] 0x00000100 PPS
at91rm9200-ohci at91rm9200-ohci: created debug files
usb usb1: default language 0x0409
usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
usb usb1: Product: AT91RM9200 OHCI
usb usb1: Manufacturer: Linux 2.6.14-M5 ohci_hcd
usb usb1: SerialNumber: at91rm9200
usb usb1: hotplug
usb usb1: adding 1-0:1.0 (config #1, interface 0)
usb 1-0:1.0: hotplug
hub 1-0:1.0: usb_probe_interface
hub 1-0:1.0: usb_probe_interface - Device: ID 0000:0000
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 2 ports detected
hub 1-0:1.0: standalone hub
hub 1-0:1.0: no power switching (usb 1.0)
hub 1-0:1.0: global over-current protection
hub 1-0:1.0: power on to power good time: 4ms
hub 1-0:1.0: local power source is good
hub 1-0:1.0: no over-current condition exists
hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0000
usbcore: registered new driver cdc_acm
drivers/usb/class/cdc-acm.c: v0.23:USB Abstract Control Model driver for USB modems and ISDN adapters
usbcore: registered new driver usblp
drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
Initializing USB Mass Storage driver...
usbcore: registered new driver usb-storage
USB Mass Storage support registered.
rtusb init ====>
usbcore: registered new driver rt73
usbcore: registered new driver usbserial
drivers/usb/serial/usb-serial.c: USB Serial support registered for Generic
usbcore: registered new driver usbserial_generic
drivers/usb/serial/usb-serial.c: USB Serial Driver core v2.0
drivers/usb/serial/usb-serial.c: USB Serial support registered for PL-2303
usbcore: registered new driver pl2303
drivers/usb/serial/pl2303.c: Prolific PL2303 USB to serial adaptor driver v0.12
mice: PS/2 mouse device common for all mice
i2c /dev entries driver
Found AT91 i2c
RTC found.
AT91 MMC: 4 wire bus mode not supported by this driver - using 1 wire
AT91RM9200 MCI initialized
NET: Registered protocol family 2
IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
TCP established hash table entries: 4096 (order: 2, 16384 bytes)
TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
TCP: Hash tables configured (established 4096 bind 4096)
TCP reno registered
ip_conntrack version 2.3 (512 buckets, 4096 max) - 240 bytes per conntrack
ctnetlink v0.90: registering with nfnetlink.
ip_tables: (C) 2000-2002 Netfilter core team
ipt_recent v0.3.1: Stephen Frost <sfrost@snowman.net>. <link removed, or I can't post>
arp_tables: (C) 2002 David S. Miller
TCP bic registered
Netfilter messages via NETLINK v0.30.
NET: Registered protocol family 1
NET: Registered protocol family 17
Bridge firewalling registered
ieee80211_crypt: registered algorithm 'NULL'
ieee80211_crypt: registered algorithm 'WEP'
RAMDISK: Compressed image found at block 0
VFS: Mounted root (ext2 filesystem).
Freeing init memory: 104K
eth0: Link down.
eth0: Link now 100-FullDuplex
cat /var/log/messages
Code:
<46>Mar 13 14:42:35 syslogd started: BusyBox v1.00 (2008.08.12-08:07+0000)
<14>Mar 13 14:42:35 kernel: Memory: 64MB = 64MB total
<13>Mar 13 14:42:35 kernel: Memory: 53760KB available (2343K code, 528K data, 104K init)
<15>Mar 13 14:42:35 kernel: Calibrating delay loop... 89.70 BogoMIPS (lpj=448512)
<12>Mar 13 14:42:35 kernel: Mount-cache hash table entries: 512
<14>Mar 13 14:42:35 kernel: CPU: Testing write buffer coherency: ok
<14>Mar 13 14:42:35 kernel: checking if image is initramfs...it isn't (no cpio magic); looks like an initrd
<14>Mar 13 14:42:35 kernel: Freeing initrd memory: 8000K
<14>Mar 13 14:42:35 kernel: NET: Registered protocol family 16
<13>Mar 13 14:42:35 kernel: SCSI subsystem initialized
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver usbfs
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver hub
<12>Mar 13 14:42:35 kernel: NetWinder Floating Point Emulator V0.97 (extended precision)
<14>Mar 13 14:42:35 kernel: JFFS2 version 2.2. (NAND) (C) 2001-2003 Red Hat, Inc.
<14>Mar 13 14:42:35 kernel: Initializing Cryptographic API
<14>Mar 13 14:42:35 kernel: Matrix500 RTC driver.
<12>Mar 13 14:42:35 kernel: Matrix500 GPIO Driver Ver 1.06 Loaded.
<12>Mar 13 14:42:35 kernel: AT91 Watchdog Timer enabled (5 seconds)
<14>Mar 13 14:42:35 kernel: ttyS0 at MMIO 0xfefff200 (irq = 1) is a AT91_SERIAL
<14>Mar 13 14:42:35 kernel: ttyS1 at MMIO 0xfefc0000 (irq = 6) is a AT91_SERIAL
<14>Mar 13 14:42:35 kernel: ttyS2 at MMIO 0xfefc4000 (irq = 7) is a AT91_SERIAL
<14>Mar 13 14:42:35 kernel: ttyS3 at MMIO 0xfefc8000 (irq = 8) is a AT91_SERIAL
<14>Mar 13 14:42:35 kernel: ttyS4 at MMIO 0xfefcc000 (irq = 9) is a AT91_SERIAL
<14>Mar 13 14:42:35 kernel: io scheduler noop registered
<14>Mar 13 14:42:35 kernel: io scheduler anticipatory registered
<12>Mar 13 14:42:35 kernel: RAMDISK driver initialized: 16 RAM disks of 15360K size 1024 blocksize
<14>Mar 13 14:42:35 kernel: PPP generic driver version 2.4.2
<14>Mar 13 14:42:35 kernel: PPP BSD Compression module registered
<14>Mar 13 14:42:35 kernel: NET: Registered protocol family 24
<14>Mar 13 14:42:35 kernel: tun: Universal TUN/TAP device driver, 1.6
<14>Mar 13 14:42:35 kernel: tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
<12>Mar 13 14:42:35 kernel: Badness in local_bh_enable at kernel/softirq.c:140
<12>Mar 13 14:42:35 last message repeated 2 time(s)
<14>Mar 13 14:42:35 kernel: eth0: AT91 ethernet at 0xfefbc000 int=24 10-HalfDuplex (00:13:48:00:5d:c0)
<14>Mar 13 14:42:35 kernel: eth0: Davicom 9161AE PHY (Copper)
<14>Mar 13 14:42:35 kernel: dm9000 Ethernet Driver
<13>Mar 13 14:42:35 kernel: Artila:probing 0x01000000 at 0x10000000
<14>Mar 13 14:42:35 kernel: phys_mapped_flash: Found 1 x16 devices at 0x0 in 16-bit bank
<12>Mar 13 14:42:35 kernel: Intel/Sharp Extended Query Table at 0x010A
<14>Mar 13 14:42:35 kernel: Using buffer write method
<13>Mar 13 14:42:35 kernel: cfi_cmdset_0001: Erase suspend on write enabled
<15>Mar 13 14:42:35 kernel: 0: offset=0x0,size=0x20000,blocks=127
<15>Mar 13 14:42:35 kernel: 1: offset=0xfe0000,size=0x8000,blocks=4
<13>Mar 13 14:42:35 kernel: 5 cmdlinepart partitions found on MTD device phys_mapped_flash
<13>Mar 13 14:42:35 kernel: Artila:using command line partition definition
<13>Mar 13 14:42:35 kernel: Creating 5 MTD partitions on "phys_mapped_flash":
<13>Mar 13 14:42:35 kernel: 0x00000000-0x00020000 : "loader"
<13>Mar 13 14:42:35 kernel: 0x00020000-0x00040000 : "env"
<13>Mar 13 14:42:35 kernel: 0x00040000-0x001a0000 : "linux"
<13>Mar 13 14:42:35 kernel: 0x001a0000-0x004a0000 : "ramdisk"
<13>Mar 13 14:42:35 kernel: 0x004a0000-0x01000000 : "userdisk"
<13>Mar 13 14:42:35 kernel: Artila:probing 0x01000000 at 0x30000000
<14>Mar 13 14:42:35 kernel: phys_mapped_flash#2: Found 1 x16 devices at 0x0 in 16-bit bank
<12>Mar 13 14:42:35 kernel: Intel/Sharp Extended Query Table at 0x010A
<14>Mar 13 14:42:35 kernel: Using buffer write method
<13>Mar 13 14:42:35 kernel: cfi_cmdset_0001: Erase suspend on write enabled
<15>Mar 13 14:42:35 kernel: 0: offset=0x0,size=0x20000,blocks=127
<15>Mar 13 14:42:35 kernel: 1: offset=0xfe0000,size=0x8000,blocks=4
<13>Mar 13 14:42:35 kernel: Artila:using static partition definition
<13>Mar 13 14:42:35 kernel: Creating 1 MTD partitions on "phys_mapped_flash#2":
<13>Mar 13 14:42:35 kernel: 0x00000000-0x01000000 : "FileSystem"
<12>Mar 13 14:42:35 kernel: Generic platform RAM MTD, (c) 2004 Simtec Electronics
<14>Mar 13 14:42:35 kernel: block2mtd: version $Revision: 1.28 $
<15>Mar 13 14:42:35 kernel: Controller
<14>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: AT91RM9200 OHCI
<14>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: new USB bus registered, assigned bus number 1
<14>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: irq 23, io mem 0x00300000
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: resetting from state 'reset', control = 0x0
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: OHCI controller state
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: OHCI 1.0, NO legacy support registers
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: control 0x083 HCFS=operational CBSR=3
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: cmdstatus 0x00000 SOC=0
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: intrstatus 0x00000044 RHSC SF
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: intrenable 0x8000001a MIE UE RD WDH
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: fminterval a7782edf
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: hcca frame #0005
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: roothub.a 02000202 POTPGT=2 NPS NDP=2(2)
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: roothub.b 00000000 PPCM=0000 DR=0000
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: roothub.status 00008000 DRWE
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: roothub.portstatus [0] 0x00000100 PPS
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: roothub.portstatus [1] 0x00000100 PPS
<15>Mar 13 14:42:35 kernel: at91rm9200-ohci at91rm9200-ohci: created debug files
<15>Mar 13 14:42:35 kernel: usb usb1: default language 0x0409
<15>Mar 13 14:42:35 kernel: usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
<14>Mar 13 14:42:35 kernel: usb usb1: Product: AT91RM9200 OHCI
<14>Mar 13 14:42:35 kernel: usb usb1: Manufacturer: Linux 2.6.14-M5 ohci_hcd
<14>Mar 13 14:42:35 kernel: usb usb1: SerialNumber: at91rm9200
<15>Mar 13 14:42:35 kernel: usb usb1: hotplug
<15>Mar 13 14:42:35 kernel: usb usb1: adding 1-0:1.0 (config #1, interface 0)
<15>Mar 13 14:42:35 kernel: usb 1-0:1.0: hotplug
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: usb_probe_interface
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: usb_probe_interface - Device: ID 0000:0000
<14>Mar 13 14:42:35 kernel: hub 1-0:1.0: USB hub found
<14>Mar 13 14:42:35 kernel: hub 1-0:1.0: 2 ports detected
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: standalone hub
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: no power switching (usb 1.0)
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: global over-current protection
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: power on to power good time: 4ms
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: local power source is good
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: no over-current condition exists
<15>Mar 13 14:42:35 kernel: hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0000
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver cdc_acm
<14>Mar 13 14:42:35 kernel: drivers/usb/class/cdc-acm.c: v0.23:USB Abstract Control Model driver for USB modems and ISDN adapters
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver usblp
<14>Mar 13 14:42:35 kernel: drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
<14>Mar 13 14:42:35 kernel: Initializing USB Mass Storage driver...
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver usb-storage
<14>Mar 13 14:42:35 kernel: USB Mass Storage support registered.
<12>Mar 13 14:42:35 kernel: rtusb init ====>
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver rt73
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver usbserial
<14>Mar 13 14:42:35 kernel: drivers/usb/serial/usb-serial.c: USB Serial support registered for Generic
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver usbserial_generic
<14>Mar 13 14:42:35 kernel: drivers/usb/serial/usb-serial.c: USB Serial Driver core v2.0
<14>Mar 13 14:42:35 kernel: drivers/usb/serial/usb-serial.c: USB Serial support registered for PL-2303
<14>Mar 13 14:42:35 kernel: usbcore: registered new driver pl2303
<14>Mar 13 14:42:35 kernel: drivers/usb/serial/pl2303.c: Prolific PL2303 USB to serial adaptor driver v0.12
<14>Mar 13 14:42:35 kernel: mice: PS/2 mouse device common for all mice
<14>Mar 13 14:42:35 kernel: i2c /dev entries driver
<14>Mar 13 14:42:35 kernel: Found AT91 i2c
<12>Mar 13 14:42:35 kernel: RTC found.
<12>Mar 13 14:42:35 kernel: AT91 MMC: 4 wire bus mode not supported by this driver - using 1 wire
<14>Mar 13 14:42:35 kernel: AT91RM9200 MCI initialized
<14>Mar 13 14:42:35 kernel: NET: Registered protocol family 2
<12>Mar 13 14:42:35 kernel: IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
<12>Mar 13 14:42:35 kernel: TCP established hash table entries: 4096 (order: 2, 16384 bytes)
<12>Mar 13 14:42:35 kernel: TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
<14>Mar 13 14:42:35 kernel: TCP: Hash tables configured (established 4096 bind 4096)
<14>Mar 13 14:42:35 kernel: TCP reno registered
<12>Mar 13 14:42:35 kernel: ip_conntrack version 2.3 (512 buckets, 4096 max) - 240 bytes per conntrack
<12>Mar 13 14:42:35 kernel: ctnetlink v0.90: registering with nfnetlink.
<12>Mar 13 14:42:35 kernel: ip_tables: (C) 2000-2002 Netfilter core team
<14>Mar 13 14:42:35 kernel: ipt_recent v0.3.1: Stephen Frost <sfrost@snowman.net>. <link removed or I can't post>
<12>Mar 13 14:42:35 kernel: arp_tables: (C) 2002 David S. Miller
<14>Mar 13 14:42:35 kernel: TCP bic registered
<12>Mar 13 14:42:35 kernel: Netfilter messages via NETLINK v0.30.
<14>Mar 13 14:42:35 kernel: NET: Registered protocol family 1
<14>Mar 13 14:42:35 kernel: NET: Registered protocol family 17
<13>Mar 13 14:42:35 kernel: Bridge firewalling registered
<15>Mar 13 14:42:35 kernel: ieee80211_crypt: registered algorithm 'NULL'
<15>Mar 13 14:42:35 kernel: ieee80211_crypt: registered algorithm 'WEP'
<13>Mar 13 14:42:35 kernel: RAMDISK: Compressed image found at block 0
<12>Mar 13 14:42:35 kernel: VFS: Mounted root (ext2 filesystem).
<14>Mar 13 14:42:35 kernel: >Freeing init memory: 104K
<14>Mar 13 14:42:35 kernel: eth0: Link down.
<14>Mar 13 14:46:31 kernel: eth0: Link now 100-FullDuplex
<38>Mar 13 14:46:32 sshd[776]: WARNING: /etc/config/ssh/moduli does not exist, using old modulus
<38>Mar 13 14:46:32 sshd[776]: Accepted password for root from 192.168.2.100 port 55056 ssh2
<33>Mar 13 14:46:32 sshd[776]: WARNING: currently everything is run as root on an embedded system :WARNING
<38>Mar 13 14:46:32 sshd[777]: wtmp_write: problem writing /var/log/wtmp: No such file or directory
__________________________________________________ ______________
With the modem:
ps -e:
Code:
PID Uid VmSize Stat Command
1 root 444 S /bin/init
2 root SWN [ksoftirqd/0]
3 root SW< [events/0]
4 root SW< [khelper]
5 root SW< [kthread]
10 root SW< [kblockd/0]
13 root SW< [khubd]
60 root SW [pdflush]
61 root SW [pdflush]
63 root SW< [aio/0]
62 root SW [kswapd0]
678 root SW [mtdblockd]
722 root SWN [jffs2_gcd_mtd4]
747 root SWN [jffs2_gcd_mtd5]
749 root 424 S < /disk/udevd --daemon
767 root 1024 S /bin/sh
768 root 496 S /bin/inetd
769 root 516 S /bin/boa
770 root 648 S /sbin/syslogd -n
771 root 648 S /sbin/klogd -n
776 root 1516 S /bin/sshd -i
777 root 1024 S -sh
854 root 652 R ps -e
dmesg:
Code:
2 NPS NDP=2(2)
at91rm9200-ohci at91rm9200-ohci: roothub.b 00000000 PPCM=0000 DR=0000
at91rm9200-ohci at91rm9200-ohci: roothub.status 00008000 DRWE
at91rm9200-ohci at91rm9200-ohci: roothub.portstatus [0] 0x00000100 PPS
at91rm9200-ohci at91rm9200-ohci: roothub.portstatus [1] 0x00000100 PPS
at91rm9200-ohci at91rm9200-ohci: created debug files
usb usb1: default language 0x0409
usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
usb usb1: Product: AT91RM9200 OHCI
usb usb1: Manufacturer: Linux 2.6.14-M5 ohci_hcd
usb usb1: SerialNumber: at91rm9200
usb usb1: hotplug
usb usb1: adding 1-0:1.0 (config #1, interface 0)
usb 1-0:1.0: hotplug
hub 1-0:1.0: usb_probe_interface
hub 1-0:1.0: usb_probe_interface - Device: ID 0000:0000
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 2 ports detected
hub 1-0:1.0: standalone hub
hub 1-0:1.0: no power switching (usb 1.0)
hub 1-0:1.0: global over-current protection
hub 1-0:1.0: power on to power good time: 4ms
hub 1-0:1.0: local power source is good
hub 1-0:1.0: no over-current condition exists
hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0000
usbcore: registered new driver cdc_acm
drivers/usb/class/cdc-acm.c: v0.23:USB Abstract Control Model driver for USB modems and ISDN adapters
usbcore: registered new driver usblp
drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
Initializing USB Mass Storage driver...
usbcore: registered new driver usb-storage
USB Mass Storage support registered.
rtusb init ====>
usbcore: registered new driver rt73
usbcore: registered new driver usbserial
drivers/usb/serial/usb-serial.c: USB Serial support registered for Generic
usbcore: registered new driver usbserial_generic
drivers/usb/serial/usb-serial.c: USB Serial Driver core v2.0
drivers/usb/serial/usb-serial.c: USB Serial support registered for PL-2303
usbcore: registered new driver pl2303
drivers/usb/serial/pl2303.c: Prolific PL2303 USB to serial adaptor driver v0.12
mice: PS/2 mouse device common for all mice
i2c /dev entries driver
Found AT91 i2c
RTC found.
AT91 MMC: 4 wire bus mode not supported by this driver - using 1 wire
AT91RM9200 MCI initialized
NET: Registered protocol family 2
IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
TCP established hash table entries: 4096 (order: 2, 16384 bytes)
TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
TCP: Hash tables configured (established 4096 bind 4096)
TCP reno registered
ip_conntrack version 2.3 (512 buckets, 4096 max) - 240 bytes per conntrack
ctnetlink v0.90: registering with nfnetlink.
ip_tables: (C) 2000-2002 Netfilter core team
ipt_recent v0.3.1: Stephen Frost <sfrost@snowman.net>. <link removed or I can't post>
arp_tables: (C) 2002 David S. Miller
TCP bic registered
Netfilter messages via NETLINK v0.30.
NET: Registered protocol family 1
NET: Registered protocol family 17
Bridge firewalling registered
ieee80211_crypt: registered algorithm 'NULL'
ieee80211_crypt: registered algorithm 'WEP'
RAMDISK: Compressed image found at block 0
VFS: Mounted root (ext2 filesystem).
Freeing init memory: 104K
eth0: Link down.
eth0: Link now 100-FullDuplex
hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0002
at91rm9200-ohci at91rm9200-ohci: GetStatus roothub.portstatus [0] = 0x00010101 CSC PPS CCS
hub 1-0:1.0: port 1, status 0101, change 0001, 12 Mb/s
hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x101
at91rm9200-ohci at91rm9200-ohci: GetStatus roothub.portstatus [0] = 0x00100103 PRSC PPS PES CCS
usb 1-1: new full speed USB device using at91rm9200-ohci and address 2
at91rm9200-ohci at91rm9200-ohci: GetStatus roothub.portstatus [0] = 0x00100103 PRSC PPS PES CCS
usb 1-1: skipped 3 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 4 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 3 descriptors after interface
usb 1-1: skipped 3 descriptors after interface
usb 1-1: skipped 4 descriptors after interface
usb 1-1: skipped 4 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 3 descriptors after interface
usb 1-1: default language 0x0409
usb 1-1: new device strings: Mfr=1, Product=2, SerialNumber=3
usb 1-1: Product: Sony Ericsson MD300
usb 1-1: Manufacturer: Sony Ericsson
usb 1-1: SerialNumber: 3534460230620680
usb 1-1: hotplug
usb 1-1: configuration #1 chosen from 3 choices
usb 1-1: adding 1-1:1.0 (config #1, interface 0)
usb 1-1:1.0: hotplug
usb-storage 1-1:1.0: usb_probe_interface
usb-storage 1-1:1.0: usb_probe_interface - Device: ID 0000:0000
usb-storage: USB Mass Storage device detected
usb-storage: -- associate_dev
usb-storage: Vendor: 0x0fce, Product: 0xd0cf, Revision: 0x0000
usb-storage: Interface Subclass: 0x06, Protocol: 0x50
usb-storage: Transport: Bulk
usb-storage: Protocol: Transparent SCSI
usb-storage: usb_stor_control_msg: rq=fe rqtype=a1 value=0000 index=00 len=1
usb-storage: GetMaxLUN command result is 1, data is 0
usb-storage: *** thread sleeping.
scsi0 : SCSI emulation for USB Mass Storage devices
usb-storage: device found at 2
usb-storage: waiting for device to settle before scanning
hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0002
usb 1-1: usb_disable_device nuking non-ep0 URBs
usb 1-1: unregistering interface 1-1:1.0
usb-storage: storage_disconnect() called
usb-storage: usb_stor_stop_transport called
usb-storage: -- usb_stor_release_resources
usb-storage: -- sending exit command to thread
usb-storage: -- dissociate_dev
usb-storage: *** thread awakened.
usb-storage: -- exiting
usb 1-1:1.0: hotplug
usb 1-1: adding 1-1:3.0 (config #3, interface 0)
usb 1-1:3.0: hotplug
usbserial_generic 1-1:3.0: usb_probe_interface
usbserial_generic 1-1:3.0: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.1 (config #3, interface 1)
usb 1-1:3.1: hotplug
cdc_acm 1-1:3.1: usb_probe_interface
cdc_acm 1-1:3.1: usb_probe_interface - Device: ID 0000:0000
cdc_acm 1-1:3.1: ttyACM0: USB ACM device
usb 1-1: adding 1-1:3.2 (config #3, interface 2)
usb 1-1:3.2: hotplug
usb 1-1: adding 1-1:3.3 (config #3, interface 3)
usb 1-1:3.3: hotplug
cdc_acm 1-1:3.3: usb_probe_interface
cdc_acm 1-1:3.3: usb_probe_interface - Device: ID 0000:0000
cdc_acm 1-1:3.3: ttyACM1: USB ACM device
usb 1-1: adding 1-1:3.4 (config #3, interface 4)
usb 1-1:3.4: hotplug
usb 1-1: adding 1-1:3.5 (config #3, interface 5)
usb 1-1:3.5: hotplug
usbserial_generic 1-1:3.5: usb_probe_interface
usbserial_generic 1-1:3.5: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.6 (config #3, interface 6)
usb 1-1:3.6: hotplug
usbserial_generic 1-1:3.6: usb_probe_interface
usbserial_generic 1-1:3.6: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.7 (config #3, interface 7)
usb 1-1:3.7: hotplug
usbserial_generic 1-1:3.7: usb_probe_interface
usbserial_generic 1-1:3.7: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.8 (config #3, interface 8)
usb 1-1:3.8: hotplug
usbserial_generic 1-1:3.8: usb_probe_interface
usbserial_generic 1-1:3.8: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.9 (config #3, interface 9)
usb 1-1:3.9: hotplug
usbserial_generic 1-1:3.9: usb_probe_interface
usbserial_generic 1-1:3.9: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.10 (config #3, interface 10)
usb 1-1:3.10: hotplug
usbserial_generic 1-1:3.10: usb_probe_interface
usbserial_generic 1-1:3.10: usb_probe_interface - Device: ID 0000:0000
cat /var/log/messages
Code:
<15>Mar 13 14:52:36 kernel: usb 1-1:3.4: hotplug
<15>Mar 13 14:52:36 kernel: usb 1-1: adding 1-1:3.5 (config #3, interface 5)
<15>Mar 13 14:52:36 kernel: usb 1-1:3.5: hotplug
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.5: usb_probe_interface
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.5: usb_probe_interface - Device: ID 0000:0000
<15>Mar 13 14:52:36 kernel: usb 1-1: adding 1-1:3.6 (config #3, interface 6)
<15>Mar 13 14:52:36 kernel: usb 1-1:3.6: hotplug
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.6: usb_probe_interface
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.6: usb_probe_interface - Device: ID 0000:0000
<15>Mar 13 14:52:36 kernel: usb 1-1: adding 1-1:3.7 (config #3, interface 7)
<15>Mar 13 14:52:36 kernel: usb 1-1:3.7: hotplug
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.7: usb_probe_interface
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.7: usb_probe_interface - Device: ID 0000:0000
<15>Mar 13 14:52:36 kernel: usb 1-1: adding 1-1:3.8 (config #3, interface 8)
<15>Mar 13 14:52:36 kernel: usb 1-1:3.8: hotplug
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.8: usb_probe_interface
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.8: usb_probe_interface - Device: ID 0000:0000
<15>Mar 13 14:52:36 kernel: usb 1-1: adding 1-1:3.9 (config #3, interface 9)
<15>Mar 13 14:52:36 kernel: usb 1-1:3.9: hotplug
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.9: usb_probe_interface
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.9: usb_probe_interface - Device: ID 0000:0000
<15>Mar 13 14:52:36 kernel: usb 1-1: adding 1-1:3.10 (config #3, interface 10)
<15>Mar 13 14:52:36 kernel: usb 1-1:3.10: hotplug
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.10: usb_probe_interface
<15>Mar 13 14:52:36 kernel: usbserial_generic 1-1:3.10: usb_probe_interface - Device: ID 0000:0000
__________________________________________________ _____________
And then, after the Internet connection:
ps -e:
Code:
PID Uid VmSize Stat Command
1 root 444 S /bin/init
2 root SWN [ksoftirqd/0]
3 root SW< [events/0]
4 root SW< [khelper]
5 root SW< [kthread]
10 root SW< [kblockd/0]
13 root SW< [khubd]
60 root SW [pdflush]
61 root SW [pdflush]
63 root SW< [aio/0]
62 root SW [kswapd0]
678 root SW [mtdblockd]
722 root SWN [jffs2_gcd_mtd4]
747 root SWN [jffs2_gcd_mtd5]
749 root 424 S < /disk/udevd --daemon
767 root 1024 S /bin/sh
768 root 496 S /bin/inetd
769 root 516 S /bin/boa
770 root 648 S /sbin/syslogd -n
771 root 648 S /sbin/klogd -n
776 root 1544 S /bin/sshd -i
777 root 1060 S -sh
1025 root 792 S pppd debug -d -detach file /etc/ppp/options connect /
1037 root 652 R ps -e
dmesg:
Code:
erface 3)
usb 1-1:3.3: hotplug
cdc_acm 1-1:3.3: usb_probe_interface
cdc_acm 1-1:3.3: usb_probe_interface - Device: ID 0000:0000
cdc_acm 1-1:3.3: ttyACM1: USB ACM device
usb 1-1: adding 1-1:3.4 (config #3, interface 4)
usb 1-1:3.4: hotplug
usb 1-1: adding 1-1:3.5 (config #3, interface 5)
usb 1-1:3.5: hotplug
usbserial_generic 1-1:3.5: usb_probe_interface
usbserial_generic 1-1:3.5: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.6 (config #3, interface 6)
usb 1-1:3.6: hotplug
usbserial_generic 1-1:3.6: usb_probe_interface
usbserial_generic 1-1:3.6: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.7 (config #3, interface 7)
usb 1-1:3.7: hotplug
usbserial_generic 1-1:3.7: usb_probe_interface
usbserial_generic 1-1:3.7: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.8 (config #3, interface 8)
usb 1-1:3.8: hotplug
usbserial_generic 1-1:3.8: usb_probe_interface
usbserial_generic 1-1:3.8: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.9 (config #3, interface 9)
usb 1-1:3.9: hotplug
usbserial_generic 1-1:3.9: usb_probe_interface
usbserial_generic 1-1:3.9: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.10 (config #3, interface 10)
usb 1-1:3.10: hotplug
usbserial_generic 1-1:3.10: usb_probe_interface
usbserial_generic 1-1:3.10: usb_probe_interface - Device: ID 0000:0000
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: modem timed out on ep0out len=0/0
usb 1-1: isModemOk timed out on ep0out len=0/0
hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0002
at91rm9200-ohci at91rm9200-ohci: GetStatus roothub.portstatus [0] = 0x00030100 PESC CSC PPS
hub 1-0:1.0: port 1, status 0100, change 0003, 12 Mb/s
usb 1-1: USB disconnect, address 2
usb 1-1: usb_disable_device nuking all URBs
usb 1-1: unregistering interface 1-1:3.0
usb 1-1:3.0: hotplug
usb 1-1: unregistering interface 1-1:3.1
usb 1-1:3.1: hotplug
usb 1-1: unregistering interface 1-1:3.2
usb 1-1:3.2: hotplug
usb 1-1: unregistering interface 1-1:3.3
usb 1-1:3.3: hotplug
usb 1-1: unregistering interface 1-1:3.4
usb 1-1:3.4: hotplug
usb 1-1: unregistering interface 1-1:3.5
usb 1-1:3.5: hotplug
usb 1-1: unregistering interface 1-1:3.6
usb 1-1:3.6: hotplug
usb 1-1: unregistering interface 1-1:3.7
usb 1-1:3.7: hotplug
usb 1-1: unregistering interface 1-1:3.8
usb 1-1:3.8: hotplug
usb 1-1: unregistering interface 1-1:3.9
usb 1-1:3.9: hotplug
usb 1-1: unregistering interface 1-1:3.10
usb 1-1:3.10: hotplug
usb 1-1: unregistering device
usb 1-1: hotplug
hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x100
hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0002
at91rm9200-ohci at91rm9200-ohci: GetStatus roothub.portstatus [0] = 0x00010101 CSC PPS CCS
hub 1-0:1.0: port 1, status 0101, change 0001, 12 Mb/s
hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x101
at91rm9200-ohci at91rm9200-ohci: GetStatus roothub.portstatus [0] = 0x00100103 PRSC PPS PES CCS
usb 1-1: new full speed USB device using at91rm9200-ohci and address 3
at91rm9200-ohci at91rm9200-ohci: GetStatus roothub.portstatus [0] = 0x00100103 PRSC PPS PES CCS
usb 1-1: skipped 3 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 4 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 3 descriptors after interface
usb 1-1: skipped 3 descriptors after interface
usb 1-1: skipped 4 descriptors after interface
usb 1-1: skipped 4 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 2 descriptors after interface
usb 1-1: skipped 3 descriptors after interface
usb 1-1: default language 0x0409
usb 1-1: new device strings: Mfr=1, Product=2, SerialNumber=3
usb 1-1: Product: Sony Ericsson MD300
usb 1-1: Manufacturer: Sony Ericsson
usb 1-1: SerialNumber: 3534460230620680
usb 1-1: hotplug
usb 1-1: configuration #1 chosen from 3 choices
usb 1-1: adding 1-1:1.0 (config #1, interface 0)
usb 1-1:1.0: hotplug
usb-storage 1-1:1.0: usb_probe_interface
usb-storage 1-1:1.0: usb_probe_interface - Device: ID 0000:0000
usb-storage: USB Mass Storage device detected
usb-storage: -- associate_dev
usb-storage: Vendor: 0x0fce, Product: 0xd0cf, Revision: 0x0000
usb-storage: Interface Subclass: 0x06, Protocol: 0x50
usb-storage: Transport: Bulk
usb-storage: Protocol: Transparent SCSI
usb-storage: usb_stor_control_msg: rq=fe rqtype=a1 value=0000 index=00 len=1
usb-storage: GetMaxLUN command result is 1, data is 0
usb-storage: *** thread sleeping.
scsi1 : SCSI emulation for USB Mass Storage devices
usb-storage: device found at 3
usb-storage: waiting for device to settle before scanning
hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0002
usb 1-1: usb_disable_device nuking non-ep0 URBs
usb 1-1: unregistering interface 1-1:1.0
usb-storage: storage_disconnect() called
usb-storage: usb_stor_stop_transport called
usb-storage: -- usb_stor_release_resources
usb-storage: -- sending exit command to thread
usb-storage: -- dissociate_dev
usb-storage: *** thread awakened.
usb-storage: -- exiting
usb 1-1:1.0: hotplug
usb 1-1: adding 1-1:3.0 (config #3, interface 0)
usb 1-1:3.0: hotplug
usbserial_generic 1-1:3.0: usb_probe_interface
usbserial_generic 1-1:3.0: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.1 (config #3, interface 1)
usb 1-1:3.1: hotplug
cdc_acm 1-1:3.1: usb_probe_interface
cdc_acm 1-1:3.1: usb_probe_interface - Device: ID 0000:0000
cdc_acm 1-1:3.1: ttyACM0: USB ACM device
usb 1-1: adding 1-1:3.2 (config #3, interface 2)
usb 1-1:3.2: hotplug
usb 1-1: adding 1-1:3.3 (config #3, interface 3)
usb 1-1:3.3: hotplug
cdc_acm 1-1:3.3: usb_probe_interface
cdc_acm 1-1:3.3: usb_probe_interface - Device: ID 0000:0000
cdc_acm 1-1:3.3: ttyACM1: USB ACM device
usb 1-1: adding 1-1:3.4 (config #3, interface 4)
usb 1-1:3.4: hotplug
usb 1-1: adding 1-1:3.5 (config #3, interface 5)
usb 1-1:3.5: hotplug
usbserial_generic 1-1:3.5: usb_probe_interface
usbserial_generic 1-1:3.5: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.6 (config #3, interface 6)
usb 1-1:3.6: hotplug
usbserial_generic 1-1:3.6: usb_probe_interface
usbserial_generic 1-1:3.6: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.7 (config #3, interface 7)
usb 1-1:3.7: hotplug
usbserial_generic 1-1:3.7: usb_probe_interface
usbserial_generic 1-1:3.7: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.8 (config #3, interface 8)
usb 1-1:3.8: hotplug
usbserial_generic 1-1:3.8: usb_probe_interface
usbserial_generic 1-1:3.8: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.9 (config #3, interface 9)
usb 1-1:3.9: hotplug
usbserial_generic 1-1:3.9: usb_probe_interface
usbserial_generic 1-1:3.9: usb_probe_interface - Device: ID 0000:0000
usb 1-1: adding 1-1:3.10 (config #3, interface 10)
usb 1-1:3.10: hotplug
usbserial_generic 1-1:3.10: usb_probe_interface
usbserial_generic 1-1:3.10: usb_probe_interface - Device: ID 0000:0000
cat /var/log/messages:
Code:
<150>Mar 13 15:08:35 chat[1005]: CONNECT
<150>Mar 13 15:08:35 chat[1005]: -- got it
<150>Mar 13 15:08:35 chat[1005]: send (^M)
<30>Mar 13 15:08:35 pppd[1004]: Serial connection established.
<31>Mar 13 15:08:35 pppd[1004]: using channel 2
<30>Mar 13 15:08:35 pppd[1004]: Using interface ppp0
<29>Mar 13 15:08:35 pppd[1004]: Connect: ppp0 <--> /dev/ttyACM0
<31>Mar 13 15:08:36 pppd[1004]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xa08f19d1> <pcomp> <accomp>]
<31>Mar 13 15:08:36 pppd[1004]: rcvd [LCP ConfReq id=0x2 <auth chap MD5> <accomp> <pcomp> <asyncmap 0x0> <magic 0x6b17dc22>]
<31>Mar 13 15:08:36 pppd[1004]: sent [LCP ConfAck id=0x2 <auth chap MD5> <accomp> <pcomp> <asyncmap 0x0> <magic 0x6b17dc22>]
<31>Mar 13 15:08:36 pppd[1004]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xa08f19d1> <pcomp> <accomp>]
<31>Mar 13 15:08:36 pppd[1004]: rcvd [CHAP Challenge id=0x0 <1f6c35ca3b58b1961704ed22b370e96e0f9ca57a2b88214607345dd2a3a0591effcc152a1bb891f6f764cd8293d0c9ceeffc85da0be801a6e7943d328300>, name = "Kermit"]
<31>Mar 13 15:08:36 pppd[1004]: sent [CHAP Response id=0x0 <8c67d0defb6e972d8881cf9dc5d9a08f>, name = "vivo"]
<31>Mar 13 15:08:36 pppd[1004]: rcvd [CHAP Success id=0x0 "Congratulations!"]
<30>Mar 13 15:08:36 pppd[1004]: Remote message: Congratulations!
<31>Mar 13 15:08:36 pppd[1004]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
<31>Mar 13 15:08:38 pppd[1004]: rcvd [IPCP ConfReq id=0x1]
<31>Mar 13 15:08:38 pppd[1004]: sent [IPCP ConfNak id=0x1 <addr 0.0.0.0>]
<31>Mar 13 15:08:38 pppd[1004]: rcvd [IPCP ConfNak id=0x1 <addr 187.117.250.164> <ms-dns1 200.142.130.202> <ms-dns3 200.220.227.56>]
<31>Mar 13 15:08:38 pppd[1004]: sent [IPCP ConfReq id=0x2 <addr 187.117.250.164> <ms-dns1 200.142.130.202> <ms-dns3 200.220.227.56>]
<31>Mar 13 15:08:38 pppd[1004]: rcvd [IPCP ConfReq id=0x2]
<31>Mar 13 15:08:38 pppd[1004]: sent [IPCP ConfAck id=0x2]
<31>Mar 13 15:08:38 pppd[1004]: rcvd [IPCP ConfAck id=0x2 <addr 187.117.250.164> <ms-dns1 200.142.130.202> <ms-dns3 200.220.227.56>]
<28>Mar 13 15:08:38 pppd[1004]: Could not determine remote IP address: defaulting to 10.64.64.64
<29>Mar 13 15:08:38 pppd[1004]: local IP address 187.117.250.164
<29>Mar 13 15:08:38 pppd[1004]: remote IP address 10.64.64.64
<29>Mar 13 15:08:38 pppd[1004]: primary DNS address 200.142.130.202
<29>Mar 13 15:08:38 pppd[1004]: secondary DNS address 200.220.227.56
<29>Mar 13 15:08:42 pppd[1015]: pppd 2.4.1 started by root, uid 0
<27>Mar 13 15:08:42 pppd[1015]: tcsetattr: Invalid argument
<31>Mar 13 15:08:42 pppd[1004]: rcvd [LCP TermReq id=0x1]
<30>Mar 13 15:08:42 pppd[1004]: LCP terminated by peer
<31>Mar 13 15:08:42 pppd[1004]: sent [LCP TermAck id=0x1]
<29>Mar 13 15:08:42 pppd[1004]: Modem hangup
<29>Mar 13 15:08:42 pppd[1004]: Connection terminated.
<30>Mar 13 15:08:42 pppd[1004]: Connect time 0.2 minutes.
<30>Mar 13 15:08:42 pppd[1004]: Sent 58 bytes, received 52 bytes.
<30>Mar 13 15:08:43 pppd[1015]: Exit.
<30>Mar 13 15:08:43 pppd[1004]: Exit.
<29>Mar 13 15:08:52 pppd[1025]: pppd 2.4.1 started by root, uid 0
<150>Mar 13 15:08:53 chat[1026]: timeout set to 15 seconds
<150>Mar 13 15:08:53 chat[1026]: abort on (\nBUSY\r)
<150>Mar 13 15:08:53 chat[1026]: abort on (\nNO ANSWER\r)
<150>Mar 13 15:08:53 chat[1026]: abort on (\nRINGING\r\n\r\nRINGING\r)
<150>Mar 13 15:08:53 chat[1026]: send (AT^M)
<150>Mar 13 15:08:53 chat[1026]: expect (OK)
<150>Mar 13 15:08:53 chat[1026]: AT^M^M
<150>Mar 13 15:08:53 chat[1026]: OK
<150>Mar 13 15:08:53 chat[1026]: -- got it
<150>Mar 13 15:08:53 chat[1026]: send (^M)
<150>Mar 13 15:08:53 chat[1026]: send (ATZ^M)
<150>Mar 13 15:08:53 chat[1026]: expect (OK)
<150>Mar 13 15:08:53 chat[1026]: ^M
<150>Mar 13 15:08:53 chat[1026]: ^MATZ^M^M
<150>Mar 13 15:08:53 chat[1026]: OK
<150>Mar 13 15:08:53 chat[1026]: -- got it
<150>Mar 13 15:08:53 chat[1026]: send (^M)
<150>Mar 13 15:08:53 chat[1026]: send (AT+CFUN=5^M)
<150>Mar 13 15:08:53 chat[1026]: expect (OK)
<150>Mar 13 15:08:53 chat[1026]: ^M
<150>Mar 13 15:08:53 chat[1026]: ^MAT+CFUN=5^M^M
<150>Mar 13 15:08:53 chat[1026]: OK
<150>Mar 13 15:08:53 chat[1026]: -- got it
<150>Mar 13 15:08:53 chat[1026]: send (^M)
<150>Mar 13 15:08:53 chat[1026]: send (AT+CGDCONT=1,"IP","zap.vivo.com.br"^M)
<150>Mar 13 15:08:54 chat[1026]: expect (OK)
<150>Mar 13 15:08:54 chat[1026]: ^M
<150>Mar 13 15:08:54 chat[1026]: ^MAT+CGDCONT=1,"IP","zap.vivo.com.br"^M^M
<150>Mar 13 15:08:54 chat[1026]: OK
<150>Mar 13 15:08:54 chat[1026]: -- got it
<150>Mar 13 15:08:54 chat[1026]: send (^M)
<150>Mar 13 15:08:54 chat[1026]: send (ATD*99***1#^M)
<150>Mar 13 15:08:54 chat[1026]: expect (CONNECT)
<150>Mar 13 15:08:54 chat[1026]: ^M
<150>Mar 13 15:08:55 chat[1026]: ^MATD*99***1#^M~^?}#@!}!}!} }9}#}%B#}%}(}"}'}"}"}&} } } } }%}&}(p}"}6(:~^M
<150>Mar 13 15:08:55 chat[1026]: CONNECT
<150>Mar 13 15:08:55 chat[1026]: -- got it
<150>Mar 13 15:08:55 chat[1026]: send (^M)
<30>Mar 13 15:08:55 pppd[1025]: Serial connection established.
<31>Mar 13 15:08:55 pppd[1025]: using channel 3
<30>Mar 13 15:08:55 pppd[1025]: Using interface ppp0
<29>Mar 13 15:08:55 pppd[1025]: Connect: ppp0 <--> /dev/ttyACM0
<31>Mar 13 15:08:56 pppd[1025]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x7c839294> <pcomp> <accomp>]
<31>Mar 13 15:08:56 pppd[1025]: rcvd [LCP ConfReq id=0x2 <auth chap MD5> <accomp> <pcomp> <asyncmap 0x0> <magic 0x8700216>]
<31>Mar 13 15:08:56 pppd[1025]: sent [LCP ConfAck id=0x2 <auth chap MD5> <accomp> <pcomp> <asyncmap 0x0> <magic 0x8700216>]
<31>Mar 13 15:08:56 pppd[1025]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x7c839294> <pcomp> <accomp>]
<31>Mar 13 15:08:56 pppd[1025]: rcvd [CHAP Challenge id=0x0 <7edf2cf58afb187156d7c4ade27330a92ecf5c653aeb48e106c7f41d92636019debf8cd5eadb7851b6b7248d425390898eafbc459acba8c166>, name = "Kermit"]
<31>Mar 13 15:08:56 pppd[1025]: sent [CHAP Response id=0x0 <e35caa40d8aa7fcbd9591cd4b69d5898>, name = "vivo"]
<31>Mar 13 15:08:56 pppd[1025]: rcvd [CHAP Success id=0x0 "Congratulations!"]
<30>Mar 13 15:08:56 pppd[1025]: Remote message: Congratulations!
<31>Mar 13 15:08:56 pppd[1025]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
<31>Mar 13 15:08:59 last message repeated 1 time(s)
<31>Mar 13 15:08:59 pppd[1025]: rcvd [IPCP ConfReq id=0x1]
<31>Mar 13 15:08:59 pppd[1025]: sent [IPCP ConfNak id=0x1 <addr 0.0.0.0>]
<31>Mar 13 15:08:59 pppd[1025]: rcvd [IPCP ConfNak id=0x1 <addr 187.1.247.38> <ms-dns1 200.142.130.202> <ms-dns3 200.220.227.56>]
<31>Mar 13 15:08:59 pppd[1025]: sent [IPCP ConfReq id=0x2 <addr 187.1.247.38> <ms-dns1 200.142.130.202> <ms-dns3 200.220.227.56>]
<31>Mar 13 15:08:59 pppd[1025]: rcvd [IPCP ConfReq id=0x2]
<31>Mar 13 15:08:59 pppd[1025]: sent [IPCP ConfAck id=0x2]
<31>Mar 13 15:08:59 pppd[1025]: rcvd [IPCP ConfAck id=0x2 <addr 187.1.247.38> <ms-dns1 200.142.130.202> <ms-dns3 200.220.227.56>]
<28>Mar 13 15:08:59 pppd[1025]: Could not determine remote IP address: defaulting to 10.64.64.64
<29>Mar 13 15:08:59 pppd[1025]: local IP address 187.1.247.38
<29>Mar 13 15:08:59 pppd[1025]: remote IP address 10.64.64.64
<29>Mar 13 15:08:59 pppd[1025]: primary DNS address 200.142.130.202
<29>Mar 13 15:08:59 pppd[1025]: secondary DNS address 200.220.227.56
__________________________________________________ ___________________________
Finally, after the system rebooted with the modem connected:
ps -e:
Code:
PID Uid VmSize Stat Command
1 root 444 S /bin/init
2 root SWN [ksoftirqd/0]
3 root SW< [events/0]
4 root SW< [khelper]
5 root SW< [kthread]
10 root SW< [kblockd/0]
13 root SW< [khubd]
60 root SW [pdflush]
61 root SW [pdflush]
63 root SW< [aio/0]
62 root SW [kswapd0]
678 root SW [mtdblockd]
709 root SW< [scsi_eh_0]
710 root SW [usb-storage]
733 root SWN [jffs2_gcd_mtd4]
758 root SWN [jffs2_gcd_mtd5]
760 root 392 S < /disk/udevd --daemon
778 root 980 S /bin/sh
779 root 496 S /bin/inetd
780 root 516 S /bin/boa
781 root 648 S /sbin/syslogd -n
782 root 648 S /sbin/klogd -n
783 root 1520 S /bin/sshd -i
784 root 1024 S -sh
785 root 652 R ps -e
dmesg:
Code:
ansfer_buf: xfer 31 bytes
usb-storage: Status code 0; transferred 31/31
usb-storage: -- transfer complete
usb-storage: Bulk command transfer result=0
usb-storage: Attempting to get CSW...
usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
usb-storage: Status code 0; transferred 13/13
usb-storage: -- transfer complete
usb-storage: Bulk status result = 0
usb-storage: Bulk Status S 0x53425355 T 0x7 R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Command TEST_UNIT_READY (6 bytes)
usb-storage: 00 00 00 00 00 00
usb-storage: Bulk Command S 0x43425355 T 0x8 L 0 F 0 Trg 0 LUN 0 CL 6
usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
usb-storage: Status code 0; transferred 31/31
usb-storage: -- transfer complete
usb-storage: Bulk command transfer result=0
usb-storage: Attempting to get CSW...
usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
usb-storage: Status code 0; transferred 13/13
usb-storage: -- transfer complete
usb-storage: Bulk status result = 0
usb-storage: Bulk Status S 0x53425355 T 0x8 R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Command READ_CAPACITY (10 bytes)
usb-storage: 25 00 00 00 00 00 00 00 00 00
usb-storage: Bulk Command S 0x43425355 T 0x9 L 8 F 128 Trg 0 LUN 0 CL 10
usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
usb-storage: Status code 0; transferred 31/31
usb-storage: -- transfer complete
usb-storage: Bulk command transfer result=0
usb-storage: usb_stor_bulk_transfer_sglist: xfer 8 bytes, 1 entries
usb-storage: Status code 0; transferred 8/8
usb-storage: -- transfer complete
usb-storage: Bulk data transfer result 0x0
usb-storage: Attempting to get CSW...
usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
usb-storage: Status code 0; transferred 13/13
usb-storage: -- transfer complete
usb-storage: Bulk status result = 0
usb-storage: Bulk Status S 0x53425355 T 0x9 R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
SCSI device sda: 348161 512-byte hdwr sectors (178 MB)
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Command MODE_SENSE (6 bytes)
usb-storage: 1a 00 3f 00 c0 00
usb-storage: Bulk Command S 0x43425355 T 0xa L 192 F 128 Trg 0 LUN 0 CL 6
usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
usb-storage: Status code 0; transferred 31/31
usb-storage: -- transfer complete
usb-storage: Bulk command transfer result=0
usb-storage: usb_stor_bulk_transfer_sglist: xfer 192 bytes, 1 entries
at91rm9200-ohci at91rm9200-ohci: urb c0e2a200 path 1 ep5in 40120000 cc 4 --> status -32
usb-storage: Status code -32; transferred 0/192
usb-storage: clearing endpoint halt for pipe 0xc0028280
usb-storage: usb_stor_control_msg: rq=01 rqtype=02 value=0000 index=85 len=0
usb-storage: usb_stor_clear_halt: result = 0
usb-storage: Bulk data transfer result 0x2
usb-storage: Attempting to get CSW...
usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
usb-storage: Status code 0; transferred 13/13
usb-storage: -- transfer complete
usb-storage: Bulk status result = 0
usb-storage: Bulk Status S 0x53425355 T 0xa R 192 Stat 0x1
usb-storage: -- transport indicates command failure
usb-storage: Issuing auto-REQUEST_SENSE
usb-storage: Bulk Command S 0x43425355 T 0xb L 18 F 128 Trg 0 LUN 0 CL 6
usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
usb-storage: Status code 0; transferred 31/31
usb-storage: -- transfer complete
usb-storage: Bulk command transfer result=0
usb-storage: usb_stor_bulk_transfer_buf: xfer 18 bytes
usb-storage: Status code 0; transferred 18/18
usb-storage: -- transfer complete
usb-storage: Bulk data transfer result 0x0
usb-storage: Attempting to get CSW...
usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
usb-storage: Status code 0; transferred 13/13
usb-storage: -- transfer complete
usb-storage: Bulk status result = 0
usb-storage: Bulk Status S 0x53425355 T 0xb R 0 Stat 0x0
usb-storage: -- Result from auto-sense is 0
usb-storage: -- code: 0x70, key: 0x5, ASC: 0x26, ASCQ: 0x0
usb-storage: (Unknown Key): (unknown ASC/ASCQ)
usb-storage: scsi cmd done, result=0x2
usb-storage: *** thread sleeping.
sda: test WP failed, assume Write Enabled
sda: assuming drive cache: write through
sda:<7>usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 00 00 00 08 00
usb-storage: Bulk Command S 0x43425355 T 0xc L 4096 F 128 Trg 0 LUN 0 CL 10
usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
usb-storage: Status code 0; transferred 31/31
usb-storage: -- transfer complete
usb-storage: Bulk command transfer result=0
usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1 entries
usb-storage: Status code 0; transferred 4096/4096
usb-storage: -- transfer complete
usb-storage: Bulk data transfer result 0x0
usb-storage: Attempting to get CSW...
usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
usb-storage: Status code 0; transferred 13/13
usb-storage: -- transfer complete
usb-storage: Bulk status result = 0
usb-storage: Bulk Status S 0x53425355 T 0xc R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
sda1
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Command ALLOW_MEDIUM_REMOVAL (6 bytes)
usb-storage: 1e 00 00 00 00 00
usb-storage: Bulk Command S 0x43425355 T 0xd L 0 F 0 Trg 0 LUN 0 CL 6
usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
usb-storage: Status code 0; transferred 31/31
usb-storage: -- transfer complete
usb-storage: Bulk command transfer result=0
usb-storage: Attempting to get CSW...
usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
usb-storage: Status code 0; transferred 13/13
usb-storage: -- transfer complete
usb-storage: Bulk status result = 0
usb-storage: Bulk Status S 0x53425355 T 0xd R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
Attached scsi removable disk sda at scsi0, channel 0, id 0, lun 0
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Bad LUN (0:1)
usb-storage: scsi cmd done, result=0x40000
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Bad target number (1:0)
usb-storage: scsi cmd done, result=0x40000
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Bad target number (2:0)
usb-storage: scsi cmd done, result=0x40000
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Bad target number (3:0)
usb-storage: scsi cmd done, result=0x40000
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Bad target number (4:0)
usb-storage: scsi cmd done, result=0x40000
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Bad target number (5:0)
usb-storage: scsi cmd done, result=0x40000
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Bad target number (6:0)
usb-storage: scsi cmd done, result=0x40000
usb-storage: *** thread sleeping.
usb-storage: queuecommand called
usb-storage: *** thread awakened.
usb-storage: Bad target number (7:0)
usb-storage: scsi cmd done, result=0x40000
usb-storage: *** thread sleeping.
usb-storage: device scan complete
eth0: Link now 100-FullDuplex
cat var/log/messages:
Code:
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Command MODE_SENSE (6 bytes)
<15>Mar 13 15:16:53 kernel: usb-storage: 1a 00 3f 00 c0 00
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk Command S 0x43425355 T 0xa L 192 F 128 Trg 0 LUN 0 CL 6
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 31/31
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk command transfer result=0
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_sglist: xfer 192 bytes, 1 entries
<15>Mar 13 15:16:53 kernel: at91rm9200-ohci at91rm9200-ohci: urb c0e2a200 path 1 ep5in 40120000 cc 4 --> status -32
<15>Mar 13 15:16:53 kernel: usb-storage: Status code -32; transferred 0/192
<15>Mar 13 15:16:53 kernel: usb-storage: clearing endpoint halt for pipe 0xc0028280
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_control_msg: rq=01 rqtype=02 value=0000 index=85 len=0
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_clear_halt: result = 0
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk data transfer result 0x2
<15>Mar 13 15:16:53 kernel: usb-storage: Attempting to get CSW...
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 13/13
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk status result = 0
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk Status S 0x53425355 T 0xa R 192 Stat 0x1
<15>Mar 13 15:16:53 kernel: usb-storage: -- transport indicates command failure
<15>Mar 13 15:16:53 kernel: usb-storage: Issuing auto-REQUEST_SENSE
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk Command S 0x43425355 T 0xb L 18 F 128 Trg 0 LUN 0 CL 6
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 31/31
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk command transfer result=0
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 18 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 18/18
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk data transfer result 0x0
<15>Mar 13 15:16:53 kernel: g to get CSW...
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 13/13
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk status result = 0
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk Status S 0x53425355 T 0xb R 0 Stat 0x0
<15>Mar 13 15:16:53 kernel: usb-storage: -- Result from auto-sense is 0
<15>Mar 13 15:16:53 kernel: usb-storage: -- code: 0x70, key: 0x5, ASC: 0x26, ASCQ: 0x0
<15>Mar 13 15:16:53 kernel: usb-storage: (Unknown Key): (unknown ASC/ASCQ)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x2
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<12>Mar 13 15:16:53 kernel: sda: test WP failed, assume Write Enabled
<11>Mar 13 15:16:53 kernel: sda: assuming drive cache: write through
<14>Mar 13 15:16:53 kernel: sda:<7>usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Command READ_10 (10 bytes)
<15>Mar 13 15:16:53 kernel: usb-storage: 28 00 00 00 00 00 00 00 08 00
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk Command S 0x43425355 T 0xc L 4096 F 128 Trg 0 LUN 0 CL 10
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 31/31
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk command transfer result=0
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1 entries
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 4096/4096
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk data transfer result 0x0
<15>Mar 13 15:16:53 kernel: usb-storage: Attempting to get CSW...
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 13/13
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk status result = 0
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk Status S 0x53425355 T 0xc R 0 Stat 0x0
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x0
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<12>Mar 13 15:16:53 kernel: sda1
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Command ALLOW_MEDIUM_REMOVAL (6 bytes)
<15>Mar 13 15:16:53 kernel: usb-storage: 1e 00 00 00 00 00
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk Command S 0x43425355 T 0xd L 0 F 0 Trg 0 LUN 0 CL 6
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 31/31
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk command transfer result=0
<15>Mar 13 15:16:53 kernel: usb-storage: Attempting to get CSW...
<15>Mar 13 15:16:53 kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
<15>Mar 13 15:16:53 kernel: usb-storage: Status code 0; transferred 13/13
<15>Mar 13 15:16:53 kernel: usb-storage: -- transfer complete
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk status result = 0
<15>Mar 13 15:16:53 kernel: usb-storage: Bulk Status S 0x53425355 T 0xd R 0 Stat 0x0
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x0
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<13>Mar 13 15:16:53 kernel: Attached scsi removable disk sda at scsi0, channel 0, id 0, lun 0
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Bad LUN (0:1)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x40000
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Bad target number (1:0)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x40000
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Bad target number (2:0)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x40000
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Bad target number (3:0)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x40000
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Bad target number (4:0)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x40000
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Bad target number (5:0)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x40000
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Bad target number (6:0)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x40000
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<15>Mar 13 15:16:53 kernel: usb-storage: queuecommand called
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread awakened.
<15>Mar 13 15:16:53 kernel: usb-storage: Bad target number (7:0)
<15>Mar 13 15:16:53 kernel: usb-storage: scsi cmd done, result=0x40000
<15>Mar 13 15:16:53 kernel: usb-storage: *** thread sleeping.
<15>Mar 13 15:16:53 kernel: usb-storage: device scan complete
<14>Mar 13 15:16:53 kernel: eth0: Link now 100-FullDuplex
<38>Mar 13 15:17:05 sshd[783]: WARNING: /etc/config/ssh/moduli does not exist, using old modulus
<38>Mar 13 15:17:07 sshd[783]: Accepted password for root from 192.168.2.100 port 56022 ssh2
<33>Mar 13 15:17:07 sshd[783]: WARNING: currently everything is run as root on an embedded system :WARNING
<38>Mar 13 15:17:07 sshd[784]: wtmp_write: problem writing /var/log/wtmp: No such file or directory
Ufff, that's it.
-
The Linux version appears to 2.6.14, which is a good thing, it is fairly modern.
It appears the difference is that the on the reboot, the 3G device is being detected as a USB storage device, likely a CD image with Windows install files on it. The last dmesg output appears to show the usb_storage module stuck in a loop trying to initialize the storage portion of the 3G device. This is preventing the usbserial_generic module from loading, hence no ttyACM0 device gets created.
In the dmesg output from when the device was connected are these messages, then there are no more usb_storage messages and usbserial_generic messages start:
usb-storage: storage_disconnect() called
usb-storage: usb_stor_stop_transport called
usb-storage: -- usb_stor_release_resources
usb-storage: -- sending exit command to thread
usb-storage: -- dissociate_dev
usb-storage: *** thread awakened.
usb-storage: -- exiting
This appears to be when the 3G Device's storage portion is ejected, which allows the usbserial_generic to take over and create ttyACM0.
I don't see that in the demsg output from after the boot with device connected, and the usb_storage messages are repeating instead.
The tricky part here is figuring out what can be done to fix the problem, given the limited Linux commands. Check if there is a cd device:
ls -l /dev/scd* /dev/sr*
and see if either exists.
If one does, try:
eject /dev/{scd0 or sr0 or whatever showed up}
If eject returns an error about not existing, or otherwise does not work, I guess you could try restarting the udev process:
Do a:
ps -e
and look for the /disk/udevd line, and use the first number on the line as a arg to the kill command:
kill nnn
Then restart udevd:
/disk/udevd --daemon
wait a few seconds, then see if /dev/ttyACM0 exists.
Another thing you could try is:
/sbin/modprobe -r usb_storage
or
/sbin/rmmod usb_storage
If none of these work, I am at a loss as to how to get the 3G device past the usb_storage stage and onto the usbgeneric_serial stage, short of un-plugging and replugging, which is what you are trying to avoid.
Maybe somebody knows how to perform a software eject of a CD device without the eject command?
-
Well, here we go again.
First, I tried the ls -l /dev/scd* /dev/sr* command, but No such file or directory for both as response. Here the list of the /dev folder, maybe it can help:
Code:
console mixer mtdblock4 ptyp1 sda4 tty6 ttyp2
cua0 mmc mtdblock5 ptyp2 sdb tty7 ttyp3
cua1 mmc0 mtdblock6 ptyp3 sdb1 tty8 ttyp4
dsp mmc1 mtdblock7 ptyp4 sdc tty9 ttyp5
flash mmc2 mtdblock8 ptyp5 sdc1 ttyACM0 ttyp6
gpio mtd0 mtdblock9 ptyp6 sdd ttyACM1 ttyp7
hda mtd1 mtdr0 ptyp7 sdd1 ttyS0 ttyp8
hda1 mtd2 mtdr1 ptyp8 sde ttyS1 ttyp9
hda2 mtd3 mtdr2 ptyp9 sequencer ttyS2 urandom
hda3 mtd4 mtdr3 ram0 sndstat ttyS3 video0
hda4 mtd5 mtdr4 ram1 spi0 ttyS4 video1
ipsec mtd6 mtdr5 ram2 spi1 ttyS5 watchdog
kmem mtd7 mtdr6 ram3 tty ttyS6 zero
lcd mtd8 mtdr7 random tty0 ttyS7
ledman mtd9 mtdr8 rtc tty1 ttyS8
log mtdblock0 mtdr9 sda tty2 ttyUSB0
loop0 mtdblock1 null sda1 tty3 ttyUSB1
mem mtdblock2 ppp sda2 tty4 ttyp0
midi00 mtdblock3 ptyp0 sda3 tty5 ttyp1
Next, I tried to restart the udevd process. After the restart, the /dev/ttyACM0 wasn't ok...
At last, I tried to use modprobe command, but it returned:
Code:
modprobe: module usb_storage not found.
modprobe: failed to remove module usb_storage
Oh, who can save me? (hahahahaha)
-
Oh no, do I have to recompile the kernel to work???
