OpenVZ Forum


Home » International » Russian » [SOLVED] WARNING: at kernel/futex.c:537 futex_lock_pi
[SOLVED] WARNING: at kernel/futex.c:537 futex_lock_pi [message #35957] Wed, 06 May 2009 10:34 Go to next message
chernomor is currently offline  chernomor
Messages: 7
Registered: October 2008
Location: Moscow
Junior Member
From: *inlife.ru
Debian Lenny
Linux salex 2.6.26-2-openvz-686 #1 SMP Thu Mar 26 03:55:11 UTC 2009 i686 GNU/Linux
ii linux-image-2.6-openvz-686 2.6.26+17+lenny1
ii linux-image-2.6.26-2-openvz-686 2.6.26-15
ii vzctl 3.0.22-14
ii vzquota 3.0.11-1


В одном из контейнеров поднят терминальный сервер на базе ltsp. В ТС запускается twinkle (sip-телефон), после чего в dmesg начинает сыпаться такое:
May  6 13:50:27 salex kernel: [1345407.174377] ------------[ cut here ]------------
May  6 13:50:27 salex kernel: [1345407.174384] WARNING: at kernel/futex.c:537 futex_lock_pi+0x296/0x748()
May  6 13:50:27 salex kernel: [1345407.174388] Modules linked in: nls_utf8 cifs nls_base coretemp w83627ehf hwmon_vid
i2c_dev ipt_MASQUERADE xt_state vzethdev vznetdev simfs vzrst vzcpt tun vzdquota vzmon vzdev xt_tcpudp xt_length ipt_ttl
xt_tcpmss xt_TCPMSS xt_multiport xt_limit xt_dscp ipt_REJECT ipv6 iptable_mangle iptable_filter iptable_nat nf_nat
nf_conntrack_ipv4 nf_conntrack ip_tables x_tables bridge 8021q loop evdev serio_raw i2c_i801 pcspkr psmouse i2c_core
iTCO_wdt button intel_agp agpgart ext3 jbd mbcache dm_mirror dm_log dm_snapshot dm_mod raid1 md_mod ide_cd_mod cdrom
ata_generic usbhid hid ff_memless sd_mod jmicron ahci libata scsi_mod dock ide_pci_generic ide_core ehci_hcd uhci_hcd 
usbcore e1000e thermal processor fan thermal_sys
May  6 13:50:27 salex kernel: [1345407.174600] Pid: 19045, comm: twinkle Tainted: G        W 2.6.26-2-openvz-686 #1
May  6 13:50:27 salex kernel: [1345407.174608]  [<c0129953>] warn_on_slowpath+0x40/0x66
May  6 13:50:27 salex kernel: [1345407.174622]  [<c013eaf4>] getnstimeofday+0x37/0xbc
May  6 13:50:27 salex kernel: [1345407.174631]  [<c013cf54>] ktime_get_ts+0x22/0x49
May  6 13:50:27 salex kernel: [1345407.174640]  [<c014adb6>] try_to_take_rt_mutex+0x11e/0x12a
May  6 13:50:27 salex kernel: [1345407.174650]  [<c02c4acf>] rt_mutex_slowlock+0x24/0x3e6
May  6 13:50:27 salex kernel: [1345407.174660]  [<c028a845>] tcp_ack+0x1739/0x18cf
May  6 13:50:27 salex kernel: [1345407.174673]  [<c01735b8>] find_extend_vma+0x12/0x49
May  6 13:50:27 salex kernel: [1345407.174682]  [<c0148fe1>] get_futex_key+0x6c/0x105
May  6 13:50:27 salex kernel: [1345407.174690]  [<c014ac1a>] rt_mutex_timed_lock+0x30/0x39
May  6 13:50:27 salex kernel: [1345407.174699]  [<c0149bed>] futex_lock_pi+0x296/0x748
May  6 13:50:27 salex kernel: [1345407.174710]  [<c013cf54>] ktime_get_ts+0x22/0x49
May  6 13:50:27 salex kernel: [1345407.174718]  [<c013cf88>] ktime_get+0xd/0x21
May  6 13:50:27 salex kernel: [1345407.174726]  [<c0114d5b>] apic_wait_icr_idle+0xe/0x15
May  6 13:50:27 salex kernel: [1345407.174736]  [<c01140db>] native_smp_send_reschedule+0x33/0x34
May  6 13:50:27 salex kernel: [1345407.174744]  [<c011fb8f>] __resched_task+0x5c/0x5f
May  6 13:50:27 salex kernel: [1345407.174753]  [<c011f539>] enqueue_task+0x52/0x5d
May  6 13:50:27 salex kernel: [1345407.174761]  [<c0120702>] activate_task+0xa5/0xdf
May  6 13:50:27 salex kernel: [1345407.174770]  [<c0126ec3>] check_preempt_wakeup+0xa2/0xc2
May  6 13:50:27 salex kernel: [1345407.174784]  [<c014a44d>] do_futex+0x3ae/0x6ff
May  6 13:50:27 salex kernel: [1345407.174791]  [<c0115102>] lapic_next_event+0xc/0x10
May  6 13:50:27 salex kernel: [1345407.174795]  [<c0141230>] clockevents_program_event+0xc4/0xd2
May  6 13:50:27 salex kernel: [1345407.174804]  [<c0131579>] run_timer_softirq+0x30/0x1d1
May  6 13:50:27 salex kernel: [1345407.174811]  [<c014a86f>] sys_futex+0xd1/0xe6
May  6 13:50:27 salex kernel: [1345407.174818]  [<c012e3e8>] irq_exit+0x84/0x9b
May  6 13:50:27 salex kernel: [1345407.174823]  [<c0108972>] syscall_call+0x7/0xb
May  6 13:50:27 salex kernel: [1345407.174833]  =======================
May  6 13:50:27 salex kernel: [1345407.174835] ---[ end trace d23225232a0d7822 ]---

May  6 13:50:28 salex kernel: [1345408.526274] ------------[ cut here ]------------
May  6 13:50:28 salex kernel: [1345408.526274] WARNING: at kernel/futex.c:537 futex_lock_pi+0x296/0x748()
May  6 13:50:28 salex kernel: [1345408.526274] Modules linked in: nls_utf8 cifs nls_base coretemp w83627ehf
 hwmon_vid i2c_dev ipt_MASQUERADE xt_state vzethdev vznetdev simfs vzrst vzcpt tun vzdquota vzmon vzdev
 xt_tcpudp xt_length ipt_ttl xt_tcpmss xt_TCPMSS xt_multiport xt_limit xt_dscp ipt_REJECT ipv6 iptable_mangle
 iptable_filter iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack ip_tables x_tables bridge 8021q loop evdev
 serio_raw i2c_i801 pcspkr psmouse i2c_core iTCO_wdt button intel_agp agpgart ext3 jbd mbcache dm_mirror
 dm_log dm_snapshot dm_mod raid1 md_mod ide_cd_mod cdrom ata_generic usbhid hid ff_memless sd_mod
 jmicron ahci libata scsi_mod dock ide_pci_generic ide_core ehci_hcd uhci_hcd usbcore e1000e thermal processor fan thermal_sys
May  6 13:50:28 salex kernel: [1345408.526274] Pid: 18895, comm: twinkle Tainted: G        W 2.6.26-2-openvz-686 #1
May  6 13:50:28 salex kernel: [1345408.526274]  [<c0129953>] warn_on_slowpath+0x40/0x66
May  6 13:50:28 salex kernel: [1345408.526274]  [<c02c402e>] schedule+0x854/0x886
May  6 13:50:28 salex kernel: [1345408.526274]  [<c014adb6>] try_to_take_rt_mutex+0x11e/0x12a
May  6 13:50:28 salex kernel: [1345408.526274]  [<c02c4e60>] rt_mutex_slowlock+0x3b5/0x3e6
May  6 13:50:28 salex kernel: [1345408.526274]  [<c01735b8>] find_extend_vma+0x12/0x49
May  6 13:50:28 salex kernel: [1345408.526274]  [<c0148fe1>] get_futex_key+0x6c/0x105
May  6 13:50:28 salex kernel: [1345408.526274]  [<c014ac1a>] rt_mutex_timed_lock+0x30/0x39
May  6 13:50:28 salex kernel: [1345408.526274]  [<c0149bed>] futex_lock_pi+0x296/0x748
May  6 13:50:28 salex kernel: [1345408.526274]  [<c011f539>] enqueue_task+0x52/0x5d
May  6 13:50:28 salex kernel: [1345408.526274]  [<c0120702>] activate_task+0xa5/0xdf
May  6 13:50:28 salex kernel: [1345408.526274]  [<c014a44d>] do_futex+0x3ae/0x6ff
May  6 13:50:28 salex kernel: [1345408.526274]  [<c02b2838>] unix_ioctl+0x8a/0x92
May  6 13:50:28 salex kernel: [1345408.526274]  [<c0255bef>] sock_ioctl+0x0/0x1ca
May  6 13:50:28 salex kernel: [1345408.526274]  [<c018e8dc>] vfs_ioctl+0x1c/0x5d
May  6 13:50:28 salex kernel: [1345408.526274]  [<c014a86f>] sys_futex+0xd1/0xe6
May  6 13:50:28 salex kernel: [1345408.526274]  [<c018ebaa>] sys_ioctl+0x2c/0x42
May  6 13:50:28 salex kernel: [1345408.526274]  [<c018ebba>] sys_ioctl+0x3c/0x42
May  6 13:50:28 salex kernel: [1345408.526274]  [<c0108972>] syscall_call+0x7/0xb
May  6 13:50:28 salex kernel: [1345408.526274]  =======================
May  6 13:50:28 salex kernel: [1345408.526274] ---[ end trace d23225232a0d7822 ]---

May  6 13:50:28 salex kernel: [1345408.530208] ------------[ cut here ]------------
May  6 13:50:28 salex kernel: [1345408.530208] WARNING: at kernel/futex.c:537 futex_lock_pi+0x296/0x748()
May  6 13:50:28 salex kernel: [1345408.530208] Modules linked in: nls_utf8 cifs nls_base coretemp
 w83627ehf hwmon_vid i2c_dev ipt_MASQUERADE xt_state vzethdev vznetdev simfs vzrst vzcpt tun vzdquota
vzmon vzdev xt_tcpudp xt_length ipt_ttl xt_tcpmss xt_TCPMSS xt_multiport xt_limit xt_dscp ipt_REJECT
 ipv6 iptable_mangle iptable_filter iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack ip_tables
 x_tables bridge 8021q loop evdev serio_raw i2c_i801 pcspkr psmouse i2c_core iTCO_wdt button intel_agp
 agpgart ext3 jbd mbcache dm_mirror dm_log dm_snapshot dm_mod raid1 md_mod ide_cd_mod cdrom ata_generic
 usbhid hid ff_memless sd_mod jmicron ahci libata scsi_mod dock ide_pci_generic ide_core ehci_hcd
 uhci_hcd usbcore e1000e thermal processor fan thermal_sys
May  6 13:50:28 salex kernel: [1345408.530208] Pid: 19037, comm: twinkle Tainted: G        W 2.6.26-2-openvz-686 #1
May  6 13:50:28 salex kernel: [1345408.530208]  [<c0129953>] warn_on_slowpath+0x40/0x66
May  6 13:50:28 salex kernel: [1345408.530208]  [<c02c402e>] schedule+0x854/0x886
May  6 13:50:28 salex kernel: [1345408.530208]  [<c02562ff>] sock_sendmsg+0xb8/0xd1
May  6 13:50:28 salex kernel: [1345408.530208]  [<c02c4e60>] rt_mutex_slowlock+0x3b5/0x3e6
May  6 13:50:28 salex kernel: [1345408.530208]  [<c013a2a0>] autoremove_wake_function+0x0/0x2d
May  6 13:50:28 salex kernel: [1345408.530208]  [<c01735b8>] find_extend_vma+0x12/0x49
May  6 13:50:28 salex kernel: [1345408.530208]  [<c0148fe1>] get_futex_key+0x6c/0x105
May  6 13:50:28 salex kernel: [1345408.530208]  [<c0149bed>] futex_lock_pi+0x296/0x748
May  6 13:50:28 salex kernel: [1345408.530208]  [<c013cf54>] ktime_get_ts+0x22/0x49
May  6 13:50:28 salex kernel: [1345408.530208]  [<c013cf88>] ktime_get+0xd/0x21
May  6 13:50:28 salex kernel: [1345408.530208]  [<c0114d5b>] apic_wait_icr_idle+0xe/0x15
May  6 13:50:28 salex kernel: [1345408.530208]  [<c0120702>] activate_task+0xa5/0xdf
May  6 13:50:28 salex kernel: [1345408.530208]  [<c0126ec3>] check_preempt_wakeup+0xa2/0xc2
May  6 13:50:28 salex kernel: [1345408.530208]  [<c014a44d>] do_futex+0x3ae/0x6ff
May  6 13:50:28 salex kernel: [1345408.530208]  [<c0256bd9>] sys_send+0x19/0x1d
May  6 13:50:28 salex kernel: [1345408.530208]  [<c014a86f>] sys_futex+0xd1/0xe6
May  6 13:50:28 salex kernel: [1345408.530208]  [<c018f3ac>] sys_poll+0x3b/0x6e
May  6 13:50:28 salex kernel: [1345408.530208]  [<c0108972>] syscall_call+0x7/0xb
May  6 13:50:28 salex kernel: [1345408.530208]  =======================
May  6 13:50:28 salex kernel: [1345408.530208] ---[ end trace d23225232a0d7822 ]---



Встречаются также проблемы и с firefox, но реже:
May  4 15:43:51 salex kernel: [1100718.210427] ------------[ cut here ]------------
May  4 15:43:51 salex kernel: [1100718.210427] WARNING: at kernel/futex.c:537 futex_lock_pi+0x296/0x748()
May  4 15:43:51 salex kernel: [1100718.210427] Modules linked in: nls_utf8 cifs nls_base coretemp
 w83627ehf hwmon_vid i2c_dev ipt_MASQUERADE xt_state vzethdev vznetdev simfs vzrst vzcpt tun vzdquota
 vzmon vzdev xt_tcpudp xt_length ipt_ttl xt_tcpmss xt_TCPMSS xt_multiport xt_limit xt_dscp ipt_REJECT
 ipv6 iptable_mangle iptable_filter iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack ip_tables
 x_tables bridge 8021q loop evdev serio_raw i2c_i801 pcspkr psmouse i2c_core iTCO_wdt button intel_agp
 agpgart ext3 jbd mbcache dm_mirror dm_log dm_snapshot dm_mod raid1 md_mod ide_cd_mod cdrom ata_generic
 usbhid hid ff_memless sd_mod jmicron ahci libata scsi_mod dock ide_pci_generic ide_core ehci_hcd
 uhci_hcd usbcore e1000e thermal processor fan thermal_sys
May  4 15:43:51 salex kernel: [1100718.210427] Pid: 18615, comm: firefox-bin Tainted: G        W 2.6.26-2-openvz-686 #1
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0129953>] warn_on_slowpath+0x40/0x66
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0257952>] sock_common_recvmsg+0x2f/0x45
May  4 15:43:51 salex kernel: [1100718.210427]  [<c025622c>] sock_recvmsg+0xc8/0xe3
May  4 15:43:51 salex kernel: [1100718.210427]  [<c02562ff>] sock_sendmsg+0xb8/0xd1
May  4 15:43:51 salex kernel: [1100718.210427]  [<c013cf54>] ktime_get_ts+0x22/0x49
May  4 15:43:51 salex kernel: [1100718.210427]  [<c013a2a0>] autoremove_wake_function+0x0/0x2d
May  4 15:43:51 salex kernel: [1100718.210427]  [<c01735b8>] find_extend_vma+0x12/0x49
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0148fe1>] get_futex_key+0x6c/0x105
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0149bed>] futex_lock_pi+0x296/0x748
May  4 15:43:51 salex kernel: [1100718.210427]  [<c013cf54>] ktime_get_ts+0x22/0x49
May  4 15:43:51 salex kernel: [1100718.210427]  [<c013cf88>] ktime_get+0xd/0x21
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0114d5b>] apic_wait_icr_idle+0xe/0x15
May  4 15:43:51 salex kernel: [1100718.210427]  [<c011f539>] enqueue_task+0x52/0x5d
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0120702>] activate_task+0xa5/0xdf
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0126ec3>] check_preempt_wakeup+0xa2/0xc2
May  4 15:43:51 salex kernel: [1100718.210427]  [<c014a44d>] do_futex+0x3ae/0x6ff
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0256bd9>] sys_send+0x19/0x1d
May  4 15:43:51 salex kernel: [1100718.210427]  [<c014a86f>] sys_futex+0xd1/0xe6
May  4 15:43:51 salex kernel: [1100718.210427]  [<c018f3ac>] sys_poll+0x3b/0x6e
May  4 15:43:51 salex kernel: [1100718.210427]  [<c0108972>] syscall_call+0x7/0xb
May  4 15:43:51 salex kernel: [1100718.210427]  =======================
May  4 15:43:51 salex kernel: [1100718.210427] ---[ end trace d23225232a0d7822 ]---


