Bug 511988 - dma-debug warning for ethernet drivers on link up
Summary: dma-debug warning for ethernet drivers on link up
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Fedora
Classification: Fedora
Component: kernel
Version: 12
Hardware: All
OS: Linux
low
medium
Target Milestone: ---
Assignee: Kernel Maintainer List
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2009-07-15 21:04 UTC by Peter Robinson
Modified: 2010-03-07 13:13 UTC (History)
4 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2010-03-07 13:13:01 UTC
Type: ---
Embargoed:


Attachments (Terms of Use)

Description Peter Robinson 2009-07-15 21:04:45 UTC
oops in atl1e driver on link up in kernel 2.6.31-0.62.rc2.git4.fc12.i686.PAE on eeePC 901

ADDRCONF(NETDEV_UP): eth0: link is not ready
type=1300 audit(1247691253.136:15109): arch=40000003 syscall=5 success=yes exit=3 a0=b803286c a1=0 a2=1000 a3=b803286c items=1 ppid=991 pid=992 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="rpm" exe="/bin/rpm" key=(null)
type=1307 audit(1247691253.136:15109):  cwd="/"
type=1302 audit(1247691253.136:15109): item=0 name="/lib/libpopt.so.0" inode=9036 dev=08:02 mode=0100755 ouid=0 ogid=0 rdev=00:00
type=1300 audit(1247691253.137:15110): arch=40000003 syscall=5 success=yes exit=3 a0=b8030ab2 a1=0 a2=48 a3=b8030ab2 items=1 ppid=991 pid=992 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="rpm" exe="/bin/rpm" key=(null)
type=1307 audit(1247691253.137:15110):  cwd="/"
type=1302 audit(1247691253.137:15110): item=0 name="/lib/libz.so.1" inode=9628 dev=08:02 mode=0100755 ouid=0 ogid=0 rdev=00:00
type=1305 audit(1247691253.138:15111): auid=4294967295 ses=4294967295 op="remove rule" key=(null) list=2 res=1
type=1305 audit(1247691253.138:15112): audit_enabled=0 old=1 auid=4294967295 ses=4294967295 res=1
Monitor-Mwait will be used to enter C-3 state
ATL1E 0000:03:00.0: ATL1E: eth0 NIC Link is Up<100 Mbps Full Duplex>
ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
------------[ cut here ]------------
WARNING: at lib/dma-debug.c:812 check_unmap+0x34d/0x515() (Not tainted)
Hardware name: 901
ATL1E 0000:03:00.0: DMA-API: device driver frees DMA memory with wrong function [device address=0x0000000034833952] [size=90 bytes] [mapped as single] [unmapped as page]
Modules linked in: ip6t_REJECT nf_conntrack_ipv6 ip6table_filter ip6_tables ipv6 cpufreq_ondemand acpi_cpufreq dm_multipath uvcvideo videodev snd_hda_codec_realtek v4l1_compat usb_storage pcspkr btusb i2c_i801 bluetooth joydev snd_hda_intel snd_hda_codec snd_hwdep iTCO_wdt snd_pcm iTCO_vendor_support snd_timer atl1e snd soundcore snd_page_alloc eeepc_laptop hwmon rfkill ata_generic pata_acpi i915 drm i2c_algo_bit i2c_core video output [last unloaded: scsi_wait_scan]
Pid: 609, comm: avahi-daemon Not tainted 2.6.31-0.62.rc2.git4.fc12.i686.PAE #1
Call Trace:
 [<c044c01f>] warn_slowpath_common+0x7b/0xa3
 [<c060ccc4>] ? check_unmap+0x34d/0x515
 [<c044c0b0>] warn_slowpath_fmt+0x34/0x48
 [<c060ccc4>] check_unmap+0x34d/0x515
 [<c0475564>] ? register_lock_class+0x25/0x2a9
 [<c060d095>] debug_dma_unmap_page+0x71/0x8a
 [<f835616b>] pci_unmap_page.clone.3+0x62/0x7e [atl1e]
 [<f8357182>] atl1e_intr+0x2ea/0x3ba [atl1e]
 [<c04a117f>] ? handle_edge_irq+0xb3/0x111
 [<c049f4a6>] handle_IRQ_event+0x56/0x112
 [<c04a1188>] handle_edge_irq+0xbc/0x111
 [<c040cff0>] handle_irq+0x50/0x6c
 [<c040c711>] do_IRQ+0x52/0xb7
 [<c040b035>] common_interrupt+0x35/0x3c
 [<c082a253>] ? _spin_unlock_irq+0x37/0x50
 [<c0444e40>] finish_task_switch+0x85/0xfd
 [<c0444df9>] ? finish_task_switch+0x3e/0xfd
 [<c082826c>] schedule+0x797/0x805
 [<c047635a>] ? mark_lock+0x29/0x1f6
 [<c0476575>] ? mark_held_locks+0x4e/0x78
 [<c0829240>] schedule_hrtimeout_range+0x43/0xe7
 [<c04105e5>] ? sched_clock+0x9/0xd
 [<c0828b94>] ? __mutex_unlock_slowpath+0x105/0x124
 [<c0476842>] ? trace_hardirqs_on_caller+0x122/0x155
 [<c047688e>] ? trace_hardirqs_on+0x19/0x2c
 [<c050d021>] poll_schedule_timeout+0x3e/0x67
 [<c050dfb6>] do_sys_poll+0x359/0x3e9
 [<c04750db>] ? lock_release_holdtime+0x39/0x143
 [<c050d643>] ? __pollwait+0x0/0xc7
 [<c050d70a>] ? pollwake+0x0/0x86
 [<c050d70a>] ? pollwake+0x0/0x86
 [<c050d70a>] ? pollwake+0x0/0x86
 [<c050d70a>] ? pollwake+0x0/0x86
 [<c050d70a>] ? pollwake+0x0/0x86
 [<c050d70a>] ? pollwake+0x0/0x86
 [<c050d70a>] ? pollwake+0x0/0x86
 [<c050d70a>] ? pollwake+0x0/0x86
 [<c047635a>] ? mark_lock+0x29/0x1f6
 [<c043b0c4>] ? __wake_up_sync_key+0x2f/0x6b
 [<c043b0e5>] ? __wake_up_sync_key+0x50/0x6b
 [<c04105e5>] ? sched_clock+0x9/0xd
 [<c082a2bb>] ? _spin_unlock_irqrestore+0x4f/0x6d
 [<c0476842>] ? trace_hardirqs_on_caller+0x122/0x155
 [<c047688e>] ? trace_hardirqs_on+0x19/0x2c
 [<c0450d60>] ? current_fs_time+0x2a/0x40
 [<c0511349>] ? touch_atime+0xca/0xf7
 [<c0506a24>] ? pipe_read+0x2d9/0x2f4
 [<c0464166>] ? autoremove_wake_function+0x0/0x55
 [<c04fe776>] ? fsnotify_access+0x62/0x7e
 [<c04fea86>] ? do_sync_read+0x0/0x10a
 [<c04ff2ee>] ? vfs_read+0xbc/0x10c
 [<c050e1c5>] sys_poll+0x53/0xaa
 [<c040a8eb>] sysenter_do_call+0x12/0x38
