[BackupPC-users] Backups very slow after upgrade to squeeze

[James L. E. <jl...@te...>]

I have been running backuppc 3.1.0-4 on Debian lenny (Xen VM) for several years with no problems.

Suddenly, after upgrading to squeeze and backuppc 3.1.0-9, nightly backup take very long (50 times) or never finish. If I start them manually from the web interface they run just fine.

Also, when the unfinished backups are still running, the machine responds very slowly even though the backuppc processes are using less than 1% of the CPU.

Any suggestions?

Jim

I'm also seeing some messages like the following the kernal logs:

Sep  7 03:14:13 backup kernel: [57960.076042] INFO: task kjournald:384 blocked for more than 120 seconds.
Sep  7 03:14:13 backup kernel: [57960.076060] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep  7 03:14:13 backup kernel: [57960.076074] kjournald     D f9ff470b     0   384      2 0x00000000
Sep  7 03:14:13 backup kernel: [57960.076094]  c218f740 00000246 001f2362 f9ff470b 001f2362 c145ee20 c145ee20 001f2362
Sep  7 03:14:13 backup kernel: [57960.076129]  c218f8fc c3170e20 00000000 6eabc211 00003480 0056c047 00026cae c13f7f00
Sep  7 03:14:13 backup kernel: [57960.076163]  0cda2623 c218f8fc 488d1fe0 00000000 0cda2623 00000000 c20e0648 0018b708
Sep  7 03:14:13 backup kernel: [57960.076196] Call Trace:
Sep  7 03:14:13 backup kernel: [57960.076208]  [<c128e462>] ? io_schedule+0x5f/0x98
Sep  7 03:14:13 backup kernel: [57960.076217]  [<c10d6fbe>] ? sync_buffer+0x30/0x33
Sep  7 03:14:13 backup kernel: [57960.076225]  [<c128e8e9>] ? __wait_on_bit+0x33/0x58
Sep  7 03:14:13 backup kernel: [57960.076232]  [<c10d6f8e>] ? sync_buffer+0x0/0x33
Sep  7 03:14:13 backup kernel: [57960.076239]  [<c128e9c3>] ? out_of_line_wait_on_bit+0xb5/0xbd
Sep  7 03:14:13 backup kernel: [57960.076247]  [<c10d6f8e>] ? sync_buffer+0x0/0x33
Sep  7 03:14:13 backup kernel: [57960.076255]  [<c104b687>] ? wake_bit_function+0x0/0x3c
Sep  7 03:14:13 backup kernel: [57960.076263]  [<c10d6f34>] ? __wait_on_buffer+0x16/0x18
Sep  7 03:14:13 backup kernel: [57960.076274]  [<ed89726a>] ? journal_commit_transaction+0x83c/0xd56 [jbd]
Sep  7 03:14:13 backup kernel: [57960.076283]  [<c1006034>] ? xen_force_evtchn_callback+0xc/0x10
Sep  7 03:14:13 backup kernel: [57960.076291]  [<c100675b>] ? xen_restore_fl_direct_end+0x0/0x1
Sep  7 03:14:13 backup kernel: [57960.076300]  [<c128f519>] ? _spin_unlock_irqrestore+0xd/0xf
Sep  7 03:14:13 backup kernel: [57960.076308]  [<c104292e>] ? try_to_del_timer_sync+0x4f/0x56
Sep  7 03:14:13 backup kernel: [57960.076316]  [<ed899b7f>] ? kjournald+0xb9/0x1e0 [jbd]
Sep  7 03:14:13 backup kernel: [57960.076324]  [<c104b65a>] ? autoremove_wake_function+0x0/0x2d
Sep  7 03:14:13 backup kernel: [57960.076333]  [<ed899ac6>] ? kjournald+0x0/0x1e0 [jbd]
Sep  7 03:14:13 backup kernel: [57960.076340]  [<c104b428>] ? kthread+0x61/0x66
Sep  7 03:14:13 backup kernel: [57960.076347]  [<c104b3c7>] ? kthread+0x0/0x66
Sep  7 03:14:13 backup kernel: [57960.076355]  [<c1009ae7>] ? kernel_thread_helper+0x7/0x10