[10.7 Lion] TimeMachine Backup dauert 2 Tage - warum?

[holler]

Tag zusammen,

ich habe das Problem das seit kurzem mein TimeMachine Backup bis zu 2 Tagen dauert (50 GB). Das heutige Backup mit 3 GB läuft nun seit 14 Stunden per WLan auf meine Synology.
Backup abschließen läuft seit ca. 3 Stunden

Das Wlan selber ist nicht das Problem, das lief zuvor auch ohne Problem (n-Wlan).

Die Hardware ist ein 13'' Macbook Late 2009, eine Synology DS211j auf der zurzeit keine anderen Prozesse laufen.

An was kann das liegen bzw. welche Daten benötigt ihr den noch?

Danke

 

[Macbeatnik]

Schau doch mal in die Konsole, ob dort etwas zum Backup steht.
Programme/Dienstprogramme/Konsole,dort dann alle Meldungen

 

[holler]

Das sind die einzigen Meldungen hierzu:

25.02.12 08:55:06,795 mds: (Warning) DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask: 0x7ffb91965380>{taskID:0x0000106f age:858ms} (not live not complete) InRange(_kMDItemIndexedByBackup,1,1)25.02.12 08:55:06,820 mds: (Warning) DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask: 0x7ffb91965380>{taskID:0x0000106f age:883ms} (not live not complete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:55:07,258 mds: (Warning) DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask: 0x7ffb91965380>{taskID:0x0000106f age:1320ms} (not live not complete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 09:41:58,605 com.apple.backupd: Copied 21 KB of 54.0 GB, 222639 of 878336 items
25.02.12 10:43:40,635 com.apple.backupd: Copied 543 KB of 54.0 GB, 444823 of 878336 items
25.02.12 11:43:41,446 com.apple.backupd: Copied 1.2 GB of 54.0 GB, 787380 of 878336 items
25.02.12 12:43:43,374 com.apple.backupd: Copied 10.7 GB of 54.0 GB, 811660 of 878336 items
25.02.12 13:43:46,097 com.apple.backupd: Copied 23.3 GB of 54.0 GB, 834577 of 878336 items
25.02.12 13:47:42,331 mds: (Error) Backup: Couldn't stat source path '/Users/xxx/Library/Messages/chat.db-shm' -- importing from backup path instead
25.02.12 13:47:42,336 mds: (Error) Backup: Couldn't stat source path '/Users/xxx/Library/Messages/chat.db-wal' -- importing from backup path instead
25.02.12 14:06:57,470 com.apple.backupd: Copied 892543 files (27.8 GB) from volume Macintosh HD.
25.02.12 14:07:02,758 com.apple.backupd: 1.16 GB required (including padding), 378.28 GB available
25.02.12 14:07:29,551 com.apple.backupd: Copied 1837 files (301 KB) from volume Macintosh HD.

 

[Macbeatnik]

Es scheint so zu sein, das zum einen ein vollständiges Backup gemacht wird( 54GB) und das dieses vermutlich auf einen Fehler in der Indizierung (mds) zurückzuführen ist.

 

[holler]

Das ist ja komisch, das zeigt mir TimeMachine die Falsche große an.

Was genau bedeutet Fehler in der Indizierung bzw. wie kann man diesen beheben? Ist damit gemeint, das Spotlight auch das Backup durchsuchen kann?

 

[Macbeatnik]

Es wird ein index erstellt, was an Daten vorhanden ist in Backup und dieses dann beim Backup mit den vorhandenen Daten abgeglichen, um dann nur die geänderten oder neu hinzugekommenen Daten zu sichern.
Dort scheint bei dir etwas falsch zu laufen.

 

[holler]

Und wie kann man diesen Fehler beheben? Den Backupordner komplett löschen?

 

[Macbeatnik]

Da ich Timemachine nicht, bzw. nur widerwillig nutze und das auch nur nur auf ausgewählten unwichtigen Rechnern , kann ich die dazu nur wenig sagen.
Vermutlich gibt es reparaturmoglichkeiten, eventl. Weiß ein anderer mehr.

 

[MacAlzenau]

Wurden denn beim zweiten Mal (also 14 Uhr 07) tatsächlich nur die angeforderten max. 1,16 GB kopiert? Dann hat sich's vielleicht eingerenkt.
Aus dem Log-Ausschnitt geht nicht hervor, wieviel beim Start des Problem-Backups an freiem Platz gefordert wurde.
Auffällig , daß TM anfangs extrem langsam arbeitet und dann bei 27 GB abschließt, obwohl erst 54 angegeben waren.
Und wie erklärt man sich, daß in der ersten Stunde über 222000 Items mit zusammen nur 21kB übertragen wurden?

 

[holler]

So hab hier mal den kompletten Log:

Spoiler

24.02.12 19:30:58,419com.apple.backupd: Copied 5.2 MB of 56.4 GB, 665004 of 876006 items25.02.12 08:40:56,717 com.apple.kextd:/Volumes/Time Machine-Backups: error reading/usr/standalone/bootcaches.plist: Input/output error.
25.02.12 08:40:56,720com.apple.backupd: Stopping backup to allow backup destination diskto be unmounted or ejected.
25.02.12 08:40:56,795 fseventsd: disklogger: failed to open output file /Volumes/TimeMachine-Backups/.fseventsd/0000000000de9cfa (Input/output error).mount point /Volumes/Time Machine-Backups/.fseventsd
25.02.12 08:40:56,795 fseventsd: disklogger: failed to open output file /Volumes/TimeMachine-Backups/.fseventsd/0000000000de9cfa (Input/output error).mount point /Volumes/Time Machine-Backups/.fseventsd
25.02.12 08:40:56,897 KernelEventAgent:tid 00000000 type 'hfs', mounted on '/Volumes/Time Machine-Backups',from '/dev/disk3s2', low disk, very low disk
25.02.12 08:40:56,907com.apple.backupd: Copied 5.2 MB of 56.4 GB, 684901 of 876006 items
25.02.12 08:40:56,981 KernelEventAgent:tid 00000000 type 'hfs', mounted on '/Volumes/Time Machine-Backups',from '/dev/disk3s2', low disk, very low disk
25.02.12 08:40:57,006com.apple.backupd: Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandlewriteData:]: Input/output error
25.02.12 08:40:57,006com.apple.backupd: Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandlewriteData:]: Input/output error
25.02.12 08:40:57,007com.apple.backupd: Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandlewriteData:]: Input/output error
25.02.12 08:40:57,007com.apple.backupd: Copied 684901 files (5.2 MB) from volume MacintoshHD.
25.02.12 08:40:57,007com.apple.backupd: Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandlewriteData:]: Input/output error
25.02.12 08:40:57,008com.apple.backupd: Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandlewriteData:]: Input/output error
25.02.12 08:40:57,009com.apple.backupd: Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandlewriteData:]: Input/output error
25.02.12 08:40:57,009com.apple.backupd: Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandlewriteData:]: Input/output error
25.02.12 08:40:59,074com.apple.backupd: Backup canceled.
25.02.12 08:41:00,353 KernelEventAgent:tid 00000000 type 'hfs', mounted on '/Volumes/Time Machine-Backups',from '/dev/disk3s2', low disk, very low disk
25.02.12 08:41:01,426com.apple.backupd: [SnapshotUtilities mountPointForVolumeRef]FSGetVolumeInfo returned: -35
25.02.12 08:41:01,426com.apple.backupd: Failed to eject volume (null) (FSVolumeRefNum:-113; status: -35; dissenting pid: -1)
25.02.12 08:41:06,828com.apple.backupd: Starting standard backup
25.02.12 08:41:06,848com.apple.backupd: Network destination already mounted at:/Volumes/Mac
25.02.12 08:41:27,131com.apple.backupd: QUICKCHECK ONLY; FILESYSTEM CLEAN
25.02.12 08:41:55,036 fseventsd: eventlogs in /Volumes/Time Machine-Backups/.fseventsd out of sync withvolume. destroying old logs. (7882 24 54662)
25.02.12 08:41:55,083 fseventsd: logdir: /Volumes/Time Machine-Backups/.fseventsd getting new uuid:201AC108-A720-4550-BD11-F5A4DEE3545A
25.02.12 08:41:56,151com.apple.backupd: Disk image /Volumes/Mac/Daniela RistausMacBook.sparsebundle mounted at: /Volumes/Time Machine-Backups
25.02.12 08:41:56,179com.apple.backupd: Backing up to: /Volumes/TimeMachine-Backups/Backups.backupdb
25.02.12 08:41:58,608com.apple.backupd: Waiting for index to be ready (101)
25.02.12 08:43:39,708 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:867ms} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:41,488 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:2648ms} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:44,323 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:5.484s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:44,323 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:5.485s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:44,355 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:5.516s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:44,355 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:5.516s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:44,355 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:5.516s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:45,056 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:6.218s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:45,514 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:6.676s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:47,054 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:8.217s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:48,516 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:9.679s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:48,518 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:9.681s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:48,519 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:9.682s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:48,520 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:9.683s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:48,521 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:9.684s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:48,521 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:9.684s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:48,521 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:9.684s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:48,521 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91a63180>{taskID:0x00001068 age:9.684s} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:43:49,591com.apple.backupd: Deep event scan at path:/ reason:must scansubdirs|
25.02.12 08:43:49,591com.apple.backupd: Finished scan
25.02.12 08:55:03,360com.apple.backupd: 65.92 GB required (including padding), 406.00 GBavailable
25.02.12 08:55:06,795 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91965380>{taskID:0x0000106f age:858ms} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:55:06,820 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91965380>{taskID:0x0000106f age:883ms} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 08:55:07,258 mds: (Warning)DiskStore: Ignored bogus fetch for <MDSQueryWithBlockTask:0x7ffb91965380>{taskID:0x0000106f age:1320ms} (not live notcomplete) InRange(_kMDItemIndexedByBackup,1,1)
25.02.12 09:41:58,605com.apple.backupd: Copied 21 KB of 54.0 GB, 222639 of 878336 items
25.02.12 10:43:40,635com.apple.backupd: Copied 543 KB of 54.0 GB, 444823 of 878336 items
25.02.12 11:43:41,446com.apple.backupd: Copied 1.2 GB of 54.0 GB, 787380 of 878336 items
25.02.12 12:43:43,374com.apple.backupd: Copied 10.7 GB of 54.0 GB, 811660 of 878336 items
25.02.12 13:43:46,097com.apple.backupd: Copied 23.3 GB of 54.0 GB, 834577 of 878336 items
25.02.12 13:47:42,331 mds: (Error)Backup: Couldn't stat source path'/Users/xxx/Library/Messages/chat.db-shm' -- importing from backuppath instead
25.02.12 13:47:42,336 mds: (Error)Backup: Couldn't stat source path'/Users/xxx/Library/Messages/chat.db-wal' -- importing from backuppath instead
25.02.12 14:06:57,470com.apple.backupd: Copied 892543 files (27.8 GB) from volumeMacintosh HD.
25.02.12 14:07:02,758com.apple.backupd: 1.16 GB required (including padding), 378.28 GBavailable
25.02.12 14:07:29,551com.apple.backupd: Copied 1837 files (301 KB) from volume MacintoshHD.
25.02.12 17:42:17,980com.apple.backupd: Backup deletion was canceled by user
25.02.12 17:42:17,980 mds: (Error)Volume: Could not find requested backup type:2 for volume
25.02.12 17:42:17,989com.apple.backupd: Starting post-backup thinning
25.02.12 17:42:35,860com.apple.backupd: Backup completed successfully.

Auf der NAS selber habe ich knapp 500 GB zugewiesen, belegt davon sind ca. 200 GB.