"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestConflicting|TestCopyWithDepth|TestManualTransformFile|TestMove|TestMoveWithoutDeleteEmptySrcDirs|TestRunTwice|TestServerSideCopyOverSelf|TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime|TestSyncImmutable|TestSyncOverlapWithFilter|TestSyncReplaceDirModTimeWithEmptyDirs|TestTransformFile)$'" - Starting (try 2/5) 2026/04/18 03:39:13 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vexitan6giba" 2026/04/18 03:39:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/04/18 03:39:13 DEBUG : Starting OpenDrive session with ID: 9565fecae3f0e172031d4f5f6c68d723eab208d3729b81e71644a450b20a31ab 2026/04/18 03:39:14 DEBUG : Creating backend with remote "/tmp/rclone721637953" === RUN TestCopyWithDepth run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:39:14 DEBUG : hello world2: Need to transfer - File not found at Destination 2026/04/18 03:39:14 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:39:14 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:39:15 DEBUG : hello world2: Uploading chunk 0, size=12, remain=0 2026/04/18 03:39:16 DEBUG : hello world2: size = 12 OK 2026/04/18 03:39:16 DEBUG : hello world2: md5 = cc2c857f89648dbd139d7b2a6665957d OK 2026/04/18 03:39:16 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (3.07s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:39:18 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2026/04/18 03:39:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xulolov5kade" 2026/04/18 03:39:20 DEBUG : Starting OpenDrive session with ID: f1d70a1ba1b365bc8a0c5e0bc6efd029b611e11bdaa670e14d924de5731cce7b sync_test.go:656: Server side copy (if possible) OpenDrive root 'rclone-test-vexitan6giba' -> OpenDrive root 'rclone-test-xulolov5kade' 2026/04/18 03:39:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/04/18 03:39:20 DEBUG : OpenDrive root 'rclone-test-xulolov5kade': Waiting for checks to finish 2026/04/18 03:39:20 DEBUG : OpenDrive root 'rclone-test-xulolov5kade': Waiting for transfers to finish 2026/04/18 03:39:21 ERROR : sub dir/hello world: Failed to copy: failed to get folder list: Directory doesn't exist (Error 404) sync_test.go:660: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:660 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestServerSideCopyOverSelf 2026/04/18 03:39:21 DEBUG : OpenDrive root 'rclone-test-xulolov5kade': Purge remote 2026/04/18 03:39:21 NOTICE: purge failed: Directory doesn't exist (Error 404) --- FAIL: TestServerSideCopyOverSelf (5.57s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:39:23 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2026/04/18 03:39:25 DEBUG : empty space: size = 1 OK 2026/04/18 03:39:25 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 2026/04/18 03:39:25 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:39:25 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2026/04/18 03:39:25 DEBUG : empty space: Unchanged skipping 2026/04/18 03:39:25 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:39:25 DEBUG : Waiting for deletions to finish 2026/04/18 03:39:25 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (3.05s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:39:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/04/18 03:39:26 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/04/18 03:39:26 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:39:26 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:39:27 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2026/04/18 03:39:28 DEBUG : nested/sub dir/file: Uploading chunk 0, size=6, remain=0 2026/04/18 03:39:28 DEBUG : sub dir/hello world: size = 11 OK 2026/04/18 03:39:28 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/18 03:39:28 INFO : sub dir/hello world: Copied (new) 2026/04/18 03:39:28 INFO : sub dir/hello world: Deleted 2026/04/18 03:39:29 DEBUG : nested/sub dir/file: size = 6 OK 2026/04/18 03:39:29 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2026/04/18 03:39:29 INFO : nested/sub dir/file: Copied (new) 2026/04/18 03:39:29 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (6.16s) === RUN TestSyncOverlapWithFilter run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:39:32 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vexitan6giba/rclone-sync-test" 2026/04/18 03:39:32 DEBUG : Starting OpenDrive session with ID: 1e1b76eaccafc1eccb5d25ab66a4f79b82c902d75e0f79bf99b1c0d135486b89 2026/04/18 03:39:33 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vexitan6giba/rclone-sync-test-include/layer2" 2026/04/18 03:39:34 DEBUG : Starting OpenDrive session with ID: f045a7acd2f888d72891d607d253d07e7f82f335939e96cb9f38ec643572eea7 2026/04/18 03:39:35 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vexitan6giba/rclone-sync-test-ignore-file" 2026/04/18 03:39:39 DEBUG : Starting OpenDrive session with ID: 35d4eee32dd10403dcc2f8e11693e9e228b4fe9906b031221b05a43ddb309847 2026/04/18 03:39:43 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0 2026/04/18 03:39:45 DEBUG : rclone-sync-test: Excluded 2026/04/18 03:39:45 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/04/18 03:39:45 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/04/18 03:39:45 DEBUG : OpenDrive root 'rclone-test-vexitan6giba/rclone-sync-test': Waiting for checks to finish 2026/04/18 03:39:45 DEBUG : OpenDrive root 'rclone-test-vexitan6giba/rclone-sync-test': Waiting for transfers to finish 2026/04/18 03:39:45 DEBUG : Waiting for deletions to finish 2026/04/18 03:39:45 INFO : There was nothing to transfer 2026/04/18 03:39:46 DEBUG : rclone-sync-test: Excluded 2026/04/18 03:39:46 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/04/18 03:39:47 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/04/18 03:39:47 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:39:47 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:39:47 DEBUG : Waiting for deletions to finish 2026/04/18 03:39:47 INFO : rclone-sync-test-include: Removing directory 2026/04/18 03:39:47 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': deleted 1 directories 2026/04/18 03:39:47 INFO : There was nothing to transfer 2026/04/18 03:39:48 DEBUG : rclone-sync-test: Excluded 2026/04/18 03:39:48 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/04/18 03:39:48 DEBUG : OpenDrive root 'rclone-test-vexitan6giba/rclone-sync-test-include/layer2': Waiting for checks to finish 2026/04/18 03:39:48 DEBUG : OpenDrive root 'rclone-test-vexitan6giba/rclone-sync-test-include/layer2': Waiting for transfers to finish 2026/04/18 03:39:48 DEBUG : Waiting for deletions to finish 2026/04/18 03:39:48 INFO : There was nothing to transfer 2026/04/18 03:39:48 DEBUG : rclone-sync-test: Excluded 2026/04/18 03:39:48 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/04/18 03:39:48 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:39:48 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:39:48 DEBUG : Waiting for deletions to finish 2026/04/18 03:39:48 INFO : There was nothing to transfer 2026/04/18 03:39:49 DEBUG : rclone-sync-test: Excluded 2026/04/18 03:39:49 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/04/18 03:39:49 DEBUG : Excluded 2026/04/18 03:39:49 DEBUG : OpenDrive root 'rclone-test-vexitan6giba/rclone-sync-test-ignore-file': Waiting for checks to finish 2026/04/18 03:39:49 DEBUG : OpenDrive root 'rclone-test-vexitan6giba/rclone-sync-test-ignore-file': Waiting for transfers to finish 2026/04/18 03:39:49 DEBUG : Waiting for deletions to finish 2026/04/18 03:39:49 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (19.27s) === RUN TestSyncImmutable run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:39:51 DEBUG : existing: Need to transfer - File not found at Destination 2026/04/18 03:39:51 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:39:51 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:39:52 DEBUG : existing: Uploading chunk 0, size=6, remain=0 2026/04/18 03:39:53 DEBUG : existing: size = 6 OK 2026/04/18 03:39:53 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2026/04/18 03:39:53 INFO : existing: Copied (new) 2026/04/18 03:39:53 DEBUG : Waiting for deletions to finish 2026/04/18 03:39:54 DEBUG : existing: size = 8 (Local file system at /tmp/rclone721637953) 2026/04/18 03:39:54 DEBUG : existing: size = 6 (OpenDrive root 'rclone-test-vexitan6giba') 2026/04/18 03:39:54 DEBUG : existing: Sizes differ 2026/04/18 03:39:54 ERROR : existing: Source and destination exist but do not match: immutable file modified 2026/04/18 03:39:54 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:39:54 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:39:54 ERROR : OpenDrive root 'rclone-test-vexitan6giba': not deleting files as there were IO errors 2026/04/18 03:39:54 ERROR : OpenDrive root 'rclone-test-vexitan6giba': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (3.46s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:39:54 DEBUG : empty_dir: Making directory with metadata 2026/04/18 03:39:54 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/04/18 03:39:54 DEBUG : empty_on_remote: Making directory with metadata 2026/04/18 03:39:54 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/04/18 03:39:54 INFO : empty_on_remote: Making directory 2026/04/18 03:39:55 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/04/18 03:39:58 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2026/04/18 03:39:58 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2026/04/18 03:39:58 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2026/04/18 03:39:58 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2026/04/18 03:39:58 INFO : empty_dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (11.45s) === RUN TestRunTwice run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:40:06 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:06 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:06 INFO : tictactoe: Making directory 2026/04/18 03:40:07 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:07 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:07 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:40:07 INFO : tictactoe/tictactoe: Making directory 2026/04/18 03:40:08 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:40:08 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/toe.txt 2026/04/18 03:40:08 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/04/18 03:40:08 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/toe.txt 2026/04/18 03:40:08 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:40:08 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:40:11 DEBUG : tictactoe/tictactoe/toe.txt: Uploading chunk 0, size=11, remain=0 2026/04/18 03:40:14 DEBUG : tictactoe/tictactoe/toe.txt: size = 11 OK 2026/04/18 03:40:14 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/18 03:40:14 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/toe.txt 2026/04/18 03:40:14 DEBUG : Waiting for deletions to finish 2026/04/18 03:40:16 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:17 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:17 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:17 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:40:18 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:40:18 DEBUG : tictactoe/tictactoe/toe.txt: size = 11 OK 2026/04/18 03:40:18 DEBUG : toe/toe/toe.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/18 03:40:18 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/toe.txt 2026/04/18 03:40:18 DEBUG : toe/toe/toe.txt: Unchanged skipping 2026/04/18 03:40:18 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:40:18 DEBUG : Waiting for deletions to finish --- PASS: TestRunTwice (18.85s) === RUN TestConflicting run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:40:25 INFO : toe: Making directory 2026/04/18 03:40:25 INFO : toe/toe: Making directory 2026/04/18 03:40:26 DEBUG : toe/toe/toe: Need to transfer - File not found at Destination 2026/04/18 03:40:26 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:40:26 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:40:27 DEBUG : toe/toe/toe: Uploading chunk 0, size=11, remain=0 2026/04/18 03:40:32 DEBUG : toe/toe/toe: size = 11 OK 2026/04/18 03:40:32 DEBUG : toe/toe/toe: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/18 03:40:32 INFO : toe/toe/toe: Copied (new) 2026/04/18 03:40:32 DEBUG : Waiting for deletions to finish --- PASS: TestConflicting (12.99s) === RUN TestMove run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:40:38 DEBUG : empty_dir: Making directory with metadata 2026/04/18 03:40:38 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/04/18 03:40:38 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:38 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:40:38 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:40:38 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:40:38 INFO : tictacempty_dir: Making directory 2026/04/18 03:40:38 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:40:38 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:38 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:38 INFO : tictactoe: Making directory 2026/04/18 03:40:39 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:39 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:39 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:40:39 INFO : tictactoe/tictactoe: Making directory 2026/04/18 03:40:39 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:40:39 DEBUG : toe.txt: transformed to: tictactoe.txt 2026/04/18 03:40:39 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:40:39 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/04/18 03:40:39 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:40:39 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:40:39 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:40:39 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:40:42 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2026/04/18 03:40:44 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/04/18 03:40:44 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/18 03:40:44 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:40:44 INFO : toe/toe/toe.txt: Deleted 2026/04/18 03:40:44 INFO : toe/toe: Removing directory 2026/04/18 03:40:44 INFO : toe: Removing directory 2026/04/18 03:40:44 INFO : empty_dir: Removing directory 2026/04/18 03:40:44 DEBUG : Local file system at /tmp/rclone721637953: deleted 3 directories --- PASS: TestMove (13.30s) === RUN TestTransformFile run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:40:51 DEBUG : empty_dir: Making directory with metadata 2026/04/18 03:40:51 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/04/18 03:40:51 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:51 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:40:51 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:40:52 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:40:52 INFO : tictacempty_dir: Making directory 2026/04/18 03:40:53 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:40:53 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:53 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:53 INFO : tictactoe: Making directory 2026/04/18 03:40:54 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:54 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:40:54 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:40:54 INFO : tictactoe/tictactoe: Making directory 2026/04/18 03:40:55 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:40:55 DEBUG : toe.txt: transformed to: tictactoe.txt 2026/04/18 03:40:55 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:40:55 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/04/18 03:40:55 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:40:55 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:40:55 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:40:55 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:40:55 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2026/04/18 03:40:58 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/04/18 03:40:58 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/18 03:40:58 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:40:58 INFO : toe/toe/toe.txt: Deleted 2026/04/18 03:40:58 INFO : toe/toe: Removing directory 2026/04/18 03:40:58 INFO : toe: Removing directory 2026/04/18 03:40:58 INFO : empty_dir: Removing directory 2026/04/18 03:40:58 DEBUG : Local file system at /tmp/rclone721637953: deleted 3 directories 2026/04/18 03:41:01 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/04/18 03:41:01 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/04/18 03:41:01 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/18 03:41:01 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/18 03:41:01 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/18 03:41:03 INFO : tictactoe/tictactoe/tictactoe.txt: Moved (server-side) to: toe/toe/toe.txt --- PASS: TestTransformFile (17.63s) === RUN TestManualTransformFile run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:41:08 DEBUG : Reset feature "Copy" 2026/04/18 03:41:08 DEBUG : Reset feature "Move" 2026/04/18 03:41:08 DEBUG : Reset feature "Copy" 2026/04/18 03:41:08 DEBUG : Reset feature "Move" 2026/04/18 03:41:08 DEBUG : empty_dir: Making directory with metadata 2026/04/18 03:41:08 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/04/18 03:41:08 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:41:08 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:41:08 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:41:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:41:09 INFO : tictacempty_dir: Making directory 2026/04/18 03:41:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/18 03:41:09 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:41:09 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:41:09 INFO : tictactoe: Making directory 2026/04/18 03:41:10 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:41:10 DEBUG : toe: transformed to: tictactoe 2026/04/18 03:41:10 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:41:10 INFO : tictactoe/tictactoe: Making directory 2026/04/18 03:41:10 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/18 03:41:10 DEBUG : toe.txt: transformed to: tictactoe.txt 2026/04/18 03:41:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:41:10 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/04/18 03:41:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:41:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:41:10 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:41:10 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:41:12 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2026/04/18 03:41:14 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/04/18 03:41:14 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/18 03:41:14 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2026/04/18 03:41:14 INFO : toe/toe/toe.txt: Deleted 2026/04/18 03:41:14 INFO : toe/toe: Removing directory 2026/04/18 03:41:14 INFO : toe: Removing directory 2026/04/18 03:41:14 INFO : empty_dir: Removing directory 2026/04/18 03:41:14 DEBUG : Local file system at /tmp/rclone721637953: deleted 3 directories 2026/04/18 03:41:15 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/04/18 03:41:15 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/04/18 03:41:15 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/18 03:41:15 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/18 03:41:15 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/18 03:41:17 DEBUG : toe/toe/toe.txt: Uploading chunk 0, size=11, remain=0 2026/04/18 03:41:19 DEBUG : toe/toe/toe.txt: size = 11 OK 2026/04/18 03:41:19 DEBUG : tictactoe/tictactoe/tictactoe.txt: Src hash empty - aborting Dst hash check 2026/04/18 03:41:19 INFO : tictactoe/tictactoe/tictactoe.txt: Copied (new) to: toe/toe/toe.txt 2026/04/18 03:41:19 INFO : tictactoe/tictactoe/tictactoe.txt: Deleted --- PASS: TestManualTransformFile (16.91s) === RUN TestBase64 run.go:185: Remote "OpenDrive root 'rclone-test-vexitan6giba'", Local "Local file system at /tmp/rclone721637953", Modify Window "1s" 2026/04/18 03:41:25 DEBUG : toe: transformed to: dG9l 2026/04/18 03:41:26 DEBUG : toe: transformed to: dG9l 2026/04/18 03:41:26 INFO : dG9l: Making directory 2026/04/18 03:41:26 DEBUG : toe: transformed to: dG9l 2026/04/18 03:41:26 DEBUG : toe: transformed to: dG9l 2026/04/18 03:41:26 DEBUG : toe/toe: transformed to: dG9l/dG9l 2026/04/18 03:41:26 INFO : dG9l/dG9l: Making directory 2026/04/18 03:41:27 DEBUG : toe/toe: transformed to: dG9l/dG9l 2026/04/18 03:41:27 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2026/04/18 03:41:27 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2026/04/18 03:41:27 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/04/18 03:41:27 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2026/04/18 03:41:27 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for checks to finish 2026/04/18 03:41:27 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Waiting for transfers to finish 2026/04/18 03:41:28 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0 2026/04/18 03:41:30 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2026/04/18 03:41:30 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/18 03:41:30 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2026/04/18 03:41:30 DEBUG : Waiting for deletions to finish 2026/04/18 03:41:31 DEBUG : dG9l: transformed to: toe 2026/04/18 03:41:31 DEBUG : dG9l: transformed to: toe 2026/04/18 03:41:31 INFO : toe: Set directory modification time (using SetModTime) 2026/04/18 03:41:31 DEBUG : Added delayed dir = "toe", newDst=toe 2026/04/18 03:41:31 DEBUG : dG9l: transformed to: toe 2026/04/18 03:41:31 DEBUG : dG9l/dG9l: transformed to: toe/toe 2026/04/18 03:41:31 INFO : toe/toe: Set directory modification time (using SetModTime) 2026/04/18 03:41:31 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe 2026/04/18 03:41:32 DEBUG : dG9lLnR4dA==: transformed to: toe.txt 2026/04/18 03:41:32 DEBUG : toe/toe/toe.txt: size = 11 OK 2026/04/18 03:41:32 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s) 2026/04/18 03:41:32 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt 2026/04/18 03:41:32 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping 2026/04/18 03:41:32 DEBUG : Local file system at /tmp/rclone721637953: Waiting for checks to finish 2026/04/18 03:41:32 DEBUG : Local file system at /tmp/rclone721637953: Waiting for transfers to finish 2026/04/18 03:41:32 DEBUG : Waiting for deletions to finish --- PASS: TestBase64 (11.08s) FAIL 2026/04/18 03:41:36 DEBUG : OpenDrive root 'rclone-test-vexitan6giba': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestConflicting|TestCopyWithDepth|TestManualTransformFile|TestMove|TestMoveWithoutDeleteEmptySrcDirs|TestRunTwice|TestServerSideCopyOverSelf|TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime|TestSyncImmutable|TestSyncOverlapWithFilter|TestSyncReplaceDirModTimeWithEmptyDirs|TestTransformFile)$'" - Finished ERROR in 2m24.344737775s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf]