Bug#517449: problem also occurs on 2.6.32
Hi,
I have the almost latest of 2.6.32-bpo.5-amd64 (2.6.32-21~bpo50+1),
and the problem occured to me several times today.
Oct 4 12:22:23 debian kernel: [1963080.712134] INFO: task sync:18607 blocked for more than 120 seconds.
Oct 4 12:22:23 debian kernel: [1963080.712138] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Oct 4 12:22:23 debian kernel: [1963080.712141] sync D 0000000000000000 0 18607 18591 0x00000000
Oct 4 12:22:23 debian kernel: [1963080.712145] ffffffff814591f0 0000000000000086 0000000000000000 ffff88009c6bbe30
Oct 4 12:22:23 debian kernel: [1963080.712150] 0000000000000000 000000000000f9e0 ffff88009c6bbfd8 0000000000015780
Oct 4 12:22:23 debian kernel: [1963080.712153] 0000000000015780 ffff88011d78f100 ffff88011d78f3f8 00000000810402dc
Oct 4 12:22:23 debian kernel: [1963080.712157] Call Trace:
Oct 4 12:22:23 debian kernel: [1963080.712166] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:22:23 debian kernel: [1963080.712170] [<ffffffff81106598>] ? bdi_sched_wait+0x9/0xe
Oct 4 12:22:23 debian kernel: [1963080.712174] [<ffffffff812f81d5>] ? __wait_on_bit+0x41/0x70
Oct 4 12:22:23 debian kernel: [1963080.712177] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:22:23 debian kernel: [1963080.712180] [<ffffffff812f826f>] ? out_of_line_wait_on_bit+0x6b/0x77
Oct 4 12:22:23 debian kernel: [1963080.712184] [<ffffffff810638fc>] ? wake_bit_function+0x0/0x23
Oct 4 12:22:23 debian kernel: [1963080.712188] [<ffffffff81106610>] ? sync_inodes_sb+0x73/0x12a
Oct 4 12:22:23 debian kernel: [1963080.712191] [<ffffffff8110a175>] ? __sync_filesystem+0x4b/0x70
Oct 4 12:22:23 debian kernel: [1963080.712194] [<ffffffff8110a234>] ? sync_filesystems+0x9a/0xe3
Oct 4 12:22:23 debian kernel: [1963080.712197] [<ffffffff8110a2c2>] ? sys_sync+0x1c/0x2e
Oct 4 12:22:23 debian kernel: [1963080.712202] [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
Oct 4 12:24:22 debian kernel: [1963200.712565] INFO: task sync:18607 blocked for more than 120 seconds.
Oct 4 12:24:22 debian kernel: [1963200.712569] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Oct 4 12:24:22 debian kernel: [1963200.712572] sync D 0000000000000000 0 18607 18591 0x00000000
Oct 4 12:24:22 debian kernel: [1963200.712577] ffffffff814591f0 0000000000000086 0000000000000000 ffff88009c6bbe30
Oct 4 12:24:22 debian kernel: [1963200.712581] 0000000000000000 000000000000f9e0 ffff88009c6bbfd8 0000000000015780
Oct 4 12:24:22 debian kernel: [1963200.712585] 0000000000015780 ffff88011d78f100 ffff88011d78f3f8 00000000810402dc
Oct 4 12:24:22 debian kernel: [1963200.712588] Call Trace:
Oct 4 12:24:22 debian kernel: [1963200.712598] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:24:22 debian kernel: [1963200.712601] [<ffffffff81106598>] ? bdi_sched_wait+0x9/0xe
Oct 4 12:24:22 debian kernel: [1963200.712606] [<ffffffff812f81d5>] ? __wait_on_bit+0x41/0x70
Oct 4 12:24:22 debian kernel: [1963200.712609] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:24:22 debian kernel: [1963200.712613] [<ffffffff812f826f>] ? out_of_line_wait_on_bit+0x6b/0x77
Oct 4 12:24:22 debian kernel: [1963200.712616] [<ffffffff810638fc>] ? wake_bit_function+0x0/0x23
Oct 4 12:24:22 debian kernel: [1963200.712620] [<ffffffff81106610>] ? sync_inodes_sb+0x73/0x12a
Oct 4 12:24:22 debian kernel: [1963200.712623] [<ffffffff8110a175>] ? __sync_filesystem+0x4b/0x70
Oct 4 12:24:22 debian kernel: [1963200.712626] [<ffffffff8110a234>] ? sync_filesystems+0x9a/0xe3
Oct 4 12:24:22 debian kernel: [1963200.712629] [<ffffffff8110a2c2>] ? sys_sync+0x1c/0x2e
Oct 4 12:24:22 debian kernel: [1963200.712634] [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
Oct 4 12:26:22 debian kernel: [1963320.712059] INFO: task sync:18607 blocked for more than 120 seconds.
Oct 4 12:26:22 debian kernel: [1963320.712064] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Oct 4 12:26:22 debian kernel: [1963320.712066] sync D 0000000000000000 0 18607 18591 0x00000000
Oct 4 12:26:22 debian kernel: [1963320.712071] ffffffff814591f0 0000000000000086 0000000000000000 ffff88009c6bbe30
Oct 4 12:26:22 debian kernel: [1963320.712075] 0000000000000000 000000000000f9e0 ffff88009c6bbfd8 0000000000015780
Oct 4 12:26:22 debian kernel: [1963320.712079] 0000000000015780 ffff88011d78f100 ffff88011d78f3f8 00000000810402dc
Oct 4 12:26:22 debian kernel: [1963320.712083] Call Trace:
Oct 4 12:26:22 debian kernel: [1963320.712092] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:26:22 debian kernel: [1963320.712096] [<ffffffff81106598>] ? bdi_sched_wait+0x9/0xe
Oct 4 12:26:22 debian kernel: [1963320.712100] [<ffffffff812f81d5>] ? __wait_on_bit+0x41/0x70
Oct 4 12:26:22 debian kernel: [1963320.712103] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:26:22 debian kernel: [1963320.712106] [<ffffffff812f826f>] ? out_of_line_wait_on_bit+0x6b/0x77
Oct 4 12:26:22 debian kernel: [1963320.712110] [<ffffffff810638fc>] ? wake_bit_function+0x0/0x23
Oct 4 12:26:22 debian kernel: [1963320.712114] [<ffffffff81106610>] ? sync_inodes_sb+0x73/0x12a
Oct 4 12:26:22 debian kernel: [1963320.712118] [<ffffffff8110a175>] ? __sync_filesystem+0x4b/0x70
Oct 4 12:26:22 debian kernel: [1963320.712121] [<ffffffff8110a234>] ? sync_filesystems+0x9a/0xe3
Oct 4 12:26:22 debian kernel: [1963320.712123] [<ffffffff8110a2c2>] ? sys_sync+0x1c/0x2e
Oct 4 12:26:22 debian kernel: [1963320.712128] [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
Oct 4 12:28:22 debian kernel: [1963440.712121] INFO: task sync:18607 blocked for more than 120 seconds.
Oct 4 12:28:22 debian kernel: [1963440.712130] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Oct 4 12:28:22 debian kernel: [1963440.712136] sync D 0000000000000000 0 18607 18591 0x00000000
Oct 4 12:28:22 debian kernel: [1963440.712147] ffffffff814591f0 0000000000000086 0000000000000000 ffff88009c6bbe30
Oct 4 12:28:22 debian kernel: [1963440.712157] 0000000000000000 000000000000f9e0 ffff88009c6bbfd8 0000000000015780
Oct 4 12:28:22 debian kernel: [1963440.712166] 0000000000015780 ffff88011d78f100 ffff88011d78f3f8 00000000810402dc
Oct 4 12:28:22 debian kernel: [1963440.712175] Call Trace:
Oct 4 12:28:22 debian kernel: [1963440.712193] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:28:22 debian kernel: [1963440.712202] [<ffffffff81106598>] ? bdi_sched_wait+0x9/0xe
Oct 4 12:28:22 debian kernel: [1963440.712212] [<ffffffff812f81d5>] ? __wait_on_bit+0x41/0x70
Oct 4 12:28:22 debian kernel: [1963440.712219] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:28:22 debian kernel: [1963440.712226] [<ffffffff812f826f>] ? out_of_line_wait_on_bit+0x6b/0x77
Oct 4 12:28:22 debian kernel: [1963440.712235] [<ffffffff810638fc>] ? wake_bit_function+0x0/0x23
Oct 4 12:28:22 debian kernel: [1963440.712243] [<ffffffff81106610>] ? sync_inodes_sb+0x73/0x12a
Oct 4 12:28:22 debian kernel: [1963440.712251] [<ffffffff8110a175>] ? __sync_filesystem+0x4b/0x70
Oct 4 12:28:22 debian kernel: [1963440.712258] [<ffffffff8110a234>] ? sync_filesystems+0x9a/0xe3
Oct 4 12:28:22 debian kernel: [1963440.712265] [<ffffffff8110a2c2>] ? sys_sync+0x1c/0x2e
Oct 4 12:28:22 debian kernel: [1963440.712275] [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
Oct 4 12:28:22 debian kernel: [1963440.712282] INFO: task sync:18862 blocked for more than 120 seconds.
Oct 4 12:28:22 debian kernel: [1963440.712286] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Oct 4 12:28:22 debian kernel: [1963440.712291] sync D ffff880005495780 0 18862 18853 0x00000000
Oct 4 12:28:22 debian kernel: [1963440.712300] ffff88011c62f810 0000000000000082 ffff8800058d8038 ffff880005495780
Oct 4 12:28:22 debian kernel: [1963440.712309] ffff8800b1545f80 000000000000f9e0 ffff880032495fd8 0000000000015780
Oct 4 12:28:22 debian kernel: [1963440.712318] 0000000000015780 ffff8800058d8000 ffff8800058d82f8 00000001812f7bee
Oct 4 12:28:22 debian kernel: [1963440.712326] Call Trace:
Oct 4 12:28:22 debian kernel: [1963440.712334] [<ffffffff812f8409>] ? __mutex_lock_common+0x122/0x192
Oct 4 12:28:22 debian kernel: [1963440.712342] [<ffffffff812f8531>] ? mutex_lock+0x1a/0x31
Oct 4 12:28:22 debian kernel: [1963440.712348] [<ffffffff8110a1ad>] ? sync_filesystems+0x13/0xe3
Oct 4 12:28:22 debian kernel: [1963440.712355] [<ffffffff8110a2b8>] ? sys_sync+0x12/0x2e
Oct 4 12:28:22 debian kernel: [1963440.712363] [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
Oct 4 12:30:22 debian kernel: [1963560.712063] INFO: task sync:18607 blocked for more than 120 seconds.
Oct 4 12:30:22 debian kernel: [1963560.712068] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Oct 4 12:30:22 debian kernel: [1963560.712070] sync D 0000000000000000 0 18607 18591 0x00000000
Oct 4 12:30:22 debian kernel: [1963560.712075] ffffffff814591f0 0000000000000086 0000000000000000 ffff88009c6bbe30
Oct 4 12:30:22 debian kernel: [1963560.712079] 0000000000000000 000000000000f9e0 ffff88009c6bbfd8 0000000000015780
Oct 4 12:30:22 debian kernel: [1963560.712083] 0000000000015780 ffff88011d78f100 ffff88011d78f3f8 00000000810402dc
Oct 4 12:30:22 debian kernel: [1963560.712087] Call Trace:
Oct 4 12:30:22 debian kernel: [1963560.712096] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:30:22 debian kernel: [1963560.712100] [<ffffffff81106598>] ? bdi_sched_wait+0x9/0xe
Oct 4 12:30:22 debian kernel: [1963560.712104] [<ffffffff812f81d5>] ? __wait_on_bit+0x41/0x70
Oct 4 12:30:22 debian kernel: [1963560.712107] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 12:30:22 debian kernel: [1963560.712110] [<ffffffff812f826f>] ? out_of_line_wait_on_bit+0x6b/0x77
Oct 4 12:30:22 debian kernel: [1963560.712114] [<ffffffff810638fc>] ? wake_bit_function+0x0/0x23
Oct 4 12:30:22 debian kernel: [1963560.712118] [<ffffffff81106610>] ? sync_inodes_sb+0x73/0x12a
Oct 4 12:30:22 debian kernel: [1963560.712121] [<ffffffff8110a175>] ? __sync_filesystem+0x4b/0x70
Oct 4 12:30:22 debian kernel: [1963560.712124] [<ffffffff8110a234>] ? sync_filesystems+0x9a/0xe3
Oct 4 12:30:22 debian kernel: [1963560.712127] [<ffffffff8110a2c2>] ? sys_sync+0x1c/0x2e
Oct 4 12:30:22 debian kernel: [1963560.712132] [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
Oct 4 12:30:22 debian kernel: [1963560.712135] INFO: task sync:18862 blocked for more than 120 seconds.
Oct 4 12:30:22 debian kernel: [1963560.712136] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Oct 4 12:30:22 debian kernel: [1963560.712138] sync D ffff880005495780 0 18862 18853 0x00000000
Oct 4 12:30:22 debian kernel: [1963560.712142] ffff88011c62f810 0000000000000082 ffff8800058d8038 ffff880005495780
Oct 4 12:30:22 debian kernel: [1963560.712146] ffff8800b1545f80 000000000000f9e0 ffff880032495fd8 0000000000015780
Oct 4 12:30:22 debian kernel: [1963560.712150] 0000000000015780 ffff8800058d8000 ffff8800058d82f8 00000001812f7bee
Oct 4 12:30:22 debian kernel: [1963560.712154] Call Trace:
Oct 4 12:30:22 debian kernel: [1963560.712157] [<ffffffff812f8409>] ? __mutex_lock_common+0x122/0x192
Oct 4 12:30:22 debian kernel: [1963560.712160] [<ffffffff812f8531>] ? mutex_lock+0x1a/0x31
Oct 4 12:30:22 debian kernel: [1963560.712163] [<ffffffff8110a1ad>] ? sync_filesystems+0x13/0xe3
Oct 4 12:30:22 debian kernel: [1963560.712166] [<ffffffff8110a2b8>] ? sys_sync+0x12/0x2e
Oct 4 12:30:22 debian kernel: [1963560.712169] [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
Oct 4 14:20:55 debian kernel: [1970193.596044] psi[9161]: segfault at 2ff9ae0 ip 0000000002ff9ae0 sp 00007fff2e900d58 error 15
Oct 4 14:22:22 debian kernel: [1970280.716055] INFO: task sync:25234 blocked for more than 120 seconds.
Oct 4 14:22:22 debian kernel: [1970280.716120] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Oct 4 14:22:22 debian kernel: [1970280.716174] sync D 0000000000000002 0 25234 25226 0x00000000
Oct 4 14:22:22 debian kernel: [1970280.716309] ffff88011ce90710 0000000000000082 000000060aba7db8 ffff88010aba7e58
Oct 4 14:22:22 debian kernel: [1970280.716511] 0000000000000000 000000000000f9e0 ffff88010aba7fd8 0000000000015780
Oct 4 14:22:22 debian kernel: [1970280.716686] 0000000000015780 ffff88011d78aa60 ffff88011d78ad58 00000000810402dc
Oct 4 14:22:22 debian kernel: [1970280.716861] Call Trace:
Oct 4 14:22:22 debian kernel: [1970280.716909] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 14:22:22 debian kernel: [1970280.716955] [<ffffffff81106598>] ? bdi_sched_wait+0x9/0xe
Oct 4 14:22:22 debian kernel: [1970280.717001] [<ffffffff812f81d5>] ? __wait_on_bit+0x41/0x70
Oct 4 14:22:22 debian kernel: [1970280.717047] [<ffffffff8110658f>] ? bdi_sched_wait+0x0/0xe
Oct 4 14:22:22 debian kernel: [1970280.717092] [<ffffffff812f826f>] ? out_of_line_wait_on_bit+0x6b/0x77
Oct 4 14:22:22 debian kernel: [1970280.717138] [<ffffffff810638fc>] ? wake_bit_function+0x0/0x23
Oct 4 14:22:22 debian kernel: [1970280.717184] [<ffffffff81106610>] ? sync_inodes_sb+0x73/0x12a
Oct 4 14:22:22 debian kernel: [1970280.717230] [<ffffffff8110a175>] ? __sync_filesystem+0x4b/0x70
Oct 4 14:22:22 debian kernel: [1970280.717275] [<ffffffff8110a234>] ? sync_filesystems+0x9a/0xe3
Oct 4 14:22:22 debian kernel: [1970280.717320] [<ffffffff8110a2c2>] ? sys_sync+0x1c/0x2e
Oct 4 14:22:22 debian kernel: [1970280.717367] [<ffffffff81010b42>] ? system_call_fastpath+0x16/0x1b
Oct 4 17:24:19 debian kernel: [1981198.213973] dumpcap uses obsolete (PF_INET,SOCK_PACKET)
I was running a bacula job with Pg database, and a cron job of 'sync',
plus my normal desktop stuff. Please let me know if there's more to
debug...
Kind regards,
--Toni++
Reply to: