[Trac #285] Wireless 31.02 Firmware w/Build 141 Wireless HAMMERS USB Bus

Zarro Boogs per Child bugtracker at laptop.org
Sun Nov 5 02:18:36 EST 2006 

#285: Wireless 31.02 Firmware w/Build 141 Wireless HAMMERS USB Bus
----------------------+-----------------------------------------------------
 Reporter:  mfoster   |       Owner:  mbletsas
     Type:  defect    |      Status:  new     
 Priority:  blocker   |   Milestone:  BTest-1 
Component:  wireless  |    Keywords:          
----------------------+-----------------------------------------------------
 Hi, Folks,

 We're seeing a nasty bug where the wireless chip absolutely hammers the
 USB bus with traffic, and this continues ~forever.  When we add the
 firmware to the Build 141 image, running with the old 31.02 firmware, the
 wireless device has traffic occuring every 6-20 '''microseconds!'''.  The
 user-visible symptom of this problem is that udev will hang for a few
 minutes.  Even after it exits, however, however, the "hammering" traffic
 continues indefinitely.

 This will certainly increase the challenge of programming the onboard
 wireless flash chip, at an absolute minimum.  Note that if the
 /lib/firmware file is renamed, this won't happen.

 USB Analyzer log (terminated at 40MB while udev is spinning):

 http://www.talix.com/Wireless%2031.02%20-$20Build$20141.ufo

 Console log:

 {{{
 NOTDOING ChipsetFlashSetup()!!!!!!!!!!!!!!!!!!
 do_vsmbios
 Call real_mode_switch_call_vsm
 cs5536: southbridge_enable: dev is 0000cee0
 cs5536: southbridge_enable: dev is 0000d180
 cs5536: southbridge_enable: dev is 0000d420
 cs5536: southbridge_enable: dev is 0000d6c0
 cs5536: southbridge_enable: dev is 0000d960
 cs5536: southbridge_enable: dev is 0000dc00
 cs5536: cs5536_pci_dev_enable_resources()
 cs5536: southbridge_init
 cs5536: southbridge_init: enable uarta, msr MDD_IRQM_YHIGH(51400021)
 cs5536: southbridge_init: enable_ide_nand_flash is 1
 cs5536: enable_ide_nand_flash
 WARNING: using deprecated flash enable mechanism
 MDD_LBAR_FLSH0 was 0x0000000000000000
 MDD_LBAR_FLSH0 is 0xfffff00720000000
 MDD_PIN_OPT was 0x0000000000000f7d
 MDD_PIN_OPT is 0x0000000000000f7c
 MDD_NANDF_DATA was 0x0000000007770777
 MDD_NANDF_DATA is 0x0000000000100010
 MDD_NADF_CNTL was 0x0000000000000777
 MDD_NADF_CNTL is 0x0000000000000010
 cs5536: EXIT enable_ide_nand_flash

 Forthmacs
 Type 'i' to interrupt stand-init sequence
 First stand-init:
 Exceptions
 CIF
 DHCP init
 memory node
 gpio
 Probing memory
 PCI host bridge
 ISA
 RTC
 The time has not been set since the real-time clock battery was replaced
 CPU nodes
 Null-NVRAM
 DCON
 nvramrc
 Probing
 probe-pci
 USB2 devices:
 /pci/usb at f,5/device at 3,0
 /pci/usb at f,5/scsi at 0,0
 /pci/usb at f,5/scsi at 0,0/disk
 USB1 devices:
 /pci/usb at f,4/hid at 2,1
 /pci/usb at f,4/keyboard at 2,0
 /pci/usb at f,4/mouse at 1,0
 Install console
 Linux version 2.6.18-20061103.5.olpc.d3aeec2d1767729
 (dilinger at learn.laptop.org) (gcc version 4.1.0
 20060304 (Red Hat 4.1.0-3)) #1 Fri Nov 3 14:35:51 EST 2006
 BIOS-provided physical RAM map:
  BIOS-e801: 0000000000000000 - 000000000009f000 (usable)
  BIOS-e801: 0000000000100000 - 0000000007700000 (usable)
 119MB LOWMEM available.
 Entering add_active_range(0, 0, 30464) 0 entries of 256 used
 Zone PFN ranges:
   DMA             0 ->     4096
   Normal       4096 ->    30464
 early_node_map[1] active PFN ranges
     0:        0 ->    30464
 On node 0 totalpages: 30464
   DMA zone: 32 pages used for memmap
   DMA zone: 0 pages reserved
   DMA zone: 4064 pages, LIFO batch:0
   Normal zone: 206 pages used for memmap
   Normal zone: 26162 pages, LIFO batch:7
 DMI not present or invalid.
 ACPI: Unable to locate RSDP
 Allocating PCI resources starting at 10000000 (gap: 07700000:f8900000)
 Detected 364.917 MHz processor.
 Built 1 zonelists.  Total pages: 30226
 Kernel command line: debug root=LABEL=OLPCRoot /boot/vmlinuz ro
 rootfstype=ext3 console=ttyS0,115200
  console=tty0 fbcon=font:SUN12x22 pci=nobios video=gxfb:1024x768-16"
 Initializing CPU#0
 CPU 0 irqstacks, hard=c06fb000 soft=c06fa000
 PID hash table entries: 512 (order: 9, 2048 bytes)
 Console: colour dummy device 80x25
 Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
 Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
 Memory: 111536k/121856k available (1859k kernel code, 9852k reserved,
 1001k data, 160k init, 0k high
 mem)
 virtual kernel memory layout:
     fixmap  : 0xffff7000 - 0xfffff000   (  32 kB)
     vmalloc : 0xc8000000 - 0xffff5000   ( 895 MB)
     lowmem  : 0xc0000000 - 0xc7700000   ( 119 MB)
       .init : 0xc06cd000 - 0xc06f5000   ( 160 kB)
       .data : 0xc05d0c0f - 0xc06cb114   (1001 kB)
       .text : 0xc0400000 - 0xc05d0c0f   (1859 kB)
 Checking if this processor honours the WP bit even in supervisor mode...
 Ok.
 Calibrating delay using timer specific routine.. 731.26 BogoMIPS
 (lpj=3656303)
 Security Framework v1.0.0 initialized
 Capability LSM initialized
 Mount-cache hash table entries: 512
 CPU: After generic identify, caps: 0080a13d c0c0a13d 00000000 00000000
 00000000 00000000 00000000
 CPU: L1 I Cache: 16K (32 bytes/line), D cache 16K (32 bytes/line)
 CPU: After all inits, caps: 0080a13d c0c0a13d 00000000 00000000 00000000
 00000000 00000000
 CPU: NSC Geode(TM) Integrated Processor by National Semi stepping 02
 Checking 'hlt' instruction... OK.
 checking if image is initramfs... it is
 Freeing initrd memory: 5465k freed
 PM: Adding info for No Bus:platform
 NET: Registered protocol family 16
 PCI: Using configuration type 1
 Setting up standard PCI resources
 ACPI: Interpreter disabled.
 SCSI subsystem initialized
 usbcore: registered new interface driver usbfs
 usbcore: registered new interface driver hub
 usbcore: registered new device driver usb
 PCI: Probing PCI hardware
 PCI: Probing PCI hardware (bus 00)
 PM: Adding info for No Bus:pci0000:00
 PM: Adding info for pci:0000:00:01.0
 PM: Adding info for pci:0000:00:01.1
 PM: Adding info for pci:0000:00:0c.0
 PM: Adding info for pci:0000:00:0c.1
 PM: Adding info for pci:0000:00:0c.2
 PM: Adding info for pci:0000:00:0f.0
 PM: Adding info for pci:0000:00:0f.2
 PM: Adding info for pci:0000:00:0f.3
 PM: Adding info for pci:0000:00:0f.4
 PM: Adding info for pci:0000:00:0f.5
 NET: Registered protocol family 2
 IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
 TCP established hash table entries: 4096 (order: 4, 65536 bytes)
 TCP bind hash table entries: 2048 (order: 3, 40960 bytes)
 TCP: Hash tables configured (established 4096 bind 2048)
 TCP reno registered
 PM: Adding info for platform:pcspkr
 apm: BIOS version 114.102 Flags 0x7369 (Driver version 1.16ac)
 apm: no 32 bit BIOS support
 Total HugeTLB memory allocated, 0
 VFS: Disk quotas dquot_6.5.1
 Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
 JFFS2 version 2.2. (NAND) (SUMMARY)  (C) 2001-2006 Red Hat, Inc.
 io scheduler noop registered
 io scheduler cfq registered (default)
 gxfb 0000:00:01.1: 8192 KiB of video memory at 0xfd000000
 Console: switching to colour frame buffer device 53x21
 fb0: Geode GX frame buffer device
 PM: Adding info for platform:vesafb.0
 Real Time Clock Driver v1.12ac
 Non-volatile memory driver v1.2
 Serial: 8250/16550 driver $Revision: 1.90 $ 1 ports, IRQ sharing enabled
 PM: Adding info for platform:serial8250
 ÿserial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a NS16550A
 RAMDISK driver initialized: 16 RAM disks of 16384K size 1024 blocksize
 NAND device: Manufacturer ID: 0xad, Chip ID: 0xdc (Hynix NAND 512MiB 3,3V
 8-bit)
 Bad block table found at page 262016, version 0x01
 Bad block table found at page 261952, version 0x01
 nand_read_bbt: Bad block at 0x0ffe0000
 nand_read_bbt: Bad block at 0x154c0000
 nand_read_bbt: Bad block at 0x1ffe0000
 Probing CS553x NAND controller CS#0 at MMIO 0x20000000
 No NAND device found!!!
 PCI: Enabling device 0000:00:0f.5 (0000 -> 0002)
 PCI: Setting latency timer of device 0000:00:0f.5 to 64
 ehci_hcd 0000:00:0f.5: EHCI Host Controller
 ehci_hcd 0000:00:0f.5: new USB bus registered, assigned bus number 1
 ehci_hcd 0000:00:0f.5: irq 10, io mem 0xfe01b000
 ehci_hcd 0000:00:0f.5: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
 PM: Adding info for usb:usb1
 PM: Adding info for No Bus:usbdev1.1_ep00
 usb usb1: configuration #1 chosen from 1 choice
 PM: Adding info for usb:1-0:1.0
 hub 1-0:1.0: USB hub found
 hub 1-0:1.0: 4 ports detected
 PM: Adding info for No Bus:usbdev1.1_ep81
 ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver
 (PCI)
 PCI: Setting latency timer of device 0000:00:0f.4 to 64
 ohci_hcd 0000:00:0f.4: OHCI Host Controller
 ohci_hcd 0000:00:0f.4: new USB bus registered, assigned bus number 2
 ohci_hcd 0000:00:0f.4: irq 10, io mem 0xfe01a000
 PM: Adding info for usb:usb2
 PM: Adding info for No Bus:usbdev2.1_ep00
 usb usb2: configuration #1 chosen from 1 choice
 PM: Adding info for usb:2-0:1.0
 hub 2-0:1.0: USB hub found
 hub 2-0:1.0: 4 ports detected
 PM: Adding info for No Bus:usbdev2.1_ep81
 usb 1-1: new high speed USB device using ehci_hcd and address 2
 USB Universal Host Controller Interface driver v3.0
 Initializing USB Mass Storage driver...
 PM: Adding info for usb:1-1
 PM: Adding info for No Bus:usbdev1.2_ep00
 usb 1-1: configuration #1 chosen from 1 choice
 PM: Adding info for usb:1-1:1.0
 PM: Adding info for No Bus:usbdev1.2_ep01
 PM: Adding info for No Bus:usbdev1.2_ep82
 PM: Adding info for No Bus:usbdev1.2_ep83
 usb 1-4: new high speed USB device using ehci_hcd and address 5
 PM: Adding info for usb:1-4
 PM: Adding info for No Bus:usbdev1.5_ep00
 usb 1-4: configuration #1 chosen from 1 choice
 PM: Adding info for usb:1-4:1.0
 PM: Adding info for No Bus:usbdev1.5_ep02
 PM: Adding info for No Bus:usbdev1.5_ep83
 usb 2-2: new low speed USB device using ohci_hcd and address 2
 PM: Adding info for usb:2-2
 PM: Adding info for No Bus:usbdev2.2_ep00
 usb 2-2: configuration #1 chosen from 1 choice
 PM: Adding info for usb:2-2:1.0
 PM: Adding info for No Bus:usbdev2.2_ep81
 usb 2-3: new low speed USB device using ohci_hcd and address 3
 PM: Adding info for usb:2-3
 PM: Adding info for No Bus:usbdev2.3_ep00
 usb 2-3: configuration #1 chosen from 1 choice
 PM: Adding info for usb:2-3:1.0
 PM: Adding info for No Bus:usbdev2.3_ep81
 PM: Adding info for usb:2-3:1.1
 PM: Adding info for No Bus:usbdev2.3_ep82
 scsi0 : SCSI emulation for USB Mass Storage devices
 PM: Adding info for No Bus:host0
 usbcore: registered new interface driver usb-storage
 USB Mass Storage support registered.
 usb-storage: device found at 2
 usb-storage: waiting for device to settle before scanning
 usbcore: registered new interface driver libusual
 usbcore: registered new interface driver hiddev
 input: Logitech as /class/input/input0
 input: USB HID v1.00 Mouse [Logitech] on usb-0000:00:0f.4-2
 input: HID 1241:1203 as /class/input/input1
 input: USB HID v1.11 Keyboard [HID 1241:1203] on usb-0000:00:0f.4-3
 input: HID 1241:1203 as /class/input/input2
 input: USB HID v1.11 Device [HID 1241:1203] on usb-0000:00:0f.4-3
 usbcore: registered new interface driver usbhid
 drivers/usb/input/hid-core.c: v2.6:USB HID core driver
 PM: Adding info for No Bus:i2c-0
 i2c_adapter i2c-0: bus error in state write
 gxfb-dcon: No DCON found on SMBus
 TCP bic registered
 Initializing XFRM netlink socket
 NET: Registered protocol family 1
 NET: Registered protocol family 17
 Using IPI Shortcut mode
 Freeing unused kernel memory: 160k freed
 Time: tsc clocksource has been installed.
 Write protecting the kernel read-only data: 678k
 PM: Adding info for No Bus:target0:0:0
 scsi 0:0:0:0: Direct-Access     JetFlash TS1GJF168        0.00 PQ: 0 ANSI:
 2
 PM: Adding info for scsi:0:0:0:0
 SCSI device sda: 2048000 512-byte hdwr sectors (1049 MB)
 sda: Write Protect is off
 sda: Mode Sense: 00 00 00 00
 sda: assuming drive cache: write through
 SCSI device sda: 2048000 512-byte hdwr sectors (1049 MB)
 sda: Write Protect is off
 sda: Mode Sense: 00 00 00 00
 sda: assuming drive cache: write through
  sda: sda1
 sd 0:0:0:0: Attached scsi removable disk sda
 PM: Adding info for No Bus:target0:0:1
 PM: Removing info for No Bus:target0:0:1
 PM: Adding info for No Bus:target0:0:2
 PM: Removing info for No Bus:target0:0:2
 PM: Adding info for No Bus:target0:0:3
 PM: Removing info for No Bus:target0:0:3
 PM: Adding info for No Bus:target0:0:4
 PM: Removing info for No Bus:target0:0:4
 PM: Adding info for No Bus:target0:0:5
 PM: Removing info for No Bus:target0:0:5
 PM: Adding info for No Bus:target0:0:6
 PM: Removing info for No Bus:target0:0:6
 PM: Adding info for No Bus:target0:0:7
 PM: Removing info for No Bus:target0:0:7
 usb-storage: device scan complete
 kjournald starting.  Commit interval 5 seconds
 EXT3-fs: mounted filesystem with ordered data mode.
 ...
 }}}

-- 
Ticket URL: <http://dev.laptop.org/ticket/285>
One Laptop Per Child <http://laptop.org/>

More information about the Bugs mailing list