2009-06-12 13:15:50

by Roman Mindalev

[permalink] [raw]
Subject: [2.6.30-02327-g8ebf975] idr_remove called for id=68 which is not allocated.

Hi. I'm updated to 2.6.30-git3 today.
Box running normally hour or two. But after any process can't to be
running. It's started from this line in log:

[ 3077.464204] idr_remove called for id=68 which is not allocated.

Traces from processes like this:

Jun 12 15:40:04 desktop kernel: [ 3077.464215] Pid: 8921, comm: udevd
Tainted: G W 2.6.30-02327-g8ebf975 #1
Jun 12 15:40:04 desktop kernel: [ 3077.464221] Call Trace:
Jun 12 15:40:04 desktop kernel: [ 3077.464237] [<ffffffff812b9faf>]
idr_remove+0x7f/0x200
Jun 12 15:40:04 desktop kernel: [ 3077.464247] [<ffffffff8148a90d>] ?
_spin_lock+0x6d/0x80
Jun 12 15:40:04 desktop kernel: [ 3077.464258] [<ffffffff811568a2>] ?
inotify_destroy_mark_entry+0xb2/0xf0
Jun 12 15:40:04 desktop kernel: [ 3077.464267] [<ffffffff811568b6>]
inotify_destroy_mark_entry+0xc6/0xf0
Jun 12 15:40:04 desktop kernel: [ 3077.464277] [<ffffffff81155bce>]
inotify_freeing_mark+0xe/0x10
Jun 12 15:40:04 desktop kernel: [ 3077.464285] [<ffffffff81153fff>]
fsnotify_destroy_mark_by_entry+0x13f/0x1a0
Jun 12 15:40:04 desktop kernel: [ 3077.464295] [<ffffffff81156833>]
inotify_destroy_mark_entry+0x43/0xf0
Jun 12 15:40:04 desktop kernel: [ 3077.464304] [<ffffffff81156977>]
sys_inotify_rm_watch+0x97/0xf0
Jun 12 15:40:04 desktop kernel: [ 3077.464315] [<ffffffff8100b6db>]
system_call_fastpath+0x16/0x1b
Jun 12 15:40:04 desktop kernel: [ 3077.472929] PGD 1003067 PUD 0
Jun 12 15:40:04 desktop kernel: [ 3077.472958] CPU 1
Jun 12 15:40:04 desktop kernel: [ 3077.472963] Modules linked in:
nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack xt_tcpudp xt_limit iptable_filter ip_tables x_tables ipv6 snd_hda_codec_realtek snd_hda_intel snd_hda_codec hid_a4tech snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm i2c_i801 uhci_hcd usbhid i2c_core usb_storage snd_timer snd_page_alloc
Jun 12 15:40:04 desktop kernel: [ 3077.473029] Pid: 8921, comm: udevd
Tainted: G W 2.6.30-02327-g8ebf975 #1 P31-ES3G
Jun 12 15:40:04 desktop kernel: [ 3077.473036] RIP:
0010:[<ffffffff811165c8>] [<ffffffff811165c8>] kmem_cache_alloc+0x68/0x190
Jun 12 15:40:04 desktop kernel: [ 3077.473047] RSP:
0018:ffff880068193d20 EFLAGS: 00010086
Jun 12 15:40:04 desktop kernel: [ 3077.473053] RAX: 0000000000000000
RBX: 0000000000000202 RCX: 0000000000000000
Jun 12 15:40:04 desktop kernel: [ 3077.473059] RDX: ffff88002c3abe60
RSI: 0000000000000001 RDI: ffffffff811165a4
Jun 12 15:40:04 desktop kernel: [ 3077.473066] RBP: ffff880068193d60
R08: 0000000000000001 R09: 0000000000000001
Jun 12 15:40:04 desktop kernel: [ 3077.473072] R10: ffff8800ced42b30
R11: 0000000000000001 R12: ffffffff00000000
Jun 12 15:40:04 desktop kernel: [ 3077.473078] R13: ffff88012f820e00
R14: 00000000000000d0 R15: ffffffff8105013e
Jun 12 15:40:04 desktop kernel: [ 3077.473086] FS:
00007f02245c6780(0000) GS:ffff88002c1dc000(0000) knlGS:0000000000000000
Jun 12 15:40:04 desktop kernel: [ 3077.473093] CS: 0010 DS: 0000 ES:
0000 CR0: 000000008005003b
Jun 12 15:40:04 desktop kernel: [ 3077.473099] CR2: ffffffff00000000
CR3: 0000000068001000 CR4: 00000000000006e0
Jun 12 15:40:04 desktop kernel: [ 3077.473105] DR0: 0000000000000000
DR1: 0000000000000000 DR2: 0000000000000000
Jun 12 15:40:04 desktop kernel: [ 3077.473111] DR3: 0000000000000000
DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jun 12 15:40:04 desktop kernel: [ 3077.473118] Process udevd (pid:
8921, threadinfo ffff880068192000, task ffff8800ced42370)
Jun 12 15:40:04 desktop kernel: [ 3077.473128] ffffffff812be248
ffff8801108d0738 000000a8c2cbe880 ffff8801108d0768
Jun 12 15:40:04 desktop kernel: [ 3077.473420] RSP <ffff880068193d20>
Jun 12 15:40:04 desktop kernel: [ 3077.473432] ---[ end trace
4eaa2a86a8e2da24 ]---

