"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncAfterChangingModtimeOnly|TestSyncDoesntUpdateModtime|TestSyncIgnoreErrors)$'" - Starting (try 2/5) 2025/03/18 11:08:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cocomay7wape" 2025/03/18 11:08:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/03/18 11:08:06 DEBUG : Starting OpenDrive session with ID: a7505c67ccf053be66f33b78a5ce7f7b80563a6f7a70e36bec22e3a32014f764 2025/03/18 11:08:06 DEBUG : Creating backend with remote "/tmp/rclone1517824090" === RUN TestSyncIgnoreErrors run.go:180: Remote "OpenDrive root 'rclone-test-cocomay7wape'", Local "Local file system at /tmp/rclone1517824090", Modify Window "1s" 2025/03/18 11:08:08 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0 2025/03/18 11:08:10 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0 2025/03/18 11:08:11 INFO : d: Making directory 2025/03/18 11:08:12 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/03/18 11:08:12 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/03/18 11:08:12 DEBUG : c/non empty space: Unchanged skipping 2025/03/18 11:08:12 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Waiting for checks to finish 2025/03/18 11:08:12 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Waiting for transfers to finish 2025/03/18 11:08:13 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0 2025/03/18 11:08:14 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/03/18 11:08:14 INFO : a/potato2: Copied (new) 2025/03/18 11:08:14 DEBUG : Waiting for deletions to finish 2025/03/18 11:08:14 INFO : b/potato: Deleted 2025/03/18 11:08:14 INFO : d: Removing directory 2025/03/18 11:08:14 INFO : b: Removing directory 2025/03/18 11:08:15 DEBUG : b: Failed to Rmdir: folder not empty 2025/03/18 11:08:15 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': failed to delete 1 directories 2025/03/18 11:08:15 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': deleted 1 directories run.go:180: Remote "OpenDrive root 'rclone-test-cocomay7wape'", Local "Local file system at /tmp/rclone1517824090", Modify Window "1s" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:964 Error: Not equal: expected: []string{"a", "c"} actual : []string{"a", "b", "c"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=2) { +([]string) (len=3) { (string) (len=1) "a", + (string) (len=1) "b", (string) (len=1) "c" Test: TestSyncIgnoreErrors Messages: directories --- FAIL: TestSyncIgnoreErrors (19.12s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "OpenDrive root 'rclone-test-cocomay7wape'", Local "Local file system at /tmp/rclone1517824090", Modify Window "1s" 2025/03/18 11:08:26 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/03/18 11:08:27 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2025/03/18 11:08:27 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Waiting for checks to finish 2025/03/18 11:08:27 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/03/18 11:08:27 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/03/18 11:08:27 DEBUG : empty space: Unchanged skipping 2025/03/18 11:08:27 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Waiting for transfers to finish 2025/03/18 11:08:27 DEBUG : Waiting for deletions to finish 2025/03/18 11:08:27 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-cocomay7wape'", Local "Local file system at /tmp/rclone1517824090", Modify Window "1s" 2025/03/18 11:08:28 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Waiting for checks to finish 2025/03/18 11:08:28 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2025/03/18 11:08:28 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/03/18 11:08:28 INFO : empty space: Updated modification time in destination 2025/03/18 11:08:28 DEBUG : empty space: Unchanged skipping 2025/03/18 11:08:28 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Waiting for transfers to finish 2025/03/18 11:08:28 DEBUG : Waiting for deletions to finish 2025/03/18 11:08:28 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-cocomay7wape'", Local "Local file system at /tmp/rclone1517824090", Modify Window "1s" --- PASS: TestSyncAfterChangingModtimeOnly (3.85s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "OpenDrive root 'rclone-test-cocomay7wape'", Local "Local file system at /tmp/rclone1517824090", Modify Window "1s" 2025/03/18 11:08:30 DEBUG : foo: Uploading chunk 0, size=3, remain=0 2025/03/18 11:08:31 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Waiting for checks to finish 2025/03/18 11:08:31 DEBUG : foo: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2025/03/18 11:08:31 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone1517824090) 2025/03/18 11:08:31 DEBUG : foo: md5 = 37b51d194a7513e45b56f6524f2d51f2 (OpenDrive root 'rclone-test-cocomay7wape') 2025/03/18 11:08:31 DEBUG : foo: md5 differ 2025/03/18 11:08:31 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Waiting for transfers to finish 2025/03/18 11:08:31 DEBUG : foo: Uploading chunk 0, size=3, remain=0 2025/03/18 11:08:32 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2025/03/18 11:08:32 INFO : foo: Copied (replaced existing) 2025/03/18 11:08:32 DEBUG : Waiting for deletions to finish run.go:180: Remote "OpenDrive root 'rclone-test-cocomay7wape'", Local "Local file system at /tmp/rclone1517824090", Modify Window "1s" --- PASS: TestSyncDoesntUpdateModtime (4.15s) FAIL 2025/03/18 11:08:33 DEBUG : OpenDrive root 'rclone-test-cocomay7wape': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncAfterChangingModtimeOnly|TestSyncDoesntUpdateModtime|TestSyncIgnoreErrors)$'" - Finished ERROR in 28.324521054s (try 2/5): exit status 1: Failed [TestSyncIgnoreErrors]