Что это может быть?

---

UPD: помог вот этот патч: http://bugzilla.openvz.org/attachment.cgi?id=977

тестовый запуск проблем не выявил

[Updated on: Thu, 28 May 2009 15:52]

Report message to a moderator

Re: WARNING: at kernel/futex.c:537 futex_lock_pi [message #35962 is a reply to message #35957] Wed, 06 May 2009 15:57 Go to previous messageGo to next message
maratrus is currently offline  maratrus
Messages: 1495
Registered: August 2007
Location: Moscow
Senior Member
From: *sw.ru
Здравствуйте,

очень похоже на
http://forum.openvz.org/index.php?t=msg&th=6955&star t=0&
если есть возможность, проверьте, пожалуйста патч.
Да, и забейте пожалуйста баг.
http://bugzilla.openvz.org/
Re: WARNING: at kernel/futex.c:537 futex_lock_pi [message #35964 is a reply to message #35962] Wed, 06 May 2009 18:59 Go to previous messageGo to next message
chernomor is currently offline  chernomor
Messages: 7
Registered: October 2008
Location: Moscow
Junior Member
From: *dnat.migtel.ru
Здравствуйте.

Действительно похоже, правда ядро, к счастью, не паникует.

Попробую завтра пересобрать.
Re: WARNING: at kernel/futex.c:537 futex_lock_pi [message #35965 is a reply to message #35962] Wed, 06 May 2009 19:33 Go to previous messageGo to next message
chernomor is currently offline  chernomor
Messages: 7
Registered: October 2008
Location: Moscow
Junior Member
From: *dnat.migtel.ru
к сожалению, патч наложился наполовину - вторая часть не подходит

Вот то, что есть в kernel/futex.c:
static int fixup_pi_state_owner(u32 __user *uaddr, struct futex_q *q,         
                                struct task_struct *newowner,                 
                                struct rw_semaphore *fshared)                 
{                                                                             
        u32 newtid = task_pid_vnr(newowner) | FUTEX_WAITERS;                  
        struct futex_pi_state *pi_state = q->pi_state;                        
        struct task_struct *oldowner = pi_state->owner;   


а вот вторая часть патча:
@@ -1047,7 +1047,7 @@ static void unqueue_me_pi(struct futex_q                
 static int fixup_pi_state_owner(u32 *uaddr, struct futex_q *q,               
                                struct task_struct *newowner)                 
 {                                                                            
-       u32 newtid = newowner->pid | FUTEX_WAITERS;                           
+       u32 newtid = virt_pid(newowner) | FUTEX_WAITERS;                      
        struct futex_pi_state *pi_state = q->pi_state;                        
        u32 uval, curval, newval;                                             
        int ret;

понять, можно ли task_pid_vnr заменить на virt_pid, я не смог Sad

Re: WARNING: at kernel/futex.c:537 futex_lock_pi [message #35966 is a reply to message #35962] Wed, 06 May 2009 20:03 Go to previous message
chernomor is currently offline  chernomor
Messages: 7
Registered: October 2008
Location: Moscow
Junior Member
From: *dnat.migtel.ru
баг: http://bugzilla.openvz.org/show_bug.cgi?id=1262&action=V iew
Previous Topic: Исчезают маршруты после перезагрузки VE
Next Topic: зависают VPSы на HN
Goto Forum:
  


Current Time: Sat Dec 07 20:02:14 GMT 2019