FS#26655 - [python2] soft lockup on 3.0-ARCH

Attached to Project: Arch Linux
Opened by Camron Bute (CamronBute) - Friday, 28 October 2011, 01:48 GMT
Last edited by Stéphane Gaudreault (stephane) - Tuesday, 20 March 2012, 10:51 GMT
Task Type Bug Report
Category Packages: Extra
Status Closed
Assigned To Stéphane Gaudreault (stephane)
Architecture x86_64
Severity High
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 0
Private No

Details

Description:

Python2 2.7.2-2 locks my server up. I have to restart, since nothing seems to respond (except for pinging the server). Does not fix itself, as the server will stop in the middle of the night and I have to restart in the morning (I know this from seeing the marks in the cron log).



Results of "uname -a":

Linux LinuxServer 3.0-ARCH #1 SMP PREEMPT Fri Oct 7 11:35:34 CEST 2011 x86_64 AMD Athlon(tm) 64 X2 Dual Core Processor 6000+ AuthenticAMD GNU/Linux


Appears on SSH terminal when it crashes:

[root@LinuxServer ~]# 2011 Oct 27 18:37:23 LinuxServer [174812.083466] Stack:
2011 Oct 27 18:37:23 LinuxServer [174812.083488] Call Trace:
2011 Oct 27 18:37:23 LinuxServer [174812.083530] Code: 00 00 00 48 8b 05 e1 e4 6e 00 41 8d b6 cf 00 00 00 49 8d 7d 18 ff 90 d0 00 00 00 49 83 bc 24 98 4c 83 81 00 74 98 0f 1f 44 00 00 <f3> 90 49 83 7d 18 00 75 f7 eb 88 0f 1f 44 00 00 49 8d 7d 10 48


Appears in /var/log/everything.log:

