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

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

[Roman Mindalev]

[-- Attachment #1: Type: text/plain, Size: 3752 bytes --]

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

[-- Attachment #2: 120609.log.tar.gz --]
[-- Type: application/x-gzip, Size: 32036 bytes --]

[-- Attachment #3: config-2.6.30-02327-g8ebf975.tar.gz --]
[-- Type: application/x-gzip, Size: 13132 bytes --]

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

[Andrew Morton]

On Fri, 12 Jun 2009 16:45:17 +0400
Roman Mindalev <r000n@r000n.net> 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?