---[ end trace d3bdc14c53854088 ]---
Mapped at:
 [<c060d438>] debug_dma_map_page+0x85/0x168
 [<f835581b>] pci_map_single.clone.2+0x7a/0x97 [atl1e]
 [<f8355e5e>] atl1e_xmit_frame+0x626/0x8d1 [atl1e]
 [<c078b0a1>] dev_hard_start_xmit+0x230/0x2c9
 [<c079d080>] __qdisc_run+0xd6/0x1cb
eth0: no IPv6 routers present

Comment 1 Peter Robinson 2009-08-06 13:57:22 UTC
Actually this looks like it might be a little more generic than just the atl1e driver as I'm getting this booting a current rawhide livecd on KVM.


------------[ cut here ]------------
WARNING: at lib/dma-debug.c:803 check_unmap+0x1f5/0x509() (Not tainted)
Hardware name: 
8139cp 0000:00:03.0: DMA-API: device driver frees DMA memory with different size [device address=0x000000001e9f8852] [map size=1536 bytes] [unmap size=1538 bytes]
Modules linked in: ipv6 dm_multipath uinput joydev 8139too virtio_balloon 8139cp mii i2c_piix4 virtio_pci i2c_core floppy squashfs pata_acpi ata_generic [last unloaded: scsi_wait_scan]
Pid: 0, comm: swapper Not tainted 2.6.31-0.125.rc5.git2.fc12.i686 #1
Call Trace:
 [<c0444408>] warn_slowpath_common+0x7b/0xa3
 [<c0606746>] ? check_unmap+0x1f5/0x509
 [<c0444499>] warn_slowpath_fmt+0x34/0x48
 [<c0606746>] check_unmap+0x1f5/0x509
 [<c04ecb43>] ? check_valid_pointer+0x2c/0x6c
 [<c0606c00>] debug_dma_unmap_page+0x62/0x7b
 [<e0dc80be>] dma_unmap_single_attrs.clone.2+0x5a/0x75 [8139cp]
 [<e0dc8220>] cp_rx_poll+0x147/0x301 [8139cp]
 [<c077e5d4>] net_rx_action+0xa7/0x1d3
 [<c044accf>] __do_softirq+0xc8/0x192
 [<c044ade2>] do_softirq+0x49/0x7f
 [<c044af36>] irq_exit+0x48/0x8c
 [<c041c302>] smp_apic_timer_interrupt+0x7a/0x99
 [<c0404416>] apic_timer_interrupt+0x36/0x3c
 [<c0425e98>] ? native_safe_halt+0xa/0xc
 [<c040ad6f>] default_idle+0x55/0x98
 [<c046e44d>] ? trace_hardirqs_off+0x19/0x2c
 [<c04029e3>] cpu_idle+0xac/0xcd
 [<c08080ee>] rest_init+0x66/0x79
 [<c0a72ae0>] start_kernel+0x36f/0x385
 [<c0a7207e>] __init_begin+0x7e/0x96
