"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyWithDryRun|TestFixCase|TestMoveOverSelf|TestNothingToTransferWithoutEmptyDirs|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDir|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension)$'" - Starting (try 2/5) 2025/04/20 04:30:29 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dunufiw6jike" 2025/04/20 04:30:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/20 04:30:29 DEBUG : Starting OpenDrive session with ID: dc501b124162fb8907c19e7c88de1800a1f78c5eb36f43cfeacefb040d4a17e9 2025/04/20 04:30:30 DEBUG : Creating backend with remote "/tmp/rclone2455708724" === RUN TestCopyWithDryRun run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" 2025/04/20 04:30:30 ERROR : OpenDrive root 'rclone-test-dunufiw6jike': error reading destination root directory: failed to get folder list: Directory doesn't exist (Error 404) 2025/04/20 04:30:30 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike': Waiting for checks to finish 2025/04/20 04:30:30 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike': Waiting for transfers to finish run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" sync_test.go:59: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:59 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestCopyWithDryRun 2025/04/20 04:30:30 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.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/panic.go:629 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1006 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:59 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestCopyWithDryRun 2025/04/20 04:30:31 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.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1338 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/panic.go:629 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1006 /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.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/panic.go:629 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1006 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:59 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestCopyWithDryRun --- FAIL: TestCopyWithDryRun (0.83s) === RUN TestMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" 2025/04/20 04:30:32 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/04/20 04:30:33 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike': Waiting for checks to finish 2025/04/20 04:30:33 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/20 04:30:33 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike': Waiting for transfers to finish 2025/04/20 04:30:33 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/04/20 04:30:34 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/04/20 04:30:34 INFO : sub dir/hello world: Copied (replaced existing) 2025/04/20 04:30:34 INFO : sub dir/hello world: Deleted run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" --- PASS: TestMoveOverSelf (5.34s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" 2025/04/20 04:30:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kunaqom1xeti" 2025/04/20 04:30:36 DEBUG : Starting OpenDrive session with ID: 2e3a061b5b4f6c615f339e6a3cbbb2ed8a360549b12f91817ac9e6ee364a3164 2025/04/20 04:30:37 DEBUG : potato2: Uploading chunk 0, size=60, remain=0 2025/04/20 04:30:39 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/04/20 04:30:41 DEBUG : potato3: Uploading chunk 0, size=68, remain=0 2025/04/20 04:30:42 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) OpenDrive root 'rclone-test-dunufiw6jike' -> OpenDrive root 'rclone-test-kunaqom1xeti' 2025/04/20 04:30:43 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/04/20 04:30:45 DEBUG : potato3: Uploading chunk 0, size=60, remain=0 2025/04/20 04:30:46 DEBUG : OpenDrive root 'rclone-test-kunaqom1xeti': Using server-side directory move 2025/04/20 04:30:46 INFO : OpenDrive root 'rclone-test-kunaqom1xeti': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/20 04:30:46 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/20 04:30:46 DEBUG : potato3: Need to transfer - File not found at Destination 2025/04/20 04:30:46 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/04/20 04:30:46 DEBUG : empty space: Unchanged skipping 2025/04/20 04:30:46 DEBUG : OpenDrive root 'rclone-test-kunaqom1xeti': Waiting for checks to finish 2025/04/20 04:30:47 INFO : empty space: Deleted 2025/04/20 04:30:47 DEBUG : OpenDrive root 'rclone-test-kunaqom1xeti': Waiting for transfers to finish 2025/04/20 04:30:47 INFO : potato3: Moved (server-side) 2025/04/20 04:30:47 INFO : potato2: Moved (server-side) 2025/04/20 04:30:47 INFO : tomatoDir: Removing directory 2025/04/20 04:30:48 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike': deleted 1 directories 2025/04/20 04:30:48 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vinaqel7veni" 2025/04/20 04:30:49 DEBUG : Starting OpenDrive session with ID: 4ccd3b94df60118f0e33455f53d3dc851439631ac0460623a71a395228db4a3b 2025/04/20 04:30:49 INFO : tomatoDir: Making directory 2025/04/20 04:30:49 DEBUG : OpenDrive root 'rclone-test-vinaqel7veni': Using server-side directory move 2025/04/20 04:30:50 DEBUG : : same parent dir (0) - using folder/rename instead of move_copy 2025/04/20 04:30:50 INFO : OpenDrive root 'rclone-test-vinaqel7veni': Server side directory move succeeded 2025/04/20 04:30:50 ERROR : : error listing: directory not found 2025/04/20 04:30:51 ERROR : : error listing: directory not found 2025/04/20 04:30:51 DEBUG : OpenDrive root 'rclone-test-vinaqel7veni': Purge remote 2025/04/20 04:30:51 DEBUG : OpenDrive root 'rclone-test-kunaqom1xeti': Purge remote 2025/04/20 04:30:51 NOTICE: purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (15.58s) === RUN TestSyncBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" 2025/04/20 04:30:53 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/20 04:30:54 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/20 04:30:56 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/20 04:30:57 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dunufiw6jike/dst" 2025/04/20 04:30:57 DEBUG : Starting OpenDrive session with ID: 6756ce073d0e186702cc0c4f1b3f81353ff3da5b07836e154d81a91bd63b0bc0 2025/04/20 04:30:58 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dunufiw6jike/backup" 2025/04/20 04:30:58 DEBUG : Starting OpenDrive session with ID: af158137790e00ea7ece6c86a6ad1e0a7cee4058696002635900af4e189fbcfb 2025/04/20 04:30:59 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/20 04:30:59 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/20 04:30:59 DEBUG : two: Unchanged skipping 2025/04/20 04:30:59 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for checks to finish 2025/04/20 04:31:00 INFO : one: Moved (server-side) 2025/04/20 04:31:00 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for transfers to finish 2025/04/20 04:31:01 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/20 04:31:02 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/20 04:31:02 INFO : one: Copied (new) 2025/04/20 04:31:02 DEBUG : Waiting for deletions to finish 2025/04/20 04:31:02 INFO : three.txt: Moved (server-side) 2025/04/20 04:31:02 INFO : three.txt: Moved into backup dir 2025/04/20 04:31:03 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/04/20 04:31:05 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/20 04:31:05 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/20 04:31:05 DEBUG : two: Unchanged skipping 2025/04/20 04:31:05 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for checks to finish 2025/04/20 04:31:05 INFO : one: Deleted 2025/04/20 04:31:05 INFO : one: Moved (server-side) 2025/04/20 04:31:05 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for transfers to finish 2025/04/20 04:31:06 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/20 04:31:07 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/20 04:31:07 INFO : one: Copied (new) 2025/04/20 04:31:07 DEBUG : Waiting for deletions to finish 2025/04/20 04:31:07 INFO : three.txt: Deleted 2025/04/20 04:31:08 INFO : three.txt: Moved (server-side) 2025/04/20 04:31:08 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 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: TestSyncBackupDir (33.54s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" 2025/04/20 04:31:26 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/20 04:31:28 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/20 04:31:29 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 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 2025/04/20 04:31:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dunufiw6jike/dst" 2025/04/20 04:31:38 DEBUG : Config file has changed externally - reloading 2025/04/20 04:31:38 DEBUG : Starting OpenDrive session with ID: 5bfaca633538502501b9a620c3abb710901ccf3559fd4cfdbad8247b0649d4db 2025/04/20 04:31:39 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dunufiw6jike/backup" 2025/04/20 04:31:39 DEBUG : Starting OpenDrive session with ID: c7c800996eaa967457da610601f82ecf1d3115a8e5b2eaaa7c915f9ef1a1fdc4 2025/04/20 04:31:40 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/20 04:31:40 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/20 04:31:40 DEBUG : two: Unchanged skipping 2025/04/20 04:31:40 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for checks to finish 2025/04/20 04:31:41 INFO : one: Moved (server-side) to: one.bak 2025/04/20 04:31:41 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for transfers to finish 2025/04/20 04:31:42 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/20 04:31:43 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/20 04:31:43 INFO : one: Copied (new) 2025/04/20 04:31:43 DEBUG : Waiting for deletions to finish 2025/04/20 04:31:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/20 04:31:43 INFO : three.txt: Moved into backup dir 2025/04/20 04:31:44 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/04/20 04:31:46 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/20 04:31:46 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/20 04:31:46 DEBUG : two: Unchanged skipping 2025/04/20 04:31:46 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for checks to finish 2025/04/20 04:31:46 INFO : one.bak: Deleted 2025/04/20 04:31:46 INFO : one: Moved (server-side) to: one.bak 2025/04/20 04:31:46 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for transfers to finish 2025/04/20 04:31:47 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/20 04:31:48 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/20 04:31:48 INFO : one: Copied (new) 2025/04/20 04:31:48 DEBUG : Waiting for deletions to finish 2025/04/20 04:31:48 INFO : three.txt.bak: Deleted 2025/04/20 04:31:48 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/20 04:31:48 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:2423 /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 (4), backup/three.txt.bak (6), dst/one (5), dst/two (3) got backup/one.bak (5), backup/three.txt.bak (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:2423 /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-dunufiw6jike'/backup/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:2423 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Not equal: expected: 4 actual : 5 Test: TestSyncBackupDirWithSuffix Messages: backup/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:2423 /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 |-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: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:2423 /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 --- FAIL: TestSyncBackupDirWithSuffix (33.74s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" 2025/04/20 04:32:00 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/20 04:32:01 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/20 04:32:03 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/20 04:32:04 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dunufiw6jike/dst" 2025/04/20 04:32:05 DEBUG : Starting OpenDrive session with ID: 3924141dc461cc506b5717c3c9e18396569aaf424e7994bd2bf731d8a93272fc 2025/04/20 04:32:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-dunufiw6jike/backup" 2025/04/20 04:32:06 DEBUG : Starting OpenDrive session with ID: b49ff1763af3437ddd50bf1066b310774d70a6215f8c84907b3678fed0f59129 2025/04/20 04:32:06 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/20 04:32:06 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/20 04:32:06 DEBUG : two: Unchanged skipping 2025/04/20 04:32:06 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for checks to finish 2025/04/20 04:32:07 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/20 04:32:07 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for transfers to finish 2025/04/20 04:32:08 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/20 04:32:09 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/20 04:32:09 INFO : one: Copied (new) 2025/04/20 04:32:09 DEBUG : Waiting for deletions to finish 2025/04/20 04:32:09 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/20 04:32:09 INFO : three.txt: Moved into backup dir 2025/04/20 04:32:11 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/04/20 04:32:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/20 04:32:12 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/20 04:32:12 DEBUG : two: Unchanged skipping 2025/04/20 04:32:12 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for checks to finish 2025/04/20 04:32:12 INFO : one-2019-01-01: Deleted 2025/04/20 04:32:13 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/20 04:32:13 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike/dst': Waiting for transfers to finish 2025/04/20 04:32:14 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/20 04:32:14 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/20 04:32:14 INFO : one: Copied (new) 2025/04/20 04:32:14 DEBUG : Waiting for deletions to finish 2025/04/20 04:32:15 INFO : three-2019-01-01.txt: Deleted 2025/04/20 04:32:15 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/20 04:32:15 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (18.63s) === RUN TestFixCase run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" 2025/04/20 04:32:18 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0 2025/04/20 04:32:20 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0 2025/04/20 04:32:22 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 0, size=8, remain=0 2025/04/20 04:32:24 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 0, size=8, remain=0 2025/04/20 04:32:27 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/20 04:32:27 DEBUG : existing: Unchanged skipping 2025/04/20 04:32:27 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2025/04/20 04:32:27 DEBUG : subdira-rclone-move-zodatiq2: same parent dir (ODFfODM0NzgyMF91UklCSQ) - using folder/rename instead of move_copy 2025/04/20 04:32:27 DEBUG : EXISTING: moving to existing-rclone-move-pizonoc1 2025/04/20 04:32:27 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-faxovum0 2025/04/20 04:32:27 DEBUG : subdira: same parent dir (ODFfODM0NzgyMF91UklCSQ) - using folder/rename instead of move_copy 2025/04/20 04:32:27 DEBUG : EXISTINGBUTDIFFERENT: same parent dir (ODFfODM0NzgyMF91UklCSQ) - using file/rename instead of move_copy for existingbutdifferent-rclone-move-faxovum0 2025/04/20 04:32:27 INFO : SUBDIRA: Fixed case by renaming to: subdira 2025/04/20 04:32:28 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-faxovum0 2025/04/20 04:32:28 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2025/04/20 04:32:28 DEBUG : EXISTING: same parent dir (ODFfODM0NzgyMF91UklCSQ) - using file/rename instead of move_copy for existing-rclone-move-pizonoc1 2025/04/20 04:32:28 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-pizonoc1 2025/04/20 04:32:28 DEBUG : EXISTING: moving to existing 2025/04/20 04:32:28 DEBUG : existingbutdifferent-rclone-move-faxovum0: same parent dir (ODFfODM0NzgyMF91UklCSQ) - using file/rename instead of move_copy for existingbutdifferent 2025/04/20 04:32:28 DEBUG : subdira/subdirb-rclone-move-mexocoh3: same parent dir (ODFfODM0Nzg4OF82dHRJVQ) - using folder/rename instead of move_copy 2025/04/20 04:32:28 DEBUG : existing-rclone-move-pizonoc1: same parent dir (ODFfODM0NzgyMF91UklCSQ) - using file/rename instead of move_copy for existing 2025/04/20 04:32:28 INFO : existingbutdifferent-rclone-move-faxovum0: Moved (server-side) to: existingbutdifferent 2025/04/20 04:32:28 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2025/04/20 04:32:28 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2025/04/20 04:32:28 DEBUG : existingbutdifferent: Uploading chunk 0, size=5, remain=0 2025/04/20 04:32:28 INFO : OpenDrive root 'rclone-test-dunufiw6jike': Can't DirMove - falling back to file moves: can't copy directory - destination already exists 2025/04/20 04:32:29 INFO : existing-rclone-move-pizonoc1: Moved (server-side) to: existing 2025/04/20 04:32:29 INFO : EXISTING: Moved (server-side) to: existing 2025/04/20 04:32:29 INFO : EXISTING: Fixed case by renaming to: existing 2025/04/20 04:32:30 DEBUG : existingbutdifferent: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/04/20 04:32:30 INFO : existingbutdifferent: Copied (replaced existing) 2025/04/20 04:32:30 INFO : subdira/subdirb/SUBDIRC/HELLO: Deleted 2025/04/20 04:32:30 INFO : subdira/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Deleted 2025/04/20 04:32:30 ERROR : subdira/subdirb-rclone-move-mexocoh3/SUBDIRC/HELLO: Couldn't move: failed to get fileinfo: File is deleted (Error 404) 2025/04/20 04:32:30 ERROR : subdira/subdirb-rclone-move-mexocoh3/SUBDIRC/subdird/filewithoutcasedifferences: Couldn't move: failed to get fileinfo: Get "https://dev.opendrive.com/api/v1/file/info.json/ODFfOTU3MDQ1MDhfQ05zYkg?session_id=dc501b124162fb8907c19e7c88de1800a1f78c5eb36f43cfeacefb040d4a17e9": failed to get fileinfo: File is deleted (Error 404) 2025/04/20 04:32:30 ERROR : SUBDIRA/subdirb: Error while attempting to rename to subdira/subdirb: RenameDir renames: failed to get fileinfo: File is deleted (Error 404) 2025/04/20 04:32:30 DEBUG : subdira/subdirb/subdirc/hello: Need to transfer - File not found at Destination 2025/04/20 04:32:30 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Need to transfer - File not found at Destination 2025/04/20 04:32:30 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike': Waiting for checks to finish 2025/04/20 04:32:30 DEBUG : OpenDrive root 'rclone-test-dunufiw6jike': Waiting for transfers to finish 2025/04/20 04:32:31 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 0, size=5, remain=0 2025/04/20 04:32:32 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 0, size=5, remain=0 2025/04/20 04:32:32 DEBUG : subdira/subdirb/subdirc/hello: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/04/20 04:32:32 INFO : subdira/subdirb/subdirc/hello: Copied (new) 2025/04/20 04:32:32 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/04/20 04:32:32 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (new) 2025/04/20 04:32:32 ERROR : OpenDrive root 'rclone-test-dunufiw6jike': not deleting files as there were IO errors 2025/04/20 04:32:32 ERROR : OpenDrive root 'rclone-test-dunufiw6jike': not deleting directories as there were IO errors sync_test.go:2655: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2655 Error: Received unexpected error: RenameDir renames: failed to get fileinfo: File is deleted (Error 404) Test: TestFixCase --- FAIL: TestFixCase (18.16s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-dunufiw6jike'", Local "Local file system at /tmp/rclone2455708724", Modify Window "1s" 2025/04/20 04:32:36 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/20 04:32:36 INFO : sub dir: Making directory 2025/04/20 04:32:36 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC sync_test.go:2868: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2868 /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:2866 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2950 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestNothingToTransferWithoutEmptyDirs --- FAIL: TestNothingToTransferWithoutEmptyDirs (6.50s) FAIL "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyWithDryRun|TestFixCase|TestMoveOverSelf|TestNothingToTransferWithoutEmptyDirs|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDir|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension)$'" - Finished ERROR in 2m13.680449428s (try 2/5): exit status 1: Failed [TestCopyWithDryRun TestSyncBackupDirWithSuffix TestFixCase TestNothingToTransferWithoutEmptyDirs]