"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopy|TestCopyDeleteBefore|TestCopyNoTraverse|TestCopyWithDryRun|TestFixCase|TestMoveWithDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopyOverSelf|TestSyncAfterAddingAFile|TestSyncAfterChangingModtimeOnly|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCopyDest|TestSyncIgnoreTimes|TestSyncImmutable|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames)$'" - Starting (try 2/5) 2025/01/22 05:02:26 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa" 2025/01/22 05:02:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 05:02:27 DEBUG : Starting OpenDrive session with ID: 5d6eaf9ac9455103d6d840336fc3f35ae3eb48190c11b7426bcf207c46b08da5 2025/01/22 05:02:27 DEBUG : Creating backend with remote "/tmp/rclone1804440921" === RUN TestCopyWithDryRun run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/22 05:02:27 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:02:27 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/01/22 05:02:27 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:28 ERROR : : error listing: failed to get folder list: Directory doesn't exist (Error 404) fstest.go:276: Error listing: failed to get folder list: Directory doesn't exist (Error 404) 2025/01/22 05:02:28 ERROR : : error listing: failed to get folder list: Directory doesn't exist (Error 404) fstest.go:276: Error listing: failed to get folder list: Directory doesn't exist (Error 404) 2025/01/22 05:02:28 ERROR : : error listing: failed to get folder list: Directory doesn't exist (Error 404) run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1175 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1353 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1337 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/runtime/panic.go:626 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1005 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1089 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:276 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1175 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1353 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1683 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/runtime/panic.go:626 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1005 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1089 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:276 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:62 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestCopyWithDryRun --- FAIL: TestCopyWithDryRun (1.22s) === RUN TestCopy run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:28 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/22 05:02:28 ERROR : OpenDrive root 'rclone-test-vihezog9cuqa': error reading destination root directory: failed to get folder list: Directory doesn't exist (Error 404) 2025/01/22 05:02:28 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:02:28 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish sync_test.go:78: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:78 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestCopy --- FAIL: TestCopy (0.54s) === RUN TestCopyNoTraverse run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/22 05:02:29 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:02:29 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:02:30 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/01/22 05:02:33 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/01/22 05:02:33 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" --- PASS: TestCopyNoTraverse (6.21s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:36 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/01/22 05:02:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-delazuw1jili" 2025/01/22 05:02:38 DEBUG : Starting OpenDrive session with ID: 26ee0efa282dcfd3584d6aacc2daf84b0ece6892814a624a6a16d6188d7a4b15 sync_test.go:626: Server side copy (if possible) OpenDrive root 'rclone-test-vihezog9cuqa' -> OpenDrive root 'rclone-test-delazuw1jili' 2025/01/22 05:02:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/22 05:02:39 DEBUG : OpenDrive root 'rclone-test-delazuw1jili': Waiting for checks to finish 2025/01/22 05:02:39 DEBUG : OpenDrive root 'rclone-test-delazuw1jili': Waiting for transfers to finish 2025/01/22 05:02:41 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/22 05:02:41 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:42 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/01/22 05:02:43 DEBUG : OpenDrive root 'rclone-test-delazuw1jili': Waiting for checks to finish 2025/01/22 05:02:43 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/22 05:02:43 DEBUG : OpenDrive root 'rclone-test-delazuw1jili': Waiting for transfers to finish 2025/01/22 05:02:44 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/22 05:02:44 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:44 DEBUG : OpenDrive root 'rclone-test-delazuw1jili': Purge remote --- PASS: TestServerSideCopyOverSelf (11.39s) === RUN TestSyncIgnoreTimes run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:47 DEBUG : existing: Uploading chunk 0, size=6, remain=0 2025/01/22 05:02:48 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:02:48 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:02:48 DEBUG : existing: Unchanged skipping 2025/01/22 05:02:48 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:02:48 DEBUG : Waiting for deletions to finish 2025/01/22 05:02:48 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:49 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/01/22 05:02:49 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:02:49 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:02:49 DEBUG : existing: Uploading chunk 0, size=6, remain=0 2025/01/22 05:02:50 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/01/22 05:02:50 INFO : existing: Copied (replaced existing) 2025/01/22 05:02:50 DEBUG : Waiting for deletions to finish run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" --- PASS: TestSyncIgnoreTimes (5.01s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:52 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/01/22 05:02:53 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:02:53 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/01/22 05:02:53 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/01/22 05:02:53 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/01/22 05:02:53 DEBUG : empty space: Unchanged skipping 2025/01/22 05:02:53 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:02:53 DEBUG : Waiting for deletions to finish 2025/01/22 05:02:53 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:02:54 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:02:54 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/01/22 05:02:54 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/01/22 05:02:54 INFO : empty space: Updated modification time in destination 2025/01/22 05:02:54 DEBUG : empty space: Unchanged skipping 2025/01/22 05:02:54 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:02:54 DEBUG : Waiting for deletions to finish 2025/01/22 05:02:54 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1007 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: empty space: Modification time difference too big |95456h54m53.123456789s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2001-02-03 04:05:06 +0000 UTC) (precision 1s) fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- FAIL: TestSyncAfterChangingModtimeOnly (11.97s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:03:04 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/01/22 05:03:05 DEBUG : potato: Need to transfer - File not found at Destination 2025/01/22 05:03:05 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/01/22 05:03:05 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:03:05 DEBUG : empty space: Unchanged skipping 2025/01/22 05:03:05 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:03:06 DEBUG : potato: Uploading chunk 0, size=60, remain=0 2025/01/22 05:03:07 DEBUG : potato: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/01/22 05:03:07 INFO : potato: Copied (new) 2025/01/22 05:03:07 DEBUG : Waiting for deletions to finish run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" --- PASS: TestSyncAfterAddingAFile (5.67s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:03:10 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0 2025/01/22 05:03:13 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0 2025/01/22 05:03:15 INFO : d: Making directory 2025/01/22 05:03:15 INFO : d/e: Making directory sync_test.go:1174: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1174 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1232 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDir (8.68s) === RUN TestCopyDeleteBefore run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:03:18 DEBUG : potato: Uploading chunk 0, size=21, remain=0 2025/01/22 05:03:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/22 05:03:20 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:03:20 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:03:21 DEBUG : potato2: Uploading chunk 0, size=19, remain=0 2025/01/22 05:03:22 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK 2025/01/22 05:03:22 INFO : potato2: Copied (new) run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- PASS: TestCopyDeleteBefore (12.85s) === RUN TestSyncWithTrackRenames run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" sync_test.go:1538: Can track renames: true 2025/01/22 05:03:31 INFO : OpenDrive root 'rclone-test-vihezog9cuqa': Making map for --track-renames 2025/01/22 05:03:31 INFO : OpenDrive root 'rclone-test-vihezog9cuqa': Finished making map for --track-renames 2025/01/22 05:03:31 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:03:31 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/01/22 05:03:31 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/01/22 05:03:31 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for renames to finish 2025/01/22 05:03:31 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:03:31 DEBUG : potato: Uploading chunk 0, size=14, remain=0 2025/01/22 05:03:32 DEBUG : yam: Uploading chunk 0, size=11, remain=0 2025/01/22 05:03:33 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2025/01/22 05:03:33 INFO : potato: Copied (new) 2025/01/22 05:03:34 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2025/01/22 05:03:34 INFO : yam: Copied (new) 2025/01/22 05:03:34 DEBUG : Waiting for deletions to finish run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:03:34 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:03:34 INFO : OpenDrive root 'rclone-test-vihezog9cuqa': Making map for --track-renames 2025/01/22 05:03:34 INFO : OpenDrive root 'rclone-test-vihezog9cuqa': Finished making map for --track-renames 2025/01/22 05:03:34 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:03:34 DEBUG : potato: Unchanged skipping 2025/01/22 05:03:34 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for renames to finish 2025/01/22 05:03:35 DEBUG : yam: same parent dir (ODFfNzk4OTIwN19hR1dxNA) - using file/rename instead of move_copy for yaml 2025/01/22 05:03:35 INFO : yam: Moved (server-side) to: yaml 2025/01/22 05:03:35 INFO : yaml: Renamed from "yam" 2025/01/22 05:03:35 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:03:35 DEBUG : Waiting for deletions to finish 2025/01/22 05:03:35 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" --- PASS: TestSyncWithTrackRenames (6.29s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:03:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/22 05:03:37 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/01/22 05:03:37 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:03:37 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:03:38 ERROR : nested/sub dir/file: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404) 2025/01/22 05:03:38 ERROR : nested/sub dir/file: Not deleting source as copy failed: failed to get folder list: Directory doesn't exist (Error 404) 2025/01/22 05:03:38 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/01/22 05:03:39 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/01/22 05:03:39 INFO : sub dir/hello world: Copied (new) 2025/01/22 05:03:39 INFO : sub dir/hello world: Deleted 2025/01/22 05:03:39 ERROR : Local file system at /tmp/rclone1804440921: not deleting directories as there were IO errors sync_test.go:1788: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1788 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs run.go:125: removing dir "sub dir" failed - try 1/3: folder not empty --- FAIL: TestMoveWithDeleteEmptySrcDirs (4.99s) === RUN TestSyncCopyDest run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:03:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/dst" 2025/01/22 05:03:42 DEBUG : Starting OpenDrive session with ID: 7231507d907ab4e96beecd4059dc8769c3572944fa265aa0df0ac895138728dd 2025/01/22 05:03:43 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/CopyDest" 2025/01/22 05:03:44 DEBUG : Starting OpenDrive session with ID: 7c7bda034ba29a84bed71e1725b42bf0c04a4ad3045bb30a98fc783f6f2ee73d 2025/01/22 05:03:45 DEBUG : one: Need to transfer - File not found at Destination 2025/01/22 05:03:45 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:03:45 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:03:46 DEBUG : one: Uploading chunk 0, size=3, remain=0 2025/01/22 05:03:47 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/01/22 05:03:47 INFO : one: Copied (new) 2025/01/22 05:03:47 DEBUG : Waiting for deletions to finish 2025/01/22 05:03:48 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/22 05:03:48 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:03:48 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:03:48 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/01/22 05:03:49 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/01/22 05:03:49 INFO : one: Copied (replaced existing) 2025/01/22 05:03:49 DEBUG : Waiting for deletions to finish 2025/01/22 05:03:50 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/01/22 05:03:53 DEBUG : CopyDest/one: Uploading chunk 0, size=5, remain=0 2025/01/22 05:03:54 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/BackupDir" 2025/01/22 05:03:55 DEBUG : Starting OpenDrive session with ID: 9391caa5be11f10e8fd891a95436b8829c643c53c9a81f15bddc7b5eb7c417c8 2025/01/22 05:03:56 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/22 05:03:56 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:03:56 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/01/22 05:03:56 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/22 05:03:57 INFO : one: Moved (server-side) 2025/01/22 05:03:58 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/22 05:03:58 INFO : one: Copied (server-side copy) 2025/01/22 05:03:58 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/01/22 05:03:58 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:03:58 DEBUG : Waiting for deletions to finish 2025/01/22 05:03:59 DEBUG : CopyDest/two: Uploading chunk 0, size=3, remain=0 2025/01/22 05:04:00 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/01/22 05:04:01 DEBUG : two: Dst hash empty - aborting Src hash check 2025/01/22 05:04:01 INFO : two: Copied (server-side copy) 2025/01/22 05:04:01 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/01/22 05:04:01 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/01/22 05:04:01 DEBUG : one: Unchanged skipping 2025/01/22 05:04:01 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:04:01 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:04:01 DEBUG : Waiting for deletions to finish 2025/01/22 05:04:01 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/01/22 05:04:01 DEBUG : one: Unchanged skipping 2025/01/22 05:04:01 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/01/22 05:04:01 DEBUG : two: Unchanged skipping 2025/01/22 05:04:01 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:04:01 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:04:01 DEBUG : Waiting for deletions to finish 2025/01/22 05:04:01 INFO : There was nothing to transfer 2025/01/22 05:04:03 DEBUG : CopyDest/three: Uploading chunk 0, size=5, remain=0 2025/01/22 05:04:04 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/01/22 05:04:04 DEBUG : three: Destination not found in --copy-dest 2025/01/22 05:04:04 DEBUG : three: Need to transfer - File not found at Destination 2025/01/22 05:04:04 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/01/22 05:04:04 DEBUG : one: Unchanged skipping 2025/01/22 05:04:04 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/01/22 05:04:04 DEBUG : two: Unchanged skipping 2025/01/22 05:04:04 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:04:04 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:04:05 DEBUG : three: Uploading chunk 0, size=7, remain=0 2025/01/22 05:04:06 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2025/01/22 05:04:06 INFO : three: Copied (new) 2025/01/22 05:04:06 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (27.94s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:04:11 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/01/22 05:04:12 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/01/22 05:04:14 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/01/22 05:04:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/dst" 2025/01/22 05:04:17 DEBUG : Starting OpenDrive session with ID: 10e574fac51030172fbd5ca8e67864b662f68b38b94cf55312962384e5fd10e8 2025/01/22 05:04:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/backup" 2025/01/22 05:04:18 DEBUG : Starting OpenDrive session with ID: bf3752e08cfc19458b145f8572fddca03d25464c3bfb43429d58f12926e6dfd3 2025/01/22 05:04:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/22 05:04:18 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:04:18 DEBUG : two: Unchanged skipping 2025/01/22 05:04:18 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:04:19 INFO : one: Moved (server-side) to: one.bak 2025/01/22 05:04:19 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:04:20 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/01/22 05:04:21 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/01/22 05:04:21 INFO : one: Copied (new) 2025/01/22 05:04:21 DEBUG : Waiting for deletions to finish 2025/01/22 05:04:21 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/22 05:04:21 INFO : three.txt: Moved into backup dir 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:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (3), backup/three.txt.bak (5), dst/one (4), dst/two (3) got backup/one.bak (4), backup/three.txt.bak (5), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/backup/one.bak: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Not equal: expected: 3 actual : 4 Test: TestSyncBackupDirWithSuffix Messages: backup/one.bak: size incorrect file=3 vs obj=4 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: backup/one.bak: Modification time difference too big |-95456h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59 +0000 UTC) (precision 1s) fstest.go:197: Not found "dst/one" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffix Messages: 1 objects not found 2025/01/22 05:04:31 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 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:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (3), backup/three.txt.bak (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/one.bak (4), backup/three.txt.bak (5), dst/three.txt (6), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/backup/one.bak: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Not equal: expected: 3 actual : 4 Test: TestSyncBackupDirWithSuffix Messages: backup/one.bak: size incorrect file=3 vs obj=4 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: backup/one.bak: Modification time difference too big |-95456h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59 +0000 UTC) (precision 1s) fstest.go:197: Not found "dst/one" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffix Messages: 1 objects not found 2025/01/22 05:04:41 DEBUG : one: Need to transfer - File not found at Destination 2025/01/22 05:04:41 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:04:41 DEBUG : two: Unchanged skipping 2025/01/22 05:04:41 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:04:41 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:04:42 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/01/22 05:04:43 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/01/22 05:04:43 INFO : one: Copied (new) 2025/01/22 05:04:43 DEBUG : Waiting for deletions to finish 2025/01/22 05:04:43 INFO : three.txt.bak: Deleted 2025/01/22 05:04:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/22 05:04:43 INFO : three.txt: Moved into backup dir --- FAIL: TestSyncBackupDirWithSuffix (36.20s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:04:47 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/01/22 05:04:49 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/01/22 05:04:50 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/01/22 05:04:52 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/dst" 2025/01/22 05:04:52 DEBUG : Starting OpenDrive session with ID: 0abaec223a9f4955e4e3e0c3545bbad05f283ff655ed92cb0d963c34ed9d5490 2025/01/22 05:04:52 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/backup" 2025/01/22 05:04:53 DEBUG : Starting OpenDrive session with ID: 955382b1a9eedf2f6879a33c83564cdd0c0cf5f804776c63da7b5fd9936f3124 2025/01/22 05:04:54 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:04:54 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/22 05:04:54 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:04:54 DEBUG : two: Unchanged skipping 2025/01/22 05:04:55 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/22 05:04:55 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:04:55 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/01/22 05:04:56 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/01/22 05:04:56 INFO : one: Copied (new) 2025/01/22 05:04:56 DEBUG : Waiting for deletions to finish 2025/01/22 05:04:57 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/22 05:04:57 INFO : three.txt: Moved into backup dir 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:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/two (3) got backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (5), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/backup/one-2019-01-01: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Not equal: expected: 3 actual : 4 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: size incorrect file=3 vs obj=4 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: Modification time difference too big |-95456h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59 +0000 UTC) (precision 1s) fstest.go:197: Not found "dst/one" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 1 objects not found 2025/01/22 05:05:06 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 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:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (5), dst/three.txt (6), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/backup/one-2019-01-01: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Not equal: expected: 3 actual : 4 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: size incorrect file=3 vs obj=4 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: Modification time difference too big |-95456h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59 +0000 UTC) (precision 1s) fstest.go:197: Not found "dst/one" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 1 objects not found 2025/01/22 05:05:17 DEBUG : one: Need to transfer - File not found at Destination 2025/01/22 05:05:17 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:05:17 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:05:17 DEBUG : two: Unchanged skipping 2025/01/22 05:05:17 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:05:17 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/01/22 05:05:19 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/01/22 05:05:19 INFO : one: Copied (new) 2025/01/22 05:05:19 DEBUG : Waiting for deletions to finish 2025/01/22 05:05:19 INFO : three-2019-01-01.txt: Deleted 2025/01/22 05:05:20 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/22 05:05:20 INFO : three.txt: Moved into backup dir fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (44.10s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:05:31 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/01/22 05:05:33 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/01/22 05:05:35 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/01/22 05:05:37 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/dst" 2025/01/22 05:05:37 DEBUG : Starting OpenDrive session with ID: de56266abe745f24a5da67a9af5d802fef61d83204dc6a7275d34666c2da8ed1 2025/01/22 05:05:38 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:05:38 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/22 05:05:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:05:38 DEBUG : two: Unchanged skipping 2025/01/22 05:05:38 DEBUG : one: same parent dir (ODFfNzk4OTIzNV9tcFduMg) - using file/rename instead of move_copy for one.bak 2025/01/22 05:05:39 INFO : one: Moved (server-side) to: one.bak 2025/01/22 05:05:39 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:05:39 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/01/22 05:05:40 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/01/22 05:05:40 INFO : one: Copied (new) 2025/01/22 05:05:40 DEBUG : Waiting for deletions to finish 2025/01/22 05:05:40 DEBUG : three.txt: same parent dir (ODFfNzk4OTIzNV9tcFduMg) - using file/rename instead of move_copy for three.txt.bak 2025/01/22 05:05:41 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/22 05:05:41 INFO : three.txt: Moved into backup dir 2025/01/22 05:05:42 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/01/22 05:05:43 DEBUG : one.bak: Excluded (Path Filter) 2025/01/22 05:05:43 DEBUG : one.bak: Excluded 2025/01/22 05:05:43 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/01/22 05:05:43 DEBUG : three.txt.bak: Excluded 2025/01/22 05:05:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/22 05:05:43 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for checks to finish 2025/01/22 05:05:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:05:43 DEBUG : two: Unchanged skipping 2025/01/22 05:05:44 INFO : one.bak: Deleted 2025/01/22 05:05:44 DEBUG : one: same parent dir (ODFfNzk4OTIzNV9tcFduMg) - using file/rename instead of move_copy for one.bak 2025/01/22 05:05:44 INFO : one: Moved (server-side) to: one.bak 2025/01/22 05:05:44 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa/dst': Waiting for transfers to finish 2025/01/22 05:05:45 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/01/22 05:05:46 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/01/22 05:05:46 INFO : one: Copied (new) 2025/01/22 05:05:46 DEBUG : Waiting for deletions to finish 2025/01/22 05:05:46 INFO : three.txt.bak: Deleted 2025/01/22 05:05:46 DEBUG : three.txt: same parent dir (ODFfNzk4OTIzNV9tcFduMg) - using file/rename instead of move_copy for three.txt.bak 2025/01/22 05:05:47 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/22 05:05:47 INFO : three.txt: Moved into backup dir fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- PASS: TestSyncBackupDirSuffixOnly (26.39s) === RUN TestSyncSuffix run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:05:57 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/01/22 05:05:59 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/01/22 05:06:01 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/01/22 05:06:03 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/dst" 2025/01/22 05:06:03 DEBUG : Starting OpenDrive session with ID: b7f4863d1b9fbe8b8cca3dbfb2f1d8215e307ffd07b657129e2f111eac9a6003 2025/01/22 05:06:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/22 05:06:04 DEBUG : one: same parent dir (ODFfNzk4OTI0MF91S2RjVQ) - using file/rename instead of move_copy for one.bak 2025/01/22 05:06:04 INFO : one: Moved (server-side) to: one.bak 2025/01/22 05:06:05 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/01/22 05:06:06 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/01/22 05:06:06 INFO : one: Copied (new) 2025/01/22 05:06:06 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:06:06 DEBUG : two: Unchanged skipping 2025/01/22 05:06:06 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/01/22 05:06:06 DEBUG : three.txt: same parent dir (ODFfNzk4OTI0MF91S2RjVQ) - using file/rename instead of move_copy for three.txt.bak 2025/01/22 05:06:07 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/22 05:06:07 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/01/22 05:06:08 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/01/22 05:06:08 INFO : three.txt: Copied (new) 2025/01/22 05:06:09 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/22 05:06:10 INFO : one.bak: Deleted 2025/01/22 05:06:10 DEBUG : one: same parent dir (ODFfNzk4OTI0MF91S2RjVQ) - using file/rename instead of move_copy for one.bak 2025/01/22 05:06:10 INFO : one: Moved (server-side) to: one.bak 2025/01/22 05:06:10 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/01/22 05:06:11 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/01/22 05:06:11 INFO : one: Copied (new) 2025/01/22 05:06:11 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:06:11 DEBUG : two: Unchanged skipping 2025/01/22 05:06:12 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/01/22 05:06:12 INFO : three.txt.bak: Deleted 2025/01/22 05:06:12 DEBUG : three.txt: same parent dir (ODFfNzk4OTI0MF91S2RjVQ) - using file/rename instead of move_copy for three.txt.bak 2025/01/22 05:06:12 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/22 05:06:13 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/01/22 05:06:13 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/01/22 05:06:13 INFO : three.txt: Copied (new) 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:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (5), dst/one.bak (4), dst/three.txt (19), dst/three.txt.bak (6), dst/two (3) got dst/one.bak (5), dst/three.txt.bak (19), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Should be true Test: TestSyncSuffix Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/dst/one.bak: md5 hash incorrect - expecting "c7957179c41f69d44f217a108c7915d8" got "0f93e81041f0cab37c37a05ae998b219" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Not equal: expected: 4 actual : 5 Test: TestSyncSuffix Messages: dst/one.bak: size incorrect file=4 vs obj=5 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Should be true Test: TestSyncSuffix Messages: dst/one.bak: Modification time difference too big |-119h59m59.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2011-12-30 12:59:59 +0000 UTC) (precision 1s) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Should be true Test: TestSyncSuffix Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/dst/three.txt.bak: md5 hash incorrect - expecting "91341eed84691a83caea73aa785736d5" got "423e01c5e84a30d129b97aedb6e219a9" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Not equal: expected: 6 actual : 19 Test: TestSyncSuffix Messages: dst/three.txt.bak: size incorrect file=6 vs obj=19 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Should be true Test: TestSyncSuffix Messages: dst/three.txt.bak: Modification time difference too big |-95576h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-30 12:59:59 +0000 UTC) (precision 1s) fstest.go:197: Not found "dst/one" fstest.go:197: Not found "dst/three.txt" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Not equal: expected: 0 actual : 2 Test: TestSyncSuffix Messages: 2 objects not found --- FAIL: TestSyncSuffix (27.19s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:06:25 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/01/22 05:06:27 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/01/22 05:06:29 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/01/22 05:06:30 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vihezog9cuqa/dst" 2025/01/22 05:06:30 DEBUG : Starting OpenDrive session with ID: d8ad57d88eb8006811fb5f25f55f7c7f4b5400c8a4d9f3aaca93af5099d94a78 2025/01/22 05:06:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/22 05:06:31 DEBUG : one: same parent dir (ODFfNzk4OTI0NV9qZE4yTg) - using file/rename instead of move_copy for one-2019-01-01 2025/01/22 05:06:32 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/22 05:06:32 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/01/22 05:06:33 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/01/22 05:06:33 INFO : one: Copied (new) 2025/01/22 05:06:33 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:06:33 DEBUG : two: Unchanged skipping 2025/01/22 05:06:34 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/01/22 05:06:34 DEBUG : three.txt: same parent dir (ODFfNzk4OTI0NV9qZE4yTg) - using file/rename instead of move_copy for three-2019-01-01.txt 2025/01/22 05:06:34 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/22 05:06:35 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/01/22 05:06:36 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/01/22 05:06:36 INFO : three.txt: Copied (new) 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:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2491 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (4), dst/one-2019-01-01 (3), dst/three-2019-01-01.txt (5), dst/three.txt (6), dst/two (3) got dst/one-2019-01-01 (4), dst/three-2019-01-01.txt (6), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2491 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/dst/one-2019-01-01: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2491 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Not equal: expected: 3 actual : 4 Test: TestSyncSuffixKeepExtension Messages: dst/one-2019-01-01: size incorrect file=3 vs obj=4 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2491 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: dst/one-2019-01-01: Modification time difference too big |-95456h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59 +0000 UTC) (precision 1s) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2491 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/dst/three-2019-01-01.txt: md5 hash incorrect - expecting "35d6d33467aae9a2e3dccb4b6b027878" got "91341eed84691a83caea73aa785736d5" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2491 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Not equal: expected: 5 actual : 6 Test: TestSyncSuffixKeepExtension Messages: dst/three-2019-01-01.txt: size incorrect file=5 vs obj=6 fstest.go:197: Not found "dst/one" fstest.go:197: Not found "dst/three.txt" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2491 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Not equal: expected: 0 actual : 2 Test: TestSyncSuffixKeepExtension Messages: 2 objects not found 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:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2497 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (4), dst/one-2019-01-01 (3), dst/three-2019-01-01.txt (5), dst/three.txt (6), dst/two (3) got dst/one-2019-01-01 (4), dst/three-2019-01-01.txt (6), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2497 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/dst/one-2019-01-01: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2497 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Not equal: expected: 3 actual : 4 Test: TestSyncSuffixKeepExtension Messages: dst/one-2019-01-01: size incorrect file=3 vs obj=4 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2497 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: dst/one-2019-01-01: Modification time difference too big |-95456h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59 +0000 UTC) (precision 1s) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2497 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: OpenDrive root 'rclone-test-vihezog9cuqa'/dst/three-2019-01-01.txt: md5 hash incorrect - expecting "35d6d33467aae9a2e3dccb4b6b027878" got "91341eed84691a83caea73aa785736d5" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2497 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Not equal: expected: 5 actual : 6 Test: TestSyncSuffixKeepExtension Messages: dst/three-2019-01-01.txt: size incorrect file=5 vs obj=6 fstest.go:197: Not found "dst/three.txt" fstest.go:197: Not found "dst/one" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2497 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Not equal: expected: 0 actual : 2 Test: TestSyncSuffixKeepExtension Messages: 2 objects not found 2025/01/22 05:06:53 DEBUG : one: Need to transfer - File not found at Destination 2025/01/22 05:06:54 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/01/22 05:06:55 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/01/22 05:06:55 INFO : one: Copied (new) 2025/01/22 05:06:55 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:06:55 DEBUG : two: Unchanged skipping 2025/01/22 05:06:55 DEBUG : three.txt: Need to transfer - File not found at Destination 2025/01/22 05:06:56 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/01/22 05:06:57 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/01/22 05:06:57 INFO : three.txt: Copied (new) --- FAIL: TestSyncSuffixKeepExtension (35.56s) === RUN TestSyncImmutable run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:06:59 DEBUG : existing: Need to transfer - File not found at Destination 2025/01/22 05:06:59 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:06:59 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:07:00 DEBUG : existing: Uploading chunk 0, size=6, remain=0 2025/01/22 05:07:01 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/01/22 05:07:01 INFO : existing: Copied (new) 2025/01/22 05:07:01 DEBUG : Waiting for deletions to finish run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:07:02 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/01/22 05:07:02 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for checks to finish 2025/01/22 05:07:02 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/01/22 05:07:02 DEBUG : OpenDrive root 'rclone-test-vihezog9cuqa': Waiting for transfers to finish 2025/01/22 05:07:02 ERROR : OpenDrive root 'rclone-test-vihezog9cuqa': not deleting files as there were IO errors 2025/01/22 05:07:02 ERROR : OpenDrive root 'rclone-test-vihezog9cuqa': not deleting directories as there were IO errors run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" --- PASS: TestSyncImmutable (4.09s) === RUN TestFixCase run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:07:04 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0 2025/01/22 05:07:06 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0 run.go:285: Failed to put "SUBDIRA/subdirb/SUBDIRC/HELLO" to "OpenDrive root 'rclone-test-vihezog9cuqa'": failed to get folder list: Directory doesn't exist (Error 404) 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:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1175 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1353 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1683 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/runtime/panic.go:626 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1005 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1089 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2648 Error: Not equal: expected: []string{} actual : []string{"SUBDIRA"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "SUBDIRA" } Test: TestFixCase Messages: directories --- FAIL: TestFixCase (13.07s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" 2025/01/22 05:07:16 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/22 05:07:16 INFO : sub dir: Making directory 2025/01/22 05:07:17 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC sync_test.go:2793: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:18 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2791 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2871 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestNothingToTransferWithEmptyDirs run.go:125: removing dir "sub dir2/very/very/very/very/very/nested/subdir" failed - try 1/3: folder not empty --- FAIL: TestNothingToTransferWithEmptyDirs (12.48s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-vihezog9cuqa'", Local "Local file system at /tmp/rclone1804440921", Modify Window "1s" sync_test.go:2793: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:18 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2791 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2875 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestNothingToTransferWithoutEmptyDirs fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- FAIL: TestNothingToTransferWithoutEmptyDirs (12.36s) FAIL "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopy|TestCopyDeleteBefore|TestCopyNoTraverse|TestCopyWithDryRun|TestFixCase|TestMoveWithDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopyOverSelf|TestSyncAfterAddingAFile|TestSyncAfterChangingModtimeOnly|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCopyDest|TestSyncIgnoreTimes|TestSyncImmutable|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames)$'" - Finished ERROR in 5m15.854221744s (try 2/5): exit status 1: Failed [TestCopyWithDryRun TestCopy TestSyncAfterChangingModtimeOnly TestSyncAfterRemovingAFileAndAddingAFileSubDir TestMoveWithDeleteEmptySrcDirs TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncSuffix TestSyncSuffixKeepExtension TestFixCase TestNothingToTransferWithEmptyDirs TestNothingToTransferWithoutEmptyDirs]