Oct 27 19:19:28 LinuxServer kernel: [ 2452.080006] BUG: soft lockup - CPU#0 stuck for 22s! [python2:1019]
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080046] Modules linked in: ipv6 ext2 radeon floppy ttm sg serio_raw r8169 snd_hda_codec_realtek usb_storage uas snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd drm_kms_helper drm i2c_algo_bit soundcore shpchp pci_hotplug wmi thermal evdev pcspkr fan sp5100_tco edac_core i2c_piix4 edac_mce_amd i2c_core k8temp processor button snd_page_alloc mii ext4 mbcache jbd2 crc16 sr_mod sd_mod cdrom pata_acpi ohci_hcd ahci libahci pata_atiixp libata scsi_mod ehci_hcd usbcore
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080075] CPU 0
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080076] Modules linked in: ipv6 ext2 radeon floppy ttm sg serio_raw r8169 snd_hda_codec_realtek usb_storage uas snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd drm_kms_helper drm i2c_algo_bit soundcore shpchp pci_hotplug wmi thermal evdev pcspkr fan sp5100_tco edac_core i2c_piix4 edac_mce_amd i2c_core k8temp processor button snd_page_alloc mii ext4 mbcache jbd2 crc16 sr_mod sd_mod cdrom pata_acpi ohci_hcd ahci libahci pata_atiixp libata scsi_mod ehci_hcd usbcore
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080098]
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080100] Pid: 1019, comm: python2 Not tainted 3.0-ARCH #1 Unknow Unknow/RS780-SB700
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080103] RIP: 0010:[<ffffffff8103e99f>] [<ffffffff8103e99f>] native_flush_tlb_others+0xff/0x130
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080111] RSP: 0018:ffff88010cad1d88 EFLAGS: 00000202
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080113] RAX: 00000000000008cf RBX: ffffffff813f2c4f RCX: 0000000000000001
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080114] RDX: 0000000000000000 RSI: 00000000000000cf RDI: 0000000000000282
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080116] RBP: ffff88010cad1db8 R08: ffff8801181c8d38 R09: 0000000000000000
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080118] R10: dead000000100100 R11: dead000000200200 R12: ffffffff813f564e
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080119] R13: ffff88010cad1d08 R14: ffffffff811158b5 R15: ffff88010cad1ce8
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080122] FS: 00007f0abbbcb700(0000) GS:ffff88011fc00000(0000) knlGS:0000000000000000
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080124] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080125] CR2: 00007f0acfd4b000 CR3: 000000010c992000 CR4: 00000000000006f0
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080127] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080129] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080131] Process python2 (pid: 1019, threadinfo ffff88010cad0000, task ffff880117fe8e60)
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080132] Stack:
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080144] ffff88010cad1dd8 ffffffff81128b13 ffff8801181c8a80 ffff8801181c8d38
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080147] ffff88010caa3e70 ffff88010f16cbb0 ffff88010cad1dd8 ffffffff8103ead2
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080150] ffff88010caa3dc0 ffff88010cad1e48 ffff88010cad1e08 ffffffff8111ad66
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080153] Call Trace:
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080170] [<ffffffff81128b13>] ? unlink_anon_vmas+0x153/0x1d0
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080173] [<ffffffff8103ead2>] flush_tlb_mm+0x62/0xd0
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080176] [<ffffffff8111ad66>] tlb_flush_mmu+0x46/0x90
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080178] [<ffffffff8111adc4>] tlb_finish_mmu+0x14/0x40
Oct 27 19:19:28 LinuxServer kernel: [ 2452.080181] [<ffffffff8112Oct 27 19:21:26 LinuxServer syslog-ng[724]: syslog-ng starting up; version='3.2.4'

Steps to reproduce:

Not sure. Appears to be happening at random, and regardless of any discernible activity
This task depends upon

Closed by  Stéphane Gaudreault (stephane)
Tuesday, 20 March 2012, 10:51 GMT
Reason for closing:  Works for me
Comment by Jelle van der Waa (jelly) - Saturday, 29 October 2011, 11:32 GMT
What python application are you running?
Comment by Camron Bute (CamronBute) - Saturday, 29 October 2011, 16:43 GMT
I am running sabnzbd 0.6.9-1
Comment by Ludovic Fauvet (etix) - Sunday, 30 October 2011, 15:34 GMT
Same here when starting xsane.
The process can't be killed afterwards.

Output from dmesg:

[ 240.293369] INFO: task python2:529 blocked for more than 120 seconds.
[ 240.293373] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 240.293376] python2 D ffff880117aaffd8 0 529 1 0x00000000
[ 240.293381] ffff880117aafd88 0000000000000082 ffff8801168f8b10 ffff8800ac699a80
[ 240.293386] ffff880117787300 ffff880117aaffd8 ffff880117aaffd8 ffff880117aaffd8
[ 240.293390] ffff8801112bab20 ffff880117787300 ffff880117aafde0 0000000000000024
[ 240.293395] Call Trace:
[ 240.293403] [<ffffffff81161600>] ? cdev_put+0x30/0x30
[ 240.293408] [<ffffffff8117bc00>] ? mntput_no_expire+0x30/0xe0
[ 240.293412] [<ffffffff8117bccf>] ? mntput+0x1f/0x30
[ 240.293416] [<ffffffff81168152>] ? path_put+0x22/0x30
[ 240.293420] [<ffffffff8116c3c8>] ? do_last+0xb8/0x9c0
[ 240.293434] [<ffffffff81405f3f>] schedule+0x3f/0x60
[ 240.293438] [<ffffffff81407589>] __mutex_lock_slowpath+0x139/0x330
[ 240.293444] [<ffffffff81407796>] mutex_lock+0x16/0x30
[ 240.293450] [<ffffffffa008d716>] pp_ioctl+0x36/0x8d8 [ppdev]
[ 240.293454] [<ffffffff8116d1d2>] ? do_filp_open+0x42/0xa0
[ 240.293459] [<ffffffff8116f31f>] do_vfs_ioctl+0x8f/0x500
[ 240.293464] [<ffffffff8114711f>] ? kmem_cache_free+0x2f/0x150
[ 240.293468] [<ffffffff81168ca3>] ? putname+0x33/0x50
[ 240.293472] [<ffffffff8116f821>] sys_ioctl+0x91/0xa0
[ 240.293476] [<ffffffff81409682>] system_call_fastpath+0x16/0x1b
Comment by Camron Bute (CamronBute) - Thursday, 10 November 2011, 04:11 GMT
Upgrading to sabnzbd-0.6.10-1 does not solve the problem
Comment by Stéphane Gaudreault (stephane) - Tuesday, 10 January 2012, 22:11 GMT
Do you still have this problem ?
Comment by Camron Bute (CamronBute) - Tuesday, 10 January 2012, 22:14 GMT
This was a problem up until about 1 month ago when I moved to a different server solution because the error made my server useless.

Loading...