---[ end trace f3c3298e5df24f15 ]---
Mapped at:
 [<c0606f6b>] debug_dma_map_page+0x6b/0x13b
 [<e0dc7c22>] dma_map_single_attrs.clone.1+0x78/0x93 [8139cp]
 [<e0dc86d0>] cp_init_rings+0xaa/0x12c [8139cp]
 [<e0dc87d6>] cp_open+0x84/0x154 [8139cp]
 [<c077ff2b>] dev_open+0x99/0xe4

Comment 2 Sergei LITVINENKO 2009-08-08 10:15:08 UTC
Host OS: Fedora-11
host kernel: 2.6.29.6-217.2.3.fc11.i686.PAE
qemu-system-x86-0.10.5-3.fc11.i586


Rawhide guest kernel is 2.6.31-0.125.rc5.git2.fc12.i686.PAE
Rawhide network work OK.

dmesg information (guest kernel):

------------[ cut here ]------------
WARNING: at lib/dma-debug.c:803 check_unmap+0x1f9/0x515() (Not tainted)
Hardware name:
8139cp 0000:00:03.0: DMA-API: device driver frees DMA memory with different size [device address=0x0000000035dea0e2] [map size=1536 bytes] [unmap size=1538 bytes]
Modules linked in: ipv6 dm_multipath uinput virtio_balloon ppdev 8139too i2c_piix4 8139cp mii i2c_core virtio_pci parport_pc parport floppy ata_generic pata_acpi [last unloaded: scsi_wait_scan]
Pid: 588, comm: ip Not tainted 2.6.31-0.125.rc5.git2.fc12.i686.PAE #1
Call Trace:
 [<c044caac>] warn_slowpath_common+0x7b/0xa3
 [<c0610df3>] ? check_unmap+0x1f9/0x515
 [<c044cb3d>] warn_slowpath_fmt+0x34/0x48
 [<c0610df3>] check_unmap+0x1f9/0x515
 [<c0611310>] debug_dma_unmap_page+0x6e/0x87
 [<f7ec51c2>] dma_unmap_single_attrs.clone.2+0x67/0x83 [8139cp]
 [<f7ec5332>] cp_rx_poll+0x154/0x323 [8139cp]
 [<c07935b4>] net_rx_action+0xa7/0x1d3
 [<c045330b>] ? __do_softirq+0x60/0x192
 [<c0453373>] __do_softirq+0xc8/0x192
 [<c0453486>] do_softirq+0x49/0x7f
 [<c04535da>] irq_exit+0x48/0x8c
 [<c040c6f1>] do_IRQ+0x92/0xb7
 [<c040afd5>] common_interrupt+0x35/0x3c
 [<c04e00d8>] ? unmap_vmas+0x23c/0x697
 [<c042ccf8>] ? kvm_deferred_mmu_op+0x57/0xdd
 [<c042ce34>] kvm_mmu_write+0x72/0x8b
 [<c042cfb3>] kvm_set_pte_at+0x32/0x47
 [<c04deccc>] set_pte_at+0x24/0x3c
 [<c04e2ade>] __do_fault+0x30d/0x413
 [<c04e2fc4>] handle_mm_fault+0x3e0/0x928
 [<c0835b25>] ? do_page_fault+0x145/0x2fa
 [<c0835b25>] ? do_page_fault+0x145/0x2fa
 [<c0835cb3>] do_page_fault+0x2d3/0x2fa
 [<c08359e0>] ? do_page_fault+0x0/0x2fa
 [<c0833d90>] error_code+0x78/0x80
---[ end trace ee2f7d5424e7e7eb ]---
Mapped at:
 [<c06116a6>] debug_dma_map_page+0x86/0x16c
 [<f7ec4d04>] dma_map_single_attrs.clone.1+0x7f/0x9c [8139cp]
 [<f7ec5810>] cp_init_rings+0xad/0x13e [8139cp]
 [<f7ec5925>] cp_open+0x84/0x154 [8139cp]
 [<c0794f0b>] dev_open+0x99/0xe4
hald-probe-stor used greatest stack depth: 5104 bytes left
eth0: no IPv6 routers present

Comment 3 Peter Robinson 2009-08-08 12:19:03 UTC
Updating description as its more than just atl1e driver.

Comment 4 Bug Zapper 2009-11-16 10:59:50 UTC
This bug appears to have been reported against 'rawhide' during the Fedora 12 development cycle.
Changing version to '12'.

More information and reason for this action is here:
http://fedoraproject.org/wiki/BugZappers/HouseKeeping

Comment 5 Vaclav "sHINOBI" Misek 2009-12-26 20:08:50 UTC
The problem seems to be fixed at last in the 2.6.31.9-174.fc12.x86_64. Should we close it, or is there anybody still observing this error?

Comment 6 Sergei LITVINENKO 2009-12-26 21:00:45 UTC
All looks like OK.

Comment 7 Peter Robinson 2010-03-07 13:13:01 UTC
Closing as I've not seen this issue for quite some time.


Note You need to log in before you can comment on or make changes to this bug.