"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyAfterDelete|TestCopyRedownload|TestCopyWithDryRun|TestMoveOverSelf|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncOverlapWithFilter)$'" - Starting (try 2/5) 2025/03/20 05:14:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zucotux3batu" 2025/03/20 05:14:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/03/20 05:14:21 DEBUG : Starting OpenDrive session with ID: 0a31f8ecba54ef7dbe407914b8e4ddf43f2c5ee117b5a12a350e38e083af028a 2025/03/20 05:14:21 DEBUG : Creating backend with remote "/tmp/rclone2515062284" === RUN TestCopyWithDryRun run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:14:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/20 05:14:22 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/03/20 05:14:22 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Waiting for checks to finish 2025/03/20 05:14:22 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Waiting for transfers to finish run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" --- PASS: TestCopyWithDryRun (1.37s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:14:23 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/03/20 05:14:25 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nuwited3vaza" 2025/03/20 05:14:25 DEBUG : Starting OpenDrive session with ID: 9c88cf54f8bda7ded46d4c3f315d1f93c1c1d42437ec2129243d420ea7c33685 sync_test.go:626: Server side copy (if possible) OpenDrive root 'rclone-test-zucotux3batu' -> OpenDrive root 'rclone-test-nuwited3vaza' 2025/03/20 05:14:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/20 05:14:26 DEBUG : OpenDrive root 'rclone-test-nuwited3vaza': Waiting for checks to finish 2025/03/20 05:14:26 DEBUG : OpenDrive root 'rclone-test-nuwited3vaza': Waiting for transfers to finish 2025/03/20 05:14:27 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/03/20 05:14:27 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:14:28 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/03/20 05:14:29 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/03/20 05:14:29 DEBUG : OpenDrive root 'rclone-test-nuwited3vaza': Waiting for checks to finish 2025/03/20 05:14:29 DEBUG : OpenDrive root 'rclone-test-nuwited3vaza': Waiting for transfers to finish 2025/03/20 05:14:30 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/03/20 05:14:30 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:14:30 DEBUG : OpenDrive root 'rclone-test-nuwited3vaza': Purge remote --- PASS: TestServerSideCopyOverSelf (9.64s) === RUN TestMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:14:33 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/03/20 05:14:35 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/03/20 05:14:35 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Waiting for checks to finish 2025/03/20 05:14:35 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Waiting for transfers to finish 2025/03/20 05:14:35 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/03/20 05:14:36 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/03/20 05:14:36 INFO : sub dir/hello world: Copied (replaced existing) 2025/03/20 05:14:36 INFO : sub dir/hello world: Deleted run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" --- PASS: TestMoveOverSelf (5.48s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:14:39 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/03/20 05:14:40 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-gatosuy2jayi" 2025/03/20 05:14:40 DEBUG : Starting OpenDrive session with ID: 61c42f42fac6c96aa328ba9d0b57ad9ef3fe1859bf1fd54c6a902dc5cbd34c03 sync_test.go:671: Server side copy (if possible) OpenDrive root 'rclone-test-zucotux3batu' -> OpenDrive root 'rclone-test-gatosuy2jayi' 2025/03/20 05:14:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/20 05:14:41 DEBUG : OpenDrive root 'rclone-test-gatosuy2jayi': Waiting for checks to finish 2025/03/20 05:14:41 DEBUG : OpenDrive root 'rclone-test-gatosuy2jayi': Waiting for transfers to finish 2025/03/20 05:14:42 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/03/20 05:14:42 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:14:43 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/03/20 05:14:44 DEBUG : OpenDrive root 'rclone-test-gatosuy2jayi': Using server-side directory move 2025/03/20 05:14:44 INFO : OpenDrive root 'rclone-test-gatosuy2jayi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/03/20 05:14:44 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/03/20 05:14:44 DEBUG : OpenDrive root 'rclone-test-gatosuy2jayi': Waiting for checks to finish 2025/03/20 05:14:44 DEBUG : OpenDrive root 'rclone-test-gatosuy2jayi': Waiting for transfers to finish 2025/03/20 05:14:45 INFO : sub dir/hello world: Deleted 2025/03/20 05:14:45 INFO : sub dir/hello world: Moved (server-side) 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/03/20 05:14:54 DEBUG : sub dir/hello world: Uploading chunk 0, size=24, remain=0 2025/03/20 05:14:55 DEBUG : testing file moves 2025/03/20 05:14:56 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/03/20 05:14:56 DEBUG : OpenDrive root 'rclone-test-gatosuy2jayi': Waiting for checks to finish 2025/03/20 05:14:56 DEBUG : OpenDrive root 'rclone-test-gatosuy2jayi': Waiting for transfers to finish 2025/03/20 05:14:56 INFO : sub dir/hello world: Deleted 2025/03/20 05:14:56 INFO : sub dir/hello world: Moved (server-side) run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:14:57 DEBUG : OpenDrive root 'rclone-test-gatosuy2jayi': Purge remote --- PASS: TestServerSideMoveOverSelf (21.37s) === RUN TestCopyAfterDelete run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:15:00 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/03/20 05:15:01 ERROR : : error listing: directory not found 2025/03/20 05:15:01 INFO : Local file system at /tmp/rclone2515062284: Making directory 2025/03/20 05:15:02 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Waiting for checks to finish 2025/03/20 05:15:02 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Waiting for transfers to finish run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" --- PASS: TestCopyAfterDelete (4.35s) === RUN TestCopyRedownload run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:15:04 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/03/20 05:15:06 DEBUG : Added delayed dir = "sub dir", newDst= 2025/03/20 05:15:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/20 05:15:06 DEBUG : Local file system at /tmp/rclone2515062284: Waiting for checks to finish 2025/03/20 05:15:06 DEBUG : Local file system at /tmp/rclone2515062284: Waiting for transfers to finish 2025/03/20 05:15:06 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/03/20 05:15:06 DEBUG : sub dir/hello world.87ffde68.partial: renamed to: sub dir/hello world 2025/03/20 05:15:06 INFO : sub dir/hello world: Copied (new) 2025/03/20 05:15:06 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" --- PASS: TestCopyRedownload (4.40s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:15:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zucotux3batu/rclone-sync-test" 2025/03/20 05:15:08 DEBUG : Starting OpenDrive session with ID: 343e13c079180a777dcbf55e6a2d29522088beb43d4e88bf6f4bf9f40d0231db 2025/03/20 05:15:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zucotux3batu/rclone-sync-test-include/layer2" 2025/03/20 05:15:09 DEBUG : Config file has changed externally - reloading 2025/03/20 05:15:10 DEBUG : Starting OpenDrive session with ID: 52085d095a08c3a6e10f6ad12119d37b1efe7c3468ff2471fad53a00a482bbca 2025/03/20 05:15:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zucotux3batu/rclone-sync-test-ignore-file" 2025/03/20 05:15:11 DEBUG : Starting OpenDrive session with ID: beb25ad91cf253ba6e6a0be623b7f00824fc2fda6d0b5c2043a704dde860a0ac 2025/03/20 05:15:13 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0 2025/03/20 05:15:15 DEBUG : rclone-sync-test: Excluded 2025/03/20 05:15:15 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/03/20 05:15:16 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/03/20 05:15:16 DEBUG : OpenDrive root 'rclone-test-zucotux3batu/rclone-sync-test': Waiting for checks to finish 2025/03/20 05:15:16 DEBUG : OpenDrive root 'rclone-test-zucotux3batu/rclone-sync-test': Waiting for transfers to finish 2025/03/20 05:15:16 DEBUG : Waiting for deletions to finish 2025/03/20 05:15:16 INFO : There was nothing to transfer 2025/03/20 05:15:16 DEBUG : rclone-sync-test: Excluded 2025/03/20 05:15:16 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/03/20 05:15:17 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/03/20 05:15:17 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Waiting for checks to finish 2025/03/20 05:15:17 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Waiting for transfers to finish 2025/03/20 05:15:17 DEBUG : Waiting for deletions to finish 2025/03/20 05:15:17 INFO : rclone-sync-test-include: Removing directory 2025/03/20 05:15:17 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': deleted 1 directories 2025/03/20 05:15:17 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-zucotux3batu'", Local "Local file system at /tmp/rclone2515062284", Modify Window "1s" 2025/03/20 05:15:17 ERROR : OpenDrive root 'rclone-test-zucotux3batu': Failed to discover whether directory is included: failed to get folder list: Folder is already deleted (Error 403) sync_test.go:2003: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2003 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2029 Error: Received unexpected error: can't sync or move files on overlapping remotes (try excluding the destination with a filter rule) Test: TestSyncOverlapWithFilter --- FAIL: TestSyncOverlapWithFilter (13.59s) FAIL 2025/03/20 05:15:21 DEBUG : OpenDrive root 'rclone-test-zucotux3batu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyAfterDelete|TestCopyRedownload|TestCopyWithDryRun|TestMoveOverSelf|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncOverlapWithFilter)$'" - Finished ERROR in 1m1.468242564s (try 2/5): exit status 1: Failed [TestSyncOverlapWithFilter]