"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestCopyAfterDelete|TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyRedownload|TestManualTransformFile|TestMove|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse|TestTransformFile)$'" - Starting (try 2/5) 2025/07/22 01:51:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bekenop7koxi" 2025/07/22 01:51:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/22 01:51:10 DEBUG : Starting OpenDrive session with ID: c459bb73b4443bcf8eee8c3c22d1cce2eeabbde8173727d3dca840edfd38ba58 2025/07/22 01:51:10 DEBUG : Creating backend with remote "/tmp/rclone3119080479" === RUN TestCopyNoTraverse run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:11 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:11 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:12 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:13 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:13 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (4.17s) === RUN TestCopyCheckFirst run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:14 INFO : OpenDrive root 'rclone-test-bekenop7koxi': Running all checks before starting transfers 2025/07/22 01:51:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:15 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:15 INFO : OpenDrive root 'rclone-test-bekenop7koxi': Checks finished, now starting transfers 2025/07/22 01:51:15 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:16 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:17 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:17 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (3.51s) === RUN TestSyncNoTraverse run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:18 ERROR : Ignoring --no-traverse with sync 2025/07/22 01:51:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:18 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:18 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:19 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:20 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:20 INFO : sub dir/hello world: Copied (new) 2025/07/22 01:51:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (3.53s) === RUN TestCopyEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:21 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/07/22 01:51:21 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/22 01:51:21 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/07/22 01:51:21 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/22 01:51:22 INFO : sub dir2: Making directory 2025/07/22 01:51:22 INFO : sub dir: Making directory 2025/07/22 01:51:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:22 INFO : sub dir2/sub sub dir2: Making directory 2025/07/22 01:51:23 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:23 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:23 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:24 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:24 INFO : sub dir/hello world: Copied (new) 2025/07/22 01:51:25 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (4.87s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:26 INFO : sub dir2: Making directory 2025/07/22 01:51:26 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/07/22 01:51:26 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/22 01:51:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:26 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:26 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:28 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:29 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:29 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (3.65s) === RUN TestMoveEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:30 DEBUG : sub dir2: Making directory with metadata 2025/07/22 01:51:30 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/22 01:51:30 INFO : sub dir2: Making directory 2025/07/22 01:51:30 INFO : sub dir: Making directory 2025/07/22 01:51:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:31 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:31 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:32 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:33 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:33 INFO : sub dir/hello world: Copied (new) 2025/07/22 01:51:33 INFO : sub dir/hello world: Deleted 2025/07/22 01:51:33 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/07/22 01:51:33 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (4.54s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:35 INFO : sub dir2: Making directory 2025/07/22 01:51:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:35 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:35 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:36 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:37 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:37 INFO : sub dir/hello world: Copied (new) 2025/07/22 01:51:37 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (3.64s) === RUN TestSyncEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:38 DEBUG : sub dir2: Making directory with metadata 2025/07/22 01:51:38 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/22 01:51:38 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/22 01:51:38 INFO : sub dir2: Making directory 2025/07/22 01:51:39 INFO : sub dir: Making directory 2025/07/22 01:51:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:39 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:39 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:40 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:41 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:41 INFO : sub dir/hello world: Copied (new) 2025/07/22 01:51:41 DEBUG : Waiting for deletions to finish 2025/07/22 01:51:41 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (4.43s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:43 INFO : sub dir2: Making directory 2025/07/22 01:51:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:43 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:43 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:44 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:45 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:51:45 INFO : sub dir/hello world: Copied (new) 2025/07/22 01:51:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (3.49s) === RUN TestServerSideCopy run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:47 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:48 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-qaguxef8cise" 2025/07/22 01:51:48 DEBUG : Config file has changed externally - reloading 2025/07/22 01:51:49 DEBUG : Starting OpenDrive session with ID: 917fa8c97f23693c58fe3c92fb599f6c25ada92763f8dea2cbbbbf743fd094e5 sync_test.go:620: Server side copy (if possible) OpenDrive root 'rclone-test-bekenop7koxi' -> OpenDrive root 'rclone-test-qaguxef8cise' 2025/07/22 01:51:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:51:50 DEBUG : OpenDrive root 'rclone-test-qaguxef8cise': Waiting for checks to finish 2025/07/22 01:51:50 DEBUG : OpenDrive root 'rclone-test-qaguxef8cise': Waiting for transfers to finish 2025/07/22 01:51:51 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/07/22 01:51:51 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/22 01:51:51 DEBUG : OpenDrive root 'rclone-test-qaguxef8cise': Purge remote --- PASS: TestServerSideCopy (6.28s) === RUN TestCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:53 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:51:55 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/22 01:51:55 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:51:55 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:51:55 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/07/22 01:51:56 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/07/22 01:51:56 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (5.22s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:51:59 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:52:00 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-heyexeh6miyo" 2025/07/22 01:52:01 DEBUG : Starting OpenDrive session with ID: 217756cc4340901d08f98fffb4d284d37685c938198cbb43bd290a2c2b14a8ee sync_test.go:656: Server side copy (if possible) OpenDrive root 'rclone-test-bekenop7koxi' -> OpenDrive root 'rclone-test-heyexeh6miyo' 2025/07/22 01:52:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:52:01 DEBUG : OpenDrive root 'rclone-test-heyexeh6miyo': Waiting for checks to finish 2025/07/22 01:52:01 DEBUG : OpenDrive root 'rclone-test-heyexeh6miyo': Waiting for transfers to finish 2025/07/22 01:52:02 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/07/22 01:52:02 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/22 01:52:03 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/07/22 01:52:05 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/22 01:52:05 DEBUG : OpenDrive root 'rclone-test-heyexeh6miyo': Waiting for checks to finish 2025/07/22 01:52:05 DEBUG : OpenDrive root 'rclone-test-heyexeh6miyo': Waiting for transfers to finish 2025/07/22 01:52:05 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/07/22 01:52:05 INFO : sub dir/hello world: Copied (server-side copy) fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:289: Sleeping for 6s just to make sure 2025/07/22 01:52:13 DEBUG : OpenDrive root 'rclone-test-heyexeh6miyo': Purge remote --- PASS: TestServerSideCopyOverSelf (16.84s) === RUN TestMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:52:16 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:52:17 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/22 01:52:17 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:52:17 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:52:18 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/07/22 01:52:19 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/07/22 01:52:19 INFO : sub dir/hello world: Copied (replaced existing) 2025/07/22 01:52:19 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (5.46s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:52:21 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:52:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-sucirul9xupu" 2025/07/22 01:52:23 DEBUG : Starting OpenDrive session with ID: edb27808f8b4f9a29d46d548ae020448d72079fd6057720373baa03073fb723f sync_test.go:701: Server side copy (if possible) OpenDrive root 'rclone-test-bekenop7koxi' -> OpenDrive root 'rclone-test-sucirul9xupu' 2025/07/22 01:52:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:52:23 DEBUG : OpenDrive root 'rclone-test-sucirul9xupu': Waiting for checks to finish 2025/07/22 01:52:23 DEBUG : OpenDrive root 'rclone-test-sucirul9xupu': Waiting for transfers to finish 2025/07/22 01:52:25 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/07/22 01:52:25 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/22 01:52:25 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/07/22 01:52:27 DEBUG : OpenDrive root 'rclone-test-sucirul9xupu': Using server-side directory move 2025/07/22 01:52:27 INFO : OpenDrive root 'rclone-test-sucirul9xupu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/22 01:52:27 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/22 01:52:27 DEBUG : OpenDrive root 'rclone-test-sucirul9xupu': Waiting for checks to finish 2025/07/22 01:52:27 DEBUG : OpenDrive root 'rclone-test-sucirul9xupu': Waiting for transfers to finish 2025/07/22 01:52:27 INFO : sub dir/hello world: Deleted 2025/07/22 01:52:27 INFO : sub dir/hello world: Moved (server-side) 2025/07/22 01:52:29 DEBUG : sub dir/hello world: Uploading chunk 0, size=24, remain=0 2025/07/22 01:52:30 DEBUG : testing file moves 2025/07/22 01:52:31 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/07/22 01:52:31 DEBUG : OpenDrive root 'rclone-test-sucirul9xupu': Waiting for checks to finish 2025/07/22 01:52:31 DEBUG : OpenDrive root 'rclone-test-sucirul9xupu': Waiting for transfers to finish 2025/07/22 01:52:31 INFO : sub dir/hello world: Deleted 2025/07/22 01:52:31 INFO : sub dir/hello world: Moved (server-side) 2025/07/22 01:52:32 DEBUG : OpenDrive root 'rclone-test-sucirul9xupu': Purge remote --- PASS: TestServerSideMoveOverSelf (13.46s) === RUN TestCopyAfterDelete run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:52:35 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:52:36 ERROR : error listing: directory not found 2025/07/22 01:52:36 INFO : Local file system at /tmp/rclone3119080479: Making directory 2025/07/22 01:52:36 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:52:36 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (4.45s) === RUN TestCopyRedownload run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:52:39 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/07/22 01:52:41 DEBUG : Added delayed dir = "sub dir", newDst= 2025/07/22 01:52:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/22 01:52:41 DEBUG : Local file system at /tmp/rclone3119080479: Waiting for checks to finish 2025/07/22 01:52:41 DEBUG : Local file system at /tmp/rclone3119080479: Waiting for transfers to finish 2025/07/22 01:52:41 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:52:41 DEBUG : sub dir/hello world.87ffde68.partial: renamed to: sub dir/hello world 2025/07/22 01:52:41 INFO : sub dir/hello world: Copied (new) 2025/07/22 01:52:41 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyRedownload (4.49s) === RUN TestMove run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:52:42 DEBUG : empty_dir: Making directory with metadata 2025/07/22 01:52:42 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/07/22 01:52:42 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:42 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:42 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:42 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:42 INFO : tictacempty_dir: Making directory 2025/07/22 01:52:43 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:43 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:43 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:43 INFO : tictactoe: Making directory 2025/07/22 01:52:43 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:43 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:43 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/07/22 01:52:43 INFO : tictactoe/tictactoe: Making directory 2025/07/22 01:52:44 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/07/22 01:52:44 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/07/22 01:52:44 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:44 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/07/22 01:52:44 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:44 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:44 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:52:44 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:52:44 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/07/22 01:52:45 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:52:45 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:45 INFO : toe/toe/toe.txt: Deleted 2025/07/22 01:52:45 INFO : toe/toe: Removing directory 2025/07/22 01:52:45 INFO : toe: Removing directory 2025/07/22 01:52:45 INFO : empty_dir: Removing directory 2025/07/22 01:52:45 DEBUG : Local file system at /tmp/rclone3119080479: deleted 3 directories --- PASS: TestMove (5.45s) === RUN TestTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:52:48 DEBUG : empty_dir: Making directory with metadata 2025/07/22 01:52:48 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/07/22 01:52:48 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:48 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:48 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:48 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:48 INFO : tictacempty_dir: Making directory 2025/07/22 01:52:48 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:48 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:48 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:48 INFO : tictactoe: Making directory 2025/07/22 01:52:49 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:49 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:49 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/07/22 01:52:49 INFO : tictactoe/tictactoe: Making directory 2025/07/22 01:52:49 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/07/22 01:52:49 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/07/22 01:52:49 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:49 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/07/22 01:52:49 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:49 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:49 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:52:49 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:52:50 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/07/22 01:52:51 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:52:51 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:51 INFO : toe/toe/toe.txt: Deleted 2025/07/22 01:52:51 INFO : toe/toe: Removing directory 2025/07/22 01:52:51 INFO : toe: Removing directory 2025/07/22 01:52:51 INFO : empty_dir: Removing directory 2025/07/22 01:52:51 DEBUG : Local file system at /tmp/rclone3119080479: deleted 3 directories 2025/07/22 01:52:52 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/07/22 01:52:52 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/07/22 01:52:52 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/07/22 01:52:52 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/07/22 01:52:53 INFO : tictactoe/tictactoe/tictactoe.txt: Moved (server-side) to: toe/toe/toe.txt --- PASS: TestTransformFile (8.13s) === RUN TestManualTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:52:56 DEBUG : Reset feature "Copy" 2025/07/22 01:52:56 DEBUG : Reset feature "Move" 2025/07/22 01:52:56 DEBUG : Reset feature "Copy" 2025/07/22 01:52:56 DEBUG : Reset feature "Move" 2025/07/22 01:52:56 DEBUG : empty_dir: Making directory with metadata 2025/07/22 01:52:56 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/07/22 01:52:56 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:56 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:56 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:56 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:56 INFO : tictacempty_dir: Making directory 2025/07/22 01:52:56 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/07/22 01:52:56 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:56 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:56 INFO : tictactoe: Making directory 2025/07/22 01:52:57 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:57 DEBUG : toe: transformed to: tictactoe 2025/07/22 01:52:57 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/07/22 01:52:57 INFO : tictactoe/tictactoe: Making directory 2025/07/22 01:52:57 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/07/22 01:52:57 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/07/22 01:52:57 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:57 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/07/22 01:52:57 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:52:57 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:57 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:52:57 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:58 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/07/22 01:52:59 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:52:59 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/07/22 01:52:59 INFO : toe/toe/toe.txt: Deleted 2025/07/22 01:52:59 INFO : toe/toe: Removing directory 2025/07/22 01:52:59 INFO : toe: Removing directory 2025/07/22 01:52:59 INFO : empty_dir: Removing directory 2025/07/22 01:52:59 DEBUG : Local file system at /tmp/rclone3119080479: deleted 3 directories 2025/07/22 01:53:00 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/07/22 01:53:00 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/07/22 01:53:00 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/07/22 01:53:00 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/07/22 01:53:01 ERROR : tictactoe/tictactoe/tictactoe.txt: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404) 2025/07/22 01:53:01 ERROR : tictactoe/tictactoe/tictactoe.txt: Not deleting source as copy failed: failed to get folder list: Directory doesn't exist (Error 404) sync_transform_test.go:465: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:465 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestManualTransformFile fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:298: Flushing the directory cache fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:465 Error: Not equal: expected: []string{} actual : []string{"toe"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "toe" } Test: TestManualTransformFile Messages: directories --- FAIL: TestManualTransformFile (15.00s) === RUN TestBase64 run.go:180: Remote "OpenDrive root 'rclone-test-bekenop7koxi'", Local "Local file system at /tmp/rclone3119080479", Modify Window "1s" 2025/07/22 01:53:11 DEBUG : toe: transformed to: dG9l 2025/07/22 01:53:11 DEBUG : toe: transformed to: dG9l 2025/07/22 01:53:11 INFO : dG9l: Making directory 2025/07/22 01:53:12 DEBUG : toe: transformed to: dG9l 2025/07/22 01:53:12 DEBUG : toe: transformed to: dG9l 2025/07/22 01:53:12 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/07/22 01:53:12 INFO : dG9l/dG9l: Making directory 2025/07/22 01:53:12 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/07/22 01:53:12 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/07/22 01:53:12 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/07/22 01:53:12 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/07/22 01:53:12 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/07/22 01:53:12 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for checks to finish 2025/07/22 01:53:12 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Waiting for transfers to finish 2025/07/22 01:53:13 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0 2025/07/22 01:53:14 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/22 01:53:14 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/07/22 01:53:14 ERROR : OpenDrive root 'rclone-test-bekenop7koxi': not deleting files as there were IO errors 2025/07/22 01:53:14 ERROR : OpenDrive root 'rclone-test-bekenop7koxi': not deleting directories as there were IO errors sync_transform_test.go:481: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:481 Error: Received unexpected error: not deleting files as there were IO errors Test: TestBase64 --- FAIL: TestBase64 (4.79s) FAIL 2025/07/22 01:53:16 DEBUG : OpenDrive root 'rclone-test-bekenop7koxi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestCopyAfterDelete|TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyRedownload|TestManualTransformFile|TestMove|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse|TestTransformFile)$'" - Finished ERROR in 2m6.909312265s (try 2/5): exit status 1: Failed [TestManualTransformFile TestBase64]