Attachment 600213 Details for Bug 842942 – Guest call trace at the 2nd time doing s3

Guest call trace at the 2nd time doing s3

s3-second-times-calltrace.txt (text/plain), 12.11 KB, created by Qunfang Zhang on 2012-07-25 02:50:56 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Qunfang Zhang

Created: 2012-07-25 02:50:56 UTC

Size: 12.11 KB

patch

obsolete

>Disabling non-boot CPUs ...
>CPU 1 is now offline
>SMP alternatives: switching to UP code
>CPU 1 offline: Remove Rx thread
>CPU1 is down
>Stopping tasks: ============================================================================================================================|
>pci_device_suspend(): virtio_pci_suspend+0x0/0x6 [virtio_pci]() returns -22
>suspend_device(): pci_device_suspend+0x0/0x34() returns -22
>Could not suspend device 0000:00:03.0: error -22
>pnp: Failed to activate device 00:02.
>pnp: Failed to activate device 00:03.
>pnp: Failed to activate device 00:05.
>pnp: Failed to activate device 00:06.
>Some devices failed to suspend
>Restarting tasks... done
>Enabling non-boot CPUs ...
>SMP alternatives: switching to SMP code
>Booting processor 1/1 eip 11000
>CPU 1 irqstacks, hard=c0776000 soft=c0756000
>Initializing CPU#1
>Calibrating delay using timer specific routine.. 3990.72 BogoMIPS (lpj=1995361)
>CPU: After generic identify, caps: 178bfbfd 2191abfd 00000000 00000000 80000001 00000000 00000002
>CPU: After vendor identify, caps: 178bfbfd 2191abfd 00000000 00000000 80000001 00000000 00000002
>, L1 D cache: 32K
>CPU: After all inits, caps: 178bf3fd 2191abfd 00000000 00000040 80000001 00000000 00000002
>Intel machine check architecture supported.
>Intel machine check reporting enabled on CPU#1.
>CPU1: Intel QEMU Virtual CPU version 0.9.1 stepping 03
>kvm-clock: cpu 1, msr 0:a012aa5, secondary cpu clock
>bnx2i: CPU 1 online: Create Rx thread
>CPU1 is up
>Disabling non-boot CPUs ...
>CPU 1 is now offline
>SMP alternatives: switching to UP code
>CPU 1 offline: Remove Rx thread
>CPU1 is down
>Stopping tasks: ===============================================================================================================================|
>pci_device_suspend(): virtio_pci_suspend+0x0/0x6 [virtio_pci]() returns -22
>suspend_device(): pci_device_suspend+0x0/0x34() returns -22
>Could not suspend device 0000:00:03.0: error -22
>pnp: Failed to activate device 00:02.
>pnp: Failed to activate device 00:03.
>pnp: Failed to activate device 00:05.
>pnp: Failed to activate device 00:06.
>Some devices failed to suspend
>Restarting tasks... done
>Enabling non-boot CPUs ...
>SMP alternatives: switching to SMP code
>Booting processor 1/1 eip 11000
>CPU 1 irqstacks, hard=c0776000 soft=c0756000
>Initializing CPU#1
>Calibrating delay using timer specific routine.. 3991.09 BogoMIPS (lpj=1995549)
>CPU: After generic identify, caps: 178bfbfd 2191abfd 00000000 00000000 80000001 00000000 00000002
>CPU: After vendor identify, caps: 178bfbfd 2191abfd 00000000 00000000 80000001 00000000 00000002
>, L1 D cache: 32K
>CPU: After all inits, caps: 178bf3fd 2191abfd 00000000 00000040 80000001 00000000 00000002
>Intel machine check architecture supported.
>Intel machine check reporting enabled on CPU#1.
>CPU1: Intel QEMU Virtual CPU version 0.9.1 stepping 03
>kvm-clock: cpu 1, msr 0:a012aa5, secondary cpu clock
>bnx2i: CPU 1 online: Create Rx thread
>CPU1 is up
>INFO: task events/0:8 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>events/0      D 00000029  3324     8      1            10     4 (L-TLB)
>       f7feff2c 00000046 21bd399a 00000029 00000000 00000000 00000002 0000000a 
>       f7c27000 21bd4520 00000029 00000b86 00000000 f7c2710c ca00c480 f59bdc80 
>       c0695570 ca009380 00000000 c06953c0 f59bdc80 00002000 f7c27000 f7feff88 
>Call Trace:
> [<c06252a2>] schedule+0x9ce/0xa57
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c054f1bc>] console_callback+0x6/0xbf
> [<c0434239>] run_workqueue+0x81/0xc5
> [<c054f1b6>] console_callback+0x0/0xbf
> [<c0434c9c>] worker_thread+0xd9/0x10d
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0434bc3>] worker_thread+0x0/0x10d
> [<c04370d6>] kthread+0xc0/0xee
> [<c0437016>] kthread+0x0/0xee
> [<c0405c87>] kernel_thread_helper+0x7/0x10
> =======================
>INFO: task gpm:2533 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>gpm           D 00000026  2584  2533      1          2542  2523 (NOTLB)
>       f483be80 00000082 936a4268 00000026 c06a2300 00000000 00000001 0000000a 
>       f5a26550 936ac827 00000026 000085bf 00000000 f5a2665c ca00c480 f59de040 
>       00000044 c06aef68 00000000 00000001 f483be80 c041ec90 ca00c93c 00000000 
>Call Trace:
> [<c041ec90>] __wake_up+0x2a/0x3d
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c054cb1c>] con_open+0x0/0x83
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c054cb2b>] con_open+0xf/0x83
> [<c0542b3d>] tty_open+0x185/0x2f1
> [<c04803e6>] chrdev_open+0x117/0x132
> [<c0487bb2>] open_namei+0x268/0x5d6
> [<c04802cf>] chrdev_open+0x0/0x132
> [<c04769f9>] __dentry_open+0xc7/0x1ab
> [<c0476b41>] nameidata_to_filp+0x19/0x28
> [<c0476b7b>] do_filp_open+0x2b/0x31
> [<c0476bbf>] do_sys_open+0x3e/0xae
> [<c0476c5c>] sys_open+0x16/0x18
> [<c0404f4b>] syscall_call+0x7/0xb
> =======================
>INFO: task bash:3326 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>bash          D 00000026  2572  3326   3320                     (NOTLB)
>       f3808ee8 00000086 938d5107 00000026 00080000 00005377 00000286 00000007 
>       ca10baa0 938d9937 00000026 00004830 00000000 ca10bbac ca00c480 f5a6dac0 
>       00000296 00000001 00000000 00000002 c0436ec9 f5a2f550 ca0151d8 00000296 
>Call Trace:
> [<c0436ec9>] kthread_bind+0x1d/0x2e
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c0441fb9>] pm_restore_console+0x5/0x1f
> [<c0441a29>] enter_state+0x116/0x170
> [<c0441b08>] state_store+0x85/0x99
> [<c0441a83>] state_store+0x0/0x99
> [<c04b1cbe>] subsys_attr_store+0x1e/0x22
> [<c04b1db0>] sysfs_write_file+0x91/0xbb
> [<c04b1d1f>] sysfs_write_file+0x0/0xbb
> [<c0478603>] vfs_write+0xa1/0x143
> [<c0478c59>] sys_write+0x3c/0x63
> [<c0404f4b>] syscall_call+0x7/0xb
> =======================
>INFO: task events/0:8 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>events/0      D 00000029  3324     8      1            10     4 (L-TLB)
>       f7feff2c 00000046 21bd399a 00000029 00000000 00000000 00000002 0000000a 
>       f7c27000 21bd4520 00000029 00000b86 00000000 f7c2710c ca00c480 f59bdc80 
>       c0695570 ca009380 00000000 c06953c0 f59bdc80 00002000 f7c27000 f7feff88 
>Call Trace:
> [<c06252a2>] schedule+0x9ce/0xa57
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c054f1bc>] console_callback+0x6/0xbf
> [<c0434239>] run_workqueue+0x81/0xc5
> [<c054f1b6>] console_callback+0x0/0xbf
> [<c0434c9c>] worker_thread+0xd9/0x10d
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0434bc3>] worker_thread+0x0/0x10d
> [<c04370d6>] kthread+0xc0/0xee
> [<c0437016>] kthread+0x0/0xee
> [<c0405c87>] kernel_thread_helper+0x7/0x10
> =======================
>INFO: task gpm:2533 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>gpm           D 00000026  2584  2533      1          2542  2523 (NOTLB)
>       f483be80 00000082 936a4268 00000026 c06a2300 00000000 00000001 0000000a 
>       f5a26550 936ac827 00000026 000085bf 00000000 f5a2665c ca00c480 f59de040 
>       00000044 c06aef68 00000000 00000001 f483be80 c041ec90 ca00c93c 00000000 
>Call Trace:
> [<c041ec90>] __wake_up+0x2a/0x3d
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c054cb1c>] con_open+0x0/0x83
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c054cb2b>] con_open+0xf/0x83
> [<c0542b3d>] tty_open+0x185/0x2f1
> [<c04803e6>] chrdev_open+0x117/0x132
> [<c0487bb2>] open_namei+0x268/0x5d6
> [<c04802cf>] chrdev_open+0x0/0x132
> [<c04769f9>] __dentry_open+0xc7/0x1ab
> [<c0476b41>] nameidata_to_filp+0x19/0x28
> [<c0476b7b>] do_filp_open+0x2b/0x31
> [<c0476bbf>] do_sys_open+0x3e/0xae
> [<c0476c5c>] sys_open+0x16/0x18
> [<c0404f4b>] syscall_call+0x7/0xb
> =======================
>INFO: task bash:3326 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>bash          D 00000026  2572  3326   3320                     (NOTLB)
>       f3808ee8 00000086 938d5107 00000026 00080000 00005377 00000286 00000007 
>       ca10baa0 938d9937 00000026 00004830 00000000 ca10bbac ca00c480 f5a6dac0 
>       00000296 00000001 00000000 00000002 c0436ec9 f5a2f550 ca0151d8 00000296 
>Call Trace:
> [<c0436ec9>] kthread_bind+0x1d/0x2e
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c0441fb9>] pm_restore_console+0x5/0x1f
> [<c0441a29>] enter_state+0x116/0x170
> [<c0441b08>] state_store+0x85/0x99
> [<c0441a83>] state_store+0x0/0x99
> [<c04b1cbe>] subsys_attr_store+0x1e/0x22
> [<c04b1db0>] sysfs_write_file+0x91/0xbb
> [<c04b1d1f>] sysfs_write_file+0x0/0xbb
> [<c0478603>] vfs_write+0xa1/0x143
> [<c0478c59>] sys_write+0x3c/0x63
> [<c0404f4b>] syscall_call+0x7/0xb
> =======================
>INFO: task events/0:8 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>events/0      D 00000029  3324     8      1            10     4 (L-TLB)
>       f7feff2c 00000046 21bd399a 00000029 00000000 00000000 00000002 0000000a 
>       f7c27000 21bd4520 00000029 00000b86 00000000 f7c2710c ca00c480 f59bdc80 
>       c0695570 ca009380 00000000 c06953c0 f59bdc80 00002000 f7c27000 f7feff88 
>Call Trace:
> [<c06252a2>] schedule+0x9ce/0xa57
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c054f1bc>] console_callback+0x6/0xbf
> [<c0434239>] run_workqueue+0x81/0xc5
> [<c054f1b6>] console_callback+0x0/0xbf
> [<c0434c9c>] worker_thread+0xd9/0x10d
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0434bc3>] worker_thread+0x0/0x10d
> [<c04370d6>] kthread+0xc0/0xee
> [<c0437016>] kthread+0x0/0xee
> [<c0405c87>] kernel_thread_helper+0x7/0x10
> =======================
>INFO: task gpm:2533 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>gpm           D 00000026  2584  2533      1          2542  2523 (NOTLB)
>       f483be80 00000082 936a4268 00000026 c06a2300 00000000 00000001 0000000a 
>       f5a26550 936ac827 00000026 000085bf 00000000 f5a2665c ca00c480 f59de040 
>       00000044 c06aef68 00000000 00000001 f483be80 c041ec90 ca00c93c 00000000 
>Call Trace:
> [<c041ec90>] __wake_up+0x2a/0x3d
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c054cb1c>] con_open+0x0/0x83
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c054cb2b>] con_open+0xf/0x83
> [<c0542b3d>] tty_open+0x185/0x2f1
> [<c04803e6>] chrdev_open+0x117/0x132
> [<c0487bb2>] open_namei+0x268/0x5d6
> [<c04802cf>] chrdev_open+0x0/0x132
> [<c04769f9>] __dentry_open+0xc7/0x1ab
> [<c0476b41>] nameidata_to_filp+0x19/0x28
> [<c0476b7b>] do_filp_open+0x2b/0x31
> [<c0476bbf>] do_sys_open+0x3e/0xae
> [<c0476c5c>] sys_open+0x16/0x18
> [<c0404f4b>] syscall_call+0x7/0xb
> =======================
>INFO: task bash:3326 blocked for more than 120 seconds.
>"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>bash          D 00000026  2572  3326   3320                     (NOTLB)
>       f3808ee8 00000086 938d5107 00000026 00080000 00005377 00000286 00000007 
>       ca10baa0 938d9937 00000026 00004830 00000000 ca10bbac ca00c480 f5a6dac0 
>       00000296 00000001 00000000 00000002 c0436ec9 f5a2f550 ca0151d8 00000296 
>Call Trace:
> [<c0436ec9>] kthread_bind+0x1d/0x2e
> [<c06268f2>] __down+0xa9/0xbb
> [<c041f863>] default_wake_function+0x0/0xc
> [<c0624877>] __down_failed+0x7/0xc
> [<c0426538>] .text.lock.printk+0xb/0x83
> [<c0441fb9>] pm_restore_console+0x5/0x1f
> [<c0441a29>] enter_state+0x116/0x170
> [<c0441b08>] state_store+0x85/0x99
> [<c0441a83>] state_store+0x0/0x99
> [<c04b1cbe>] subsys_attr_store+0x1e/0x22
> [<c04b1db0>] sysfs_write_file+0x91/0xbb
> [<c04b1d1f>] sysfs_write_file+0x0/0xbb
> [<c0478603>] vfs_write+0xa1/0x143
> [<c0478c59>] sys_write+0x3c/0x63
> [<c0404f4b>] syscall_call+0x7/0xb
> =======================

Actions: View

Attachments on bug 842942: 600213