Kernel compiled as x86_64 SMP PREEMPT, no this problem with 2.6.30


Attachments:
(No filename) (3.66 kB)
120609.log.tar.gz (31.29 kB)
config-2.6.30-02327-g8ebf975.tar.gz (12.82 kB)
Download all attachments

2009-06-22 19:57:19

by Andrew Morton

[permalink] [raw]
Subject: Re: [2.6.30-02327-g8ebf975] idr_remove called for id=68 which is not allocated.

On Fri, 12 Jun 2009 16:45:17 +0400
Roman Mindalev <[email protected]> wrote:

> Hi. I'm updated to 2.6.30-git3 today.
> Box running normally hour or two. But after any process can't to be
> running. It's started from this line in log:
>
> [ 3077.464204] idr_remove called for id=68 which is not allocated.
>
> Traces from processes like this:
>
> Jun 12 15:40:04 desktop kernel: [ 3077.464215] Pid: 8921, comm: udevd
> Tainted: G W 2.6.30-02327-g8ebf975 #1
> Jun 12 15:40:04 desktop kernel: [ 3077.464221] Call Trace:
> Jun 12 15:40:04 desktop kernel: [ 3077.464237] [<ffffffff812b9faf>]
> idr_remove+0x7f/0x200
> Jun 12 15:40:04 desktop kernel: [ 3077.464247] [<ffffffff8148a90d>] ?
> _spin_lock+0x6d/0x80
> Jun 12 15:40:04 desktop kernel: [ 3077.464258] [<ffffffff811568a2>] ?
> inotify_destroy_mark_entry+0xb2/0xf0
> Jun 12 15:40:04 desktop kernel: [ 3077.464267] [<ffffffff811568b6>]
> inotify_destroy_mark_entry+0xc6/0xf0
> Jun 12 15:40:04 desktop kernel: [ 3077.464277] [<ffffffff81155bce>]
> inotify_freeing_mark+0xe/0x10
> Jun 12 15:40:04 desktop kernel: [ 3077.464285] [<ffffffff81153fff>]
> fsnotify_destroy_mark_by_entry+0x13f/0x1a0
> Jun 12 15:40:04 desktop kernel: [ 3077.464295] [<ffffffff81156833>]
> inotify_destroy_mark_entry+0x43/0xf0
> Jun 12 15:40:04 desktop kernel: [ 3077.464304] [<ffffffff81156977>]
> sys_inotify_rm_watch+0x97/0xf0
> Jun 12 15:40:04 desktop kernel: [ 3077.464315] [<ffffffff8100b6db>]
> system_call_fastpath+0x16/0x1b
> Jun 12 15:40:04 desktop kernel: [ 3077.472929] PGD 1003067 PUD 0
> Jun 12 15:40:04 desktop kernel: [ 3077.472958] CPU 1
> Jun 12 15:40:04 desktop kernel: [ 3077.472963] Modules linked in:

Thanks. Eric has recently merged a patch which should fix this
("inotify: inotify_destroy_mark_entry could get called twice"). Please
retest Linus's current tree?