This is now typical for me:
14/10/2011 17:50:43.086 com.apple.backupd: Starting standard backup
14/10/2011 17:50:43.109 com.apple.backupd: Backing up to: /Volumes/Backup/Backups.backupdb
14/10/2011 17:50:44.520 com.apple.backupd: 617.2 MB required (including padding), 124.22 GB available
14/10/2011 17:50:47.845 com.apple.backupd: Copied 1524 files (19.9 MB) from volume Macintosh HD.
14/10/2011 17:50:47.906 com.apple.backupd: 592.5 MB required (including padding), 124.20 GB available
14/10/2011 17:50:48.676 com.apple.backupd: Copied 96 files (93 bytes) from volume Macintosh HD.
14/10/2011 17:50:49.256 com.apple.backupd: Starting post-backup thinning
14/10/2011 17:50:49.256 com.apple.backupd: No post-back up thinning needed: no expired backups exist
14/10/2011 17:50:49.276 com.apple.backupd: Backup completed successfully.
So I have no complaints with a 6 second backup, but does anyone know why the file copy stage happens twice? And those 96 files must be pretty tiny, unless they are just 0 byte lock files or something.